-
Notifications
You must be signed in to change notification settings - Fork 0
/
queue_log.txt
6716 lines (6716 loc) · 883 KB
/
queue_log.txt
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
06-21 17:01:29.972 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.001 4934-4934/com.malvinstn.testqueue D/CancellationJob: CancellationJob() called with: id = [4935dd88-ea18-472a-87ad-c21a57413967]
06-21 17:01:30.005 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] post message com.birbit.android.jobqueue.messaging.message.AddJobMessage@4a4de83
06-21 17:01:30.005 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690004700208
06-21 17:01:30.005 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690004700208
06-21 17:01:30.005 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19605561595 ns
06-21 17:01:30.005 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.005 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message com.birbit.android.jobqueue.messaging.message.AddJobMessage@4a4de83
06-21 17:01:30.005 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type ADD_JOB
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/CancellationJob: CancellationJob() called with: id = [bd358ee6-5391-4f4c-a103-410b572fd171]
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] post message com.birbit.android.jobqueue.messaging.message.AddJobMessage@37832db
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/CancellationJob: CancellationJob() called with: id = [18a22173-8b40-44b2-a5e2-70fcf6c6a4bb]
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] post message com.birbit.android.jobqueue.messaging.message.AddJobMessage@a0e9178
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/CancellationJob: CancellationJob() called with: id = [fee42d92-aa3f-48ed-818f-af5bf5580097]
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] post message com.birbit.android.jobqueue.messaging.message.AddJobMessage@356b051
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/CancellationJob: CancellationJob() called with: id = [702f8a54-ae9d-45b6-97c2-b8328dcbd434]
06-21 17:01:30.019 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] post message com.birbit.android.jobqueue.messaging.message.AddJobMessage@547f0b6
06-21 17:01:30.044 4934-4953/com.malvinstn.testqueue D/MainApplication: added job class: CancellationJob priority: 100 delay: 0 group : cancel persistent: true
06-21 17:01:30.044 4934-4953/com.malvinstn.testqueue D/CancellationJob: onAdded() called with id: 4935dd88-ea18-472a-87ad-c21a57413967
06-21 17:01:30.044 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? false isRunning? true waiting workers? 0
06-21 17:01:30.046 4934-4953/com.malvinstn.testqueue D/MainApplication: check above load factor: totalCons:0 minCons:0 maxConsCount: 1, loadFactor 5 remainingJobs: 1 runningsHolders: 0. isAbove:true
06-21 17:01:30.046 4934-4953/com.malvinstn.testqueue D/MainApplication: nothing has been poked. are we above load factor? true
06-21 17:01:30.046 4934-4953/com.malvinstn.testqueue D/MainApplication: adding another consumer
06-21 17:01:30.048 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690047360260
06-21 17:01:30.048 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.048 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.048 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.048 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock forever
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2046
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690058715103
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690058715103
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19551546700 ns
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message com.birbit.android.jobqueue.messaging.message.AddJobMessage@37832db
06-21 17:01:30.059 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type ADD_JOB
06-21 17:01:30.067 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.067 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.067 4934-4953/com.malvinstn.testqueue D/MainApplication: added job class: CancellationJob priority: 100 delay: 0 group : cancel persistent: true
06-21 17:01:30.067 4934-4953/com.malvinstn.testqueue D/CancellationJob: onAdded() called with id: bd358ee6-5391-4f4c-a103-410b572fd171
06-21 17:01:30.067 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? false isRunning? true waiting workers? 0
06-21 17:01:30.067 4934-4953/com.malvinstn.testqueue D/MainApplication: too many consumers, clearly above load factor 1
06-21 17:01:30.067 4934-4953/com.malvinstn.testqueue D/MainApplication: nothing has been poked. are we above load factor? false
06-21 17:01:30.068 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690067122916
06-21 17:01:30.068 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690067122916
06-21 17:01:30.068 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19543138887 ns
06-21 17:01:30.068 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.068 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message com.birbit.android.jobqueue.messaging.message.AddJobMessage@a0e9178
06-21 17:01:30.068 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type ADD_JOB
06-21 17:01:30.071 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.071 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.071 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=900000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2046
06-21 17:01:30.072 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.078 4934-4953/com.malvinstn.testqueue D/MainApplication: added job class: CancellationJob priority: 100 delay: 0 group : cancel persistent: true
06-21 17:01:30.078 4934-4953/com.malvinstn.testqueue D/CancellationJob: onAdded() called with id: 18a22173-8b40-44b2-a5e2-70fcf6c6a4bb
06-21 17:01:30.078 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? false isRunning? true waiting workers? 0
06-21 17:01:30.078 4934-4953/com.malvinstn.testqueue D/MainApplication: too many consumers, clearly above load factor 1
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: nothing has been poked. are we above load factor? false
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690078220885
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690078220885
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19532040918 ns
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 0
06-21 17:01:30.079 4934-4953/com.malvinstn.testqueue D/MainApplication: too many consumers, clearly above load factor 1
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: nothing has been poked. are we above load factor? false
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690079151666
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690079151666
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19531110137 ns
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message com.birbit.android.jobqueue.messaging.message.AddJobMessage@356b051
06-21 17:01:30.080 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type ADD_JOB
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: added job class: CancellationJob priority: 100 delay: 0 group : cancel persistent: true
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/CancellationJob: onAdded() called with id: fee42d92-aa3f-48ed-818f-af5bf5580097
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? false isRunning? true waiting workers? 0
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: too many consumers, clearly above load factor 1
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: nothing has been poked. are we above load factor? false
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690090648385
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690090648385
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19519613418 ns
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message com.birbit.android.jobqueue.messaging.message.AddJobMessage@547f0b6
06-21 17:01:30.091 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type ADD_JOB
06-21 17:01:30.098 4934-4953/com.malvinstn.testqueue D/MainApplication: added job class: CancellationJob priority: 100 delay: 0 group : cancel persistent: true
06-21 17:01:30.098 4934-4953/com.malvinstn.testqueue D/CancellationJob: onAdded() called with id: 702f8a54-ae9d-45b6-97c2-b8328dcbd434
06-21 17:01:30.098 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? false isRunning? true waiting workers? 0
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: too many consumers, clearly above load factor 1
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: nothing has been poked. are we above load factor? false
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690098225260
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690098225260
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19512036543 ns
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.099 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.111 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result com.birbit.android.jobqueue.JobHolder@bd2f47b8
06-21 17:01:30.111 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.111 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690111034895
06-21 17:01:30.111 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690111034895
06-21 17:01:30.112 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19499226908 ns
06-21 17:01:30.112 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.112 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.112 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.112 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.112 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.112 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690111388280
06-21 17:01:30.112 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.113 4934-5015/com.malvinstn.testqueue D/MainApplication: running job JobHolder
06-21 17:01:30.113 4934-5015/com.malvinstn.testqueue D/MainApplication: running job CancellationJob
06-21 17:01:30.113 4934-5015/com.malvinstn.testqueue E/MainApplication: error while executing job com.malvinstn.testqueue.CancellationJob@cc72cec
java.lang.RuntimeException: Forced Exception
at com.malvinstn.testqueue.CancellationJob.onRun(CancellationJob.java:46)
at com.birbit.android.jobqueue.Job.safeRun(Job.java:229)
at com.birbit.android.jobqueue.JobHolder.safeRun(JobHolder.java:132)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.handleRunJob(ConsumerManager.java:398)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.access$000(ConsumerManager.java:317)
at com.birbit.android.jobqueue.ConsumerManager$Consumer$2.handleMessage(ConsumerManager.java:345)
at com.birbit.android.jobqueue.messaging.SafeMessageQueue.consume(SafeMessageQueue.java:36)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.run(ConsumerManager.java:380)
at java.lang.Thread.run(Thread.java:818)
06-21 17:01:30.113 4934-5015/com.malvinstn.testqueue D/CancellationJob: shouldReRunOnThrowable() called with: throwable = [java.lang.RuntimeException: Forced Exception], runCount = [1], maxRunCount = [3], id = [4935dd88-ea18-472a-87ad-c21a57413967]
06-21 17:01:30.114 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: null
06-21 17:01:30.114 4934-5015/com.malvinstn.testqueue D/MainApplication: safeRunResult for com.malvinstn.testqueue.CancellationJob@cc72cec : false. re run:true. cancelled: false
06-21 17:01:30.115 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] post message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.115 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690114207708
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690114230000
06-21 17:01:30.115 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690114230000
06-21 17:01:30.115 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19496031803 ns
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.115 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type RUN_JOB_RESULT
06-21 17:01:30.115 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.115 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock forever
06-21 17:01:30.122 4934-4953/com.malvinstn.testqueue D/MainApplication: reinsert job result true
06-21 17:01:30.122 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690121960937
06-21 17:01:30.122 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690121960937
06-21 17:01:30.122 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19488300866 ns
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.123 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.131 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result com.birbit.android.jobqueue.JobHolder@f75ae571
06-21 17:01:30.131 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.132 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690131161249
06-21 17:01:30.132 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690131161562
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690131161562
06-21 17:01:30.132 4934-5015/com.malvinstn.testqueue D/MainApplication: running job JobHolder
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19479100241 ns
06-21 17:01:30.132 4934-5015/com.malvinstn.testqueue D/MainApplication: running job CancellationJob
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.132 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.132 4934-5015/com.malvinstn.testqueue E/MainApplication: error while executing job com.malvinstn.testqueue.CancellationJob@7c029a2
java.lang.RuntimeException: Forced Exception
at com.malvinstn.testqueue.CancellationJob.onRun(CancellationJob.java:46)
at com.birbit.android.jobqueue.Job.safeRun(Job.java:229)
at com.birbit.android.jobqueue.JobHolder.safeRun(JobHolder.java:132)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.handleRunJob(ConsumerManager.java:398)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.access$000(ConsumerManager.java:317)
at com.birbit.android.jobqueue.ConsumerManager$Consumer$2.handleMessage(ConsumerManager.java:345)
at com.birbit.android.jobqueue.messaging.SafeMessageQueue.consume(SafeMessageQueue.java:36)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.run(ConsumerManager.java:380)
at java.lang.Thread.run(Thread.java:818)
06-21 17:01:30.132 4934-5015/com.malvinstn.testqueue D/CancellationJob: shouldReRunOnThrowable() called with: throwable = [java.lang.RuntimeException: Forced Exception], runCount = [1], maxRunCount = [3], id = [bd358ee6-5391-4f4c-a103-410b572fd171]
06-21 17:01:30.133 4934-5015/com.malvinstn.testqueue D/MainApplication: safeRunResult for com.malvinstn.testqueue.CancellationJob@7c029a2 : false. re run:true. cancelled: false
06-21 17:01:30.133 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: null
06-21 17:01:30.134 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] post message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.134 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690133488177
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690133525937
06-21 17:01:30.134 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.134 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690133525937
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19476735866 ns
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.134 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type RUN_JOB_RESULT
06-21 17:01:30.134 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.134 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock forever
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: reinsert job result true
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690140724478
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690140724478
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19469537325 ns
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.141 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.142 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.142 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.150 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result com.birbit.android.jobqueue.JobHolder@b67efc0e
06-21 17:01:30.150 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.150 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690150064895
06-21 17:01:30.150 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690150065208
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690150064895
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19460196908 ns
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: running job JobHolder
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: running job CancellationJob
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.151 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue E/MainApplication: error while executing job com.malvinstn.testqueue.CancellationJob@cc148ab
java.lang.RuntimeException: Forced Exception
at com.malvinstn.testqueue.CancellationJob.onRun(CancellationJob.java:46)
at com.birbit.android.jobqueue.Job.safeRun(Job.java:229)
at com.birbit.android.jobqueue.JobHolder.safeRun(JobHolder.java:132)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.handleRunJob(ConsumerManager.java:398)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.access$000(ConsumerManager.java:317)
at com.birbit.android.jobqueue.ConsumerManager$Consumer$2.handleMessage(ConsumerManager.java:345)
at com.birbit.android.jobqueue.messaging.SafeMessageQueue.consume(SafeMessageQueue.java:36)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.run(ConsumerManager.java:380)
at java.lang.Thread.run(Thread.java:818)
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/CancellationJob: shouldReRunOnThrowable() called with: throwable = [java.lang.RuntimeException: Forced Exception], runCount = [1], maxRunCount = [3], id = [18a22173-8b40-44b2-a5e2-70fcf6c6a4bb]
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: safeRunResult for com.malvinstn.testqueue.CancellationJob@cc148ab : false. re run:true. cancelled: false
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] post message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690150826874
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.151 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock forever
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: null
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690151818802
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690151818802
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19458443001 ns
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.152 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type RUN_JOB_RESULT
06-21 17:01:30.159 4934-4953/com.malvinstn.testqueue D/MainApplication: reinsert job result true
06-21 17:01:30.159 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690158975520
06-21 17:01:30.159 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690158975520
06-21 17:01:30.159 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19451286283 ns
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.160 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result com.birbit.android.jobqueue.JobHolder@1cff03c4
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690170436457
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690170436145
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690170436457
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19439825346 ns
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue D/MainApplication: running job JobHolder
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue D/MainApplication: running job CancellationJob
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.171 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue E/MainApplication: error while executing job com.malvinstn.testqueue.CancellationJob@46b6320
java.lang.RuntimeException: Forced Exception
at com.malvinstn.testqueue.CancellationJob.onRun(CancellationJob.java:46)
at com.birbit.android.jobqueue.Job.safeRun(Job.java:229)
at com.birbit.android.jobqueue.JobHolder.safeRun(JobHolder.java:132)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.handleRunJob(ConsumerManager.java:398)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.access$000(ConsumerManager.java:317)
at com.birbit.android.jobqueue.ConsumerManager$Consumer$2.handleMessage(ConsumerManager.java:345)
at com.birbit.android.jobqueue.messaging.SafeMessageQueue.consume(SafeMessageQueue.java:36)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.run(ConsumerManager.java:380)
at java.lang.Thread.run(Thread.java:818)
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue D/CancellationJob: shouldReRunOnThrowable() called with: throwable = [java.lang.RuntimeException: Forced Exception], runCount = [1], maxRunCount = [3], id = [fee42d92-aa3f-48ed-818f-af5bf5580097]
06-21 17:01:30.171 4934-5015/com.malvinstn.testqueue D/MainApplication: safeRunResult for com.malvinstn.testqueue.CancellationJob@46b6320 : false. re run:true. cancelled: false
06-21 17:01:30.172 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] post message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.172 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690171179635
06-21 17:01:30.172 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.172 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.172 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.172 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock forever
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: null
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690172668698
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690172668698
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19437593105 ns
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.173 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type RUN_JOB_RESULT
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: reinsert job result true
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690179827030
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690179827030
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19430434773 ns
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.180 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.181 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.181 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.181 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.181 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result com.birbit.android.jobqueue.JobHolder@32bfc295
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690189401197
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message com.birbit.android.jobqueue.messaging.message.RunJobMessage@bd5039f
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690189401510
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690189401510
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue D/MainApplication: running job JobHolder
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19420860293 ns
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue D/MainApplication: running job CancellationJob
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.190 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue E/MainApplication: error while executing job com.malvinstn.testqueue.CancellationJob@dd9611
java.lang.RuntimeException: Forced Exception
at com.malvinstn.testqueue.CancellationJob.onRun(CancellationJob.java:46)
at com.birbit.android.jobqueue.Job.safeRun(Job.java:229)
at com.birbit.android.jobqueue.JobHolder.safeRun(JobHolder.java:132)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.handleRunJob(ConsumerManager.java:398)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.access$000(ConsumerManager.java:317)
at com.birbit.android.jobqueue.ConsumerManager$Consumer$2.handleMessage(ConsumerManager.java:345)
at com.birbit.android.jobqueue.messaging.SafeMessageQueue.consume(SafeMessageQueue.java:36)
at com.birbit.android.jobqueue.ConsumerManager$Consumer.run(ConsumerManager.java:380)
at java.lang.Thread.run(Thread.java:818)
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue D/CancellationJob: shouldReRunOnThrowable() called with: throwable = [java.lang.RuntimeException: Forced Exception], runCount = [1], maxRunCount = [3], id = [702f8a54-ae9d-45b6-97c2-b8328dcbd434]
06-21 17:01:30.190 4934-5015/com.malvinstn.testqueue D/MainApplication: safeRunResult for com.malvinstn.testqueue.CancellationJob@dd9611 : false. re run:true. cancelled: false
06-21 17:01:30.191 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] post message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.191 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690190140208
06-21 17:01:30.191 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.191 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.191 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.191 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock forever
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: null
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690191803073
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690191803073
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19418458730 ns
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message com.birbit.android.jobqueue.messaging.message.RunJobResultMessage@b0638b5
06-21 17:01:30.192 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type RUN_JOB_RESULT
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: reinsert job result true
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690199347760
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690199347760
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19410914043 ns
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.200 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.202 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.202 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.203 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.203 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.203 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.203 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.203 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.203 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=900000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@4f4824d}. reschedule:false
06-21 17:01:30.204 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690203294323
06-21 17:01:30.204 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14986839478 ns
06-21 17:01:30.204 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.204 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.207 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.207 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2047
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690209687864
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690209687864
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19400573939 ns
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.210 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.211 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.211 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.212 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.212 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.215 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.215 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@72dd450 at time 1466499709610261803
06-21 17:01:30.215 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690215075416
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690215075416
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19395186387 ns
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.216 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? false
06-21 17:01:30.217 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.217 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.217 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=1800000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2047
06-21 17:01:30.217 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690217095833
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690217095833
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19393165970 ns
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690217826406
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690217860624
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14972307395 ns
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690217860624
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19392401179 ns
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690217997551
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14972136250 ns
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.218 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.218 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.219 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.219 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.219 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.219 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.219 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.219 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@b13f649 at time 1466499709610261803
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690219189791
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690219189791
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19391072012 ns
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.220 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.221 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.221 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.221 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.221 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.221 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.221 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690220652499
06-21 17:01:30.221 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14969481302 ns
06-21 17:01:30.221 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.221 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.221 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=1800000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@2004b4e}. reschedule:false
06-21 17:01:30.222 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.222 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.228 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2048
06-21 17:01:30.228 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690228033749
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690228033749
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19382228054 ns
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.229 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@6a3e57c at time 1466499709610261803
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690229818020
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690229818020
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19380443783 ns
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.230 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.231 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.231 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? false
06-21 17:01:30.231 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.231 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.232 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.232 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.232 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=2700000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2048
06-21 17:01:30.232 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690231479999
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690231479999
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19378781804 ns
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.232 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690232259791
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690232259791
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19378002012 ns
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.233 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.233 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690232823281
06-21 17:01:30.233 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14957310520 ns
06-21 17:01:30.233 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.233 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.233 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690233020260
06-21 17:01:30.233 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14957113541 ns
06-21 17:01:30.234 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.234 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.234 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.234 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@599ce5a at time 1466499709610261803
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690233831666
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690233831666
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19376430137 ns
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.234 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.235 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.235 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.235 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.235 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.235 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.236 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.236 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.236 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.236 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.236 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.236 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690235426666
06-21 17:01:30.236 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14954707135 ns
06-21 17:01:30.236 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.236 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.236 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=2700000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@2f8788b}. reschedule:false
06-21 17:01:30.237 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.237 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2049
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690240711249
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690240711249
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19369550554 ns
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.241 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@23f2581 at time 1466499709610261803
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690242755937
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690242755937
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19367505866 ns
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.243 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? false
06-21 17:01:30.244 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.244 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.245 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.245 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.246 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=3600000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2049
06-21 17:01:30.246 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.246 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690245492031
06-21 17:01:30.246 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690245492031
06-21 17:01:30.246 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19364769772 ns
06-21 17:01:30.247 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.247 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.247 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.248 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.248 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.248 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690248403645
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14941730156 ns
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690248588906
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14941544895 ns
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.249 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.249 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.249 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690249038228
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690249038228
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19361223575 ns
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.250 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.251 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.251 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.251 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.251 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.251 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.251 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.252 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690251099791
06-21 17:01:30.252 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14939034010 ns
06-21 17:01:30.252 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.252 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.252 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.252 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=3600000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@9f41267}. reschedule:false
06-21 17:01:30.254 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.254 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2050
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690258493437
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690258493437
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19351768366 ns
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.259 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.260 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.260 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@a63ceb2 at time 1466499709610261803
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690260716041
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690260716041
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19349545762 ns
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.261 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? false
06-21 17:01:30.262 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.262 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.262 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=4500000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2050
06-21 17:01:30.262 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.262 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690261841823
06-21 17:01:30.262 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690261841823
06-21 17:01:30.262 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19348419980 ns
06-21 17:01:30.262 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.262 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.262 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690262555781
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690262582135
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14927578020 ns
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690262582135
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19347679668 ns
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690262706666
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14927427135 ns
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.263 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.263 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@5256e03 at time 1466499709610261803
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690263937968
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690263937968
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19346323835 ns
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.264 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.265 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.265 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.265 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.265 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.265 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.265 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.266 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.266 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.266 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.266 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.266 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.266 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690265471093
06-21 17:01:30.266 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14924662708 ns
06-21 17:01:30.266 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.266 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.266 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=4500000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@1c6f180}. reschedule:false
06-21 17:01:30.267 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.267 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.281 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2051
06-21 17:01:30.281 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690281031405
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690281031405
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19329230398 ns
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.282 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.283 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.283 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.284 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.284 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@d86e75f at time 1466499709610261803
06-21 17:01:30.284 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.284 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690284073593
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690284073593
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19326188210 ns
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? false
06-21 17:01:30.285 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.285 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.285 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=5400000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2051
06-21 17:01:30.285 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690284820989
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690284820989
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19325440814 ns
06-21 17:01:30.285 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690285651250
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690285651250
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19324610553 ns
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690285787760
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14904346041 ns
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.286 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690285952291
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14904181510 ns
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.286 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.287 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.287 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.287 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.287 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@f69a5ac at time 1466499709610261803
06-21 17:01:30.287 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.287 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690286987656
06-21 17:01:30.287 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690286987656
06-21 17:01:30.287 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19323274147 ns
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.288 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.289 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.289 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.289 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.289 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.289 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.289 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690288441249
06-21 17:01:30.289 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14901692552 ns
06-21 17:01:30.289 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.289 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.289 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=5400000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@f7e2275}. reschedule:false
06-21 17:01:30.290 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.290 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2052
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690294487448
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690294487448
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19315774355 ns
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.295 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.296 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.296 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.297 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.297 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.297 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=6300000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2052
06-21 17:01:30.297 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@fa17098 at time 1466499709610261803
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690297487447
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690297487447
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19312774356 ns
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.298 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690298237239
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690298237239
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19312024564 ns
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.299 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690298709999
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14891423802 ns
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690298854062
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14891279739 ns
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.299 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@59b70f1 at time 1466499709610261803
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690299938072
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690299938072
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19310323731 ns
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.300 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.301 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.301 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.301 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.301 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.301 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.301 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.302 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.302 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.302 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.302 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.302 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.302 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690301403749
06-21 17:01:30.302 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14888730052 ns
06-21 17:01:30.302 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.302 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.302 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=6300000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@afeb4d6}. reschedule:false
06-21 17:01:30.303 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.303 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2053
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690310242655
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690310242655
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19300019148 ns
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.311 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.312 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.312 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.312 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.312 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@6652b2d at time 1466499709610261803
06-21 17:01:30.312 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.312 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690312008437
06-21 17:01:30.312 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690312008437
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19298253366 ns
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.313 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? false
06-21 17:01:30.314 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.314 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.314 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=7200000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2053
06-21 17:01:30.314 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.314 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690313913385
06-21 17:01:30.314 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690313913385
06-21 17:01:30.314 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19296348418 ns
06-21 17:01:30.314 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690314794791
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690314808854
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14875339010 ns
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690314808854
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19295452949 ns
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690314946718
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14875187083 ns
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.315 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.315 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.316 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.316 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.316 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.316 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@e094862 at time 1466499709610261803
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690316296145
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690316296145
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19293965658 ns
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type JOB_CONSUMER_IDLE
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue V/MainApplication: looking for next job
06-21 17:01:30.317 4934-4953/com.malvinstn.testqueue V/MainApplication: non persistent result null
06-21 17:01:30.318 4934-4953/com.malvinstn.testqueue V/MainApplication: persistent result null
06-21 17:01:30.318 4934-4953/com.malvinstn.testqueue D/MainApplication: keep alive: 1466499705190133801
06-21 17:01:30.318 4934-4953/com.malvinstn.testqueue D/MainApplication: Consumer idle, will kill? false . isRunning: true
06-21 17:01:30.318 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message Command[2] at time 1466499705190133801
06-21 17:01:30.318 4934-4953/com.malvinstn.testqueue D/MainApplication: poke consumer manager at 1466499705190133801
06-21 17:01:30.318 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690317946353
06-21 17:01:30.318 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14872187448 ns
06-21 17:01:30.318 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.318 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.319 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] on finished job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=7200000, networkStatus=1, overrideDeadlineInMs=null, data=android.app.job.JobParameters@bcdddf3}. reschedule:false
06-21 17:01:30.320 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.320 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.327 4934-4953/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] scheduled a framework job. Success? true id: 1 created id: 2054
06-21 17:01:30.327 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690326892760
06-21 17:01:30.327 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690326892760
06-21 17:01:30.327 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19283369043 ns
06-21 17:01:30.328 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.328 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.328 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.328 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.328 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.328 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.328 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.328 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.330 4934-4934/com.malvinstn.testqueue D/com.malvinstn.testqueue.JobService: getJobManager() called with:
06-21 17:01:30.330 4934-4934/com.malvinstn.testqueue D/MainApplication: MainApplication::getJobManager()
06-21 17:01:30.330 4934-4934/com.malvinstn.testqueue D/MainApplication: [FW Scheduler] start job SchedulerConstraint{uuid='ba4d5780-85eb-4c15-b6bf-7daa76238ee2', delayInMs=8100000, networkStatus=1, overrideDeadlineInMs=null, data=null} 2054
06-21 17:01:30.330 4934-4934/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] post message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@e3788ae at time 1466499709610261803
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690330644895
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690330644895
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19279616908 ns
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message com.birbit.android.jobqueue.messaging.message.SchedulerMessage@a788cb
06-21 17:01:30.331 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] consuming message of type SCHEDULER
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: considering adding a new consumer. Should poke all waiting? true isRunning? true waiting workers? 1
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: there are waiting workers, will poke them instead
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [consumer_3] post message Command[2]
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: there were waiting workers, poked them and I'm done
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690331404947
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690331421562
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14858712239 ns
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message Command[2]
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: Consumer has been poked.
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690331619791
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: returning next ready at 14858514010 ns
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] remove message null
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: consumer manager on idle
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690331404947
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19278856856 ns
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] next delayed job 1466499709610261803
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_SCHEDULER_5] remove message null
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_RUN_JOB_RESULT_6] remove message null
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_ADD_JOB_2] remove message null
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] remove message null
06-21 17:01:30.332 4934-4953/com.malvinstn.testqueue D/MainApplication: joq idle. running:? true
06-21 17:01:30.332 4934-5015/com.malvinstn.testqueue D/MainApplication: [queue_JOB_CONSUMER_IDLE_4] post message com.birbit.android.jobqueue.messaging.message.JobConsumerIdleMessage@dbd21b7
06-21 17:01:30.333 4934-5015/com.malvinstn.testqueue D/MainApplication: [consumer_3] will wait on the lock until 1466499705190133801
06-21 17:01:30.333 4934-4953/com.malvinstn.testqueue D/MainApplication: Job queue idle. next job at: 1466499709610261803
06-21 17:01:30.333 4934-4953/com.malvinstn.testqueue D/MainApplication: add delayed message com.birbit.android.jobqueue.messaging.message.ConstraintChangeMessage@dde664f at time 1466499709610261803
06-21 17:01:30.333 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] did on idle post a message? true
06-21 17:01:30.334 4934-4953/com.malvinstn.testqueue D/MainApplication: [priority_mq] looking for next message at time 1466499690333110625
06-21 17:01:30.334 4934-4953/com.malvinstn.testqueue D/MainApplication: flushing messages at time 1466499690333110625
06-21 17:01:30.334 4934-4953/com.malvinstn.testqueue D/MainApplication: returning next ready at 19277151178 ns