[2018-05-18 14:22:44] DEBUG[9749]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.465653 SystemName: deacsrewk12 Device: IAX2/312 State: UNAVAILABLE [2018-05-18 14:22:44] DEBUG[9750]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.465653 SystemName: deacsrewk12 Device: IAX2/312 State: UNAVAILABLE [2018-05-18 14:22:44] DEBUG[9932]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.465653 SystemName: deacsrewk12 Device: IAX2/312 State: UNAVAILABLE [2018-05-18 14:22:44] DEBUG[10994]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[10994]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[11019]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11004]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11019]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[11004]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[9745]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[11014]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11024]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11014]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[9746]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[11024]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[9747]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[9744]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 317 [2018-05-18 14:22:44] DEBUG[9744]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 317 [2018-05-18 14:22:44] DEBUG[9748]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[9749]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[9750]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[10989]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[10989]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[9932]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.466136 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Unregistered [2018-05-18 14:22:44] DEBUG[10999]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11029]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[10999]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[11029]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[11011]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11034]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11034]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[11016]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[11016]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11026]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[11011]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11026]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[10991]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[10991]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11001]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[11001]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11021]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[11021]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11037]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[11037]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11008]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 318 [2018-05-18 14:22:44] DEBUG[11008]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 318 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[11031]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 229 [2018-05-18 14:22:44] DEBUG[11031]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 229 [2018-05-18 14:22:44] DEBUG[9719]: chan_iax2.c:3083 sched_delay_remove: schedule decrement of callno used for 192.168.33.49 in 60 seconds [2018-05-18 14:22:44] DEBUG[9713]: chan_iax2.c:2715 peercnt_remove: ip callno count decremented to 510 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9713]: chan_iax2.c:2715 peercnt_remove: ip callno count decremented to 509 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9713]: chan_iax2.c:2715 peercnt_remove: ip callno count decremented to 508 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9713]: chan_iax2.c:2715 peercnt_remove: ip callno count decremented to 507 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9713]: chan_iax2.c:2715 peercnt_remove: ip callno count decremented to 506 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9713]: chan_iax2.c:2715 peercnt_remove: ip callno count decremented to 8 for 10.249.4.141 [2018-05-18 14:22:44] DEBUG[9719]: chan_iax2.c:2553 calltoken_required: Determining if address 192.168.33.49 with username 312 requires calltoken vali dation. Optional = 0 calltoken_required = 3 [2018-05-18 14:22:44] DEBUG[9719]: chan_iax2.c:2683 peercnt_add: ip callno count incremented to 507 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9696]: devicestate.c:369 _ast_device_state: No provider found, checking channel drivers for IAX2 - 312 [2018-05-18 14:22:44] DEBUG[9696]: chan_iax2.c:14498 iax2_devicestate: Checking device state for device 312 [2018-05-18 14:22:44] DEBUG[9696]: chan_iax2.c:14507 iax2_devicestate: Found peer. What's device state of 312? addr=(null), defaddr=(null) maxms=2000, lastms=0 [2018-05-18 14:22:44] DEBUG[9696]: devicestate.c:474 do_state_change: Changing state for IAX2/312 - state 5 (Unavailable) [2018-05-18 14:22:44] DEBUG[9696]: devicestate.c:369 _ast_device_state: No provider found, checking channel drivers for IAX2 - 312 [2018-05-18 14:22:44] DEBUG[9696]: chan_iax2.c:14498 iax2_devicestate: Checking device state for device 312 [2018-05-18 14:22:44] DEBUG[9696]: chan_iax2.c:14507 iax2_devicestate: Found peer. What's device state of 312? addr=192.168.33.49:63267, defaddr=(null ) maxms=2000, lastms=0 [2018-05-18 14:22:44] DEBUG[9696]: devicestate.c:474 do_state_change: Changing state for IAX2/312 - state 0 (Unknown) -- Registered IAX2 '312' (AUTHENTICATED) at 192.168.33.49:63267 [2018-05-18 14:22:44] DEBUG[9745]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[9746]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[9747]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[9721]: chan_iax2.c:2683 peercnt_add: ip callno count incremented to 508 for 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9748]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[9734]: app_queue.c:2486 device_state_cb: Device 'IAX2/312' changed to state '0' (Unknown) but we don't care because they'r e not a member of any queue. [2018-05-18 14:22:44] DEBUG[9749]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[9932]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[9721]: chan_iax2.c:2637 peercnt_modify: peercnt entry 192.168.33.49 modified limit:16382 registered:1[2018-05-18 14:22:44] DEBUG[9750]: manager.c:5971 match_filter: Examining AMI event: Event: DeviceStateChange Privilege: call,all Timestamp: 1526646164.874947 SystemName: deacsrewk12 Device: IAX2/312 State: UNKNOWN [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 225 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 225 [2018-05-18 14:22:44] DEBUG[11010]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 225 [2018-05-18 14:22:44] DEBUG[11010]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 225 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 225 [2018-05-18 14:22:44] DEBUG[10996]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 225 [2018-05-18 14:22:44] DEBUG[11036]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 225 [2018-05-18 14:22:44] DEBUG[11036]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 225 [2018-05-18 14:22:44] DEBUG[11031]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 225 [2018-05-18 14:22:44] DEBUG[11031]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 225 [2018-05-18 14:22:44] DEBUG[11004]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 366 [2018-05-18 14:22:44] DEBUG[11004]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 366 [2018-05-18 14:22:44] DEBUG[10994]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 366 [2018-05-18 14:22:44] DEBUG[10994]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 366 [2018-05-18 14:22:44] DEBUG[9750]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.875386 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Registered Address: 192.168.33.49 [2018-05-18 14:22:44] DEBUG[11034]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 366 [2018-05-18 14:22:44] DEBUG[11001]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 225 [2018-05-18 14:22:44] DEBUG[11034]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 366 [2018-05-18 14:22:44] DEBUG[11001]: res_http_websocket.c:356 __ast_websocket_write: Writing websocket text frame, length 225 [2018-05-18 14:22:44] DEBUG[9746]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.875386 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Registered Address: 192.168.33.49 [2018-05-18 14:22:44] DEBUG[9932]: manager.c:5971 match_filter: Examining AMI event: Event: PeerStatus Privilege: system,all Timestamp: 1526646164.875386 SystemName: deacsrewk12 ChannelType: IAX2 Peer: IAX2/312 PeerStatus: Registered Address: 192.168.33.49 [2018-05-18 14:22:44] DEBUG[11019]: res_http_websocket.c:1428 __ast_websocket_write_string: Writing websocket string of length 366