Untitled

From Voluminous Moth, 2 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/43794873 Embed
Download Paste or View Raw
  1. [2021-02-01 14:08:43] DEBUG[2504004][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Answer'
  2. [2021-02-01 14:08:43] DEBUG[2503720]: devicestate.c:466 do_state_change: Changing state for PJSIP/6249 - state 2 (In use)
  3. [2021-02-01 14:08:43] DEBUG[2503802]: app_queue.c:2586 device_state_cb: Device 'PJSIP/6249' changed to state '2' (In use) but we don't care because they're not a member of any queue.
  4. [2021-02-01 14:08:43] DEBUG[2503723]: devicestate.c:357 _ast_device_state: Checking if I can find provider for "Custom" - number: DND6249
  5. [2021-02-01 14:08:43] DEBUG[2503723]: db.c:392 db_get_common: Unable to find key 'DND6249' in family 'CustomDevstate'
  6. [2021-02-01 14:08:43] DEBUG[2503745]: res_pjsip_session.c:5234 session_inv_on_media_update:  PJSIP/6249-00000000
  7. [2021-02-01 14:08:43] DEBUG[2503745]: res_rtp_asterisk.c:8089 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x7f6ad401fa10'
  8. [2021-02-01 14:08:43] DEBUG[2503745]: acl.c:1045 ast_ouraddrfor: For destination '[my IP was redacted]', our source address is '[my server IP was redacted]'.
  9. [2021-02-01 14:08:43] DEBUG[2503745]: res_rtp_asterisk.c:8292 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x7f6ad401fa10'
  10. [2021-02-01 14:08:43] DEBUG[2503745]: rtp_engine.c:1318 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 0 based on m type on 0x7f6abbd8bfc0
  11. [2021-02-01 14:08:43] DEBUG[2503745]: rtp_engine.c:1204 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 0 (0x7f6ad403d4f8) from 0x7f6abbd8bfc0 to 0x7f6ad401fbe8
  12. [2021-02-01 14:08:43] DEBUG[2503745]: rtp_engine.c:1204 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 101 (0x7f6ad4013fd8) from 0x7f6abbd8bfc0 to 0x7f6ad401fbe8
  13. [2021-02-01 14:08:43] DEBUG[2503745]: channel.c:5707 set_format: Channel PJSIP/6249-00000000 setting read format path: ulaw -> ulaw
  14. [2021-02-01 14:08:43] DEBUG[2503745]: channel.c:5707 set_format: Channel PJSIP/6249-00000000 setting write format path: ulaw -> ulaw
  15. [2021-02-01 14:08:43] DEBUG[2503745]: res_rtp_asterisk.c:8788 ast_rtp_activate: ast_rtp_activate (0x7f6ad40335c0) - setup and perform DTLS'
  16. [2021-02-01 14:08:43] DEBUG[2503745]: res_rtp_asterisk.c:2472 dtls_perform_handshake: dtls_perform_handshake (0x7f6ad40335c0) - ssl = (nil), setup = 0
  17. [2021-02-01 14:08:43] DEBUG[2503745]: res_rtp_asterisk.c:2472 dtls_perform_handshake: dtls_perform_handshake (0x7f6ad40335c0) - ssl = (nil), setup = 0
  18. [2021-02-01 14:08:43] DEBUG[2503745]: res_pjsip_session.c:5298 session_inv_on_media_update:  PJSIP/6249-00000000
  19. [2021-02-01 14:08:43] DEBUG[2503745]: res_pjsip_session.c:4478 handle_outgoing_response:  PJSIP/6249-00000000: Method is INVITE, Response is 200 OK
  20. [2021-02-01 14:08:43] DEBUG[2503745]: res_pjsip_session.c:4497 handle_outgoing_response:  PJSIP/6249-00000000
  21. [2021-02-01 14:08:43] DEBUG[2503745]: res_pjsip_session.c:4618 session_inv_on_state_changed: PJSIP/6249-00000000: Source of transaction state change is TX_MSG
  22. [2021-02-01 14:08:43] DEBUG[2504004][C-00000001]: chan_pjsip.c:1644 chan_pjsip_indicate:  PJSIP/6249-00000000: Indicated Stop generators
  23. [2021-02-01 14:08:43] DEBUG[2504004][C-00000001]: chan_pjsip.c:1882 chan_pjsip_indicate:  PJSIP/6249-00000000
  24. [2021-02-01 14:08:44] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7f6ad400d728 for Request msg ACK/cseq=19268 (rdata0x7f6af4133ed8)
  25. [2021-02-01 14:08:44] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/distributor-00000037 associated with dialog dlg0x7f6ad400d728
  26. [2021-02-01 14:08:44] DEBUG[2503745]: res_pjsip_session.c:4562 handle_incoming_before_media: PJSIP/6249-00000000: Received request
  27. [2021-02-01 14:08:44] DEBUG[2503745]: res_pjsip_session.c:4377 handle_incoming_request:  PJSIP/6249-00000000: Method is ACK
  28. [2021-02-01 14:08:44] DEBUG[2503745]: chan_pjsip.c:3256 chan_pjsip_incoming_ack:  PJSIP/6249-00000000
  29. [2021-02-01 14:08:44] DEBUG[2503745]: chan_pjsip.c:3260 chan_pjsip_incoming_ack:  PJSIP/6249-00000000: Queueing SRCCHANGE
  30. [2021-02-01 14:08:44] DEBUG[2503745]: chan_pjsip.c:3264 chan_pjsip_incoming_ack:  PJSIP/6249-00000000
  31. [2021-02-01 14:08:44] DEBUG[2503745]: res_pjsip_session.c:4387 handle_incoming_request:  PJSIP/6249-00000000
  32. [2021-02-01 14:08:44] DEBUG[2504004][C-00000001]: res_rtp_asterisk.c:6031 ast_rtcp_interpret: Got RTCP report of 60 bytes from [my IP was redacted]:10001
  33. [2021-02-01 14:08:44] DEBUG[2504004][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Wait'
  34. [2021-02-01 14:08:44] DEBUG[2504004][C-00000001]: res_rtp_asterisk.c:6031 ast_rtcp_interpret: Got RTCP report of 60 bytes from [my IP was redacted]:10001
  35. [2021-02-01 14:08:45] WARNING[2504004][C-00000001]: pbx.c:2927 pbx_extension_helper: No application 'Macro' for extension (from-internal, *65, 6)
  36. [2021-02-01 14:08:45] DEBUG[2504004][C-00000001]: pbx.c:4441 __ast_pbx_run: Spawn extension (from-internal,*65,6) exited non-zero on 'PJSIP/6249-00000000'
  37. [2021-02-01 14:08:45] DEBUG[2504004][C-00000001]: channel.c:2440 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'PJSIP/6249-00000000'
  38. [2021-02-01 14:08:45] DEBUG[2504004][C-00000001]: channel.c:2440 ast_softhangup_nolock: Soft-Hanging (0x80) up channel 'PJSIP/6249-00000000'
  39. [2021-02-01 14:08:45] WARNING[2504004][C-00000001]: pbx.c:2927 pbx_extension_helper: No application 'Macro' for extension (from-internal, h, 1)
  40. [2021-02-01 14:08:45] DEBUG[2504004][C-00000001]: pbx.c:4251 ast_pbx_h_exten_run: Spawn extension (from-internal,h,1) exited non-zero on 'PJSIP/6249-00000000'
  41. [2021-02-01 14:08:45] DEBUG[2504004][C-00000001]: channel.c:2530 ast_hangup: Channel 0x7f6ad403db60 'PJSIP/6249-00000000' hanging up.  Refs: 2
  42. [2021-02-01 14:08:45] DEBUG[2504004][C-00000001]: chan_pjsip.c:2493 hangup_cause2sip: AST hangup cause 0 (no match found in PJSIP)
  43. [2021-02-01 14:08:45] DEBUG[2503745]: rtp_engine.c:455 instance_destructor: Destroyed RTP instance '0x7f6ad401fa10'
  44. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4460 handle_outgoing_request:  PJSIP/6249-00000000: Method is BYE
  45. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4470 handle_outgoing_request:  PJSIP/6249-00000000
  46. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '[my IP was redacted]'
  47. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '[my IP was redacted]' is 'TLS transport'
  48. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '[my IP was redacted]' is an IP address, skipping resolution
  49. [2021-02-01 14:08:45] DEBUG[2503745]: channel.c:2177 ast_channel_destructor: Channel 0x7f6ad403db60 'PJSIP/6249-00000000' destroying
  50. [2021-02-01 14:08:45] DEBUG[2503745]: stasis.c:437 topic_dtor: Destroying topic. name: channel:1612202923.0, detail:
  51. [2021-02-01 14:08:45] DEBUG[2503745]: stasis.c:446 topic_dtor: Topic 'channel:1612202923.0': 0x7f6ad4039130 destroyed
  52. [2021-02-01 14:08:45] DEBUG[2503730]: cdr.c:1460 cdr_object_finalize: Finalized CDR for PJSIP/6249-00000000 - start 1612202923.846979 answer 1612202923.850236 end 1612202925.047220 dur 1.200 bill 1.196 dispo ANSWERED
  53. [2021-02-01 14:08:45] DEBUG[2503720]: devicestate.c:466 do_state_change: Changing state for PJSIP/6249 - state 1 (Not in use)
  54. [2021-02-01 14:08:45] DEBUG[2503723]: devicestate.c:357 _ast_device_state: Checking if I can find provider for "Custom" - number: DND6249
  55. [2021-02-01 14:08:45] DEBUG[2503723]: db.c:392 db_get_common: Unable to find key 'DND6249' in family 'CustomDevstate'
  56. [2021-02-01 14:08:45] DEBUG[2503802]: app_queue.c:2586 device_state_cb: Device 'PJSIP/6249' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
  57. [2021-02-01 14:08:45] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7f6ad400d728 for Response msg 200/BYE/cseq=30219 (rdata0x7f6af4133ed8)
  58. [2021-02-01 14:08:45] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/distributor-00000037 associated with dialog dlg0x7f6ad400d728
  59. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4618 session_inv_on_state_changed: 6249: Source of transaction state change is RX_MSG
  60. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4562 handle_incoming_before_media: 6249: Received response
  61. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4429 handle_incoming_response:  6249: Response is 200 OK
  62. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4441 handle_incoming_response:  6249
  63. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4429 handle_incoming_response:  6249: Response is 200 OK
  64. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4441 handle_incoming_response:  6249
  65. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:4854 session_inv_on_tsx_state_changed: 6249: BYE received final response code 200
  66. [2021-02-01 14:08:45] DEBUG[2503745]: res_pjsip_session.c:2943 session_destructor: 6249: Destroying SIP session
  67. [2021-02-01 14:08:59] DEBUG[2503749]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  68. [2021-02-01 14:08:59] DEBUG[2503748]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  69. [2021-02-01 14:08:59] DEBUG[2503747]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  70. [2021-02-01 14:08:59] DEBUG[2503742]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 9
  71. [2021-02-01 14:08:59] DEBUG[2503742]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 8
  72. [2021-02-01 14:08:59] DEBUG[2503742]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 7
  73. [2021-02-01 14:08:59] DEBUG[2503751]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  74. [2021-02-01 14:08:59] DEBUG[2503714]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 11
  75. [2021-02-01 14:08:59] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  76. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Login'
  77. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  78. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  79. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  80. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  81. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  82. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  83. [2021-02-01 14:09:02] DEBUG[2504087]: config.c:3847 ast_parse_arg: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
  84. [2021-02-01 14:09:02] DEBUG[2504087]: config.c:3760 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
  85. [2021-02-01 14:09:02] DEBUG[2504087]: config.c:3847 ast_parse_arg: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
  86. [2021-02-01 14:09:02] DEBUG[2504087]: config.c:3760 ast_parse_arg: extract uint from [59698] in [0, 4294967295] gives [59698](0)
  87. [2021-02-01 14:09:02] DEBUG[2504087]: config.c:3760 ast_parse_arg: extract uint from [60] in [0, 86400] gives [60](0)
  88. [2021-02-01 14:09:02] DEBUG[2504087]: manager.c:6683 process_message: Running action 'Command'
  89. [2021-02-01 14:09:05] DEBUG[2504003]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  90. [2021-02-01 14:09:05] DEBUG[2503713]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 12
  91. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR '6249'
  92. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:856 sip_options_qualify_contact: Qualifying contact '6249;@67ae132bbe973ede944a0d9e568e6bda' on AOR '6249'
  93. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip.c:4327 endpt_send_request: 0x7f6ad4036930: Wrapper created
  94. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip.c:4342 endpt_send_request: 0x7f6ad4036930: Set timer to 3000 msec
  95. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '[my IP was redacted]'
  96. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '[my IP was redacted]' is 'TLS transport'
  97. [2021-02-01 14:09:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '[my IP was redacted]' is an IP address, skipping resolution
  98. [2021-02-01 14:09:17] DEBUG[2503750]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  99. [2021-02-01 14:09:17] DEBUG[2503714]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 10
  100. [2021-02-01 14:09:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:519 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=48934 (rdata0x7f6af4133ed8).  Using request transaction as basis.
  101. [2021-02-01 14:09:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:127 find_request_serializer: Found transaction tsx0x7f6ad400d728 for Response msg 200/OPTIONS/cseq=48934 (rdata0x7f6af4133ed8).
  102. [2021-02-01 14:09:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:137 find_request_serializer: Found serializer pjsip/options/6249-0000003e on transaction tsx0x7f6ad400d728
  103. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip.c:4205 endpt_send_request_cb: 0x7f6ad4036930: PJSIP tsx response received
  104. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip.c:4218 endpt_send_request_cb: 0x7f6ad4036930: Cancelling timer
  105. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip.c:4227 endpt_send_request_cb: 0x7f6ad4036930: Timer cancelled
  106. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip.c:4248 endpt_send_request_cb: 0x7f6ad4036930: Callbacks executed
  107. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip.c:4304 send_request_wrapper_destructor: 0x7f6ad4036930: wrapper destroyed
  108. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:755 sip_options_contact_status_notify_task: Contact 6249/sip:6249@[my IP was redacted]:59698;transport=TLS;ob status didn't change: Reachable, RTT: 185.153 msec
  109. [2021-02-01 14:09:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:775 sip_options_contact_status_notify_task: AOR '6249' now has 1 available contacts
  110. [2021-02-01 14:09:18] DEBUG[2503746]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  111. [2021-02-01 14:09:18] DEBUG[2503742]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 6
  112. [2021-02-01 14:09:29] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  113. [2021-02-01 14:09:59] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  114. [2021-02-01 14:10:01] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Login'
  115. [2021-02-01 14:10:01] DEBUG[2503776]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead
  116. [2021-02-01 14:10:02] DEBUG[2503776]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead
  117. [2021-02-01 14:10:02] DEBUG[2503776]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 3 instead
  118. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  119. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  120. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  121. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  122. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  123. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  124. [2021-02-01 14:10:02] DEBUG[2504421]: config.c:3847 ast_parse_arg: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
  125. [2021-02-01 14:10:02] DEBUG[2504421]: config.c:3760 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
  126. [2021-02-01 14:10:02] DEBUG[2504421]: config.c:3847 ast_parse_arg: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
  127. [2021-02-01 14:10:02] DEBUG[2504421]: config.c:3760 ast_parse_arg: extract uint from [59698] in [0, 4294967295] gives [59698](0)
  128. [2021-02-01 14:10:02] DEBUG[2504421]: config.c:3760 ast_parse_arg: extract uint from [60] in [0, 86400] gives [60](0)
  129. [2021-02-01 14:10:02] DEBUG[2504421]: manager.c:6683 process_message: Running action 'Command'
  130. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR '6249'
  131. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:856 sip_options_qualify_contact: Qualifying contact '6249;@67ae132bbe973ede944a0d9e568e6bda' on AOR '6249'
  132. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip.c:4327 endpt_send_request: 0x7f6ad4036720: Wrapper created
  133. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip.c:4342 endpt_send_request: 0x7f6ad4036720: Set timer to 3000 msec
  134. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '[my IP was redacted]'
  135. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '[my IP was redacted]' is 'TLS transport'
  136. [2021-02-01 14:10:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '[my IP was redacted]' is an IP address, skipping resolution
  137. [2021-02-01 14:10:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:519 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=12301 (rdata0x7f6af4133ed8).  Using request transaction as basis.
  138. [2021-02-01 14:10:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:127 find_request_serializer: Found transaction tsx0x7f6ad400d728 for Response msg 200/OPTIONS/cseq=12301 (rdata0x7f6af4133ed8).
  139. [2021-02-01 14:10:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:137 find_request_serializer: Found serializer pjsip/options/6249-0000003e on transaction tsx0x7f6ad400d728
  140. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip.c:4205 endpt_send_request_cb: 0x7f6ad4036720: PJSIP tsx response received
  141. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip.c:4218 endpt_send_request_cb: 0x7f6ad4036720: Cancelling timer
  142. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip.c:4227 endpt_send_request_cb: 0x7f6ad4036720: Timer cancelled
  143. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip.c:4248 endpt_send_request_cb: 0x7f6ad4036720: Callbacks executed
  144. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip.c:4304 send_request_wrapper_destructor: 0x7f6ad4036720: wrapper destroyed
  145. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:755 sip_options_contact_status_notify_task: Contact 6249/sip:6249@[my IP was redacted]:59698;transport=TLS;ob status didn't change: Reachable, RTT: 165.262 msec
  146. [2021-02-01 14:10:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:775 sip_options_contact_status_notify_task: AOR '6249' now has 1 available contacts
  147. [2021-02-01 14:10:29] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  148. [2021-02-01 14:10:59] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  149. [2021-02-01 14:11:01] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Login'
  150. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  151. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  152. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  153. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  154. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  155. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  156. [2021-02-01 14:11:02] DEBUG[2504684]: config.c:3847 ast_parse_arg: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
  157. [2021-02-01 14:11:02] DEBUG[2504684]: config.c:3760 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
  158. [2021-02-01 14:11:02] DEBUG[2504684]: config.c:3847 ast_parse_arg: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
  159. [2021-02-01 14:11:02] DEBUG[2504684]: config.c:3760 ast_parse_arg: extract uint from [59698] in [0, 4294967295] gives [59698](0)
  160. [2021-02-01 14:11:02] DEBUG[2504684]: config.c:3760 ast_parse_arg: extract uint from [60] in [0, 86400] gives [60](0)
  161. [2021-02-01 14:11:02] DEBUG[2504684]: manager.c:6683 process_message: Running action 'Command'
  162. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR '6249'
  163. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:856 sip_options_qualify_contact: Qualifying contact '6249;@67ae132bbe973ede944a0d9e568e6bda' on AOR '6249'
  164. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip.c:4327 endpt_send_request: 0x7f6ad4006a90: Wrapper created
  165. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip.c:4342 endpt_send_request: 0x7f6ad4006a90: Set timer to 3000 msec
  166. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '[my IP was redacted]'
  167. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '[my IP was redacted]' is 'TLS transport'
  168. [2021-02-01 14:11:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '[my IP was redacted]' is an IP address, skipping resolution
  169. [2021-02-01 14:11:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:519 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=63199 (rdata0x7f6af4133ed8).  Using request transaction as basis.
  170. [2021-02-01 14:11:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:127 find_request_serializer: Found transaction tsx0x7f6ad400d728 for Response msg 200/OPTIONS/cseq=63199 (rdata0x7f6af4133ed8).
  171. [2021-02-01 14:11:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:137 find_request_serializer: Found serializer pjsip/options/6249-0000003e on transaction tsx0x7f6ad400d728
  172. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip.c:4205 endpt_send_request_cb: 0x7f6ad4006a90: PJSIP tsx response received
  173. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip.c:4218 endpt_send_request_cb: 0x7f6ad4006a90: Cancelling timer
  174. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip.c:4227 endpt_send_request_cb: 0x7f6ad4006a90: Timer cancelled
  175. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip.c:4248 endpt_send_request_cb: 0x7f6ad4006a90: Callbacks executed
  176. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip.c:4304 send_request_wrapper_destructor: 0x7f6ad4006a90: wrapper destroyed
  177. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:755 sip_options_contact_status_notify_task: Contact 6249/sip:6249@[my IP was redacted]:59698;transport=TLS;ob status didn't change: Reachable, RTT: 166.577 msec
  178. [2021-02-01 14:11:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:775 sip_options_contact_status_notify_task: AOR '6249' now has 1 available contacts
  179. [2021-02-01 14:11:29] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  180. [2021-02-01 14:11:59] DEBUG[2503757]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
  181. [2021-02-01 14:12:01] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Login'
  182. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  183. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  184. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  185. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  186. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  187. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  188. [2021-02-01 14:12:02] DEBUG[2504930]: config.c:3847 ast_parse_arg: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
  189. [2021-02-01 14:12:02] DEBUG[2504930]: config.c:3760 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
  190. [2021-02-01 14:12:02] DEBUG[2504930]: config.c:3847 ast_parse_arg: extract double from [3.000000] in [-inf, inf] gives [3.000000](0)
  191. [2021-02-01 14:12:02] DEBUG[2504930]: config.c:3760 ast_parse_arg: extract uint from [59698] in [0, 4294967295] gives [59698](0)
  192. [2021-02-01 14:12:02] DEBUG[2504930]: config.c:3760 ast_parse_arg: extract uint from [60] in [0, 86400] gives [60](0)
  193. [2021-02-01 14:12:02] DEBUG[2504930]: manager.c:6683 process_message: Running action 'Command'
  194. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR '6249'
  195. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip/pjsip_options.c:856 sip_options_qualify_contact: Qualifying contact '6249;@67ae132bbe973ede944a0d9e568e6bda' on AOR '6249'
  196. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip.c:4327 endpt_send_request: 0x7f6ad4006a90: Wrapper created
  197. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip.c:4342 endpt_send_request: 0x7f6ad4006a90: Set timer to 3000 msec
  198. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '[my IP was redacted]'
  199. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '[my IP was redacted]' is 'TLS transport'
  200. [2021-02-01 14:12:17] DEBUG[2503745]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '[my IP was redacted]' is an IP address, skipping resolution
  201. [2021-02-01 14:12:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:519 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=10033 (rdata0x7f6af4133ed8).  Using request transaction as basis.
  202. [2021-02-01 14:12:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:127 find_request_serializer: Found transaction tsx0x7f6ad400d728 for Response msg 200/OPTIONS/cseq=10033 (rdata0x7f6af4133ed8).
  203. [2021-02-01 14:12:18] DEBUG[2503744]: res_pjsip/pjsip_distributor.c:137 find_request_serializer: Found serializer pjsip/options/6249-0000003e on transaction tsx0x7f6ad400d728
  204. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip.c:4205 endpt_send_request_cb: 0x7f6ad4006a90: PJSIP tsx response received
  205. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip.c:4218 endpt_send_request_cb: 0x7f6ad4006a90: Cancelling timer
  206. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip.c:4227 endpt_send_request_cb: 0x7f6ad4006a90: Timer cancelled
  207. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip.c:4248 endpt_send_request_cb: 0x7f6ad4006a90: Callbacks executed
  208. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip.c:4304 send_request_wrapper_destructor: 0x7f6ad4006a90: wrapper destroyed
  209. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:755 sip_options_contact_status_notify_task: Contact 6249/sip:6249@[my IP was redacted]:59698;transport=TLS;ob status didn't change: Reachable, RTT: 164.185 msec
  210. [2021-02-01 14:12:18] DEBUG[2503745]: res_pjsip/pjsip_options.c:775 sip_options_contact_status_notify_task: AOR '6249' now has 1 available contacts

Reply to "Untitled"

Here you can reply to the paste above