UWSCで排他制御しようと思ってるんですが
http://d.hatena.ne.jp/junjun777/20110810/uwsc_mutex
ここのソースを実行してみたところ（スレッド数は4にしてます)
以下のようなログが出力されるのですが、同じログが複数出るのと
finやstartが出力されない時があるのが理解不能です。何故なのか教えてくれませんか？
21:10.308 TestThread init 1
21:10.346 TestThread start 1 True
21:10.364 TestThread init 2
21:10.385 TestThread init 3
21:10.406 TestThread init 4
21:10.462 TestThread end 1(154)
21:10.475 TestThread fin 1 True(167)
21:10.475 TestThread start 2 True
21:10.582 TestThread end 2(218)
21:10.610 TestThread fin 2 True(246)
21:10.611 TestThread start 4 True
21:10.719 TestThread end 4(313)
21:10.723 TestThread start 3 True
21:10.723 TestThread start 3 True
21:10.881 TestThread end 3(496)
21:10.885 TestThread fin 3 True(500)