[19:53:57.584] New invocation is queued and will start shortly
[19:53:58.774] Starting the invocation (attempt 1)
[19:53:58.811] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:53:58.811] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:53:58.811] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017822999623918960",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8235a853755047c6ea7c4f16b925bf4bc8d81d86\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9017822999623918960",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"gitiles_ref:refs/heads/master"
]
}
[19:53:59.705] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616097238902551",
"id": "8852392937579410768",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616097239431730",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181495",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017822999623918960",
"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/8852392937579410768/+/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": "1616097239431888",
"url": "https://ci.chromium.org/b/8852392937579410768",
"utcnow_ts": "1616097239657646"
}
}
[19:53:59.705] Task URL: https://ci.chromium.org/b/8852392937579410768
[19:53:59.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:2:0) after 1m0s
[19:54:02.705] Received PubSub notification, asking Buildbucket for the build status
[19:54:02.779] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:59.753] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:2:0)
[19:54:59.753] Timer tick, asking Buildbucket for the build status
[19:54:59.817] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:59.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:4:0) after 1m0s
[19:55:59.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:4:0)
[19:55:59.794] Timer tick, asking Buildbucket for the build status
[19:55:59.861] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:59.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:5:0) after 1m0s
[19:56:59.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:5:0)
[19:56:59.880] Timer tick, asking Buildbucket for the build status
[19:57:00.197] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:00.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:6:0) after 1m0s
[19:58:00.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:6:0)
[19:58:00.215] Timer tick, asking Buildbucket for the build status
[19:58:00.298] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:00.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:7:0) after 1m0s
[19:59:00.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:7:0)
[19:59:00.295] Timer tick, asking Buildbucket for the build status
[19:59:00.370] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:00.370] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:8:0) after 1m0s
[20:00:00.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:8:0)
[20:00:00.400] Timer tick, asking Buildbucket for the build status
[20:00:00.664] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:00.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:9:0) after 1m0s
[20:01:00.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:9:0)
[20:01:00.724] Timer tick, asking Buildbucket for the build status
[20:01:00.799] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:00.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:10:0) after 1m0s
[20:02:00.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:10:0)
[20:02:00.809] Timer tick, asking Buildbucket for the build status
[20:02:00.894] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:00.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:11:0) after 1m0s
[20:03:01.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:11:0)
[20:03:01.182] Timer tick, asking Buildbucket for the build status
[20:03:01.440] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:01.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:12:0) after 1m0s
[20:04:01.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:12:0)
[20:04:01.452] Timer tick, asking Buildbucket for the build status
[20:04:01.604] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:01.604] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:13:0) after 1m0s
[20:05:01.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:13:0)
[20:05:01.644] Timer tick, asking Buildbucket for the build status
[20:05:01.972] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:01.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:14:0) after 1m0s
[20:06:02.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:14:0)
[20:06:02.009] Timer tick, asking Buildbucket for the build status
[20:06:02.085] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:02.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:15:0) after 1m0s
[20:07:02.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:15:0)
[20:07:02.151] Timer tick, asking Buildbucket for the build status
[20:07:02.237] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:02.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:16:0) after 1m0s
[20:08:02.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:16:0)
[20:08:02.258] Timer tick, asking Buildbucket for the build status
[20:08:02.530] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:02.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:17:0) after 1m0s
[20:09:02.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:17:0)
[20:09:02.595] Timer tick, asking Buildbucket for the build status
[20:09:02.676] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:02.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:18:0) after 1m0s
[20:10:02.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:18:0)
[20:10:02.701] Timer tick, asking Buildbucket for the build status
[20:10:02.837] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:02.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:19:0) after 1m0s
[20:11:02.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:19:0)
[20:11:02.888] Timer tick, asking Buildbucket for the build status
[20:11:02.965] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:02.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:20:0) after 1m0s
[20:12:03.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:20:0)
[20:12:03.016] Timer tick, asking Buildbucket for the build status
[20:12:03.154] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:03.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:21:0) after 1m0s
[20:13:03.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:21:0)
[20:13:03.173] Timer tick, asking Buildbucket for the build status
[20:13:03.407] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:03.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:22:0) after 1m0s
[20:14:03.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:22:0)
[20:14:03.428] Timer tick, asking Buildbucket for the build status
[20:14:03.632] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:03.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:23:0) after 1m0s
[20:15:03.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:23:0)
[20:15:03.677] Timer tick, asking Buildbucket for the build status
[20:15:03.982] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:03.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:24:0) after 1m0s
[20:16:04.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:24:0)
[20:16:04.009] Timer tick, asking Buildbucket for the build status
[20:16:04.114] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:04.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:25:0) after 1m0s
[20:17:04.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:25:0)
[20:17:04.125] Timer tick, asking Buildbucket for the build status
[20:17:04.234] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:04.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:26:0) after 1m0s
[20:18:04.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:26:0)
[20:18:04.282] Timer tick, asking Buildbucket for the build status
[20:18:04.367] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:04.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:27:0) after 1m0s
[20:19:04.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:27:0)
[20:19:04.414] Timer tick, asking Buildbucket for the build status
[20:19:04.496] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:04.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:28:0) after 1m0s
[20:20:04.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:28:0)
[20:20:04.514] Timer tick, asking Buildbucket for the build status
[20:20:04.612] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:04.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:29:0) after 1m0s
[20:21:04.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:29:0)
[20:21:04.670] Timer tick, asking Buildbucket for the build status
[20:21:04.724] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:04.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:30:0) after 1m0s
[20:22:04.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:30:0)
[20:22:04.775] Timer tick, asking Buildbucket for the build status
[20:22:04.909] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:04.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:31:0) after 1m0s
[20:23:04.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:31:0)
[20:23:04.963] Timer tick, asking Buildbucket for the build status
[20:23:05.078] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:05.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:32:0) after 1m0s
[20:24:05.631] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:32:0)
[20:24:05.631] Timer tick, asking Buildbucket for the build status
[20:24:05.715] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:05.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:33:0) after 1m0s
[20:25:05.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:33:0)
[20:25:05.988] Timer tick, asking Buildbucket for the build status
[20:25:06.079] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:06.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:34:0) after 1m0s
[20:26:06.133] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:34:0)
[20:26:06.133] Timer tick, asking Buildbucket for the build status
[20:26:06.201] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:06.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:35:0) after 1m0s
[20:27:06.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:35:0)
[20:27:06.510] Timer tick, asking Buildbucket for the build status
[20:27:06.583] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:06.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:36:0) after 1m0s
[20:28:06.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:36:0)
[20:28:06.720] Timer tick, asking Buildbucket for the build status
[20:28:06.782] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:06.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:37:0) after 1m0s
[20:29:06.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:37:0)
[20:29:06.787] Timer tick, asking Buildbucket for the build status
[20:29:06.879] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:06.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:38:0) after 1m0s
[20:30:06.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:38:0)
[20:30:06.872] Timer tick, asking Buildbucket for the build status
[20:30:06.957] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:06.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:39:0) after 1m0s
[20:31:07.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:39:0)
[20:31:07.129] Timer tick, asking Buildbucket for the build status
[20:31:07.350] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:07.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:40:0) after 1m0s
[20:32:07.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:40:0)
[20:32:07.430] Timer tick, asking Buildbucket for the build status
[20:32:07.494] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:07.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:41:0) after 1m0s
[20:33:07.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:41:0)
[20:33:07.628] Timer tick, asking Buildbucket for the build status
[20:33:07.698] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:07.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:42:0) after 1m0s
[20:34:07.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:42:0)
[20:34:07.819] Timer tick, asking Buildbucket for the build status
[20:34:08.013] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:08.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:43:0) after 1m0s
[20:35:08.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:43:0)
[20:35:08.082] Timer tick, asking Buildbucket for the build status
[20:35:08.144] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:08.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:44:0) after 1m0s
[20:36:08.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:44:0)
[20:36:08.219] Timer tick, asking Buildbucket for the build status
[20:36:08.296] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:08.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:45:0) after 1m0s
[20:37:08.333] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:45:0)
[20:37:08.333] Timer tick, asking Buildbucket for the build status
[20:37:08.397] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:08.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:46:0) after 1m0s
[20:38:08.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:46:0)
[20:38:08.419] Timer tick, asking Buildbucket for the build status
[20:38:08.501] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:08.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:47:0) after 1m0s
[20:39:08.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:47:0)
[20:39:08.626] Timer tick, asking Buildbucket for the build status
[20:39:08.710] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:08.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:48:0) after 1m0s
[20:40:08.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:48:0)
[20:40:08.704] Timer tick, asking Buildbucket for the build status
[20:40:08.789] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:08.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:49:0) after 1m0s
[20:41:08.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:49:0)
[20:41:08.848] Timer tick, asking Buildbucket for the build status
[20:41:08.903] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:08.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:50:0) after 1m0s
[20:42:09.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:50:0)
[20:42:09.104] Timer tick, asking Buildbucket for the build status
[20:42:09.348] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:09.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:51:0) after 1m0s
[20:43:09.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:51:0)
[20:43:09.458] Timer tick, asking Buildbucket for the build status
[20:43:09.715] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:09.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:52:0) after 1m0s
[20:44:09.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:52:0)
[20:44:09.783] Timer tick, asking Buildbucket for the build status
[20:44:09.859] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:09.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:53:0) after 1m0s
[20:45:09.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:53:0)
[20:45:09.990] Timer tick, asking Buildbucket for the build status
[20:45:10.154] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:10.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:54:0) after 1m0s
[20:46:10.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:54:0)
[20:46:10.282] Timer tick, asking Buildbucket for the build status
[20:46:10.363] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:10.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:55:0) after 1m0s
[20:47:10.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:55:0)
[20:47:10.446] Timer tick, asking Buildbucket for the build status
[20:47:10.615] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:10.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:56:0) after 1m0s
[20:48:10.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:56:0)
[20:48:10.654] Timer tick, asking Buildbucket for the build status
[20:48:10.772] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:10.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:57:0) after 1m0s
[20:49:10.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:57:0)
[20:49:10.768] Timer tick, asking Buildbucket for the build status
[20:49:11.316] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:11.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:58:0) after 1m0s
[20:50:11.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:58:0)
[20:50:11.540] Timer tick, asking Buildbucket for the build status
[20:50:12.123] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:12.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:59:0) after 1m0s
[20:51:12.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:59:0)
[20:51:12.202] Timer tick, asking Buildbucket for the build status
[20:51:12.315] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:12.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:60:0) after 1m0s
[20:52:12.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:60:0)
[20:52:12.427] Timer tick, asking Buildbucket for the build status
[20:52:12.749] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:12.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:61:0) after 1m0s
[20:53:13.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:61:0)
[20:53:13.000] Timer tick, asking Buildbucket for the build status
[20:53:13.087] Build 8852392937579410768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:13.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9017822999623918960:62:0) after 1m0s
[20:53:56.244] Received PubSub notification, asking Buildbucket for the build status
[20:53:56.705] Build 8852392937579410768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:53:56.705] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616100835413309",
"created_by": "project:chromium",
"created_ts": "1616097238902551",
"id": "8852392937579410768",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-114-7gbp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181495, \"got_angle_revision\": \"1f1b4ffeff1356cc474dd8b4f55189ade357c855\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\", \"got_revision_cp\": \"refs/heads/master@{#864393}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"10c783109050db482a0b7e7f7b31d6befa0848fa\", \"got_v8_revision_cp\": \"refs/heads/9.1.156@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\", \"swarm_hashes_refs/heads/master(at){#864393}_without_patch\": {\"absl_hardening_tests\": \"985d461be5b371c2cd99f29c80c7e0ee1ce67903\", \"accessibility_unittests\": \"7ec0df354aaa1e978094a147156264e6ab489a65\", \"angle_unittests\": \"baedd85f020957c6ce87671864b270bdcb09a88e\", \"app_shell_unittests\": \"3471ff2648ad3389e03e3004d3cec2f73a868b5a\", \"aura_unittests\": \"dac56770ea7987bb481175e314d9bdaab4d25a18\", \"base_unittests\": \"4c5a1baf849bc93aadd11c7fe109ed47b89f4f67\", \"base_util_unittests\": \"ccb96359eca39961374b9c243e2ad75c625be189\", \"blink_common_unittests\": \"1e458a7df458752dd1f92c26c449e9a96a683f2d\", \"blink_fuzzer_unittests\": \"932e864e1903d87347c153460b1edfb69d405f58\", \"blink_heap_unittests\": \"de11f92b5aeee07b619180c586cb1294d18f4aae\", \"blink_platform_unittests\": \"ffe732d845dfd575a675ab933cb8d9f84c559c90\", \"blink_python_tests\": \"dfb1f808e7c7956a8e551cbf5b913c1333d6a919\", \"blink_unittests\": \"c3e466feafb888a943aeaf0ba24e9607df2e01a8\", \"blink_web_tests\": \"e7d80bcb0805b0e89c28659a3344b4f695ebc9c0\", \"boringssl_crypto_tests\": \"03ecbd01b825caa588428c309f9bc55e3287df31\", \"boringssl_ssl_tests\": \"05f9755b40a2b8f92ed372bcead8bd70c3ddff2d\", \"browser_tests\": \"c150aa5a2671debaf160f24d2ef3f4a4391deb96\", \"capture_unittests\": \"cab7716bb6cb16564723dd47f9bc39aa8bba059f\", \"cast_unittests\": \"9101d6d8360f452397bffc534bf5b079a91621ca\", \"cc_unittests\": \"39766886fac6f7fbfc15ad4d63136b527a031b4c\", \"chrome_app_unittests\": \"0c3f664800aad803d8c8198454a29bfb433ace86\", \"chromedriver_unittests\": \"678ec4c557a25e1ca7cd2928d054f43e0938977f\", \"color_unittests\": \"e5fb963e4ee18aa31d1f0ece3976be5b620f2bc2\", \"components_browsertests\": \"c131764da370cb6756f7f3ee371332d07080b809\", \"components_unittests\": \"fdb271d7b0263b3b61e1ee3047f41b05067639fb\", \"compositor_unittests\": \"d96800aa1b968786f39e2cab00ce3f05aa6bda8c\", \"content_browsertests\": \"0a176afdd8da8a95e8714ccd3feeb01aabec2cb3\", \"content_shell_crash_test\": \"b1a70ed77719666250d55497c3f0099f887a30fd\", \"content_unittests\": \"8009a6f4bbeadb0f89fcd927e56e6e3f575e435b\", \"crashpad_tests\": \"02b3649f8979e89e017e300195a995f236801d9d\", \"cronet_tests\": \"10d7e0586783241d8128428057962b4104e8b973\", \"cronet_unittests\": \"cfaeaf78182575c59f3b22fd4a4b28fb3815b289\", \"crypto_unittests\": \"e942de98218df15ed21ad8fe678f613c37b4af20\", \"dbus_unittests\": \"b386dbafb81ab25faae16e3c67c247e599999dbc\", \"device_unittests\": \"c2aff476ebe33202b7b7f48a3509374161bacf8d\", \"display_unittests\": \"c54ef80f2d6387651efcff46c86945f5ed91bf04\", \"events_unittests\": \"b61cdef8df35d3c64013fcc53ab43e98d6b11ed0\", \"extensions_browsertests\": \"c7f769dae7fc656f732dd2abbec82726c3b9c6a9\", \"extensions_unittests\": \"098246fb9897149e721334a12f1ff3f5c62c5aea\", \"filesystem_service_unittests\": \"8fbb00ac10c2e3bd323d90c1650c074c1559f2be\", \"flatbuffers_unittests\": \"053e6ac98b117f7c5980e972268db1930536e1f7\", \"gcm_unit_tests\": \"43e45032f2bf70edd1c733518f6ae0bf0f9cfa58\", \"gfx_unittests\": \"f40c397ecc5a0d879f47ad86ae0ad7b30e97d9ec\", \"gin_unittests\": \"a4cceb708b53c5331d34ad517142edfe4cdbe8dc\", \"google_apis_unittests\": \"f7fab8ad700907964ad8ee27ba5dedf221915627\", \"gpu_unittests\": \"11648d2d6ab3382d35b55e6317ab0a0897fef3ea\", \"grit_python_unittests\": \"7ad08adeec1e5c63b898e2e191a9ad3e35ab39f9\", \"gwp_asan_unittests\": \"9abe2161f8080ea02f6a77ff08aea380c3e87b1f\", \"headless_browsertests\": \"337ee11ed33cdf7aef17a61377cb0c36ec910459\", \"headless_unittests\": \"c85cd47a99570506b89cfb89e82a9606ee75f142\", \"interactive_ui_tests\": \"e96b7c81b7e6a84ed47602f91814e2ce289cdb16\", \"ipc_tests\": \"184995b81a7c355465f23a24234cd8f09c17157e\", \"jingle_unittests\": \"7a7a6db60a3523308225179f2171564e157b8119\", \"latency_unittests\": \"39b0b01bbbaca394340522b6c7a237b792e09818\", \"libjingle_xmpp_unittests\": \"72a20c772a8d95761189d5eace138412659db12e\", \"liburlpattern_unittests\": \"66500aab3ba9e45319952b4927a329afb974aba7\", \"media_blink_unittests\": \"14f31275d3733327de21dbd7e528f7f5cdd62ed9\", \"media_unittests\": \"09d205de582416c6a9efe9806f5213a080c89a17\", \"message_center_unittests\": \"389a204ff0eae351cee0af98e9d932a48edf2710\", \"metrics_python_tests\": \"400918fe37d30b1ac2909a4a5b5f9be2683fd7e1\", \"midi_unittests\": \"3a450ff72c312ba282fa8e09484183548e44ff10\", \"mojo_core_unittests\": \"232783913153943772c81ce31a34d8d7b3084ea9\", \"mojo_unittests\": \"48aac615e83d0052962cfec476298e411457e918\", \"nacl_helper_nonsfi_unittests\": \"5f4df721f1d1848265008053ffaada7e93960a40\", \"nacl_loader_unittests\": \"3120572d4c56f299a0e62378332cd07627a642b1\", \"native_theme_unittests\": \"d1e46d54da322af48544fe7098acdb05b2b2a15d\", \"net_unittests\": \"47b068e0e543bba97723aa5f2b910fd9c1b6a7b5\", \"openscreen_unittests\": \"e90f3f9e0ed537702d583b7c0fd8b7e502919462\", \"pdf_unittests\": \"cf877fc32c41963e1020e6c1ea0d67a273cec7e7\", \"perfetto_unittests\": \"f250e3c3d9a57eef44f1aeb5c893a2ae6a1570fc\", \"ppapi_unittests\": \"bafed2c1bc724e84c711977995b1e03c351f0006\", \"printing_unittests\": \"137fbbf3c51e210e74b2d6f72a2239c771e7c62b\", \"remoting_unittests\": \"f55227fbba8c20953bafadd535e12604a52c2ed1\", \"sandbox_linux_unittests\": \"0cd85ffd02e1c1ff8067618f14fda43c92ce7039\", \"service_manager_unittests\": \"ce77b5a118574f75abee482a494f2b9c4b2b3bb0\", \"services_unittests\": \"96c36018ebcefce4c5413bc98ee0086b2a44014c\", \"shell_dialogs_unittests\": \"e4f6c04d7b97958f93e645c237d7fe0a758bf02a\", \"skia_unittests\": \"264d6f5cd69e96144b3da1a522b3693b2f09ae0c\", \"snapshot_unittests\": \"775911beec723050558c4125903315acde81aaa1\", \"sql_unittests\": \"b7df69893ee1d9a98d4432dce9b4d548dea91605\", \"storage_unittests\": \"62d404f5374484ca9dd475a53ffb9c715a8883c0\", \"sync_integration_tests\": \"d5c670f58c8564dab8e660af6b407b3bdaf819cb\", \"telemetry_gpu_unittests\": \"e9d2b880dcea02162e22f3d8b4adee4ac200ce89\", \"telemetry_perf_unittests\": \"5a188ff565418ac81391d52f8039395f1cc5a475\", \"telemetry_unittests\": \"1bcf63c339f77aeada691b8576a2f6c5eba302f3\", \"traffic_annotation_auditor_unittests\": \"b4a9f9d7affb0a48b2e768944d36e50a9d071d9e\", \"ui_base_unittests\": \"321a623e7570c9ae2b2c5790f55909456f7ea88d\", \"ui_touch_selection_unittests\": \"a126ae35996e153b4a67452ffc0cb705410564eb\", \"unit_tests\": \"b9e3958ecabf5773aeac49110facfc307d142b4e\", \"url_unittests\": \"fe1ca9403a0b59b7ebeb4c0a7916bf15f5f77150\", \"views_perftests\": \"16262e2cf9a74c4c91f7489136d20f7f7e32f999\", \"views_unittests\": \"9ffc192fc1f0ecb09a2db69aa1a826729afc8c6f\", \"viz_unittests\": \"bdf3a3b91a373aa37b70d5ab184a4750bda75b84\", \"vr_common_unittests\": \"1895efb78309a45305f6e62f75cba915c5c2bd10\", \"vr_pixeltests\": \"4970fd46142ed15894e6400774cab8596bb6a02f\", \"webdriver_wpt_tests\": \"98cfeac5bd115e15aa8e001005a4e0dc3c4b9708\", \"weblayer_browsertests\": \"dcd2c34f92aa5e9cc07bd18e03b497ebff7594a4\", \"weblayer_unittests\": \"4bcb826ed451a03a201ddaf0b3f1ac917f774330\", \"wm_unittests\": \"df18db87538fb8668c8f778ba5d307db59d18aaf\", \"wtf_unittests\": \"39dacaaffcb691f9c03e08d8ba862f7ba69e73a1\", \"x11_unittests\": \"c1a7a014ae3af7b690d79d97db4a6ef2cafa1393\", \"xr_browser_tests\": \"9b994e0eda10e1a709872e296227314fe78f79a7\", \"zlib_unittests\": \"727082b7a33068567d0610799de2d496c948004b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4637b11d2a56187e25d14badb4fa154a12143e816e25cb34f5fe38c5c81ebb47_v2\", \"builder_780c9145325dc86747733fbdb99013a37dc03cbcb79ce91d7b045cb0873240e8_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-114-7gbp\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616097241853944",
"status": "COMPLETED",
"status_changed_ts": "1616100835820131",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181495",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017822999623918960",
"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/8852392937579410768/+/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:525fb87159540a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616100835827198",
"url": "https://ci.chromium.org/b/8852392937579410768",
"utcnow_ts": "1616100836649251"
}
}
[20:53:56.705] Invocation finished in 59m59.135337099s with status SUCCEEDED