[13:48:51.559] New invocation is queued and will start shortly
[13:48:55.963] Starting the invocation (attempt 1)
[13:48:56.009] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:48:56.009] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:48:56.009] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055171919918642000",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e24a57d7a40b45b27d8db848eb0a14dea929724d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055171919918642000",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e24a57d7a40b45b27d8db848eb0a14dea929724d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e24a57d7a40b45b27d8db848eb0a14dea929724d",
"gitiles_ref:refs/heads/master"
]
}
[13:48:56.959] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580478536154534",
"id": "8889741854432133024",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e24a57d7a40b45b27d8db848eb0a14dea929724d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580478536621050",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168081",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e24a57d7a40b45b27d8db848eb0a14dea929724d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e24a57d7a40b45b27d8db848eb0a14dea929724d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055171919918642000",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8889741854432133024/+/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": "1580478536621148",
"url": "https://ci.chromium.org/b/8889741854432133024",
"utcnow_ts": "1580478536946543"
}
}
[13:48:56.959] Task URL: https://ci.chromium.org/b/8889741854432133024
[13:48:56.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:2:0) after 1m0s
[13:49:56.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:2:0)
[13:49:56.946] Timer tick, asking Buildbucket for the build status
[13:49:57.331] Build 8889741854432133024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:49:57.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:3:0) after 1m0s
[13:50:57.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:3:0)
[13:50:57.419] Timer tick, asking Buildbucket for the build status
[13:50:57.647] Build 8889741854432133024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:50:57.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:4:0) after 1m0s
[13:51:57.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:4:0)
[13:51:57.697] Timer tick, asking Buildbucket for the build status
[13:51:57.845] Build 8889741854432133024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:57.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:5:0) after 1m0s
[13:52:57.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:5:0)
[13:52:57.870] Timer tick, asking Buildbucket for the build status
[13:52:58.007] Build 8889741854432133024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:52:58.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:6:0) after 1m0s
[13:53:07.536] Received PubSub notification, asking Buildbucket for the build status
[13:53:07.696] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:58.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:6:0)
[13:53:58.022] Timer tick, asking Buildbucket for the build status
[13:53:58.161] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:58.161] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:8:0) after 1m0s
[13:54:58.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:8:0)
[13:54:58.187] Timer tick, asking Buildbucket for the build status
[13:54:58.410] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:58.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:9:0) after 1m0s
[13:55:58.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:9:0)
[13:55:58.499] Timer tick, asking Buildbucket for the build status
[13:55:58.996] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:58.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:10:0) after 1m0s
[13:56:59.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:10:0)
[13:56:59.006] Timer tick, asking Buildbucket for the build status
[13:56:59.410] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:59.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:11:0) after 1m0s
[13:57:59.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:11:0)
[13:57:59.562] Timer tick, asking Buildbucket for the build status
[13:57:59.697] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:59.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:12:0) after 1m0s
[13:58:59.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:12:0)
[13:58:59.777] Timer tick, asking Buildbucket for the build status
[13:58:59.928] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:59.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:13:0) after 1m0s
[13:59:59.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:13:0)
[13:59:59.981] Timer tick, asking Buildbucket for the build status
[14:00:00.393] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:00.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:14:0) after 1m0s
[14:01:00.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:14:0)
[14:01:00.472] Timer tick, asking Buildbucket for the build status
[14:01:01.143] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:01.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:15:0) after 1m0s
[14:02:01.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:15:0)
[14:02:01.324] Timer tick, asking Buildbucket for the build status
[14:02:01.851] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:01.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:16:0) after 1m0s
[14:03:01.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:16:0)
[14:03:01.885] Timer tick, asking Buildbucket for the build status
[14:03:02.059] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:02.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:17:0) after 1m0s
[14:04:02.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:17:0)
[14:04:02.056] Timer tick, asking Buildbucket for the build status
[14:04:02.256] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:02.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:18:0) after 1m0s
[14:05:02.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:18:0)
[14:05:02.290] Timer tick, asking Buildbucket for the build status
[14:05:02.435] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:02.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:19:0) after 1m0s
[14:06:02.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:19:0)
[14:06:02.491] Timer tick, asking Buildbucket for the build status
[14:06:02.766] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:02.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:20:0) after 1m0s
[14:07:02.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:20:0)
[14:07:02.813] Timer tick, asking Buildbucket for the build status
[14:07:03.225] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:03.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:21:0) after 1m0s
[14:08:03.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:21:0)
[14:08:03.266] Timer tick, asking Buildbucket for the build status
[14:08:03.458] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:03.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:22:0) after 1m0s
[14:09:03.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:22:0)
[14:09:03.487] Timer tick, asking Buildbucket for the build status
[14:09:03.571] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:03.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:23:0) after 1m0s
[14:10:03.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:23:0)
[14:10:03.625] Timer tick, asking Buildbucket for the build status
[14:10:03.758] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:03.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:24:0) after 1m0s
[14:11:03.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:24:0)
[14:11:03.822] Timer tick, asking Buildbucket for the build status
[14:11:04.262] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:04.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:25:0) after 1m0s
[14:12:04.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:25:0)
[14:12:04.300] Timer tick, asking Buildbucket for the build status
[14:12:04.924] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:04.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:26:0) after 1m0s
[14:13:05.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:26:0)
[14:13:05.069] Timer tick, asking Buildbucket for the build status
[14:13:05.602] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:05.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:27:0) after 1m0s
[14:14:05.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:27:0)
[14:14:05.784] Timer tick, asking Buildbucket for the build status
[14:14:05.979] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:05.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:28:0) after 1m0s
[14:15:05.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:28:0)
[14:15:05.999] Timer tick, asking Buildbucket for the build status
[14:15:06.191] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:06.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:29:0) after 1m0s
[14:16:06.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:29:0)
[14:16:06.265] Timer tick, asking Buildbucket for the build status
[14:16:06.953] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:06.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:30:0) after 1m0s
[14:17:07.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:30:0)
[14:17:07.022] Timer tick, asking Buildbucket for the build status
[14:17:07.615] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:07.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:31:0) after 1m0s
[14:18:07.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:31:0)
[14:18:07.628] Timer tick, asking Buildbucket for the build status
[14:18:07.782] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:07.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:32:0) after 1m0s
[14:19:07.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:32:0)
[14:19:07.873] Timer tick, asking Buildbucket for the build status
[14:19:08.340] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:08.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:33:0) after 1m0s
[14:20:08.363] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:33:0)
[14:20:08.363] Timer tick, asking Buildbucket for the build status
[14:20:08.650] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:08.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:34:0) after 1m0s
[14:21:08.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:34:0)
[14:21:08.632] Timer tick, asking Buildbucket for the build status
[14:21:08.763] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:08.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:35:0) after 1m0s
[14:22:08.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:35:0)
[14:22:08.768] Timer tick, asking Buildbucket for the build status
[14:22:08.953] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:08.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:36:0) after 1m0s
[14:23:09.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:36:0)
[14:23:09.056] Timer tick, asking Buildbucket for the build status
[14:23:09.300] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:09.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:37:0) after 1m0s
[14:24:09.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:37:0)
[14:24:09.442] Timer tick, asking Buildbucket for the build status
[14:24:09.795] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:09.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:38:0) after 1m0s
[14:25:09.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:38:0)
[14:25:09.889] Timer tick, asking Buildbucket for the build status
[14:25:10.127] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:10.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:39:0) after 1m0s
[14:26:10.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:39:0)
[14:26:10.153] Timer tick, asking Buildbucket for the build status
[14:26:10.975] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:10.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:40:0) after 1m0s
[14:27:11.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:40:0)
[14:27:11.121] Timer tick, asking Buildbucket for the build status
[14:27:11.283] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:11.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:41:0) after 1m0s
[14:28:11.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:41:0)
[14:28:11.298] Timer tick, asking Buildbucket for the build status
[14:28:11.455] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:11.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:42:0) after 1m0s
[14:29:11.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:42:0)
[14:29:11.535] Timer tick, asking Buildbucket for the build status
[14:29:11.866] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:11.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:43:0) after 1m0s
[14:30:11.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:43:0)
[14:30:11.965] Timer tick, asking Buildbucket for the build status
[14:30:12.429] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:12.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:44:0) after 1m0s
[14:31:12.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:44:0)
[14:31:12.468] Timer tick, asking Buildbucket for the build status
[14:31:12.854] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:12.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:45:0) after 1m0s
[14:32:12.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:45:0)
[14:32:12.912] Timer tick, asking Buildbucket for the build status
[14:32:13.104] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:13.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:46:0) after 1m0s
[14:33:13.217] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:46:0)
[14:33:13.217] Timer tick, asking Buildbucket for the build status
[14:33:13.991] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:13.991] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:47:0) after 1m0s
[14:34:14.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:47:0)
[14:34:14.035] Timer tick, asking Buildbucket for the build status
[14:34:14.243] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:14.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:48:0) after 1m0s
[14:35:14.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:48:0)
[14:35:14.280] Timer tick, asking Buildbucket for the build status
[14:35:14.484] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:14.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:49:0) after 1m0s
[14:36:14.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:49:0)
[14:36:14.522] Timer tick, asking Buildbucket for the build status
[14:36:14.708] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:14.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:50:0) after 1m0s
[14:37:14.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:50:0)
[14:37:14.766] Timer tick, asking Buildbucket for the build status
[14:37:15.043] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:15.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:51:0) after 1m0s
[14:38:15.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:51:0)
[14:38:15.138] Timer tick, asking Buildbucket for the build status
[14:38:15.341] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:15.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:52:0) after 1m0s
[14:39:15.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:52:0)
[14:39:15.397] Timer tick, asking Buildbucket for the build status
[14:39:15.791] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:15.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:53:0) after 1m0s
[14:40:15.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:53:0)
[14:40:15.808] Timer tick, asking Buildbucket for the build status
[14:40:16.112] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:16.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:54:0) after 1m0s
[14:41:16.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:54:0)
[14:41:16.150] Timer tick, asking Buildbucket for the build status
[14:41:16.842] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:16.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:55:0) after 1m0s
[14:42:16.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:55:0)
[14:42:16.998] Timer tick, asking Buildbucket for the build status
[14:42:17.155] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:17.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:56:0) after 1m0s
[14:43:17.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:56:0)
[14:43:17.258] Timer tick, asking Buildbucket for the build status
[14:43:17.778] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:17.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:57:0) after 1m0s
[14:44:17.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:57:0)
[14:44:17.845] Timer tick, asking Buildbucket for the build status
[14:44:18.109] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:18.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:58:0) after 1m0s
[14:45:18.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:58:0)
[14:45:18.087] Timer tick, asking Buildbucket for the build status
[14:45:18.363] Build 8889741854432133024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:18.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055171919918642000:59:0) after 1m0s
[14:45:43.157] Received PubSub notification, asking Buildbucket for the build status
[14:45:43.801] Build 8889741854432133024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:45:43.801] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580481942565287",
"created_by": "project:chromium",
"created_ts": "1580478536154534",
"id": "8889741854432133024",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e24a57d7a40b45b27d8db848eb0a14dea929724d\"}}",
"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-65-xjlq\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168081, \"got_angle_revision\": \"5de415c5e3f9616298f0d99d2f618e211870a8f2\", \"got_dawn_revision\": \"f44a809f9a3b6506df5cc1105198ed136c6b26ec\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"e24a57d7a40b45b27d8db848eb0a14dea929724d\", \"got_revision_cp\": \"refs/heads/master@{#737306}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"79b36b640692e3b4561642c06ec36a9e02a1636c\", \"got_v8_revision\": \"a4dcd39d521d14c4b1cac020812e44ee04a7f244\", \"got_v8_revision_cp\": \"refs/heads/8.1.307@{#1}\", \"got_webrtc_revision\": \"bfe3ef8feb3b4e351403be38fe626df4e796648c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30435}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e24a57d7a40b45b27d8db848eb0a14dea929724d\", \"swarm_hashes_refs/heads/master(at){#737306}_without_patch\": {\"accessibility_unittests\": \"2691c7d8c196ef67566b391a9dc3b0c57a9b4b4e\", \"angle_unittests\": \"bd00ff9227586307cb57a31af37b1fdd225c2c57\", \"app_shell_unittests\": \"23a99ca67adbe17e9157b6ef17b2a81fc6dda9c4\", \"aura_unittests\": \"0292e8c1129cf81f53490acbee3655823a6b030f\", \"base_unittests\": \"3d81bc5f9867ad331929078e9c0c0166cd1d137f\", \"base_util_unittests\": \"9df72150f520e47a065e34ffbbd95547d3e78aca\", \"blink_common_unittests\": \"12cc2df53c1fbf0ba85bc49dd309aecbe598ebcc\", \"blink_fuzzer_unittests\": \"0fa0f897f75f22b466e2c0faad8b848403d924dc\", \"blink_heap_unittests\": \"6f3303b4f7497f49e531e4c90855f7debe5ae71f\", \"blink_platform_unittests\": \"75eead337aa9ba9b00aef1846183fd480739afbb\", \"blink_python_tests\": \"b6e48a6c6c48cc7e8bc24adcc11179b72a71355b\", \"blink_unittests\": \"5e2f84cd3cd68ebc49ea07ad08c40ecab8e64710\", \"blink_web_tests\": \"f990512df46aa0be388b7b1ba549a9f64a8e9975\", \"boringssl_crypto_tests\": \"bd108cbf56997e0091bac875a9e008b99fc33987\", \"boringssl_ssl_tests\": \"5a90601a93ef567a1413055e26bf68959ad98b3c\", \"browser_tests\": \"39812ced9444b390881e3d1c245fd84f98e0fa5d\", \"cacheinvalidation_unittests\": \"30510a4e082b91caa83f9ec66c076c8b47370c7c\", \"capture_unittests\": \"f7124ec42c7a61b03179b0f0be04e94374ea1300\", \"cast_unittests\": \"dbc2616d74f10640d29afee2bb6d9806826b0a0d\", \"cc_unittests\": \"2b6ffacc8f7fcc1fe4ca8bfaf214c45736b4fb0c\", \"chrome_app_unittests\": \"913e3902e27603e8e62472b4f54fd89abf1c5111\", \"chromedriver_unittests\": \"19fe16da1cae9d8ad374232e8124475b5f1279b9\", \"components_browsertests\": \"bf7b9d8ef8047c187e503192f444cdff45aec419\", \"components_unittests\": \"65f43391877ad45576e0d65287075328e32e20bb\", \"compositor_unittests\": \"fe550ce48e52448516c45b088faf9b4ee8d4ce66\", \"content_browsertests\": \"76b7b1d2fbb0a15c5b38bea27698b40513adee20\", \"content_shell_crash_test\": \"4505de200c15ee65a43f4562376123e81eaa335e\", \"content_unittests\": \"d947f20ae70d6c07be3e3510e968252cb13e9f6b\", \"crashpad_tests\": \"caf6e253e1ca9134ed839ea29b532506a07abaed\", \"cronet_tests\": \"4f1d56cf68ecbf0d725c999dc67f753df88ffb55\", \"cronet_unittests\": \"e56b7b02396dd58136f7a1b9a0f1ca4ba1286e9a\", \"crypto_unittests\": \"6c9f7b02eb547862dd4750cb9c28ee4cd87cee24\", \"dbus_unittests\": \"5e1980c7a68ac27f35060eb5f54592b68c1fd199\", \"device_unittests\": \"117bbd68703f1d478b5502bd620d11f2ce66e566\", \"display_unittests\": \"ba52ee803a296ade6908bdc6730eb8df97bbfbc1\", \"events_unittests\": \"2bd75c3fee16561caa9d2a2339d7ca9b8040e9b2\", \"extensions_browsertests\": \"2df8761d68f38471b6eb2d5e20ec7c8e39d286f7\", \"extensions_unittests\": \"0082ea923fa6762742abb29aca31c04b252ba866\", \"filesystem_service_unittests\": \"11de83ff39a097be684961758948f07c80aecf68\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"168f8bffe979dfab4a422e9f99d2d6cf0929e402\", \"gfx_unittests\": \"e43ff468d7c599266cc83f6931537df9e107c421\", \"gin_unittests\": \"6e3e1792ca0682a62d06326b42160d8c95022437\", \"google_apis_unittests\": \"708c6d90ed8e188b8d18c7234d1aba6f1d2966b0\", \"gpu_unittests\": \"d8ba07fe688c3a3a4281d961fca677255d55b815\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"9c61eafa7efd31c23606691a5cfe5a4ba29bfcfd\", \"headless_browsertests\": \"9c5b9e6bb61dd65163506150dff3aa65902a9f3b\", \"headless_unittests\": \"02c18f9861d9b1461705f76b65b16dd717f95ec0\", \"interactive_ui_tests\": \"ad17657c3b484dcd4b18140609473ddec543aece\", \"ipc_tests\": \"a54afb99283620b9d7e439f8c477fbb77e1b06df\", \"jingle_unittests\": \"210639a8620bb84664957a4ba8d71f5eb3cd45f2\", \"latency_unittests\": \"3c574c6fe2f3ebdffd6bb6a1731d7c29f29cfcab\", \"libjingle_xmpp_unittests\": \"9295f1dc3c63f3da09bed273366aaeb77a02cc31\", \"media_blink_unittests\": \"fbfba04b5f132f9e95dda3bbed201bbd689d675c\", \"media_service_unittests\": \"9332465fa83038d0b66b2901862a3465a17e40b4\", \"media_unittests\": \"abe48c2fbe41847496c0f5b366fa4d556d7d87d6\", \"message_center_unittests\": \"7d8586aa56afd327fdbb127e12a3ad08d4d0355d\", \"metrics_python_tests\": \"87c48bde70871287007441f9f602e160ac0d4838\", \"midi_unittests\": \"141fa196443ca39238393e94f19d85367983b4d4\", \"mojo_core_unittests\": \"33061139d2472f1aaed98106dc39ec5c82dbdc4b\", \"mojo_unittests\": \"d699c16e744a513ebc4d6ac48f3c8668da6b73d0\", \"nacl_helper_nonsfi_unittests\": \"b38cf9a1686b6244e7c9103410b90dd9cea96356\", \"nacl_loader_unittests\": \"d54ff49957cee16d3d09175e3ed0e4b8b9b9b648\", \"native_theme_unittests\": \"3867c579c5c955d499a7afd8708391d96dc885da\", \"net_unittests\": \"3fe7f1e97fa3d8f6ec43cdc5720c9c19898293c9\", \"pdf_unittests\": \"ed99a71f6a67adc18e962383f95cc4d0d2c12023\", \"perfetto_unittests\": \"5a61ee5762056e3e7d1357afc493a8b09376a4a9\", \"ppapi_unittests\": \"04662a871c67b656f70e3dcc61b2113de48b985d\", \"printing_unittests\": \"487143ed620e59d87acb299b50aeffaab6fe93a0\", \"remoting_unittests\": \"5da835498e341c5e2d0d10307cd07e8511084e6b\", \"sandbox_linux_unittests\": \"af75a11607e896bc93f7cc4489c7f0f7da01f839\", \"service_manager_unittests\": \"c440bb5e8bc3b39adbb795478d38c78a94dbac38\", \"services_unittests\": \"c5c60380c683ec9faffac580e35c9629805a5547\", \"shell_dialogs_unittests\": \"0b9ed8beabb7779e72183fe82da3e9fe3f2b2bd2\", \"skia_unittests\": \"fdc1e9fff43d2eee455492d18c7c47a790bcb8b4\", \"snapshot_unittests\": \"380959406e0224b82560ffbb6435707142cf0353\", \"sql_unittests\": \"f9ddcdee568d40e96d49027b79fad7f9c5be09d2\", \"storage_unittests\": \"7aabaa489dc3e1ea20c4281fe4a01c5b4abb2244\", \"sync_integration_tests\": \"e89968110a45e8d159c500dbbf87c321c28144cb\", \"telemetry_gpu_unittests\": \"34fe4254e391ec4c3366018826d18c1e588d8af8\", \"telemetry_perf_unittests\": \"79b71e7ff4561860cd1394960f140d127272784b\", \"telemetry_unittests\": \"81c2cf92ad6b2a3fa619562d93f255d65fda89b6\", \"traffic_annotation_auditor_unittests\": \"906babba50a42f89c6c8b8e8944a9197ca7a3782\", \"ui_base_unittests\": \"f41ab7d1e8eaae21895e3c7e299e6acd436da6ee\", \"ui_touch_selection_unittests\": \"f7e2d23612a86cb54db17988c2c17a791d6b6dc8\", \"unit_tests\": \"b5d5c54720e2b1408d4b162c885c8a85b76159a0\", \"url_unittests\": \"323938815a8a38d869b9731bf3968ee2abf23d8a\", \"views_perftests\": \"0eda5dd374c7e8714d3f944998ba660543a6b3b0\", \"views_unittests\": \"9f387b514a8660dc278fdbb38856878f69248de1\", \"viz_unittests\": \"9a7c33d4e12a71324d16e43104011759c0b555cb\", \"vr_common_unittests\": \"c3dcff0a69960a565fce59056dfb4a573a57f6da\", \"vr_pixeltests\": \"2bd8e185983f9361226db38f73659205f191d4ff\", \"webdriver_wpt_tests\": \"aa859d5b1701f2b4be008ad8b73275aa90b1f0b8\", \"weblayer_browsertests\": \"928f40332c7b6227f43de1a9adfb7897b24f9289\", \"wm_unittests\": \"50d9efabe197a5de1cd9a69a415d1f96be1d10d0\", \"wtf_unittests\": \"fce5fe5d538172301055b6cba9457aae5ae7b159\", \"xr_browser_tests\": \"eb111adfc0826a6669e0acace1ec6c557808f595\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_387e931c7b633ee2f48b892df14288ffa2061feda74b30444810d565fa474120_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_v2\", \"builder_957c0f1a1c74c3ada6665996f2ff614223d5da32e2cc2316ff7cc4c9c4beae5c_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_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-65-xjlq\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4804-5c544e0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580478786830332",
"status": "COMPLETED",
"status_changed_ts": "1580481942793888",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168081",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e24a57d7a40b45b27d8db848eb0a14dea929724d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e24a57d7a40b45b27d8db848eb0a14dea929724d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055171919918642000",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8889741854432133024/+/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:4a14ae2557b5ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580481942800037",
"url": "https://ci.chromium.org/b/8889741854432133024",
"utcnow_ts": "1580481943792857"
}
}
[14:45:43.801] Invocation finished in 56m52.263501267s with status SUCCEEDED