[SERVER-20179] wiredtiger progress reporting is too verbose Created: 28/Aug/15  Updated: 15/Sep/15  Resolved: 11/Sep/15

Status: Closed
Project: Core Server
Component/s: WiredTiger
Affects Version/s: None
Fix Version/s: 3.1.8

Type: Bug Priority: Major - P3
Reporter: Eric Milkie Assignee: Alexander Gorrod
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Issue Links:
Depends
Related
is related to WT-2081 WiredTiger verify progress reporting ... Closed
Backwards Compatibility: Fully Compatible
Operating System: ALL
Steps To Reproduce:

	
[js_test:basicPlus] 2015-08-28T14:28:04.640+0000 2015-08-28T14:28:04.582+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 68
[js_test:basicPlus] 2015-08-28T14:28:04.645+0000 2015-08-28T14:28:04.579+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 60
[js_test:basicPlus] 2015-08-28T14:28:04.646+0000 2015-08-28T14:28:04.576+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 50
[js_test:basicPlus] 2015-08-28T14:28:04.653+0000 2015-08-28T14:28:04.574+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 40
[js_test:basicPlus] 2015-08-28T14:28:04.653+0000 2015-08-28T14:28:04.573+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 30
[js_test:basicPlus] 2015-08-28T14:28:04.653+0000 2015-08-28T14:28:04.571+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 20
[js_test:basicPlus] 2015-08-28T14:28:04.654+0000 2015-08-28T14:28:04.570+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 10
[js_test:basicPlus] 2015-08-28T14:28:04.656+0000 2015-08-28T14:28:04.567+0000 I INDEX    [conn276] validating index test.update_serializability1.$b_1
[js_test:basicPlus] 2015-08-28T14:28:04.660+0000 2015-08-28T14:28:04.532+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 61
[js_test:basicPlus] 2015-08-28T14:28:04.662+0000 2015-08-28T14:28:04.532+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 60
[js_test:basicPlus] 2015-08-28T14:28:04.664+0000 2015-08-28T14:28:04.530+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 50
[js_test:basicPlus] 2015-08-28T14:28:04.664+0000 2015-08-28T14:28:04.529+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 40
[js_test:basicPlus] 2015-08-28T14:28:04.666+0000 2015-08-28T14:28:04.527+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 30
[js_test:basicPlus] 2015-08-28T14:28:04.666+0000 2015-08-28T14:28:04.525+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 20
[js_test:basicPlus] 2015-08-28T14:28:04.666+0000 2015-08-28T14:28:04.522+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 10
[js_test:basicPlus] 2015-08-28T14:28:04.667+0000 2015-08-28T14:28:04.518+0000 I INDEX    [conn276] validating index test.update_serializability1.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.667+0000 2015-08-28T14:28:04.483+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 89
[js_test:basicPlus] 2015-08-28T14:28:04.667+0000 2015-08-28T14:28:04.482+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 80
[js_test:basicPlus] 2015-08-28T14:28:04.667+0000 2015-08-28T14:28:04.480+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 70
[js_test:basicPlus] 2015-08-28T14:28:04.667+0000 2015-08-28T14:28:04.479+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 60
[js_test:basicPlus] 2015-08-28T14:28:04.668+0000 2015-08-28T14:28:04.478+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 50
[js_test:basicPlus] 2015-08-28T14:28:04.668+0000 2015-08-28T14:28:04.476+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 40
[js_test:basicPlus] 2015-08-28T14:28:04.668+0000 2015-08-28T14:28:04.475+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 30
[js_test:basicPlus] 2015-08-28T14:28:04.668+0000 2015-08-28T14:28:04.473+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 20
[js_test:basicPlus] 2015-08-28T14:28:04.668+0000 2015-08-28T14:28:04.472+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 10
[js_test:basicPlus] 2015-08-28T14:28:04.669+0000 2015-08-28T14:28:04.469+0000 I INDEX    [conn276] validating index test.update_serializability1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.669+0000 2015-08-28T14:28:04.447+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 208
[js_test:basicPlus] 2015-08-28T14:28:04.669+0000 2015-08-28T14:28:04.446+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 200
[js_test:basicPlus] 2015-08-28T14:28:04.669+0000 2015-08-28T14:28:04.445+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 190
[js_test:basicPlus] 2015-08-28T14:28:04.670+0000 2015-08-28T14:28:04.444+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 180
[js_test:basicPlus] 2015-08-28T14:28:04.670+0000 2015-08-28T14:28:04.443+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 170
[js_test:basicPlus] 2015-08-28T14:28:04.670+0000 2015-08-28T14:28:04.442+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 160
[js_test:basicPlus] 2015-08-28T14:28:04.670+0000 2015-08-28T14:28:04.441+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 150
[js_test:basicPlus] 2015-08-28T14:28:04.670+0000 2015-08-28T14:28:04.440+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 140
[js_test:basicPlus] 2015-08-28T14:28:04.671+0000 2015-08-28T14:28:04.439+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 130
[js_test:basicPlus] 2015-08-28T14:28:04.671+0000 2015-08-28T14:28:04.439+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 120
[js_test:basicPlus] 2015-08-28T14:28:04.671+0000 2015-08-28T14:28:04.438+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 110
[js_test:basicPlus] 2015-08-28T14:28:04.671+0000 2015-08-28T14:28:04.437+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 100
[js_test:basicPlus] 2015-08-28T14:28:04.671+0000 2015-08-28T14:28:04.436+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 90
[js_test:basicPlus] 2015-08-28T14:28:04.672+0000 2015-08-28T14:28:04.435+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 80
[js_test:basicPlus] 2015-08-28T14:28:04.672+0000 2015-08-28T14:28:04.434+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 70
[js_test:basicPlus] 2015-08-28T14:28:04.672+0000 2015-08-28T14:28:04.433+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 60
[js_test:basicPlus] 2015-08-28T14:28:04.672+0000 2015-08-28T14:28:04.432+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 50
[js_test:basicPlus] 2015-08-28T14:28:04.672+0000 2015-08-28T14:28:04.431+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 40
[js_test:basicPlus] 2015-08-28T14:28:04.673+0000 2015-08-28T14:28:04.430+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 30
[js_test:basicPlus] 2015-08-28T14:28:04.673+0000 2015-08-28T14:28:04.429+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 20
[js_test:basicPlus] 2015-08-28T14:28:04.673+0000 2015-08-28T14:28:04.428+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 10
[js_test:basicPlus] 2015-08-28T14:28:04.673+0000 2015-08-28T14:28:04.426+0000 I COMMAND  [conn276] CMD: validate test.update_serializability1
[js_test:basicPlus] 2015-08-28T14:28:04.673+0000 2015-08-28T14:28:04.425+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.674+0000 2015-08-28T14:28:04.425+0000 I INDEX    [conn276] validating index test.update_multi6.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.674+0000 2015-08-28T14:28:04.424+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.674+0000 2015-08-28T14:28:04.423+0000 I COMMAND  [conn276] CMD: validate test.update_multi6
[js_test:basicPlus] 2015-08-28T14:28:04.674+0000 2015-08-28T14:28:04.422+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.674+0000 2015-08-28T14:28:04.421+0000 I INDEX    [conn276] validating index test.update_multi5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.674+0000 2015-08-28T14:28:04.421+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.675+0000 2015-08-28T14:28:04.420+0000 I COMMAND  [conn276] CMD: validate test.update_multi5
[js_test:basicPlus] 2015-08-28T14:28:04.675+0000 2015-08-28T14:28:04.420+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.675+0000 2015-08-28T14:28:04.418+0000 I INDEX    [conn276] validating index test.update_multi3.$k_1
[js_test:basicPlus] 2015-08-28T14:28:04.675+0000 2015-08-28T14:28:04.417+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.675+0000 2015-08-28T14:28:04.414+0000 I INDEX    [conn276] validating index test.update_multi3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.676+0000 2015-08-28T14:28:04.414+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.676+0000 2015-08-28T14:28:04.410+0000 I COMMAND  [conn276] CMD: validate test.update_multi3
[js_test:basicPlus] 2015-08-28T14:28:04.676+0000 2015-08-28T14:28:04.409+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.676+0000 2015-08-28T14:28:04.408+0000 I INDEX    [conn276] validating index test.update_mulit4.$k_1
[js_test:basicPlus] 2015-08-28T14:28:04.676+0000 2015-08-28T14:28:04.408+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.676+0000 2015-08-28T14:28:04.407+0000 I INDEX    [conn276] validating index test.update_mulit4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.677+0000 2015-08-28T14:28:04.407+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 3
[js_test:basicPlus] 2015-08-28T14:28:04.677+0000 2015-08-28T14:28:04.406+0000 I COMMAND  [conn276] CMD: validate test.update_mulit4
[js_test:basicPlus] 2015-08-28T14:28:04.677+0000 2015-08-28T14:28:04.406+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.677+0000 2015-08-28T14:28:04.405+0000 I INDEX    [conn276] validating index test.update_mul.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.677+0000 2015-08-28T14:28:04.404+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.678+0000 2015-08-28T14:28:04.404+0000 I COMMAND  [conn276] CMD: validate test.update_mul
[js_test:basicPlus] 2015-08-28T14:28:04.678+0000 2015-08-28T14:28:04.403+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.678+0000 2015-08-28T14:28:04.402+0000 I INDEX    [conn276] validating index test.update_mods.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.678+0000 2015-08-28T14:28:04.402+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.678+0000 2015-08-28T14:28:04.401+0000 I COMMAND  [conn276] CMD: validate test.update_mods
[js_test:basicPlus] 2015-08-28T14:28:04.678+0000 2015-08-28T14:28:04.401+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.679+0000 2015-08-28T14:28:04.400+0000 I INDEX    [conn276] validating index test.update_min_max.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.679+0000 2015-08-28T14:28:04.399+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.679+0000 2015-08-28T14:28:04.399+0000 I COMMAND  [conn276] CMD: validate test.update_min_max
[js_test:basicPlus] 2015-08-28T14:28:04.679+0000 2015-08-28T14:28:04.398+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.679+0000 2015-08-28T14:28:04.398+0000 I INDEX    [conn276] validating index test.update_invalid1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.680+0000 2015-08-28T14:28:04.397+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.680+0000 2015-08-28T14:28:04.397+0000 I COMMAND  [conn276] CMD: validate test.update_invalid1
[js_test:basicPlus] 2015-08-28T14:28:04.680+0000 2015-08-28T14:28:04.396+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.680+0000 2015-08-28T14:28:04.395+0000 I INDEX    [conn276] validating index test.update_currentdate.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.680+0000 2015-08-28T14:28:04.394+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.681+0000 2015-08-28T14:28:04.394+0000 I COMMAND  [conn276] CMD: validate test.update_currentdate
[js_test:basicPlus] 2015-08-28T14:28:04.681+0000 2015-08-28T14:28:04.393+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.681+0000 2015-08-28T14:28:04.393+0000 I INDEX    [conn276] validating index test.update_blank1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.681+0000 2015-08-28T14:28:04.392+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.681+0000 2015-08-28T14:28:04.392+0000 I COMMAND  [conn276] CMD: validate test.update_blank1
[js_test:basicPlus] 2015-08-28T14:28:04.682+0000 2015-08-28T14:28:04.391+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.682+0000 2015-08-28T14:28:04.391+0000 I INDEX    [conn276] validating index test.update_bit.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.682+0000 2015-08-28T14:28:04.390+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.682+0000 2015-08-28T14:28:04.390+0000 I COMMAND  [conn276] CMD: validate test.update_bit
[js_test:basicPlus] 2015-08-28T14:28:04.682+0000 2015-08-28T14:28:04.388+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.683+0000 2015-08-28T14:28:04.387+0000 I INDEX    [conn276] validating index test.update_arraymatch5.$abc.visible_1_testarray.visible_1_testarray.xxx_1
[js_test:basicPlus] 2015-08-28T14:28:04.683+0000 2015-08-28T14:28:04.386+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.683+0000 2015-08-28T14:28:04.385+0000 I INDEX    [conn276] validating index test.update_arraymatch5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.683+0000 2015-08-28T14:28:04.384+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.683+0000 2015-08-28T14:28:04.384+0000 I COMMAND  [conn276] CMD: validate test.update_arraymatch5
[js_test:basicPlus] 2015-08-28T14:28:04.683+0000 2015-08-28T14:28:04.383+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.684+0000 2015-08-28T14:28:04.383+0000 I INDEX    [conn276] validating index test.update_arraymatch4.$arr_1
[js_test:basicPlus] 2015-08-28T14:28:04.684+0000 2015-08-28T14:28:04.382+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.684+0000 2015-08-28T14:28:04.382+0000 I INDEX    [conn276] validating index test.update_arraymatch4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.684+0000 2015-08-28T14:28:04.382+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.684+0000 2015-08-28T14:28:04.381+0000 I COMMAND  [conn276] CMD: validate test.update_arraymatch4
[js_test:basicPlus] 2015-08-28T14:28:04.685+0000 2015-08-28T14:28:04.379+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.685+0000 2015-08-28T14:28:04.378+0000 I INDEX    [conn276] validating index test.update_arraymatch3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.685+0000 2015-08-28T14:28:04.377+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.685+0000 2015-08-28T14:28:04.376+0000 I COMMAND  [conn276] CMD: validate test.update_arraymatch3
[js_test:basicPlus] 2015-08-28T14:28:04.685+0000 2015-08-28T14:28:04.374+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.686+0000 2015-08-28T14:28:04.370+0000 I INDEX    [conn276] validating index test.update_arraymatch2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.686+0000 2015-08-28T14:28:04.370+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.686+0000 2015-08-28T14:28:04.364+0000 I COMMAND  [conn276] CMD: validate test.update_arraymatch2
[js_test:basicPlus] 2015-08-28T14:28:04.686+0000 2015-08-28T14:28:04.364+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.686+0000 2015-08-28T14:28:04.363+0000 I INDEX    [conn276] validating index test.update_arraymatch1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.686+0000 2015-08-28T14:28:04.363+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.687+0000 2015-08-28T14:28:04.362+0000 I COMMAND  [conn276] CMD: validate test.update_arraymatch1
[js_test:basicPlus] 2015-08-28T14:28:04.687+0000 2015-08-28T14:28:04.362+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.687+0000 2015-08-28T14:28:04.361+0000 I INDEX    [conn276] validating index test.update_addToSet3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.687+0000 2015-08-28T14:28:04.361+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.687+0000 2015-08-28T14:28:04.361+0000 I COMMAND  [conn276] CMD: validate test.update_addToSet3
[js_test:basicPlus] 2015-08-28T14:28:04.688+0000 2015-08-28T14:28:04.360+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.688+0000 2015-08-28T14:28:04.360+0000 I INDEX    [conn276] validating index test.update_addToSet2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.688+0000 2015-08-28T14:28:04.359+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.688+0000 2015-08-28T14:28:04.359+0000 I COMMAND  [conn276] CMD: validate test.update_addToSet2
[js_test:basicPlus] 2015-08-28T14:28:04.688+0000 2015-08-28T14:28:04.358+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.689+0000 2015-08-28T14:28:04.357+0000 I INDEX    [conn276] validating index test.update_addToSet1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.689+0000 2015-08-28T14:28:04.357+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.689+0000 2015-08-28T14:28:04.356+0000 I COMMAND  [conn276] CMD: validate test.update_addToSet1
[js_test:basicPlus] 2015-08-28T14:28:04.689+0000 2015-08-28T14:28:04.355+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.689+0000 2015-08-28T14:28:04.355+0000 I INDEX    [conn276] validating index test.update9.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.690+0000 2015-08-28T14:28:04.355+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.690+0000 2015-08-28T14:28:04.354+0000 I COMMAND  [conn276] CMD: validate test.update9
[js_test:basicPlus] 2015-08-28T14:28:04.690+0000 2015-08-28T14:28:04.352+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.690+0000 2015-08-28T14:28:04.352+0000 I INDEX    [conn276] validating index test.update7.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.690+0000 2015-08-28T14:28:04.351+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.690+0000 2015-08-28T14:28:04.351+0000 I INDEX    [conn276] validating index test.update7.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.691+0000 2015-08-28T14:28:04.351+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.691+0000 2015-08-28T14:28:04.350+0000 I COMMAND  [conn276] CMD: validate test.update7
[js_test:basicPlus] 2015-08-28T14:28:04.691+0000 2015-08-28T14:28:04.350+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.691+0000 2015-08-28T14:28:04.349+0000 I INDEX    [conn276] validating index test.update6.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.691+0000 2015-08-28T14:28:04.349+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.692+0000 2015-08-28T14:28:04.348+0000 I COMMAND  [conn276] CMD: validate test.update6
[js_test:basicPlus] 2015-08-28T14:28:04.692+0000 2015-08-28T14:28:04.348+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.692+0000 2015-08-28T14:28:04.347+0000 I INDEX    [conn276] validating index test.update5.$date_1_referer_1_name_1
[js_test:basicPlus] 2015-08-28T14:28:04.692+0000 2015-08-28T14:28:04.347+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.692+0000 2015-08-28T14:28:04.347+0000 I INDEX    [conn276] validating index test.update5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.692+0000 2015-08-28T14:28:04.346+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.693+0000 2015-08-28T14:28:04.346+0000 I COMMAND  [conn276] CMD: validate test.update5
[js_test:basicPlus] 2015-08-28T14:28:04.693+0000 2015-08-28T14:28:04.345+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.693+0000 2015-08-28T14:28:04.345+0000 I INDEX    [conn276] validating index test.unset2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.693+0000 2015-08-28T14:28:04.344+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.693+0000 2015-08-28T14:28:04.344+0000 I COMMAND  [conn276] CMD: validate test.unset2
[js_test:basicPlus] 2015-08-28T14:28:04.694+0000 2015-08-28T14:28:04.343+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.694+0000 2015-08-28T14:28:04.343+0000 I INDEX    [conn276] validating index test.unset.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.694+0000 2015-08-28T14:28:04.343+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.694+0000 2015-08-28T14:28:04.342+0000 I COMMAND  [conn276] CMD: validate test.unset
[js_test:basicPlus] 2015-08-28T14:28:04.694+0000 2015-08-28T14:28:04.342+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.694+0000 2015-08-28T14:28:04.341+0000 I INDEX    [conn276] validating index test.type1.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.695+0000 2015-08-28T14:28:04.340+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.695+0000 2015-08-28T14:28:04.340+0000 I INDEX    [conn276] validating index test.type1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.695+0000 2015-08-28T14:28:04.339+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.695+0000 2015-08-28T14:28:04.339+0000 I COMMAND  [conn276] CMD: validate test.type1
[js_test:basicPlus] 2015-08-28T14:28:04.695+0000 2015-08-28T14:28:04.338+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.696+0000 2015-08-28T14:28:04.338+0000 I INDEX    [conn276] validating index test.twodspheredesc.$coordinates_2dsphere_field_-1
[js_test:basicPlus] 2015-08-28T14:28:04.696+0000 2015-08-28T14:28:04.337+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.696+0000 2015-08-28T14:28:04.337+0000 I INDEX    [conn276] validating index test.twodspheredesc.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.696+0000 2015-08-28T14:28:04.337+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.696+0000 2015-08-28T14:28:04.336+0000 I COMMAND  [conn276] CMD: validate test.twodspheredesc
[js_test:basicPlus] 2015-08-28T14:28:04.697+0000 2015-08-28T14:28:04.336+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.697+0000 2015-08-28T14:28:04.335+0000 I INDEX    [conn276] validating index test.ts1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.697+0000 2015-08-28T14:28:04.335+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.697+0000 2015-08-28T14:28:04.334+0000 I COMMAND  [conn276] CMD: validate test.ts1
[js_test:basicPlus] 2015-08-28T14:28:04.697+0000 2015-08-28T14:28:04.334+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.697+0000 2015-08-28T14:28:04.333+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_93.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.698+0000 2015-08-28T14:28:04.333+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.698+0000 2015-08-28T14:28:04.333+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_93
[js_test:basicPlus] 2015-08-28T14:28:04.698+0000 2015-08-28T14:28:04.332+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.698+0000 2015-08-28T14:28:04.332+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_92.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.698+0000 2015-08-28T14:28:04.332+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.699+0000 2015-08-28T14:28:04.331+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_92
[js_test:basicPlus] 2015-08-28T14:28:04.699+0000 2015-08-28T14:28:04.331+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.699+0000 2015-08-28T14:28:04.330+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_91.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.699+0000 2015-08-28T14:28:04.317+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.699+0000 2015-08-28T14:28:04.316+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_91
[js_test:basicPlus] 2015-08-28T14:28:04.700+0000 2015-08-28T14:28:04.315+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.700+0000 2015-08-28T14:28:04.315+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_90.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.700+0000 2015-08-28T14:28:04.315+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.700+0000 2015-08-28T14:28:04.314+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_90
[js_test:basicPlus] 2015-08-28T14:28:04.700+0000 2015-08-28T14:28:04.314+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.701+0000 2015-08-28T14:28:04.314+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_89.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.701+0000 2015-08-28T14:28:04.313+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.701+0000 2015-08-28T14:28:04.313+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_89
[js_test:basicPlus] 2015-08-28T14:28:04.701+0000 2015-08-28T14:28:04.312+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.701+0000 2015-08-28T14:28:04.312+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_88.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.702+0000 2015-08-28T14:28:04.312+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.702+0000 2015-08-28T14:28:04.311+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_88
[js_test:basicPlus] 2015-08-28T14:28:04.702+0000 2015-08-28T14:28:04.311+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.702+0000 2015-08-28T14:28:04.310+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_87.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.702+0000 2015-08-28T14:28:04.310+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.702+0000 2015-08-28T14:28:04.310+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_87
[js_test:basicPlus] 2015-08-28T14:28:04.703+0000 2015-08-28T14:28:04.309+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.703+0000 2015-08-28T14:28:04.309+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_86.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.703+0000 2015-08-28T14:28:04.308+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.703+0000 2015-08-28T14:28:04.308+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_86
[js_test:basicPlus] 2015-08-28T14:28:04.703+0000 2015-08-28T14:28:04.307+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.704+0000 2015-08-28T14:28:04.307+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_85.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.704+0000 2015-08-28T14:28:04.307+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.704+0000 2015-08-28T14:28:04.306+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_85
[js_test:basicPlus] 2015-08-28T14:28:04.704+0000 2015-08-28T14:28:04.306+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.705+0000 2015-08-28T14:28:04.305+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_84.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.705+0000 2015-08-28T14:28:04.305+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.705+0000 2015-08-28T14:28:04.305+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_84
[js_test:basicPlus] 2015-08-28T14:28:04.705+0000 2015-08-28T14:28:04.304+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.705+0000 2015-08-28T14:28:04.304+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_38.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.706+0000 2015-08-28T14:28:04.304+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.706+0000 2015-08-28T14:28:04.303+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_38
[js_test:basicPlus] 2015-08-28T14:28:04.706+0000 2015-08-28T14:28:04.303+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.706+0000 2015-08-28T14:28:04.302+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_37.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.706+0000 2015-08-28T14:28:04.302+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.707+0000 2015-08-28T14:28:04.302+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_37
[js_test:basicPlus] 2015-08-28T14:28:04.707+0000 2015-08-28T14:28:04.300+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.707+0000 2015-08-28T14:28:04.300+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_36.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.707+0000 2015-08-28T14:28:04.300+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.707+0000 2015-08-28T14:28:04.299+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_36
[js_test:basicPlus] 2015-08-28T14:28:04.708+0000 2015-08-28T14:28:04.299+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.708+0000 2015-08-28T14:28:04.298+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_35.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.708+0000 2015-08-28T14:28:04.298+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.708+0000 2015-08-28T14:28:04.298+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_35
[js_test:basicPlus] 2015-08-28T14:28:04.708+0000 2015-08-28T14:28:04.297+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.709+0000 2015-08-28T14:28:04.297+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_34.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.709+0000 2015-08-28T14:28:04.296+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.709+0000 2015-08-28T14:28:04.296+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_34
[js_test:basicPlus] 2015-08-28T14:28:04.709+0000 2015-08-28T14:28:04.296+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.709+0000 2015-08-28T14:28:04.295+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_31.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.709+0000 2015-08-28T14:28:04.295+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.710+0000 2015-08-28T14:28:04.294+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_31
[js_test:basicPlus] 2015-08-28T14:28:04.710+0000 2015-08-28T14:28:04.294+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.710+0000 2015-08-28T14:28:04.294+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_30.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.710+0000 2015-08-28T14:28:04.293+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.710+0000 2015-08-28T14:28:04.293+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_30
[js_test:basicPlus] 2015-08-28T14:28:04.711+0000 2015-08-28T14:28:04.292+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.711+0000 2015-08-28T14:28:04.292+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_29.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.711+0000 2015-08-28T14:28:04.292+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.711+0000 2015-08-28T14:28:04.291+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_29
[js_test:basicPlus] 2015-08-28T14:28:04.711+0000 2015-08-28T14:28:04.291+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.712+0000 2015-08-28T14:28:04.290+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_28.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.712+0000 2015-08-28T14:28:04.290+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.712+0000 2015-08-28T14:28:04.290+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_28
[js_test:basicPlus] 2015-08-28T14:28:04.712+0000 2015-08-28T14:28:04.289+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.712+0000 2015-08-28T14:28:04.289+0000 I INDEX    [conn276] validating index test.tmp.mr.jstests_mr_killop_27.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.713+0000 2015-08-28T14:28:04.288+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.713+0000 2015-08-28T14:28:04.288+0000 I COMMAND  [conn276] CMD: validate test.tmp.mr.jstests_mr_killop_27
[js_test:basicPlus] 2015-08-28T14:28:04.713+0000 2015-08-28T14:28:04.287+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.713+0000 2015-08-28T14:28:04.287+0000 I INDEX    [conn276] validating index test.things3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.713+0000 2015-08-28T14:28:04.287+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.714+0000 2015-08-28T14:28:04.286+0000 I COMMAND  [conn276] CMD: validate test.things3
[js_test:basicPlus] 2015-08-28T14:28:04.714+0000 2015-08-28T14:28:04.286+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.714+0000 2015-08-28T14:28:04.285+0000 I INDEX    [conn276] validating index test.things.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.714+0000 2015-08-28T14:28:04.285+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.714+0000 2015-08-28T14:28:04.284+0000 I COMMAND  [conn276] CMD: validate test.things
[js_test:basicPlus] 2015-08-28T14:28:04.714+0000 2015-08-28T14:28:04.284+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.715+0000 2015-08-28T14:28:04.283+0000 I INDEX    [conn276] validating index test.text_spanish.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.715+0000 2015-08-28T14:28:04.283+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.715+0000 2015-08-28T14:28:04.283+0000 I COMMAND  [conn276] CMD: validate test.text_spanish
[js_test:basicPlus] 2015-08-28T14:28:04.715+0000 2015-08-28T14:28:04.282+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.715+0000 2015-08-28T14:28:04.282+0000 I INDEX    [conn276] validating index test.text_proj.$x_text
[js_test:basicPlus] 2015-08-28T14:28:04.716+0000 2015-08-28T14:28:04.281+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.716+0000 2015-08-28T14:28:04.281+0000 I INDEX    [conn276] validating index test.text_proj.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.716+0000 2015-08-28T14:28:04.280+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.716+0000 2015-08-28T14:28:04.280+0000 I COMMAND  [conn276] CMD: validate test.text_proj
[js_test:basicPlus] 2015-08-28T14:28:04.716+0000 2015-08-28T14:28:04.279+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.716+0000 2015-08-28T14:28:04.279+0000 I INDEX    [conn276] validating index test.text_phrase.$title_text_text_text
[js_test:basicPlus] 2015-08-28T14:28:04.717+0000 2015-08-28T14:28:04.278+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.717+0000 2015-08-28T14:28:04.278+0000 I INDEX    [conn276] validating index test.text_phrase.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.717+0000 2015-08-28T14:28:04.277+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.717+0000 2015-08-28T14:28:04.276+0000 I COMMAND  [conn276] CMD: validate test.text_phrase
[js_test:basicPlus] 2015-08-28T14:28:04.717+0000 2015-08-28T14:28:04.276+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.718+0000 2015-08-28T14:28:04.275+0000 I INDEX    [conn276] validating index test.text_parition1.$x_1_y_text
[js_test:basicPlus] 2015-08-28T14:28:04.718+0000 2015-08-28T14:28:04.275+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.718+0000 2015-08-28T14:28:04.274+0000 I INDEX    [conn276] validating index test.text_parition1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.718+0000 2015-08-28T14:28:04.274+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.718+0000 2015-08-28T14:28:04.274+0000 I COMMAND  [conn276] CMD: validate test.text_parition1
[js_test:basicPlus] 2015-08-28T14:28:04.719+0000 2015-08-28T14:28:04.273+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.719+0000 2015-08-28T14:28:04.272+0000 I INDEX    [conn276] validating index test.text_mix.$$**_text
[js_test:basicPlus] 2015-08-28T14:28:04.719+0000 2015-08-28T14:28:04.272+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.719+0000 2015-08-28T14:28:04.271+0000 I INDEX    [conn276] validating index test.text_mix.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.719+0000 2015-08-28T14:28:04.271+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.719+0000 2015-08-28T14:28:04.270+0000 I COMMAND  [conn276] CMD: validate test.text_mix
[js_test:basicPlus] 2015-08-28T14:28:04.720+0000 2015-08-28T14:28:04.270+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.720+0000 2015-08-28T14:28:04.269+0000 I INDEX    [conn276] validating index test.text_blogwild.$dummy_text
[js_test:basicPlus] 2015-08-28T14:28:04.720+0000 2015-08-28T14:28:04.269+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.720+0000 2015-08-28T14:28:04.268+0000 I INDEX    [conn276] validating index test.text_blogwild.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.720+0000 2015-08-28T14:28:04.268+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.721+0000 2015-08-28T14:28:04.267+0000 I COMMAND  [conn276] CMD: validate test.text_blogwild
[js_test:basicPlus] 2015-08-28T14:28:04.721+0000 2015-08-28T14:28:04.267+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.721+0000 2015-08-28T14:28:04.266+0000 I INDEX    [conn276] validating index test.text_blog.$title_text_text_text
[js_test:basicPlus] 2015-08-28T14:28:04.721+0000 2015-08-28T14:28:04.266+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.721+0000 2015-08-28T14:28:04.262+0000 I INDEX    [conn276] validating index test.text_blog.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.722+0000 2015-08-28T14:28:04.262+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.722+0000 2015-08-28T14:28:04.251+0000 I COMMAND  [conn276] CMD: validate test.text_blog
[js_test:basicPlus] 2015-08-28T14:28:04.722+0000 2015-08-28T14:28:04.250+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.722+0000 2015-08-28T14:28:04.250+0000 I INDEX    [conn276] validating index test.text5.$y_text_z_1
[js_test:basicPlus] 2015-08-28T14:28:04.722+0000 2015-08-28T14:28:04.249+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.723+0000 2015-08-28T14:28:04.241+0000 I INDEX    [conn276] validating index test.text5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.723+0000 2015-08-28T14:28:04.241+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.723+0000 2015-08-28T14:28:04.241+0000 I COMMAND  [conn276] CMD: validate test.text5
[js_test:basicPlus] 2015-08-28T14:28:04.723+0000 2015-08-28T14:28:04.240+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.723+0000 2015-08-28T14:28:04.240+0000 I INDEX    [conn276] validating index test.text4.$y_text_z_1
[js_test:basicPlus] 2015-08-28T14:28:04.723+0000 2015-08-28T14:28:04.239+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.724+0000 2015-08-28T14:28:04.239+0000 I INDEX    [conn276] validating index test.text4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.724+0000 2015-08-28T14:28:04.238+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.724+0000 2015-08-28T14:28:04.238+0000 I COMMAND  [conn276] CMD: validate test.text4
[js_test:basicPlus] 2015-08-28T14:28:04.724+0000 2015-08-28T14:28:04.237+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.724+0000 2015-08-28T14:28:04.237+0000 I INDEX    [conn276] validating index test.text3.$x_text_z_1
[js_test:basicPlus] 2015-08-28T14:28:04.725+0000 2015-08-28T14:28:04.236+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.725+0000 2015-08-28T14:28:04.236+0000 I INDEX    [conn276] validating index test.text3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.725+0000 2015-08-28T14:28:04.236+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.725+0000 2015-08-28T14:28:04.235+0000 I COMMAND  [conn276] CMD: validate test.text3
[js_test:basicPlus] 2015-08-28T14:28:04.725+0000 2015-08-28T14:28:04.235+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.726+0000 2015-08-28T14:28:04.234+0000 I INDEX    [conn276] validating index test.text2.$x_text
[js_test:basicPlus] 2015-08-28T14:28:04.726+0000 2015-08-28T14:28:04.234+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.726+0000 2015-08-28T14:28:04.233+0000 I INDEX    [conn276] validating index test.text2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.726+0000 2015-08-28T14:28:04.233+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.726+0000 2015-08-28T14:28:04.233+0000 I COMMAND  [conn276] CMD: validate test.text2
[js_test:basicPlus] 2015-08-28T14:28:04.726+0000 2015-08-28T14:28:04.232+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.727+0000 2015-08-28T14:28:04.225+0000 I INDEX    [conn276] validating index test.text1.$x_text
[js_test:basicPlus] 2015-08-28T14:28:04.727+0000 2015-08-28T14:28:04.225+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.727+0000 2015-08-28T14:28:04.219+0000 I INDEX    [conn276] validating index test.text1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.727+0000 2015-08-28T14:28:04.219+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.727+0000 2015-08-28T14:28:04.217+0000 I COMMAND  [conn276] CMD: validate test.text1
[js_test:basicPlus] 2015-08-28T14:28:04.728+0000 2015-08-28T14:28:04.216+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.728+0000 2015-08-28T14:28:04.216+0000 I INDEX    [conn276] validating index test.testGeoUpdate.$loc_2d
[js_test:basicPlus] 2015-08-28T14:28:04.728+0000 2015-08-28T14:28:04.215+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.728+0000 2015-08-28T14:28:04.215+0000 I INDEX    [conn276] validating index test.testGeoUpdate.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.728+0000 2015-08-28T14:28:04.212+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.728+0000 2015-08-28T14:28:04.212+0000 I COMMAND  [conn276] CMD: validate test.testGeoUpdate
[js_test:basicPlus] 2015-08-28T14:28:04.729+0000 2015-08-28T14:28:04.211+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.729+0000 2015-08-28T14:28:04.211+0000 I COMMAND  [conn276] CMD: validate test.system.profile
[js_test:basicPlus] 2015-08-28T14:28:04.729+0000 2015-08-28T14:28:04.210+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.729+0000 2015-08-28T14:28:04.210+0000 I INDEX    [conn276] validating index test.system.js.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.729+0000 2015-08-28T14:28:04.210+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.730+0000 2015-08-28T14:28:04.209+0000 I COMMAND  [conn276] CMD: validate test.system.js
[js_test:basicPlus] 2015-08-28T14:28:04.730+0000 2015-08-28T14:28:04.207+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.730+0000 2015-08-28T14:28:04.207+0000 I INDEX    [conn276] validating index test.sub1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.730+0000 2015-08-28T14:28:04.206+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.730+0000 2015-08-28T14:28:04.206+0000 I COMMAND  [conn276] CMD: validate test.sub1
[js_test:basicPlus] 2015-08-28T14:28:04.730+0000 2015-08-28T14:28:04.205+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.731+0000 2015-08-28T14:28:04.205+0000 I INDEX    [conn276] validating index test.string_with_nul_bytes.js.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.731+0000 2015-08-28T14:28:04.204+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.731+0000 2015-08-28T14:28:04.203+0000 I COMMAND  [conn276] CMD: validate test.string_with_nul_bytes.js
[js_test:basicPlus] 2015-08-28T14:28:04.731+0000 2015-08-28T14:28:04.201+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 3
[js_test:basicPlus] 2015-08-28T14:28:04.731+0000 2015-08-28T14:28:04.198+0000 I INDEX    [conn276] validating index test.storageDetailsCommand.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.732+0000 2015-08-28T14:28:04.196+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 6
[js_test:basicPlus] 2015-08-28T14:28:04.732+0000 2015-08-28T14:28:04.192+0000 I COMMAND  [conn276] CMD: validate test.storageDetailsCommand
[js_test:basicPlus] 2015-08-28T14:28:04.732+0000 2015-08-28T14:28:04.192+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.732+0000 2015-08-28T14:28:04.191+0000 I INDEX    [conn276] validating index test.stages_text.$x_text
[js_test:basicPlus] 2015-08-28T14:28:04.732+0000 2015-08-28T14:28:04.191+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.733+0000 2015-08-28T14:28:04.189+0000 I INDEX    [conn276] validating index test.stages_text.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.733+0000 2015-08-28T14:28:04.189+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.733+0000 2015-08-28T14:28:04.189+0000 I COMMAND  [conn276] CMD: validate test.stages_text
[js_test:basicPlus] 2015-08-28T14:28:04.733+0000 2015-08-28T14:28:04.188+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.733+0000 2015-08-28T14:28:04.186+0000 I INDEX    [conn276] validating index test.stages_or.$baz_1
[js_test:basicPlus] 2015-08-28T14:28:04.733+0000 2015-08-28T14:28:04.186+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.734+0000 2015-08-28T14:28:04.186+0000 I INDEX    [conn276] validating index test.stages_or.$bar_1
[js_test:basicPlus] 2015-08-28T14:28:04.734+0000 2015-08-28T14:28:04.185+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.734+0000 2015-08-28T14:28:04.185+0000 I INDEX    [conn276] validating index test.stages_or.$foo_1
[js_test:basicPlus] 2015-08-28T14:28:04.734+0000 2015-08-28T14:28:04.184+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.734+0000 2015-08-28T14:28:04.183+0000 I INDEX    [conn276] validating index test.stages_or.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.735+0000 2015-08-28T14:28:04.182+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.735+0000 2015-08-28T14:28:04.179+0000 I COMMAND  [conn276] CMD: validate test.stages_or
[js_test:basicPlus] 2015-08-28T14:28:04.735+0000 2015-08-28T14:28:04.178+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.735+0000 2015-08-28T14:28:04.178+0000 I INDEX    [conn276] validating index test.stages_mergesort.$baz_1_bar_1
[js_test:basicPlus] 2015-08-28T14:28:04.735+0000 2015-08-28T14:28:04.177+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.736+0000 2015-08-28T14:28:04.177+0000 I INDEX    [conn276] validating index test.stages_mergesort.$foo_1_bar_1
[js_test:basicPlus] 2015-08-28T14:28:04.736+0000 2015-08-28T14:28:04.176+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.736+0000 2015-08-28T14:28:04.176+0000 I INDEX    [conn276] validating index test.stages_mergesort.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.736+0000 2015-08-28T14:28:04.176+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.736+0000 2015-08-28T14:28:04.175+0000 I COMMAND  [conn276] CMD: validate test.stages_mergesort
[js_test:basicPlus] 2015-08-28T14:28:04.736+0000 2015-08-28T14:28:04.175+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.737+0000 2015-08-28T14:28:04.174+0000 I INDEX    [conn276] validating index test.stages_limit_skip.$foo_1
[js_test:basicPlus] 2015-08-28T14:28:04.737+0000 2015-08-28T14:28:04.174+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.737+0000 2015-08-28T14:28:04.173+0000 I INDEX    [conn276] validating index test.stages_limit_skip.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.737+0000 2015-08-28T14:28:04.172+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.737+0000 2015-08-28T14:28:04.169+0000 I COMMAND  [conn276] CMD: validate test.stages_limit_skip
[js_test:basicPlus] 2015-08-28T14:28:04.738+0000 2015-08-28T14:28:04.169+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.738+0000 2015-08-28T14:28:04.168+0000 I INDEX    [conn276] validating index test.stages_ixscan.$foo_1_baz_1
[js_test:basicPlus] 2015-08-28T14:28:04.738+0000 2015-08-28T14:28:04.168+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.738+0000 2015-08-28T14:28:04.167+0000 I INDEX    [conn276] validating index test.stages_ixscan.$foo_1
[js_test:basicPlus] 2015-08-28T14:28:04.738+0000 2015-08-28T14:28:04.167+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.739+0000 2015-08-28T14:28:04.166+0000 I INDEX    [conn276] validating index test.stages_ixscan.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.739+0000 2015-08-28T14:28:04.166+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.739+0000 2015-08-28T14:28:04.166+0000 I COMMAND  [conn276] CMD: validate test.stages_ixscan
[js_test:basicPlus] 2015-08-28T14:28:04.739+0000 2015-08-28T14:28:04.164+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.739+0000 2015-08-28T14:28:04.164+0000 I INDEX    [conn276] validating index test.stages_fetch.$foo_1
[js_test:basicPlus] 2015-08-28T14:28:04.739+0000 2015-08-28T14:28:04.163+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.745+0000 2015-08-28T14:28:04.158+0000 I INDEX    [conn276] validating index test.stages_fetch.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.745+0000 2015-08-28T14:28:04.157+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.745+0000 2015-08-28T14:28:04.149+0000 I COMMAND  [conn276] CMD: validate test.stages_fetch
[js_test:basicPlus] 2015-08-28T14:28:04.745+0000 2015-08-28T14:28:04.149+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.746+0000 2015-08-28T14:28:04.148+0000 I INDEX    [conn276] validating index test.stages_delete.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.746+0000 2015-08-28T14:28:04.148+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.746+0000 2015-08-28T14:28:04.148+0000 I COMMAND  [conn276] CMD: validate test.stages_delete
[js_test:basicPlus] 2015-08-28T14:28:04.746+0000 2015-08-28T14:28:04.147+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.747+0000 2015-08-28T14:28:04.147+0000 I INDEX    [conn276] validating index test.stages_collection_scan.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.747+0000 2015-08-28T14:28:04.146+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.747+0000 2015-08-28T14:28:04.146+0000 I COMMAND  [conn276] CMD: validate test.stages_collection_scan
[js_test:basicPlus] 2015-08-28T14:28:04.747+0000 2015-08-28T14:28:04.145+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.748+0000 2015-08-28T14:28:04.145+0000 I INDEX    [conn276] validating index test.stages_and_sorted.$baz_1
[js_test:basicPlus] 2015-08-28T14:28:04.748+0000 2015-08-28T14:28:04.144+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.748+0000 2015-08-28T14:28:04.144+0000 I INDEX    [conn276] validating index test.stages_and_sorted.$bar_1
[js_test:basicPlus] 2015-08-28T14:28:04.748+0000 2015-08-28T14:28:04.143+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.749+0000 2015-08-28T14:28:04.143+0000 I INDEX    [conn276] validating index test.stages_and_sorted.$foo_1
[js_test:basicPlus] 2015-08-28T14:28:04.749+0000 2015-08-28T14:28:04.143+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.749+0000 2015-08-28T14:28:04.142+0000 I INDEX    [conn276] validating index test.stages_and_sorted.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.749+0000 2015-08-28T14:28:04.142+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.750+0000 2015-08-28T14:28:04.141+0000 I COMMAND  [conn276] CMD: validate test.stages_and_sorted
[js_test:basicPlus] 2015-08-28T14:28:04.750+0000 2015-08-28T14:28:04.141+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.750+0000 2015-08-28T14:28:04.140+0000 I INDEX    [conn276] validating index test.stages_and_hashed.$baz_1
[js_test:basicPlus] 2015-08-28T14:28:04.750+0000 2015-08-28T14:28:04.140+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.751+0000 2015-08-28T14:28:04.139+0000 I INDEX    [conn276] validating index test.stages_and_hashed.$bar_1
[js_test:basicPlus] 2015-08-28T14:28:04.751+0000 2015-08-28T14:28:04.139+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.751+0000 2015-08-28T14:28:04.139+0000 I INDEX    [conn276] validating index test.stages_and_hashed.$foo_1
[js_test:basicPlus] 2015-08-28T14:28:04.751+0000 2015-08-28T14:28:04.138+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.752+0000 2015-08-28T14:28:04.138+0000 I INDEX    [conn276] validating index test.stages_and_hashed.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.752+0000 2015-08-28T14:28:04.137+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.752+0000 2015-08-28T14:28:04.137+0000 I COMMAND  [conn276] CMD: validate test.stages_and_hashed
[js_test:basicPlus] 2015-08-28T14:28:04.752+0000 2015-08-28T14:28:04.136+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.753+0000 2015-08-28T14:28:04.135+0000 I INDEX    [conn276] validating index test.sps.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.753+0000 2015-08-28T14:28:04.135+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.753+0000 2015-08-28T14:28:04.134+0000 I COMMAND  [conn276] CMD: validate test.sps
[js_test:basicPlus] 2015-08-28T14:28:04.753+0000 2015-08-28T14:28:04.130+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 8
[js_test:basicPlus] 2015-08-28T14:28:04.753+0000 2015-08-28T14:28:04.129+0000 I INDEX    [conn276] validating index test.sphere.$loc_2d
[js_test:basicPlus] 2015-08-28T14:28:04.754+0000 2015-08-28T14:28:04.128+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.754+0000 2015-08-28T14:28:04.128+0000 I INDEX    [conn276] validating index test.sphere.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.754+0000 2015-08-28T14:28:04.127+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 8
[js_test:basicPlus] 2015-08-28T14:28:04.754+0000 2015-08-28T14:28:04.126+0000 I COMMAND  [conn276] CMD: validate test.sphere
[js_test:basicPlus] 2015-08-28T14:28:04.755+0000 2015-08-28T14:28:04.125+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.755+0000 2015-08-28T14:28:04.124+0000 I INDEX    [conn276] validating index test.sort_numeric.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.755+0000 2015-08-28T14:28:04.123+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.760+0000 2015-08-28T14:28:04.123+0000 I INDEX    [conn276] validating index test.sort_numeric.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.761+0000 2015-08-28T14:28:04.115+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.761+0000 2015-08-28T14:28:04.114+0000 I COMMAND  [conn276] CMD: validate test.sort_numeric
[js_test:basicPlus] 2015-08-28T14:28:04.761+0000 2015-08-28T14:28:04.114+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.761+0000 2015-08-28T14:28:04.113+0000 I INDEX    [conn276] validating index test.sort6.$c_1
[js_test:basicPlus] 2015-08-28T14:28:04.762+0000 2015-08-28T14:28:04.113+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.762+0000 2015-08-28T14:28:04.113+0000 I INDEX    [conn276] validating index test.sort6.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.762+0000 2015-08-28T14:28:04.112+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.762+0000 2015-08-28T14:28:04.112+0000 I COMMAND  [conn276] CMD: validate test.sort6
[js_test:basicPlus] 2015-08-28T14:28:04.763+0000 2015-08-28T14:28:04.111+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.763+0000 2015-08-28T14:28:04.111+0000 I INDEX    [conn276] validating index test.sort5.$y.b_1__id_-1
[js_test:basicPlus] 2015-08-28T14:28:04.763+0000 2015-08-28T14:28:04.110+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.763+0000 2015-08-28T14:28:04.110+0000 I INDEX    [conn276] validating index test.sort5.$y.b_1_y.a_-1
[js_test:basicPlus] 2015-08-28T14:28:04.764+0000 2015-08-28T14:28:04.110+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.764+0000 2015-08-28T14:28:04.109+0000 I INDEX    [conn276] validating index test.sort5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.764+0000 2015-08-28T14:28:04.109+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.764+0000 2015-08-28T14:28:04.108+0000 I COMMAND  [conn276] CMD: validate test.sort5
[js_test:basicPlus] 2015-08-28T14:28:04.765+0000 2015-08-28T14:28:04.108+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.765+0000 2015-08-28T14:28:04.107+0000 I INDEX    [conn276] validating index test.sort4.$name_1
[js_test:basicPlus] 2015-08-28T14:28:04.765+0000 2015-08-28T14:28:04.107+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.765+0000 2015-08-28T14:28:04.105+0000 I INDEX    [conn276] validating index test.sort4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.766+0000 2015-08-28T14:28:04.105+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.766+0000 2015-08-28T14:28:04.104+0000 I COMMAND  [conn276] CMD: validate test.sort4
[js_test:basicPlus] 2015-08-28T14:28:04.766+0000 2015-08-28T14:28:04.104+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.766+0000 2015-08-28T14:28:04.103+0000 I INDEX    [conn276] validating index test.sort3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.766+0000 2015-08-28T14:28:04.103+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.767+0000 2015-08-28T14:28:04.102+0000 I COMMAND  [conn276] CMD: validate test.sort3
[js_test:basicPlus] 2015-08-28T14:28:04.767+0000 2015-08-28T14:28:04.102+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.767+0000 2015-08-28T14:28:04.101+0000 I INDEX    [conn276] validating index test.sort2.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.767+0000 2015-08-28T14:28:04.101+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.768+0000 2015-08-28T14:28:04.101+0000 I INDEX    [conn276] validating index test.sort2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.768+0000 2015-08-28T14:28:04.100+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.768+0000 2015-08-28T14:28:04.100+0000 I COMMAND  [conn276] CMD: validate test.sort2
[js_test:basicPlus] 2015-08-28T14:28:04.768+0000 2015-08-28T14:28:04.099+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.769+0000 2015-08-28T14:28:04.099+0000 I INDEX    [conn276] validating index test.sort10.$x_-1
[js_test:basicPlus] 2015-08-28T14:28:04.769+0000 2015-08-28T14:28:04.099+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.769+0000 2015-08-28T14:28:04.097+0000 I INDEX    [conn276] validating index test.sort10.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.769+0000 2015-08-28T14:28:04.096+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.770+0000 2015-08-28T14:28:04.095+0000 I COMMAND  [conn276] CMD: validate test.sort10
[js_test:basicPlus] 2015-08-28T14:28:04.770+0000 2015-08-28T14:28:04.095+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.770+0000 2015-08-28T14:28:04.094+0000 I INDEX    [conn276] validating index test.sort1.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.770+0000 2015-08-28T14:28:04.094+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.771+0000 2015-08-28T14:28:04.093+0000 I INDEX    [conn276] validating index test.sort1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.771+0000 2015-08-28T14:28:04.093+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.771+0000 2015-08-28T14:28:04.092+0000 I COMMAND  [conn276] CMD: validate test.sort1
[js_test:basicPlus] 2015-08-28T14:28:04.771+0000 2015-08-28T14:28:04.092+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.771+0000 2015-08-28T14:28:04.090+0000 I INDEX    [conn276] validating index test.slice1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.772+0000 2015-08-28T14:28:04.090+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.772+0000 2015-08-28T14:28:04.086+0000 I COMMAND  [conn276] CMD: validate test.slice1
[js_test:basicPlus] 2015-08-28T14:28:04.772+0000 2015-08-28T14:28:04.086+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.772+0000 2015-08-28T14:28:04.085+0000 I INDEX    [conn276] validating index test.show_record_id.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.773+0000 2015-08-28T14:28:04.085+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.773+0000 2015-08-28T14:28:04.085+0000 I COMMAND  [conn276] CMD: validate test.show_record_id
[js_test:basicPlus] 2015-08-28T14:28:04.773+0000 2015-08-28T14:28:04.084+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.773+0000 2015-08-28T14:28:04.084+0000 I INDEX    [conn276] validating index test.shell_wc_a.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.774+0000 2015-08-28T14:28:04.083+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.774+0000 2015-08-28T14:28:04.083+0000 I COMMAND  [conn276] CMD: validate test.shell_wc_a
[js_test:basicPlus] 2015-08-28T14:28:04.774+0000 2015-08-28T14:28:04.082+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.774+0000 2015-08-28T14:28:04.082+0000 I INDEX    [conn276] validating index test.set6.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.775+0000 2015-08-28T14:28:04.079+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.775+0000 2015-08-28T14:28:04.079+0000 I COMMAND  [conn276] CMD: validate test.set6
[js_test:basicPlus] 2015-08-28T14:28:04.775+0000 2015-08-28T14:28:04.078+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.775+0000 2015-08-28T14:28:04.078+0000 I INDEX    [conn276] validating index test.set5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.776+0000 2015-08-28T14:28:04.077+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.776+0000 2015-08-28T14:28:04.077+0000 I COMMAND  [conn276] CMD: validate test.set5
[js_test:basicPlus] 2015-08-28T14:28:04.776+0000 2015-08-28T14:28:04.076+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.776+0000 2015-08-28T14:28:04.076+0000 I INDEX    [conn276] validating index test.set4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.776+0000 2015-08-28T14:28:04.075+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.777+0000 2015-08-28T14:28:04.074+0000 I COMMAND  [conn276] CMD: validate test.set4
[js_test:basicPlus] 2015-08-28T14:28:04.777+0000 2015-08-28T14:28:04.074+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.777+0000 2015-08-28T14:28:04.073+0000 I INDEX    [conn276] validating index test.set3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.777+0000 2015-08-28T14:28:04.070+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.778+0000 2015-08-28T14:28:04.070+0000 I COMMAND  [conn276] CMD: validate test.set3
[js_test:basicPlus] 2015-08-28T14:28:04.778+0000 2015-08-28T14:28:04.069+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.778+0000 2015-08-28T14:28:04.069+0000 I INDEX    [conn276] validating index test.set2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.778+0000 2015-08-28T14:28:04.068+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.779+0000 2015-08-28T14:28:04.068+0000 I COMMAND  [conn276] CMD: validate test.set2
[js_test:basicPlus] 2015-08-28T14:28:04.779+0000 2015-08-28T14:28:04.067+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.779+0000 2015-08-28T14:28:04.067+0000 I INDEX    [conn276] validating index test.set1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.779+0000 2015-08-28T14:28:04.067+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.780+0000 2015-08-28T14:28:04.066+0000 I COMMAND  [conn276] CMD: validate test.set1
[js_test:basicPlus] 2015-08-28T14:28:04.780+0000 2015-08-28T14:28:04.065+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.780+0000 2015-08-28T14:28:04.064+0000 I INDEX    [conn276] validating index test.server9547.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.780+0000 2015-08-28T14:28:04.064+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.781+0000 2015-08-28T14:28:04.062+0000 I INDEX    [conn276] validating index test.server9547.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.781+0000 2015-08-28T14:28:04.062+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.781+0000 2015-08-28T14:28:04.061+0000 I COMMAND  [conn276] CMD: validate test.server9547
[js_test:basicPlus] 2015-08-28T14:28:04.781+0000 2015-08-28T14:28:04.061+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.781+0000 2015-08-28T14:28:04.060+0000 I INDEX    [conn276] validating index test.server9385.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.782+0000 2015-08-28T14:28:04.058+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.782+0000 2015-08-28T14:28:04.057+0000 I COMMAND  [conn276] CMD: validate test.server9385
[js_test:basicPlus] 2015-08-28T14:28:04.782+0000 2015-08-28T14:28:04.056+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.782+0000 2015-08-28T14:28:04.056+0000 I INDEX    [conn276] validating index test.server848.$loc_2d
[js_test:basicPlus] 2015-08-28T14:28:04.783+0000 2015-08-28T14:28:04.055+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.783+0000 2015-08-28T14:28:04.055+0000 I INDEX    [conn276] validating index test.server848.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.783+0000 2015-08-28T14:28:04.055+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.783+0000 2015-08-28T14:28:04.054+0000 I COMMAND  [conn276] CMD: validate test.server848
[js_test:basicPlus] 2015-08-28T14:28:04.784+0000 2015-08-28T14:28:04.052+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.784+0000 2015-08-28T14:28:04.052+0000 I INDEX    [conn276] validating index test.server7756.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.784+0000 2015-08-28T14:28:04.051+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.784+0000 2015-08-28T14:28:04.051+0000 I COMMAND  [conn276] CMD: validate test.server7756
[js_test:basicPlus] 2015-08-28T14:28:04.785+0000 2015-08-28T14:28:04.050+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.785+0000 2015-08-28T14:28:04.050+0000 I INDEX    [conn276] validating index test.server5346.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.785+0000 2015-08-28T14:28:04.050+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.785+0000 2015-08-28T14:28:04.048+0000 I COMMAND  [conn276] CMD: validate test.server5346
[js_test:basicPlus] 2015-08-28T14:28:04.786+0000 2015-08-28T14:28:04.048+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.786+0000 2015-08-28T14:28:04.047+0000 I INDEX    [conn276] validating index test.server1470.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.786+0000 2015-08-28T14:28:04.046+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.786+0000 2015-08-28T14:28:04.045+0000 I COMMAND  [conn276] CMD: validate test.server1470
[js_test:basicPlus] 2015-08-28T14:28:04.787+0000 2015-08-28T14:28:04.044+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.787+0000 2015-08-28T14:28:04.044+0000 I INDEX    [conn276] validating index test.server1238.$loc_2d
[js_test:basicPlus] 2015-08-28T14:28:04.787+0000 2015-08-28T14:28:04.043+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.787+0000 2015-08-28T14:28:04.041+0000 I INDEX    [conn276] validating index test.server1238.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.788+0000 2015-08-28T14:28:04.041+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.788+0000 2015-08-28T14:28:04.040+0000 I COMMAND  [conn276] CMD: validate test.server1238
[js_test:basicPlus] 2015-08-28T14:28:04.788+0000 2015-08-28T14:28:04.040+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.788+0000 2015-08-28T14:28:04.039+0000 I INDEX    [conn276] validating index test.scan_capped_id.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.788+0000 2015-08-28T14:28:04.039+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.789+0000 2015-08-28T14:28:04.038+0000 I COMMAND  [conn276] CMD: validate test.scan_capped_id
[js_test:basicPlus] 2015-08-28T14:28:04.789+0000 2015-08-28T14:28:04.038+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.789+0000 2015-08-28T14:28:04.036+0000 I INDEX    [conn276] validating index test.rename_stayTemp_dest.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.789+0000 2015-08-28T14:28:04.036+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.790+0000 2015-08-28T14:28:04.035+0000 I COMMAND  [conn276] CMD: validate test.rename_stayTemp_dest
[js_test:basicPlus] 2015-08-28T14:28:04.790+0000 2015-08-28T14:28:04.035+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.790+0000 2015-08-28T14:28:04.034+0000 I INDEX    [conn276] validating index test.rename3a.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.790+0000 2015-08-28T14:28:04.033+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.791+0000 2015-08-28T14:28:04.033+0000 I COMMAND  [conn276] CMD: validate test.rename3a
[js_test:basicPlus] 2015-08-28T14:28:04.791+0000 2015-08-28T14:28:04.031+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.791+0000 2015-08-28T14:28:04.031+0000 I INDEX    [conn276] validating index test.rename2c.$name_1_date_1_time_1_renameCollection_1_mongodb_1_testing_1_data_1
[js_test:basicPlus] 2015-08-28T14:28:04.792+0000 2015-08-28T14:28:04.031+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.792+0000 2015-08-28T14:28:04.028+0000 I INDEX    [conn276] validating index test.rename2c.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.792+0000 2015-08-28T14:28:04.028+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.792+0000 2015-08-28T14:28:04.028+0000 I COMMAND  [conn276] CMD: validate test.rename2c
[js_test:basicPlus] 2015-08-28T14:28:04.792+0000 2015-08-28T14:28:04.027+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.793+0000 2015-08-28T14:28:04.027+0000 I INDEX    [conn276] validating index test.rename2b.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.793+0000 2015-08-28T14:28:04.026+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.793+0000 2015-08-28T14:28:04.026+0000 I COMMAND  [conn276] CMD: validate test.rename2b
[js_test:basicPlus] 2015-08-28T14:28:04.793+0000 2015-08-28T14:28:04.023+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.794+0000 2015-08-28T14:28:04.022+0000 I INDEX    [conn276] validating index test.removetest2.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.794+0000 2015-08-28T14:28:04.022+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.794+0000 2015-08-28T14:28:04.022+0000 I INDEX    [conn276] validating index test.removetest2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.794+0000 2015-08-28T14:28:04.021+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.795+0000 2015-08-28T14:28:04.021+0000 I COMMAND  [conn276] CMD: validate test.removetest2
[js_test:basicPlus] 2015-08-28T14:28:04.795+0000 2015-08-28T14:28:04.020+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.795+0000 2015-08-28T14:28:04.019+0000 I INDEX    [conn276] validating index test.removetest.$x_-1
[js_test:basicPlus] 2015-08-28T14:28:04.795+0000 2015-08-28T14:28:04.019+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.796+0000 2015-08-28T14:28:04.018+0000 I INDEX    [conn276] validating index test.removetest.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.796+0000 2015-08-28T14:28:04.018+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.796+0000 2015-08-28T14:28:04.017+0000 I INDEX    [conn276] validating index test.removetest.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.796+0000 2015-08-28T14:28:04.017+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.797+0000 2015-08-28T14:28:04.017+0000 I COMMAND  [conn276] CMD: validate test.removetest
[js_test:basicPlus] 2015-08-28T14:28:04.797+0000 2015-08-28T14:28:04.016+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.797+0000 2015-08-28T14:28:04.016+0000 I INDEX    [conn276] validating index test.remove_justone.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.797+0000 2015-08-28T14:28:04.015+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.798+0000 2015-08-28T14:28:04.015+0000 I COMMAND  [conn276] CMD: validate test.remove_justone
[js_test:basicPlus] 2015-08-28T14:28:04.798+0000 2015-08-28T14:28:04.014+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.798+0000 2015-08-28T14:28:04.014+0000 I INDEX    [conn276] validating index test.remove8.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.798+0000 2015-08-28T14:28:04.014+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.799+0000 2015-08-28T14:28:04.013+0000 I COMMAND  [conn276] CMD: validate test.remove8
[js_test:basicPlus] 2015-08-28T14:28:04.799+0000 2015-08-28T14:28:04.013+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.799+0000 2015-08-28T14:28:04.012+0000 I INDEX    [conn276] validating index test.remove7.$tags_1
[js_test:basicPlus] 2015-08-28T14:28:04.799+0000 2015-08-28T14:28:04.012+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.800+0000 2015-08-28T14:28:04.011+0000 I INDEX    [conn276] validating index test.remove7.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.800+0000 2015-08-28T14:28:04.011+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.800+0000 2015-08-28T14:28:04.011+0000 I COMMAND  [conn276] CMD: validate test.remove7
[js_test:basicPlus] 2015-08-28T14:28:04.800+0000 2015-08-28T14:28:04.010+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.800+0000 2015-08-28T14:28:04.010+0000 I INDEX    [conn276] validating index test.remove6.$tags_1
[js_test:basicPlus] 2015-08-28T14:28:04.801+0000 2015-08-28T14:28:04.009+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.801+0000 2015-08-28T14:28:04.008+0000 I INDEX    [conn276] validating index test.remove6.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.801+0000 2015-08-28T14:28:04.008+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.801+0000 2015-08-28T14:28:04.006+0000 I COMMAND  [conn276] CMD: validate test.remove6
[js_test:basicPlus] 2015-08-28T14:28:04.802+0000 2015-08-28T14:28:04.005+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.802+0000 2015-08-28T14:28:04.005+0000 I INDEX    [conn276] validating index test.remove4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.802+0000 2015-08-28T14:28:04.005+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.802+0000 2015-08-28T14:28:04.004+0000 I COMMAND  [conn276] CMD: validate test.remove4
[js_test:basicPlus] 2015-08-28T14:28:04.803+0000 2015-08-28T14:28:04.004+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.803+0000 2015-08-28T14:28:04.003+0000 I INDEX    [conn276] validating index test.remove3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.803+0000 2015-08-28T14:28:04.003+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.803+0000 2015-08-28T14:28:04.002+0000 I COMMAND  [conn276] CMD: validate test.remove3
[js_test:basicPlus] 2015-08-28T14:28:04.804+0000 2015-08-28T14:28:04.002+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.804+0000 2015-08-28T14:28:04.001+0000 I INDEX    [conn276] validating index test.regex_not_id.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.804+0000 2015-08-28T14:28:04.001+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.804+0000 2015-08-28T14:28:04.000+0000 I COMMAND  [conn276] CMD: validate test.regex_not_id
[js_test:basicPlus] 2015-08-28T14:28:04.805+0000 2015-08-28T14:28:04.000+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.805+0000 2015-08-28T14:28:03.999+0000 I INDEX    [conn276] validating index test.regex_matches_self.$r_1
[js_test:basicPlus] 2015-08-28T14:28:04.805+0000 2015-08-28T14:28:03.999+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.805+0000 2015-08-28T14:28:03.999+0000 I INDEX    [conn276] validating index test.regex_matches_self.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.806+0000 2015-08-28T14:28:03.998+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.806+0000 2015-08-28T14:28:03.998+0000 I COMMAND  [conn276] CMD: validate test.regex_matches_self
[js_test:basicPlus] 2015-08-28T14:28:04.806+0000 2015-08-28T14:28:03.997+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.806+0000 2015-08-28T14:28:03.996+0000 I INDEX    [conn276] validating index test.regex_limit.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.807+0000 2015-08-28T14:28:03.996+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.807+0000 2015-08-28T14:28:03.996+0000 I COMMAND  [conn276] CMD: validate test.regex_limit
[js_test:basicPlus] 2015-08-28T14:28:04.807+0000 2015-08-28T14:28:03.995+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.807+0000 2015-08-28T14:28:03.994+0000 I INDEX    [conn276] validating index test.regex_embed1.$a.x_1
[js_test:basicPlus] 2015-08-28T14:28:04.808+0000 2015-08-28T14:28:03.994+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.808+0000 2015-08-28T14:28:03.994+0000 I INDEX    [conn276] validating index test.regex_embed1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.808+0000 2015-08-28T14:28:03.993+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.808+0000 2015-08-28T14:28:03.993+0000 I COMMAND  [conn276] CMD: validate test.regex_embed1
[js_test:basicPlus] 2015-08-28T14:28:04.809+0000 2015-08-28T14:28:03.992+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.809+0000 2015-08-28T14:28:03.992+0000 I INDEX    [conn276] validating index test.regex9.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.809+0000 2015-08-28T14:28:03.991+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.809+0000 2015-08-28T14:28:03.991+0000 I COMMAND  [conn276] CMD: validate test.regex9
[js_test:basicPlus] 2015-08-28T14:28:04.809+0000 2015-08-28T14:28:03.990+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.810+0000 2015-08-28T14:28:03.990+0000 I INDEX    [conn276] validating index test.regex8.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.810+0000 2015-08-28T14:28:03.990+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.810+0000 2015-08-28T14:28:03.989+0000 I INDEX    [conn276] validating index test.regex8.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.810+0000 2015-08-28T14:28:03.989+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.811+0000 2015-08-28T14:28:03.988+0000 I COMMAND  [conn276] CMD: validate test.regex8
[js_test:basicPlus] 2015-08-28T14:28:04.811+0000 2015-08-28T14:28:03.987+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.811+0000 2015-08-28T14:28:03.986+0000 I INDEX    [conn276] validating index test.regex6.$name_1
[js_test:basicPlus] 2015-08-28T14:28:04.811+0000 2015-08-28T14:28:03.986+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.812+0000 2015-08-28T14:28:03.986+0000 I INDEX    [conn276] validating index test.regex6.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.812+0000 2015-08-28T14:28:03.985+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.812+0000 2015-08-28T14:28:03.985+0000 I COMMAND  [conn276] CMD: validate test.regex6
[js_test:basicPlus] 2015-08-28T14:28:04.812+0000 2015-08-28T14:28:03.984+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.813+0000 2015-08-28T14:28:03.984+0000 I INDEX    [conn276] validating index test.regex5.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.813+0000 2015-08-28T14:28:03.983+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.813+0000 2015-08-28T14:28:03.983+0000 I INDEX    [conn276] validating index test.regex5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.813+0000 2015-08-28T14:28:03.982+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.813+0000 2015-08-28T14:28:03.982+0000 I COMMAND  [conn276] CMD: validate test.regex5
[js_test:basicPlus] 2015-08-28T14:28:04.814+0000 2015-08-28T14:28:03.981+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.814+0000 2015-08-28T14:28:03.981+0000 I INDEX    [conn276] validating index test.regex4.$name_1
[js_test:basicPlus] 2015-08-28T14:28:04.814+0000 2015-08-28T14:28:03.981+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.814+0000 2015-08-28T14:28:03.980+0000 I INDEX    [conn276] validating index test.regex4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.815+0000 2015-08-28T14:28:03.980+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.815+0000 2015-08-28T14:28:03.979+0000 I COMMAND  [conn276] CMD: validate test.regex4
[js_test:basicPlus] 2015-08-28T14:28:04.815+0000 2015-08-28T14:28:03.979+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.815+0000 2015-08-28T14:28:03.978+0000 I INDEX    [conn276] validating index test.regex3.$name_1
[js_test:basicPlus] 2015-08-28T14:28:04.816+0000 2015-08-28T14:28:03.978+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.816+0000 2015-08-28T14:28:03.977+0000 I INDEX    [conn276] validating index test.regex3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.816+0000 2015-08-28T14:28:03.977+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.816+0000 2015-08-28T14:28:03.977+0000 I COMMAND  [conn276] CMD: validate test.regex3
[js_test:basicPlus] 2015-08-28T14:28:04.817+0000 2015-08-28T14:28:03.976+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.817+0000 2015-08-28T14:28:03.976+0000 I INDEX    [conn276] validating index test.regex2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.817+0000 2015-08-28T14:28:03.975+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.817+0000 2015-08-28T14:28:03.975+0000 I COMMAND  [conn276] CMD: validate test.regex2
[js_test:basicPlus] 2015-08-28T14:28:04.818+0000 2015-08-28T14:28:03.974+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.818+0000 2015-08-28T14:28:03.974+0000 I INDEX    [conn276] validating index test.regex0.$point_2d_words_1
[js_test:basicPlus] 2015-08-28T14:28:04.818+0000 2015-08-28T14:28:03.973+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.818+0000 2015-08-28T14:28:03.973+0000 I INDEX    [conn276] validating index test.regex0.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.819+0000 2015-08-28T14:28:03.972+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.819+0000 2015-08-28T14:28:03.972+0000 I COMMAND  [conn276] CMD: validate test.regex0
[js_test:basicPlus] 2015-08-28T14:28:04.819+0000 2015-08-28T14:28:03.971+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.819+0000 2015-08-28T14:28:03.971+0000 I INDEX    [conn276] validating index test.ref4b.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.819+0000 2015-08-28T14:28:03.971+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.820+0000 2015-08-28T14:28:03.970+0000 I COMMAND  [conn276] CMD: validate test.ref4b
[js_test:basicPlus] 2015-08-28T14:28:04.820+0000 2015-08-28T14:28:03.970+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.820+0000 2015-08-28T14:28:03.969+0000 I INDEX    [conn276] validating index test.ref4a.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.820+0000 2015-08-28T14:28:03.969+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.821+0000 2015-08-28T14:28:03.968+0000 I COMMAND  [conn276] CMD: validate test.ref4a
[js_test:basicPlus] 2015-08-28T14:28:04.821+0000 2015-08-28T14:28:03.968+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.821+0000 2015-08-28T14:28:03.967+0000 I INDEX    [conn276] validating index test.ref2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.821+0000 2015-08-28T14:28:03.967+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.822+0000 2015-08-28T14:28:03.966+0000 I COMMAND  [conn276] CMD: validate test.ref2
[js_test:basicPlus] 2015-08-28T14:28:04.822+0000 2015-08-28T14:28:03.966+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.822+0000 2015-08-28T14:28:03.965+0000 I INDEX    [conn276] validating index test.read_majority_mmap.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.822+0000 2015-08-28T14:28:03.965+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.823+0000 2015-08-28T14:28:03.965+0000 I COMMAND  [conn276] CMD: validate test.read_majority_mmap
[js_test:basicPlus] 2015-08-28T14:28:04.823+0000 2015-08-28T14:28:03.964+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.823+0000 2015-08-28T14:28:03.964+0000 I INDEX    [conn276] validating index test.query1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.823+0000 2015-08-28T14:28:03.963+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.824+0000 2015-08-28T14:28:03.963+0000 I COMMAND  [conn276] CMD: validate test.query1
[js_test:basicPlus] 2015-08-28T14:28:04.824+0000 2015-08-28T14:28:03.962+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.824+0000 2015-08-28T14:28:03.962+0000 I INDEX    [conn276] validating index test.push_sort.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.824+0000 2015-08-28T14:28:03.961+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.825+0000 2015-08-28T14:28:03.961+0000 I COMMAND  [conn276] CMD: validate test.push_sort
[js_test:basicPlus] 2015-08-28T14:28:04.825+0000 2015-08-28T14:28:03.960+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.825+0000 2015-08-28T14:28:03.960+0000 I INDEX    [conn276] validating index test.push.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.825+0000 2015-08-28T14:28:03.959+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.826+0000 2015-08-28T14:28:03.959+0000 I COMMAND  [conn276] CMD: validate test.push
[js_test:basicPlus] 2015-08-28T14:28:04.826+0000 2015-08-28T14:28:03.958+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.826+0000 2015-08-28T14:28:03.957+0000 I INDEX    [conn276] validating index test.pullall2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.826+0000 2015-08-28T14:28:03.957+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.827+0000 2015-08-28T14:28:03.956+0000 I COMMAND  [conn276] CMD: validate test.pullall2
[js_test:basicPlus] 2015-08-28T14:28:04.827+0000 2015-08-28T14:28:03.956+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.827+0000 2015-08-28T14:28:03.955+0000 I INDEX    [conn276] validating index test.pull_remove1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.827+0000 2015-08-28T14:28:03.955+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.828+0000 2015-08-28T14:28:03.954+0000 I COMMAND  [conn276] CMD: validate test.pull_remove1
[js_test:basicPlus] 2015-08-28T14:28:04.828+0000 2015-08-28T14:28:03.954+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.828+0000 2015-08-28T14:28:03.953+0000 I INDEX    [conn276] validating index test.pull_or.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.828+0000 2015-08-28T14:28:03.953+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.829+0000 2015-08-28T14:28:03.952+0000 I COMMAND  [conn276] CMD: validate test.pull_or
[js_test:basicPlus] 2015-08-28T14:28:04.829+0000 2015-08-28T14:28:03.952+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.829+0000 2015-08-28T14:28:03.951+0000 I INDEX    [conn276] validating index test.pull2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.829+0000 2015-08-28T14:28:03.951+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.830+0000 2015-08-28T14:28:03.951+0000 I COMMAND  [conn276] CMD: validate test.pull2
[js_test:basicPlus] 2015-08-28T14:28:04.830+0000 2015-08-28T14:28:03.950+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.830+0000 2015-08-28T14:28:03.950+0000 I INDEX    [conn276] validating index test.proj_key1.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.830+0000 2015-08-28T14:28:03.949+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.831+0000 2015-08-28T14:28:03.949+0000 I INDEX    [conn276] validating index test.proj_key1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.831+0000 2015-08-28T14:28:03.948+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.831+0000 2015-08-28T14:28:03.948+0000 I COMMAND  [conn276] CMD: validate test.proj_key1
[js_test:basicPlus] 2015-08-28T14:28:04.831+0000 2015-08-28T14:28:03.947+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.832+0000 2015-08-28T14:28:03.947+0000 I INDEX    [conn276] validating index test.polytest2.$loc_2d
[js_test:basicPlus] 2015-08-28T14:28:04.832+0000 2015-08-28T14:28:03.946+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.832+0000 2015-08-28T14:28:03.945+0000 I INDEX    [conn276] validating index test.polytest2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.832+0000 2015-08-28T14:28:03.944+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 3
[js_test:basicPlus] 2015-08-28T14:28:04.832+0000 2015-08-28T14:28:03.944+0000 I COMMAND  [conn276] CMD: validate test.polytest2
[js_test:basicPlus] 2015-08-28T14:28:04.833+0000 2015-08-28T14:28:03.943+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.833+0000 2015-08-28T14:28:03.943+0000 I INDEX    [conn276] validating index test.places.$loc_2d
[js_test:basicPlus] 2015-08-28T14:28:04.833+0000 2015-08-28T14:28:03.942+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.833+0000 2015-08-28T14:28:03.939+0000 I INDEX    [conn276] validating index test.places.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.834+0000 2015-08-28T14:28:03.939+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.834+0000 2015-08-28T14:28:03.937+0000 I COMMAND  [conn276] CMD: validate test.places
[js_test:basicPlus] 2015-08-28T14:28:04.834+0000 2015-08-28T14:28:03.937+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.834+0000 2015-08-28T14:28:03.936+0000 I INDEX    [conn276] validating index test.otherthings3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.835+0000 2015-08-28T14:28:03.936+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.835+0000 2015-08-28T14:28:03.936+0000 I COMMAND  [conn276] CMD: validate test.otherthings3
[js_test:basicPlus] 2015-08-28T14:28:04.835+0000 2015-08-28T14:28:03.935+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.835+0000 2015-08-28T14:28:03.935+0000 I INDEX    [conn276] validating index test.otherthings.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.836+0000 2015-08-28T14:28:03.934+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.836+0000 2015-08-28T14:28:03.934+0000 I COMMAND  [conn276] CMD: validate test.otherthings
[js_test:basicPlus] 2015-08-28T14:28:04.836+0000 2015-08-28T14:28:03.933+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.836+0000 2015-08-28T14:28:03.932+0000 I INDEX    [conn276] validating index test.objid5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.837+0000 2015-08-28T14:28:03.932+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.837+0000 2015-08-28T14:28:03.932+0000 I COMMAND  [conn276] CMD: validate test.objid5
[js_test:basicPlus] 2015-08-28T14:28:04.837+0000 2015-08-28T14:28:03.931+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.837+0000 2015-08-28T14:28:03.929+0000 I INDEX    [conn276] validating index test.objid3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.837+0000 2015-08-28T14:28:03.929+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.838+0000 2015-08-28T14:28:03.927+0000 I COMMAND  [conn276] CMD: validate test.objid3
[js_test:basicPlus] 2015-08-28T14:28:04.838+0000 2015-08-28T14:28:03.926+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.838+0000 2015-08-28T14:28:03.926+0000 I INDEX    [conn276] validating index test.objid2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.838+0000 2015-08-28T14:28:03.925+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.839+0000 2015-08-28T14:28:03.925+0000 I COMMAND  [conn276] CMD: validate test.objid2
[js_test:basicPlus] 2015-08-28T14:28:04.839+0000 2015-08-28T14:28:03.924+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.839+0000 2015-08-28T14:28:03.924+0000 I INDEX    [conn276] validating index test.objid1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.839+0000 2015-08-28T14:28:03.923+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.840+0000 2015-08-28T14:28:03.923+0000 I COMMAND  [conn276] CMD: validate test.objid1
[js_test:basicPlus] 2015-08-28T14:28:04.840+0000 2015-08-28T14:28:03.921+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 3
[js_test:basicPlus] 2015-08-28T14:28:04.840+0000 2015-08-28T14:28:03.921+0000 I INDEX    [conn276] validating index test.objNestTest.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.840+0000 2015-08-28T14:28:03.920+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.841+0000 2015-08-28T14:28:03.920+0000 I INDEX    [conn276] validating index test.objNestTest.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.841+0000 2015-08-28T14:28:03.919+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 4
[js_test:basicPlus] 2015-08-28T14:28:04.841+0000 2015-08-28T14:28:03.919+0000 I COMMAND  [conn276] CMD: validate test.objNestTest
[js_test:basicPlus] 2015-08-28T14:28:04.841+0000 2015-08-28T14:28:03.918+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.842+0000 2015-08-28T14:28:03.918+0000 I INDEX    [conn276] validating index test.numberint.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.842+0000 2015-08-28T14:28:03.917+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.842+0000 2015-08-28T14:28:03.917+0000 I COMMAND  [conn276] CMD: validate test.numberint
[js_test:basicPlus] 2015-08-28T14:28:04.842+0000 2015-08-28T14:28:03.916+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.842+0000 2015-08-28T14:28:03.916+0000 I INDEX    [conn276] validating index test.null2.$a.b_1
[js_test:basicPlus] 2015-08-28T14:28:04.843+0000 2015-08-28T14:28:03.915+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.843+0000 2015-08-28T14:28:03.915+0000 I INDEX    [conn276] validating index test.null2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.843+0000 2015-08-28T14:28:03.915+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.843+0000 2015-08-28T14:28:03.914+0000 I COMMAND  [conn276] CMD: validate test.null2
[js_test:basicPlus] 2015-08-28T14:28:04.844+0000 2015-08-28T14:28:03.914+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 0
[js_test:basicPlus] 2015-08-28T14:28:04.844+0000 2015-08-28T14:28:03.913+0000 I INDEX    [conn276] validating index test.null1.$y_1
[js_test:basicPlus] 2015-08-28T14:28:04.844+0000 2015-08-28T14:28:03.913+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.844+0000 2015-08-28T14:28:03.912+0000 I INDEX    [conn276] validating index test.null1.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.845+0000 2015-08-28T14:28:03.912+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.845+0000 2015-08-28T14:28:03.911+0000 I INDEX    [conn276] validating index test.null1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.845+0000 2015-08-28T14:28:03.911+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.845+0000 2015-08-28T14:28:03.911+0000 I COMMAND  [conn276] CMD: validate test.null1
[js_test:basicPlus] 2015-08-28T14:28:04.846+0000 2015-08-28T14:28:03.910+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.846+0000 2015-08-28T14:28:03.910+0000 I INDEX    [conn276] validating index test.not1.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.846+0000 2015-08-28T14:28:03.909+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.846+0000 2015-08-28T14:28:03.909+0000 I INDEX    [conn276] validating index test.not1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.847+0000 2015-08-28T14:28:03.909+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.847+0000 2015-08-28T14:28:03.908+0000 I COMMAND  [conn276] CMD: validate test.not1
[js_test:basicPlus] 2015-08-28T14:28:04.847+0000 2015-08-28T14:28:03.908+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.847+0000 2015-08-28T14:28:03.907+0000 I COMMAND  [conn276] CMD: validate test.no_id
[js_test:basicPlus] 2015-08-28T14:28:04.847+0000 2015-08-28T14:28:03.907+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.848+0000 2015-08-28T14:28:03.906+0000 I INDEX    [conn276] validating index test.ne1.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.848+0000 2015-08-28T14:28:03.906+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.848+0000 2015-08-28T14:28:03.905+0000 I INDEX    [conn276] validating index test.ne1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.848+0000 2015-08-28T14:28:03.905+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.849+0000 2015-08-28T14:28:03.904+0000 I COMMAND  [conn276] CMD: validate test.ne1
[js_test:basicPlus] 2015-08-28T14:28:04.849+0000 2015-08-28T14:28:03.904+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.849+0000 2015-08-28T14:28:03.903+0000 I INDEX    [conn276] validating index test.mycoll.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.849+0000 2015-08-28T14:28:03.903+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.850+0000 2015-08-28T14:28:03.903+0000 I COMMAND  [conn276] CMD: validate test.mycoll
[js_test:basicPlus] 2015-08-28T14:28:04.850+0000 2015-08-28T14:28:03.902+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.850+0000 2015-08-28T14:28:03.901+0000 I INDEX    [conn276] validating index test.multinest.$data.loc_2d_zip_1
[js_test:basicPlus] 2015-08-28T14:28:04.850+0000 2015-08-28T14:28:03.901+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.851+0000 2015-08-28T14:28:03.901+0000 I INDEX    [conn276] validating index test.multinest.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.851+0000 2015-08-28T14:28:03.900+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.851+0000 2015-08-28T14:28:03.900+0000 I COMMAND  [conn276] CMD: validate test.multinest
[js_test:basicPlus] 2015-08-28T14:28:04.851+0000 2015-08-28T14:28:03.899+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.851+0000 2015-08-28T14:28:03.899+0000 I INDEX    [conn276] validating index test.multidim.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.852+0000 2015-08-28T14:28:03.898+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.852+0000 2015-08-28T14:28:03.897+0000 I COMMAND  [conn276] CMD: validate test.multidim
[js_test:basicPlus] 2015-08-28T14:28:04.852+0000 2015-08-28T14:28:03.897+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.852+0000 2015-08-28T14:28:03.896+0000 I INDEX    [conn276] validating index test.multi2.$x_1_a_1
[js_test:basicPlus] 2015-08-28T14:28:04.853+0000 2015-08-28T14:28:03.896+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.853+0000 2015-08-28T14:28:03.895+0000 I INDEX    [conn276] validating index test.multi2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.853+0000 2015-08-28T14:28:03.895+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.853+0000 2015-08-28T14:28:03.895+0000 I COMMAND  [conn276] CMD: validate test.multi2
[js_test:basicPlus] 2015-08-28T14:28:04.854+0000 2015-08-28T14:28:03.894+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.854+0000 2015-08-28T14:28:03.893+0000 I INDEX    [conn276] validating index test.mr_undef_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.854+0000 2015-08-28T14:28:03.893+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.854+0000 2015-08-28T14:28:03.893+0000 I COMMAND  [conn276] CMD: validate test.mr_undef_out
[js_test:basicPlus] 2015-08-28T14:28:04.855+0000 2015-08-28T14:28:03.892+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.855+0000 2015-08-28T14:28:03.892+0000 I INDEX    [conn276] validating index test.mr_undef.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.855+0000 2015-08-28T14:28:03.891+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.855+0000 2015-08-28T14:28:03.891+0000 I COMMAND  [conn276] CMD: validate test.mr_undef
[js_test:basicPlus] 2015-08-28T14:28:04.856+0000 2015-08-28T14:28:03.890+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.856+0000 2015-08-28T14:28:03.890+0000 I INDEX    [conn276] validating index test.mr_stored.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.856+0000 2015-08-28T14:28:03.890+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.856+0000 2015-08-28T14:28:03.889+0000 I COMMAND  [conn276] CMD: validate test.mr_stored
[js_test:basicPlus] 2015-08-28T14:28:04.856+0000 2015-08-28T14:28:03.888+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.857+0000 2015-08-28T14:28:03.888+0000 I INDEX    [conn276] validating index test.mr_sort.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.857+0000 2015-08-28T14:28:03.888+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.857+0000 2015-08-28T14:28:03.887+0000 I INDEX    [conn276] validating index test.mr_sort.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.857+0000 2015-08-28T14:28:03.887+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.858+0000 2015-08-28T14:28:03.886+0000 I COMMAND  [conn276] CMD: validate test.mr_sort
[js_test:basicPlus] 2015-08-28T14:28:04.858+0000 2015-08-28T14:28:03.886+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.858+0000 2015-08-28T14:28:03.885+0000 I INDEX    [conn276] validating index test.mr_replace.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.858+0000 2015-08-28T14:28:03.885+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.859+0000 2015-08-28T14:28:03.885+0000 I COMMAND  [conn276] CMD: validate test.mr_replace
[js_test:basicPlus] 2015-08-28T14:28:04.859+0000 2015-08-28T14:28:03.884+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.859+0000 2015-08-28T14:28:03.883+0000 I INDEX    [conn276] validating index test.mr_outreduce_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.859+0000 2015-08-28T14:28:03.883+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.860+0000 2015-08-28T14:28:03.883+0000 I COMMAND  [conn276] CMD: validate test.mr_outreduce_out
[js_test:basicPlus] 2015-08-28T14:28:04.860+0000 2015-08-28T14:28:03.882+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.860+0000 2015-08-28T14:28:03.881+0000 I INDEX    [conn276] validating index test.mr_outreduce2_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.860+0000 2015-08-28T14:28:03.880+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.861+0000 2015-08-28T14:28:03.880+0000 I COMMAND  [conn276] CMD: validate test.mr_outreduce2_out
[js_test:basicPlus] 2015-08-28T14:28:04.861+0000 2015-08-28T14:28:03.879+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.861+0000 2015-08-28T14:28:03.879+0000 I INDEX    [conn276] validating index test.mr_outreduce2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.861+0000 2015-08-28T14:28:03.879+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.862+0000 2015-08-28T14:28:03.878+0000 I COMMAND  [conn276] CMD: validate test.mr_outreduce2
[js_test:basicPlus] 2015-08-28T14:28:04.862+0000 2015-08-28T14:28:03.878+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.862+0000 2015-08-28T14:28:03.877+0000 I INDEX    [conn276] validating index test.mr_outreduce.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.862+0000 2015-08-28T14:28:03.877+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.862+0000 2015-08-28T14:28:03.876+0000 I COMMAND  [conn276] CMD: validate test.mr_outreduce
[js_test:basicPlus] 2015-08-28T14:28:04.863+0000 2015-08-28T14:28:03.876+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.863+0000 2015-08-28T14:28:03.875+0000 I INDEX    [conn276] validating index test.mr_merge_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.863+0000 2015-08-28T14:28:03.875+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.863+0000 2015-08-28T14:28:03.874+0000 I COMMAND  [conn276] CMD: validate test.mr_merge_out
[js_test:basicPlus] 2015-08-28T14:28:04.864+0000 2015-08-28T14:28:03.874+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.864+0000 2015-08-28T14:28:03.873+0000 I INDEX    [conn276] validating index test.mr_merge2_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.864+0000 2015-08-28T14:28:03.873+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.864+0000 2015-08-28T14:28:03.872+0000 I COMMAND  [conn276] CMD: validate test.mr_merge2_out
[js_test:basicPlus] 2015-08-28T14:28:04.865+0000 2015-08-28T14:28:03.872+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.865+0000 2015-08-28T14:28:03.871+0000 I INDEX    [conn276] validating index test.mr_merge2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.865+0000 2015-08-28T14:28:03.871+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.866+0000 2015-08-28T14:28:03.871+0000 I COMMAND  [conn276] CMD: validate test.mr_merge2
[js_test:basicPlus] 2015-08-28T14:28:04.866+0000 2015-08-28T14:28:03.870+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.866+0000 2015-08-28T14:28:03.869+0000 I INDEX    [conn276] validating index test.mr_merge.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.866+0000 2015-08-28T14:28:03.869+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.867+0000 2015-08-28T14:28:03.869+0000 I COMMAND  [conn276] CMD: validate test.mr_merge
[js_test:basicPlus] 2015-08-28T14:28:04.867+0000 2015-08-28T14:28:03.868+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.867+0000 2015-08-28T14:28:03.867+0000 I INDEX    [conn276] validating index test.mr_index3.$name_1_tags_1
[js_test:basicPlus] 2015-08-28T14:28:04.867+0000 2015-08-28T14:28:03.867+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.868+0000 2015-08-28T14:28:03.866+0000 I INDEX    [conn276] validating index test.mr_index3.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.868+0000 2015-08-28T14:28:03.866+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.868+0000 2015-08-28T14:28:03.866+0000 I COMMAND  [conn276] CMD: validate test.mr_index3
[js_test:basicPlus] 2015-08-28T14:28:04.869+0000 2015-08-28T14:28:03.865+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.869+0000 2015-08-28T14:28:03.865+0000 I INDEX    [conn276] validating index test.mr_index2.$arr_1
[js_test:basicPlus] 2015-08-28T14:28:04.869+0000 2015-08-28T14:28:03.864+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.869+0000 2015-08-28T14:28:03.864+0000 I INDEX    [conn276] validating index test.mr_index2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.870+0000 2015-08-28T14:28:03.863+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.870+0000 2015-08-28T14:28:03.863+0000 I COMMAND  [conn276] CMD: validate test.mr_index2
[js_test:basicPlus] 2015-08-28T14:28:04.870+0000 2015-08-28T14:28:03.862+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.871+0000 2015-08-28T14:28:03.862+0000 I INDEX    [conn276] validating index test.mr_index.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.871+0000 2015-08-28T14:28:03.862+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.871+0000 2015-08-28T14:28:03.861+0000 I COMMAND  [conn276] CMD: validate test.mr_index
[js_test:basicPlus] 2015-08-28T14:28:04.871+0000 2015-08-28T14:28:03.860+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.872+0000 2015-08-28T14:28:03.860+0000 I INDEX    [conn276] validating index test.mr_errorhandling.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.872+0000 2015-08-28T14:28:03.860+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.872+0000 2015-08-28T14:28:03.859+0000 I COMMAND  [conn276] CMD: validate test.mr_errorhandling
[js_test:basicPlus] 2015-08-28T14:28:04.872+0000 2015-08-28T14:28:03.859+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.873+0000 2015-08-28T14:28:03.858+0000 I INDEX    [conn276] validating index test.mr_comments_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.873+0000 2015-08-28T14:28:03.858+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.873+0000 2015-08-28T14:28:03.857+0000 I COMMAND  [conn276] CMD: validate test.mr_comments_out
[js_test:basicPlus] 2015-08-28T14:28:04.874+0000 2015-08-28T14:28:03.856+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.874+0000 2015-08-28T14:28:03.855+0000 I INDEX    [conn276] validating index test.mr_comments.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.874+0000 2015-08-28T14:28:03.855+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.874+0000 2015-08-28T14:28:03.855+0000 I COMMAND  [conn276] CMD: validate test.mr_comments
[js_test:basicPlus] 2015-08-28T14:28:04.875+0000 2015-08-28T14:28:03.854+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.875+0000 2015-08-28T14:28:03.854+0000 I INDEX    [conn276] validating index test.mr_bigobject_out.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.875+0000 2015-08-28T14:28:03.853+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.876+0000 2015-08-28T14:28:03.853+0000 I COMMAND  [conn276] CMD: validate test.mr_bigobject_out
[js_test:basicPlus] 2015-08-28T14:28:04.876+0000 2015-08-28T14:28:03.852+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.876+0000 2015-08-28T14:28:03.852+0000 I INDEX    [conn276] validating index test.mrMutableReceiver.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.877+0000 2015-08-28T14:28:03.851+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.877+0000 2015-08-28T14:28:03.851+0000 I COMMAND  [conn276] CMD: validate test.mrMutableReceiver
[js_test:basicPlus] 2015-08-28T14:28:04.877+0000 2015-08-28T14:28:03.850+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.877+0000 2015-08-28T14:28:03.850+0000 I INDEX    [conn276] validating index test.mr5.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.878+0000 2015-08-28T14:28:03.850+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.878+0000 2015-08-28T14:28:03.849+0000 I COMMAND  [conn276] CMD: validate test.mr5
[js_test:basicPlus] 2015-08-28T14:28:04.878+0000 2015-08-28T14:28:03.849+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.878+0000 2015-08-28T14:28:03.848+0000 I INDEX    [conn276] validating index test.mr4.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.879+0000 2015-08-28T14:28:03.848+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.879+0000 2015-08-28T14:28:03.847+0000 I COMMAND  [conn276] CMD: validate test.mr4
[js_test:basicPlus] 2015-08-28T14:28:04.879+0000 2015-08-28T14:28:03.847+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.880+0000 2015-08-28T14:28:03.846+0000 I INDEX    [conn276] validating index test.mr2.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.880+0000 2015-08-28T14:28:03.846+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.880+0000 2015-08-28T14:28:03.845+0000 I COMMAND  [conn276] CMD: validate test.mr2
[js_test:basicPlus] 2015-08-28T14:28:04.880+0000 2015-08-28T14:28:03.841+0000 W STORAGE  [conn276] verify() returned EBUSY. Not treating as invalid.
[js_test:basicPlus] 2015-08-28T14:28:04.881+0000 2015-08-28T14:28:03.838+0000 I INDEX    [conn276] validating index test.mr1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.881+0000 2015-08-28T14:28:03.836+0000 W STORAGE  [conn276] verify() returned EBUSY. Not treating as invalid.
[js_test:basicPlus] 2015-08-28T14:28:04.881+0000 2015-08-28T14:28:03.832+0000 I COMMAND  [conn276] CMD: validate test.mr1
[js_test:basicPlus] 2015-08-28T14:28:04.882+0000 2015-08-28T14:28:03.831+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.882+0000 2015-08-28T14:28:03.827+0000 I INDEX    [conn276] validating index test.mod1.$a_1
[js_test:basicPlus] 2015-08-28T14:28:04.882+0000 2015-08-28T14:28:03.827+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.882+0000 2015-08-28T14:28:03.826+0000 I INDEX    [conn276] validating index test.mod1.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.883+0000 2015-08-28T14:28:03.826+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.883+0000 2015-08-28T14:28:03.826+0000 I COMMAND  [conn276] CMD: validate test.mod1
[js_test:basicPlus] 2015-08-28T14:28:04.883+0000 2015-08-28T14:28:03.825+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.883+0000 2015-08-28T14:28:03.825+0000 I INDEX    [conn276] validating index test.minmaxtest.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.884+0000 2015-08-28T14:28:03.824+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.884+0000 2015-08-28T14:28:03.824+0000 I COMMAND  [conn276] CMD: validate test.minmaxtest
[js_test:basicPlus] 2015-08-28T14:28:04.884+0000 2015-08-28T14:28:03.823+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.885+0000 2015-08-28T14:28:03.823+0000 I INDEX    [conn276] validating index test.minmax_edge.$a_1_b_-1_c_1
[js_test:basicPlus] 2015-08-28T14:28:04.885+0000 2015-08-28T14:28:03.822+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.885+0000 2015-08-28T14:28:03.822+0000 I INDEX    [conn276] validating index test.minmax_edge.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.885+0000 2015-08-28T14:28:03.821+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.886+0000 2015-08-28T14:28:03.820+0000 I COMMAND  [conn276] CMD: validate test.minmax_edge
[js_test:basicPlus] 2015-08-28T14:28:04.886+0000 2015-08-28T14:28:03.820+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.886+0000 2015-08-28T14:28:03.818+0000 I INDEX    [conn276] validating index test.maxscan.$x_1
[js_test:basicPlus] 2015-08-28T14:28:04.887+0000 2015-08-28T14:28:03.818+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.887+0000 2015-08-28T14:28:03.817+0000 I INDEX    [conn276] validating index test.maxscan.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.887+0000 2015-08-28T14:28:03.817+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.887+0000 2015-08-28T14:28:03.816+0000 I COMMAND  [conn276] CMD: validate test.maxscan
[js_test:basicPlus] 2015-08-28T14:28:04.888+0000 2015-08-28T14:28:03.810+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.888+0000 2015-08-28T14:28:03.809+0000 I INDEX    [conn276] validating index test.max_doc_size.$_id_
[js_test:basicPlus] 2015-08-28T14:28:04.888+0000 2015-08-28T14:28:03.809+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.889+0000 2015-08-28T14:28:03.808+0000 I COMMAND  [conn276] CMD: validate test.max_doc_size
[js_test:basicPlus] 2015-08-28T14:28:04.889+0000 2015-08-28T14:28:03.808+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.889+0000 2015-08-28T14:28:03.807+0000 I INDEX    [conn276] validating index test.many2.$y_1
[js_test:basicPlus] 2015-08-28T14:28:04.889+0000 2015-08-28T14:28:03.807+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.890+0000 2015-08-28T14:28:03.806+0000 I INDEX    [conn276] validating index test.many2.$63_1
[js_test:basicPlus] 2015-08-28T14:28:04.890+0000 2015-08-28T14:28:03.806+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.890+0000 2015-08-28T14:28:03.806+0000 I INDEX    [conn276] validating index test.many2.$62_1
[js_test:basicPlus] 2015-08-28T14:28:04.891+0000 2015-08-28T14:28:03.805+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.891+0000 2015-08-28T14:28:03.805+0000 I INDEX    [conn276] validating index test.many2.$61_1
[js_test:basicPlus] 2015-08-28T14:28:04.891+0000 2015-08-28T14:28:03.804+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.891+0000 2015-08-28T14:28:03.804+0000 I INDEX    [conn276] validating index test.many2.$60_1
[js_test:basicPlus] 2015-08-28T14:28:04.892+0000 2015-08-28T14:28:03.804+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.892+0000 2015-08-28T14:28:03.803+0000 I INDEX    [conn276] validating index test.many2.$59_1
[js_test:basicPlus] 2015-08-28T14:28:04.892+0000 2015-08-28T14:28:03.803+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.892+0000 2015-08-28T14:28:03.802+0000 I INDEX    [conn276] validating index test.many2.$58_1
[js_test:basicPlus] 2015-08-28T14:28:04.893+0000 2015-08-28T14:28:03.802+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.893+0000 2015-08-28T14:28:03.801+0000 I INDEX    [conn276] validating index test.many2.$57_1
[js_test:basicPlus] 2015-08-28T14:28:04.893+0000 2015-08-28T14:28:03.801+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.894+0000 2015-08-28T14:28:03.801+0000 I INDEX    [conn276] validating index test.many2.$56_1
[js_test:basicPlus] 2015-08-28T14:28:04.894+0000 2015-08-28T14:28:03.800+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.894+0000 2015-08-28T14:28:03.799+0000 I INDEX    [conn276] validating index test.many2.$55_1
[js_test:basicPlus] 2015-08-28T14:28:04.894+0000 2015-08-28T14:28:03.797+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.895+0000 2015-08-28T14:28:03.796+0000 I INDEX    [conn276] validating index test.many2.$54_1
[js_test:basicPlus] 2015-08-28T14:28:04.895+0000 2015-08-28T14:28:03.796+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.895+0000 2015-08-28T14:28:03.796+0000 I INDEX    [conn276] validating index test.many2.$53_1
[js_test:basicPlus] 2015-08-28T14:28:04.896+0000 2015-08-28T14:28:03.795+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.896+0000 2015-08-28T14:28:03.795+0000 I INDEX    [conn276] validating index test.many2.$52_1
[js_test:basicPlus] 2015-08-28T14:28:04.896+0000 2015-08-28T14:28:03.794+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.896+0000 2015-08-28T14:28:03.794+0000 I INDEX    [conn276] validating index test.many2.$51_1
[js_test:basicPlus] 2015-08-28T14:28:04.897+0000 2015-08-28T14:28:03.793+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.897+0000 2015-08-28T14:28:03.792+0000 I INDEX    [conn276] validating index test.many2.$50_1
[js_test:basicPlus] 2015-08-28T14:28:04.897+0000 2015-08-28T14:28:03.792+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.897+0000 2015-08-28T14:28:03.790+0000 I INDEX    [conn276] validating index test.many2.$49_1
[js_test:basicPlus] 2015-08-28T14:28:04.898+0000 2015-08-28T14:28:03.790+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.898+0000 2015-08-28T14:28:03.789+0000 I INDEX    [conn276] validating index test.many2.$48_1
[js_test:basicPlus] 2015-08-28T14:28:04.898+0000 2015-08-28T14:28:03.789+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.899+0000 2015-08-28T14:28:03.789+0000 I INDEX    [conn276] validating index test.many2.$47_1
[js_test:basicPlus] 2015-08-28T14:28:04.899+0000 2015-08-28T14:28:03.788+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.899+0000 2015-08-28T14:28:03.787+0000 I INDEX    [conn276] validating index test.many2.$46_1
[js_test:basicPlus] 2015-08-28T14:28:04.899+0000 2015-08-28T14:28:03.787+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.900+0000 2015-08-28T14:28:03.785+0000 I INDEX    [conn276] validating index test.many2.$45_1
[js_test:basicPlus] 2015-08-28T14:28:04.900+0000 2015-08-28T14:28:03.785+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.900+0000 2015-08-28T14:28:03.784+0000 I INDEX    [conn276] validating index test.many2.$44_1
[js_test:basicPlus] 2015-08-28T14:28:04.901+0000 2015-08-28T14:28:03.784+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.901+0000 2015-08-28T14:28:03.783+0000 I INDEX    [conn276] validating index test.many2.$43_1
[js_test:basicPlus] 2015-08-28T14:28:04.901+0000 2015-08-28T14:28:03.783+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.901+0000 2015-08-28T14:28:03.782+0000 I INDEX    [conn276] validating index test.many2.$42_1
[js_test:basicPlus] 2015-08-28T14:28:04.902+0000 2015-08-28T14:28:03.778+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.902+0000 2015-08-28T14:28:03.777+0000 I INDEX    [conn276] validating index test.many2.$41_1
[js_test:basicPlus] 2015-08-28T14:28:04.902+0000 2015-08-28T14:28:03.777+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.902+0000 2015-08-28T14:28:03.777+0000 I INDEX    [conn276] validating index test.many2.$40_1
[js_test:basicPlus] 2015-08-28T14:28:04.903+0000 2015-08-28T14:28:03.776+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.903+0000 2015-08-28T14:28:03.776+0000 I INDEX    [conn276] validating index test.many2.$39_1
[js_test:basicPlus] 2015-08-28T14:28:04.903+0000 2015-08-28T14:28:03.775+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.904+0000 2015-08-28T14:28:03.775+0000 I INDEX    [conn276] validating index test.many2.$38_1
[js_test:basicPlus] 2015-08-28T14:28:04.904+0000 2015-08-28T14:28:03.775+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.904+0000 2015-08-28T14:28:03.774+0000 I INDEX    [conn276] validating index test.many2.$37_1
[js_test:basicPlus] 2015-08-28T14:28:04.904+0000 2015-08-28T14:28:03.774+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.905+0000 2015-08-28T14:28:03.773+0000 I INDEX    [conn276] validating index test.many2.$36_1
[js_test:basicPlus] 2015-08-28T14:28:04.905+0000 2015-08-28T14:28:03.772+0000 I STORAGE  [conn276] WiredTiger progress WT_SESSION.verify 2
[js_test:basicPlus] 2015-08-28T14:28:04.905+0000 2015-08-28T14:28:03.772+0000 I INDEX    [conn276] validating index test.many2.$35_1

Participants:
Linked BF Score: 0

 Description   

https://logkeeper.mongodb.org/build/55e05b7190413011a2a633c0/test/55e06faabe07c47abfa7cea0

The above jstest failed because the global ramlog overflowed with WiredTiger progress messages. Such messages are being logged a rate of one or two per ms, which can adversely affect performance (since we synchronously flush the log after each entry is written).



 Comments   
Comment by Eric Milkie [ 11/Sep/15 ]

Never mind, it's in the linked WT ticket.

Comment by Eric Milkie [ 11/Sep/15 ]

Thanks alexander.gorrod! Can you mention what technique was used to solve it?

Comment by Alexander Gorrod [ 04/Sep/15 ]

This is in the WiredTiger develop branch. It will be available in MongoDB master next time we merge updates from WiredTiger

Comment by Alexander Gorrod [ 01/Sep/15 ]

I didn't get to this today, I'll take a look at the option of tweaking the MongoDB event handler tomorrow.

Comment by Michael Cahill (Inactive) [ 31/Aug/15 ]

agorrod, can you please take a look? Maybe we could either (1) add a configuration for verify progress frequency, or (2) add something in MongoDB's event handler implementation to skip output of progress updates. My starting point would be to limit progress updates to 1 per second by default...

Comment by Eric Milkie [ 28/Aug/15 ]

The progress metering for "verify" appears to report every 10 hits, but also at the first hit. Every 10 might be too often, and logging at the first hit means we always see progress for every verified table, even if that table has almost no entries.

Generated at Thu Feb 08 03:53:25 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.