[02:59:18.130] New invocation is queued and will start shortly
[02:59:19.284] Starting the invocation (attempt 1)
[02:59:19.361] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:59:19.364] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:59:19.365] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9048418014036678400",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"91b955a98e739b28139fd1e0802864be04ed9aae\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9048418014036678400",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/91b955a98e739b28139fd1e0802864be04ed9aae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91b955a98e739b28139fd1e0802864be04ed9aae",
"gitiles_ref:refs/heads/master"
]
}
[02:59:21.090] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1586919559462626",
"id": "8882987951975932368",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"91b955a98e739b28139fd1e0802864be04ed9aae\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1586919560814020",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/27549",
"builder:Deterministic Linux",
"buildset:commit/git/91b955a98e739b28139fd1e0802864be04ed9aae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91b955a98e739b28139fd1e0802864be04ed9aae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9048418014036678400",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8882987951975932368/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1586919560814170",
"url": "https://ci.chromium.org/b/8882987951975932368",
"utcnow_ts": "1586919561073722"
}
}
[02:59:21.090] Task URL: https://ci.chromium.org/b/8882987951975932368
[02:59:21.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:2:0) after 1m0s
[02:59:23.728] Received PubSub notification, asking Buildbucket for the build status
[02:59:23.817] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:21.223] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:2:0)
[03:00:21.223] Timer tick, asking Buildbucket for the build status
[03:00:21.570] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:21.570] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:4:0) after 1m0s
[03:01:21.944] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:4:0)
[03:01:21.944] Timer tick, asking Buildbucket for the build status
[03:01:22.051] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:22.051] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:5:0) after 1m0s
[03:02:22.220] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:5:0)
[03:02:22.220] Timer tick, asking Buildbucket for the build status
[03:02:22.373] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:22.373] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:6:0) after 1m0s
[03:03:22.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:6:0)
[03:03:22.367] Timer tick, asking Buildbucket for the build status
[03:03:22.661] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:22.661] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:7:0) after 1m0s
[03:04:22.820] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:7:0)
[03:04:22.820] Timer tick, asking Buildbucket for the build status
[03:04:23.118] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:23.118] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:8:0) after 1m0s
[03:05:23.119] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:8:0)
[03:05:23.119] Timer tick, asking Buildbucket for the build status
[03:05:23.241] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:23.241] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:9:0) after 1m0s
[03:06:23.244] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:9:0)
[03:06:23.244] Timer tick, asking Buildbucket for the build status
[03:06:23.353] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:23.353] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:10:0) after 1m0s
[03:07:23.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:10:0)
[03:07:23.435] Timer tick, asking Buildbucket for the build status
[03:07:23.560] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:23.560] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:11:0) after 1m0s
[03:08:23.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:11:0)
[03:08:23.718] Timer tick, asking Buildbucket for the build status
[03:08:23.838] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:23.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:12:0) after 1m0s
[03:09:23.919] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:12:0)
[03:09:23.919] Timer tick, asking Buildbucket for the build status
[03:09:24.014] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:24.014] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:13:0) after 1m0s
[03:10:24.031] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:13:0)
[03:10:24.031] Timer tick, asking Buildbucket for the build status
[03:10:24.208] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:24.208] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:14:0) after 1m0s
[03:11:24.365] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:14:0)
[03:11:24.365] Timer tick, asking Buildbucket for the build status
[03:11:24.520] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:24.520] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:15:0) after 1m0s
[03:12:24.579] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:15:0)
[03:12:24.579] Timer tick, asking Buildbucket for the build status
[03:12:24.714] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:24.714] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:16:0) after 1m0s
[03:13:24.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:16:0)
[03:13:24.880] Timer tick, asking Buildbucket for the build status
[03:13:25.084] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:25.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:17:0) after 1m0s
[03:14:25.171] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:17:0)
[03:14:25.171] Timer tick, asking Buildbucket for the build status
[03:14:25.464] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:25.464] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:18:0) after 1m0s
[03:15:25.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:18:0)
[03:15:25.519] Timer tick, asking Buildbucket for the build status
[03:15:25.831] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:25.831] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:19:0) after 1m0s
[03:16:25.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:19:0)
[03:16:25.906] Timer tick, asking Buildbucket for the build status
[03:16:26.094] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:20:0) after 1m0s
[03:17:26.131] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:20:0)
[03:17:26.131] Timer tick, asking Buildbucket for the build status
[03:17:26.394] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:26.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:21:0) after 1m0s
[03:18:26.450] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:21:0)
[03:18:26.450] Timer tick, asking Buildbucket for the build status
[03:18:26.551] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:26.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:22:0) after 1m0s
[03:19:26.594] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:22:0)
[03:19:26.594] Timer tick, asking Buildbucket for the build status
[03:19:26.703] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:26.703] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:23:0) after 1m0s
[03:20:26.814] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:23:0)
[03:20:26.814] Timer tick, asking Buildbucket for the build status
[03:20:26.988] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:26.988] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:24:0) after 1m0s
[03:21:27.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:24:0)
[03:21:27.143] Timer tick, asking Buildbucket for the build status
[03:21:27.438] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:27.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:25:0) after 1m0s
[03:22:27.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:25:0)
[03:22:27.502] Timer tick, asking Buildbucket for the build status
[03:22:27.663] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:27.663] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:26:0) after 1m0s
[03:23:27.683] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:26:0)
[03:23:27.683] Timer tick, asking Buildbucket for the build status
[03:23:27.996] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:27.996] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:27:0) after 1m0s
[03:24:28.052] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:27:0)
[03:24:28.052] Timer tick, asking Buildbucket for the build status
[03:24:28.439] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:28.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:28:0) after 1m0s
[03:25:28.562] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:28:0)
[03:25:28.562] Timer tick, asking Buildbucket for the build status
[03:25:28.722] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:28.722] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:29:0) after 1m0s
[03:26:28.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:29:0)
[03:26:28.778] Timer tick, asking Buildbucket for the build status
[03:26:28.918] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:28.918] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:30:0) after 1m0s
[03:27:29.014] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:30:0)
[03:27:29.014] Timer tick, asking Buildbucket for the build status
[03:27:29.287] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:29.287] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:31:0) after 1m0s
[03:28:29.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:31:0)
[03:28:29.422] Timer tick, asking Buildbucket for the build status
[03:28:29.903] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:29.903] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:32:0) after 1m0s
[03:29:30.152] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:32:0)
[03:29:30.152] Timer tick, asking Buildbucket for the build status
[03:29:30.266] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:30.266] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:33:0) after 1m0s
[03:30:30.387] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:33:0)
[03:30:30.387] Timer tick, asking Buildbucket for the build status
[03:30:31.439] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:31.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:34:0) after 1m0s
[03:31:31.621] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:34:0)
[03:31:31.621] Timer tick, asking Buildbucket for the build status
[03:31:31.859] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:31.859] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:35:0) after 1m0s
[03:32:31.891] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:35:0)
[03:32:31.891] Timer tick, asking Buildbucket for the build status
[03:32:32.143] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:32.143] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:36:0) after 1m0s
[03:33:32.229] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:36:0)
[03:33:32.229] Timer tick, asking Buildbucket for the build status
[03:33:32.385] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:32.385] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:37:0) after 1m0s
[03:34:32.403] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:37:0)
[03:34:32.403] Timer tick, asking Buildbucket for the build status
[03:34:32.528] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:32.528] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:38:0) after 1m0s
[03:35:32.541] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:38:0)
[03:35:32.541] Timer tick, asking Buildbucket for the build status
[03:35:32.819] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:32.819] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:39:0) after 1m0s
[03:36:32.881] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:39:0)
[03:36:32.881] Timer tick, asking Buildbucket for the build status
[03:36:32.997] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:32.997] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:40:0) after 1m0s
[03:37:33.042] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:40:0)
[03:37:33.042] Timer tick, asking Buildbucket for the build status
[03:37:33.121] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:33.122] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:41:0) after 1m0s
[03:38:33.146] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:41:0)
[03:38:33.146] Timer tick, asking Buildbucket for the build status
[03:38:33.423] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:33.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:42:0) after 1m0s
[03:39:33.444] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:42:0)
[03:39:33.444] Timer tick, asking Buildbucket for the build status
[03:39:33.710] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:43:0) after 1m0s
[03:40:33.775] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:43:0)
[03:40:33.775] Timer tick, asking Buildbucket for the build status
[03:40:34.377] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:34.377] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:44:0) after 1m0s
[03:41:34.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:44:0)
[03:41:34.413] Timer tick, asking Buildbucket for the build status
[03:41:34.698] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:34.698] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:45:0) after 1m0s
[03:42:34.745] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:45:0)
[03:42:34.745] Timer tick, asking Buildbucket for the build status
[03:42:34.854] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:34.854] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:46:0) after 1m0s
[03:43:34.911] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:46:0)
[03:43:34.911] Timer tick, asking Buildbucket for the build status
[03:43:35.064] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:35.064] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:47:0) after 1m0s
[03:44:35.225] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:47:0)
[03:44:35.225] Timer tick, asking Buildbucket for the build status
[03:44:35.386] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:35.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:48:0) after 1m0s
[03:45:35.464] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:48:0)
[03:45:35.464] Timer tick, asking Buildbucket for the build status
[03:45:36.038] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:36.038] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:49:0) after 1m0s
[03:46:36.039] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:49:0)
[03:46:36.039] Timer tick, asking Buildbucket for the build status
[03:46:36.250] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:36.250] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:50:0) after 1m0s
[03:47:36.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:50:0)
[03:47:36.270] Timer tick, asking Buildbucket for the build status
[03:47:36.421] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:36.421] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:51:0) after 1m0s
[03:48:36.445] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:51:0)
[03:48:36.445] Timer tick, asking Buildbucket for the build status
[03:48:36.683] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:36.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:52:0) after 1m0s
[03:49:36.832] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:52:0)
[03:49:36.832] Timer tick, asking Buildbucket for the build status
[03:49:37.134] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:37.134] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:53:0) after 1m0s
[03:50:37.232] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:53:0)
[03:50:37.232] Timer tick, asking Buildbucket for the build status
[03:50:38.343] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:38.344] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:54:0) after 1m0s
[03:51:38.372] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:54:0)
[03:51:38.372] Timer tick, asking Buildbucket for the build status
[03:51:38.554] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:38.554] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:55:0) after 1m0s
[03:52:38.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:55:0)
[03:52:38.650] Timer tick, asking Buildbucket for the build status
[03:52:38.811] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:38.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:56:0) after 1m0s
[03:53:38.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:56:0)
[03:53:38.822] Timer tick, asking Buildbucket for the build status
[03:53:38.947] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:38.947] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:57:0) after 1m0s
[03:54:39.001] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:57:0)
[03:54:39.001] Timer tick, asking Buildbucket for the build status
[03:54:39.109] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:39.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:58:0) after 1m0s
[03:55:39.152] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:58:0)
[03:55:39.152] Timer tick, asking Buildbucket for the build status
[03:55:39.457] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:39.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:59:0) after 1m0s
[03:56:39.481] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:59:0)
[03:56:39.481] Timer tick, asking Buildbucket for the build status
[03:56:39.614] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:39.614] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:60:0) after 1m0s
[03:57:39.640] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:60:0)
[03:57:39.640] Timer tick, asking Buildbucket for the build status
[03:57:39.772] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:39.772] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:61:0) after 1m0s
[03:58:39.807] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:61:0)
[03:58:39.807] Timer tick, asking Buildbucket for the build status
[03:58:39.925] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:39.925] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:62:0) after 1m0s
[03:59:39.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:62:0)
[03:59:39.981] Timer tick, asking Buildbucket for the build status
[03:59:40.107] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.107] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:63:0) after 1m0s
[04:00:40.220] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:63:0)
[04:00:40.220] Timer tick, asking Buildbucket for the build status
[04:00:40.691] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:40.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:64:0) after 1m0s
[04:01:40.716] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:64:0)
[04:01:40.716] Timer tick, asking Buildbucket for the build status
[04:01:41.053] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:41.053] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:65:0) after 1m0s
[04:02:41.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:65:0)
[04:02:41.143] Timer tick, asking Buildbucket for the build status
[04:02:41.431] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:41.431] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:66:0) after 1m0s
[04:03:41.484] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:66:0)
[04:03:41.484] Timer tick, asking Buildbucket for the build status
[04:03:41.850] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:41.850] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:67:0) after 1m0s
[04:04:41.839] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:67:0)
[04:04:41.839] Timer tick, asking Buildbucket for the build status
[04:04:42.027] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:42.027] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:68:0) after 1m0s
[04:05:42.082] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:68:0)
[04:05:42.082] Timer tick, asking Buildbucket for the build status
[04:05:43.525] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:43.525] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:69:0) after 1m0s
[04:06:43.566] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:69:0)
[04:06:43.566] Timer tick, asking Buildbucket for the build status
[04:06:44.288] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:44.288] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:70:0) after 1m0s
[04:07:44.381] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:70:0)
[04:07:44.381] Timer tick, asking Buildbucket for the build status
[04:07:44.518] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:44.518] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:71:0) after 1m0s
[04:08:44.567] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:71:0)
[04:08:44.567] Timer tick, asking Buildbucket for the build status
[04:08:45.072] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:45.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:72:0) after 1m0s
[04:09:45.109] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:72:0)
[04:09:45.109] Timer tick, asking Buildbucket for the build status
[04:09:45.327] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:73:0) after 1m0s
[04:10:45.369] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:73:0)
[04:10:45.369] Timer tick, asking Buildbucket for the build status
[04:10:45.780] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:45.780] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:74:0) after 1m0s
[04:11:45.851] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:74:0)
[04:11:45.851] Timer tick, asking Buildbucket for the build status
[04:11:46.118] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:46.118] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:75:0) after 1m0s
[04:12:46.155] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:75:0)
[04:12:46.155] Timer tick, asking Buildbucket for the build status
[04:12:46.581] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:46.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:76:0) after 1m0s
[04:13:46.580] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:76:0)
[04:13:46.580] Timer tick, asking Buildbucket for the build status
[04:13:46.697] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:46.697] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:77:0) after 1m0s
[04:14:46.722] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:77:0)
[04:14:46.722] Timer tick, asking Buildbucket for the build status
[04:14:46.858] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:46.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:78:0) after 1m0s
[04:15:46.895] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:78:0)
[04:15:46.895] Timer tick, asking Buildbucket for the build status
[04:15:49.567] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:49.567] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:79:0) after 1m0s
[04:16:49.593] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:79:0)
[04:16:49.593] Timer tick, asking Buildbucket for the build status
[04:16:49.721] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:49.721] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:80:0) after 1m0s
[04:17:49.757] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:80:0)
[04:17:49.757] Timer tick, asking Buildbucket for the build status
[04:17:49.896] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:49.896] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:81:0) after 1m0s
[04:18:49.965] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:81:0)
[04:18:49.965] Timer tick, asking Buildbucket for the build status
[04:18:50.109] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:50.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:82:0) after 1m0s
[04:19:50.189] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:82:0)
[04:19:50.189] Timer tick, asking Buildbucket for the build status
[04:19:50.300] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:50.300] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:83:0) after 1m0s
[04:20:50.411] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:83:0)
[04:20:50.411] Timer tick, asking Buildbucket for the build status
[04:20:55.282] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:55.282] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:84:0) after 1m0s
[04:21:55.360] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:84:0)
[04:21:55.360] Timer tick, asking Buildbucket for the build status
[04:21:58.598] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:58.598] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:85:0) after 1m0s
[04:22:58.656] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:85:0)
[04:22:58.656] Timer tick, asking Buildbucket for the build status
[04:22:58.798] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:58.798] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:86:0) after 1m0s
[04:23:58.838] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:86:0)
[04:23:58.838] Timer tick, asking Buildbucket for the build status
[04:23:59.092] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:59.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:87:0) after 1m0s
[04:24:59.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:87:0)
[04:24:59.128] Timer tick, asking Buildbucket for the build status
[04:24:59.498] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:59.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:88:0) after 1m0s
[04:25:59.533] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:88:0)
[04:25:59.533] Timer tick, asking Buildbucket for the build status
[04:26:00.255] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:00.255] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:89:0) after 1m0s
[04:27:00.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:89:0)
[04:27:00.299] Timer tick, asking Buildbucket for the build status
[04:27:00.424] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:00.424] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:90:0) after 1m0s
[04:28:00.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:90:0)
[04:28:00.459] Timer tick, asking Buildbucket for the build status
[04:28:00.699] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:00.699] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:91:0) after 1m0s
[04:29:01.148] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:91:0)
[04:29:01.148] Timer tick, asking Buildbucket for the build status
[04:29:01.353] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:01.353] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:92:0) after 1m0s
[04:30:01.449] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:92:0)
[04:30:01.449] Timer tick, asking Buildbucket for the build status
[04:30:01.652] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:01.652] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:93:0) after 1m0s
[04:31:01.716] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:93:0)
[04:31:01.716] Timer tick, asking Buildbucket for the build status
[04:31:03.179] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:03.179] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:94:0) after 1m0s
[04:32:03.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:94:0)
[04:32:03.219] Timer tick, asking Buildbucket for the build status
[04:32:03.478] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:03.478] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:95:0) after 1m0s
[04:33:03.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:95:0)
[04:33:03.524] Timer tick, asking Buildbucket for the build status
[04:33:03.657] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:03.657] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:96:0) after 1m0s
[04:34:03.772] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:96:0)
[04:34:03.772] Timer tick, asking Buildbucket for the build status
[04:34:04.323] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:04.323] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:97:0) after 1m0s
[04:35:04.343] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:97:0)
[04:35:04.343] Timer tick, asking Buildbucket for the build status
[04:35:04.957] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:04.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:98:0) after 1m0s
[04:36:05.126] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:98:0)
[04:36:05.126] Timer tick, asking Buildbucket for the build status
[04:36:05.590] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:05.590] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:99:0) after 1m0s
[04:37:05.591] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:99:0)
[04:37:05.591] Timer tick, asking Buildbucket for the build status
[04:37:05.939] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:05.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:100:0) after 1m0s
[04:38:05.984] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:100:0)
[04:38:05.984] Timer tick, asking Buildbucket for the build status
[04:38:06.353] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:06.353] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:101:0) after 1m0s
[04:39:06.446] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:101:0)
[04:39:06.446] Timer tick, asking Buildbucket for the build status
[04:39:06.635] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:06.635] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:102:0) after 1m0s
[04:40:06.779] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:102:0)
[04:40:06.779] Timer tick, asking Buildbucket for the build status
[04:40:07.209] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:07.209] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:103:0) after 1m0s
[04:41:07.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:103:0)
[04:41:07.262] Timer tick, asking Buildbucket for the build status
[04:41:07.556] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:07.556] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:104:0) after 1m0s
[04:42:07.607] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:104:0)
[04:42:07.607] Timer tick, asking Buildbucket for the build status
[04:42:07.936] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:07.936] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:105:0) after 1m0s
[04:43:08.018] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:105:0)
[04:43:08.018] Timer tick, asking Buildbucket for the build status
[04:43:08.390] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:08.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:106:0) after 1m0s
[04:44:08.472] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:106:0)
[04:44:08.472] Timer tick, asking Buildbucket for the build status
[04:44:08.652] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:08.652] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:107:0) after 1m0s
[04:45:08.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:107:0)
[04:45:08.676] Timer tick, asking Buildbucket for the build status
[04:45:10.963] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:10.963] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:108:0) after 1m0s
[04:46:11.162] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:108:0)
[04:46:11.162] Timer tick, asking Buildbucket for the build status
[04:46:11.432] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:11.432] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:109:0) after 1m0s
[04:47:11.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:109:0)
[04:47:11.555] Timer tick, asking Buildbucket for the build status
[04:47:11.959] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:11.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:110:0) after 1m0s
[04:48:12.085] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:110:0)
[04:48:12.085] Timer tick, asking Buildbucket for the build status
[04:48:12.280] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:12.280] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:111:0) after 1m0s
[04:49:12.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:111:0)
[04:49:12.299] Timer tick, asking Buildbucket for the build status
[04:49:12.594] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:12.594] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:112:0) after 1m0s
[04:50:12.685] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:112:0)
[04:50:12.685] Timer tick, asking Buildbucket for the build status
[04:50:12.826] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:12.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:113:0) after 1m0s
[04:51:12.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:113:0)
[04:51:12.849] Timer tick, asking Buildbucket for the build status
[04:51:12.988] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:12.988] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:114:0) after 1m0s
[04:52:13.073] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:114:0)
[04:52:13.073] Timer tick, asking Buildbucket for the build status
[04:52:13.387] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:13.387] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:115:0) after 1m0s
[04:53:13.480] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:115:0)
[04:53:13.480] Timer tick, asking Buildbucket for the build status
[04:53:13.638] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:13.638] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:116:0) after 1m0s
[04:54:13.766] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:116:0)
[04:54:13.766] Timer tick, asking Buildbucket for the build status
[04:54:13.850] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:13.850] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:117:0) after 1m0s
[04:55:13.903] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:117:0)
[04:55:13.903] Timer tick, asking Buildbucket for the build status
[04:55:14.094] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:14.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:118:0) after 1m0s
[04:56:14.242] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:118:0)
[04:56:14.242] Timer tick, asking Buildbucket for the build status
[04:56:14.957] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:14.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:119:0) after 1m0s
[04:57:15.179] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:119:0)
[04:57:15.179] Timer tick, asking Buildbucket for the build status
[04:57:15.334] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:15.334] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:120:0) after 1m0s
[04:58:15.387] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:120:0)
[04:58:15.387] Timer tick, asking Buildbucket for the build status
[04:58:15.509] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:15.509] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:121:0) after 1m0s
[04:59:15.554] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:121:0)
[04:59:15.554] Timer tick, asking Buildbucket for the build status
[04:59:15.803] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:15.803] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:122:0) after 1m0s
[05:00:15.792] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:122:0)
[05:00:15.792] Timer tick, asking Buildbucket for the build status
[05:00:17.226] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:17.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:123:0) after 1m0s
[05:01:17.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:123:0)
[05:01:17.276] Timer tick, asking Buildbucket for the build status
[05:01:17.373] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:17.373] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:124:0) after 1m0s
[05:02:17.429] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:124:0)
[05:02:17.429] Timer tick, asking Buildbucket for the build status
[05:02:17.484] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:17.484] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:125:0) after 1m0s
[05:03:17.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:125:0)
[05:03:17.549] Timer tick, asking Buildbucket for the build status
[05:03:17.696] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:17.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:126:0) after 1m0s
[05:04:17.800] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:126:0)
[05:04:17.800] Timer tick, asking Buildbucket for the build status
[05:04:17.927] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:17.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:127:0) after 1m0s
[05:05:17.982] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:127:0)
[05:05:17.982] Timer tick, asking Buildbucket for the build status
[05:05:18.335] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:18.335] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:128:0) after 1m0s
[05:06:18.505] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:128:0)
[05:06:18.506] Timer tick, asking Buildbucket for the build status
[05:06:18.683] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:18.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:129:0) after 1m0s
[05:07:18.702] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:129:0)
[05:07:18.702] Timer tick, asking Buildbucket for the build status
[05:07:18.838] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:18.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:130:0) after 1m0s
[05:08:18.901] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:130:0)
[05:08:18.901] Timer tick, asking Buildbucket for the build status
[05:08:19.315] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:19.315] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:131:0) after 1m0s
[05:09:19.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:131:0)
[05:09:19.453] Timer tick, asking Buildbucket for the build status
[05:09:19.577] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:19.577] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:132:0) after 1m0s
[05:10:19.587] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:132:0)
[05:10:19.587] Timer tick, asking Buildbucket for the build status
[05:10:19.726] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:19.726] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:133:0) after 1m0s
[05:11:19.763] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:133:0)
[05:11:19.763] Timer tick, asking Buildbucket for the build status
[05:11:20.049] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:20.049] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:134:0) after 1m0s
[05:12:20.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:134:0)
[05:12:20.092] Timer tick, asking Buildbucket for the build status
[05:12:20.221] Build 8882987951975932368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:20.221] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9048418014036678400:135:0) after 1m0s
[05:13:16.939] Received PubSub notification, asking Buildbucket for the build status
[05:13:16.995] Build 8882987951975932368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:13:16.995] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1586927596316551",
"created_by": "project:chromium",
"created_ts": "1586919559462626",
"id": "8882987951975932368",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"91b955a98e739b28139fd1e0802864be04ed9aae\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-98-eb9v\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 27549, \"got_angle_revision\": \"6e0d718a48d8fb5e66a6f8a1838adbf8c7f5ff4c\", \"got_dawn_revision\": \"beaaa5a601408355ac3c92b05dae208f335d5f55\", \"got_nacl_revision\": \"d304d90ecc17351ce0fdab3e7452052a469c0976\", \"got_revision\": \"91b955a98e739b28139fd1e0802864be04ed9aae\", \"got_revision_cp\": \"refs/heads/master@{#759117}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"9418b511c73bc2b079d8b8d8d6fcb5d40b814159\", \"got_v8_revision\": \"1eaee9392eb6dc812d731479c2a392af6518dc5b\", \"got_v8_revision_cp\": \"refs/heads/8.4.60@{#1}\", \"got_webrtc_revision\": \"2136b569eb2eec25da9483f7ca92a04a168a4f14\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31071}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"91b955a98e739b28139fd1e0802864be04ed9aae\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_31f9c608b916ebd07e1cfec73b2b8101f9550df8735b67c11bca9750a3699f79_v2\", \"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_51404ba3b6f05ce141d384d3296cd5be42dd7afdebed1d32389a1e1f727b3be5_v2\", \"builder_71ab4550ecc1db180544ee9bd5ebac58222d39ccc6e59d756fcde46903b3067c_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-98-eb9v\"], \"image\": [\"chrome-xenial-20040500-42c1ff87780\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4967-6d1ba8b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1586919563104768",
"status": "COMPLETED",
"status_changed_ts": "1586927596661625",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/27549",
"builder:Deterministic Linux",
"buildset:commit/git/91b955a98e739b28139fd1e0802864be04ed9aae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91b955a98e739b28139fd1e0802864be04ed9aae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9048418014036678400",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8882987951975932368/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4b949860d590d110",
"user_agent:luci-scheduler"
],
"updated_ts": "1586927596669608",
"url": "https://ci.chromium.org/b/8882987951975932368",
"utcnow_ts": "1586927596987005"
}
}
[05:13:16.995] Invocation finished in 2h13m58.882028895s with status SUCCEEDED