SphereServer BugTracker - SphereServer
View Issue Details
0001911SphereServerexecutable - genericpublic11-02-11 13:2913-03-11 23:40
Robert 
MrSugarCube 
normalminorsometimes
resolvedfixed 
20-09-2009, 0.56b Prerelease 
0.56c Nightly 
Automated (specify build number)
None
None
1415
0001911: Verifying account
Some of the players stuck with verifying account screen while logging in. It must be caused by yesterday revisions (10.02.11), build r1411 is does not produce this error.
No tags attached.
txt test.txt (4) 15-02-11 14:19
http://tracker.sphere.torfo.org/bugs/file_download.php?file_id=58&type=bug
Issue History
11-02-11 13:29RobertNew Issue
11-02-11 21:19MrSugarCubeNote Added: 0000907
12-02-11 02:41RobertNote Added: 0000908
12-02-11 02:44RobertNote Added: 0000909
12-02-11 03:40MrSugarCubeNote Added: 0000910
13-02-11 02:08MrSugarCubeNote Added: 0000911
15-02-11 08:26RobertNote Added: 0000920
15-02-11 11:00MrSugarCubeNote Added: 0000921
15-02-11 11:46RobertNote Added: 0000922
15-02-11 11:46RobertNote View State: 0000922: private
15-02-11 14:01RobertNote Deleted: 0000922
15-02-11 14:19RobertNote Added: 0000925
15-02-11 14:19RobertFile Added: test.txt
15-02-11 15:26RobertNote Added: 0000926
15-02-11 18:37RobertNote Edited: 0000925bug_revision_view_page.php?bugnote_id=0000925#r130
16-02-11 19:54RobertNote Added: 0000931
17-02-11 00:13RobertNote Added: 0000932
17-02-11 02:07MrSugarCubeNote Added: 0000933
21-02-11 01:29MrSugarCubeNote Added: 0000943
24-02-11 11:41RobertNote Added: 0000946
24-02-11 12:22RobertNote Edited: 0000946bug_revision_view_page.php?bugnote_id=0000946#r162
27-02-11 00:38RobertNote Added: 0000951
13-03-11 23:40MrSugarCubeStatusnew => resolved
13-03-11 23:40MrSugarCubeFixed in Version => 0.56c Nightly
13-03-11 23:40MrSugarCubeResolutionopen => fixed
13-03-11 23:40MrSugarCubeAssigned To => MrSugarCube

Notes
(0000907)
MrSugarCube   
11-02-11 21:19   
Does this affect any particular client version(s)?

Are there any messages shown in console when they connect? (there may be something of interest when debug flag 08000 is enabled too (DebugFlags in Sphere.ini))
(0000908)
Robert   
12-02-11 02:41   
02:27:DEBUG:Receiving new connection
02:27:DEBUG:Retrieving IP history for '88.x.214.87'.
02:27:DEBUG:Incoming connection from '88.x.214.87' [blocked=0, ttl=300, pings=10, connecting=2, connected=2]
02:27:DEBUG:32:Allocated slot for client (592).
02:27:DEBUG:32:Clearing client state.
02:27:DEBUG:32:Initialising client
02:27:DEBUG:32:Opening network state
02:27:DEBUG:32:Determining async mode
02:27:DEBUG:32:State initialised, registering client instance.
02:27:DEBUG:32:Client successfully initialised.
02:27:DEBUG:31:Receiving new client login handshake.
02:27:DEBUG:31:Client connected with a seed of 0xc0a80102 (new handshake=1, seed length=21, received=83, version=0x600062).
02:27:ERROR:31:Bad Login 9 (The account details entered are invalid (username or password is too short, too long or contains invalid characters). This can sometimes be caused by incorrect/missing encryption keys)
02:27:DEBUG:31:Client being closed by read-thread
02:27:DEBUG:31:Flushing data for client.

Client version is 2.0.3, username and password lenghts are ok.
(0000909)
Robert   
12-02-11 02:44   
02:36:DEBUG:Receiving new connection
02:36:DEBUG:Retrieving IP history for '88.x.214.87'.
02:36:DEBUG:Incoming connection from '88.x.214.87' [blocked=0, ttl=300, pings=73, connecting=0, connected=0]
02:36:DEBUG:Closing incoming connection [max ip=8, clients max ip=16).
02:36:ERROR:Connection from 88.x.214.87 rejected.
02:36:DEBUG:Receiving new connection
02:36:DEBUG:Retrieving IP history for '88.x.214.87'.
02:36:DEBUG:Incoming connection from '88.x.214.87' [blocked=0, ttl=300, pings=74, connecting=0, connected=0]
02:36:DEBUG:Closing incoming connection [max ip=8, clients max ip=16).
02:36:ERROR:Connection from 88.x.214.87 rejected.

It's the only one connection from this ip.
(0000910)
MrSugarCube   
12-02-11 03:40   
The first connection looks like it's from a 6.0.6 client (definitely not 2.0.3) and they appear to have entered either:
- no password
- a password which is too long (30+ characters), or
- a password which contains invalid characters (some symbols such as '{', '|', '}', '~' and newlines are rejected to prevent some exploits).

I can't see any other condition that triggers a Bad Login 9 (assuming encryption key is correct).


The second connection has exceeded your 'MAXPINGS' setting by trying to connect too often. Assuming there aren't another 70 connection attempts right before then there could be a problem with pings not decaying over time, which I'll look into.
(0000911)
MrSugarCube   
13-02-11 02:08   
A fix for MAXPINGS is in the latest automated build (1416+).
(0000920)
Robert   
15-02-11 08:26   
Still have same problem
09:19:DEBUG:Receiving new connection
09:19:DEBUG:Retrieving IP history for '91.x.163.209'.
09:19:DEBUG:Incoming connection from '91.x.163.209' [blocked=0, ttl=300, pings=377, connecting=0, connected=0]
09:19:DEBUG:Closing incoming connection [max ip=8, clients max ip=16).
09:19:ERROR:Connection from 91.x.163.209 rejected.
09:20:DEBUG:Receiving new connection
09:20:DEBUG:Retrieving IP history for '91.x.163.209'.
09:20:DEBUG:Incoming connection from '91.x.163.209' [blocked=0, ttl=300, pings=378, connecting=0, connected=0]
09:20:DEBUG:Closing incoming connection [max ip=8, clients max ip=16).
09:20:ERROR:Connection from 91.x.163.209 rejected.
(0000921)
MrSugarCube   
15-02-11 11:00   
What are MAXPINGS and NETTTL set to? Is that IP making a connection every minute without leaving some time between attempts?
(0000925)
Robert   
15-02-11 14:19   
(edited on: 15-02-11 18:37)
Sorry i was given wrong information, the log i wrote for a site (www.uoplayer.com) which is recording online player stats. As you said it is making a connection every minute with leaving 60 seconds time between attemps, if i downgrade to 15.01.2011 release there is no problem with this.

Real problem,
03:02:DEBUG:Receiving new connection
03:02:DEBUG:Retrieving IP history for '88.x.168.112'.
03:02:DEBUG:Incoming connection from '88.x.168.112' [blocked=0, ttl=297, pings=1, connecting=0, connected=1]
03:02:DEBUG:23:Allocated slot for client (456).
03:02:DEBUG:23:Clearing client state.
03:02:DEBUG:23:Initialising client
03:02:DEBUG:23:Opening network state
03:02:DEBUG:23:Determining async mode
03:02:DEBUG:23:State initialised, registering client instance.
03:02:DEBUG:23:Client successfully initialised.
03:02:DEBUG:23:Receiving old client login handshake.
03:02:DEBUG:23:Client connected with a seed of 0x7f000001 (new handshake=0, seed length=4, received=4, version=0x0).
03:02:DEBUG:20:Client being closed by read-thread
03:02:DEBUG:20:Client being closed by read-thread
03:02:DEBUG:20:Client being closed by write-thread
03:02:DEBUG:20:Client is being cleared since marked to close.
03:02:DEBUG:20:Clearing client state.
03:12:ERROR:Connection from 88.252.168.112 rejected.

(0000926)
Robert   
15-02-11 15:26   
I have just sent you the log file url from sphere's main site
(0000931)
Robert   
16-02-11 19:54   
i have been running last build for 4 hours, no one has reported this problem yet.
(0000932)
Robert   
17-02-11 00:13   
even if i set NetTTL 120 it blocks the sites which recording online players of shards (for example uoplayer.com, this site makes connection in every minute)
(0000933)
MrSugarCube   
17-02-11 02:07   
So with the latest build, is it just the IPs that connect every minute for stats that have this problem? I've tweaked the behaviour slightly so that they shouldn't be affected in tomorrow's build.

In the meantime you could try reducing NetTTL even further (around 30) or raising MaxPings to a stupidly high value (like 1000000000), although this will effectively disable (or severely reduce) the protection they offer.
(0000943)
MrSugarCube   
21-02-11 01:29   
Is this problem still present in the latest builds?
(0000946)
Robert   
24-02-11 11:41   
(edited on: 24-02-11 12:22)
Verifying account issue no longer exists but we are still having trouble with maxpings (i have just set netTTL=30, i will report it again later)

(0000951)
Robert   
27-02-11 00:38   
can be marked as resolved