Not sure what happened here, but logs show weird activity - HELP Please

[completetech]

So long story short. I was looking at the CLI: asterisk -r to see if a remote extension was connecting to the box and the weird thing is that right after that a call came in (this has happened before) and I was not able to pickup the call. i.e. the deskphone, and two WiFi phones sitting on my desk rang one super short ring and then the call was disconnected.

When I goto the GUI and look at the reports for the call log it shows that the call had a destination of "s"

I am a little lost. what could be happening here?


Thanks,
Nathan





dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 702 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 702
-- dialparties.agi: dbset CALLTRACE/702 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/703 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/704 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/705 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/706 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/707 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 708 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 708
-- dialparties.agi: dbset CALLTRACE/708 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 709 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 709
-- dialparties.agi: dbset CALLTRACE/709 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 710 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 710
-- dialparties.agi: dbset CALLTRACE/710 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 711 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 711
-- dialparties.agi: dbset CALLTRACE/711 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/712 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 713 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 713
-- dialparties.agi: dbset CALLTRACE/713 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 714 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 714
-- dialparties.agi: dbset CALLTRACE/714 to NumberCallingMe
dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
dialparties.agi: Extension 715 has ExtensionState: 4
-- dialparties.agi: Checking CW and CFB status for extension 715
-- dialparties.agi: dbset CALLTRACE/715 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/501 to NumberCallingMe
-- dialparties.agi: dbset CALLTRACE/502 to NumberCallingMe
-- dialparties.agi: Filtered ARG3: 701-702-703-704-705-706-707-708-709-710-711-712-713-714-715-501-502
-- <SIP/didforsale2-0000003c>AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("SIP/didforsale2-0000003c", "SIP/701&SIP/703&SIP/704&SIP/705&SIP/706&SIP/707&SIP/712&SIP/501&SIP/502,28,m(default)twTRWM(auto-blkvm)") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called 701
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called 703
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called 705
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called 706
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called 707
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called 502
-- Started music on hold, class 'default', on SIP/didforsale2-0000003c
-- SIP/706-00000040 is ringing
-- SIP/502-00000042 is ringing
-- Got SIP response 302 "Moved Temporarily" back from 192.168.1.1:1029
-- Now forwarding SIP/didforsale2-0000003c to 'SIP/0016163229621::::UDP@MyIP' (thanks to SIP/502-00000042)
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Executing [0016163229621@from-sip-external:1] NoOp("SIP/MyIP-00000044", "Received incoming SIP connection from unknown peer to 0016163229621") in new stack
-- Executing [0016163229621@from-sip-external:2] Set("SIP/MyIP-00000044", "DID=0016163229621") in new stack
-- Executing [0016163229621@from-sip-external:3] Goto("SIP/MyIP-00000044", "s,1") in new stack
-- Goto (from-sip-external,s,1)
-- Executing [s@from-sip-external:1] GotoIf("SIP/MyIP-00000044", "0?from-trunk,0016163229621,1") in new stack
-- Executing [s@from-sip-external:2] Set("SIP/MyIP-00000044", "TIMEOUT(absolute)=15") in new stack
Channel will hangup at 2011-07-25 17:18:22.449 EDT.
-- Executing [s@from-sip-external:3] Answer("SIP/MyIP-00000044", "") in new stack
-- SIP/MyIP-00000043 connected line has changed. Saving it until answer for SIP/didforsale2-0000003c
-- SIP/MyIP-00000043 answered SIP/didforsale2-0000003c
-- Executing [s@macro-auto-blkvm:1] Set("SIP/MyIP-00000043", "__MACRO_RESULT=") in new stack
-- Executing [s@macro-auto-blkvm:2] NoOp("SIP/MyIP-00000043", "Deleting: BLKVM/777/SIP/didforsale2-0000003c TRUE") in new stack
-- Stopped music on hold on SIP/didforsale2-0000003c
-- Executing [s@from-sip-external:4] Wait("SIP/MyIP-00000044", "2") in new stack
-- Executing [s@from-sip-external:5] Playback("SIP/MyIP-00000044", "ss-noservice") in new stack
-- <SIP/MyIP-00000044> Playing 'ss-noservice.gsm' (language 'en')
-- Executing [s@from-sip-external:6] PlayTones("SIP/MyIP-00000044", "congestion") in new stack
-- Executing [s@from-sip-external:7] Congestion("SIP/MyIP-00000044", "5") in new stack
== Spawn extension (from-sip-external, s, 7) exited non-zero on 'SIP/MyIP-00000044'
-- Executing [h@from-sip-external:1] NoOp("SIP/MyIP-00000044", "Hangup") in new stack
-- Executing [h@from-sip-external:2] Set("SIP/MyIP-00000044", "DID=s") in new stack
-- Executing [h@from-sip-external:3] Goto("SIP/MyIP-00000044", "s,1") in new stack
-- Goto (from-sip-external,s,1)
-- Executing [s@from-sip-external:1] GotoIf("SIP/MyIP-00000044", "0?from-trunk,s,1") in new stack
-- Executing [s@from-sip-external:2] Set("SIP/MyIP-00000044", "TIMEOUT(absolute)=15") in new stack
Channel will hangup at 2011-07-25 17:18:35.101 EDT.
-- Executing [s@from-sip-external:3] Answer("SIP/MyIP-00000044", "") in new stack
== Spawn extension (from-sip-external, s, 3) exited non-zero on 'SIP/MyIP-00000044'
-- Executing [h@macro-dial:1] Macro("SIP/didforsale2-0000003c", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/didforsale2-0000003c", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,4)
-- Executing [s@macro-hangupcall:4] GotoIf("SIP/didforsale2-0000003c", "0?skipblkvm") in new stack
-- Executing [s@macro-hangupcall:5] NoOp("SIP/didforsale2-0000003c", "Cleaning Up Block VM Flag: BLKVM/777/SIP/didforsale2-0000003c") in new stack
-- Executing [s@macro-hangupcall:6] NoOp("SIP/didforsale2-0000003c", "Deleting: BLKVM/777/SIP/didforsale2-0000003c ") in new stack
-- Executing [s@macro-hangupcall:7] GotoIf("SIP/didforsale2-0000003c", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] Hangup("SIP/didforsale2-0000003c", "") in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/didforsale2-0000003c' in macro 'hangupcall'
== Spawn extension (macro-dial, h, 1) exited non-zero on 'SIP/didforsale2-0000003c'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/didforsale2-0000003c' in macro 'dial'
== Spawn extension (ext-group, 777, 14) exited non-zero on 'SIP/didforsale2-0000003c'
-- Executing [h@ext-group:1] Macro("SIP/didforsale2-0000003c", "hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/didforsale2-0000003c", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,4)
-- Executing [s@macro-hangupcall:4] GotoIf("SIP/didforsale2-0000003c", "0?skipblkvm") in new stack
-- Executing [s@macro-hangupcall:5] NoOp("SIP/didforsale2-0000003c", "Cleaning Up Block VM Flag: BLKVM/777/SIP/didforsale2-0000003c") in new stack
-- Executing [s@macro-hangupcall:6] NoOp("SIP/didforsale2-0000003c", "Deleting: BLKVM/777/SIP/didforsale2-0000003c ") in new stack
-- Executing [s@macro-hangupcall:7] GotoIf("SIP/didforsale2-0000003c", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] Hangup("SIP/didforsale2-0000003c", "") in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/didforsale2-0000003c' in macro 'hangupcall'
== Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/didforsale2-0000003c'
-- Incoming call: Got SIP response 482 "Loop Detected" back from 209.216.15.70:5060

 

[jmullinix]

Maybe call forwarding is set for extension 502. I see a "moved temporarily" in the call for that extension.