[Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x2697cc8 - Created CDR for channel SIP/valerio-00000000 [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x2697cc8 - Transitioning CDR for SIP/valerio-00000000 from state NONE to Single [Oct 17 17:51:45] VERBOSE[4444][C-00000000] pbx.c: -- Executing [106@ebali_segreteria:1] Macro("SIP/valerio-00000000", "voicemail,SIP/fabiana") in new stack [Oct 17 17:51:45] VERBOSE[4444][C-00000000] pbx.c: -- Executing [s@macro-voicemail:1] SIPAddHeader("SIP/valerio-00000000", ""Alert-Info: ;info=alert-internal;x-line-id=3"") in new stack [Oct 17 17:51:45] VERBOSE[4444][C-00000000] pbx.c: -- Executing [s@macro-voicemail:2] Dial("SIP/valerio-00000000", "SIP/fabiana,20,rtwW") in new stack [Oct 17 17:51:45] VERBOSE[4444][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x24a1308 - Created CDR for channel SIP/fabiana-00000001 [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x24a1308 - Transitioning CDR for SIP/fabiana-00000001 from state NONE to Single [Oct 17 17:51:45] VERBOSE[4444][C-00000000] app_dial.c: -- Called SIP/fabiana [Oct 17 17:51:45] VERBOSE[4314] cdr.c: Dial Begin message for SIP/valerio-00000000, SIP/fabiana-00000001: 1413561105.00669824 [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x2697cc8 - Processing Dial Begin message for channel SIP/valerio-00000000, peer SIP/fabiana-00000001 [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x2697cc8 - Updated Party A SIP/valerio-00000000 snapshot [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x2697cc8 - Updated Party B SIP/fabiana-00000001 snapshot [Oct 17 17:51:45] VERBOSE[4314] cdr.c: 0x2697cc8 - Transitioning CDR for SIP/valerio-00000000 from state Single to Dial [Oct 17 17:51:45] VERBOSE[4444][C-00000000] app_dial.c: -- SIP/fabiana-00000001 is ringing [Oct 17 17:51:46] VERBOSE[4444][C-00000000] app_dial.c: -- SIP/fabiana-00000001 is ringing [Oct 17 17:51:46] WARNING[4406][C-00000000] chan_sip.c: Ignoring unexpected crypto attribute in SDP answer [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x24a1308 - Set answered time to 1413561106.829379 [Oct 17 17:51:46] VERBOSE[4444][C-00000000] app_dial.c: -- SIP/fabiana-00000001 answered SIP/valerio-00000000 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: Dial End message for SIP/valerio-00000000, SIP/fabiana-00000001: 1413561106.00829861 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Processing Dial End message for channel SIP/valerio-00000000, peer SIP/fabiana-00000001 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Transitioning CDR for SIP/valerio-00000000 from state Dial to DialedPending [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Set answered time to 1413561106.830249 [Oct 17 17:51:46] VERBOSE[4444][C-00000000] bridge_channel.c: -- Channel SIP/valerio-00000000 joined 'simple_bridge' basic-bridge <1bc873fd-8632-4d64-8318-b445a78c6ad9> [Oct 17 17:51:46] VERBOSE[4445][C-00000000] bridge_channel.c: -- Channel SIP/fabiana-00000001 joined 'simple_bridge' basic-bridge <1bc873fd-8632-4d64-8318-b445a78c6ad9> [Oct 17 17:51:46] VERBOSE[4314] cdr.c: Bridge Enter message for channel SIP/valerio-00000000: 1413561106.00831316 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Updating Party A SIP/valerio-00000000 snapshot [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Processing bridge enter for SIP/valerio-00000000 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Transitioning CDR for SIP/valerio-00000000 from state DialedPending to Dial [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Transitioning CDR for SIP/valerio-00000000 from state Dial to Bridged [Oct 17 17:51:46] VERBOSE[4314] cdr.c: Bridge Enter message for channel SIP/fabiana-00000001: 1413561106.00831694 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x24a1308 - Updating Party A SIP/fabiana-00000001 snapshot [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x24a1308 - Processing bridge enter for SIP/fabiana-00000001 [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x24a1308 - Transitioning CDR for SIP/fabiana-00000001 from state Single to Bridged [Oct 17 17:51:46] VERBOSE[4314] cdr.c: 0x2697cc8 - Party A SIP/valerio-00000000 has new Party B SIP/fabiana-00000001 [Oct 17 17:51:46] VERBOSE[4445][C-00000000] res_rtp_asterisk.c: > 0x7ff2c40c4d90 -- Probation passed - setting RTP source address to 192.168.2.161:64318 [Oct 17 17:51:46] VERBOSE[4444][C-00000000] res_rtp_asterisk.c: > 0x7ff2880775d0 -- Probation passed - setting RTP source address to 192.168.2.146:55052 [Oct 17 17:51:58] VERBOSE[4444][C-00000000] res_musiconhold.c: -- Started music on hold, class 'default', on channel 'SIP/valerio-00000000' [Oct 17 17:52:01] VERBOSE[4406][C-00000001] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Created CDR for channel SIP/fabiana-00000002 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Transitioning CDR for SIP/fabiana-00000002 from state NONE to Single [Oct 17 17:52:01] VERBOSE[4446][C-00000001] pbx.c: -- Executing [129@aral_locali:1] Macro("SIP/fabiana-00000002", "voicemail,SIP/francesca") in new stack [Oct 17 17:52:01] VERBOSE[4446][C-00000001] pbx.c: -- Executing [s@macro-voicemail:1] SIPAddHeader("SIP/fabiana-00000002", ""Alert-Info: ;info=alert-internal;x-line-id=3"") in new stack [Oct 17 17:52:01] VERBOSE[4446][C-00000001] pbx.c: -- Executing [s@macro-voicemail:2] Dial("SIP/fabiana-00000002", "SIP/francesca,20,rtwW") in new stack [Oct 17 17:52:01] VERBOSE[4446][C-00000001] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Created CDR for channel SIP/francesca-00000003 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Transitioning CDR for SIP/francesca-00000003 from state NONE to Single [Oct 17 17:52:01] VERBOSE[4314] cdr.c: Dial Begin message for SIP/fabiana-00000002, SIP/francesca-00000003: 1413561121.00035991 [Oct 17 17:52:01] VERBOSE[4446][C-00000001] app_dial.c: -- Called SIP/francesca [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Processing Dial Begin message for channel SIP/fabiana-00000002, peer SIP/francesca-00000003 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Updated Party A SIP/fabiana-00000002 snapshot [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Updated Party B SIP/francesca-00000003 snapshot [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Transitioning CDR for SIP/fabiana-00000002 from state Single to Dial [Oct 17 17:52:01] VERBOSE[4446][C-00000001] app_dial.c: -- SIP/francesca-00000003 is ringing [Oct 17 17:52:01] VERBOSE[4446][C-00000001] app_dial.c: -- SIP/francesca-00000003 is ringing [Oct 17 17:52:01] WARNING[4406][C-00000001] chan_sip.c: Ignoring unexpected crypto attribute in SDP answer [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Set answered time to 1413561121.808691 [Oct 17 17:52:01] VERBOSE[4446][C-00000001] app_dial.c: -- SIP/francesca-00000003 answered SIP/fabiana-00000002 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: Dial End message for SIP/fabiana-00000002, SIP/francesca-00000003: 1413561121.00809162 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Processing Dial End message for channel SIP/fabiana-00000002, peer SIP/francesca-00000003 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Transitioning CDR for SIP/fabiana-00000002 from state Dial to DialedPending [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Set answered time to 1413561121.809354 [Oct 17 17:52:01] VERBOSE[4447][C-00000001] bridge_channel.c: -- Channel SIP/francesca-00000003 joined 'simple_bridge' basic-bridge [Oct 17 17:52:01] VERBOSE[4314] cdr.c: Bridge Enter message for channel SIP/francesca-00000003: 1413561121.00810435 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Updating Party A SIP/francesca-00000003 snapshot [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Processing bridge enter for SIP/francesca-00000003 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Transitioning CDR for SIP/francesca-00000003 from state Single to Bridged [Oct 17 17:52:01] VERBOSE[4446][C-00000001] bridge_channel.c: -- Channel SIP/fabiana-00000002 joined 'simple_bridge' basic-bridge [Oct 17 17:52:01] VERBOSE[4314] cdr.c: Bridge Enter message for channel SIP/fabiana-00000002: 1413561121.00811021 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Updating Party A SIP/fabiana-00000002 snapshot [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Processing bridge enter for SIP/fabiana-00000002 [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Transitioning CDR for SIP/fabiana-00000002 from state DialedPending to Dial [Oct 17 17:52:01] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Transitioning CDR for SIP/fabiana-00000002 from state Dial to Bridged [Oct 17 17:52:01] VERBOSE[4447][C-00000001] res_rtp_asterisk.c: > 0x25e4970 -- Probation passed - setting RTP source address to 192.168.2.129:64420 [Oct 17 17:52:01] VERBOSE[4446][C-00000001] res_rtp_asterisk.c: > 0x24baef0 -- Probation passed - setting RTP source address to 192.168.2.161:63434 [Oct 17 17:52:08] VERBOSE[4447][C-00000001] res_musiconhold.c: -- Started music on hold, class 'default', on channel 'SIP/francesca-00000003' [Oct 17 17:52:10] VERBOSE[4444][C-00000000] res_musiconhold.c: -- Stopped music on hold on SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4447][C-00000001] res_musiconhold.c: -- Stopped music on hold on SIP/francesca-00000003 [Oct 17 17:52:10] VERBOSE[4406][C-00000000] bridge_channel.c: -- Channel SIP/valerio-00000000 left 'simple_bridge' basic-bridge <1bc873fd-8632-4d64-8318-b445a78c6ad9> [Oct 17 17:52:10] VERBOSE[4314] cdr.c: Bridge Leave message for SIP/valerio-00000000: 1413561130.00155721 [Oct 17 17:52:10] VERBOSE[4406][C-00000000] bridge_channel.c: -- Channel SIP/valerio-00000000 swapped with SIP/fabiana-00000002 into 'simple_bridge' basic-bridge [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x2697cc8 - Processing Bridge Leave for SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x2697cc8 - Transitioning CDR for SIP/valerio-00000000 from state Bridged to Finalized [Oct 17 17:52:10] VERBOSE[4406][C-00000000] bridge_channel.c: -- Channel SIP/fabiana-00000002 left 'simple_bridge' basic-bridge [Oct 17 17:52:10] VERBOSE[4314] cdr.c: Bridge Enter message for channel SIP/valerio-00000000: 1413561130.00155918 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x2697cc8 - Updating Party A SIP/valerio-00000000 snapshot [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x2697cc8 - Processing bridge enter for SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Created CDR for channel SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Transitioning CDR for SIP/valerio-00000000 from state NONE to Single [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Set answered time to 1413561130.156003 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x2697cc8 - Updating Party A SIP/valerio-00000000 snapshot [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x2697cc8 - Processing bridge enter for SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Updating Party A SIP/valerio-00000000 snapshot [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Processing bridge enter for SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Party A SIP/valerio-00000000 has new Party B SIP/francesca-00000003 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Transitioning CDR for SIP/valerio-00000000 from state Single to Bridged [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ef238 - Created CDR for channel SIP/valerio-00000000 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ef238 - Transitioning CDR for SIP/valerio-00000000 from state NONE to Single [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ef238 - Set answered time to 1413561130.156085 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ef238 - Transitioning CDR for SIP/valerio-00000000 from state Single to Bridged [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ef238 - Party A SIP/valerio-00000000 has new Party B SIP/fabiana-00000002 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: Bridge Leave message for SIP/fabiana-00000002: 1413561130.00155971 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Processing Bridge Leave for SIP/fabiana-00000002 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Transitioning CDR for SIP/fabiana-00000002 from state Bridged to Finalized [Oct 17 17:52:10] VERBOSE[4445][C-00000000] bridge_channel.c: -- Channel SIP/fabiana-00000001 left 'simple_bridge' basic-bridge <1bc873fd-8632-4d64-8318-b445a78c6ad9> [Oct 17 17:52:10] VERBOSE[4446][C-00000001] app_macro.c: == Spawn extension (macro-voicemail, s, 2) exited non-zero on 'SIP/fabiana-00000002' in macro 'voicemail' [Oct 17 17:52:10] VERBOSE[4446][C-00000001] pbx.c: == Spawn extension (aral_locali, 129, 1) exited non-zero on 'SIP/fabiana-00000002' [Oct 17 17:52:10] VERBOSE[4314] cdr.c: Bridge Leave message for SIP/fabiana-00000001: 1413561130.00156724 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x24a1308 - Processing Bridge Leave for SIP/fabiana-00000001 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x24a1308 - Transitioning CDR for SIP/fabiana-00000001 from state Bridged to Finalized [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x24a1308 - Beginning finalize/dispatch for SIP/fabiana-00000001 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x24a1308 - Dispatching CDR for Party A SIP/fabiana-00000001, Party B [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ef238 - Transitioning CDR for SIP/valerio-00000000 from state Bridged to Finalized [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Beginning finalize/dispatch for SIP/fabiana-00000002 [Oct 17 17:52:10] VERBOSE[4314] cdr.c: 0x7ff2880ebc78 - Dispatching CDR for Party A SIP/fabiana-00000002, Party B SIP/francesca-00000003 [Oct 17 17:52:10] VERBOSE[4314] cdr_adaptive_odbc.c: > [INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid) VALUES ({ ts '2014-10-17 17:52:01' },'"fabiana" ','fabiana','129','aral_locali','SIP/fabiana-00000002','SIP/francesca-00000003','Dial','SIP/francesca,20,rtwW',9,8,'ANSWERED',3,'1413561121.2')] [Oct 17 17:52:14] VERBOSE[4447][C-00000001] bridge_channel.c: -- Channel SIP/francesca-00000003 left 'simple_bridge' basic-bridge [Oct 17 17:52:14] VERBOSE[4314] cdr.c: Bridge Leave message for SIP/francesca-00000003: 1413561134.00948365 [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Processing Bridge Leave for SIP/francesca-00000003 [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Transitioning CDR for SIP/francesca-00000003 from state Bridged to Finalized [Oct 17 17:52:14] VERBOSE[4444][C-00000000] bridge_channel.c: -- Channel SIP/valerio-00000000 left 'simple_bridge' basic-bridge [Oct 17 17:52:14] VERBOSE[4314] cdr.c: Bridge Leave message for SIP/valerio-00000000: 1413561134.00948874 [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Processing Bridge Leave for SIP/valerio-00000000 [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x7ff2880f4d08 - Transitioning CDR for SIP/valerio-00000000 from state Bridged to Finalized [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Beginning finalize/dispatch for SIP/francesca-00000003 [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x7ff2880e26e8 - Dispatching CDR for Party A SIP/francesca-00000003, Party B [Oct 17 17:52:14] VERBOSE[4444][C-00000000] app_macro.c: == Spawn extension (macro-voicemail, s, 2) exited non-zero on 'SIP/valerio-00000000' in macro 'voicemail' [Oct 17 17:52:14] VERBOSE[4444][C-00000000] pbx.c: == Spawn extension (ebali_segreteria, 106, 1) exited non-zero on 'SIP/valerio-00000000' [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x2697cc8 - Beginning finalize/dispatch for SIP/valerio-00000000 [Oct 17 17:52:14] VERBOSE[4314] cdr.c: 0x2697cc8 - Dispatching CDR for Party A SIP/valerio-00000000, Party B SIP/fabiana-00000001 [Oct 17 17:52:14] VERBOSE[4314] cdr_adaptive_odbc.c: > [INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid) VALUES ({ ts '2014-10-17 17:51:45' },'"valerio" ','valerio','106','ebali_segreteria','SIP/valerio-00000000','SIP/fabiana-00000001','Dial','SIP/fabiana,20,rtwW',24,23,'ANSWERED',3,'1413561105.0')] [Oct 17 17:52:15] VERBOSE[4314] cdr_adaptive_odbc.c: > [INSERT INTO cdr (calldate,clid,src,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid) VALUES ({ ts '2014-10-17 17:52:10' },'"valerio" ','valerio','SIP/valerio-00000000','SIP/francesca-00000003','Dial','SIP/fabiana,20,rtwW',4,4,'ANSWERED',3,'1413561105.0')] [Oct 17 17:52:15] VERBOSE[4314] cdr_adaptive_odbc.c: > [INSERT INTO cdr (calldate,clid,src,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid) VALUES ({ ts '2014-10-17 17:52:10' },'"valerio" ','valerio','SIP/valerio-00000000','SIP/fabiana-00000002','Dial','SIP/fabiana,20,rtwW',0,0,'ANSWERED',3,'1413561105.0')] [Oct 17 17:52:24] VERBOSE[4443] asterisk.c: -- Remote UNIX connection disconnected