[19:29:54.610] New invocation is queued and will start shortly
[19:29:55.703] Starting the invocation (attempt 1)
[19:29:55.745] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:29:55.745] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:29:55.745] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018458691457746384",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e0b83bd148da6292b92cda0306ec95b1bbf634c4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9018458691457746384",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e0b83bd148da6292b92cda0306ec95b1bbf634c4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0b83bd148da6292b92cda0306ec95b1bbf634c4",
"gitiles_ref:refs/heads/master"
]
}
[19:29:56.443] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615490995888009",
"id": "8853028629454497408",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0b83bd148da6292b92cda0306ec95b1bbf634c4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615490996227723",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181308",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e0b83bd148da6292b92cda0306ec95b1bbf634c4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0b83bd148da6292b92cda0306ec95b1bbf634c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018458691457746384",
"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/8853028629454497408/+/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": "1615490996227832",
"url": "https://ci.chromium.org/b/8853028629454497408",
"utcnow_ts": "1615490996436246"
}
}
[19:29:56.443] Task URL: https://ci.chromium.org/b/8853028629454497408
[19:29:56.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:2:0) after 1m0s
[19:30:22.574] Received PubSub notification, asking Buildbucket for the build status
[19:30:22.703] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:56.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:2:0)
[19:30:56.480] Timer tick, asking Buildbucket for the build status
[19:30:56.541] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:56.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:4:0) after 1m0s
[19:31:56.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:4:0)
[19:31:56.679] Timer tick, asking Buildbucket for the build status
[19:31:56.755] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:56.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:5:0) after 1m0s
[19:32:56.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:5:0)
[19:32:56.759] Timer tick, asking Buildbucket for the build status
[19:32:57.002] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:57.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:6:0) after 1m0s
[19:33:57.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:6:0)
[19:33:57.068] Timer tick, asking Buildbucket for the build status
[19:33:57.291] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:57.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:7:0) after 1m0s
[19:34:57.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:7:0)
[19:34:57.315] Timer tick, asking Buildbucket for the build status
[19:34:57.448] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:57.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:8:0) after 1m0s
[19:35:57.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:8:0)
[19:35:57.507] Timer tick, asking Buildbucket for the build status
[19:35:57.586] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:57.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:9:0) after 1m0s
[19:36:57.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:9:0)
[19:36:57.569] Timer tick, asking Buildbucket for the build status
[19:36:57.691] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:57.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:10:0) after 1m0s
[19:37:57.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:10:0)
[19:37:57.791] Timer tick, asking Buildbucket for the build status
[19:37:57.850] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:57.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:11:0) after 1m0s
[19:38:57.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:11:0)
[19:38:57.851] Timer tick, asking Buildbucket for the build status
[19:38:57.927] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:57.927] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:12:0) after 1m0s
[19:39:58.110] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:12:0)
[19:39:58.111] Timer tick, asking Buildbucket for the build status
[19:39:58.332] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:58.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:13:0) after 1m0s
[19:40:58.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:13:0)
[19:40:58.353] Timer tick, asking Buildbucket for the build status
[19:40:58.438] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:58.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:14:0) after 1m0s
[19:41:58.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:14:0)
[19:41:58.518] Timer tick, asking Buildbucket for the build status
[19:41:58.611] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:58.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:15:0) after 1m0s
[19:42:58.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:15:0)
[19:42:58.686] Timer tick, asking Buildbucket for the build status
[19:42:58.749] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:58.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:16:0) after 1m0s
[19:43:58.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:16:0)
[19:43:58.777] Timer tick, asking Buildbucket for the build status
[19:43:58.945] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:58.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:17:0) after 1m0s
[19:44:59.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:17:0)
[19:44:59.077] Timer tick, asking Buildbucket for the build status
[19:44:59.190] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:59.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:18:0) after 1m0s
[19:45:59.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:18:0)
[19:45:59.240] Timer tick, asking Buildbucket for the build status
[19:45:59.304] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:59.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:19:0) after 1m0s
[19:46:59.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:19:0)
[19:46:59.385] Timer tick, asking Buildbucket for the build status
[19:46:59.459] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:59.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:20:0) after 1m0s
[19:47:59.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:20:0)
[19:47:59.477] Timer tick, asking Buildbucket for the build status
[19:47:59.598] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:59.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:21:0) after 1m0s
[19:48:59.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:21:0)
[19:48:59.608] Timer tick, asking Buildbucket for the build status
[19:48:59.694] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:59.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:22:0) after 1m0s
[19:49:59.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:22:0)
[19:49:59.711] Timer tick, asking Buildbucket for the build status
[19:49:59.784] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:59.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:23:0) after 1m0s
[19:50:59.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:23:0)
[19:50:59.814] Timer tick, asking Buildbucket for the build status
[19:50:59.882] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:59.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:24:0) after 1m0s
[19:51:59.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:24:0)
[19:51:59.912] Timer tick, asking Buildbucket for the build status
[19:51:59.999] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:25:0) after 1m0s
[19:53:00.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:25:0)
[19:53:00.019] Timer tick, asking Buildbucket for the build status
[19:53:00.263] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:00.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:26:0) after 1m0s
[19:54:00.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:26:0)
[19:54:00.320] Timer tick, asking Buildbucket for the build status
[19:54:00.548] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:00.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:27:0) after 1m0s
[19:55:00.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:27:0)
[19:55:00.543] Timer tick, asking Buildbucket for the build status
[19:55:00.719] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:00.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:28:0) after 1m0s
[19:56:00.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:28:0)
[19:56:00.744] Timer tick, asking Buildbucket for the build status
[19:56:00.827] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:00.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:29:0) after 1m0s
[19:57:00.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:29:0)
[19:57:00.865] Timer tick, asking Buildbucket for the build status
[19:57:01.081] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:01.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:30:0) after 1m0s
[19:58:01.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:30:0)
[19:58:01.255] Timer tick, asking Buildbucket for the build status
[19:58:01.440] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:01.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:31:0) after 1m0s
[19:59:01.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:31:0)
[19:59:01.470] Timer tick, asking Buildbucket for the build status
[19:59:01.554] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:01.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:32:0) after 1m0s
[20:00:01.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:32:0)
[20:00:01.587] Timer tick, asking Buildbucket for the build status
[20:00:02.146] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:02.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:33:0) after 1m0s
[20:01:02.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:33:0)
[20:01:02.237] Timer tick, asking Buildbucket for the build status
[20:01:02.310] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:02.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:34:0) after 1m0s
[20:02:02.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:34:0)
[20:02:02.370] Timer tick, asking Buildbucket for the build status
[20:02:02.722] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:02.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:35:0) after 1m0s
[20:03:02.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:35:0)
[20:03:02.768] Timer tick, asking Buildbucket for the build status
[20:03:02.975] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:02.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:36:0) after 1m0s
[20:04:02.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:36:0)
[20:04:02.993] Timer tick, asking Buildbucket for the build status
[20:04:03.074] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:03.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:37:0) after 1m0s
[20:05:03.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:37:0)
[20:05:03.096] Timer tick, asking Buildbucket for the build status
[20:05:03.184] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:03.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:38:0) after 1m0s
[20:06:03.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:38:0)
[20:06:03.211] Timer tick, asking Buildbucket for the build status
[20:06:03.562] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:03.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:39:0) after 1m0s
[20:07:03.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:39:0)
[20:07:03.580] Timer tick, asking Buildbucket for the build status
[20:07:04.416] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:04.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:40:0) after 1m0s
[20:08:04.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:40:0)
[20:08:04.423] Timer tick, asking Buildbucket for the build status
[20:08:07.224] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:07.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:41:0) after 1m0s
[20:09:07.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:41:0)
[20:09:07.343] Timer tick, asking Buildbucket for the build status
[20:09:07.403] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:07.403] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:42:0) after 1m0s
[20:10:07.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:42:0)
[20:10:07.449] Timer tick, asking Buildbucket for the build status
[20:10:07.600] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:07.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:43:0) after 1m0s
[20:11:07.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:43:0)
[20:11:07.717] Timer tick, asking Buildbucket for the build status
[20:11:08.141] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:08.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:44:0) after 1m0s
[20:12:08.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:44:0)
[20:12:08.192] Timer tick, asking Buildbucket for the build status
[20:12:08.296] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:08.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:45:0) after 1m0s
[20:13:08.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:45:0)
[20:13:08.337] Timer tick, asking Buildbucket for the build status
[20:13:08.421] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:08.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:46:0) after 1m0s
[20:14:08.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:46:0)
[20:14:08.525] Timer tick, asking Buildbucket for the build status
[20:14:08.726] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:08.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:47:0) after 1m0s
[20:15:08.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:47:0)
[20:15:08.866] Timer tick, asking Buildbucket for the build status
[20:15:08.958] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:08.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:48:0) after 1m0s
[20:16:09.047] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:48:0)
[20:16:09.047] Timer tick, asking Buildbucket for the build status
[20:16:09.823] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:09.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:49:0) after 1m0s
[20:17:10.145] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:49:0)
[20:17:10.145] Timer tick, asking Buildbucket for the build status
[20:17:10.247] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:10.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:50:0) after 1m0s
[20:18:10.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:50:0)
[20:18:10.318] Timer tick, asking Buildbucket for the build status
[20:18:10.390] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:10.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:51:0) after 1m0s
[20:19:10.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:51:0)
[20:19:10.468] Timer tick, asking Buildbucket for the build status
[20:19:10.554] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:10.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:52:0) after 1m0s
[20:20:10.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:52:0)
[20:20:10.586] Timer tick, asking Buildbucket for the build status
[20:20:10.909] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:10.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:53:0) after 1m0s
[20:21:10.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:53:0)
[20:21:10.993] Timer tick, asking Buildbucket for the build status
[20:21:11.075] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:54:0) after 1m0s
[20:22:11.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:54:0)
[20:22:11.096] Timer tick, asking Buildbucket for the build status
[20:22:11.167] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:11.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:55:0) after 1m0s
[20:23:11.217] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:55:0)
[20:23:11.217] Timer tick, asking Buildbucket for the build status
[20:23:11.324] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:11.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:56:0) after 1m0s
[20:24:11.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:56:0)
[20:24:11.566] Timer tick, asking Buildbucket for the build status
[20:24:11.649] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:11.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:57:0) after 1m0s
[20:25:11.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:57:0)
[20:25:11.792] Timer tick, asking Buildbucket for the build status
[20:25:11.906] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:11.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:58:0) after 1m0s
[20:26:11.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:58:0)
[20:26:11.959] Timer tick, asking Buildbucket for the build status
[20:26:12.035] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:12.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:59:0) after 1m0s
[20:27:12.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:59:0)
[20:27:12.109] Timer tick, asking Buildbucket for the build status
[20:27:12.193] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:12.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:60:0) after 1m0s
[20:28:12.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:60:0)
[20:28:12.226] Timer tick, asking Buildbucket for the build status
[20:28:12.312] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:12.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:61:0) after 1m0s
[20:29:12.344] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:61:0)
[20:29:12.344] Timer tick, asking Buildbucket for the build status
[20:29:12.625] Build 8853028629454497408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:12.625] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018458691457746384:62:0) after 1m0s
[20:30:03.584] Received PubSub notification, asking Buildbucket for the build status
[20:30:03.662] Build 8853028629454497408: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:30:03.663] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615494602350364",
"created_by": "project:chromium",
"created_ts": "1615490995888009",
"id": "8853028629454497408",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0b83bd148da6292b92cda0306ec95b1bbf634c4\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"e0b83bd148da6292b92cda0306ec95b1bbf634c4\", \"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-fu69\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181308, \"got_angle_revision\": \"6f74a12b396bb8039f1f2446e62dbe07ec0886a5\", \"got_dawn_revision\": \"9f6bc4e3a95a5ae4e009d76812f752340d1d6181\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"e0b83bd148da6292b92cda0306ec95b1bbf634c4\", \"got_revision_cp\": \"refs/heads/master@{#862078}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a2fe8e1b2111e69d8846991f9d9bcc5c49505d1c\", \"got_v8_revision\": \"08ad3359339358d65587e274741c75e3c140c91f\", \"got_v8_revision_cp\": \"refs/heads/9.1.102@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0b83bd148da6292b92cda0306ec95b1bbf634c4\", \"swarm_hashes_refs/heads/master(at){#862078}_without_patch\": {\"absl_hardening_tests\": \"61d1822cc46f24d13f4c427fd7070ba4a5e8638a\", \"accessibility_unittests\": \"e43904361cd571890c68ee8e605a03027d061ff3\", \"angle_unittests\": \"96787f6ebea019aabd83d3f01b0f4c1f5181953f\", \"app_shell_unittests\": \"2e3f7bc90ca181f46ee632f3b44b301cb836a464\", \"aura_unittests\": \"add6eb76dbd0572aefc3dcdd418b03ae49bd0bec\", \"base_unittests\": \"3374dc7a3a9ce5f462667dee37fbb09e49905a18\", \"base_util_unittests\": \"6dad678209150d41d83c97ce88800881b92edb10\", \"blink_common_unittests\": \"b5993c7ad728fbc62baa62c03ab105a1c1fad455\", \"blink_fuzzer_unittests\": \"098d72a7228c30c32cbdd1679419d2243563031e\", \"blink_heap_unittests\": \"63da05763f3118142cb3af56ae733f1353c9b9b0\", \"blink_platform_unittests\": \"6251d95acae463bc670288e7317f4884814f7056\", \"blink_python_tests\": \"dc3eb96472a596b8e00a4a30b9d6cbd3f0fac0c4\", \"blink_unittests\": \"b22318e4f217a0870f17a856f5ad14e95545b516\", \"blink_web_tests\": \"bbc31c29d8770edc9315decd5e77ca570f191de6\", \"boringssl_crypto_tests\": \"9418988c9d9f77d5282d9aea117e3e756154bf47\", \"boringssl_ssl_tests\": \"7b31f2d160f8bd9424e3da4271e6b4317ab748d8\", \"browser_tests\": \"3f83d062ebac974824174e3d3ff0c5c8b2ad9159\", \"capture_unittests\": \"a0916b2b7f9adc2fd2c3cc0545ba0eaf126a6dd4\", \"cast_unittests\": \"aa75917e25cade7861737f2d3b34851768d024fe\", \"cc_unittests\": \"3c06f3db89c1bec4812b0406d6fa2536ff5e4f40\", \"chrome_app_unittests\": \"05d1de25990fdb75dbdbffb99dc08150c4155121\", \"chromedriver_unittests\": \"ba22cf11dac49fb0220ca0e3bbeedc93b434353d\", \"color_unittests\": \"fb90d9064d64b9dd06988c9481a054601faed847\", \"components_browsertests\": \"199ad4d380acb88d6d7d59c7d7891a032fcfaf82\", \"components_unittests\": \"2e5a104af52b50af02d696288d4747bad1ec2231\", \"compositor_unittests\": \"b33f6947e8eb7f98b42af696123268cdd6d7c54a\", \"content_browsertests\": \"cd1d3f5b662dda0ee6d4a3aeef7bb23281df3d84\", \"content_shell_crash_test\": \"6b7007937db00b437dd56409ffab295a3f8dc702\", \"content_unittests\": \"b116869157c7044f1e1df717f921c607df8de6a5\", \"crashpad_tests\": \"d7c30871af45611aaab09ba2644fac5c0392e30e\", \"cronet_tests\": \"16d1ea44c5d0aaecb1afc4346c1acbd3d143a13c\", \"cronet_unittests\": \"411659a9dc979526ba28775dd31dcc07ff91e004\", \"crypto_unittests\": \"df8741530921ed96df1ebf10b4c33faad9d3ed6d\", \"dbus_unittests\": \"deca5b7206c5741d46a0bfce55e4a5947b622384\", \"device_unittests\": \"2b8e7accc77d6f107c323ba31e6c3d8d7753c6d3\", \"display_unittests\": \"09e9c1657bf583ab56c7cf435a76fd7055459b0d\", \"events_unittests\": \"fe2888a5f8a5e16687f0d89483ffff2ff125b9c2\", \"extensions_browsertests\": \"d5bbdb2b0f9447399faf25c3834bfb2173d2e98a\", \"extensions_unittests\": \"059fb3546de2ec2a937899b7ab03ed645ecf5e47\", \"filesystem_service_unittests\": \"2a035084e7330430d11eb7aa1beae09e33be6f21\", \"flatbuffers_unittests\": \"917c1926f9a03d6ef30fc9665ef3a1d956152ddf\", \"gcm_unit_tests\": \"22781caf2abad51f439516d6e8258b4b66522ec5\", \"gfx_unittests\": \"a8568edfeb0802f69eb0a40305d4c1e8a980a2b0\", \"gin_unittests\": \"640d3eff4c6556f112d2f1ae07a473901be4a9ae\", \"google_apis_unittests\": \"138b19c582505685a28c78775cc2ba74d1323be7\", \"gpu_unittests\": \"c4a106a3caaeeb4aba0376a68a9c79756803b80c\", \"grit_python_unittests\": \"34dc6f3cc2d88a5fac848f4742c34f024387cdb5\", \"gwp_asan_unittests\": \"a553a97b8ca64faee7f7964549b881ffc02729b7\", \"headless_browsertests\": \"5a3ed81e86852c649f127144c3fa6fab5adc6e8c\", \"headless_unittests\": \"82f0d2568ca95acbd62754521f74e08a9e8c9670\", \"interactive_ui_tests\": \"c35e266e4d4bfd1fbbb208c0fdaa94415bea860c\", \"ipc_tests\": \"d7b525d9aa1d06452023c9266a40c95b97d64be6\", \"jingle_unittests\": \"d11ace192a0d29123aebf7fb93eacf8049f90edd\", \"latency_unittests\": \"1ca98d0a6b49786bfd4c94c079365b6def1257db\", \"libjingle_xmpp_unittests\": \"0bbe17259f403b961a64578c1210c9eceb47a10a\", \"liburlpattern_unittests\": \"b8b6d4d89a83d172b2669585a7b05990d1c00a46\", \"media_blink_unittests\": \"03ba232fc758c745306e21e25fba42d67e6afddd\", \"media_unittests\": \"46c69bc9460ad8d37a1632515dddd78a388bba39\", \"message_center_unittests\": \"106024a8b8c236033a9a897f09cff6fc4cc093c3\", \"metrics_python_tests\": \"755dc3db139bb32b75fd149aea152278794adbe2\", \"midi_unittests\": \"9bc3ebc7ed5b47a609edacd492d19f1cfc1aec57\", \"mojo_core_unittests\": \"7f5af554b3900f5a4cc8ad74474c3eeb7a2b2445\", \"mojo_unittests\": \"4f026c9bdc8b0b3e083791a919418fdf441857fd\", \"nacl_helper_nonsfi_unittests\": \"07c9d0f3d1d7515293464f5d4e41dc0504a2022a\", \"nacl_loader_unittests\": \"526bf9f55f50f8410130c2e26c8ca0c0cf3c7ada\", \"native_theme_unittests\": \"87e91a5d484abc17652703e3631dd4b4d4248ec0\", \"net_unittests\": \"863be421bf44f1af52d19949e1aa5946fba5b03c\", \"openscreen_unittests\": \"21cd75413d4eb253c00f1d89c7779adf0b3a3c5a\", \"pdf_unittests\": \"f3b6266e87a406165eb1abf8b9c8cc00e7625ef8\", \"perfetto_unittests\": \"d9288baddc6ca2c384e9182aa85f2374e0652a9e\", \"ppapi_unittests\": \"07b05a3ecd537a33be72990e37e7a741f0de322c\", \"printing_unittests\": \"87ab143b95545e36e36351a4a41fdbb1474ffb9b\", \"remoting_unittests\": \"ee41db074f9b7ab386098be56565ff3c17113ec7\", \"sandbox_linux_unittests\": \"6717ba18bc90dffc0de3cadcd79204f97f3ed367\", \"service_manager_unittests\": \"a254551633dfbe7c4268c784bad15ef3b29aba60\", \"services_unittests\": \"8dc9a32fa4e5c0c6ea324c77524b6ad5ab54d0f8\", \"shell_dialogs_unittests\": \"3b4900848326fead57525815b9ebe68fa4886824\", \"skia_unittests\": \"488e9016f0df5645141da91a9f46aba773b611c4\", \"snapshot_unittests\": \"04fef327e68d54d2059aedd1ba2266651420426c\", \"sql_unittests\": \"3390bac8978f47e2d543eb81b1c9e03e14370e22\", \"storage_unittests\": \"603d1df742a5eb90c2d144ae89b601e2f1cf41db\", \"sync_integration_tests\": \"daa34998c4ec189cf253331fb1684856ecaf713c\", \"telemetry_gpu_unittests\": \"a1f8bfab51fd1fb737f325467bd17d5a65aeb6d0\", \"telemetry_perf_unittests\": \"73b0882cd2360e96a17fc143670569a59156c61a\", \"telemetry_unittests\": \"8984bb3575e46ffcf7f931dbb7e53e5cdf2fe5f4\", \"traffic_annotation_auditor_unittests\": \"71114b097c8651a2b0f6fa434dcfb79782bfd7c1\", \"ui_base_unittests\": \"373fb54da33de7dfabb1c79bb1cec355501c7b48\", \"ui_touch_selection_unittests\": \"c9831dd20f313237347506d8ec715500ad89f37e\", \"unit_tests\": \"9eebd973b411e61ee5baf455b2b92ffb336a52be\", \"url_unittests\": \"a314dddc031f6f4281d9cf7656d5c5e09c52de0e\", \"views_perftests\": \"8e7954b281407672bba58854ca411c383b604cfd\", \"views_unittests\": \"a04d7d9ba4979a79971129e1e0d7382961a22fe3\", \"viz_unittests\": \"ad926398f3091f90476be05cb71c34d1a1f964b0\", \"vr_common_unittests\": \"10e49864a3171140c60df6f80cca219db92fc849\", \"vr_pixeltests\": \"1fb0afc8ae230ec718f3a3fd5ced60b432283b14\", \"webdriver_wpt_tests\": \"57d2ad005ed971dd1972783333c576d804c24331\", \"weblayer_browsertests\": \"5e731e1b4fdb6ca82c365826eae37267f5f06624\", \"weblayer_unittests\": \"aa1bd6fa26d32b6e9ee8903b8090f5e6f0955a79\", \"wm_unittests\": \"1d6b770e0a470752bd954e87a37ee5532ea06f2f\", \"wtf_unittests\": \"9d208fb03a8c7b9031f107891c74c0d788038073\", \"x11_unittests\": \"1f5398858bbe2771dc6f72454849f21f18ffd00d\", \"xr_browser_tests\": \"213671327e14a891e9ef64624219624af1e665ad\", \"zlib_unittests\": \"d6eb00d8fd11ea31d783eb39800334010fc7880d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_9f247f4686c5cf47ea762220cc037b3e853f43c1442c10d387faa806e919f9a9_v2\", \"builder_de67dcb387b95c18ea83e54ea320474d260d319089536d63e3279fdbb975ff72_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-fu69\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615491020615193",
"status": "COMPLETED",
"status_changed_ts": "1615494602671417",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181308",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e0b83bd148da6292b92cda0306ec95b1bbf634c4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0b83bd148da6292b92cda0306ec95b1bbf634c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018458691457746384",
"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/8853028629454497408/+/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:523b95e1f8716e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615494602675656",
"url": "https://ci.chromium.org/b/8853028629454497408",
"utcnow_ts": "1615494603655090"
}
}
[20:30:03.663] Invocation finished in 1h0m9.068284509s with status SUCCEEDED