Board Game Arena

I feel like I'm hounding this issue a little, but I am seeing absolutely no error messages that I've put into my code. Apologies for the post full of code that's about to follow, but I feel like I'm going crazy not understanding what's going wrong. I would really appreciate any help anyone has. For reference, here's the newGameSetup function, where I have excessive amounts of debug calls just to try and make something happen.

protected function setupNewGame( $players, $options = array() )
{
$sql = "DELETE FROM player WHERE 1 ";
self::DbQuery( $sql );
$default_colors = array( "ff0000", "008000", "0000ff", "ffa500", "773300" );
$sql = "INSERT INTO player (player_id, player_color, player_canal, player_name, player_avatar) VALUES ";
$values = array();
foreach( $players as $player_id => $player )
{
$color = array_shift( $default_colors );
$values[] = "('".$player_id."','$color','".$player['player_canal']."','".addslashes( $player['player_name'] )."','".addslashes( $player['player_avatar'] )."')";
}
$sql .= implode( $values, ',' );
self::DbQuery( $sql );
self::reloadPlayersBasicInfos();
$this->rollAll();
$msg = "rolled";
self::debug($msg);
self::error($msg);
self::warn($msg);
$this->activeNextPlayer();
self::debug("nextplayeractivated");
self::error("activated");
}

And when I start a new game, I see none of those messages in the SQL/Request logs: (http://en.1.studio.boardgamearena.com/f ... 534#bottom)

07/11 14:47:12 [info] [T7534] [2246801/coolpapa0] /img/blankdie.jpg0
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] /farkle/farkle/wakeup.html?myturnack=true&table=7534&dojo.preventCache=13838320426270
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] 0.31 SELECT global_id, global_value FROM global WHERE 1 0
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] setTable0
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] 0.29 SELECT global_id, global_value FROM global WHERE 1 0
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] notifyNow notification. Type = yourturnack0
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] 0.63 SELECT player_id, player_remaining_reflexion_time,

UNIX_TIMESTAMP( player_start_reflexion_time ) start_reflexion_time, UNIX_TIMESTAMP( NOW() ) now_time

FROM player 0
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] DB commit: action validate0
07/11 14:47:17 [notice] [T7534] [2246801/coolpapa0] OK-0 57 d8 c13 e0 I29 A23 V1 T1 /farkle/farkle/wakeup.html?myturnack=true&table=7534&dojo.preventCache=13838320426270
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] /web/scripterror?log=Script%20error.%0AScript%3A%20chrome%3A%2F%2Fsap%2Fcontent%2Fsap.xml%0AUrl%3A%20http%3A%2F%2Fen.1.studio.boardgamearena.com%2Ffarkle%3Ftable%3D7534%0AU%3D2246801%0AMozilla%2F5.0%20(Windows%20NT%206.1%3B%20WOW64%3B%20rv%3A25.0)%20Gecko%2F20100101%20Firefox%2F25.0%0A&table=7534&dojo.preventCache=13838320426700
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] /web/scripterror?log=Error%3A%20Permission%20denied%20to%20access%20property%20%27toString%27%0AScript%3A%20%0AUrl%3A%20http%3A%2F%2Fen.1.studio.boardgamearena.com%2Ffarkle%3Ftable%3D7534%0AU%3D2246801%0AMozilla%2F5.0%20(Windows%20NT%206.1%3B%20WOW64%3B%20rv%3A25.0)%20Gecko%2F20100101%20Firefox%2F25.0%0A&table=7534&dojo.preventCache=13838320426620
07/11 14:47:17 [info] [T7534] [2246801/coolpapa0] /web/index.php0
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] /web/index.php0
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] /farkle/farkle/notificationHistory.html?table=7534&from=2&privateinc=1&dojo.preventCache=13838320435110
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] 0.33 SELECT global_id, global_value FROM global WHERE 1 0
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] setTable0
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] 0.39 SELECT global_id, global_value FROM global WHERE 1 0
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] 18.6 SELECT gamelog_packet_id, gamelog_move_id, gamelog_player, gamelog_notification FROM gamelog WHERE gamelog_packet_id >= 2 AND ( gamelog_player IS NULL OR gamelog_player='2246801') ORDER BY gamelog_packet_id ASC 0
07/11 14:47:18 [info] [T7534] [2246801/coolpapa0] DB commit: action validate0
07/11 14:47:18 [notice] [T7534] [2246801/coolpapa0] OK-0 49 d21 c0 e0 I18 A27 V1 T1 /farkle/farkle/notificationHistory.html?table=7534&from=2&privateinc=1&dojo.preventCache=1383832043511

Hi,

I will check but debug log message may not be processed during game setup.

Did you manage to use debug logs during the game itself?

Cheers,

I have some messages in the function called at the start of the first game state. I haven't gotten much farther than that in the code - I just haven't had the time to dig into the javascript side yet. Would there be similar issues for that initial game state?

All right, I see now. Later in the game, I'm getting the error messages to appear. Thanks again for the help.