[2015-05-30 14:15:53 | StorageServer] Now listening for connections on 127.0.0.1:8000...
[2015-05-30 14:16:15 | GameSpyQRServer] [192.168.10.64:62983] Received availability request for 'mariokartwii' from 192.168.10.64:62983...
[2015-05-30 14:16:15 | NasServer] Request to /ac from ('192.168.10.64', 46400)
[2015-05-30 14:16:15 | NasServer] {'lang': '03', 'gamecd': 'RMCP', 'makercd': '01', 'unitcd': '1', 'ipaddr': '192.168.10.64', 'region': '02', 'userid': '1332113423010', 'gsbrcd': 'RMCJ1142o2r', 'cfc': '4558571766239210', 'confmethod': '00', 'ingamesn': '\x00S\x00i\x00m\x00m\x00 \x00t\x00h\x00r\x00e\x00e', 'devtime': '150530132857', 'csnum': 'LEH100105018', 'action': 'login', 'macadr': '0017ab5c7680', 'sdkver': '001000'}
127.0.0.1 - - [30/May/2015 14:16:15] "POST /ac HTTP/1.1" 200 -
[2015-05-30 14:16:15 | GamespyDatabase] [wr2NAlnV] STARTING: SELECT COUNT(*) FROM banned WHERE gameid = RMC AND ipaddr = 192.168.10.64
[2015-05-30 14:16:15 | GamespyDatabase] [wr2NAlnV] DONE: Took 0.000601768493652 real time / 0.000598 processor time
[2015-05-30 14:16:15 | GamespyDatabase] [OeBbskAr] STARTING: SELECT COUNT(*) FROM nas_logins WHERE authtoken = NDSQGSRRwRvp9oL0pd6AFMIWYjnmc7ZuD7dSjFdBnDUAwsBUxsBpPVL2ETyhBHD4pcBoZey9FWsBSBFtgCo
[2015-05-30 14:16:15 | GamespyDatabase] [OeBbskAr] DONE: Took 0.000101089477539 real time / 9.80000000002e-05 processor time
[2015-05-30 14:16:15 | GamespyDatabase] [pP2SSe8X] STARTING: SELECT * FROM nas_logins WHERE userid = 1332113423010
[2015-05-30 14:16:15 | GamespyDatabase] [pP2SSe8X] DONE: Took 0.000133037567139 real time / 0.000129 processor time
[2015-05-30 14:16:15 | GamespyDatabase] [Bit4Nd7W] STARTING: UPDATE nas_logins SET authtoken = NDSQGSRRwRvp9oL0pd6AFMIWYjnmc7ZuD7dSjFdBnDUAwsBUxsBpPVL2ETyhBHD4pcBoZey9FWsBSBFtgCo, data = {"lang": "03", "gamecd": "RMCP", "makercd": "01", "unitcd": "1", "challenge": "SI2pRtEj", "ipaddr": "192.168.10.64", "region": "02", "userid": "1332113423010", "gsbrcd": "RMCJ1142o2r", "cfc": "4558571766239210", "confmethod": "00", "ingamesn": "AFMAaQBtAG0AIAB0AGgAcgBlAGU_", "devtime": "150530132857", "csnum": "LEH100105018", "action": "login", "macadr": "0017ab5c7680", "sdkver": "001000"} WHERE userid = 1332113423010
[2015-05-30 14:16:15 | GamespyDatabase] [Bit4Nd7W] DONE: Took 0.00037407875061 real time / 0.000371 processor time
[2015-05-30 14:16:15 | NasServer] login response to ('192.168.10.64', 46400)
[2015-05-30 14:16:15 | NasServer] {'locator': 'gamespy.com', 'token': 'NDSQGSRRwRvp9oL0pd6AFMIWYjnmc7ZuD7dSjFdBnDUAwsBUxsBpPVL2ETyhBHD4pcBoZey9FWsBSBFtgCo', 'retry': '0', 'returncd': '001', 'challenge': 'SI2pRtEj', 'datetime': '20150530141615'}
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116] Received connection from 192.168.10.64:63116
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116] SENDING: '\lc\1\challenge\OVBQANAGLG\id\1\final\'...
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116] RESPONSE: '\ka\\final\'...
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116] {'ka': '', '__cmd__': 'ka', '__cmd_val__': ''}
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116] RESPONSE: '\login\\challenge\BwiFGNL3zdG0nwUXDw4u2FxH2GSri9i7\authtoken\NDSQGSRRwRvp9oL0pd6AFMIWYjnmc7ZuD7dSjFdBnDUAwsBUxsBpPVL2ETyhBHD4pcBoZey9FWsBSBFtgCo\partnerid\11\response\9cf3ec170601c507f99ef49e112d8746\firewall\1\port\0\productid\11059\gamename\mariokartwii\namespaceid\16\sdkrevision\3\quiet\0\id\1\final\'...
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116] {'authtoken': 'NDSQGSRRwRvp9oL0pd6AFMIWYjnmc7ZuD7dSjFdBnDUAwsBUxsBpPVL2ETyhBHD4pcBoZey9FWsBSBFtgCo', 'gamename': 'mariokartwii', 'firewall': '1', 'namespaceid': '16', 'challenge': 'BwiFGNL3zdG0nwUXDw4u2FxH2GSri9i7', '__cmd_val__': '', 'quiet': '0', 'port': '0', 'sdkrevision': '3', '__cmd__': 'login', 'partnerid': '11', 'id': '1', 'login': '', 'response': '9cf3ec170601c507f99ef49e112d8746', 'productid': '11059'}
[2015-05-30 14:16:16 | GamespyDatabase] [cCNipa5w] STARTING: SELECT data FROM nas_logins WHERE authtoken = NDSQGSRRwRvp9oL0pd6AFMIWYjnmc7ZuD7dSjFdBnDUAwsBUxsBpPVL2ETyhBHD4pcBoZey9FWsBSBFtgCo
[2015-05-30 14:16:16 | GamespyDatabase] [cCNipa5w] DONE: Took 0.000581026077271 real time / 0.000576 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [HObnhKiW] STARTING: SELECT COUNT(*) FROM users WHERE userid = 1332113423010 AND gsbrcd = RMCJ1142o2r
[2015-05-30 14:16:16 | GamespyDatabase] [HObnhKiW] DONE: Took 0.0192239284515 real time / 0.000253 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [YIDGyN7i] STARTING: SELECT * FROM users WHERE userid = 1332113423010 and gsbrcd = RMCJ1142o2r
[2015-05-30 14:16:16 | GamespyDatabase] [YIDGyN7i] DONE: Took 0.000241994857788 real time / 0.000239 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [joF8QH3K] STARTING: SELECT COUNT(*) FROM users WHERE profileid = 50
[2015-05-30 14:16:16 | GamespyDatabase] [joF8QH3K] DONE: Took 9.91821289062e-05 real time / 9.5e-05 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [P40J9Psa] STARTING: DELETE FROM sessions WHERE profileid = 50
[2015-05-30 14:16:16 | GamespyDatabase] [P40J9Psa] DONE: Took 0.000291109085083 real time / 0.000288 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [2rXq0SkK] STARTING: SELECT COUNT(*) FROM sessions WHERE session = 39706383
[2015-05-30 14:16:16 | GamespyDatabase] [2rXq0SkK] DONE: Took 0.000118017196655 real time / 0.000106 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [VrZ86NFV] STARTING: INSERT INTO sessions VALUES (39706383, 50, amEzTGNJTmtZNVJLWmtKNA__)
[2015-05-30 14:16:16 | GamespyDatabase] [VrZ86NFV] DONE: Took 0.000189781188965 real time / 0.000189 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [aBo43Ss7] STARTING: SELECT * FROM buddies WHERE userProfileId = 0 AND blocked = 0
[2015-05-30 14:16:16 | GamespyDatabase] [aBo43Ss7] DONE: Took 0.000163078308105 real time / 0.000152 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [Gp43Gtvo] STARTING: SELECT * FROM buddies WHERE userProfileId = 0 AND blocked = 1
[2015-05-30 14:16:16 | GamespyDatabase] [Gp43Gtvo] DONE: Took 0.000104904174805 real time / 0.000102 processor time
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116 | 50 | RMCJ] SENDING: \lc\2\sesskey\39706383\proof\954891d0013b72b2a4f67561b5f6568b\userid\1332113423010\profileid\50\uniquenick\16ok2bel2RMCJ1142o2r\lt\amEzTGNJTmtZNVJLWmtKNA__\id\1\final\
[2015-05-30 14:16:16 | GamespyDatabase] [l2jJ3ZfM] STARTING: SELECT * FROM pending_messages WHERE targetid = 50
[2015-05-30 14:16:16 | GamespyDatabase] [l2jJ3ZfM] DONE: Took 0.000246047973633 real time / 0.000192 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [Q2va17RH] STARTING: SELECT * FROM buddies WHERE userProfileId = 50 AND blocked = 0
[2015-05-30 14:16:16 | GamespyDatabase] [Q2va17RH] DONE: Took 0.0383861064911 real time / 0.000198 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [mHOuhcaD] STARTING: SELECT * FROM buddies WHERE userProfileId = 50 AND blocked = 0
[2015-05-30 14:16:16 | GamespyDatabase] [mHOuhcaD] DONE: Took 3.71932983398e-05 real time / 3.5e-05 processor time
Unhandled Error
Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/twisted/python/log.py", line 88, in callWithLogger
    return callWithContext({"system": lp}, func, *args, **kw)
  File "/usr/lib/python2.7/dist-packages/twisted/python/log.py", line 73, in callWithContext
    return context.call({ILogContext: newCtx}, func, *args, **kw)
  File "/usr/lib/python2.7/dist-packages/twisted/python/context.py", line 118, in callWithContext
    return self.currentContext().callWithContext(ctx, func, *args, **kw)
  File "/usr/lib/python2.7/dist-packages/twisted/python/context.py", line 81, in callWithContext
    return func(*args,**kw)
--- <exception caught here> ---
  File "/usr/lib/python2.7/dist-packages/twisted/internet/posixbase.py", line 619, in _doReadOrWrite
    why = selectable.doWrite()
  File "/usr/lib/python2.7/dist-packages/twisted/internet/abstract.py", line 247, in doWrite
    self.dataBuffer, self.offset, self._tempDataBuffer)
  File "/usr/lib/python2.7/dist-packages/twisted/internet/abstract.py", line 31, in _concatenate
    return buffer(bObj, offset) + b"".join(bArray)
exceptions.TypeError: sequence item 1: expected string, bytearray found
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116 | 50 | RMCJ] Client disconnected
[2015-05-30 14:16:16 | GamespyDatabase] [UgH3PblP] STARTING: SELECT * FROM buddies WHERE userProfileId = 50 AND blocked = 0
[2015-05-30 14:16:16 | GamespyDatabase] [UgH3PblP] DONE: Took 6.41345977783e-05 real time / 6.10000000001e-05 processor time
[2015-05-30 14:16:16 | GamespyDatabase] [m8HgNt9n] STARTING: DELETE FROM sessions WHERE profileid = 39706383
[2015-05-30 14:16:16 | GamespyDatabase] [m8HgNt9n] DONE: Took 5.22136688232e-05 real time / 5.1e-05 processor time
[2015-05-30 14:16:16 | GameSpyProfileServer] [192.168.10.64:63116 | 50 | RMCJ] Deleted session