[Dec 3 10:47:41] VERBOSE[2955] logger.c:
<--- SIP read from 193.35.17.122:5060 --->
BYE sip:
[email protected] SIP/2.0
CSeq: 2 BYE
Via: SIP/2.0/UDP 193.35.17.122:5060
From: <sip:
[email protected]>;tag=0312440716521978576256421
Call-ID:
[email protected]To: "123456" <sip:
[email protected]>;tag=as39dbb889
Content-Length: 0
<------------->
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 0: BYE sip:
[email protected] SIP/2.0 (34)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 1: CSeq: 2 BYE (11)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 2: Via: SIP/2.0/UDP 193.35.17.122:5060 (35)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 3: From: <sip:
[email protected]>;tag=0312440716521978576256421 (69)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 4: Call-ID:
[email protected] (53)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 5: To: "123456" <sip:
[email protected]>;tag=as39dbb889 (52)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 6: Content-Length: 0 (17)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 7: (0)
[Dec 3 10:47:41] VERBOSE[2955] logger.c: --- (7 headers 0 lines) ---
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: = No match Their Call ID:
[email protected] Their Tag 4fa8f7eb71cc68cca91a14abea886308.0f97 Our tag: as03219ff3
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: = No match Their Call ID:
[email protected] Their Tag c21710acc22b10ac47456d6ce729a Our tag: as130c5f33
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: = Found Their Call ID:
[email protected] Their Tag 0312440716521978576256421 Our tag: as39dbb889
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: **** Received BYE ( 8 ) - Command in SIP BYE
[Dec 3 10:47:41] VERBOSE[2955] logger.c: Sending to 193.35.17.122 : 5060 (no NAT)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Setting SIP_ALREADYGONE on dialog
[email protected][Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Received bye, issuing owner hangup
[Dec 3 10:47:41] VERBOSE[2955] logger.c:
<--- Transmitting (no NAT) to 193.35.17.122:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 193.35.17.122:5060;received=193.35.17.122
From: <sip:
[email protected]>;tag=0312440716521978576256421
To: "123456" <sip:
[email protected]>;tag=as39dbb889
Call-ID:
[email protected]CSeq: 2 BYE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:
[email protected]>
Content-Length: 0
<------------>
[Dec 3 10:47:41] DEBUG[3893] channel.c: Didn't get a frame from channel: SIP/Globa_Termin-090308a0
[Dec 3 10:47:41] DEBUG[3893] channel.c: Bridge stops bridging channels SIP/Globa_Termin-090308a0 and SIP/freecalls-08fdbed8
[Dec 3 10:47:41] DEBUG[3893] channel.c: Hanging up channel 'SIP/freecalls-08fdbed8'
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Hangup call SIP/freecalls-08fdbed8, SIP callid
[email protected])
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: update_call_counter(442083576515) - decrement call limit counter on hangup
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Updating call counter for outgoing call
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Call to peer 'freecalls' removed from call limit 50
[Dec 3 10:47:41] DEBUG[3893] devicestate.c: Notification of state change to be queued on device/channel SIP/freecalls
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: No provider found, checking channel drivers for SIP - freecalls
[Dec 3 10:47:41] DEBUG[2949] chan_sip.c: Checking device state for peer freecalls
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: Changing state for SIP/freecalls - state 1 (Not in use)
[Dec 3 10:47:41] DEBUG[3968] app_queue.c: Device 'SIP/freecalls' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Dec 3 10:47:41] VERBOSE[3893] logger.c: Scheduling destruction of SIP dialog '
[email protected]' in 32000 ms (Method: INVITE)
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Strict routing enforced for session
[email protected][Dec 3 10:47:41] VERBOSE[3893] logger.c: set_destination: Parsing <sip:
[email protected]:5060> for address/port to send to
[Dec 3 10:47:41] VERBOSE[3893] logger.c: set_destination: set destination to 194.120.0.198, port 5060
[Dec 3 10:47:41] VERBOSE[3893] logger.c: Reliably Transmitting (no NAT) to 194.120.0.198:5060:
BYE sip:
[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 71.51.65.34:5060;branch=z9hG4bK4f0e6024;rport
From: "123456" <sip:
[email protected]>;tag=as130c5f33
To: <sip:
[email protected]>;tag=c21710acc22b10ac47456d6ce729a
Call-ID:
[email protected]CSeq: 104 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Authorization: Digest username="usernametest", realm="sipdiscount.com", algorithm=MD5, uri="sip:
[email protected]:5060", nonce="2974819579", response="caa33176f9bf87e60a5dc8a2abf64943", opaque=""
Content-Length: 0
---
[Dec 3 10:47:41] DEBUG[3893] sched.c: Attempted to delete nonexistent schedule entry 0!
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #338
[Dec 3 10:47:41] DEBUG[3893] devicestate.c: Notification of state change to be queued on device/channel SIP/freecalls-08fdbed8
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: No provider found, checking channel drivers for SIP - freecalls
[Dec 3 10:47:41] DEBUG[3893] rtp.c: Channel '<unspecified>' has no RTP, not doing anything
[Dec 3 10:47:41] DEBUG[2949] chan_sip.c: Checking device state for peer freecalls
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: Changing state for SIP/freecalls - state 1 (Not in use)
[Dec 3 10:47:41] DEBUG[3893] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Dec 3 10:47:41] DEBUG[3969] app_queue.c: Device 'SIP/freecalls' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Dec 3 10:47:41] DEBUG[3893] res_agi.c: SIP/Globa_Termin-090308a0 hungup
[Dec 3 10:47:41] DEBUG[3893] pbx.c: Spawn extension (a2billing-callback,1000,1) exited non-zero on 'SIP/Globa_Termin-090308a0'
[Dec 3 10:47:41] VERBOSE[3893] logger.c: == Spawn extension (a2billing-callback, 1000, 1) exited non-zero on 'SIP/Globa_Termin-090308a0'
[Dec 3 10:47:41] DEBUG[3893] channel.c: Soft-Hanging up channel 'SIP/Globa_Termin-090308a0'
[Dec 3 10:47:41] DEBUG[3893] channel.c: Hanging up channel 'SIP/Globa_Termin-090308a0'
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Hangup call SIP/Globa_Termin-090308a0, SIP callid
[email protected])
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: update_call_counter(0015147879423) - decrement call limit counter on hangup
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Updating call counter for outgoing call
[Dec 3 10:47:41] DEBUG[3893] chan_sip.c: Call to peer 'Globa_Termin' removed from call limit 50
[Dec 3 10:47:41] DEBUG[3893] devicestate.c: Notification of state change to be queued on device/channel SIP/Globa_Termin
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: No provider found, checking channel drivers for SIP - Globa_Termin
[Dec 3 10:47:41] DEBUG[3893] devicestate.c: Notification of state change to be queued on device/channel SIP/Globa_Termin-090308a0
[Dec 3 10:47:41] DEBUG[2949] chan_sip.c: Checking device state for peer Globa_Termin
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: Changing state for SIP/Globa_Termin - state 1 (Not in use)
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: No provider found, checking channel drivers for SIP - Globa_Termin
[Dec 3 10:47:41] DEBUG[3893] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Dec 3 10:47:41] DEBUG[3970] app_queue.c: Device 'SIP/Globa_Termin' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Dec 3 10:47:41] DEBUG[2949] chan_sip.c: Checking device state for peer Globa_Termin
[Dec 3 10:47:41] DEBUG[3893] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2007-12-03 10:44:48','123456','123456','1000','a2billing-callback', 'SIP/Globa_Termin-090308a0','SIP/freecalls-08fdbed8','Dial','sip/freecalls/442083576515|60|HRgrL(3480000:61000:30000)',173,161,'ANSWERED',3,'')
[Dec 3 10:47:41] DEBUG[2949] devicestate.c: Changing state for SIP/Globa_Termin - state 1 (Not in use)
[Dec 3 10:47:41] DEBUG[3971] app_queue.c: Device 'SIP/Globa_Termin' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Auto destroying SIP dialog '
[email protected]'
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Destroying SIP dialog
[email protected][Dec 3 10:47:41] VERBOSE[2955] logger.c: Really destroying SIP dialog '
[email protected]' Method: REGISTER
[Dec 3 10:47:41] VERBOSE[2955] logger.c: Really destroying SIP dialog '
[email protected]' Method: BYE
[Dec 3 10:47:41] VERBOSE[2955] logger.c:
<--- SIP read from 194.120.0.198:5060 --->
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 71.51.65.34:5060;branch=z9hG4bK4f0e6024;rport
From: "123456" <sip:
[email protected]>;tag=as130c5f33
To: <sip:
[email protected]>;tag=c21710acc22b10ac47456d6ce729a
Contact: sip:
[email protected]:5060
Call-ID:
[email protected]CSeq: 104 BYE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS,INFO,MESSAGE
Content-Length: 0
<------------->
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 0: SIP/2.0 200 Ok (14)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 1: Via: SIP/2.0/UDP 71.51.65.34:5060;branch=z9hG4bK4f0e6024;rport (62)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 2: From: "123456" <sip:
[email protected]>;tag=as130c5f33 (54)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 3: To: <sip:
[email protected]>;tag=c21710acc22b10ac47456d6ce729a (75)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 4: Contact: sip:
[email protected]:5060 (44)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 5: Call-ID:
[email protected] (53)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 6: CSeq: 104 BYE (13)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 7: Server: (Very nice Sip Registrar/Proxy Server) (46)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 8: Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS,INFO,MESSAGE (58 )
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 9: Content-Length: 0 (17)
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Header 10: (0)
[Dec 3 10:47:41] VERBOSE[2955] logger.c: --- (10 headers 0 lines) ---
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: = Found Their Call ID:
[email protected] Their Tag c21710acc22b10ac47456d6ce729a Our tag: as130c5f33
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #338
[Dec 3 10:47:41] DEBUG[2955] chan_sip.c: Stopping retransmission on '
[email protected]' of Request 104: Match Not Found
[Dec 3 10:47:41] VERBOSE[2955] logger.c: Really destroying SIP dialog '
[email protected]' Method: INVITE
<------------->
[Dec 3 11:34:04] VERBOSE[2955] logger.c: --- (10 headers 0 lines) ---
[Dec 3 11:34:04] VERBOSE[2955] logger.c: Sending to 194.120.0.198 : 5060 (no NAT)
[Dec 3 11:34:04] VERBOSE[2955] logger.c:
<--- Transmitting (no NAT) to 194.120.0.198:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 194.120.0.198:5060;branch=z9hG4bK5c6f1f71;received=194.120.0.198
From: <sip:
[email protected]>;tag=c81710acc82b10ac47454acfeca79
To: "123456" <sip:
[email protected]>;tag=as0f5b2818
Call-ID:
[email protected]CSeq: 0 BYE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:
[email protected]>
Content-Length: 0
<------------>
[Dec 3 11:34:04] VERBOSE[2955] logger.c: Really destroying SIP dialog '
[email protected]' Method: BYE
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:1139 - -> dialstatus : ANSWER, answered time is 622
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback:
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:1143 - [USEDRATECARD=0]
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:829 - ft2c_package_offer : 0 ; ; 0
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:900 - [CC_asterisk_stop QUERY = INSERT INTO cc_call (uniqueid,sessionid,username,nasipaddress,starttime,sessiontime, calledstation, terminatecause, stoptime, calledrate, sessionbill, calledcountry, calledsub, destination, id_tariffgroup, id_tariffplan, id_ratecard, id_trunk, src, sipiax, buyrate, buycost, id_card_package_offer) VALUES ('1196698952.15', 'SIP/Globa_Termin-0902deb8', '4667986686', '', CURRENT_TIMESTAMP - INTERVAL 622 SECOND , '622', '442083576515', 'ANSWER', now(), '0.015', '+0.165', '', '', 'UK London', '2', '2', '2', '2', '123456', '0', '0.005', '0.051833333333333', '0')]
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:903 - [CC_asterisk_stop 1.1: SQL: DONE : result=1]
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:921 - [CC_asterisk_stop 1.2: SQL: UPDATE cc_card SET credit= credit-0.165 , redial='442083576515' , lastuse=now(), nbused=nbused+1 WHERE username='4667986686']
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:926 - UPDATE cc_trunk SET secondusedreal = secondusedreal + 622 WHERE id_trunk='2'
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:930 - UPDATE cc_tariffplan SET secondusedreal = secondusedreal + 622 WHERE id='2'
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.A2Billing.php - line:649 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse-1 WHERE username='4667986686']
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:a2billing.php - line:978 - [CALLBACK 1ST LEG]:[INFO FOR THE 1ST LEG - callback_username=4667986686
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.A2Billing.php - line:1640 - SELECT credit, tariff, activated, inuse, simultaccess, typepaid, creditlimit, language, removeinterprefix, redial, enableexpire, UNIX_TIMESTAMP(expirationdate), expiredays, nbused, UNIX_TIMESTAMP(firstusedate), UNIX_TIMESTAMP(cc_card.creationdate), cc_card.currency, cc_card.lastname, cc_card.firstname, cc_card.email, cc_card.uipass, cc_card.id_campaign, cc_card.id, useralias FROM cc_card LEFT JOIN cc_tariffgroup ON tariff=cc_tariffgroup.id WHERE username='4667986686'
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.A2Billing.php - line:1714 - [SET LANGUAGE() fr]
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.A2Billing.php - line:649 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='4667986686']
[Dec 3 11:34:04] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:a2billing.php - line:984 - [CALLBACK 1ST LEG]:[MAKE BILLING FOR THE 1ST LEG - TARIFF:2;CALLED=0015147879423]
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:a2billing.php - line:999 - [CALLBACK]:[RateEngine -> answeredtime=681]
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:829 - ft2c_package_offer : 0 ; ; 0
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:900 - [CC_asterisk_stop QUERY = INSERT INTO cc_call (uniqueid,sessionid,username,nasipaddress,starttime,sessiontime, calledstation, terminatecause, stoptime, calledrate, sessionbill, calledcountry, calledsub, destination, id_tariffgroup, id_tariffplan, id_ratecard, id_trunk, src, sipiax, buyrate, buycost, id_card_package_offer) VALUES ('1196698952.15', 'SIP/Globa_Termin-0902deb8', '4667986686', '', CURRENT_TIMESTAMP - INTERVAL 681 SECOND , '681', '0015147879423', 'ANSWERED', now(), '0.01', '+0.12', '', '', 'Montreal2', '2', '2', '4', '2', '123456', '4', '0.005', '0.05675', '0')]
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:903 - [CC_asterisk_stop 1.1: SQL: DONE : result=1]
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:921 - [CC_asterisk_stop 1.2: SQL: UPDATE cc_card SET credit= credit-0.12 , lastuse=now(), nbused=nbused+1 WHERE username='4667986686']
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:926 - UPDATE cc_trunk SET secondusedreal = secondusedreal + 681 WHERE id_trunk='2'
[Dec 3 11:34:05] VERBOSE[5206] logger.c: a2billing.php|1|callback: file:Class.RateEngine.php - line:930 - UPDATE cc_tariffplan SET secondusedreal = secondusedreal + 681 WHERE id='2'
[Dec 3 11:34:05] VERBOSE[5206] logger.c: == Spawn extension (a2billing-callback, 1000, 1) exited non-zero on 'SIP/Globa_Termin-0902deb8'
[Dec 3 11:34:05] VERBOSE[5206] logger.c: Scheduling destruction of SIP dialog '
[email protected]' in 32000 ms (Method: INVITE)
[Dec 3 11:34:05] VERBOSE[5206] logger.c: set_destination: Parsing <sip:193.35.17.122:5060;transport=udp> for address/port to send to
[Dec 3 11:34:05] VERBOSE[5206] logger.c: set_destination: set destination to 193.35.17.122, port 5060
[Dec 3 11:34:05] VERBOSE[5206] logger.c: Reliably Transmitting (no NAT) to 193.35.17.122:5060:
BYE sip:193.35.17.122:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 71.51.65.34:5060;branch=z9hG4bK046da0a0;rport
From: "123456" <sip:
[email protected]>;tag=as50e70870
To: <sip:
[email protected]>;tag=0312220717352001215936433
Call-ID:
[email protected]CSeq: 104 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Proxy-Authorization: Digest username="5145859500", realm="VoipSwitch", algorithm=MD5, uri="sip:193.35.17.122:5060", nonce="119669895503170533712223548149", response="e5e200e15149f324feef1e57aceecd95", opaque=""
Content-Length: 0
---
[Dec 3 11:34:05] VERBOSE[2955] logger.c:
<--- SIP read from 193.35.17.122:5060 --->
SIP/2.0 200 OK
CSeq: 104 BYE
Via: SIP/2.0/UDP 71.51.65.34:5060;branch=z9hG4bK046da0a0;rport
From: "123456" <sip:
[email protected]>;tag=as50e70870
Call-ID:
[email protected]To: <sip:
[email protected]>;tag=0312220717352001215936433
Contact: <sip:193.35.17.122:5060;transport=udp>
Content-Length: 0
<------------->
[Dec 3 11:34:05] VERBOSE[2955] logger.c: --- (8 headers 0 lines) ---
[Dec 3 11:34:05] VERBOSE[2955] logger.c: Really destroying SIP dialog '
[email protected]' Method: INVITE