Closed Bug 1313622 Opened 9 years ago Closed 9 years ago

generic worker live log on windows shows an incomplete log

Categories

(Taskcluster :: Workers, defect)

Product:
Taskcluster
Component:
Workers
Platform:
Unspecified
Windows
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED FIXED

People

(Reporter: grenade, Assigned: pmoore)

Details

when watching live logs live (while the build is in progress) we see an incomplete log. eg the following three lines encompass the entire checkout stage: [taskcluster 2016-10-28T09:23:50.918Z] === Task Starting === [taskcluster 2016-10-28T09:23:50.918Z] Executing command 0: "c:\Program Files\Mercurial\hg.exe" robustcheckout --sharebase y:\hg-shared --purge --upstream https://hg.mozilla.org/mozilla-unified --revision 5226cdee0ae642b34669e5a487d1e5bd09b534a4 https://hg.mozilla.org/try/ .\build\src [taskcluster 2016-10-28T09:43:06.904Z] Exit Code: 0 the same section of the log after the build has completed shows this: [taskcluster 2016-10-28T07:14:47.290Z] === Task Starting === [taskcluster 2016-10-28T07:14:47.290Z] Executing command 0: "c:\Program Files\Mercurial\hg.exe" robustcheckout --sharebase y:\hg-shared --purge --upstream https://hg.mozilla.org/mozilla-unified --revision 8ad10e5ba221de4e82eb18e5ecc58b85415979f1 https://hg.mozilla.org/try/ .\build\src Z:\task_1477638546>"c:\Program Files\Mercurial\hg.exe" robustcheckout --sharebase y:\hg-shared --purge --upstream https://hg.mozilla.org/mozilla-unified --revision 8ad10e5ba221de4e82eb18e5ecc58b85415979f1 https://hg.mozilla.org/try/ .\build\src clone [ ] 20356862/1625501188 2m51s clone [> ] 61198238/1625501188 1m21s clone [=> ] 93630387/1625501188 1m09s clone [=> ] 115506842/1625501188 1m08s clone [==> ] 128509936/1625501188 1m15s clone [==> ] 136152406/1625501188 1m23s clone [==> ] 144138880/1625501188 1m28s clone [===> ] 161524940/1625501188 1m28s clone [===> ] 183106644/1625501188 1m24s clone [====> ] 194661070/1625501188 1m26s clone [====> ] 207712234/1625501188 1m27s clone [====> ] 217953584/1625501188 1m30s clone [=====> ] 235975025/1625501188 1m28s clone [=====> ] 239559083/1625501188 1m32s clone [=====> ] 247371398/1625501188 1m34s clone [=====> ] 269710409/1625501188 1m32s clone [======> ] 281543950/1625501188 1m33s clone [======> ] 303515203/1625501188 1m29s clone [======> ] 308710630/1625501188 1m32s clone [=======> ] 313562421/1625501188 1m34s clone [=======> ] 320311423/1625501188 1m36s clone [=======> ] 326007342/1625501188 1m38s clone [=======> ] 338399338/1625501188 1m38s clone [=======> ] 346534657/1625501188 1m38s clone [========> ] 355580460/1625501188 1m40s clone [========> ] 361476263/1625501188 1m42s clone [========> ] 364815900/1625501188 1m44s clone [========> ] 367819898/1625501188 1m47s clone [========> ] 377038928/1625501188 1m48s clone [========> ] 383558183/1625501188 1m49s clone [=========> ] 394857789/1625501188 1m48s clone [=========> ] 401164094/1625501188 1m49s clone [==========> ] 432722734/1625501188 1m41s clone [==========> ] 453856826/1625501188 1m37s clone [==========> ] 461689008/1625501188 1m39s clone [===========> ] 470056848/1625501188 1m39s clone [===========> ] 474688085/1625501188 1m41s clone [===========> ] 481723953/1625501188 1m41s clone [===========> ] 488340309/1625501188 1m42s clone [===========> ] 493365670/1625501188 1m43s clone [===========> ] 496091852/1625501188 1m44s clone [===========> ] 497987767/1625501188 1m46s clone [============> ] 507870021/1625501188 1m45s clone [============> ] 519980549/1625501188 1m44s clone [============> ] 538030218/1625501188 1m41s clone [=============> ] 545555224/1625501188 1m41s clone [=============> ] 550244998/1625501188 1m42s clone [=============> ] 555392619/1625501188 1m43s clone [=============> ] 571269232/1625501188 1m41s clone [==============> ] 582622268/1625501188 1m39s clone [==============> ] 589138413/1625501188 1m39s clone [==============> ] 597932886/1625501188 1m39s clone [==============> ] 609188597/1625501188 1m38s clone [==============> ] 616764878/1625501188 1m37s clone [===============> ] 625305719/1625501188 1m37s clone [===============> ] 636752840/1625501188 1m36s clone [===============> ] 649269534/1625501188 1m34s clone [===============> ] 651067207/1625501188 1m36s clone [===============> ] 657744299/1625501188 1m36s clone [================> ] 687723097/1625501188 1m30s clone [=================> ] 706372898/1625501188 1m27s clone [=================> ] 710585058/1625501188 1m28s clone [=================> ] 713138091/1625501188 1m29s clone [=================> ] 715804330/1625501188 1m29s clone [=================> ] 717314019/1625501188 1m30s clone [=================> ] 726554347/1625501188 1m30s clone [=================> ] 730108899/1625501188 1m30s clone [=================> ] 731701090/1625501188 1m31s clone [==================> ] 735619692/1625501188 1m32s clone [==================> ] 749110779/1625501188 1m30s clone [==================> ] 749529972/1625501188 1m31s clone [==================> ] 760118903/1625501188 1m30s clone [==================> ] 761945458/1625501188 1m31s clone [==================> ] 768156517/1625501188 1m31s clone [==================> ] 769711384/1625501188 1m32s clone [==================> ] 771352926/1625501188 1m32s clone [==================> ] 773441865/1625501188 1m33s clone [===================> ] 783922418/1625501188 1m32s clone [===================> ] 784644014/1625501188 1m33s clone [===================> ] 786194457/1625501188 1m34s clone [===================> ] 788791799/1625501188 1m34s clone [===================> ] 790731370/1625501188 1m35s clone [===================> ] 800063507/1625501188 1m34s clone [===================> ] 808643720/1625501188 1m33s clone [====================> ] 819463258/1625501188 1m31s clone [====================> ] 830172199/1625501188 1m30s clone [====================> ] 835019300/1625501188 1m30s clone [====================> ] 838892478/1625501188 1m30s clone [====================> ] 841560052/1625501188 1m30s clone [====================> ] 849631733/1625501188 1m29s clone [=====================> ] 858827640/1625501188 1m28s clone [=====================> ] 868339418/1625501188 1m27s clone [=====================> ] 878170476/1625501188 1m26s clone [=====================> ] 886211403/1625501188 1m25s clone [======================> ] 904469201/1625501188 1m22s clone [======================> ] 909572409/1625501188 1m22s clone [======================> ] 918777379/1625501188 1m21s clone [======================> ] 928851328/1625501188 1m20s clone [=======================> ] 935848332/1625501188 1m19s clone [=======================> ] 941186393/1625501188 1m19s clone [=======================> ] 946615891/1625501188 1m19s clone [=======================> ] 953145758/1625501188 1m18s clone [=======================> ] 965512725/1625501188 1m16s clone [========================> ] 980091270/1625501188 1m14s clone [========================> ] 992131839/1625501188 1m13s clone [========================> ] 1005239429/1625501188 1m11s clone [=========================> ] 1020296082/1625501188 1m09s clone [=========================> ] 1025408817/1625501188 1m09s clone [=========================> ] 1031082425/1625501188 1m08s clone [==========================> ] 1044976428/1625501188 1m06s clone [==========================> ] 1048348416/1625501188 1m06s clone [==========================> ] 1058018938/1625501188 1m05s clone [==========================> ] 1063280068/1625501188 1m05s clone [==========================> ] 1074890621/1625501188 1m03s clone [===========================> ] 1086773061/1625501188 1m02s clone [============================> ] 1107266101/1625501188 59s clone [=============================> ] 1108708604/1625501188 59s clone [=============================> ] 1109535095/1625501188 59s clone [===========================> ] 1110500219/1625501188 1m00s clone [===========================> ] 1112872132/1625501188 1m00s clone [===========================> ] 1114145953/1625501188 1m00s clone [===========================> ] 1114557905/1625501188 1m00s clone [===========================> ] 1121706324/1625501188 1m00s clone [============================> ] 1123209276/1625501188 1m00s clone [============================> ] 1123911195/1625501188 1m00s clone [=============================> ] 1138090719/1625501188 58s clone [=============================> ] 1139703412/1625501188 58s clone [=============================> ] 1140462770/1625501188 59s clone [==============================> ] 1146218050/1625501188 58s clone [==============================> ] 1151409281/1625501188 58s clone [==============================> ] 1153950987/1625501188 58s clone [==============================> ] 1160698149/1625501188 57s clone [==============================> ] 1169130445/1625501188 56s clone [==============================> ] 1180156918/1625501188 54s clone [===============================> ] 1194572894/1625501188 52s clone [===============================> ] 1208073993/1625501188 50s clone [===============================> ] 1214172897/1625501188 50s clone [================================> ] 1220828611/1625501188 49s clone [================================> ] 1224917478/1625501188 49s clone [================================> ] 1232271315/1625501188 48s clone [================================> ] 1247251635/1625501188 46s clone [=================================> ] 1258331730/1625501188 45s clone [=================================> ] 1263483781/1625501188 44s clone [==================================> ] 1313953945/1625501188 37s clone [====================================> ] 1392466073/1625501188 26s clone [======================================> ] 1472944281/1625501188 17s clone [========================================> ] 1548971498/1625501188 08s clone [==========================================> ] 1624010218/1625501188 01s manifests [ ] 1/266 ensuring https://hg.mozilla.org/try/@8ad10e5ba221de4e82eb18e5ecc58b85415979f1 is available at .\build\src (cloning from upstream repo https://hg.mozilla.org/mozilla-unified) (sharing from new pooled repository 8ba995b74e18334ab3707f27e9eb8f4e37ba3d29) applying clone bundle from https://s3-eu-central-1.amazonaws.com/moz-hg-bundles-eu-central-1/mozilla-unified/2d0a7bf026ed4aadcb17f9e1f9de0f1488b17cee.packed1-gd.hg 267706 files to transfer, 1.51 GB of data transferred 1.51 GB in 157.5 seconds (9.84 MB/sec) finished applying clone bundle searching for changes adding changesets adding manifests adding file changes added 266 changesets with 1416 changes to 786 files (+1 heads) searching [ <=> ] 2 changesets [===> ] 1/12 searching for changes no changes found adding remote bookmark aurora adding remote bookmark beta adding remote bookmark central adding remote bookmark esr10 adding remote bookmark esr17 adding remote bookmark esr24 adding remote bookmark esr31 adding remote bookmark esr38 adding remote bookmark esr45 adding remote bookmark fx-team adding remote bookmark inbound adding remote bookmark release (pulling to obtain 8ad10e5ba221de4e82eb18e5ecc58b85415979f1) searching for changes adding changesets adding manifests adding file changes added 12 changesets with 26 changes to 17 files (+1 heads) updating [ ] 100/149213 updating [ ] 1600/149213 updating [ ] 2700/149213 2m08s updating [> ] 3900/149213 2m06s updating [> ] 4700/149213 2m17s updating [> ] 5800/149213 2m17s updating [=> ] 7000/149213 2m14s updating [=> ] 8400/149213 2m08s updating [==> ] 9800/149213 2m03s updating [==> ] 11300/149213 2m00s updating [==> ] 12600/149213 1m58s updating [===> ] 13800/149213 1m57s updating [===> ] 15100/149213 1m55s updating [====> ] 16400/149213 1m54s updating [====> ] 17600/149213 1m53s updating [====> ] 18900/149213 1m51s updating [=====> ] 20300/149213 1m50s updating [=====> ] 22100/149213 1m46s updating [======> ] 23400/149213 1m44s updating [======> ] 24700/149213 1m43s updating [=======> ] 26000/149213 1m42s updating [=======> ] 26700/149213 1m43s updating [=======> ] 27600/149213 1m44s updating [========> ] 29100/149213 1m41s updating [========> ] 30200/149213 1m41s updating [=========> ] 31800/149213 1m39s updating [=========> ] 33500/149213 1m36s updating [=========> ] 34600/149213 1m36s updating [==========> ] 36200/149213 1m34s updating [==========> ] 37700/149213 1m32s updating [===========> ] 38700/149213 1m32s updating [===========> ] 39800/149213 1m33s updating [===========> ] 41000/149213 1m32s updating [============> ] 42300/149213 1m31s updating [============> ] 43600/149213 1m29s updating [=============> ] 45000/149213 1m28s updating [=============> ] 45700/149213 1m28s updating [=============> ] 46700/149213 1m28s updating [==============> ] 47800/149213 1m27s updating [==============> ] 49100/149213 1m26s updating [==============> ] 50200/149213 1m25s updating [===============> ] 51500/149213 1m24s updating [===============> ] 52900/149213 1m23s updating [================> ] 54700/149213 1m21s updating [================> ] 55600/149213 1m20s updating [================> ] 56500/149213 1m20s updating [=================> ] 58100/149213 1m18s updating [=================> ] 59700/149213 1m17s updating [==================> ] 61100/149213 1m15s updating [==================> ] 62500/149213 1m14s updating [===================> ] 63800/149213 1m13s updating [===================> ] 65100/149213 1m12s updating [===================> ] 66300/149213 1m11s updating [====================> ] 67800/149213 1m09s updating [====================> ] 69400/149213 1m08s updating [=====================> ] 71400/149213 1m05s updating [=====================> ] 72800/149213 1m04s updating [======================> ] 74200/149213 1m03s updating [======================> ] 75900/149213 1m01s updating [=======================> ] 77200/149213 1m00s updating [========================> ] 78800/149213 58s updating [=========================> ] 80700/149213 56s updating [=========================> ] 82000/149213 55s updating [==========================> ] 83000/149213 54s updating [==========================> ] 84900/149213 53s updating [===========================> ] 86700/149213 51s updating [===========================> ] 87800/149213 50s updating [============================> ] 89300/149213 49s updating [============================> ] 90200/149213 48s updating [============================> ] 91300/149213 47s updating [=============================> ] 92700/149213 46s updating [=============================> ] 93700/149213 46s updating [==============================> ] 95000/149213 45s updating [==============================> ] 96100/149213 44s updating [===============================> ] 97500/149213 43s updating [===============================> ] 99000/149213 41s updating [===============================> ] 100000/149213 41s updating [================================> ] 101100/149213 40s updating [================================> ] 102000/149213 39s updating [================================> ] 103100/149213 38s updating [=================================> ] 104100/149213 38s updating [=================================> ] 105400/149213 37s updating [==================================> ] 106800/149213 35s updating [==================================> ] 107900/149213 35s updating [==================================> ] 108600/149213 34s updating [===================================> ] 110000/149213 33s updating [===================================> ] 111300/149213 32s updating [===================================> ] 112200/149213 31s updating [====================================> ] 113200/149213 30s updating [====================================> ] 114400/149213 29s updating [====================================> ] 115600/149213 29s updating [=====================================> ] 117000/149213 27s updating [=====================================> ] 118200/149213 26s updating [======================================> ] 120000/149213 25s updating [======================================> ] 121300/149213 24s updating [=======================================> ] 122400/149213 23s updating [=======================================> ] 123800/149213 22s updating [========================================> ] 125200/149213 20s updating [========================================> ] 126600/149213 19s updating [=========================================> ] 128000/149213 18s updating [=========================================> ] 129100/149213 17s updating [=========================================> ] 130800/149213 16s updating [==========================================> ] 132300/149213 14s updating [==========================================> ] 133600/149213 13s updating [===========================================> ] 135100/149213 12s updating [===========================================> ] 136400/149213 11s updating [============================================> ] 137700/149213 10s updating [============================================> ] 139200/149213 09s updating [=============================================> ] 140700/149213 07s updating [=============================================> ] 141800/149213 07s updating [=============================================> ] 143000/149213 06s updating [==============================================> ] 143800/149213 05s updating [==============================================> ] 144600/149213 04s updating [==============================================> ] 146000/149213 03s updating [===============================================> ] 147500/149213 02s updating [===============================================> ] 148400/149213 01s updating [===============================================> ] 149200/149213 01s 149213 files updated, 0 files merged, 0 files removed, 0 files unresolved updated to 8ad10e5ba221de4e82eb18e5ecc58b85415979f1 [taskcluster 2016-10-28T07:21:05.598Z] Exit Code: 0 live log would be a lot more useful if it was less economical with the progress updates.
Yeah, this is not intentional. I wonder what is happening.
Assignee: nobody → pmoore
It looks like for some reason the task output is going to the backing log, but not to the live log. I suspect this is a generic worker bug rather than livelog bug or log viewer bug...
To rule out the possibility this is a log viewer bug, it would be useful to curl the livelog artifact in a terminal while a task is running, just to see if the output matches what is displayed in the log viewer.
Released in https://github.com/taskcluster/generic-worker/releases/tag/v6.0.9
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → FIXED
Component: Generic-Worker → Workers
You need to log in before you can comment on or make changes to this bug.