Current-time: Dec 30 01:12:45 PST (UTC -0800) H323-conf-id: 7D376E6A B347526D 2B070C28 05BAC577 SIP Call-ID: YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA. Requests: 5 REQUEST-- Dec 30 01:12:19: Processing request (BE ver1.245.2.11,pid20219): NAS-IP-Address = '82.103.128.3' User-Name = '412X55XXXXX' Called-Station-Id = '4178ABCDEFGH' Calling-Station-Id = '412X55XXXXX' h323-conf-id = '7D376E6A B347526D 2B070C28 05BAC577' call-id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Digest-Attributes = 'User-Name = "412X55XXXXX"' Digest-Attributes = 'Realm = "82.103.128.3"' Digest-Attributes = 'Nonce = "4830db0770c4d1f1fe187b0aa88d8b7a4efd8073"' Digest-Attributes = 'URI = "sip:4178ABCDEFGH@dk1.youroute.net"' Digest-Attributes = 'Method = "INVITE"' Digest-Attributes = 'Algorithm = "MD5"' Digest-Response = 'beaa514a0cb491abae72c3fb9ff014a0' h323-remote-address = '212.147.8.99' h323-ivr-out = 'PortaBilling_Routing:SIP' h323-session-protocol = 'sipv2' h323-ivr-out = 'PortaBilling_AuthMethod:INVITE' h323-ivr-out = 'PortaBilling_Notify:rtpp_level=3' h323-ivr-out = 'PortaBilling_Seed:318065550' NAS-Port = '5060' Dec 30 01:12:19: h323-conf-id=7D376E6A B347526D 2B070C28 05BAC577/1, call-id=YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA./1 Dec 30 01:12:19: H323/SIP call, use h323-conf-id, but remember call-id Dec 30 01:12:19: Checking if this call comes through a VoIP from vendor connection Dec 30 01:12:19: No VoIP from vendor connections were found Dec 30 01:12:19: PrepareNexecute 'AccountAuth' Dec 30 01:12:20: SQL query 'AccountAuth' executed in 0.172362 seconds Dec 30 01:12:20: Found Account: 412X55XXXXX[71344,credit,balance=0.25000,limit=none] of customer Swiss office test[30815,balance=0.25000,limit=11.00000] Dec 30 01:12:20: PrepareNexecute 'GetSessions' Dec 30 01:12:20: SQL query 'GetSessions' executed in 0.001297 seconds Dec 30 01:12:20: Account 412X55XXXXX is not logged in yet Dec 30 01:12:20: Verification of password using method 'digest_response': success Dec 30 01:12:20: PrepareNexecute 'GetAttributeValues' Dec 30 01:12:20: SQL query 'GetAttributeValues' executed in 0.001387 seconds Dec 30 01:12:20: PrepareNexecute 'GetCustomerActiveCalls' Dec 30 01:12:20: SQL query 'GetCustomerActiveCalls' executed in 0.002529 seconds Dec 30 01:12:20: PrepareNexecute 'GetAttributeValues' Dec 30 01:12:20: SQL query 'GetAttributeValues' executed in 0.001413 seconds Dec 30 01:12:20: Applying 'clir' service check translation rule on CLD ... Dec 30 01:12:20: Translation 's/^\*81/Y/; # hide=*81' applied: '4178ABCDEFGH' unchanged Dec 30 01:12:20: Applying customer dialing translation rule on CLD ... Dec 30 01:12:20: Translation 'local_to_e164($_,{cc=>'41',dp=>'0',ip=>'00'});#% cc=41 dp=0 ip=00' applied: '4178ABCDEFGH' unchanged Dec 30 01:12:20: Applying node translation rule on CLD ... Dec 30 01:12:20: Translation 's/^011//; s/^00//; s/^3450011//; s/^3950011//; s/^3542011//; s/^627463//;' applied: '4178ABCDEFGH' unchanged Dec 30 01:12:20: PrepareNexecute 'AccountAuth' Dec 30 01:12:20: SQL query 'AccountAuth' executed in 0.001431 seconds Dec 30 01:12:20: CLD '4178ABCDEFGH' is an off-net number Dec 30 01:12:20: Setting up a new charge with tariff 'private' ... Dec 30 01:12:20: Using peak rate, since no off-peak is defined Dec 30 01:12:20: PrepareNexecute 'GetPricePerDestination*' Dec 30 01:12:20: SQL query 'GetPricePerDestination*' executed in 0.00286 seconds Dec 30 01:12:20: Compute maximum call duration for leg Account 412X55XXXXX to destination with funds 10.75 (customer funds 10.75) Dec 30 01:12:20: Maximum call duration: 5863 announced as 5863 (+1x1x0.11+5862x1x0.11) by rate 10560089 using 318116572 as seed Dec 30 01:12:20: Applying 'clir' service check translation rule on CLD ... Dec 30 01:12:20: Translation 's/^\*81/Y/; # hide=*81' applied: '4178ABCDEFGH' unchanged Dec 30 01:12:20: Remote termination 'PortaSIP': Calculating routing for 4178ABCDEFGH Dec 30 01:12:20: RTP Proxy with origination preference 'Always', Calling party NOT behind NAT. Dec 30 01:12:20: Looking up routes to '4178ABCDEFGH' using '' routing plan Dec 30 01:12:20: PrepareNexecute 'GetRoutingPerDestination*' Dec 30 01:12:20: SQL query 'GetRoutingPerDestination*' executed in 0.004707 seconds Dec 30 01:12:20: Checked 2011-12-30 10:12:20 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 Dec 30 01:12:20: Start of call is peak level 0 Dec 30 01:12:20: Using peak rate, since no off-peak is defined Dec 30 01:12:20: Checked 2011-12-30 10:12:20 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 Dec 30 01:12:20: Start of call is peak level 0 Dec 30 01:12:20: Checked 2011-12-30 10:12:20 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 Dec 30 01:12:20: Start of call is peak level 0 Dec 30 01:12:20: Checked 2011-12-30 10:12:20 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 Dec 30 01:12:20: Start of call is peak level 0 Dec 30 01:12:20: Applying route outgoing CLD translation on '4178ABCDEFGH' ... Dec 30 01:12:20: Translation 's/^(\d)/00$1/;' applied: '4178ABCDEFGH' -> '004178ABCDEFGH' Dec 30 01:12:20: Applying route outgoing CLD translation on '4178ABCDEFGH' ... Dec 30 01:12:20: Translation 's/^(\d)/\+$1/;' applied: '4178ABCDEFGH' -> '+4178ABCDEFGH' Dec 30 01:12:20: Applying route outgoing CLD translation on '4178ABCDEFGH' ... Dec 30 01:12:20: Translation 's/^(\d)/\+$1/;' applied: '4178ABCDEFGH' -> '+4178ABCDEFGH' Dec 30 01:12:20: Applying route outgoing CLD translation on '4178ABCDEFGH' ... Dec 30 01:12:20: Translation 's/^(\d)/00$1/;' applied: '4178ABCDEFGH' -> '004178ABCDEFGH' Dec 30 01:12:20: Applying route outgoing CLI translation on '412X55XXXXX' ... Dec 30 01:12:20: Translation 's/^(\d)/00$1/;' applied: '412X55XXXXX' -> '00412X55XXXXX' Dec 30 01:12:20: Applying route outgoing CLI translation on '412X55XXXXX' ... Dec 30 01:12:20: Translation 's/^(\d)/\+$1/;' applied: '412X55XXXXX' -> '+412X55XXXXX' Dec 30 01:12:20: Applying route outgoing CLI translation on '412X55XXXXX' ... Dec 30 01:12:20: Translation 's/^(\d)/\+$1/;' applied: '412X55XXXXX' -> '+412X55XXXXX' Dec 30 01:12:20: Applying route outgoing CLI translation on '412X55XXXXX' ... Dec 30 01:12:20: Translation 's/^(\d)/00$1/;' applied: '412X55XXXXX' -> '00412X55XXXXX' Dec 30 01:12:20: Result routes to destination '4178ABCDEFGH': 004178ABCDEFGH@212.249.15.5, prio = 5, cost = 0.13772, 'AstSS7 Swi' - to remote GW +4178ABCDEFGH@212.249.15.9, prio = 5, cost = 0.13772, 'OpenSER 9' - to remote GW +4178ABCDEFGH@212.249.15.4, prio = 5, cost = 0.13772, 'OpenSER 3' - to remote GW 004178ABCDEFGH@217.168.45.4, prio = 5, cost = 0.14664, 'Asterisk SS7' - to remote GW 4178ABCDEFGH@80.77.12.195, prio = 4, cost = 0.17930, 'reliance UK' - to remote GW Dec 30 01:12:20: Logging in account '412X55XXXXX'(71344) to '7D376E6A B347526D 2B070C28 05BAC577' Dec 30 01:12:20: PrepareNexecute 'AddSession' Dec 30 01:12:20: SQL query 'AddSession' executed in 0.001175 seconds Dec 30 01:12:20: Authentication acknowledge response h323-ivr-in = PortaBilling_Routing:@;g-hunt=seq h323-ivr-in = PortaBilling_Routing: @;g-hunt=skip;expires=300;credit-time=5863;rtpp=1;patience=20 h323-ivr-in = PortaBilling_Routing: 004178ABCDEFGH@212.249.15.5;cli=00412X55XXXXX h323-ivr-in = PortaBilling_Routing: +4178ABCDEFGH@212.249.15.9;cli=+412X55XXXXX h323-ivr-in = PortaBilling_Routing: +4178ABCDEFGH@212.249.15.4;cli=+412X55XXXXX h323-ivr-in = PortaBilling_Routing: 004178ABCDEFGH@217.168.45.4;cli=00412X55XXXXX h323-ivr-in = PortaBilling_Routing: 4178ABCDEFGH@80.77.12.195 h323-billing-model = 0 h323-ivr-in = Tariff:private h323-ivr-in = PortaBilling_CLI:412X55XXXXX h323-ivr-in = MOH:1 h323-ivr-in = PortaBilling_CompleteNumber:4178ABCDEFGH h323-ivr-in = PortaBilling_Auth_CLD:4178ABCDEFGH h323-ivr-in = PortaBilling_Auth_Reseller_CLD:4178ABCDEFGH h323-ivr-in = DURATION:5863 h323-return-code = 0 h323-currency = CHF h323-credit-time = 5863 h323-preferred-lang = fr Dec 30 01:12:20: ...Done Authorize/INVITE in 0.222209 seconds, 0.189161 spent in SQL queries. --REQUEST REQUEST-- Dec 30 01:12:27: Processing request (BE ver1.245.2.11,pid20219): NAS-IP-Address = '82.103.128.3' User-Name = '412X55XXXXX' Called-Station-Id = '004178ABCDEFGH' Calling-Station-Id = '412X55XXXXX' Acct-Status-Type = 'Start' h323-call-origin = 'originate' h323-call-type = 'VoIP' h323-setup-time = '09:12:20.335 GMT Fri Dec 30 2011' h323-connect-time = '09:12:27.605 GMT Fri Dec 30 2011' h323-conf-id = '7D376E6A B347526D 2B070C28 05BAC577' call-id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Session-Id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Delay-Time = '0' h323-session-protocol = 'sipv2' NAS-Port = '5060' h323-ivr-out = 'PortaBilling_Auth_CLD:4178ABCDEFGH' h323-ivr-out = 'DURATION:5863' h323-remote-address = '212.249.15.5' h323-ivr-out = 'PortaBilling_Seed:318065550' alert-timepoint = '09:12:20.417 GMT Fri Dec 30 2011' Exec-Program-Log = 'porta-billing.pl' Dec 30 01:12:27: h323-conf-id=7D376E6A B347526D 2B070C28 05BAC577/1, call-id=YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA./1 Dec 30 01:12:27: Found a call in cache with such id Dec 30 01:12:27: Copied account: 412X55XXXXX[71344,credit,balance=0.25000,limit=none] of customer Swiss office test[30815,balance=0.25000,limit=11.00000] from '82.103.128.3' into the current request Dec 30 01:12:27: Fixing time with duration 0 seconds from connect time Dec 30 01:12:27: PrepareNexecute 'GetActiveLegIdByAcct' Dec 30 01:12:27: SQL query 'GetActiveLegIdByAcct' executed in 0.003334 seconds Dec 30 01:12:27: Looking up vendor/connection Dec 30 01:12:27: Trying to match connection for call Dec 30 01:12:27: Looking for a connection VoIP/originate Dec 30 01:12:27: Outgoing VoIP, matching by the remote IP address '212.249.15.5' (env 1) Dec 30 01:12:27: Found connection 218 'AstSS7 Swi' to vendor 'Verizon' Dec 30 01:12:27: Found vendor/connection Dec 30 01:12:27: PrepareNexecute 'InsertActiveLeg' Dec 30 01:12:27: SQL query 'InsertActiveLeg' executed in 0.001978 seconds Dec 30 01:12:27: Accounting response Dec 30 01:12:27: ...Done Accounting/Start/originate/VoIP in 0.012073 seconds, 0.005312 spent in SQL queries. --REQUEST REQUEST-- Dec 30 01:12:27: Processing request (BE ver1.245.2.11,pid20219): NAS-IP-Address = '82.103.128.3' User-Name = '412X55XXXXX' Called-Station-Id = '4178ABCDEFGH' Calling-Station-Id = '412X55XXXXX' Acct-Status-Type = 'Start' h323-call-origin = 'answer' h323-call-type = 'VoIP' h323-setup-time = '09:12:20.224 GMT Fri Dec 30 2011' h323-connect-time = '09:12:27.621 GMT Fri Dec 30 2011' h323-conf-id = '7D376E6A B347526D 2B070C28 05BAC577' call-id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Session-Id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Delay-Time = '0' h323-session-protocol = 'sipv2' NAS-Port = '5060' alert-timepoint = '09:12:27.621 GMT Fri Dec 30 2011' h323-remote-address = '212.147.8.99' h323-ivr-out = 'PortaBilling_Seed:318065550' Exec-Program-Log = 'porta-billing.pl' Dec 30 01:12:27: h323-conf-id=7D376E6A B347526D 2B070C28 05BAC577/1, call-id=YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA./1 Dec 30 01:12:27: Found a call in cache with such id Dec 30 01:12:27: Copied account: 412X55XXXXX[71344,credit,balance=0.25000,limit=none] of customer Swiss office test[30815,balance=0.25000,limit=11.00000] from '82.103.128.3' into the current request Dec 30 01:12:27: Fixing time with duration 0 seconds from connect time Dec 30 01:12:27: PrepareNexecute 'GetActiveLegIdByAcct' Dec 30 01:12:27: SQL query 'GetActiveLegIdByAcct' executed in 0.001296 seconds Dec 30 01:12:27: Looking up vendor/connection Dec 30 01:12:27: Trying to match connection for call Dec 30 01:12:27: Looking for a connection VoIP/answer Dec 30 01:12:27: VoIP, matching by the node IP '82.103.128.3' and User-Name '412X55XXXXX' Dec 30 01:12:27: No VoIP from vendor connections were found Dec 30 01:12:27: Connection to vendor not found Dec 30 01:12:27: PrepareNexecute 'InsertActiveLeg' Dec 30 01:12:27: SQL query 'InsertActiveLeg' executed in 0.001392 seconds Dec 30 01:12:27: Accounting response Dec 30 01:12:27: ...Done Accounting/Start/answer/VoIP in 0.009280 seconds, 0.002688 spent in SQL queries. --REQUEST REQUEST-- Dec 30 01:12:33: Processing request (BE ver1.245.2.11,pid20219): NAS-IP-Address = '82.103.128.3' User-Name = '412X55XXXXX' Called-Station-Id = '4178ABCDEFGH' Calling-Station-Id = '412X55XXXXX' Acct-Status-Type = 'Stop' h323-call-origin = 'answer' h323-call-type = 'VoIP' h323-setup-time = '09:12:20.224 GMT Fri Dec 30 2011' h323-connect-time = '09:12:27.224 GMT Fri Dec 30 2011' h323-disconnect-time = '09:12:32.224 GMT Fri Dec 30 2011' h323-disconnect-cause = '0' h323-voice-quality = '0' h323-conf-id = '7D376E6A B347526D 2B070C28 05BAC577' call-id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Session-Id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Session-Time = '5' Acct-Delay-Time = '0' h323-session-protocol = 'sipv2' NAS-Port = '5060' h323-ivr-out = 'PortaBilling_Session:unlock' h323-remote-address = '212.147.8.99' h323-ivr-out = 'PortaBilling_Seed:318065550' Acct-Terminate-Cause = 'User-Request' alert-timepoint = '09:12:27.621 GMT Fri Dec 30 2011' Exec-Program-Log = 'porta-billing.pl' Dec 30 01:12:33: h323-conf-id=7D376E6A B347526D 2B070C28 05BAC577/1, call-id=YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA./1 Dec 30 01:12:33: Found a call in cache with such id Dec 30 01:12:33: Copied account: 412X55XXXXX[71344,credit,balance=0.26,limit=none] of customer Swiss office test[30815,balance=0.26,limit=11.00000] from '82.103.128.3' into the current request Dec 30 01:12:33: PrepareNexecute 'GetActiveLegIdByAcct' Dec 30 01:12:33: SQL query 'GetActiveLegIdByAcct' executed in 0.000924 seconds Dec 30 01:12:33: PrepareNexecute 'DeleteActiveLeg' Dec 30 01:12:33: SQL query 'DeleteActiveLeg' executed in 0.001284 seconds Dec 30 01:12:33: Force unlock requested by NAS Dec 30 01:12:33: Scheduling 412X55XXXXX for logout, call lifetime reduced to 15 Dec 30 01:12:33: PrepareNexecute 'GetSessions' Dec 30 01:12:33: SQL query 'GetSessions' executed in 0.000495 seconds Dec 30 01:12:33: Logging out account '412X55XXXXX'(71344) from '7D376E6A B347526D 2B070C28 05BAC577' Dec 30 01:12:33: PrepareNexecute 'RemoveSession' Dec 30 01:12:33: SQL query 'RemoveSession' executed in 0.004416 seconds Dec 30 01:12:33: Set lifetime with 15s to Fri Dec 30 01:12:48 2011 Dec 30 01:12:33: Looking up vendor/connection Dec 30 01:12:33: Trying to match connection for call Dec 30 01:12:33: Looking for a connection VoIP/answer Dec 30 01:12:33: VoIP, matching by the node IP '82.103.128.3' and User-Name '412X55XXXXX' Dec 30 01:12:33: No VoIP from vendor connections were found Dec 30 01:12:33: Connection to vendor not found Dec 30 01:12:33: No connection from vendor Dec 30 01:12:33: Accounting response Dec 30 01:12:33: ...Done Accounting/Stop/answer/VoIP in 0.014968 seconds, 0.007119 spent in SQL queries. --REQUEST REQUEST-- Dec 30 01:12:33: Processing request (BE ver1.245.2.11,pid20219): NAS-IP-Address = '82.103.128.3' User-Name = '412X55XXXXX' Called-Station-Id = '004178ABCDEFGH' Calling-Station-Id = '412X55XXXXX' Acct-Status-Type = 'Stop' h323-call-origin = 'originate' h323-call-type = 'VoIP' h323-setup-time = '09:12:20.335 GMT Fri Dec 30 2011' h323-connect-time = '09:12:27.335 GMT Fri Dec 30 2011' h323-disconnect-time = '09:12:32.335 GMT Fri Dec 30 2011' h323-disconnect-cause = '0' h323-voice-quality = '0' h323-conf-id = '7D376E6A B347526D 2B070C28 05BAC577' call-id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Session-Id = 'YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA.' Acct-Session-Time = '5' Acct-Delay-Time = '0' h323-session-protocol = 'sipv2' NAS-Port = '5060' h323-ivr-out = 'PortaBilling_Auth_CLD:4178ABCDEFGH' h323-ivr-out = 'DURATION:5863' h323-remote-address = '212.249.15.5' h323-ivr-out = 'PortaBilling_Seed:318065550' Acct-Terminate-Cause = 'User-Request' alert-timepoint = '09:12:20.417 GMT Fri Dec 30 2011' Exec-Program-Log = 'porta-billing.pl' Dec 30 01:12:33: h323-conf-id=7D376E6A B347526D 2B070C28 05BAC577/1, call-id=YzFiMTYzNzI5ODdhYjQxMjE1MWEwNTFmZWI2MjRjMTA./1 Dec 30 01:12:33: Found a call in cache with such id Dec 30 01:12:33: Copied account: 412X55XXXXX[71344,credit,balance=0.25000,limit=none] of customer Swiss office test[30815,balance=0.25000,limit=11.00000] from '82.103.128.3' into the current request Dec 30 01:12:33: PrepareNexecute 'GetActiveLegIdByAcct' Dec 30 01:12:33: SQL query 'GetActiveLegIdByAcct' executed in 0.001378 seconds Dec 30 01:12:33: PrepareNexecute 'DeleteActiveLeg' Dec 30 01:12:33: SQL query 'DeleteActiveLeg' executed in 0.001258 seconds Dec 30 01:12:33: End of the outgoing call for logged in account. Waiting another outgoing call or hang up Dec 30 01:12:33: Set lifetime with 15s to Fri Dec 30 01:12:48 2011 Dec 30 01:12:33: Looking up vendor/connection Dec 30 01:12:33: Trying to match connection for call Dec 30 01:12:33: Looking for a connection VoIP/originate Dec 30 01:12:33: Outgoing VoIP, matching by the remote IP address '212.249.15.5' (env 1) Dec 30 01:12:33: Found connection 218 'AstSS7 Swi' to vendor 'Verizon' Dec 30 01:12:33: Found vendor/connection Dec 30 01:12:33: Applying connection translation rule on CLD ... Dec 30 01:12:33: Translation 's/^00//;' applied: '004178ABCDEFGH' -> '4178ABCDEFGH' Dec 30 01:12:33: Charging call ... Dec 30 01:12:33: Using peak rate, since no off-peak is defined Dec 30 01:12:33: Can reuse the already initialized charge. Dec 30 01:12:33: Calculating account's charge by tariff 'private' Dec 30 01:12:33: Call to '4178ABCDEFGH' with duration 5 seconds will be charged for 5 seconds and cost is 0.01 (1x1x0.11+4x1x0.11^2) by rate 10560089 using 318116572 as seed Dec 30 01:12:33: Setting up a new charge with tariff 'verizon-WHS' ... Dec 30 01:12:33: Checked 2011-12-30 10:12:27 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 Dec 30 01:12:33: Checked 2011-12-30 10:12:32 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 Dec 30 01:12:33: Start of call is peak level 0 Dec 30 01:12:33: End of call is peak level 0 Dec 30 01:12:33: PrepareNexecute 'GetPricePerDestination*' Dec 30 01:12:33: SQL query 'GetPricePerDestination*' executed in 0.001644 seconds Dec 30 01:12:33: Calculating vendor's charge by tariff 'verizon-WHS' Dec 30 01:12:33: Call to '4178ABCDEFGH' with duration 5 seconds will be charged for 5 seconds and cost is 0.0094 (1x1x0.1127+4x1x0.1127) by rate 7430186 using 318116572 as seed Dec 30 01:12:33: Charging account for the call Dec 30 01:12:33: Inserting CDR Dec 30 01:12:33: PrepareNexecute 'InsertAccountCDR' Dec 30 01:12:33: SQL query 'InsertAccountCDR' executed in 0.001531 seconds Dec 30 01:12:33: Charging credit account 412X55XXXXX 0.01 Dec 30 01:12:33: PrepareNexecute 'UpdateAccountBalance' Dec 30 01:12:33: SQL query 'UpdateAccountBalance' executed in 0.000859 seconds Dec 30 01:12:33: Charging account's owner for the call Dec 30 01:12:33: Charging customer 30815 'Swiss office test' 0.01 Dec 30 01:12:33: PrepareNexecute 'UpdateCustomerBalance' Dec 30 01:12:33: SQL query 'UpdateCustomerBalance' executed in 0.002304 seconds Dec 30 01:12:33: Charging vendor for the call Dec 30 01:12:33: Charging vendor 55 'Verizon' 0.0094 Dec 30 01:12:33: Inserting CDR Dec 30 01:12:33: PrepareNexecute 'InsertVendorCDR' Dec 30 01:12:33: SQL query 'InsertVendorCDR' executed in 0.001566 seconds Dec 30 01:12:33: PrepareNexecute 'UpdateVendorBalance' Dec 30 01:12:33: SQL query 'UpdateVendorBalance' executed in 0.001223 seconds Dec 30 01:12:33: Accounting response Dec 30 01:12:33: ...Done Accounting/Stop/originate/VoIP in 0.030627 seconds, 0.011763 spent in SQL queries. --REQUEST