Current-time: Jan 29 10:34:44 PST (UTC -0800) H323-conf-id: 6B792B44 670A8D27 4C39EEB7 52640EED SIP Call-ID: 94.23.225.212-1264789977.6 SIP Call-ID: 94.23.242.200-1264789976.118 Requests: 6 REQUEST-- Jan 29 10:32:56: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.242.200' NAS-Port-Name = 'SIP/41215500329-086fca10' User-Name = '41215500329' Called-Station-Id = '0216912818' Calling-Station-Id = '41215500329' h323-conf-id = '6B792B44 670A8D27 4C39EEB7 52640EED' call-id = '94.23.242.200-1264789976.118' Digest-Attributes = 'User-Name = "41215500329"' Digest-Attributes = 'Realm = "switzernet"' Digest-Attributes = 'Nonce = "0108f579"' Digest-Attributes = 'URI = "sip:0216912818@94.23.242.200"' Digest-Attributes = 'Method = "INVITE"' Digest-Attributes = 'Algorithm = "MD5"' Digest-Response = '03d9ad7855b879bdfc956292666ee858' h323-ivr-out = 'PortaBilling_Routing:SIP' Jan 29 10:32:56: h323-conf-id=6B792B44 670A8D27 4C39EEB7 52640EED/1, call-id=94.23.242.200-1264789976.118/1 Jan 29 10:32:56: H323/SIP call, use h323-conf-id, but remember call-id Jan 29 10:32:56: Checking if this call comes through a VoIP from vendor connection Jan 29 10:32:56: No VoIP from vendor connections were found Jan 29 10:32:56: PrepareNexecute 'AccountAuth' Jan 29 10:32:56: SQL query 'AccountAuth' executed in 0.001941 seconds Jan 29 10:32:56: Found Account: 41215500329[58048,credit,balance=0.23000,limit=none] of customer Swiss offices[16165,balance=633.51000,limit=700.00000] Jan 29 10:32:56: PrepareNexecute 'GetSessions' Jan 29 10:32:56: SQL query 'GetSessions' executed in 0.001231 seconds Jan 29 10:32:56: Account 41215500329 is already logged in, other session with call ID=2129AF17 60390377 2722CBBF 613CEA4D Jan 29 10:32:56: Verification of password using method 'digest_response': success Jan 29 10:32:56: Applying customer dialing translation rule on CLD ... Jan 29 10:32:56: Translation 'local_to_e164($_,{cc=>'41',dp=>'0',ip=>'00'});#% cc=41 dp=0 ip=00' applied: '0216912818' -> '41216912818' Jan 29 10:32:56: PrepareNexecute 'AccountAuth' Jan 29 10:32:56: SQL query 'AccountAuth' executed in 0.001463 seconds Jan 29 10:32:56: CLD '41216912818' is an off-net number Jan 29 10:32:56: Setting up a new charge with tariff 'business' ... Jan 29 10:32:56: Using peak rate, since no off-peak is defined Jan 29 10:32:56: PrepareNexecute 'GetPricePerDestination*' Jan 29 10:32:56: SQL query 'GetPricePerDestination*' executed in 0.001802 seconds Jan 29 10:32:56: Compute maximum call duration for leg Account 41215500329 to destination with funds 66.49 (customer funds 66.49 reduced to 66.49) Jan 29 10:32:56: Maximum call duration: 199470 announced as 199470 (+1x1x0.02+199469x1x0.02) by rate 2487323 using 50711 as seed Jan 29 10:32:56: Remote termination 'PortaSIP': Calculating routing for 41216912818 Jan 29 10:32:56: RTP Proxy with origination preference 'Undetermined', Calling party NOT behind NAT. Jan 29 10:32:56: Looking up routes to '41216912818' using '' routing plan Jan 29 10:32:56: PrepareNexecute 'GetRoutingPerDestination*' Jan 29 10:32:56: SQL query 'GetRoutingPerDestination*' executed in 0.006183 seconds Jan 29 10:32:56: Checked 2010-01-29 19:32:56 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}': 1 Jan 29 10:32:56: Start of call is peak level 1 Jan 29 10:32:56: Using peak rate, since no off-peak is defined Jan 29 10:32:56: Checked 2010-01-29 19:32:56 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}': 1 Jan 29 10:32:56: Start of call is peak level 1 Jan 29 10:32:56: Checked 2010-01-29 19:32:56 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}': 1 Jan 29 10:32:56: Start of call is peak level 1 Jan 29 10:32:56: Using peak rate, since no off-peak is defined Jan 29 10:32:56: Using peak rate, since no off-peak is defined Jan 29 10:32:56: Using peak rate, since no off-peak is defined Jan 29 10:32:56: Checked 2010-01-29 19:32:56 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}': 1 Jan 29 10:32:56: Start of call is peak level 1 Jan 29 10:32:56: Using peak rate, since no off-peak is defined Jan 29 10:32:56: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:56: Translation 's/^(\d)/\+$1/;' applied: '41216912818' -> '+41216912818' Jan 29 10:32:56: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:56: Translation 's/^(\d)/\+$1/;' applied: '41216912818' -> '+41216912818' Jan 29 10:32:56: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:56: Translation 's/^(\d)/00$1/;' applied: '41216912818' -> '0041216912818' Jan 29 10:32:56: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:56: Translation 's/^(\d)/\+$1/' applied: '41216912818' -> '+41216912818' Jan 29 10:32:56: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:56: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 10:32:56: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:56: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 10:32:56: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:56: Translation 's/^(\d)/00$1/;' applied: '41215500329' -> '0041215500329' Jan 29 10:32:56: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:56: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 10:32:56: Result routes to destination '41216912818': +41216912818@212.249.15.3, prio = 10, cost = 0.00654, off-peak, 'OpenSER 3' - to remote GW +41216912818@212.249.15.9, prio = 10, cost = 0.00654, off-peak, 'OpenSER 9' - to remote GW 0041216912818@217.168.45.4, prio = 9, cost = 0.00000, off-peak, 'Asterisk SS7' - to remote GW +41216912818@87.106.213.52, prio = 8, cost = 0.00000, 'OpenSER UK' - to remote GW Jan 29 10:32:56: Enhance lifetime with 1800s to Fri Jan 29 11:02:56 2010 Jan 29 10:32:56: Authentication acknowledge response h323-ivr-in = PortaBilling_Routing:@;g-hunt=seq h323-ivr-in = PortaBilling_Routing: @;g-hunt=skip;expires=300;credit-time=199470;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-billing-model = 0 h323-ivr-in = Tariff:business h323-ivr-in = PortaBilling_CLI:41215500329 h323-ivr-in = MOH:1 h323-ivr-in = PortaBilling_CompleteNumber:41216912818 h323-ivr-in = PortaBilling_Auth_CLD:41216912818 h323-ivr-in = PortaBilling_Auth_Reseller_CLD:41216912818 h323-ivr-in = DURATION:199470 h323-return-code = 0 h323-currency = CHF h323-credit-time = 199470 h323-preferred-lang = en Jan 29 10:32:56: ...Done Authorize/INVITE in 0.041355 seconds, 0.012620 spent in SQL queries. --REQUEST REQUEST-- Jan 29 10:32:57: Processing request (BE ver1.245.2.11,pid15598): NAS-IP-Address = '94.23.225.212' NAS-Port-Name = 'SIP/94.23.242.200-683b0080' User-Name = '94.23.242.200' Called-Station-Id = '41216912818' Calling-Station-Id = '41215500329' h323-conf-id = '6B792B44 670A8D27 4C39EEB7 52640EED' call-id = '94.23.225.212-1264789977.6' Password = 'xxxxxxxxx' h323-ivr-out = 'PortaBilling_Routing:SIP' Jan 29 10:32:57: h323-conf-id=6B792B44 670A8D27 4C39EEB7 52640EED/1, call-id=94.23.225.212-1264789977.6/1 Jan 29 10:32:57: Found a call in cache with such id Jan 29 10:32:57: Authorization of the trusted node Jan 29 10:32:57: IP based authorization, force 'plaintext' password verification Jan 29 10:32:57: Verification of password using method 'plaintext': success Jan 29 10:32:57: '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 10:32:57: PrepareNexecute 'AccountAuth' Jan 29 10:32:57: SQL query 'AccountAuth' executed in 0.001418 seconds Jan 29 10:32:57: CLD '41216912818' is an off-net number Jan 29 10:32:57: Remote termination 'PortaSIP': Calculating routing for 41216912818 Jan 29 10:32:57: Routing information retrieved from previous auth request Jan 29 10:32:57: RTP Proxy with origination preference '', Calling party NOT behind NAT. Jan 29 10:32:57: Looking up routes to '41216912818' using '' routing plan Jan 29 10:32:57: PrepareNexecute 'GetRoutingPerDestination*' Jan 29 10:32:57: SQL query 'GetRoutingPerDestination*' executed in 0.006009 seconds Jan 29 10:32:57: Checked 2010-01-29 19:32:57 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}': 1 Jan 29 10:32:57: Start of call is peak level 1 Jan 29 10:32:57: Using peak rate, since no off-peak is defined Jan 29 10:32:57: Checked 2010-01-29 19:32:57 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}': 1 Jan 29 10:32:57: Start of call is peak level 1 Jan 29 10:32:57: Checked 2010-01-29 19:32:57 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}': 1 Jan 29 10:32:57: Start of call is peak level 1 Jan 29 10:32:57: Using peak rate, since no off-peak is defined Jan 29 10:32:57: Using peak rate, since no off-peak is defined Jan 29 10:32:57: Using peak rate, since no off-peak is defined Jan 29 10:32:57: Checked 2010-01-29 19:32:57 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}': 1 Jan 29 10:32:57: Start of call is peak level 1 Jan 29 10:32:57: Using peak rate, since no off-peak is defined Jan 29 10:32:57: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:57: Translation 's/^(\d)/\+$1/;' applied: '41216912818' -> '+41216912818' Jan 29 10:32:57: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:57: Translation 's/^(\d)/\+$1/;' applied: '41216912818' -> '+41216912818' Jan 29 10:32:57: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:57: Translation 's/^(\d)/00$1/;' applied: '41216912818' -> '0041216912818' Jan 29 10:32:57: Applying route outgoing CLD translation on '41216912818' ... Jan 29 10:32:57: Translation 's/^(\d)/\+$1/' applied: '41216912818' -> '+41216912818' Jan 29 10:32:57: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:57: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 10:32:57: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:57: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329' Jan 29 10:32:57: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:57: Translation 's/^(\d)/00$1/;' applied: '41215500329' -> '0041215500329' Jan 29 10:32:57: Applying route outgoing CLI translation on '41215500329' ... Jan 29 10:32:57: Translation 's/^(\d)/\+$1/;' applied: '41215500329' -> '+41215500329'