[18:21:52.409] New invocation is queued and will start shortly
[18:21:53.513] Starting the invocation (attempt 1)
[18:21:53.556] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:21:53.556] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:21:53.556] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054611161584299392",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b1eeea08595d53e1344fa8bab2fab09ea651d68a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054611161584299392",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b1eeea08595d53e1344fa8bab2fab09ea651d68a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1eeea08595d53e1344fa8bab2fab09ea651d68a",
"gitiles_ref:refs/heads/master"
]
}
[18:21:54.785] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581013313838493",
"id": "8889181099386782848",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1eeea08595d53e1344fa8bab2fab09ea651d68a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581013314573624",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168311",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b1eeea08595d53e1344fa8bab2fab09ea651d68a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1eeea08595d53e1344fa8bab2fab09ea651d68a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054611161584299392",
"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/8889181099386782848/+/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": "1581013314573742",
"url": "https://ci.chromium.org/b/8889181099386782848",
"utcnow_ts": "1581013314772035"
}
}
[18:21:54.785] Task URL: https://ci.chromium.org/b/8889181099386782848
[18:21:54.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:2:0) after 1m0s
[18:22:54.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:2:0)
[18:22:54.888] Timer tick, asking Buildbucket for the build status
[18:22:55.085] Build 8889181099386782848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:22:55.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:3:0) after 1m0s
[18:23:55.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:3:0)
[18:23:55.127] Timer tick, asking Buildbucket for the build status
[18:23:55.322] Build 8889181099386782848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:23:55.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:4:0) after 1m0s
[18:24:55.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:4:0)
[18:24:55.375] Timer tick, asking Buildbucket for the build status
[18:24:55.609] Build 8889181099386782848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:24:55.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:5:0) after 1m0s
[18:25:55.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:5:0)
[18:25:55.650] Timer tick, asking Buildbucket for the build status
[18:25:55.821] Build 8889181099386782848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:25:55.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:6:0) after 1m0s
[18:26:06.146] Received PubSub notification, asking Buildbucket for the build status
[18:26:06.283] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:55.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:6:0)
[18:26:55.889] Timer tick, asking Buildbucket for the build status
[18:26:56.166] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:56.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:8:0) after 1m0s
[18:27:56.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:8:0)
[18:27:56.215] Timer tick, asking Buildbucket for the build status
[18:27:56.485] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:56.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:9:0) after 1m0s
[18:28:56.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:9:0)
[18:28:56.525] Timer tick, asking Buildbucket for the build status
[18:28:56.835] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:56.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:10:0) after 1m0s
[18:29:56.961] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:10:0)
[18:29:56.961] Timer tick, asking Buildbucket for the build status
[18:29:57.866] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:57.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:11:0) after 1m0s
[18:30:57.897] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:11:0)
[18:30:57.897] Timer tick, asking Buildbucket for the build status
[18:30:58.067] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:58.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:12:0) after 1m0s
[18:31:58.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:12:0)
[18:31:58.195] Timer tick, asking Buildbucket for the build status
[18:31:58.718] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:58.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:13:0) after 1m0s
[18:32:58.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:13:0)
[18:32:58.780] Timer tick, asking Buildbucket for the build status
[18:32:58.950] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:58.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:14:0) after 1m0s
[18:33:59.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:14:0)
[18:33:59.231] Timer tick, asking Buildbucket for the build status
[18:34:00.096] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:00.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:15:0) after 1m0s
[18:35:00.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:15:0)
[18:35:00.222] Timer tick, asking Buildbucket for the build status
[18:35:01.490] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:01.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:16:0) after 1m0s
[18:36:01.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:16:0)
[18:36:01.524] Timer tick, asking Buildbucket for the build status
[18:36:01.685] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:01.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:17:0) after 1m0s
[18:37:01.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:17:0)
[18:37:01.776] Timer tick, asking Buildbucket for the build status
[18:37:01.917] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:01.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:18:0) after 1m0s
[18:38:02.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:18:0)
[18:38:02.025] Timer tick, asking Buildbucket for the build status
[18:38:02.238] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:02.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:19:0) after 1m0s
[18:39:02.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:19:0)
[18:39:02.461] Timer tick, asking Buildbucket for the build status
[18:39:02.586] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:02.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:20:0) after 1m0s
[18:40:02.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:20:0)
[18:40:02.802] Timer tick, asking Buildbucket for the build status
[18:40:04.478] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:04.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:21:0) after 1m0s
[18:41:04.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:21:0)
[18:41:04.604] Timer tick, asking Buildbucket for the build status
[18:41:04.764] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:04.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:22:0) after 1m0s
[18:42:04.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:22:0)
[18:42:04.785] Timer tick, asking Buildbucket for the build status
[18:42:05.118] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:05.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:23:0) after 1m0s
[18:43:05.185] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:23:0)
[18:43:05.185] Timer tick, asking Buildbucket for the build status
[18:43:05.335] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:05.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:24:0) after 1m0s
[18:44:05.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:24:0)
[18:44:05.461] Timer tick, asking Buildbucket for the build status
[18:44:05.639] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:05.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:25:0) after 1m0s
[18:45:05.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:25:0)
[18:45:05.693] Timer tick, asking Buildbucket for the build status
[18:45:06.609] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:06.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:26:0) after 1m0s
[18:46:06.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:26:0)
[18:46:06.637] Timer tick, asking Buildbucket for the build status
[18:46:06.859] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:06.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:27:0) after 1m0s
[18:47:06.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:27:0)
[18:47:06.945] Timer tick, asking Buildbucket for the build status
[18:47:07.089] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:07.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:28:0) after 1m0s
[18:48:07.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:28:0)
[18:48:07.146] Timer tick, asking Buildbucket for the build status
[18:48:07.544] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:07.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:29:0) after 1m0s
[18:49:07.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:29:0)
[18:49:07.658] Timer tick, asking Buildbucket for the build status
[18:49:07.887] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:07.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:30:0) after 1m0s
[18:50:07.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:30:0)
[18:50:07.939] Timer tick, asking Buildbucket for the build status
[18:50:08.653] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:08.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:31:0) after 1m0s
[18:51:08.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:31:0)
[18:51:08.667] Timer tick, asking Buildbucket for the build status
[18:51:08.857] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:08.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:32:0) after 1m0s
[18:52:09.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:32:0)
[18:52:09.061] Timer tick, asking Buildbucket for the build status
[18:52:09.206] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:09.206] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:33:0) after 1m0s
[18:53:09.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:33:0)
[18:53:09.262] Timer tick, asking Buildbucket for the build status
[18:53:09.437] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:09.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:34:0) after 1m0s
[18:54:09.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:34:0)
[18:54:09.529] Timer tick, asking Buildbucket for the build status
[18:54:09.782] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:09.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:35:0) after 1m0s
[18:55:09.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:35:0)
[18:55:09.890] Timer tick, asking Buildbucket for the build status
[18:55:10.467] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:10.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:36:0) after 1m0s
[18:56:10.612] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:36:0)
[18:56:10.612] Timer tick, asking Buildbucket for the build status
[18:56:11.315] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:11.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:37:0) after 1m0s
[18:57:11.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:37:0)
[18:57:11.391] Timer tick, asking Buildbucket for the build status
[18:57:11.575] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:11.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:38:0) after 1m0s
[18:58:11.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:38:0)
[18:58:11.800] Timer tick, asking Buildbucket for the build status
[18:58:11.971] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:11.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:39:0) after 1m0s
[18:59:12.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:39:0)
[18:59:12.200] Timer tick, asking Buildbucket for the build status
[18:59:12.541] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:12.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:40:0) after 1m0s
[19:00:12.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:40:0)
[19:00:12.685] Timer tick, asking Buildbucket for the build status
[19:00:12.886] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:12.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:41:0) after 1m0s
[19:01:12.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:41:0)
[19:01:12.944] Timer tick, asking Buildbucket for the build status
[19:01:13.209] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:13.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:42:0) after 1m0s
[19:02:13.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:42:0)
[19:02:13.406] Timer tick, asking Buildbucket for the build status
[19:02:13.666] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:43:0) after 1m0s
[19:03:13.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:43:0)
[19:03:13.715] Timer tick, asking Buildbucket for the build status
[19:03:14.268] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:14.268] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:44:0) after 1m0s
[19:04:14.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:44:0)
[19:04:14.381] Timer tick, asking Buildbucket for the build status
[19:04:14.565] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:14.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:45:0) after 1m0s
[19:05:14.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:45:0)
[19:05:14.645] Timer tick, asking Buildbucket for the build status
[19:05:14.846] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:14.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:46:0) after 1m0s
[19:06:14.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:46:0)
[19:06:14.992] Timer tick, asking Buildbucket for the build status
[19:06:15.229] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:15.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:47:0) after 1m0s
[19:07:15.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:47:0)
[19:07:15.264] Timer tick, asking Buildbucket for the build status
[19:07:15.401] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:15.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:48:0) after 1m0s
[19:08:15.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:48:0)
[19:08:15.487] Timer tick, asking Buildbucket for the build status
[19:08:15.629] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:15.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:49:0) after 1m0s
[19:09:15.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:49:0)
[19:09:15.683] Timer tick, asking Buildbucket for the build status
[19:09:15.907] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:15.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:50:0) after 1m0s
[19:10:15.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:50:0)
[19:10:15.930] Timer tick, asking Buildbucket for the build status
[19:10:18.569] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:18.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:51:0) after 1m0s
[19:11:18.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:51:0)
[19:11:18.681] Timer tick, asking Buildbucket for the build status
[19:11:19.138] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:19.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:52:0) after 1m0s
[19:12:19.267] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:52:0)
[19:12:19.267] Timer tick, asking Buildbucket for the build status
[19:12:20.090] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:20.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:53:0) after 1m0s
[19:13:20.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:53:0)
[19:13:20.162] Timer tick, asking Buildbucket for the build status
[19:13:21.054] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:21.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:54:0) after 1m0s
[19:14:21.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:54:0)
[19:14:21.113] Timer tick, asking Buildbucket for the build status
[19:14:21.284] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:21.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:55:0) after 1m0s
[19:15:21.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:55:0)
[19:15:21.312] Timer tick, asking Buildbucket for the build status
[19:15:21.865] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:21.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:56:0) after 1m0s
[19:16:21.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:56:0)
[19:16:21.966] Timer tick, asking Buildbucket for the build status
[19:16:22.121] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:22.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:57:0) after 1m0s
[19:17:22.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:57:0)
[19:17:22.201] Timer tick, asking Buildbucket for the build status
[19:17:22.367] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:22.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:58:0) after 1m0s
[19:18:22.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:58:0)
[19:18:22.443] Timer tick, asking Buildbucket for the build status
[19:18:22.722] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:22.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:59:0) after 1m0s
[19:19:22.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:59:0)
[19:19:22.813] Timer tick, asking Buildbucket for the build status
[19:19:22.960] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:22.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:60:0) after 1m0s
[19:20:23.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:60:0)
[19:20:23.072] Timer tick, asking Buildbucket for the build status
[19:20:23.245] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:23.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:61:0) after 1m0s
[19:21:23.267] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:61:0)
[19:21:23.267] Timer tick, asking Buildbucket for the build status
[19:21:23.450] Build 8889181099386782848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:23.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054611161584299392:62:0) after 1m0s
[19:22:02.358] Received PubSub notification, asking Buildbucket for the build status
[19:22:02.507] Build 8889181099386782848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:22:02.511] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581016921574200",
"created_by": "project:chromium",
"created_ts": "1581013313838493",
"id": "8889181099386782848",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1eeea08595d53e1344fa8bab2fab09ea651d68a\"}}",
"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-39-7846\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168311, \"got_angle_revision\": \"7572c967ba08ce22e2c25ec56932d7c47c83846a\", \"got_dawn_revision\": \"c30635174e125bda00771900089c34751e28faeb\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"b1eeea08595d53e1344fa8bab2fab09ea651d68a\", \"got_revision_cp\": \"refs/heads/master@{#739021}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"dd48b7e2d06e74e1d20da5f43224b4ae99873e64\", \"got_v8_revision\": \"612c52d10514336ba9991e821a72a60a84aeb04c\", \"got_v8_revision_cp\": \"refs/heads/8.2.21@{#1}\", \"got_webrtc_revision\": \"065348503c2ea5b0a6cb4f78144b13ca74777365\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30472}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1eeea08595d53e1344fa8bab2fab09ea651d68a\", \"swarm_hashes_refs/heads/master(at){#739021}_without_patch\": {\"accessibility_unittests\": \"c476b0bcc11ba152c6a6fc340002e020f907e704\", \"angle_unittests\": \"2b37da74655bf9b1a07fccbb504ebd1d0402b32c\", \"app_shell_unittests\": \"367eebe8edd4c16b4dd3f2922c5035a7f5f24163\", \"aura_unittests\": \"cea3bfc61ea89cdd7b8b72f162a7da297332ae5e\", \"base_unittests\": \"36198e0771feb24b46c826f6bb7255d19614230a\", \"base_util_unittests\": \"906910cddb87f4755d82dd435d27747f6c1badb6\", \"blink_common_unittests\": \"6b386e162a4f59bc3becf12436f43abbbb91959c\", \"blink_fuzzer_unittests\": \"795894e804c6f6691c25f0e0fd83b2cadf5607a0\", \"blink_heap_unittests\": \"8b9f3921b2bc359956d1066a0ce2bbc9187edbbf\", \"blink_platform_unittests\": \"1a1a01b7a636288da8c9522ae202e757bb56aff3\", \"blink_python_tests\": \"d7f21172367f7b8db988768819d0c9d95284efd8\", \"blink_unittests\": \"01004b371e8a11caad53e8d91c11cbdfa6bc6378\", \"blink_web_tests\": \"5ad68eff4d2a5a60cb14777752d8d4c1123f43a8\", \"boringssl_crypto_tests\": \"a10a169aa28122b4122bdeb0f64c700c8bf46507\", \"boringssl_ssl_tests\": \"bc5cd69267a8515d9e847f2b03d2067fdabc86ca\", \"browser_tests\": \"a2049615b5d85e41d056b606c7066eafae9c0a40\", \"cacheinvalidation_unittests\": \"f2964eee9064f6349c1800d9577e0d91db078200\", \"capture_unittests\": \"033a0cc22cf33f19e040df2334e318378803b8bb\", \"cast_unittests\": \"3543d94313e6e035b45d8ca662413b15eaa5165e\", \"cc_unittests\": \"d1d849fb6caa05e0a1ecc846dbe21faf9d165962\", \"chrome_app_unittests\": \"5df1992d61ce4339eb2a5c42b267da9ebf645f6b\", \"chromedriver_unittests\": \"4e94418c265bb17a2862fcd3e1c15496bb572708\", \"components_browsertests\": \"8b36463547dd57853e90dc605a5f983d514f1708\", \"components_unittests\": \"aa3caa7ab6292bd7ea9209162d33ff6ef63b66e7\", \"compositor_unittests\": \"5834bc7f85adc78e368230e6591d34aad4d9327d\", \"content_browsertests\": \"9fe3f3b29c265bc41c373ce407311ad9ddc8925e\", \"content_shell_crash_test\": \"ff98bbb65fdb83a2ef5a3e933266c94671b9a525\", \"content_unittests\": \"dff22fea824411af065ef35aadeb869d16c75030\", \"crashpad_tests\": \"51b1d37b0fd48ea4473d84f994fb1d1db7c77bac\", \"cronet_tests\": \"2b6a606deb698587a8985265573b6f8615194271\", \"cronet_unittests\": \"f53c1f4106739e00b5a71df7c822f28a971af39e\", \"crypto_unittests\": \"da018043f50d9d3b7a233d656e6b612f675fb53e\", \"dbus_unittests\": \"7950e48e51f7f1989f606f49f7843e1395ef832d\", \"device_unittests\": \"8a81993a43899ac21eed3f57d046c603b16864d1\", \"display_unittests\": \"d2c1580e259f0afde5e2e8876b9c1477de15f75d\", \"events_unittests\": \"899dc7c497d85dfd30d50cfa37fad3a0581adcfd\", \"extensions_browsertests\": \"4edb3a0125160d367de221476e8d43b410fe69b8\", \"extensions_unittests\": \"d1535bfb4ba5693ad36bed08daebbfa1d633bbb7\", \"filesystem_service_unittests\": \"0cf3d157d8a259c231e514645804148024469a34\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"5ef420808b5d0b2aadd2a30eebc3c48fad1f60ea\", \"gfx_unittests\": \"4afaa249aed96d19f612fd1911dc98ee531bfbbe\", \"gin_unittests\": \"eb8f1aa1f74f0d00f0fed413faecfe274b1da81b\", \"google_apis_unittests\": \"f0170b14d847a4aa344780fa1cbc535973390ae0\", \"gpu_unittests\": \"d0e9ebe4916a30ddee1fdf619ad3ee49ef111f9c\", \"grit_python_unittests\": \"fef1b8d37dbc77945832da49380e67c1a64b0e20\", \"gwp_asan_unittests\": \"d7fc741ea7c6bb3421d6034614fc16efa73dab04\", \"headless_browsertests\": \"d2895008f022d9e6f1fb934dd05427c1c0e80cd9\", \"headless_unittests\": \"2767872e813b0ecd238f2a531aad9d9d548987e0\", \"interactive_ui_tests\": \"555d0980a4e50ea90b152b1acfbd0deab7599642\", \"ipc_tests\": \"233ea27f2de7ae067c009d01eceaf11317bd8b35\", \"jingle_unittests\": \"eaaec9c81bc68746823f8c262f7c4ebfaa6c0a28\", \"latency_unittests\": \"16a329dae09e3e6dc6f0bf418cd7968e64dc7bb8\", \"libjingle_xmpp_unittests\": \"f289ca1f1a0d585d29abb172635dfad56191ed28\", \"media_blink_unittests\": \"85b62f43ff625b76292585b4bb2a1c943c5c1353\", \"media_service_unittests\": \"2df5417e0b5e3f38d6a336fe91381461cb00d994\", \"media_unittests\": \"d18f6ea557d34dcb68d3bc1eb8e5581b4fc07a75\", \"message_center_unittests\": \"8944f43d5fcdcdc33527c6327e5dbde757d2a539\", \"metrics_python_tests\": \"2bd984d2b5970d936205d046cdbb291a02f9559f\", \"midi_unittests\": \"48768a04acf50b187fca29d09a943e3649e58f79\", \"mojo_core_unittests\": \"aa18c7f183e2d8bdce03bd7e444209f6601b69d4\", \"mojo_unittests\": \"d03fd2a162966d29d848abfa2008846962847cb2\", \"nacl_helper_nonsfi_unittests\": \"1245060376498636e2000814a0d299fcbc7fc830\", \"nacl_loader_unittests\": \"35c5fc16afb52bb1f873ac1b5d4a5b3dd4e8a648\", \"native_theme_unittests\": \"0b70262631d1ce68fda20cd2b2d7dc5e0cd0c0ef\", \"net_unittests\": \"89de1186cf916fb5656fe418a3faf28d61325130\", \"pdf_unittests\": \"097d82d42cf3c61f2584af72a4850418080b72e7\", \"perfetto_unittests\": \"2201a21790dba4c3d09a32d21c7b388914345f20\", \"ppapi_unittests\": \"098a1d93c39b1d76506df79c4f9023d229cec389\", \"printing_unittests\": \"110a616177a56052a243452adc86c3d5ddd1d3fe\", \"remoting_unittests\": \"c8ce55ad0c791830c823e3e2b3038e3bc3a2fd6c\", \"sandbox_linux_unittests\": \"d89303ddbeb387c2412b01385e70a65d4e4ea8bd\", \"service_manager_unittests\": \"5429133136bc6e86a40bc008a8d19ebf5000020d\", \"services_unittests\": \"3d5c6a6717171dc071324e2ce6d27c2e70f6d0f6\", \"shell_dialogs_unittests\": \"74d2362ac2dd61d014b2122e9ba10c387e0ff35d\", \"skia_unittests\": \"e118d8f28e82543f9f0b523aa920926b372b0f1e\", \"snapshot_unittests\": \"9e44ac080856472b055082904d0ce7c20b486b33\", \"sql_unittests\": \"1847f8f72ab7a055794377234dd1bd991fb3c652\", \"storage_unittests\": \"3a4599cbef65a66427a624aa413013bd29c58d77\", \"sync_integration_tests\": \"d5f2e7848972f5db5e20ba6b9df1e95458807498\", \"telemetry_gpu_unittests\": \"59e18591dcdc880bf0226897b60f2c71cb82fc22\", \"telemetry_perf_unittests\": \"5f5d66ab13f4180836e0ae5d790e41540eb7d2e2\", \"telemetry_unittests\": \"f7a7d661fee34582c617c3d1c6b43f10c193b0c8\", \"traffic_annotation_auditor_unittests\": \"36e660fbc2ecf08337ceaf6b7413d0640f310add\", \"ui_base_unittests\": \"65c262ff5abba389b71d0c97d9f672215864e4c2\", \"ui_touch_selection_unittests\": \"5787e14c4b5f68a15cea6ba338a8f9fe71282845\", \"unit_tests\": \"01dec159483d6efef3b0faa75928f2880f8706e8\", \"url_unittests\": \"63fc71c4c79689dc09e4d372cfa577e65ad3b90e\", \"views_perftests\": \"dde9ec2100eb8d2dd99b123f09794e1061d89280\", \"views_unittests\": \"468a9158dc873b8072669c3145162deef6b4314e\", \"viz_unittests\": \"93b2fdbb7b2abf4f87c27d1bc7ca4ed360750aa9\", \"vr_common_unittests\": \"12d6fc11e6c7e875a95aff97274739198bf2346c\", \"vr_pixeltests\": \"35d60002fe2fb81f645b29f57b06b4843d6b7e39\", \"webdriver_wpt_tests\": \"f7f593f894f1f2329db5b610d822dcc9388907cb\", \"weblayer_browsertests\": \"1cfdc7552c198936ec17ed224b0a2923ea4134cb\", \"wm_unittests\": \"63ef8531743f6c2f43cd38de16b4ef5b098e2133\", \"wtf_unittests\": \"3452328183122d0f6c2eb05405206572a73c431f\", \"xr_browser_tests\": \"fc0b679ffdf4132323f4ce3df1b4e2832d7e30c3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_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-39-7846\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581013565135867",
"status": "COMPLETED",
"status_changed_ts": "1581016921953993",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168311",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b1eeea08595d53e1344fa8bab2fab09ea651d68a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1eeea08595d53e1344fa8bab2fab09ea651d68a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054611161584299392",
"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/8889181099386782848/+/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:4a348e368f3ae010",
"user_agent:luci-scheduler"
],
"updated_ts": "1581016921958264",
"url": "https://ci.chromium.org/b/8889181099386782848",
"utcnow_ts": "1581016922486857"
}
}
[19:22:02.511] Invocation finished in 1h0m10.151641803s with status SUCCEEDED