[2015-05-30 15:20:19 | GameSpyQRServer] [192.168.10.64:58803] Received availability request for 'mariokartwii' from 192.168.10.64:58803...
[2015-05-30 15:20:19 | NasServer] Request to /ac from ('192.168.10.64', 54989)
[2015-05-30 15:20:19 | NasServer] {'lang': '03', 'gamecd': 'RMCP', 'makercd': '01', 'unitcd': '1', 'ipaddr': '192.168.10.64', 'region': '02', 'userid': '1332113423010', 'gsbrcd': 'RMCJ06u5i53', 'cfc': '4558571766239210', 'confmethod': '00', 'ingamesn': '\x00t\x00i\x00t\x00i\x00a', 'devtime': '150530143301', 'csnum': 'LEH100105018', 'action': 'login', 'macadr': '0017ab5c7680', 'sdkver': '001000'}
127.0.0.1 - - [30/May/2015 15:20:19] "POST /ac HTTP/1.1" 200 -
[2015-05-30 15:20:19 | GamespyDatabase] [FSkEpMGn] STARTING: SELECT COUNT(*) FROM banned WHERE gameid = RMC AND ipaddr = 192.168.10.64
[2015-05-30 15:20:19 | GamespyDatabase] [FSkEpMGn] DONE: Took 0.000559091567993 real time / 0.000555 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [yEJHnaTG] STARTING: SELECT COUNT(*) FROM nas_logins WHERE authtoken = NDShEAZ7HVbabDHfZDa9sXbYcWwN9FIRiNKVVumR4rSYmmMZ0Yws8KzvZbRK22XbscoNp4RLNJBG4obZJZ0
[2015-05-30 15:20:19 | GamespyDatabase] [yEJHnaTG] DONE: Took 9.58442687988e-05 real time / 9.29999999999e-05 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [uJ9FgRUM] STARTING: SELECT * FROM nas_logins WHERE userid = 1332113423010
[2015-05-30 15:20:19 | GamespyDatabase] [uJ9FgRUM] DONE: Took 0.000117063522339 real time / 0.000114 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [PsdKNNCE] STARTING: UPDATE nas_logins SET authtoken = NDShEAZ7HVbabDHfZDa9sXbYcWwN9FIRiNKVVumR4rSYmmMZ0Yws8KzvZbRK22XbscoNp4RLNJBG4obZJZ0, data = {"lang": "03", "gamecd": "RMCP", "makercd": "01", "unitcd": "1", "challenge": "tGaimvWY", "ipaddr": "192.168.10.64", "region": "02", "userid": "1332113423010", "gsbrcd": "RMCJ06u5i53", "cfc": "4558571766239210", "confmethod": "00", "ingamesn": "AHQAaQB0AGkAYQ__", "devtime": "150530143301", "csnum": "LEH100105018", "action": "login", "macadr": "0017ab5c7680", "sdkver": "001000"} WHERE userid = 1332113423010
[2015-05-30 15:20:19 | GamespyDatabase] [PsdKNNCE] DONE: Took 0.000272989273071 real time / 0.000272 processor time
[2015-05-30 15:20:19 | NasServer] login response to ('192.168.10.64', 54989)
[2015-05-30 15:20:19 | NasServer] {'locator': 'gamespy.com', 'token': 'NDShEAZ7HVbabDHfZDa9sXbYcWwN9FIRiNKVVumR4rSYmmMZ0Yws8KzvZbRK22XbscoNp4RLNJBG4obZJZ0', 'retry': '0', 'returncd': '001', 'challenge': 'tGaimvWY', 'datetime': '20150530152019'}
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038] Received connection from 192.168.10.64:56038
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038] SENDING: '\lc\1\challenge\CKHRMVUSDB\id\1\final\'...
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038] RESPONSE: '\ka\\final\'...
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038] {'ka': '', '__cmd__': 'ka', '__cmd_val__': ''}
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038] RESPONSE: '\login\\challenge\w0KnFHnfRSt1TMkcqg4tKNQ8c6x9ITbl\authtoken\NDShEAZ7HVbabDHfZDa9sXbYcWwN9FIRiNKVVumR4rSYmmMZ0Yws8KzvZbRK22XbscoNp4RLNJBG4obZJZ0\partnerid\11\response\1aadb84af2c48a6c05f4d13c5d1581f6\firewall\1\port\0\productid\11059\gamename\mariokartwii\namespaceid\16\sdkrevision\3\quiet\0\id\1\final\'...
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038] {'authtoken': 'NDShEAZ7HVbabDHfZDa9sXbYcWwN9FIRiNKVVumR4rSYmmMZ0Yws8KzvZbRK22XbscoNp4RLNJBG4obZJZ0', 'gamename': 'mariokartwii', 'firewall': '1', 'namespaceid': '16', 'challenge': 'w0KnFHnfRSt1TMkcqg4tKNQ8c6x9ITbl', '__cmd_val__': '', 'quiet': '0', 'port': '0', 'sdkrevision': '3', '__cmd__': 'login', 'partnerid': '11', 'id': '1', 'login': '', 'response': '1aadb84af2c48a6c05f4d13c5d1581f6', 'productid': '11059'}
[2015-05-30 15:20:19 | GamespyDatabase] [tXH0eLt6] STARTING: SELECT data FROM nas_logins WHERE authtoken = NDShEAZ7HVbabDHfZDa9sXbYcWwN9FIRiNKVVumR4rSYmmMZ0Yws8KzvZbRK22XbscoNp4RLNJBG4obZJZ0
[2015-05-30 15:20:19 | GamespyDatabase] [tXH0eLt6] DONE: Took 0.000559091567993 real time / 0.000555 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [YKtd8MYd] STARTING: SELECT COUNT(*) FROM users WHERE userid = 1332113423010 AND gsbrcd = RMCJ06u5i53
[2015-05-30 15:20:19 | GamespyDatabase] [YKtd8MYd] DONE: Took 0.000147819519043 real time / 0.000146 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [rfhuNcB7] STARTING: SELECT * FROM users WHERE userid = 1332113423010 and gsbrcd = RMCJ06u5i53
[2015-05-30 15:20:19 | GamespyDatabase] [rfhuNcB7] DONE: Took 0.000209808349609 real time / 0.000207 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [EMuOKmJo] STARTING: SELECT COUNT(*) FROM users WHERE profileid = 51
[2015-05-30 15:20:19 | GamespyDatabase] [EMuOKmJo] DONE: Took 8.9168548584e-05 real time / 8.69999999999e-05 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [YTn2HpAU] STARTING: DELETE FROM sessions WHERE profileid = 51
[2015-05-30 15:20:19 | GamespyDatabase] [YTn2HpAU] DONE: Took 0.000297069549561 real time / 0.000279 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [PxfPhtge] STARTING: SELECT COUNT(*) FROM sessions WHERE session = 44443395
[2015-05-30 15:20:19 | GamespyDatabase] [PxfPhtge] DONE: Took 0.000116109848022 real time / 0.000101 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [IXNkuHnx] STARTING: INSERT INTO sessions VALUES (44443395, 51, YnZtbkhsOHVPNXk2Q3FaUQ__)
[2015-05-30 15:20:19 | GamespyDatabase] [IXNkuHnx] DONE: Took 0.000180959701538 real time / 0.000179 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [N044fjsj] STARTING: SELECT * FROM buddies WHERE userProfileId = 0 AND blocked = 0
[2015-05-30 15:20:19 | GamespyDatabase] [N044fjsj] DONE: Took 0.000153064727783 real time / 0.00014 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [V0NUvGRh] STARTING: SELECT * FROM buddies WHERE userProfileId = 0 AND blocked = 1
[2015-05-30 15:20:19 | GamespyDatabase] [V0NUvGRh] DONE: Took 9.89437103271e-05 real time / 9.69999999998e-05 processor time
[2015-05-30 15:20:19 | GameSpyProfileServer] [192.168.10.64:56038 | 51 | RMCJ] SENDING: \lc\2\sesskey\44443395\proof\59ebcd017250895bc7c14c0b9aee0720\userid\1332113423010\profileid\51\uniquenick\16ok2bel2RMCJ06u5i53\lt\YnZtbkhsOHVPNXk2Q3FaUQ__\id\1\final\
[2015-05-30 15:20:19 | GamespyDatabase] [ork73ZUf] STARTING: SELECT * FROM pending_messages WHERE targetid = 51
[2015-05-30 15:20:19 | GamespyDatabase] [ork73ZUf] DONE: Took 9.58442687988e-05 real time / 9.30000000001e-05 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [5lI1MHYt] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:19 | GamespyDatabase] [5lI1MHYt] DONE: Took 4.60147857666e-05 real time / 4.3e-05 processor time
[2015-05-30 15:20:19 | GamespyDatabase] [GCRWlV2F] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:19 | GamespyDatabase] [GCRWlV2F] DONE: Took 3.60012054443e-05 real time / 3.40000000001e-05 processor time
[2015-05-30 15:20:19 | GameSpyQRServer] [192.168.10.64:64812 0581a82b] Received heartbeat from 192.168.10.64:64812... localip0192.168.1.4localip1190.190.190.190localport64812natneg1statechanged1gamenamemariokartwiipublicip0publicport0numplayers0maxplayers0unknown51unknown0unknown90unknown1unknown0unknown0unknown0
[2015-05-30 15:20:19 | GameSpyQRServer] [192.168.10.64:64812 0581a82b] publicport 0 doesn't match localport 64812, so changing publicport to 64812...
[2015-05-30 15:20:19 | GameSpyQRServer] [192.168.10.64:64812 0581a82b] Sent challenge to 192.168.10.64:64812...
[2015-05-30 15:20:19 | GameSpyQRServer] [192.168.10.64:64812 0581a82b] Received challenge from 192.168.10.64:64812... A8gyFH62b1nksQf9/3JI1nJAH60A
[2015-05-30 15:20:19 | GameSpyQRServer] [192.168.10.64:64812 0581a82b] Sent client registered to 192.168.10.64:64812...
[2015-05-30 15:20:20 | GamespyBackendServer] Added {'natneg': '1', '__session__': 92383275, 'localport': '64812', 'unknown': '0', 'gamename': 'mariokartwii', 'numplayers': '0', 'maxplayers': '0', 'publicip': '1074440384', '__console__': 0, 'localip0': '192.168.1.4', 'localip1': '190.190.190.190', 'publicport': '64812', 'statechanged': '1'} to the server list for mariokartwii
[2015-05-30 15:20:20 | GamespyBackendServer] mariokartwii servers: 1
[2015-05-30 15:20:20 | GameSpyProfileServer] [192.168.10.64:56038 | 51 | RMCJ] RESPONSE: '\status\1\sesskey\44443395\statstring\/SCM/1/SCN/0/VER/90\locstring\\final\\updatepro\\sesskey\44443395\firstname\Wii:4558571766239210@RMCP\partnerid\11\final\'...
[2015-05-30 15:20:20 | GameSpyProfileServer] [192.168.10.64:56038 | 51 | RMCJ] {'status': '1', '__cmd_val__': '1', 'statstring': '/SCM/1/SCN/0/VER/90', '__cmd__': 'status', 'sesskey': '44443395', 'locstring': ''}
[2015-05-30 15:20:20 | GamespyDatabase] [cKWbQ57z] STARTING: SELECT * FROM buddies WHERE buddyProfileId = 51 AND status = 0
[2015-05-30 15:20:20 | GamespyDatabase] [cKWbQ57z] DONE: Took 0.000195980072021 real time / 0.000191 processor time
[2015-05-30 15:20:20 | GamespyDatabase] [SKyfgUW8] STARTING: SELECT * FROM buddies WHERE buddyProfileId = 51 AND status = 1 AND notified = 0
[2015-05-30 15:20:20 | GamespyDatabase] [SKyfgUW8] DONE: Took 0.000128984451294 real time / 0.000126 processor time
[2015-05-30 15:20:20 | GamespyDatabase] [aQlIEnHg] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:20 | GamespyDatabase] [aQlIEnHg] DONE: Took 4.50611114502e-05 real time / 4.20000000001e-05 processor time
[2015-05-30 15:20:20 | GameSpyProfileServer] [192.168.10.64:56038 | 51 | RMCJ] {'updatepro': '', 'firstname': 'Wii:4558571766239210@RMCP', '__cmd_val__': '', '__cmd__': 'updatepro', 'sesskey': '44443395', 'partnerid': '11'}
[2015-05-30 15:20:20 | GamespyDatabase] [LBopPvS4] STARTING: UPDATE users SET "firstname" = Wii:4558571766239210@RMCP WHERE profileid = 51
[2015-05-30 15:20:20 | GamespyDatabase] [LBopPvS4] DONE: Took 0.000221014022827 real time / 0.000218 processor time
[2015-05-30 15:20:22 | GameSpyProfileServer] [192.168.10.64:56038 | 51 | RMCJ] RESPONSE: '\addbuddy\\sesskey\44443395\newprofileid\52\reason\\final\'...
[2015-05-30 15:20:22 | GameSpyProfileServer] [192.168.10.64:56038 | 51 | RMCJ] {'addbuddy': '', '__cmd_val__': '', 'reason': '', '__cmd__': 'addbuddy', 'sesskey': '44443395', 'newprofileid': '52'}
[2015-05-30 15:20:22 | GamespyDatabase] [sjZ9OwWv] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:22 | GamespyDatabase] [sjZ9OwWv] DONE: Took 7.39097595215e-05 real time / 7e-05 processor time
[2015-05-30 15:20:22 | GamespyDatabase] [U5ZyUrjl] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:22 | GamespyDatabase] [U5ZyUrjl] DONE: Took 4.60147857666e-05 real time / 4.3e-05 processor time
[2015-05-30 15:20:22 | GamespyDatabase] [MS61xCnm] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:22 | GamespyDatabase] [MS61xCnm] DONE: Took 4.50611114502e-05 real time / 4.19999999999e-05 processor time
[2015-05-30 15:20:22 | GamespyDatabase] [O2jDfSL4] STARTING: SELECT * FROM buddies WHERE userProfileId = 51 AND blocked = 0
[2015-05-30 15:20:22 | GamespyDatabase] [O2jDfSL4] DONE: Took 4.48226928711e-05 real time / 4.20000000001e-05 processor time
[2015-05-30 15:20:23 | GameSpyPlayerSearchServer] SEARCH RESPONSE: \otherslist\\sesskey\44443395\profileid\51\numopids\4\opids\42|49|45|52\namespaceid\16\gamename\mariokartwii\final\
{'gamename': 'mariokartwii', '__cmd_val__': '', 'opids': '42|49|45|52', 'numopids': '4', '__cmd__': 'otherslist', 'sesskey': '44443395', 'profileid': '51', 'namespaceid': '16', 'otherslist': ''}
[2015-05-30 15:20:23 | GamespyDatabase] [bDehtxj2] STARTING: SELECT * FROM users WHERE profileid = 42
[2015-05-30 15:20:23 | GamespyDatabase] [bDehtxj2] DONE: Took 0.00070595741272 real time / 0.000691 processor time
[2015-05-30 15:20:23 | GamespyDatabase] [xWDB4EUT] STARTING: SELECT * FROM users WHERE profileid = 49
[2015-05-30 15:20:23 | GamespyDatabase] [xWDB4EUT] DONE: Took 7.89165496826e-05 real time / 7.60000000002e-05 processor time
[2015-05-30 15:20:23 | GamespyDatabase] [gqiuobc0] STARTING: SELECT * FROM users WHERE profileid = 45
[2015-05-30 15:20:23 | GamespyDatabase] [gqiuobc0] DONE: Took 7.08103179932e-05 real time / 6.8e-05 processor time
[2015-05-30 15:20:23 | GamespyDatabase] [DMIr5Qkc] STARTING: SELECT * FROM users WHERE profileid = 52
[2015-05-30 15:20:23 | GamespyDatabase] [DMIr5Qkc] DONE: Took 6.48498535156e-05 real time / 6.30000000001e-05 processor time
[2015-05-30 15:20:23 | GameSpyPlayerSearchServer] SENDING: \otherslist\\o\42\uniquenick\44debj1n0RMCJ2vi1q59\o\49\uniquenick\2mfuhcevlRMCJ08r6r9d\o\45\uniquenick\6lmebvel8RMCJ3knrffp\o\52\uniquenick\44debj1n0RMCJ278d7t1\oldone\\final\
[2015-05-30 15:20:23 | StorageServer] SakeStorageServer SOAPAction "http://gamespy.net/sake/GetMyRecords"
[2015-05-30 15:20:23 | StorageServer] <?xml version="1.0" encoding="UTF-8"?><SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" xmlns:SOAP-ENC="http://schemas.xmlsoap.org/soap/encoding/" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:ns1="http://gamespy.net/sake"><SOAP-ENV:Body><ns1:GetMyRecords><ns1:gameid>1687</ns1:gameid><ns1:secretKey>9r3Rmy</ns1:secretKey><ns1:loginTicket>YnZtbkhsOHVPNXk2Q3FaUQ__</ns1:loginTicket><ns1:tableid>FriendInfo</ns1:tableid><ns1:fields><ns1:string>info</ns1:string><ns1:string>recordid</ns1:string></ns1:fields></ns1:GetMyRecords></SOAP-ENV:Body></SOAP-ENV:Envelope>
[2015-05-30 15:20:23 | GamespyDatabase] [6cO77NCE] STARTING: SELECT profileid FROM sessions WHERE loginticket = YnZtbkhsOHVPNXk2Q3FaUQ__
[2015-05-30 15:20:23 | GamespyDatabase] [6cO77NCE] DONE: Took 0.000443935394287 real time / 0.000439 processor time
[2015-05-30 15:20:23 | StorageServer] SELECT info,recordid FROM g1687_FriendInfo WHERE  ( ownerid = 51 )
127.0.0.1 - - [30/May/2015 15:20:23] "POST /SakeStorageServer/StorageServer.asmx HTTP/1.1" 200 -
[2015-05-30 15:20:23 | StorageServer] "http://gamespy.net/sake/GetMyRecords" response to ('127.0.0.1', 58307)
[2015-05-30 15:20:39 | GameSpyQRServer] [192.168.10.64:64812 0581a82b] Received keep alive from 192.168.10.64:64812...