Nov 13 03:33:14 VERBOSE[1348]: -- Reloading module 'chan_mgcp.so' (Media Gateway Control Protocol (MGCP)) Nov 13 03:33:14 VERBOSE[1348]: Reloading MGCP Nov 13 03:33:14 VERBOSE[1348]: == Parsing '/etc/asterisk/mgcp.conf': Nov 13 03:33:14 VERBOSE[1348]: == Parsing '/etc/asterisk/mgcp.conf': Found Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '0' on aaln/4@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '1' on aaln/4@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '0' on aaln/3@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '1' on aaln/3@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '0' on aaln/2@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '1' on aaln/2@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '0' on aaln/1@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Allocating subchannel '1' on aaln/1@192.168.1.96 Nov 13 03:33:14 VERBOSE[1348]: -- Added gateway '192.168.1.96' Nov 13 03:33:14 VERBOSE[1348]: == MGCP Listening on 0.0.0.0:2727 Nov 13 03:33:14 VERBOSE[1348]: == Using TOS bits 0 Nov 13 03:33:14 DEBUG[1348]: Slow sequence is 0 Nov 13 03:33:14 VERBOSE[1348]: Posting Request: AUEP 5 aaln/1@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: -- MGCP Auditing endpoint aaln/1@192.168.1.96 for hookstate Nov 13 03:33:14 DEBUG[1348]: Slow sequence is 0 Nov 13 03:33:14 VERBOSE[1348]: Posting Request: AUEP 6 aaln/2@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: -- MGCP Auditing endpoint aaln/2@192.168.1.96 for hookstate Nov 13 03:33:14 DEBUG[1348]: Slow sequence is 0 Nov 13 03:33:14 VERBOSE[1348]: Posting Request: AUEP 7 aaln/3@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: -- MGCP Auditing endpoint aaln/3@192.168.1.96 for hookstate Nov 13 03:33:14 DEBUG[1348]: Slow sequence is 0 Nov 13 03:33:14 VERBOSE[1348]: Posting Request: AUEP 8 aaln/4@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: -- MGCP Auditing endpoint aaln/4@192.168.1.96 for hookstate Nov 13 03:33:14 VERBOSE[1348]: MGCP read: 200 6 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: Verb: '200', Identifier: '6', Endpoint: 'OK', Version: '(null)' Nov 13 03:33:14 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:33:14 DEBUG[1348]: Got response back on transaction 6 Nov 13 03:33:14 VERBOSE[1348]: MGCP read: 200 7 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: Verb: '200', Identifier: '7', Endpoint: 'OK', Version: '(null)' Nov 13 03:33:14 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:33:14 DEBUG[1348]: Got response back on transaction 7 Nov 13 03:33:14 VERBOSE[1348]: MGCP read: 200 8 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:33:14 VERBOSE[1348]: Verb: '200', Identifier: '8', Endpoint: 'OK', Version: '(null)' Nov 13 03:33:14 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:33:14 DEBUG[1348]: Got response back on transaction 8 Nov 13 03:33:15 VERBOSE[1348]: Retransmitting #1 transaction 5 on [192.168.1.96] Nov 13 03:33:15 VERBOSE[1348]: MGCP read: 200 5 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:33:15 VERBOSE[1348]: Verb: '200', Identifier: '5', Endpoint: 'OK', Version: '(null)' Nov 13 03:33:15 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:33:15 DEBUG[1348]: Got response back on transaction 5 Nov 13 03:33:16 DEBUG[1348]: Scheduled a registration timeout # 127 ******************* DURING/AFTER REBOOT OF MH ******************* Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840246 aaln/1@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840246', Endpoint: 'aaln/1@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/1@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Handling request 'RSIP' on aaln/1@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: -- Resetting interface aaln/1@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: Transmitting: 200 972840246 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: -- MGCP Asked to indicate tone: on aaln/1@192.168.1.96-1 in cxmode: inactive Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: RQNT 9 aaln/1@[192.168.1.96] MGCP 1.0 X: 5d473e42 R: L/hd(N) to 192.168.1.96:2427 Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: AUEP 10 aaln/1@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840247 aaln/2@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840247', Endpoint: 'aaln/2@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/2@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Handling request 'RSIP' on aaln/2@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: -- Resetting interface aaln/2@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: Transmitting: 200 972840247 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: -- MGCP Asked to indicate tone: on aaln/2@192.168.1.96-1 in cxmode: inactive Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: RQNT 11 aaln/2@[192.168.1.96] MGCP 1.0 X: 3a538dac R: L/hd(N) to 192.168.1.96:2427 Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: AUEP 12 aaln/2@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840248 aaln/3@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840248', Endpoint: 'aaln/3@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/3@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Handling request 'RSIP' on aaln/3@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: -- Resetting interface aaln/3@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: Transmitting: 200 972840248 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: -- MGCP Asked to indicate tone: on aaln/3@192.168.1.96-1 in cxmode: inactive Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: RQNT 13 aaln/3@[192.168.1.96] MGCP 1.0 X: 6f0fbc9c R: L/hd(N) to 192.168.1.96:2427 Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: AUEP 14 aaln/3@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840249 aaln/4@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840249', Endpoint: 'aaln/4@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/4@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Handling request 'RSIP' on aaln/4@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: -- Resetting interface aaln/4@192.168.1.96 Nov 13 03:37:12 VERBOSE[1348]: Transmitting: 200 972840249 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: -- MGCP Asked to indicate tone: on aaln/4@192.168.1.96-1 in cxmode: inactive Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: RQNT 15 aaln/4@[192.168.1.96] MGCP 1.0 X: 67c37c76 R: L/hd(N) to 192.168.1.96:2427 Nov 13 03:37:12 DEBUG[1348]: Slow sequence is 0 Nov 13 03:37:12 VERBOSE[1348]: Posting Request: AUEP 16 aaln/4@[192.168.1.96] MGCP 1.0 F: A to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840246 aaln/1@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840246', Endpoint: 'aaln/1@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/1@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Retransmitting: 200 972840246 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840246 aaln/1@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840246', Endpoint: 'aaln/1@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/1@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Retransmitting: 200 972840246 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840247 aaln/2@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840247', Endpoint: 'aaln/2@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/2@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Retransmitting: 200 972840247 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840248 aaln/3@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840248', Endpoint: 'aaln/3@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/3@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Retransmitting: 200 972840248 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840247 aaln/2@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840247', Endpoint: 'aaln/2@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/2@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Retransmitting: 200 972840247 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: RSIP 972840249 aaln/4@[192.168.1.96] MGCP 1.0 NCS 1.0 RM: restart X-mhversion: MAC0090a003157fAPPMGCP4.0-WD#ATS--GM19---#LDR1.0-US-#ATS--GM08---# from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: 'RSIP', Identifier: '972840249', Endpoint: 'aaln/4@[192.168.1.96]', Version: 'MGCP 1.0' Nov 13 03:37:12 VERBOSE[1348]: 3 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Coundn't determine subchannel, assuming current master aaln/4@192.168.1.96-1 Nov 13 03:37:12 VERBOSE[1348]: Retransmitting: 200 972840249 OK to 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 528 9 Incompatible protocol version from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '528', Identifier: '9', Endpoint: 'Incompatible', Version: 'protocol version' Nov 13 03:37:12 VERBOSE[1348]: 1 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 9 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 200 10 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '200', Identifier: '10', Endpoint: 'OK', Version: '(null)' Nov 13 03:37:12 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 10 Nov 13 03:37:12 VERBOSE[1348]: -- No command found on [192.168.1.96] for transaction 10. Ignoring... Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 528 11 Incompatible protocol version from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '528', Identifier: '11', Endpoint: 'Incompatible', Version: 'protocol version' Nov 13 03:37:12 VERBOSE[1348]: 1 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 11 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 200 12 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '200', Identifier: '12', Endpoint: 'OK', Version: '(null)' Nov 13 03:37:12 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 12 Nov 13 03:37:12 VERBOSE[1348]: -- No command found on [192.168.1.96] for transaction 12. Ignoring... Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 528 13 Incompatible protocol version from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '528', Identifier: '13', Endpoint: 'Incompatible', Version: 'protocol version' Nov 13 03:37:12 VERBOSE[1348]: 1 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 13 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 200 14 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '200', Identifier: '14', Endpoint: 'OK', Version: '(null)' Nov 13 03:37:12 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 14 Nov 13 03:37:12 VERBOSE[1348]: -- No command found on [192.168.1.96] for transaction 14. Ignoring... Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 528 15 Incompatible protocol version from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '528', Identifier: '15', Endpoint: 'Incompatible', Version: 'protocol version' Nov 13 03:37:12 VERBOSE[1348]: 1 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 15 Nov 13 03:37:12 VERBOSE[1348]: MGCP read: 200 16 OK A: p:10-100,a:PCMU,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:30-90,a:G.723,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive A: p:10-100,a:G.728,e:on,t:01,s:on,v:l,m:sendonly;recvonly;sendrecv;inactive from 192.168.1.96:2427 Nov 13 03:37:12 VERBOSE[1348]: Verb: '200', Identifier: '16', Endpoint: 'OK', Version: '(null)' Nov 13 03:37:12 VERBOSE[1348]: 4 headers, 0 lines Nov 13 03:37:12 DEBUG[1348]: Got response back on transaction 16 Nov 13 03:37:12 VERBOSE[1348]: -- No command found on [192.168.1.96] for transaction 16. Ignoring