rendered paste bodyApr 1 21:58:42 WARNING[548]: Context 'default' tries includes nonexistent context 'parkedcalls'
Apr 1 21:58:42 WARNING[548]: Context 'local' tries includes nonexistent context 'parkedcalls'
Apr 1 21:58:42 DEBUG[548]: winconsole pid 2052 fd 8
Apr 1 21:58:42 DEBUG[548]: VM Review Option disabled globally
Apr 1 21:58:42 DEBUG[548]: VM Operator break disabled globally
Apr 1 21:58:42 DEBUG[548]: VM CID Info before msg disabled globally
Apr 1 21:58:42 DEBUG[548]: ENVELOPE before msg enabled globally
Apr 1 21:58:42 DEBUG[548]: We are not going to skip to the next msg after save/delete
Apr 1 21:58:43 DEBUG[548]: Unable to find key 'xlite1' in family 'SIP/Registry'
Apr 1 21:58:43 DEBUG[548]: Unable to find key '3000' in family 'SIP/Registry'
Apr 1 21:58:43 DEBUG[548]: Unable to find key '3001' in family 'SIP/Registry'
Apr 1 21:58:43 DEBUG[548]: Unable to find key '3002' in family 'IAX/Registry'
Apr 1 21:58:43 DEBUG[548]: Unable to find key '3003' in family 'IAX/Registry'
Apr 1 21:58:44 DEBUG[548]: Setting NAT on RTP to 0
Apr 1 21:58:44 DEBUG[548]: Setting NAT on RTP to 0
Apr 1 21:58:45 ERROR[548]: Unable to register device 'auto'
Apr 1 21:58:45 ERROR[548]: Unable to register device 'auto'
Apr 1 21:58:45 WARNING[548]: CAPI not installed, CAPI disabled!
Apr 2 00:09:30 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:13:48 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:25 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:26 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:26 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:27 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:27 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:30 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:30 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:32 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:33 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:33 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:34 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:34 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:39 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:44 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:48 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:49 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:49 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:50 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:50 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:51 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:52 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:52 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:53 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:53 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:54 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:55 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:55 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:56 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:57 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:57 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:57 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:58 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:17:59 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:01 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:02 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:03 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:05 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:06 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:07 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:09 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:10 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:11 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:12 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:12 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:17 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:18 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:24 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:25 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:26 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:28 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:29 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:30 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:30 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:31 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:32 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:32 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:33 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:35 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:35 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:36 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:37 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:39 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:40 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:43 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:43 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:44 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:47 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:49 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:18:49 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:19:14 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:19:33 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:19:34 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:19:36 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:19:40 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:19:45 NOTICE[548]: Request to schedule in the past?!?!
Apr 2 00:27:06 WARNING[2256]: Context 'default' tries includes nonexistent context 'parkedcalls'
Apr 2 00:27:06 WARNING[2256]: Context 'local' tries includes nonexistent context 'parkedcalls'
Apr 2 00:27:06 DEBUG[2256]: winconsole pid 2876 fd 8
Apr 2 00:27:06 DEBUG[2256]: VM Review Option disabled globally
Apr 2 00:27:06 DEBUG[2256]: VM Operator break disabled globally
Apr 2 00:27:06 DEBUG[2256]: VM CID Info before msg disabled globally
Apr 2 00:27:06 DEBUG[2256]: ENVELOPE before msg enabled globally
Apr 2 00:27:06 DEBUG[2256]: We are not going to skip to the next msg after save/delete
Apr 2 00:27:09 DEBUG[2256]: Unable to find key 'xlite1' in family 'SIP/Registry'
Apr 2 00:27:09 DEBUG[2256]: Unable to find key '3000' in family 'SIP/Registry'
Apr 2 00:27:09 DEBUG[2256]: Unable to find key '3001' in family 'SIP/Registry'
Apr 2 00:27:10 DEBUG[2256]: Unable to find key '3002' in family 'IAX/Registry'
Apr 2 00:27:10 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 00:27:10 DEBUG[2256]: Unable to find key '3003' in family 'IAX/Registry'
Apr 2 00:27:11 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 00:27:11 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 00:27:12 ERROR[2256]: Unable to register device 'auto'
Apr 2 00:27:12 ERROR[2256]: Unable to register device 'auto'
Apr 2 00:27:12 WARNING[2256]: CAPI not installed, CAPI disabled!
Apr 2 00:31:12 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 00:33:23 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 00:45:37 NOTICE[2256]: Removed default indication country 'us'
Apr 2 00:45:37 DEBUG[2256]: VM Review Option disabled globally
Apr 2 00:45:37 DEBUG[2256]: VM Operator break disabled globally
Apr 2 00:45:37 DEBUG[2256]: VM CID Info before msg disabled globally
Apr 2 00:45:37 DEBUG[2256]: ENVELOPE before msg enabled globally
Apr 2 00:45:37 DEBUG[2256]: We are not going to skip to the next msg after save/delete
Apr 2 00:45:37 DEBUG[2256]: Unable to find key '3003' in family 'SIP/Registry'
Apr 2 00:45:37 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 00:45:42 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 00:45:44 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 00:51:41 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 00:51:42 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 01:58:27 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 03:00:58 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 03:00:58 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 12:27:34 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:27:36 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:30:17 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:30:22 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:30:51 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 12:32:01 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:32:02 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:31 NOTICE[2256]: Removed default indication country 'us'
Apr 2 12:33:31 DEBUG[2256]: VM Review Option disabled globally
Apr 2 12:33:31 DEBUG[2256]: VM Operator break disabled globally
Apr 2 12:33:31 DEBUG[2256]: VM CID Info before msg disabled globally
Apr 2 12:33:31 DEBUG[2256]: ENVELOPE before msg enabled globally
Apr 2 12:33:31 DEBUG[2256]: We are not going to skip to the next msg after save/delete
Apr 2 12:33:35 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:33:35 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:33:36 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:33:38 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:41 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:42 WARNING[2256]: Maximum retries exceeded on call 6de3b1150cab86287043bfa4398150e9@192.168.1.113 for seqno 102 (Non-critical Request)
Apr 2 12:33:43 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:44 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:45 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:45 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:33:51 DEBUG[2256]: Auto destroying call '6de3b1150cab86287043bfa4398150e9@192.168.1.113'
Apr 2 12:35:16 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:35:18 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:35:18 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:35:19 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:35:19 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:36:11 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:36:12 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:36:13 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:36:16 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:36:17 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:38:27 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:38:30 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:38:33 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:38:52 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:38:53 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:39:12 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:39:24 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:39:26 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:39:32 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:40:01 NOTICE[2256]: Removed default indication country 'us'
Apr 2 12:40:01 DEBUG[2256]: VM Review Option disabled globally
Apr 2 12:40:01 DEBUG[2256]: VM Operator break disabled globally
Apr 2 12:40:01 DEBUG[2256]: VM CID Info before msg disabled globally
Apr 2 12:40:01 DEBUG[2256]: ENVELOPE before msg enabled globally
Apr 2 12:40:01 DEBUG[2256]: We are not going to skip to the next msg after save/delete
Apr 2 12:40:11 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:40:12 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:40:18 WARNING[2256]: Maximum retries exceeded on call 0d1f079676035e0940f7702c6fa72ca5@192.168.1.113 for seqno 102 (Non-critical Request)
Apr 2 12:40:27 DEBUG[2256]: Auto destroying call '0d1f079676035e0940f7702c6fa72ca5@192.168.1.113'
Apr 2 12:40:54 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:41:44 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:45:25 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:45:59 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:46:00 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:46:01 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:46:28 VERBOSE[2256]: Asterisk Event Logger restarted
Apr 2 12:47:32 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 12:54:57 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:54:58 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 12:55:07 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 12:55:14 NOTICE[2256]: No registration for peer 'xlite1' (from 192.168.1.2)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/extconfig.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/extconfig.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/manager.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/manager.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/enum.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/rtp.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/rtp.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: == RTP Allocating from port range 10000 -> 20000
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'pbx_config' (Text Extension Configuration)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/extensions.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/extensions.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Setting global variable 'CONSOLE' to 'Console/dsp'
Apr 2 12:56:12 VERBOSE[2256]: -- Setting global variable 'IAXINFO' to 'guest'
Apr 2 12:56:12 VERBOSE[2256]: -- Setting global variable 'TRUNK' to 'Zap/g2'
Apr 2 12:56:12 VERBOSE[2256]: -- Setting global variable 'TRUNKMSD' to '1'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'iaxtel700'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91700XXXXXXX' priority 1 to iaxtel700
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'iaxprovider'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'pstn'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_01.' priority 1 to pstn
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_02.' priority 1 to pstn
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'trunkint'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_9011.' priority 1 to trunkint
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_9011.' priority 2 to trunkint
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'trunkld'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91NXXNXXXXXX' priority 1 to trunkld
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91NXXNXXXXXX' priority 2 to trunkld
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'trunklocal'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_9NXXXXXX' priority 1 to trunklocal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_9NXXXXXX' priority 2 to trunklocal
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'trunktollfree'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91800NXXXXXX' priority 1 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91800NXXXXXX' priority 2 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91888NXXXXXX' priority 1 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91888NXXXXXX' priority 2 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91877NXXXXXX' priority 1 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91877NXXXXXX' priority 2 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91866NXXXXXX' priority 1 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_91866NXXXXXX' priority 2 to trunktollfree
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'international'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'longdistance' in context 'international'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'trunkint' in context 'international'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'longdistance'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'local' in context 'longdistance'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'trunkld' in context 'longdistance'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'local'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'parkedcalls' in context 'local'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'trunklocal' in context 'local'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'iaxtel700' in context 'local'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'trunktollfree' in context 'local'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'iaxprovider' in context 'local'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'macro-stdexten'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 1 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 2 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's-NOANSWER' priority 1 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's-NOANSWER' priority 2 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's-BUSY' priority 1 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's-BUSY' priority 2 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '_s-.' priority 1 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 'a' priority 1 to macro-stdexten
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'demo'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 3 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 4 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 5 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 's' priority 6 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '2' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '2' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '1000' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '1234' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '1234' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '1235' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '1236' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '1236' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'internal' in context 'demo'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '#' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '#' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 't' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension 'i' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '500' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '500' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '500' priority 3 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '500' priority 4 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '600' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '600' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '600' priority 3 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '600' priority 4 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '8500' priority 1 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '8500' priority 2 to demo
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'internal'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3000' priority 1 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3000' priority 2 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3000' priority 3 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3000' priority 102 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3000' priority 103 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3001' priority 1 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3001' priority 2 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3001' priority 3 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3001' priority 102 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3001' priority 103 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3002' priority 1 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3002' priority 2 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3002' priority 3 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3002' priority 102 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3002' priority 103 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3003' priority 1 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3003' priority 2 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3003' priority 3 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3003' priority 102 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '3003' priority 103 to internal
Apr 2 12:56:12 VERBOSE[2256]: -- Registered extension context 'default'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'demo' in context 'default'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'parkedcalls' in context 'default'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'pstn' in context 'default'
Apr 2 12:56:12 VERBOSE[2256]: -- Including context 'internal' in context 'default'
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99990' priority 1 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99990' priority 2 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99990' priority 3 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99991' priority 1 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99991' priority 2 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99991' priority 3 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99992' priority 1 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99992' priority 2 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99992' priority 3 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99992' priority 4 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99999' priority 1 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99999' priority 2 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Added extension '99999' priority 3 to default
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'pbx_spool' (Outgoing Spool Support)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'res_agi' (Asterisk Gateway Interface (AGI))
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'res_features' (Call Parking Resource)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'res_crypto' (Cryptographic Digital Signatures)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'res_musiconhold' (Music On Hold Resource)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'res_indications' (Indications Configuration)
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'cl'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'tw'
Apr 2 12:56:12 NOTICE[2256]: Removed default indication country 'us'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'us'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'au'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'fr'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'de'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'nl'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'uk'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'fi'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'no'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'br'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'za'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'it'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'us-o'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'gr'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'ru'
Apr 2 12:56:12 VERBOSE[2256]: -- Unregistered indication country 'nz'
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/indications.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/indications.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'cl'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'tw'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'us'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'au'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'fr'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'de'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'nl'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'uk'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'fi'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'no'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'br'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'za'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'it'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'us-o'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'gr'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'ru'
Apr 2 12:56:12 VERBOSE[2256]: -- Registered indication country 'nz'
Apr 2 12:56:12 VERBOSE[2256]: -- Setting default indication country to 'us'
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'res_monitor' (Call Monitoring Resource)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'cdr_csv' (Comma Separated Values CDR Backend)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'cdr_manager' (Asterisk Call Manager CDR Backend)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/cdr_manager.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/cdr_manager.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_dial' (Dialing Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_playback' (Trivial Playback Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_voicemail' (Comedian Mail (Voicemail System))
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/voicemail.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/voicemail.conf': Found
Apr 2 12:56:12 DEBUG[2256]: VM Review Option disabled globally
Apr 2 12:56:12 DEBUG[2256]: VM Operator break disabled globally
Apr 2 12:56:12 DEBUG[2256]: VM CID Info before msg disabled globally
Apr 2 12:56:12 DEBUG[2256]: ENVELOPE before msg enabled globally
Apr 2 12:56:12 DEBUG[2256]: We are not going to skip to the next msg after save/delete
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_directory' (Extension Directory)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_mp3' (Silly MP3 Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_system' (Generic System() application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_echo' (Simple Echo Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_record' (Trivial Record Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_image' (Image Transmission Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_url' (Send URL Applications)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_disa' (DISA (Direct Inward System Access) Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_adsiprog' (Asterisk ADSI Programming Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_getcpeid' (Get ADSI CPE ID)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_milliwatt' (Digital Milliwatt (mu-law) Test Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_zapateller' (Block Telemarketers with Special Information Tone)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_setcallerid' (Set CallerID Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_festival' (Simple Festival Interface)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_queue' (True Call Queueing)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/queues.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/queues.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_senddtmf' (Send DTMF digits Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_parkandannounce' (Call Parking and Announce Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_striplsd' (Strip trailing digits)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_setcidname' (Set CallerID Name)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_lookupcidname' (Look up CallerID Name from local database)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_substring' ((Deprecated) Save substring digits in a given variable)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_macro' (Extension Macros)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_authenticate' (Authentication Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_softhangup' (Hangs up the requested channel)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_lookupblacklist' (Look up Caller*ID name/number from blacklist database)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_waitforring' (Waits until first ring after time)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_privacy' (Require phone number to be entered, if no CallerID sent)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_db' (Database access functions for Asterisk extension logic)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_chanisavail' (Check if channel is available)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_enumlookup' (ENUM Lookup)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/enum.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_transfer' (Transfer)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_setcidnum' (Set CallerID Number)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_cdr' (Make sure asterisk doesn't save CDR for a certain call)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_hasnewvoicemail' (Indicator for whether a voice mailbox has messages in a given folder.)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_sayunixtime' (Say time)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_cut' (Cuts up variables)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_read' (Read Variable Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_setcdruserfield' (CDR user field apps)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_random' (Random goto)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_ices' (Encode and Stream via icecast and ices)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_eval' (Reevaluates strings)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_nbscat' (Silly NBS Stream Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_sendtext' (Send Text Applications)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_exec' (Executes applications)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_sms' (SMS/PSTN handler)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_groupcount' (Group Management Routines)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_txtcidname' (TXTCIDName)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/enum.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_controlplayback' (Control Playback Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_talkdetect' (Playback with Talk Detection)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_userevent' (Custom User Event Application)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_verbose' (Send verbose output)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_capiCD' ((CAPI*) Call Deflection, the magic thing.)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_capiECT' ((CAPI*) ECT)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_capiHOLD' ((CAPI*) HOLD)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_capiMCID' ((CAPI*) Malicious Caller ID, the evil thing.)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_capiNoES' ((CAPI*) No Echo Suppression.)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'app_capiRETRIEVE' ((CAPI*) RETRIEVE)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_gsm' (Raw GSM data)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_wav_gsm' (Microsoft WAV format (Proprietary GSM))
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_vox' (Dialogic VOX (ADPCM) File Format)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_pcm' (Raw uLaw 8khz Audio support (PCM))
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_g729' (Raw G729 data)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_pcm_alaw' (Raw aLaw 8khz PCM Audio support)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_h263' (Raw h263 data)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_g726' (Raw G.726 (16/24/32/40kbps) data)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_ilbc' (Raw iLBC data)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_sln' (Raw Signed Linear Audio support (SLN))
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'format_jpeg' (JPEG (Joint Picture Experts Group) Image Format)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_gsm' (GSM/PCM16 (signed linear) Codec Translator)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_ulaw' (Mu-law Coder/Decoder)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_alaw' (A-law Coder/Decoder)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_a_mu' (A-law and Mulaw direct Coder/Decoder)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_g726' (ITU G.726-32kbps G726 Transcoder)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_adpcm' (Adaptive Differential PCM Coder/Decoder)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'codec_lpc10' (LPC10 2.4kbps (signed linear) Voice Coder)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_sip' (Session Initiation Protocol (SIP))
Apr 2 12:56:12 VERBOSE[2256]: Reloading SIP
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_agent' (Agent Proxy Channel)
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/sip.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/sip.conf': == Parsing '/asterisk/etc/agents.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/sip.conf': == Parsing '/asterisk/etc/agents.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: Found
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_iax2' (Inter Asterisk eXchange (Ver 2))
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/iax.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/iax.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: == Loaded firmware 'iaxy.bin'
Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/iaxprov.conf': Apr 2 12:56:12 VERBOSE[2256]: == Parsing '/asterisk/etc/iaxprov.conf': Found
Apr 2 12:56:12 VERBOSE[2256]: -- Loaded provisioning template 'default'
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_local' (Local Proxy Channel)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_oss' (OSS Console Channel Driver)
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_tapi' (Win32 TAPI Driver )
Apr 2 12:56:12 VERBOSE[2256]: -- Reloading module 'chan_capi' (Common ISDN API for Asterisk)
Apr 2 12:56:19 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:56:20 DEBUG[2256]: Setting NAT on RTP to 0
Apr 2 12:56:26 WARNING[2256]: Maximum retries exceeded on call 0d1f079676035e0940f7702c6fa72ca5@192.168.1.113 for seqno 102 (Non-critical Request)
Apr 2 12:56:35 DEBUG[2256]: Auto destroying call '0d1f079676035e0940f7702c6fa72ca5@192.168.1.113'
Apr 2 12:56:38 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 12:57:45 NOTICE[2256]: Request to schedule in the past?!?!
Apr 2 12:58:32 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 12:58:33 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 12:59:33 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:01:35 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:01:37 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:02:52 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:02:56 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:03 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:05 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:45 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:46 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:47 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:47 DEBUG[2256]: Raw Hangup 192.168.1.2:4569, src=7, dst=26459
Apr 2 13:03:48 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:03:49 DEBUG[2256]: Raw Hangup 192.168.1.2:4569, src=8, dst=26460
Apr 2 13:03:51 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:52 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:54 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:03:59 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:04:03 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:05:32 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:05:34 NOTICE[2256]: Rejected connect attempt from 192.168.1.2
Apr 2 13:05:35 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:05:50 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:05:52 NOTICE[2256]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:06:31 VERBOSE[2256]: Beginning asterisk shutdown....
Apr 2 13:06:31 VERBOSE[2256]: Executing last minute cleanups
Apr 2 13:06:31 VERBOSE[2256]: == Destroying any remaining musiconhold processes
Apr 2 13:06:31 DEBUG[2256]: killing 2876!
Apr 2 13:06:31 WARNING[2256]: fd 8 Close handles
Apr 2 13:06:31 WARNING[2256]: fd 8 handles closed
Apr 2 13:06:31 DEBUG[2256]: mpg123 pid 2876 and child died after 0 bytes read
Apr 2 13:06:31 VERBOSE[2256]: Asterisk cleanly ending (0).
Apr 2 13:06:57 VERBOSE[1896]: Asterisk Event Logger Started /asterisk/log/event_log
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Ping
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Events
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Logoff
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Hangup
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Status
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Setvar
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Getvar
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Redirect
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Originate
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Command
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action ExtensionState
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action AbsoluteTimeout
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action MailboxStatus
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action MailboxCount
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action ListCommands
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/manager.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/manager.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: Asterisk Management interface listening on port 5038
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/rtp.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/rtp.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: == RTP Allocating from port range 10000 -> 20000
Apr 2 13:06:57 VERBOSE[1896]: Asterisk PBX Core Initializing
Apr 2 13:06:57 VERBOSE[1896]: Registering builtin applications:
Apr 2 13:06:57 VERBOSE[1896]: [AbsoluteTimeout]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'AbsoluteTimeout'
Apr 2 13:06:57 VERBOSE[1896]: [Answer]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Answer'
Apr 2 13:06:57 VERBOSE[1896]: [BackGround]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'BackGround'
Apr 2 13:06:57 VERBOSE[1896]: [Busy]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Busy'
Apr 2 13:06:57 VERBOSE[1896]: [Congestion]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Congestion'
Apr 2 13:06:57 VERBOSE[1896]: [DigitTimeout]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DigitTimeout'
Apr 2 13:06:57 VERBOSE[1896]: [Goto]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Goto'
Apr 2 13:06:57 VERBOSE[1896]: [GotoIf]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'GotoIf'
Apr 2 13:06:57 VERBOSE[1896]: [GotoIfTime]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'GotoIfTime'
Apr 2 13:06:57 VERBOSE[1896]: [Hangup]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Hangup'
Apr 2 13:06:57 VERBOSE[1896]: [NoOp]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'NoOp'
Apr 2 13:06:57 VERBOSE[1896]: [Prefix]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Prefix'
Apr 2 13:06:57 VERBOSE[1896]: [Progress]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Progress'
Apr 2 13:06:57 VERBOSE[1896]: [ResetCDR]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ResetCDR'
Apr 2 13:06:57 VERBOSE[1896]: [ResponseTimeout]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ResponseTimeout'
Apr 2 13:06:57 VERBOSE[1896]: [Ringing]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Ringing'
Apr 2 13:06:57 VERBOSE[1896]: [SayNumber]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SayNumber'
Apr 2 13:06:57 VERBOSE[1896]: [SayDigits]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SayDigits'
Apr 2 13:06:57 VERBOSE[1896]: [SayAlpha]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SayAlpha'
Apr 2 13:06:57 VERBOSE[1896]: [SayPhonetic]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SayPhonetic'
Apr 2 13:06:57 VERBOSE[1896]: [SetAccount]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetAccount'
Apr 2 13:06:57 VERBOSE[1896]: [SetAMAFlags]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetAMAFlags'
Apr 2 13:06:57 VERBOSE[1896]: [SetGlobalVar]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetGlobalVar'
Apr 2 13:06:57 VERBOSE[1896]: [SetLanguage]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetLanguage'
Apr 2 13:06:57 VERBOSE[1896]: [SetVar]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetVar'
Apr 2 13:06:57 VERBOSE[1896]: [StripMSD]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'StripMSD'
Apr 2 13:06:57 VERBOSE[1896]: [Suffix]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Suffix'
Apr 2 13:06:57 VERBOSE[1896]: [Wait]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Wait'
Apr 2 13:06:57 VERBOSE[1896]: [WaitExten]
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'WaitExten'
Apr 2 13:06:57 VERBOSE[1896]: Asterisk Dynamic Loader Starting:
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/modules.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/modules.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: [pbx_config]Apr 2 13:06:57 VERBOSE[1896]: [pbx_config] => (Text Extension Configuration)
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/extensions.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/extensions.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: -- Setting global variable 'CONSOLE' to 'Console/dsp'
Apr 2 13:06:57 VERBOSE[1896]: -- Setting global variable 'IAXINFO' to 'guest'
Apr 2 13:06:57 VERBOSE[1896]: -- Setting global variable 'TRUNK' to 'Zap/g2'
Apr 2 13:06:57 VERBOSE[1896]: -- Setting global variable 'TRUNKMSD' to '1'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'iaxtel700'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91700XXXXXXX' priority 1 to iaxtel700
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'iaxprovider'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'pstn'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_01.' priority 1 to pstn
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_02.' priority 1 to pstn
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'trunkint'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_9011.' priority 1 to trunkint
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_9011.' priority 2 to trunkint
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'trunkld'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91NXXNXXXXXX' priority 1 to trunkld
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91NXXNXXXXXX' priority 2 to trunkld
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'trunklocal'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_9NXXXXXX' priority 1 to trunklocal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_9NXXXXXX' priority 2 to trunklocal
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'trunktollfree'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91800NXXXXXX' priority 1 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91800NXXXXXX' priority 2 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91888NXXXXXX' priority 1 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91888NXXXXXX' priority 2 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91877NXXXXXX' priority 1 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91877NXXXXXX' priority 2 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91866NXXXXXX' priority 1 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_91866NXXXXXX' priority 2 to trunktollfree
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'international'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'longdistance' in context 'international'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'trunkint' in context 'international'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'longdistance'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'local' in context 'longdistance'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'trunkld' in context 'longdistance'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'local'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'parkedcalls' in context 'local'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'trunklocal' in context 'local'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'iaxtel700' in context 'local'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'trunktollfree' in context 'local'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'iaxprovider' in context 'local'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'macro-stdexten'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 1 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 2 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's-NOANSWER' priority 1 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's-NOANSWER' priority 2 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's-BUSY' priority 1 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's-BUSY' priority 2 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '_s-.' priority 1 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 'a' priority 1 to macro-stdexten
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'demo'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 3 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 4 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 5 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 's' priority 6 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '2' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '2' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '1000' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '1234' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '1234' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '1235' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '1236' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '1236' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'internal' in context 'demo'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '#' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '#' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 't' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension 'i' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '500' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '500' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '500' priority 3 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '500' priority 4 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '600' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '600' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '600' priority 3 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '600' priority 4 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '8500' priority 1 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '8500' priority 2 to demo
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'internal'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3000' priority 1 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3000' priority 2 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3000' priority 3 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3000' priority 102 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3000' priority 103 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3001' priority 1 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3001' priority 2 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3001' priority 3 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3001' priority 102 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3001' priority 103 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3002' priority 1 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3002' priority 2 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3002' priority 3 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3002' priority 102 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3002' priority 103 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3003' priority 1 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3003' priority 2 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3003' priority 3 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3003' priority 102 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '3003' priority 103 to internal
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'default'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'demo' in context 'default'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'parkedcalls' in context 'default'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'pstn' in context 'default'
Apr 2 13:06:57 VERBOSE[1896]: -- Including context 'internal' in context 'default'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99990' priority 1 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99990' priority 2 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99990' priority 3 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99991' priority 1 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99991' priority 2 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99991' priority 3 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99992' priority 1 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99992' priority 2 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99992' priority 3 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99992' priority 4 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99999' priority 1 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99999' priority 2 to default
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '99999' priority 3 to default
Apr 2 13:06:57 WARNING[1896]: Context 'default' tries includes nonexistent context 'parkedcalls'
Apr 2 13:06:57 WARNING[1896]: Context 'local' tries includes nonexistent context 'parkedcalls'
Apr 2 13:06:57 VERBOSE[1896]: [skipping pbx_wilcalu]
Apr 2 13:06:57 VERBOSE[1896]: [pbx_spool]Apr 2 13:06:57 VERBOSE[1896]: [pbx_spool] => (Outgoing Spool Support)
Apr 2 13:06:57 VERBOSE[1896]: [skipping res_adsi]
Apr 2 13:06:57 VERBOSE[1896]: [res_agi]Apr 2 13:06:57 VERBOSE[1896]: [res_agi] => (Asterisk Gateway Interface (AGI))
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DeadAGI'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'EAGI'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'AGI'
Apr 2 13:06:57 VERBOSE[1896]: [res_features]Apr 2 13:06:57 VERBOSE[1896]: [res_features] => (Call Parking Resource)
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/features.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/features.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: -- Registered extension context 'parkedcalls'
Apr 2 13:06:57 VERBOSE[1896]: -- Added extension '700' priority 1 to parkedcalls
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ParkedCall'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Park'
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action ParkedCalls
Apr 2 13:06:57 VERBOSE[1896]: [res_crypto]Apr 2 13:06:57 VERBOSE[1896]: [res_crypto] => (Cryptographic Digital Signatures)
Apr 2 13:06:57 VERBOSE[1896]: -- Loaded PUBLIC key 'freeworlddialup'
Apr 2 13:06:57 VERBOSE[1896]: -- Loaded PUBLIC key 'iaxtel'
Apr 2 13:06:57 VERBOSE[1896]: [res_musiconhold]Apr 2 13:06:57 VERBOSE[1896]: [res_musiconhold] => (Music On Hold Resource)
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/musiconhold.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/musiconhold.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'MusicOnHold'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'WaitMusicOnHold'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetMusicOnHold'
Apr 2 13:06:57 VERBOSE[1896]: [res_indications]Apr 2 13:06:57 VERBOSE[1896]: [res_indications] => (Indications Configuration)
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/indications.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/indications.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'cl'
Apr 2 13:06:57 DEBUG[1896]: winconsole pid 3964 fd 8
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'tw'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'us'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'au'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'fr'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'de'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'nl'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'uk'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'fi'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'no'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'br'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'za'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'it'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'us-o'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'gr'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'ru'
Apr 2 13:06:57 VERBOSE[1896]: -- Registered indication country 'nz'
Apr 2 13:06:57 VERBOSE[1896]: -- Setting default indication country to 'us'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'PlayTones'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'StopPlayTones'
Apr 2 13:06:57 VERBOSE[1896]: [res_monitor]Apr 2 13:06:57 VERBOSE[1896]: [res_monitor] => (Call Monitoring Resource)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Monitor'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'StopMonitor'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ChangeMonitor'
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Monitor
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action StopMonitor
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action ChangeMonitor
Apr 2 13:06:57 VERBOSE[1896]: [cdr_csv]Apr 2 13:06:57 VERBOSE[1896]: [cdr_csv] => (Comma Separated Values CDR Backend)
Apr 2 13:06:57 VERBOSE[1896]: [cdr_manager]Apr 2 13:06:57 VERBOSE[1896]: [cdr_manager] => (Asterisk Call Manager CDR Backend)
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/cdr_manager.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/cdr_manager.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: [skipping cdr_odbc]
Apr 2 13:06:57 VERBOSE[1896]: [app_dial]Apr 2 13:06:57 VERBOSE[1896]: [app_dial] => (Dialing Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Dial'
Apr 2 13:06:57 VERBOSE[1896]: [app_playback]Apr 2 13:06:57 VERBOSE[1896]: [app_playback] => (Trivial Playback Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Playback'
Apr 2 13:06:57 VERBOSE[1896]: [app_voicemail]Apr 2 13:06:57 VERBOSE[1896]: [app_voicemail] => (Comedian Mail (Voicemail System))
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'VoiceMail'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'VoiceMail2'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'VoiceMailMain'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'VoiceMailMain2'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'MailboxExists'
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/voicemail.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/voicemail.conf': Found
Apr 2 13:06:57 DEBUG[1896]: VM Review Option disabled globally
Apr 2 13:06:57 DEBUG[1896]: VM Operator break disabled globally
Apr 2 13:06:57 DEBUG[1896]: VM CID Info before msg disabled globally
Apr 2 13:06:57 DEBUG[1896]: ENVELOPE before msg enabled globally
Apr 2 13:06:57 DEBUG[1896]: We are not going to skip to the next msg after save/delete
Apr 2 13:06:57 VERBOSE[1896]: [app_directory]Apr 2 13:06:57 VERBOSE[1896]: [app_directory] => (Extension Directory)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Directory'
Apr 2 13:06:57 VERBOSE[1896]: [app_mp3]Apr 2 13:06:57 VERBOSE[1896]: [app_mp3] => (Silly MP3 Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'MP3Player'
Apr 2 13:06:57 VERBOSE[1896]: [app_system]Apr 2 13:06:57 VERBOSE[1896]: [app_system] => (Generic System() application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'TrySystem'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'System'
Apr 2 13:06:57 VERBOSE[1896]: [app_echo]Apr 2 13:06:57 VERBOSE[1896]: [app_echo] => (Simple Echo Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Echo'
Apr 2 13:06:57 VERBOSE[1896]: [app_record]Apr 2 13:06:57 VERBOSE[1896]: [app_record] => (Trivial Record Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Record'
Apr 2 13:06:57 VERBOSE[1896]: [app_image]Apr 2 13:06:57 VERBOSE[1896]: [app_image] => (Image Transmission Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SendImage'
Apr 2 13:06:57 VERBOSE[1896]: [app_url]Apr 2 13:06:57 VERBOSE[1896]: [app_url] => (Send URL Applications)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SendURL'
Apr 2 13:06:57 VERBOSE[1896]: [app_disa]Apr 2 13:06:57 VERBOSE[1896]: [app_disa] => (DISA (Direct Inward System Access) Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DISA'
Apr 2 13:06:57 VERBOSE[1896]: [app_adsiprog]Apr 2 13:06:57 VERBOSE[1896]: [app_adsiprog] => (Asterisk ADSI Programming Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ADSIProg'
Apr 2 13:06:57 VERBOSE[1896]: [app_getcpeid]Apr 2 13:06:57 VERBOSE[1896]: [app_getcpeid] => (Get ADSI CPE ID)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'GetCPEID'
Apr 2 13:06:57 VERBOSE[1896]: [app_milliwatt]Apr 2 13:06:57 VERBOSE[1896]: [app_milliwatt] => (Digital Milliwatt (mu-law) Test Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Milliwatt'
Apr 2 13:06:57 VERBOSE[1896]: [app_zapateller]Apr 2 13:06:57 VERBOSE[1896]: [app_zapateller] => (Block Telemarketers with Special Information Tone)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Zapateller'
Apr 2 13:06:57 VERBOSE[1896]: [app_setcallerid]Apr 2 13:06:57 VERBOSE[1896]: [app_setcallerid] => (Set CallerID Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetCallerPres'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetCallerID'
Apr 2 13:06:57 VERBOSE[1896]: [app_festival]Apr 2 13:06:57 VERBOSE[1896]: [app_festival] => (Simple Festival Interface)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Festival'
Apr 2 13:06:57 VERBOSE[1896]: [app_queue]Apr 2 13:06:57 VERBOSE[1896]: [app_queue] => (True Call Queueing)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Queue'
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action Queues
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action QueueStatus
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action QueueAdd
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action QueueRemove
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'AddQueueMember'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'RemoveQueueMember'
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/queues.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/queues.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: [app_senddtmf]Apr 2 13:06:57 VERBOSE[1896]: [app_senddtmf] => (Send DTMF digits Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SendDTMF'
Apr 2 13:06:57 VERBOSE[1896]: [app_parkandannounce]Apr 2 13:06:57 VERBOSE[1896]: [app_parkandannounce] => (Call Parking and Announce Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ParkAndAnnounce'
Apr 2 13:06:57 VERBOSE[1896]: [app_striplsd]Apr 2 13:06:57 VERBOSE[1896]: [app_striplsd] => (Strip trailing digits)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'StripLSD'
Apr 2 13:06:57 VERBOSE[1896]: [app_setcidname]Apr 2 13:06:57 VERBOSE[1896]: [app_setcidname] => (Set CallerID Name)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetCIDName'
Apr 2 13:06:57 VERBOSE[1896]: [app_lookupcidname]Apr 2 13:06:57 VERBOSE[1896]: [app_lookupcidname] => (Look up CallerID Name from local database)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'LookupCIDName'
Apr 2 13:06:57 VERBOSE[1896]: [app_substring]Apr 2 13:06:57 VERBOSE[1896]: [app_substring] => ((Deprecated) Save substring digits in a given variable)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SubString'
Apr 2 13:06:57 VERBOSE[1896]: [app_macro]Apr 2 13:06:57 VERBOSE[1896]: [app_macro] => (Extension Macros)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Macro'
Apr 2 13:06:57 VERBOSE[1896]: [app_authenticate]Apr 2 13:06:57 VERBOSE[1896]: [app_authenticate] => (Authentication Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Authenticate'
Apr 2 13:06:57 VERBOSE[1896]: [app_softhangup]Apr 2 13:06:57 VERBOSE[1896]: [app_softhangup] => (Hangs up the requested channel)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SoftHangup'
Apr 2 13:06:57 VERBOSE[1896]: [app_lookupblacklist]Apr 2 13:06:57 VERBOSE[1896]: [app_lookupblacklist] => (Look up Caller*ID name/number from blacklist database)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'LookupBlacklist'
Apr 2 13:06:57 VERBOSE[1896]: [app_waitforring]Apr 2 13:06:57 VERBOSE[1896]: [app_waitforring] => (Waits until first ring after time)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'WaitForRing'
Apr 2 13:06:57 VERBOSE[1896]: [app_privacy]Apr 2 13:06:57 VERBOSE[1896]: [app_privacy] => (Require phone number to be entered, if no CallerID sent)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'PrivacyManager'
Apr 2 13:06:57 VERBOSE[1896]: [app_db]Apr 2 13:06:57 VERBOSE[1896]: [app_db] => (Database access functions for Asterisk extension logic)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DBget'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DBput'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DBdel'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DBdeltree'
Apr 2 13:06:57 VERBOSE[1896]: [app_chanisavail]Apr 2 13:06:57 VERBOSE[1896]: [app_chanisavail] => (Check if channel is available)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ChanIsAvail'
Apr 2 13:06:57 VERBOSE[1896]: [app_enumlookup]Apr 2 13:06:57 VERBOSE[1896]: [app_enumlookup] => (ENUM Lookup)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'EnumLookup'
Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Apr 2 13:06:57 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 13:06:57 VERBOSE[1896]: [app_transfer]Apr 2 13:06:57 VERBOSE[1896]: [app_transfer] => (Transfer)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Transfer'
Apr 2 13:06:57 VERBOSE[1896]: [app_setcidnum]Apr 2 13:06:57 VERBOSE[1896]: [app_setcidnum] => (Set CallerID Number)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetCIDNum'
Apr 2 13:06:57 VERBOSE[1896]: [app_cdr]Apr 2 13:06:57 VERBOSE[1896]: [app_cdr] => (Make sure asterisk doesn't save CDR for a certain call)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'NoCDR'
Apr 2 13:06:57 VERBOSE[1896]: [app_hasnewvoicemail]Apr 2 13:06:57 VERBOSE[1896]: [app_hasnewvoicemail] => (Indicator for whether a voice mailbox has messages in a given folder.)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'HasVoicemail'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'HasNewVoicemail'
Apr 2 13:06:57 VERBOSE[1896]: [app_sayunixtime]Apr 2 13:06:57 VERBOSE[1896]: [app_sayunixtime] => (Say time)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SayUnixTime'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'DateTime'
Apr 2 13:06:57 VERBOSE[1896]: [app_cut]Apr 2 13:06:57 VERBOSE[1896]: [app_cut] => (Cuts up variables)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Cut'
Apr 2 13:06:57 VERBOSE[1896]: [app_read]Apr 2 13:06:57 VERBOSE[1896]: [app_read] => (Read Variable Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Read'
Apr 2 13:06:57 VERBOSE[1896]: [app_setcdruserfield]Apr 2 13:06:57 VERBOSE[1896]: [app_setcdruserfield] => (CDR user field apps)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SetCDRUserField'
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'AppendCDRUserField'
Apr 2 13:06:57 VERBOSE[1896]: == Manager registered action SetCDRUserField
Apr 2 13:06:57 VERBOSE[1896]: [app_random]Apr 2 13:06:57 VERBOSE[1896]: [app_random] => (Random goto)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Random'
Apr 2 13:06:57 VERBOSE[1896]: [app_ices]Apr 2 13:06:57 VERBOSE[1896]: [app_ices] => (Encode and Stream via icecast and ices)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'ICES'
Apr 2 13:06:57 VERBOSE[1896]: [app_eval]Apr 2 13:06:57 VERBOSE[1896]: [app_eval] => (Reevaluates strings)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Eval'
Apr 2 13:06:57 VERBOSE[1896]: [app_nbscat]Apr 2 13:06:57 VERBOSE[1896]: [app_nbscat] => (Silly NBS Stream Application)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'NBScat'
Apr 2 13:06:57 VERBOSE[1896]: [app_sendtext]Apr 2 13:06:57 VERBOSE[1896]: [app_sendtext] => (Send Text Applications)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'SendText'
Apr 2 13:06:57 VERBOSE[1896]: [app_exec]Apr 2 13:06:57 VERBOSE[1896]: [app_exec] => (Executes applications)
Apr 2 13:06:57 VERBOSE[1896]: == Registered application 'Exec'
Apr 2 13:06:57 VERBOSE[1896]: [app_sms]Apr 2 13:06:58 VERBOSE[1896]: [app_sms] => (SMS/PSTN handler)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'SMS'
Apr 2 13:06:58 VERBOSE[1896]: [app_groupcount]Apr 2 13:06:58 VERBOSE[1896]: [app_groupcount] => (Group Management Routines)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'GetGroupCount'
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'SetGroup'
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'CheckGroup'
Apr 2 13:06:58 VERBOSE[1896]: [app_txtcidname]Apr 2 13:06:58 VERBOSE[1896]: [app_txtcidname] => (TXTCIDName)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'TXTCIDName'
Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 13:06:58 VERBOSE[1896]: [app_controlplayback]Apr 2 13:06:58 VERBOSE[1896]: [app_controlplayback] => (Control Playback Application)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'ControlPlayback'
Apr 2 13:06:58 VERBOSE[1896]: [app_talkdetect]Apr 2 13:06:58 VERBOSE[1896]: [app_talkdetect] => (Playback with Talk Detection)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'BackgroundDetect'
Apr 2 13:06:58 VERBOSE[1896]: [app_userevent]Apr 2 13:06:58 VERBOSE[1896]: [app_userevent] => (Custom User Event Application)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'UserEvent'
Apr 2 13:06:58 VERBOSE[1896]: [app_verbose]Apr 2 13:06:58 VERBOSE[1896]: [app_verbose] => (Send verbose output)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'Verbose'
Apr 2 13:06:58 VERBOSE[1896]: [app_capiCD]Apr 2 13:06:58 VERBOSE[1896]: [app_capiCD] => ((CAPI*) Call Deflection, the magic thing.)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'capiCD'
Apr 2 13:06:58 VERBOSE[1896]: [app_capiECT]Apr 2 13:06:58 VERBOSE[1896]: [app_capiECT] => ((CAPI*) ECT)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'capiECT'
Apr 2 13:06:58 VERBOSE[1896]: [app_capiHOLD]Apr 2 13:06:58 VERBOSE[1896]: [app_capiHOLD] => ((CAPI*) HOLD)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'capiHOLD'
Apr 2 13:06:58 VERBOSE[1896]: [app_capiMCID]Apr 2 13:06:58 VERBOSE[1896]: [app_capiMCID] => ((CAPI*) Malicious Caller ID, the evil thing.)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'capiMCID'
Apr 2 13:06:58 VERBOSE[1896]: [app_capiNoES]Apr 2 13:06:58 VERBOSE[1896]: [app_capiNoES] => ((CAPI*) No Echo Suppression.)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'capiNoES'
Apr 2 13:06:58 VERBOSE[1896]: [app_capiRETRIEVE]Apr 2 13:06:58 VERBOSE[1896]: [app_capiRETRIEVE] => ((CAPI*) RETRIEVE)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'capiRETRIEVE'
Apr 2 13:06:58 VERBOSE[1896]: [format_gsm]Apr 2 13:06:58 VERBOSE[1896]: [format_gsm] => (Raw GSM data)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format gsm, extension(s) gsm
Apr 2 13:06:58 VERBOSE[1896]: [format_wav_gsm]Apr 2 13:06:58 VERBOSE[1896]: [format_wav_gsm] => (Microsoft WAV format (Proprietary GSM))
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format wav49, extension(s) WAV|wav49
Apr 2 13:06:58 VERBOSE[1896]: [skipping format_wav]
Apr 2 13:06:58 VERBOSE[1896]: [format_vox]Apr 2 13:06:58 VERBOSE[1896]: [format_vox] => (Dialogic VOX (ADPCM) File Format)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format vox, extension(s) vox
Apr 2 13:06:58 VERBOSE[1896]: [format_pcm]Apr 2 13:06:58 VERBOSE[1896]: [format_pcm] => (Raw uLaw 8khz Audio support (PCM))
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format pcm, extension(s) pcm|ulaw|ul|mu
Apr 2 13:06:58 VERBOSE[1896]: [format_g729]Apr 2 13:06:58 VERBOSE[1896]: [format_g729] => (Raw G729 data)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format g729, extension(s) g729
Apr 2 13:06:58 VERBOSE[1896]: [format_pcm_alaw]Apr 2 13:06:58 VERBOSE[1896]: [format_pcm_alaw] => (Raw aLaw 8khz PCM Audio support)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format alaw, extension(s) alaw|al
Apr 2 13:06:58 VERBOSE[1896]: [format_h263]Apr 2 13:06:58 VERBOSE[1896]: [format_h263] => (Raw h263 data)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format h263, extension(s) h263
Apr 2 13:06:58 VERBOSE[1896]: [format_g726]Apr 2 13:06:58 VERBOSE[1896]: [format_g726] => (Raw G.726 (16/24/32/40kbps) data)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format g726-40, extension(s) g726-40
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format g726-32, extension(s) g726-32
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format g726-24, extension(s) g726-24
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format g726-16, extension(s) g726-16
Apr 2 13:06:58 VERBOSE[1896]: [format_ilbc]Apr 2 13:06:58 VERBOSE[1896]: [format_ilbc] => (Raw iLBC data)
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format iLBC, extension(s) ilbc
Apr 2 13:06:58 VERBOSE[1896]: [format_sln]Apr 2 13:06:58 VERBOSE[1896]: [format_sln] => (Raw Signed Linear Audio support (SLN))
Apr 2 13:06:58 VERBOSE[1896]: == Registered file format sln, extension(s) sln|raw
Apr 2 13:06:58 VERBOSE[1896]: [format_jpeg]Apr 2 13:06:58 VERBOSE[1896]: [format_jpeg] => (JPEG (Joint Picture Experts Group) Image Format)
Apr 2 13:06:58 VERBOSE[1896]: == Registered format 'jpg' (JPEG (Joint Picture Experts Group))
Apr 2 13:06:58 VERBOSE[1896]: [codec_gsm]Apr 2 13:06:58 VERBOSE[1896]: [codec_gsm] => (GSM/PCM16 (signed linear) Codec Translator)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'gsmtolin' from format gsm to slin, cost 2
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lintogsm' from format slin to gsm, cost 4
Apr 2 13:06:58 VERBOSE[1896]: [codec_ulaw]Apr 2 13:06:58 VERBOSE[1896]: [codec_ulaw] => (Mu-law Coder/Decoder)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'ulawtolin' from format ulaw to slin, cost 1
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lintoulaw' from format slin to ulaw, cost 1
Apr 2 13:06:58 VERBOSE[1896]: [codec_alaw]Apr 2 13:06:58 VERBOSE[1896]: [codec_alaw] => (A-law Coder/Decoder)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'alawtolin' from format alaw to slin, cost 1
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lintoalaw' from format slin to alaw, cost 1
Apr 2 13:06:58 VERBOSE[1896]: [codec_a_mu]Apr 2 13:06:58 VERBOSE[1896]: [codec_a_mu] => (A-law and Mulaw direct Coder/Decoder)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1
Apr 2 13:06:58 VERBOSE[1896]: [codec_g726]Apr 2 13:06:58 VERBOSE[1896]: [codec_g726] => (ITU G.726-32kbps G726 Transcoder)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'g726tolin' from format g726 to slin, cost 5
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lintog726' from format slin to g726, cost 6
Apr 2 13:06:58 VERBOSE[1896]: [codec_adpcm]Apr 2 13:06:58 VERBOSE[1896]: [codec_adpcm] => (Adaptive Differential PCM Coder/Decoder)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1
Apr 2 13:06:58 VERBOSE[1896]: [codec_lpc10]Apr 2 13:06:58 VERBOSE[1896]: [codec_lpc10] => (LPC10 2.4kbps (signed linear) Voice Coder)
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 5
Apr 2 13:06:58 VERBOSE[1896]: == Registered translator 'lintolpc10' from format slin to lpc10, cost 6
Apr 2 13:06:58 VERBOSE[1896]: [chan_sip]Apr 2 13:06:58 VERBOSE[1896]: [chan_sip] => (Session Initiation Protocol (SIP))
Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/sip.conf': Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/sip.conf': Found
Apr 2 13:06:58 DEBUG[1896]: Unable to find key 'xlite1' in family 'SIP/Registry'
Apr 2 13:06:58 DEBUG[1896]: Unable to find key '3000' in family 'SIP/Registry'
Apr 2 13:06:58 DEBUG[1896]: Unable to find key '3001' in family 'SIP/Registry'
Apr 2 13:06:58 VERBOSE[1896]: == SIP Listening on 0.0.0.0:5060
Apr 2 13:06:58 VERBOSE[1896]: == Using TOS bits 0
Apr 2 13:06:58 VERBOSE[1896]: == Registered channel type 'SIP' (Session Initiation Protocol (SIP))
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'SIPDtmfMode'
Apr 2 13:06:58 VERBOSE[1896]: [chan_agent]Apr 2 13:06:58 VERBOSE[1896]: [chan_agent] => (Agent Proxy Channel)
Apr 2 13:06:58 VERBOSE[1896]: == Registered channel type 'Agent' (Call Agent Proxy Channel)
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'AgentLogin'
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'AgentCallbackLogin'
Apr 2 13:06:58 VERBOSE[1896]: == Registered application 'AgentMonitorOutgoing'
Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/agents.conf': Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/agents.conf': Found
Apr 2 13:06:58 VERBOSE[1896]: [skipping chan_mgcp]
Apr 2 13:06:58 VERBOSE[1896]: [chan_iax2]Apr 2 13:06:58 VERBOSE[1896]: [chan_iax2] => (Inter Asterisk eXchange (Ver 2))
Apr 2 13:06:58 VERBOSE[1896]: == Manager registered action IAXpeers
Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/iax.conf': Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/iax.conf': Found
Apr 2 13:06:58 DEBUG[1896]: Unable to find key '3002' in family 'IAX/Registry'
Apr 2 13:06:58 DEBUG[1896]: Unable to find key '3003' in family 'IAX/Registry'
Apr 2 13:06:58 VERBOSE[1896]: == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2))
Apr 2 13:06:58 VERBOSE[1896]: == Using TOS bits 16
Apr 2 13:06:58 VERBOSE[1896]: == IAX Ready and Listening on 0.0.0.0 port 4569
Apr 2 13:06:58 VERBOSE[1896]: == Loaded firmware 'iaxy.bin'
Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/iaxprov.conf': Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/iaxprov.conf': Found
Apr 2 13:06:58 VERBOSE[1896]: -- Loaded provisioning template 'default'
Apr 2 13:06:58 VERBOSE[1896]: [skipping chan_iax]
Apr 2 13:06:58 VERBOSE[1896]: [chan_local]Apr 2 13:06:58 VERBOSE[1896]: [chan_local] => (Local Proxy Channel)
Apr 2 13:06:58 VERBOSE[1896]: == Registered channel type 'Local' (Local Proxy Channel Driver)
Apr 2 13:06:58 VERBOSE[1896]: [skipping chan_skinny]
Apr 2 13:06:58 VERBOSE[1896]: [chan_oss]Apr 2 13:06:58 VERBOSE[1896]: [chan_oss] => (OSS Console Channel Driver)
Apr 2 13:06:58 VERBOSE[1896]: == Console is full duplex
Apr 2 13:06:58 VERBOSE[1896]: == Registered channel type 'Console' (OSS Console Channel Driver)
Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/oss.conf': Apr 2 13:06:58 VERBOSE[1896]: == Parsing '/asterisk/etc/oss.conf': Found
Apr 2 13:06:58 VERBOSE[1896]: [chan_tapi]Apr 2 13:06:58 VERBOSE[1896]: [chan_tapi] => (Win32 TAPI Driver )
Apr 2 13:06:59 VERBOSE[1896]: == Found Tapi Line DeviceId:3 IPCONF LINE
Apr 2 13:06:59 VERBOSE[1896]: == Found Tapi Line DeviceId:4 H323 Line
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/tapi.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/tapi.conf': Found
Apr 2 13:06:59 ERROR[1896]: Unable to register device 'auto'
Apr 2 13:06:59 ERROR[1896]: Unable to register device 'auto'
Apr 2 13:06:59 VERBOSE[1896]: == Registered channel type 'TAPI' (Win32 TAPI Driver )
Apr 2 13:06:59 VERBOSE[1896]: == Registered application 'TapiMode'
Apr 2 13:06:59 VERBOSE[1896]: [chan_capi]Apr 2 13:06:59 VERBOSE[1896]: [chan_capi] => (Common ISDN API for Asterisk)
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/capi.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/capi.conf': Found
Apr 2 13:06:59 WARNING[1896]: CAPI not installed, CAPI disabled!
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/extconfig.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/extconfig.conf': Found
Apr 2 13:06:59 VERBOSE[1896]: Asterisk Event Logger restarted
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/manager.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/manager.conf': Found
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/enum.conf': Found
Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/rtp.conf': Apr 2 13:06:59 VERBOSE[1896]: == Parsing '/asterisk/etc/rtp.conf': Found
Apr 2 13:06:59 VERBOSE[1896]: == RTP Allocating from port range 10000 -> 20000
Apr 2 13:06:59 VERBOSE[1896]: Asterisk Ready.
Apr 2 13:06:59 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:06:59 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:07:13 VERBOSE[1896]: Asterisk Event Logger restarted
Apr 2 13:07:34 VERBOSE[1896]: Asterisk Event Logger restarted
Apr 2 13:08:16 VERBOSE[1896]: == Parsing '/asterisk/etc/manager.conf': Apr 2 13:08:16 VERBOSE[1896]: == Parsing '/asterisk/etc/manager.conf': Found
Apr 2 13:08:30 VERBOSE[1896]: -- Reloading module 'res_features' (Call Parking Resource)
Apr 2 13:08:52 VERBOSE[1896]: -- Reloading module 'app_voicemail' (Comedian Mail (Voicemail System))
Apr 2 13:08:52 VERBOSE[1896]: == Parsing '/asterisk/etc/voicemail.conf': Apr 2 13:08:52 VERBOSE[1896]: == Parsing '/asterisk/etc/voicemail.conf': Found
Apr 2 13:08:52 DEBUG[1896]: VM Review Option disabled globally
Apr 2 13:08:52 DEBUG[1896]: VM Operator break disabled globally
Apr 2 13:08:52 DEBUG[1896]: VM CID Info before msg disabled globally
Apr 2 13:08:52 DEBUG[1896]: ENVELOPE before msg enabled globally
Apr 2 13:08:52 DEBUG[1896]: We are not going to skip to the next msg after save/delete
Apr 2 13:09:20 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:09:21 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:09:23 NOTICE[1896]: Rejected connect attempt from 192.168.1.2
Apr 2 13:09:34 NOTICE[1896]: Rejected connect attempt from 192.168.1.2
Apr 2 13:09:35 NOTICE[1896]: Rejected connect attempt from 192.168.1.2
Apr 2 13:09:36 NOTICE[1896]: Rejected connect attempt from 192.168.1.2
Apr 2 13:12:20 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:12:23 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:12:26 NOTICE[1896]: Rejected connect attempt from 192.168.1.2
Apr 2 13:12:33 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:12:43 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:12:53 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:13:03 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:13:13 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:13:23 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:13:33 NOTICE[1896]: No registration for peer '3000' (from 192.168.1.2)
Apr 2 13:13:35 VERBOSE[1896]: -- Registered SIP '3000' at 192.168.1.2 port 5060 expires 3600
Apr 2 13:13:35 VERBOSE[1896]: -- Saved useragent "Softphone2" for peer 3000
Apr 2 13:13:45 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:13:45 DEBUG[1896]: Check for res for 3000
Apr 2 13:13:45 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:13:45 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:13:45 VERBOSE[1896]: -- Executing Playback("SIP/3000-bfa4", "demo-echotest") in new stack
Apr 2 13:13:45 DEBUG[1896]: Stopping retransmission on '5f5a8f31fa5f0624@Ym94Mg..' of Response 1: Found
Apr 2 13:13:45 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:13:45 VERBOSE[1896]: -- Playing 'demo-echotest' (language 'en')
Apr 2 13:13:46 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:13:47 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:48 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:49 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:50 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:50 DEBUG[1896]: Auto destroying call 'e90c1415cf1cf913@Ym94Mg..'
Apr 2 13:13:51 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:52 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:52 WARNING[1896]: Maximum retries exceeded on call 797d87a62756c7144c598d93643bd725@192.168.1.113 for seqno 102 (Non-critical Request)
Apr 2 13:13:54 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:55 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:56 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:57 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:13:59 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:00 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:01 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:01 DEBUG[1896]: Auto destroying call '797d87a62756c7144c598d93643bd725@192.168.1.113'
Apr 2 13:14:02 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:04 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:04 VERBOSE[1896]: -- Executing Echo("SIP/3000-bfa4", "") in new stack
Apr 2 13:14:19 VERBOSE[1896]: == Spawn extension (default, 600, 2) exited non-zero on 'SIP/3000-bfa4'
Apr 2 13:14:19 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:14:19 DEBUG[1896]: Check for res for 3000
Apr 2 13:14:19 DEBUG[1896]: Call from user '3000' is 2 out of 0
Apr 2 13:14:19 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:14:19 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:14:19 VERBOSE[1896]: -- Executing Playback("SIP/3000-ed43", "demo-echotest") in new stack
Apr 2 13:14:19 DEBUG[1896]: Stopping retransmission on 'a76f614f63055978@Ym94Mg..' of Response 1: Found
Apr 2 13:14:19 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:14:19 VERBOSE[1896]: -- Playing 'demo-echotest' (language 'en')
Apr 2 13:14:22 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:22 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:22 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:22 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:22 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:23 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:28 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:29 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:31 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:33 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:14:33 VERBOSE[1896]: == Spawn extension (default, 600, 1) exited non-zero on 'SIP/3000-ed43'
Apr 2 13:14:33 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:14:55 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:14:55 DEBUG[1896]: Check for res for 3000
Apr 2 13:14:55 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:14:55 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:14:55 VERBOSE[1896]: -- Executing Playback("SIP/3000-e07e", "demo-abouttotry") in new stack
Apr 2 13:14:55 DEBUG[1896]: Stopping retransmission on 'd52f7573344eec5f@Ym94Mg..' of Response 1: Found
Apr 2 13:14:55 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:14:55 VERBOSE[1896]: -- Playing 'demo-abouttotry' (language 'en')
Apr 2 13:14:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:00 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:02 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:04 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:05 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:06 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:07 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:08 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:09 VERBOSE[1896]: -- Executing Dial("SIP/3000-e07e", "IAX2/guest@misery.digium.com/s@default") in new stack
Apr 2 13:15:10 VERBOSE[1896]: -- Called guest@misery.digium.com/s@default
Apr 2 13:15:13 VERBOSE[1896]: -- Call accepted by 216.207.245.8 (format gsm)
Apr 2 13:15:13 VERBOSE[1896]: -- Format for call is gsm
Apr 2 13:15:13 VERBOSE[1896]: -- IAX2/216.207.245.8:4569/1 is ringing
Apr 2 13:15:13 DEBUG[1896]: Driver for channel 'SIP/3000-e07e' does not support indication 3, emulating it
Apr 2 13:15:14 VERBOSE[1896]: -- IAX2/216.207.245.8:4569/1 answered SIP/3000-e07e
Apr 2 13:15:15 DEBUG[1896]: Ooh, voice format changed to 2
Apr 2 13:15:24 DEBUG[1896]: Didn't get a frame from channel: SIP/3000-e07e
Apr 2 13:15:24 DEBUG[1896]: Bridge stops bridging channels SIP/3000-e07e and IAX2/216.207.245.8:4569/1
Apr 2 13:15:24 DEBUG[1896]: We're hanging up IAX2/216.207.245.8:4569/1 now...
Apr 2 13:15:24 VERBOSE[1896]: -- Hungup 'IAX2/216.207.245.8:4569/1'
Apr 2 13:15:24 DEBUG[1896]: Exiting with DIALSTATUS=ANSWER.
Apr 2 13:15:24 VERBOSE[1896]: == Spawn extension (default, 500, 2) exited non-zero on 'SIP/3000-e07e'
Apr 2 13:15:24 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:15:34 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:15:34 DEBUG[1896]: Check for res for 3000
Apr 2 13:15:34 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:15:34 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:15:34 VERBOSE[1896]: -- Executing Playback("SIP/3000-8df5", "demo-abouttotry") in new stack
Apr 2 13:15:34 DEBUG[1896]: Stopping retransmission on 'a9242f595e70cc21@Ym94Mg..' of Response 1: Found
Apr 2 13:15:34 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:15:34 VERBOSE[1896]: -- Playing 'demo-abouttotry' (language 'en')
Apr 2 13:15:35 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:36 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:37 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:38 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:40 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:41 VERBOSE[1896]: == Spawn extension (default, 500, 1) exited non-zero on 'SIP/3000-8df5'
Apr 2 13:15:41 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:15:44 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:15:44 DEBUG[1896]: Check for res for 3000
Apr 2 13:15:44 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:15:44 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:15:44 VERBOSE[1896]: -- Executing Playback("SIP/3000-680d", "demo-echotest") in new stack
Apr 2 13:15:44 DEBUG[1896]: Stopping retransmission on '070e7e36a9712d31@Ym94Mg..' of Response 1: Found
Apr 2 13:15:44 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:15:44 VERBOSE[1896]: -- Playing 'demo-echotest' (language 'en')
Apr 2 13:15:48 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:50 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:51 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:52 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:53 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:55 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:57 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:58 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:15:59 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:00 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:02 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:03 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:04 VERBOSE[1896]: -- Executing Echo("SIP/3000-680d", "") in new stack
Apr 2 13:16:14 VERBOSE[1896]: == Spawn extension (default, 600, 2) exited non-zero on 'SIP/3000-680d'
Apr 2 13:16:14 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:16:14 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:16:14 DEBUG[1896]: Check for res for 3000
Apr 2 13:16:14 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:16:14 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:16:14 VERBOSE[1896]: -- Executing Playback("SIP/3000-90d9", "demo-echotest") in new stack
Apr 2 13:16:14 DEBUG[1896]: Stopping retransmission on '7f30e00496554d45@Ym94Mg..' of Response 1: Found
Apr 2 13:16:15 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:16:15 VERBOSE[1896]: -- Playing 'demo-echotest' (language 'en')
Apr 2 13:16:16 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:17 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:20 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:21 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:22 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:25 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:26 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:27 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:28 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:31 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:32 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:33 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:16:34 VERBOSE[1896]: -- Executing Echo("SIP/3000-90d9", "") in new stack
Apr 2 13:26:33 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:26:33 DEBUG[1896]: Check for res for 3000
Apr 2 13:26:33 DEBUG[1896]: Call from user '3000' is 2 out of 0
Apr 2 13:26:33 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:26:33 VERBOSE[1896]: -- Executing Playback("SIP/3000-06e5", "demo-echotest") in new stack
Apr 2 13:26:33 DEBUG[1896]: Stopping retransmission on '923bc613be48234e@Ym94Mg..' of Response 1: Found
Apr 2 13:26:33 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:26:33 VERBOSE[1896]: -- Playing 'demo-echotest' (language 'en')
Apr 2 13:26:34 VERBOSE[1896]: == Spawn extension (default, 600, 2) exited non-zero on 'SIP/3000-90d9'
Apr 2 13:26:34 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:26:36 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:26:36 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:26:36 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:26:36 DEBUG[1896]: Request to schedule in the past?!?!
Apr 2 13:26:48 VERBOSE[1896]: == Spawn extension (default, 600, 1) exited non-zero on 'SIP/3000-06e5'
Apr 2 13:26:48 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:26:48 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:26:48 DEBUG[1896]: Check for res for 3000
Apr 2 13:26:48 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:26:48 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:26:48 VERBOSE[1896]: -- Executing Playback("SIP/3000-9acf", "demo-abouttotry") in new stack
Apr 2 13:26:48 DEBUG[1896]: Stopping retransmission on '696184263171c858@Ym94Mg..' of Response 1: Found
Apr 2 13:26:48 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:26:48 VERBOSE[1896]: -- Playing 'demo-abouttotry' (language 'en')
Apr 2 13:26:50 VERBOSE[1896]: == Spawn extension (default, 500, 1) exited non-zero on 'SIP/3000-9acf'
Apr 2 13:26:50 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:26:57 DEBUG[1896]: Setting NAT on RTP to 0
Apr 2 13:26:57 DEBUG[1896]: Check for res for 3000
Apr 2 13:26:57 DEBUG[1896]: Call from user '3000' is 1 out of 0
Apr 2 13:26:57 DEBUG[1896]: build_route: Contact hop: <sip:softphone@192.168.1.2:5060>
Apr 2 13:26:57 VERBOSE[1896]: -- Executing Playback("SIP/3000-9db0", "demo-abouttotry") in new stack
Apr 2 13:26:57 DEBUG[1896]: Stopping retransmission on 'ef6f1f2382739853@Ym94Mg..' of Response 1: Found
Apr 2 13:26:57 DEBUG[1896]: Ooh, format changed from unknown to ulaw
Apr 2 13:26:57 VERBOSE[1896]: -- Playing 'demo-abouttotry' (language 'en')
Apr 2 13:27:02 VERBOSE[1896]: == Spawn extension (default, 500, 1) exited non-zero on 'SIP/3000-9db0'
Apr 2 13:27:02 DEBUG[1896]: update_user_counter(3000) - decrement inUse counter
Apr 2 13:28:04 VERBOSE[1896]: -- Registered '3002' (UNAUTHENTICATED) at 192.168.1.2:2883
Apr 2 13:28:10 VERBOSE[1896]: -- Accepting unauthenticated call from 192.168.1.2, requested format = 1024, actual format = 1024
Apr 2 13:28:10 VERBOSE[1896]: -- Executing Playback("IAX2/3002@3002/3", "demo-abouttotry") in new stack
Apr 2 13:28:10 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:28:10 WARNING[1896]: Unable to open demo-abouttotry (format ilbc): No such file or directory
Apr 2 13:28:10 WARNING[1896]: ast_streamfile failed on IAX2/3002@3002/3 for demo-abouttotry
Apr 2 13:28:10 VERBOSE[1896]: -- Executing Dial("IAX2/3002@3002/3", "IAX2/guest@misery.digium.com/s@default") in new stack
Apr 2 13:28:10 VERBOSE[1896]: -- Called guest@misery.digium.com/s@default
Apr 2 13:28:13 VERBOSE[1896]: -- Call accepted by 216.207.245.8 (format gsm)
Apr 2 13:28:13 VERBOSE[1896]: -- Format for call is gsm
Apr 2 13:28:13 NOTICE[1896]: Unable to find a path from ilbc to gsm
Apr 2 13:28:13 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:28:13 VERBOSE[1896]: -- IAX2/216.207.245.8:4569/4 is ringing
Apr 2 13:28:14 VERBOSE[1896]: -- IAX2/216.207.245.8:4569/4 answered IAX2/3002@3002/3
Apr 2 13:28:14 WARNING[1896]: No path to translate from IAX2/3002@3002/3(1024) to IAX2/216.207.245.8:4569/4(2)
Apr 2 13:28:14 WARNING[1896]: Had to drop call because I couldn't make IAX2/3002@3002/3 compatible with IAX2/216.207.245.8:4569/4
Apr 2 13:28:14 DEBUG[1896]: We're hanging up IAX2/216.207.245.8:4569/4 now...
Apr 2 13:28:14 VERBOSE[1896]: -- Hungup 'IAX2/216.207.245.8:4569/4'
Apr 2 13:28:14 VERBOSE[1896]: == Spawn extension (default, 500, 2) exited non-zero on 'IAX2/3002@3002/3'
Apr 2 13:28:14 DEBUG[1896]: We're hanging up IAX2/3002@3002/3 now...
Apr 2 13:28:14 VERBOSE[1896]: -- Hungup 'IAX2/3002@3002/3'
Apr 2 13:28:15 DEBUG[1896]: Ooh, voice format changed to 2
Apr 2 13:28:37 VERBOSE[1896]: -- Accepting unauthenticated call from 192.168.1.2, requested format = 1024, actual format = 1024
Apr 2 13:28:37 VERBOSE[1896]: -- Executing Playback("IAX2/3002@3002/5", "demo-echotest") in new stack
Apr 2 13:28:37 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:28:37 WARNING[1896]: Unable to open demo-echotest (format ilbc): No such file or directory
Apr 2 13:28:37 WARNING[1896]: ast_streamfile failed on IAX2/3002@3002/5 for demo-echotest
Apr 2 13:28:37 VERBOSE[1896]: -- Executing Echo("IAX2/3002@3002/5", "") in new stack
Apr 2 13:29:15 DEBUG[1896]: Immediately destroying 5, having received hangup
Apr 2 13:29:15 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '80001234@default' does not exist
Apr 2 13:29:15 VERBOSE[1896]: == Spawn extension (default, 600, 2) exited non-zero on 'IAX2/3002@3002/5'
Apr 2 13:29:15 DEBUG[1896]: We're hanging up IAX2/3002@3002/5 now...
Apr 2 13:29:15 DEBUG[1896]: Really destroying IAX2/3002@3002/5 now...
Apr 2 13:29:15 VERBOSE[1896]: -- Hungup 'IAX2/3002@3002/5'
Apr 2 13:29:19 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '80001234@default' does not exist
Apr 2 13:29:22 VERBOSE[1896]: -- Accepting unauthenticated call from 192.168.1.2, requested format = 1024, actual format = 1024
Apr 2 13:29:22 VERBOSE[1896]: -- Executing Playback("IAX2/3002@3002/3", "demo-abouttotry") in new stack
Apr 2 13:29:22 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:29:22 WARNING[1896]: Unable to open demo-abouttotry (format ilbc): No such file or directory
Apr 2 13:29:22 WARNING[1896]: ast_streamfile failed on IAX2/3002@3002/3 for demo-abouttotry
Apr 2 13:29:22 VERBOSE[1896]: -- Executing Dial("IAX2/3002@3002/3", "IAX2/guest@misery.digium.com/s@default") in new stack
Apr 2 13:29:22 VERBOSE[1896]: -- Called guest@misery.digium.com/s@default
Apr 2 13:29:23 VERBOSE[1896]: -- Call accepted by 216.207.245.8 (format gsm)
Apr 2 13:29:23 VERBOSE[1896]: -- Format for call is gsm
Apr 2 13:29:23 NOTICE[1896]: Unable to find a path from ilbc to gsm
Apr 2 13:29:23 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:29:23 VERBOSE[1896]: -- IAX2/216.207.245.8:4569/4 is ringing
Apr 2 13:29:24 VERBOSE[1896]: -- IAX2/216.207.245.8:4569/4 answered IAX2/3002@3002/3
Apr 2 13:29:24 WARNING[1896]: No path to translate from IAX2/3002@3002/3(1024) to IAX2/216.207.245.8:4569/4(2)
Apr 2 13:29:24 WARNING[1896]: Had to drop call because I couldn't make IAX2/3002@3002/3 compatible with IAX2/216.207.245.8:4569/4
Apr 2 13:29:24 DEBUG[1896]: We're hanging up IAX2/216.207.245.8:4569/4 now...
Apr 2 13:29:24 VERBOSE[1896]: -- Hungup 'IAX2/216.207.245.8:4569/4'
Apr 2 13:29:24 VERBOSE[1896]: == Spawn extension (default, 500, 2) exited non-zero on 'IAX2/3002@3002/3'
Apr 2 13:29:24 DEBUG[1896]: We're hanging up IAX2/3002@3002/3 now...
Apr 2 13:29:24 VERBOSE[1896]: -- Hungup 'IAX2/3002@3002/3'
Apr 2 13:29:28 VERBOSE[1896]: -- Accepting unauthenticated call from 192.168.1.2, requested format = 1024, actual format = 1024
Apr 2 13:29:28 VERBOSE[1896]: -- Executing Playback("IAX2/3002@3002/7", "demo-echotest") in new stack
Apr 2 13:29:28 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:29:28 WARNING[1896]: Unable to open demo-echotest (format ilbc): No such file or directory
Apr 2 13:29:28 WARNING[1896]: ast_streamfile failed on IAX2/3002@3002/7 for demo-echotest
Apr 2 13:29:28 VERBOSE[1896]: -- Executing Echo("IAX2/3002@3002/7", "") in new stack
Apr 2 13:29:32 DEBUG[1896]: Immediately destroying 7, having received hangup
Apr 2 13:29:32 VERBOSE[1896]: == Spawn extension (default, 600, 2) exited non-zero on 'IAX2/3002@3002/7'
Apr 2 13:29:32 DEBUG[1896]: We're hanging up IAX2/3002@3002/7 now...
Apr 2 13:29:32 DEBUG[1896]: Really destroying IAX2/3002@3002/7 now...
Apr 2 13:29:32 VERBOSE[1896]: -- Hungup 'IAX2/3002@3002/7'
Apr 2 13:29:34 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '123@default' does not exist
Apr 2 13:29:36 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '80001234@default' does not exist
Apr 2 13:29:39 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '80001234@default' does not exist
Apr 2 13:29:40 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '80001234@default' does not exist
Apr 2 13:29:58 VERBOSE[1896]: -- Registered '3002' (UNAUTHENTICATED) at 192.168.1.2:4569
Apr 2 13:30:03 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '80001234@default' does not exist
Apr 2 13:30:05 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '81118111@default' does not exist
Apr 2 13:30:07 NOTICE[1896]: Rejected connect attempt from 192.168.1.2, request '123@default' does not exist
Apr 2 13:30:12 VERBOSE[1896]: -- Accepting unauthenticated call from 192.168.1.2, requested format = 1024, actual format = 1024
Apr 2 13:30:12 VERBOSE[1896]: -- Executing Playback("IAX2/3002@3002/16", "demo-echotest") in new stack
Apr 2 13:30:12 NOTICE[1896]: Unable to find a path from gsm to ilbc
Apr 2 13:30:12 WARNING[1896]: Unable to open demo-echotest (format ilbc): No such file or directory
Apr 2 13:30:12 WARNING[1896]: ast_streamfile failed on IAX2/3002@3002/16 for demo-echotest
Apr 2 13:30:12 VERBOSE[1896]: -- Executing Echo("IAX2/3002@3002/16", "") in new stack
Apr 2 13:30:37 DEBUG[1896]: Immediately destroying 16, having received hangup
Apr 2 13:30:37 VERBOSE[1896]: == Spawn extension (default, 600, 2) exited non-zero on 'IAX2/3002@3002/16'
Apr 2 13:30:37 DEBUG[1896]: We're hanging up IAX2/3002@3002/16 now...
Apr 2 13:30:37 DEBUG[1896]: Really destroying IAX2/3002@3002/16 now...
Apr 2 13:30:37 VERBOSE[1896]: -- Hungup 'IAX2/3002@3002/16'
Apr 2 14:23:35 NOTICE[1896]: Request to schedule in the past?!?!