[Feb 27 17:10:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 0d7f0ea0492cac246299d5e7460e57c8@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:10:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: That's odd...  Got a response on a call we dont know about. Callid 51554e1c353d9c4c104f10174fdfaac3@193.179.38.20
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Invalid SIP message - rejected , no callid, len 505
[Feb 27 17:10:05] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 15ebb8c52a0480e030b9fdf53ba0a48c@10.0.0.2 - REGISTER (No RTP)
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:05] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 798533013af76098741ca7ba5e8c1221@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:05] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 77319ed1327d15e44f66e2e21fd262bd@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:05] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 451854fe54367d174eef480c22353853@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:05] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 1f08d0141015fac568e68e3b53e4ccd1@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 4e80b1df09c087434b1e8d5a273d3db5@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 50a389405763bbfc18941f88389c854a@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 48960b21779892be759ed3a936a65537@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 01c6ea1e27b8f35702fc7ce66c7402a2@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 0ae496003f03836c6e718bf941b73653@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 01cdf3a01abc071a2bf700b567ebb9ea@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog 'asquimlrdodcemz@194.149.113.180'
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Destroying SIP dialog asquimlrdodcemz@194.149.113.180
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 50204f4367a6952554cf1abc0e04332b@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:06] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:06] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 3db900715cd98b641847efb4730a332c@193.179.38.20
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:06] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:07] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 11975df44b25363b6324c541042820bb@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 7b01bdf40ab7753b284999365ab5f7e9@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 2c7fc7cf31d5334a40a59bac1dd35bb2@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Stopping retransmission on '3db900715cd98b641847efb4730a332c@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 4b10b46e595356f544e694fd4a0d92d8@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 22a15c69370f63d831bbb815286f7480@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:07] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:08] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 69353a9d7386d6072f247fbb591d54a6@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:08] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 7a748e5c52980a7b38e95ed82a88815c@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:08] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 6df39c29485577fd61fdb03403f82bd6@193.179.38.20
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:08] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 0f399190791ac04d6acbeb335732d9b1@193.179.38.20
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Stopping retransmission on '0f399190791ac04d6acbeb335732d9b1@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:08] DEBUG[23606] chan_sip.c: Stopping retransmission on '6df39c29485577fd61fdb03403f82bd6@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:09] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:10:11] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:11] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:11] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 54a5027e568ab91d1bec39017e7af78c@193.179.38.20
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:11] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:11] DEBUG[23606] chan_sip.c: Stopping retransmission on '54a5027e568ab91d1bec39017e7af78c@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:12] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:12] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:12] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 56016f190eca8a237601eea3017c9f14@193.179.38.20
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:12] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:12] DEBUG[23606] chan_sip.c: Stopping retransmission on '56016f190eca8a237601eea3017c9f14@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:14] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:14] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:14] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 68a9542f78710d721eefbc2018cc3503@193.179.38.20
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:14] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:14] DEBUG[23606] chan_sip.c: Stopping retransmission on '68a9542f78710d721eefbc2018cc3503@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:15] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:15] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:15] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 48d5df9968edd6f52d66b4ea49c2b2f1@193.179.38.20
[Feb 27 17:10:15] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:10:15] DEBUG[23606] chan_sip.c: Stopping retransmission on '48d5df9968edd6f52d66b4ea49c2b2f1@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:10:17] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '2834cc5a0a4d493b122556db37c4b9c0@192.168.40.4'
[Feb 27 17:10:17] DEBUG[23606] chan_sip.c: Destroying SIP dialog 2834cc5a0a4d493b122556db37c4b9c0@192.168.40.4
[Feb 27 17:10:18] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:18] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:18] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 685756ac1bc6f39d0a3e528707337f6c@193.179.38.20
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:18] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:18] DEBUG[23606] chan_sip.c: Stopping retransmission on '685756ac1bc6f39d0a3e528707337f6c@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:27] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:27] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:27] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 69b687d82217195404eaa97b11393db3@193.179.38.20
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:27] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:27] DEBUG[23606] chan_sip.c: Stopping retransmission on '69b687d82217195404eaa97b11393db3@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:27] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:27] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 1ce8d16e7d0a53c1243ade8f0147ed50@172.20.24.11 - OPTIONS (No RTP)
[Feb 27 17:10:27] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket...
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:28] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 057058326f638c3679cab6010cdeb61c@193.179.38.20
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:28] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 015ae3bb70d8c007754da4b6476c1c3e@193.179.38.20
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Stopping retransmission on '057058326f638c3679cab6010cdeb61c@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:28] DEBUG[23606] chan_sip.c: Stopping retransmission on '015ae3bb70d8c007754da4b6476c1c3e@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:31] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:31] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:31] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 0ce98d3277e3301e34a30bcd2387ad70@193.179.38.20
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:31] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Stopping retransmission on '0ce98d3277e3301e34a30bcd2387ad70@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:32] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4bb272d12d58278e78f60b3e5058cf97@193.179.38.20
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Stopping retransmission on '4bb272d12d58278e78f60b3e5058cf97@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '0d7f0ea0492cac246299d5e7460e57c8@192.168.40.4'
[Feb 27 17:10:32] DEBUG[23606] chan_sip.c: Destroying SIP dialog 0d7f0ea0492cac246299d5e7460e57c8@192.168.40.4
[Feb 27 17:10:34] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:34] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:34] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4410d3bd4e16b9bc4489857308880ba4@193.179.38.20
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:34] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:35] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:35] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:35] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 5cc5cc6b1a80f3263ef965bd0251fa60@193.179.38.20
[Feb 27 17:10:35] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:10:35] DEBUG[23606] chan_sip.c: Stopping retransmission on '5cc5cc6b1a80f3263ef965bd0251fa60@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:10:35] DEBUG[23606] chan_sip.c: Stopping retransmission on '4410d3bd4e16b9bc4489857308880ba4@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:37] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '798533013af76098741ca7ba5e8c1221@85.216.201.137'
[Feb 27 17:10:37] DEBUG[23606] chan_sip.c: Destroying SIP dialog 798533013af76098741ca7ba5e8c1221@85.216.201.137
[Feb 27 17:10:37] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '77319ed1327d15e44f66e2e21fd262bd@85.216.201.137'
[Feb 27 17:10:37] DEBUG[23606] chan_sip.c: Destroying SIP dialog 77319ed1327d15e44f66e2e21fd262bd@85.216.201.137
[Feb 27 17:10:37] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '451854fe54367d174eef480c22353853@85.216.201.137'
[Feb 27 17:10:37] DEBUG[23606] chan_sip.c: Destroying SIP dialog 451854fe54367d174eef480c22353853@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '1f08d0141015fac568e68e3b53e4ccd1@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 1f08d0141015fac568e68e3b53e4ccd1@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '4e80b1df09c087434b1e8d5a273d3db5@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 4e80b1df09c087434b1e8d5a273d3db5@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '50a389405763bbfc18941f88389c854a@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 50a389405763bbfc18941f88389c854a@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '48960b21779892be759ed3a936a65537@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 48960b21779892be759ed3a936a65537@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '01c6ea1e27b8f35702fc7ce66c7402a2@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 01c6ea1e27b8f35702fc7ce66c7402a2@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '0ae496003f03836c6e718bf941b73653@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 0ae496003f03836c6e718bf941b73653@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '01cdf3a01abc071a2bf700b567ebb9ea@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 01cdf3a01abc071a2bf700b567ebb9ea@85.216.201.137
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 034aca6d5e5dc0ee150e331c521f8d86@193.179.38.20
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Stopping retransmission on '034aca6d5e5dc0ee150e331c521f8d86@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '50204f4367a6952554cf1abc0e04332b@85.216.201.137'
[Feb 27 17:10:38] DEBUG[23606] chan_sip.c: Destroying SIP dialog 50204f4367a6952554cf1abc0e04332b@85.216.201.137
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '11975df44b25363b6324c541042820bb@85.216.201.137'
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Destroying SIP dialog 11975df44b25363b6324c541042820bb@85.216.201.137
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '7b01bdf40ab7753b284999365ab5f7e9@85.216.201.137'
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Destroying SIP dialog 7b01bdf40ab7753b284999365ab5f7e9@85.216.201.137
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '2c7fc7cf31d5334a40a59bac1dd35bb2@85.216.201.137'
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Destroying SIP dialog 2c7fc7cf31d5334a40a59bac1dd35bb2@85.216.201.137
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '4b10b46e595356f544e694fd4a0d92d8@85.216.201.137'
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Destroying SIP dialog 4b10b46e595356f544e694fd4a0d92d8@85.216.201.137
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '22a15c69370f63d831bbb815286f7480@85.216.201.137'
[Feb 27 17:10:39] DEBUG[23606] chan_sip.c: Destroying SIP dialog 22a15c69370f63d831bbb815286f7480@85.216.201.137
[Feb 27 17:10:40] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '69353a9d7386d6072f247fbb591d54a6@85.216.201.137'
[Feb 27 17:10:40] DEBUG[23606] chan_sip.c: Destroying SIP dialog 69353a9d7386d6072f247fbb591d54a6@85.216.201.137
[Feb 27 17:10:40] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '15ebb8c52a0480e030b9fdf53ba0a48c@10.0.0.2'
[Feb 27 17:10:40] DEBUG[23606] chan_sip.c: Destroying SIP dialog 15ebb8c52a0480e030b9fdf53ba0a48c@10.0.0.2
[Feb 27 17:10:40] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 4319 (__sip_destroy): Error: attempt to destroy locked mutex '&p->pvt_lock'.
[Feb 27 17:10:40] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 5853 (find_call): Error: '&p->pvt_lock' was locked here.
[Feb 27 17:10:40] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 4319 (__sip_destroy): Error destroying mutex &p->pvt_lock: Device or resource busy
[Feb 27 17:10:40] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '7a748e5c52980a7b38e95ed82a88815c@85.216.201.137'
[Feb 27 17:10:40] DEBUG[23606] chan_sip.c: Destroying SIP dialog 7a748e5c52980a7b38e95ed82a88815c@85.216.201.137
[Feb 27 17:10:45] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:45] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for d0800000176effff@192.168.1.101 - REGISTER (No RTP)
[Feb 27 17:10:45] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto UDP socket...
[Feb 27 17:10:45] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket...
[Feb 27 17:10:45] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/734
[Feb 27 17:10:45] DEBUG[23606] chan_sip.c: Checking device state for peer 734
[Feb 27 17:10:45] DEBUG[23606] devicestate.c: Changing state for SIP/734 - state 1 (Not in use)
[Feb 27 17:10:45] DEBUG[23606] chan_sip.c: Checking device state for peer 734
[Feb 27 17:10:47] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:47] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:47] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 3b17bfeb59b80a6d0dacecba3ad1ad7b@193.179.38.20
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:47] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:47] DEBUG[23606] chan_sip.c: Stopping retransmission on '3b17bfeb59b80a6d0dacecba3ad1ad7b@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:48] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 34ad0a994dc09d995cad7c83247dcb5e@193.179.38.20
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:48] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 51a266cd6e7b2d1844debd99176b1b63@193.179.38.20
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Stopping retransmission on '51a266cd6e7b2d1844debd99176b1b63@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:48] DEBUG[23606] chan_sip.c: Stopping retransmission on '34ad0a994dc09d995cad7c83247dcb5e@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:52] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 13a5a03721e9e57b1841451021b0aa74@193.179.38.20
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:52] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 6a5d6c4070e4a36313cd2c3c3c65243e@193.179.38.20
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Stopping retransmission on '6a5d6c4070e4a36313cd2c3c3c65243e@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:52] DEBUG[23606] chan_sip.c: Stopping retransmission on '13a5a03721e9e57b1841451021b0aa74@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:55] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 6bfd29db619e400673be2a8958aa6229@193.179.38.20
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Stopping retransmission on '6bfd29db619e400673be2a8958aa6229@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:55] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 5d1a30a72162897e4454e90567e2dd8e@193.179.38.20
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:55] DEBUG[23606] chan_sip.c: Stopping retransmission on '5d1a30a72162897e4454e90567e2dd8e@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:58] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:10:58] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:10:58] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 39c4de966362cee056b32de43eb05a69@193.179.38.20
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:10:58] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:10:58] DEBUG[23606] chan_sip.c: Stopping retransmission on '39c4de966362cee056b32de43eb05a69@193.179.38.20' of Request 102: Match Found
[Feb 27 17:10:59] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '1ce8d16e7d0a53c1243ade8f0147ed50@172.20.24.11'
[Feb 27 17:10:59] DEBUG[23606] chan_sip.c: Destroying SIP dialog 1ce8d16e7d0a53c1243ade8f0147ed50@172.20.24.11
[Feb 27 17:11:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 3107b93a115d7331792457681f346f99@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:11:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:02] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:02] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for YX655zR5oIfoK0N5FulVaTE0wM_1pM - REGISTER (No RTP)
[Feb 27 17:11:02] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto UDP socket...
[Feb 27 17:11:02] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket...
[Feb 27 17:11:02] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/286
[Feb 27 17:11:02] DEBUG[23606] chan_sip.c: Checking device state for peer 286
[Feb 27 17:11:02] DEBUG[23606] devicestate.c: Changing state for SIP/286 - state 1 (Not in use)
[Feb 27 17:11:07] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:07] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:07] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 6ef5a4c76b50357875c6756d6da8d458@193.179.38.20
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:07] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:07] DEBUG[23606] chan_sip.c: Stopping retransmission on '6ef5a4c76b50357875c6756d6da8d458@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:08] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:08] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:08] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4819ea667ae7243e7723343a654d4e6c@193.179.38.20
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:08] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:09] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:09] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:09] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 74b3fe250d0adf58036723eb24515026@193.179.38.20
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:09] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:09] DEBUG[23606] chan_sip.c: Stopping retransmission on '4819ea667ae7243e7723343a654d4e6c@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:09] DEBUG[23606] chan_sip.c: Stopping retransmission on '74b3fe250d0adf58036723eb24515026@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:12] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4631f0df6e39b6d1702e3c1c225829c5@193.179.38.20
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Stopping retransmission on '4631f0df6e39b6d1702e3c1c225829c5@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:12] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 33a602b331714093280433b724785759@193.179.38.20
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:12] DEBUG[23606] chan_sip.c: Stopping retransmission on '33a602b331714093280433b724785759@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:15] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4bc067e41cee36a57e482d1261e39a27@193.179.38.20
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Stopping retransmission on '4bc067e41cee36a57e482d1261e39a27@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:15] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 35dd11554931572667cef1b73e574a49@193.179.38.20
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:15] DEBUG[23606] chan_sip.c: Stopping retransmission on '35dd11554931572667cef1b73e574a49@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:17] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog 'd0800000176effff@192.168.1.101'
[Feb 27 17:11:17] DEBUG[23606] chan_sip.c: Destroying SIP dialog d0800000176effff@192.168.1.101
[Feb 27 17:11:18] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:18] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:18] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 629e819a348e06fd24f951f404fb7ccc@193.179.38.20
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:18] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:19] DEBUG[23606] chan_sip.c: Stopping retransmission on '629e819a348e06fd24f951f404fb7ccc@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:22] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:22] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:22] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 1c284de945c2429007125d6e47219cf7@193.179.38.20
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:22] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:22] DEBUG[23606] chan_sip.c: Stopping retransmission on '1c284de945c2429007125d6e47219cf7@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:27] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:27] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:27] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 7fa206e877c3e93b5dcd44f53e089850@193.179.38.20
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:27] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:27] DEBUG[23606] chan_sip.c: Stopping retransmission on '7fa206e877c3e93b5dcd44f53e089850@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:27] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:27] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 61e4daa427f0171e7ec5075b51073038@172.20.24.11 - OPTIONS (No RTP)
[Feb 27 17:11:27] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket...
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:29] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 07cee7844512183b0a1e53590d896367@193.179.38.20
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:29] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 760f863321a7fbbc6a4a0bc20f10148a@193.179.38.20
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Stopping retransmission on '07cee7844512183b0a1e53590d896367@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:29] DEBUG[23606] chan_sip.c: Stopping retransmission on '760f863321a7fbbc6a4a0bc20f10148a@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:32] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 31a74bff7bc5e63a79c263f54d3a0216@193.179.38.20
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Stopping retransmission on '31a74bff7bc5e63a79c263f54d3a0216@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:32] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 35fce1802466eb7624d70b0069b5d76a@193.179.38.20
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog '3107b93a115d7331792457681f346f99@192.168.40.4'
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Destroying SIP dialog 3107b93a115d7331792457681f346f99@192.168.40.4
[Feb 27 17:11:32] DEBUG[23606] chan_sip.c: Stopping retransmission on '35fce1802466eb7624d70b0069b5d76a@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:34] DEBUG[23606] chan_sip.c: Auto destroying SIP dialog 'YX655zR5oIfoK0N5FulVaTE0wM_1pM'
[Feb 27 17:11:34] DEBUG[23606] chan_sip.c: Destroying SIP dialog YX655zR5oIfoK0N5FulVaTE0wM_1pM
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:35] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 3c83e3e621888f2226bb44960b164133@193.179.38.20
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Stopping retransmission on '3c83e3e621888f2226bb44960b164133@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:35] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 759cf8475eedfaac2afcb92b7647b93f@193.179.38.20
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:35] DEBUG[23606] chan_sip.c: Stopping retransmission on '759cf8475eedfaac2afcb92b7647b93f@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 18041 (handle_request_do): mutex '&p->pvt_lock' freed more times than we've locked!
[Feb 27 17:11:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 18041 (handle_request_do): Error releasing mutex: Operation not permitted
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: That's odd...  Got a response on a call we dont know about. Callid 0616924b7762feb27514c5ef7e45d000@193.179.38.20
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Invalid SIP message - rejected , no callid, len 505
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 5e57d28b71e8f9ed2ff0d98f17c7a896@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 3a8bd07b3200a5050c0a456a0c71113e@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 15ebb8c52a0480e030b9fdf53ba0a48c@10.0.0.2 - REGISTER (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 24dab70c342e5e0837f584335a256c12@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 66e89af857efd1d8309d1e296c55ab17@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 180be8516b398d8c187cf71f7dc16f53@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 2511258049bc28017fb96dbd79f20d4e@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 47f822812d77b5c018c482be1f5006f9@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Missing scheme 'sips:' in 'sip:726@85.216.201.137:5061;transport=TLS'
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:38] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 5791d1eb0e3997d64bc3961f7357694a@193.179.38.20
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:38] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/726
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Checking device state for peer 726
[Feb 27 17:11:38] DEBUG[23606] devicestate.c: Changing state for SIP/726 - state 1 (Not in use)
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Stopping retransmission on '5791d1eb0e3997d64bc3961f7357694a@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:11:38] NOTICE[23606] chan_sip.c: Peer '726' is now Reachable. (143ms / 4000ms)
[Feb 27 17:11:38] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/726
[Feb 27 17:11:38] DEBUG[23606] chan_sip.c: Checking device state for peer 726
[Feb 27 17:11:38] DEBUG[23606] devicestate.c: Changing state for SIP/726 - state 1 (Not in use)
[Feb 27 17:11:39] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:39] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:39] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 085bdd6734e05179719da7e333586951@193.179.38.20
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:39] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:39] DEBUG[23606] chan_sip.c: Stopping retransmission on '085bdd6734e05179719da7e333586951@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:44] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:44] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 4d47de616a90c0eb254d1318762ed2fe@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:11:44] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:11:47] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:47] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:47] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4dd542357ccfbfa5155938ab6305734f@193.179.38.20
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:47] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:47] DEBUG[23606] chan_sip.c: Stopping retransmission on '4dd542357ccfbfa5155938ab6305734f@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:49] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 304a717f7f5fc9ca6c7eaf314cfdb96b@193.179.38.20
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:49] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 20eca8db12af0fbe77be36054cff1348@193.179.38.20
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Stopping retransmission on '20eca8db12af0fbe77be36054cff1348@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:49] DEBUG[23606] chan_sip.c: Stopping retransmission on '304a717f7f5fc9ca6c7eaf314cfdb96b@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:52] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 061f1d8f4e2bb9966a52c5305dc655fd@193.179.38.20
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Stopping retransmission on '061f1d8f4e2bb9966a52c5305dc655fd@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:52] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 162900057ff82cf125d2e50006e8cae5@193.179.38.20
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:52] DEBUG[23606] chan_sip.c: Stopping retransmission on '162900057ff82cf125d2e50006e8cae5@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:55] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 6796e37b0372c279110f58fc37eacd93@193.179.38.20
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Stopping retransmission on '6796e37b0372c279110f58fc37eacd93@193.179.38.20' of Request 102: Match Not Found
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:55] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 614d422054588e6434fabcaa4de973f7@193.179.38.20
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] sched.c: Attempted to delete nonexistent schedule entry 0!
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto UDP socket...
[Feb 27 17:11:55] DEBUG[23606] chan_sip.c: Stopping retransmission on '614d422054588e6434fabcaa4de973f7@193.179.38.20' of Request 102: Match Found
[Feb 27 17:11:58] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Feb 27 17:11:58] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:11:58] DEBUG[23606] chan_sip.c: Initializing initreq for method OPTIONS - callid 4ae6453454f9b413696f10010d2148fd@193.179.38.20
[Feb 27 17:11:58] DEBUG[23606] chan_sip.c: Trying to put 'OPTIONS si' onto TLS socket...
[Feb 27 17:12:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:12:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 5d6b1833070b1ba75b15e60c3a3fbf95@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:12:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:12:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 5 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 10 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 15 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 20 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 25 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 30 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 35 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 40 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 45 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:45] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:12:45] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 6f76788e2d36838c40e931aa709d1509@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:12:45] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:12:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 50 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:50] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:12:50] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 2834cc5a0a4d493b122556db37c4b9c0@192.168.40.4 - REGISTER (No RTP)
[Feb 27 17:12:50] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 40' onto TLS socket...
[Feb 27 17:12:50] DEBUG[23606] chan_sip.c: Missing scheme 'sips:' in 'sip:s@192.168.40.4:5061;transport=TLS'
[Feb 27 17:12:50] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:12:50] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/bill-gw
[Feb 27 17:12:50] DEBUG[23606] chan_sip.c: Checking device state for peer bill-gw
[Feb 27 17:12:50] DEBUG[23606] devicestate.c: Changing state for SIP/bill-gw - state 1 (Not in use)
[Feb 27 17:12:50] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/bill
[Feb 27 17:12:50] DEBUG[23606] chan_sip.c: Checking device state for peer bill
[Feb 27 17:12:50] DEBUG[23606] devicestate.c: Changing state for SIP/bill - state 1 (Not in use)
[Feb 27 17:12:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 55 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:12:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 60 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:12:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:13:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 40569112181c25662b5cf1fd6f5e620c@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:13:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:13:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 65 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 70 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 75 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 80 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 85 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 90 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 95 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 100 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 105 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:45] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:13:45] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 388422b960c45c8259fb3b5f5a385f27@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:13:45] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:13:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 110 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 115 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:13:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 120 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:13:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:14:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 6881af41616b705b3eb718ae55af21c1@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:14:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:14:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 125 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 130 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 135 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 140 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 145 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 150 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 155 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 160 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 165 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:44] DEBUG[23606] chan_sip.c: Missing scheme 'sips:' in 'sip:726@85.216.201.137:5061;transport=TLS'
[Feb 27 17:14:44] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:14:44] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/726
[Feb 27 17:14:44] DEBUG[23606] chan_sip.c: Checking device state for peer 726
[Feb 27 17:14:44] DEBUG[23606] devicestate.c: Changing state for SIP/726 - state 1 (Not in use)
[Feb 27 17:14:46] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:14:46] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 4bca97c55136e3d6325c3d8560278196@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:14:46] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:14:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 170 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 175 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:14:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 180 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:14:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:15:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 236b234306c9d2d32662e2203bb87053@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:15:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:15:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 185 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 190 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 195 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 200 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 205 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 210 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 215 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 220 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 225 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:46] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:15:46] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 6e0c7a08718f17a27eaa7e9976a7a6cd@85.216.201.137 - OPTIONS (No RTP)
[Feb 27 17:15:46] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:15:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 230 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 235 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:15:55] DEBUG[23606] chan_sip.c: Missing scheme 'sips:' in 'sip:s@192.168.40.4:5061;transport=TLS'
[Feb 27 17:15:55] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:15:55] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/bill-gw
[Feb 27 17:15:55] DEBUG[23606] chan_sip.c: Checking device state for peer bill-gw
[Feb 27 17:15:55] DEBUG[23606] devicestate.c: Changing state for SIP/bill-gw - state 1 (Not in use)
[Feb 27 17:15:55] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/bill
[Feb 27 17:15:55] DEBUG[23606] chan_sip.c: Checking device state for peer bill
[Feb 27 17:15:55] DEBUG[23606] devicestate.c: Changing state for SIP/bill - state 1 (Not in use)
[Feb 27 17:15:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 240 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:15:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:16:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 74afd0783f6653f928593d3a42bc3c3b@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:16:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:16:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 245 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 250 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 255 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 260 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 265 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 270 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 275 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 280 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 285 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 290 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 295 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:16:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 300 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:16:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:17:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 2d8c989075785ccf6797e2e72fb64a69@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:17:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:17:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 305 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 310 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 315 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 320 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 325 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 330 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 335 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 340 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 345 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 350 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 355 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:17:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 360 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:17:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:18:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 69cfe9070636df786be885266db6ad6a@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:18:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:18:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 365 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 370 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 375 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 380 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 385 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:23] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 390 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:28] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 395 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:33] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 400 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:38] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 405 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:43] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 410 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:48] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 415 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:53] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:18:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 420 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:18:58] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:19:00] DEBUG[23606] chan_sip.c: Missing scheme 'sips:' in 'sip:s@192.168.40.4:5061;transport=TLS'
[Feb 27 17:19:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 20' onto TLS socket...
[Feb 27 17:19:00] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/bill-gw
[Feb 27 17:19:00] DEBUG[23606] chan_sip.c: Checking device state for peer bill-gw
[Feb 27 17:19:00] DEBUG[23606] devicestate.c: Changing state for SIP/bill-gw - state 1 (Not in use)
[Feb 27 17:19:00] DEBUG[23606] devicestate.c: Notification of state change to be queued on device/channel SIP/bill
[Feb 27 17:19:00] DEBUG[23606] chan_sip.c: Checking device state for peer bill
[Feb 27 17:19:00] DEBUG[23606] devicestate.c: Changing state for SIP/bill - state 1 (Not in use)
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 264017 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 40004 cost path from gsm to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 244017 cost path from gsm to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 40005 cost path from unknown to g723, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 244018 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 64005 cost path from alaw to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 24003 cost path from alaw to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 24003 cost path from alaw to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 268018 cost path from alaw to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 88006 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 264017 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 40004 cost path from gsm to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 244017 cost path from gsm to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 40005 cost path from unknown to g723, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 244018 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 88006 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] acl.c: Found IP address for this socket
[Feb 27 17:19:00] DEBUG[23606] chan_sip.c: Allocating new SIP dialog for 4797270e3f76ec966a2342f3455ea821@192.168.40.4 - OPTIONS (No RTP)
[Feb 27 17:19:00] DEBUG[23606] chan_sip.c: Trying to put 'SIP/2.0 50' onto TLS socket...
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 264017 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 40004 cost path from gsm to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 244017 cost path from gsm to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 40005 cost path from unknown to g723, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 244018 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 88006 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48005 cost path from unknown to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20003 cost path from g723 to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 264017 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 4001 cost path from gsm to unknown, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44004 cost path from gsm to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 248017 cost path from gsm to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44005 cost path from unknown to g723, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 248018 cost path from unknown to unknown, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44003 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 248016 cost path from unknown to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 88006 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48005 cost path from unknown to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20003 cost path from g723 to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 4001 cost path from gsm to unknown, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44004 cost path from gsm to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44005 cost path from unknown to g723, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44003 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 88006 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48005 cost path from unknown to gsm, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 48004 cost path from unknown to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20003 cost path from g723 to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 4001 cost path from gsm to unknown, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44004 cost path from gsm to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44005 cost path from unknown to g723, via 2
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 44003 cost path from unknown to g723, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20003 cost path from g723 to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20002 cost path from g723 to unknown, via 6
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 4001 cost path from gsm to unknown, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 20003 cost path from g723 to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 4001 cost path from gsm to unknown, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Discovered 2 cost path from unknown to gsm, via 3
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] translate.c: Resetting translation matrix
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx app_dial_gosub_virtual_context app_dial
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-guest pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx callout pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-pj-test pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx testservices pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx linestates pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx zamestnanci pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-bill pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx posli_oznameni pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx volej_naptr pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx hledej_jmeno pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx parkedcalls features
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx app_dial_gosub_virtual_context app_dial
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx app_dial_gosub_virtual_context app_dial
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-guest pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx callout pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-pj-test pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx testservices pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx linestates pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx zamestnanci pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-bill pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx posli_oznameni pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx volej_naptr pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx hledej_jmeno pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx parkedcalls features
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-guest pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx from-guest pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx callout pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx callout pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx from-preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-pj-test pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx from-pj-test pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx testservices pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx testservices pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx linestates pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx linestates pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx preciosa pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx zamestnanci pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx zamestnanci pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx from-bill pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx from-bill pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx posli_oznameni pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx posli_oznameni pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx volej_naptr pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx volej_naptr pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx hledej_jmeno pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: delete ctx hledej_jmeno pbx_ael
[Feb 27 17:19:00] DEBUG[23606] pbx.c: check ctx parkedcalls features
[Feb 27 17:19:00] DEBUG[23606] channel.c: Unregistering channel type 'Zap'
[Feb 27 17:19:01] DEBUG[23606] channel.c: Unregistering channel type 'SIP'
[Feb 27 17:19:01] DEBUG[23606] chan_sip.c: ast_wait_for_input returned -1
[Feb 27 17:19:01] DEBUG[23606] chan_sip.c: ast_wait_for_input returned -1
[Feb 27 17:19:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 425 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:19:03] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:19:05] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 21408 (unload_module): Deadlock? waited 5 sec for mutex '&monlock'?
[Feb 27 17:19:05] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 18353 (do_monitor): '&monlock' was locked here.
[Feb 27 17:19:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 430 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:19:08] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:19:10] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 21408 (unload_module): Deadlock? waited 10 sec for mutex '&monlock'?
[Feb 27 17:19:10] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 18353 (do_monitor): '&monlock' was locked here.
[Feb 27 17:19:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 435 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:19:13] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:19:15] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 21408 (unload_module): Deadlock? waited 15 sec for mutex '&monlock'?
[Feb 27 17:19:15] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 18353 (do_monitor): '&monlock' was locked here.
[Feb 27 17:19:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): Deadlock? waited 440 sec for mutex 'p->socket.ser->lock'?
[Feb 27 17:19:18] ERROR[23606] /root/src/asterisk-r104085/include/asterisk/lock.h: chan_sip.c line 2510 (__sip_xmit): 'p->socket.ser->lock' was locked here.
[Feb 27 17:19:27] NOTICE[25058] cdr.c: CDR simple logging enabled.
[Feb 27 17:19:27] NOTICE[25058] loader.c: 81 modules will be loaded.
[Feb 27 17:19:29] NOTICE[25058] pbx_ael.c: Starting AEL load process.
[Feb 27 17:19:29] NOTICE[25058] pbx_ael.c: AEL load process: parsed config file name '/etc/asterisk/extensions.ael'.
[Feb 27 17:19:29] NOTICE[25058] pbx_ael.c: AEL load process: checked config file name '/etc/asterisk/extensions.ael'.
[Feb 27 17:19:29] NOTICE[25058] chan_sip.c: Peer '734' is now Reachable. (20ms / 4000ms)
[Feb 27 17:19:29] NOTICE[25058] chan_sip.c: Peer '743' is now Reachable. (223ms / 4000ms)
[Feb 27 17:19:29] NOTICE[25058] pbx_ael.c: AEL load process: compiled config file name '/etc/asterisk/extensions.ael'.
[Feb 27 17:19:29] NOTICE[25058] pbx_ael.c: AEL load process: merged config file name '/etc/asterisk/extensions.ael'.
[Feb 27 17:19:29] NOTICE[25058] pbx_ael.c: AEL load process: verified config file name '/etc/asterisk/extensions.ael'.
[Feb 27 17:19:29] WARNING[25058] pbx.c: Requested contexts didn't get merged
[Feb 27 17:19:29] NOTICE[25058] chan_sip.c: Peer '777' is now Reachable. (614ms / 4000ms)
[Feb 27 17:19:31] NOTICE[25058] chan_sip.c: Peer '324' is now Reachable. (220ms / 4000ms)
[Feb 27 17:19:31] NOTICE[25058] chan_sip.c: Peer 'prec-gw' is now Reachable. (26ms / 2000ms)
[Feb 27 17:19:32] NOTICE[25058] chan_sip.c: Peer '408' is now Reachable. (54ms / 4000ms)
[Feb 27 17:19:32] NOTICE[25058] chan_sip.c: Peer '726' is now Reachable. (1407ms / 4000ms)
[Feb 27 17:19:36] NOTICE[25058] chan_sip.c: Peer '286' is now Reachable. (101ms / 4000ms)
[Feb 27 17:19:38] NOTICE[25058] chan_sip.c: Peer '355' is now UNREACHABLE!  Last qualify: 0
[Feb 27 17:19:53] NOTICE[25058] chan_sip.c: Peer '355' is now Reachable. (5ms / 4000ms)
[Feb 27 17:20:47] NOTICE[25058] chan_sip.c: Peer '286' is now UNREACHABLE!  Last qualify: 2089
[Feb 27 17:21:17] NOTICE[25058] chan_sip.c: Peer '286' is now Reachable. (2128ms / 4000ms)
[Feb 27 17:22:05] NOTICE[25058] chan_sip.c: Peer 'bill-gw' is now Reachable. (4ms / 2000ms)
[Feb 27 17:22:48] NOTICE[25058] chan_sip.c: Peer '286' is now Lagged. (4123ms / 4000ms)
[Feb 27 17:22:58] NOTICE[25058] chan_sip.c: Peer '286' is now Reachable. (152ms / 4000ms)
[Feb 27 17:23:47] NOTICE[25058] chan_sip.c: Peer '286' is now UNREACHABLE!  Last qualify: 1102
[Feb 27 17:24:00] NOTICE[25058] chan_sip.c: Peer '286' is now Reachable. (3138ms / 4000ms)