Hi All!I try to use SEMS-1.4.2 with webconference application. Calls put to webconference by direct access (first participant) and then by webconference.dialout called by xmlrpc. Scenario works fine.
But sometimes I get following in SEMS log: ----------------------------------- Feb 20 09:51:23 pbx sems[15965]: [#803087400] [send, transport.cpp:98] DEBUG: send msg --++-- BYE sip:[email protected]:5061 SIP/2.0^M Via: SIP/2.0/UDP 172.16.1.2:50060;branch=z9h G4bKdCNLzaGU;rport^M From: <sip:[email protected]>;tag=6013B634-4F41DF59000DAA7A-0180A800^M To: "MagazinStalnoff" <sip:[email protected]>;tag=19b2d718edc66508o0^ M CSeq: 10 BYE^M Call-ID: fa0e2e849e1130a02dd157a262e6a1fa^M Max-Forwards: 70^M Content-Length: 0^M ^M --++-- Feb 20 09:51:23 pbx sems[15965]: [#803087400] [send_request, trans_layer.cpp:901] DEBUG: update_uac_request tt->_t =0x0 Feb 20 09:51:23 pbx sems[15965]: [#803087400] [update_uac_request, trans_layer.cpp:1417] DEBUG: update_uac_request(t=0x8030d2300) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [reset_timer, sip_trans.cpp:185] DEBUG: New timer of type E at time=121445 (repeated=0) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [reset_timer, sip_trans.cpp:185] DEBUG: New timer of type F at time=123020 (repeated=0) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [reset_timer, sip_trans.cpp:185] DEBUG: New timer of type M at time=121820 (repeated=0) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [gotoNextItem, AmPlaylist.cpp:62] DEBUG: posting AmAudioEvent::noAudio event! Feb 20 09:51:23 pbx sems[15965]: [#803087400] [postEvent, AmEventQueue.cpp:53] DEBUG: AmEventQueue: trying to post event Feb 20 09:51:23 pbx sems[15965]: [#803087400] [postEvent, AmEventQueue.cpp:64] DEBUG: AmEventQueue: event posted Feb 20 09:51:23 pbx sems[15965]: [#803087400] [removeChannel, AmMultiPartyMixer.cpp:66] DEBUG: removed channel: #0 Feb 20 09:51:23 pbx sems[15965]: [#803087400] [~AmConferenceStatus, AmConferenceStatus.cpp:133] DEBUG: AmConferenceStatus::~AmConferenceStatus(): conf_id = YGUBKq5Rl1F+SjAN79FRA Q Feb 20 09:51:23 pbx sems[15965]: [#803087400] [process, AmSession.cpp:785] DEBUG: AmSession processing event Feb 20 09:51:23 pbx sems[15965]: [#803087400] [processEvents, AmEventQueue.cpp:83] DEBUG: event processed (18WebConferenceEvent) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [processEvents, AmEventQueue.cpp:79] DEBUG: before processing event (12AmAudioEvent) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [process, WebConferenceDialog.cpp:316] DEBUG: ########## noAudio event ######### Feb 20 09:51:23 pbx sems[15965]: [#803087400] [processEvents, AmEventQueue.cpp:83] DEBUG: event processed (12AmAudioEvent) Feb 20 09:51:23 pbx sems[15965]: [#803087400] [processingCycle, AmSession.cpp:463] DEBUG: ^^ S [fa0e2e849e1130a02dd157a262e6a1fa|6013B634-4F41DF59000DAA7A-0180A800] Disconnected , stopped, 1 UACTransPending ^^ Feb 20 09:51:23 pbx sems[15965]: [#803087400] [finalize, AmSession.cpp:523] DEBUG: running finalize sequence... Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv --++-- SIP/2.0 200 Closing^M Call-id: fa0e2e849e1130a02dd157a262e6a1 fa^M Cseq: 10 BYE^M From: <sip:[email protected]>;tag=6013B634-4F41DF59000DAA7A-0180A800^M To: "MagazinStalnoff" <sip:[email protected]>;tag=19b2d718edc66508o0^M Via: SIP/2.0/UDP 172.16.1.2:50060;branch=z9hG4bKdCNLzaGU;rport^M Content-length: 0^M ^M --++-- Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKdCNLzaGU Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1 Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [received_msg, trans_layer.cpp:1204] DEBUG: Reply matched an existing transaction Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [update_uac_reply, trans_layer.cpp:1247] DEBUG: update_uac_reply(reply code = 200, trans=0x8030d2300) Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing old timer of type E (this=0x803b55a00) Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing old timer of type M (this=0x803b55a40) Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing old timer of type F (this=0x803b559c0) Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [reset_timer, sip_trans.cpp:185] DEBUG: New timer of type K at time=121670 (repeated=0) Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handle_sip_reply, SipCtrlInterface.cpp:626] DEBUG: Received reply: 200 Closing Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: reply.callid = <fa0e2e849e1130a02dd157a262e6a1fa> Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.local_tag = <6013B634-4F41DF59000DAA7A-0180A800> Feb 20 09:51:23 pbx sems[15965]: [#803087400] [destroy, AmSession.cpp:553] DEBUG: AmSession::destroy() Feb 20 09:51:23 pbx sems[15965]: [#803087400] [stopAndQueue, AmSessionContainer.cpp:182] INFO: session cleaner about to stop 6013B634-4F41DF59000DAA7A-0180A800 Feb 20 09:51:23 pbx sems[15965]: [#803087400] [stop, AmThread.cpp:153] DEBUG: Thread 34410624000 (34410624000) calling on_stop, give it a chance to clean up. Feb 20 09:51:23 pbx sems[15965]: [#803087400] [on_stop, AmSession.cpp:537] DEBUG: AmSession::stop() Feb 20 09:51:23 pbx sems[15965]: [#803087400] [removeFromProcessor, AmMediaProcessor.cpp:141] DEBUG: AmMediaProcessor::removeSession Feb 20 09:51:23 pbx sems[15965]: [#803087400] [removeFromProcessor, AmMediaProcessor.cpp:146] DEBUG: callgroup is 'YGUBKq5Rl1F+SjAN79FRAQ', thread 0 Feb 20 09:51:23 pbx sems[15965]: [#803087400] [postEvent, AmEventQueue.cpp:53] DEBUG: AmEventQueue: trying to post event Feb 20 09:51:23 pbx sems[15965]: [#803087400] [postEvent, AmEventQueue.cpp:64] DEBUG: AmEventQueue: event posted Feb 20 09:51:23 pbx sems[15965]: [#803087400] [stop, AmThread.cpp:168] DEBUG: Thread 34410624000 (34410624000) finished detach. Feb 20 09:51:23 pbx sems[15965]: [#803087400] [finalize, AmSession.cpp:529] DEBUG: session is stopped. Feb 20 09:51:23 pbx sems[15965]: [#803087400] [_start, AmThread.cpp:72] DEBUG: Thread 34410624000 is ending. Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.remote_tag = <19b2d718edc66508o0> Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: cseq = <10> Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handleSipMsg, AmSipDispatcher.cpp:51] DEBUG: unhandled SIP reply: BYE [code:200;phrase:[Closing];i:fa0e2e849e1130a02dd157a262e6a1fa;cseq:10;cseq meth:BYE;l-tag:6013B634-4F41DF59000DAA7A-0180A800;r-tag:19b2d718edc66508o0;]
Feb 20 09:51:23 pbx sems[15965]: [#80180b000] [handle_sip_reply, SipCtrlInterface.cpp:636] DEBUG: ^^ M [fa0e2e849e1130a02dd157a262e6a1fa|6013B634-4F41DF59000DAA7A-0180A800] ru S IP reply 200 Closing handled ^^ Feb 20 09:51:24 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:77] DEBUG: Session cleaner starting its work Feb 20 09:51:24 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:97] DEBUG: session [0x803440000] still running Feb 20 09:51:24 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:97] DEBUG: session [0x80309c800] still running Feb 20 09:51:24 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:93] DEBUG: session [0x80381e400] has been destroyed Feb 20 09:51:24 pbx sems[15965]: [#801809400] [save, CallStats.cpp:36] DEBUG: saved statistics: 8588 total 1744 failed 434799 seconds (7246 min) Feb 20 09:51:24 pbx sems[15965]: [#801809400] [~AmSession, AmSession.cpp:111] DEBUG: AmSession destructor finished Feb 20 09:51:24 pbx sems[15965]: [#801809400] [~AmSipDialog, AmSipDialog.cpp:61] DEBUG: callid = 7F8ACB67-4F41DF59000D92A3-01808000 Feb 20 09:51:24 pbx sems[15965]: [#801809400] [~AmSipDialog, AmSipDialog.cpp:62] DEBUG: local_tag = 7028B137-4F41DF59000D929E-01808000 Feb 20 09:51:24 pbx sems[15965]: [#801809400] [~AmSipDialog, AmSipDialog.cpp:63] DEBUG: uac_trans.size() = 0 Feb 20 09:51:24 pbx sems[15965]: [#801809400] [~AmSipDialog, AmSipDialog.cpp:71] DEBUG: uas_trans.size() = 0 Feb 20 09:51:24 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:97] DEBUG: session [0x803087400] still running Feb 20 09:51:24 pbx sems[15965]: [#801809400] [run, AmSessionContainer.cpp:134] DEBUG: Session cleaner finished Feb 20 09:51:25 pbx sems[15965]: [#80180a400] [trans_timer_cb, sip_trans.cpp:156] DEBUG: Transaction timer expired: type=K, trans=0x803489900, eta=121520, t=121520 Feb 20 09:51:25 pbx sems[15965]: [#80180a400] [reset_timer, sip_trans.cpp:139] DEBUG: Clearing old timer of type K (this=0x803b558c0) Feb 20 09:51:34 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:77] DEBUG: Session cleaner starting its work Feb 20 09:51:34 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:97] DEBUG: session [0x803440000] still running Feb 20 09:51:34 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:97] DEBUG: session [0x80309c800] still runningFeb 20 09:51:34 pbx sems[15965]: [#801809400] [clean_sessions, AmSessionContainer.cpp:97] DEBUG: session [0x803087400] still running
-----------------------------------As I see session 0x803087400 closed by BYE request, then BYE-OK received (OK unhandled) and session appears is "still running" in logfile. It stay in this state while SEMS restarted.
When SEMS has many such sessions then we have no audio for new conferences (signalling works fine)
I can send more logs and do some tests with different config if it's needed Thanks! -- CU, Victor Gamov
<<attachment: vit.vcf>>
_______________________________________________ Sems mailing list [email protected] http://lists.iptel.org/mailman/listinfo/sems
