client login failing when upgrading from server version 2.13 to 2.17

Post here your questions about SFS2X. Here we discuss all server-side matters. For client API questions see the dedicated forums.

Moderators: Lapo, Bax

sanre6
Posts: 2
Joined: 11 May 2021, 10:10

client login failing when upgrading from server version 2.13 to 2.17

Postby sanre6 » 11 May 2021, 11:31

Hi,

we wanted to upgrade our sfs server version from 2.13.0 to 2.17.0. We create a test server with 2.17 version with our extension jar and all other dependency libs. server starts up fine but on login request of user it fails with below error. I will add the server config as well if needed unable to attach an xml file.

11 May 2021 | 11:22:30,274 | INFO | SocketReader | bitswarm.sessions.DefaultSessionManager | | Session created: { Id: 1, Type: DEFAULT, Logged: No, IP: 202.133.59.102:47822 } on Server port: 9933 <---> 47822
11 May 2021 | 11:22:30,400 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | Binary size: 48
80 00 2D 12 00 03 00 01 63 02 00 00 01 61 03 00 ..-.....c....a..
00 00 01 70 12 00 02 00 03 61 70 69 08 00 05 31 ...p.....api...1
2E 37 2E 38 00 02 63 6C 08 00 05 55 6E 69 74 79 .7.8..cl...Unity

11 May 2021 | 11:22:30,400 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | STATE: WAIT_NEW_PACKET
11 May 2021 | 11:22:30,401 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | NORMAL SIZED PACKET: 45
11 May 2021 | 11:22:30,402 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | <<< PACKET COMPLETE >>>
11 May 2021 | 11:22:30,403 | DEBUG | SFSWorker:Sys:1 | v2.protocol.SFSProtocolCodec | |
(sfs_object) p:
(utf_string) cl: Unity
(utf_string) api: 1.7.8


(short) a: 0
(byte) c: 0


11 May 2021 | 11:22:30,403 | DEBUG | SFSWorker:Sys:1 | controllers.v290.SystemReqController | | {IN}: Handshake
11 May 2021 | 11:22:30,406 | DEBUG | SFSWorker:Sys:1 | v2.protocol.SFSProtocolCodec | | {OUT}: Handshake
11 May 2021 | 11:22:30,407 | DEBUG | SFSWorker:Sys:1 | protocol.binary.BinaryIoHandler | | Binary size: 77
12 00 03 00 01 70 12 00 03 00 02 63 74 04 00 00 .....p.....ct...
04 00 00 02 6D 73 04 00 07 A1 20 00 02 74 6B 08 ....ms.......tk.
00 20 37 30 34 62 63 39 31 34 66 64 65 66 65 38 ..704bc914fdefe8
30 39 64 37 30 65 39 34 66 65 36 36 65 33 32 62 09d70e94fe66e32b
35 39 00 01 61 03 00 00 00 01 63 02 00 59..a.....c..

11 May 2021 | 11:22:30,433 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | Binary size: 94
80 00 5B 12 00 03 00 01 63 02 01 00 01 61 03 00 ..[.....c....a..
0D 00 01 70 12 00 03 00 01 63 08 00 09 66 69 6E ...p.....c...fin
64 4D 61 74 63 68 00 01 72 04 FF FF FF FF 00 01 dMatch..r.......
70 12 00 03 00 07 6C 6F 62 62 79 49 64 04 00 2C p.....lobbyId..,
96 37 00 05 72 65 74 72 79 04 00 00 00 00 00 0C .7..retry.......
69 73 41 73 79 6E 63 4C 6F 62 62 79 01 00 isAsyncLobby..

11 May 2021 | 11:22:30,433 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | STATE: WAIT_NEW_PACKET
11 May 2021 | 11:22:30,434 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | NORMAL SIZED PACKET: 91
11 May 2021 | 11:22:30,434 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | <<< PACKET COMPLETE >>>
11 May 2021 | 11:22:30,434 | DEBUG | SFSWorker:Sys:2 | v2.protocol.SFSProtocolCodec | |
(sfs_object) p:
(sfs_object) p:
(int) retry: 0
(int) lobbyId: 2922039
(bool) isAsyncLobby: false


(int) r: -1
(utf_string) c: findMatch


(short) a: 13
(byte) c: 1


11 May 2021 | 11:22:30,435 | DEBUG | SFSWorker:Ext:2 | controllers.v290.ExtensionReqController | | [Req Type: (Tcp), Sender: { Id: 1, Type: DEFAULT, Logged: No, IP: 202.133.59.102:47822 }]
11 May 2021 | 11:22:30,436 | WARN | SFSWorker:Ext:2 | controllers.v290.ExtensionReqController | | com.smartfoxserver.v2.exceptions.SFSExtensionException: Extension Request refused. Sender is not a User: { Id: 1, Type: DEFAULT, Logged: No, IP: 202.133.59.102:47822 }
com.smartfoxserver.v2.controllers.v290.ExtensionReqController.processRequest(ExtensionReqController.java:93)
com.smartfoxserver.v2.controllers.v290.ExtensionReqController$1.run(ExtensionReqController.java:68)
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
java.lang.Thread.run(Thread.java:748)

11 May 2021 | 11:22:31,454 | INFO | https-jsse-nio-8443-exec-7 | tomcat.secure.CryptoKeyManager | | CryptKeyManager initialized.
11 May 2021 | 11:22:31,665 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | Binary size: 579
C0 02 40 1B DF 5D 79 EB B7 69 58 9C D2 38 88 6E ..@..]y..iX..8.n
81 03 72 4E 66 D3 EE C1 CB 8B CF 87 B2 73 1D 0C ..rNf........s..
BA 79 01 39 CF C3 0E F7 7C 99 F4 1A CE 9B 4A 19 .y.9....|.....J.
E0 3F D1 04 61 52 39 40 8A 5F 46 A0 19 1F CE 22 .?..aR9@._F...."
71 1D B2 C8 34 50 51 85 D0 9A CA 35 FA 9A 1B 70 q...4PQ....5...p
FB 7F E8 E9 56 10 7D 2C 7A 2A E7 B6 BC F1 89 F6 ....V.},z*......
2A ED 8E F1 B1 34 22 48 82 B2 A7 1D 91 CE 56 AD *....4"H......V.
73 CE 47 10 72 71 4D BC CD 04 E8 34 A7 3E E4 29 s.G.rqM....4.>.)
1D 6E 4F E8 43 4F 4E C0 64 60 FF C9 86 AF 25 DD .nO.CON.d`....%.
61 CB 66 4C 18 F4 80 5B 61 9E D7 1B D6 CE FB D4 a.fL...[a.......
E5 58 D6 5B 22 4F 47 53 CC C4 DA F0 7B 66 85 00 .X.["OGS....{f..
8F 0F C0 9E E9 6B 9E 5A 64 68 FA 4C FD 92 8A B4 .....k.Zdh.L....
D9 66 B4 2C 3E 2C 0D D4 6A 22 88 6F 05 7D ED 51 .f.,>,..j".o.}.Q
47 79 3D 47 73 6D 2D 9C 1B C5 B7 CD CA D2 B6 40 Gy=Gsm-........@
43 4C 0F 72 DD 7E 60 B4 EA DA 66 55 A4 A1 DD 0E CL.r.~`...fU....
13 78 4A FA 77 96 41 14 3A 8D 56 25 83 59 F8 98 .xJ.w.A.:.V%.Y..
E3 96 F1 CD 05 8E EF DA 94 BA 66 02 5C C2 DA 20 ..........f.\...
71 97 AF 61 F3 FD E5 42 1F 92 DD 06 DF 0D 26 94 q..a...B......&.
C2 50 A4 FB 40 E2 C9 E8 27 52 67 62 9E 0B E4 91 .P..@...'Rgb....
3A 2D B4 A9 29 53 EB 20 EF 41 41 04 D0 17 E0 51 :-..)S...AA....Q
CE 75 B9 89 61 15 3B D0 EC 6F 93 6D A4 76 6E 48 .u..a.;..o.m.vnH
00 D6 2B A0 47 D5 7E D7 6A 73 B4 D0 8F EB 74 83 ..+.G.~.js....t.
D8 55 BE 47 D2 A5 15 33 BE BC 90 C6 42 1C B2 20 .U.G...3....B...
8F 17 49 91 8D 40 90 51 32 57 39 41 EE 01 9C 56 ..I..@.Q2W9A...V
53 A0 7B 00 E8 AC 41 42 9A 11 9F F5 E7 E5 B5 E6 S.{...AB........
E4 C6 55 55 FD DE 96 18 5E 45 EE 03 0C 7B 26 75 ..UU....^E...{&u
C7 DA 53 1F B0 C2 61 10 22 AF 7F 10 03 25 69 DB ..S...a."....%i.
09 DA 43 1E 80 E3 A9 09 84 AE C6 17 C6 55 45 8B ..C..........UE.
87 34 7C 63 EA D9 F0 7B 6C FF 5D 71 35 8F AE 01 .4|c...{l.]q5...
AB 61 5A 2F 60 2F A5 79 71 EA F4 B5 CA B8 55 8F .aZ/`/.yq.....U.
FD 81 07 F9 03 4D 54 AE A5 B7 B4 07 43 27 08 22 .....MT.....C'."
6D 21 C7 3C B8 BF B0 C7 98 23 B2 A0 B7 77 28 1B m!.<.....#...w(.
B5 AE 20 E8 66 48 05 B7 08 CF 25 07 7C 13 F4 91 ....fH....%.|...
44 EE 97 14 CA FE D6 C8 ED 9D FB 7C 39 57 86 3C D..........|9W.<
99 9C 82 E4 DF 79 8A 6A 06 68 F6 1B 11 4F 44 DA .....y.j.h...OD.
7E E3 59 87 26 DA 5C 49 0F 5F 3A B1 DD 3B 3D 70 ~.Y.&.\I._:..;=p
6C A5 88 l..

11 May 2021 | 11:22:31,666 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | STATE: WAIT_NEW_PACKET
11 May 2021 | 11:22:31,666 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | NORMAL SIZED PACKET: 576
11 May 2021 | 11:22:31,666 | DEBUG | SocketReader | protocol.binary.BinaryIoHandler | | <<< PACKET COMPLETE >>>
11 May 2021 | 11:22:38,530 | WARN | pool-1-thread-2 | Extensions | | {__lib__}: KBZE ELK_SYNC_LOG RoomCount: 0 UserCount: 0
11 May 2021 | 11:22:48,530 | WARN | pool-1-thread-3 | Extensions | | {__lib__}: KBZE ELK_SYNC_LOG RoomCount: 0 UserCount: 0
11 May 2021 | 11:22:58,530 | WARN | pool-1-thread-3 | Extensions | | {__lib__}: KBZE ELK_SYNC_LOG RoomCount: 0 UserCount: 0
11 May 2021 | 11:23:08,530 | WARN | pool-1-thread-2 | Extensions | | {__lib__}: KBZE ELK_SYNC_LOG RoomCount: 0 UserCount: 0
11 May 2021 | 11:23:08,756 | INFO | Scheduler1-thread-1 | bitswarm.sessions.DefaultSessionManager | | Session removed: { Id: 1, Type: DEFAULT, Logged: No, IP: 202.133.59.102:47822 }
11 May 2021 | 11:23:08,757 | DEBUG | Scheduler1-thread-1 | bitswarm.sessions.DefaultSessionManager | | Removed idle session: { Id: 1, Type: DEFAULT, Logged: No, IP: 202.133.59.102:47822 }
User avatar
Lapo
Site Admin
Posts: 23008
Joined: 21 Mar 2005, 09:50
Location: Italy

Re: client login failing when upgrading from server version 2.13 to 2.17

Postby Lapo » 12 May 2021, 07:28

Hi,
this doesn't look like an issue with the version of the server.

The first request sent by the client is the Handshake which is part of the connection sequence. Right after that the server receives an Extension request, but there is no LoginRequest prior to that, which is illegal.

A user must be logged in before he/she can send any other requests, including an Extension Request.
You need to double check what your client side code is doing and make sure that the user is logged in before sending other requests.

Best regards.
Lapo
--
gotoAndPlay()
...addicted to flash games
sanre6
Posts: 2
Joined: 11 May 2021, 10:10

Re: client login failing when upgrading from server version 2.13 to 2.17

Postby sanre6 » 12 May 2021, 08:54

Hi Lapo,

The same Unity client side code is working fine when server version is 2.13.0.
Are all client side versions backward compatible with the new server version ?


Thanks,
Sanre6
User avatar
Lapo
Site Admin
Posts: 23008
Joined: 21 Mar 2005, 09:50
Location: Italy

Re: client login failing when upgrading from server version 2.13 to 2.17

Postby Lapo » 12 May 2021, 11:29

That is actually not possible :)
At least not for the transcript you have posted. All versions of SFS2X work the in the same way:

- first you have to connect
- then you have to login into a Zone (by sending a LoginRequest)
- finally you can send whatever request you want

In the debug log you have sent above there is no trace of a Login request and therefore any version of SFS2X will behave exactly the same, raising an exception because the Extension request is sent by a client that is not logged in.

I'd recommend double checking your client side code.

Cheers
Lapo

--

gotoAndPlay()

...addicted to flash games

Return to “SFS2X Questions”

Who is online

Users browsing this forum: No registered users and 52 guests