[Jun 18 11:50:06] WARNING[4883] rtp.c: Unable to set TOS to 184
[Jun 18 11:50:06] VERBOSE[5139] logger.c:  [] CALL FOR: CUST 10 [*71423182205682*] [7142318220]
[Jun 18 11:50:06] DEBUG[5139] chan_zap.c: Dialing '*7142318220*5682*'
[Jun 18 11:50:06] DEBUG[5139] chan_zap.c: Deferring dialing...
[Jun 18 11:50:07] DEBUG[5139] chan_zap.c: Ignoring wink on channel 128
[Jun 18 11:50:07] DEBUG[5139] chan_zap.c: Sent deferred digit string: T*7142318220*5682
[Jun 18 11:50:10] DEBUG[5139] chan_zap.c: Engaged echo training on channel 128
[Jun 18 11:50:12] DEBUG[5139] chan_zap.c: Echo cancellation already on
[Jun 18 11:50:49] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:50:49] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:50:52] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:50:52] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:50:53] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:50:53] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:51:55] WARNING[4883] rtp.c: Unable to set TOS to 184
[Jun 18 11:51:55] VERBOSE[5147] logger.c:  [5671-67.] ROUTE TO: CUST 10 [*6179143400*5671*] [MASSACHUSETTS] COUNT:1
[Jun 18 11:51:55] DEBUG[5147] chan_zap.c: Dialing '*6179143400*5671*'
[Jun 18 11:51:55] DEBUG[5147] chan_zap.c: Deferring dialing...
[Jun 18 11:51:55] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:51:55] DEBUG[5147] chan_zap.c: Ignoring wink on channel 129
[Jun 18 11:51:55] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:51:55] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:51:55] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:51:56] DEBUG[5147] chan_zap.c: Sent deferred digit string: T*6179143400*5671
[Jun 18 11:51:56] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:51:56] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:51:56] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:51:57] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:51:57] DEBUG[5139] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:51:57] DEBUG[5139] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:51:59] DEBUG[5147] chan_zap.c: Engaged echo training on channel 129
[Jun 18 11:51:59] VERBOSE[5139] logger.c:  [5682-inn] HANGUP CID:[7142318220] IVR:[5682] EXTEN:[h] STAT:[-ANSWER] [16]
[Jun 18 11:51:59] VERBOSE[5139] logger.c:  [5682-inn] HANGUP: [7142318220*5682*] [ANA-CA-NEXTEL CO] COUNT:1 DUR:113
[Jun 18 11:51:59] WARNING[5139] file.c: Failed to write frame
[Jun 18 11:51:59] WARNING[5139] app_playback.c: ast_streamfile failed on SIP/innovative-b7e00468 for vm-goodbye
[Jun 18 11:51:59] DEBUG[5139] app_macro.c: Executed application: ResetCDR
[Jun 18 11:51:59] DEBUG[5139] app_macro.c: Executed application: NoCDR
[Jun 18 11:51:59] DEBUG[5139] app_macro.c: Executed application: GotoIf
[Jun 18 11:51:59] DEBUG[5139] app_macro.c: Executed application: GotoIf
[Jun 18 11:51:59] DEBUG[5139] app_macro.c: Executed application: GotoIf
[Jun 18 11:51:59] DEBUG[5139] res_monitor.c: monitor executing ( nice -n 19 soxmix "/var/spool/asterisk/monitor/5682-7142318220--in.wav" "/var/spool/asterisk/monitor/5682-7142318220--out.wav" "/var/spool/asterisk/monitor/5682-7142318220-.wav"  && rm -f "/var/spool/asterisk/monitor/5682-7142318220--"* ) &
[Jun 18 11:52:01] DEBUG[5147] chan_zap.c: Echo cancellation already on
[Jun 18 11:52:13] DEBUG[5147] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:52:13] DEBUG[5147] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:52:15] DEBUG[5147] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:52:15] DEBUG[5147] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:52:19] DEBUG[5147] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:52:19] DEBUG[5147] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:52:24] DEBUG[5147] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:52:24] DEBUG[5147] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:52:29] DEBUG[5147] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:52:29] DEBUG[5147] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:52:33] VERBOSE[5147] logger.c:  [5671-67.] HANGUP CID:[6179143400] IVR:[5671] EXTEN:[h] STAT:[-ANSWER] [16]
[Jun 18 11:52:33] VERBOSE[5147] logger.c:  [5671-67.] HANGUP: [6179143400*5671*] [MASSACHUSETTS] COUNT:1 DUR:38
[Jun 18 11:52:33] WARNING[5147] file.c: Failed to write frame
[Jun 18 11:52:33] WARNING[5147] app_playback.c: ast_streamfile failed on SIP/67.154.128.68-b7e05388 for vm-goodbye
[Jun 18 11:52:33] DEBUG[5147] app_macro.c: Executed application: ResetCDR
[Jun 18 11:52:33] DEBUG[5147] app_macro.c: Executed application: NoCDR
[Jun 18 11:52:33] DEBUG[5147] app_macro.c: Executed application: GotoIf
[Jun 18 11:52:33] DEBUG[5147] app_macro.c: Executed application: GotoIf
[Jun 18 11:52:33] DEBUG[5147] app_macro.c: Executed application: GotoIf
[Jun 18 11:52:52] WARNING[4883] rtp.c: Unable to set TOS to 184
[Jun 18 11:52:52] VERBOSE[5155] logger.c:  [5671-67.] ROUTE TO: CUST 10 [*6179143400*5671*] [MASSACHUSETTS] COUNT:1
[Jun 18 11:52:52] DEBUG[5155] chan_zap.c: Dialing '*6179143400*5671*'
[Jun 18 11:52:52] DEBUG[5155] chan_zap.c: Deferring dialing...
[Jun 18 11:52:52] DEBUG[5155] chan_zap.c: Ignoring wink on channel 130
[Jun 18 11:52:53] DEBUG[5155] chan_zap.c: Sent deferred digit string: T*6179143400*5671
[Jun 18 11:52:56] DEBUG[5155] chan_zap.c: Engaged echo training on channel 130
[Jun 18 11:52:58] DEBUG[5155] chan_zap.c: Echo cancellation already on
[Jun 18 11:53:09] DEBUG[5155] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:53:09] DEBUG[5155] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:53:14] DEBUG[5155] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:53:14] DEBUG[5155] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:53:30] VERBOSE[5155] logger.c:  [5671-67.] HANGUP CID:[6179143400] IVR:[5671] EXTEN:[h] STAT:[-ANSWER] [16]
[Jun 18 11:53:30] VERBOSE[5155] logger.c:  [5671-67.] HANGUP: [6179143400*5671*] [MASSACHUSETTS] COUNT:1 DUR:38
[Jun 18 11:53:30] WARNING[5155] file.c: Failed to write frame
[Jun 18 11:53:30] WARNING[5155] app_playback.c: ast_streamfile failed on SIP/67.154.128.68-b7e00468 for vm-goodbye
[Jun 18 11:53:30] DEBUG[5155] app_macro.c: Executed application: ResetCDR
[Jun 18 11:53:30] DEBUG[5155] app_macro.c: Executed application: NoCDR
[Jun 18 11:53:30] DEBUG[5155] app_macro.c: Executed application: GotoIf
[Jun 18 11:53:30] DEBUG[5155] app_macro.c: Executed application: GotoIf
[Jun 18 11:53:30] DEBUG[5155] app_macro.c: Executed application: GotoIf
[Jun 18 11:53:55] WARNING[4883] rtp.c: Unable to set TOS to 184
[Jun 18 11:53:55] VERBOSE[5161] logger.c:  [5671-67.] ROUTE TO: CUST 10 [*6179143400*5671*] [MASSACHUSETTS] COUNT:1
[Jun 18 11:53:55] DEBUG[5161] chan_zap.c: Dialing '*6179143400*5671*'
[Jun 18 11:53:55] DEBUG[5161] chan_zap.c: Deferring dialing...
[Jun 18 11:53:55] DEBUG[5161] chan_zap.c: Ignoring wink on channel 131
[Jun 18 11:53:56] DEBUG[5161] chan_zap.c: Sent deferred digit string: T*6179143400*5671
[Jun 18 11:53:59] DEBUG[5161] chan_zap.c: Engaged echo training on channel 131
[Jun 18 11:54:01] DEBUG[5161] chan_zap.c: Echo cancellation already on
[Jun 18 11:54:12] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:12] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:15] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:15] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:19] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:19] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:21] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:21] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:22] DEBUG[5161] chan_zap.c: Started VLDTMF digit '0'
[Jun 18 11:54:22] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '0'
[Jun 18 11:54:23] DEBUG[5161] chan_zap.c: Started VLDTMF digit '#'
[Jun 18 11:54:23] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '#'
[Jun 18 11:54:27] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:27] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:28] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:28] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:28] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:28] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:29] DEBUG[5161] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:54:29] DEBUG[5161] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:54:33] VERBOSE[5161] logger.c:  [5671-67.] HANGUP CID:[6179143400] IVR:[5671] EXTEN:[h] STAT:[-ANSWER] [16]
[Jun 18 11:54:33] VERBOSE[5161] logger.c:  [5671-67.] HANGUP: [6179143400*5671*] [MASSACHUSETTS] COUNT:1 DUR:38
[Jun 18 11:54:33] WARNING[5161] file.c: Failed to write frame
[Jun 18 11:54:33] WARNING[5161] app_playback.c: ast_streamfile failed on SIP/67.154.128.68-b7e00468 for vm-goodbye
[Jun 18 11:54:33] DEBUG[5161] app_macro.c: Executed application: ResetCDR
[Jun 18 11:54:33] DEBUG[5161] app_macro.c: Executed application: NoCDR
[Jun 18 11:54:33] DEBUG[5161] app_macro.c: Executed application: GotoIf
[Jun 18 11:54:33] DEBUG[5161] app_macro.c: Executed application: GotoIf
[Jun 18 11:54:33] DEBUG[5161] app_macro.c: Executed application: GotoIf
[Jun 18 11:54:39] WARNING[4883] rtp.c: Unable to set TOS to 184
[Jun 18 11:54:39] VERBOSE[5167] logger.c:  [5671-67.] ROUTE TO: CUST 10 [*6179143400*5671*] [MASSACHUSETTS] COUNT:1
[Jun 18 11:54:39] DEBUG[5167] chan_zap.c: Dialing '*6179143400*5671*'
[Jun 18 11:54:39] DEBUG[5167] chan_zap.c: Deferring dialing...
[Jun 18 11:54:40] DEBUG[5167] chan_zap.c: Ignoring wink on channel 132
[Jun 18 11:54:40] DEBUG[5167] chan_zap.c: Sent deferred digit string: T*6179143400*5671
[Jun 18 11:54:43] DEBUG[5167] chan_zap.c: Engaged echo training on channel 132
[Jun 18 11:54:46] DEBUG[5167] chan_zap.c: Echo cancellation already on
[Jun 18 11:55:00] DEBUG[5167] chan_zap.c: Started VLDTMF digit '2'
[Jun 18 11:55:00] DEBUG[5167] chan_zap.c: Ending VLDTMF digit '2'
[Jun 18 11:55:11] DEBUG[5167] chan_zap.c: Started VLDTMF digit '2'
[Jun 18 11:55:11] DEBUG[5167] chan_zap.c: Ending VLDTMF digit '2'
[Jun 18 11:55:13] DEBUG[5167] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:55:13] DEBUG[5167] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:55:17] VERBOSE[5167] logger.c:  [5671-67.] HANGUP CID:[6179143400] IVR:[5671] EXTEN:[h] STAT:[-ANSWER] [16]
[Jun 18 11:55:17] VERBOSE[5167] logger.c:  [5671-67.] HANGUP: [6179143400*5671*] [MASSACHUSETTS] COUNT:1 DUR:38
[Jun 18 11:55:17] WARNING[5167] file.c: Failed to write frame
[Jun 18 11:55:17] WARNING[5167] app_playback.c: ast_streamfile failed on SIP/67.154.128.68-b7e00468 for vm-goodbye
[Jun 18 11:55:17] DEBUG[5167] app_macro.c: Executed application: ResetCDR
[Jun 18 11:55:17] DEBUG[5167] app_macro.c: Executed application: NoCDR
[Jun 18 11:55:17] DEBUG[5167] app_macro.c: Executed application: GotoIf
[Jun 18 11:55:17] DEBUG[5167] app_macro.c: Executed application: GotoIf
[Jun 18 11:55:17] DEBUG[5167] app_macro.c: Executed application: GotoIf
[Jun 18 11:55:53] WARNING[4883] rtp.c: Unable to set TOS to 184
[Jun 18 11:55:57] VERBOSE[5173] logger.c:  [5671-67.] ROUTE TO: CUST 10 [*4133305182*5671*] [SPR-MA-OMNIPOINT] COUNT:1
[Jun 18 11:55:57] DEBUG[5173] chan_zap.c: Dialing '*4133305182*5671*'
[Jun 18 11:55:57] DEBUG[5173] chan_zap.c: Deferring dialing...
[Jun 18 11:55:57] DEBUG[5173] chan_zap.c: Ignoring wink on channel 133
[Jun 18 11:55:58] DEBUG[5173] chan_zap.c: Sent deferred digit string: T*4133305182*5671
[Jun 18 11:56:01] DEBUG[5173] chan_zap.c: Engaged echo training on channel 133
[Jun 18 11:56:03] DEBUG[5173] chan_zap.c: Echo cancellation already on
[Jun 18 11:56:16] DEBUG[5173] chan_zap.c: Started VLDTMF digit '1'
[Jun 18 11:56:16] DEBUG[5173] chan_zap.c: Ending VLDTMF digit '1'
[Jun 18 11:56:24] VERBOSE[5173] logger.c:  [5671-67.] HANGUP CID:[4133305182] IVR:[5671] EXTEN:[h] STAT:[-ANSWER] [16]
[Jun 18 11:56:24] VERBOSE[5173] logger.c:  [5671-67.] HANGUP: [4133305182*5671*] [SPR-MA-OMNIPOINT] COUNT:1 DUR:31
[Jun 18 11:56:24] WARNING[5173] file.c: Failed to write frame
[Jun 18 11:56:24] WARNING[5173] app_playback.c: ast_streamfile failed on SIP/67.154.128.68-b7e01c68 for vm-goodbye