Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Monster Hunter Freedom - multiplayer broke for some people #17969

Closed
hrydgard opened this issue Aug 24, 2023 · 36 comments · Fixed by #17982
Closed

Monster Hunter Freedom - multiplayer broke for some people #17969

hrydgard opened this issue Aug 24, 2023 · 36 comments · Fixed by #17982

Comments

@hrydgard
Copy link
Owner

hrydgard commented Aug 24, 2023

I got a report on the RetroAchievements discord that multiplayer broke for them in this game recently - after the introduction of RetroAchievements.

"Me and (other user) use Radmin to play multi, when we tried to upgrade the build it simply stopped working, no clue why"

Asking the user to try some builds now.

Apparently it broke between -581 and -598, pointing to #17744. That's weird though because that's the HTTPS upgrade, and it shouldn't be very relevant to multiplayer...

They are playing on Windows.

@hrydgard hrydgard added this to the v1.16.0 milestone Aug 24, 2023
@matheussanjour
Copy link

matheussanjour commented Aug 24, 2023

Me and my friend (user: Brolly2000) were trying to narrow down the exact version where multiplayer stopped working for us. It took some time but we were able to pinpoint the exact version after numerous tries. The last working build is version v1.15.4-581-g30862d075 and it stops working at version v1.15.4-598-gd4c0d1da6. We can actually get in the lobby together and interact, but as soon as we enter any quest with any given host we are facing a long loading screen and eventually a full disconnect . Also, we've also tried the latest build and we still got the same disconnect error.
image

@hrydgard
Copy link
Owner Author

Thank you for testing!

What do you mean with "Also, we've also tried the earliest build and we still got the same disconnect error"? Earliest build with retroachievment support, or which earliest?

@matheussanjour
Copy link

Sorry. The latest PPSSPP nightly build.

@hrydgard
Copy link
Owner Author

hrydgard commented Aug 24, 2023

It's really hard to understand how that change broke anything - it just adds support for HTTPS requests via native Windows APIs through a wrapper called naett. It doesn't touch the multiplayer code.

So maybe it's something global - like, now we link to winhttp.lib, how does that affect things? naett doesn't seem to do any kind of special initialization though.

I suppose I could add a .ini option or something to disable HTTPS requests, and then we can see if that somehow has an effect. I'll do that tomorrow today.

hrydgard added a commit that referenced this issue Aug 24, 2023
Simply set:

```ini
[General]
DisableHTTPS = True
```

Added to help debug issue #17969
@anr2me
Copy link
Collaborator

anr2me commented Aug 24, 2023

Does communication through naett could affect the timing? like blocking syscalls from returning or triggering another PSP thread to run while the network thread was waiting for packet until timeout may be.

As some games might be sensitive to timings, not sure about MH games tho, but there was a game that have issue because another PSP thread kicks in while the game's network thread was waiting for something, and at the time it switched back to the network thread (which took about 500ms), the timeout already reached. It happened because we don't have an accurate timings on networking, thus causing those threads to overlap at critical time (that other thread seems to have an interval of 1 second, thus kicks in every second), meanwhile on JPCSP LLE those threads never overlap.

@hrydgard
Copy link
Owner Author

But there shouldn't be any naett calls during gameplay when retroachievements are off at least, that's what confuses me the most...

@hrydgard
Copy link
Owner Author

Confirmed now that turning off the HTTPS/naett stuff actually does fix the problem! So it's not some change that snuck into that PR, it's actually directly affected by the HTTPS requests somehow.

I don't understand that at all though...

Think I'm going to add some additional logging to see if any unexpected HTTP requests happen..

@hrydgard
Copy link
Owner Author

hrydgard commented Aug 25, 2023

@matheussanjour Can you try the following, in a fresh build (after #17978, so -1217 or newer ):

  • Go to Settings/Tools/Developer Tools/Logging Channels
  • Select Log Level = Warning at the top of the screen
  • For HTTP, SCENET and ACHIEVEMENTS specifically, set Log Level = Info

Then open the debug console from the window menu, where you can see logging, and try to reproduce the problem again.

Copy any interesting-looking HTTP lines from the log that happens just before or near the hang, and paste them in an comment here on this thead.

Thanks!

@anr2me
Copy link
Collaborator

anr2me commented Aug 25, 2023

Confirmed now that turning off the HTTPS/naett stuff actually does fix the problem! So it's not some change that snuck into that PR, it's actually directly affected by the HTTPS requests somehow.

I don't understand that at all though...

Think I'm going to add some additional logging to see if any unexpected HTTP requests happen..

That is strange indeed.
I also wondered whether this issue only occurred when using VPN, specifically Radmin VPN, as people at HunsterVerse are using OpenVPN as i remembered.

@matheussanjour
Copy link

11:10:652 user_main I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) requested information on 1 ULUS10391 groups
11:11:655 user_main I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) joined ULUS10391 group MHP2Q000
11:11:659 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 139 Bytes of Data from Adhoc Server
11:11:659 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][26.201.xx.252][Seraph]
11:59:299 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
12:00:616 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:00:652 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:00:652 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:00:652 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:01:686 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:01:718 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:01:718 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:01:718 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:02:753 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:02:787 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:02:787 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:02:787 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:03:821 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:03:853 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:03:853 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:03:853 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:04:889 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:04:921 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:04:922 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:04:922 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:05:957 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:05:990 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:05:990 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:05:990 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:07:025 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:07:058 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:07:058 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:07:058 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:08:092 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:08:124 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:08:124 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:08:124 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:09:160 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:09:192 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:09:192 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:09:192 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:10:227 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:10:260 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:10:260 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:10:260 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:11:295 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:11:329 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:11:329 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:11:329 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:12:362 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:12:395 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:12:395 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:12:395 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:13:430 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:13:463 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:13:463 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:13:463 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:14:498 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:14:531 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:14:531 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:14:531 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:15:567 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:15:599 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:15:599 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:15:599 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:16:633 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:16:666 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:16:666 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:16:666 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:17:702 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:17:735 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:17:735 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:17:735 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:18:770 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:18:801 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:18:801 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:18:801 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:19:838 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:19:870 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:19:870 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:19:870 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:20:911 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:20:955 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:20:955 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:20:955 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:21:972 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:22:006 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:22:006 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:22:006 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:23:040 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:23:072 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:23:072 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:23:072 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:24:108 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:24:141 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:24:141 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:24:141 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:25:176 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:25:209 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:25:209 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:25:209 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:26:243 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:26:276 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:26:276 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:26:276 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:27:311 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:27:344 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:27:344 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:27:344 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:28:378 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:28:413 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:28:413 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:28:413 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:29:446 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:29:479 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:29:479 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:29:479 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:30:513 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:30:547 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:30:547 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:30:547 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:31:582 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:31:615 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:31:615 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:31:615 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:32:650 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:32:682 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:32:682 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:32:682 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:33:717 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:33:751 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:33:751 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:33:751 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:34:785 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:34:817 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:34:817 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:34:817 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:35:854 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:35:886 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:35:886 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:35:886 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:36:920 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:36:954 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:36:954 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:36:954 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:37:989 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:38:021 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: https://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
12:38:025 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:38:025 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:38:025 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:39:055 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:39:088 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:39:088 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:39:089 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:40:125 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:40:157 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:40:158 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:40:158 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:41:192 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:41:224 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:41:224 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:41:224 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:42:258 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:42:292 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:42:292 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:42:292 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:43:326 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:43:360 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:43:360 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:43:360 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:44:395 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:44:429 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:44:429 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:44:429 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:45:463 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:45:495 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:45:495 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:45:495 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:46:529 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:46:563 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:46:563 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:46:563 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:47:597 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:47:631 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:47:631 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:47:631 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:48:666 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:48:698 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:48:699 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:48:699 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:49:734 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:49:766 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:49:766 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:49:766 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:50:800 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:50:835 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:50:835 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:50:835 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:51:868 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:51:901 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:51:901 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:51:901 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:52:935 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:52:969 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:52:969 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:52:969 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:54:004 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:54:037 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:54:037 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:54:037 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:55:072 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:55:106 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:55:106 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:55:106 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:56:140 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:56:173 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:56:173 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:56:173 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:57:207 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:57:241 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:57:241 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:57:241 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:58:276 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:58:309 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:58:309 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:58:309 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
12:59:343 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
12:59:376 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3896 sceNetAdhocPtpConnect(2, 0, 1) at 088aa638
12:59:376 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:3812 sceNetAdhocPtpConnect[2]: Socket Error (10022) to 26.201.xx.252:20001
12:59:376 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
13:00:410 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3375 sceNetAdhocPtpOpen(98:ce:a2:89:9b:42, 0, b4:8f:14:78:e1:99, 20001, 8192, 250000, 100, 0) at 088aa500
13:00:476 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
13:00:476 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 20001, 8192, 0) at 088aaf10
13:04:116 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 00097000): atrac3 stereo audio
13:51:632 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) left ULUS10391 group MHP2Q000
13:51:638 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 5 Bytes of Data from Adhoc Server
13:51:638 idle0 I[SCENET]: HLE\proAdhoc.cpp:1658 FriendFinder: Incoming Peer Data Delete Request...
13:51:638 idle0 I[SCENET]: HLE\proAdhoc.cpp:488 Removing Friend Peer b4:8f:14:78:e1:99 [26.201.xx.252]
13:51:643 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) stopped playing ULUS10391

@matheussanjour
Copy link

matheussanjour commented Aug 25, 2023

Here's the log from the moment my friend Seraph entered lobby till he left the game after we got the disconnect error. I tried looking for any HTTP line as requested by I didn't find any notable one. Only those marked by this SCENET thing. It does mention a socket error though, maybe that's relevant somehow

@hrydgard
Copy link
Owner Author

hrydgard commented Aug 25, 2023

Maybe we can find something by comparing a log from when it's working (HTTPDisabled = true) ?

Though 10022 is just "invalid argument" which really doesn't say much...

Here's where the error is reported from, just a connect... https://github.com/hrydgard/ppsspp/blob/master/Core/HLE/sceNetAdhoc.cpp#L3812

@hrydgard
Copy link
Owner Author

Do you see anything with [HTTP] at all, from the start of the process?

@matheussanjour
Copy link

22:28:614 Core\Config.cpp:1193 I[LOADER]: Loading controller config: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/controls.ini
22:28:615 Core\Config.cpp:1209 I[LOADER]: Config loaded: 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
22:28:866 Vulkan\VulkanLoader.cpp:317 I[G3D]: Forcing Vulkan availability to true
22:28:961 Thread\ThreadManager.cpp:225 I[SYSTEM]: ThreadManager::Init(compute threads: 4, all: 8)
22:28:985 Core\Config.cpp:1291 I[LOADER]: Config saved (got storage permission): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
22:28:986 Core\Config.cpp:1304 I[LOADER]: Controller config saved: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/controls.ini
22:28:986 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Using host: http://retroachievements.org
22:28:986 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Attempting to log in Siegfried5270 (with token)
22:28:986 Net\HTTPRequest.cpp:13 I[HTTP]: HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
22:28:986 Core\RetroAchievements.cpp:418 I[ACHIEVEMENTS]: Achievements initialized.
22:28:988 Vulkan\VulkanLoader.cpp:527 I[G3D]: VulkanLoad: Base functions loaded.
22:29:167 Vulkan\VulkanLoader.cpp:603 I[G3D]: Vulkan instance functions loaded.
22:29:169 Vulkan\VulkanContext.cpp:539 I[G3D]: Chose physical device 0: NVIDIA GeForce GTX 1050 Ti
22:29:169 Vulkan\VulkanContext.cpp:581 I[G3D]: Memory Types (11):
22:29:169 Vulkan\VulkanContext.cpp:590 I[G3D]: 7: Heap 0; Flags: DEVICE_LOCAL
22:29:169 Vulkan\VulkanContext.cpp:590 I[G3D]: 8: Heap 1; Flags: HOST_VISIBLE HOST_COHERENT
22:29:169 Vulkan\VulkanContext.cpp:590 I[G3D]: 9: Heap 1; Flags: HOST_VISIBLE HOST_CACHED HOST_COHERENT
22:29:169 Vulkan\VulkanContext.cpp:590 I[G3D]: 10: Heap 2; Flags: DEVICE_LOCAL HOST_VISIBLE HOST_COHERENT
22:29:295 Vulkan\VulkanLoader.cpp:610 I[G3D]: Vulkan device functions loaded.
22:29:295 Vulkan\VulkanContext.cpp:743 I[G3D]: Vulkan Device created: NVIDIA GeForce GTX 1050 Ti
22:29:295 Vulkan\VulkanLoader.cpp:317 I[G3D]: Forcing Vulkan availability to true
22:29:295 Vulkan\VulkanContext.cpp:845 I[G3D]: Creating Vulkan surface for window (00007FF701710000 000000000010065E)
22:29:301 Vulkan\VulkanContext.cpp:1206 I[G3D]: swapchain_format: 44 (/3)
22:29:301 Vulkan\VulkanContext.cpp:1274 I[G3D]: surfCapabilities_.current: 1460x928 min: 1460x928 max: 1460x928 computed: 1460x928
22:29:301 Vulkan\VulkanContext.cpp:1289 I[G3D]: Supported present modes: FIFO, FIFO_RELAXED, MAILBOX, IMMEDIATE
22:29:301 Vulkan\VulkanContext.cpp:1319 I[G3D]: Chosen present mode: 1 (MAILBOX). numSwapChainImages: 3/8
22:29:301 Vulkan\VulkanContext.cpp:1364 I[G3D]: Transform supported: IDENTITY current: IDENTITY chosen: IDENTITY
22:29:313 Vulkan\VulkanContext.cpp:1427 I[G3D]: Created swapchain: 1460x928
22:29:313 Vulkan\VulkanQueueRunner.cpp:47 I[G3D]: VulkanQueueRunner::CreateDeviceObjects
22:29:470 Vulkan\VulkanRenderManager.cpp:305 I[G3D]: Starting Vulkan submission thread
22:29:470 Vulkan\VulkanRenderManager.cpp:308 I[G3D]: Starting Vulkan compiler thread
22:29:471 UI\NativeApp.cpp:828 I[SYSTEM]: NativeInitGraphics
22:29:472 Vulkan\VulkanRenderManager.cpp:135 I[G3D]: Pipeline (x/2) time on PoolWorker 0: 0.74 ms, 0.76 ms since scheduling rpType: 0008 sampleBits: 1 (global_texcolor)
22:29:472 Vulkan\VulkanRenderManager.cpp:135 I[G3D]: Pipeline (x/2) time on PoolWorker 1: 0.74 ms, 0.76 ms since scheduling rpType: 0008 sampleBits: 1 (global_color)
22:29:473 UI\NativeApp.cpp:890 I[SYSTEM]: NativeInitGraphics completed
22:29:473 UI\DiscordIntegration.cpp:60 I[SYSTEM]: Discord connection initialized
22:29:482 Windows\WASAPIStream.cpp:57 I[SCEAUDIO]: Switching to WASAPI audio device: 'T27B350 (NVIDIA High Definition Audio)'
22:29:485 UI\NativeApp.cpp:1151 I[G3D]: Resized flag set - recalculating bounds
22:29:487 Vulkan\VulkanRenderManager.cpp:349 I[G3D]: Vulkan submission thread joined. Frame=1
22:29:487 Vulkan\VulkanRenderManager.cpp:357 I[G3D]: Vulkan compiler thread joined.
22:29:487 Vulkan\VulkanQueueRunner.cpp:247 I[G3D]: Backbuffers destroyed
22:29:488 Vulkan\VulkanContext.cpp:1274 I[G3D]: surfCapabilities_.current: 1460x928 min: 1460x928 max: 1460x928 computed: 1460x928
22:29:488 Vulkan\VulkanContext.cpp:1289 I[G3D]: Supported present modes: FIFO, FIFO_RELAXED, MAILBOX, IMMEDIATE
22:29:488 Vulkan\VulkanContext.cpp:1319 I[G3D]: Chosen present mode: 1 (MAILBOX). numSwapChainImages: 3/8
22:29:488 Vulkan\VulkanContext.cpp:1364 I[G3D]: Transform supported: IDENTITY current: IDENTITY chosen: IDENTITY
22:29:492 Vulkan\VulkanContext.cpp:1427 I[G3D]: Created swapchain: 1460x928
22:29:492 Vulkan\VulkanRenderManager.cpp:293 I[G3D]: Updating inflight frames to 3
22:29:493 Vulkan\VulkanRenderManager.cpp:305 I[G3D]: Starting Vulkan submission thread
22:29:493 Vulkan\VulkanRenderManager.cpp:308 I[G3D]: Starting Vulkan compiler thread
22:29:493 UI\Screen.cpp:147 I[SYSTEM]: ScreenManager::resized(dp: 1460x928)
22:29:643 Core\Config.cpp:1291 I[LOADER]: Config saved (clearFailedGPUBackends): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
22:29:644 Core\Config.cpp:1304 I[LOADER]: Controller config saved: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/controls.ini
22:30:516 Windows\WASAPIStream.cpp:142 I[SCEAUDIO]: Changed audio device property {9855c4cd-df8c-449c-a181-8191b68bd06c}#0
22:31:444 Net\HTTPClient.cpp:557 I[HTTP]: Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
22:31:450 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Siegfried5270 logged in successfully
22:31:450 Core\RetroAchievements.cpp:349 I[ACHIEVEMENTS]: Successful login by token.
22:32:963 Core\Config.cpp:1291 I[LOADER]: Config saved (MainScreen::OnGameSelectedInstant): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
22:32:964 Core\Config.cpp:1304 I[LOADER]: Controller config saved: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/controls.ini
22:32:981 Core\Config.cpp:1291 I[LOADER]: Config saved (changeGameSpecific): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
22:32:983 Core\Config.cpp:1304 I[LOADER]: Controller config saved: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/controls.ini
22:32:994 Core\System.cpp:426 N[BOOT]: PPSSPP v1.15.4-1217-g564c4b38b Windows 64 bit
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Encore mode disabled
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Unofficial disabled
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Identifying game: C:/Games/Retroarch/PSP/Monster Hunter Freedom Unite (Europe) (En,Fr,De,Es,It).iso
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Opened Monster Hunter Freedom Unite (Europe) (En,Fr,De,Es,It).iso
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Found PSP_GAME at sector 23
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Found PARAM.SFO at sector 54240
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Hashing PSP_GAME\PARAM.SFO contents (472 bytes @ sector 54240)
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Found PSP_GAME at sector 23
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Found SYSDIR at sector 24
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Found EBOOT.BIN at sector 32
22:32:995 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Hashing PSP_GAME\SYSDIR\EBOOT.BIN contents (1910384 bytes @ sector 32)
22:33:002 Core\RetroAchievements.cpp:208 I[ACHIEVEMENTS]: RetroAchievements: Generated hash 294a4c5028d20fa3f93b2593626235a8
22:33:002 Net\HTTPRequest.cpp:13 I[HTTP]: HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
22:33:004 Core\Compatibility.cpp:146 N[G3D]: UnitsPerMeter for ULES01213: 100.000000
22:33:004 Core\MemMap.cpp:313 I[MEMMAP]: Memory system initialized. Base at 0000021F5B3D0000 (RAM at @ 00007FF702661088, uncached @ 00007FF7026610A0)
22:33:005 Core\PSPLoaders.cpp:249 I[LOADER]: ULES01213 : MONSTER HUNTER FREEDOM UNITE™
22:33:021 Core\Config.cpp:1291 I[LOADER]: Config saved (changeGameSpecific): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
22:33:032 Core\PSPLoaders.cpp:309 I[LOADER]: Loading disc0:/PSP_GAME/SYSDIR/EBOOT.BIN...
22:33:032 HLE\sceKernel.cpp:109 I[SCEKERNEL]: Initializing kernel...
22:33:038 HLE\sceKernelMemory.cpp:440 I[SCEKERNEL]: Kernel and user memory pools initialized
22:33:039 Common\ExceptionHandlerSetup.cpp:84 I[SYSTEM]: Installing exception handler
22:33:046 HLE\sceNet.cpp:213 I[SCENET]: LocalHost IP will be 127.0.0.1 [98:ce:a2:89:9b:42]
22:33:046 HLE\proAdhocServer.cpp:1683 I[SCENET]: AdhocServer: Begin of AdhocServer Thread
22:33:046 Util\PortManager.cpp:464 I[SCENET]: UPnPService: Begin of UPnPService Thread
22:33:046 HLE\proAdhocServer.cpp:1698 I[SCENET]: AdhocServer: Listening for Connections on TCP Port 27312
22:33:060 Util\PPGeDraw.cpp:308 I[SCEGE]: PPGe drawing library initialized. DL: 08000400 Data: 08010400 Atlas: 08020d00 (2097152) Args: 08020400
22:33:060 HLE\sceKernel.cpp:161 I[SCEKERNEL]: Kernel initialized.
22:33:062 ELF\PrxDecrypter.cpp:701 I[LOADER]: Decrypting tag C0CB167C
22:33:062 ELF\PrxDecrypter.cpp:755 I[LOADER]: Decrypting tag C0CB167C
22:33:080 HLE\sceKernelModule.cpp:1384 I[LOADER]: Module MonsterHunterPortable2ndG: 089d3a80 0890e6bc 0890e6d4
22:33:096 HLE\ReplaceTables.cpp:1643 I[HLE]: Replaced memcpy at 0880c084 with hash 9e6ce11f9d49f954
22:33:096 HLE\ReplaceTables.cpp:1643 I[HLE]: Replaced memmove at 0880c1a8 with hash d019b067b58cf6c3
22:33:096 HLE\ReplaceTables.cpp:1643 I[HLE]: Replaced memset at 0880c464 with hash fe5dd338ab862291
22:33:096 HLE\sceKernelModule.cpp:1503 I[LOADER]: Exporting ent 0 named MonsterHunterPortable2ndG, 2 funcs, 4 vars, resident 0890ec1c
22:33:096 HLE\sceKernelModule.cpp:1852 I[LOADER]: Module entry: 08906934 (MonsterHunterPortable2ndG 0101)
22:33:564 root I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
22:33:566 root I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:208 RetroAchievements: Identified game: 3732 (294a4c5028d20fa3f93b2593626235a8)
22:33:566 root I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:208 RetroAchievements: Fetching data for game 3732
22:33:566 root I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
22:34:250 root I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
22:34:265 root I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:208 RetroAchievements: Starting session for game 3732
22:34:265 root I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
22:34:265 root I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
22:34:265 root I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
22:34:803 root I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
22:34:806 root I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
22:34:989 root I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
22:34:994 root I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:208 RetroAchievements: 179/179 memory addresses valid
22:34:994 root I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:208 RetroAchievements: Game 3732 loaded, hardcode enabled
22:34:994 root N[ACHIEVEMENTS]: Core\RetroAchievements.cpp:648 Load callback: 0 ((null))
22:34:995 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 0.12 ms, 0.13 ms since scheduling rpType: 0008 sampleBits: 1 (presentation)
22:34:995 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 0.13 ms, 0.14 ms since scheduling rpType: 0008 sampleBits: 1 (presentation)
22:35:019 root I[G3D]: Vulkan\GPU_Vulkan.cpp:395 GPU_Vulkan::InitDeviceObjects
22:35:021 root N[G3D]: Vulkan\ShaderManagerVulkan.cpp:654 ShaderCache: Loaded 6 vertex, 20 fragment shaders and 0 geometry shaders (failed 0)
22:35:021 root N[G3D]: Vulkan\PipelineManagerVulkan.cpp:762 Creating 95 pipelines from cache (1x MSAA)...
22:35:022 root N[G3D]: Vulkan\PipelineManagerVulkan.cpp:807 Recreated Vulkan pipeline cache (95 pipelines, 0 failed).
22:35:022 root I[G3D]: Vulkan\GPU_Vulkan.cpp:156 Loaded Vulkan pipeline cache.
22:35:032 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 0: 5.72 ms, 10.46 ms since scheduling rpType: 0000 sampleBits: 1 (game)
22:35:033 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 0: 1.48 ms, 11.95 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:036 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 3.20 ms, 15.19 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:036 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 3: 8.10 ms, 15.19 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:040 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 1: 4.59 ms, 18.25 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:040 root I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 2: 2.68 ms, 18.28 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:041 root N[BOOT]: UI\EmuScreen.cpp:370 Booted C:/Games/Retroarch/PSP/Monster Hunter Freedom Unite (Europe) (En,Fr,De,Es,It).iso...
22:35:041 root I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 276=sceKernelCreateThread(user_main, 08906a48, 00000020, 32768, 80000000, 00000000)
22:35:041 root I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(276, 33, 09fffed0)
22:35:042 user_main W[JIT]: x86\Jit.cpp:325 Detected rounding mode usage, rebuilding jit with checks
22:35:043 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 6.00 ms, 21.22 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:043 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 6.00 ms, 21.21 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:043 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 3: 0.60 ms, 21.84 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:043 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 3.59 ms, 21.89 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:045 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 3: 2.10 ms, 23.95 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:045 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 5.78 ms, 24.05 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:048 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 279=sceKernelCreateThread(priorityChanger, 08890494, 00000028, 512, 00000000, 00000000)
22:35:048 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(279, 4, 08a631a0)
22:35:048 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 280=sceKernelCreateThread(CheckExitGame, 08890020, 0000006f, 4096, 00000000, 00000000)
22:35:048 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(280, 0, 00000000)
22:35:049 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 0: 6.37 ms, 27.60 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:049 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 3.63 ms, 27.60 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:049 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 5.70 ms, 27.60 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:050 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 4.14 ms, 28.21 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:051 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 1.88 ms, 29.48 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:051 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 1.88 ms, 29.50 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:051 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 2.35 ms, 30.01 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:053 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 3.37 ms, 31.51 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:053 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 2.09 ms, 31.50 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:053 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 2.06 ms, 31.50 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:053 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 1.61 ms, 31.62 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:056 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 3.06 ms, 34.67 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:056 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 3.18 ms, 34.68 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:056 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 3.20 ms, 34.71 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:057 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 4.02 ms, 35.52 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:058 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 1.95 ms, 36.65 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:058 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 1.95 ms, 36.65 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:058 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 1.80 ms, 36.64 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:058 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 1.24 ms, 36.74 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:059 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 2: 1.31 ms, 37.93 ms since scheduling rpType: 0000 sampleBits: 1 (game)
22:35:059 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 0: 1.31 ms, 37.94 ms since scheduling rpType: 0000 sampleBits: 1 (game)
22:35:059 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 1.21 ms, 37.93 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:060 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 1.39 ms, 38.01 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:061 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 1: 1.73 ms, 39.71 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:061 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 1.78 ms, 39.72 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:062 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 2.68 ms, 40.68 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:062 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 2.70 ms, 40.70 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:062 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 1: 0.97 ms, 40.69 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:062 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 1.03 ms, 40.73 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:064 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 1.97 ms, 42.58 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:064 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 2.00 ms, 42.59 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:064 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 1: 1.97 ms, 42.56 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:064 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 2: 1.90 ms, 42.65 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:066 user_main W[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Creating 565 FBO at 04000000/00000000 stride=0 4x272 (force=1)
22:35:067 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 0: 2.65 ms, 45.22 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:067 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/1) time on PoolWorker 3: 2.57 ms, 45.20 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:067 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 1: 2.53 ms, 45.17 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:067 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 2: 2.67 ms, 45.31 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:068 user_main I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:5 Creating FBO for 04000000 (z: 00000000) : 4 x 272 x 565
22:35:068 user_main W[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Creating 5551 FBO at 04044000/04088000 stride=512 480x272 (force=1)
22:35:069 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 2: 2.24 ms, 48.01 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:069 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/2) time on PoolWorker 1: 2.31 ms, 48.05 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:069 user_main I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:5 Creating FBO for 04044000 (z: 04088000) : 480 x 272 x 5551
22:35:070 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 284=sceKernelCreateThread(fakeRofsLoader, 0884de3c, 00000030, 4096, 00000000, 00000000)
22:35:070 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(284, 4, 09fff66c)
22:35:070 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 286=sceKernelCreateThread(sha1Thread, 0884e5b4, 00000031, 4096, 00000000, 00000000)
22:35:070 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(286, 4, 09fff66c)
22:35:070 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 288=sceKernelCreateThread(transferThread, 0884e678, 00000013, 4096, 00000000, 00000000)
22:35:070 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(288, 4, 09fff66c)
22:35:071 user_main W[LOADER]: HLE\sceKernelModule.cpp:2516 sceKernelLoadModuleByID: unsupported options size=00000014, flags=00000000, pos=0, access=1, data=2, text=2
22:35:071 user_main I[G3D]: Vulkan\VulkanRenderManager.cpp:135 Pipeline (x/3) time on PoolWorker 3: 3.96 ms, 49.69 ms since scheduling rpType: 0001 sampleBits: 1 (game)
22:35:071 user_main I[SCEMODULE]: HLE\sceKernelModule.cpp:1166 ~SCE module, skipping header
22:35:071 user_main I[SCEMODULE]: HLE\sceKernelModule.cpp:1182 Loading module sceFont_Library with version 0101, devkit 03070010, crc fc5cc933
22:35:071 user_main I[LOADER]: ELF\PrxDecrypter.cpp:701 Decrypting tag 457B06F0
22:35:071 user_main I[LOADER]: ELF\PrxDecrypter.cpp:755 Decrypting tag 457B06F0
22:35:071 user_main I[LOADER]: ELF\PrxDecrypter.cpp:810 Decrypting tag 457B06F0
22:35:071 user_main I[SCEMODULE]: HLE\sceKernelModule.cpp:2554 290=sceKernelLoadModuleByID(5,flag=00000000,00000014,00000002,00000002,position = 00000000)
22:35:071 user_main I[SCEMODULE]: HLE\sceKernelModule.cpp:2171 sceKernelStartModule(290,asize=00000000,aptr=00000000,retptr=09fff658,00000000): faked (undecryptable module)
22:35:071 user_main I[SCEUTIL]: HLE\sceUtility.cpp:541 0=sceUtilityLoadModule(00000100)
22:35:100 user_main I[SCEUTIL]: HLE\sceUtility.cpp:541 0=sceUtilityLoadModule(00000101)
22:35:117 user_main E[FILESYS]: FileSystems\DirectoryFileSystem.cpp:58 DirectoryFileSystem::OpenFile('/font/zh_gb.pgf'): FAILED, 2 - access = 1 'O 'istema não pode encontrar o arquivo especificado.
22:35:133 user_main I[SCEFONT]: HLE\sceFont.cpp:1007 sceFontNewLib(089ad380, 09fff65c)
22:35:150 user_main I[SCEFONT]: HLE\sceFont.cpp:775 Entering PostAllocCallback::run
22:35:150 user_main I[SCEFONT]: HLE\sceFont.cpp:789 Leaving PostAllocCallback::run
22:35:150 user_main I[SCEFONT]: HLE\sceFont.cpp:1221 00000000=sceFontFindOptimumFont(08a63220, 09fff5b4, 09fff65c):
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_sin_lut8192.dat'...
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_sin_lut8192.dat'
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_sin_lut_delta.dat'...
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_sin_lut_delta.dat'
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_sin_lut_interval_delta.dat'...
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_sin_lut_interval_delta.dat'
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_sin_lut_exceptions.dat'...
22:35:167 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_sin_lut_exceptions.dat'
22:35:169 user_main W[HLE]: HLE\sceOpenPSID.cpp:40 UNTESTED sceOpenPSIDGetOpenPSID(09fff650)
22:35:169 user_main W[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Creating 5551 FBO at 04000000/04088000 stride=512 480x272 (force=1)
22:35:172 user_main I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:5 Creating FBO for 04000000 (z: 04088000) : 480 x 272 x 5551
22:35:184 idle0 I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Decimating FBO for 04000000 (4x272 565), age 6
22:35:184 idle0 I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Decimating FBO for 04044000 (480x272 5551), age 6
22:35:184 idle0 I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Decimating FBO for 04044000 (480x272 5551), age 6
22:35:184 user_main W[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Creating 5551 FBO at 04044000/04088000 stride=512 480x272 (force=1)
22:35:186 user_main I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:5 Creating FBO for 04044000 (z: 04088000) : 480 x 272 x 5551
22:35:334 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 294=sceKernelCreateThread(sndPrxLoader, 088805a0, 00000010, 4096, 00000000, 00000000)
22:35:334 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(294, 4, 09fff61c)
22:35:334 sndPrxLoader I[SCEUTIL]: HLE\sceUtility.cpp:541 0=sceUtilityLoadModule(00000300)
22:35:368 idle0 I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Decimating FBO for 04000000 (480x272 5551), age 6
22:35:368 sndPrxLoader I[SCEUTIL]: HLE\sceUtility.cpp:541 0=sceUtilityLoadModule(00000301)
22:35:400 idle0 I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Decimating FBO for 04044000 (480x272 5551), age 6
22:35:400 sndPrxLoader I[SCEUTIL]: HLE\sceUtility.cpp:541 0=sceUtilityLoadModule(00000302)
22:35:400 sndPrxLoader I[SCEKERNEL]: HLE\sceKernelThread.cpp:2192 sceKernelExitDeleteThread(0)
22:35:435 user_main I[SCEIO]: HLE\sceIo.cpp:1182 stdout: PSP===== Capcom Sound Engine for [PSP] Ver1.8 =====
22:35:435 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 298=sceKernelCreateThread(CsePspSndDrvThread, 088a4004, 00000012, 4096, 00000000, 00000000)
22:35:435 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(298, 4, 09fff5dc)
22:35:436 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 299=sceKernelCreateThread(CsePspSasRendThread, 088a356c, 00000011, 4096, 00000000, 00000000)
22:35:436 user_main I[SCESAS]: HLE\sceSas.cpp:247 sceSasInit(08a8b200, 256, 32, 0, 44100)
22:35:436 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(299, 0, 00000000)
22:35:436 user_main I[SCEIO]: HLE\sceIo.cpp:1182 stdout: PSP===== Capcom Sound Engine CORE Ver2.0 =====
22:35:437 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 303=sceKernelCreateThread(CsePspAtrac3DecodeThrd, 088a2498, 00000011, 32768, 00000000, 00000000)
22:35:437 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(303, 4, 09fff5cc)
22:35:437 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 304=sceKernelCreateThread(CsePspAtrac3DecodeThrd, 088a2498, 00000011, 32768, 00000000, 00000000)
22:35:437 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(304, 4, 09fff5cc)
22:36:529 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:122 sceUtilitySavedataInitStart(09a06aa8) - AUTOLOAD (0)
22:36:529 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:123 sceUtilitySavedataInitStart(09a06aa8) : Game key (hex): 4A1FF359AEB6EFF81CA8CB23BCA57BB3
22:36:536 user_main I[SCEUTIL]: Dialog\SavedataParam.cpp:688 Loading file with size 1483008 in ms0:/PSP/SAVEDATA/ULES01213/MHP2NDG.BIN
22:36:556 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 307=sceKernelCreateThread(checkSumThread, 088c3010, 00000035, 4096, 00000000, 00000000)
22:36:556 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(307, 4, 09fff60c)
22:36:618 checkSumThre I[SCEKERNEL]: HLE\sceKernelThread.cpp:2192 sceKernelExitDeleteThread(0)
22:39:579 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:122 sceUtilitySavedataInitStart(09a06aa8) - AUTOLOAD (0)
22:39:579 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:123 sceUtilitySavedataInitStart(09a06aa8) : Game key (hex): 4A1FF359AEB6EFF81CA8CB23BCA57BB3
22:39:585 idle0 I[SCEUTIL]: Dialog\SavedataParam.cpp:688 Loading file with size 1483008 in ms0:/PSP/SAVEDATA/ULES01213/MHP2NDG.BIN
22:39:604 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 310=sceKernelCreateThread(checkSumThread, 088c3010, 00000035, 4096, 00000000, 00000000)
22:39:604 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(310, 4, 09ffe44c)
22:39:666 checkSumThre I[SCEKERNEL]: HLE\sceKernelThread.cpp:2192 sceKernelExitDeleteThread(0)
22:39:730 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_asin_lut65536.dat'...
22:39:731 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_asin_lut65536.dat'
22:39:731 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_asin_lut_indices.dat'...
22:39:731 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_asin_lut_indices.dat'
22:39:731 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_asin_lut_deltas.dat'...
22:39:732 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_asin_lut_deltas.dat'
22:40:686 idle0 I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:292 Progress indicator show: Complete the 8 Star Gathering Hall Quest Log, progress: '18/36' (50.000000)
22:40:874 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b8db40, 00253000): atrac3 stereo audio
22:42:737 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_log2_lut65536.dat'...
22:42:737 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_log2_lut65536.dat'
22:42:737 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_log2_lut65536_quadratic.dat'...
22:42:737 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_log2_lut65536_quadratic.dat'
22:42:737 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_log2_lut.dat'...
22:42:738 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_log2_lut.dat'
22:42:738 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_exp2_lut65536.dat'...
22:42:738 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_exp2_lut65536.dat'
22:42:738 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:826 Loading 'vfpu/vfpu_exp2_lut.dat'...
22:42:738 user_main I[CPU]: MIPS\MIPSVFPUUtils.cpp:835 Successfully loaded 'vfpu/vfpu_exp2_lut.dat'
22:50:581 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
22:50:681 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c3fb40, 00108000): atrac3 stereo audio
22:50:681 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=__sceSasRevType(08a8b200, 65535): invalid type
22:51:744 user_main I[ME]: HLE\sceAtrac.cpp:1975 3=sceAtracSetDataAndGetID(08b72b40, 00011800): atrac3 stereo audio
22:56:462 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(3)
22:56:815 user_main I[ME]: HLE\sceAtrac.cpp:1975 3=sceAtracSetDataAndGetID(08c37b40, 00008000): atrac3 stereo audio
22:56:955 CsePspAtrac3 I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(3)
22:57:289 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
22:57:327 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b8db40, 00253000): atrac3 stereo audio
22:58:255 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
22:58:298 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b76340, 0013f000): atrac3 stereo audio
23:04:018 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
23:04:580 idle0 I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
23:12:944 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
23:12:984 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b8db40, 00253000): atrac3 stereo audio
23:13:883 user_main W[SCENET]: HLE\sceNet.cpp:653 sceNetInit(poolsize=131072, calloutpri=32, calloutstack=0, netintrpri=32, netintrstack=0) at 088ac674
23:13:917 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1293 0=sceNetAdhocInit(): at 088ac720
23:13:950 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1300 sceNetAdhocctlInit(8192, 48, 09a1daf0) at 088ac794
23:13:950 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 311=sceNetAdhocctlInit(8192, 48, 09a1daf0)
23:13:950 idle0 I[SCENET]: HLE\proAdhoc.cpp:1380 FriendFinder: Begin of Friend Finder Thread
23:13:952 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 26.109.xx.243
23:13:961 idle0 I[SCENET]: HLE\proAdhoc.cpp:1413 FriendFinder: Network [RE]Initialized
23:13:963 CsePspAtrac3 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
23:13:963 CsePspAtrac3 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) started playing ULUS10391
23:13:000 user_main W[SCENET]: HLE\sceNetAdhoc.cpp:2457 UNTESTED sceNetAdhocctlAddHandler(88ac0e8, 0): added handler 0
23:14:050 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2276 sceNetAdhocctlScan() at 088ae048
23:14:073 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) requested information on 0 ULUS10391 groups
23:14:082 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 1 Bytes of Data from Adhoc Server
23:14:082 idle0 I[SCENET]: HLE\proAdhoc.cpp:1744 FriendFinder: Incoming Scan complete response...
23:14:250 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2334 sceNetAdhocctlGetScanInfo([09fff59c]=812, 09a28534) at 088b0800
23:14:817 user_main I[SCEUTIL]: HLE\sceUtility.cpp:671 0=sceUtilityNetconfInitStart(09a1db0c)
23:15:020 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2864 sceNetAdhocctlConnect(MHP2Q000) at 088ac4f0
23:15:030 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) joined ULUS10391 group MHP2Q000
23:15:037 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 7 Bytes of Data from Adhoc Server
23:15:037 idle0 I[SCENET]: HLE\proAdhoc.cpp:1498 FriendFinder: Incoming OPCODE_CONNECT_BSSID [98:ce:a2:89:9b:42]
23:17:472 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 26.201.xx.252
23:17:494 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
23:17:494 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) started playing ULUS10391
23:17:604 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) requested information on 1 ULUS10391 groups
23:18:813 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) joined ULUS10391 group MHP2Q000
23:18:824 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 139 Bytes of Data from Adhoc Server
23:18:824 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][26.201.xx.252][Seraph]
23:20:052 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
23:20:973 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 10000, 8192, 0) at 088aaf10
23:24:078 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b84340, 00154000): atrac3 stereo audio
24:20:593 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2248 sceNetAdhocPdpDelete(1, 0) at 088ab04c
24:20:611 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id
24:20:611 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2527 Marked for Timedout Peer List (1)
24:20:611 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2559 00000000=sceNetAdhocctlDisconnect() at 088ac958 [group=MHP2Q000]
24:20:621 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id
24:20:622 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) left ULUS10391 group MHP2Q000
24:20:629 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id
24:20:639 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id
24:20:649 user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id
24:20:686 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2570 sceNetAdhocctlDelHandler(0) at 088ac9e0
24:20:695 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2630 sceNetAdhocctlTerm() at 088aca50
24:20:697 user_main I[SCENET]: HLE\proAdhoc.cpp:1780 FriendFinder: End of Friend Finder Thread
24:20:697 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2603 Cleared Peer List (1)
24:20:699 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) stopped playing ULUS10391
24:20:704 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3067 0=sceNetAdhocTerm()
24:20:715 user_main W[SCENET]: HLE\sceNet.cpp:602 sceNetTerm() at 088acaf8
24:20:743 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
24:20:784 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b8db40, 00253000): atrac3 stereo audio
25:04:009 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
25:04:580 user_main I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
25:10:517 user_main W[SCENET]: HLE\sceNet.cpp:653 sceNetInit(poolsize=131072, calloutpri=32, calloutstack=0, netintrpri=32, netintrstack=0) at 088ac674
25:10:549 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1293 0=sceNetAdhocInit(): at 088ac720
25:10:584 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1300 sceNetAdhocctlInit(8192, 48, 09a1daf0) at 088ac794
25:10:584 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 314=sceNetAdhocctlInit(8192, 48, 09a1daf0)
25:10:584 idle0 I[SCENET]: HLE\proAdhoc.cpp:1380 FriendFinder: Begin of Friend Finder Thread
25:10:592 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 26.109.xx.243
25:10:595 idle0 I[SCENET]: HLE\proAdhoc.cpp:1413 FriendFinder: Network [RE]Initialized
25:10:603 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
25:10:603 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) started playing ULUS10391
25:10:634 user_main W[SCENET]: HLE\sceNetAdhoc.cpp:2457 UNTESTED sceNetAdhocctlAddHandler(88ac0e8, 0): added handler 0
25:10:683 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2276 sceNetAdhocctlScan() at 088ae048
25:10:702 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) requested information on 1 ULUS10391 groups
25:10:703 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 16 Bytes of Data from Adhoc Server
25:10:703 idle0 I[SCENET]: HLE\proAdhoc.cpp:1697 Incoming Group Information...
25:10:714 idle0 I[SCENET]: HLE\proAdhoc.cpp:1744 FriendFinder: Incoming Scan complete response...
25:10:883 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2334 sceNetAdhocctlGetScanInfo([09fff59c]=812, 09a28534) at 088b0800
25:11:185 user_main I[SCEUTIL]: HLE\sceUtility.cpp:671 0=sceUtilityNetconfInitStart(09a1db0c)
25:11:386 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2864 sceNetAdhocctlConnect(MHP2Q000) at 088ac4f0
25:11:405 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) joined ULUS10391 group MHP2Q000
25:11:416 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 146 Bytes of Data from Adhoc Server
25:11:416 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][26.201.xx.252][Seraph]
25:11:427 idle0 I[SCENET]: HLE\proAdhoc.cpp:1498 FriendFinder: Incoming OPCODE_CONNECT_BSSID [b4:8f:14:78:e1:99]
25:16:419 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
25:17:339 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 10000, 8192, 0) at 088aaf10
25:20:444 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b84340, 00154000): atrac3 stereo audio
25:52:832 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
25:52:855 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 20001, 8192, 0) at 088aaf10
25:53:222 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 000cc000): atrac3 stereo audio
25:58:772 user_main W[G3D]: Common\FramebufferManagerCommon.cpp:2496 Block transfer readback 256x272 4bpp from 04000000 (x:0 y:0 stride:256) -> 0962d570 (x:0 y:0 stride:256)
25:58:774 user_main I[ME]: HLE\sceAtrac.cpp:1975 3=sceAtracSetDataAndGetID(08b68b40, 00007800): atrac3 stereo audio
25:59:005 idle0 I[FRAMEBUF]: Common\FramebufferManagerCommon.cpp:1 Decimating FBO for 04000000 (480x272 5551), age 6
25:59:394 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
25:59:394 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b9c340, 00064800): atrac3 stereo audio
25:59:415 CsePspAtrac3 I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(3)
26:01:629 user_main I[ME]: HLE\sceAtrac.cpp:1975 3=sceAtracSetDataAndGetID(08c6f340, 00004800): atrac3 stereo audio
26:01:781 CsePspAtrac3 I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(3)
26:01:856 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=_sceSasRevType(08a8b200, 65535): invalid type
26:03:553 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
26:03:553 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2248 sceNetAdhocPdpDelete(2, 0) at 088ab04c
26:03:670 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:122 sceUtilitySavedataInitStart(09a06aa8) - AUTOLOAD (0)
26:03:670 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:123 sceUtilitySavedataInitStart(09a06aa8) : Game key (hex): 4A1FF359AEB6EFF81CA8CB23BCA57BB3
26:03:695 idle0 I[SCEUTIL]: Dialog\SavedataParam.cpp:688 Loading file with size 1483008 in ms0:/PSP/SAVEDATA/ULES01213/MHP2NDG.BIN
26:03:714 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 319=sceKernelCreateThread(checkSumThread, 088c3010, 00000035, 4096, 00000000, 00000000)
26:03:714 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(319, 4, 09fff60c)
26:03:780 checkSumThre I[SCEKERNEL]: HLE\sceKernelThread.cpp:2192 sceKernelExitDeleteThread(0)
26:03:781 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 320=sceKernelCreateThread(calcSumThread, 088c2fd8, 00000035, 4096, 00000000, 00000000)
26:03:781 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2101 0=sceKernelStartThread(320, 4, 09fff60c)
26:03:846 calcSumThrea I[SCEKERNEL]: HLE\sceKernelThread.cpp:2192 sceKernelExitDeleteThread(0)
26:03:847 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:122 sceUtilitySavedataInitStart(09a06aa8) - AUTOSAVE (1)
26:03:847 user_main I[SCEUTIL]: Dialog\PSPSaveDialog.cpp:123 sceUtilitySavedataInitStart(09a06aa8) : Game key (hex): 4A1FF359AEB6EFF81CA8CB23BCA57BB3
26:03:868 user_main I[SCEUTIL]: Dialog\SavedataParam.cpp:576 Saving file with size 1483024 in ms0:/PSP/SAVEDATA/ULES01213/MHP2NDG.BIN
26:04:009 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b84340, 00154000): atrac3 stereo audio
27:04:002 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
27:04:595 idle0 I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
27:48:208 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) left ULUS10391 group MHP2Q000
27:48:216 user_main I[SCENET]: HLE\proAdhoc.cpp:1479 Received 5 Bytes of Data from Adhoc Server
27:48:216 user_main I[SCENET]: HLE\proAdhoc.cpp:1658 FriendFinder: Incoming Peer Data Delete Request...
27:48:216 user_main I[SCENET]: HLE\proAdhoc.cpp:488 Removing Friend Peer b4:8f:14:78:e1:99 [26.201.xx.252]
27:48:526 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) stopped playing ULUS10391
27:53:682 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2248 sceNetAdhocPdpDelete(1, 0) at 088ab04c
27:53:689 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2527 Marked for Timedout Peer List (1)
27:53:689 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2559 00000000=sceNetAdhocctlDisconnect() at 088ac958 [group=MHP2Q000]
27:53:694 user_main I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) left ULUS10391 group MHP2Q000
27:53:717 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2570 sceNetAdhocctlDelHandler(0) at 088ac9e0
27:53:721 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2630 sceNetAdhocctlTerm() at 088aca50
27:53:721 user_main I[SCENET]: HLE\proAdhoc.cpp:1780 FriendFinder: End of Friend Finder Thread
27:53:722 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2603 Cleared Peer List (1)
27:53:725 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3067 0=sceNetAdhocTerm()
27:53:727 user_main I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) stopped playing ULUS10391
27:53:729 user_main W[SCENET]: HLE\sceNet.cpp:602 sceNetTerm() at 088acaf8
27:53:753 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
27:53:893 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b8db40, 00253000): atrac3 stereo audio
28:19:944 user_main W[SCENET]: HLE\sceNet.cpp:653 sceNetInit(poolsize=131072, calloutpri=32, calloutstack=0, netintrpri=32, netintrstack=0) at 088ac674
28:19:978 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1293 0=sceNetAdhocInit(): at 088ac720
28:20:012 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1300 sceNetAdhocctlInit(8192, 48, 09a1daf0) at 088ac794
28:20:012 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 323=sceNetAdhocctlInit(8192, 48, 09a1daf0)
28:20:013 idle0 I[SCENET]: HLE\proAdhoc.cpp:1380 FriendFinder: Begin of Friend Finder Thread
28:20:019 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 26.109.xx.243
28:20:024 idle0 I[SCENET]: HLE\proAdhoc.cpp:1413 FriendFinder: Network [RE]Initialized
28:20:030 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
28:20:030 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) started playing ULUS10391
28:20:062 user_main W[SCENET]: HLE\sceNetAdhoc.cpp:2457 UNTESTED sceNetAdhocctlAddHandler(88ac0e8, 0): added handler 0
28:20:111 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2276 sceNetAdhocctlScan() at 088ae048
28:20:133 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) requested information on 0 ULUS10391 groups
28:20:134 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 1 Bytes of Data from Adhoc Server
28:20:134 idle0 I[SCENET]: HLE\proAdhoc.cpp:1744 FriendFinder: Incoming Scan complete response...
28:20:312 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2334 sceNetAdhocctlGetScanInfo([09fff59c]=812, 09a28534) at 088b0800
28:20:646 user_main I[SCEUTIL]: HLE\sceUtility.cpp:671 0=sceUtilityNetconfInitStart(09a1db0c)
28:20:847 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2864 sceNetAdhocctlConnect(MHP2Q000) at 088ac4f0
28:20:857 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) joined ULUS10391 group MHP2Q000
28:20:859 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 7 Bytes of Data from Adhoc Server
28:20:859 idle0 I[SCENET]: HLE\proAdhoc.cpp:1498 FriendFinder: Incoming OPCODE_CONNECT_BSSID [98:ce:a2:89:9b:42]
28:25:864 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
28:26:818 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 10000, 8192, 0) at 088aaf10
28:29:921 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b84340, 00154000): atrac3 stereo audio
28:38:505 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 26.201.xx.252
28:38:506 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
28:38:506 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) started playing ULUS10391
28:38:611 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) requested information on 1 ULUS10391 groups
28:39:742 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) joined ULUS10391 group MHP2Q000
28:39:751 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 139 Bytes of Data from Adhoc Server
28:39:751 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][26.201.xx.252][Seraph]
29:04:019 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
29:04:644 idle0 I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
29:37:170 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
29:37:488 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3975 sceNetAdhocPtpListen(98:ce:a2:89:9b:42, 20001, 8192, 250000, 100, 3, 0) at 088aa820
29:37:954 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3638 sceNetAdhocPtpAccept[2->3:20001]: Established (26.201.xx.252:42380) - state: 4
29:37:955 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
29:37:955 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 20001, 8192, 0) at 088aaf10
29:38:788 idle0 I[G3D]: UI\NativeApp.cpp:1151 Resized flag set - recalculating bounds
29:38:809 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:349 Vulkan submission thread joined. Frame=2
29:38:809 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:357 Vulkan compiler thread joined.
29:38:809 idle0 I[G3D]: Vulkan\VulkanQueueRunner.cpp:247 Backbuffers destroyed
29:38:811 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1274 surfCapabilities
.current: 1920x1080 min: 1920x1080 max: 1920x1080 computed: 1920x1080
29:38:811 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1289 Supported present modes: FIFO, FIFO_RELAXED, MAILBOX, IMMEDIATE
29:38:811 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1319 Chosen present mode: 1 (MAILBOX). numSwapChainImages: 3/8
29:38:811 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1364 Transform supported: IDENTITY current: IDENTITY chosen: IDENTITY
29:38:814 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1427 Created swapchain: 1920x1080
29:38:814 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:305 Starting Vulkan submission thread
29:38:814 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:308 Starting Vulkan compiler thread
29:38:814 idle0 I[SYSTEM]: UI\Screen.cpp:147 ScreenManager::resized(dp: 1920x1080)
29:42:293 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0009b000): atrac3 stereo audio
29:55:539 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=__sceSasRevType(08a8b200, 65535): invalid type
29:56:003 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
29:56:122 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0009b000): atrac3 stereo audio
29:56:655 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
29:56:656 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c42b40, 0001f800): atrac3 stereo audio
30:02:395 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
30:02:395 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0015f800): atrac3 stereo audio
30:23:371 CsePspSndDrv I[SCEIO]: HLE\sceIo.cpp:1182 stdout: PSP�󂢂Ă���{�C�X���[�N����
30:23:371 CsePspSndDrv I[SCEIO]: HLE\sceIo.cpp:1182 stdout: PSP�󂢂Ă���{�C�X���[�N����
30:23:406 CsePspSndDrv I[SCEIO]: HLE\sceIo.cpp:1182 stdout: PSP�󂢂Ă���{�C�X���[�N����
30:23:505 CsePspSndDrv I[SCEIO]: HLE\sceIo.cpp:1182 stdout: PSP�󂢂Ă���{�C�X���[�N����
31:04:007 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
31:04:754 idle0 I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
31:33:401 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
31:33:402 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c00b40, 0001a800): atrac3 stereo audio
31:41:598 CsePspAtrac3 I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
31:43:663 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=__sceSasRevType(08a8b200, 65535): invalid type
31:44:432 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0009b000): atrac3 stereo audio
32:06:820 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=__sceSasRevType(08a8b200, 65535): invalid type
32:18:366 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=__sceSasRevType(08a8b200, 65535): invalid type
32:31:846 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=_sceSasRevType(08a8b200, 65535): invalid type
32:32:310 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
32:32:429 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0009b000): atrac3 stereo audio
32:32:961 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
32:32:961 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c42b40, 0001f800): atrac3 stereo audio
32:38:700 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
32:38:700 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0015f800): atrac3 stereo audio
33:04:009 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
33:22:144 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
33:22:481 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0009b000): atrac3 stereo audio
33:24:086 idle0 E[HTTP]: Net\HTTPClient.cpp:366 Could not parse HTTP status code:
33:24:113 idle0 I[ACHIEVEMENTS]: Core\RetroAchievements.cpp:208 RetroAchievements: Ping response error: No response
33:27:050 idle0 N[SCENET]: HLE\proAdhoc.cpp:1327 Send Chat mano to Adhoc Server
33:27:053 CsePspAtrac3 I[SCENET]: HLE\proAdhocServer.cpp:1215 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) sent "mano" to 1 players in ULUS10391 group MHP2Q000
33:28:118 idle0 N[SCENET]: HLE\proAdhoc.cpp:1327 Send Chat ta ae to Adhoc Server
33:28:119 user_main I[SCENET]: HLE\proAdhocServer.cpp:1215 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) sent "ta ae" to 1 players in ULUS10391 group MHP2Q000
33:38:010 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) left ULUS10391 group MHP2Q000
33:38:010 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 26.201.xx.252) stopped playing ULUS10391
33:38:016 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 5 Bytes of Data from Adhoc Server
33:38:016 idle0 I[SCENET]: HLE\proAdhoc.cpp:1658 FriendFinder: Incoming Peer Data Delete Request...
33:38:016 idle0 I[SCENET]: HLE\proAdhoc.cpp:488 Removing Friend Peer b4:8f:14:78:e1:99 [26.201.xx.252]
33:43:400 idle0 I[G3D]: UI\NativeApp.cpp:1151 Resized flag set - recalculating bounds
33:43:403 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:349 Vulkan submission thread joined. Frame=1
33:43:404 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:357 Vulkan compiler thread joined.
33:43:404 idle0 I[G3D]: Vulkan\VulkanQueueRunner.cpp:247 Backbuffers destroyed
33:43:405 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1274 surfCapabilities
.current: 1460x928 min: 1460x928 max: 1460x928 computed: 1460x928
33:43:405 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1289 Supported present modes: FIFO, FIFO_RELAXED, MAILBOX, IMMEDIATE
33:43:405 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1319 Chosen present mode: 1 (MAILBOX). numSwapChainImages: 3/8
33:43:405 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1364 Transform supported: IDENTITY current: IDENTITY chosen: IDENTITY
33:43:408 idle0 I[G3D]: Vulkan\VulkanContext.cpp:1427 Created swapchain: 1460x928
33:43:409 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:305 Starting Vulkan submission thread
33:43:409 idle0 I[G3D]: Vulkan\VulkanRenderManager.cpp:308 Starting Vulkan compiler thread
33:43:409 idle0 I[SYSTEM]: UI\Screen.cpp:147 ScreenManager::resized(dp: 1460x928)
33:43:836 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(3,0) at 088aaeb4
33:48:887 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
33:48:887 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c1b340, 00027800): atrac3 stereo audio
33:56:799 user_main I[ME]: HLE\sceAtrac.cpp:1975 3=sceAtracSetDataAndGetID(08c73b40, 00005000): atrac3 stereo audio
33:59:068 CsePspAtrac3 I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(3)
34:00:133 CsePspSndDrv E[SCESAS]: HLE\sceSas.cpp:641 80420020=__sceSasRevType(08a8b200, 65535): invalid type
34:00:966 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
34:00:966 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2248 sceNetAdhocPdpDelete(2, 0) at 088ab04c
35:04:013 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: http://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
35:04:769 idle0 I[HTTP]: Net\HTTPClient.cpp:557 Completed requesting http://retroachievements.org/dorequest.php (storing result to memory)
35:31:908 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 26.201.xx.252
35:31:919 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391

@hrydgard
Copy link
Owner Author

Hm, all the HTTP requests seem retroachievements related. no idea how those could disturb the other stuff...

This one is kind of weird though, but it's from the working log:

HTTP]: Net\HTTPClient.cpp:366 Could not parse HTTP status code:

@matheussanjour
Copy link

matheussanjour commented Aug 25, 2023

Here's the log from playing with HTTPDisabled = true on version 1217, we actually got to play and initiate the quest but after some time I got a strange connecting to Retroachievements message at the top (it shouldn't be there during gameplay tho, which I found strange) and then we got the same error as before. Yesterday I was playing just fine with HTTPDisabled = true, but today I updated to 1217 and this happened

@hrydgard
Copy link
Owner Author

Huh, that's bizarre. If you go back to the old version again, does it still consistently work? I'm thinking problem with your VPN stuff? If you're seeing "Contacting RetorAchievements" during gameplay, that means that a request to RA (which do happen from time to time while connected) took more than 3s which is very long (though could also be server issues).

I'm really struggling to come up with an explanation for all this :(

@anr2me
Copy link
Collaborator

anr2me commented Aug 25, 2023

Here's the log from the moment my friend Seraph entered lobby till he left the game after we got the disconnect error. I tried looking for any HTTP line as requested by I didn't find any notable one. Only those marked by this SCENET thing. It does mention a socket error though, maybe that's relevant somehow

With HTTPDisabled = true did you also see that socket error 10022 (invalid argument), because those argument should never be invalid in normal circumstances.
Because your 2nd logs seems to came from a different side of the players (the one Listening) instead of the one Connecting (as in your 1st logs), so it's hard to compare both logs.

In a case where socket error 10022 occurred during connect is because the socket passed as an argument is a listening socket, not sure how it can be triggered by simply enabling HTTP (linking to WinHTTP), unless a corruption occurred in the socket list, resulting it to try to use a wrong or non-existent socket fd.

Edit: There is also a note related to EALREADY error during non-blocking connect which apparently reported as EINVAL on Winsock 1.1

Note In order to preserve backward compatibility, this error is reported as WSAEINVAL to Windows Sockets 1.1 applications that link to either Winsock.dll or Wsock32.dll.

But PPSSPP is using WS2_32.dll (Winsock 2) so this note is probably irrelevant.

@matheussanjour
Copy link

matheussanjour commented Aug 25, 2023

@hrydgard I'll make a test when my friend is available. It'll probably work normally as we've played for almost 4h yesterday nonstop with no problems (tested and played on 1199). Now in a matter os minutes on 1217 we got this disconnect error. I'll try to see if I can replicate it to see if this was only a one time thing.

@anr2me I tried searching the log for a socket error 10022 but there was none, to make sure I did ctrl F and searched for it but no results. It only appears without HTTPDisabled = true. There is a invalid socket id error but I think it's not related (user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id)

A different side of players? strange, both of them came from my debug console but it does mention my friend Seraph entering lobby and connecting (but maybe it would be interesting to see my friend's side?)

@anr2me
Copy link
Collaborator

anr2me commented Aug 25, 2023

@hrydgard I'll make a test when my friend is available. It'll probably work normally as we've played for almost 4h yesterday nonstop with no problems (tested and played on 1199). Now in a matter os minutes on 1217 we got this disconnect error. I'll try to seee if I can replicate it to see if this was only a one time thing.

@anr2me I tried searching the log for a socket error 10022 but there was none, to make sure I did ctrl F and searched for it but no results. It only appears without HTTPDisabled = true. There is a invalid socket id error but I think it's not related (user_main E[SCENET]: HLE\sceNetAdhoc.cpp:1767 80410701=sceNetAdhocPdpSend(-1, ������, 00002710, 09a1dc64[21170000], 186, 0, 0): invalid socket id)

A different side of players? strange, both of them came from my debug console but it does mention my friend Seraph entering lobby and connecting (but maybe it would be interesting to see my friend's side?)

Yes, you should try to check both side logs to find sceNetAdhocPtpConnect when playing with HTTP disabled, usually depends on who host and who joined the room/quest.

Meanwhile, the error 80410701=sceNetAdhocPdpSend due to invalid socket id (-1) is probably because the socket already closed/deleted and the game resetting the variable that store the socket id to an invalid value (-1) after deleting the socket.

Btw, does any other MH games have similar issue?

@matheussanjour
Copy link

53:00:105 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) left ULUS10391 group MHP2Q000
53:00:116 user_main I[SCENET]: HLE\proAdhoc.cpp:1479 Received 5 Bytes of Data from Adhoc Server
53:00:116 user_main I[SCENET]: HLE\proAdhoc.cpp:1658 FriendFinder: Incoming Peer Data Delete Request...
53:00:116 user_main I[SCENET]: HLE\proAdhoc.cpp:488 Removing Friend Peer b4:8f:14:78:e1:99 [25.63.xx.140]
53:00:402 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) stopped playing ULUS10391
53:00:903 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 25.63.xx.140
53:00:925 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
53:00:925 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) started playing ULUS10391
53:01:056 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) requested information on 1 ULUS10391 groups
53:01:801 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) joined ULUS10391 group MHP2Q000
53:01:808 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 139 Bytes of Data from Adhoc Server
53:01:808 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][25.63.xx.140][Seraph]
53:01:808 idle0 W[SCENET]: HLE\proAdhoc.cpp:230 Friend Peer Already Existed! Updating [b4:8f:14:78:e1:99][25.63.xx.140][Seraph]
53:03:018 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: https://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
53:15:794 idle0 I[COMMON]: File\FileUtil.cpp:928 CreateEmptyFile: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/installedTEMP.txt
53:15:796 idle0 I[COMMON]: File\FileUtil.cpp:468 Delete: file C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/installedTEMP.txt
53:45:767 idle0 I[LOADER]: Core\Config.cpp:1291 Config saved (GameSettingsScreen::onFinish): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
53:46:723 idle0 I[G3D]: Common\TextureCacheCommon.cpp:2512 Texture cached cleared from 93 textures
54:03:686 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2248 sceNetAdhocPdpDelete(1, 0) at 088ab04c
54:03:753 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2527 Marked for Timedout Peer List (1)
54:03:753 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2559 00000000=sceNetAdhocctlDisconnect() at 088ac958 [group=MHP2Q000]
54:03:762 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) left ULUS10391 group MHP2Q000
54:04:020 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2570 sceNetAdhocctlDelHandler(0) at 088ac9e0
54:04:053 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2630 sceNetAdhocctlTerm() at 088aca50
54:04:061 user_main I[SCENET]: HLE\proAdhoc.cpp:1780 FriendFinder: End of Friend Finder Thread
54:04:061 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2603 Cleared Peer List (1)
54:04:070 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 26.109.xx.243) stopped playing ULUS10391
54:04:087 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3067 0=sceNetAdhocTerm()
54:04:120 user_main W[SCENET]: HLE\sceNet.cpp:602 sceNetTerm() at 088acaf8
54:04:549 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
54:05:303 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b8db40, 00253000): atrac3 stereo audio
54:07:429 idle0 I[COMMON]: File\FileUtil.cpp:928 CreateEmptyFile: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/installedTEMP.txt
54:07:430 idle0 I[COMMON]: File\FileUtil.cpp:468 Delete: file C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/installedTEMP.txt
54:09:449 idle0 I[SCENET]: Net\Resolve.cpp:214 GetIPList from Fallback
54:13:415 idle0 I[COMMON]: File\FileUtil.cpp:928 CreateEmptyFile: C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/installedTEMP.txt
54:13:416 idle0 I[COMMON]: File\FileUtil.cpp:468 Delete: file C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/installedTEMP.txt
54:14:151 idle0 I[LOADER]: Core\Config.cpp:1291 Config saved (GameSettingsScreen::onFinish): 'C:/Users/mathe/OneDrive/Área de Trabalho/ppsspp/memstick/PSP/SYSTEM/ppsspp.ini'
54:15:140 idle0 I[G3D]: Common\TextureCacheCommon.cpp:2512 Texture cached cleared from 93 textures
54:16:149 user_main W[SCENET]: HLE\sceNet.cpp:653 sceNetInit(poolsize=131072, calloutpri=32, calloutstack=0, netintrpri=32, netintrstack=0) at 088ac674
54:16:182 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1293 0=sceNetAdhocInit(): at 088ac720
54:16:216 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1300 sceNetAdhocctlInit(8192, 48, 09a1daf0) at 088ac794
54:16:216 user_main I[SCEKERNEL]: HLE\sceKernelThread.cpp:2004 317=sceNetAdhocctlInit(8192, 48, 09a1daf0)
54:16:216 idle0 I[SCENET]: HLE\proAdhoc.cpp:1380 FriendFinder: Begin of Friend Finder Thread
54:16:219 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 25.64.xx.219
54:16:227 CsePspAtrac3 I[SCENET]: HLE\proAdhoc.cpp:1413 FriendFinder: Network [RE]Initialized
54:16:230 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
54:16:230 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 25.64.xx.219) started playing ULUS10391
54:16:266 user_main W[SCENET]: HLE\sceNetAdhoc.cpp:2457 UNTESTED sceNetAdhocctlAddHandler(88ac0e8, 0): added handler 0
54:16:316 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2276 sceNetAdhocctlScan() at 088ae048
54:16:328 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 25.64.xx.219) requested information on 1 ULUS10391 groups
54:16:337 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 16 Bytes of Data from Adhoc Server
54:16:337 idle0 I[SCENET]: HLE\proAdhoc.cpp:1697 Incoming Group Information...
54:16:348 idle0 I[SCENET]: HLE\proAdhoc.cpp:1744 FriendFinder: Incoming Scan complete response...
54:16:516 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2334 sceNetAdhocctlGetScanInfo([09fff59c]=812, 09a28534) at 088b0800
54:16:916 user_main I[SCEUTIL]: HLE\sceUtility.cpp:671 0=sceUtilityNetconfInitStart(09a1db0c)
54:17:116 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:2864 sceNetAdhocctlConnect(MHP2Q000) at 088ac4f0
54:17:131 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Sanjour (MAC: 98:ce:a2:89:9b:42 - IP: 25.64.xx.219) joined ULUS10391 group MHP2Q000
54:17:141 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 146 Bytes of Data from Adhoc Server
54:17:141 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][25.63.xx.140][Seraph]
54:17:152 idle0 I[SCENET]: HLE\proAdhoc.cpp:1498 FriendFinder: Incoming OPCODE_CONNECT_BSSID [b4:8f:14:78:e1:99]
54:22:152 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
54:23:069 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 10000, 8192, 0) at 088aaf10
54:26:175 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08b84340, 00154000): atrac3 stereo audio
54:41:239 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) left ULUS10391 group MHP2Q000
54:41:242 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 5 Bytes of Data from Adhoc Server
54:41:242 idle0 I[SCENET]: HLE\proAdhoc.cpp:1658 FriendFinder: Incoming Peer Data Delete Request...
54:41:242 idle0 I[SCENET]: HLE\proAdhoc.cpp:488 Removing Friend Peer b4:8f:14:78:e1:99 [25.63.xx.140]
54:41:545 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) stopped playing ULUS10391
54:42:051 idle0 I[SCENET]: HLE\proAdhocServer.cpp:550 AdhocServer: New Connection from 25.63.xx.140
54:42:062 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1351 AdhocServer: Crosslinked ULES01213 to ULUS10391
54:42:062 idle0 I[SCENET]: HLE\proAdhocServer.cpp:644 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) started playing ULUS10391
54:42:193 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1101 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) requested information on 1 ULUS10391 groups
54:42:940 idle0 I[SCENET]: HLE\proAdhocServer.cpp:907 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) joined ULUS10391 group MHP2Q000
54:42:941 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 139 Bytes of Data from Adhoc Server
54:42:941 idle0 I[SCENET]: HLE\proAdhoc.cpp:1584 FriendFinder: Incoming OPCODE_CONNECT [b4:8f:14:78:e1:99][25.63.xx.140][Seraph]
54:42:941 idle0 W[SCENET]: HLE\proAdhoc.cpp:230 Friend Peer Already Existed! Updating [b4:8f:14:78:e1:99][25.63.xx.140][Seraph]
55:03:010 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: https://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)
55:28:701 user_main I[ME]: HLE\sceAtrac.cpp:1727 0=sceAtracReleaseAtracID(2)
55:29:020 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3975 sceNetAdhocPtpListen(98:ce:a2:89:9b:42, 20001, 8192, 250000, 100, 3, 0) at 088aa820
56:29:046 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:3954 sceNetAdhocPtpClose(2,0) at 088aaeb4
56:29:046 user_main I[SCENET]: HLE\sceNetAdhoc.cpp:1374 sceNetAdhocPdpCreate(98:ce:a2:89:9b:42, 20001, 8192, 0) at 088aaf10
56:32:884 user_main I[ME]: HLE\sceAtrac.cpp:1975 2=sceAtracSetDataAndGetID(08c78b40, 0008c000): atrac3 stereo audio
56:41:008 idle0 I[SCENET]: HLE\proAdhocServer.cpp:1003 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) left ULUS10391 group MHP2Q000
56:41:008 idle0 I[SCENET]: HLE\proAdhocServer.cpp:693 AdhocServer: Seraph (MAC: b4:8f:14:78:e1:99 - IP: 25.63.xx.140) stopped playing ULUS10391
56:41:011 idle0 I[SCENET]: HLE\proAdhoc.cpp:1479 Received 5 Bytes of Data from Adhoc Server
56:41:011 idle0 I[SCENET]: HLE\proAdhoc.cpp:1658 FriendFinder: Incoming Peer Data Delete Request...
56:41:011 idle0 I[SCENET]: HLE\proAdhoc.cpp:488 Removing Friend Peer b4:8f:14:78:e1:99 [25.63.xx.140]
57:03:013 idle0 I[HTTP]: Net\HTTPRequest.cpp:13 HTTP POST request: https://retroachievements.org/dorequest.php (Contacting RetroAchievements server...)

@matheussanjour
Copy link

matheussanjour commented Aug 25, 2023

Here's a new generated log without HTTPDisabled = true. This time we switched Radmin to Hamachi in order to see any difference. This log didn't generate so many red lines as log 1. Strange.

@anr2me
Copy link
Collaborator

anr2me commented Aug 25, 2023

That logs is showing sceNetAdhocPtpListen which is the other side of sceNetAdhocPtpConnect, so you should be able to find sceNetAdhocPtpConnect on the other player's logs.

Here's a new generated log without HTTPDisabled = true.

Huh, the one that gets error sceNetAdhocPtpConnect[2]: Socket Error (10022) was without DisableHTTPS = True (which is DisableHTTPS = False) isn't?
since we know it have error 10022 (from your first logs) we need to compare it with the logs containing sceNetAdhocPtpConnect with DisableHTTPS = True.

Btw, is this issue also occurred on other MH games? (if you have tested them too)

@matheussanjour
Copy link

matheussanjour commented Aug 25, 2023

Yes, it was without DisableHTTPS = True. Is there any problem by using a pastebin link? Those logs are very big for a comment section. We did a new test now without HTTPDisabled = true, we add the line manually as requested by @hrydgard in the ppsspp.ini so by default it should be HTTPDisabled = false I think. Regarding other Monster Hunter games, we didn't test them yet but we can try.
He's using my IP and I'm the host of the quest. The same error happens with me when i join his IP or quests.

My log: https://pastebin.com/6wSXEZrs
My friend's log: https://pastebin.com/3MCx8WiH

@matheussanjour
Copy link

Also, I talked with another person I know in a MH discord server and he told me that he's also having the same problem with his friend and they were also stuck on a very old build because of that. So it's not something that's only happening to me and my friend

@anr2me
Copy link
Collaborator

anr2me commented Aug 25, 2023

Noproblem using pastebin.

But you're testing an already known logs (which is the default) that have HTTPS Enabled suspected to cause the issue, the new logs should have HTTPS Disabled (by using DisableHTTPS = True) which supposed to work.

We did a new test now without HTTPDisabled = true

This would result the same error with your first logs, because it have HTTPS enabled if you remove that line (since you said "without" i assumed you removed it)

Instead of removing the line, you should change the value between True or False, where the default (without that line will be the same as False)

@matheussanjour
Copy link

Alright. Here's some new generated logs with DisableHTTPS = True. With this line we can enter the quest together without any problems. Now we have logs for both situations

My log: https://pastebin.com/bLDYPXFT
My friend's log: https://pastebin.com/K0KtNBDK

@anr2me
Copy link
Collaborator

anr2me commented Aug 25, 2023

Thanks for testing!
And i don't see any error during sceNetAdhocPtpConnect without HTTPS.

It's strange that using HTTPS could trigger socket error 10022, unless WinHTTP was using Winsock 1.1 during WSAStartup but the WinHTTP.dll i had (from Win 11) seems to use WS2_32 too just like PPSSPP, so it probably use Winsock 2 too.

However, using Winsock version 1.1 during WSAStartup is the only explanation that have a possibility to get error 10022 (EINVAL) during connect, as we currently didn't treat EINVAL the same way to EALREADY (as suggested for backward-compatibility), it end up treated as a real error.

@anr2me
Copy link
Collaborator

anr2me commented Aug 26, 2023

I tried to treat WSAEINVAL similar to WSAEALREADY

@matheussanjour please try the artifact from this PR later #17982 with DisableHTTPS = False to see whether that PR makes a difference or not (you will probably still seeing error 10022 in the logs but if the game is working properly you can ignore that error)

If it still have issue, please show the logs from both sides.

@matheussanjour
Copy link

Sorry, I don't have any experience with this Github stuff. What do you mean by this PR? Is there a download button there? Can't seem to understand it

@anr2me
Copy link
Collaborator

anr2me commented Aug 26, 2023

Go to this page https://github.com/hrydgard/ppsspp/actions/runs/5981601233?pr=17982 (Summary), at the bottom of the page (when it's done building) you will see "Artifacts" where you can download the "Windows x64 build" to test with.

@anr2me
Copy link
Collaborator

anr2me commented Aug 26, 2023

Ouch it's auto-closed without being confirmed yet

@anr2me anr2me reopened this Aug 26, 2023
@hrydgard
Copy link
Owner Author

hrydgard commented Aug 26, 2023

You guys can also just test the latest buildbot build now. Set DisableHTTP to false when testing.

@matheussanjour
Copy link

Just made a test with DisableHTTPS = False on version 1240 and it works like a charm now! Thank you so much. I will warn some friends that were stuck on old builds about the update!

@anr2me
Copy link
Collaborator

anr2me commented Aug 26, 2023

Thanks for testing guys!

@anr2me anr2me closed this as completed Aug 26, 2023
@hrydgard
Copy link
Owner Author

@matheussanjour thanks so much for your testing!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

Successfully merging a pull request may close this issue.

3 participants