[Oct 8 15:23:50] VERBOSE[6856] logger.c: -- Local/1111111111@context-8ff8,1 is making progress passing it to SIP/PEERNAME7114-7436-090c4b20 [Oct 8 15:23:51] VERBOSE[4012] logger.c: -- Stopped music on hold on SIP/gateway1-b74b0f90 [Oct 8 15:23:51] VERBOSE[6826] logger.c: == Spawn extension (pstn-in, 7814557114, 8) exited non-zero on 'SIP/PEERNAME7114-7436-090c4b20' [Oct 8 15:23:52] VERBOSE[6856] logger.c: -- Nobody picked up in 5000 ms [Oct 8 15:23:52] VERBOSE[6859] logger.c: == Spawn extension (macro-pstn-cam-out, s, 3) exited non-zero on 'Local/1111111111@context-8ff8,2' in macro 'pstn-cam-out' [Oct 8 15:23:52] VERBOSE[6859] logger.c: == Spawn extension (context, 1111111111, 51) exited non-zero on 'Local/1111111111@context-8ff8,2' [Oct 8 15:23:52] VERBOSE[6856] logger.c: -- Executing Dial("SIP/gateway1-b74b0f90", "SIP/PEERNAME7428|5|rM(setmoh^classical)") [Oct 8 15:23:52] VERBOSE[6856] logger.c: -- Called PEERNAME7428 [Oct 8 15:23:52] VERBOSE[4012] logger.c: -- Got SIP response 302 "Moved Temporarily" back from 11.11.11.11 [Oct 8 15:23:52] VERBOSE[6856] logger.c: -- Now forwarding SIP/gateway1-b74b0f90 to 'Local/2222222222@context' (thanks to SIP/PEERNAME7428-09398be0) [Oct 8 15:23:52] VERBOSE[6860] logger.c: -- Executing Macro("Local/2222222222@context-9d45,2", "callerid-name-lookup") [Oct 8 15:23:52] VERBOSE[6860] logger.c: -- Executing [s@macro-callerid-name-lookup:1] AGI("Local/2222222222@context-9d45,2", "cnam.php") in new stack [Oct 8 15:23:52] VERBOSE[6860] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/cnam.php [Oct 8 15:23:52] NOTICE[6860] utils.c: Timed out trying to write [Oct 8 15:23:53] NOTICE[6860] utils.c: Timed out trying to write [Oct 8 15:23:53] NOTICE[6860] utils.c: Timed out trying to write [Oct 8 15:23:53] NOTICE[6860] utils.c: Timed out trying to write [Oct 8 15:23:53] NOTICE[6860] utils.c: Timed out trying to write [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] VERBOSE[6860] logger.c: == cnam.php: Failed to execute '/var/lib/asterisk/agi-bin/cnam.php': No such file or directory [Oct 8 15:23:53] ERROR[6860] utils.c: write() returned error: Broken pipe [Oct 8 15:23:53] VERBOSE[6860] logger.c: -- Executing [s@macro-callerid-name-lookup:2] Set("Local/2222222222@context-9d45,2", "CALLERID(name)= ") in new stack [Oct 8 15:23:53] VERBOSE[6860] logger.c: -- Executing Macro("Local/2222222222@context-9d45,2", "voip-nocongest-out|2222222222|server5|70") [Oct 8 15:23:53] VERBOSE[6860] logger.c: -- Executing [s@macro-voip-nocongest-out:1] Dial("Local/2222222222@context-9d45,2", "SIP/2222222222@server5|70") in new stack [Oct 8 15:23:53] VERBOSE[6860] logger.c: -- Called 2222222222@server5 [Oct 8 15:23:54] VERBOSE[6764] logger.c: == Spawn extension (pstn-in, 7742336043, 10) exited non-zero on 'SIP/gateway2-b7649308' [Oct 8 15:23:54] VERBOSE[4012] logger.c: -- Got SIP response 482 "Loop Detected" back from 22.22.22.22 [Oct 8 15:23:54] VERBOSE[6860] logger.c: -- Now forwarding Local/2222222222@context-9d45,2 to 'Local/2222222222@pstn-in' (thanks to SIP/server5-0927a390) [Oct 8 15:23:54] WARNING[6860] channel.c: PBX may not have been terminated properly on 'SIP/server5-0927a390' [Oct 8 15:23:54] WARNING[6863] channel.c: Unable to write to alert pipe on SIP/server5-0927a390 (qlen = 0): Bad file descriptor! [Oct 8 15:23:54] WARNING[4015] channel.c: read() failed: Bad file descriptor [Oct 8 15:23:54] WARNING[4015] channel.c: read() failed: Bad file descriptor