Closed Bug 1375509 Opened 8 years ago Closed 8 years ago

Windows loaner instructions timeout waiting for credentials to be written to disk

Categories

(Infrastructure & Operations :: RelOps: General, task)

Product:
Infrastructure & Operations
Component:
RelOps: General
Type:
task
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: Alex_Gaynor, Assigned: grenade)

Details

Following the instructions at https://wiki.mozilla.org/ReleaseEngineering/How_To/Self_Provision_a_TaskCluster_Windows_Instance, the created task will spin waiting for the credentials to be written to disk. From IRC: 10:18:23 <grenade> Alex_Gaynor: looks like a bug in the encryption mechanism. the loan prep script has completed but failed to create the encrypted credentials file. In the event this is somehow connected to my pubkey, the URL I was using is https://keybase.io/alex_gaynor/pgp_keys.asc
event logs from the loan prep task: Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Task found Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Setting reclaim timer... Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Current claim will expire at 2017-06-22 14:29:38.797 +0000 UTC Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Reclaiming 3 mins earlier, at 2017-06-22 14:26:38.797 +0000 UTC Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Time to wait until then is 16m59.7271156s Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 This is more than 30 seconds away - so setting a timer Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 JSON payload: { Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "maxRunTime": 5400, Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "artifacts": [ Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: { Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "path": "..\\loan", Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "name": "public/test_info", Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "type": "directory", Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "expires": "2018-06-22T08:25:07.412Z" Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: } Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: ], Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "env": { Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "REQUESTER_EMAIL": "agaynor@mozilla.com", Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "REQUESTER_PUBLIC_KEY_URL": "https://keybase.io/alex_gaynor/pgp_keys.asc" Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: }, Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "command": [ Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "c:\\mozilla-build\\python\\python.exe -c \"import os, json; json.dump({'requester': { 'email': os.environ['REQUESTER_EMAIL'], 'publickeyurl': os.environ['REQUESTER_PUBLIC_KEY_URL'], 'taskFolder': os.getcwd()}}, open(r'z:\\loan-request.json', 'wb'))\"", Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: "c:\\mozilla-build\\python\\python.exe -c \"exec(\\\"import os.path, time\\nwhile (not os.path.exists(r'z:\\loan\\credentials.txt.gpg')):\\n time.sleep(1)\\\"\")" Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: ] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: } Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Running task https://tools.taskcluster.net/task-inspector/#CbArybEqSZ6HzF2g4NDO7g/0 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Creating wrapper script: Z:\task_1498137226\command_000000_wrapper.bat Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Deadline: 2017-06-22 15:39:39.0708859 +0000 GMT Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Created command: Z:\task_1498137226\command_000000_wrapper.bat Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Creating wrapper script: Z:\task_1498137226\command_000001_wrapper.bat Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Deadline: 2017-06-22 15:39:39.0708859 +0000 GMT Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Created command: Z:\task_1498137226\command_000001_wrapper.bat Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Uploading artifact: public/logs/live.log Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Disk available: 104650854400 bytes Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Executing command 0: Z:\task_1498137226\command_000000_wrapper.bat Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [0 2 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 8000000000000018 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [8000000000000018 1 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 800000000000001B Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [800000000000001B 20 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 800000000001801B Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [800000000001801B 10 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 800000000001B01B Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerifyVersionInfoW with args: [C0422EC360 33 800000000001B01B] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call CreateProcessW with args: [0 C0424E62A0 0 0 1 614 0 C042398360 C0420D4070 C04237E6C0] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: time="2017-06-22T14:09:39Z" level=info msg="processInfo: 0xc042304020" Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call SetPriorityClass with args: [718 8000] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call ResumeThread with args: [618] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call GetProcessMemoryInfo with args: [718 C0424C4960 50] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call CloseHandle with args: [718] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 0 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Error when closing handle: The handle is invalid. Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Executing command 1: Z:\task_1498137226\command_000001_wrapper.bat Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [0 2 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 8000000000000018 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [8000000000000018 1 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 800000000000001B Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [800000000000001B 20 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 800000000001801B Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerSetConditionMask with args: [800000000001801B 10 3] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 800000000001B01B Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call VerifyVersionInfoW with args: [C0420F8480 33 800000000001B01B] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call CreateProcessW with args: [0 C04224CEA0 0 0 1 614 0 C042382A20 C0420E4310 C042212740] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: time="2017-06-22T14:09:39Z" level=info msg="processInfo: 0xc0424a0100" Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call SetPriorityClass with args: [70C 8000] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Making system call ResumeThread with args: [718] Jun 22 15:09:39 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com generic-worker: 2017/06/22 14:09:39 Result: 1 Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: loan request from agaynor@mozilla.com/https://keybase.io/alex_gaynor/pgp_keys.asc (Z:\task_1498137226) at 6/22/2017 2:09:39 PM detected at 6/22/2017 2:10:01 PM Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: begin Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\crash-stats-api.token, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\gapi.data, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\google-oauth-api.key, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\mozilla-api.key, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\mozilla-desktop-geoloc-api.key, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\mozilla-fennec-geoloc-api.key, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\oauth.txt, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\occ-installers.tok, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\builds\tc-sccache.boto, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\Users\Administrator\.ovpn, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\Users\Administrator\AppData\Roaming\Viscosity, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: path: C:\Windows\SysWOW64\config\systemprofile\AppData\Roaming\gnupg, deleted. Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-Secrets :: end Jun 22 15:10:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: begin Jun 22 15:10:03 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com HaltOnIdle: loaner state unknown Jun 22 15:10:23 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\atom, deleted. Jun 22 15:10:23 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\Comms, deleted. Jun 22 15:10:23 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\ConnectedDevicesPlatform, deleted. Jun 22 15:10:23 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\Administrator\AppData\Local\Microsoft. Jun 22 15:10:23 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\NVIDIA, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\Administrator\AppData\Local\Packages. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\PeerDistRepub, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\Programs, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\Publishers, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\SquirrelTemp, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\Temp, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Local\TileDataLayer, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Roaming\Adobe, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Roaming\Atom, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Roaming\Microsoft, deleted. Jun 22 15:10:24 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\Administrator\AppData\Roaming\Sublime Text 3, deleted. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\atom, deleted. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Local\Comms. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\ConnectedDevicesPlatform, deleted. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Local\Microsoft. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\Mozilla, deleted. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Local\NVIDIA. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Local\Packages. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\pip, deleted. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\Publishers, deleted. Jun 22 15:10:34 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\SquirrelTemp, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Local\Temp. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Local\TileDataLayer. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Local\VirtualStore, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Roaming\Adobe, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Roaming\Atom, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\GenericWorker\AppData\Roaming\Microsoft. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\GenericWorker\AppData\Roaming\Mozilla, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Local\Comms, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Local\ConnectedDevicesPlatform, deleted. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\inst\AppData\Local\Microsoft. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\inst\AppData\Local\Packages. Jun 22 15:10:35 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Local\Publishers, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Local\Temp, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Local\TileDataLayer, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Local\VirtualStore, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Roaming\Adobe, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\inst\AppData\Roaming\Microsoft, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\Comms, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\ConnectedDevicesPlatform, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\DBG, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\t-w1064-vanilla\AppData\Local\Microsoft. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\MicrosoftEdge, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\Microsoft_Corporation, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: failed to delete path: C:\Users\t-w1064-vanilla\AppData\Local\Packages. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\Publishers, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\Temp, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\TileDataLayer, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\VirtualStore, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Local\resmon.resmoncfg, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Roaming\Adobe, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: path: C:\Users\t-w1064-vanilla\AppData\Roaming\Microsoft, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-UserAppData :: end Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-InstanceConfig :: begin Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-InstanceConfig :: failed to delete path: C:\Users\inst. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-InstanceConfig :: failed to delete path: C:\Users\t-w1064-vanilla. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-InstanceConfig :: path: C:\log\*.zip, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-InstanceConfig :: path: C:\dsc\MozillaMaintenance, deleted. Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Remove-InstanceConfig :: end Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Get-GeneratedPassword :: begin Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Get-GeneratedPassword :: end Jun 22 15:10:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Set-Credentials :: begin Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Set-Credentials :: credentials set for user: Administrator. Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Set-Credentials :: end Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Get-GeneratedPassword :: begin Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Get-GeneratedPassword :: end Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Set-Credentials :: begin Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Set-Credentials :: credentials set for user: GenericWorker. Jun 22 15:10:37 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: Set-Credentials :: end Jun 22 15:10:40 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: credentials encrypted in task artefacts Jun 22 15:10:40 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com PrepLoaner: waiting for loan request task to complete Jun 22 15:12:03 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com HaltOnIdle: loaner state unknown Jun 22 15:14:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com HaltOnIdle: loaner state unknown Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: The DscTimer is running LCM method PerformRequiredConfigurationChecks with the flag set to 1. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: The local configuration manager started. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: Job {2D0A1711-5755-11E7-914D-0A7DC08084D2} : Operation Consistency Check or Pull started by user sid S-1-5-20 from computer NULL. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: Job {2D0A1711-5755-11E7-914D-0A7DC08084D2} : Details logging started to C:\Windows\System32\Configuration\ConfigurationStatus\{2D0A1711-5755-11E7-914D-0A7DC08084D2}-0.details.json. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: The DscTimer has successfully run LCM method PerformRequiredConfigurationChecks with flag 1. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: The DscTimer is running LCM method PerformRequiredConfigurationChecks with the flag set to 5. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: Job {2D0A1712-5755-11E7-914D-0A7DC08084D2} : Operation Consistency Check or Pull started by user sid S-1-5-20 from computer NULL. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: Job {2D0A1712-5755-11E7-914D-0A7DC08084D2} : Details logging started to C:\Windows\System32\Configuration\ConfigurationStatus\{2D0A1712-5755-11E7-914D-0A7DC08084D2}-0.details.json. Jun 22 15:15:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: The DscTimer has successfully run LCM method PerformRequiredConfigurationChecks with flag 5. Jun 22 15:15:36 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com Microsoft-Windows-DSC: The local configuration manager was shut down. Jun 22 15:16:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com HaltOnIdle: loaner state unknown Jun 22 15:18:02 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com HaltOnIdle: loaner state is unknown and has not been rectified since last check at 2:16:02 PM. instance will be terminated. Jun 22 15:18:03 i-0d8a4f93022943117.gecko-t-win10-64.use1.mozilla.com User32: The process C:\Windows\system32\shutdown.exe (I-0D8A4F9302294) has initiated the shutdown of computer I-0D8A4F9302294 on behalf of user NT AUTHORITY\SYSTEM for the following reason: Application: Maintenance (Planned) Reason Code: 0x80040001 Shutdown Type: shutdown Comment: HaltOnIdle :: loaner state unknown and unrectified
i think the gpg encryption error is getting swallowed here: https://github.com/mozilla-releng/OpenCloudConfig/blob/66fa595b4f55/userdata/PrepLoaner.ps1#L354-L356 the contents of the stdout and stderr files should tell us why the encryption has failed.
Alex, initially I thought that the problem might be more widespread, but others have reported that loaners are working for them. If you make another attempt to get a loaner and it fails, can you reopen this bug and attach the stdout and stderr files from the loan task artifacts, please?
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
Will do!
You need to log in before you can comment on or make changes to this bug.