[01:03:48.401] New invocation is queued and will start shortly
[01:03:49.551] Starting the invocation (attempt 1)
[01:03:49.590] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:03:49.591] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:03:49.591] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9049874831847859744",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"33c7149e159a5ac4002c359d9f84ede6eb332ef2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9049874831847859744",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/33c7149e159a5ac4002c359d9f84ede6eb332ef2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/33c7149e159a5ac4002c359d9f84ede6eb332ef2",
"gitiles_ref:refs/heads/master"
]
}
[01:03:50.489] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585530229718232",
"id": "8884444769800993520",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"33c7149e159a5ac4002c359d9f84ede6eb332ef2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585530230291711",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170366",
"builder:Linux Builder (dbg)",
"buildset:commit/git/33c7149e159a5ac4002c359d9f84ede6eb332ef2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/33c7149e159a5ac4002c359d9f84ede6eb332ef2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049874831847859744",
"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/8884444769800993520/+/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": "1585530230291836",
"url": "https://ci.chromium.org/b/8884444769800993520",
"utcnow_ts": "1585530230479214"
}
}
[01:03:50.489] Task URL: https://ci.chromium.org/b/8884444769800993520
[01:03:50.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:2:0) after 1m0s
[01:04:50.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:2:0)
[01:04:50.508] Timer tick, asking Buildbucket for the build status
[01:04:50.794] Build 8884444769800993520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:50.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:3:0) after 1m0s
[01:05:50.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:3:0)
[01:05:50.865] Timer tick, asking Buildbucket for the build status
[01:05:51.126] Build 8884444769800993520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:51.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:4:0) after 1m0s
[01:06:51.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:4:0)
[01:06:51.252] Timer tick, asking Buildbucket for the build status
[01:06:51.341] Build 8884444769800993520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:51.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:5:0) after 1m0s
[01:07:51.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:5:0)
[01:07:51.353] Timer tick, asking Buildbucket for the build status
[01:07:51.659] Build 8884444769800993520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:51.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:6:0) after 1m0s
[01:08:04.723] Received PubSub notification, asking Buildbucket for the build status
[01:08:04.868] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:51.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:6:0)
[01:08:51.770] Timer tick, asking Buildbucket for the build status
[01:08:52.077] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:52.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:8:0) after 1m0s
[01:09:52.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:8:0)
[01:09:52.157] Timer tick, asking Buildbucket for the build status
[01:09:52.277] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:52.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:9:0) after 1m0s
[01:10:52.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:9:0)
[01:10:52.390] Timer tick, asking Buildbucket for the build status
[01:10:52.520] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:52.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:10:0) after 1m0s
[01:11:52.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:10:0)
[01:11:52.530] Timer tick, asking Buildbucket for the build status
[01:11:52.736] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:52.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:11:0) after 1m0s
[01:12:52.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:11:0)
[01:12:52.760] Timer tick, asking Buildbucket for the build status
[01:12:52.887] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:52.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:12:0) after 1m0s
[01:13:52.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:12:0)
[01:13:52.943] Timer tick, asking Buildbucket for the build status
[01:13:53.870] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:53.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:13:0) after 1m0s
[01:14:53.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:13:0)
[01:14:53.903] Timer tick, asking Buildbucket for the build status
[01:14:54.056] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:54.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:14:0) after 1m0s
[01:15:54.249] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:14:0)
[01:15:54.249] Timer tick, asking Buildbucket for the build status
[01:15:54.394] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:54.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:15:0) after 1m0s
[01:16:54.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:15:0)
[01:16:54.498] Timer tick, asking Buildbucket for the build status
[01:16:54.671] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:54.671] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:16:0) after 1m0s
[01:17:54.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:16:0)
[01:17:54.701] Timer tick, asking Buildbucket for the build status
[01:17:54.818] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:54.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:17:0) after 1m0s
[01:18:54.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:17:0)
[01:18:54.873] Timer tick, asking Buildbucket for the build status
[01:18:55.132] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:55.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:18:0) after 1m0s
[01:19:55.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:18:0)
[01:19:55.258] Timer tick, asking Buildbucket for the build status
[01:19:55.757] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:55.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:19:0) after 1m0s
[01:20:55.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:19:0)
[01:20:55.775] Timer tick, asking Buildbucket for the build status
[01:20:55.875] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:20:0) after 1m0s
[01:21:56.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:20:0)
[01:21:56.052] Timer tick, asking Buildbucket for the build status
[01:21:56.224] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:56.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:21:0) after 1m0s
[01:22:56.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:21:0)
[01:22:56.315] Timer tick, asking Buildbucket for the build status
[01:22:56.418] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:56.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:22:0) after 1m0s
[01:23:56.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:22:0)
[01:23:56.449] Timer tick, asking Buildbucket for the build status
[01:23:56.585] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:56.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:23:0) after 1m0s
[01:24:56.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:23:0)
[01:24:56.626] Timer tick, asking Buildbucket for the build status
[01:24:56.759] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:56.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:24:0) after 1m0s
[01:25:56.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:24:0)
[01:25:56.858] Timer tick, asking Buildbucket for the build status
[01:25:57.418] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:57.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:25:0) after 1m0s
[01:26:57.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:25:0)
[01:26:57.393] Timer tick, asking Buildbucket for the build status
[01:26:57.832] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:57.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:26:0) after 1m0s
[01:27:57.857] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:26:0)
[01:27:57.857] Timer tick, asking Buildbucket for the build status
[01:27:58.098] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:58.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:27:0) after 1m0s
[01:28:58.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:27:0)
[01:28:58.142] Timer tick, asking Buildbucket for the build status
[01:28:58.322] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:58.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:28:0) after 1m0s
[01:29:58.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:28:0)
[01:29:58.353] Timer tick, asking Buildbucket for the build status
[01:29:58.885] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:58.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:29:0) after 1m0s
[01:30:58.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:29:0)
[01:30:58.908] Timer tick, asking Buildbucket for the build status
[01:30:59.224] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:59.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:30:0) after 1m0s
[01:31:59.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:30:0)
[01:31:59.298] Timer tick, asking Buildbucket for the build status
[01:31:59.770] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:59.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:31:0) after 1m0s
[01:32:59.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:31:0)
[01:32:59.805] Timer tick, asking Buildbucket for the build status
[01:33:00.069] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:00.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:32:0) after 1m0s
[01:34:00.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:32:0)
[01:34:00.164] Timer tick, asking Buildbucket for the build status
[01:34:00.335] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:00.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:33:0) after 1m0s
[01:35:00.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:33:0)
[01:35:00.505] Timer tick, asking Buildbucket for the build status
[01:35:00.814] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:00.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:34:0) after 1m0s
[01:36:00.824] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:34:0)
[01:36:00.824] Timer tick, asking Buildbucket for the build status
[01:36:01.194] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:01.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:35:0) after 1m0s
[01:37:01.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:35:0)
[01:37:01.357] Timer tick, asking Buildbucket for the build status
[01:37:01.688] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:01.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:36:0) after 1m0s
[01:38:01.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:36:0)
[01:38:01.790] Timer tick, asking Buildbucket for the build status
[01:38:02.261] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:02.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:37:0) after 1m0s
[01:39:02.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:37:0)
[01:39:02.380] Timer tick, asking Buildbucket for the build status
[01:39:02.597] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:02.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:38:0) after 1m0s
[01:40:02.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:38:0)
[01:40:02.623] Timer tick, asking Buildbucket for the build status
[01:40:03.092] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:03.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:39:0) after 1m0s
[01:41:03.139] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:39:0)
[01:41:03.139] Timer tick, asking Buildbucket for the build status
[01:41:03.373] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:03.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:40:0) after 1m0s
[01:42:03.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:40:0)
[01:42:03.469] Timer tick, asking Buildbucket for the build status
[01:42:03.668] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:03.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:41:0) after 1m0s
[01:43:03.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:41:0)
[01:43:03.795] Timer tick, asking Buildbucket for the build status
[01:43:03.969] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:03.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:42:0) after 1m0s
[01:44:04.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:42:0)
[01:44:04.085] Timer tick, asking Buildbucket for the build status
[01:44:04.270] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:04.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:43:0) after 1m0s
[01:45:04.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:43:0)
[01:45:04.442] Timer tick, asking Buildbucket for the build status
[01:45:04.815] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:04.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:44:0) after 1m0s
[01:46:04.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:44:0)
[01:46:04.831] Timer tick, asking Buildbucket for the build status
[01:46:05.499] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:05.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:45:0) after 1m0s
[01:47:05.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:45:0)
[01:47:05.486] Timer tick, asking Buildbucket for the build status
[01:47:05.838] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:05.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:46:0) after 1m0s
[01:48:05.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:46:0)
[01:48:05.928] Timer tick, asking Buildbucket for the build status
[01:48:06.244] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:06.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:47:0) after 1m0s
[01:49:06.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:47:0)
[01:49:06.316] Timer tick, asking Buildbucket for the build status
[01:49:06.605] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:06.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:48:0) after 1m0s
[01:50:06.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:48:0)
[01:50:06.638] Timer tick, asking Buildbucket for the build status
[01:50:06.944] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:06.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:49:0) after 1m0s
[01:51:07.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:49:0)
[01:51:07.018] Timer tick, asking Buildbucket for the build status
[01:51:07.228] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:07.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:50:0) after 1m0s
[01:52:07.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:50:0)
[01:52:07.310] Timer tick, asking Buildbucket for the build status
[01:52:07.434] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:07.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:51:0) after 1m0s
[01:53:07.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:51:0)
[01:53:07.502] Timer tick, asking Buildbucket for the build status
[01:53:08.279] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:08.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:52:0) after 1m0s
[01:54:08.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:52:0)
[01:54:08.299] Timer tick, asking Buildbucket for the build status
[01:54:08.528] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:08.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:53:0) after 1m0s
[01:55:08.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:53:0)
[01:55:08.593] Timer tick, asking Buildbucket for the build status
[01:55:08.956] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:08.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:54:0) after 1m0s
[01:56:09.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:54:0)
[01:56:09.038] Timer tick, asking Buildbucket for the build status
[01:56:09.312] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:09.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:55:0) after 1m0s
[01:57:09.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:55:0)
[01:57:09.341] Timer tick, asking Buildbucket for the build status
[01:57:09.627] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:09.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:56:0) after 1m0s
[01:58:09.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:56:0)
[01:58:09.635] Timer tick, asking Buildbucket for the build status
[01:58:09.751] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:09.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:57:0) after 1m0s
[01:59:09.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:57:0)
[01:59:09.778] Timer tick, asking Buildbucket for the build status
[01:59:10.102] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:58:0) after 1m0s
[02:00:10.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:58:0)
[02:00:10.129] Timer tick, asking Buildbucket for the build status
[02:00:10.365] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:10.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:59:0) after 1m0s
[02:01:11.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:59:0)
[02:01:11.498] Timer tick, asking Buildbucket for the build status
[02:01:12.430] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:12.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:60:0) after 1m0s
[02:02:12.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:60:0)
[02:02:12.592] Timer tick, asking Buildbucket for the build status
[02:02:12.785] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:12.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:61:0) after 1m0s
[02:03:12.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:61:0)
[02:03:12.945] Timer tick, asking Buildbucket for the build status
[02:03:14.218] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:14.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:62:0) after 1m0s
[02:04:14.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:62:0)
[02:04:14.379] Timer tick, asking Buildbucket for the build status
[02:04:15.256] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:15.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:63:0) after 1m0s
[02:05:15.272] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:63:0)
[02:05:15.272] Timer tick, asking Buildbucket for the build status
[02:05:15.431] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:15.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:64:0) after 1m0s
[02:06:15.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:64:0)
[02:06:15.455] Timer tick, asking Buildbucket for the build status
[02:06:15.948] Build 8884444769800993520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:15.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049874831847859744:65:0) after 1m0s
[02:06:26.726] Received PubSub notification, asking Buildbucket for the build status
[02:06:26.990] Build 8884444769800993520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:06:26.990] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585533985544092",
"created_by": "project:chromium",
"created_ts": "1585530229718232",
"id": "8884444769800993520",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"33c7149e159a5ac4002c359d9f84ede6eb332ef2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$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-134-0nsa\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 170366, \"got_angle_revision\": \"1b2dd6f92a6ba4ac4738ae796692e4844bfd28c7\", \"got_dawn_revision\": \"81431f5034b4ce3a0cdc339709bd8ca8856fe427\", \"got_nacl_revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"got_revision\": \"33c7149e159a5ac4002c359d9f84ede6eb332ef2\", \"got_revision_cp\": \"refs/heads/master@{#754376}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"1c29477aa06ea733994d1d11d6bf63ba27ac8359\", \"got_v8_revision\": \"5269c3133de0093c180c57cc51dac04406293e24\", \"got_v8_revision_cp\": \"refs/heads/8.3.102@{#1}\", \"got_webrtc_revision\": \"d19513f3ffbb939fd56b5377b678bb31d3154e14\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30881}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"33c7149e159a5ac4002c359d9f84ede6eb332ef2\", \"swarm_hashes_refs/heads/master(at){#754376}_without_patch\": {\"accessibility_unittests\": \"a9cf4b0bce48fa28f298203916549c130f2a338a\", \"angle_unittests\": \"fe0accf8aa928a049c1d763cf4d5321037131ab4\", \"app_shell_unittests\": \"44b61b4d31ca838f7045b3ef026f64421f3ddf22\", \"aura_unittests\": \"9521ded7b4b4cb185adbe899e7ac60e245069c4b\", \"base_unittests\": \"8401e32e6e838770c31d5c5c170b67167a30e62b\", \"base_util_unittests\": \"1214a59a6afd13312d6a96344b9284e0c36409b4\", \"blink_common_unittests\": \"4b3e3d4f8d82db27fce1565ef599642f5045d756\", \"blink_fuzzer_unittests\": \"d9187e2505d47ad6ae4d85ebe5ea6872779100fe\", \"blink_heap_unittests\": \"57c74bf03ff56df8a0184648944044ce1c73316a\", \"blink_platform_unittests\": \"5c83351ec32ecc67c4c8fd9a389ce55b5d65fad3\", \"blink_python_tests\": \"1ce73d10530136c0d13ef26b9d72ced006d5b05a\", \"blink_unittests\": \"43ee33b741674b3ea859063f4dc764dbf9597f41\", \"blink_web_tests\": \"76ee20f88a3b92c19b36c8628682acbd25a5e21e\", \"boringssl_crypto_tests\": \"28ae931b6a1edd97a6d821322165725e83761a2c\", \"boringssl_ssl_tests\": \"34243de82de3da7847506deb1c15f0cb83def7c4\", \"browser_tests\": \"dc606048c02b32ef1fe5308f64f0d0f9fbfb4f9c\", \"cacheinvalidation_unittests\": \"9d355ccf7d9faadf5567ebd73ea72c0e697b9013\", \"capture_unittests\": \"a3209baec0c96765fc5b89d675305077ec16e864\", \"cast_unittests\": \"bcd63a192eb1c64e1033aea02edfbc4bd8929976\", \"cc_unittests\": \"8b590f728354985e8707b7afecfac4b961301d1a\", \"chrome_app_unittests\": \"00f20b20f69a3b7ecaeb7f0971bf7625fc47f38b\", \"chromedriver_unittests\": \"6614134452099583193de49b2c2dea8081688fe2\", \"components_browsertests\": \"ab3d2b31406aa2efcbf886e99aec0e5f2cadac2d\", \"components_unittests\": \"1e5ec791f0e7853ba40dad17fcb9c4767431ac73\", \"compositor_unittests\": \"e862be91cd9a152d799183f352e5ae9f424e8430\", \"content_browsertests\": \"78f7785de9e7a2ed87cc6124dcb5d4153be9e65a\", \"content_shell_crash_test\": \"9e21f40b326ed8c800c51fb813756f411a982bbf\", \"content_unittests\": \"c00fb5ed29954d7bdcd75a769948c53e895a3faa\", \"crashpad_tests\": \"049435eac23ab5c2751355c92369e60d341a8b0d\", \"cronet_tests\": \"eeeaa434a703a4d5ad8d645b30059e66092851b9\", \"cronet_unittests\": \"2d03bbd4c96a67958f229cfd1dfba9aa3062bd7f\", \"crypto_unittests\": \"ca20b8202bca6097602afa787dffdc9831ef188b\", \"dbus_unittests\": \"3ef7c6a84b7c31194f1e09f326d07e5a9ac3f4e0\", \"device_unittests\": \"48bfda78f94ba699c2ba70dd64bdc2db8597e74f\", \"display_unittests\": \"b2581e60f2470441447eba7557b2d79ce0613b56\", \"events_unittests\": \"6761585ecd16778b7fcb0310099f3ad14382c222\", \"extensions_browsertests\": \"b91f7f5d5741fe1365e3bab60a94768547b7e97a\", \"extensions_unittests\": \"316df25f1fdd853f9687941711bd9d979708938e\", \"filesystem_service_unittests\": \"9fe2e5df934bb2c97a755a8f0b3cc1efd053aed3\", \"flatbuffers_unittests\": \"d5a97f0e268c1e3e0a07477fbb5635ba77b25a21\", \"gcm_unit_tests\": \"9dd8815cfc8237bfb6d1e3ded2fee29f39bdceca\", \"gfx_unittests\": \"541be55b0e5e1bbad4d2a4485bdacc6bd793d8b3\", \"gin_unittests\": \"88920848aaa85028aa0ee539a64e2f63f6ff547f\", \"google_apis_unittests\": \"e3957bf1922342820586e775efbe0ae279c2b74d\", \"gpu_unittests\": \"7963e00612fa2d855ce44f1dab2c6ca4379637cf\", \"grit_python_unittests\": \"80f5a51f967e2faed892921e2afba85033089616\", \"gwp_asan_unittests\": \"3a077f51d9530fe94bca7550b1a96dbe2e5f5e2f\", \"headless_browsertests\": \"b0b1a3136e8c20db2990121613d44be6fbed173a\", \"headless_unittests\": \"90ce412bd52238da2877ac35ebf95fbeed9489b5\", \"interactive_ui_tests\": \"2f6d7363cff3025a7d81da2ef5da06b4f3c7aedc\", \"ipc_tests\": \"b1526c4d53cd58a385ca34a5167cf03be712090e\", \"jingle_unittests\": \"d11a57679f3dc145dde89e4aa53c6a5da13c47e9\", \"latency_unittests\": \"853e8345004c5542f6fcb4a94ca8a1af80d8b362\", \"libjingle_xmpp_unittests\": \"67cb612a16288ab668c978cbc9dfe0412774ea7a\", \"media_blink_unittests\": \"3aeb16b474bedfda968a636410410efec1d5e36e\", \"media_unittests\": \"5f3fa3c3b5d6f8760125172bf71537ffd49f985a\", \"message_center_unittests\": \"1c16c1037122e0bd5e5d9698660ec20f0017297b\", \"metrics_python_tests\": \"d7dfaa88bd4571f82bdae9a8505722e0c68c9180\", \"midi_unittests\": \"20a36798dd4131f22395ad101af5ae8bb9fa340e\", \"mojo_core_unittests\": \"f7ba463bca1012b061888117b172ed8f1e15afc2\", \"mojo_unittests\": \"c14cd0c4f38d7fc3b565792c0259c562b5864d40\", \"nacl_helper_nonsfi_unittests\": \"dea35ff9e2723e143e9130360833f64ab82a7061\", \"nacl_loader_unittests\": \"6d9e1d7d297257149b01d6247c1122a2ffdb4e4a\", \"native_theme_unittests\": \"72e40ccd49a8036dfe81e8ecdcd282c8633b5632\", \"net_unittests\": \"f019fd084379f0b895de25fdb8d1c5e4fd2db4d7\", \"pdf_unittests\": \"cc440596f92812ec5570d9c3986209971f783587\", \"perfetto_unittests\": \"d9023dcc9b2ea4d744171e411111a5060dbeff20\", \"ppapi_unittests\": \"ffcd21e5e31b661a27fee3a6cd198a5dbe46c02d\", \"printing_unittests\": \"5635d2409c4cb8ea966847a8abd6c2ff5fea1f85\", \"remoting_unittests\": \"dd4cd56d1076c2d893a11a9710e94cccde3e9dc1\", \"sandbox_linux_unittests\": \"b5eb5f329a19e5f9f70b388850e337d5c7492cf1\", \"service_manager_unittests\": \"1240e180950c981f3ecf4873933e27d698ed3f9b\", \"services_unittests\": \"56ea67e34b6cee9614b386e46d14143ddfac3bba\", \"shell_dialogs_unittests\": \"5e61fcea9273d0c1ba6d5a07c8b3c908669ab672\", \"skia_unittests\": \"864f3212b6e0ac94bf4e25a4af11e476b89665eb\", \"snapshot_unittests\": \"e86009d01e75e69d4869095188035c585ec579f3\", \"sql_unittests\": \"d9ca26465ffbc4d4b3bc7b1576b15aa681f665f6\", \"storage_unittests\": \"8c562f5186b824e5010ee478c9e60ba0ed2d0cd8\", \"sync_integration_tests\": \"09be8f3a27a428e4534ce1b1d92eb84590ff8728\", \"telemetry_gpu_unittests\": \"47b6ace544dd6a36ac5741efcc538066a1fcb1eb\", \"telemetry_perf_unittests\": \"5e67aa5926fbe62fe7ba1d6136ce1550334eb5fd\", \"telemetry_unittests\": \"67ffc1ed5f69d32de4784a903375f6a56b9c93a6\", \"traffic_annotation_auditor_unittests\": \"f07c278abcfc67c246ce896116db41b446fab223\", \"ui_base_unittests\": \"e941fda1f0eed56eedea2fa09901f445b6e79beb\", \"ui_touch_selection_unittests\": \"36da261add124676edf8dec76f8eebd846705770\", \"unit_tests\": \"fb0babe371c55cfbdeadf4a74a5d7b669af85462\", \"url_unittests\": \"7e9dbfb6c60d260ec560d93beed179a344dd3154\", \"views_perftests\": \"2ab971b18874a7a8b6157a01df69625e51010291\", \"views_unittests\": \"5ca0d97219591b7b502f4ab88529679aaddee404\", \"viz_unittests\": \"c78f231658e80ec350366ee853b9106bd5284d21\", \"vr_common_unittests\": \"836de5c04f70206b41bce75bd3387f5817536fae\", \"vr_pixeltests\": \"4091da0eb9c5de269ef4fa40b9fce488dac27b17\", \"webdriver_wpt_tests\": \"3d5e799ff71514b0c1a8b72a55532db9945fae16\", \"weblayer_browsertests\": \"2160045ad29241cd9daeea2c036007d44ba23dcc\", \"wm_unittests\": \"84def5dcd70ac8995488e16435774614663f6556\", \"wtf_unittests\": \"565431fda1d2728f47a767f32c0937da86f6ab7f\", \"xr_browser_tests\": \"19249a42c0275d7685772946532960fc1d7f7390\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-134-0nsa\"], \"image\": [\"chrome-xenial-20032700-43453ad22a9\"], \"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\": [\"4901-91f024a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585530483948975",
"status": "COMPLETED",
"status_changed_ts": "1585533985970117",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170366",
"builder:Linux Builder (dbg)",
"buildset:commit/git/33c7149e159a5ac4002c359d9f84ede6eb332ef2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/33c7149e159a5ac4002c359d9f84ede6eb332ef2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049874831847859744",
"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/8884444769800993520/+/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:4b41c8df396cdc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585533985974549",
"url": "https://ci.chromium.org/b/8884444769800993520",
"utcnow_ts": "1585533986979246"
}
}
[02:06:26.990] Invocation finished in 1h2m38.607043404s with status SUCCEEDED