[22:12:48.817] New invocation is queued and will start shortly
[22:12:50.363] Starting the invocation (attempt 1)
[22:12:50.424] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:12:50.424] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:12:50.424] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054143647293498624",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a6525084baad058bc1233e2d51efbb91813fb0fd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054143647293498624",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/a6525084baad058bc1233e2d51efbb91813fb0fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6525084baad058bc1233e2d51efbb91813fb0fd",
"gitiles_ref:refs/heads/master"
]
}
[22:12:51.003] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581459170566822",
"id": "8888713584722364640",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a6525084baad058bc1233e2d51efbb91813fb0fd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581459170844921",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168496",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a6525084baad058bc1233e2d51efbb91813fb0fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6525084baad058bc1233e2d51efbb91813fb0fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054143647293498624",
"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/8888713584722364640/+/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": "1581459170845032",
"url": "https://ci.chromium.org/b/8888713584722364640",
"utcnow_ts": "1581459170994470"
}
}
[22:12:51.003] Task URL: https://ci.chromium.org/b/8888713584722364640
[22:12:51.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:2:0) after 1m0s
[22:13:51.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:2:0)
[22:13:51.029] Timer tick, asking Buildbucket for the build status
[22:13:51.683] Build 8888713584722364640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:51.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:3:0) after 1m0s
[22:14:51.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:3:0)
[22:14:51.697] Timer tick, asking Buildbucket for the build status
[22:14:51.826] Build 8888713584722364640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:51.826] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:4:0) after 1m0s
[22:15:51.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:4:0)
[22:15:51.847] Timer tick, asking Buildbucket for the build status
[22:15:52.613] Build 8888713584722364640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:52.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:5:0) after 1m0s
[22:16:52.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:5:0)
[22:16:52.632] Timer tick, asking Buildbucket for the build status
[22:16:53.054] Build 8888713584722364640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:53.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:6:0) after 1m0s
[22:17:53.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:6:0)
[22:17:53.063] Timer tick, asking Buildbucket for the build status
[22:17:53.913] Build 8888713584722364640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:53.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:7:0) after 1m0s
[22:17:56.818] Received PubSub notification, asking Buildbucket for the build status
[22:17:56.930] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:53.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:7:0)
[22:18:53.942] Timer tick, asking Buildbucket for the build status
[22:18:54.459] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:54.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:9:0) after 1m0s
[22:19:54.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:9:0)
[22:19:54.468] Timer tick, asking Buildbucket for the build status
[22:19:54.638] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:54.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:10:0) after 1m0s
[22:20:54.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:10:0)
[22:20:54.718] Timer tick, asking Buildbucket for the build status
[22:20:55.494] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:55.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:11:0) after 1m0s
[22:21:55.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:11:0)
[22:21:55.549] Timer tick, asking Buildbucket for the build status
[22:21:55.804] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:55.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:12:0) after 1m0s
[22:22:55.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:12:0)
[22:22:55.817] Timer tick, asking Buildbucket for the build status
[22:22:56.131] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:56.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:13:0) after 1m0s
[22:23:56.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:13:0)
[22:23:56.201] Timer tick, asking Buildbucket for the build status
[22:23:56.646] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:56.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:14:0) after 1m0s
[22:24:56.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:14:0)
[22:24:56.722] Timer tick, asking Buildbucket for the build status
[22:24:57.042] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:57.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:15:0) after 1m0s
[22:25:57.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:15:0)
[22:25:57.078] Timer tick, asking Buildbucket for the build status
[22:25:57.244] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:57.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:16:0) after 1m0s
[22:26:57.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:16:0)
[22:26:57.276] Timer tick, asking Buildbucket for the build status
[22:26:57.423] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:57.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:17:0) after 1m0s
[22:27:57.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:17:0)
[22:27:57.447] Timer tick, asking Buildbucket for the build status
[22:27:57.653] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:57.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:18:0) after 1m0s
[22:28:57.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:18:0)
[22:28:57.694] Timer tick, asking Buildbucket for the build status
[22:28:57.861] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:57.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:19:0) after 1m0s
[22:29:57.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:19:0)
[22:29:57.938] Timer tick, asking Buildbucket for the build status
[22:29:58.101] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:58.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:20:0) after 1m0s
[22:30:58.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:20:0)
[22:30:58.277] Timer tick, asking Buildbucket for the build status
[22:30:58.764] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:58.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:21:0) after 1m0s
[22:31:58.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:21:0)
[22:31:58.891] Timer tick, asking Buildbucket for the build status
[22:31:59.126] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:59.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:22:0) after 1m0s
[22:32:59.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:22:0)
[22:32:59.174] Timer tick, asking Buildbucket for the build status
[22:32:59.721] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:59.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:23:0) after 1m0s
[22:33:59.751] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:23:0)
[22:33:59.751] Timer tick, asking Buildbucket for the build status
[22:33:59.922] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:59.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:24:0) after 1m0s
[22:34:59.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:24:0)
[22:34:59.945] Timer tick, asking Buildbucket for the build status
[22:35:00.187] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:00.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:25:0) after 1m0s
[22:36:00.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:25:0)
[22:36:00.225] Timer tick, asking Buildbucket for the build status
[22:36:00.396] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:26:0) after 1m0s
[22:37:00.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:26:0)
[22:37:00.435] Timer tick, asking Buildbucket for the build status
[22:37:00.584] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:00.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:27:0) after 1m0s
[22:38:00.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:27:0)
[22:38:00.626] Timer tick, asking Buildbucket for the build status
[22:38:01.105] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:01.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:28:0) after 1m0s
[22:39:01.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:28:0)
[22:39:01.168] Timer tick, asking Buildbucket for the build status
[22:39:01.692] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:01.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:29:0) after 1m0s
[22:40:01.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:29:0)
[22:40:01.708] Timer tick, asking Buildbucket for the build status
[22:40:02.220] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:02.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:30:0) after 1m0s
[22:41:02.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:30:0)
[22:41:02.248] Timer tick, asking Buildbucket for the build status
[22:41:02.402] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:02.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:31:0) after 1m0s
[22:42:02.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:31:0)
[22:42:02.449] Timer tick, asking Buildbucket for the build status
[22:42:02.707] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:02.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:32:0) after 1m0s
[22:43:02.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:32:0)
[22:43:02.921] Timer tick, asking Buildbucket for the build status
[22:43:03.238] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:03.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:33:0) after 1m0s
[22:44:03.267] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:33:0)
[22:44:03.267] Timer tick, asking Buildbucket for the build status
[22:44:03.501] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:03.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:34:0) after 1m0s
[22:45:03.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:34:0)
[22:45:03.551] Timer tick, asking Buildbucket for the build status
[22:45:03.921] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:03.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:35:0) after 1m0s
[22:46:04.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:35:0)
[22:46:04.151] Timer tick, asking Buildbucket for the build status
[22:46:04.857] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:04.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:36:0) after 1m0s
[22:47:05.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:36:0)
[22:47:05.032] Timer tick, asking Buildbucket for the build status
[22:47:05.325] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:05.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:37:0) after 1m0s
[22:48:05.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:37:0)
[22:48:05.371] Timer tick, asking Buildbucket for the build status
[22:48:05.804] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:05.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:38:0) after 1m0s
[22:49:05.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:38:0)
[22:49:05.851] Timer tick, asking Buildbucket for the build status
[22:49:06.020] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:06.020] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:39:0) after 1m0s
[22:50:06.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:39:0)
[22:50:06.039] Timer tick, asking Buildbucket for the build status
[22:50:06.224] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:06.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:40:0) after 1m0s
[22:51:06.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:40:0)
[22:51:06.200] Timer tick, asking Buildbucket for the build status
[22:51:06.592] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:06.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:41:0) after 1m0s
[22:52:06.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:41:0)
[22:52:06.675] Timer tick, asking Buildbucket for the build status
[22:52:07.250] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:07.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:42:0) after 1m0s
[22:53:07.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:42:0)
[22:53:07.327] Timer tick, asking Buildbucket for the build status
[22:53:07.465] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:07.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:43:0) after 1m0s
[22:54:07.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:43:0)
[22:54:07.535] Timer tick, asking Buildbucket for the build status
[22:54:07.724] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:07.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:44:0) after 1m0s
[22:55:07.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:44:0)
[22:55:07.741] Timer tick, asking Buildbucket for the build status
[22:55:07.967] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:07.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:45:0) after 1m0s
[22:56:08.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:45:0)
[22:56:08.108] Timer tick, asking Buildbucket for the build status
[22:56:08.253] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:08.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:46:0) after 1m0s
[22:57:08.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:46:0)
[22:57:08.301] Timer tick, asking Buildbucket for the build status
[22:57:09.312] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:09.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:47:0) after 1m0s
[22:58:09.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:47:0)
[22:58:09.417] Timer tick, asking Buildbucket for the build status
[22:58:09.558] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:09.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:48:0) after 1m0s
[22:59:09.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:48:0)
[22:59:09.584] Timer tick, asking Buildbucket for the build status
[22:59:09.718] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:09.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:49:0) after 1m0s
[23:00:09.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:49:0)
[23:00:09.774] Timer tick, asking Buildbucket for the build status
[23:00:11.115] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:11.115] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:50:0) after 1m0s
[23:01:11.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:50:0)
[23:01:11.181] Timer tick, asking Buildbucket for the build status
[23:01:12.506] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:12.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:51:0) after 1m0s
[23:02:12.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:51:0)
[23:02:12.529] Timer tick, asking Buildbucket for the build status
[23:02:12.729] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:12.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:52:0) after 1m0s
[23:03:12.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:52:0)
[23:03:12.755] Timer tick, asking Buildbucket for the build status
[23:03:12.901] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:12.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:53:0) after 1m0s
[23:04:13.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:53:0)
[23:04:13.038] Timer tick, asking Buildbucket for the build status
[23:04:13.251] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:13.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:54:0) after 1m0s
[23:05:13.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:54:0)
[23:05:13.275] Timer tick, asking Buildbucket for the build status
[23:05:13.696] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:13.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:55:0) after 1m0s
[23:06:13.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:55:0)
[23:06:13.927] Timer tick, asking Buildbucket for the build status
[23:06:14.145] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:14.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:56:0) after 1m0s
[23:07:14.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:56:0)
[23:07:14.314] Timer tick, asking Buildbucket for the build status
[23:07:14.600] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:14.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:57:0) after 1m0s
[23:08:14.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:57:0)
[23:08:14.668] Timer tick, asking Buildbucket for the build status
[23:08:14.853] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:14.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:58:0) after 1m0s
[23:09:14.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:58:0)
[23:09:14.989] Timer tick, asking Buildbucket for the build status
[23:09:15.162] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:15.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:59:0) after 1m0s
[23:10:15.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:59:0)
[23:10:15.165] Timer tick, asking Buildbucket for the build status
[23:10:15.868] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:60:0) after 1m0s
[23:11:15.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:60:0)
[23:11:15.993] Timer tick, asking Buildbucket for the build status
[23:11:16.392] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:16.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:61:0) after 1m0s
[23:12:16.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:61:0)
[23:12:16.454] Timer tick, asking Buildbucket for the build status
[23:12:17.149] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:17.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:62:0) after 1m0s
[23:13:17.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:62:0)
[23:13:17.129] Timer tick, asking Buildbucket for the build status
[23:13:17.292] Build 8888713584722364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:17.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054143647293498624:63:0) after 1m0s
[23:14:10.626] Received PubSub notification, asking Buildbucket for the build status
[23:14:10.759] Build 8888713584722364640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:14:10.759] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581462849412192",
"created_by": "project:chromium",
"created_ts": "1581459170566822",
"id": "8888713584722364640",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a6525084baad058bc1233e2d51efbb91813fb0fd\"}}",
"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-36-4dpn\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168496, \"got_angle_revision\": \"b40d48a67c932625f349c6e58036c2d96b62489b\", \"got_dawn_revision\": \"713d9cbf939e525e025a69a17bda0bf51b449c47\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"a6525084baad058bc1233e2d51efbb91813fb0fd\", \"got_revision_cp\": \"refs/heads/master@{#740447}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"a0453d55f721e528656a41742f28e0882863e1bb\", \"got_v8_revision\": \"56ab990e69987df228757938e23a9bafccab2fe5\", \"got_v8_revision_cp\": \"refs/heads/8.2.57@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a6525084baad058bc1233e2d51efbb91813fb0fd\", \"swarm_hashes_refs/heads/master(at){#740447}_without_patch\": {\"accessibility_unittests\": \"affee4b4c288d08f8ac49dc94a0d90c612a33fd6\", \"angle_unittests\": \"a4a5bed26edb0c4312ee14ce6a51cbcdf24c72ad\", \"app_shell_unittests\": \"8469e27678ba9fa1c869e3a8fd4486ec317dd81f\", \"aura_unittests\": \"78592893fc8aaff2ec1beadbc61252ef5325a98c\", \"base_unittests\": \"c7a4ce7d44ae42a3e85fa1b1bbd96895b4d672c6\", \"base_util_unittests\": \"74042a8d363256e8e45437af6b2b7cf667f3044c\", \"blink_common_unittests\": \"18fb9c9f060b5379f2546b4e38b6b0c92bda548e\", \"blink_fuzzer_unittests\": \"fc0ed556f83d160126d7682c2f12d32ded1e38b2\", \"blink_heap_unittests\": \"c6ff15d67aeeddfa88b53b1dea4519fe75b55b66\", \"blink_platform_unittests\": \"4f31a1248f105854ffecc40906e1f105ab1d5b8f\", \"blink_python_tests\": \"acbe0e65e2ef22591758dc88ad393ffaf4be1edb\", \"blink_unittests\": \"406431419971192d4e6b68eaa25efbd54c72c8a6\", \"blink_web_tests\": \"0989cb9af5d1957af2ad412fff21e02cbfbd2b65\", \"boringssl_crypto_tests\": \"60cfd6f6d968c11bf815a112934f52c9ba8acced\", \"boringssl_ssl_tests\": \"523f6d6b5a38170f8ae28820c3b6f7138adb87ba\", \"browser_tests\": \"5eb5cf3dba973891811de56439613886515c498f\", \"cacheinvalidation_unittests\": \"311698b85a7ef57e58bd5beb9cd18783e06bcafe\", \"capture_unittests\": \"64a7892743c264cfaa78e51637443ef2fcaa407e\", \"cast_unittests\": \"7ffe994ec024f8c97f80469baff08583d9564db6\", \"cc_unittests\": \"dbc9f6f5df71ae746d750b1093863ae15ce60f54\", \"chrome_app_unittests\": \"c436bf0ee20e5867cafe841391d07ccd5bf8d6ef\", \"chromedriver_unittests\": \"721480df71f846ac12157fa718893c81679a94f3\", \"components_browsertests\": \"3ae6c6e89f54776a585920e76bce7323ab555bdc\", \"components_unittests\": \"2e674cd169f57cbd8186612e24a38c48430aacdf\", \"compositor_unittests\": \"13595ab6b9335e8ed1b69c945baae36a06c8c3e1\", \"content_browsertests\": \"18f5129be8b67578eebfef705393fa0c8be6cd9d\", \"content_shell_crash_test\": \"b29745fec88569b241a1c452848dbc29e348527f\", \"content_unittests\": \"a913ad98357f443913dd14b6407ce3bc8b3462b8\", \"crashpad_tests\": \"e54076a009d63d4191413a4e782050416c2ac726\", \"cronet_tests\": \"244cee5571280069c979b5532bf2ba2183584047\", \"cronet_unittests\": \"2edaf00a0be659ccaf5b3a7923ff78c959b5a34d\", \"crypto_unittests\": \"bb6a24422c3c82d3892c2877204e1c1cf8c44227\", \"dbus_unittests\": \"a56018a2fd525afe17e30bec818920e6039b914e\", \"device_unittests\": \"406305cd5062e2cb998375d261f70497b44900ae\", \"display_unittests\": \"864330277e11602099f7ca23aded87aade1c6441\", \"events_unittests\": \"06866e819fd2a52e3bf3aa6aefdb1c29b812e54f\", \"extensions_browsertests\": \"840044d04e2c9084e495480032464b2446be7dce\", \"extensions_unittests\": \"ee25a6410c55a8d74001ca4924c08f74ceb097da\", \"filesystem_service_unittests\": \"6128808a6dbf5abb2128a44f76d74f513dc17cc5\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"62d810ef02185146dbd6e1e8eedca69a36aca1e7\", \"gfx_unittests\": \"6962aac6da027790fb77e949bc483b0d2d5a8a34\", \"gin_unittests\": \"5afab201d24e2fed93c955e31b5bf73cdaecac61\", \"google_apis_unittests\": \"fad7eecbe89ac8444a0ae4bb4e17d01c0961636a\", \"gpu_unittests\": \"14764190089cbfc9d915cbaaaebdd9f99fa17ae8\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"0a44c745c4606a3e2565f247bbc6eb332d6034a5\", \"headless_browsertests\": \"4de12433eb241c359cb6fb90633eb84664a60a63\", \"headless_unittests\": \"8d71127a8a883e8c0c07ff5e74210834226bf46b\", \"interactive_ui_tests\": \"e63f9a6d813a9ad45d74e188acda8cde698a67b5\", \"ipc_tests\": \"3972c1412ee83076ab02ca4b050a42146eed7ab7\", \"jingle_unittests\": \"b3046bf71ce5ed23f2ec92f296aad9b33ef5eefd\", \"latency_unittests\": \"e4c55c893eb8b492d4c1bb0d47dd02942825d61e\", \"libjingle_xmpp_unittests\": \"83eecb36bca3f9e7c7e5b0da7440c24c6aad6e5a\", \"media_blink_unittests\": \"81101ab492b96b0a8b4e279e7e982ebf419570de\", \"media_service_unittests\": \"2e5435c7adca6fd849447456d2d0647147abf955\", \"media_unittests\": \"974845f40dd849762a112f48c1162246733d4378\", \"message_center_unittests\": \"86c4e3e53a739db6fc145e829dc571233347e161\", \"metrics_python_tests\": \"ec8e16cebffebfb4c6203a2c2bd8dc0e6db682c8\", \"midi_unittests\": \"6bf1dc599164c619c52c48cb48b5fbe09b400c4e\", \"mojo_core_unittests\": \"8d41e070d6e38fd76ffd1deae3d5307b51a5a6a3\", \"mojo_unittests\": \"3a56a0795be35f8d08a6cc2a6498b0a9db765762\", \"nacl_helper_nonsfi_unittests\": \"435a37c530c2fed9373c89fc3d2c90b6efce927b\", \"nacl_loader_unittests\": \"31a7cca775cdad4ea4fdb67ae5c5ba580a0bf604\", \"native_theme_unittests\": \"f3eb49f4599379d06a215b738e20f27f90a14514\", \"net_unittests\": \"435d4e1fafb146d708676098d26e8c5106339d0c\", \"pdf_unittests\": \"e403ceace7a979e18b7a926a268b6fbb67d3b31c\", \"perfetto_unittests\": \"6eaa08f45edabb7a5c9b1673e0eee6cdba9aa7fc\", \"ppapi_unittests\": \"54f89ed360e1c58fabe5e3d976c5eaaf582634e3\", \"printing_unittests\": \"66520326ea6e37f03a153c592dcc6a8ca4ee184b\", \"remoting_unittests\": \"66750331d42775d40729089ce43c99eb325a4fcc\", \"sandbox_linux_unittests\": \"3d117347cefae28a408b5c2645847fd4c5641aa7\", \"service_manager_unittests\": \"1e379c62869bb07579c820bea036a85e3bc802b9\", \"services_unittests\": \"d43fbfac363f87d0d4072e4502fc2503541e6f22\", \"shell_dialogs_unittests\": \"8ade79fb0859ec38773012910e004fbdfaf52a68\", \"skia_unittests\": \"45aa43361338c499fadeab3bc41780adb128ef05\", \"snapshot_unittests\": \"f84ec3436afeff699d8e26dad4584bd07377061c\", \"sql_unittests\": \"30998c2d90f45e29abb7c0b04578062934851a99\", \"storage_unittests\": \"ac875faca9ed7c692b687b62d374ff310ee1f3f2\", \"sync_integration_tests\": \"64b30278eda31ac21f87cbff22817a13e932bca5\", \"telemetry_gpu_unittests\": \"840a0c6cfca56f77d91500aa19b4bf1e0a9aab30\", \"telemetry_perf_unittests\": \"3ecca890a636319e0020d058c99758a38a50bb1b\", \"telemetry_unittests\": \"1fbb17a39d652235992071db1292d91f2103dba7\", \"traffic_annotation_auditor_unittests\": \"23173584ecc17ca402ddfc06995b1beed352e769\", \"ui_base_unittests\": \"9b0ca8dd30a6c2c56b7d7f21734b77a2323d5808\", \"ui_touch_selection_unittests\": \"dce9ee186676f707b0869f0ed852f828db9bb812\", \"unit_tests\": \"2daae126cb427fc09ef5001c366756607e9e98e9\", \"url_unittests\": \"914caa414937cdeb32952b5296b686f6ee0e9f90\", \"views_perftests\": \"ef8227e5bd2ac78532c1b7b4b3a04b153e1b32fd\", \"views_unittests\": \"d793fee57be353b8b24450270c02dccd37d8d075\", \"viz_unittests\": \"c69a560ca41b1f19574f8b83bd2b88649931187c\", \"vr_common_unittests\": \"9ea92535e32f12e351ae9be84d44aecc6ae67996\", \"vr_pixeltests\": \"d509c086484240e70955b1c9510f00b3c00f94ed\", \"webdriver_wpt_tests\": \"d44acc4bd5ca9d2f92ad827c4fb08ae783151a9f\", \"weblayer_browsertests\": \"9b612e5794da2a80b5e5c2fbe9bf93d5e8578002\", \"wm_unittests\": \"ee8fdd55594687a9b7d8f06706746ae2724462f9\", \"wtf_unittests\": \"e36ada7e9a2e6c7b1dd89ff26e193f6c6a202bfb\", \"xr_browser_tests\": \"81194f6e00bd2b38fe2b9fd0143c53ef9bce9552\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_69f5bab4a885266166e099964d6d1498f6d3761c92bc1d5b083a29cb822fe90c_v2\", \"builder_746f25208a125120dfc7ef61e5ef35531bce9c0f7be870eb0811bf6f56bb01d6_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_v2\", \"builder_ffe623326ac74578ce917ff55ef6cefa75094e5ad3ad5fe3409a729d79be647a_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-36-4dpn\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581459475654735",
"status": "COMPLETED",
"status_changed_ts": "1581462849777740",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168496",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a6525084baad058bc1233e2d51efbb91813fb0fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6525084baad058bc1233e2d51efbb91813fb0fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054143647293498624",
"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/8888713584722364640/+/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:4a4f2170568a6510",
"user_agent:luci-scheduler"
],
"updated_ts": "1581462849781537",
"url": "https://ci.chromium.org/b/8888713584722364640",
"utcnow_ts": "1581462850751179"
}
}
[23:14:10.759] Invocation finished in 1h1m22.028214202s with status SUCCEEDED