1146251 - [Midori 2.0][Crash][Video streaming]MS crashed when play RTSP video

Status: RESOLVED DUPLICATE of bug 1088538

(Firefox OS Graveyard :: Gaia::Browser, defect, P1)

Description

[sync-1]

FFOS2.0 baseline BuildID: 20140916000205
 
 DEFECT DESCRIPTION:
 MS crashed when play RTSP video
 
  REPRODUCING PROCEDURES:
 1.Open broswer and go to :http://60.12.220.48/stream.html;
 2.RTSP test->rtsp test for interaction->select 'long1.mp4/long2.mp4' to play;
 3.After several minutes,ms crashed and restart.->KO
 
 
  EXPECTED BEHAVIOUR:
 MS can play this video normally without crash.
 
  ASSOCIATE SPECIFICATION:
 
  TEST PLAN REFERENCE:
 
  TOOLS AND PLATFORMS USED:
 
  USER IMPACT:
 
  REPRODUCING RATE:5/5
 
  For FT PR, Please list reference mobile's behavior:

Comment 1

[sync-1]

Attachment: log

Comment 2

[sync-1]

Attachment: Crash report stack

Comment 3

[Gregor Wagner [:gwagner]]

Ben, any ideas?

Comment 4

[Ethan Tseng [:ethan]]

(In reply to sync-1 from comment #0)
> FFOS2.0 baseline BuildID: 20140916000205

This build is pretty old.
We resolved several critical crash issues of RTSP streaming in the past few months. Perhaps the issue you encountered is already fixed. Could you try to reproduce this bug using the latest build?

Comment 5

[Benjamin Chen [:bechen]]

See bug 1088538, looks like the same crash.

Comment 6

[ChengLin.Wu]

(In reply to Benjamin Chen [:bechen] from comment #5)
> See bug 1088538, looks like the same crash.

It's a good news.

There is a related log:
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:1
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:0, isAudio:1, info->mNPTMappingValid:1
I/Gecko   ( 1689): <TZM_LOG> -*- JrdExtension -*-: getSysInfo: "BATTERY_TEMP"
I/Gecko   ( 1689): <TZM_LOG> -*- jrd_service.jsm -*-: receiveMessage: JrdSrv:Common
I/Gecko   ( 1689): <TZM_LOG> -*- jrd_service.jsm -*-: _readSysFile entry.
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
I/Gecko   ( 1689): <TZM_LOG> -*- jrd_service.jsm -*-: _readSysFile:success
I/Gecko   ( 1689): <TZM_LOG> -*- JrdExtension -*-: receiveMessage: {"target":{},"name":"JrdSrv:Common:Return","sync":false,"json":{"data":{"result":"OK","data":"252\n"},"requestID":"id{e52b292e-7730-4bd8-8795-ba06c8d817c4}"},"data":{"data":{"result":"OK","data":"252\n"},"requestID":"id{e52b292e-7730-4bd8-8795-ba06c8d817c4}"},"objects":{}}
I/Gecko   ( 1689): <TZM_LOG> -*- JrdExtension -*-: Success: {"data":{"result":"OK","data":"252\n"},"requestID":"id{e52b292e-7730-4bd8-8795-ba06c8d817c4}"}
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
I/GeckoDump( 1689): battery temperatur 25.2 level 2
I/Gecko   ( 1689): <TZM_LOG> -*- JrdExtension -*-: receiveMessage: {"target":{},"name":"JrdSrv:Common:Return","sync":false,"json":{"data":{"result":"OK","data":"252\n"},"requestID":"id{e52b292e-7730-4bd8-8795-ba06c8d817c4}"},"data":{"data":{"result":"OK","data":"252\n"},"requestID":"id{e52b292e-7730-4bd8-8795-ba06c8d817c4}"},"objects":{}}
I/Gecko   ( 1689): <TZM_LOG> -*- JrdExtension -*-: Success: Can not find the request object with id{e52b292e-7730-4bd8-8795-ba06c8d817c4}
I/Gecko   ( 1689): -------------- WifiCommand: wifi gecko: terminate = falseSIGNAL_POLL
D/wpa_supplicant( 1854): RX ctrl_iface - hexdump(len=11): 53 49 47 4e 41 4c 5f 50 4f 4c 4c
D/wpa_supplicant( 1854): wlan0: Control interface command 'SIGNAL_POLL'
D/wpa_supplicant( 1854): nl80211: survey data missing!
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
E/        ( 1700): not in avi mode
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:403) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): dropping damaged access unit.
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:440) void android::RTSPSource::onMessageReceived(const android::sp<android::AMessage>&): 
I/Module  ( 1689): RtspConnectionHandler::kWhatAccessUnit: trackIndex:2
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:667) void android::RTSPSource::onTrackDataAvailable(size_t): 
I/Module  ( 1689): RTSPSource::onTrackDataAvailable(size_t trackIndex) err:-1011, isAudio:0, info->mNPTMappingValid:1
I/Gecko   ( 1689): [Parent 1689] ###!!! ABORT: file ../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp, line 689
E/Gecko   ( 1689): mozalloc_abort: [Parent 1689] ###!!! ABORT: file ../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp, line 689
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
V/audio_hw_primary( 1700): out_write: writing buffer (3840 bytes) to pcm device
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()
I/Module  ( 1689): :--(../../../../../../../../../gecko/netwerk/protocol/rtsp/rtsp/RTSPSource.cpp:106) void android::RTSPSource::play(): 
I/Module  ( 1689): RTSPSource::play()

Comment 7

[Jonathan Hao (inactive) [:jhao]]

(In reply to ChengLin.Wu from comment #6)

A small suggestion:
Logs containing this much text should be added as attachments, rather than being put in comments.

Comment 8

[ChengLin.Wu]

(In reply to Jonathan Hao [:jhao] from comment #7)
> (In reply to ChengLin.Wu from comment #6)
> 
> A small suggestion:
> Logs containing this much text should be added as attachments, rather than
> being put in comments.

OK, it's my mistake!

Comment 9

[Naoki Hirata :nhirata (please use needinfo instead of cc)]

Can we close this out and then mark bug 1088538 blocking midori?

Comment 10

[Ethan Tseng [:ethan]]

(In reply to Naoki Hirata :nhirata (please use needinfo instead of cc) from comment #9)
> Can we close this out and then mark bug 1088538 blocking midori?
Sure. But before we do that, I would like to make sure two things:
1. The root cause of this bug is exactly the same as bug 1088538.
2. Is there any new issue raised from this bug that we have not resolved yet?

I indicate point 2. because I found something unusual while reproducing this bug:
The playback would suddenly jump to the end-of-stream by playing the long-duration clip specified in the STR in comment 0.

Please give us several days to clarify these two issues.

Comment 11

[Ethan Tseng [:ethan]]

Meanwhile, could reporter provide feedback to comment 4?

Comment 12

[Ethan Tseng [:ethan]]

I can reproduce this bug on the latest build of mozilla-b2g32_v2_0.
Hence we could ignore my question in comment 4.

Comment 13

[Ethan Tseng [:ethan]]

Attachment: gdb_backtrace.log

The GDB backtrace log while reproducing this bug on the latest 2.0 build.

Comment 14

[Ethan Tseng [:ethan]]

The patch of bug 1088538 could avoid the crash problem in this bug.

However, even if the crash is avoided, we still cannot play the whole video clip of long1.mp4. The playback would jump to end-of-stream at around 35 seconds from the beginning (which is exactly the crash point). And we don't know the root cause yet. More investigation is needed. But I'll request to uplift the patch of bug 1088538 to v2.0 first.

Comment 15

[Beatriz Rodríguez [:brg]]

The patch from bug 1088538 had just landed in v2.0.
https://bugzilla.mozilla.org/show_bug.cgi?id=1088538#c22

Ethan, Could you please update your testing result? Thanks!

Comment 16

[Ethan Tseng [:ethan]]

(In reply to Beatriz Rodríguez [:brg] from comment #15)
> The patch from bug 1088538 had just landed in v2.0.
> https://bugzilla.mozilla.org/show_bug.cgi?id=1088538#c22
> 
> Ethan, Could you please update your testing result? Thanks!

Hi Beatriz,

I verified with the latest build of 2.0. The result is the same as I said in comment 14:
It does not crash anymore, but the playback jumps to the end after playing 34~35 seconds.

Since this bug was originally reported as crash problem, I suggest we set this bug as resolved fixed by bug 1088538, and file another bug to track the playback issue. By this way we can avoid confusion and also have record for the new issue.

What do you think?

Comment 17

[Beatriz Rodríguez [:brg]]

(In reply to Ethan Tseng [:ethan] from comment #16)
> 
> What do you think?
Agree! Thanks!

Comment 18

[Ethan Tseng [:ethan]]

This crash issue is caused by the same root cause that we fixed in bug 1088538.
Thus set it as duplicate of bug 1088538.

Comment 19

[Ethan Tseng [:ethan]]

Bug 1151760 was filed for the record of the issue stated in comment 14.