search

hrydgard / ppsspp

A PSP emulator for Android, Windows, Mac and Linux, written in C++. Want to contribute? Join us on Discord at https://discord.gg/5NJB6dD or just send pull requests / issues. For discussion use the forums at forums.ppsspp.org.
https://www.ppsspp.org
Other
11.4k stars 2.19k forks source link

Lego indiana jones 2 network issue #13289

Open Lemoncak3 opened 4 years ago

Lemoncak3 commented 4 years ago

I tried the ad hoc network in this game with my brother, in channel 1 and 11 i can see my brother's name but after confirm then pop up notif box saying there is network error connection. Screenshot_20200816-040959 Screenshot_20200816-041016 Sorry i can't provide any logs since i am using android phone..

anr2me commented 4 years ago

@Lemoncak3 have you tried this game again with the latest dev build at https://buildbot.orphis.net/ppsspp/ ?

Lemoncak3 commented 4 years ago

Sorry bit busy will try it later. Thanks a lot :) @hrydgard and @ANR2ME

Lemoncak3 commented 4 years ago

Still same error Screenshot_20200912-033455 Screenshot_20200912-033147 try with all wlan channel still failed. And after the adhoc server exit PPSSPP before client does, client's PPSSPP got crashed.

Oh yeah i notice something, when the IP is wrong instead getting ad hoc server error like usual it shows this error and then crashed. Screenshot_20200912-031716

anr2me commented 4 years ago

That socket error 32 will shows up on clients if Adhoc Server already shutdown, it's normal, it's an error if a client tries to send something to server while the server already closed the socket.

Btw, what you mean by crash? was it forced closing PPSSPP ?

Lemoncak3 commented 4 years ago

But after that error 32 PPSSPP will force close on it's own. Well after network initialized thing we tried to connect but failed but it seems we already connected so when i am as the adhoc server close the game before the client, the client's PPSSPP will force close on it's own. The same thing happen to dynasty strikeforce

anr2me commented 4 years ago

On windows, it worked without any problem image

anr2me commented 4 years ago

This will only occurred on Client side if the one with Adhoc Server was closed first image

Lemoncak3 commented 4 years ago

Will it happens to PSP2 then? Usually after playing the adhoc server left before client and we were told to save ingame cause the network disconnected and i don't remember if the PPSSPP will crash. Please correct me if i am wrong :)

anr2me commented 4 years ago

Normally (just like on windows) disconnection won't cause a crash, the game will get an error code returned and show the error message on screen where players can go back to main menu afterward.

Anyway, i tested Android vs PC(Windows) where PC have the adhoc server, and you're right after the one on PC "Exit to Menu" followed by the one on android, PPSSPP on android got forced close :( i think it have similar situation with SIGPIPE issue but with different signal

2020-09-12 05:42:34.515 7851-8486/? E/PPSSPP: [SCENET] FriendFinder: Socket Error (32) when sending OPCODE_PING
2020-09-12 05:42:34.516 7851-8486/? A/libc: FORTIFY: FD_SET: file descriptor -1 < 0
2020-09-12 05:42:34.517 7851-8486/? A/libc: Fatal signal 6 (SIGABRT), code -6 (SI_TKILL) in tid 8486 (FriendFinder), pid 7851 (androidInit)
anr2me commented 4 years ago

Okay, i've fixed the crash issue on this PR https://github.com/hrydgard/ppsspp/pull/13415

But there is still disconnection issue when android joining the host on PC :(

Lemoncak3 commented 4 years ago

Both of us are not in PPSSPP setting when going to multiplayer menu, i got request from my brother PPSSPP but after i accept there is few seconds of delay before we both got connection error message.

anr2me commented 4 years ago

Yeah, that few seconds delay was really weird, it didn't happen on windows vs windows, but android vs windows also got that delay, i think there is something different on android🤔 may be it wasn't using non-blocking socket

ghost commented 4 years ago

I noticed even on PC multiple instances that there is a delay until the other player appears in lobby (with other games).

anr2me commented 4 years ago

I noticed even on PC multiple instances that there is a delay until the other player appears in lobby (with other games).

if you mean a short delay like when you enter the lobby on DBZ SB2 then it's intentional hleDelayResult when creating/joining a group, which fixes the stuck dialog/message box on Naruto Ultimate Ninja Heroes 3 and .Hack//Link, while the lag on Lego indiana Jones 2 took a really long time (about 10 seconds freeze before it started loading), sometimes it only took 2 seconds freeze when i managed to get a successful join (android as the host)

anr2me commented 4 years ago

Hmm.. i think i'm starting to see what's different between Android and Windows, based on these VERBOSE LOGS from Lego Indiana Jones 2:

On Windows the game is polling incoming data with non-blocking PdpRecv every 10ms

59:02:472 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
59:02:472 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001BB3F08F1C2, 000001BB3F08F1C0, 000001BB3E0EA900, 000001BB3F08F260, 0, 1) at 08930b50
59:02:472 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
59:02:482 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
59:02:482 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001BB3F08F1C2, 000001BB3F08F1C0, 000001BB3E0EA900, 000001BB3F08F260, 0, 1) at 08930b50
59:02:482 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
59:02:492 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
59:02:492 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001BB3F08F1C2, 000001BB3F08F1C0, 000001BB3E0EA900, 000001BB3F08F260, 0, 1) at 08930b50
59:02:492 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
59:02:502 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
59:02:502 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001BB3F08F1C2, 000001BB3F08F1C0, 000001BB3E0EA900, 000001BB3F08F260, 0, 1) at 08930b50
59:02:502 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
59:02:512 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
59:02:512 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001BB3F08F1C2, 000001BB3F08F1C0, 000001BB3E0EA900, 000001BB3F08F260, 0, 1) at 08930b50
59:02:512 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
59:02:522 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
59:02:522 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001BB3F08F1C2, 000001BB3F08F1C0, 000001BB3E0EA900, 000001BB3F08F260, 0, 1) at 08930b50
59:02:522 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]

While on Android it's doing it every 1ms ... That's way too much!

2020-09-13 19:59:01.635 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.635 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.635 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.636 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.636 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.636 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.637 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.637 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.637 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.639 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.639 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.639 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.639 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.639 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.639 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.640 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.640 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.640 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.641 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.641 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.641 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.642 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.642 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.643 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.644 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 19:59:01.644 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 19:59:01.644 23110-23302/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 19:59:01.645 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocPdpDelete(1, 0) at 08930134
2020-09-13 19:59:01.645 23110-23302/? I/PPSSPP: [SCENET] sceNetAdhocctlDisconnect() at 08930174 [group=Azukavbs]

I have no why the game is using different interval on different platform like this...

PS: The only timings related data used here is CoreTiming::GetGlobalTimeUsScaled() which is used for last_recv field of PeerInfo, but i found nothing wrong with it, it's ticking at the same pace on both android & windows

anr2me commented 4 years ago

This is the Windows Logs with SCEKERNEL and SCERTC on Debug Level to see what the game is using between each PdpRecv interval:

58:55:246 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
58:55:246 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001E60960F1C2, 000001E60960F1C0, 000001E60866A900, 000001E60960F260, 0, 1) at 08930b50
58:55:246 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
58:55:246 user_main    D[SCEKERNEL]: HLE\sceKernelThread.cpp:2518 0=sceKernelDelayThreadCB(00002710): delaying 10010 usecs
58:55:246 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: user_main -> idle0 (276->272, pc: 088c98f8->08000000, thread delayed) +16us
58:55:246 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +42us
58:55:246 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25ef4, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f40, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f8c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25fd8, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26024, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26070, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c260bc, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26108, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26154, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c261a0, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c261ec, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26238, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26284, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c262d0, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2631c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26368, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c263b4, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26400, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2644c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26498, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c264e4, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26530, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2657c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c265c8, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26614, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26660, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c266ac, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c266f8, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26744, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26790, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c267dc, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26828, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelEventFlag.cpp:311 0=sceKernelSetEventFlag(321, 00000001)
58:55:247 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a041a8->08000000, blocking audio) +12us
58:55:247 AdhocThread  D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> AdhocThread (272->333, pc: 08000000->08000104, thread delay finished) +3159us
58:55:247 AdhocThread  D[SCEKERNEL]: HLE\sceKernelThread.cpp:2529 0=__NetTriggerCallbacks(): delaying 10010 usecs
58:55:247 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: AdhocThread -> idle0 (333->272, pc: 08000104->08000000, thread delayed) +14us
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +2440us
58:55:247 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25ef4, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f40, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f8c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25fd8, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26024, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26070, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c260bc, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26108, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26154, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c261a0, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c261ec, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26238, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26284, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c262d0, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2631c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26368, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c263b4, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26400, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2644c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26498, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c264e4, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26530, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2657c, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c265c8, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26614, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26660, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c266ac, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c266f8, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26744, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26790, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c267dc, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26828, 1)
58:55:247 FUSION THREA D[SCEKERNEL]: HLE\sceKernelEventFlag.cpp:311 0=sceKernelSetEventFlag(321, 00000001)
58:55:247 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a041a8->08000000, blocking audio) +12us
58:55:283 user_main    D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> user_main (272->276, pc: 08000000->088c98f8, thread delay finished) +3971us
58:55:283 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
58:55:283 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001E60960F1C2, 000001E60960F1C0, 000001E60866A900, 000001E60960F260, 0, 1) at 08930b50
58:55:283 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
58:55:283 user_main    D[SCEKERNEL]: HLE\sceKernelThread.cpp:2518 0=sceKernelDelayThreadCB(00002710): delaying 10010 usecs
58:55:283 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: user_main -> idle0 (276->272, pc: 088c98f8->08000000, thread delayed) +16us
58:55:283 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +1625us
58:55:283 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
58:55:283 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
58:55:283 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25ef4, 1)
58:55:283 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f40, 1)
58:55:283 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f8c, 1)
...
...
58:55:284 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
58:55:284 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001E60960F1C2, 000001E60960F1C0, 000001E60866A900, 000001E60960F260, 0, 1) at 08930b50
58:55:284 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
58:55:284 user_main    D[SCEKERNEL]: HLE\sceKernelThread.cpp:2518 0=sceKernelDelayThreadCB(00002710): delaying 10010 usecs
58:55:284 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: user_main -> idle0 (276->272, pc: 088c98f8->08000000, thread delayed) +16us
58:55:284 AdhocThread  D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> AdhocThread (272->333, pc: 08000000->08000104, thread delay finished) +3403us
58:55:284 AdhocThread  D[SCEKERNEL]: HLE\sceKernelThread.cpp:2529 0=__NetTriggerCallbacks(): delaying 10010 usecs
58:55:284 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: AdhocThread -> idle0 (333->272, pc: 08000104->08000000, thread delayed) +14us
58:55:284 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +1373us
58:55:284 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25ef4, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f40, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25f8c, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c25fd8, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26024, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26070, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c260bc, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26108, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26154, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c261a0, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c261ec, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26238, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26284, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c262d0, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2631c, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26368, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c263b4, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26400, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2644c, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26498, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c264e4, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26530, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c2657c, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c265c8, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26614, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26660, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c266ac, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c266f8, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26744, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26790, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c267dc, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelMutex.cpp:928 sceKernelTryLockLwMutex(08c26828, 1)
58:55:285 FUSION THREA D[SCEKERNEL]: HLE\sceKernelEventFlag.cpp:311 0=sceKernelSetEventFlag(321, 00000001)
58:55:285 idle0        D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a041a8->08000000, blocking audio) +12us
58:55:285 user_main    D[SCEKERNEL]: HLE\sceKernelThread.cpp:3098 Context switch: idle0 -> user_main (272->276, pc: 08000000->088c98f8, thread delay finished) +5027us
58:55:285 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:2141 sceNetAdhocctlGetPeerInfo(f4:99:30:62:e4:2b, 152, 09fff1c8) at 08930ab4
58:55:285 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1325 sceNetAdhocPdpRecv(1, 000001E60960F1C2, 000001E60960F1C0, 000001E60866A900, 000001E60960F260, 0, 1) at 08930b50
58:55:285 user_main    V[SCENET]: HLE\sceNetAdhoc.cpp:1423 Socket Error (10035) on sceNetAdhocPdpRecv[1:4330] [size=1444]
anr2me commented 4 years ago

Okay these are Logcat on the same scene on Android:

2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c267dc, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26828, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelSetEventFlag(321, 00000001)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a041a8->08000000, blocking audio) +12us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +128us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +327us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25ef4, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f40, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f8c, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25fd8, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26024, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26070, 1)
2020-09-13 22:10:42.610 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c260bc, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26108, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26154, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261a0, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261ec, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26238, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26284, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c262d0, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2631c, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26368, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c263b4, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26400, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2644c, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26498, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c264e4, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26530, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2657c, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c265c8, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26614, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26660, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266ac, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266f8, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26744, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26790, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c267dc, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26828, 1)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelSetEventFlag(321, 00000001)
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a041a8->08000000, blocking audio) +12us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +491us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> AdhocThread (272->333, pc: 08000000->08000104, thread delay finished) +210us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=__NetTriggerCallbacks(): delaying 10010 usecs
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: AdhocThread -> idle0 (333->272, pc: 08000104->08000000, thread delayed) +14us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +785us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +992us
2020-09-13 22:10:42.611 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
2020-09-13 22:10:42.612 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +11us
2020-09-13 22:10:42.612 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.612 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.612 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.612 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.612 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.613 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +143us
2020-09-13 22:10:42.613 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25ef4, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f40, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f8c, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25fd8, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26024, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26070, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c260bc, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26108, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26154, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261a0, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261ec, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26238, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26284, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c262d0, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2631c, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26368, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c263b4, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26400, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2644c, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26498, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c264e4, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26530, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2657c, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c265c8, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26614, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26660, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266ac, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266f8, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26744, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26790, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c267dc, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26828, 1)
2020-09-13 22:10:42.614 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelSetEventFlag(321, 00000001)
2020-09-13 22:10:42.619 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26238, 1)
2020-09-13 22:10:42.619 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266f8, 1)
2020-09-13 22:10:42.619 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.625 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26024, 1)
2020-09-13 22:10:42.625 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261a0, 1)
2020-09-13 22:10:42.626 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: AdhocThread -> idle0 (333->272, pc: 08000104->08000000, thread delayed) +14us
2020-09-13 22:10:42.626 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.626 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2644c, 1)
2020-09-13 22:10:42.627 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c267dc, 1)
2020-09-13 22:10:42.627 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.628 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c262d0, 1)
2020-09-13 22:10:42.628 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.628 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.629 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.630 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.630 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26530, 1)
2020-09-13 22:10:42.631 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266ac, 1)
2020-09-13 22:10:42.632 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c260bc, 1)
2020-09-13 22:10:42.632 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c262d0, 1)
2020-09-13 22:10:42.632 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c265c8, 1)
2020-09-13 22:10:42.632 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.632 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.633 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.634 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
2020-09-13 22:10:42.634 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25ef4, 1)
2020-09-13 22:10:42.634 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f40, 1)
2020-09-13 22:10:42.634 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f8c, 1)
2020-09-13 22:10:42.634 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261a0, 1)
2020-09-13 22:10:42.634 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.635 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c2657c, 1)
2020-09-13 22:10:42.636 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +796us
2020-09-13 22:10:42.636 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25ef4, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f40, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f8c, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25fd8, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26024, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26070, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c260bc, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26108, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26284, 1)
2020-09-13 22:10:42.639 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c266ac, 1)
2020-09-13 22:10:42.640 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.641 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.641 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.641 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.641 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.641 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.642 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +197us
2020-09-13 22:10:42.642 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.644 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.644 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.644 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.645 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.646 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c262d0, 1)
2020-09-13 22:10:42.646 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.648 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.648 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.649 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c261a0, 1)
2020-09-13 22:10:42.649 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26530, 1)
2020-09-13 22:10:42.649 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26614, 1)
2020-09-13 22:10:42.649 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.649 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.650 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c26284, 1)
2020-09-13 22:10:42.650 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c263b4, 1)
2020-09-13 22:10:42.651 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> user_main (272->276, pc: 08000000->0891e1e8, thread delay finished) +1010us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocctlGetPeerInfo(28:b5:ff:ca:1f:ef, 152, 09fff228) at 08930ab4
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] sceNetAdhocPdpRecv(1, 0x7609fff222, 0x7609fff220, 0x760905a900, 0x7609fff2c0, 0, 1) at 08930b50
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCENET] Socket Error (11) on sceNetAdhocPdpRecv[1:4330] [size=1444]
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] 0=sceKernelDelayThreadCB(000003e8): delaying 1010 usecs
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: user_main -> idle0 (276->272, pc: 0891e1e8->08000000, thread delayed) +17us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a041a8, audio drain) +129us
2020-09-13 22:10:42.773 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: FUSION THREAD 0x8934338 -> idle0 (322->272, pc: 08a02b98->08000000, sas core) +5us
2020-09-13 22:10:42.774 13509-13531/? I/PPSSPP: [SCEKERNEL] Context switch: idle0 -> FUSION THREAD 0x8934338 (272->322, pc: 08000000->08a02b98, woke from sas mix) +173us
2020-09-13 22:10:42.774 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25ef4, 1)
2020-09-13 22:10:42.774 13509-13531/? I/PPSSPP: [SCEKERNEL] sceKernelTryLockLwMutex(08c25f40, 1)

Looks like sceKernelDelayThreadCB on Android is always 1ms while on Windows it's 10ms ... i wonder who set this value? and why it's different on different platform Edit: nevermind, i'm also seeing 1ms delay at some point on Windows

anr2me commented 4 years ago

@Lemoncak3 could you test this game again later after PR https://github.com/hrydgard/ppsspp/pull/13432 gets merged

Lemoncak3 commented 4 years ago

Still have disconnection issue, don't know why even though strikeforce worked now. Screenshot_20200915-134635 Did your fix worked for warrior orochi too @ANR2ME ?

anr2me commented 4 years ago

Ah you're right, i guess i was just lucky to get it to work yesterday :(

And, no, Warriors Orochi 2 is not affected by that PR, because that game is already running at 100% .. it's just that the FPS/VPS were too low (4/4 FPS/VPS) for some reason. Enabling Debug Logs on Kernel channel on WO2 is also absurdly way to slow (almost freeze) which is abnormal, because other games doesn't have that much performance impact related to Kernel's Debug Logs, so that game might be doing something abnormal that triggers the Kernel to print the logs waaay too much (which probably waste too much time on kernel stuff)

anr2me commented 4 years ago

@Lemoncak3 could you try the test build on PR https://github.com/hrydgard/ppsspp/pull/13519 to see if that PR really resolved this issue?

anr2me commented 2 years ago

The issue with Lego Indiana Jones 2, at the time multiplayer mission started it send 1 datagram packet and then waited for a reply for a short time, if it didn't get the reply the game will show that connection error dialog, if it managed to get the reply it will close the socket and multiplayer game progressed. Meanwhile the other player will wait for this 1 datagram packet and then send a reply and close the socket, and multiplayer game progressed, otherwise will shows connection error dialog when didn't receive the packet after a short while.

There are 2 possibilities for the game not to get the packet, either the packet was lost on the way (since datagram is not guaranteed to be delivered successfully, and the game didn't try to send again), or the receiver wasn't ready to listen on the port yet (ie. if the sender were progressing faster than the receiver) thus unable to receive the packet.

Using an alternative slow speed on the sender might helped making sure the receiver end to be ready to listen on the port.