-
Notifications
You must be signed in to change notification settings - Fork 0
/
Copy pathhedwig-server.log.2013-04-11
3717 lines (3717 loc) · 622 KB
/
hedwig-server.log.2013-04-11
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
301
302
303
304
305
306
307
308
309
310
311
312
313
314
315
316
317
318
319
320
321
322
323
324
325
326
327
328
329
330
331
332
333
334
335
336
337
338
339
340
341
342
343
344
345
346
347
348
349
350
351
352
353
354
355
356
357
358
359
360
361
362
363
364
365
366
367
368
369
370
371
372
373
374
375
376
377
378
379
380
381
382
383
384
385
386
387
388
389
390
391
392
393
394
395
396
397
398
399
400
401
402
403
404
405
406
407
408
409
410
411
412
413
414
415
416
417
418
419
420
421
422
423
424
425
426
427
428
429
430
431
432
433
434
435
436
437
438
439
440
441
442
443
444
445
446
447
448
449
450
451
452
453
454
455
456
457
458
459
460
461
462
463
464
465
466
467
468
469
470
471
472
473
474
475
476
477
478
479
480
481
482
483
484
485
486
487
488
489
490
491
492
493
494
495
496
497
498
499
500
501
502
503
504
505
506
507
508
509
510
511
512
513
514
515
516
517
518
519
520
521
522
523
524
525
526
527
528
529
530
531
532
533
534
535
536
537
538
539
540
541
542
543
544
545
546
547
548
549
550
551
552
553
554
555
556
557
558
559
560
561
562
563
564
565
566
567
568
569
570
571
572
573
574
575
576
577
578
579
580
581
582
583
584
585
586
587
588
589
590
591
592
593
594
595
596
597
598
599
600
601
602
603
604
605
606
607
608
609
610
611
612
613
614
615
616
617
618
619
620
621
622
623
624
625
626
627
628
629
630
631
632
633
634
635
636
637
638
639
640
641
642
643
644
645
646
647
648
649
650
651
652
653
654
655
656
657
658
659
660
661
662
663
664
665
666
667
668
669
670
671
672
673
674
675
676
677
678
679
680
681
682
683
684
685
686
687
688
689
690
691
692
693
694
695
696
697
698
699
700
701
702
703
704
705
706
707
708
709
710
711
712
713
714
715
716
717
718
719
720
721
722
723
724
725
726
727
728
729
730
731
732
733
734
735
736
737
738
739
740
741
742
743
744
745
746
747
748
749
750
751
752
753
754
755
756
757
758
759
760
761
762
763
764
765
766
767
768
769
770
771
772
773
774
775
776
777
778
779
780
781
782
783
784
785
786
787
788
789
790
791
792
793
794
795
796
797
798
799
800
801
802
803
804
805
806
807
808
809
810
811
812
813
814
815
816
817
818
819
820
821
822
823
824
825
826
827
828
829
830
831
832
833
834
835
836
837
838
839
840
841
842
843
844
845
846
847
848
849
850
851
852
853
854
855
856
857
858
859
860
861
862
863
864
865
866
867
868
869
870
871
872
873
874
875
876
877
878
879
880
881
882
883
884
885
886
887
888
889
890
891
892
893
894
895
896
897
898
899
900
901
902
903
904
905
906
907
908
909
910
911
912
913
914
915
916
917
918
919
920
921
922
923
924
925
926
927
928
929
930
931
932
933
934
935
936
937
938
939
940
941
942
943
944
945
946
947
948
949
950
951
952
953
954
955
956
957
958
959
960
961
962
963
964
965
966
967
968
969
970
971
972
973
974
975
976
977
978
979
980
981
982
983
984
985
986
987
988
989
990
991
992
993
994
995
996
997
998
999
1000
2013-04-11 18:04:21,167 - INFO [main:HedwigHubTestBase1@158] - STARTING null
2013-04-11 18:04:21,315 - INFO [main:PortAssignment@32] - assigning port 11221
2013-04-11 18:04:22,162 - INFO [main:ClientBase@388] - STARTING server
2013-04-11 18:04:22,263 - INFO [main:Environment@98] - Server environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:04:22,263 - INFO [main:Environment@98] - Server environment:host.name=ly-PC
2013-04-11 18:04:22,263 - INFO [main:Environment@98] - Server environment:java.version=1.7.0_01
2013-04-11 18:04:22,263 - INFO [main:Environment@98] - Server environment:java.vendor=Oracle Corporation
2013-04-11 18:04:22,264 - INFO [main:Environment@98] - Server environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:04:22,264 - INFO [main:Environment@98] - Server environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:04:22,264 - INFO [main:Environment@98] - Server environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:04:22,265 - INFO [main:Environment@98] - Server environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:04:22,265 - INFO [main:Environment@98] - Server environment:java.compiler=<NA>
2013-04-11 18:04:22,265 - INFO [main:Environment@98] - Server environment:os.name=Windows 7
2013-04-11 18:04:22,265 - INFO [main:Environment@98] - Server environment:os.arch=x86
2013-04-11 18:04:22,266 - INFO [main:Environment@98] - Server environment:os.version=6.1
2013-04-11 18:04:22,266 - INFO [main:Environment@98] - Server environment:user.name=ly
2013-04-11 18:04:22,267 - INFO [main:Environment@98] - Server environment:user.home=C:\Users\ly
2013-04-11 18:04:22,267 - INFO [main:Environment@98] - Server environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:04:22,291 - INFO [main:ZooKeeperServer@171] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir F:\logDir\test5378682057854998466.junit.dir\version-2 snapdir F:\logDir\test5378682057854998466.junit.dir\version-2
2013-04-11 18:04:22,367 - INFO [main:NIOServerCnxnFactory@110] - binding to port 0.0.0.0/0.0.0.0:15000
2013-04-11 18:04:22,374 - INFO [main:FileTxnSnapLog@238] - Snapshotting: 0x0 to F:\logDir\test5378682057854998466.junit.dir\version-2\snapshot.0
2013-04-11 18:04:22,416 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:04:22,417 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49773
2013-04-11 18:04:22,422 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@820] - Processing stat command from /127.0.0.1:49773
2013-04-11 18:04:22,426 - INFO [Thread-2:NIOServerCnxn$StatCommand@655] - Stat command output
2013-04-11 18:04:22,426 - INFO [Thread-2:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49773 (no session established for client)
2013-04-11 18:04:22,426 - INFO [main:JMXEnv@133] - ensureOnly:[InMemoryDataTree, StandaloneServer_port]
2013-04-11 18:04:22,443 - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
2013-04-11 18:04:22,444 - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
2013-04-11 18:04:22,444 - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
2013-04-11 18:04:22,444 - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
2013-04-11 18:04:22,444 - INFO [main:ClientBase@384] - Client test setup finished
2013-04-11 18:04:22,470 - INFO [main:Environment@98] - Client environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:04:22,470 - INFO [main:Environment@98] - Client environment:host.name=ly-PC
2013-04-11 18:04:22,470 - INFO [main:Environment@98] - Client environment:java.version=1.7.0_01
2013-04-11 18:04:22,470 - INFO [main:Environment@98] - Client environment:java.vendor=Oracle Corporation
2013-04-11 18:04:22,471 - INFO [main:Environment@98] - Client environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:04:22,471 - INFO [main:Environment@98] - Client environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:04:22,471 - INFO [main:Environment@98] - Client environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:04:22,471 - INFO [main:Environment@98] - Client environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:04:22,471 - INFO [main:Environment@98] - Client environment:java.compiler=<NA>
2013-04-11 18:04:22,471 - INFO [main:Environment@98] - Client environment:os.name=Windows 7
2013-04-11 18:04:22,472 - INFO [main:Environment@98] - Client environment:os.arch=x86
2013-04-11 18:04:22,472 - INFO [main:Environment@98] - Client environment:os.version=6.1
2013-04-11 18:04:22,472 - INFO [main:Environment@98] - Client environment:user.name=ly
2013-04-11 18:04:22,472 - INFO [main:Environment@98] - Client environment:user.home=C:\Users\ly
2013-04-11 18:04:22,472 - INFO [main:Environment@98] - Client environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:04:22,473 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@a93a16
2013-04-11 18:04:22,490 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:22,508 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:22,509 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:22,509 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49776
2013-04-11 18:04:22,512 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49776
2013-04-11 18:04:22,531 - INFO [SyncThread:0:FileTxnLog@199] - Creating new log file: log.1
2013-04-11 18:04:22,591 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88d99ee0000 with negotiated timeout 30000 for client /127.0.0.1:49776
2013-04-11 18:04:22,591 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88d99ee0000, negotiated timeout = 30000
2013-04-11 18:04:22,593 - INFO [main:JMXEnv@105] - expect:0x13df88d99ee0000
2013-04-11 18:04:22,593 - INFO [main:JMXEnv@108] - found:0x13df88d99ee0000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x13df88d99ee0000
2013-04-11 18:04:23,083 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@9e7e94
2013-04-11 18:04:23,085 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:23,086 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:23,087 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49779
2013-04-11 18:04:23,087 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:23,097 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49779
2013-04-11 18:04:23,111 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88d99ee0001 with negotiated timeout 10000 for client /127.0.0.1:49779
2013-04-11 18:04:23,111 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88d99ee0001, negotiated timeout = 10000
2013-04-11 18:04:23,125 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:04:23,447 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:04:23,490 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:04:23,490 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:04:23,495 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:04:23,496 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:04:23,500 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150011303839997630502291test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:04:23,524 - INFO [BookieJournal-15001:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150011303839997630502291test\current\19ba9c.txn
2013-04-11 18:04:23,626 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@ea0c2a
2013-04-11 18:04:23,631 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:23,633 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:23,634 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:23,643 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49784
2013-04-11 18:04:23,643 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49784
2013-04-11 18:04:23,746 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88d99ee0002 with negotiated timeout 10000 for client /127.0.0.1:49784
2013-04-11 18:04:23,746 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88d99ee0002, negotiated timeout = 10000
2013-04-11 18:04:23,747 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:04:23,816 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:04:23,818 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:04:23,818 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:04:23,818 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:04:23,818 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:04:23,819 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150024680552174343562418test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:04:23,822 - INFO [BookieJournal-15002:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150024680552174343562418test\current\19bbc8.txn
2013-04-11 18:04:23,912 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@d5b224
2013-04-11 18:04:23,915 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:23,917 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:23,917 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49789
2013-04-11 18:04:23,918 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:23,918 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49789
2013-04-11 18:04:24,012 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88d99ee0003 with negotiated timeout 10000 for client /127.0.0.1:49789
2013-04-11 18:04:24,012 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88d99ee0003, negotiated timeout = 10000
2013-04-11 18:04:24,013 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:04:24,147 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:04:24,150 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:04:24,150 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:04:24,150 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:04:24,151 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:04:24,151 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150036929333511292819186test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:04:24,156 - INFO [BookieJournal-15003:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150036929333511292819186test\current\19bd16.txn
2013-04-11 18:04:24,410 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.zookeeper.ZooKeeperWatcherBase@47411c
2013-04-11 18:04:24,413 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:24,413 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:24,414 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:24,414 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49794
2013-04-11 18:04:24,414 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49794
2013-04-11 18:04:24,457 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88d99ee0004 with negotiated timeout 10000 for client /127.0.0.1:49794
2013-04-11 18:04:24,458 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88d99ee0004, negotiated timeout = 10000
2013-04-11 18:04:24,796 - INFO [Thread-7:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=2000 watcher=org.apache.hedwig.server.netty.PubSubServer$1@1095197
2013-04-11 18:04:24,799 - INFO [Thread-7-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:24,800 - INFO [Thread-7-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:24,801 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:24,801 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49797
2013-04-11 18:04:24,802 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49797
2013-04-11 18:04:24,914 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88d99ee0005 with negotiated timeout 6000 for client /127.0.0.1:49797
2013-04-11 18:04:24,914 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88d99ee0005, negotiated timeout = 6000
2013-04-11 18:04:25,389 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x2 zxid:0x12 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers/METADATA Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers/METADATA
2013-04-11 18:04:25,478 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x3 zxid:0x13 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers
2013-04-11 18:04:25,503 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x4 zxid:0x14 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone Error:KeeperErrorCode = NoNode for /hedwig/standalone
2013-04-11 18:04:25,521 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x5 zxid:0x15 txntype:-1 reqpath:n/a Error Path:/hedwig Error:KeeperErrorCode = NoNode for /hedwig
2013-04-11 18:04:25,849 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0xb zxid:0x1b txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/hosts Error:KeeperErrorCode = NoNode for /hedwig/standalone/hosts
2013-04-11 18:04:25,940 - INFO [Thread-7-EventThread:ZkTopicManager$2@103] - Successfully registered hub hostname: "192.168.0.155:4080:9876"
czxid: 29
with zookeeper
2013-04-11 18:04:26,034 - INFO [Thread-7:PubSubServer@146] - isNotStandalone...................realPersistenceManager is BookkeeperPersistenceManager
2013-04-11 18:04:26,298 - INFO [Thread-7:FIFODeliveryManager@172] - persistenceMgr is ReadAheadCache...................
2013-04-11 18:04:26,573 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:04:27,160 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:04:27,186 - INFO [main:HedwigHubTestBase1@162] - HedwigHub test setup finished
2013-04-11 18:04:27,634 - INFO [Thread-7-EventThread:ZkTopicManager$ZkGetOwnerOp$1@165] - 192.168.0.155:4080:9876 : Least loaded owner hostname: "192.168.0.155:4080:9876"
czxid: 29
is chosen for topic messageQueue-test
2013-04-11 18:04:27,642 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x16 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test
2013-04-11 18:04:27,665 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x17 zxid:0x1f txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics
2013-04-11 18:04:27,847 - INFO [Thread-7-EventThread:BookkeeperPersistenceManager$AcquireOp@876] - Process 0 ledgers for topic messageQueue-test starting from seq id 1.
2013-04-11 18:04:27,920 - INFO [Thread-7-EventThread:AbstractSubscriptionManager$AcquireOp$1$1@268] - Subscription manager successfully acquired topic: messageQueue-test
2013-04-11 18:04:28,140 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88d99ee0005 type:create cxid:0x20 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test/subscribers Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test/subscribers
2013-04-11 18:04:28,235 - INFO [Thread-7-EventThread:CallbackUtils$3@148] - [standalone] all cross-region subscriptions succeeded
2013-04-11 18:04:28,336 - INFO [pool-14-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15001
2013-04-11 18:04:28,391 - INFO [pool-14-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15003
2013-04-11 18:04:28,397 - INFO [New I/O client worker #2-1:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15001
2013-04-11 18:04:28,397 - INFO [New I/O client worker #2-2:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15003
2013-04-11 18:04:28,677 - INFO [pool-14-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15002
2013-04-11 18:04:28,686 - INFO [New I/O client worker #2-3:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15002
2013-04-11 18:04:29,717 - INFO [DeliveryManagerThread:SubscribeHandler$1$1@206] - Subscribe request (2) for (topic:messageQueue-test, subscriber:@q_subid) from channel /127.0.0.1:49835 succeed - its subscription data is State : { consumeSeqId: local:0 };Preferences : { System Preferences : [] };
2013-04-11 18:04:29,736 - INFO [DeliveryManagerThread:SubscribeHandler$1$1@206] - Subscribe request (2) for (topic:messageQueue-test, subscriber:@q_subid) from channel /127.0.0.1:49834 succeed - its subscription data is State : { consumeSeqId: local:0 };Preferences : { System Preferences : [] };
2013-04-11 18:04:29,742 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1250] - lastConsumedSeq: 0; topic=messageQueue-test; subscriberId=@q_subid
2013-04-11 18:04:29,786 - INFO [Thread-12:HedwigClientImpl@112] - Stopping the client!
2013-04-11 18:04:29,786 - INFO [Thread-12:AbstractHChannelManager@607] - Shutting down the channels manager.
2013-04-11 18:04:29,787 - INFO [New I/O server worker #1-4:SubscriptionChannelManager@98] - Subscription channel /127.0.0.1:49835 for Topic: messageQueue-test, SubscriberId: @q_subid is disconnected.
2013-04-11 18:04:29,789 - INFO [Thread-12:HedwigClientImpl@122] - Completed stopping the client!
2013-04-11 18:04:29,808 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:04:29,809 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@643] - Subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 143) is throttled : last delivered 143, last consumed 50.
2013-04-11 18:04:29,824 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,824 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,824 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,824 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,825 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,825 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,825 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,825 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,825 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,825 - INFO [pool-8-thread-1:FIFODeliveryManager$ActiveSubscriberState@596] - Try to wake up subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 143) to deliver messages again : last delivered 143, last consumed 51.
2013-04-11 18:04:29,825 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,826 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,827 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,827 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,827 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,827 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,827 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,828 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,828 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,829 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,830 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,831 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,933 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:04:29,934 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@643] - Subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 200) is throttled : last delivered 200, last consumed 142.
2013-04-11 18:04:29,939 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,946 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,946 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,946 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,946 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,946 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,947 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,948 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,948 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,948 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,948 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,958 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,958 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,958 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,958 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,958 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,958 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,959 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,960 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:29,963 - INFO [DeliveryManagerThread:FIFODeliveryManager$ActiveSubscriberState@596] - Try to wake up subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 200) to deliver messages again : last delivered 200, last consumed 199.
2013-04-11 18:04:30,052 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:04:30,052 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@643] - Subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 550) is throttled : last delivered 550, last consumed 399.
2013-04-11 18:04:30,071 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:04:30,071 - INFO [New I/O server worker #1-5:SubscriptionChannelManager@98] - Subscription channel /127.0.0.1:49834 for Topic: messageQueue-test, SubscriberId: @q_subid is disconnected.
2013-04-11 18:04:30,072 - INFO [DeliveryManagerThread:FIFODeliveryManager$RemoveChannelForQueueRequest@1483] - Subscriber Topic: messageQueue-test, SubscriberId: @q_subid will be removed.
2013-04-11 18:04:30,072 - INFO [DeliveryManagerThread:FIFODeliveryManager$RemoveChannelForQueueRequest@1486] - Last consumed seq: 500
2013-04-11 18:04:30,072 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:04:44,332 - INFO [main:HedwigHubTestBase1@168] - tearDown starting
2013-04-11 18:04:44,409 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15001
2013-04-11 18:04:44,411 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15002
2013-04-11 18:04:44,413 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15003
2013-04-11 18:04:44,414 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88d99ee0005
2013-04-11 18:04:44,433 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49797 which had sessionid 0x13df88d99ee0005
2013-04-11 18:04:44,435 - INFO [Thread-7-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:04:44,436 - INFO [main:ZooKeeper@679] - Session: 0x13df88d99ee0005 closed
2013-04-11 18:04:44,455 - INFO [New I/O client worker #3-1:HChannelHandler@235] - Channel [id: 0x00cb8484, /127.0.0.1:49802 :> localhost/127.0.0.1:4080] was disconnected to host localhost/127.0.0.1:4080.
2013-04-11 18:04:44,455 - INFO [New I/O client worker #4-1:HChannelHandler@235] - Channel [id: 0x012c17ce, /127.0.0.1:49821 :> /127.0.0.1:4080] was disconnected to host /127.0.0.1:4080.
2013-04-11 18:04:44,456 - INFO [New I/O client worker #3-1:AbstractHChannelManager@363] - NonSubscription Channel [id: 0x00cb8484, /127.0.0.1:49802 :> localhost/127.0.0.1:4080] to localhost/127.0.0.1:4080 disconnected.
2013-04-11 18:04:44,456 - INFO [New I/O client worker #4-1:AbstractHChannelManager@363] - NonSubscription Channel [id: 0x012c17ce, /127.0.0.1:49821 :> /127.0.0.1:4080] to /127.0.0.1:4080 disconnected.
2013-04-11 18:04:44,482 - INFO [NIOServerFactory-15001:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:04:44,483 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88d99ee0001
2013-04-11 18:04:44,501 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49779 which had sessionid 0x13df88d99ee0001
2013-04-11 18:04:44,501 - INFO [main:ZooKeeper@679] - Session: 0x13df88d99ee0001 closed
2013-04-11 18:04:44,502 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:04:44,502 - INFO [Thread-4:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:04:44,503 - WARN [BookieJournal-15001:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:04:44,531 - WARN [NIOServerFactory-15002:NIOServerFactory@159] - Exception in server socket loop: /0:0:0:0:0:0:0:0
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.bookkeeper.proto.NIOServerFactory.run(NIOServerFactory.java:146)
2013-04-11 18:04:44,532 - INFO [NIOServerFactory-15002:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:04:44,533 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88d99ee0002
2013-04-11 18:04:44,560 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49784 which had sessionid 0x13df88d99ee0002
2013-04-11 18:04:44,561 - INFO [main:ZooKeeper@679] - Session: 0x13df88d99ee0002 closed
2013-04-11 18:04:44,561 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:04:44,562 - INFO [Thread-5:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:04:44,562 - WARN [BookieJournal-15002:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:04:44,591 - INFO [NIOServerFactory-15003:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:04:44,593 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88d99ee0003
2013-04-11 18:04:44,651 - INFO [main:ZooKeeper@679] - Session: 0x13df88d99ee0003 closed
2013-04-11 18:04:44,651 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:04:44,652 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@349] - caught end of stream exception
EndOfStreamException: Unable to read additional data from client sessionid 0x13df88d99ee0003, likely client has closed socket
at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:224)
at java.lang.Thread.run(Thread.java:722)
2013-04-11 18:04:44,652 - INFO [Thread-6:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:04:44,652 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49789 which had sessionid 0x13df88d99ee0003
2013-04-11 18:04:44,652 - WARN [BookieJournal-15003:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:04:44,673 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88d99ee0004
2013-04-11 18:04:44,700 - INFO [main:ZooKeeper@679] - Session: 0x13df88d99ee0004 closed
2013-04-11 18:04:44,701 - INFO [main:ClientBase@425] - tearDown starting
2013-04-11 18:04:44,701 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:04:44,700 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49794 which had sessionid 0x13df88d99ee0004
2013-04-11 18:04:44,702 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88d99ee0000
2013-04-11 18:04:44,715 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49776 which had sessionid 0x13df88d99ee0000
2013-04-11 18:04:44,715 - INFO [main:ZooKeeper@679] - Session: 0x13df88d99ee0000 closed
2013-04-11 18:04:44,716 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:04:44,716 - INFO [main:ClientBase@395] - STOPPING server
2013-04-11 18:04:44,726 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@240] - NIOServerCnxn factory exited run method
2013-04-11 18:04:44,728 - INFO [main:ZooKeeperServer@428] - shutting down
2013-04-11 18:04:44,728 - INFO [main:SessionTrackerImpl@220] - Shutting down
2013-04-11 18:04:44,728 - INFO [main:PrepRequestProcessor@733] - Shutting down
2013-04-11 18:04:44,729 - INFO [main:SyncRequestProcessor@173] - Shutting down
2013-04-11 18:04:44,729 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@135] - PrepRequestProcessor exited loop!
2013-04-11 18:04:44,729 - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
2013-04-11 18:04:44,730 - INFO [main:FinalRequestProcessor@414] - shutdown of request processor complete
2013-04-11 18:04:44,730 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:04:45,000 - INFO [SessionTracker:SessionTrackerImpl@162] - SessionTrackerImpl exited loop!
2013-04-11 18:04:45,731 - INFO [main:JMXEnv@133] - ensureOnly:[]
2013-04-11 18:04:45,735 - INFO [main:HedwigHubTestBase1@171] - FINISHED null
2013-04-11 18:04:58,256 - INFO [main:HedwigHubTestBase1@158] - STARTING null
2013-04-11 18:04:58,274 - INFO [main:PortAssignment@32] - assigning port 11221
2013-04-11 18:04:58,436 - INFO [main:ClientBase@388] - STARTING server
2013-04-11 18:04:58,444 - INFO [main:Environment@98] - Server environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:04:58,444 - INFO [main:Environment@98] - Server environment:host.name=ly-PC
2013-04-11 18:04:58,444 - INFO [main:Environment@98] - Server environment:java.version=1.7.0_01
2013-04-11 18:04:58,444 - INFO [main:Environment@98] - Server environment:java.vendor=Oracle Corporation
2013-04-11 18:04:58,445 - INFO [main:Environment@98] - Server environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:04:58,445 - INFO [main:Environment@98] - Server environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:04:58,445 - INFO [main:Environment@98] - Server environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:04:58,445 - INFO [main:Environment@98] - Server environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:04:58,445 - INFO [main:Environment@98] - Server environment:java.compiler=<NA>
2013-04-11 18:04:58,446 - INFO [main:Environment@98] - Server environment:os.name=Windows 7
2013-04-11 18:04:58,446 - INFO [main:Environment@98] - Server environment:os.arch=x86
2013-04-11 18:04:58,446 - INFO [main:Environment@98] - Server environment:os.version=6.1
2013-04-11 18:04:58,446 - INFO [main:Environment@98] - Server environment:user.name=ly
2013-04-11 18:04:58,447 - INFO [main:Environment@98] - Server environment:user.home=C:\Users\ly
2013-04-11 18:04:58,447 - INFO [main:Environment@98] - Server environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:04:58,460 - INFO [main:ZooKeeperServer@171] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir F:\logDir\test7175497768174423575.junit.dir\version-2 snapdir F:\logDir\test7175497768174423575.junit.dir\version-2
2013-04-11 18:04:58,473 - INFO [main:NIOServerCnxnFactory@110] - binding to port 0.0.0.0/0.0.0.0:15000
2013-04-11 18:04:58,478 - INFO [main:FileTxnSnapLog@238] - Snapshotting: 0x0 to F:\logDir\test7175497768174423575.junit.dir\version-2\snapshot.0
2013-04-11 18:04:58,498 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:04:58,499 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49862
2013-04-11 18:04:58,503 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@820] - Processing stat command from /127.0.0.1:49862
2013-04-11 18:04:58,506 - INFO [Thread-2:NIOServerCnxn$StatCommand@655] - Stat command output
2013-04-11 18:04:58,506 - INFO [Thread-2:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49862 (no session established for client)
2013-04-11 18:04:58,507 - INFO [main:JMXEnv@133] - ensureOnly:[InMemoryDataTree, StandaloneServer_port]
2013-04-11 18:04:58,510 - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
2013-04-11 18:04:58,510 - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
2013-04-11 18:04:58,511 - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
2013-04-11 18:04:58,511 - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
2013-04-11 18:04:58,511 - INFO [main:ClientBase@384] - Client test setup finished
2013-04-11 18:04:58,519 - INFO [main:Environment@98] - Client environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:04:58,519 - INFO [main:Environment@98] - Client environment:host.name=ly-PC
2013-04-11 18:04:58,519 - INFO [main:Environment@98] - Client environment:java.version=1.7.0_01
2013-04-11 18:04:58,519 - INFO [main:Environment@98] - Client environment:java.vendor=Oracle Corporation
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:java.compiler=<NA>
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:os.name=Windows 7
2013-04-11 18:04:58,520 - INFO [main:Environment@98] - Client environment:os.arch=x86
2013-04-11 18:04:58,521 - INFO [main:Environment@98] - Client environment:os.version=6.1
2013-04-11 18:04:58,521 - INFO [main:Environment@98] - Client environment:user.name=ly
2013-04-11 18:04:58,521 - INFO [main:Environment@98] - Client environment:user.home=C:\Users\ly
2013-04-11 18:04:58,521 - INFO [main:Environment@98] - Client environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:04:58,522 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@180809
2013-04-11 18:04:58,535 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:58,538 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:58,539 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:58,539 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49865
2013-04-11 18:04:58,541 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49865
2013-04-11 18:04:58,544 - INFO [SyncThread:0:FileTxnLog@199] - Creating new log file: log.1
2013-04-11 18:04:58,580 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88e26f30000 with negotiated timeout 30000 for client /127.0.0.1:49865
2013-04-11 18:04:58,581 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88e26f30000, negotiated timeout = 30000
2013-04-11 18:04:58,593 - INFO [main:JMXEnv@105] - expect:0x13df88e26f30000
2013-04-11 18:04:58,593 - INFO [main:JMXEnv@108] - found:0x13df88e26f30000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x13df88e26f30000
2013-04-11 18:04:58,814 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@fd56be
2013-04-11 18:04:58,816 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:58,816 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:58,817 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49868
2013-04-11 18:04:58,817 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:58,827 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49868
2013-04-11 18:04:58,841 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88e26f30001 with negotiated timeout 10000 for client /127.0.0.1:49868
2013-04-11 18:04:58,841 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88e26f30001, negotiated timeout = 10000
2013-04-11 18:04:58,845 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:04:59,011 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:04:59,037 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:04:59,037 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:04:59,058 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:04:59,058 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:04:59,061 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150011587836372278996938test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:04:59,068 - INFO [BookieJournal-15001:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150011587836372278996938test\current\1a4575.txn
2013-04-11 18:04:59,141 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@887b6
2013-04-11 18:04:59,143 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:59,144 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:59,145 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:59,145 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49873
2013-04-11 18:04:59,145 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49873
2013-04-11 18:04:59,230 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88e26f30002 with negotiated timeout 10000 for client /127.0.0.1:49873
2013-04-11 18:04:59,230 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88e26f30002, negotiated timeout = 10000
2013-04-11 18:04:59,232 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:04:59,302 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:04:59,305 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:04:59,305 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:04:59,306 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:04:59,306 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:04:59,307 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150021996525943518668273test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:04:59,317 - INFO [BookieJournal-15002:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150021996525943518668273test\current\1a466f.txn
2013-04-11 18:04:59,342 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@e0a659
2013-04-11 18:04:59,344 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:59,344 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:59,345 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:59,345 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49878
2013-04-11 18:04:59,345 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49878
2013-04-11 18:04:59,419 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88e26f30003 with negotiated timeout 10000 for client /127.0.0.1:49878
2013-04-11 18:04:59,419 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88e26f30003, negotiated timeout = 10000
2013-04-11 18:04:59,420 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:04:59,434 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:04:59,435 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:04:59,435 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:04:59,435 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:04:59,436 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:04:59,436 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie15003759196472559657963test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:04:59,439 - INFO [BookieJournal-15003:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie15003759196472559657963test\current\1a46e9.txn
2013-04-11 18:04:59,469 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.zookeeper.ZooKeeperWatcherBase@154614c
2013-04-11 18:04:59,472 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:59,472 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:59,473 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:59,483 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49883
2013-04-11 18:04:59,483 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49883
2013-04-11 18:04:59,527 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88e26f30004 with negotiated timeout 10000 for client /127.0.0.1:49883
2013-04-11 18:04:59,527 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88e26f30004, negotiated timeout = 10000
2013-04-11 18:04:59,610 - INFO [Thread-7:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=2000 watcher=org.apache.hedwig.server.netty.PubSubServer$1@631b3b
2013-04-11 18:04:59,612 - INFO [Thread-7-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:04:59,613 - INFO [Thread-7-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:04:59,613 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:04:59,613 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49886
2013-04-11 18:04:59,614 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49886
2013-04-11 18:04:59,641 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88e26f30005 with negotiated timeout 6000 for client /127.0.0.1:49886
2013-04-11 18:04:59,641 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88e26f30005, negotiated timeout = 6000
2013-04-11 18:04:59,707 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x2 zxid:0x12 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers/METADATA Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers/METADATA
2013-04-11 18:04:59,728 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x3 zxid:0x13 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers
2013-04-11 18:04:59,741 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x4 zxid:0x14 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone Error:KeeperErrorCode = NoNode for /hedwig/standalone
2013-04-11 18:04:59,761 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x5 zxid:0x15 txntype:-1 reqpath:n/a Error Path:/hedwig Error:KeeperErrorCode = NoNode for /hedwig
2013-04-11 18:04:59,875 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0xb zxid:0x1b txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/hosts Error:KeeperErrorCode = NoNode for /hedwig/standalone/hosts
2013-04-11 18:04:59,928 - INFO [Thread-7-EventThread:ZkTopicManager$2@103] - Successfully registered hub hostname: "192.168.0.155:4080:9876"
czxid: 29
with zookeeper
2013-04-11 18:04:59,934 - INFO [Thread-7:PubSubServer@146] - isNotStandalone...................realPersistenceManager is BookkeeperPersistenceManager
2013-04-11 18:04:59,940 - INFO [Thread-7:FIFODeliveryManager@172] - persistenceMgr is ReadAheadCache...................
2013-04-11 18:04:59,983 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:05:00,118 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:05:00,121 - INFO [main:HedwigHubTestBase1@162] - HedwigHub test setup finished
2013-04-11 18:05:00,173 - INFO [Thread-7-EventThread:ZkTopicManager$ZkGetOwnerOp$1@165] - 192.168.0.155:4080:9876 : Least loaded owner hostname: "192.168.0.155:4080:9876"
czxid: 29
is chosen for topic messageQueue-test
2013-04-11 18:05:00,174 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x16 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test
2013-04-11 18:05:00,202 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x17 zxid:0x1f txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics
2013-04-11 18:05:00,284 - INFO [Thread-7-EventThread:BookkeeperPersistenceManager$AcquireOp@876] - Process 0 ledgers for topic messageQueue-test starting from seq id 1.
2013-04-11 18:05:00,299 - INFO [Thread-7-EventThread:AbstractSubscriptionManager$AcquireOp$1$1@268] - Subscription manager successfully acquired topic: messageQueue-test
2013-04-11 18:05:00,386 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88e26f30005 type:create cxid:0x20 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test/subscribers Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test/subscribers
2013-04-11 18:05:00,437 - INFO [Thread-7-EventThread:CallbackUtils$3@148] - [standalone] all cross-region subscriptions succeeded
2013-04-11 18:05:00,455 - INFO [pool-17-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15002
2013-04-11 18:05:00,473 - INFO [pool-17-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15001
2013-04-11 18:05:00,476 - INFO [New I/O client worker #2-1:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15002
2013-04-11 18:05:00,477 - INFO [New I/O client worker #2-2:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15001
2013-04-11 18:05:00,721 - INFO [pool-17-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15003
2013-04-11 18:05:00,764 - INFO [New I/O client worker #2-3:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15003
2013-04-11 18:05:01,509 - INFO [DeliveryManagerThread:SubscribeHandler$1$1@206] - Subscribe request (2) for (topic:messageQueue-test, subscriber:@q_subid) from channel /127.0.0.1:49924 succeed - its subscription data is State : { consumeSeqId: local:0 };Preferences : { System Preferences : [] };
2013-04-11 18:05:01,522 - INFO [DeliveryManagerThread:SubscribeHandler$1$1@206] - Subscribe request (2) for (topic:messageQueue-test, subscriber:@q_subid) from channel /127.0.0.1:49923 succeed - its subscription data is State : { consumeSeqId: local:0 };Preferences : { System Preferences : [] };
2013-04-11 18:05:01,529 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1250] - lastConsumedSeq: 0; topic=messageQueue-test; subscriberId=@q_subid
2013-04-11 18:05:01,532 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1250] - lastConsumedSeq: 0; topic=messageQueue-test; subscriberId=@q_subid
2013-04-11 18:05:01,574 - INFO [Thread-12:HedwigClientImpl@112] - Stopping the client!
2013-04-11 18:05:01,574 - INFO [Thread-12:AbstractHChannelManager@607] - Shutting down the channels manager.
2013-04-11 18:05:01,575 - INFO [New I/O server worker #1-5:SubscriptionChannelManager@98] - Subscription channel /127.0.0.1:49923 for Topic: messageQueue-test, SubscriberId: @q_subid is disconnected.
2013-04-11 18:05:01,576 - INFO [Thread-12:HedwigClientImpl@122] - Completed stopping the client!
2013-04-11 18:05:01,577 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:05:01,644 - INFO [New I/O server worker #1-4:SubscriptionChannelManager@98] - Subscription channel /127.0.0.1:49924 for Topic: messageQueue-test, SubscriberId: @q_subid is disconnected.
2013-04-11 18:05:01,645 - INFO [DeliveryManagerThread:FIFODeliveryManager$RemoveChannelForQueueRequest@1483] - Subscriber Topic: messageQueue-test, SubscriberId: @q_subid will be removed.
2013-04-11 18:05:01,645 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:05:01,645 - INFO [DeliveryManagerThread:FIFODeliveryManager$RemoveChannelForQueueRequest@1486] - Last consumed seq: 159
2013-04-11 18:05:01,645 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:05:01,645 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@643] - Subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 581) is throttled : last delivered 581, last consumed 150.
2013-04-11 18:05:16,318 - INFO [main:HedwigHubTestBase1@168] - tearDown starting
2013-04-11 18:05:16,361 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15001
2013-04-11 18:05:16,363 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15002
2013-04-11 18:05:16,364 - INFO [New I/O client worker #2-3:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15003
2013-04-11 18:05:16,366 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88e26f30005
2013-04-11 18:05:16,385 - INFO [main:ZooKeeper@679] - Session: 0x13df88e26f30005 closed
2013-04-11 18:05:16,385 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49886 which had sessionid 0x13df88e26f30005
2013-04-11 18:05:16,386 - INFO [Thread-7-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:05:16,388 - INFO [New I/O client worker #3-1:HChannelHandler@235] - Channel [id: 0x00ed7209, /127.0.0.1:49891 :> localhost/127.0.0.1:4080] was disconnected to host localhost/127.0.0.1:4080.
2013-04-11 18:05:16,388 - INFO [New I/O client worker #3-1:AbstractHChannelManager@363] - NonSubscription Channel [id: 0x00ed7209, /127.0.0.1:49891 :> localhost/127.0.0.1:4080] to localhost/127.0.0.1:4080 disconnected.
2013-04-11 18:05:16,389 - INFO [New I/O client worker #4-1:HChannelHandler@235] - Channel [id: 0x003de98f, /0:0:0:0:0:0:0:0:49910 :> /127.0.0.1:4080] was disconnected to host /127.0.0.1:4080.
2013-04-11 18:05:16,389 - INFO [New I/O client worker #4-1:AbstractHChannelManager@363] - NonSubscription Channel [id: 0x003de98f, /0:0:0:0:0:0:0:0:49910 :> /127.0.0.1:4080] to /127.0.0.1:4080 disconnected.
2013-04-11 18:05:16,394 - WARN [NIOServerFactory-15001:NIOServerFactory@159] - Exception in server socket loop: /0:0:0:0:0:0:0:0
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.bookkeeper.proto.NIOServerFactory.run(NIOServerFactory.java:146)
2013-04-11 18:05:16,395 - INFO [NIOServerFactory-15001:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:05:16,396 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88e26f30001
2013-04-11 18:05:16,417 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49868 which had sessionid 0x13df88e26f30001
2013-04-11 18:05:16,417 - INFO [main:ZooKeeper@679] - Session: 0x13df88e26f30001 closed
2013-04-11 18:05:16,417 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:05:16,417 - INFO [Thread-4:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:05:16,417 - WARN [BookieJournal-15001:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:05:16,449 - WARN [NIOServerFactory-15002:NIOServerFactory@159] - Exception in server socket loop: /0:0:0:0:0:0:0:0
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.bookkeeper.proto.NIOServerFactory.run(NIOServerFactory.java:146)
2013-04-11 18:05:16,450 - INFO [NIOServerFactory-15002:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:05:16,450 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88e26f30002
2013-04-11 18:05:16,478 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49873 which had sessionid 0x13df88e26f30002
2013-04-11 18:05:16,481 - INFO [main:ZooKeeper@679] - Session: 0x13df88e26f30002 closed
2013-04-11 18:05:16,481 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:05:16,481 - INFO [Thread-5:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:05:16,482 - WARN [BookieJournal-15002:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:05:16,507 - WARN [NIOServerFactory-15003:NIOServerFactory@159] - Exception in server socket loop: /0:0:0:0:0:0:0:0
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.bookkeeper.proto.NIOServerFactory.run(NIOServerFactory.java:146)
2013-04-11 18:05:16,508 - INFO [NIOServerFactory-15003:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:05:16,508 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88e26f30003
2013-04-11 18:05:16,535 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49878 which had sessionid 0x13df88e26f30003
2013-04-11 18:05:16,535 - INFO [main:ZooKeeper@679] - Session: 0x13df88e26f30003 closed
2013-04-11 18:05:16,535 - INFO [Thread-6:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:05:16,536 - WARN [BookieJournal-15003:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:05:16,536 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:05:16,557 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88e26f30004
2013-04-11 18:05:16,607 - INFO [main:ZooKeeper@679] - Session: 0x13df88e26f30004 closed
2013-04-11 18:05:16,608 - INFO [main:ClientBase@425] - tearDown starting
2013-04-11 18:05:16,607 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:05:16,607 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49883 which had sessionid 0x13df88e26f30004
2013-04-11 18:05:16,609 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88e26f30000
2013-04-11 18:05:16,676 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49865 which had sessionid 0x13df88e26f30000
2013-04-11 18:05:16,676 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:05:16,676 - INFO [main:ZooKeeper@679] - Session: 0x13df88e26f30000 closed
2013-04-11 18:05:16,677 - INFO [main:ClientBase@395] - STOPPING server
2013-04-11 18:05:16,678 - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@236] - Ignoring exception
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:204)
at java.lang.Thread.run(Thread.java:722)
2013-04-11 18:05:16,678 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@240] - NIOServerCnxn factory exited run method
2013-04-11 18:05:16,678 - INFO [main:ZooKeeperServer@428] - shutting down
2013-04-11 18:05:16,678 - INFO [main:SessionTrackerImpl@220] - Shutting down
2013-04-11 18:05:16,678 - INFO [main:PrepRequestProcessor@733] - Shutting down
2013-04-11 18:05:16,678 - INFO [main:SyncRequestProcessor@173] - Shutting down
2013-04-11 18:05:16,678 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@135] - PrepRequestProcessor exited loop!
2013-04-11 18:05:16,679 - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
2013-04-11 18:05:16,679 - INFO [main:FinalRequestProcessor@414] - shutdown of request processor complete
2013-04-11 18:05:16,679 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:05:17,675 - INFO [main:JMXEnv@133] - ensureOnly:[]
2013-04-11 18:05:17,682 - INFO [main:HedwigHubTestBase1@171] - FINISHED null
2013-04-11 18:06:15,007 - INFO [main:HedwigHubTestBase1@158] - STARTING null
2013-04-11 18:06:15,024 - INFO [main:PortAssignment@32] - assigning port 11221
2013-04-11 18:06:15,161 - INFO [main:ClientBase@388] - STARTING server
2013-04-11 18:06:15,169 - INFO [main:Environment@98] - Server environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:06:15,169 - INFO [main:Environment@98] - Server environment:host.name=ly-PC
2013-04-11 18:06:15,169 - INFO [main:Environment@98] - Server environment:java.version=1.7.0_01
2013-04-11 18:06:15,169 - INFO [main:Environment@98] - Server environment:java.vendor=Oracle Corporation
2013-04-11 18:06:15,169 - INFO [main:Environment@98] - Server environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:06:15,169 - INFO [main:Environment@98] - Server environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:06:15,170 - INFO [main:Environment@98] - Server environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:06:15,170 - INFO [main:Environment@98] - Server environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:06:15,170 - INFO [main:Environment@98] - Server environment:java.compiler=<NA>
2013-04-11 18:06:15,170 - INFO [main:Environment@98] - Server environment:os.name=Windows 7
2013-04-11 18:06:15,171 - INFO [main:Environment@98] - Server environment:os.arch=x86
2013-04-11 18:06:15,171 - INFO [main:Environment@98] - Server environment:os.version=6.1
2013-04-11 18:06:15,171 - INFO [main:Environment@98] - Server environment:user.name=ly
2013-04-11 18:06:15,172 - INFO [main:Environment@98] - Server environment:user.home=C:\Users\ly
2013-04-11 18:06:15,172 - INFO [main:Environment@98] - Server environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:06:15,186 - INFO [main:ZooKeeperServer@171] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir F:\logDir\test5922061213487867233.junit.dir\version-2 snapdir F:\logDir\test5922061213487867233.junit.dir\version-2
2013-04-11 18:06:15,198 - INFO [main:NIOServerCnxnFactory@110] - binding to port 0.0.0.0/0.0.0.0:15000
2013-04-11 18:06:15,204 - INFO [main:FileTxnSnapLog@238] - Snapshotting: 0x0 to F:\logDir\test5922061213487867233.junit.dir\version-2\snapshot.0
2013-04-11 18:06:15,224 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:06:15,225 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49945
2013-04-11 18:06:15,228 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@820] - Processing stat command from /127.0.0.1:49945
2013-04-11 18:06:15,230 - INFO [Thread-2:NIOServerCnxn$StatCommand@655] - Stat command output
2013-04-11 18:06:15,231 - INFO [Thread-2:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49945 (no session established for client)
2013-04-11 18:06:15,231 - INFO [main:JMXEnv@133] - ensureOnly:[InMemoryDataTree, StandaloneServer_port]
2013-04-11 18:06:15,234 - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
2013-04-11 18:06:15,235 - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
2013-04-11 18:06:15,235 - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
2013-04-11 18:06:15,235 - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
2013-04-11 18:06:15,235 - INFO [main:ClientBase@384] - Client test setup finished
2013-04-11 18:06:15,243 - INFO [main:Environment@98] - Client environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:06:15,243 - INFO [main:Environment@98] - Client environment:host.name=ly-PC
2013-04-11 18:06:15,243 - INFO [main:Environment@98] - Client environment:java.version=1.7.0_01
2013-04-11 18:06:15,243 - INFO [main:Environment@98] - Client environment:java.vendor=Oracle Corporation
2013-04-11 18:06:15,244 - INFO [main:Environment@98] - Client environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:06:15,244 - INFO [main:Environment@98] - Client environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:06:15,244 - INFO [main:Environment@98] - Client environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:06:15,244 - INFO [main:Environment@98] - Client environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:06:15,244 - INFO [main:Environment@98] - Client environment:java.compiler=<NA>
2013-04-11 18:06:15,244 - INFO [main:Environment@98] - Client environment:os.name=Windows 7
2013-04-11 18:06:15,245 - INFO [main:Environment@98] - Client environment:os.arch=x86
2013-04-11 18:06:15,245 - INFO [main:Environment@98] - Client environment:os.version=6.1
2013-04-11 18:06:15,245 - INFO [main:Environment@98] - Client environment:user.name=ly
2013-04-11 18:06:15,245 - INFO [main:Environment@98] - Client environment:user.home=C:\Users\ly
2013-04-11 18:06:15,245 - INFO [main:Environment@98] - Client environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:06:15,246 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@a93a16
2013-04-11 18:06:15,260 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:15,263 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:15,265 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49948
2013-04-11 18:06:15,266 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:15,268 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49948
2013-04-11 18:06:15,271 - INFO [SyncThread:0:FileTxnLog@199] - Creating new log file: log.1
2013-04-11 18:06:15,303 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88f52a90000 with negotiated timeout 30000 for client /127.0.0.1:49948
2013-04-11 18:06:15,304 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88f52a90000, negotiated timeout = 30000
2013-04-11 18:06:15,306 - INFO [main:JMXEnv@105] - expect:0x13df88f52a90000
2013-04-11 18:06:15,307 - INFO [main:JMXEnv@108] - found:0x13df88f52a90000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x13df88f52a90000
2013-04-11 18:06:15,404 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@1785b00
2013-04-11 18:06:15,406 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:15,407 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:15,407 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:15,407 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49951
2013-04-11 18:06:15,418 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49951
2013-04-11 18:06:15,436 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88f52a90001 with negotiated timeout 10000 for client /127.0.0.1:49951
2013-04-11 18:06:15,436 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88f52a90001, negotiated timeout = 10000
2013-04-11 18:06:15,439 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:06:15,599 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:06:15,611 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:06:15,612 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:06:15,615 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:06:15,615 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:06:15,618 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie1500119903557470808336test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:06:15,624 - INFO [BookieJournal-15001:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie1500119903557470808336test\current\1b7080.txn
2013-04-11 18:06:15,694 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@2e42fe
2013-04-11 18:06:15,696 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:15,697 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:15,698 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:15,698 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49956
2013-04-11 18:06:15,698 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49956
2013-04-11 18:06:15,764 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88f52a90002 with negotiated timeout 10000 for client /127.0.0.1:49956
2013-04-11 18:06:15,764 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88f52a90002, negotiated timeout = 10000
2013-04-11 18:06:15,764 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:06:15,783 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:06:15,784 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:06:15,784 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:06:15,785 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:06:15,785 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:06:15,785 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150023030243123189775051test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:06:15,789 - INFO [BookieJournal-15002:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150023030243123189775051test\current\1b7126.txn
2013-04-11 18:06:15,815 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@c92ff9
2013-04-11 18:06:15,816 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:15,817 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:15,817 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:15,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49961
2013-04-11 18:06:15,818 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49961
2013-04-11 18:06:15,885 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88f52a90003 with negotiated timeout 10000 for client /127.0.0.1:49961
2013-04-11 18:06:15,885 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88f52a90003, negotiated timeout = 10000
2013-04-11 18:06:15,886 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:06:15,899 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:06:15,900 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:06:15,901 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:06:15,901 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:06:15,901 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:06:15,901 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie15003964573024500457060test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:06:15,904 - INFO [BookieJournal-15003:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie15003964573024500457060test\current\1b7199.txn
2013-04-11 18:06:15,976 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.zookeeper.ZooKeeperWatcherBase@bff36c
2013-04-11 18:06:15,978 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:15,978 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:15,978 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:15,978 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49966
2013-04-11 18:06:15,979 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49966
2013-04-11 18:06:16,010 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88f52a90004 with negotiated timeout 10000 for client /127.0.0.1:49966
2013-04-11 18:06:16,010 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88f52a90004, negotiated timeout = 10000
2013-04-11 18:06:16,064 - INFO [Thread-7:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=2000 watcher=org.apache.hedwig.server.netty.PubSubServer$1@90f71b
2013-04-11 18:06:16,066 - INFO [Thread-7-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:16,067 - INFO [Thread-7-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:16,067 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:16,067 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:49969
2013-04-11 18:06:16,067 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:49969
2013-04-11 18:06:16,085 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88f52a90005 with negotiated timeout 6000 for client /127.0.0.1:49969
2013-04-11 18:06:16,085 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88f52a90005, negotiated timeout = 6000
2013-04-11 18:06:16,152 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x2 zxid:0x12 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers/METADATA Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers/METADATA
2013-04-11 18:06:16,172 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x3 zxid:0x13 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers
2013-04-11 18:06:16,194 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x4 zxid:0x14 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone Error:KeeperErrorCode = NoNode for /hedwig/standalone
2013-04-11 18:06:16,208 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x5 zxid:0x15 txntype:-1 reqpath:n/a Error Path:/hedwig Error:KeeperErrorCode = NoNode for /hedwig
2013-04-11 18:06:16,321 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0xb zxid:0x1b txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/hosts Error:KeeperErrorCode = NoNode for /hedwig/standalone/hosts
2013-04-11 18:06:16,380 - INFO [Thread-7-EventThread:ZkTopicManager$2@103] - Successfully registered hub hostname: "192.168.0.155:4080:9876"
czxid: 29
with zookeeper
2013-04-11 18:06:16,394 - INFO [Thread-7:PubSubServer@146] - isNotStandalone...................realPersistenceManager is BookkeeperPersistenceManager
2013-04-11 18:06:16,408 - INFO [Thread-7:FIFODeliveryManager@172] - persistenceMgr is ReadAheadCache...................
2013-04-11 18:06:16,457 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:06:16,589 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:06:16,598 - INFO [main:HedwigHubTestBase1@162] - HedwigHub test setup finished
2013-04-11 18:06:16,657 - INFO [Thread-7-EventThread:ZkTopicManager$ZkGetOwnerOp$1@165] - 192.168.0.155:4080:9876 : Least loaded owner hostname: "192.168.0.155:4080:9876"
czxid: 29
is chosen for topic messageQueue-test
2013-04-11 18:06:16,658 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x16 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test
2013-04-11 18:06:16,673 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x17 zxid:0x1f txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics
2013-04-11 18:06:16,755 - INFO [Thread-7-EventThread:BookkeeperPersistenceManager$AcquireOp@876] - Process 0 ledgers for topic messageQueue-test starting from seq id 1.
2013-04-11 18:06:16,769 - INFO [Thread-7-EventThread:AbstractSubscriptionManager$AcquireOp$1$1@268] - Subscription manager successfully acquired topic: messageQueue-test
2013-04-11 18:06:16,832 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88f52a90005 type:create cxid:0x20 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test/subscribers Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test/subscribers
2013-04-11 18:06:16,883 - INFO [Thread-7-EventThread:CallbackUtils$3@148] - [standalone] all cross-region subscriptions succeeded
2013-04-11 18:06:16,894 - INFO [pool-16-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15002
2013-04-11 18:06:16,911 - INFO [pool-16-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15001
2013-04-11 18:06:16,913 - INFO [New I/O client worker #2-1:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15002
2013-04-11 18:06:16,914 - INFO [New I/O client worker #2-2:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15001
2013-04-11 18:06:17,075 - INFO [pool-17-thread-1:PerChannelBookieClient@117] - Connecting to bookie: /192.168.0.155:15003
2013-04-11 18:06:17,119 - INFO [New I/O client worker #2-3:PerChannelBookieClient$1@137] - Successfully connected to bookie: /192.168.0.155:15003
2013-04-11 18:06:17,851 - INFO [DeliveryManagerThread:SubscribeHandler$1$1@206] - Subscribe request (2) for (topic:messageQueue-test, subscriber:@q_subid) from channel /127.0.0.1:50007 succeed - its subscription data is State : { consumeSeqId: local:0 };Preferences : { System Preferences : [] };
2013-04-11 18:06:17,854 - INFO [DeliveryManagerThread:SubscribeHandler$1$1@206] - Subscribe request (2) for (topic:messageQueue-test, subscriber:@q_subid) from channel /127.0.0.1:50006 succeed - its subscription data is State : { consumeSeqId: local:0 };Preferences : { System Preferences : [] };
2013-04-11 18:06:17,857 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1250] - lastConsumedSeq: 0; topic=messageQueue-test; subscriberId=@q_subid
2013-04-11 18:06:17,905 - INFO [Thread-12:HedwigClientImpl@112] - Stopping the client!
2013-04-11 18:06:17,905 - INFO [Thread-12:AbstractHChannelManager@607] - Shutting down the channels manager.
2013-04-11 18:06:17,907 - INFO [New I/O server worker #1-5:SubscriptionChannelManager@98] - Subscription channel /127.0.0.1:50006 for Topic: messageQueue-test, SubscriberId: @q_subid is disconnected.
2013-04-11 18:06:17,908 - INFO [Thread-12:HedwigClientImpl@122] - Completed stopping the client!
2013-04-11 18:06:17,909 - INFO [DeliveryManagerThread:FIFODeliveryManager$ConsumerCluster@1312] - addConsumeSeqIdToCluster: Some messages are in retryMessageQueue.
2013-04-11 18:06:17,964 - INFO [New I/O server worker #1-4:SubscriptionChannelManager@98] - Subscription channel /127.0.0.1:50007 for Topic: messageQueue-test, SubscriberId: @q_subid is disconnected.
2013-04-11 18:06:17,964 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:06:17,964 - INFO [DeliveryManagerThread:FIFODeliveryManager$RemoveChannelForQueueRequest@1483] - Subscriber Topic: messageQueue-test, SubscriberId: @q_subid will be removed.
2013-04-11 18:06:17,965 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:06:17,965 - INFO [DeliveryManagerThread:FIFODeliveryManager$RemoveChannelForQueueRequest@1486] - Last consumed seq: 500
2013-04-11 18:06:17,965 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@727] - Can't find waiting queueConsumer. Put the message in retryMessageQueue.
2013-04-11 18:06:17,965 - INFO [pool-18-thread-1:FIFODeliveryManager$ActiveSubscriberState@643] - Subscriber (Topic: messageQueue-testSubscriber: @q_subid, DeliveryPtr: 532) is throttled : last delivered 532, last consumed 201.
2013-04-11 18:06:32,732 - INFO [main:HedwigHubTestBase1@168] - tearDown starting
2013-04-11 18:06:32,782 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15001
2013-04-11 18:06:32,784 - INFO [main:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15002
2013-04-11 18:06:32,786 - INFO [New I/O client worker #2-3:PerChannelBookieClient@472] - Disconnected from bookie: /192.168.0.155:15003
2013-04-11 18:06:32,787 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88f52a90005
2013-04-11 18:06:32,807 - INFO [main:ZooKeeper@679] - Session: 0x13df88f52a90005 closed
2013-04-11 18:06:32,807 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49969 which had sessionid 0x13df88f52a90005
2013-04-11 18:06:32,807 - INFO [Thread-7-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:06:32,810 - INFO [New I/O client worker #5-1:HChannelHandler@235] - Channel [id: 0x006a39cc, /127.0.0.1:49992 :> /127.0.0.1:4080] was disconnected to host /127.0.0.1:4080.
2013-04-11 18:06:32,811 - INFO [New I/O client worker #5-1:AbstractHChannelManager@363] - NonSubscription Channel [id: 0x006a39cc, /127.0.0.1:49992 :> /127.0.0.1:4080] to /127.0.0.1:4080 disconnected.
2013-04-11 18:06:32,811 - INFO [New I/O client worker #3-1:HChannelHandler@235] - Channel [id: 0x014f6322, /127.0.0.1:49974 :> localhost/127.0.0.1:4080] was disconnected to host localhost/127.0.0.1:4080.
2013-04-11 18:06:32,811 - INFO [New I/O client worker #3-1:AbstractHChannelManager@363] - NonSubscription Channel [id: 0x014f6322, /127.0.0.1:49974 :> localhost/127.0.0.1:4080] to localhost/127.0.0.1:4080 disconnected.
2013-04-11 18:06:32,814 - INFO [NIOServerFactory-15001:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:06:32,815 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88f52a90001
2013-04-11 18:06:32,830 - INFO [main:ZooKeeper@679] - Session: 0x13df88f52a90001 closed
2013-04-11 18:06:32,830 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49951 which had sessionid 0x13df88f52a90001
2013-04-11 18:06:32,830 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:06:32,830 - INFO [Thread-4:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:06:32,831 - WARN [BookieJournal-15001:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:06:32,854 - WARN [NIOServerFactory-15002:NIOServerFactory@159] - Exception in server socket loop: /0:0:0:0:0:0:0:0
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.bookkeeper.proto.NIOServerFactory.run(NIOServerFactory.java:146)
2013-04-11 18:06:32,854 - INFO [NIOServerFactory-15002:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:06:32,855 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88f52a90002
2013-04-11 18:06:32,863 - ERROR [main-EventThread:ZkUtils$3@162] - ZK error syncing nodes when getting children:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode = ConnectionLoss for /ledgers
at org.apache.zookeeper.KeeperException.create(KeeperException.java:99)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.bookkeeper.util.ZkUtils$3.processResult(ZkUtils.java:162)
at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:619)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:497)
2013-04-11 18:06:32,864 - WARN [GarbageCollectorThread:ScanAndCompareGarbageCollector@103] - Exception when iterating over the metadata {}
java.io.IOException: Error on getting children from node /ledgers
at org.apache.bookkeeper.util.ZkUtils.getChildrenInSingleNode(ZkUtils.java:140)
at org.apache.bookkeeper.meta.FlatLedgerManager$2.next(FlatLedgerManager.java:144)
at org.apache.bookkeeper.bookie.ScanAndCompareGarbageCollector.gc(ScanAndCompareGarbageCollector.java:76)
at org.apache.bookkeeper.bookie.GarbageCollectorThread.doGcLedgers(GarbageCollectorThread.java:302)
at org.apache.bookkeeper.bookie.GarbageCollectorThread.run(GarbageCollectorThread.java:271)
2013-04-11 18:06:32,882 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49956 which had sessionid 0x13df88f52a90002
2013-04-11 18:06:32,882 - INFO [main:ZooKeeper@679] - Session: 0x13df88f52a90002 closed
2013-04-11 18:06:32,882 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:06:32,882 - INFO [Thread-5:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:06:32,883 - WARN [BookieJournal-15002:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:06:32,908 - WARN [NIOServerFactory-15003:NIOServerFactory@159] - Exception in server socket loop: /0:0:0:0:0:0:0:0
java.nio.channels.ClosedChannelException
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:211)
at org.apache.bookkeeper.proto.NIOServerFactory.run(NIOServerFactory.java:146)
2013-04-11 18:06:32,913 - INFO [NIOServerFactory-15003:NIOServerFactory@162] - NIOServerCnxn factory exitedloop.
2013-04-11 18:06:32,916 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88f52a90003
2013-04-11 18:06:32,949 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49961 which had sessionid 0x13df88f52a90003
2013-04-11 18:06:32,950 - INFO [main:ZooKeeper@679] - Session: 0x13df88f52a90003 closed
2013-04-11 18:06:32,951 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:06:32,951 - INFO [Thread-6:LedgerDirsManager$LedgerDirsMonitor@212] - LedgerDirsMonitor thread is interrupted
2013-04-11 18:06:32,951 - WARN [BookieJournal-15003:Journal@549] - Journal exits when shutting down
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.bookkeeper.bookie.Journal.run(Journal.java:497)
2013-04-11 18:06:32,984 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88f52a90004
2013-04-11 18:06:33,021 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49966 which had sessionid 0x13df88f52a90004
2013-04-11 18:06:33,022 - INFO [main:ZooKeeper@679] - Session: 0x13df88f52a90004 closed
2013-04-11 18:06:33,022 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:06:33,022 - INFO [main:ClientBase@425] - tearDown starting
2013-04-11 18:06:33,023 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@466] - Processed session termination for sessionid: 0x13df88f52a90000
2013-04-11 18:06:33,040 - INFO [main:ZooKeeper@679] - Session: 0x13df88f52a90000 closed
2013-04-11 18:06:33,040 - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
2013-04-11 18:06:33,040 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:49948 which had sessionid 0x13df88f52a90000
2013-04-11 18:06:33,040 - INFO [main:ClientBase@395] - STOPPING server
2013-04-11 18:06:33,042 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@240] - NIOServerCnxn factory exited run method
2013-04-11 18:06:33,042 - INFO [main:ZooKeeperServer@428] - shutting down
2013-04-11 18:06:33,043 - INFO [main:SessionTrackerImpl@220] - Shutting down
2013-04-11 18:06:33,043 - INFO [main:PrepRequestProcessor@733] - Shutting down
2013-04-11 18:06:33,043 - INFO [main:SyncRequestProcessor@173] - Shutting down
2013-04-11 18:06:33,043 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@135] - PrepRequestProcessor exited loop!
2013-04-11 18:06:33,043 - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
2013-04-11 18:06:33,044 - INFO [main:FinalRequestProcessor@414] - shutdown of request processor complete
2013-04-11 18:06:33,045 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:06:34,045 - INFO [main:JMXEnv@133] - ensureOnly:[]
2013-04-11 18:06:34,051 - INFO [main:HedwigHubTestBase1@171] - FINISHED null
2013-04-11 18:06:43,682 - INFO [main:HedwigHubTestBase1@158] - STARTING null
2013-04-11 18:06:43,700 - INFO [main:PortAssignment@32] - assigning port 11221
2013-04-11 18:06:43,853 - INFO [main:ClientBase@388] - STARTING server
2013-04-11 18:06:43,861 - INFO [main:Environment@98] - Server environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:06:43,861 - INFO [main:Environment@98] - Server environment:host.name=ly-PC
2013-04-11 18:06:43,861 - INFO [main:Environment@98] - Server environment:java.version=1.7.0_01
2013-04-11 18:06:43,861 - INFO [main:Environment@98] - Server environment:java.vendor=Oracle Corporation
2013-04-11 18:06:43,861 - INFO [main:Environment@98] - Server environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:06:43,862 - INFO [main:Environment@98] - Server environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:06:43,862 - INFO [main:Environment@98] - Server environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:06:43,862 - INFO [main:Environment@98] - Server environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:06:43,862 - INFO [main:Environment@98] - Server environment:java.compiler=<NA>
2013-04-11 18:06:43,862 - INFO [main:Environment@98] - Server environment:os.name=Windows 7
2013-04-11 18:06:43,863 - INFO [main:Environment@98] - Server environment:os.arch=x86
2013-04-11 18:06:43,863 - INFO [main:Environment@98] - Server environment:os.version=6.1
2013-04-11 18:06:43,863 - INFO [main:Environment@98] - Server environment:user.name=ly
2013-04-11 18:06:43,864 - INFO [main:Environment@98] - Server environment:user.home=C:\Users\ly
2013-04-11 18:06:43,864 - INFO [main:Environment@98] - Server environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:06:43,878 - INFO [main:ZooKeeperServer@171] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir F:\logDir\test8097777040030439405.junit.dir\version-2 snapdir F:\logDir\test8097777040030439405.junit.dir\version-2
2013-04-11 18:06:43,891 - INFO [main:NIOServerCnxnFactory@110] - binding to port 0.0.0.0/0.0.0.0:15000
2013-04-11 18:06:43,896 - INFO [main:FileTxnSnapLog@238] - Snapshotting: 0x0 to F:\logDir\test8097777040030439405.junit.dir\version-2\snapshot.0
2013-04-11 18:06:43,916 - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 15000
2013-04-11 18:06:43,917 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50035
2013-04-11 18:06:43,921 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxn@820] - Processing stat command from /127.0.0.1:50035
2013-04-11 18:06:43,924 - INFO [Thread-2:NIOServerCnxn$StatCommand@655] - Stat command output
2013-04-11 18:06:43,925 - INFO [Thread-2:NIOServerCnxn@1000] - Closed socket connection for client /127.0.0.1:50035 (no session established for client)
2013-04-11 18:06:43,925 - INFO [main:JMXEnv@133] - ensureOnly:[InMemoryDataTree, StandaloneServer_port]
2013-04-11 18:06:43,929 - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
2013-04-11 18:06:43,929 - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
2013-04-11 18:06:43,929 - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
2013-04-11 18:06:43,929 - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
2013-04-11 18:06:43,930 - INFO [main:ClientBase@384] - Client test setup finished
2013-04-11 18:06:43,938 - INFO [main:Environment@98] - Client environment:zookeeper.version=3.4.3-1240972, built on 02/06/2012 10:48 GMT
2013-04-11 18:06:43,938 - INFO [main:Environment@98] - Client environment:host.name=ly-PC
2013-04-11 18:06:43,938 - INFO [main:Environment@98] - Client environment:java.version=1.7.0_01
2013-04-11 18:06:43,938 - INFO [main:Environment@98] - Client environment:java.vendor=Oracle Corporation
2013-04-11 18:06:43,939 - INFO [main:Environment@98] - Client environment:java.home=D:\Program Files (x86)\Java\jdk1.7.0_01\jre
2013-04-11 18:06:43,939 - INFO [main:Environment@98] - Client environment:java.class.path=C:\Users\ly\Git\msgbus\hedwig-server\target\test-classes;C:\Users\ly\Git\msgbus\hedwig-server\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\classes;C:\Users\ly\Git\msgbus\hedwig-protocol\target\test-classes;F:\cloud compute\maven\repository\com\google\protobuf\protobuf-java\2.4.1\protobuf-java-2.4.1.jar;F:\cloud compute\maven\repository\junit\junit\4.8.1\junit-4.8.1.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-api\1.6.4\slf4j-api-1.6.4.jar;F:\cloud compute\maven\repository\org\slf4j\slf4j-log4j12\1.6.4\slf4j-log4j12-1.6.4.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.16\log4j-1.2.16.jar;C:\Users\ly\Git\msgbus\hedwig-client\target\classes;F:\cloud compute\maven\repository\org\jboss\netty\netty\3.2.4.Final\netty-3.2.4.Final.jar;F:\cloud compute\maven\repository\commons-configuration\commons-configuration\1.6\commons-configuration-1.6.jar;F:\cloud compute\maven\repository\commons-collections\commons-collections\3.2.1\commons-collections-3.2.1.jar;F:\cloud compute\maven\repository\commons-lang\commons-lang\2.4\commons-lang-2.4.jar;F:\cloud compute\maven\repository\commons-digester\commons-digester\1.8\commons-digester-1.8.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils\1.7.0\commons-beanutils-1.7.0.jar;F:\cloud compute\maven\repository\commons-beanutils\commons-beanutils-core\1.8.0\commons-beanutils-core-1.8.0.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3.jar;F:\cloud compute\maven\repository\org\apache\derby\derby\10.8.2.2\derby-10.8.2.2.jar;F:\cloud compute\maven\repository\org\apache\zookeeper\zookeeper\3.4.3\zookeeper-3.4.3-tests.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1.jar;F:\cloud compute\maven\repository\com\google\guava\guava\13.0.1\guava-13.0.1.jar;F:\cloud compute\maven\repository\commons-cli\commons-cli\1.2\commons-cli-1.2.jar;F:\cloud compute\maven\repository\commons-codec\commons-codec\1.6\commons-codec-1.6.jar;F:\cloud compute\maven\repository\commons-io\commons-io\2.1\commons-io-2.1.jar;F:\cloud compute\maven\repository\org\apache\bookkeeper\bookkeeper-server\4.2.1\bookkeeper-server-4.2.1-tests.jar;F:\cloud compute\maven\repository\log4j\log4j\1.2.15\log4j-1.2.15.jar;F:\cloud compute\maven\repository\jline\jline\0.9.94\jline-0.9.94.jar;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.1.0\target\classes;F:\cloud compute\workspace\bookkeeper\compat-deps\hedwig-server-compat-4.0.0\target\classes;C:\Users\ly\Git\msgbus\msgbus-mq\target\classes;F:\cloud compute\maven\repository\com\netflix\curator\curator-client\1.2.3\curator-client-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-framework\1.2.3\curator-framework-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-recipes\1.2.3\curator-recipes-1.2.3.jar;F:\cloud compute\maven\repository\com\netflix\curator\curator-test\1.2.3\curator-test-1.2.3.jar;F:\cloud compute\maven\repository\org\javassist\javassist\3.15.0-GA\javassist-3.15.0-GA.jar;F:\cloud compute\maven\repository\org\apache\commons\commons-math\2.2\commons-math-2.2.jar;F:\cloud compute\maven\repository\commons-logging\commons-logging\1.1.1\commons-logging-1.1.1.jar;F:\cloud compute\maven\repository\org\hamcrest\hamcrest-all\1.3\hamcrest-all-1.3.jar;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/207/1/.cp/;/F:/cloud compute/eclipse/configuration/org.eclipse.osgi/bundles/206/1/.cp/
2013-04-11 18:06:43,942 - INFO [main:Environment@98] - Client environment:java.library.path=D:\Program Files (x86)\Java\jdk1.7.0_01\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:/Program Files (x86)/Java/jre7/bin/client;D:/Program Files (x86)/Java/jre7/bin;D:/Program Files (x86)/Java/jre7/lib/i386;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\Program Files (x86)\Git\cmd;D:\Program Files\TortoiseGit\bin;D:\Program Files (x86)\Rational\common;D:\Program Files\TortoiseSVN\bin;D:\Program Files (x86)\Java\jdk1.7.0_01\bin;F:\cloud compute\maven\apache-maven-3.0.4\bin;D:\Program Files (x86)\Git\bin;F:\cloud compute\eclipse;;.
2013-04-11 18:06:43,943 - INFO [main:Environment@98] - Client environment:java.io.tmpdir=C:\Users\ly\AppData\Local\Temp\
2013-04-11 18:06:43,943 - INFO [main:Environment@98] - Client environment:java.compiler=<NA>
2013-04-11 18:06:43,945 - INFO [main:Environment@98] - Client environment:os.name=Windows 7
2013-04-11 18:06:43,945 - INFO [main:Environment@98] - Client environment:os.arch=x86
2013-04-11 18:06:43,946 - INFO [main:Environment@98] - Client environment:os.version=6.1
2013-04-11 18:06:43,946 - INFO [main:Environment@98] - Client environment:user.name=ly
2013-04-11 18:06:43,946 - INFO [main:Environment@98] - Client environment:user.home=C:\Users\ly
2013-04-11 18:06:43,946 - INFO [main:Environment@98] - Client environment:user.dir=C:\Users\ly\Git\msgbus\hedwig-server
2013-04-11 18:06:43,948 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@24b943
2013-04-11 18:06:43,962 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:43,964 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:43,965 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:43,965 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50038
2013-04-11 18:06:43,967 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:50038
2013-04-11 18:06:43,969 - INFO [SyncThread:0:FileTxnLog@199] - Creating new log file: log.1
2013-04-11 18:06:44,002 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88fc2be0000 with negotiated timeout 30000 for client /127.0.0.1:50038
2013-04-11 18:06:44,002 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88fc2be0000, negotiated timeout = 30000
2013-04-11 18:06:44,005 - INFO [main:JMXEnv@105] - expect:0x13df88fc2be0000
2013-04-11 18:06:44,005 - INFO [main:JMXEnv@108] - found:0x13df88fc2be0000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x13df88fc2be0000
2013-04-11 18:06:44,134 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@9e7e94
2013-04-11 18:06:44,136 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:44,137 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:44,137 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:44,138 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50041
2013-04-11 18:06:44,148 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:50041
2013-04-11 18:06:44,180 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88fc2be0001 with negotiated timeout 10000 for client /127.0.0.1:50041
2013-04-11 18:06:44,180 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88fc2be0001, negotiated timeout = 10000
2013-04-11 18:06:44,184 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:06:44,345 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:06:44,373 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:06:44,373 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:06:44,404 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:06:44,404 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:06:44,408 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150013116139303114302733test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:06:44,418 - INFO [BookieJournal-15001:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150013116139303114302733test\current\1be0f8.txn
2013-04-11 18:06:44,527 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@13037f1
2013-04-11 18:06:44,530 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:44,530 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:44,531 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:44,540 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50046
2013-04-11 18:06:44,540 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:50046
2013-04-11 18:06:44,654 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88fc2be0002 with negotiated timeout 10000 for client /127.0.0.1:50046
2013-04-11 18:06:44,654 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88fc2be0002, negotiated timeout = 10000
2013-04-11 18:06:44,707 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:06:44,816 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:06:44,818 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:06:44,819 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:06:44,820 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:06:44,820 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:06:44,820 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150021394285678562249463test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:06:44,825 - INFO [BookieJournal-15002:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150021394285678562249463test\current\1be291.txn
2013-04-11 18:06:44,850 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.bookie.Bookie$6@6c9125
2013-04-11 18:06:44,853 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:44,853 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:44,854 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50051
2013-04-11 18:06:44,854 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:44,854 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:50051
2013-04-11 18:06:44,894 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88fc2be0003 with negotiated timeout 10000 for client /127.0.0.1:50051
2013-04-11 18:06:44,894 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88fc2be0003, negotiated timeout = 10000
2013-04-11 18:06:44,896 - WARN [main:Bookie@494] - INSTANCEID not exists in zookeeper. Not considering it for data verification
2013-04-11 18:06:44,922 - INFO [main:Bookie@527] - instantiate ledger manager org.apache.bookkeeper.meta.FlatLedgerManagerFactory
2013-04-11 18:06:44,923 - INFO [main:LedgerCacheImpl@73] - maxMemory = 259522560
2013-04-11 18:06:44,924 - INFO [main:LedgerCacheImpl@74] - openFileLimit is 900, pageSize is 8192, pageLimit is 10560
2013-04-11 18:06:44,924 - INFO [main:GarbageCollectorThread@246] - Minor Compaction : enabled=true, threshold=0.20000000298023224, interval=3600000
2013-04-11 18:06:44,924 - INFO [main:GarbageCollectorThread@248] - Major Compaction : enabled=true, threshold=0.800000011920929, interval=86400000
2013-04-11 18:06:44,925 - ERROR [main:Journal$LastLogMark@177] - Problems reading from C:\Users\ly\AppData\Local\Temp\bookie150033567278487224906422test\current\lastMark (this is okay if it is the first time starting this bookie
2013-04-11 18:06:44,928 - INFO [BookieJournal-15003:JournalChannel@66] - Opening journal C:\Users\ly\AppData\Local\Temp\bookie150033567278487224906422test\current\1be2f8.txn
2013-04-11 18:06:44,984 - INFO [main:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=10000 watcher=org.apache.bookkeeper.zookeeper.ZooKeeperWatcherBase@bff36c
2013-04-11 18:06:44,988 - INFO [main-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:44,989 - INFO [main-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:44,990 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50056
2013-04-11 18:06:44,991 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:44,992 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:50056
2013-04-11 18:06:45,037 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88fc2be0004 with negotiated timeout 10000 for client /127.0.0.1:50056
2013-04-11 18:06:45,037 - INFO [main-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88fc2be0004, negotiated timeout = 10000
2013-04-11 18:06:45,084 - INFO [Thread-7:ZooKeeper@433] - Initiating client connection, connectString=127.0.0.1:15000 sessionTimeout=2000 watcher=org.apache.hedwig.server.netty.PubSubServer$1@631b3b
2013-04-11 18:06:45,087 - INFO [Thread-7-SendThread():ClientCnxn$SendThread@933] - Opening socket connection to server /127.0.0.1:15000
2013-04-11 18:06:45,088 - INFO [Thread-7-SendThread(127.0.0.1:15000):ZooKeeperSaslClient@125] - Client will not SASL-authenticate because the default JAAS configuration section 'Client' could not be found. If you are not using SASL, you may ignore this. On the other hand, if you expected SASL to work, please fix your JAAS configuration.
2013-04-11 18:06:45,088 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@846] - Socket connection established to 127.0.0.1/127.0.0.1:15000, initiating session
2013-04-11 18:06:45,089 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:NIOServerCnxnFactory@213] - Accepted socket connection from /127.0.0.1:50059
2013-04-11 18:06:45,089 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:15000:ZooKeeperServer@838] - Client attempting to establish new session at /127.0.0.1:50059
2013-04-11 18:06:45,104 - INFO [SyncThread:0:ZooKeeperServer@604] - Established session 0x13df88fc2be0005 with negotiated timeout 6000 for client /127.0.0.1:50059
2013-04-11 18:06:45,104 - INFO [Thread-7-SendThread(127.0.0.1:15000):ClientCnxn$SendThread@1175] - Session establishment complete on server 127.0.0.1/127.0.0.1:15000, sessionid = 0x13df88fc2be0005, negotiated timeout = 6000
2013-04-11 18:06:45,168 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x2 zxid:0x12 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers/METADATA Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers/METADATA
2013-04-11 18:06:45,189 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x3 zxid:0x13 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/managers Error:KeeperErrorCode = NoNode for /hedwig/standalone/managers
2013-04-11 18:06:45,203 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x4 zxid:0x14 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone Error:KeeperErrorCode = NoNode for /hedwig/standalone
2013-04-11 18:06:45,221 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x5 zxid:0x15 txntype:-1 reqpath:n/a Error Path:/hedwig Error:KeeperErrorCode = NoNode for /hedwig
2013-04-11 18:06:45,327 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0xb zxid:0x1b txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/hosts Error:KeeperErrorCode = NoNode for /hedwig/standalone/hosts
2013-04-11 18:06:45,383 - INFO [Thread-7-EventThread:ZkTopicManager$2@103] - Successfully registered hub hostname: "192.168.0.155:4080:9876"
czxid: 29
with zookeeper
2013-04-11 18:06:45,398 - INFO [Thread-7:PubSubServer@146] - isNotStandalone...................realPersistenceManager is BookkeeperPersistenceManager
2013-04-11 18:06:45,405 - INFO [Thread-7:FIFODeliveryManager@172] - persistenceMgr is ReadAheadCache...................
2013-04-11 18:06:45,463 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:06:45,586 - INFO [Thread-7:PubSubServer@282] - Going into receive loop
2013-04-11 18:06:45,590 - INFO [main:HedwigHubTestBase1@162] - HedwigHub test setup finished
2013-04-11 18:06:45,648 - INFO [Thread-7-EventThread:ZkTopicManager$ZkGetOwnerOp$1@165] - 192.168.0.155:4080:9876 : Least loaded owner hostname: "192.168.0.155:4080:9876"
czxid: 29
is chosen for topic messageQueue-test
2013-04-11 18:06:45,649 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x16 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test
2013-04-11 18:06:45,678 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x17 zxid:0x1f txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics
2013-04-11 18:06:45,755 - INFO [Thread-7-EventThread:BookkeeperPersistenceManager$AcquireOp@876] - Process 0 ledgers for topic messageQueue-test starting from seq id 1.
2013-04-11 18:06:45,764 - INFO [Thread-7-EventThread:AbstractSubscriptionManager$AcquireOp$1$1@268] - Subscription manager successfully acquired topic: messageQueue-test
2013-04-11 18:06:45,813 - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@617] - Got user-level KeeperException when processing sessionid:0x13df88fc2be0005 type:create cxid:0x20 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/hedwig/standalone/topics/messageQueue-test/subscribers Error:KeeperErrorCode = NoNode for /hedwig/standalone/topics/messageQueue-test/subscribers
2013-04-11 18:06:45,862 - INFO [Thread-7-EventThread:CallbackUtils$3@148] - [standalone] all cross-region subscriptions succeeded