Nov 9 16:47:23 VERBOSE[22385]: -- Hungup 'Zap/1-1' Nov 9 16:47:23 DEBUG[22385]: Exiting with DIALSTATUS=CANCEL. Nov 9 16:47:23 VERBOSE[22385]: == Spawn extension (macro-freedialout, s, 2) exited non-zero on 'SIP/support-004-1aea' in $Nov 9 16:47:23 VERBOSE[22385]: == Spawn extension (consoll, 64925201, 3) exited non-zero on 'SIP/support-004-1aea' Nov 9 16:47:23 VERBOSE[22385]: -- Executing Congestion("SIP/support-004-1aea", "") in new stack Nov 9 16:47:23 VERBOSE[22385]: == Spawn extension (consoll, h, 1) exited non-zero on 'SIP/support-004-1aea' Nov 9 16:47:23 DEBUG[22385]: cdr_pgsql: inserting a CDR record. Nov 9 16:47:23 DEBUG[22385]: cdr_pgsql: SQL command executed: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstc$Nov 9 16:47:23 VERBOSE[22385]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer 999999006 Nov 9 16:47:23 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:23 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:23 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:23 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:23 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:23 DEBUG[22385]: Failed to grab lock, trying again... .... Nov 9 16:47:26 DEBUG[22385]: Avoiding initial deadlock for 'SIP/support-004-1aea' Nov 9 16:47:26 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:26 WARNING[22385]: Avoided initial deadlock for 'SIP/support-004-1aea', 10 retries! Nov 9 16:47:26 DEBUG[22385]: Failed to grab lock, trying again... Nov 9 16:47:26 DEBUG[22385]: Failed to grab lock, trying again... .... --- THIS CONTINUES TO SCROLL UNTIL ASTERISK IS STOPPED OR AFTER 10-30 MINUTES --- --- AFTER THIS IT WORKS OK, BUT THE PROBLEM ALWAYS COMES BACK --- --- MORE - using cdr_odbc.so now Nov 13 17:33:38 DEBUG[26409]: (Provisional) Stopping retransmission (but retaining packet) on '2004cb0b5e4912d6766e8b5735a17$Nov 13 17:33:38 VERBOSE[26409]: -- SIP/000006002-f196 is ringing Nov 13 17:33:42 DEBUG[26409]: Auto destroying call 'RA-1131785255749842@192.168.1.5' Nov 13 17:33:51 DEBUG[26409]: Auto destroying call '328512ABE94344BABD380E2285B0EB7B@consoll.no' Nov 13 17:33:54 VERBOSE[26409]: -- Registered SIP '000006002' at 85.164.7.143 port 5060 expires 60 Nov 13 17:33:54 VERBOSE[26409]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer 000006002 Nov 13 17:33:59 VERBOSE[26409]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer 000004003 Nov 13 17:34:04 VERBOSE[26409]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer consoll-012 Nov 13 17:34:07 DEBUG[26409]: update_user_counter(000006002) - decrement outUse counter Nov 13 17:34:07 DEBUG[26409]: Exiting with DIALSTATUS=CANCEL. Nov 13 17:34:07 VERBOSE[26409]: == Spawn extension (macro-dialin, s, 99) exited non-zero on 'SIP/000004003-0c72' in macro $Nov 13 17:34:07 VERBOSE[26409]: == Spawn extension (from-sip, DIAL, 1) exited non-zero on 'SIP/000004003-0c72' Nov 13 17:34:07 VERBOSE[26409]: -- Executing Congestion("SIP/000004003-0c72", "") in new stack Nov 13 17:34:07 VERBOSE[26409]: == Spawn extension (from-sip, h, 1) exited non-zero on 'SIP/000004003-0c72' Nov 13 17:34:07 DEBUG[26409]: Acked pending invite 102 Nov 13 17:34:07 DEBUG[26409]: Stopping retransmission on '2004cb0b5e4912d6766e8b5735a1780e@194.19.112.174' of Request 102: F$Nov 13 17:34:07 DEBUG[26409]: Stopping retransmission on '2004cb0b5e4912d6766e8b5735a1780e@194.19.112.174' of Request 102: F$Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:34:07 DEBUG[26409]: Failed to grab lock, trying again... ---SCROLLING--- Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: Failed to grab lock, trying again... Nov 13 17:49:39 DEBUG[26409]: update_user_counter(000004003) - decrement inUse counter Nov 13 17:49:39 DEBUG[26409]: Stopping retransmission on 'CA-1131899631816619@192.168.1.103' of Response 29: Found Nov 13 17:49:39 DEBUG[26409]: Auto destroying call 'RA-1131642661587472@192.168.2.100' Nov 13 17:49:39 DEBUG[26409]: Auto destroying call 'RA-113087592199374@192.168.1.103' Nov 13 17:49:39 DEBUG[26409]: Auto destroying call 'RA-1130462257263880@192.168.1.55' Nov 13 17:49:39 DEBUG[26409]: Setting NAT on RTP to 4 Nov 13 17:49:39 DEBUG[26409]: Setting NAT on VRTP to 4 Nov 13 17:49:39 DEBUG[26409]: Setting NAT on RTP to 4 Nov 13 17:49:39 DEBUG[26409]: Setting NAT on VRTP to 4 Nov 13 17:49:39 VERBOSE[26409]: -- Registered SIP 'consoll-003' at 85.221.7.57 port 5060 expires 60 Nov 13 17:49:39 VERBOSE[26409]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer consoll-003 Nov 13 17:49:39 VERBOSE[26409]: -- Registered SIP '999999004' at 80.213.80.9 port 10767 expires 60 Nov 13 17:49:39 VERBOSE[26409]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer 999999004 Nov 13 17:49:41 VERBOSE[26409]: -- Registered SIP '999999006' at 85.221.0.54 port 5060 expires 60 Nov 13 17:49:41 VERBOSE[26409]: -- Saved useragent "i3micro Vood 121s_1_9_0_1_GA" for peer 999999006 --- Also seeing the following every now and then. Nov 8 13:53:27 DEBUG[8233]: Auto destroying call 'RA-1130460054553571@192.168.2.100' Nov 8 13:53:28 DEBUG[8233]: Auto destroying call 'RA-113087592199374@192.168.1.103' Nov 8 13:53:32 DEBUG[8233]: Auto destroying call 'B5307A46A3FE4174AE306742F49BA750@consoll.no' Nov 8 13:53:35 DEBUG[8233]: Auto destroying call 'RA-1131448511378735@80.212.38.82' Nov 8 13:53:35 DEBUG[8233]: Auto destroying call '8E2AE18779DE4F83B25A60E3011969CC@consoll.no' Nov 8 13:53:36 DEBUG[8233]: Auto destroying call '29A120E74E174EE29276F30BE1F8C159@consoll.no'