Are you making sure you hit update?

absolutely. I've also got three other players in the game who've seen my little green check mark next to my name (after it happened the first time, everybody started paying more attention to it).

Email Kestas to check the logs. The new code could potentially have a bug. I don't know how much changed int he order handling routines. He's a good guy. He'll check the logs.

Here are the order logs:
Time: Aug 20 2009 12:45:36 (GMT+0)
Submission #: 1
Array
(
[2196743] => Array
(
[type] => Support move
[toTerr] => Silesia
[fromTerr] => Bohemia
)

[2196735] => Array
(
[type] => Move
[toTerr] => Sweden
)

[2196723] => Array
(
[type] => Move
[toTerr] => Silesia
)

[2196722] => Array
(
[type] => Support move
[toTerr] => Sweden
[fromTerr] => Baltic Sea
)

[2196721] => Array
(
[type] => Move
[toTerr] => North Sea
)

)


Time: Aug 21 2009 19:33:12 (GMT+0)
Submission #: 1
Array
(
[2218339] => Array
(
[type] => Build Army
[toTerr] => Munich
)

)


Time: Aug 21 2009 19:33:15 (GMT+0)
Submission #: 2
Array
(
[2218339] => Array
(
[type] => Build Army
[toTerr] => Munich
)

)


Time: Aug 24 2009 18:11:58 (GMT+0)
Submission #: 1
Array
(
[2242906] => Array
(
[type] => Hold
)

[2242901] => Array
(
[type] => Move
)

[2242895] => Array
(
[type] => Hold
)

[2242884] => Array
(
[type] => Move
)

[2242883] => Array
(
[type] => Move
)

[2242882] => Array
(
[type] => Move
)

)


Time: Aug 24 2009 18:12:28 (GMT+0)
Submission #: 2
Array
(
[2242906] => Array
(
[type] => Hold
)

[2242901] => Array
(
[type] => Move
[toTerr] => Silesia
)

[2242895] => Array
(
[type] => Hold
)

[2242884] => Array
(
[type] => Move
[toTerr] => Bohemia
)

[2242883] => Array
(
[type] => Move
[toTerr] => 0
)

[2242882] => Array
(
[type] => Convoy
[toTerr] => 0
)

)


Time: Aug 24 2009 18:12:56 (GMT+0)
Submission #: 3
Array
(
[2242906] => Array
(
[type] => Hold
)

[2242901] => Array
(
[type] => Move
[toTerr] => Silesia
)

[2242895] => Array
(
[type] => Hold
)

[2242884] => Array
(
[type] => Move
[toTerr] => Bohemia
)

[2242883] => Array
(
[type] => Move
[toTerr] => Holland
)

[2242882] => Array
(
[type] => Convoy
[toTerr] => Holland
)

)


Time: Aug 24 2009 18:13:24 (GMT+0)
Submission #: 4
Array
(
[2242906] => Array
(
[type] => Hold
)

[2242901] => Array
(
[type] => Move
[toTerr] => Silesia
)

[2242895] => Array
(
[type] => Hold
)

[2242884] => Array
(
[type] => Move
[toTerr] => Bohemia
)

[2242883] => Array
(
[type] => Move
[toTerr] => Holland
)

[2242882] => Array
(
[type] => Convoy
[toTerr] => Holland
)

)

Looks like there was a gap between Aug 21 2009 19:33:15 (GMT+0), the unit placing phase, and Aug 24 2009 18:11:58 (GMT+0), the last phase, so the order logs are showing NMR for that phase

For the record: I'm one of the players who saw the checkmarks. In the global conversation, NinjaPirateRobotZombie also said that s/he saw the checkmarks.

I've downloaded the http access logs, and the phpDip access logs, and painstakingly pieced them together by time and IP address hoping to finally find the elusive bug:

Aug 21 2009 05:55:04 Game progressed to Unit-placing, Autumn 1905

Time: Aug 21 2009 19:33:12 (GMT+0)
69.180.72.162 - - [21/Aug/2009:12:33:11 -0700] "POST /board.php?gameID=12366 HTTP/1.1" 200 7937 "http://webdiplomacy.net/board.php?gameID=12366&msgCountry=France&rand=38409" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"

Time: Aug 21 2009 19:33:15 (GMT+0)
69.180.72.162 - - [21/Aug/2009:12:33:15 -0700] "POST /board.php?gameID=12366 HTTP/1.1" 200 7768 "http://webdiplomacy.net/board.php?gameID=12366" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"


Aug 21 2009 18:05:05 Game progressed to Diplomacy, Spring 1906
// <-- Note that there is nothing here, during the critical period

Aug 23 2009 06:10:06 Game progressed to Retreats, Spring 1906

69.180.72.162 - - [23/Aug/2009:19:15:55 -0700] "POST /board.php?gameID=12366&msgCountry=Russia HTTP/1.1" 200 6640 "http://www.webdiplomacy.net/board.php?gameID=12366&msgCountry=Russia&rand=82619" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"


Aug 24 2009 04:35:04 Game progressed to Diplomacy, Autumn 1906

69.180.72.162 - - [24/Aug/2009:11:09:19 -0700] "POST /board.php?gameID=12366&msgCountry=Turkey HTTP/1.1" 200 7630 "http://www.webdiplomacy.net/board.php?gameID=12366&msgCountry=Turkey&rand=2599" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"
69.180.72.162 - - [24/Aug/2009:11:09:55 -0700] "POST /board.php?gameID=12366&msgCountry=Russia HTTP/1.1" 200 6959 "http://www.webdiplomacy.net/board.php?gameID=12366&msgCountry=Russia&rand=76606" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"

Time: Aug 24 2009 18:11:58 (GMT+0)
69.180.72.162 - - [24/Aug/2009:11:11:57 -0700] "POST /board.php?gameID=12366 HTTP/1.1" 200 8112 "http://www.webdiplomacy.net/board.php?gameID=12366&msgCountry=France&rand=6051" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"

Time: Aug 24 2009 18:12:28 (GMT+0)
69.180.72.162 - - [24/Aug/2009:11:12:28 -0700] "POST /board.php?gameID=12366 HTTP/1.1" 200 8112 "http://www.webdiplomacy.net/board.php?gameID=12366" "Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US) AppleWebKit/530.5 (KHTML, like Gecko) Chrome/2.0.172.39 Safari/530.5"


All the logs corroborate each other; http access logs, order logs, chat logs, notice logs, and multi-accounter logs. All the times match, and they all say there was no access to the board during the Diplomacy, Spring 1906 by that user (including orders logs, IP address access, no messages or raw http access logs).

Is it possible that the ticks you saw were during a different phase? Say the retreats phase immediately afterwards or unit placing phase immediately before, when you would be expected to have seen the tick?

The time I've spent tracking down this bug is depressing, and I always give people credit, but it's starting to reach the point that I can't justify even looking into these mysterious occurrences because it has *always* ended up that all the logs corroborate each other perfectly, no matter how much extra data I log.

I hope you guys are at least being *very* careful when you say you saw checkmarks, that you *know* it was during the correct phase, because I have better things to do than this

Kestas, I appreciate you taking the time with this, and my impression is that it happened for a third time in the few hours before this post. Not my country, not me playing, so I can't 100% positive. Same game. Since I don't have a saved record of it, I (like you) must suspect human error, but my memory is that in all three of these instances there was a checkmark indicating that some orders were entered. This is buttressed by several other players remembering the checkmark in the prior instances.

http://webdiplomacy.net/board.php?gameID=12366

Hi Kestas,

Not trying to be offputting here, just a simple question. Are the logs you record stored in the DB? Or are they on text files of some sort? I ask because if the DB did some massive rollback for some bizarre reason, then logs stored in the DG would have the same rollback performed on them, but if they are stored as flat files on disk, that doesn't apply.

It's just a thought...

Here is why I ask. I once had something of a similar nature happen. The DB was set to autorollback uncommitted changes in a session when the session timed out minutes or even hours later. I accidentally began a transaction on a web submittal and one logic path out of the routine didn't have either a commit or a rollback, so it looked like the data was there, then suddenly any changes that heppened in that session were undone. It took me a long time to track that one down, but now I never forget to track my transactions when I see weird disappearing data.

Meanwhile, could a mod force the game to be paused? We're a few hours away from the deadline, and the player may be screwed pretty bad if it happens this turn. The last couple of times he didn't have too many attackers, but this time he probably does.

Draugnar, the logs are there precisely for that reason, as I understand it.

Which is why I respectfully and politely asked if they were stored independent of the DB or within it. A log inside the DB is very convenient but can be rolled back if the transaction of the session in which it was stored gets rolled back.

I was polite. I explained why I asked and what once happened to me. Is that OK, Ghostie?

Yes, I'm not criticising the question or anything- you've misread my tone there- but rather saying that my understanding is that the logs are not part of the database.

Good, then they should reflect if a rollback occured as they are independent of it.

Kestas, anything I can do to help? I can hold my own with SQL statements and catching little quirks in them if that is any help.

Ok, the player at issue just seems to have re-entered his orders. Or at least the checkmark is there again.

Just to prove that I'm not crazy, another player just confirmed that they also saw a checkmark earlier this phase, and then he also stated that there wasn't one.