me=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225243 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x09979600, leftVol=4096, rightVol=4096
225244 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1012, callbacks=false
225244 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1012 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225245 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225245 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225246 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225246 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225246 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225246 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225246 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225246 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225247 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x09979A00, leftVol=4096, rightVol=4096
225247 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1014, callbacks=false
225247 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1014 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225247 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225247 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225249 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225250 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225251 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225252 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225252 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelSignalSema id=0x24 name='Sndp' signal=1
225252 [ATRAC3 play thread] DEBUG hle.sceAudio - free buffer 1484656480
225252 [ATRAC3 play thread] DEBUG hle.sceAudio - sceAudioOutputPannedBlocking[not blocking] leftVol=32768, rightVol=32768, channel=SoundChannel[1](sourceSampleOffset=0, restLength=0, buffers queued=0, isOutputBlock=false, Stereo, reserved=true, sampleLength=2048, sampleRate=44100)
225252 [ATRAC3 play thread] DEBUG hle.sceAudio - doAudioOutput(SoundChannel[1](sourceSampleOffset=0, restLength=0, buffers queued=0, isOutputBlock=false, Stereo, reserved=true, sampleLength=2048, sampleRate=44100), 0x09977E00)
225253 [ATRAC3 play thread] DEBUG hle.sceAudio - alSourceQueueBuffer buffer=1484656480, SoundChannel[1](sourceSampleOffset=0, restLength=2048, buffers queued=1, isOutputBlock=false, Stereo, reserved=true, sampleLength=2048, sampleRate=44100)
225253 [ATRAC3 play thread] DEBUG hle.sceCtrl - hleCtrlExecuteSampling
225253 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.071427
225253 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.071427
225253 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.079362
225253 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.079362
225254 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Triggering Interrupt VBLANK(0x1E)
225254 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Calling InterruptHandler 0x08806A78(0x00000000,0x00000000)
225254 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuSuspendIntr interruptsEnabled=true
225254 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuResumeIntr flag=1
225254 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - End of Interrupt
225254 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelSignalSema id=0x28 name='At3p' signal=1
225254 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1000, callbacks=false
225255 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1000 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225255 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225255 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225255 [FileCtlThread] DEBUG hle.sceUmdUser - sceUmdGetDriveStat - 32
225258 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225258 [FileCtlThread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225259 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1000, callbacks=false
225259 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1000 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225259 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225259 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225261 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225261 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225261 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=5000, callbacks=false
225261 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=5000 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225261 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225261 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225262 [FileCtlThread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=10000, callbacks=true
225262 [FileCtlThread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=10000 forever:false thread:'FileCtlThread' caller:'ThreadManForUser.hleKernelDelayThread'
225262 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225267 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225267 [FileCtlThread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225267 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelWaitSema(id=0x28,signal=1,timeout=0x0) callbacks=true
225267 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelWaitSema - 'At3p' fast check succeeded
225267 [ATRAC3 play thread] DEBUG hle.sceAtrac3plus - sceAtracGetNextSample: atracID=0, nbrSamplesAddr=0x09feca64
225268 [ATRAC3 play thread] DEBUG hle.sceAtrac3plus - sceAtracDecodeData: atracID=0, samplesAddr=0x09979E00, samplesNbrAddr=0x09FECA60, outEndAddr=0x09985E0C, remainFramesAddr=0x09985E08
225268 [ATRAC3 play thread] DEBUG hle.sceAtrac3plus - sceAtracDecodeData returning 0x00000000, samples=2048, end=0, remainFrames=-1, currentSample=1480704/4469389, AtracID[id=0, inputBufferAddr=0x09986000, inputBufferSize=1572864, inputBufferOffset=271848, inputBufferWritableBytes=0, inputBufferNeededBytes=0]
225268 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=2300, callbacks=false
225269 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=2300 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225269 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225270 [ATRAC3 play thread] DEBUG hle.sceCtrl - hleCtrlExecuteSampling
225270 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.071427
225270 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.071427
225270 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.063493
225270 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.063493
225271 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Triggering Interrupt VBLANK(0x1E)
225273 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Calling InterruptHandler 0x08806A78(0x00000000,0x00000000)
225274 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuSuspendIntr interruptsEnabled=true
225274 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuResumeIntr flag=1
225274 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - End of Interrupt
225274 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225275 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225276 [ATRAC3 play thread] DEBUG hle.sceAudio - sceAudioSetChannelDataLen channel=1, sampleCount=2048
225276 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelWaitSema(id=0x24,signal=1,timeout=0x0) callbacks=true
225276 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelWaitSema - 'Sndp' fast check succeeded
225276 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225276 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225277 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x09979E00, leftVol=4096, rightVol=4096
225277 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1013, callbacks=false
225277 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1013 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225277 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225277 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225278 [FileCtlThread] DEBUG hle.sceUmdUser - sceUmdGetDriveStat - 32
225278 [FileCtlThread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=10000, callbacks=true
225278 [FileCtlThread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=10000 forever:false thread:'FileCtlThread' caller:'ThreadManForUser.hleKernelDelayThread'
225280 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225281 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225282 [FileCtlThread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225283 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225283 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225283 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225283 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225283 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997A200, leftVol=4096, rightVol=4096
225283 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1013, callbacks=false
225284 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1013 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225284 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225285 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225286 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225286 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225286 [ATRAC3 play thread] DEBUG hle.sceCtrl - hleCtrlExecuteSampling
225286 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.071427
225286 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.071427
225287 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.063493
225287 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.063493
225287 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Triggering Interrupt VBLANK(0x1E)
225287 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Calling InterruptHandler 0x08806A78(0x00000000,0x00000000)
225287 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuSuspendIntr interruptsEnabled=true
225287 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuResumeIntr flag=1
225287 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - End of Interrupt
225288 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225288 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225288 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225290 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997A600, leftVol=4096, rightVol=4096
225290 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1012, callbacks=false
225292 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1012 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225292 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225292 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225292 [FileCtlThread] DEBUG hle.sceUmdUser - sceUmdGetDriveStat - 32
225300 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225300 [FileCtlThread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225300 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225300 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225300 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225300 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225301 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997AA00, leftVol=4096, rightVol=4096
225303 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1014, callbacks=false
225304 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1014 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225304 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225305 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225305 [ATRAC3 play thread] DEBUG hle.sceCtrl - hleCtrlExecuteSampling
225306 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.055558
225306 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.055558
225306 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Triggering Interrupt VBLANK(0x1E)
225306 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Calling InterruptHandler 0x08806A78(0x00000000,0x00000000)
225306 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuSuspendIntr interruptsEnabled=true
225306 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuResumeIntr flag=1
225307 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - End of Interrupt
225307 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225307 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225307 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225307 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225309 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225310 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225311 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997AE00, leftVol=4096, rightVol=4096
225311 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1014, callbacks=false
225311 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1014 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225312 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225312 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225312 [FileCtlThread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=10000, callbacks=true
225312 [FileCtlThread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=10000 forever:false thread:'FileCtlThread' caller:'ThreadManForUser.hleKernelDelayThread'
225312 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225312 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225313 [FileCtlThread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225313 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225314 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225316 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225317 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225318 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997B200, leftVol=4096, rightVol=4096
225319 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=952, callbacks=false
225319 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=952 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225319 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225319 [ATRAC3 play thread] DEBUG hle.sceCtrl - hleCtrlExecuteSampling
225320 [ATRAC3 play thread] DEBUG controller - Controller Event on Z Axis(z): 0.063493
225320 [ATRAC3 play thread] DEBUG controller - Unknown Controller Event in DeadZone on Z Axis(z): 0.063493
225320 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Triggering Interrupt VBLANK(0x1E)
225320 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Calling InterruptHandler 0x08806A78(0x00000000,0x00000000)
225322 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuSuspendIntr interruptsEnabled=true
225322 [ATRAC3 play thread] DEBUG hle.Kernel_Library - sceKernelCpuResumeIntr flag=1
225323 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - End of Interrupt
225323 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225323 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225323 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225323 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225324 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225324 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225325 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997B600, leftVol=4096, rightVol=4096
225325 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1013, callbacks=false
225326 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1013 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225326 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'FileCtlThread(Status PSP_THREAD_READY, Wait None)' after reschedule
225326 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=17 name:'FileCtlThread'
225326 [FileCtlThread] DEBUG hle.sceUmdUser - sceUmdGetDriveStat - 32
225326 [FileCtlThread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=10000, callbacks=true
225327 [FileCtlThread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=10000 forever:false thread:'FileCtlThread' caller:'ThreadManForUser.hleKernelDelayThread'
225327 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225327 [FileCtlThread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225327 [FileCtlThread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225327 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225328 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225328 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelLockLwMutex redirecting to hleKernelLockLwMutex
225328 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelLockLwMutex SceKernelLwMutexInfo(uid=27, name=SceLibsndpPrevExcl, mutexUid=0, lwMutexOpaqueWorkAreaAddr=0x89A5110, initCount=0, lockedCount=1, numWaitThreads=0, attr=0x0, threadid=0x29), count=1, timeout_addr=0x00000000, wait=true, doCallbacks=false - fast check succeeded
225329 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasCoreWithMix 0x089A6A00, sasInOut=0x0997BA00, leftVol=4096, rightVol=4096
225331 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1013, callbacks=false
225332 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1013 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225333 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225334 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'ATRAC3 play thread(Status PSP_THREAD_READY, Wait None)' after reschedule
225334 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - ---------------------------------------- SceUID=29 name:'ATRAC3 play thread'
225334 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelUnlockLwMutex (workAreaAddr=0x89a5110, count=1)
225334 [ATRAC3 play thread] DEBUG hle.sceSasCore - __sceSasGetAllEnvelopeHeights(sasCore=0x089A6A00, heightsAddr=0x09FEC9B0)
225335 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelSignalSema id=0x24 name='Sndp' signal=1
225335 [ATRAC3 play thread] DEBUG hle.sceAudio - free buffer 1484656480
225335 [ATRAC3 play thread] DEBUG hle.sceAudio - sceAudioOutputPannedBlocking[not blocking] leftVol=32768, rightVol=32768, channel=SoundChannel[1](sourceSampleOffset=0, restLength=0, buffers queued=0, isOutputBlock=false, Stereo, reserved=true, sampleLength=2048, sampleRate=44100)
225335 [ATRAC3 play thread] DEBUG hle.sceAudio - doAudioOutput(SoundChannel[1](sourceSampleOffset=0, restLength=0, buffers queued=0, isOutputBlock=false, Stereo, reserved=true, sampleLength=2048, sampleRate=44100), 0x09979E00)
225335 [ATRAC3 play thread] DEBUG hle.sceAudio - alSourceQueueBuffer buffer=1484656480, SoundChannel[1](sourceSampleOffset=0, restLength=2048, buffers queued=1, isOutputBlock=false, Stereo, reserved=true, sampleLength=2048, sampleRate=44100)
225336 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - sceKernelSignalSema id=0x28 name='At3p' signal=1
225336 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - hleKernelDelayThread micros=1000, callbacks=false
225336 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - -------------------- hleKernelThreadWait micros=1000 forever:false thread:'ATRAC3 play thread' caller:'ThreadManForUser.hleKernelDelayThread'
225336 [ATRAC3 play thread] DEBUG hle.ThreadManForUser - Context switching to 'idle0(Status PSP_THREAD_READY, Wait None)' after reschedule
225494 [GUI] WARN  ge - Unimplemented VCV: colorR=255, colorG=255, colorB=255
225495 [GUI] WARN  ge - Unimplemented VCV: colorR=255, colorG=255, colorB=255
225667 [GUI] WARN  ge - Unimplemented VCV: colorR=255, colorG=255, colorB=255
225668 [GUI] WARN  ge - Unimplemented VCV: colorR=255, colorG=255, colorB=255