[15:33:58.826] New invocation is queued and will start shortly
[15:33:59.916] Starting the invocation (attempt 1)
[15:33:59.964] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:33:59.964] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:33:59.964] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029526364784808576",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6ebc098eb363cb08cacbe543c0bca563c7d5d2bc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029526364784808576",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/6ebc098eb363cb08cacbe543c0bca563c7d5d2bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ebc098eb363cb08cacbe543c0bca563c7d5d2bc",
"gitiles_ref:refs/heads/master"
]
}
[15:34:02.002] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604936040442911",
"id": "8864096302415764000",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ebc098eb363cb08cacbe543c0bca563c7d5d2bc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604936040880351",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42720",
"builder:linux-chromeos-rel",
"buildset:commit/git/6ebc098eb363cb08cacbe543c0bca563c7d5d2bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ebc098eb363cb08cacbe543c0bca563c7d5d2bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029526364784808576",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864096302415764000/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1604936040880515",
"url": "https://ci.chromium.org/b/8864096302415764000",
"utcnow_ts": "1604936041992539"
}
}
[15:34:02.002] Task URL: https://ci.chromium.org/b/8864096302415764000
[15:34:02.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:2:0) after 1m0s
[15:34:57.060] Received PubSub notification, asking Buildbucket for the build status
[15:34:57.136] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:02.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:2:0)
[15:35:02.120] Timer tick, asking Buildbucket for the build status
[15:35:02.193] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:02.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:4:0) after 1m0s
[15:36:02.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:4:0)
[15:36:02.248] Timer tick, asking Buildbucket for the build status
[15:36:02.444] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:02.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:5:0) after 1m0s
[15:37:02.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:5:0)
[15:37:02.496] Timer tick, asking Buildbucket for the build status
[15:37:02.607] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:02.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:6:0) after 1m0s
[15:38:03.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:6:0)
[15:38:03.192] Timer tick, asking Buildbucket for the build status
[15:38:03.308] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:03.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:7:0) after 1m0s
[15:39:03.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:7:0)
[15:39:03.464] Timer tick, asking Buildbucket for the build status
[15:39:03.894] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:03.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:8:0) after 1m0s
[15:40:05.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:8:0)
[15:40:05.078] Timer tick, asking Buildbucket for the build status
[15:40:05.494] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:05.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:9:0) after 1m0s
[15:41:05.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:9:0)
[15:41:05.700] Timer tick, asking Buildbucket for the build status
[15:41:05.842] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:05.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:10:0) after 1m0s
[15:42:05.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:10:0)
[15:42:05.892] Timer tick, asking Buildbucket for the build status
[15:42:05.974] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:05.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:11:0) after 1m0s
[15:43:06.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:11:0)
[15:43:06.012] Timer tick, asking Buildbucket for the build status
[15:43:06.481] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:06.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:12:0) after 1m0s
[15:44:06.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:12:0)
[15:44:06.612] Timer tick, asking Buildbucket for the build status
[15:44:06.770] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:06.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:13:0) after 1m0s
[15:45:06.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:13:0)
[15:45:06.864] Timer tick, asking Buildbucket for the build status
[15:45:07.022] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:07.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:14:0) after 1m0s
[15:46:07.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:14:0)
[15:46:07.054] Timer tick, asking Buildbucket for the build status
[15:46:07.639] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:07.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:15:0) after 1m0s
[15:47:07.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:15:0)
[15:47:07.775] Timer tick, asking Buildbucket for the build status
[15:47:08.139] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:08.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:16:0) after 1m0s
[15:48:08.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:16:0)
[15:48:08.209] Timer tick, asking Buildbucket for the build status
[15:48:08.294] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:08.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:17:0) after 1m0s
[15:49:08.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:17:0)
[15:49:08.325] Timer tick, asking Buildbucket for the build status
[15:49:08.601] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:08.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:18:0) after 1m0s
[15:50:08.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:18:0)
[15:50:08.734] Timer tick, asking Buildbucket for the build status
[15:50:08.800] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:08.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:19:0) after 1m0s
[15:51:08.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:19:0)
[15:51:08.881] Timer tick, asking Buildbucket for the build status
[15:51:08.962] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:08.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:20:0) after 1m0s
[15:52:08.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:20:0)
[15:52:08.960] Timer tick, asking Buildbucket for the build status
[15:52:09.045] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:09.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:21:0) after 1m0s
[15:53:09.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:21:0)
[15:53:09.068] Timer tick, asking Buildbucket for the build status
[15:53:09.267] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:09.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:22:0) after 1m0s
[15:54:09.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:22:0)
[15:54:09.312] Timer tick, asking Buildbucket for the build status
[15:54:09.431] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:09.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:23:0) after 1m0s
[15:55:09.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:23:0)
[15:55:09.475] Timer tick, asking Buildbucket for the build status
[15:55:09.549] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:09.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:24:0) after 1m0s
[15:56:09.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:24:0)
[15:56:09.638] Timer tick, asking Buildbucket for the build status
[15:56:09.723] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:09.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:25:0) after 1m0s
[15:57:09.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:25:0)
[15:57:09.734] Timer tick, asking Buildbucket for the build status
[15:57:09.892] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:09.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:26:0) after 1m0s
[15:58:09.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:26:0)
[15:58:09.950] Timer tick, asking Buildbucket for the build status
[15:58:10.098] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:10.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:27:0) after 1m0s
[15:59:10.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:27:0)
[15:59:10.266] Timer tick, asking Buildbucket for the build status
[15:59:10.955] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:10.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:28:0) after 1m0s
[16:00:11.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:28:0)
[16:00:11.082] Timer tick, asking Buildbucket for the build status
[16:00:11.819] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:11.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:29:0) after 1m0s
[16:01:11.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:29:0)
[16:01:11.848] Timer tick, asking Buildbucket for the build status
[16:01:11.947] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:11.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:30:0) after 1m0s
[16:02:11.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:30:0)
[16:02:11.979] Timer tick, asking Buildbucket for the build status
[16:02:12.104] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:12.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:31:0) after 1m0s
[16:03:12.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:31:0)
[16:03:12.095] Timer tick, asking Buildbucket for the build status
[16:03:12.177] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:12.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:32:0) after 1m0s
[16:04:12.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:32:0)
[16:04:12.289] Timer tick, asking Buildbucket for the build status
[16:04:12.425] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:12.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:33:0) after 1m0s
[16:05:12.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:33:0)
[16:05:12.670] Timer tick, asking Buildbucket for the build status
[16:05:13.133] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:13.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:34:0) after 1m0s
[16:06:13.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:34:0)
[16:06:13.198] Timer tick, asking Buildbucket for the build status
[16:06:13.274] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:13.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:35:0) after 1m0s
[16:07:13.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:35:0)
[16:07:13.315] Timer tick, asking Buildbucket for the build status
[16:07:13.480] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:13.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:36:0) after 1m0s
[16:08:13.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:36:0)
[16:08:13.694] Timer tick, asking Buildbucket for the build status
[16:08:14.064] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:14.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:37:0) after 1m0s
[16:09:14.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:37:0)
[16:09:14.189] Timer tick, asking Buildbucket for the build status
[16:09:15.354] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:15.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:38:0) after 1m0s
[16:10:15.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:38:0)
[16:10:15.437] Timer tick, asking Buildbucket for the build status
[16:10:15.711] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:15.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:39:0) after 1m0s
[16:11:15.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:39:0)
[16:11:15.901] Timer tick, asking Buildbucket for the build status
[16:11:16.022] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:16.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:40:0) after 1m0s
[16:12:16.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:40:0)
[16:12:16.185] Timer tick, asking Buildbucket for the build status
[16:12:16.472] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:16.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:41:0) after 1m0s
[16:13:16.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:41:0)
[16:13:16.631] Timer tick, asking Buildbucket for the build status
[16:13:16.705] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:16.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:42:0) after 1m0s
[16:14:16.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:42:0)
[16:14:16.824] Timer tick, asking Buildbucket for the build status
[16:14:16.946] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:16.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:43:0) after 1m0s
[16:15:16.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:43:0)
[16:15:16.973] Timer tick, asking Buildbucket for the build status
[16:15:17.044] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:17.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:44:0) after 1m0s
[16:16:17.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:44:0)
[16:16:17.161] Timer tick, asking Buildbucket for the build status
[16:16:17.242] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:17.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:45:0) after 1m0s
[16:17:17.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:45:0)
[16:17:17.273] Timer tick, asking Buildbucket for the build status
[16:17:17.389] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:17.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:46:0) after 1m0s
[16:18:17.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:46:0)
[16:18:17.551] Timer tick, asking Buildbucket for the build status
[16:18:18.296] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:18.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:47:0) after 1m0s
[16:19:18.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:47:0)
[16:19:18.463] Timer tick, asking Buildbucket for the build status
[16:19:18.942] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:18.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:48:0) after 1m0s
[16:20:19.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:48:0)
[16:20:19.094] Timer tick, asking Buildbucket for the build status
[16:20:19.239] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:19.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:49:0) after 1m0s
[16:21:19.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:49:0)
[16:21:19.486] Timer tick, asking Buildbucket for the build status
[16:21:19.637] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:19.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:50:0) after 1m0s
[16:22:19.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:50:0)
[16:22:19.923] Timer tick, asking Buildbucket for the build status
[16:22:20.234] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:20.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:51:0) after 1m0s
[16:23:20.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:51:0)
[16:23:20.304] Timer tick, asking Buildbucket for the build status
[16:23:20.441] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:20.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:52:0) after 1m0s
[16:24:20.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:52:0)
[16:24:20.644] Timer tick, asking Buildbucket for the build status
[16:24:20.836] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:20.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:53:0) after 1m0s
[16:25:20.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:53:0)
[16:25:20.924] Timer tick, asking Buildbucket for the build status
[16:25:21.146] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:21.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:54:0) after 1m0s
[16:26:21.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:54:0)
[16:26:21.311] Timer tick, asking Buildbucket for the build status
[16:26:21.433] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:21.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:55:0) after 1m0s
[16:27:21.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:55:0)
[16:27:21.650] Timer tick, asking Buildbucket for the build status
[16:27:21.782] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:21.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:56:0) after 1m0s
[16:28:21.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:56:0)
[16:28:21.805] Timer tick, asking Buildbucket for the build status
[16:28:21.988] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:21.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:57:0) after 1m0s
[16:29:22.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:57:0)
[16:29:22.042] Timer tick, asking Buildbucket for the build status
[16:29:22.216] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:22.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:58:0) after 1m0s
[16:30:22.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:58:0)
[16:30:22.506] Timer tick, asking Buildbucket for the build status
[16:30:22.912] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:22.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:59:0) after 1m0s
[16:31:22.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:59:0)
[16:31:22.982] Timer tick, asking Buildbucket for the build status
[16:31:23.142] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:23.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:60:0) after 1m0s
[16:32:23.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:60:0)
[16:32:23.232] Timer tick, asking Buildbucket for the build status
[16:32:23.437] Build 8864096302415764000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:23.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029526364784808576:61:0) after 1m0s
[16:32:54.949] Received PubSub notification, asking Buildbucket for the build status
[16:32:55.281] Build 8864096302415764000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:32:55.281] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604939573104873",
"created_by": "project:chromium",
"created_ts": "1604936040442911",
"id": "8864096302415764000",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ebc098eb363cb08cacbe543c0bca563c7d5d2bc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"6ebc098eb363cb08cacbe543c0bca563c7d5d2bc\", \"project\": \"chromium/src\"}], \"$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-central1-b-8-62-jc0p\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42720, \"got_angle_revision\": \"40c5e53e7bbab464d547daedd3200c7240835e8d\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"6ebc098eb363cb08cacbe543c0bca563c7d5d2bc\", \"got_revision_cp\": \"refs/heads/master@{#825340}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"63061fa20129ce03607682bd628f81d220aaf3cb\", \"got_v8_revision_cp\": \"refs/heads/8.8.259@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ebc098eb363cb08cacbe543c0bca563c7d5d2bc\", \"swarm_hashes_refs/heads/master(at){#825340}_without_patch\": {\"absl_hardening_tests\": \"5cf8548fdf5883830b1823d60d8e32d0609e1000\", \"accessibility_unittests\": \"8aaded740cada78b7f7efc594db8ec436e3ee84a\", \"angle_unittests\": \"12d9d9fb11aa7a18a8ca996c2b2428e896e971c4\", \"app_list_unittests\": \"7e81272cf80d011a7ce4667b90f66eab8c233398\", \"app_shell_unittests\": \"6b74a2a920158821bcd56df2484e28e660eecbb2\", \"ash_unittests\": \"ba3e30295e4938c159c954524a35ecd4793185f6\", \"aura_unittests\": \"0d60ecc9d291e8a0b6ef024340559e0dd3e2f44c\", \"base_unittests\": \"558236ecefff56efc6db835f5d4b8baa456a64d6\", \"base_util_unittests\": \"fc88f4085b2ac870a6882ec27a38474d2784ae4d\", \"blink_common_unittests\": \"68ef8fb2b6fa4f296567fe9c971d3e0e8c8712b9\", \"blink_fuzzer_unittests\": \"143149c379c8d1a6f69b34cba226280a522f065a\", \"blink_heap_unittests\": \"2f68a6cb00c282eca0f27b94aab893f4e34591f8\", \"blink_platform_unittests\": \"6539f82138c511abd4271869dae4dbcb4c99b0ce\", \"blink_unittests\": \"66ccf590c7ae6832f7d9cde58a962e01860699ad\", \"boringssl_crypto_tests\": \"e99bc3ef7cce01970d721ef22f7064aaa9ebeb29\", \"boringssl_ssl_tests\": \"5ad12f3267be23f5c172440092f6540b9d69e79f\", \"browser_tests\": \"c9530912a572847b9f3376551ce01fd664a004fa\", \"capture_unittests\": \"67799870776be8f01d11b1e94fc162161306ce90\", \"cast_unittests\": \"148e5d8586f5bdfe998919195a82756c3a0b3ae2\", \"cc_unittests\": \"62a2ad4e02a600467f3f30071c03d3164f9e8eab\", \"chrome_app_unittests\": \"1c7ba49cac37601b316e7e9143d74268d41dd6b7\", \"chromedriver_unittests\": \"b0bcc3a443dedd9ecb2b1a7a24d5b76068dd78d9\", \"chromeos_components_unittests\": \"0257a2194b0a9321acdc09803a7656eaff0f7de7\", \"chromeos_unittests\": \"2adb1e4866d517907d9829979448f96500c9101e\", \"components_browsertests\": \"b1ab97f76ce97f59f6264d4a3fa3499cb601e5f2\", \"components_unittests\": \"30c76e93ea6882ca681a5e3f26379687256ade47\", \"compositor_unittests\": \"82a8d90a0a349483f9a02d32189a9cedde871fc7\", \"content_browsertests\": \"54053ba5e0950e7c8a3eec0732a65b7a928c2e78\", \"content_unittests\": \"3e38180938d17d2e31619da4f29dd63329dbadd5\", \"crypto_unittests\": \"ec573648c50f3780cafb2b0a851b5668fc495668\", \"dbus_unittests\": \"fdb4032f6f6d5ef927c41e70d29fec5f1efabe24\", \"device_unittests\": \"364329602a7e7aca4835ed066c429091ee94fe25\", \"display_unittests\": \"025d806443ce7b4d52d7aecfee34503f91f5107a\", \"events_unittests\": \"404dc9afd49ad6e30bde9e4efbda1be7417832a8\", \"exo_unittests\": \"ef3aa6d636182eb4f77708312bcbfcc56585860d\", \"extensions_browsertests\": \"2bc79f3464533a372c6774266b35ddddb6e34b53\", \"extensions_unittests\": \"fb8de6858b8e98a155fdb0285e8c9bbb9f0cf071\", \"filesystem_service_unittests\": \"789a5e12cd06139580816bc5b2207f1986c4c1dd\", \"gcm_unit_tests\": \"24cf5def0636f707bfb38799315350933eccfebb\", \"gfx_unittests\": \"799ad961badd300614d99c7c4ffd5110085392f3\", \"gin_unittests\": \"6bc2ce824497d2ef4b7c1b7f0d03e327eeaddfb1\", \"gl_unittests_ozone\": \"b37ec960102613661f75c1417d27a9914cf00e69\", \"google_apis_unittests\": \"0dd1fa66cee6b24aa279e266bd1d3c2a00c3eec4\", \"gpu_unittests\": \"1a602d31a2cc2e3c918c669f3947e50b3044b5bb\", \"gwp_asan_unittests\": \"b59c6e96a54857e9b3394d3da14848b87e6f0eaf\", \"interactive_ui_tests\": \"d5fc8fed110e69bbda0778ff8c5c64d28f460c56\", \"ipc_tests\": \"0463c9a0e153bf56c662323827a29f407074ce80\", \"jingle_unittests\": \"687b4fd7adcc7b9a0e882acb7f6943972428987a\", \"keyboard_unittests\": \"20b7f0fbe147cf3955e12718b431f9fd771f233f\", \"latency_unittests\": \"bb53ee09e72648639149e989b51d22eca9132d01\", \"libjingle_xmpp_unittests\": \"c0b31692d067ce2b1670abee65724326652bc492\", \"liburlpattern_unittests\": \"ba6825c71aa24f0e29a7c19717f1f98ad45e73ad\", \"media_blink_unittests\": \"96e30d4130ee3b6dfe1d591d4a42d2333671f950\", \"media_unittests\": \"3c414a3bb0d849a2689008fa416fcbc27e339c1b\", \"message_center_unittests\": \"4de69828da8cb7b0e75943344ded609127c63680\", \"midi_unittests\": \"3c4da349d4effa5f4c40f054faab57689c1c7e85\", \"mojo_core_unittests\": \"8d7d1b7d7ce3180f10a9b6517cb09ff70da98009\", \"mojo_unittests\": \"3ed7cf72a03a97cd4d3652d0c6530e2783556980\", \"nacl_helper_nonsfi_unittests\": \"e3a04913b51809456f3311c0c424cfcfbf4d10cf\", \"nacl_loader_unittests\": \"f950bdffb7fa779b36df02187b0894317c15b014\", \"native_theme_unittests\": \"f60cf9d75b67f21ad05d9363348f546e646d0ed6\", \"net_unittests\": \"8a15e901506326d0633bbe8e25d2ae0021561d17\", \"ozone_gl_unittests\": \"c59c628c437a98b332d229e50175bdffef63b245\", \"ozone_unittests\": \"f6613e0d8da40c99d089f72a7dc93fec1cdd3b67\", \"ozone_x11_unittests\": \"ce503f928f22ebdae4a2711d0615b6d2df9a29e7\", \"pdf_unittests\": \"9f9f471effbd84b81bedabdd2b1ffba339f79cb3\", \"perfetto_unittests\": \"df6b79f0018c1f869788a199bf8ea1ed310fc787\", \"ppapi_unittests\": \"44838337c713dfee073342edb13b7bd7ce8d534c\", \"printing_unittests\": \"9ddc0b723d46ba198591b22e7d319b2e35137466\", \"remoting_unittests\": \"e1bf73f9471be0db8949a2b138dfbde318d853a4\", \"sandbox_linux_unittests\": \"c22e116ef96cc1f01e4d1f32ecf4f5c12a30af30\", \"service_manager_unittests\": \"5edeeb88accf8575bbd02ca21fbc48ee9c4ffd6d\", \"services_unittests\": \"331b2d5427a91d29a45290a7d986d35517b54ff0\", \"shell_dialogs_unittests\": \"959c36aa79d7794837ebefaa9d8098e8101e42a4\", \"skia_unittests\": \"56a4ad77b46add45ebb291ac86baa39dbb510131\", \"snapshot_unittests\": \"7f1470d31a055dc80681f8a1ad131a545326f7c1\", \"sql_unittests\": \"e93b1ea709eb5a5f28809f50c5c89a1a25721801\", \"storage_unittests\": \"e47630269b0f9a53cef29121166a4eeac5761ab7\", \"sync_integration_tests\": \"47e6ef7354dd0ceb575c96bd28947199a90ded0b\", \"traffic_annotation_auditor_unittests\": \"30c2f08b06e8ab1447888f0f9b0267ef248704e2\", \"ui_base_unittests\": \"50d1c23e313e73408ebb3494756423e5ae242730\", \"ui_chromeos_unittests\": \"0a1ccc3fee0c21703b06e57f93107a3c77f78b2f\", \"ui_touch_selection_unittests\": \"275f7a920d9a6463f1250c8d360b3851f18ad839\", \"unit_tests\": \"d021a8ec7712e10d8335330757f7755df72d70ee\", \"url_unittests\": \"47ab65cccc03001cd80555637d9bed0aee345ed9\", \"usage_time_limit_unittests\": \"b71068f713afe340c6a510c280ccf44968cef924\", \"views_unittests\": \"423804fa3a8c806dd1e14bfa6fb38ec311b7ed2f\", \"viz_unittests\": \"a386dd5c827584c2409a581ff688200006413924\", \"wayland_client_perftests\": \"b35a6eeedba25d7418f560734bf2520b9bfd2e42\", \"wm_unittests\": \"1556d14251c8a3e861935df13f0cf8cde427627e\", \"wtf_unittests\": \"6ec88d97033be5d132da92f5c87df615cff2caa3\", \"zlib_unittests\": \"04dbf045a83ba96b5879a8c030db6289bdce2b35\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5bdf72a3926559fa6928bf9d114bb55d47a1d8787cd310af89eaf0a3f1b217d8_v2\", \"builder_6159e06f8ec59f87cd367f3a045e0109f3afef443740ad3b1d35ff114f227900_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-62-jc0p\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604936096230112",
"status": "COMPLETED",
"status_changed_ts": "1604939573558531",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42720",
"builder:linux-chromeos-rel",
"buildset:commit/git/6ebc098eb363cb08cacbe543c0bca563c7d5d2bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ebc098eb363cb08cacbe543c0bca563c7d5d2bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029526364784808576",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864096302415764000/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4fc6760bf722fa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604939573632165",
"url": "https://ci.chromium.org/b/8864096302415764000",
"utcnow_ts": "1604939575261622"
}
}
[16:32:55.281] Invocation finished in 58m56.481405239s with status SUCCEEDED