Oct 24 12:13:27 DEBUG[14510] pbx.c: Expression result is '1'
Oct 24 12:13:27 DEBUG[14510] pbx.c: Function result is 's'
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Executing Set("SIP/10.0.3.99-09ec3800", "DID=s") in new stack
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Executing Goto("SIP/10.0.3.99-09ec3800", "s|1") in new stack
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Goto (from-sip-external,s,1)
Oct 24 12:13:27 DEBUG[14510] pbx.c: Expression result is '0'
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Executing GotoIf("SIP/10.0.3.99-09ec3800", "0?from-trunk|s|1") in new stack
Oct 24 12:13:27 DEBUG[14510] pbx.c: Not taking any branch
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Executing Set("SIP/10.0.3.99-09ec3800", "TIMEOUT(absolute)=15") in new stack
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Channel will hangup at 2006-10-24 16:13:42 UTC.
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Executing Answer("SIP/10.0.3.99-09ec3800", "") in new stack
Oct 24 12:13:27 VERBOSE[14510] logger.c: -- Executing Wait("SIP/10.0.3.99-09ec3800", "2") in new stack
Oct 24 12:13:27 DEBUG[14513] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:27 VERBOSE[14513] logger.c: == Spawn extension (from-sip-external, s, 6) exited non-zero on 'SIP/10.0.3.99-09ec6420'
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing NoOp("SIP/10.0.3.99-09ec6420", "Received incoming SIP connection from unknown peer to T") in new stack
Oct 24 12:13:27 DEBUG[14513] pbx.c: Expression result is '1'
Oct 24 12:13:27 DEBUG[14513] pbx.c: Function result is 's'
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing Set("SIP/10.0.3.99-09ec6420", "DID=s") in new stack
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing Goto("SIP/10.0.3.99-09ec6420", "s|1") in new stack
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Goto (from-sip-external,s,1)
Oct 24 12:13:27 DEBUG[14513] pbx.c: Expression result is '0'
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing GotoIf("SIP/10.0.3.99-09ec6420", "0?from-trunk|s|1") in new stack
Oct 24 12:13:27 DEBUG[14513] pbx.c: Not taking any branch
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing Set("SIP/10.0.3.99-09ec6420", "TIMEOUT(absolute)=15") in new stack
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Channel will hangup at 2006-10-24 16:13:42 UTC.
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing Answer("SIP/10.0.3.99-09ec6420", "") in new stack
Oct 24 12:13:27 VERBOSE[14513] logger.c: -- Executing Wait("SIP/10.0.3.99-09ec6420", "2") in new stack
Oct 24 12:13:29 VERBOSE[14510] logger.c: -- Executing Playback("SIP/10.0.3.99-09ec3800", "ss-noservice") in new stack
Oct 24 12:13:29 DEBUG[14510] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:29 VERBOSE[14510] logger.c: -- Playing 'ss-noservice' (language 'en')
Oct 24 12:13:29 VERBOSE[14513] logger.c: -- Executing Playback("SIP/10.0.3.99-09ec6420", "ss-noservice") in new stack
Oct 24 12:13:29 DEBUG[14513] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:29 VERBOSE[14513] logger.c: -- Playing 'ss-noservice' (language 'en')
Oct 24 12:13:32 NOTICE[13914] chan_sip.c: -- Registration for '3527023650@sip.broadvoice.com@sip.broadvoice.com' timed out, trying again (Attempt #72)
Oct 24 12:13:32 DEBUG[13914] chan_sip.c: Stopping retransmission on '2f5b20885d597e52443281fb5abf755e@sip.broadvoice.com' of Request 173: Match Found
Oct 24 12:13:32 DEBUG[13914] chan_sip.c: Scheduled a registration timeout for sip.broadvoice.com id #1440 
Oct 24 12:13:33 DEBUG[14510] channel.c: Scheduling timer at 79 sample intervals
Oct 24 12:13:33 DEBUG[14513] channel.c: Scheduling timer at 79 sample intervals
Oct 24 12:13:33 DEBUG[14510] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:33 DEBUG[14510] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:33 VERBOSE[14510] logger.c: -- Executing Congestion("SIP/10.0.3.99-09ec3800", "") in new stack
Oct 24 12:13:33 DEBUG[14510] channel.c: Driver for channel 'SIP/10.0.3.99-09ec3800' does not support indication 8, emulating it
Oct 24 12:13:33 DEBUG[14510] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:33 DEBUG[14513] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:33 DEBUG[14513] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:33 VERBOSE[14513] logger.c: -- Executing Congestion("SIP/10.0.3.99-09ec6420", "") in new stack
Oct 24 12:13:33 DEBUG[14513] channel.c: Driver for channel 'SIP/10.0.3.99-09ec6420' does not support indication 8, emulating it
Oct 24 12:13:33 DEBUG[14513] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:39 DEBUG[19148] manager.c: Manager received command 'Command'
Oct 24 12:13:39 DEBUG[19148] manager.c: Manager received command 'Command'
Oct 24 12:13:42 DEBUG[14513] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:42 VERBOSE[14513] logger.c: == Spawn extension (from-sip-external, s, 6) exited non-zero on 'SIP/10.0.3.99-09ec6420'
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing NoOp("SIP/10.0.3.99-09ec6420", "Received incoming SIP connection from unknown peer to T") in new stack
Oct 24 12:13:42 DEBUG[14513] pbx.c: Expression result is '1'
Oct 24 12:13:42 DEBUG[14513] pbx.c: Function result is 's'
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing Set("SIP/10.0.3.99-09ec6420", "DID=s") in new stack
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing Goto("SIP/10.0.3.99-09ec6420", "s|1") in new stack
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Goto (from-sip-external,s,1)
Oct 24 12:13:42 DEBUG[14513] pbx.c: Expression result is '0'
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing GotoIf("SIP/10.0.3.99-09ec6420", "0?from-trunk|s|1") in new stack
Oct 24 12:13:42 DEBUG[14513] pbx.c: Not taking any branch
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing Set("SIP/10.0.3.99-09ec6420", "TIMEOUT(absolute)=15") in new stack
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Channel will hangup at 2006-10-24 16:13:57 UTC.
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing Answer("SIP/10.0.3.99-09ec6420", "") in new stack
Oct 24 12:13:42 VERBOSE[14513] logger.c: -- Executing Wait("SIP/10.0.3.99-09ec6420", "2") in new stack
Oct 24 12:13:42 DEBUG[14510] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:42 VERBOSE[14510] logger.c: == Spawn extension (from-sip-external, s, 6) exited non-zero on 'SIP/10.0.3.99-09ec3800'
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing NoOp("SIP/10.0.3.99-09ec3800", "Received incoming SIP connection from unknown peer to T") in new stack
Oct 24 12:13:42 DEBUG[14510] pbx.c: Expression result is '1'
Oct 24 12:13:42 DEBUG[14510] pbx.c: Function result is 's'
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing Set("SIP/10.0.3.99-09ec3800", "DID=s") in new stack
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing Goto("SIP/10.0.3.99-09ec3800", "s|1") in new stack
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Goto (from-sip-external,s,1)
Oct 24 12:13:42 DEBUG[14510] pbx.c: Expression result is '0'
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing GotoIf("SIP/10.0.3.99-09ec3800", "0?from-trunk|s|1") in new stack
Oct 24 12:13:42 DEBUG[14510] pbx.c: Not taking any branch
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing Set("SIP/10.0.3.99-09ec3800", "TIMEOUT(absolute)=15") in new stack
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Channel will hangup at 2006-10-24 16:13:57 UTC.
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing Answer("SIP/10.0.3.99-09ec3800", "") in new stack
Oct 24 12:13:42 VERBOSE[14510] logger.c: -- Executing Wait("SIP/10.0.3.99-09ec3800", "2") in new stack
Oct 24 12:13:44 VERBOSE[14513] logger.c: -- Executing Playback("SIP/10.0.3.99-09ec6420", "ss-noservice") in new stack
Oct 24 12:13:44 DEBUG[14513] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:44 VERBOSE[14513] logger.c: -- Playing 'ss-noservice' (language 'en')
Oct 24 12:13:44 VERBOSE[14510] logger.c: -- Executing Playback("SIP/10.0.3.99-09ec3800", "ss-noservice") in new stack
Oct 24 12:13:44 DEBUG[14510] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:44 VERBOSE[14510] logger.c: -- Playing 'ss-noservice' (language 'en')
Oct 24 12:13:48 DEBUG[14513] channel.c: Scheduling timer at 79 sample intervals
Oct 24 12:13:48 DEBUG[14513] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:48 DEBUG[14513] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:48 VERBOSE[14513] logger.c: -- Executing Congestion("SIP/10.0.3.99-09ec6420", "") in new stack
Oct 24 12:13:48 DEBUG[14513] channel.c: Driver for channel 'SIP/10.0.3.99-09ec6420' does not support indication 8, emulating it
Oct 24 12:13:48 DEBUG[14513] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:48 DEBUG[14510] channel.c: Scheduling timer at 79 sample intervals
Oct 24 12:13:48 DEBUG[14510] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:48 DEBUG[14510] channel.c: Scheduling timer at 0 sample intervals
Oct 24 12:13:48 VERBOSE[14510] logger.c: -- Executing Congestion("SIP/10.0.3.99-09ec3800", "") in new stack
Oct 24 12:13:48 DEBUG[14510] channel.c: Driver for channel 'SIP/10.0.3.99-09ec3800' does not support indication 8, emulating it
Oct 24 12:13:48 DEBUG[14510] channel.c: Scheduling timer at 160 sample intervals
Oct 24 12:13:48 DEBUG[14510] channel.c: Scheduling timer at 160 sample intervals