DomGarguilo commented on issue #1793:
URL: https://github.com/apache/accumulo/issues/1793#issuecomment-889423370
Normally this test will create a slow op for a compaction then enter into a
loop to wait until it shows up in the metrics file. To complete successfully,
in the file `currentFateOps` will be ` >= tableCount`. Typically, this takes
some time to happen and will appear in the file after about a minute. Somehow
`currentFateOps` was always zero and `totalFateOps` was always 4. or
comparison, this is how the output of the test should look.
<details>
```
2021-07-29T15:29:06,002 [util.SlowOps] INFO : Created table id: 1, name
'MasterMetricsIT_compactionMetrics0_0'
2021-07-29T15:29:06,263 [util.SlowOps] INFO : Starting compaction. Attempt 1
2021-07-29T15:29:09,303 [util.SlowOps] DEBUG: Compaction running for
MasterMetricsIT_compactionMetrics0_0
2021-07-29T15:29:09,303 [functional.MasterMetricsIT] INFO : Received metrics
LineUpdate{lastUpdate=15017606383, line='1627586943249 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=0,
totalFateOps=0, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0'}
2021-07-29T15:29:14,318 [functional.MasterMetricsIT] INFO : Received metrics
update LineUpdate{lastUpdate=20019595081, line='1627586948250 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=0,
totalFateOps=0, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0'}
2021-07-29T15:29:24,319 [functional.MasterMetricsIT] INFO : Received metrics
update LineUpdate{lastUpdate=30026699322, line='1627586958252 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=0,
totalFateOps=0, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0'}
2021-07-29T15:29:34,319 [functional.MasterMetricsIT] INFO : Received metrics
update LineUpdate{lastUpdate=40034605394, line='1627586968244 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=0,
totalFateOps=0, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0'}
2021-07-29T15:29:44,320 [functional.MasterMetricsIT] INFO : Received metrics
update LineUpdate{lastUpdate=50042852335, line='1627586978249 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=0,
totalFateOps=0, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0'}
2021-07-29T15:29:54,320 [functional.MasterMetricsIT] INFO : Received metrics
update LineUpdate{lastUpdate=60051744888, line='1627586988245 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=0,
totalFateOps=0, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0'}
2021-07-29T15:30:04,321 [functional.MasterMetricsIT] INFO : Received metrics
update LineUpdate{lastUpdate=70056551575, line='1627586998247 master.fate:
Context=master, ProcessName=Master, Hostname=thor, currentFateOps=1,
totalFateOps=3, totalZkConnErrors=0, FateTxState_NEW=0,
FateTxState_IN_PROGRESS=1, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_FAILED=0, FateTxState_SUCCESSFUL=0, FateTxState_UNKNOWN=0,
FateTxOpType_CompactRange=1'}
2021-07-29T15:30:04,322 [functional.MasterMetricsIT] INFO : Found required
number of fate operations
2021-07-29T15:30:04,322 [functional.MasterMetricsIT] ERROR: IN_PROGRESS: 1
2021-07-29T15:30:09,706 [functional.MasterMetricsIT] INFO : Received metrics
{1627587003247 master.fate: Context=master, FateTxOpType_CompactRange=1,
FateTxState_FAILED=0, FateTxState_FAILED_IN_PROGRESS=0,
FateTxState_IN_PROGRESS=1, FateTxState_NEW=0, FateTxState_SUCCESSFUL=0,
FateTxState_UNKNOWN=0, Hostname=thor, ProcessName=Master, currentFateOps=1,
totalFateOps=3, totalZkConnErrors=0}
```
</details>
My guess is that this is not an issue with the test. Potentially the slow op
was not actually slow and completed faster than expected
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
