Crash on become active - iOS5 sound related?

cocos2d for iPhone » Programming » CocosDenshion (sound engine)

Crash on become active - iOS5 sound related?

(36 posts) (8 voices)

Started 7 months ago by SpudGT
Latest reply from Steve Oldmeadow

Tags:

SpudGT
Member

In the middle of making my iPad application universal, ran into a crash on my iPhone 4 running iOS 5 (cocos2d 1.0.1). Not seeing the same problem in my iPad2 running iOS 5.

General sequence of events:
- Start application, loads scene with background music playing
- put app to background, wait over a minute
- resume the app, app seems hung, then crashes (is terminated) about 10 seconds later

It's not 100% repeatable, but happens a lot.

I set the following:
[[CDAudioManager sharedManager] setResignBehavior:kAMRBStopPlay autoHandle:YES];

On resign active I see (note I added a bit of debug output):

Oct 17 14:29:41 unknown MyApp[6397] <Warning>: cocos2d: Frame interval: 15
Oct 17 14:29:41 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - resign active called
Oct 17 14:29:41 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - Attempt to set Audio session category AVAudioSessionCategoryAmbient
Oct 17 14:29:41 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - Audio session set category AVAudioSessionCategoryAmbient succeeded
Oct 17 14:29:41 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - audio source was playing <CDLongAudioSource: 0x3411b0>
Oct 17 14:29:41 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - handled resign active

Then on become active:

Oct 17 14:32:38 unknown MyApp[6397] <Warning>: cocos2d: Frame interval: 15
Oct 17 14:32:38 unknown MyApp[6397] <Warning>: cocos2d: Frame interval: 1
Oct 17 14:32:38 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - become active called
Oct 17 14:32:38 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - Other audio is not playing audio will be exclusive
Oct 17 14:32:38 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - Attempt to set Audio session category AVAudioSessionCategorySoloAmbient
Oct 17 14:32:47 unknown MyApp[6397] <Error>: 14:32:47.932 <0x3f807ce8> AudioSessionSetProperty posting message to kill mediaserverd (6317)
Oct 17 14:32:47 unknown mediaserverd[6317] <Error>: <<<< FIGSERVER >>>> FigPlayerRemoteServer_KillAndForceCrashReport: RPCTimeout message received to terminate [6317] with reason 'AudioSessionSetProperty'
Oct 17 14:32:48 unknown ReportCrash[6400] <Error>: Saved crashreport to /Library/Logs/CrashReporter/mediaserverd-2011-10-17-143248.plist using uid: 0 gid: 0, synthetic_euid: 0 egid: 0
Oct 17 14:32:48 unknown mediaserverd[6317] <Error>: <<<< FIGSERVER >>>> FigPlayerRemoteServer_KillAndForceCrashReport: RPCTimeout message received; stackshot generated
Oct 17 14:32:48 unknown mediaserverd[6317] <Error>: <<<< FIGSERVER >>>> FigPlayerRemoteServer_KillAndForceCrashReport: TERMINATING our process [6317]
Oct 17 14:32:48 unknown com.apple.launchd[1] <Notice>: (com.apple.mediaserverd) Exited: Killed: 9
Oct 17 14:32:48 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - Audio session set category AVAudioSessionCategorySoloAmbient succeeded
Oct 17 14:32:48 unknown MyApp[6397] <Warning>: Denshion::CDAudioManager - audio source was system paused <CDLongAudioSource: 0x3411b0>
Oct 17 14:32:48 unknown kernel[0] <Debug>: launchd[6401] Builtin profile: mediaserverd (sandbox)
Oct 17 14:32:49 unknown mediaserverd[6401] <Error>: 14:32:49.368387 com.apple.AVConference: /SourceCache/GameKitServices/GameKitServices-344.3/AVConference.subproj/Sources/AVConferenceServer.m:1867: AVConferenceServerStart
Oct 17 14:32:49 unknown SpringBoard[15] <Warning>: com.domain.MyApp failed to resume in time
Oct 17 14:32:49 unknown SpringBoard[15] <Warning>: Forcing crash report of MyApp[6397]...
Oct 17 14:32:49 unknown SpringBoard[15] <Warning>: Finished crash reporting.
Oct 17 14:32:49 unknown com.apple.launchd[1] <Notice>: (UIKitApplication:com.domain.MyApp[0x678d]) Exited: Killed: 9
Oct 17 14:32:49 unknown SpringBoard[15] <Warning>: Application 'My App' exited abnormally with signal 9: Killed: 9

Looks like I am getting stuck in audioSessionSetCategory ...

Anyone have tips on where to start looking (or what additional information would be useful)?

Posted 7 months ago #

SpudGT
Member

I was reading through some of the apple docs on the AVAudioSession:

AVAudioSessionCategorySoloAmbient
The default category; used unless you set a category with the setCategory:error: method.
When you use this category, audio from other apps is silenced. Your audio is silenced by screen locking and by the Silent switch (called the Ring/Silent switch on iPhone).

So I commented this out:
//[[CDAudioManager sharedManager] setResignBehavior:kAMRBStopPlay autoHandle:YES];

So at this point my resign behavior seems to be correct ... but no more crashing.

Posted 7 months ago #

donutzeatpeople
Member

@SpudGT so having commented out this line, your background music is paused and resumed correctly when the application is resigned and resumed? Have you noticed any issues?

The reason I ask is that the comment above the setResignBehavior:autoHandle: line in GameSoundManager (which I suppose you might be using, too?) reads:

//If you are using background music you probably want to do this. Basically it makes sure your background music
//is paused and resumed properly when the application is resigned and resumed. Without it you will find that
//music you had paused will restart even if you don't want it to or your music will start playing sooner than
//you want.
[audioManager setResignBehavior:kAMRBStopPlay autoHandle:YES];

Posted 6 months ago #

donutzeatpeople
Member

Also, should this thread be moved to the CocosDenshion section of the forum? Or maybe not, since GameSoundManager is an abstraction of CocosDenshion?

Posted 6 months ago #
SpudGT
Member

I have been running with that line commented out on iOS versions from 4.3.5 to 5.0.1 ... no funky audio issues noted.

Never had a chance to get at the root cause of the crashing on iOS 5. Has anyone else run into this crash?

Posted 6 months ago #
Eezlebrot
Member

Spud you're not alone - I have the same problem with a currently available game in the app store. In iOS4 (all versions) I have no problems, but in iOS5 there is an occasional crash (timeout) on resume. It is only noticed when the app is exited on the main menu screen (the only time I have background music playing). I've briefly looked at the crash logs and was trying to narrow my focus when I came across this thread. I plan on spending a little more time looking into it and trying your fix.

I don't really know what I'm doing in the audio arena all that well - I came across another thread recommending recoding the original mp3 into a caf format (http://www.cocos2d-iphone.org/forum/topic/21625). This did resolve the issue, but the file size is way too large and I'm not certain that this is actually a 'fix' or it's just masking something else. There are allusions to potential memory corruptions in that thread, so I'm also going to go down that rabbit hole. I'll post something if I find anything of value.

Posted 6 months ago #
jcljk
Member

hi @SpudGT, @Eezlebrot,

I also have the same issue, has any one resolve this yet ?

Posted 5 months ago #
ShaneT
Member

Hi,

If you add an exception breakpoint all my apps crash everytime a new sound is loaded or played. It isn't causing any crashes if the exception isn't enabled though.

Think it must be a bug in SimpleAudioEngine.

You should enable this to find out where exactly the exception is happening.

You just click the breakpoint tab, and then click the + bottom left.

Very useful for those that didn't know that.

cheers

Shane

Posted 5 months ago #
ShaneT
Member

I just used instruments to test my app for leaks, and a leak was detected in SimpleAudioEngine and then crashed my app as well.

Posted 5 months ago #
Eezlebrot
Member

Here's my crappy work around, and it's working for me so far - I specifically set autoHandle to NO:

[[CDAudioManager sharedManager] setResignBehavior:kAMRBStopPlay autoHandle:NO];

Then I used a 1 second timer within my applicationDidBecomeActive to call CDAudioManager's applicationDidBecomeActive. Conceptually this will allow the application to return from this function so the watchdog doesn't kick us out. This is admittedly a crappy work around, I don't like it, but it's the only luck I've had so far. Keep in mind you'll also need to explicitly call CDAudioManager's applicationWillResignActive from your applicationWillResignActive.

Good luck and let me know if you find a non-crappy workaround/fix.

Posted 5 months ago #
ShaneT
Member

So this is a known issue then.

Does anyone else have a better way of fixing this issue?

Posted 5 months ago #
Steve Oldmeadow
Administrator

The issue appears to only effect a small minority of people. I can't reproduce it. I discussed the issue with SpudGT via private messages and the issue appears to be with another process running on the device. It is OS version specific, it could be an issue that is fixed in 5.1.

Posted 5 months ago #
PDiTO
Member

Hi everyone, i'm also experiencing this issue too. I'm just to put 5.1 on my device shortly, will update as to whether it improves things.

Posted 5 months ago #
PDiTO
Member

No improvement in iOS 5.1. Issue still exactly the same.

Posted 5 months ago #
Steve Oldmeadow
Administrator

@PDiTO - how about some useful information like crash logs and what device? The original poster experienced the issue on an iPhone 4 but not an iPad 2, is that the same for you? Also, can you reproduce the issue with the FadeToGrey demo?

Posted 5 months ago #
ShaneT
Member

Yes still there on my new 5.0.1 device as well.

It is brand new out of the box and still creates an exception here on start up.

Maybe it has something to do with our actual media if it only affects a small minority of people.

// Set the desired client (output) data format
status = ExtAudioFileSetProperty(extRef, kExtAudioFileProperty_ClientDataFormat, sizeof(theOutputFormat), &theOutputFormat);
if (status != noErr)

Posted 5 months ago #
PDiTO
Member

Hi Steve, apologies for lack of info. It was a very useless post, my feeble excuse, it was early!

I'm also experiencing the issue on an iPhone 4. Unfortunately I have no other device to test it on.

Running the app through Xcode, I notice the following in the console on become active (Note: this appears only when the app becoming active hangs, it doesn't appear when the app becoming active correctly resumes with no hang - it seems totally random as to when it hangs and when it doesn't)

16:42:04.676 <0x3ee7ecb8> AudioSessionSetProperty posting message to kill mediaserverd (146)
16:42:05.964 <0x3ee7ecb8> AQClientMgr: error messaging the server; reconnecting.

Posted 5 months ago #

SpudGT
Member

Steve,

I did some testing with the FadeToGrey demo on my iPhone 4 (iOS 5.0.1 and Cocos2d 1.0.1) and did not see the crash.

I did notice a situation where the background music would stop playing until a 'Reset' is done in FadeToGrey. Here is what I did:

1. Launch 'Where's My Water' (WMW)
2. Launch FadeToGrey, start background music
3. Double click home button, choose WMW from multitasking bar
4. Let MWM run for a a few seconds (it's background music starts)
5. Double click home button, choose FadeToGrey from multitasking bar
6. FadeToGrey background music is gone, pressing 'Background Music' will not restart it

Console output when pressing repeatedly pressing 'Background Music' while in this state:

2011-12-05 09:58:10.861 CocosDenshion - Fade To Grey[9861:707] cocos2d: deallocing <CCScaleTo = 003660E0 | Tag = -1061138430>
2011-12-05 09:58:10.863 CocosDenshion - Fade To Grey[9861:707] >>Test five
2011-12-05 09:58:10.868 CocosDenshion - Fade To Grey[9861:707] >> Background music is not playing
2011-12-05 09:58:10.872 CocosDenshion - Fade To Grey[9861:707] cocos2d: deallocing <CCScaleTo = 0036E380 | Tag = -1061138430>
2011-12-05 09:58:12.271 CocosDenshion - Fade To Grey[9861:707] cocos2d: deallocing <CCScaleTo = 003660E0 | Tag = -1061138430>
2011-12-05 09:58:12.277 CocosDenshion - Fade To Grey[9861:707] >>Test five
2011-12-05 09:58:12.281 CocosDenshion - Fade To Grey[9861:707] >> Background music is not playing
2011-12-05 09:58:12.285 CocosDenshion - Fade To Grey[9861:707] cocos2d: deallocing <CCScaleTo = 0036E380 | Tag = -1061138430>

Burt

Posted 5 months ago #

PDiTO
Member

If anyone has any ideas for a fix, even if temp, please let me know. App is due for release 8th and all the marketing has been done :/ Sure I could get Apple to do a fast review if I can correct it!!!

Here is console from a crash:-

Dec 5 17:00:26 unknown MyApp[99] <Error>: 17:00:26.480 <0x3eb8fcb8> AudioSessionSetProperty posting message to kill mediaserverd (32)
Dec 5 17:00:26 unknown mediaserverd[32] <Error>: <<<< FIGSERVER >>>> FigPlayerRemoteServer_KillAndForceCrashReport: RPCTimeout message received to terminate [32] with reason 'AudioSessionSetProperty'
Dec 5 17:00:26 unknown ReportCrash[115] <Error>: libMobileGestalt computeUniqueDeviceID: total time for bb to return imei: 0
Dec 5 17:00:26 unknown ReportCrash[115] <Notice>: Saved crashreport to /Library/Logs/CrashReporter/mediaserverd-2011-12-05-170026.plist using uid: 0 gid: 0, synthetic_euid: 0 egid: 0
Dec 5 17:00:26 unknown mediaserverd[32] <Error>: <<<< FIGSERVER >>>> FigPlayerRemoteServer_KillAndForceCrashReport: RPCTimeout message received; stackshot generated
Dec 5 17:00:26 unknown mediaserverd[32] <Error>: <<<< FIGSERVER >>>> FigPlayerRemoteServer_KillAndForceCrashReport: TERMINATING our process [32]
Dec 5 17:00:26 unknown com.apple.launchd[1] <Notice>: (com.apple.mediaserverd) Exited: Killed: 9
Dec 5 17:00:26 unknown MyApp[99] <Error>: [17:00:26.772] <<<< Remote >>>> FigServer_KillAndForceCrashReport: posted message to kill media server (32), err = 0 (0x0)
Dec 5 17:00:26 unknown mediaserverd[116] <Error>: /SourceCache/EmbeddedCoreMediaFramework/EmbeddedCoreMedia-839.35.1/BuildSystem/XcodeProjects/CoreMedia/../../../Sources/Utilities/FigNote.c FigNote_OneTimeInitializationForMediaServerd: Set up SIGUSR1 handler on queue 0x119df0
Dec 5 17:00:27 unknown com.apple.mediaserverd[116] <Notice>: 2011-12-05 05:00:26.997902 PM [AirTunes] HAL plugin started
Dec 5 17:00:27 unknown kernel[0] <Debug>: launchd[116] Builtin profile: mediaserverd (sandbox)
Dec 5 17:00:27 unknown mediaserverd[116] <Error>: <<<< FIGSERVER >>>> FigMovieStartServing: mediaserverd is now completely initialized
Dec 5 17:00:27 unknown MyApp[99] <Notice>: 17:00:27.898 <0x3eb8fcb8> AQClientMgr: error messaging the server; reconnecting.
Dec 5 17:00:27 unknown mediaserverd[116] <Error>: [17:00:27.931] <___ RecorderServer ___> recorderPreWarmServer_registerForApplicationStateChangeNotification: Registering for application state change callbacks with springboard
Dec 5 17:00:28 unknown mediaserverd[116] <Error>: 17:00:28.015 <AudioQueueServer> AudioSessionSetClientPlayState(99): cannot get ClientInfo
Dec 5 17:00:28 unknown mediaserverd[116] <Error>: 17:00:28.017 <AudioQueueServer> AudioQueue: Error 'ini?' from AudioSessionSetClientPlayState(99)
Dec 5 17:00:28 unknown SpringBoard[52] <Warning>: MyApp failed to resume in time
Dec 5 17:00:28 unknown SpringBoard[52] <Warning>: Forcing crash report of MyApp[99]...
Dec 5 17:00:28 unknown mediaserverd[116] <Error>: 17:00:28.076358 com.apple.AVConference: /SourceCache/GameKitServices/GameKitServices-344.11/AVConference.subproj/Sources/AVConferenceServer.m:1867: AVConferenceServerStart
Dec 5 17:00:28 unknown SpringBoard[52] <Warning>: Finished crash reporting.
Dec 5 17:00:28 unknown ReportCrash[115] <Error>: libMobileGestalt computeUniqueDeviceID: total time for bb to return imei: 0
Dec 5 17:00:28 unknown com.apple.launchd[1] <Notice>: (UIKitApplication:myAppHere[0xeb2b]) Exited: Killed: 9
Dec 5 17:00:28 unknown SpringBoard[52] <Warning>: Application 'myAppHere' exited abnormally with signal 9: Killed: 9

Posted 5 months ago #
Eezlebrot
Member

I will try to remember to post my crash logs later, I'll also try against an iPad2. I have seen the failure running iOS5 on both an iPhone4 and iPhone4S. I have seen timeouts reported on resume with other parts of the application other than audio manager - namely iAd services. I have not had any timeout issues since setting a 1 second delay timer on resume of the application and resume of the audiomanager.

Posted 5 months ago #
ShaneT
Member

Does your app actually crash, or is it just an exception that is raised?

My apps do not crash, there is just an exception raised.

But when I checked using for leaks using the instrument it crashes.

To test if it is the media I guess you could change all the sound affect to the one sound and see if it still happens.

Possible it is just one sound file that is making your app crash.

Cheers

Shane

Posted 5 months ago #
PDiTO
Member

@ShaneT:- Don't believe the App crashes, it is just terminated for taking to long to resume.

@Eezlebrot:- Could you explain in a bit more detail what measures you implemented. This could be the short term fix i'm looking for.

Posted 5 months ago #
Eezlebrot
Member

Mine was definitely crashing from taking too long, not each time, but it was happening; perhaps I've got other problems. That said, symptom-wise (like the original post) this was running clean and clear in iOS4.x.

I don't have the code directly in front of me, but if you look at my earlier post above, I basically set the autohandle to 'no' and created a one off NSTimer scheduler within my application's didBecomeActive to explicitly call CDAudioManager's applicationDidBecomeActive. This will obviously delay the background music when coming back into the app, but I imagine you could play with it and reduce the time. This is not a great workaround, but it's been working for me.

Posted 5 months ago #
Steve Oldmeadow
Administrator
```
Dec 5 17:00:26 unknown MyApp[99] <Error>: 17:00:26.480 <0x3eb8fcb8> AudioSessionSetProperty posting message to kill mediaserverd (32)
```
That doesn't look good. Seems like it may be a new "feature" in iOS 5. Killing the mediaserver daemon because of a change to an audio session property seems a bit drastic.

Based on those logs it looks like your apps trigger a kill of the mediaserver, the mediaserver fails to restart because of various errors and then your app won't resume in time because it is waiting for the mediaserver to restart so that it can play audio.

I'll see if I can at least identify what triggers the killiing of the mediaserver daemon.
Posted 5 months ago #
Steve Oldmeadow
Administrator

I'm still not able to reproduce, the fact that FadeToGrey is working also means it is not a general issue with audio. There must be something specific to your apps or environment. We really need as much detail as possible to see what is the common factor amongst the problematic apps.

I found a post in the developer forums with a similar issue that is triggered by local and push notifications.
https://devforums.apple.com/message/583670

At the moment @Eezlebrot 's work around seems pretty good to me i.e. defer restarting the audio. From some other posts on the developer forum it does seem like something is broken with AVFoundation audio on 5.x.

Posted 5 months ago #
PDiTO
Member

Thanks very much @Eezlebrot and @Steve.

I'm going to try and to implement the NSTimer fix in the meantime. Quick question...is there any benefit to using NSTimer as opposed to

[self performSelector:@selector(methodHere) withObject:nil afterDelay:1.0]; ?

Posted 5 months ago #
PDiTO
Member

As an update, i've implemented Eezlebrot's temporary fix in both the paid and lite version of my app, and both are now resuming from background fine on my iPhone 4 running iOS5.

Posted 5 months ago #
Eezlebrot
Member

Here's some additional datapoints - my app runs iAd, AdMob, GameCenter, and CCDirector, all of which were trying to do something when the app resumes (not necessarily in applicationDidBecomeActive). I've spaced all of them out with timers (with the exception of GC) to reduce potential for resource starvation. Not sure if it's important or not, just throwing out another data point.

Posted 5 months ago #
donutzeatpeople
Member

@Eezlebrot, just out of curiosity, how did you delay Game Center from re-authenticating upon applicationDidBecomeActive?

The truth is, I don't actually understand how Game Center re-authenticates when the application comes back to the foreground because it seems to be happening outside my code. I, too, would like to delay it from happening.

Posted 5 months ago #
Eezlebrot
Member

Sorry, I abbreviated game center as GC "with the exception of GC" -- I have no idea how to delay GC from coming back, I'll be very interested if you ever figure it out.

Posted 5 months ago #

RSS feed for this topic

12 Next »

Reply »

You must log in to post.