2016-05-17 13:41:04.348          app.c !Eltex pa_sip 3.1.1.51. Log started
2016-05-17 13:41:04.348          app.c  set log_level 5
2016-05-17 13:41:04.348          ipc.c  pbx-ipc SIP: TX (005)
2016-05-17 13:41:04.348          ipc.c  		 15 23 01 00 05                                  .#...
2016-05-17 13:41:04.348          ipc.c  PBXIPC-SIP. TX. trace 
2016-05-17 13:41:04.348          ipc.c  		# trace-level [5]
2016-05-17 13:41:11.555 sip_endpoint.c  Processing incoming message: Request msg INVITE/cseq=1 (rdata0x312c9c)
2016-05-17 13:41:11.556         call.c  acquire_call: 4955193042@192.168.0.36:5060 --> efir@192.168.9.35:5060 (call 001c)
2016-05-17 13:41:11.556         call.c  call 001c. transport proto: UDP
2016-05-17 13:41:11.556         call.c  call_check_isup: call 001c. checking for ISUP presence
2016-05-17 13:41:11.556         call.c  call_check_isup: call 001c. ISUP message not found
2016-05-17 13:41:11.556 mod_pa_pjsip.c  invite_process: call 001c. Encapsulate ISUP: 'NO'
2016-05-17 13:41:11.556 mod_pa_pjsip.c  invite_process: call 001c. Set local-contact "<sip:4955193042@192.168.9.35:5060>"
2016-05-17 13:41:11.556    tsx0x281634  Transaction created for Request msg INVITE/cseq=1 (rdata0x312c9c)
2016-05-17 13:41:11.556    tsx0x281634  Incoming Request msg INVITE/cseq=1 (rdata0x312c9c) in state Null
2016-05-17 13:41:11.556    tsx0x281634  State changed from Null to Trying, event=RX_MSG
2016-05-17 13:41:11.556    dlg0x3102dc  Transaction tsx0x281634 state changed to Trying
2016-05-17 13:41:11.556    dlg0x3102dc  UAS dialog created
2016-05-17 13:41:11.556 mod_pa_pjsip.c  invite_process: call 001c. Dialog created
2016-05-17 13:41:11.557 mod_pa_pjsip.c  invite_process: call 001c. Option for call:, use timer, support timer
2016-05-17 13:41:11.557    dlg0x3102dc  Module mod-invite added as dialog usage, data=0x282404
2016-05-17 13:41:11.557    dlg0x3102dc  Session count inc to 2 by mod-invite
2016-05-17 13:41:11.557    inv0x3102dc  UAS invite session created for dialog dlg0x3102dc
2016-05-17 13:41:11.557       endpoint  Response msg 100/INVITE/cseq=1 (tdta0x3151e0) created
2016-05-17 13:41:11.557    dlg0x3102dc  Initial answer Response msg 100/INVITE/cseq=1 (tdta0x3151e0)
2016-05-17 13:41:11.557    inv0x3102dc  Sending Response msg 100/INVITE/cseq=1 (tdta0x3151e0)
2016-05-17 13:41:11.557    dlg0x3102dc  Sending Response msg 100/INVITE/cseq=1 (tdta0x3151e0)
2016-05-17 13:41:11.557    tsx0x281634  Sending Response msg 100/INVITE/cseq=1 (tdta0x3151e0) in state Trying
2016-05-17 13:41:11.557  sip_resolve.c  Target '192.168.0.36:5060' type=UDP resolved to '192.168.0.36:5060' type=UDP (UDP transport)
2016-05-17 13:41:11.557    tsx0x281634  State changed from Trying to Proceeding, event=TX_MSG
2016-05-17 13:41:11.557    dlg0x3102dc  Transaction tsx0x281634 state changed to Proceeding
2016-05-17 13:41:11.557         call.c  call_on_state_changed: call 001c. changed to INCOMING state with event TSX_STATE
2016-05-17 13:41:11.557          ipc.c  pbx-ipc SIP: TX (317)
2016-05-17 13:41:11.557          ipc.c  		 01 01 02 00 1C 00 24 2A 00 33 32 36 38 32 37 31 ......$*.3268271
2016-05-17 13:41:11.557          ipc.c  		 2D 33 36 37 32 34 37 30 34 36 32 2D 36 30 36 36 -3672470462-6066
2016-05-17 13:41:11.558          ipc.c  		 30 33 40 73 62 63 2D 30 2E 74 65 6C 65 63 65 74 03@sbc-0.telecet
2016-05-17 13:41:11.558          ipc.c  		 2E 72 75 03 02 00 01 00 22 0A 00 31 30 2E 36 33 .ru....."..192.168.
2016-05-17 13:41:11.558          ipc.c  		 2E 30 2E 33 36 11 00 00 05 02 00 C4 13 07 02 00 .0.36...........
2016-05-17 13:41:11.558          ipc.c  		 C4 13 08 2F 00 00 00 00 00 00 00 00 00 00 00 00 .../............
2016-05-17 13:41:11.558          ipc.c  		 04 00 00 00 65 66 69 72 00 00 00 00 00 00 00 00 ....TEST........
2016-05-17 13:41:11.558          ipc.c  		 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2016-05-17 13:41:11.558          ipc.c  		 00 00 00 00 09 2F 00 00 00 00 0A 00 00 00 00 00 ...../..........
2016-05-17 13:41:11.558          ipc.c  		 00 00 0A 00 00 00 38 34 33 35 31 39 33 30 34 32 ......4955193042
2016-05-17 13:41:11.558          ipc.c  		 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2016-05-17 13:41:11.558          ipc.c  		 00 00 00 00 00 00 0D 84 00 76 3D 30 0D 0A 6F 3D .........v=0..o=
2016-05-17 13:41:11.558          ipc.c  		 73 62 63 2D 30 20 32 34 34 30 39 31 36 37 37 20 sbc-0 244091677 
2016-05-17 13:41:11.558          ipc.c  		 32 34 34 30 39 31 36 37 37 20 49 4E 20 49 50 34 244091677 IN IP4
2016-05-17 13:41:11.558          ipc.c  		 20 31 30 2E 36 33 2E 30 2E 33 36 0D 0A 73 3D 73  192.168.0.36..s=s
2016-05-17 13:41:11.558          ipc.c  		 69 70 20 63 61 6C 6C 0D 0A 63 3D 49 4E 20 49 50 ip call..c=IN IP
2016-05-17 13:41:11.558          ipc.c  		 34 20 31 30 2E 36 33 2E 30 2E 33 39 0D 0A 74 3D 4 192.168.0.39..t=
2016-05-17 13:41:11.558          ipc.c  		 30 20 30 0D 0A 6D 3D 61 75 64 69 6F 20 31 32 34 0 0..m=audio 124
2016-05-17 13:41:11.558          ipc.c  		 35 38 20 52 54 50 2F 41 56 50 20 38 20 31 38 0D 58 RTP/AVP 8 18.
2016-05-17 13:41:11.558          ipc.c  		 0A 61 3D 70 74 69 6D 65 3A 32 30 0D 0A          .a=ptime:20..
2016-05-17 13:41:11.558          ipc.c  PBXIPC-SIP. TX. seize 
2016-05-17 13:41:11.558          ipc.c  		# callref 001c
2016-05-17 13:41:11.558          ipc.c  		# Call-ID len (42)
2016-05-17 13:41:11.558          ipc.c  		# trunkID 01
2016-05-17 13:41:11.558          ipc.c  		# Hostname '192.168.0.36'
2016-05-17 13:41:11.558          ipc.c  		# Display name ''
2016-05-17 13:41:11.558          ipc.c  		# port 5060
2016-05-17 13:41:11.558          ipc.c  		# local-port 5060
2016-05-17 13:41:11.559          ipc.c  		# cdpn <TEST> (4) cat 0x00
2016-05-17 13:41:11.559          ipc.c  		# cgpn <4955193042> (10) cat 0x0a
2016-05-17 13:41:11.559          ipc.c  		# SDP len (132)
2016-05-17 13:41:11.559          ipc.c !pbx-ipc SIP: RX (011)
2016-05-17 13:41:11.559          ipc.c  		 06 01 02 00 1C 00 0F 02 00 93 01                ...........
2016-05-17 13:41:11.559          ipc.c  PBXIPC-SIP. RX. rel-req 
2016-05-17 13:41:11.559          ipc.c  		# callref 001c
2016-05-17 13:41:11.559          ipc.c  		# cause 403
2016-05-17 13:41:11.559         call.c !call_on_tsx_state_changed: call 001c. tsx 'tsx0x281634', role UAS, method INVITE, event TX_MSG, tsx-state: Proceeding
2016-05-17 13:41:11.578      ipc_msg.c  ipc_msg_parse: msg type = rel-req [6]
2016-05-17 13:41:11.578      ipc_msg.c  get_ie: ie [1][callref] size [2]
2016-05-17 13:41:11.578      ipc_msg.c  get_ie: ie [15][cause] size [2]
2016-05-17 13:41:11.578          ipc.c  ipc_process: msg ptr = 0x318130. type [6]
2016-05-17 13:41:11.578         call.c  call_release_req: call 001c. release req w/cause 403 q850-cause: 0
2016-05-17 13:41:11.578         call.c  call_end_session: call 001c. End session with response 403 (q850-cause: 0)
2016-05-17 13:41:11.578    inv0x3102dc  End session in state 'incoming' with code 403 (terminate pending)
2016-05-17 13:41:11.578    inv0x3102dc  Sending Response msg 403/INVITE/cseq=1 (tdta0x3151e0)
2016-05-17 13:41:11.578    dlg0x3102dc  Sending Response msg 403/INVITE/cseq=1 (tdta0x3151e0)
2016-05-17 13:41:11.578    tsx0x281634  Sending Response msg 403/INVITE/cseq=1 (tdta0x3151e0) in state Proceeding
2016-05-17 13:41:11.579    tsx0x281634  State changed from Proceeding to Completed, event=TX_MSG
2016-05-17 13:41:11.579    dlg0x3102dc  Transaction tsx0x281634 state changed to Completed
2016-05-17 13:41:11.579         call.c  call_on_state_changed: call 001c. changed to DISCONNCTD state with event TSX_STATE
2016-05-17 13:41:11.579         call.c  call_state_disconnected: call 001c. Disconnected. Event type TSX_STATE with response 403
2016-05-17 13:41:11.579          ipc.c  pbx-ipc SIP: TX (011)
2016-05-17 13:41:11.579          ipc.c  		 07 01 02 00 1C 00 0F 02 00 93 01                ...........
2016-05-17 13:41:11.579          ipc.c  PBXIPC-SIP. TX. rel-cplt 
2016-05-17 13:41:11.579          ipc.c  		# callref 001c
2016-05-17 13:41:11.579          ipc.c  		# cause 403
2016-05-17 13:41:11.579    dlg0x3102dc  Session count dec to 2 by mod-invite
2016-05-17 13:41:11.579         call.c  call_on_tsx_state_changed: inv <0x282404>, mod-data <(nil)>
2016-05-17 13:41:11.580 sip_endpoint.c !Processing incoming message: Request msg ACK/cseq=1 (rdata0x312c9c)
2016-05-17 13:41:11.580    tsx0x281634  Incoming Request msg ACK/cseq=1 (rdata0x312c9c) in state Completed
2016-05-17 13:41:11.580    tsx0x281634  State changed from Completed to Confirmed, event=RX_MSG
2016-05-17 13:41:11.580    dlg0x3102dc  Transaction tsx0x281634 state changed to Confirmed
2016-05-17 13:41:11.580         call.c  call_on_tsx_state_changed: inv <0x282404>, mod-data <(nil)>
2016-05-17 13:41:11.608    tsx0x281634 !Timeout timer event
2016-05-17 13:41:11.608    tsx0x281634  State changed from Confirmed to Terminated, event=TIMER
2016-05-17 13:41:11.608    dlg0x3102dc  Transaction tsx0x281634 state changed to Terminated
2016-05-17 13:41:11.608         call.c  call_on_tsx_state_changed: inv <0x282404>, mod-data <(nil)>
2016-05-17 13:41:11.608    dlg0x3102dc  Dialog destroyed
2016-05-17 13:41:11.608    tsx0x281634  Timeout timer event
2016-05-17 13:41:11.608    tsx0x281634  State changed from Terminated to Destroyed, event=TIMER
2016-05-17 13:41:11.608   tdta0x3151e0  Destroying txdata Response msg 403/INVITE/cseq=1 (tdta0x3151e0)
2016-05-17 13:41:11.608    tsx0x281634  Transaction destroyed!
2016-05-17 13:41:15.681          ipc.c !pbx-ipc SIP: RX (005)
2016-05-17 13:41:15.681          ipc.c  		 15 23 01 00 00                                  .#...
2016-05-17 13:41:15.681          ipc.c  PBXIPC-SIP. RX. trace 
2016-05-17 13:41:15.681          ipc.c  		# trace-level [0]
2016-05-17 13:41:15.708      ipc_msg.c !ipc_msg_parse: msg type = trace [21]
2016-05-17 13:41:15.708      ipc_msg.c  get_ie: ie [35][trace-level] size [1]
2016-05-17 13:41:15.708          ipc.c  ipc_process: msg ptr = 0x311188. type [21]
2016-05-17 13:41:15.728          app.c  set log_level 0
