[16:23:40.252] New invocation is queued and will start shortly
[16:23:41.357] Starting the invocation (attempt 1)
[16:23:41.393] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:23:41.393] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:23:41.394] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055252776984148704",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c1135661a4919df618312b03db714dafcea4b096\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055252776984148704",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/c1135661a4919df618312b03db714dafcea4b096",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c1135661a4919df618312b03db714dafcea4b096",
"gitiles_ref:refs/heads/master"
]
}
[16:23:42.792] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580401421986406",
"id": "8889822714497810688",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c1135661a4919df618312b03db714dafcea4b096\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580401422674013",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168038",
"builder:Linux Builder (dbg)",
"buildset:commit/git/c1135661a4919df618312b03db714dafcea4b096",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c1135661a4919df618312b03db714dafcea4b096",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055252776984148704",
"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/8889822714497810688/+/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": "1580401422674147",
"url": "https://ci.chromium.org/b/8889822714497810688",
"utcnow_ts": "1580401422785249"
}
}
[16:23:42.792] Task URL: https://ci.chromium.org/b/8889822714497810688
[16:23:42.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:2:0) after 1m0s
[16:24:42.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:2:0)
[16:24:42.969] Timer tick, asking Buildbucket for the build status
[16:24:43.131] Build 8889822714497810688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:43.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:3:0) after 1m0s
[16:25:43.160] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:3:0)
[16:25:43.160] Timer tick, asking Buildbucket for the build status
[16:25:43.291] Build 8889822714497810688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:43.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:4:0) after 1m0s
[16:26:43.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:4:0)
[16:26:43.438] Timer tick, asking Buildbucket for the build status
[16:26:43.635] Build 8889822714497810688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:43.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:5:0) after 1m0s
[16:27:43.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:5:0)
[16:27:43.667] Timer tick, asking Buildbucket for the build status
[16:27:43.927] Build 8889822714497810688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:43.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:6:0) after 1m0s
[16:28:05.855] Received PubSub notification, asking Buildbucket for the build status
[16:28:06.011] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:43.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:6:0)
[16:28:43.963] Timer tick, asking Buildbucket for the build status
[16:28:44.148] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:44.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:8:0) after 1m0s
[16:29:44.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:8:0)
[16:29:44.253] Timer tick, asking Buildbucket for the build status
[16:29:44.426] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:44.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:9:0) after 1m0s
[16:30:44.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:9:0)
[16:30:44.585] Timer tick, asking Buildbucket for the build status
[16:30:45.211] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:45.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:10:0) after 1m0s
[16:31:45.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:10:0)
[16:31:45.219] Timer tick, asking Buildbucket for the build status
[16:31:45.369] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:45.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:11:0) after 1m0s
[16:32:45.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:11:0)
[16:32:45.505] Timer tick, asking Buildbucket for the build status
[16:32:45.877] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:45.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:12:0) after 1m0s
[16:33:45.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:12:0)
[16:33:45.878] Timer tick, asking Buildbucket for the build status
[16:33:46.027] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:46.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:13:0) after 1m0s
[16:34:46.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:13:0)
[16:34:46.124] Timer tick, asking Buildbucket for the build status
[16:34:46.533] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:46.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:14:0) after 1m0s
[16:35:46.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:14:0)
[16:35:46.629] Timer tick, asking Buildbucket for the build status
[16:35:47.760] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:47.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:15:0) after 1m0s
[16:36:47.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:15:0)
[16:36:47.814] Timer tick, asking Buildbucket for the build status
[16:36:48.750] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:48.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:16:0) after 1m0s
[16:37:48.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:16:0)
[16:37:48.911] Timer tick, asking Buildbucket for the build status
[16:37:49.056] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:49.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:17:0) after 1m0s
[16:38:49.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:17:0)
[16:38:49.193] Timer tick, asking Buildbucket for the build status
[16:38:49.367] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:49.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:18:0) after 1m0s
[16:39:49.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:18:0)
[16:39:49.540] Timer tick, asking Buildbucket for the build status
[16:39:50.143] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:50.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:19:0) after 1m0s
[16:40:50.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:19:0)
[16:40:50.244] Timer tick, asking Buildbucket for the build status
[16:40:50.520] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:50.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:20:0) after 1m0s
[16:41:50.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:20:0)
[16:41:50.588] Timer tick, asking Buildbucket for the build status
[16:41:51.039] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:51.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:21:0) after 1m0s
[16:42:51.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:21:0)
[16:42:51.045] Timer tick, asking Buildbucket for the build status
[16:42:51.543] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:51.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:22:0) after 1m0s
[16:43:51.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:22:0)
[16:43:51.626] Timer tick, asking Buildbucket for the build status
[16:43:51.910] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:51.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:23:0) after 1m0s
[16:44:51.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:23:0)
[16:44:51.933] Timer tick, asking Buildbucket for the build status
[16:44:52.112] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:52.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:24:0) after 1m0s
[16:45:52.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:24:0)
[16:45:52.253] Timer tick, asking Buildbucket for the build status
[16:45:52.507] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:52.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:25:0) after 1m0s
[16:46:52.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:25:0)
[16:46:52.549] Timer tick, asking Buildbucket for the build status
[16:46:52.811] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:52.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:26:0) after 1m0s
[16:47:52.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:26:0)
[16:47:52.958] Timer tick, asking Buildbucket for the build status
[16:47:53.446] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:53.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:27:0) after 1m0s
[16:48:53.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:27:0)
[16:48:53.428] Timer tick, asking Buildbucket for the build status
[16:48:55.149] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:55.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:28:0) after 1m0s
[16:49:55.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:28:0)
[16:49:55.216] Timer tick, asking Buildbucket for the build status
[16:49:55.367] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:55.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:29:0) after 1m0s
[16:50:55.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:29:0)
[16:50:55.458] Timer tick, asking Buildbucket for the build status
[16:50:55.691] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:55.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:30:0) after 1m0s
[16:51:55.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:30:0)
[16:51:55.793] Timer tick, asking Buildbucket for the build status
[16:51:55.986] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:55.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:31:0) after 1m0s
[16:52:56.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:31:0)
[16:52:56.119] Timer tick, asking Buildbucket for the build status
[16:52:56.308] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:56.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:32:0) after 1m0s
[16:53:56.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:32:0)
[16:53:56.417] Timer tick, asking Buildbucket for the build status
[16:53:56.600] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:56.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:33:0) after 1m0s
[16:54:56.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:33:0)
[16:54:56.655] Timer tick, asking Buildbucket for the build status
[16:54:56.821] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:56.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:34:0) after 1m0s
[16:55:56.897] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:34:0)
[16:55:56.897] Timer tick, asking Buildbucket for the build status
[16:55:57.199] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:57.200] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:35:0) after 1m0s
[16:56:57.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:35:0)
[16:56:57.264] Timer tick, asking Buildbucket for the build status
[16:56:57.492] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:57.492] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:36:0) after 1m0s
[16:57:57.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:36:0)
[16:57:57.521] Timer tick, asking Buildbucket for the build status
[16:57:57.874] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:57.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:37:0) after 1m0s
[16:58:57.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:37:0)
[16:58:57.963] Timer tick, asking Buildbucket for the build status
[16:58:58.574] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:58.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:38:0) after 1m0s
[16:59:58.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:38:0)
[16:59:58.638] Timer tick, asking Buildbucket for the build status
[16:59:58.858] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:58.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:39:0) after 1m0s
[17:00:58.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:39:0)
[17:00:58.958] Timer tick, asking Buildbucket for the build status
[17:00:59.104] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:59.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:40:0) after 1m0s
[17:01:59.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:40:0)
[17:01:59.193] Timer tick, asking Buildbucket for the build status
[17:01:59.366] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:59.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:41:0) after 1m0s
[17:02:59.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:41:0)
[17:02:59.413] Timer tick, asking Buildbucket for the build status
[17:02:59.712] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:42:0) after 1m0s
[17:03:59.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:42:0)
[17:03:59.732] Timer tick, asking Buildbucket for the build status
[17:04:00.143] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:00.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:43:0) after 1m0s
[17:05:00.232] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:43:0)
[17:05:00.232] Timer tick, asking Buildbucket for the build status
[17:05:00.394] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:00.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:44:0) after 1m0s
[17:06:00.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:44:0)
[17:06:00.408] Timer tick, asking Buildbucket for the build status
[17:06:00.705] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:00.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:45:0) after 1m0s
[17:07:00.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:45:0)
[17:07:00.795] Timer tick, asking Buildbucket for the build status
[17:07:01.024] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:01.024] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:46:0) after 1m0s
[17:08:01.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:46:0)
[17:08:01.188] Timer tick, asking Buildbucket for the build status
[17:08:01.351] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:01.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:47:0) after 1m0s
[17:09:01.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:47:0)
[17:09:01.417] Timer tick, asking Buildbucket for the build status
[17:09:01.576] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:01.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:48:0) after 1m0s
[17:10:01.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:48:0)
[17:10:01.600] Timer tick, asking Buildbucket for the build status
[17:10:01.742] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:01.742] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:49:0) after 1m0s
[17:11:01.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:49:0)
[17:11:01.778] Timer tick, asking Buildbucket for the build status
[17:11:02.005] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:02.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:50:0) after 1m0s
[17:12:02.101] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:50:0)
[17:12:02.101] Timer tick, asking Buildbucket for the build status
[17:12:02.588] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:02.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:51:0) after 1m0s
[17:13:02.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:51:0)
[17:13:02.720] Timer tick, asking Buildbucket for the build status
[17:13:02.930] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:02.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:52:0) after 1m0s
[17:14:03.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:52:0)
[17:14:03.062] Timer tick, asking Buildbucket for the build status
[17:14:03.245] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:03.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:53:0) after 1m0s
[17:15:03.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:53:0)
[17:15:03.282] Timer tick, asking Buildbucket for the build status
[17:15:03.441] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:03.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:54:0) after 1m0s
[17:16:03.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:54:0)
[17:16:03.511] Timer tick, asking Buildbucket for the build status
[17:16:03.737] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:03.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:55:0) after 1m0s
[17:17:03.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:55:0)
[17:17:03.758] Timer tick, asking Buildbucket for the build status
[17:17:04.512] Build 8889822714497810688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:04.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055252776984148704:56:0) after 1m0s
[17:18:02.972] Received PubSub notification, asking Buildbucket for the build status
[17:18:03.450] Build 8889822714497810688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:18:03.450] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580404682234069",
"created_by": "project:chromium",
"created_ts": "1580401421986406",
"id": "8889822714497810688",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c1135661a4919df618312b03db714dafcea4b096\"}}",
"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-central1-b-8-140-0cna\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168038, \"got_angle_revision\": \"ee07cb317579dfda40dabb5d6d9c209e0e9e0643\", \"got_dawn_revision\": \"48e7da791f1496ea836f8d92d1af1b120eee1d6a\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"c1135661a4919df618312b03db714dafcea4b096\", \"got_revision_cp\": \"refs/heads/master@{#736887}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"6c3dc3581eaf4345c0507d5ac7bb013d55351547\", \"got_v8_revision\": \"a4dcd39d521d14c4b1cac020812e44ee04a7f244\", \"got_v8_revision_cp\": \"refs/heads/8.1.307@{#1}\", \"got_webrtc_revision\": \"5dca3f1336a6d18633aea31b043ca4466bbae33b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30423}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c1135661a4919df618312b03db714dafcea4b096\", \"swarm_hashes_refs/heads/master(at){#736887}_without_patch\": {\"accessibility_unittests\": \"05a363b481b3b62e368b5d9680508eca89374946\", \"angle_unittests\": \"0f3fac627117fd5497f25375c2c89868b979162d\", \"app_shell_unittests\": \"058954751e5b9bfcecb4e33c3d6494850cce13c7\", \"aura_unittests\": \"78649018575dd9f8198edc51f50fc2de98456129\", \"base_unittests\": \"f2be53a5482d1c32a34ce5fb0dfe7d728ee1653a\", \"base_util_unittests\": \"59ffe0b6b277e9c7094df13c2a42c0dbf256e585\", \"blink_common_unittests\": \"2f106fb6459e254183a79b73db65f2f45e38ad82\", \"blink_fuzzer_unittests\": \"79c2b9bbbc3e9bd2510591c03bbcadd35409a912\", \"blink_heap_unittests\": \"afbf2066a85e1fc4c1a0023c8176d0720dbdaf6e\", \"blink_platform_unittests\": \"4d154d92d0cabda4573a508c498109fc5cd89867\", \"blink_python_tests\": \"99b2e0e988f954af6260bbb4364c85b77ec8ec1d\", \"blink_unittests\": \"02455cd84cd6e44effbf65af1eba5774a6dcca94\", \"blink_web_tests\": \"1d37d4503b6c76b0ddab6f483fa23354798b7db6\", \"boringssl_crypto_tests\": \"69d574fa72df0002c8679dd9d9b1715e037d7fe1\", \"boringssl_ssl_tests\": \"b864b087e12e9f8e946ec3222bc137258cf6b6a5\", \"browser_tests\": \"9fc2c6f7eca3904383d68760bc1ee6ad0673c884\", \"cacheinvalidation_unittests\": \"430fa97896890dbd19eddd862433f09d1b06372c\", \"capture_unittests\": \"cdd6db91be394d06bfad3f44ccf78e9a44a05f5a\", \"cast_unittests\": \"b7bd3db861546a72f88091c17b8db002b7e0d7d4\", \"cc_unittests\": \"b62b290a62563395b19183a082333ce2c9ed95cb\", \"chrome_app_unittests\": \"c04719d1b085e72737c628b9a8d1a14144ed0d89\", \"chromedriver_unittests\": \"577690d6d11b08c6f59b70b4ed620cf9494a40eb\", \"components_browsertests\": \"a8ef627caadcb25f0261df1c164c1f84675fce30\", \"components_unittests\": \"c2025a25c07c58dc3bc4e2843d9100c00f3837d8\", \"compositor_unittests\": \"a8cd8cc5e21af9431f56424cec25343bb7ade5e1\", \"content_browsertests\": \"3a305ec3282157e9334f8075c368ccab5265ad17\", \"content_shell_crash_test\": \"8f837c875a2c8815e397a14104ed4a0747b9d6d6\", \"content_unittests\": \"5e5ac84e76edffbce949cca1f64f0d50e8f6ea5e\", \"crashpad_tests\": \"5d393d3131bb4390cd974a63cc174db376ab6eb5\", \"cronet_tests\": \"f30052a3bd42f3b38014f0e3f7a0aff105daa101\", \"cronet_unittests\": \"462159cf60cf0c1df15d59acd07ea4e7811f9f1d\", \"crypto_unittests\": \"f1c3bb2a4aa2f7efca0ed0bff3e14c93001dcc76\", \"dbus_unittests\": \"16be16de8b791abf68d67e698f9bdc7998562f52\", \"device_unittests\": \"e5c8fc48323afc6055755d9d28dee64cc31d07ad\", \"display_unittests\": \"bc435db1ff754b2c6dd455f3bbb8853d141f71ce\", \"events_unittests\": \"47604396a2004c5e47aff47a98d10dd9401c6dff\", \"extensions_browsertests\": \"9b271c0e133fc48601c49dbd25dfaae5aba8d105\", \"extensions_unittests\": \"c3c0258c74f4402a4cc85f2a874500c336487125\", \"filesystem_service_unittests\": \"900c29777ff27a4643eefefb699d9799a65c04f6\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"aa1fab22590426eb753f129e461ee883cccdc41a\", \"gfx_unittests\": \"874fd442ecdf45e70b420658134e03dfc9be46ac\", \"gin_unittests\": \"b92af31f73ad6de8ee52e58a8d184b9ab834862d\", \"google_apis_unittests\": \"bd079d1249236de31626910585929be3eb8d6d4d\", \"gpu_unittests\": \"f5d8509993e07cc310ac1ed0d92aca443effa967\", \"grit_python_unittests\": \"896da2265579af982b0c06ff8662522b696fea99\", \"gwp_asan_unittests\": \"42356ef43019fbf619a793ab89d18eac5408ca5d\", \"headless_browsertests\": \"6ecc75afbe85640f16c2d87d58f577ed622e2c6d\", \"headless_unittests\": \"f7d3e95c273f3300918528a4dc87f0b651445294\", \"interactive_ui_tests\": \"d1d6998c5d4687a1c521f7620287a186f24a93a9\", \"ipc_tests\": \"ced41ef7cc3fea31511a46ec333426bec959fa6a\", \"jingle_unittests\": \"5c563810b6c99a1b8041d255b70f1a9811b49d17\", \"latency_unittests\": \"4e841d4b1185f17ac9d411db2e7fa4d4689ba969\", \"libjingle_xmpp_unittests\": \"180ad316887b28ef7743399c84fcdf3c75edf4b9\", \"media_blink_unittests\": \"5387a71f547b275d7c312afab84b44fe66e5c19c\", \"media_service_unittests\": \"cb357e21b4185a14c273a85c065fb13713b89d6c\", \"media_unittests\": \"95c88bb6214c07f09424e3d497399c843bfff7cf\", \"message_center_unittests\": \"bee54afd40318582f254b3f3e0a88fe8efc2ff56\", \"metrics_python_tests\": \"4ab6673be3f4083924fcc76c11ec726e3fc9d639\", \"midi_unittests\": \"b6427305c00aec15b332a5ea98d67d5140a7d7b4\", \"mojo_core_unittests\": \"10fc861d9754dcee00bc1034ae344ddbc6aaf2ad\", \"mojo_unittests\": \"c0bb560d16a4aebc70db8d96aad802fe4bdc697a\", \"nacl_helper_nonsfi_unittests\": \"099385c72dd768078c760addd6f683e2f2c94dbb\", \"nacl_loader_unittests\": \"aab736dcbcd14e0f06e65e60ba04ac73ada4600a\", \"native_theme_unittests\": \"6a5d8c5386ec4188610ce465dd579e2f778e897d\", \"net_unittests\": \"92a4e0d6a7fd6e9d7eb276b0e93f9c0b8f1c364b\", \"pdf_unittests\": \"f62a05ef39f8f8123038b43dda3adbbfb8ddee27\", \"perfetto_unittests\": \"a207ddd190868a30ef24a7b0e0a97dbce75a3ff5\", \"ppapi_unittests\": \"2eeeed7af30045cf63dcf5b83b679e6c03b8c4a2\", \"printing_unittests\": \"2bd823fe680a51e28976e67dd328de4413e70b07\", \"remoting_unittests\": \"febcce147b8fd1ea2c3aee077acd64d537e98412\", \"sandbox_linux_unittests\": \"152f44cd9bf03a04c66c20758abd0e37556838c5\", \"service_manager_unittests\": \"2de9da082afc0745ebe5e88b4d228f4dd1a445d7\", \"services_unittests\": \"b7605ae66edb43e65cf1ebbf7a354c2ed7f4ce85\", \"shell_dialogs_unittests\": \"37cadcd1051888602a24f60153515d05f458eeed\", \"skia_unittests\": \"1ff9aae1da95dd0747096d20b3ec8d859180c755\", \"snapshot_unittests\": \"243075417eacb3a3e951d704ece4e18dadc8e86c\", \"sql_unittests\": \"80f84286a30b8ad362a5b986ab09ccb825a6f45a\", \"storage_unittests\": \"3e76e79c7b3e642c3a8d09ef82a11fdd752f7ec4\", \"sync_integration_tests\": \"d9c03c1bb956068650721888b4f66e1a32878f15\", \"telemetry_gpu_unittests\": \"a20764ef0d9275a35c1720f9c957d1bce9672955\", \"telemetry_perf_unittests\": \"1ef7ca679e1d9e82eae0432e2b32a063cf44e3db\", \"telemetry_unittests\": \"5edad0e3675e4b61b2910ab3fe4d82cd8f86e765\", \"traffic_annotation_auditor_unittests\": \"a39fd320bc5f77a8b5a1e834bce3f3aedb302638\", \"ui_base_unittests\": \"50f42264855f8a9b8d3d0826991a1b67a6711087\", \"ui_touch_selection_unittests\": \"6df2c926b7ccbf505a8e5bef2266f54ff5370d1d\", \"unit_tests\": \"bf664ac08e4a27a73bab23129b0b878c261284f6\", \"url_unittests\": \"1c31b9380ba354272d59a437b3ef3f4e09333ecb\", \"views_perftests\": \"5886f1b2a04da7b658dd5470b452f2ec327b3e5c\", \"views_unittests\": \"c78552f249efe43cbe812a3fee2974dcde0eb3b1\", \"viz_unittests\": \"963a314325657345266e9b3d3523626cb7c91283\", \"vr_common_unittests\": \"7c11edad72f01fbf53c8140eb304cd93d476b970\", \"vr_pixeltests\": \"03f77aa3ed953eb2fd522b7c9f2c576daae6708d\", \"webdriver_wpt_tests\": \"ad5d33eb5de33faca6db59c71d742b510a9e24d4\", \"weblayer_browsertests\": \"96f08ec98c0ec402f326957f0188acf9988cbfea\", \"wm_unittests\": \"cb2b64e1d5b825c0d97998e45ff3c4249b2f0591\", \"wtf_unittests\": \"b8ef0129c77856789034fac82affecfd42e8ba8b\", \"xr_browser_tests\": \"b9b8152296a876439a95896d47708882fb5db9a7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_36472ddafb4960b563fa0dfd4883d4c097bf9805cea97d60ada20c6be0c80514_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"builder_cf6abf55cdbfec2f8730e0d1b2ac77b75ec912af81408768f32132b6b2b4dab2_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-central1-b-8-140-0cna\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580401684627914",
"status": "COMPLETED",
"status_changed_ts": "1580404682682959",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168038",
"builder:Linux Builder (dbg)",
"buildset:commit/git/c1135661a4919df618312b03db714dafcea4b096",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c1135661a4919df618312b03db714dafcea4b096",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055252776984148704",
"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/8889822714497810688/+/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:4a10157a3a83bd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580404682687001",
"url": "https://ci.chromium.org/b/8889822714497810688",
"utcnow_ts": "1580404683401272"
}
}
[17:18:03.450] Invocation finished in 54m23.218932522s with status SUCCEEDED