Bug 44736 - 0.34.2.6 Issues with profiler quitting after a few seconds.
Summary: 0.34.2.6 Issues with profiler quitting after a few seconds.
Status: RESOLVED ANSWERED
Alias: None
Product: Profiler
Classification: Xamarin
Component: Android ()
Version: 0.34.x
Hardware: PC Windows
: High normal
Target Milestone: (C8)
Assignee: Jose Miguel Torres
URL:
Depends on:
Blocks:
 
Reported: 2016-09-26 12:52 UTC by Brad Chase
Modified: 2016-10-26 15:28 UTC (History)
6 users (show)

Tags:
Is this bug a regression?: ---
Last known good build:

Notice (2018-05-24): bugzilla.xamarin.com is now in read-only mode.

Please join us on Visual Studio Developer Community and in the Xamarin and Mono organizations on GitHub to continue tracking issues. Bugzilla will remain available for reference in read-only mode. We will continue to work on open Bugzilla bugs, copy them to the new locations as needed for follow-up, and add the new items under Related Links.

Our sincere thanks to everyone who has contributed on this bug tracker over the years. Thanks also for your understanding as we make these adjustments and improvements for the future.


Please create a new report on Developer Community with your current version information, steps to reproduce, and relevant error messages or log files if you are hitting an issue that looks similar to this resolved bug and you do not yet see a matching new report.

Related Links:
Status:
RESOLVED ANSWERED

Description Brad Chase 2016-09-26 12:52:06 UTC
It seems the new version of the profiler gets farther than the last but it still keeps quitting intermittently... Here are some of the logs:

immediate shutdown:
09-23 14:46:35.434 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.456 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.481 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.500 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.522 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.544 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.565 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 14:46:35.604 W/OpenGLRenderer( 4324): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...
09-23 14:46:35.623 W/art (22069): unexpectedly found a contender on mutex Task processor lock

The next one:

09-23 15:11:24.765 W/ActivityManager( 2940): Permission Denial: Accessing service ComponentInfo{com.google.android.music/com.google.android.music.dial.DialMediaRouteProviderService} from pid=22868, uid=10066 that is not exported from uid 10071
09-23 15:11:24.787 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624549565015210, TAG=ContextManagerWakeLock, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:11:24.836 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624549614243567, TAG=GCoreFlp, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:11:24.843 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 15:11:24.847 D/Resampler( 2629): configure: SOURCE rate=48000 format=1 channels=1
09-23 15:11:24.847 D/Resampler( 2629): configure: DST rate=16000 format=1 channels=1
09-23 15:11:24.847 D/RouteManager( 2629): -executeUnmuteRoutingStage-
09-23 15:11:24.847 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: RoutageState, current state: Flow|Path|Configure
09-23 15:11:24.847 D/RouteManager( 2629): audio-parameter-manager: Configuration application request {
09-23 15:11:24.847 D/RouteManager( 2629): audio-parameter-manager: Applying configurations {
09-23 15:11:24.847 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Unmute" from domain "Routing.Sst.Mute.HwCodec.Capture.AmicDmics"
09-23 15:11:24.847 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Unmute" from domain "Routing.Sst.Mute.Media.Capture.Regular"
09-23 15:11:24.848 D/RouteManager( 2629): audio-parameter-manager: } Applying configurations
09-23 15:11:24.848 D/RouteManager( 2629): audio-parameter-manager: } Configuration application request
09-23 15:11:24.848 D/RouteManager( 2629): doReconsiderRouting: DONE
09-23 15:11:24.848 D/EVENT_THREAD( 2629): void CEventThread::run() Do poll with timeout: -1
09-23 15:11:24.863 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 15:11:24.870 I/WAKELOCK_RELEASE( 2940): TIMESTAMP=624549648577689, TAG=GCoreFlp, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:11:24.871 I/MicroDetectionWorker( 2732): onReady
09-23 15:11:24.873 E/SQLiteLog( 3936): (2067) abort at 35 in [INSERT INTO context(context_name,end_time,context_family,module_id,version,sync_state_mod_time_millis,start_time,sync_state,context_id,time_type,proto_blob) VALUES (?,?,?,?,?,?,?,?,?,?,?)]: UNI
09-23 15:11:24.885 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 15:11:24.891 W/SpeechLevelGenerator( 2732): Really low audio levels detected. The audio input may have issues.
09-23 15:11:24.937 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624549715677030, TAG=NlpWakeLock, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:11:24.946 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624549724308362, TAG=NlpCollectorWakeLock, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:11:24.954 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624549732548482, TAG=NlpCollectorWakeLock, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:11:24.959 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624549737723851, TAG=NlpCollectorWakeLock, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=




Next one:

09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Selection criterion changed event: Criterion name: InputSources, current state: VoiceRecognition
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Selection criterion changed event: Criterion name: StatesChanged, current state: InputSources
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Configuration application request {
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Applying configurations {
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Applying configuration "Applicable" from domain "StreamRoute.Media.Capture"
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Applying configuration "No" from domain "StreamRoute.NeedReconfigure.Capture"
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: Applying configuration "Yes" from domain "Route.HwCodec0IA.Capture.Applicable"
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: } Applying configurations
09-23 15:12:53.146 D/AudioIntelHal/AudioPlatformState( 2629): route-parameter-manager: } Configuration application request
09-23 15:12:53.146 D/EVENT_THREAD( 2629): void CEventThread::trig(void*, uint32_t): in
09-23 15:12:53.146 D/EVENT_THREAD( 2629): void CEventThread::trig(void*, uint32_t): out
09-23 15:12:53.147 D/RouteManager( 2629): doReconsiderRouting: Route state:
09-23 15:12:53.147 D/RouteManager( 2629): -Previously Enabled Route in Input = 
09-23 15:12:53.147 D/RouteManager( 2629): -Previously Enabled Route in Output = 
09-23 15:12:53.147 D/RouteManager( 2629): -Selected Route in Input = Media|HwCodec0IA
09-23 15:12:53.147 D/RouteManager( 2629): -Selected Route in Output = 
09-23 15:12:53.147 D/RouteManager( 2629): -executeMuteRoutingStage-
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: RoutageState, current state: Flow
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Configuration application request {
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Applying configurations {
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: } Applying configurations
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: } Configuration application request
09-23 15:12:53.147 D/RouteManager( 2629): -executeDisableRoutingStage-
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: RoutageState, current state: Path
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Configuration application request {
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Applying configurations {
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Locked" from domain "Routing.Sst.Enable.VoiceProcessingLock.Uplink"
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Locked" from domain "Routing.Sst.Enable.VoiceProcessingLock.Downlink"
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: } Applying configurations
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: } Configuration application request
09-23 15:12:53.147 D/RouteManager( 2629): -executeConfigureRoutingStage-
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: RoutageState, current state: Configure
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: OpenedCaptureRoutes, current state: Media|HwCodec0IA
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: InputSources, current state: VoiceRecognition
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Configuration application request {
09-23 15:12:53.147 D/RouteManager( 2629): audio-parameter-manager: Applying configurations {
09-23 15:12:53.148 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Enable" from domain "Routing.Configure.HWCodec.Capture.MainMic"
09-23 15:12:53.148 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Bind" from domain "Routing.Sst.Configure.Capture.Media.Amic"
09-23 15:12:53.148 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "Bind" from domain "Routing.Sst.Configure.Speech.Uplink.AmicDmics"
09-23 15:12:53.148 W/RouteManager( 2629): audio-parameter-manager: Unable to forward synchronize configurable element /Audio/EVEREST/MIXER/M_INPUT/MIC1LM/SWITCH: Failed to open mixer control: M_Input_Mixer MIC1LM_IMP_CNTL
09-23 15:12:53.148 W/RouteManager( 2629): audio-parameter-manager: Unable to forward synchronize configurable element /Audio/EVEREST/MIXER/P_INPUT/MIC1LP/SWITCH: Failed to open mixer control: P_Input_Mixer MIC1LP_IMP_CNTL
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: } Applying configurations
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: } Configuration application request
09-23 15:12:53.164 D/RouteManager( 2629): -executeEnableRoutingStage-
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: Selection criterion changed event: Criterion name: RoutageState, current state: Path|Configure
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: Configuration application request {
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: Applying configurations {
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "UnLocked" from domain "Routing.Sst.Enable.VoiceProcessingLock.Uplink"
09-23 15:12:53.164 D/RouteManager( 2629): audio-parameter-manager: Applying configuration "UnLocked" from domain "Routing.Sst.Enable.VoiceProcessingLock.Downlink"
09-23 15:12:53.165 D/RouteManager( 2629): audio-parameter-manager: } Applying configurations
09-23 15:12:53.165 D/RouteManager( 2629): audio-parameter-manager: } Configuration application request
09-23 15:12:53.165 D/TinyAlsaAudioDevice( 2629): open: card (cherrytrailaud, 0) 
09-23 15:12:53.165 D/TinyAlsaAudioDevice( 2629): config (rate=48000 format=0 channels= 2).
09-23 15:12:53.165 D/TinyAlsaAudioDevice( 2629): RingBuffer config: periodSize=960 nbPeriod=4startTh=1 stop Th=3840 silence Th=0
09-23 15:12:53.171 E/hwc ( 2427): Invalid fence on InternalBuffer
09-23 15:12:53.176 D/AndroidRuntime(23606): >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<<
09-23 15:12:53.180 D/AndroidRuntime(23606): CheckJNI is OFF
09-23 15:12:53.205 I/WAKELOCK_ACQUIRE( 2940): TIMESTAMP=624637982966716, TAG=ContextManagerWakeLock, TYPE=PARTIAL_WAKE_LOCK , COUNT=0, PID=3936, UID=10007, FLAGS=
09-23 15:12:53.210 W/art (23606): Could not create mount namespace.
09-23 15:12:53.210 W/ (23606): Failed to bind-mount /system/lib/x86/cpuinfo as /proc/cpuinfo: Operation not permitted
09-23 15:12:53.210 E/ (23606): Cannot create code cache directory ./code_cache: Read-only file system.
09-23 15:12:53.225 E/SQLiteLog( 3936): (2067) abort at 35 in [INSERT INTO context(context_name,end_time,context_family,module_id,version,sync_state_mod_time_millis,start_time,sync_state,context_id,time_type,proto_blob) VALUES (?,?,?,?,?,?,?,?,?,?,?)]: UNI
09-23 15:12:53.246 E/HAL (23606): load: module=/system/lib/hw/memtrack.default.so
09-23 15:12:53.246 E/HAL (23606): dlopen failed: library "/system/lib/hw/memtrack.default.so" not found
09-23 15:12:53.246 E/memtrack(23606): Couldn't load memtrack module (Invalid argument)
Comment 1 Rodrigo Moya 2016-09-27 16:38:22 UTC
hmm, looks bad indeed, but seems to be a bug in the runtime. Can you please let us know what version of Xamarin.Android are you using?

Also, what does the profiler log say?
Comment 2 Brad Chase 2016-09-27 23:07:21 UTC
Sure, we are using 4.2.0.2 because debugging is broken in any version higher so we can't move up.  I believe we are giving you guys access to our repository but I am not sure where we last left off on that.  I will check in the AM.

As far as the log, what's the best way to get that and then send it to you?
Comment 3 Brad Chase 2016-09-27 23:07:41 UTC
Sure, we are using 4.2.0.2 because debugging is broken in any version higher so we can't move up.  I believe we are giving you guys access to our repository but I am not sure where we last left off on that.  I will check in the AM.

As far as the log, what's the best way to get that and then send it to you?
Comment 5 Rodrigo Moya 2016-09-28 08:41:38 UTC
@Brad the log is in %localappdata&\Logs\Xamarin.Profiler on Windows and ~/Library/Logs/Xamarin.Profiler on Mac. Just get the latest one after reproducing the bug again.

As for the X.Android version, I guess it is too old, not sure though, so looping in the Xamarin.Android guys. @Jonp?
Comment 6 Brad Chase 2016-09-30 16:18:36 UTC
ok I will grab the log when I get time to test the profiler again.  As a note, you guys now have access to our repository.  The Xamarin team was able to repro the issues we are having on not being able to upgrade with debug crashes using our code.  Do you think you might be able to run the profiler against our repository to work through the issues?  If you need to work through it, you can email me at brad.chase@argus.aero and Ill walk you through getting to the point where it bombs, its generally right after login and loading a view, it can never load an actual view from the master/details page.  You should also have my phone number from a previous email.
Comment 7 Brad Chase 2016-10-24 14:40:15 UTC
I have not had alot of time to test the profiler, but I wanted to verify and say the issue SPECIFICALLY has been fixed.  The issue I am running into now is the output on the performance is just one level deep on the tree for an async call.  So there is no info for all the UI work being done.  Are these unrelated?
Comment 8 Rodrigo Moya 2016-10-26 15:28:01 UTC
Yes, I guess so, you're probably seeing https://bugzilla.xamarin.com/show_bug.cgi?id=41427 or others that are similar. If you want to share the MLPD with me, I can have a look and see what the problem might be. But it looks to be that one, which is a problem we're seeing with the latest changes in the runtime profiler.

I will close this one if the issue is fixed. Feel free to reopen or add your comments to the other bug (or open any new ones)