Summary:	ASTERISK-14442: AGI Script dialparties.agi returned error: Broken Pipe
Reporter:	Gary Regan (bateleur)	Labels:
Date Opened:	2009-07-09 09:35:31	Date Closed:	2011-06-07 14:00:35
Priority:	Major	Regression?	No
Status:	Closed/Complete	Components:	Applications/app_dial
Versions:		Frequency of Occurrence
Related Issues:
Environment:		Attachments:
Description:	Not sure if this is the correct category ... Running Trixbox 2.8 with Asterisk 1.6.  It has been up and running for a few weeks now and has been running fairly succesfully.  As of yesterday, any internal extension dialed fails with the device displaying "Declined"... it doesn't even route to voicemail.  We are thus unable to reach any extension.  We are still able to dial externally via DAHDI or via VOIP trunks.  We can dial queues directly and incomming calls are still routed via IVR to queues.  The agents extensions within those queues however never ring and as such we loose the call. The only error I can find in the log files is the broken pipe error.  The dialplan.agi error occurs every single time thus making it impossible to dial an internal extention.  The recordingcheck error also occurs every time but the results (I don't think) are as crippling as the inability to dial. The only change we made to our config on the day was to add a new IAX2 trunk for inbound VOIP calls. ****** ADDITIONAL INFORMATION ****** Excerpt for asterisk log: [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-user-callerid,s,20) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:20] NoOp("SIP/2999-b76839b0", "Using CallerID "test" <2999>") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:2] Set("SIP/2999-b76839b0", "RingGroupMethod=none") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:3] Set("SIP/2999-b76839b0", "VMBOX=2110") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:4] Set("SIP/2999-b76839b0", "EXTTOCALL=2110") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:5] Set("SIP/2999-b76839b0", "CFUEXT=") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:6] Set("SIP/2999-b76839b0", "CFBEXT=") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:7] Set("SIP/2999-b76839b0", "RT=15") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:8] Macro("SIP/2999-b76839b0", "record-enable,2110,IN") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-record-enable:1] GotoIf("SIP/2999-b76839b0", "1?check") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-record-enable,s,4) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-record-enable:4] AGI("SIP/2999-b76839b0", "recordingcheck,20090709-154843,1247147323.16") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck [Jul  9 15:48:43] ERROR[11064] utils.c: write() returned error: Broken pipe [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- <SIP/2999-b76839b0>AGI Script recordingcheck completed, returning 0 [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-record-enable:5] MacroExit("SIP/2999-b76839b0", "") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:9] Macro("SIP/2999-b76839b0", "dial,15,tr,2110") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-dial:1] GotoIf("SIP/2999-b76839b0", "1?dial") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-dial,s,3) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-dial:3] AGI("SIP/2999-b76839b0", "dialparties.agi") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [Jul  9 15:48:43] ERROR[11064] utils.c: write() returned error: Broken pipe [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- <SIP/2999-b76839b0>AGI Script dialparties.agi completed, returning 0 [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-dial:4] NoOp("SIP/2999-b76839b0", "Returned from dialparties with no extensions to call and DIALSTATUS: ") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:10] GotoIf("SIP/2999-b76839b0", "0?exit,return") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:11] Set("SIP/2999-b76839b0", "SV_DIALSTATUS=") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:12] GosubIf("SIP/2999-b76839b0", "0?docfu,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:13] GosubIf("SIP/2999-b76839b0", "0?docfb,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:14] Set("SIP/2999-b76839b0", "DIALSTATUS=") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:15] NoOp("SIP/2999-b76839b0", "Voicemail is '2110'") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:16] GotoIf("SIP/2999-b76839b0", "0?s-,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:17] NoOp("SIP/2999-b76839b0", "Sending to Voicemail box 2110") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-exten-vm:18] Macro("SIP/2999-b76839b0", "vm,2110,,") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-vm:1] Macro("SIP/2999-b76839b0", "user-callerid,SKIPTTL") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/2999-b76839b0", "AMPUSER=2999") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/2999-b76839b0", "0?report") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/2999-b76839b0", "0?Set(REALCALLERIDNUM=2999)") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/2999-b76839b0", "AMPUSER=2999") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/2999-b76839b0", "AMPUSERCIDNAME=test") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/2999-b76839b0", "0?report") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/2999-b76839b0", "AMPUSERCID=2999") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:8] Set("SIP/2999-b76839b0", "CALLERID(all)="test" <2999>") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:9] Set("SIP/2999-b76839b0", "REALCALLERIDNUM=2999") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:10] ExecIf("SIP/2999-b76839b0", "0?Set(CHANNEL(language)=)") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/2999-b76839b0", "1?continue") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-user-callerid,s,20) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-user-callerid:20] NoOp("SIP/2999-b76839b0", "Using CallerID "test" <2999>") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/2999-b76839b0", "VMGAIN=""") in new stack [Jul  9 15:48:43] WARNING[11064] func_db.c: DB requires an argument, DB(<family>/<key>) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-vm:3] GotoIf("SIP/2999-b76839b0", "1?vmx,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-vm,vmx,1) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmx@macro-vm:1] GotoIf("SIP/2999-b76839b0", "0?s-,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmx@macro-vm:2] Set("SIP/2999-b76839b0", "MODE=unavail") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmx@macro-vm:3] GotoIf("SIP/2999-b76839b0", "1?notdirect") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-vm,vmx,5) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmx@macro-vm:5] NoOp("SIP/2999-b76839b0", "Checking if ext 2110 is enabled: ") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmx@macro-vm:6] GotoIf("SIP/2999-b76839b0", "1?s-,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-vm,s-,1) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [2110@from-internal:2] Goto("SIP/2999-b76839b0", "vmret,1") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (from-internal,vmret,1) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmret@from-internal:1] GotoIf("SIP/2999-b76839b0", "0?playret") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [vmret@from-internal:2] Hangup("SIP/2999-b76839b0", "") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:   == Spawn extension (from-internal, vmret, 2) exited non-zero on 'SIP/2999-b76839b0' [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [h@from-internal:1] Macro("SIP/2999-b76839b0", "hangupcall") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/2999-b76839b0", "vw") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-hangupcall:2] NoCDR("SIP/2999-b76839b0", "") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-hangupcall:3] GotoIf("SIP/2999-b76839b0", "1?skiprg") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-hangupcall,s,6) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-hangupcall:6] GotoIf("SIP/2999-b76839b0", "1?skipblkvm") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-hangupcall,s,9) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-hangupcall:9] GotoIf("SIP/2999-b76839b0", "1?theend") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Goto (macro-hangupcall,s,11) [Jul  9 15:48:43] VERBOSE[11064] logger.c:     -- Executing [s@macro-hangupcall:11] Hangup("SIP/2999-b76839b0", "") in new stack [Jul  9 15:48:43] VERBOSE[11064] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/2999-b76839b0' in macro 'hangupcall' [Jul  9 15:48:43] VERBOSE[11064] logger.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2999-b76839b0'
Comments:	By: Russell Bryant (russell) 2009-07-09 09:43:01 Please pursue this issue through the trixbox support forums.