May 8 13:10:06 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 192.168.0.0 May 8 13:10:06 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 10.0.0.0 May 8 13:10:06 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 172.16.0.0 May 8 13:10:06 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 169.254.0.0 May 8 13:10:06 DEBUG[14976] chan_sip.c: build_route: Contact hop: May 8 13:10:06 VERBOSE[15115] logger.c: -- Executing Macro("SIP/222-d346", "callStart|1501|||210|us") in new stack May 8 13:10:06 VERBOSE[15115] logger.c: -- Executing Wait("SIP/222-d346", "1") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "COMPANY=") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "COMPANYID=210") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "LANGUAGE()=us") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "CALLEEIDNUM=1501") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "CALLERNUM=") in new stack May 8 13:10:07 DEBUG[15115] db.c: Unable to find key '1501' in family 'speednumber' May 8 13:10:07 DEBUG[15115] func_db.c: DB: speednumber/1501 not found in database. May 8 13:10:07 DEBUG[15115] pbx.c: Function result is '' May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "SPEED=") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "RecordFilename=1147086606.2") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "TOUCH_MONITOR=1147086606.2") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing GotoIf("SIP/222-d346", "?found:notfound") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Goto (macro-callStart,s,13) May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Goto("SIP/222-d346", "phone-in|1501|1") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Goto (phone-in,1501,1) May 8 13:10:07 VERBOSE[15115] logger.c: == Channel 'SIP/222-d346' jumping out of macro 'callStart' May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Macro("SIP/222-d346", "queue||1501") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "CalledQueue=1501") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Answer("SIP/222-d346", "") in new stack May 8 13:10:07 VERBOSE[15115] logger.c: -- Executing Wait("SIP/222-d346", "1") in new stack May 8 13:10:08 VERBOSE[15115] logger.c: -- Executing Playback("SIP/222-d346", "ivr/IVR-1501") in new stack May 8 13:10:08 WARNING[15115] file.c: File ivr/IVR-1501 does not exist in any format May 8 13:10:08 WARNING[15115] file.c: Unable to open ivr/IVR-1501 (format 0x4 (ulaw)): No such file or directory May 8 13:10:08 WARNING[15115] app_playback.c: ast_streamfile failed on SIP/222-d346 for ivr/IVR-1501 May 8 13:10:08 VERBOSE[15115] logger.c: -- Executing Goto("SIP/222-d346", "phones|Queue-1501|1") in new stack May 8 13:10:08 VERBOSE[15115] logger.c: -- Goto (phones,Queue-1501,1) May 8 13:10:08 VERBOSE[15115] logger.c: == Channel 'SIP/222-d346' jumping out of macro 'queue' May 8 13:10:08 VERBOSE[15115] logger.c: -- Executing Macro("SIP/222-d346", "callStart|Queue-1501|||210|us") in new stack May 8 13:10:08 VERBOSE[15115] logger.c: -- Executing Wait("SIP/222-d346", "1") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "COMPANY=") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "COMPANYID=210") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "LANGUAGE()=us") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "CALLEEIDNUM=Queue-1501") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "CALLERNUM=") in new stack May 8 13:10:09 DEBUG[15115] db.c: Unable to find key 'Queue-1501' in family 'speednumber' May 8 13:10:09 DEBUG[15115] func_db.c: DB: speednumber/Queue-1501 not found in database. May 8 13:10:09 DEBUG[15115] pbx.c: Function result is '' May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "SPEED=") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "RecordFilename=1147086606.2") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Set("SIP/222-d346", "TOUCH_MONITOR=1147086606.2") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing GotoIf("SIP/222-d346", "?found:notfound") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Goto (macro-callStart,s,13) May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Goto("SIP/222-d346", "phone-in|Queue-1501|1") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Goto (phone-in,Queue-1501,1) May 8 13:10:09 VERBOSE[15115] logger.c: == Channel 'SIP/222-d346' jumping out of macro 'callStart' May 8 13:10:09 VERBOSE[15115] logger.c: -- Executing Queue("SIP/222-d346", "1501|tTwW|||300") in new stack May 8 13:10:09 VERBOSE[15115] logger.c: -- Started music on hold, class 'default', on SIP/222-d346 May 8 13:10:09 DEBUG[15115] channel.c: Scheduling timer at 160 sample intervals May 8 13:10:09 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 192.168.0.0 May 8 13:10:09 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 10.0.0.0 May 8 13:10:09 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 172.16.0.0 May 8 13:10:09 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 169.254.0.0 May 8 13:10:09 VERBOSE[15115] logger.c: -- Called SIP/333 May 8 13:10:09 DEBUG[15115] channel.c: Scheduling timer at 0 sample intervals May 8 13:10:09 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:09 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:09 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:10 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:11 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:12 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:15 VERBOSE[15115] logger.c: -- SIP/333-963b is ringing May 8 13:10:19 VERBOSE[15115] logger.c: -- Nobody picked up in 10000 ms May 8 13:10:19 DEBUG[15115] chan_sip.c: Acked pending invite 102 May 8 13:10:20 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 192.168.0.0 May 8 13:10:20 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 10.0.0.0 May 8 13:10:20 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 172.16.0.0 May 8 13:10:20 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 169.254.0.0 May 8 13:10:23 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 192.168.0.0 May 8 13:10:23 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 10.0.0.0 May 8 13:10:23 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 172.16.0.0 May 8 13:10:23 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 169.254.0.0 May 8 13:10:25 VERBOSE[15115] logger.c: -- Stopped music on hold on SIP/222-d346 May 8 13:10:25 DEBUG[15115] channel.c: Scheduling timer at 0 sample intervals May 8 13:10:25 DEBUG[15115] channel.c: Scheduling timer at 160 sample intervals May 8 13:10:25 VERBOSE[15115] logger.c: -- Playing 'queue-youarenext' (language 'us') May 8 13:10:26 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 192.168.0.0 May 8 13:10:26 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 10.0.0.0 May 8 13:10:26 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 172.16.0.0 May 8 13:10:26 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 169.254.0.0 May 8 13:10:27 DEBUG[15115] channel.c: Scheduling timer at 0 sample intervals May 8 13:10:27 VERBOSE[15115] logger.c: -- Started music on hold, class 'default', on SIP/222-d346 May 8 13:10:27 DEBUG[15115] channel.c: Scheduling timer at 160 sample intervals May 8 13:10:27 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 192.168.0.0 May 8 13:10:27 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 10.0.0.0 May 8 13:10:27 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 172.16.0.0 May 8 13:10:27 DEBUG[15115] acl.c: ##### Testing 192.168.7.25 with 169.254.0.0 May 8 13:10:27 VERBOSE[15115] logger.c: -- Called SIP/333 May 8 13:10:27 DEBUG[15115] channel.c: Scheduling timer at 0 sample intervals May 8 13:10:27 VERBOSE[15115] logger.c: -- SIP/333-512c is ringing May 8 13:10:27 VERBOSE[15115] logger.c: -- SIP/333-512c is ringing May 8 13:10:27 VERBOSE[15115] logger.c: -- SIP/333-512c is ringing May 8 13:10:28 VERBOSE[15115] logger.c: -- SIP/333-512c is ringing May 8 13:10:28 VERBOSE[15115] logger.c: -- SIP/333-512c is ringing May 8 13:10:30 VERBOSE[15115] logger.c: -- Stopped music on hold on SIP/222-d346 May 8 13:10:30 DEBUG[15115] channel.c: Scheduling timer at 0 sample intervals May 8 13:10:30 DEBUG[15115] chan_sip.c: Acked pending invite 102 May 8 13:10:30 VERBOSE[15115] logger.c: == Spawn extension (IVRQUEUE-1501, 1, 0) exited non-zero on 'SIP/222-d346' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '"J" <222>' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '222' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '1' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is 'IVRQUEUE-1501' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is 'SIP/222-d346' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is 'Queue' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '1501|tTwW|||300' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '2006-05-08 13:10:06' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '2006-05-08 13:10:07' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '2006-05-08 13:10:30' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '24' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '23' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is 'ANSWERED' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is 'DOCUMENTATION' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '1147086606.2' May 8 13:10:30 DEBUG[15115] pbx.c: Function result is '' May 8 13:10:30 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 192.168.0.0 May 8 13:10:30 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 10.0.0.0 May 8 13:10:30 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 172.16.0.0 May 8 13:10:30 DEBUG[14976] acl.c: ##### Testing 192.168.7.25 with 169.254.0.0 May 8 13:10:32 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 192.168.0.0 May 8 13:10:32 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 10.0.0.0 May 8 13:10:32 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 172.16.0.0 May 8 13:10:32 DEBUG[14976] acl.c: ##### Testing 192.168.7.35 with 169.254.0.0