Logs from `config.debug` sometimes go to the wrong place. #4286

jwalton · 2021-08-23T14:46:25Z

What version of Hls.js are you using?

v1.0.10

What browser (including version) are you using?

Chrome Version 92.0.4515.159 (Official Build) (x86_64)

What OS (including version) are you using?

MacOS 10.15.7

Test stream

No response

Configuration

// Start two hls.js instances, each logging to a custom logger:

const logger1 = new CircularLog();
const logger2 = new CircularLog();

const hls1 = new Hls({ debug: logger1, ... });
const hls2 = new Hls({ debug: logger2, ... });

Additional player setup steps

No response

Checklist

The issue observed is not already reported by searching on Github under https://github.com/video-dev/hls.js/issues
The issue occurs in the stable client (latest release) on https://hls-js.netlify.com/demo and not just on my page
The issue occurs in the latest client (main branch) on https://hls-js-dev.netlify.com/demo and not just on my page
The stream has correct Access-Control-Allow-Origin headers (CORS)
There are no network errors such as 404s in the browser console when trying to play the stream

Steps to reproduce

Start both HLS instances playing, then do something that will cause errors in the first instance, such as blocking the domain for the .ts files in Chrome's debug tools.

Expected behaviour

All logs from the first HLS.js instance should end up in the first CircularLog instance.

What actually happened?

Most log lines will go to the correct CircularLog instance, but some errors from the first Hls instance will end up in the second Hls instance's log. e.g.:

0 while loading https://blahblahblah.com/video/1629726271541_15968.ts

Errors like this will only ever show up in the second log, never in the first.

The problem here is caused by the fact that whenever you create a new Hls.js instance, the various functions on the passed in debug instance get copied to a global variable called exportedLogger in logger.ts. So all instances of hls.js end up sharing the most recently passed in logger. In many cases, the logs end up in the right place because classes end up binding a function from logger to an instance variable, so they keep whatever logger was assigned when they were created. But, this is not always the case, so lots of logs leak to the wrong logger.

One fix would be to hunt down all the places where the logger is called directly and do this bind trick, but a better fix would probably be to pass a log instance to all these classes when they are created, instead of having a global variable in the first place.

Console output

First instance:

2021-08-23T14:28:58.240Z - Debug: [PlaybackController] Added player CASH - Drive Thru Overhead.
2021-08-23T14:28:58.871Z - Info: [PlaybackController] Loaded short playlist [2021-08-23T13:28:29.001Z,2021-08-23T13:58:29.000Z] from local
2021-08-23T14:28:58.874Z - Info: [PlaybackController] Player switched state: undefined -> loading
2021-08-23T14:28:58.877Z - Info: 
2021-08-23T14:28:58.878Z - Info: attachMedia
2021-08-23T14:28:58.909Z - Info: [buffer-controller]: Media source opened
2021-08-23T14:28:58.909Z - Info: stopLoad
2021-08-23T14:28:58.910Z - Info: loadSource:https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/video.m3u8?stream=bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725309000&end=1629727109000
2021-08-23T14:28:58.910Z - Info: [stream-controller]: Trigger BUFFER_RESET
2021-08-23T14:28:58.911Z - Info: [level-controller]: manifest loaded, 1 level(s) found, first bitrate: 0
2021-08-23T14:28:58.911Z - Info: 1 bufferCodec event(s) expected
2021-08-23T14:28:58.911Z - Info: startLoad(916.493)
2021-08-23T14:28:58.911Z - Info: [level-controller]: switching to level 0 from -1
2021-08-23T14:28:58.911Z - Info: [stream-controller]: STOPPED->IDLE
2021-08-23T14:28:58.911Z - Info: [subtitle-stream-controller]: STOPPED->IDLE
2021-08-23T14:28:58.911Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:28:58.911Z - Info: [buffer-controller]: Updating Media Source duration to 1820.352
2021-08-23T14:28:58.912Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:28:58.912Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:28:59.463Z - Info: [PlaybackController] Loaded playlist [2021-08-23T04:00:00.000Z,2021-08-23T14:28:58.886Z] from local
2021-08-23T14:28:59.551Z - Info: demuxing in webworker
2021-08-23T14:28:59.555Z - Info: [transmuxer-interface, main]: Starting new transmux session for sn: 57 p: -1 level: 0 id: 1
        discontinuity: true
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: true
        timeOffset: 910.1759999999992
2021-08-23T14:28:59.555Z - Info: [stream-controller]: Loaded fragment 57 of level 0
2021-08-23T14:28:59.620Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:28:59.620Z - Info: [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[/avc1.4d401e]
2021-08-23T14:28:59.620Z - Info: [buffer-controller]: creating sourceBuffer(video/mp4;codecs=avc1.4d401e)
2021-08-23T14:28:59.620Z - Info: [audio-stream-controller]: InitPTS for cc: 0 found from main: 5563573780
2021-08-23T14:28:59.621Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:28:59.635Z - Info: [stream-controller]: Buffered main sn: 57 of level 0 [910.176,926.138]
2021-08-23T14:28:59.635Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:28:59.636Z - Info: [stream-controller]: Loading fragment 58 cc: 0 of [0-113] level: 0, target: 926.144
2021-08-23T14:28:59.636Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:28:59.637Z - Info: [stream-controller]: seek to target start position 916.493 from current time 0
2021-08-23T14:28:59.638Z - Info: [stream-controller]: media seeking to 916.493, state: FRAG_LOADING
2021-08-23T14:28:59.638Z - Info: [audio-stream-controller]: media seeking to 916.493, state: STOPPED
2021-08-23T14:28:59.638Z - Info: [subtitle-stream-controller]: media seeking to 916.493, state: IDLE
2021-08-23T14:28:59.710Z - Info: [stream-controller]: Media seeked to 916.502
2021-08-23T14:28:59.717Z - Info: [PlaybackController] Player switched state: loading -> playing
2021-08-23T14:28:59.908Z - Info: [stream-controller]: Loaded fragment 58 of level 0
2021-08-23T14:28:59.914Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:28:59.914Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:28:59.925Z - Info: [stream-controller]: Buffered main sn: 58 of level 0 [910.176,942.134]
2021-08-23T14:28:59.925Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:28:59.925Z - Info: [stream-controller]: Loading fragment 59 cc: 0 of [0-113] level: 0, target: 942.134
2021-08-23T14:28:59.925Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:28:59.932Z - Info: [stream-controller]: Loaded fragment 59 of level 0
2021-08-23T14:28:59.938Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:28:59.939Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:28:59.949Z - Info: [stream-controller]: Buffered main sn: 59 of level 0 [910.176,958.130]
2021-08-23T14:28:59.949Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:29:11.411Z - Info: [stream-controller]: Loading fragment 60 cc: 0 of [0-113] level: 0, target: 958.13
2021-08-23T14:29:11.411Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:11.473Z - Info: [stream-controller]: Loaded fragment 60 of level 0
2021-08-23T14:29:11.475Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:29:11.475Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:29:11.481Z - Info: [stream-controller]: Buffered main sn: 60 of level 0 [910.176,974.125]
2021-08-23T14:29:11.481Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:29:14.420Z - Info: [PlaybackController] Seeking to 2021-08-23T13:44:46.481Z
2021-08-23T14:29:14.780Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:14.780Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:14.837Z - Info: [stream-controller]: media seeking to 993.830, state: FRAG_LOADING
2021-08-23T14:29:14.837Z - Info: [audio-stream-controller]: media seeking to 993.830, state: STOPPED
2021-08-23T14:29:14.837Z - Info: [subtitle-stream-controller]: media seeking to 993.830, state: IDLE
2021-08-23T14:29:14.839Z - Info: [PlaybackController] Player switched state: playing -> waiting
2021-08-23T14:29:14.875Z - Warn: [stream-controller]: Fragment 62 of main 0 failed to load, retrying in 1000ms
2021-08-23T14:29:14.875Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:14.962Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:14.962Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:14.970Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:14.970Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:14.980Z - Warn: [stream-controller]: Fragment 62 of main 0 failed to load, retrying in 2000ms
2021-08-23T14:29:14.980Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.016Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.016Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.116Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:15.116Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.117Z - Warn: [stream-controller]: Fragment 62 of main 0 failed to load, retrying in 4000ms
2021-08-23T14:29:15.117Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.212Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.212Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.316Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:15.316Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.318Z - Warn: [stream-controller]: Fragment 62 of main 0 failed to load, retrying in 8000ms
2021-08-23T14:29:15.318Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.419Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.419Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.512Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:15.512Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.513Z - Warn: [stream-controller]: Fragment 62 of main 0 failed to load, retrying in 16000ms
2021-08-23T14:29:15.513Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.618Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.618Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.712Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:15.712Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.713Z - Warn: [stream-controller]: Fragment 62 of main 0 failed to load, retrying in 32000ms
2021-08-23T14:29:15.713Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.851Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.851Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.912Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:15.912Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.913Z - Info: [stream-controller]: FRAG_LOADING->STOPPED
2021-08-23T14:29:15.913Z - Info: [subtitle-stream-controller]: IDLE->STOPPED
2021-08-23T14:29:15.913Z - Info: [stream-controller]: STOPPED->ERROR
2021-08-23T14:29:15.914Z - Info: [PlaybackController] Player switched state: waiting -> error
2021-08-23T14:29:15.914Z - Error: [PlaybackController] Player error: Error: HLS error: networkError-fragLoadError
    at hlsErrorToError (webpack-internal:///./src/components/root/routes/components/video-player/components/player/hlsUtils.ts:80:10)
    at Hls.eval (webpack-internal:///./src/components/root/routes/components/video-player/components/player/PlayerCore.tsx:266:79)
    at EventEmitter.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:305:33)
    at Hls.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:16642:26)
    at Hls.trigger (webpack-internal:///./node_modules/hls.js/dist/hls.js:16647:19)
    at StreamController.handleFragLoadError (webpack-internal:///./node_modules/hls.js/dist/hls.js:3440:16)
    at eval (webpack-internal:///./node_modules/hls.js/dist/hls.js:3397:21)
2021-08-23T14:29:19.829Z - Info: [PlaybackController] Seeking to 2021-08-23T13:44:55.146Z
2021-08-23T14:29:20.937Z - Info: [level-controller]: Attempt loading level index 0 with URL-id 0 https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/video.m3u8?stream=bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725309000&end=1629727109000
2021-08-23T14:29:20.937Z - Info: [stream-controller]: ERROR->STOPPED
2021-08-23T14:29:20.937Z - Info: [stream-controller]: Override startPosition with lastCurrentTime @993.830
2021-08-23T14:29:20.937Z - Info: [stream-controller]: STOPPED->IDLE
2021-08-23T14:29:20.937Z - Info: [stream-controller]: Loading fragment 62 cc: 0 of [0-113] level: 0, target: 993.83
2021-08-23T14:29:20.937Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:20.938Z - Info: [subtitle-stream-controller]: STOPPED->IDLE
2021-08-23T14:29:20.938Z - Info: [stream-controller]: FRAG_LOADING->STOPPED
2021-08-23T14:29:20.938Z - Info: [subtitle-stream-controller]: IDLE->STOPPED
2021-08-23T14:29:20.939Z - Info: [stream-controller]: Trigger BUFFER_RESET
2021-08-23T14:29:20.944Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:20.945Z - Info: [level-controller]: manifest loaded, 1 level(s) found, first bitrate: 0
2021-08-23T14:29:20.945Z - Info: [level-controller]: Attempt loading level index 0 with URL-id 0 https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/video.m3u8?stream=bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725309000&end=1629727109000
2021-08-23T14:29:20.946Z - Info: [stream-controller]: STOPPED->IDLE
2021-08-23T14:29:20.946Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:20.946Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:20.947Z - Info: [subtitle-stream-controller]: STOPPED->IDLE
2021-08-23T14:29:20.948Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:20.949Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:20.960Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 1000ms
2021-08-23T14:29:20.960Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.046Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.046Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.151Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:21.151Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.152Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 2000ms
2021-08-23T14:29:21.152Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.298Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.298Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.346Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:21.346Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.347Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 4000ms
2021-08-23T14:29:21.347Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.446Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.446Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.547Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:21.547Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.548Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 8000ms
2021-08-23T14:29:21.548Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.812Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.812Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.893Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:21.893Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.895Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 16000ms
2021-08-23T14:29:21.895Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.953Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.953Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:22.046Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:22.046Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:22.048Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 32000ms
2021-08-23T14:29:22.048Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:22.151Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:22.151Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:22.246Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 916.493
2021-08-23T14:29:22.246Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:22.248Z - Info: [stream-controller]: FRAG_LOADING->STOPPED
2021-08-23T14:29:22.248Z - Info: [subtitle-stream-controller]: IDLE->STOPPED
2021-08-23T14:29:22.248Z - Info: [stream-controller]: STOPPED->ERROR
2021-08-23T14:29:22.248Z - Error: [PlaybackController] Player error: Error: HLS error: networkError-fragLoadError
    at hlsErrorToError (webpack-internal:///./src/components/root/routes/components/video-player/components/player/hlsUtils.ts:80:10)
    at Hls.eval (webpack-internal:///./src/components/root/routes/components/video-player/components/player/PlayerCore.tsx:266:79)
    at EventEmitter.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:305:33)
    at Hls.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:16642:26)
    at Hls.trigger (webpack-internal:///./node_modules/hls.js/dist/hls.js:16647:19)
    at StreamController.handleFragLoadError (webpack-internal:///./node_modules/hls.js/dist/hls.js:3440:16)
    at eval (webpack-internal:///./node_modules/hls.js/dist/hls.js:3397:21)

Second instance:

2021-08-23T14:29:02.831Z - Debug: [PlaybackController] Added player EXT - Drive Thru Lane.
2021-08-23T14:29:04.112Z - Info: [PlaybackController] Loaded short playlist [2021-08-23T13:28:31.999Z,2021-08-23T13:58:31.998Z] from local
2021-08-23T14:29:04.116Z - Info: [PlaybackController] Player switched state: undefined -> loading
2021-08-23T14:29:04.120Z - Info: 
2021-08-23T14:29:04.121Z - Info: attachMedia
2021-08-23T14:29:04.136Z - Info: [buffer-controller]: Media source opened
2021-08-23T14:29:04.136Z - Info: stopLoad
2021-08-23T14:29:04.136Z - Info: loadSource:https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/video.m3u8?stream=6769ccad-1a6c-43cd-b66d-6546b17381dc&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725311000&end=1629727111000
2021-08-23T14:29:04.136Z - Info: [stream-controller]: Trigger BUFFER_RESET
2021-08-23T14:29:04.137Z - Info: [level-controller]: manifest loaded, 1 level(s) found, first bitrate: 0
2021-08-23T14:29:04.138Z - Info: 1 bufferCodec event(s) expected
2021-08-23T14:29:04.138Z - Info: startLoad(915.659)
2021-08-23T14:29:04.138Z - Info: [level-controller]: switching to level 0 from -1
2021-08-23T14:29:04.138Z - Info: [stream-controller]: STOPPED->IDLE
2021-08-23T14:29:04.138Z - Info: [subtitle-stream-controller]: STOPPED->IDLE
2021-08-23T14:29:04.138Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:04.138Z - Info: [buffer-controller]: Updating Media Source duration to 1820.352
2021-08-23T14:29:04.138Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:04.138Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:04.155Z - Info: demuxing in webworker
2021-08-23T14:29:04.163Z - Info: [transmuxer-interface, main]: Starting new transmux session for sn: 57 p: -1 level: 0 id: 1
        discontinuity: true
        trackSwitch: true
        contiguous: false
        accurateTimeOffset: true
        timeOffset: 910.1759999999992
2021-08-23T14:29:04.163Z - Info: [stream-controller]: Loaded fragment 57 of level 0
2021-08-23T14:29:04.241Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:29:04.241Z - Info: [stream-controller]: Init video buffer, container:video/mp4, codecs[level/parsed]=[/avc1.4d401e]
2021-08-23T14:29:04.241Z - Info: [buffer-controller]: creating sourceBuffer(video/mp4;codecs=avc1.4d401e)
2021-08-23T14:29:04.241Z - Info: [audio-stream-controller]: InitPTS for cc: 0 found from main: 5564014962
2021-08-23T14:29:04.242Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:29:04.256Z - Info: [stream-controller]: Buffered main sn: 57 of level 0 [910.176,926.209]
2021-08-23T14:29:04.256Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:29:04.256Z - Info: [stream-controller]: Loading fragment 58 cc: 0 of [0-113] level: 0, target: 926.209
2021-08-23T14:29:04.256Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:04.257Z - Info: [stream-controller]: seek to target start position 915.659 from current time 0
2021-08-23T14:29:04.258Z - Info: [stream-controller]: media seeking to 915.659, state: FRAG_LOADING
2021-08-23T14:29:04.258Z - Info: [audio-stream-controller]: media seeking to 915.659, state: STOPPED
2021-08-23T14:29:04.258Z - Info: [subtitle-stream-controller]: media seeking to 915.659, state: IDLE
2021-08-23T14:29:04.280Z - Info: [stream-controller]: Loaded fragment 58 of level 0
2021-08-23T14:29:04.287Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:29:04.288Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:29:04.293Z - Info: [stream-controller]: Buffered main sn: 58 of level 0 [910.176,942.209]
2021-08-23T14:29:04.293Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:29:04.293Z - Info: [stream-controller]: Loading fragment 59 cc: 0 of [0-113] level: 0, target: 942.209
2021-08-23T14:29:04.293Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:04.309Z - Info: [stream-controller]: Loaded fragment 59 of level 0
2021-08-23T14:29:04.321Z - Info: [stream-controller]: FRAG_LOADING->PARSING
2021-08-23T14:29:04.321Z - Info: [stream-controller]: PARSING->PARSED
2021-08-23T14:29:04.326Z - Info: [stream-controller]: Buffered main sn: 59 of level 0 [910.176,958.209]
2021-08-23T14:29:04.326Z - Info: [stream-controller]: PARSED->IDLE
2021-08-23T14:29:04.382Z - Info: [stream-controller]: Media seeked to 915.667
2021-08-23T14:29:04.389Z - Info: [PlaybackController] Player switched state: loading -> playing
2021-08-23T14:29:04.607Z - Info: [PlaybackController] Loaded playlist [2021-08-23T04:00:00.000Z,2021-08-23T14:29:04.125Z] from local
2021-08-23T14:29:14.420Z - Info: [PlaybackController] Seeking to 2021-08-23T13:44:46.481Z
2021-08-23T14:29:14.846Z - Info: [stream-controller]: media seeking to 988.988, state: IDLE
2021-08-23T14:29:14.846Z - Info: [audio-stream-controller]: media seeking to 988.988, state: STOPPED
2021-08-23T14:29:14.846Z - Info: [subtitle-stream-controller]: media seeking to 988.988, state: IDLE
2021-08-23T14:29:14.848Z - Info: [PlaybackController] Player switched state: playing -> waiting
2021-08-23T14:29:14.869Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:14.869Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:14.874Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:14.963Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:14.963Z - Warn: [stream-controller]: Fragment 61 of main 0 failed to load, retrying in 1000ms
2021-08-23T14:29:14.963Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:14.972Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:14.972Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:14.975Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:14.975Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:14.980Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:14.984Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:14.984Z - Warn: [stream-controller]: Fragment 61 of main 0 failed to load, retrying in 2000ms
2021-08-23T14:29:14.984Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.040Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.040Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.117Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:15.140Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:15.140Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.141Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:15.141Z - Warn: [stream-controller]: Fragment 61 of main 0 failed to load, retrying in 4000ms
2021-08-23T14:29:15.141Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.238Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.238Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.318Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:15.338Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:15.338Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.339Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:15.339Z - Warn: [stream-controller]: Fragment 61 of main 0 failed to load, retrying in 8000ms
2021-08-23T14:29:15.339Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.444Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.444Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.513Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:15.538Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:15.538Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.539Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:15.539Z - Warn: [stream-controller]: Fragment 61 of main 0 failed to load, retrying in 16000ms
2021-08-23T14:29:15.539Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.638Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.638Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.713Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:15.743Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:15.743Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.745Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:15.745Z - Warn: [stream-controller]: Fragment 61 of main 0 failed to load, retrying in 32000ms
2021-08-23T14:29:15.745Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:15.853Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:15.853Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:15.913Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726282667_15968.ts
2021-08-23T14:29:15.913Z - Error: fragLoadError reaches max retry, redispatch as fatal ...
2021-08-23T14:29:15.913Z - Info: stopLoad
2021-08-23T14:29:15.997Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:15.997Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:15.998Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726271541_15968.ts
2021-08-23T14:29:15.998Z - Error: fragLoadError reaches max retry, redispatch as fatal ...
2021-08-23T14:29:15.998Z - Info: stopLoad
2021-08-23T14:29:15.998Z - Info: [stream-controller]: FRAG_LOADING->STOPPED
2021-08-23T14:29:15.998Z - Info: [subtitle-stream-controller]: IDLE->STOPPED
2021-08-23T14:29:15.998Z - Info: [stream-controller]: STOPPED->ERROR
2021-08-23T14:29:15.999Z - Info: [PlaybackController] Player switched state: waiting -> error
2021-08-23T14:29:15.999Z - Error: [PlaybackController] Player error: Error: HLS error: networkError-fragLoadError
    at hlsErrorToError (webpack-internal:///./src/components/root/routes/components/video-player/components/player/hlsUtils.ts:80:10)
    at Hls.eval (webpack-internal:///./src/components/root/routes/components/video-player/components/player/PlayerCore.tsx:266:79)
    at EventEmitter.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:305:33)
    at Hls.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:16642:26)
    at Hls.trigger (webpack-internal:///./node_modules/hls.js/dist/hls.js:16647:19)
    at StreamController.handleFragLoadError (webpack-internal:///./node_modules/hls.js/dist/hls.js:3440:16)
    at eval (webpack-internal:///./node_modules/hls.js/dist/hls.js:3397:21)
2021-08-23T14:29:19.829Z - Info: [PlaybackController] Seeking to 2021-08-23T13:44:55.146Z
2021-08-23T14:29:20.937Z - Info: startLoad(-1)
2021-08-23T14:29:20.938Z - Info: stopLoad
2021-08-23T14:29:20.938Z - Info: loadSource:https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/video.m3u8?stream=bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725309000&end=1629727109000
2021-08-23T14:29:20.945Z - Info: 1 bufferCodec event(s) expected
2021-08-23T14:29:20.945Z - Info: startLoad(916.493)
2021-08-23T14:29:20.960Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:21.013Z - Info: startLoad(-1)
2021-08-23T14:29:21.013Z - Info: [level-controller]: Attempt loading level index 0 with URL-id 0 https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/video.m3u8?stream=6769ccad-1a6c-43cd-b66d-6546b17381dc&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725311000&end=1629727111000
2021-08-23T14:29:21.013Z - Info: [stream-controller]: ERROR->STOPPED
2021-08-23T14:29:21.014Z - Info: [stream-controller]: Override startPosition with lastCurrentTime @988.988
2021-08-23T14:29:21.014Z - Info: [stream-controller]: STOPPED->IDLE
2021-08-23T14:29:21.014Z - Info: [stream-controller]: Loading fragment 61 cc: 0 of [0-113] level: 0, target: 988.988
2021-08-23T14:29:21.014Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.015Z - Info: [subtitle-stream-controller]: STOPPED->IDLE
2021-08-23T14:29:21.015Z - Info: stopLoad
2021-08-23T14:29:21.015Z - Info: [stream-controller]: FRAG_LOADING->STOPPED
2021-08-23T14:29:21.015Z - Info: [subtitle-stream-controller]: IDLE->STOPPED
2021-08-23T14:29:21.015Z - Info: loadSource:https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/video.m3u8?stream=6769ccad-1a6c-43cd-b66d-6546b17381dc&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725311000&end=1629727111000
2021-08-23T14:29:21.015Z - Info: [stream-controller]: Trigger BUFFER_RESET
2021-08-23T14:29:21.018Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:21.019Z - Info: [level-controller]: manifest loaded, 1 level(s) found, first bitrate: 0
2021-08-23T14:29:21.019Z - Info: 1 bufferCodec event(s) expected
2021-08-23T14:29:21.019Z - Info: startLoad(915.659)
2021-08-23T14:29:21.019Z - Info: [level-controller]: Attempt loading level index 0 with URL-id 0 https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/video.m3u8?stream=6769ccad-1a6c-43cd-b66d-6546b17381dc&motiongrid=true&ai=true&discontinuityTag=true&source=cloudfirst&begin=1629725311000&end=1629727111000
2021-08-23T14:29:21.020Z - Info: [stream-controller]: STOPPED->IDLE
2021-08-23T14:29:21.020Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:21.020Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.021Z - Info: [subtitle-stream-controller]: STOPPED->IDLE
2021-08-23T14:29:21.021Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:21.022Z - Info: [stream-controller]: Level 0 loaded [0,113], cc [0, 0] duration:1820.3520000000026
2021-08-23T14:29:21.028Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:21.028Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 1000ms
2021-08-23T14:29:21.028Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.120Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.120Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.152Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:21.223Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:21.223Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.224Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:21.224Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 2000ms
2021-08-23T14:29:21.224Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.320Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.320Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.347Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:21.423Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:21.423Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.425Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:21.425Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 4000ms
2021-08-23T14:29:21.425Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.520Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.520Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.548Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:21.811Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:21.811Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.856Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:21.857Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 8000ms
2021-08-23T14:29:21.857Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:21.874Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:21.874Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:21.895Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:21.920Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:21.920Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:21.922Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:21.922Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 16000ms
2021-08-23T14:29:21.922Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:22.020Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:22.020Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:22.047Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:22.120Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:22.120Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:22.126Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:22.126Z - Warn: [stream-controller]: Fragment 57 of main 0 failed to load, retrying in 32000ms
2021-08-23T14:29:22.126Z - Info: [stream-controller]: FRAG_LOADING->FRAG_LOADING_WAITING_RETRY
2021-08-23T14:29:22.220Z - Info: [stream-controller]: retryDate reached, switch back to IDLE state
2021-08-23T14:29:22.220Z - Info: [stream-controller]: FRAG_LOADING_WAITING_RETRY->IDLE
2021-08-23T14:29:22.247Z - Error: 0 while loading https://blahblahblah.com/cameras/yJQnDvWfUlJzUWNN/ts/bd9c2b4e-dcf4-4156-e398-b19f6a09dcc5/1629726202683_15968.ts
2021-08-23T14:29:22.248Z - Error: fragLoadError reaches max retry, redispatch as fatal ...
2021-08-23T14:29:22.248Z - Info: stopLoad
2021-08-23T14:29:22.320Z - Info: [stream-controller]: Loading fragment 57 cc: 0 of [0-113] level: 0, target: 915.659
2021-08-23T14:29:22.320Z - Info: [stream-controller]: IDLE->FRAG_LOADING
2021-08-23T14:29:22.321Z - Error: 0 while loading https://blahblahblah.com/cameras/JxZpBuWze6sDXWd2/ts/6769ccad-1a6c-43cd-b66d-6546b17381dc/1629726207527_15968.ts
2021-08-23T14:29:22.321Z - Error: fragLoadError reaches max retry, redispatch as fatal ...
2021-08-23T14:29:22.321Z - Info: stopLoad
2021-08-23T14:29:22.321Z - Info: [stream-controller]: FRAG_LOADING->STOPPED
2021-08-23T14:29:22.321Z - Info: [subtitle-stream-controller]: IDLE->STOPPED
2021-08-23T14:29:22.321Z - Info: [stream-controller]: STOPPED->ERROR
2021-08-23T14:29:22.322Z - Error: [PlaybackController] Player error: Error: HLS error: networkError-fragLoadError
    at hlsErrorToError (webpack-internal:///./src/components/root/routes/components/video-player/components/player/hlsUtils.ts:80:10)
    at Hls.eval (webpack-internal:///./src/components/root/routes/components/video-player/components/player/PlayerCore.tsx:266:79)
    at EventEmitter.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:305:33)
    at Hls.emit (webpack-internal:///./node_modules/hls.js/dist/hls.js:16642:26)
    at Hls.trigger (webpack-internal:///./node_modules/hls.js/dist/hls.js:16647:19)
    at StreamController.handleFragLoadError (webpack-internal:///./node_modules/hls.js/dist/hls.js:3440:16)
    at eval (webpack-internal:///./node_modules/hls.js/dist/hls.js:3397:21)

Chrome media internals output

No response

The text was updated successfully, but these errors were encountered:

robwalch · 2021-09-23T21:20:21Z

Duplicates #2461 (Not a Contribution)

stale · 2022-04-17T19:02:06Z

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs.

stale · 2022-04-29T08:01:53Z

This issue has been automatically closed because it has not had recent activity. If this issue is still valid, please ping a maintainer and ask them to label it accordingly.

jwalton added Bug Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. labels Aug 23, 2021

robwalch removed the Needs Triage If there is a suspected stream issue, apply this label to triage if it is something we should fix. label Sep 23, 2021

stale bot added the Stale label Apr 17, 2022

stale bot closed this as completed Apr 29, 2022

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Logs from `config.debug` sometimes go to the wrong place. #4286

Logs from `config.debug` sometimes go to the wrong place. #4286

jwalton commented Aug 23, 2021

robwalch commented Sep 23, 2021

stale bot commented Apr 17, 2022

stale bot commented Apr 29, 2022

Logs from config.debug sometimes go to the wrong place. #4286

Logs from config.debug sometimes go to the wrong place. #4286

Comments

jwalton commented Aug 23, 2021

What version of Hls.js are you using?

What browser (including version) are you using?

What OS (including version) are you using?

Test stream

Configuration

Additional player setup steps

Checklist

Steps to reproduce

Expected behaviour

What actually happened?

Console output

Chrome media internals output

robwalch commented Sep 23, 2021

stale bot commented Apr 17, 2022

stale bot commented Apr 29, 2022

Logs from `config.debug` sometimes go to the wrong place. #4286

Logs from `config.debug` sometimes go to the wrong place. #4286