Skip to content

Instantly share code, notes, and snippets.

@KrzysFR

KrzysFR/Transaction Logs

Created April 27, 2018 15:00
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save KrzysFR/7bdc3e3c598955dcd2c564f7c5b3924e to your computer and use it in GitHub Desktop.
Save KrzysFR/7bdc3e3c598955dcd2c564f7c5b3924e to your computer and use it in GitHub Desktop.
Download ZIP
Raw
Transaction Logs
=== FoundationDB.Layers.Directories.DirectoryFacts.Test_Concurrent_Directory_Creation_With_Custom_Prefix() === 16:56:30.6413691
Transaction #113 (read/write, 20 operations, '#' = 0.5 ms, started 14:56:30.6132900Z, ended 14:56:30.6413691Z)
┌ oper. ┬─────────────────────────────────────────────────────────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 rv°│ ######X │ T+ 5.825 ~ 8.473 ( 2,648 µs) │ │ GetReadVersion => 62110623213066
│ 1 G │ ___________________:. │ T+ 13.654 ~ 13.755 ( 101 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 2 // │ ____________________` │ T+ 13.760 │ - - │ // Looking for child Second under node T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00>...
│:2 G │ ____________________&$ │ T+ 13.763 ~ 14.443 ( 680 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Second<00> => not_found
│ 3 G │ _____________________. │ T+ 14.448 ~ 14.470 ( 22 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 4 R │ _____________________.; │ T+ 14.474 ~ 14.614 ( 139 µs) │ 44 30 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><FF>}, limit(1), reverse, Exact => 1 result(s), has_more
│ 5 a │ ______________________` │ T+ 14.618 ~ 14.622 ( 4 µs) │ 30 │ Atomic_Add T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><14>, <01 00 00 00 00 00 00 00>
│ 6 G │ ______________________: │ T+ 14.623 ~ 14.706 ( 83 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><13> => not_found
│ 7 s │ ______________________` │ T+ 14.707 ~ 14.710 ( 3 µs) │ 24 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><13> = ''
│ 8 // │ ______________________` │ T+ 14.714 │ - - │ // Allocated prefix 19 from window [0..63] (2 used)
│:8 // │ ______________________` │ T+ 14.717 │ - - │ // Ensure that there is no data already present under prefix T<02>DL<00><15><13>
│:8 R │ ______________________; │ T+ 14.720 ~ 14.825 ( 105 µs) │ 14 0 │ GetRange fGE{T<02>DL<00><15><13>} <= k < fGE{T<02>DL<00><15><14>}, limit(1), Exact => 0 result(s)
│ 9 // │ ______________________` │ T+ 14.828 │ - - │ // Ensure that the prefix T<02>DL<00><15><13> has not already been allocated
│:9 R │ ______________________:` │ T+ 14.831 ~ 14.921 ( 90 µs) │ 24 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><13><00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 10 R │ _______________________; │ T+ 14.927 ~ 15.033 ( 105 µs) │ 32 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><13><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><14><00>}, limit(1), Exact => 0 result(s)
│ 11 // │ _______________________` │ T+ 15.038 │ - - │ // Registering the new prefix T<02>DL<00><15><13> into the folder sub-tree
│:11 s │ _______________________` │ T+ 15.040 ~ 15.043 ( 3 µs) │ 31 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Second<00> = T<02>DL<00><15><13>
│ 12 s │ _______________________` │ T+ 15.044 ~ 15.045 ( 1 µs) │ 23 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15><13><00><01>layer<00> = ''
│ 13 s │ _______________________` │ T+ 15.051 ~ 15.052 ( 1 µs) │ 43 │ Set T<02>DL<00><15><13> = 'This belongs to the second directory'
│ 14 Co°│ _________________________________________$############# │ T+ 22.086 ~ 27.530 ( 5,445 µs) │ │ Commit
└────────┴─────────────────────────────────────────────────────────┴──────────────────────────────────┴─────────────┘
> Read 54 bytes and Committed 263 bytes in 27.557 ms and 1 attempt(s)
[FdbDirectoryLayer] DirectoryLayer(path=, contents=T<02>DL<00>, nodes=T<02>DL<00><FE>)
Committing T1...
T1 committed
Transaction #116 (read/write, 13 operations, '#' = 0.5 ms, started 14:56:30.6604141Z, ended 14:56:30.6704414Z)
┌ oper. ┬─────────────────────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 rv°│ #####` │ T+ 0.006 ~ 2.553 ( 2,547 µs) │ │ GetReadVersion => 62110623261139
│ 1 G │ _____`+ │ T+ 3.030 ~ 3.218 ( 188 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 2 // │ ______` │ T+ 3.251 │ - - │ // Looking for child First under node T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00>...
│:2 G │ ______: │ T+ 3.255 ~ 3.366 ( 111 µs) │ 23 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>First<00> => not_found
│ 3 G │ ______. │ T+ 3.371 ~ 3.401 ( 31 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 4 // │ ______` │ T+ 3.405 │ - - │ // Ensure that the prefix T<02>DL<00><02>abc<00> hasn't already been allocated
│:4 R │ ______;` │ T+ 3.412 ~ 3.559 ( 147 µs) │ 28 0 │ GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><02>abc<00><FF><00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 5 R │ _______: │ T+ 3.565 ~ 3.685 ( 120 µs) │ 39 0 │ GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><02>abc<00><FF><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><02>abc<01><00>}, limit(1), Exact => 0 result(s)
│ 6 // │ _______` │ T+ 3.692 │ - - │ // Registering the new prefix T<02>DL<00><02>abc<00> into the folder sub-tree
│:6 s │ _______` │ T+ 3.694 ~ 3.699 ( 4 µs) │ 33 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>First<00> = T<02>DL<00><02>abc<00>
│ 7 s │ _______` │ T+ 3.700 ~ 3.700 ( 1 µs) │ 27 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><02>abc<00><FF><00><01>layer<00> = ''
│ 8 s │ _______` │ T+ 3.707 ~ 3.708 ( 1 µs) │ 45 │ Set T<02>DL<00><02>abc<00> = 'This belongs to the first directory'
│ 9 Co°│ ________;########## │ T+ 4.401 ~ 9.609 ( 5,208 µs) │ │ Commit
└────────┴─────────────────────┴──────────────────────────────────┴─────────────┘
> Read 24 bytes and Committed 189 bytes in 9.650 ms and 1 attempt(s)
Committing T2...
Transaction #117 (read/write, 13 operations, '#' = 0.5 ms, started 14:56:30.6604141Z, ended 14:56:30.6784635Z)
┌ oper. ┬─────────────────────────────────────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 rv°│ #####: │ T+ 0.054 ~ 2.609 ( 2,556 µs) │ │ GetReadVersion => 62110623261139
│ 1 G │ _______: │ T+ 3.712 ~ 3.809 ( 96 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 2 // │ _______` │ T+ 3.812 │ - - │ // Looking for child Second under node T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00>...
│:2 G │ _______;+ │ T+ 3.814 ~ 4.133 ( 319 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Second<00> => not_found
│ 3 G │ ________` │ T+ 4.138 ~ 4.157 ( 19 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 4 // │ ________` │ T+ 4.161 │ - - │ // Ensure that the prefix T<02>DL<00><02>def<00> hasn't already been allocated
│:4 R │ ________: │ T+ 4.166 ~ 4.262 ( 96 µs) │ 28 0 │ GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><02>def<00><FF><00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 5 R │ ________: │ T+ 4.271 ~ 4.363 ( 92 µs) │ 39 0 │ GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><02>def<00><FF><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><02>def<01><00>}, limit(1), Exact => 0 result(s)
│ 6 // │ ________` │ T+ 4.369 │ - - │ // Registering the new prefix T<02>DL<00><02>def<00> into the folder sub-tree
│:6 s │ ________` │ T+ 4.371 ~ 4.375 ( 4 µs) │ 34 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Second<00> = T<02>DL<00><02>def<00>
│ 7 s │ ________` │ T+ 4.376 ~ 4.377 ( 1 µs) │ 27 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><02>def<00><FF><00><01>layer<00> = ''
│ 8 s │ ________` │ T+ 4.384 ~ 4.387 ( 3 µs) │ 46 │ Set T<02>DL<00><02>def<00> = 'This belongs to the second directory'
│ 9 !Co°│ ____________________x############## │ T+ 9.985 ~ 17.115 ( 7,130 µs) │ │ Commit => [NotCommitted] Transaction not committed due to conflict with another transaction
└────────┴─────────────────────────────────────┴──────────────────────────────────┴─────────────┘
> Read 24 bytes and Committed 191 bytes in 17.914 ms and 1 attempt(s)
=== FoundationDB.Layers.Directories.DirectoryFacts.Test_CreateOrOpen_Simple() === 16:56:30.7185693

Transaction #119 (read/write, 14 operations, '#' = 0.5 ms, started 14:56:30.7376197Z, ended 14:56:30.7496513Z)
┌ oper. ┬───────────────────────────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G °│ ######= │ T+ 0.346 ~ 3.542 ( 3,196 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => not_found
│ 1 G │ ______: │ T+ 3.568 ~ 3.676 ( 108 µs) │ 21 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => not_found
│ 2 G │ ______` │ T+ 3.680 ~ 3.701 ( 20 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => not_found
│ 3 s │ ______` │ T+ 3.704 ~ 3.711 ( 6 µs) │ 36 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> = <01><00><00><00><00><00><00><00><00><00><00><00>
│ 4 R │ ______.. │ T+ 3.720 ~ 3.844 ( 124 µs) │ 44 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><FF>}, limit(1), reverse, Exact => 0 result(s)
│ 5 a │ _______` │ T+ 3.855 ~ 3.860 ( 5 µs) │ 30 │ Atomic_Add T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><14>, <01 00 00 00 00 00 00 00>
│ 6 G │ _______: │ T+ 3.862 ~ 3.938 ( 77 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><09> => not_found
│ 7 s │ _______` │ T+ 3.940 ~ 3.943 ( 3 µs) │ 24 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><09> = ''
│ 8 R │ _______: │ T+ 3.950 ~ 4.048 ( 99 µs) │ 14 0 │ GetRange fGE{T<02>DL<00><15><09>} <= k < fGE{T<02>DL<00><15><0A>}, limit(1), Exact => 0 result(s)
│ 9 R │ _______: │ T+ 4.054 ~ 4.138 ( 84 µs) │ 24 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><09><00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 10 R │ _______: │ T+ 4.144 ~ 4.233 ( 89 µs) │ 32 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><09><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><0A><00>}, limit(1), Exact => 0 result(s)
│ 11 s │ _______` │ T+ 4.241 ~ 4.244 ( 4 µs) │ 28 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> = T<02>DL<00><15><09>
│ 12 s │ _______` │ T+ 4.245 ~ 4.246 ( 1 µs) │ 23 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15><09><00><01>layer<00> = ''
│ 13 Co°│ _______`################# │ T+ 4.262 ~ 12.601 ( 8,339 µs) │ │ Commit
└────────┴───────────────────────────┴──────────────────────────────────┴─────────────┘
> Committed 253 bytes in 12.621 ms and 1 attempt(s)
Transaction #120 (read-only, 3 operations, '#' = 0.5 ms, started 14:56:30.7516571Z, ended 14:56:30.7526609Z)
┌ oper. ┬────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ #= │ T+ 0.185 ~ 1.162 ( 977 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 1 G │ _: │ T+ 1.175 ~ 1.308 ( 133 µs) │ 21 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => T<02>DL<00><15><09>
│ 2 G │ _: │ T+ 1.314 ~ 1.448 ( 134 µs) │ 23 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15><09><00><01>layer<00> => ''
└────────┴────┴──────────────────────────────────┴─────────────┘
> Read 19 bytes in 1.474 ms and 1 attempt(s)
=== FoundationDB.Layers.Directories.DirectoryFacts.Test_CreateOrOpen_SubFolder() === 16:56:30.7536630
=== FoundationDB.Layers.Directories.DirectoryFacts.Test_CreateOrOpen_With_Layer() === 16:56:30.7887546
Transaction #122 (read/write, 38 operations, '#' = 0.5 ms, started 14:56:30.7656938Z, ended 14:56:30.7847447Z)
┌ oper. ┬─────────────────────────────────────────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ & │ T+ 0.654 ~ 1.090 ( 436 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => not_found
│ 1 G │ _.; │ T+ 1.575 ~ 1.770 ( 195 µs) │ 21 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => not_found
│ 2 G │ ___. │ T+ 2.243 ~ 2.286 ( 43 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => not_found
│ 3 s │ ___` │ T+ 2.289 ~ 2.307 ( 17 µs) │ 36 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> = <01><00><00><00><00><00><00><00><00><00><00><00>
│ 4 R │ ___; │ T+ 2.313 ~ 2.468 ( 154 µs) │ 44 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><FF>}, limit(1), reverse, Exact => 0 result(s)
│ 5 a │ ___` │ T+ 2.473 ~ 2.486 ( 12 µs) │ 30 │ Atomic_Add T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><14>, <01 00 00 00 00 00 00 00>
│ 6 G °│ ___:###########: │ T+ 2.487 ~ 8.008 ( 5,521 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15> => not_found
│ 7 s │ _______________` │ T+ 8.013 ~ 8.021 ( 8 µs) │ 24 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15> = ''
│ 8 R │ _______________+ │ T+ 8.032 ~ 8.202 ( 170 µs) │ 14 0 │ GetRange fGE{T<02>DL<00><15> } <= k < fGE{T<02>DL<00><15>!}, limit(1), Exact => 0 result(s)
│ 9 R │ _______________: │ T+ 8.210 ~ 8.303 ( 93 µs) │ 24 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15> <00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 10 R │ _______________.. │ T+ 8.357 ~ 8.451 ( 94 µs) │ 32 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15> <00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15>!<00>}, limit(1), Exact => 0 result(s)
│ 11 G │ ________________. │ T+ 8.636 ~ 8.668 ( 32 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 12 G │ ___________________. │ T+ 10.014 ~ 10.045 ( 30 µs) │ 21 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => not_found
│ 13 G │ _______________________.. │ T+ 12.238 ~ 12.307 ( 68 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 14 R │ ________________________. │ T+ 12.317 ~ 12.347 ( 30 µs) │ 44 30 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><FF>}, limit(1), reverse, Exact => 1 result(s), has_more
│ 15 a │ ________________________` │ T+ 12.364 ~ 12.369 ( 5 µs) │ 30 │ Atomic_Add T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><14>, <01 00 00 00 00 00 00 00>
│ 16 G │ ________________________+ │ T+ 12.371 ~ 12.553 ( 182 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><12> => not_found
│ 17 s │ ________________________` │ T+ 12.554 ~ 12.558 ( 4 µs) │ 24 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><12> = ''
│ 18 R │ ________________________; │ T+ 12.564 ~ 12.687 ( 123 µs) │ 14 0 │ GetRange fGE{T<02>DL<00><15><12>} <= k < fGE{T<02>DL<00><15><13>}, limit(1), Exact => 0 result(s)
│ 19 R │ ________________________.. │ T+ 12.693 ~ 12.819 ( 126 µs) │ 24 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><12><00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 20 R │ _________________________` │ T+ 12.826 ~ 12.847 ( 22 µs) │ 32 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><12><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><13><00>}, limit(1), Exact => 0 result(s)
│ 21 G │ _________________________` │ T+ 12.879 ~ 12.903 ( 23 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 22 G │ _________________________` │ T+ 12.913 ~ 12.928 ( 14 µs) │ 21 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => not_found
│ 23 G │ _________________________` │ T+ 12.930 ~ 12.941 ( 11 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 24 R │ _________________________` │ T+ 12.944 ~ 12.960 ( 16 µs) │ 44 30 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><FF>}, limit(1), reverse, Exact => 1 result(s), has_more
│ 25 a │ _________________________` │ T+ 12.963 ~ 12.966 ( 3 µs) │ 30 │ Atomic_Add T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><14>, <01 00 00 00 00 00 00 00>
│ 26 G │ _________________________: │ T+ 12.966 ~ 13.044 ( 78 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><02> => not_found
│ 27 s │ _________________________` │ T+ 13.044 ~ 13.046 ( 2 µs) │ 24 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15><02> = ''
│ 28 R │ _________________________: │ T+ 13.048 ~ 13.131 ( 83 µs) │ 14 0 │ GetRange fGE{T<02>DL<00><15><02>} <= k < fGE{T<02>DL<00><15><03>}, limit(1), Exact => 0 result(s)
│ 29 R │ _________________________` │ T+ 13.134 ~ 13.148 ( 14 µs) │ 24 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 30 R │ _________________________` │ T+ 13.151 ~ 13.163 ( 13 µs) │ 32 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15><03><00>}, limit(1), Exact => 0 result(s)
│ 31 s │ _________________________` │ T+ 13.167 ~ 13.173 ( 5 µs) │ 28 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> = T<02>DL<00><15><02>
│ 32 s │ _________________________` │ T+ 13.173 ~ 13.174 ( 0 µs) │ 23 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00><01>layer<00> = ''
│ 33 s │ __________________________` │ T+ 13.635 ~ 13.639 ( 4 µs) │ 29 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00><14><02>Bar<00> = T<02>DL<00><15><12>
│ 34 s │ __________________________` │ T+ 13.640 ~ 13.640 ( 1 µs) │ 23 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15><12><00><01>layer<00> = ''
│ 35 s │ __________________________` │ T+ 13.653 ~ 13.655 ( 2 µs) │ 29 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15><12><00><14><02>Baz<00> = T<02>DL<00><15>
│ 36 s │ __________________________` │ T+ 13.656 ~ 13.656 ( 1 µs) │ 23 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15> <00><01>layer<00> = ''
│ 37 Co°│ __________________________.############ │ T+ 13.665 ~ 19.536 ( 5,871 µs) │ │ Commit
└────────┴─────────────────────────────────────────┴──────────────────────────────────┴─────────────┘
> Read 108 bytes and Committed 633 bytes in 19.561 ms and 1 attempt(s)
Transaction #123 (read-only, 3 operations, '#' = 0.5 ms, started 14:56:30.7857475Z, ended 14:56:30.7867503Z)
┌ oper. ┬───┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ ; │ T+ 0.011 ~ 0.309 ( 298 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 1 G │ . │ T+ 0.319 ~ 0.419 ( 100 µs) │ 21 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => T<02>DL<00><15><02>
│ 2 G │ x │ T+ 0.424 ~ 0.944 ( 520 µs) │ 23 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00><01>layer<00> => ''
└────────┴───┴──────────────────────────────────┴─────────────┘
> Read 19 bytes in 0.958 ms and 1 attempt(s)
Transaction #124 (read-only, 5 operations, '#' = 0.5 ms, started 14:56:30.7867503Z, ended 14:56:30.7887546Z)
┌ oper. ┬─────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ #: │ T+ 0.437 ~ 1.040 ( 603 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 1 G │ _.+ │ T+ 1.405 ~ 1.631 ( 226 µs) │ 21 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => T<02>DL<00><15><02>
│ 2 G │ __: │ T+ 1.636 ~ 1.735 ( 99 µs) │ 23 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00><01>layer<00> => ''
│ 3 G │ __: │ T+ 1.741 ~ 1.827 ( 86 µs) │ 22 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15><02><00><14><02>Bar<00> => T<02>DL<00><15><12>
│ 4 G │ __: │ T+ 1.830 ~ 1.928 ( 98 µs) │ 23 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15><12><00><01>layer<00> => ''
└────────┴─────┴──────────────────────────────────┴─────────────┘
> Read 26 bytes in 1.943 ms and 1 attempt(s)
Transaction #126 (read/write, 14 operations, '#' = 0.5 ms, started 14:56:30.8078080Z, ended 14:56:30.8178323Z)
┌ oper. ┬──────────────────────┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ #. │ T+ 0.147 ~ 0.677 ( 530 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => not_found
│ 1 G │ _: │ T+ 0.686 ~ 0.799 ( 113 µs) │ 21 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => not_found
│ 2 G │ _` │ T+ 0.802 ~ 0.826 ( 24 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => not_found
│ 3 s │ _` │ T+ 0.829 ~ 0.834 ( 4 µs) │ 36 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> = <01><00><00><00><00><00><00><00><00><00><00><00>
│ 4 R │ _; │ T+ 0.841 ~ 0.970 ( 130 µs) │ 44 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><FF>}, limit(1), reverse, Exact => 0 result(s)
│ 5 a │ _` │ T+ 0.974 ~ 0.978 ( 4 µs) │ 30 │ Atomic_Add T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><14><14>, <01 00 00 00 00 00 00 00>
│ 6 G │ _: │ T+ 0.979 ~ 1.094 ( 115 µs) │ 24 0 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15>" => not_found
│ 7 s │ _` │ T+ 1.095 ~ 1.098 ( 3 µs) │ 24 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>hca<00><15><01><15>" = ''
│ 8 R │ _.. │ T+ 1.102 ~ 1.197 ( 95 µs) │ 14 0 │ GetRange fGE{T<02>DL<00><15>"} <= k < fGE{T<02>DL<00><15>#}, limit(1), Exact => 0 result(s)
│ 9 R │ __: │ T+ 1.204 ~ 1.316 ( 112 µs) │ 24 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15>"<00><00>}, limit(1), reverse, Exact => 0 result(s)
│ 10 R │ __: │ T+ 1.321 ~ 1.409 ( 88 µs) │ 32 0 │ Snapshot.GetRange fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15>"<00>} <= k < fGE{T<02>DL<00><FE><01>T<02>DL<00><FF><15>#<00>}, limit(1), Exact => 0 result(s)
│ 11 s │ __` │ T+ 1.414 ~ 1.417 ( 3 µs) │ 28 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> = T<02>DL<00><15>"
│ 12 s │ __` │ T+ 1.418 ~ 1.419 ( 1 µs) │ 32 │ Set T<02>DL<00><FE><01>T<02>DL<00><FF><15>"<00><01>layer<00> = 'AcmeLayer'
│ 13 Co°│ __=################# │ T+ 1.426 ~ 10.216 ( 8,790 µs) │ │ Commit
└────────┴──────────────────────┴──────────────────────────────────┴─────────────┘
> Committed 262 bytes in 10.229 ms and 1 attempt(s)
Transaction #127 (read-only, 3 operations, '#' = 0.5 ms, started 14:56:30.8188354Z, ended 14:56:30.8198399Z)
┌ oper. ┬───┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ X │ T+ 0.135 ~ 0.674 ( 539 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 1 G │ : │ T+ 0.688 ~ 0.822 ( 135 µs) │ 21 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => T<02>DL<00><15>"
│ 2 G │ . │ T+ 0.827 ~ 0.949 ( 122 µs) │ 23 9 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15>"<00><01>layer<00> => 'AcmeLayer'
└────────┴───┴──────────────────────────────────┴─────────────┘
> Read 28 bytes in 0.968 ms and 1 attempt(s)
Transaction #128 (read-only, 3 operations, '#' = 0.5 ms, started 14:56:30.8208407Z, ended 14:56:30.8218427Z)
┌ oper. ┬───┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ X │ T+ 0.014 ~ 0.476 ( 462 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 1 G │ : │ T+ 0.483 ~ 0.592 ( 109 µs) │ 21 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => T<02>DL<00><15>"
│ 2 G │ : │ T+ 0.597 ~ 0.704 ( 107 µs) │ 23 9 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15>"<00><01>layer<00> => 'AcmeLayer'
└────────┴───┴──────────────────────────────────┴─────────────┘
> Read 28 bytes in 0.989 ms and 1 attempt(s)
Transaction #129 (read-only, 3 operations, '#' = 0.5 ms, started 14:56:30.8228501Z, ended 14:56:30.8238485Z)
┌ oper. ┬───┬──── start ──── end ── duration ──┬─ sent recv ┐
│ 0 G │ X │ T+ 0.007 ~ 0.512 ( 505 µs) │ 24 12 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><01>version<00> => <01><00><00><00><00><00><00><00><00><00><00><00>
│ 1 G │ . │ T+ 0.524 ~ 0.637 ( 114 µs) │ 21 7 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><FE><00><14><02>Foo<00> => T<02>DL<00><15>"
│ 2 G │ : │ T+ 0.642 ~ 0.782 ( 141 µs) │ 23 9 │ Get T<02>DL<00><FE><01>T<02>DL<00><FF><15>"<00><01>layer<00> => 'AcmeLayer'
└────────┴───┴──────────────────────────────────┴─────────────┘
> Read 28 bytes in 0.804 ms and 1 attempt(s)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment