Matchmaking Elite Progressive : Bug

[niarfman]

Hi Nadeo Team,

I have tried Progressive MatchMaking for Elite with min 2 player per Team and max 3.

It works fine if 4 players are on server => Go 2V2

The same with 6 players => 3V3

But If a game start 2V2 and 2 substitutes join, the game crash with a runtime error and following error message occurs in logs :

Code: Select all

[2014/08/21 19:10:48] Runtime error: [Modes/ShootMania/ModeSport.Script.txt : 2122, 23][Called from : 383, 15] The procedure SetPlayerClan failed to complete :
        Wrong clan
Restarting the match....
[2014/08/21 19:10:48] Script 'Mode:Elite' halted: [Modes/ShootMania/ModeSport.Script.txt : 2122, 23][Called from : 383, 15] The procedure SetPlayerClan failed to complete :
        Wrong clan

Any Idea ?

Ps : If only one more player join, no pb, the game become 2v3. But if a new player join again => Crash

[niarfman]

Already 13 days without any answer or indication for knowing if this thread has been read

[novationx]

[Eole]

The error occurs at a pretty strange place. The players were still waiting the beginning of the match when it happened? The match was played on one or several maps?
You can try to run the lobby and match servers with the following settings turned on to get more details : S_MatchmakingLogAPIError, S_MatchmakingLogAPIDebug and S_MatchmakingLogMiscDebug.

[niarfman]

Hi Eole,

Thanks for the answer, but it's a little too late. These servers are stopped.

Maybe I will try to restart them soon. If I get the same issue, I will update here.

[niarfman]

I have restart The Progressive Lobby Lvx. Hope it will be fine.

Keep you informed.

[Eole]

Ok, thanks.

[niarfman]

The bug is still here. Encountered today around 18:40:26 - 18:40:50

There is the log from Lobby server:

Code: Select all

[2014/09/24 18:40:26] <chat> [lvxelitelobby ($i$39cL$9f3V$6ffX$fff$o _$6ff$i$oProgressive Elite $fffLobby 40K-90K $000Te)] ?%1$<%2$> joined his match as a substitute.?$000»$09f?$39cL$9f3?$6ff? $fff'tRAIT$01FOF$0FFBiB
[2014/09/24 18:40:26] <chat> [lvxelitelobby ($i$39cL$9f3V$6ffX$fff$o _$6ff$i$oProgressive Elite $fffLobby 40K-90K $000Te)] ?%1$<%2$> joined his match as a substitute.?$000»$09f?$o$s$n$FFFi$999x$FFFdQ$FFFy$FF0*

Code: Select all

[2014/09/24 18:40:14] Script 'Mode:Elite': 28141910> [API] Response: POST /lobby-server/matchmaking-live
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><status availableservers="1" playersnb="6"/><matches/><substitutes><player login="mrboogietw" server="lvxelitematch1" matchid="154951"/><player login="biboo" server="lvxelitematch1" matchid="154951"/></substitutes><masters><master login="yk0z"/><master login="choupa"/><master login="five50"/><master login="cryum_chafa"/><master login="five50"/><master login="zegodzila"/><master login="biboo"/><master login="five50"/><master login="epsybam"/><master login="tigroo87"/><master login="tigroo87"/><master login="egophagus"/><master login="egophagus"/><master login="egophagus"/><master login="lyneya"/><master login="kiss_cool"/><master login="nampo"/><master login="biboo"/><master login="biboo"/><master login="biboo"/></masters></response>

-->
28141910> [API] Match to execute: []

[2014/09/24 18:40:26] Script 'Mode:Elite': 28153770> [SERVER] biboo > Sent as substitute (154951) on server : "lvxelitematch1"
28153770> [SERVER] mrboogietw > Sent as substitute (154951) on server : "lvxelitematch1"

[2014/09/24 18:40:26] Disconnection of mrboogietw(88.170.228.118:2350)
[2014/09/24 18:40:26] Disconnection of biboo(82.228.208.9:2350)
[2014/09/24 18:40:28] Script 'Mode:Elite': 28156160> [API] Request: POST /lobby-server/matchmaking-live
<!--

{
        "lobby": "lvxelitelobby",
        "gamemode": "Elite",
        "titleid": "SMStormElite@nadeolabs",
        "format": [3, 3],
        "players": [

        ],
        "cancelers": [

        ]
}
-->

[2014/09/24 18:40:29] Script 'Mode:Elite': 28156740> [API] Response: POST /lobby-server/matchmaking-live
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><status availableservers="1" playersnb="6"/><matches/><substitutes/><masters><master login="yk0z"/><master login="choupa"/><master login="five50"/><master login="cryum_chafa"/><master login="five50"/><master login="zegodzila"/><master login="biboo"/><master login="five50"/><master login="epsybam"/><master login="tigroo87"/><master login="tigroo87"/><master login="egophagus"/><master login="egophagus"/><master login="egophagus"/><master login="lyneya"/><master login="kiss_cool"/><master login="nampo"/><master login="biboo"/><master login="biboo"/><master login="biboo"/></masters></response>

-->
28156740> [API] Match to execute: []

[2014/09/24 18:40:42] Script 'Mode:Elite': 28170210> [API] Request: POST /lobby-server/matchmaking-live
<!--

{
        "lobby": "lvxelitelobby",
        "gamemode": "Elite",
        "titleid": "SMStormElite@nadeolabs",
        "format": [3, 3],
        "players": [

        ],
        "cancelers": [

        ]
}
-->

[2014/09/24 18:40:43] Script 'Mode:Elite': 28170700> [API] Response: POST /lobby-server/matchmaking-live
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><status availableservers="1" playersnb="6"/><matches/><substitutes/><masters><master login="yk0z"/><master login="choupa"/><master login="five50"/><master login="cryum_chafa"/><master login="five50"/><master login="zegodzila"/><master login="biboo"/><master login="five50"/><master login="epsybam"/><master login="tigroo87"/><master login="tigroo87"/><master login="egophagus"/><master login="egophagus"/><master login="egophagus"/><master login="lyneya"/><master login="kiss_cool"/><master login="nampo"/><master login="biboo"/><master login="biboo"/><master login="biboo"/></masters></response>

-->
28170700> [API] Match to execute: []

[2014/09/24 18:40:56] Script 'Mode:Elite': 28184170> [API] Request: POST /lobby-server/matchmaking-live
<!--

{
        "lobby": "lvxelitelobby",
        "gamemode": "Elite",
        "titleid": "SMStormElite@nadeolabs",
        "format": [3, 3],
        "players": [

        ],
        "cancelers": [

        ]
}
-->

[2014/09/24 18:40:57] Script 'Mode:Elite': 28184690> [API] Response: POST /lobby-server/matchmaking-live
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><status availableservers="1" playersnb="0"/><matches/><substitutes/><masters><master login="yk0z"/><master login="choupa"/><master login="five50"/><master login="cryum_chafa"/><master login="five50"/><master login="zegodzila"/><master login="biboo"/><master login="five50"/><master login="epsybam"/><master login="tigroo87"/><master login="tigroo87"/><master login="egophagus"/><master login="egophagus"/><master login="egophagus"/><master login="lyneya"/><master login="kiss_cool"/><master login="nampo"/><master login="biboo"/><master login="biboo"/><master login="biboo"/></masters></response>

-->
28184690> [API] Match to execute: []

[2014/09/24 18:41:04] Connection of a new player: mrboogietw(88.170.228.118:2350)
[2014/09/24 18:41:10] Script 'Mode:Elite': 28197800> [API] Request: POST /lobby-server/matchmaking-live
<!--

{
        "lobby": "lvxelitelobby",
        "gamemode": "Elite",
        "titleid": "SMStormElite@nadeolabs",
        "format": [3, 3],
        "players": [

        ],
        "cancelers": [

        ]
}
-->

[2014/09/24 18:41:10] Script 'Mode:Elite': 28197990> [API] Response: POST /lobby-server/matchmaking-live
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><status availableservers="1" playersnb="0"/><matches/><substitutes/><masters><master login="yk0z"/><master login="choupa"/><master login="five50"/><master login="cryum_chafa"/><master login="five50"/><master login="zegodzila"/><master login="biboo"/><master login="five50"/><master login="epsybam"/><master login="tigroo87"/><master login="tigroo87"/><master login="egophagus"/><master login="egophagus"/><master login="egophagus"/><master login="lyneya"/><master login="kiss_cool"/><master login="nampo"/><master login="biboo"/><master login="biboo"/><master login="biboo"/></masters></response>

-->
28197990> [API] Match to execute: []

[2014/09/24 18:41:15] Script 'Mode:Elite': 28203370> [API] Request: GET /lobby-server/player-connection?login=mrboogietw&lobbylogin=lvxelitelobby

[2014/09/24 18:41:15] Script 'Mode:Elite': 28203440> [API] Response: GET /lobby-server/player-connection?login=xxx&lobbylogin=lvxelitelobby
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response/>

-->

[2014/09/24 18:41:22] Script 'Mode:Elite': 28209810> [SERVER] mrboogietw > Sent to match () on server : ""

And now the log from match server:

Code: Select all

[2014/09/24 18:39:19] Script 'Mode:Elite': 28102180> [API] Request: POST /match-server/live
<!--

{
        "serverlogin": "lvxelitematch1",
        "status": 3,
        "matchid": 154951,
        "missingplayers": [
{
        "login":"*player0*",
        "kicked": false,
        "clan": 1
},
{
        "login":"*player1*",
        "kicked": false,
        "clan": 2
}]
}
-->

[2014/09/24 18:39:27] Script 'Mode:Elite': 28110160> Win by elimination of attack player

[2014/09/24 18:40:09] Script 'Mode:Elite': 28151740> Win by elimination of all defense players

[2014/09/24 18:40:19] Script 'Mode:Elite': 28162040> [API] Request: POST /match-server/live
<!--

{
        "serverlogin": "lvxelitematch1",
        "status": 3,
        "matchid": 154951,
        "missingplayers": [
{
        "login":"*player0*",
        "kicked": false,
        "clan": 1
},
{
        "login":"*player1*",
        "kicked": false,
        "clan": 2
}]
}
-->

[2014/09/24 18:40:27] Connection of a new player: mrboogietw(88.170.228.118:2350)
[2014/09/24 18:40:27] Connection of a new player: biboo(82.228.208.9:2350)
[2014/09/24 18:40:35] Script 'Mode:Elite': 28177720> [API] Request: Get /match-server/match?serverlogin=lvxelitematch1

[2014/09/24 18:40:35] Script 'Mode:Elite': 28177830> [API] Response: GET /match-server/match?serverlogin=lvxelitematch1
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><match id="154951"><server lobby="lvxelitelobby" match="lvxelitematch1"/><format><clan number="2"/><clan number="2"/></format><players><player login="*player0*" clan="1" replaced="true"/><player login="*player1*" clan="2" replaced="true"/><player login="biboo" clan="2"/><player login="choupa" clan="1" order="1"/><player login="devilounet" clan="0" order="1"/><player login="kiss_cool" clan="1"/><player login="mrboogietw" clan="1"/><player login="zackaris" clan="0"/></players></match></response>

-->

[2014/09/24 18:40:35] Script 'Mode:Elite': 28177840> [API] Request: POST /match-server/live
<!--

{
        "serverlogin": "lvxelitematch1",
        "status": 3,
        "matchid": 154951,
        "missingplayers": [
{
        "login":"mrboogietw",
        "kicked": false,
        "clan": 2
}]
}
-->

[2014/09/24 18:40:36] Script 'Mode:Elite': 28179280> [API] Request: Get /match-server/match?serverlogin=lvxelitematch1

[2014/09/24 18:40:36] Script 'Mode:Elite': 28179370> [API] Response: GET /match-server/match?serverlogin=lvxelitematch1
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response><match id="154951"><server lobby="lvxelitelobby" match="lvxelitematch1"/><format><clan number="2"/><clan number="2"/></format><players><player login="*player0*" clan="1" replaced="true"/><player login="*player1*" clan="2" replaced="true"/><player login="biboo" clan="2"/><player login="choupa" clan="1" order="1"/><player login="devilounet" clan="0" order="1"/><player login="kiss_cool" clan="1"/><player login="mrboogietw" clan="1"/><player login="zackaris" clan="0"/></players></match></response>

-->

[2014/09/24 18:40:36] Script 'Mode:Elite': 28179380> [API] Request: POST /match-server/live
<!--

{
        "serverlogin": "lvxelitematch1",
        "status": 2,
        "matchid": 154951,
        "missingplayers": []
}
-->

[2014/09/24 18:40:45] Script 'Mode:Elite': 28187870> Win by elimination of attack player

[2014/09/24 18:40:48] Runtime error: [Modes/ShootMania/ModeSport.Script.txt : 425, 22][Called from : 290, 27] The procedure SetPlayerClan failed to complete :
        Wrong clan
Restarting the match....
[2014/09/24 18:40:48] Script 'Mode:Elite' halted: [Modes/ShootMania/ModeSport.Script.txt : 425, 22][Called from : 290, 27] The procedure SetPlayerClan failed to complete :
        Wrong clan

[2014/09/24 18:40:50] Script 'Mode:Elite': 28192860> Script: ModeBase.Script.txt | Version: 2014-08-01
28192860> Script: Mode.Script.txt | Version: 2014-02-07
28192860> Script: AFK.Script.txt | Version: 2014-05-16
28192860> Script: XmlRpc.Script.txt | Version: 2014-08-28
28192860> Script: Clublink.Script.txt | Version: 2014-04-22
28192860> Script: ScoresTable.Script.txt | Version: 2014-05-30
28192860> Script: ModeMatchmaking.Script.txt | Version: 2014-09-09
28192860> Script: Layers.Script.txt | Version: 2014-01-08
28192860> Script: Message.Script.txt | Version: 2013-12-16
28192860> Script: VoteMap.Script.txt | Version: 2014-07-31
28192860> Script: SpawnScreen.Script.txt | Version: 2013-11-08
28192860> Script: ModeSport.Script.txt | Version: 2014-08-01
28192860> Script: SM.Script.txt | Version: 2014-04-10
28192860> Script: Json.Script.txt | Version: 2013-12-10
28192860> Script: Toss.Script.txt | Version: 2013-07-16
28192860> Script: Score.Script.txt | Version: 2012-10-31
28192860> Script: Layers.Script.txt | Version: 2014-01-08
28192860> Script: WarmUp2.Script.txt | Version: 2014-04-23
28192860> Script: Message.Script.txt | Version: 2013-12-16
28192860> Script: Interface.Script.txt | Version: 2014-07-15
28192860> Script: SpawnScreen.Script.txt | Version: 2013-11-08
28192860> Script: Elite.Script.txt | Version: 2014-07-25
28192860> Script: Draft.Script.txt | Version: 2012-12-17
28192860> Script: CustomUI.Script.txt | Version: 2014-05-19
28192860> Script: Settings.Script.txt | Version: 2013-03-08
28192860> Script: EliteStats.Script.txt | Version: 2014-06-04
28192860> Script: ElitePractice.Script.txt | Version: 2014-07-25
28192860> Script: EliteEndSequence.Script.txt | Version: 2014-07-21

[2014/09/24 18:40:50] Script 'Mode:Elite': 28193400> [API] Request: POST /match-server/live
<!--

{
        "serverlogin": "lvxelitematch1",
        "status": 0,
        "matchid": -1,
        "missingplayers": []
}
-->

[2014/09/24 18:40:50] Script 'Mode:Elite': 28193410> [API] Request: Get /match-server/match?serverlogin=lvxelitematch1

[2014/09/24 18:40:50] Script 'Mode:Elite': 28193620> [API] Response: GET /match-server/match?serverlogin=lvxelitematch1
<!--
<?xml version="1.0" encoding="UTF-8"?>
<response/>

-->

[2014/09/24 18:40:50] Disconnection of choupa(109.212.92.190:2350)
[2014/09/24 18:40:50] Disconnection of devilounet(85.117.134.92:2350)
[2014/09/24 18:40:50] Disconnection of zackaris(90.51.140.8:2350)
[2014/09/24 18:40:50] Disconnection of kiss_cool(88.167.89.96:2350)
[2014/09/24 18:40:50] Disconnection of mrboogietw(88.170.228.118:2350)
[2014/09/24 18:40:50] Disconnection of biboo(82.228.208.9:2350)
[2014/09/24 18:41:51] Script 'Mode:Elite': 28253580> [API] Request: POST /match-server/live
<!--

{
        "serverlogin": "lvxelitematch1",
        "status": 0,
        "matchid": -1,
        "missingplayers": []
}
-->

[niarfman]

The error occurs at a pretty strange place. The players were still waiting the beginning of the match when it happened? The match was played on one or several maps?

For more information :
The error occurs when 1 or 2 substitutes are coming from Lobby to Match Server where 4 others players are already here and playing.

As soon as the 1 or 2 substitutes are seen on the Match Server, the Match Server crashs, all players are "kicked" from the server.

[Eole]

The logs were really helpful to solve the problem, thanks a lot for that.
You can check this post if you want to test the latest matchmaking script which fix this bug.