Current-time: Jan 29 05:06:10 PST (UTC -0800) H323-conf-id: 2B0DC894 610BE37C 6CEDBF39 371800DD SIP Call-ID: 94.23.242.200-1264770327.92 SIP Call-ID: 94.23.225.212-1264770328.10 Requests: 7 REQUEST-- Jan 29 05:05:28: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.225.212' NAS-Port-Name = 'SIP/94.23.242.200-007370f0' User-Name = '94.23.242.200' Called-Station-Id = '41216912818' Calling-Station-Id = '41215500329' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.225.212-1264770328.10' Password = 'xxxxxxxxx' h323-ivr-out = 'PortaBilling_Routing:SIP' Jan 29 05:05:28: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.225.212-1264770328.10/1 Jan 29 05:05:28: H323/SIP call, use h323-conf-id, but remember call-id Jan 29 05:05:28: Authorization of the trusted node Jan 29 05:05:28: IP based authorization, force 'plaintext' password verification Jan 29 05:05:28: Verification of password using method 'plaintext': success Jan 29 05:05:28: '94.23.242.200' is a trusted node's ID or IP, unable to use it as an account ID. Correct account ID should be available on the other call legs Jan 29 05:05:28: PrepareNexecute 'AccountAuth' Jan 29 05:05:28: SQL query 'AccountAuth' executed in 0.00196 seconds Jan 29 05:05:28: CLD '41216912818' is an off-net number Jan 29 05:05:28: Remote termination 'PortaSIP': Calculating routing for 41216912818 Jan 29 05:05:28: Falling back to default routing since no routing plan information is available Jan 29 05:05:28: RTP Proxy with origination preference '', Calling party NOT behind NAT. Jan 29 05:05:28: Looking up routes to '41216912818' using '' routing plan Jan 29 05:05:28: PrepareNexecute 'GetRoutingPerDestination*' Jan 29 05:05:28: SQL query 'GetRoutingPerDestination*' executed in 0.006019 seconds Jan 29 05:05:28: Checked 2010-01-29 14:05:28 Europe/Zurich against 'hr{17-7} wd{mo-fr }, wd{sa su }, md{25} mo{dec}, md{26} mo{dec}, md{1} mo{jan}, md{2} mo{jan}, md{10} mo{apr}, md{13} mo{apr}, md{1} mo{may}, md{1} mo{jun}, md{1} mo{aug}': 0 Jan 29 05:05:28: Start of call is peak level 0 Jan 29 05:05:28: Using peak rate, since no off-peak is defined Jan 29 05:05:28: Checked 2010-01-29 14:05:28 Europe/Zurich against 'hr{17-7} wd{mo-fr }, wd{sa su }, md{25} mo{dec}, md{26} mo{dec}, md{1} mo{jan}, md{2} mo{jan}, md{10} mo{apr}, md{13} mo{apr}, md{1} mo{may}, md{1} mo{jun}, md{1} mo{aug}': 0 Jan 29 05:05:28: Start of call is peak level 0 Jan 29 05:05:28: Checked 2010-01-29 14:05:28 Europe/Zurich against 'hr{17-7} wd{mo-fr }, wd{sa su }, md{25} mo{dec}, md{26} mo{dec}, md{1} mo{jan}, md{2} mo{jan}, md{10} mo{apr}, md{13} mo{apr}, md{1} mo{may}, md{1} mo{jun}, md{1} mo{aug}': 0 Jan 29 05:05:28: Start of call is peak level 0 Jan 29 05:05:28: Using peak rate, since no off-peak is defined Jan 29 05:05:28: Using peak rate, since no off-peak is defined Jan 29 05:05:28: Using peak rate, since no off-peak is defined Jan 29 05:05:28: Checked 2010-01-29 14:05:28 Europe/Zurich against 'hr{17-7} wd{mo-fr }, wd{sa su }, md{25} mo{dec}, md{26} mo{dec}, md{1} mo{jan}, md{2} mo{jan}, md{10} mo{apr}, md{13} mo{apr}, md{1} mo{may}, md{1} mo{jun}, md{1} mo{aug}': 0 Jan 29 05:05:28: Start of call is peak level 0 Jan 29 05:05:28: Using peak rate, since no off-peak is defined Jan 29 05:05:28: Applying route outgoing CLD translation on '41216912818' ... Jan 29 05:05:28: Translation 's/^(\d)/\+$1/;' applied: '41216912818' -> '+41216912818' Jan 29 05:05:28: Applying route outgoing CLD translation on '41216912818' ... Jan 29 05:05:28: Translation 's/^(\d)/\+$1/;' applied: '41216912818' -> '+41216912818' Jan 29 05:05:28: Applying route outgoing CLD translation on '41216912818' ... Jan 29 05:05:28: Translation 's/^(\d)/00$1/;' applied: '41216912818' -> '0041216912818' Jan 29 05:05:28: Applying route outgoing CLD translation on '41216912818' ... Jan 29 05:05:28: Translation 's/^(\d)/\+$1/' applied: '41216912818' -> '+41216912818' Jan 29 05:05:28: Applying route outgoing CLI translation on '41215500329' ... Jan 29 05:05:28: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 05:05:28: Applying route outgoing CLI translation on '41215500329' ... Jan 29 05:05:28: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 05:05:28: Applying route outgoing CLI translation on '41215500329' ... Jan 29 05:05:28: Translation 's/^(\d)/00$1/;' applied: '41215500329' -> '0041215500329' Jan 29 05:05:28: Applying route outgoing CLI translation on '41215500329' ... Jan 29 05:05:28: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 05:05:28: Result routes to destination '41216912818': +41216912818@212.249.15.3, prio = 10, cost = 0.01120, 'OpenSER 3' - to remote GW +41216912818@212.249.15.9, prio = 10, cost = 0.01120, 'OpenSER 9' - to remote GW 0041216912818@217.168.45.4, prio = 9, cost = 0.00000, 'Asterisk SS7' - to remote GW +41216912818@87.106.213.52, prio = 8, cost = 0.00000, 'OpenSER UK' - to remote GW Jan 29 05:05:28: Authentication acknowledge response h323-ivr-in = PortaBilling_Routing:@;g-hunt=seq h323-ivr-in = PortaBilling_Routing: @;g-hunt=skip;expires=300;credit-time=-1;cli=+41215500329;patience=20 h323-ivr-in = PortaBilling_Routing: +41216912818@212.249.15.3 h323-ivr-in = PortaBilling_Routing: +41216912818@212.249.15.9 h323-ivr-in = PortaBilling_Routing: 0041216912818@217.168.45.4;cli=0041215500329;rtpp=1 h323-ivr-in = PortaBilling_Routing: +41216912818@87.106.213.52 h323-ivr-in = PortaBilling_CacheExpiration:Never h323-ivr-in = PortaBilling_CLI:41215500329 h323-ivr-in = PortaBilling_CompleteNumber:41216912818 h323-ivr-in = PortaBilling_Auth_CLD:41216912818 h323-ivr-in = PortaBilling_Auth_Reseller_CLD:41216912818 h323-return-code = 13 Jan 29 05:05:28: ...Done Authorize in 0.038872 seconds, 0.007979 spent in SQL queries. --REQUEST REQUEST-- Jan 29 05:05:35: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.225.212' User-Name = '94.23.242.200' Called-Station-Id = '+41216912818' Calling-Station-Id = '41215500329' Acct-Status-Type = 'Start' h323-call-origin = 'originate' h323-call-type = 'VoIP' h323-setup-time = '13:05:28 GMT Fri Jan 29 2010' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.225.212-1264770328.10' session-protocol = 'sipv2' h323-remote-address = '212.249.15.3' Exec-Program-Log = 'porta-billing.pl' Jan 29 05:05:35: fixup: Using value '13:05:28 GMT Fri Jan 29 2010' to supply missing h323-connect-time attribute in Start accounting Jan 29 05:05:35: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.225.212-1264770328.10/1 Jan 29 05:05:35: H323/SIP call, use h323-conf-id, but remember call-id Jan 29 05:05:35: Fixing time with duration 0 seconds from connect time Jan 29 05:05:35: PrepareNexecute 'GetActiveLegIdByAcct' Jan 29 05:05:35: SQL query 'GetActiveLegIdByAcct' executed in 0.001143 seconds Jan 29 05:05:35: '94.23.242.200' is a trusted node's ID or IP, unable to use it as an account ID. Correct account ID should be available on the other call legs Jan 29 05:05:35: Looking up vendor/connection Jan 29 05:05:35: Trying to match connection for call Jan 29 05:05:35: Looking for a connection VoIP/originate Jan 29 05:05:35: Outgoing VoIP, matching by the remote IP address '212.249.15.3' (env 1) Jan 29 05:05:35: Found connection 205 'OpenSER 3' to vendor 'Verizon' Jan 29 05:05:35: Found vendor/connection Jan 29 05:05:35: PrepareNexecute 'InsertActiveLeg' Jan 29 05:05:35: SQL query 'InsertActiveLeg' executed in 0.001167 seconds Jan 29 05:05:35: Accounting response Jan 29 05:05:35: ...Done Accounting/Start/originate/VoIP in 0.010391 seconds, 0.002310 spent in SQL queries. --REQUEST REQUEST-- Jan 29 05:05:36: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.225.212' User-Name = '94.23.242.200' Called-Station-Id = '41216912818' Calling-Station-Id = '41215500329' Acct-Status-Type = 'Start' h323-call-origin = 'answer' h323-call-type = 'VoIP' h323-setup-time = '13:05:28 GMT Fri Jan 29 2010' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.225.212-1264770328.10' session-protocol = 'sipv2' h323-remote-address = '94.23.242.200' Exec-Program-Log = 'porta-billing.pl' Jan 29 05:05:36: fixup: Using value '13:05:28 GMT Fri Jan 29 2010' to supply missing h323-connect-time attribute in Start accounting Jan 29 05:05:36: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.225.212-1264770328.10/1 Jan 29 05:05:36: Found a call in cache with such id Jan 29 05:05:36: Fixing time with duration 0 seconds from connect time Jan 29 05:05:36: PrepareNexecute 'GetActiveLegIdByAcct' Jan 29 05:05:36: SQL query 'GetActiveLegIdByAcct' executed in 0.006134 seconds Jan 29 05:05:36: '94.23.242.200' is a trusted node's ID or IP, unable to use it as an account ID. Correct account ID should be available on the other call legs Jan 29 05:05:36: Looking up vendor/connection Jan 29 05:05:36: Trying to match connection for call Jan 29 05:05:36: Looking for a connection VoIP/answer Jan 29 05:05:36: VoIP, matching by the node IP '94.23.225.212' and User-Name '94.23.242.200' Jan 29 05:05:36: No VoIP from vendor connections were found Jan 29 05:05:36: Connection to vendor not found Jan 29 05:05:36: PrepareNexecute 'InsertActiveLeg' Jan 29 05:05:36: SQL query 'InsertActiveLeg' executed in 0.001438 seconds Jan 29 05:05:36: Accounting response Jan 29 05:05:36: ...Done Accounting/Start/answer/VoIP in 0.012918 seconds, 0.007572 spent in SQL queries. --REQUEST REQUEST-- Jan 29 05:05:45: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.225.212' User-Name = '94.23.242.200' Called-Station-Id = '+41216912818' Calling-Station-Id = '41215500329' Acct-Status-Type = 'Stop' h323-call-origin = 'originate' h323-call-type = 'VoIP' h323-setup-time = '13:05:28 GMT Fri Jan 29 2010' h323-connect-time = '13:05:36 GMT Fri Jan 29 2010' h323-disconnect-time = '13:05:45 GMT Fri Jan 29 2010' h323-disconnect-cause = '10' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.225.212-1264770328.10' Acct-Session-Time = '9' session-protocol = 'sipv2' h323-remote-address = '212.249.15.3' Exec-Program-Log = 'porta-billing.pl' Jan 29 05:05:45: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.225.212-1264770328.10/1 Jan 29 05:05:45: Found a call in cache with such id Jan 29 05:05:45: PrepareNexecute 'GetActiveLegIdByAcct' Jan 29 05:05:45: SQL query 'GetActiveLegIdByAcct' executed in 0.001433 seconds Jan 29 05:05:45: PrepareNexecute 'DeleteActiveLeg' Jan 29 05:05:45: SQL query 'DeleteActiveLeg' executed in 0.000808 seconds Jan 29 05:05:45: User-Name needs correction. Postponing. Jan 29 05:05:45: Accounting response Jan 29 05:05:45: ...Done Accounting/Stop/originate/VoIP in 0.009118 seconds, 0.002241 spent in SQL queries. --REQUEST REQUEST-- Jan 29 05:05:45: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.242.200' User-Name = '41215500329' Called-Station-Id = '+41216912818' Calling-Station-Id = '41215500329' Acct-Status-Type = 'Stop' h323-call-origin = 'originate' h323-call-type = 'VoIP' h323-setup-time = '13:05:28 GMT Fri Jan 29 2010' h323-connect-time = '13:05:35 GMT Fri Jan 29 2010' h323-disconnect-time = '13:05:45 GMT Fri Jan 29 2010' h323-disconnect-cause = '10' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.242.200-1264770327.92' Acct-Session-Time = '10' session-protocol = 'sipv2' h323-remote-address = '94.23.225.212' Exec-Program-Log = 'porta-billing.pl' Jan 29 05:05:45: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.242.200-1264770327.92/1 Jan 29 05:05:45: Found a call in cache with such id Jan 29 05:05:46: PrepareNexecute 'GetActiveLegIdByAcct' Jan 29 05:05:46: SQL query 'GetActiveLegIdByAcct' executed in 0.001062 seconds Jan 29 05:05:46: PrepareNexecute 'AccountAccounting' Jan 29 05:05:46: SQL query 'AccountAccounting' executed in 0.002002 seconds Jan 29 05:05:46: Found Account: 41215500329[58048,credit,balance=0.18000,limit=none] of customer Swiss offices[16165,balance=631.40000,limit=700.00000] Jan 29 05:05:46: End of the outgoing call for account, which appears as never logged-in Jan 29 05:05:46: Set lifetime with 15s to Fri Jan 29 05:06:01 2010 Jan 29 05:05:46: Looking up vendor/connection Jan 29 05:05:46: Trying to match connection for call Jan 29 05:05:46: Looking for a connection VoIP/originate Jan 29 05:05:46: Outgoing VoIP, matching by the remote IP address '94.23.225.212' (env 1) Jan 29 05:05:46: Call goes to our trusted node astrad.switzernet.com, on net leg Jan 29 05:05:46: Connection to vendor not found Jan 29 05:05:46: No connection to vendor - on-net call leg Jan 29 05:05:46: Copying randomization seed '32429' into request's PortaBilling_Seed attribute Jan 29 05:05:46: Copied account: 41215500329[58048,credit,balance=0.18000,limit=none] of customer Swiss offices[16165,balance=631.40000,limit=700.00000] into cached request, early attemt to charge ... Jan 29 05:05:46: Looking up vendor/connection Jan 29 05:05:46: Trying to match connection for call Jan 29 05:05:46: Looking for a connection VoIP/originate Jan 29 05:05:46: Outgoing VoIP, matching by the remote IP address '212.249.15.3' (env 1) Jan 29 05:05:46: Found connection 205 'OpenSER 3' to vendor 'Verizon' Jan 29 05:05:46: Found vendor/connection Jan 29 05:05:46: Applying connection translation rule on CLD ... Jan 29 05:05:46: Translation 's/^\+//;' applied: '+41216912818' -> '41216912818' Jan 29 05:05:46: Charging call ... Jan 29 05:05:46: Setting up a new charge with tariff 'business' ... Jan 29 05:05:46: Using peak rate, since no off-peak is defined Jan 29 05:05:46: PrepareNexecute 'GetPricePerDestination*' Jan 29 05:05:46: SQL query 'GetPricePerDestination*' executed in 0.001667 seconds Jan 29 05:05:46: Calculating account's charge by tariff 'business' Jan 29 05:05:46: Call to '41216912818' with duration 9 seconds will be charged for 9 seconds and cost is 0.01 (1x1x0.02+8x1x0.02^2) by rate 2487323 using 73061 as seed Jan 29 05:05:46: Setting up a new charge with tariff 'verizon-PRE' ... Jan 29 05:05:46: Checked 2010-01-29 14:05:36 Europe/Zurich against 'hr{17-7} wd{mo-fr }, wd{sa su }, md{25} mo{dec}, md{26} mo{dec}, md{1} mo{jan}, md{2} mo{jan}, md{10} mo{apr}, md{13} mo{apr}, md{1} mo{may}, md{1} mo{jun}, md{1} mo{aug}': 0 Jan 29 05:05:46: Checked 2010-01-29 14:05:45 Europe/Zurich against 'hr{17-7} wd{mo-fr }, wd{sa su }, md{25} mo{dec}, md{26} mo{dec}, md{1} mo{jan}, md{2} mo{jan}, md{10} mo{apr}, md{13} mo{apr}, md{1} mo{may}, md{1} mo{jun}, md{1} mo{aug}': 0 Jan 29 05:05:46: Start of call is peak level 0 Jan 29 05:05:46: End of call is peak level 0 Jan 29 05:05:46: PrepareNexecute 'GetPricePerDestination*' Jan 29 05:05:46: SQL query 'GetPricePerDestination*' executed in 0.00195 seconds Jan 29 05:05:46: Calculating vendor's charge by tariff 'verizon-PRE' Jan 29 05:05:46: Call to '41216912818' with duration 9 seconds will be charged for 9 seconds and cost is 0.01057 (1x1x0.5398+8x1x0.0118) by rate 3530937 using 73061 as seed Jan 29 05:05:46: Charging account for the call Jan 29 05:05:46: Inserting CDR Jan 29 05:05:46: PrepareNexecute 'InsertAccountCDR' Jan 29 05:05:46: SQL query 'InsertAccountCDR' executed in 0.001505 seconds Jan 29 05:05:46: Charging credit account 41215500329 0.01 Jan 29 05:05:46: PrepareNexecute 'UpdateAccountBalance' Jan 29 05:05:46: SQL query 'UpdateAccountBalance' executed in 0.000925 seconds Jan 29 05:05:46: Charging account's owner for the call Jan 29 05:05:46: Charging customer 16165 'Swiss offices' 0.01 Jan 29 05:05:46: PrepareNexecute 'UpdateCustomerBalance' Jan 29 05:05:46: SQL query 'UpdateCustomerBalance' executed in 0.000838 seconds Jan 29 05:05:46: Charging vendor for the call Jan 29 05:05:46: Charging vendor 55 'Verizon' 0.01057 Jan 29 05:05:46: Inserting CDR Jan 29 05:05:46: PrepareNexecute 'InsertVendorCDR' Jan 29 05:05:46: SQL query 'InsertVendorCDR' executed in 0.001069 seconds Jan 29 05:05:46: PrepareNexecute 'UpdateVendorBalance' Jan 29 05:05:46: SQL query 'UpdateVendorBalance' executed in 0.001143 seconds Jan 29 05:05:46: Accounting response Jan 29 05:05:46: ...Done Accounting/Stop/originate/VoIP in 0.032872 seconds, 0.012161 spent in SQL queries. --REQUEST REQUEST-- Jan 29 05:05:46: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.225.212' User-Name = '94.23.242.200' Called-Station-Id = '41216912818' Calling-Station-Id = '41215500329' Acct-Status-Type = 'Stop' h323-call-origin = 'answer' h323-call-type = 'VoIP' h323-setup-time = '13:05:28 GMT Fri Jan 29 2010' h323-connect-time = '13:05:36 GMT Fri Jan 29 2010' h323-disconnect-time = '13:05:45 GMT Fri Jan 29 2010' h323-disconnect-cause = '10' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.225.212-1264770328.10' Acct-Session-Time = '9' session-protocol = 'sipv2' h323-remote-address = '94.23.242.200' Exec-Program-Log = 'porta-billing.pl' Jan 29 05:05:46: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.225.212-1264770328.10/1 Jan 29 05:05:46: Found a call in cache with such id Jan 29 05:05:46: Replacing username '94.23.242.200' with the real username '41215500329' Jan 29 05:05:46: Copied account: 41215500329[58048,credit,balance=0.19,limit=none] of customer Swiss offices[16165,balance=631.41,limit=700.00000] from '94.23.242.200' into the current request Jan 29 05:05:46: PrepareNexecute 'GetActiveLegIdByAcct' Jan 29 05:05:46: SQL query 'GetActiveLegIdByAcct' executed in 0.086826 seconds Jan 29 05:05:46: PrepareNexecute 'DeleteActiveLeg' Jan 29 05:05:46: SQL query 'DeleteActiveLeg' executed in 0.001076 seconds Jan 29 05:05:46: End of call on incoming call leg from access NAS Jan 29 05:05:46: No logged in accounts, call lifetime reduced to 15 Jan 29 05:05:46: Set lifetime with 15s to Fri Jan 29 05:06:01 2010 Jan 29 05:05:46: Looking up vendor/connection Jan 29 05:05:46: Trying to match connection for call Jan 29 05:05:46: Looking for a connection VoIP/answer Jan 29 05:05:46: VoIP, matching by the node IP '94.23.225.212' and User-Name '94.23.242.200' Jan 29 05:05:46: No VoIP from vendor connections were found Jan 29 05:05:46: Connection to vendor not found Jan 29 05:05:46: No connection from vendor Jan 29 05:05:46: Accounting response Jan 29 05:05:46: ...Done Accounting/Stop/answer/VoIP in 0.094271 seconds, 0.087902 spent in SQL queries. --REQUEST REQUEST-- Jan 29 05:05:46: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.242.200' User-Name = '41215500329' Called-Station-Id = '0216912818' Calling-Station-Id = '41215500329' Acct-Status-Type = 'Stop' h323-call-origin = 'answer' h323-call-type = 'VoIP' h323-setup-time = '13:05:27 GMT Fri Jan 29 2010' h323-connect-time = '13:05:35 GMT Fri Jan 29 2010' h323-disconnect-time = '13:05:45 GMT Fri Jan 29 2010' h323-disconnect-cause = '10' h323-conf-id = '2B0DC894 610BE37C 6CEDBF39 371800DD' call-id = '94.23.242.200-1264770327.92' Acct-Session-Time = '10' session-protocol = 'sipv2' h323-remote-address = '94.23.242.200' Exec-Program-Log = 'porta-billing.pl' Jan 29 05:05:46: h323-conf-id=2B0DC894 610BE37C 6CEDBF39 371800DD/1, call-id=94.23.242.200-1264770327.92/1 Jan 29 05:05:46: Found a call in cache with such id Jan 29 05:05:46: Copied account: 41215500329[58048,credit,balance=0.19,limit=none] of customer Swiss offices[16165,balance=631.41,limit=700.00000] from '94.23.242.200' into the current request Jan 29 05:05:46: PrepareNexecute 'GetActiveLegIdByAcct' Jan 29 05:05:46: SQL query 'GetActiveLegIdByAcct' executed in 0.001091 seconds Jan 29 05:05:46: End of call on incoming call leg from access NAS Jan 29 05:05:46: No logged in accounts, call lifetime reduced to 15 Jan 29 05:05:46: Set lifetime with 15s to Fri Jan 29 05:06:01 2010 Jan 29 05:05:46: Looking up vendor/connection Jan 29 05:05:46: Trying to match connection for call Jan 29 05:05:46: Looking for a connection VoIP/answer Jan 29 05:05:46: VoIP, matching by the node IP '94.23.242.200' and User-Name '41215500329' Jan 29 05:05:46: No VoIP from vendor connections were found Jan 29 05:05:46: Connection to vendor not found Jan 29 05:05:46: No connection from vendor Jan 29 05:05:46: Accounting response Jan 29 05:05:46: ...Done Accounting/Stop/answer/VoIP in 0.006743 seconds, 0.001091 spent in SQL queries. --REQUEST CALL-- Jan 29 05:06:25: Removing call 2B0DC894 610BE37C 6CEDBF39 371800DD/1 Jan 29 05:06:25: No logged in accounts, call lifetime reduced to 15 Jan 29 05:06:25: Cleaning up the call Jan 29 05:06:25: Found more than one potential candidate to restore username: 2B0DC894 610BE37C 6CEDBF39 371800DD originate/VoIP: 41215500329 2B0DC894 610BE37C 6CEDBF39 371800DD originate/VoIP: 41215500329 Jan 29 05:06:25: Attempt to fix usernames for 2 requests Jan 29 05:06:25: Changing '94.23.242.200' -> '41215500329' Jan 29 05:06:25: Copying randomization seed '32429' into request's PortaBilling_Seed attribute Jan 29 05:06:25: Copied account: 41215500329[58048,credit,balance=0.19,limit=none] of customer Swiss offices[16165,balance=631.41,limit=700.00000] into the fixed request Jan 29 05:06:25: Changing '94.23.242.200' -> '41215500329' Jan 29 05:06:25: Copying randomization seed '32429' into request's PortaBilling_Seed attribute Jan 29 05:06:25: Copied account: 41215500329[58048,credit,balance=0.19,limit=none] of customer Swiss offices[16165,balance=631.41,limit=700.00000] into the fixed request Jan 29 05:06:25: Processing answer/VoIP call leg Jan 29 05:06:25: Processing answer/VoIP call leg Jan 29 05:06:25: Processing answer/VoIP call leg Jan 29 05:06:25: Skipping Start accounting Jan 29 05:06:25: Processing originate/VoIP call leg Jan 29 05:06:25: Processing originate/VoIP call leg Jan 29 05:06:25: Skipping Start accounting Jan 29 05:06:25: Processing originate/VoIP call leg Jan 29 05:06:25: There are no unsaved CDRs for this call left Jan 29 05:06:25: Call '2B0DC894 610BE37C 6CEDBF39 371800DD/1' deleted from the cache Jan 29 05:06:25: Call 2B0DC894 610BE37C 6CEDBF39 371800DD/1 removed --CALL