rjm
Guru
- Joined
- Oct 21, 2007
- Messages
- 478
- Reaction score
- 21
I call out from extension 701 with 48 ***-***-**** and get a "Please wait while I connect your call" message. Then I hear music. Then music along with a ring-ring, ring-ring, ... in the background of the music. Then my called number rings. When I answer the called number and say hello, I then hear ringing in the receiver of the called number. Soon after, I get a message on the called number phone that says that "The party at extension 701 is not available, please leave a message".
Incoming calls connect properly (although IPkall doesn't seem to pass CID properly and I get the CID of my IPK number).
BTW, I check the install docs 12 times. Although I know I must have missed something, I just can't figure out what.
Log file:
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Noop
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Macro
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-vm:2] Set("SIP/66.54.140.46-08b98db8", "VMGAIN=""") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Set
[2009-09-13 18:10:50] DEBUG[3679] func_db.c: DB: BLKVM/700/SIP/66.54.140.46-08b98db8 not found in database.
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-vm:3] GotoIf("SIP/66.54.140.46-08b98db8", "1?vmx|1") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-vm,vmx,1)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:1] GotoIf("SIP/66.54.140.46-08b98db8", "0?s-NOANSWER|1") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:2] Set("SIP/66.54.140.46-08b98db8", "MODE=unavail") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Set
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:3] GotoIf("SIP/66.54.140.46-08b98db8", "1?notdirect") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-vm,vmx,5)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] DEBUG[3679] func_db.c: DB: AMPUSER/701/vmx/unavail/state not found in database.
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:5] NoOp("SIP/66.54.140.46-08b98db8", "Checking if ext 701 is enabled: ") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Noop
[2009-09-13 18:10:50] DEBUG[3679] func_db.c: DB: AMPUSER/701/vmx/unavail/state not found in database.
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:6] GotoIf("SIP/66.54.140.46-08b98db8", "1?s-NOANSWER|1") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-vm,s-NOANSWER,1)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/66.54.140.46-08b98db8", "get-vmcontext|701") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-get-vmcontext:1] Set("SIP/66.54.140.46-08b98db8", "VMCONTEXT=default") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Set
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/66.54.140.46-08b98db8", "0?200:300") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-get-vmcontext,s,300)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/66.54.140.46-08b98db8", "") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: NoOp
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Macro
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s-NOANSWER@macro-vm:2] VoiceMail("SIP/66.54.140.46-08b98db8", "701@default|u") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'vm-theperson' (language 'en')
[2009-09-13 18:10:52] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'digits/7' (language 'en')
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-dialout-trunk, s, 26) exited non-zero on 'SIP/701-08b8dc60' in macro 'dialout-trunk'
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-dialout-trunk, s, 26) exited non-zero on 'SIP/701-08b8dc60'
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/701-08b8dc60", "hangupcall|") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/701-08b8dc60", "1?skiprg") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Goto (macro-hangupcall,s,4)
[2009-09-13 18:10:52] DEBUG[3673] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/701-08b8dc60", "1?skipblkvm") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Goto (macro-hangupcall,s,7)
[2009-09-13 18:10:52] DEBUG[3673] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/701-08b8dc60", "1?theend") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Goto (macro-hangupcall,s,9)
[2009-09-13 18:10:52] DEBUG[3673] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/701-08b8dc60", "") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/701-08b8dc60' in macro 'hangupcall'
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/701-08b8dc60'
[2009-09-13 18:10:52] VERBOSE[3504] logger.c: -- Stopped music on hold on Local/2158170885@custom-gv-15f5,2
[2009-09-13 18:10:52] VERBOSE[3504] logger.c: == Local/2158170885@custom-gv-15f5,2 got tired of being parked
[2009-09-13 18:10:53] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'digits/0' (language 'en')
[2009-09-13 18:10:54] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'digits/1' (language 'en')
[2009-09-13 18:10:54] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'vm-isunavail' (language 'en')
[2009-09-13 18:10:55] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'vm-intro' (language 'en')
[2009-09-13 18:10:57] VERBOSE[3679] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'SIP/66.54.140.46-08b98db8' in macro 'vm'
[2009-09-13 18:10:57] VERBOSE[3679] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'SIP/66.54.140.46-08b98db8'
Does anyone have any idea why this may be happening?
It looks like 701 isn't parking so it looks busy to the called number, or the called number never makes it to the parking lot. All the conf files park in 75.
arrrrgggggg
Incoming calls connect properly (although IPkall doesn't seem to pass CID properly and I get the CID of my IPK number).
BTW, I check the install docs 12 times. Although I know I must have missed something, I just can't figure out what.
Log file:
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Noop
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Macro
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-vm:2] Set("SIP/66.54.140.46-08b98db8", "VMGAIN=""") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Set
[2009-09-13 18:10:50] DEBUG[3679] func_db.c: DB: BLKVM/700/SIP/66.54.140.46-08b98db8 not found in database.
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-vm:3] GotoIf("SIP/66.54.140.46-08b98db8", "1?vmx|1") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-vm,vmx,1)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:1] GotoIf("SIP/66.54.140.46-08b98db8", "0?s-NOANSWER|1") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:2] Set("SIP/66.54.140.46-08b98db8", "MODE=unavail") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Set
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:3] GotoIf("SIP/66.54.140.46-08b98db8", "1?notdirect") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-vm,vmx,5)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] DEBUG[3679] func_db.c: DB: AMPUSER/701/vmx/unavail/state not found in database.
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:5] NoOp("SIP/66.54.140.46-08b98db8", "Checking if ext 701 is enabled: ") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Noop
[2009-09-13 18:10:50] DEBUG[3679] func_db.c: DB: AMPUSER/701/vmx/unavail/state not found in database.
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [vmx@macro-vm:6] GotoIf("SIP/66.54.140.46-08b98db8", "1?s-NOANSWER|1") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-vm,s-NOANSWER,1)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/66.54.140.46-08b98db8", "get-vmcontext|701") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-get-vmcontext:1] Set("SIP/66.54.140.46-08b98db8", "VMCONTEXT=default") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Set
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/66.54.140.46-08b98db8", "0?200:300") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Goto (macro-get-vmcontext,s,300)
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/66.54.140.46-08b98db8", "") in new stack
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: NoOp
[2009-09-13 18:10:50] DEBUG[3679] app_macro.c: Executed application: Macro
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- Executing [s-NOANSWER@macro-vm:2] VoiceMail("SIP/66.54.140.46-08b98db8", "701@default|u") in new stack
[2009-09-13 18:10:50] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'vm-theperson' (language 'en')
[2009-09-13 18:10:52] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'digits/7' (language 'en')
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-dialout-trunk, s, 26) exited non-zero on 'SIP/701-08b8dc60' in macro 'dialout-trunk'
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-dialout-trunk, s, 26) exited non-zero on 'SIP/701-08b8dc60'
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/701-08b8dc60", "hangupcall|") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/701-08b8dc60", "1?skiprg") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Goto (macro-hangupcall,s,4)
[2009-09-13 18:10:52] DEBUG[3673] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/701-08b8dc60", "1?skipblkvm") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Goto (macro-hangupcall,s,7)
[2009-09-13 18:10:52] DEBUG[3673] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/701-08b8dc60", "1?theend") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Goto (macro-hangupcall,s,9)
[2009-09-13 18:10:52] DEBUG[3673] app_macro.c: Executed application: GotoIf
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/701-08b8dc60", "") in new stack
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/701-08b8dc60' in macro 'hangupcall'
[2009-09-13 18:10:52] VERBOSE[3673] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/701-08b8dc60'
[2009-09-13 18:10:52] VERBOSE[3504] logger.c: -- Stopped music on hold on Local/2158170885@custom-gv-15f5,2
[2009-09-13 18:10:52] VERBOSE[3504] logger.c: == Local/2158170885@custom-gv-15f5,2 got tired of being parked
[2009-09-13 18:10:53] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'digits/0' (language 'en')
[2009-09-13 18:10:54] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'digits/1' (language 'en')
[2009-09-13 18:10:54] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'vm-isunavail' (language 'en')
[2009-09-13 18:10:55] VERBOSE[3679] logger.c: -- <SIP/66.54.140.46-08b98db8> Playing 'vm-intro' (language 'en')
[2009-09-13 18:10:57] VERBOSE[3679] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'SIP/66.54.140.46-08b98db8' in macro 'vm'
[2009-09-13 18:10:57] VERBOSE[3679] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'SIP/66.54.140.46-08b98db8'
Does anyone have any idea why this may be happening?
It looks like 701 isn't parking so it looks busy to the called number, or the called number never makes it to the parking lot. All the conf files park in 75.
arrrrgggggg