[11:24:01.447] New invocation is queued and will start shortly
[11:24:03.883] Starting the invocation (attempt 1)
[11:24:03.923] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:24:03.923] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:24:03.923] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030085672464693120",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4ae6404551dbbfc0c4536f3db515489ac68c1ed1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9030085672464693120",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4ae6404551dbbfc0c4536f3db515489ac68c1ed1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ae6404551dbbfc0c4536f3db515489ac68c1ed1",
"gitiles_ref:refs/heads/master"
]
}
[11:24:04.591] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604402644021802",
"id": "8864655609101534288",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ae6404551dbbfc0c4536f3db515489ac68c1ed1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604402644431680",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42524",
"builder:linux-chromeos-rel",
"buildset:commit/git/4ae6404551dbbfc0c4536f3db515489ac68c1ed1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ae6404551dbbfc0c4536f3db515489ac68c1ed1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030085672464693120",
"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/8864655609101534288/+/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": "1604402644431793",
"url": "https://ci.chromium.org/b/8864655609101534288",
"utcnow_ts": "1604402644581314"
}
}
[11:24:04.591] Task URL: https://ci.chromium.org/b/8864655609101534288
[11:24:04.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:2:0) after 1m0s
[11:24:08.375] Received PubSub notification, asking Buildbucket for the build status
[11:24:08.561] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:04.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:2:0)
[11:25:04.614] Timer tick, asking Buildbucket for the build status
[11:25:04.761] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:04.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:4:0) after 1m0s
[11:26:04.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:4:0)
[11:26:04.786] Timer tick, asking Buildbucket for the build status
[11:26:05.159] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:05.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:5:0) after 1m0s
[11:27:05.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:5:0)
[11:27:05.178] Timer tick, asking Buildbucket for the build status
[11:27:05.345] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:05.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:6:0) after 1m0s
[11:28:05.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:6:0)
[11:28:05.395] Timer tick, asking Buildbucket for the build status
[11:28:05.471] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:05.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:7:0) after 1m0s
[11:29:05.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:7:0)
[11:29:05.559] Timer tick, asking Buildbucket for the build status
[11:29:05.711] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:05.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:8:0) after 1m0s
[11:30:05.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:8:0)
[11:30:05.764] Timer tick, asking Buildbucket for the build status
[11:30:06.185] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:06.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:9:0) after 1m0s
[11:31:06.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:9:0)
[11:31:06.304] Timer tick, asking Buildbucket for the build status
[11:31:06.394] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:06.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:10:0) after 1m0s
[11:32:06.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:10:0)
[11:32:06.503] Timer tick, asking Buildbucket for the build status
[11:32:06.580] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:06.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:11:0) after 1m0s
[11:33:06.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:11:0)
[11:33:06.679] Timer tick, asking Buildbucket for the build status
[11:33:07.003] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:07.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:12:0) after 1m0s
[11:34:07.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:12:0)
[11:34:07.050] Timer tick, asking Buildbucket for the build status
[11:34:07.173] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:07.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:13:0) after 1m0s
[11:35:07.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:13:0)
[11:35:07.233] Timer tick, asking Buildbucket for the build status
[11:35:07.535] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:07.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:14:0) after 1m0s
[11:36:07.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:14:0)
[11:36:07.589] Timer tick, asking Buildbucket for the build status
[11:36:07.715] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:07.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:15:0) after 1m0s
[11:37:07.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:15:0)
[11:37:07.793] Timer tick, asking Buildbucket for the build status
[11:37:07.850] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:07.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:16:0) after 1m0s
[11:38:07.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:16:0)
[11:38:07.985] Timer tick, asking Buildbucket for the build status
[11:38:08.053] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:08.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:17:0) after 1m0s
[11:39:08.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:17:0)
[11:39:08.106] Timer tick, asking Buildbucket for the build status
[11:39:08.456] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:08.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:18:0) after 1m0s
[11:40:08.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:18:0)
[11:40:08.476] Timer tick, asking Buildbucket for the build status
[11:40:08.655] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:08.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:19:0) after 1m0s
[11:41:08.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:19:0)
[11:41:08.680] Timer tick, asking Buildbucket for the build status
[11:41:09.263] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:09.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:20:0) after 1m0s
[11:42:09.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:20:0)
[11:42:09.293] Timer tick, asking Buildbucket for the build status
[11:42:09.403] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:09.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:21:0) after 1m0s
[11:43:09.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:21:0)
[11:43:09.424] Timer tick, asking Buildbucket for the build status
[11:43:09.492] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:09.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:22:0) after 1m0s
[11:44:09.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:22:0)
[11:44:09.562] Timer tick, asking Buildbucket for the build status
[11:44:09.737] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:09.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:23:0) after 1m0s
[11:45:09.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:23:0)
[11:45:09.781] Timer tick, asking Buildbucket for the build status
[11:45:09.879] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:24:0) after 1m0s
[11:46:09.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:24:0)
[11:46:09.901] Timer tick, asking Buildbucket for the build status
[11:46:10.072] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:10.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:25:0) after 1m0s
[11:47:10.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:25:0)
[11:47:10.110] Timer tick, asking Buildbucket for the build status
[11:47:10.206] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:10.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:26:0) after 1m0s
[11:48:10.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:26:0)
[11:48:10.271] Timer tick, asking Buildbucket for the build status
[11:48:10.555] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:10.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:27:0) after 1m0s
[11:49:10.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:27:0)
[11:49:10.595] Timer tick, asking Buildbucket for the build status
[11:49:10.743] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:10.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:28:0) after 1m0s
[11:50:10.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:28:0)
[11:50:10.784] Timer tick, asking Buildbucket for the build status
[11:50:12.951] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:12.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:29:0) after 1m0s
[11:51:12.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:29:0)
[11:51:12.981] Timer tick, asking Buildbucket for the build status
[11:51:13.102] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:13.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:30:0) after 1m0s
[11:52:13.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:30:0)
[11:52:13.164] Timer tick, asking Buildbucket for the build status
[11:52:13.648] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:13.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:31:0) after 1m0s
[11:53:13.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:31:0)
[11:53:13.644] Timer tick, asking Buildbucket for the build status
[11:53:13.743] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:13.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:32:0) after 1m0s
[11:54:13.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:32:0)
[11:54:13.829] Timer tick, asking Buildbucket for the build status
[11:54:13.974] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:13.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:33:0) after 1m0s
[11:55:13.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:33:0)
[11:55:13.979] Timer tick, asking Buildbucket for the build status
[11:55:14.748] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:14.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:34:0) after 1m0s
[11:56:14.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:34:0)
[11:56:14.806] Timer tick, asking Buildbucket for the build status
[11:56:14.975] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:14.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:35:0) after 1m0s
[11:57:15.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:35:0)
[11:57:15.014] Timer tick, asking Buildbucket for the build status
[11:57:15.380] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:15.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:36:0) after 1m0s
[11:58:15.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:36:0)
[11:58:15.428] Timer tick, asking Buildbucket for the build status
[11:58:15.608] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:15.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:37:0) after 1m0s
[11:59:15.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:37:0)
[11:59:15.668] Timer tick, asking Buildbucket for the build status
[11:59:15.935] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:15.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:38:0) after 1m0s
[12:00:16.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:38:0)
[12:00:16.010] Timer tick, asking Buildbucket for the build status
[12:00:16.606] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:16.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:39:0) after 1m0s
[12:01:16.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:39:0)
[12:01:16.672] Timer tick, asking Buildbucket for the build status
[12:01:17.874] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:17.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:40:0) after 1m0s
[12:02:17.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:40:0)
[12:02:17.977] Timer tick, asking Buildbucket for the build status
[12:02:18.128] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:18.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:41:0) after 1m0s
[12:03:18.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:41:0)
[12:03:18.190] Timer tick, asking Buildbucket for the build status
[12:03:18.416] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:18.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:42:0) after 1m0s
[12:04:18.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:42:0)
[12:04:18.468] Timer tick, asking Buildbucket for the build status
[12:04:18.625] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:18.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:43:0) after 1m0s
[12:05:18.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:43:0)
[12:05:18.646] Timer tick, asking Buildbucket for the build status
[12:05:18.793] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:18.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:44:0) after 1m0s
[12:06:18.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:44:0)
[12:06:18.857] Timer tick, asking Buildbucket for the build status
[12:06:19.500] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:19.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:45:0) after 1m0s
[12:07:19.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:45:0)
[12:07:19.594] Timer tick, asking Buildbucket for the build status
[12:07:19.747] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:19.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:46:0) after 1m0s
[12:08:19.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:46:0)
[12:08:19.870] Timer tick, asking Buildbucket for the build status
[12:08:20.030] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:20.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:47:0) after 1m0s
[12:09:20.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:47:0)
[12:09:20.052] Timer tick, asking Buildbucket for the build status
[12:09:20.263] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:20.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:48:0) after 1m0s
[12:10:20.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:48:0)
[12:10:20.291] Timer tick, asking Buildbucket for the build status
[12:10:20.545] Build 8864655609101534288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:20.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030085672464693120:49:0) after 1m0s
[12:10:57.843] Received PubSub notification, asking Buildbucket for the build status
[12:10:57.944] Build 8864655609101534288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:10:57.944] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604405456779299",
"created_by": "project:chromium",
"created_ts": "1604402644021802",
"id": "8864655609101534288",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ae6404551dbbfc0c4536f3db515489ac68c1ed1\"}}",
"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\": \"4ae6404551dbbfc0c4536f3db515489ac68c1ed1\", \"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-east1-d-8-100-9gap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42524, \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"71d2e1d23d49c3616209dbf4776fd720aaa11560\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"4ae6404551dbbfc0c4536f3db515489ac68c1ed1\", \"got_revision_cp\": \"refs/heads/master@{#823500}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"959f41988a18e8511dcde1d646d798de4ea6b59d\", \"got_v8_revision\": \"e0ff8ffa26e56bf39b22eea7731e1b434d2d7378\", \"got_v8_revision_cp\": \"refs/heads/8.8.219@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ae6404551dbbfc0c4536f3db515489ac68c1ed1\", \"swarm_hashes_refs/heads/master(at){#823500}_without_patch\": {\"absl_hardening_tests\": \"6390cf44d49e6804e88c9418f650e415e0e4a016\", \"accessibility_unittests\": \"7d715e7239615bff52e57532bb7e03756be158c3\", \"angle_unittests\": \"2161f818e067300066748e35ce35cc57e56bd5c4\", \"app_list_unittests\": \"73ec1b442e58278298f817e19e515796977ec47e\", \"app_shell_unittests\": \"0a7e0f2af97d9837838be940b5ab7079027258a7\", \"ash_unittests\": \"80ed27ce950931f9639fff2e8274c7766e154ec0\", \"aura_unittests\": \"916d8ff8ca9c7431ad087a99db25db12bab3d368\", \"base_unittests\": \"53efe49fdee46574cd3e5dc86b917128da350dc1\", \"base_util_unittests\": \"23f2752f7dca17b32ba36b2710671b7f5528cbde\", \"blink_common_unittests\": \"63efbd29a78f16e11a6c7f4bae1154e25d50a0dc\", \"blink_fuzzer_unittests\": \"574cf5bd71c1071377f4d45f83dec48773be8797\", \"blink_heap_unittests\": \"7598e73af85ac1d9968dec929cf3ccf6015c9c4f\", \"blink_platform_unittests\": \"54ecb5a838361f860baa742cf1247d5267322aeb\", \"blink_unittests\": \"dd94ec437daa58d73506c92cb73c4dc907edd5c1\", \"boringssl_crypto_tests\": \"f420e0de05a3dabee8571d2fc6244240bbb0f171\", \"boringssl_ssl_tests\": \"f1d6018c16093bb1bafdbf4d5185fa1f6e33fcd8\", \"browser_tests\": \"cea5669b0803b2f7b5c954ce1dd395af0e034f7b\", \"capture_unittests\": \"c8a24aa9f08e9ba00784feb757d17f2f8a62ded5\", \"cast_unittests\": \"75df9ba2863ad9b60013d02d0bd6682424f89f76\", \"cc_unittests\": \"88d1a288c25d650dcfc519d6f470e6f037f4690b\", \"chrome_app_unittests\": \"ec60c7e90694d38fc8d0dcf86b36db059c2dc187\", \"chromedriver_unittests\": \"4a3adcc4d8199249841dc8e9007fcc07d5eeaa73\", \"chromeos_components_unittests\": \"76f5a4a4da627d569efe9c7b25eb23f32d873422\", \"chromeos_unittests\": \"6f1d403baa3e8546669baa538e358d7532c52df5\", \"components_browsertests\": \"51d8e283528e8af4191001320b8776e69f8a2b85\", \"components_unittests\": \"211c353d923caad3f0e15ee4cb956ad96f85c220\", \"compositor_unittests\": \"0e93df94a7a86631d4d39868cbf1f3e623b874ed\", \"content_browsertests\": \"814a0af19120783851331fbf5d03b166783717f0\", \"content_unittests\": \"8efb45394688c6e8c321ff196d9431db0380c01f\", \"crypto_unittests\": \"d5d7d21ce06fdc4aef434f0abe780e44de4e5f07\", \"dbus_unittests\": \"754eb8dc915e836b6dfa2c4be542a0baf9ea04e6\", \"device_unittests\": \"ff346c32972af0ffdbaa0c494e58623d34f9802a\", \"display_unittests\": \"aa8b121d42db37c7ed761d69a26de4adb0e59ea2\", \"events_unittests\": \"8ffdbc801da6d01edc25544216906fb84bfa50f0\", \"exo_unittests\": \"7d7838bc6dd919b52e40d7f1eb525f19428e1001\", \"extensions_browsertests\": \"38ae7b736e776aec6114d9a73472521510fe8f65\", \"extensions_unittests\": \"75fe67555fb9a2aabe6b27f360a1b4ee75efb647\", \"filesystem_service_unittests\": \"3e40377e9b51ecdc15d887805f0966386d73b474\", \"gcm_unit_tests\": \"5d3bf1a1c4d9d68e80e08b5ef914c69f0857c711\", \"gfx_unittests\": \"ccd748e5a7d054baea550a468524cd3ffd7430f7\", \"gin_unittests\": \"156dfe46711b44267d5e8452f16f46ec6adc0a3d\", \"gl_unittests_ozone\": \"e8800658f90a798bfa6373f8d0a8fcd7970ad5ca\", \"google_apis_unittests\": \"c5cc0e3320cefa65171c4a450b9737525e747faa\", \"gpu_unittests\": \"f183d7a9e74d173fd9d12b6b25dfff20a7573e67\", \"gwp_asan_unittests\": \"d59599e29d46f1c1585ab043287eab83868263f9\", \"interactive_ui_tests\": \"27b068fa47e6d98873290eb1fc00a8c163facba6\", \"ipc_tests\": \"30d9ff5e805763bf056506dafa408c9c3e8eeaa9\", \"jingle_unittests\": \"d7c51e49a810b229f227b5c03ef76626053fbdb2\", \"keyboard_unittests\": \"cf94338a733634b66e6826f684f7feb6b802c547\", \"latency_unittests\": \"e9b2a67f4d49635414fc4123a6735ec32635a756\", \"libjingle_xmpp_unittests\": \"8d7a7f7fb9002b12515c166e79c19fc8cc6ecd1f\", \"liburlpattern_unittests\": \"60ea08db9caa654a8117cd517d4d01c01d732286\", \"media_blink_unittests\": \"4763c00d772a0376b8b50bd941e84d1dc3436362\", \"media_unittests\": \"fb0f1f1084e015f340505146eba80d7d90482b8e\", \"message_center_unittests\": \"74236a1cfd778d572b3dba9b196a8697ccb14552\", \"midi_unittests\": \"1782575a44500ae21502ae6dfaa93ad8f28d209b\", \"mojo_core_unittests\": \"a609d95db065dd7855f8b51f6415835bb8777988\", \"mojo_unittests\": \"df01815338e11c979315fb67b4ca8d716955eefa\", \"nacl_helper_nonsfi_unittests\": \"029b566bd5e11d901febab259c4709ef257b51ed\", \"nacl_loader_unittests\": \"24b036d46cca5b6cd043ca047febf342ef9dfc07\", \"native_theme_unittests\": \"51c4b0c93427ba9b48487ef313dca3ed8dc80d26\", \"net_unittests\": \"9abcc972795fe6a8c44e0ef814f82bbce6badc19\", \"ozone_gl_unittests\": \"3dd3e27b44955ad56afa361c64ac6d94a20c3048\", \"ozone_unittests\": \"70f3a4ad68b622b015fc3c0441874b4956b2b91a\", \"ozone_x11_unittests\": \"275ef31b640f128f744f5e0d9f738f16ce25e03e\", \"pdf_unittests\": \"be5fbc3436daf48959dd53ad8c51672728395a8c\", \"perfetto_unittests\": \"bf30f08a265b1f9bbf2b274d93a32c12b49ffd0b\", \"ppapi_unittests\": \"3170a83f8a8708101a95a4d256bc057ccd3cb183\", \"printing_unittests\": \"24510309211b204550602028421cc20d8752bcfd\", \"remoting_unittests\": \"337f4767d2b1c044175aa2f872620cba60b63bba\", \"sandbox_linux_unittests\": \"df17c542da2e26e79925b517df9a4982bb38d465\", \"service_manager_unittests\": \"6950d04cc36ed452d8e71e5011cf81e35e5be283\", \"services_unittests\": \"857c707d83aec649ebb797bde03b14d386e8cff5\", \"shell_dialogs_unittests\": \"337656dd0448f48084c1269e9c45e69c0dd40afc\", \"skia_unittests\": \"14280152a9a72e5fb783d3492ae0c9b9b94980b8\", \"snapshot_unittests\": \"dd603aac4c6de4414f3269ca295d259f85fb67f0\", \"sql_unittests\": \"00f660688f7b429931f3b45feadc75a1f7ca674c\", \"storage_unittests\": \"d47a602dc648b41a6f78d1324bbb01b2d712dfc8\", \"sync_integration_tests\": \"294079982002ab1a4960d893dc572f648f82142b\", \"traffic_annotation_auditor_unittests\": \"c27dad3d0befb7317e7dce426e4b6818bbb912e7\", \"ui_base_unittests\": \"812d7aa6362831d178a63cfbd2cf5ece701f4775\", \"ui_chromeos_unittests\": \"07c70e8d3d1e13592fd78ababc5768962e1d1ab9\", \"ui_touch_selection_unittests\": \"e11769c03ef408a1d70f7a1e5e68d242b43d6490\", \"unit_tests\": \"b2b6e946842e4a69b334d1cf18166b2ea9d7a61f\", \"url_unittests\": \"8f7ba53a949c77b54293491a522bf750f511f9b8\", \"usage_time_limit_unittests\": \"835916185f670a0223911b4b40949cdc72de9d04\", \"views_unittests\": \"0447dfb4e12fed59504d43e904e36a8bd02b4485\", \"viz_unittests\": \"4a0c619c76d2cfee77d17dbe189a1c952dc8a553\", \"wayland_client_perftests\": \"db57165d8f04005842bec8ed9d8bf97f09fda57b\", \"wm_unittests\": \"0dfef846dd24fa1df450280dd637630a84f6f21a\", \"wtf_unittests\": \"5d36f95a87c0a3777f0b0cd106582c6a87919741\", \"zlib_unittests\": \"f8385e6951cd94f7ed1a432d8a102595d4722d30\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5bdf72a3926559fa6928bf9d114bb55d47a1d8787cd310af89eaf0a3f1b217d8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_bf4ffcf77741609bcc4ae2575614d3999de9f994863a8df9c7f9ae8acc5b63c4_v2\", \"builder_f08bf93ee1d13a1d2260b922ab0417b8152c7ae32d2a7424478e3a5e3e0f73f7_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-east1-d-8-100-9gap\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604402647397598",
"status": "COMPLETED",
"status_changed_ts": "1604405457409600",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42524",
"builder:linux-chromeos-rel",
"buildset:commit/git/4ae6404551dbbfc0c4536f3db515489ac68c1ed1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ae6404551dbbfc0c4536f3db515489ac68c1ed1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030085672464693120",
"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/8864655609101534288/+/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:4fa6ab0ec2cb1b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604405457415058",
"url": "https://ci.chromium.org/b/8864655609101534288",
"utcnow_ts": "1604405457935026"
}
}
[12:10:57.944] Invocation finished in 46m56.514077071s with status SUCCEEDED