[Jun 1 17:39:15] VERBOSE[13972] config.c: == Parsing '/etc/asterisk/logger.conf': [Jun 1 17:39:15] DEBUG[13972] config.c: Parsing /etc/asterisk/logger.conf [Jun 1 17:39:15] VERBOSE[13972] config.c: == Found [Jun 1 17:39:15] VERBOSE[13972] logger.c: Asterisk Event Logger restarted [Jun 1 17:39:15] VERBOSE[13972] logger.c: Asterisk Queue Logger restarted [Jun 1 17:39:15] VERBOSE[13972] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:15] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:15] VERBOSE[14063] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:16] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:16] VERBOSE[14226] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:16] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:16] VERBOSE[14365] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:17] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:17] VERBOSE[14522] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:17] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:17] VERBOSE[14663] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:17] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - cg11451 [Jun 1 17:39:17] DEBUG[3631] chan_sip.c: -REALTIME- peer expired registration. Name: cg11451. Realtime peer objects now 20 [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: Checking device state for peer cg11451 [Jun 1 17:39:17] DEBUG[3631] chan_sip.c: Destroying SIP peer cg11451 [Jun 1 17:39:17] DEBUG[3631] chan_sip.c: -REALTIME- peer Destroyed. Name: cg11451. Realtime Peer objects: 19 [Jun 1 17:39:17] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:17] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'cg11451' AND host = 'dynamic' [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: -REALTIME- peer built. Name: cg11451. Peer objects: 20 [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: SIP Seeding peer from astdb: 'cg11451' at cg11451@10.43.2.237:5060 for 120 [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: -REALTIME- loading peer from database to memory. Name: cg11451. Peer objects: 20 [Jun 1 17:39:17] DEBUG[2986] devicestate.c: Changing state for SIP/cg11451 - state 1 (Not in use) [Jun 1 17:39:17] DEBUG[2986] devicestate.c: device 'SIP/cg11451' state '1' [Jun 1 17:39:17] DEBUG[3642] app_queue.c: Device 'SIP/cg11451' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:39:17] DEBUG[3631] chan_sip.c: -REALTIME- peer expired registration. Name: cg11451. Realtime peer objects now 20 [Jun 1 17:39:17] DEBUG[3631] chan_sip.c: Destroying SIP peer cg11451 [Jun 1 17:39:17] DEBUG[3631] chan_sip.c: -REALTIME- peer Destroyed. Name: cg11451. Realtime Peer objects: 19 [Jun 1 17:39:17] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - cg11451 [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: Checking device state for peer cg11451 [Jun 1 17:39:17] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:17] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'cg11451' AND host = 'dynamic' [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: -REALTIME- peer built. Name: cg11451. Peer objects: 20 [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: SIP Seeding peer from astdb: 'cg11451' at cg11451@10.43.2.237:5060 for 120 [Jun 1 17:39:17] DEBUG[2986] chan_sip.c: -REALTIME- loading peer from database to memory. Name: cg11451. Peer objects: 20 [Jun 1 17:39:17] DEBUG[2986] devicestate.c: Changing state for SIP/cg11451 - state 1 (Not in use) [Jun 1 17:39:17] DEBUG[2986] devicestate.c: device 'SIP/cg11451' state '1' [Jun 1 17:39:17] DEBUG[3642] app_queue.c: Device 'SIP/cg11451' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:39:17] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:17] VERBOSE[14821] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:18] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:18] VERBOSE[14961] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:18] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:18] VERBOSE[15124] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:18] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:18] VERBOSE[15263] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:19] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:19] VERBOSE[15421] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:19] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:19] VERBOSE[15560] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:19] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:19] VERBOSE[15718] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:20] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:20] VERBOSE[15857] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:20] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:20] VERBOSE[16017] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:20] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:20] VERBOSE[16157] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:21] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:21] VERBOSE[16315] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:21] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:21] VERBOSE[16454] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:21] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:21] VERBOSE[16612] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:22] DEBUG[3230] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:22] DEBUG[3230] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '660508100%' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:22] DEBUG[3230] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:22] DEBUG[3230] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '660508100_%' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:22] DEBUG[3230] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:22] DEBUG[3230] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'bell4' AND priority = '1' ORDER BY exten [Jun 1 17:39:22] DEBUG[3230] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Jun 1 17:39:22] DEBUG[3230] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Jun 1 17:39:22] DEBUG[3230] devicestate.c: device 'DAHDI/1-1' state '2' [Jun 1 17:39:22] VERBOSE[3230] chan_dahdi.c: -- Accepting overlap call from '11451' to '660508100' on channel 0/1, span 1 [Jun 1 17:39:22] DEBUG[3642] app_queue.c: Device 'DAHDI/1-1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 1 17:39:22] VERBOSE[16752] chan_dahdi.c: -- Starting simple switch on 'DAHDI/1-1' [Jun 1 17:39:22] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:22] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '660508100_%' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:22] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:22] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'bell4' AND priority = '1' ORDER BY exten [Jun 1 17:39:22] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:22] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:22] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:22] VERBOSE[16753] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:22] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:22] VERBOSE[16909] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:22] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:22] VERBOSE[17050] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:23] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:23] VERBOSE[17213] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:23] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:23] VERBOSE[17352] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:23] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:23] VERBOSE[17510] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:24] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:24] VERBOSE[17671] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:24] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:24] VERBOSE[17828] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:24] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:24] VERBOSE[17969] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:25] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:25] VERBOSE[18127] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:25] DEBUG[16752] chan_dahdi.c: Enabled echo cancellation on channel 1 [Jun 1 17:39:25] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for DAHDI - 1 [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:25] DEBUG[2986] devicestate.c: Changing state for DAHDI/1 - state 2 (In use) [Jun 1 17:39:25] DEBUG[2986] devicestate.c: device 'DAHDI/1' state '2' [Jun 1 17:39:25] DEBUG[3642] app_queue.c: Device 'DAHDI/1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "campagna=100") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '2' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '2' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "28") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (bell4,660508100,28) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '28' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '28' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "13:00-20:30|*|31|dec?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '29' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '29' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "13:00-20:30|*|24|dec?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '30' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '30' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoiftime("DAHDI/1-1", "*|*|6|jan?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '31' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '31' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|13|apr?bell4|660508100|50") [Jun 1 17:39:25] WARNING[16752] pbx.c: The application delimiter is now the comma, not the pipe. Did you forget to convert your dialplan? (GotoIfTime(*|*|13|apr?bell4|660508100|50)) [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '32' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '32' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|25|dec?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '33' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '33' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|26|dec?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '34' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '34' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|8|dec?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '35' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '35' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|1|nov?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '36' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '36' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|15|aug?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '37' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '37' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|2|jun?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '38' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '38' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|1|may?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '39' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '39' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "17:00-19:30|*|25|may?local|manutenzione|1") [Jun 1 17:39:25] WARNING[16752] pbx.c: The application delimiter is now the comma, not the pipe. Did you forget to convert your dialplan? (NoOp(17:00-19:30|*|25|may?local|manutenzione|1)) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '40' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '40' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|25|apr?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '41' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '41' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|sun|*|*?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '42' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '42' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "*|*|1|jan?bell4,660508100,50") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '43' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '660508100' AND context = 'bell4' AND priority = '43' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing Gotoiftime("DAHDI/1-1", "7:30-19:30|mon-fri|*|*?local,recuptest,1") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,recuptest,1) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recuptest' AND context = 'local' AND priority = '1' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recuptest' AND context = 'local' AND priority = '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "campagna=100") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recuptest' AND context = 'local' AND priority = '2' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recuptest' AND context = 'local' AND priority = '2' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "local|ippocode|2") [Jun 1 17:39:25] WARNING[16752] pbx.c: The application delimiter is now the comma, not the pipe. Did you forget to convert your dialplan? (NoOp(local|ippocode|2)) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recuptest' AND context = 'local' AND priority = '3' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recuptest' AND context = 'local' AND priority = '3' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "local,recup2,1") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,recup2,1) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '1' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "11") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,recup2,11) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '11' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '11' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Function result is '11451' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "CALLERID(num)=000011451") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '12' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '12' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "poa=") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '13' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '13' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "30") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,recup2,30) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '30' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '30' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "campagna=100") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '31' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'recup2' AND context = 'local' AND priority = '31' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "local,ippocode,2") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,2) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '2' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '2' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Function result is '66' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "media=66") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '3' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '3' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Function result is 'piena' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "1?19:4") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '4' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '4' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Function result is '000011451' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing system("DAHDI/1-1", "/home/asterisk/gqueuec 1243870762.2 100 inaccoglienza 000011451 > /tmp/gqueuecl") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '5' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '5' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "benvenuto100") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '6' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '6' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing system("DAHDI/1-1", "/home/asterisk/gqpc 1243870762.2 100") [Jun 1 17:39:25] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '7' [Jun 1 17:39:25] VERBOSE[18281] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '7' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "ippocode100") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '8' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '8' [Jun 1 17:39:25] DEBUG[16752] db.c: Unable to find key '1243870762.2' in family '100' [Jun 1 17:39:25] DEBUG[16752] func_db.c: DB: 100/1243870762.2 not found in database. [Jun 1 17:39:25] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "op=") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '9' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '9' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "100") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,100) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '100' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '100' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?130") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '101' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '101' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?130") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '102' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '102' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "1?180") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,180) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '180' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '180' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "c=1") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '181' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '181' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "1?182:183") [Jun 1 17:39:25] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,182) [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '182' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '182' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Function result is '4' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "msgcoda=4") [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '183' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '183' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?192") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '184' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '184' [Jun 1 17:39:25] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?189") [Jun 1 17:39:25] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '185' [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:25] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '185' [Jun 1 17:39:25] VERBOSE[16752] pbx_realtime.c: -- Executing playback("DAHDI/1-1", "codarecup/4") [Jun 1 17:39:25] DEBUG[16752] chan_dahdi.c: Requested indication -1 on channel DAHDI/1-1 [Jun 1 17:39:25] DEBUG[16752] channel.c: Set channel DAHDI/1-1 to write format gsm [Jun 1 17:39:25] DEBUG[16752] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Jun 1 17:39:25] VERBOSE[16752] file.c: -- Playing 'codarecup/4.gsm' (language 'en') [Jun 1 17:39:25] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for DAHDI - 1 [Jun 1 17:39:25] DEBUG[2986] devicestate.c: Changing state for DAHDI/1 - state 2 (In use) [Jun 1 17:39:25] DEBUG[2986] devicestate.c: device 'DAHDI/1' state '2' [Jun 1 17:39:25] DEBUG[3642] app_queue.c: Device 'DAHDI/1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 1 17:39:25] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:25] VERBOSE[18439] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:27] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:27] VERBOSE[18657] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:27] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:27] VERBOSE[18816] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:29] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:29] VERBOSE[19032] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:29] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:29] VERBOSE[19190] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:31] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:31] VERBOSE[19407] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:31] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:31] VERBOSE[19566] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:33] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:33] VERBOSE[19787] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:33] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:33] VERBOSE[19967] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:35] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:35] VERBOSE[20183] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:35] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:35] VERBOSE[20342] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:36] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:36] VERBOSE[20560] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:37] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:37] VERBOSE[20718] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:38] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:38] VERBOSE[20934] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:39] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:39] VERBOSE[21091] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:40] DEBUG[16752] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 1 17:39:40] DEBUG[16752] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 1 17:39:40] DEBUG[16752] channel.c: Set channel DAHDI/1-1 to write format alaw [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '186' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '186' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '3' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "msgcoda=3") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '187' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '187' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "310") [Jun 1 17:39:40] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,310) [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '310' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '310' [Jun 1 17:39:40] DEBUG[16752] db.c: Unable to find key '1243870762.2' in family '100' [Jun 1 17:39:40] DEBUG[16752] func_db.c: DB: 100/1243870762.2 not found in database. [Jun 1 17:39:40] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "op=") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '311' [Jun 1 17:39:40] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '311' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "10") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '312' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '312' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "CALLERID(name)=") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '313' [Jun 1 17:39:40] VERBOSE[21310] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '313' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "10") [Jun 1 17:39:40] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,10) [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '10' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '10' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "1?6:11") [Jun 1 17:39:40] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,6) [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '6' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '6' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing system("DAHDI/1-1", "/home/asterisk/gqpc 1243870762.2 100") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '7' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '7' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "ippocode100") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '8' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '8' [Jun 1 17:39:40] DEBUG[16752] db.c: Unable to find key '1243870762.2' in family '100' [Jun 1 17:39:40] DEBUG[16752] func_db.c: DB: 100/1243870762.2 not found in database. [Jun 1 17:39:40] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "op=") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '9' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '9' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "100") [Jun 1 17:39:40] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,100) [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '100' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '100' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?130") [Jun 1 17:39:40] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '101' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '101' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?130") [Jun 1 17:39:40] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '102' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '102' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "1?180") [Jun 1 17:39:40] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,180) [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '180' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '180' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '2' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "c=2") [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '181' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '181' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?182:183") [Jun 1 17:39:40] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,183) [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '183' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '183' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?192") [Jun 1 17:39:40] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '184' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '184' [Jun 1 17:39:40] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?189") [Jun 1 17:39:40] DEBUG[16752] pbx.c: Not taking any branch [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '185' [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:40] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '185' [Jun 1 17:39:40] VERBOSE[16752] pbx_realtime.c: -- Executing playback("DAHDI/1-1", "codarecup/3") [Jun 1 17:39:40] DEBUG[16752] channel.c: Set channel DAHDI/1-1 to write format gsm [Jun 1 17:39:40] DEBUG[16752] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Jun 1 17:39:40] VERBOSE[16752] file.c: -- Playing 'codarecup/3.gsm' (language 'en') [Jun 1 17:39:41] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:41] VERBOSE[21473] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:42] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:42] VERBOSE[21716] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:42] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:42] VERBOSE[21874] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:45] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:45] VERBOSE[21998] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:45] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:45] VERBOSE[22130] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:46] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:46] VERBOSE[22288] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:46] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:46] VERBOSE[22427] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:46] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:46] VERBOSE[22586] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:47] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:47] VERBOSE[22725] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:47] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:47] VERBOSE[22883] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:47] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:47] VERBOSE[23022] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:48] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:48] VERBOSE[23181] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:48] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:48] VERBOSE[23320] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:48] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:48] VERBOSE[23478] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:49] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:49] VERBOSE[23617] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:49] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:49] VERBOSE[23777] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:49] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:49] VERBOSE[23916] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:50] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:50] VERBOSE[24074] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:50] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:50] VERBOSE[24218] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:50] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:50] VERBOSE[24375] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:51] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:51] VERBOSE[24515] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:51] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:51] VERBOSE[24694] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:51] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:51] VERBOSE[24837] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:52] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:52] VERBOSE[24995] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:52] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:52] VERBOSE[25134] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:52] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:52] VERBOSE[25292] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:53] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:53] VERBOSE[25431] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:53] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:53] VERBOSE[25588] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:53] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:53] VERBOSE[25731] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:54] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:54] VERBOSE[25889] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:54] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:54] VERBOSE[26028] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:54] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:54] VERBOSE[26188] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:55] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:55] VERBOSE[26327] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:55] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:55] VERBOSE[26484] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:55] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:55] VERBOSE[26626] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:55] DEBUG[16752] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 1 17:39:55] DEBUG[16752] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 1 17:39:55] DEBUG[16752] channel.c: Set channel DAHDI/1-1 to write format alaw [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '186' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '186' [Jun 1 17:39:55] DEBUG[16752] pbx.c: Expression result is '2' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "msgcoda=2") [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '187' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '187' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "310") [Jun 1 17:39:55] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,310) [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '310' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '310' [Jun 1 17:39:55] DEBUG[16752] pbx.c: Function result is 'pa9405@astw03.sociale.it' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "op=pa9405@astw03.sociale.it") [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '311' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '311' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "10") [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '312' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '312' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "CALLERID(name)=") [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '313' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '313' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing goto("DAHDI/1-1", "10") [Jun 1 17:39:55] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,10) [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '10' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '10' [Jun 1 17:39:55] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing gotoif("DAHDI/1-1", "0?6:11") [Jun 1 17:39:55] VERBOSE[16752] pbx.c: -- Goto (local,ippocode,11) [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '11' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '11' [Jun 1 17:39:55] DEBUG[16752] pbx.c: Function result is '2009/06/01/17.39.55' [Jun 1 17:39:55] DEBUG[16752] pbx.c: Function result is '000011451' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing set("DAHDI/1-1", "rec=/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2") [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '12' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '12' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing monitor("DAHDI/1-1", "gsm,/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2,m") [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '13' [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:55] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '13' [Jun 1 17:39:55] DEBUG[16752] pbx.c: Function result is '000011451' [Jun 1 17:39:55] VERBOSE[16752] pbx_realtime.c: -- Executing system("DAHDI/1-1", "/home/asterisk/gq1 pa9405@astw03.sociale.it chiamato 100 1243870762.2 "/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2" 000011451 > /tmp/100-pa9405@astw03.sociale.it") [Jun 1 17:39:56] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:56] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '14' [Jun 1 17:39:56] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:56] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '14' [Jun 1 17:39:56] DEBUG[16752] pbx.c: Function result is 'pa9405' [Jun 1 17:39:56] VERBOSE[16752] pbx_realtime.c: -- Executing playback("DAHDI/1-1", "sia/pa9405") [Jun 1 17:39:56] DEBUG[16752] channel.c: Set channel DAHDI/1-1 to write format ulaw [Jun 1 17:39:56] DEBUG[16752] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Jun 1 17:39:56] VERBOSE[16752] file.c: -- Playing 'sia/pa9405.ulaw' (language 'en') [Jun 1 17:39:56] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:56] VERBOSE[26816] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:56] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:56] VERBOSE[26955] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:56] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:56] VERBOSE[27113] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:57] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:57] VERBOSE[27252] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:57] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:57] VERBOSE[27410] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:57] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:57] VERBOSE[27549] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:58] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:58] VERBOSE[27707] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:58] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:58] VERBOSE[27847] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:58] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:58] VERBOSE[28004] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:59] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:59] VERBOSE[28144] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:59] DEBUG[16752] channel.c: Scheduling timer at (96 requested / 96 actual) timer ticks per second [Jun 1 17:39:59] DEBUG[16752] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 1 17:39:59] DEBUG[16752] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 1 17:39:59] DEBUG[16752] channel.c: Set channel DAHDI/1-1 to write format alaw [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '15' [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '15' [Jun 1 17:39:59] VERBOSE[16752] pbx_realtime.c: -- Executing retrydial("DAHDI/1-1", "silenzio,1,1,sip/pa9405@astw03.sociale.it,18,A()") [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Asked to create a SIP channel with formats: 0x8 (alaw) [Jun 1 17:39:59] VERBOSE[16752] netsock.c: == Using SIP RTP CoS mark 5 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Allocating new SIP dialog for 47d8310e7e52404d12a008f343f04aab@10.43.100.206 - INVITE (With RTP) [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' AND host = 'dynamic' [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Setting NAT on RTP to On [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Jun 1 17:39:59] DEBUG[16752] acl.c: Found IP address for this socket [Jun 1 17:39:59] DEBUG[16752] frame.c: Could not find preferred codec - Going for the best codec [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: *** Our native formats are 0x8 (alaw) [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: *** Joint capabilities are 0x8 (alaw) [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: *** Our capabilities are 0x6 (gsm|ulaw) [Jun 1 17:39:59] DEBUG[16752] frame.c: Could not find preferred codec - Going for the best codec [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: *** Our preferred formats from the incoming channel are 0x8 (alaw) [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: This channel will not be able to handle video. [Jun 1 17:39:59] DEBUG[16752] rtp.c: Channel 'DAHDI/1-1' has no RTP, not doing anything [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable DIALEDTIME. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable ANSWEREDTIME. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable DIALEDPEERNAME. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable DIALEDPEERNUMBER. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable DIALSTATUS. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable PLAYBACKSTATUS. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable SYSTEMSTATUS. [Jun 1 17:39:59] DEBUG[16752] channel.c: Copying hard-transferable variable MONITORED. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable rec. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable op. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable DB_RESULT. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable msgcoda. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable c. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable media. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable poa. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable campagna. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable CALLEDTON. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable ANI2. [Jun 1 17:39:59] DEBUG[16752] channel.c: Not copying variable TRANSFERCAPABILITY. [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Outgoing Call for pa9405 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Updating call counter for outgoing call [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Our T38 capability (0), joint T38 capability (0) [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'pa9405' AND host = 'dynamic' [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: -REALTIME- peer built. Name: pa9405. Peer objects: 21 [Jun 1 17:39:59] DEBUG[16752] db.c: Unable to find key 'pa9405' in family 'SIP/Registry' [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: -REALTIME- loading peer from database to memory. Name: pa9405. Peer objects: 21 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Destroying SIP peer pa9405 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: -REALTIME- peer Destroyed. Name: pa9405. Realtime Peer objects: 20 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: ** Our capability: 0x6 (gsm|ulaw) Video flag: False Text flag: False [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: ** Our prefcodec: 0x8 (alaw) [Jun 1 17:39:59] VERBOSE[16752] chan_sip.c: Audio is at 10.43.100.206 port 10354 [Jun 1 17:39:59] VERBOSE[16752] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Jun 1 17:39:59] VERBOSE[16752] chan_sip.c: Adding codec 0x2 (gsm) to SDP [Jun 1 17:39:59] VERBOSE[16752] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: -- Done with adding codecs to SDP [Jun 1 17:39:59] DEBUG[16752] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=158) [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Done building SDP. Settling with this capability: 0x6 (gsm|ulaw) [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Initializing initreq for method INVITE - callid 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 0 [ 43]: INVITE sip:pa9405@astw03.sociale.it SIP/2.0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;rport [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 4 [ 34]: To: [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 5 [ 38]: Contact: [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 6 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 9 [ 35]: Date: Mon, 01 Jun 2009 15:39:59 GMT [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 13 [ 19]: Content-Length: 286 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 14 [ 0]: [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 1 [ 47]: o=root 374306371 374306371 IN IP4 10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 2 [ 22]: s=Asterisk PBX 1.6.1.0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 5 [ 29]: m=audio 10354 RTP/AVP 0 3 101 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:39:59] VERBOSE[16752] chan_sip.c: Reliably Transmitting (NAT) to 10.43.100.166:5060: INVITE sip:pa9405@astw03.sociale.it SIP/2.0 Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;rport Max-Forwards: 70 From: "000011451" ;tag=as07779a0d To: Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX 1.6.1.0 Date: Mon, 01 Jun 2009 15:39:59 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces, timer Content-Type: application/sdp Content-Length: 286 v=0 o=root 374306371 374306371 IN IP4 10.43.100.206 s=Asterisk PBX 1.6.1.0 c=IN IP4 10.43.100.206 t=0 0 m=audio 10354 RTP/AVP 0 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 0 [ 43]: INVITE sip:pa9405@astw03.sociale.it SIP/2.0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;rport [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 4 [ 34]: To: [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 5 [ 38]: Contact: [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 6 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 9 [ 35]: Date: Mon, 01 Jun 2009 15:39:59 GMT [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 13 [ 19]: Content-Length: 286 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Header 14 [ 0]: [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 1 [ 47]: o=root 374306371 374306371 IN IP4 10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 2 [ 22]: s=Asterisk PBX 1.6.1.0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.206 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 5 [ 29]: m=audio 10354 RTP/AVP 0 3 101 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #62 [Jun 1 17:39:59] DEBUG[16752] chan_sip.c: Trying to put 'INVITE sip' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:39:59] VERBOSE[16752] app_dial.c: -- Called pa9405@astw03.sociale.it [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'local' AND priority = '-1' [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'local' AND priority = '-1' ORDER BY exten [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'ippocode' AND context = 'default' AND priority = '-1' [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '-1' ORDER BY exten [Jun 1 17:39:59] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:39:59] VERBOSE[28305] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:39:59] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 From: "000011451" ;tag=as07779a0d To: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <-------------> [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 2 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 3 [ 34]: To: [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 6 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 8 [ 19]: Supported: replaces [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Contact: [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Header 11 [ 0]: [Jun 1 17:39:59] VERBOSE[3631] chan_sip.c: --- (11 headers 0 lines) --- [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: *** SIP TIMER: Cancelling retransmission #62 - INVITE (got response) [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' Request 102: Found [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: SIP response 100 to standard invite [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: -REALTIME- peer expired registration. Name: cg11451. Realtime peer objects now 20 [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: Destroying SIP peer cg11451 [Jun 1 17:39:59] DEBUG[3631] chan_sip.c: -REALTIME- peer Destroyed. Name: cg11451. Realtime Peer objects: 19 [Jun 1 17:39:59] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - cg11451 [Jun 1 17:39:59] DEBUG[2986] chan_sip.c: Checking device state for peer cg11451 [Jun 1 17:39:59] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:39:59] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'cg11451' AND host = 'dynamic' [Jun 1 17:39:59] DEBUG[2986] chan_sip.c: -REALTIME- peer built. Name: cg11451. Peer objects: 20 [Jun 1 17:39:59] DEBUG[2986] chan_sip.c: SIP Seeding peer from astdb: 'cg11451' at cg11451@10.43.2.237:5060 for 120 [Jun 1 17:39:59] DEBUG[2986] chan_sip.c: -REALTIME- loading peer from database to memory. Name: cg11451. Peer objects: 20 [Jun 1 17:39:59] DEBUG[2986] devicestate.c: Changing state for SIP/cg11451 - state 1 (Not in use) [Jun 1 17:39:59] DEBUG[2986] devicestate.c: device 'SIP/cg11451' state '1' [Jun 1 17:39:59] DEBUG[3642] app_queue.c: Device 'SIP/cg11451' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:40:00] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:00] VERBOSE[28446] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: -REALTIME- peer expired registration. Name: cg11451. Realtime peer objects now 20 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Destroying SIP peer cg11451 [Jun 1 17:40:00] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - cg11451 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: -REALTIME- peer Destroyed. Name: cg11451. Realtime Peer objects: 19 [Jun 1 17:40:00] DEBUG[2986] chan_sip.c: Checking device state for peer cg11451 [Jun 1 17:40:00] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:00] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'cg11451' AND host = 'dynamic' [Jun 1 17:40:00] DEBUG[2986] chan_sip.c: -REALTIME- peer built. Name: cg11451. Peer objects: 20 [Jun 1 17:40:00] DEBUG[2986] chan_sip.c: SIP Seeding peer from astdb: 'cg11451' at cg11451@10.43.2.237:5060 for 120 [Jun 1 17:40:00] DEBUG[2986] chan_sip.c: -REALTIME- loading peer from database to memory. Name: cg11451. Peer objects: 20 [Jun 1 17:40:00] DEBUG[2986] devicestate.c: Changing state for SIP/cg11451 - state 1 (Not in use) [Jun 1 17:40:00] DEBUG[2986] devicestate.c: device 'SIP/cg11451' state '1' [Jun 1 17:40:00] DEBUG[3642] app_queue.c: Device 'SIP/cg11451' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:40:00] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:00] VERBOSE[28604] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 From: "000011451" ;tag=as07779a0d To: ;tag=as54de8de0 Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <-------------> [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 0 [ 19]: SIP/2.0 180 Ringing [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 2 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 3 [ 49]: To: ;tag=as54de8de0 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 6 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 8 [ 19]: Supported: replaces [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Contact: [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 11 [ 0]: [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: --- (11 headers 0 lines) --- [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' Request 102: Found [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: SIP response 180 to standard invite [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 From: "000011451" ;tag=as07779a0d To: ;tag=as54de8de0 Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 265 v=0 o=root 28764 28764 IN IP4 10.43.100.166 s=session c=IN IP4 10.43.100.166 t=0 0 m=audio 11960 RTP/AVP 0 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <-------------> [Jun 1 17:40:00] VERBOSE[16752] app_dial.c: -- SIP/astw03.sociale.it-08812850 is ringing [Jun 1 17:40:00] DEBUG[16752] chan_dahdi.c: Requested indication 3 on channel DAHDI/1-1 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 0 [ 28]: SIP/2.0 183 Session Progress [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 2 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 3 [ 49]: To: ;tag=as54de8de0 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:00] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - astw03.sociale.it [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 6 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 8 [ 19]: Supported: replaces [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Contact: [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 10 [ 29]: Content-Type: application/sdp [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 11 [ 19]: Content-Length: 265 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Header 12 [ 0]: [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 1 [ 39]: o=root 28764 28764 IN IP4 10.43.100.166 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 2 [ 9]: s=session [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.166 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 5 [ 29]: m=audio 11960 RTP/AVP 0 3 101 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:00] DEBUG[2986] chan_sip.c: Checking device state for peer astw03.sociale.it [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: --- (12 headers 13 lines) --- [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' Request 102: Found [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: SIP response 183 to standard invite [Jun 1 17:40:00] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:00] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' AND host = 'dynamic' [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Found RTP audio format 0 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Found RTP audio format 3 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Found RTP audio format 101 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Peer audio RTP is at port 10.43.100.166:11960 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Found audio description format PCMU for ID 0 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Found audio description format GSM for ID 3 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Found audio description format telephone-event for ID 101 [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Got unsupported a:fmtp in SDP offer [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Capabilities: us - 0x6 (gsm|ulaw), peer - audio=0x6 (gsm|ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x6 (gsm|ulaw) [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Jun 1 17:40:00] VERBOSE[3631] chan_sip.c: Peer audio RTP is at port 10.43.100.166:11960 [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: We're settling with these formats: 0x6 (gsm|ulaw) [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: We have an owner, now see if we need to change this call [Jun 1 17:40:00] DEBUG[3631] chan_sip.c: Oooh, we need to change our audio formats since our peer supports only 0x6 (gsm|ulaw) and not 0x8 (alaw) [Jun 1 17:40:00] DEBUG[3631] channel.c: Set channel SIP/astw03.sociale.it-08812850 to read format alaw [Jun 1 17:40:00] DEBUG[3631] channel.c: Set channel SIP/astw03.sociale.it-08812850 to write format alaw [Jun 1 17:40:00] VERBOSE[16752] app_dial.c: -- SIP/astw03.sociale.it-08812850 is making progress passing it to DAHDI/1-1 [Jun 1 17:40:00] DEBUG[16752] chan_dahdi.c: Requested indication 14 on channel DAHDI/1-1 [Jun 1 17:40:00] DEBUG[16752] chan_dahdi.c: Received AST_CONTROL_PROGRESS on DAHDI/1-1 [Jun 1 17:40:00] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:00] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' [Jun 1 17:40:00] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:00] DEBUG[2986] devicestate.c: Changing state for SIP/astw03.sociale.it - state 6 (Ringing) [Jun 1 17:40:00] DEBUG[2986] devicestate.c: device 'SIP/astw03.sociale.it' state '6' [Jun 1 17:40:00] DEBUG[3642] app_queue.c: Device 'SIP/astw03.sociale.it' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Jun 1 17:40:00] DEBUG[16752] rtp.c: Ooh, format changed from unknown to ulaw [Jun 1 17:40:00] DEBUG[16752] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Jun 1 17:40:00] VERBOSE[28765] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:01] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:01] VERBOSE[28921] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:01] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:01] VERBOSE[29075] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:01] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:01] VERBOSE[29084] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 From: "000011451" ;tag=as07779a0d To: ;tag=as54de8de0 Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 265 v=0 o=root 28764 28765 IN IP4 10.43.100.166 s=session c=IN IP4 10.43.100.166 t=0 0 m=audio 11960 RTP/AVP 0 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <-------------> [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK35b2a6da;received=10.43.100.206;rport=5060 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 49]: To: ;tag=as54de8de0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 19]: Supported: replaces [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Contact: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 29]: Content-Type: application/sdp [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 11 [ 19]: Content-Length: 265 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 12 [ 0]: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 1 [ 39]: o=root 28764 28765 IN IP4 10.43.100.166 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 2 [ 9]: s=session [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.166 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 5 [ 29]: m=audio 11960 RTP/AVP 0 3 101 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: --- (12 headers 13 lines) --- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Acked pending invite 102 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Stopping retransmission on '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' of Request 102: Match Found [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: SIP response 200 to standard invite [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Found RTP audio format 0 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Found RTP audio format 3 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Found RTP audio format 101 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Peer audio RTP is at port 10.43.100.166:11960 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Found audio description format PCMU for ID 0 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Found audio description format GSM for ID 3 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Found audio description format telephone-event for ID 101 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Got unsupported a:fmtp in SDP offer [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Capabilities: us - 0x6 (gsm|ulaw), peer - audio=0x6 (gsm|ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x6 (gsm|ulaw) [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Peer audio RTP is at port 10.43.100.166:11960 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: We're settling with these formats: 0x6 (gsm|ulaw) [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: We have an owner, now see if we need to change this call [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Updating call counter for outgoing call [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: build_route: Contact hop: [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: list_route: hop: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Strict routing enforced for session 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: set_destination: Parsing for address/port to send to [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: set_destination: set destination to 10.43.100.166, port 5060 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Transmitting (NAT) to 10.43.100.166:5060: ACK sip:pa9405@10.43.100.166 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK5f815ec3;rport Max-Forwards: 70 From: "000011451" ;tag=as07779a0d To: ;tag=as54de8de0 Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 ACK User-Agent: Asterisk PBX 1.6.1.0 Content-Length: 0 --- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 36]: ACK sip:pa9405@10.43.100.166 SIP/2.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK5f815ec3;rport [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 49]: To: ;tag=as54de8de0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 38]: Contact: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 13]: CSeq: 102 ACK [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 0]: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Trying to put 'ACK sip:pa' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> INVITE sip:000011451@10.43.100.206 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;rport From: ;tag=as54de8de0 To: "000011451" ;tag=as07779a0d Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 236 v=0 o=root 28764 28766 IN IP4 10.43.8.14 s=session c=IN IP4 10.43.8.14 t=0 0 m=audio 49184 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <-------------> [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 42]: INVITE sip:000011451@10.43.100.206 SIP/2.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;rport [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 51]: From: ;tag=as54de8de0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 60]: To: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 35]: Contact: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 19]: Supported: replaces [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 12 [ 19]: Content-Length: 236 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 13 [ 0]: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 1 [ 36]: o=root 28764 28766 IN IP4 10.43.8.14 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 2 [ 9]: s=session [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 3 [ 19]: c=IN IP4 10.43.8.14 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 5 [ 27]: m=audio 49184 RTP/AVP 0 101 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 7 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 8 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 9 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 10 [ 10]: a=ptime:20 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=sendrecv [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: --- (13 headers 12 lines) --- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Begin: parsing SIP "Supported: replaces" [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Found SIP option: -replaces- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Matched SIP option: replaces [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Potential spiral detected. Original RURI was sip:pa9405@astw03.sociale.it, new RURI is sip:000011451@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[3631] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '000011451' AND host = 'dynamic' [Jun 1 17:40:01] DEBUG[3631] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[3631] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '000011451' [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Setting NAT on RTP to On [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Jun 1 17:40:01] WARNING[3631] chan_sip.c: No such host: 000011451 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: ** Our capability: 0x6 (gsm|ulaw) Video flag: True Text flag: True [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: ** Our prefcodec: 0x8 (alaw) [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Audio is at 10.43.100.206 port 10354 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Adding codec 0x2 (gsm) to SDP [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: -- Done with adding codecs to SDP [Jun 1 17:40:01] DEBUG[3631] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=158) [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Done building SDP. Settling with this capability: 0x6 (gsm|ulaw) [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Initializing already initialized SIP dialog 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 (presumably reinvite) [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 39]: INVITE sip:pa9405@10.43.100.166 SIP/2.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;rport [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 30]: To: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 38]: Contact: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 16]: CSeq: 103 INVITE [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Date: Mon, 01 Jun 2009 15:40:01 GMT [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 13 [ 19]: Content-Length: 286 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 14 [ 0]: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 1 [ 47]: o=root 374306371 374306372 IN IP4 10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 2 [ 22]: s=Asterisk PBX 1.6.1.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 5 [ 29]: m=audio 10354 RTP/AVP 0 3 101 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Reliably Transmitting (NAT) to 10.43.100.166:5060: INVITE sip:pa9405@10.43.100.166 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;rport Max-Forwards: 70 From: "000011451" ;tag=as07779a0d To: Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 103 INVITE User-Agent: Asterisk PBX 1.6.1.0 Date: Mon, 01 Jun 2009 15:40:01 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces, timer Content-Type: application/sdp Content-Length: 286 v=0 o=root 374306371 374306372 IN IP4 10.43.100.206 s=Asterisk PBX 1.6.1.0 c=IN IP4 10.43.100.206 t=0 0 m=audio 10354 RTP/AVP 0 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 39]: INVITE sip:pa9405@10.43.100.166 SIP/2.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;rport [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 30]: To: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 38]: Contact: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 16]: CSeq: 103 INVITE [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Date: Mon, 01 Jun 2009 15:40:01 GMT [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 13 [ 19]: Content-Length: 286 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 14 [ 0]: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 1 [ 47]: o=root 374306371 374306372 IN IP4 10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 2 [ 22]: s=Asterisk PBX 1.6.1.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 5 [ 29]: m=audio 10354 RTP/AVP 0 3 101 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #67 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Trying to put 'INVITE sip' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> SIP/2.0 491 Request Pending Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;received=10.43.100.206;rport=5060 From: "000011451" ;tag=as07779a0d To: ;tag=as07779a0d Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 <-------------> [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 27]: SIP/2.0 491 Request Pending [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;received=10.43.100.206;rport=5060 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 45]: To: ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 103 INVITE [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 19]: Supported: replaces [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 39]: X-Asterisk-HangupCause: Normal Clearing [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 11 [ 30]: X-Asterisk-HangupCauseCode: 16 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 12 [ 0]: [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: --- (12 headers 0 lines) --- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Acked pending invite 103 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #67 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Stopping retransmission on '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' of Request 103: Match Found [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: SIP response 491 to standard invite [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Strict routing enforced for session 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: set_destination: Parsing for address/port to send to [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: set_destination: set destination to 10.43.100.166, port 5060 [Jun 1 17:40:01] VERBOSE[3631] chan_sip.c: Transmitting (NAT) to 10.43.100.166:5060: ACK sip:pa9405@10.43.100.166 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;rport Max-Forwards: 70 From: "000011451" ;tag=as07779a0d To: ;tag=as07779a0d Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 103 ACK User-Agent: Asterisk PBX 1.6.1.0 Content-Length: 0 --- [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 0 [ 36]: ACK sip:pa9405@10.43.100.166 SIP/2.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK21b08094;rport [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 4 [ 45]: To: ;tag=as07779a0d [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 5 [ 38]: Contact: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 6 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 7 [ 13]: CSeq: 103 ACK [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Header 10 [ 0]: [Jun 1 17:40:01] DEBUG[3631] chan_sip.c: Trying to put 'ACK sip:pa' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:40:01] VERBOSE[16752] app_dial.c: -- SIP/astw03.sociale.it-08812850 answered DAHDI/1-1 [Jun 1 17:40:01] DEBUG[16752] features.c: bridge answer set, chan answer set [Jun 1 17:40:01] DEBUG[16752] chan_dahdi.c: Requested indication 20 on channel DAHDI/1-1 [Jun 1 17:40:01] DEBUG[16752] channel.c: Got a FRAME_CONTROL (8) frame on channel SIP/astw03.sociale.it-08812850 [Jun 1 17:40:01] DEBUG[16752] chan_dahdi.c: Requested indication 20 on channel DAHDI/1-1 [Jun 1 17:40:01] DEBUG[16752] channel.c: Bridge stops bridging channels DAHDI/1-1 and SIP/astw03.sociale.it-08812850 [Jun 1 17:40:01] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - astw03.sociale.it [Jun 1 17:40:01] DEBUG[2986] chan_sip.c: Checking device state for peer astw03.sociale.it [Jun 1 17:40:01] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '1' [Jun 1 17:40:01] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' AND host = 'dynamic' [Jun 1 17:40:01] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' [Jun 1 17:40:01] DEBUG[2986] devicestate.c: Changing state for SIP/astw03.sociale.it - state 2 (In use) [Jun 1 17:40:01] DEBUG[2986] devicestate.c: device 'SIP/astw03.sociale.it' state '2' [Jun 1 17:40:01] DEBUG[3642] app_queue.c: Device 'SIP/astw03.sociale.it' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 1 17:40:01] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '1' [Jun 1 17:40:01] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:01] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '1' [Jun 1 17:40:01] DEBUG[16752] pbx.c: Function result is '000011451' [Jun 1 17:40:01] VERBOSE[16752] pbx_realtime.c: -- Executing system("DAHDI/1-1", "/home/asterisk/gqueuedel "1243870762.2" "000011451" "016" "local" local") [Jun 1 17:40:01] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:01] VERBOSE[29269] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:02] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:02] VERBOSE[29408] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:02] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> INVITE sip:000011451@10.43.100.206 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;rport From: ;tag=as54de8de0 To: "000011451" ;tag=as07779a0d Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 236 v=0 o=root 28764 28766 IN IP4 10.43.8.14 s=session c=IN IP4 10.43.8.14 t=0 0 m=audio 49184 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <-------------> [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 0 [ 42]: INVITE sip:000011451@10.43.100.206 SIP/2.0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;rport [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 2 [ 51]: From: ;tag=as54de8de0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 3 [ 60]: To: "000011451" ;tag=as07779a0d [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 4 [ 35]: Contact: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 5 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 6 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 7 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 9 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 10 [ 19]: Supported: replaces [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 12 [ 19]: Content-Length: 236 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 13 [ 0]: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 1 [ 36]: o=root 28764 28766 IN IP4 10.43.8.14 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 2 [ 9]: s=session [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 3 [ 19]: c=IN IP4 10.43.8.14 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 5 [ 27]: m=audio 49184 RTP/AVP 0 101 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 7 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 8 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 9 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 10 [ 10]: a=ptime:20 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=sendrecv [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: --- (13 headers 12 lines) --- [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Ignoring SIP message because of retransmit (INVITE Seqno 102, ours 102) [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: Ignoring this INVITE request [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Got a SIP re-transmit of INVITE for call 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: SIP/astw03.sociale.it-08812850: This call is UP.... [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: <--- Transmitting (NAT) to 10.43.100.166:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;received=10.43.100.166;rport=5060 From: ;tag=as54de8de0 To: "000011451" ;tag=as07779a0d Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE Server: Asterisk PBX 1.6.1.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces, timer Contact: Content-Length: 0 <------------> [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;received=10.43.100.166;rport=5060 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 2 [ 51]: From: ;tag=as54de8de0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 3 [ 60]: To: "000011451" ;tag=as07779a0d [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 6 [ 28]: Server: Asterisk PBX 1.6.1.0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 9 [ 38]: Contact: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 11 [ 0]: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Trying to put 'SIP/2.0 10' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: ** Our capability: 0x6 (gsm|ulaw) Video flag: True Text flag: True [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: ** Our prefcodec: 0x8 (alaw) [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: Audio is at 10.43.100.206 port 10354 [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: Adding codec 0x2 (gsm) to SDP [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: -- Done with adding codecs to SDP [Jun 1 17:40:02] DEBUG[3631] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=158) [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Done building SDP. Settling with this capability: 0x6 (gsm|ulaw) [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: <--- Transmitting (NAT) to 10.43.100.166:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;received=10.43.100.166;rport=5060 From: ;tag=as54de8de0 To: "000011451" ;tag=as07779a0d Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 INVITE Server: Asterisk PBX 1.6.1.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 286 v=0 o=root 374306371 374306373 IN IP4 10.43.100.206 s=Asterisk PBX 1.6.1.0 c=IN IP4 10.43.100.206 t=0 0 m=audio 10354 RTP/AVP 0 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK57470bf0;received=10.43.100.166;rport=5060 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 2 [ 51]: From: ;tag=as54de8de0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 3 [ 60]: To: "000011451" ;tag=as07779a0d [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 6 [ 28]: Server: Asterisk PBX 1.6.1.0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 9 [ 38]: Contact: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 10 [ 29]: Content-Type: application/sdp [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 11 [ 19]: Content-Length: 286 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 12 [ 0]: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 0 [ 3]: v=0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 1 [ 47]: o=root 374306371 374306373 IN IP4 10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 2 [ 22]: s=Asterisk PBX 1.6.1.0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 3 [ 22]: c=IN IP4 10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 4 [ 5]: t=0 0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 5 [ 29]: m=audio 10354 RTP/AVP 0 3 101 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 8 [ 33]: a=rtpmap:101 telephone-event/8000 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 9 [ 15]: a=fmtp:101 0-16 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 10 [ 25]: a=silenceSupp:off - - - - [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Body 12 [ 10]: a=sendrecv [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:40:02] VERBOSE[29565] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> ACK sip:000011451@10.43.100.206 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK19484483;rport From: ;tag=as54de8de0 To: "000011451" ;tag=as07779a0d Contact: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 <-------------> [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 0 [ 39]: ACK sip:000011451@10.43.100.206 SIP/2.0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.166:5060;branch=z9hG4bK19484483;rport [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 2 [ 51]: From: ;tag=as54de8de0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 3 [ 60]: To: "000011451" ;tag=as07779a0d [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 4 [ 35]: Contact: [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 5 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 6 [ 13]: CSeq: 102 ACK [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 7 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: Header 10 [ 0]: [Jun 1 17:40:02] VERBOSE[3631] chan_sip.c: --- (10 headers 0 lines) --- [Jun 1 17:40:02] DEBUG[3631] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jun 1 17:40:02] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:02] VERBOSE[29705] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:03] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:03] VERBOSE[29863] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:03] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:03] VERBOSE[30002] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:03] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:03] VERBOSE[30163] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:04] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:04] VERBOSE[30301] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:04] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:04] VERBOSE[30459] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:04] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:04] VERBOSE[30601] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:05] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:05] VERBOSE[30759] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:05] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:05] VERBOSE[30898] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:05] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:05] VERBOSE[31054] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:06] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:06] VERBOSE[31195] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:06] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:06] VERBOSE[31353] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:06] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:06] VERBOSE[31495] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:07] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:07] VERBOSE[31652] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:07] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:07] VERBOSE[31807] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '2' [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '2' [Jun 1 17:40:07] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:07] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "/home/asterisk/gqtax "1243870762.2" "" "DAHDI/1-1" &") [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '3' [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '3' [Jun 1 17:40:07] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:07] DEBUG[16752] func_strings.c: FUNCTION REGEX ([.])() [Jun 1 17:40:07] DEBUG[16752] pbx.c: Function result is '0' [Jun 1 17:40:07] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?4:7") [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '4' [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '4' [Jun 1 17:40:07] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:07] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "================================== Q U E S T I O N A R I O per ") [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '5' [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '5' [Jun 1 17:40:07] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:07] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "/home/asterisk/questionario "1243870762.2" "" &") [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:07] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '6' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '6' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '7' [Jun 1 17:40:08] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '7' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '8' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '8' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '9' [Jun 1 17:40:08] VERBOSE[32021] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '9' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:10") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '10' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '10' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:11") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '11' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '11' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:12") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '12' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '12' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:13") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '13' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '13' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:14") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '14' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '14' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:15") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '15' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '15' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:16") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '16' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '16' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:17") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '17' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '17' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:18") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '18' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '18' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:19") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '19' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '19' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:20") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '20' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '20' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:21") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '21' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '21' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100:22") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '22' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '22' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '23' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '23' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '24' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '24' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '25' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '25' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '26' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '26' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '27' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '27' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '28' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '28' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '0' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "0?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '29' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '29' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Expression result is '1' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "1?100") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '30' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '30' [Jun 1 17:40:08] VERBOSE[16752] pbx_realtime.c: -- Executing noop("DAHDI/1-1", "local-h-30") [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'local' AND priority = '31' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'local' AND priority = '31' ORDER BY exten [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'default' AND priority = '31' [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[16752] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '31' ORDER BY exten [Jun 1 17:40:08] DEBUG[16752] cdr_addon_mysql.c: Inserting a CDR record. [Jun 1 17:40:08] DEBUG[16752] cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr (uniqueid,calldate,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags) VALUES ('1243870762.2','2009-06-01 17:39:22','11451','ippocode','local','DAHDI/1-1','SIP/astw03.sociale.it-08812850','RetryDial','Retrying','46','7','ANSWERED','3') [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '2009-06-01 17:39:22' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '11451' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'local' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'DAHDI/1-1' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'SIP/astw03.sociale.it-08812850' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'RetryDial' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'Retrying' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '46' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '7' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'ANSWERED' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is 'DOCUMENTATION' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '(null)' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '1243870762.2' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '(null)' [Jun 1 17:40:08] DEBUG[16752] pbx.c: Function result is '(null)' [Jun 1 17:40:08] DEBUG[16752] cdr_sqlite3_custom.c: About to log: INSERT INTO cdr (calldate,clid,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield,test) VALUES ('2009-06-01 17:39:22','11451','local','DAHDI/1-1','SIP/astw03.sociale.it-08812850','RetryDial','Retrying','46','7','ANSWERED','DOCUMENTATION','','1243870762.2','','') [Jun 1 17:40:08] DEBUG[16752] channel.c: Hanging up channel 'SIP/astw03.sociale.it-08812850' [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Hangup call SIP/astw03.sociale.it-08812850, SIP callid 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:08] VERBOSE[16752] chan_sip.c: Scheduling destruction of SIP dialog '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' in 3200 ms (Method: ACK) [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Strict routing enforced for session 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:08] VERBOSE[16752] chan_sip.c: set_destination: Parsing for address/port to send to [Jun 1 17:40:08] VERBOSE[16752] chan_sip.c: set_destination: set destination to 10.43.100.166, port 5060 [Jun 1 17:40:08] VERBOSE[16752] chan_sip.c: Reliably Transmitting (NAT) to 10.43.100.166:5060: BYE sip:pa9405@10.43.100.166 SIP/2.0 Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK5d39799f;rport Max-Forwards: 70 From: "000011451" ;tag=as07779a0d To: ;tag=as07779a0d Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 104 BYE User-Agent: Asterisk PBX 1.6.1.0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 0 [ 36]: BYE sip:pa9405@10.43.100.166 SIP/2.0 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK5d39799f;rport [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 3 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 4 [ 45]: To: ;tag=as07779a0d [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 5 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 6 [ 13]: CSeq: 104 BYE [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 7 [ 32]: User-Agent: Asterisk PBX 1.6.1.0 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 8 [ 39]: X-Asterisk-HangupCause: Normal Clearing [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 9 [ 30]: X-Asterisk-HangupCauseCode: 16 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Header 11 [ 0]: [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #69 [Jun 1 17:40:08] DEBUG[16752] chan_sip.c: Trying to put 'BYE sip:pa' onto UDP socket destined for 10.43.100.166:5060 [Jun 1 17:40:08] DEBUG[16752] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Jun 1 17:40:08] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - astw03.sociale.it [Jun 1 17:40:08] DEBUG[16752] pbx.c: Spawn extension (local,ippocode,15) exited non-zero on 'DAHDI/1-1' [Jun 1 17:40:08] DEBUG[2986] chan_sip.c: Checking device state for peer astw03.sociale.it [Jun 1 17:40:08] VERBOSE[16752] pbx.c: == Spawn extension (local, ippocode, 15) exited non-zero on 'DAHDI/1-1' [Jun 1 17:40:08] DEBUG[16752] channel.c: Soft-Hanging up channel 'DAHDI/1-1' [Jun 1 17:40:08] DEBUG[16752] channel.c: Hanging up channel 'DAHDI/1-1' [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: dahdi_hangup(DAHDI/1-1) [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/1-1 [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Hangup: channel: 1 index = 0, normal = 12, callwait = -1, thirdcall = -1 [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Not yet hungup... Calling hangup once with icause, and clearing call [Jun 1 17:40:08] VERBOSE[3631] chan_sip.c: <--- SIP read from UDP://10.43.100.166:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK5d39799f;received=10.43.100.206;rport=5060 From: "000011451" ;tag=as07779a0d To: ;tag=as07779a0d Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 CSeq: 104 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <-------------> [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 10.43.100.206:5060;branch=z9hG4bK5d39799f;received=10.43.100.206;rport=5060 [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Disabled echo cancellation on channel 1 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 2 [ 62]: From: "000011451" ;tag=as07779a0d [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 3 [ 45]: To: ;tag=as07779a0d [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 4 [ 55]: Call-ID: 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/1-1 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 5 [ 13]: CSeq: 104 BYE [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 6 [ 24]: User-Agent: Asterisk PBX [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 7 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jun 1 17:40:08] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' AND host = 'dynamic' [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 8 [ 19]: Supported: replaces [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 9 [ 35]: Contact: [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Header 11 [ 0]: [Jun 1 17:40:08] VERBOSE[3631] chan_sip.c: --- (11 headers 0 lines) --- [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #69 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Stopping retransmission on '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' of Request 104: Match Found [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: Destroying SIP dialog 79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206 [Jun 1 17:40:08] VERBOSE[3631] chan_sip.c: Really destroying SIP dialog '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' Method: ACK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: ---------- SIP HISTORY for '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: * SIP Call [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 001. NewChan Channel SIP/astw03.sociale.it-08812850 - from 79c5b95a06eb532a0 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 002. TxReqRel INVITE / 102 INVITE - INVITE [Jun 1 17:40:08] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:08] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'astw03.sociale.it' [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 003. Rx SIP/2.0 / 102 INVITE / 100 Trying [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 004. Rx SIP/2.0 / 102 INVITE / 180 Ringing [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 005. Rx SIP/2.0 / 102 INVITE / 183 Session Progress [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 006. Rx SIP/2.0 / 102 INVITE / 200 OK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 007. TxReq ACK / 102 ACK - ACK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 008. Rx INVITE / 102 INVITE / sip:000011451@10.43.100.206 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 009. TxReqRel INVITE / 103 INVITE - INVITE [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 010. Rx SIP/2.0 / 103 INVITE / 491 Request Pending [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 011. TxReq ACK / 103 ACK - ACK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 012. Rx INVITE / 102 INVITE / sip:000011451@10.43.100.206 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 013. TxResp SIP/2.0 / 102 INVITE - 100 Trying [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 014. TxResp SIP/2.0 / 102 INVITE - 200 OK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 015. Rx ACK / 102 ACK / sip:000011451@10.43.100.206 [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 016. Hangup Cause Normal Clearing [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 017. SchedDestroy 3200 ms [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 018. TxReqRel BYE / 104 BYE - BYE [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 019. RTCPaudio Quality: [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: 020. Rx SIP/2.0 / 104 BYE / 200 OK [Jun 1 17:40:08] DEBUG[3631] chan_sip.c: ---------- END SIP HISTORY for '79c5b95a06eb532a0769611b0eaaf2f5@10.43.100.206' [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Updated conferencing on 1, with 0 conference users [Jun 1 17:40:08] DEBUG[16752] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/1-1 [Jun 1 17:40:08] VERBOSE[16752] chan_dahdi.c: -- Hungup 'DAHDI/1-1' [Jun 1 17:40:08] DEBUG[16752] res_monitor.c: monitor executing ( nice -n 19 soxmix "/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2-in.gsm" "/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2-out.gsm" "/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2.gsm" && rm -f "/var/spool/asterisk/monitor/2009/06/01/17.39.55-100-da000011451a1243870762.2-"* ) & [Jun 1 17:40:08] DEBUG[2986] devicestate.c: Changing state for SIP/astw03.sociale.it - state 1 (Not in use) [Jun 1 17:40:08] DEBUG[2986] devicestate.c: device 'SIP/astw03.sociale.it' state '1' [Jun 1 17:40:08] DEBUG[3642] app_queue.c: Device 'SIP/astw03.sociale.it' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:40:08] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for DAHDI - 1 [Jun 1 17:40:08] DEBUG[2986] devicestate.c: Changing state for DAHDI/1 - state 0 (Unknown) [Jun 1 17:40:08] DEBUG[2986] devicestate.c: device 'DAHDI/1' state '0' [Jun 1 17:40:08] DEBUG[3642] app_queue.c: Device 'DAHDI/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Jun 1 17:40:08] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:08] VERBOSE[32162] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:08] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:08] VERBOSE[32325] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:09] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:09] VERBOSE[32465] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:09] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:09] VERBOSE[32626] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:09] DEBUG[3631] chan_sip.c: -REALTIME- peer expired registration. Name: cg11451. Realtime peer objects now 20 [Jun 1 17:40:09] DEBUG[3631] chan_sip.c: Destroying SIP peer cg11451 [Jun 1 17:40:09] DEBUG[3631] chan_sip.c: -REALTIME- peer Destroyed. Name: cg11451. Realtime Peer objects: 19 [Jun 1 17:40:09] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - cg11451 [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: Checking device state for peer cg11451 [Jun 1 17:40:09] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:09] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'cg11451' AND host = 'dynamic' [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: -REALTIME- peer built. Name: cg11451. Peer objects: 20 [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: SIP Seeding peer from astdb: 'cg11451' at cg11451@10.43.2.237:5060 for 120 [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: -REALTIME- loading peer from database to memory. Name: cg11451. Peer objects: 20 [Jun 1 17:40:09] DEBUG[2986] devicestate.c: Changing state for SIP/cg11451 - state 1 (Not in use) [Jun 1 17:40:09] DEBUG[2986] devicestate.c: device 'SIP/cg11451' state '1' [Jun 1 17:40:09] DEBUG[3642] app_queue.c: Device 'SIP/cg11451' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:40:09] DEBUG[2986] devicestate.c: No provider found, checking channel drivers for SIP - cg11451 [Jun 1 17:40:09] DEBUG[3631] chan_sip.c: -REALTIME- peer expired registration. Name: cg11451. Realtime peer objects now 20 [Jun 1 17:40:09] DEBUG[3631] chan_sip.c: Destroying SIP peer cg11451 [Jun 1 17:40:09] DEBUG[3631] chan_sip.c: -REALTIME- peer Destroyed. Name: cg11451. Realtime Peer objects: 19 [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: Checking device state for peer cg11451 [Jun 1 17:40:09] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Connection okay. [Jun 1 17:40:09] DEBUG[2986] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'cg11451' AND host = 'dynamic' [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: -REALTIME- peer built. Name: cg11451. Peer objects: 20 [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: SIP Seeding peer from astdb: 'cg11451' at cg11451@10.43.2.237:5060 for 120 [Jun 1 17:40:09] DEBUG[2986] chan_sip.c: -REALTIME- loading peer from database to memory. Name: cg11451. Peer objects: 20 [Jun 1 17:40:09] DEBUG[2986] devicestate.c: Changing state for SIP/cg11451 - state 1 (Not in use) [Jun 1 17:40:09] DEBUG[2986] devicestate.c: device 'SIP/cg11451' state '1' [Jun 1 17:40:09] DEBUG[3642] app_queue.c: Device 'SIP/cg11451' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 1 17:40:09] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:09] VERBOSE[306] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:10] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:10] VERBOSE[493] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:10] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:10] VERBOSE[633] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:10] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:10] VERBOSE[791] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:11] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:11] VERBOSE[930] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:11] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:11] VERBOSE[1091] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:11] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:11] VERBOSE[1248] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:12] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:12] VERBOSE[1405] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:12] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:12] VERBOSE[1547] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:12] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:12] VERBOSE[1712] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:13] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:13] VERBOSE[1866] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:13] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:13] VERBOSE[2027] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:13] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:13] VERBOSE[2166] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:14] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:14] VERBOSE[2324] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:14] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:14] VERBOSE[2466] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:14] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:14] VERBOSE[2623] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:15] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:15] VERBOSE[2767] asterisk.c: -- Remote UNIX connection disconnected [Jun 1 17:40:15] VERBOSE[2972] asterisk.c: -- Remote UNIX connection [Jun 1 17:40:15] VERBOSE[2941] asterisk.c: -- Remote UNIX connection disconnected