Summary:	ASTERISK-15848: Adaptive Jitter Buffer issue
Reporter:	Scott Johnson (globalnetinc)	Labels:
Date Opened:	2010-03-20 19:32:22	Date Closed:
Priority:	Minor	Regression?	No
Status:	Open/New	Components:	Core/Jitterbuffer
Versions:		Frequency of Occurrence
Related Issues:
Environment:		Attachments:
Description:	After upgrade to 1.6.2.6 the adaptive part of th jitter buffer works for the first time in 1.6.2.x.  It does however have some issues. The first word or two are broken up.  In the log these errors are created for every call. [Mar 20 18:28:21] WARNING[25522]: abstract_jb.c:469 create_jb: Failed to put first frame in the jitterbuffer on channel 'SIP/VoIP360Residential-00000001' [Mar 20 18:28:21] WARNING[25522]: abstract_jb.c:469 create_jb: Failed to put first frame in the jitterbuffer on channel 'SIP/4065511212-00000000' [Mar 20 18:28:21] WARNING[25522]: chan_iax2.c:1094 jb_warning_output: Resyncing the jb. last_delay 0, this delay -40544659, threshold 500, new offset 40544659 [Mar 20 18:28:21] WARNING[25522]: chan_iax2.c:1094 jb_warning_output: Resyncing the jb. last_delay 0, this delay -13990, threshold 500, new offset 13990
Comments:	By: Scott Johnson (globalnetinc) 2010-03-20 19:58:05 I also see these errors. [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24721 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24722 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24723 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24724 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24725 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24726 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24727 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24728 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24729 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24730 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24731 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24732 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24733 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24734 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=24735 >= next=24721, jbnext=24721! [Mar 20 18:56:31] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=25121 >= next=25121, jbnext=25121! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28166 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28167 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28168 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28169 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28170 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28171 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28172 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28173 >= next=28166, jbnext=28166! [Mar 20 18:56:34] WARNING[30973]: abstract_jb.c:429 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=28174 >= next=28166, jbnext=28166! By: Scott Johnson (globalnetinc) 2010-08-18 22:30:12 [Aug 18 21:28:40] WARNING[5140]: abstract_jb.c:468 create_jb: Failed to put first frame in the jitterbuffer on channel 'SIP/4065511212-00000002' [Aug 18 21:28:40] WARNING[5140]: abstract_jb.c:468 create_jb: Failed to put first frame in the jitterbuffer on channel 'SIP/VoIP360Residential-00000003' [Aug 18 21:28:40] WARNING[5140]: chan_iax2.c:1146 jb_warning_output: Resyncing the jb. last_delay 0, this delay -8620, threshold 800, new offset 8620 [Aug 18 21:28:40] WARNING[5140]: chan_iax2.c:1146 jb_warning_output: Resyncing the jb. last_delay 0, this delay -12888993, threshold 800, new offset 12888993 Still an issue in 1.8 By: petergg (petergg) 2010-08-19 05:16:26 hi, i can confirm the problem (no audio) with VOIP-Phone Thomson ST2030 (codec settings jitter adaptive) abstract_jb.c:471 create_jb: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXXXX' -- adaptive jitterbuffer created on channel SIP/XXXXXXX Asterisk 1.4.35 By: andre valentin (avalentin) 2010-09-11 11:13:17 Hi! I can confirm the problem, too. Asterisk: 1.8.0 Beta4 / SVN 286266 Aasta 55i 57i SNOM 360 Leads to no audio. It happens if I do an attended transfer of a dahdi channel from one sip phone to another. By: ks-steven (ks-steven) 2010-09-17 09:09:56 Hi, I also have the exect same problem on Asterisk 1.6.2.9 By: leurk (leurk) 2010-09-22 04:49:29 Same here: 1.6.2.11, no audio from call center. [Sep 21 21:03:16] VERBOSE[16901] app_queue.c:     -- Called SIP/CC01 [Sep 21 21:03:16] VERBOSE[16901] app_queue.c:     -- SIP/CC01-00002fbc is ringing [Sep 21 21:03:16] VERBOSE[16901] app_queue.c:     -- SIP/CC01-00002fbc is ringing [Sep 21 21:03:24] VERBOSE[16901] app_queue.c:     -- SIP/CC01-00002fbc answered SIP/openser-new-00002fbb [Sep 21 21:03:24] VERBOSE[16901] res_musiconhold.c:     -- Stopped music on hold on SIP/openser-new-00002fbb [Sep 21 21:03:24] VERBOSE[16901] pbx.c:     -- Executing [s@macro-now-timestamp-gmt:1] Set("SIP/CC01-00002fbc", "START_TIME=2010-09-21 18:03:24") in new stack [Sep 21 21:03:24] WARNING[16901] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/openser-new-00002fbb' [Sep 21 21:03:24] VERBOSE[16901] abstract_jb.c:     -- adaptive jitterbuffer created on channel SIP/openser-new-00002fbb [Sep 21 21:03:24] WARNING[16901] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/CC01-00002fbc' [Sep 21 21:03:24] VERBOSE[16901] abstract_jb.c:     -- adaptive jitterbuffer created on channel SIP/CC01-00002fbc [Sep 21 21:03:38] VERBOSE[16901] pbx.c:     -- Executing [h@576-queue-expert-linear:1] ResetCDR("SIP/openser-new-00002fbb", "w") in new stack [Sep 21 21:03:38] VERBOSE[16901] pbx.c:     -- Executing [h@576-queue-expert-linear:2] NoCDR("SIP/openser-new-00002fbb", "") in new stack [Sep 21 21:03:38] VERBOSE[16901] pbx.c:     -- Executing [h@576-queue-expert-linear:3] Hangup("SIP/openser-new-00002fbb", "") in new stack [Sep 21 21:03:38] VERBOSE[16901] abstract_jb.c:     -- adaptive jitterbuffer destroyed on channel SIP/CC01-00002fbc [Sep 21 21:03:38] VERBOSE[16901] pbx.c:   == Spawn extension (576-queue-expert-linear, s, 4) exited non-zero on 'SIP/openser-new-00002fbb' [Sep 21 21:03:38] VERBOSE[16901] abstract_jb.c:     -- adaptive jitterbuffer destroyed on channel SIP/openser-new-00002fbb By: Jaco Kroon (jkroon) 2010-10-06 02:08:14 Can confirm on 1.6.2.13: [Oct  6 08:39:31] WARNING[898] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXXXXX-0000243e' [Oct  6 08:39:32] WARNING[898] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXXXXX-0000243f' [Oct  6 08:39:34] WARNING[904] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXX-00002443' [Oct  6 08:39:35] WARNING[904] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXX-00002444' [Oct  6 08:39:38] WARNING[889] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XX-00002437' [Oct  6 08:39:38] WARNING[889] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXX-00002438' [Oct  6 08:39:41] WARNING[925] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXX-00002448' [Oct  6 08:39:41] WARNING[925] abstract_jb.c: Failed to put first frame in the jitterbuffer on channel 'SIP/XXXXX-00002449' jb related settings in sip.conf: jbenable=yes jbforce=yes jbimpl=adaptive for me this only happens when bridging sip to sip (I still force the JB because some clients have really horrid connections and then the other party complains about call breakup if their phones can't deal with the jitter). By: Scott Johnson (globalnetinc) 2010-12-03 09:39:53.000-0600 also see these errors [Dec  3 01:35:32] WARNING[9181]: abstract_jb.c:428 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=9359 >= next=9359, jbnext=9359! [Dec  3 01:35:32] WARNING[9181]: abstract_jb.c:428 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=9360 >= next=9359, jbnext=9359! [Dec  3 01:35:32] WARNING[9181]: abstract_jb.c:428 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=9361 >= next=9359, jbnext=9359! [Dec  3 01:35:32] WARNING[9181]: abstract_jb.c:428 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=9362 >= next=9359, jbnext=9359! [Dec  3 01:35:32] WARNING[9181]: abstract_jb.c:428 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=9363 >= next=9359, jbnext=9359! [Dec  3 01:35:32] WARNING[9181]: abstract_jb.c:428 jb_get_and_deliver: JB_IMPL_NOFRAME is returned from the adaptive jb when now=9364 >= next=9359, jbnext=9359! By: Yaakov (yaakovd) 2010-12-06 08:45:52.000-0600 I am in 1.4.32 version and I also facing with these Warnings and when it happens the the audio is cut , what should I do? WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232738 >= next=232737, jbnext=232737! [2010-12-06 15:23:20] WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232739 >= next=232737, jbnext=232737! [2010-12-06 15:23:20] WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232740 >= next=232737, jbnext=232737! [2010-12-06 15:23:20] WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232741 >= next=232737, jbnext=232737! [2010-12-06 15:23:20] WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232742 >= next=232737, jbnext=232737! [2010-12-06 15:23:20] WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232743 >= next=232737, jbnext=232737! [2010-12-06 15:23:20] WARNING[29220]: abstract_jb.c:431 jb_get_and_deliver: JB_IMPL_NOFRAME is retuned from the adaptive jb when now=232744 >= next=232737, jbnext=2327 By: Sean Darcy (seandarcy) 2012-01-20 12:46:29.831-0600 And in 10.1.0-rc1    -- adaptive jitterbuffer created on channel DAHDI/1-1 [Jan 20 12:25:53] WARNING[2932]: abstract_jb.c:384 jb_get_and_deliver: AST_JB_IMPL_NOFRAME is returned from the adaptive jb when now=4496 >= next=4496, jbnext=4496! [Jan 20 12:25:53] WARNING[2932]: abstract_jb.c:384 jb_get_and_deliver: AST_JB_IMPL_NOFRAME is returned from the adaptive jb when now=4498 >= next=4496, jbnext=4496! [Jan 20 12:25:53] WARNING[2932]: abstract_jb.c:384 jb_get_and_deliver: AST_JB_IMPL_NOFRAME is returned from the adaptive jb when now=4499 >= next=4496, jbnext=4496! .......... chan_dahdi.conf: jbenable = yes jbimpl = adaptive Doesn't seem to affect the sound. Is this just an over-zealous warning?