EnableVideoTrackIfNeeded hits a DCHECK when trying MediaSession API |
||||
Issue description1. Checkout ToT, make a debug build and install it on a Pixel XL. 2. Navigate https://xxyzzzq.github.io/sandbox/media-session/full-test.html, and start playing. 3. Press Home key to background Chrome. 4. Switch track from the media notification. Observed: A DCHECK is hit in logcat **Note** I can only repro on Pixel XL running N, unable to reproduce on N5X running M. Seems like related to recent changes about background playback.
,
Jan 20 2017
See the following logcat (happens after pressing next track from the notification)
01-20 18:41:17.354 4653 4756 V chromium: [VERBOSE1:pipeline_impl.cc(776)] kPlaying -> kStopping
01-20 18:41:17.354 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kStopping"}
01-20 18:41:17.354 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(112)] ~RendererImpl
01-20 18:41:17.354 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(88)] ~AudioRendererImpl
01-20 18:41:17.355 4653 4756 V chromium: [VERBOSE1:decoder_stream.cc(72)] ~DecoderStream<audio>
01-20 18:41:17.355 4653 4756 V chromium: [VERBOSE1:mojo_audio_decoder.cc(34)] ~MojoAudioDecoder
01-20 18:41:17.356 4653 4756 V chromium: [VERBOSE1:pipeline_impl.cc(776)] kStopping -> kStopped
01-20 18:41:17.356 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kStopped"}
01-20 18:41:17.356 4697 4768 V chromium: [VERBOSE1:media_codec_audio_decoder.cc(34)] ~MediaCodecAudioDecoder
01-20 18:41:17.357 4653 4722 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: WEBMEDIAPLAYER_DESTROYED {}
01-20 18:41:17.358 4653 4722 W chromium: [WARNING:HTMLMediaElement.cpp(1949)] currentPlaybackPosition readyState = 4 but no webMeidaPlayer to provide currentPlaybackPosition
01-20 18:41:17.359 4653 4722 W chromium: [WARNING:HTMLMediaElement.cpp(1949)] currentPlaybackPosition readyState = 4 but no webMeidaPlayer to provide currentPlaybackPosition
01-20 18:41:17.360 4611 4693 V chromium: [VERBOSE1:audio_sync_reader.cc(81)] Trailing glitch count on destruction: 0
01-20 18:41:17.360 4611 4693 V chromium: [VERBOSE1:audio_sync_reader.cc(116)] ASR: number of detected audio glitches: 0 out of 1141
01-20 18:41:17.375 4653 4722 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kCreated"}
01-20 18:41:17.375 4653 4722 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: WEBMEDIAPLAYER_CREATED {}
01-20 18:41:17.375 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(579)] setVolume(1)
01-20 18:41:17.376 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(613)] setPreload(2)
01-20 18:41:17.376 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(310)] load(0, http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3, 0)
01-20 18:41:17.376 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(381)] DoLoad
01-20 18:41:17.376 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1731)] SetNetworkState(2)
01-20 18:41:17.376 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1739)] SetReadyState(0)
01-20 18:41:17.376 4653 4722 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: LOAD {"url":"http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3"}
01-20 18:41:17.377 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(245)] SetPreload(2)
01-20 18:41:17.377 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(579)] UpdateBufferSizes
01-20 18:41:17.377 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(579)] UpdateBufferSizes
01-20 18:41:17.377 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(579)] UpdateBufferSizes
01-20 18:41:17.377 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(75)] Start @ 0
01-20 18:41:17.378 4653 4722 V chromium: [VERBOSE1:ResourceFetcher.cpp(684)] Loading Resource for "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3"
01-20 18:41:17.380 4611 4693 V chromium: [VERBOSE1:remote_database_manager.cc(189)] Checking for client 0xcad85288 and URL http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3
01-20 18:41:17.381 4611 4693 V chromium: [VERBOSE1:partial_data.cc(65)] Range start: 0 end: -1
01-20 18:41:17.382 4611 4693 V chromium: [VERBOSE1:remote_database_manager.cc(88)] OnRequestDone took 1 ms for client 0xcad85288 and URL http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3
01-20 18:41:17.389 4611 4693 V chromium: [VERBOSE1:spdy_session_key.cc(21)] SpdySessionKey(host=xxyzzzq.github.io:80, proxy=direct://, privacy=0
01-20 18:41:17.389 4611 4693 V chromium: [VERBOSE1:http_stream_parser.cc(231)] SendRequest() request_line = "GET /sandbox/media-session/media/buddy.mp3 HTTP/1.1
01-20 18:41:17.389 4611 4693 V chromium: " headers = "Host: xxyzzzq.github.io
01-20 18:41:17.389 4611 4693 V chromium: Connection: keep-alive
01-20 18:41:17.389 4611 4693 V chromium: Accept-Encoding: identity;q=1, *;q=0
01-20 18:41:17.389 4611 4693 V chromium: User-Agent: Mozilla/5.0 (Linux; Android 7.1.2; Pixel XL Build/N2G19) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/57.0.2987.0 Mobile Safari/537.36
01-20 18:41:17.389 4611 4693 V chromium: Accept: */*
01-20 18:41:17.389 4611 4693 V chromium: Referer: http://xxyzzzq.github.io/sandbox/media-session/full-test.html
01-20 18:41:17.389 4611 4693 V chromium: Accept-Language: en-US,zh-CN;q=0.8,zh;q=0.6,en;q=0.4
01-20 18:41:17.389 4611 4693 V chromium: Range: bytes=0-1710078
01-20 18:41:17.389 4611 4693 V chromium: If-Modified-Since: Fri, 20 Jan 2017 14:59:26 GMT
01-20 18:41:17.389 4611 4693 V chromium:
01-20 18:41:17.389 4611 4693 V chromium: "
01-20 18:41:17.395 4653 4722 V chromium: [VERBOSE1:webmediaplayer_cast_android.cc(336)] OnRemoteRouteAvailabilityChanged
01-20 18:41:17.482 4611 4693 V chromium: [VERBOSE1:http_stream_parser.cc(1007)] ParseResponseHeaders() content_length = "1710079
01-20 18:41:17.482 4611 4693 V chromium: " headers = "HTTP/1.1 206 Partial Content
01-20 18:41:17.482 4611 4693 V chromium: Server: GitHub.com
01-20 18:41:17.482 4611 4693 V chromium: Content-Type: audio/mp3
01-20 18:41:17.482 4611 4693 V chromium: Last-Modified: Fri, 20 Jan 2017 14:59:26 GMT
01-20 18:41:17.482 4611 4693 V chromium: Access-Control-Allow-Origin: *
01-20 18:41:17.482 4611 4693 V chromium: Expires: Fri, 20 Jan 2017 15:58:22 GMT
01-20 18:41:17.482 4611 4693 V chromium: Cache-Control: max-age=600
01-20 18:41:17.482 4611 4693 V chromium: X-GitHub-Request-Id: 5A60:244E9:ED0FE4:13DF32B:58823144
01-20 18:41:17.482 4611 4693 V chromium: Accept-Ranges: bytes
01-20 18:41:17.482 4611 4693 V chromium: Age: 0
01-20 18:41:17.482 4611 4693 V chromium: Content-Range: bytes 0-1710078/1710079
01-20 18:41:17.482 4611 4693 V chromium: Content-Length: 1710079
01-20 18:41:17.482 4611 4693 V chromium: Accept-Ranges: bytes
01-20 18:41:17.482 4611 4693 V chromium: Date: Fri, 20 Jan 2017 18:41:17 GMT
01-20 18:41:17.482 4611 4693 V chromium: Via: 1.1 varnish
01-20 18:41:17.482 4611 4693 V chromium: Connection: keep-alive
01-20 18:41:17.482 4611 4693 V chromium: X-Served-By: cache-lhr6345-LHR
01-20 18:41:17.482 4611 4693 V chromium: X-Cache: HIT
01-20 18:41:17.482 4611 4693 V chromium: X-Cache-Hits: 0
01-20 18:41:17.482 4611 4693 V chromium: X-Timer: S1484937677.773703,VS0,VE85
01-20 18:41:17.482 4611 4693 V chromium: Vary: Accept-Encoding
01-20 18:41:17.482 4611 4693 V chromium: X-Fastly-Request-ID: 95578cb5ca2396d3b7f49b1168662219194d0f95
01-20 18:41:17.482 4611 4693 V chromium: "
01-20 18:41:17.482 4611 4693 W chromium: [WARNING:http_cache_transaction.cc(2514)] Failed to revalidate partial entry
01-20 18:41:17.485 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(339)] OnResponseStarted: http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3
01-20 18:41:17.485 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 12366
01-20 18:41:17.488 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(224)] didReceiveResponse: HTTP/1.1 206
01-20 18:41:17.489 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 12366 bytes
01-20 18:41:17.491 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 27480
01-20 18:41:17.491 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 12366)
01-20 18:41:17.491 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.491 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1610)] NotifyDownloading
01-20 18:41:17.492 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 27480 bytes
01-20 18:41:17.493 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.493 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1610)] NotifyDownloading
01-20 18:41:17.494 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 39846)
01-20 18:41:17.494 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.494 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1576)] DataSourceInitialized
01-20 18:41:17.495 4653 4722 V chromium: [VERBOSE1:media_interface_provider.cc(27)] GetInterface
01-20 18:41:17.495 4653 4722 V chromium: [VERBOSE1:media_interface_provider.cc(51)] GetMediaInterfaceFactory
01-20 18:41:17.495 4653 4722 V chromium: [VERBOSE1:mojo_audio_decoder.cc(30)] MojoAudioDecoder
01-20 18:41:17.495 4653 4722 V chromium: [VERBOSE1:decoder_stream.cc(67)] DecoderStream<audio>
01-20 18:41:17.495 4653 4722 V chromium: [VERBOSE1:decoder_stream.cc(67)] DecoderStream<video>
01-20 18:41:17.495 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 32768
01-20 18:41:17.495 4653 4722 V chromium: [VERBOSE1:renderer_impl.cc(97)] RendererImpl
01-20 18:41:17.496 4653 4756 V chromium: [VERBOSE1:pipeline_impl.cc(776)] kCreated -> kStarting
01-20 18:41:17.496 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kStarting"}
01-20 18:41:17.496 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 16696
01-20 18:41:17.497 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 32768 bytes
01-20 18:41:17.498 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 16696 bytes
01-20 18:41:17.498 4611 4611 V chromium: [VERBOSE1:media_interface_proxy.cc(77)] GetMediaInterfaceFactory
01-20 18:41:17.499 4697 4768 V chromium: [VERBOSE1:media_codec_audio_decoder.cc(30)] MediaCodecAudioDecoder
01-20 18:41:17.499 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 72614)
01-20 18:41:17.500 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.500 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(65536, 89310)
01-20 18:41:17.500 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.501 4653 4816 V chromium: [VERBOSE1:multibuffer_data_source.cc(350)] Read: 0 offset, 8192 bytes
01-20 18:41:17.501 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.502 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(65536, 89310)
01-20 18:41:17.501 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 12366
01-20 18:41:17.502 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.502 4653 4816 V chromium: [VERBOSE1:multibuffer_data_source.cc(350)] Read: 0 offset, 32768 bytes
01-20 18:41:17.504 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.506 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 12366 bytes
01-20 18:41:17.506 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(65536, 89310)
01-20 18:41:17.506 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.507 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(65536, 89310)
01-20 18:41:17.507 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.507 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 101676)
01-20 18:41:17.507 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.508 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: MEDIA_INFO_LOG_ENTRY {"info":"FFmpegDemuxer: created audio stream, config codec: mp3 bytes_per_channel: 2 channel_layout: 3 samples_per_second: 44100 sample_format: 5 bytes_per_frame: 4 seek_preroll: 0ms codec_delay: 0 has extra data? false encrypted? false"}
01-20 18:41:17.508 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: DURATION_SET {"duration":122.122449}
01-20 18:41:17.509 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(135)] Initialize
01-20 18:41:17.509 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(376)] InitializeAudioRenderer
01-20 18:41:17.509 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(331)] Initialize
01-20 18:41:17.510 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(579)] UpdateBufferSizes
01-20 18:41:17.511 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 30228
01-20 18:41:17.511 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1215)] OnMetadata
01-20 18:41:17.511 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1739)] SetReadyState(1)
01-20 18:41:17.512 4611 4693 V chromium: [VERBOSE1:audio_renderer_host.cc(229)] AudioRendererHost@0xd5fb25c0::OnRequestDeviceAuthorization(stream_id=2, render_frame_id=2, session_id=0, device_id=, security_origin=http://xxyzzzq.github.io)
01-20 18:41:17.513 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 32768
01-20 18:41:17.513 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 20818
01-20 18:41:17.513 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 30228 bytes
01-20 18:41:17.514 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.514 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 32768 bytes
01-20 18:41:17.515 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 131904)
01-20 18:41:17.515 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.515 4653 4726 V chromium: [VERBOSE1:audio_output_device.cc(347)] AudioOutputDevice authorized, session_id: 0, device_id: , matched_device_id:
01-20 18:41:17.515 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 20818 bytes
01-20 18:41:17.516 4653 4756 V chromium: [VERBOSE1:decoder_stream.cc(105)] Initialize<audio>
01-20 18:41:17.516 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 164672)
01-20 18:41:17.516 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.516 4653 4756 V chromium: [VERBOSE1:decoder_selector.cc(228)] DecoderInitDone: FFmpegAudioDecoder selected. DecryptingDemuxerStream not selected.
01-20 18:41:17.516 4653 4756 V chromium: [VERBOSE1:decoder_stream.cc(267)] OnDecoderSelected<audio>: FFmpegAudioDecoder
01-20 18:41:17.516 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(449)] OnAudioBufferStreamInitialized: 1
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(939)] ChangeState_Locked : 1 -> 3
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:audio_renderer_mixer_manager.cc(208)] GetMixer mixer: 0xcb0d6500 latency: 3
01-20 18:41:17.517 4653 4756 V chromium: input: format: 1 channel_layout: 3 channels: 2 sample_rate: 44100 bits_per_sample: 16 frames_per_buffer: 441 effects: 0 mic_positions:
01-20 18:41:17.517 4653 4756 V chromium: output: format: 1 channel_layout: 3 channels: 2 sample_rate: 48000 bits_per_sample: 16 frames_per_buffer: 1024 effects: 0 mic_positions:
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(404)] OnAudioRendererInitializeDone: 0
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(425)] InitializeVideoRenderer
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:decoder_stream.cc(72)] ~DecoderStream<video>
01-20 18:41:17.517 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 2748 bytes
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(453)] OnVideoRendererInitializeDone: 0
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(201)] StartPlayingFrom
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(165)] SetMediaTime(0 s)
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(314)] StartPlaying
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(939)] ChangeState_Locked : 3 -> 4
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(276)] SetPlaybackRate(0)
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(725)] SetPlaybackRate(0)
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(297)] SetVolume
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:pipeline_impl.cc(776)] kStarting -> kPlaying
01-20 18:41:17.517 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kPlaying"}
01-20 18:41:17.518 4653 4756 V chromium: [VERBOSE1:audio_discard_helper.cc(172)] Front discard of 1105 out of 1152 frames starting at 0
01-20 18:41:17.518 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.519 4611 4693 V chromium: [VERBOSE1:audio_renderer_host.cc(288)] AudioRendererHost@0xd5fb25c0::OnCreateStream(stream_id=2)
01-20 18:41:17.520 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 17862
01-20 18:41:17.520 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 31602
01-20 18:41:17.521 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.522 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.522 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(952)] SetBufferingState_Locked : 0 -> 1
01-20 18:41:17.522 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(643)] OnBufferingStateChange audio BUFFERING_HAVE_NOTHING -> BUFFERING_HAVE_ENOUGH
01-20 18:41:17.522 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(755)] StartPlayback
01-20 18:41:17.522 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(102)] StartTicking
01-20 18:41:17.523 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(163840, 185490)
01-20 18:41:17.523 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.523 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.524 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.524 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.525 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 5496
01-20 18:41:17.526 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 12366
01-20 18:41:17.526 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.527 4611 4695 V chromium: [VERBOSE1:audio_converter.cc(72)] Rebuffering from 1024 to 192
01-20 18:41:17.527 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 5496
01-20 18:41:17.527 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(163840, 188238)
01-20 18:41:17.527 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.528 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.529 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.529 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 4122 bytes
01-20 18:41:17.530 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 17862 bytes
01-20 18:41:17.532 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.532 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 32768
01-20 18:41:17.533 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 15322
01-20 18:41:17.533 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 31602 bytes
01-20 18:41:17.534 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 8244 bytes
01-20 18:41:17.535 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.536 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 10992
01-20 18:41:17.536 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 6870 bytes
01-20 18:41:17.537 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.538 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 13740
01-20 18:41:17.538 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 15114
01-20 18:41:17.539 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.539 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.540 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.540 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.541 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1251)] OnBufferingStateChange(1)
01-20 18:41:17.541 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(1739)] SetReadyState(4)
01-20 18:41:17.541 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(554)] setRate(1)
01-20 18:41:17.541 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(579)] setVolume(1)
01-20 18:41:17.541 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(297)] SetVolume
01-20 18:41:17.542 4653 4722 V chromium: [VERBOSE1:webmediaplayer_impl.cc(420)] play
01-20 18:41:17.542 4653 4756 V chromium: [VERBOSE1:pipeline_impl.cc(776)] kPlaying -> kSuspending
01-20 18:41:17.542 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kSuspending"}
01-20 18:41:17.542 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(276)] SetPlaybackRate(0)
01-20 18:41:17.542 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(725)] SetPlaybackRate(0)
01-20 18:41:17.542 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.542 4653 4756 V chromium: [VERBOSE1:renderer_impl.cc(112)] ~RendererImpl
01-20 18:41:17.542 4653 4756 V chromium: [VERBOSE1:audio_renderer_impl.cc(88)] ~AudioRendererImpl
01-20 18:41:17.542 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 9618
01-20 18:41:17.543 4653 4756 V chromium: [VERBOSE1:decoder_stream.cc(72)] ~DecoderStream<audio>
01-20 18:41:17.543 4653 4756 V chromium: [VERBOSE1:mojo_audio_decoder.cc(34)] ~MojoAudioDecoder
01-20 18:41:17.543 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(579)] UpdateBufferSizes
01-20 18:41:17.543 4653 4722 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PLAY {}
01-20 18:41:17.543 4653 4756 V chromium: [VERBOSE1:pipeline_impl.cc(776)] kSuspending -> kSuspended
01-20 18:41:17.543 4653 4756 V chromium: [VERBOSE1:render_media_log.cc(36)] MediaEvent: PIPELINE_STATE_CHANGED {"pipeline_state":"kSuspended"}
01-20 18:41:17.543 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.544 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.544 4697 4768 V chromium: [VERBOSE1:media_codec_audio_decoder.cc(34)] ~MediaCodecAudioDecoder
01-20 18:41:17.544 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 2748 bytes
01-20 18:41:17.545 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 2748 bytes
01-20 18:41:17.546 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 2748 bytes
01-20 18:41:17.547 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 5496 bytes
01-20 18:41:17.547 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 12366 bytes
01-20 18:41:17.548 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.548 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4096
01-20 18:41:17.549 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 11018
01-20 18:41:17.550 4611 4693 V chromium: [VERBOSE1:audio_sync_reader.cc(81)] Trailing glitch count on destruction: 0
01-20 18:41:17.550 4611 4693 V chromium: [VERBOSE1:audio_sync_reader.cc(116)] ASR: number of detected audio glitches: 0 out of 14
01-20 18:41:17.550 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 32768
01-20 18:41:17.550 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 9826
01-20 18:41:17.550 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 1374 bytes
01-20 18:41:17.551 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 5496 bytes
01-20 18:41:17.552 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 2748 bytes
01-20 18:41:17.552 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.552 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 6870 bytes
01-20 18:41:17.553 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.553 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 9618
01-20 18:41:17.554 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.554 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.554 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.554 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(163840, 192360)
01-20 18:41:17.554 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.555 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 210222)
01-20 18:41:17.555 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.555 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 1374 bytes
01-20 18:41:17.556 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.556 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.557 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.558 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 24732
01-20 18:41:17.558 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.558 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 32768 bytes
01-20 18:41:17.559 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 15322 bytes
01-20 18:41:17.560 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(0, 241824)
01-20 18:41:17.560 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.560 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(229376, 250068)
01-20 18:41:17.560 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.561 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.561 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 16488
01-20 18:41:17.562 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.562 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.562 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.563 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 10992
01-20 18:41:17.563 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.563 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.564 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.564 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.564 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.565 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.565 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.566 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.566 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.567 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.567 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.567 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 2748 bytes
01-20 18:41:17.568 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 10992 bytes
01-20 18:41:17.568 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.569 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 12366
01-20 18:41:17.569 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(522)] ProgressCallback(229376, 256938)
01-20 18:41:17.569 4653 4722 V chromium: [VERBOSE1:multibuffer_data_source.cc(543)] UpdateLoadingState_Locked
01-20 18:41:17.569 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 4122 bytes
01-20 18:41:17.570 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 13740 bytes
01-20 18:41:17.571 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 5496
01-20 18:41:17.571 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 19236
01-20 18:41:17.572 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 19236
01-20 18:41:17.572 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4122
01-20 18:41:17.572 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 15114 bytes
01-20 18:41:17.573 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 6870
01-20 18:41:17.573 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 12366
01-20 18:41:17.574 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.574 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 6870 bytes
01-20 18:41:17.575 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.575 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 8244
01-20 18:41:17.575 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.576 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 6870 bytes
01-20 18:41:17.576 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.576 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 5496
01-20 18:41:17.577 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 9618
01-20 18:41:17.577 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 1374
01-20 18:41:17.578 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 1374 bytes
01-20 18:41:17.578 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 2748
01-20 18:41:17.578 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 12366
01-20 18:41:17.579 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4096
01-20 18:41:17.579 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 1374 bytes
01-20 18:41:17.580 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4096
01-20 18:41:17.581 4653 4722 V chromium: [VERBOSE1:resource_multibuffer_data_provider.cc(372)] didReceiveData: 1374 bytes
01-20 18:41:17.582 4611 4693 V chromium: [VERBOSE1:resource_loader.cc(361)] OnReadCompleted: "http://xxyzzzq.github.io/sandbox/media-session/media/buddy.mp3" bytes_read = 4096
01-20 18:41:17.609 4653 4722 F chromium: [FATAL:webmediaplayer_impl.cc(2154)] Check failed: !IsHidden().
01-20 18:41:17.609 4653 4722 F chromium: #00 0xd5d6436d /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x0009d36d
01-20 18:41:17.609 4653 4722 F chromium: #01 0xd2e04001 /data/data/org.chromium.chrome/incremental-install-files/lib/libmedia_blink.cr.so+0x00037001
01-20 18:41:17.609 4653 4722 F chromium: #02 0xd2dffe0d /data/data/org.chromium.chrome/incremental-install-files/lib/libmedia_blink.cr.so+0x00032e0d
01-20 18:41:17.609 4653 4722 F chromium: #03 0xd32e9c87 /data/data/org.chromium.chrome/incremental-install-files/lib/libmedia.cr.so+0x000afc87
01-20 18:41:17.609 4653 4722 F chromium: #04 0xd32e9935 /data/data/org.chromium.chrome/incremental-install-files/lib/libmedia.cr.so+0x000af935
01-20 18:41:17.609 4653 4722 F chromium: #05 0xd32e9f5f /data/data/org.chromium.chrome/incremental-install-files/lib/libmedia.cr.so+0x000aff5f
01-20 18:41:17.609 4653 4722 F chromium: #06 0xd33822b9 /data/data/org.chromium.chrome/incremental-install-files/lib/libmedia.cr.so+0x001482b9
01-20 18:41:17.609 4653 4722 F chromium: #07 0xd5d50ef7 /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x00089ef7
01-20 18:41:17.609 4653 4722 F chromium: #08 0xd5d50ddf /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x00089ddf
01-20 18:41:17.609 4653 4722 F chromium: #09 0xd2aeb931 /data/data/org.chromium.chrome/incremental-install-files/lib/libblink_platform.cr.so+0x00206931
01-20 18:41:17.609 4653 4722 F chromium: #10 0xd2aea8db /data/data/org.chromium.chrome/incremental-install-files/lib/libblink_platform.cr.so+0x002058db
01-20 18:41:17.609 4653 4722 F chromium: #11 0xd2aecba9 /data/data/org.chromium.chrome/incremental-install-files/lib/libblink_platform.cr.so+0x00207ba9
01-20 18:41:17.609 4653 4722 F chromium: #12 0xd2aecb75 /data/data/org.chromium.chrome/incremental-install-files/lib/libblink_platform.cr.so+0x00207b75
01-20 18:41:17.609 4653 4722 F chromium: #13 0xd5d50ef7 /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x00089ef7
01-20 18:41:17.609 4653 4722 F chromium: #14 0xd5d50ddf /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x00089ddf
01-20 18:41:17.609 4653 4722 F chromium: #15 0xd5d6ba37 /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x000a4a37
01-20 18:41:17.609 4653 4722 F chromium: #16 0xd5d6bc8d /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x000a4c8d
01-20 18:41:17.609 4653 4722 F chromium: #17 0xd5d6be71 /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x000a4e71
01-20 18:41:17.609 4653 4722 F chromium: #18 0xd5d6d9e9 /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x000a69e9
01-20 18:41:17.609 4653 4722 F chromium: #19 0xd5d6b86b /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x000a486b
01-20 18:41:17.609 4653 4722 F chromium: #20 0xd5d86a99 /data/data/org.chromium.chrome/incremental-install-files/lib/libbase.cr.so+0x000bfa99
01-20 18:41:17.609 4653 4722 F chromium: #21 0xcfdb058b /data/data/org.chromium.chrome/incremental-install-files/lib/libcontent.cr.so+0x00adc58b
01-20 18:41:17.609 4653 4722 F chromium: #22 0xcfe51611 /data/data/org.chromium.chrome/incremental-install-files/lib/libcontent.cr.so+0x00b7d611
01-20 18:41:17.609 4653 4722 F chromium: #23 0xcfe507d5 /data/data/org.chromium.chrome/incremental-install-files/lib/libcontent.cr.so+0x00b7c7d5
01-20 18:41:17.609 4653 4722 F chromium: #24 0xd7fdd965 /data/data/org.chromium.chrome/incremental-install-files/optimized-dexes/content.public.android.content_java.dex.dex+0x0018e965
01-20 18:41:17.609 4653 4722 F chromium:
01-20 18:41:18.664 4611 4693 V chromium: [VERBOSE1:node_controller.cc(585)] Dropped peer B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.664 4611 4693 V chromium: [VERBOSE1:node.cc(409)] Observing lost connection from node 64CBEDBB9E969C37.8B4632C26BA0AB56 to node B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.665 4611 4693 V chromium: [VERBOSE1:node_controller.cc(714)] Dropping message for unknown peer: B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.671 4697 4731 V chromium: [VERBOSE1:node_controller.cc(585)] Dropped peer B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.671 4697 4731 V chromium: [VERBOSE1:node.cc(409)] Observing lost connection from node 79345D8EF721A1F8.D6B98BB2AE700C38 to node B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.672 4697 4731 V chromium: [VERBOSE1:ipc_sync_channel.cc(470)] Canceling pending sends
01-20 18:41:18.675 4611 4611 V chromium: [VERBOSE1:media_interface_proxy.cc(45)] ~MediaInterfaceProxy
01-20 18:41:18.678 4697 4768 V chromium: [VERBOSE1:interface_factory_impl.cc(55)] ~InterfaceFactoryImpl
01-20 18:41:18.691 4611 4693 V chromium: [VERBOSE1:media_stream_dispatcher_host.cc(100)] MediaStreamDispatcherHost::OnChannelClosing
01-20 18:41:18.700 4697 4724 V chromium: [VERBOSE1:ipc_sync_channel.cc(470)] Canceling pending sends
01-20 18:41:18.700 4697 4731 V chromium: [VERBOSE1:ipc_sync_channel.cc(470)] Canceling pending sends
01-20 18:41:18.702 4697 4731 V chromium: [VERBOSE1:node.cc(409)] Observing lost connection from node 79345D8EF721A1F8.D6B98BB2AE700C38 to node B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.702 4697 4731 V chromium: [VERBOSE1:node_controller.cc(1194)] Could not be introduced to peer B78FFC3DB8997673.6D6DD9A925E733C4
01-20 18:41:18.728 4611 4611 V chromium: [VERBOSE1:subprocess_metrics_provider.cc(99)] Reported 201 histograms from subprocess #3
01-20 18:41:18.738 4611 4693 V chromium: [VERBOSE1:node.cc(388)] Merging local ports 2DD0CB667DF2D95F.FD2ABEB83284E09D@64CBEDBB9E969C37.8B4632C26BA0AB56 and 3ABB13ADFD698872.6C45CB97A36A708D@64CBEDBB9E969C37.8B4632C26BA0AB56
01-20 18:41:26.244 4611 4712 E chromium: [ERROR:shell_integration_android.cc(27)] Not implemented reached in shell_integration::DefaultWebClientState shell_integration::GetDefaultBrowser()
01-20 18:41:30.312 4611 4693 V chromium: [VERBOSE1:quic_connection.cc(2080)] Client: last packet 5363720710 now:5392729841 idle_duration:29009131 idle_network_timeout: 29000000
01-20 18:41:30.313 4611 4693 V chromium: [VERBOSE1:quic_connection.cc(2088)] Client: No recent network activity.
01-20 18:41:30.313 4611 4693 V chromium: [VERBOSE1:quic_connection.cc(1929)] Client: Closing connection: 12919592114203268114, with error: QUIC_NETWORK_IDLE_TIMEOUT (25), and details: No recent network activity.
01-20 18:41:30.314 4611 4693 V chromium: [VERBOSE1:quic_connection.cc(1991)] Cancelling all QuicConnection alarms.
,
Jan 20 2017
The following revision refers to this bug: https://chromium.googlesource.com/chromium/src.git/+/fd01a87860fe7f105d25135a9ca0f8c3ecd9bdda commit fd01a87860fe7f105d25135a9ca0f8c3ecd9bdda Author: avayvod <avayvod@chromium.org> Date: Fri Jan 20 19:29:36 2017 [Video] Remove wrong DCHECK from EnableVideoTrackIfNeeded The DCHECK doesn't make sense as we might reenable the track during suspend/resume while in the background. BUG= 683163 TEST=none Review-Url: https://codereview.chromium.org/2641373002 Cr-Commit-Position: refs/heads/master@{#445128} [modify] https://crrev.com/fd01a87860fe7f105d25135a9ca0f8c3ecd9bdda/media/blink/webmediaplayer_impl.cc
,
Jan 20 2017
Potentially needs a merge to 57 (if some debug bots playing media in the background will break), however doesn't affect the release builds so not a blocker.
,
Jan 21 2017
Your change meets the bar and is auto-approved for M57. Please go ahead and merge the CL to branch 2987 manually. Please contact milestone owner if you have questions. Owners: amineer@(clank), cmasso@(bling), ketakid@(cros), govind@(desktop) For more details visit https://www.chromium.org/issue-tracking/autotriage - Your friendly Sheriffbot
,
Jan 24 2017
Committed patchset #1 (id:1) manually as 6f71fe8fcc06145939bcdf31d7ac2e9140fffa3f. Description: [Video] Remove wrong DCHECK from EnableVideoTrackIfNeeded The DCHECK doesn't make sense as we might reenable the track during suspend/resume while in the background. BUG= 683163 TEST=none Review-Url: https://codereview.chromium.org/2641373002 Cr-Commit-Position: refs/heads/master@{#445128} (cherry picked from commit fd01a87860fe7f105d25135a9ca0f8c3ecd9bdda) Review-Url: https://codereview.chromium.org/2651533007 . Cr-Commit-Position: refs/branch-heads/2987@{#58} Cr-Branched-From: ad51088c0e8776e8dcd963dbe752c4035ba6dab6-refs/heads/master@{#444943} Committed: https://chromium.googlesource.com/chromium/src/+/6f71fe8fcc06145939bcdf31d7ac2e9140fffa3f Affected files (+0, -2 lines): M media/blink/webmediaplayer_impl.cc |
||||
►
Sign in to add a comment |
||||
Comment 1 by avayvod@chromium.org
, Jan 20 2017