[06:56:00.779] New invocation is queued and will start shortly
[06:56:02.131] Starting the invocation (attempt 1)
[06:56:02.181] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:56:02.181] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:56:02.181] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018053137750688800",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3774cef9c44f02108e3b266be72c59c3605619ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9018053137750688800",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/3774cef9c44f02108e3b266be72c59c3605619ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3774cef9c44f02108e3b266be72c59c3605619ee",
"gitiles_ref:refs/heads/master"
]
}
[06:56:02.947] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615877762294294",
"id": "8852623075483136976",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3774cef9c44f02108e3b266be72c59c3605619ee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615877762784531",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181413",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3774cef9c44f02108e3b266be72c59c3605619ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3774cef9c44f02108e3b266be72c59c3605619ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018053137750688800",
"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/8852623075483136976/+/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": "1615877762784793",
"url": "https://ci.chromium.org/b/8852623075483136976",
"utcnow_ts": "1615877762941304"
}
}
[06:56:02.947] Task URL: https://ci.chromium.org/b/8852623075483136976
[06:56:02.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:2:0) after 1m0s
[06:57:03.074] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:2:0)
[06:57:03.074] Timer tick, asking Buildbucket for the build status
[06:57:03.462] Build 8852623075483136976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:03.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:3:0) after 1m0s
[06:58:03.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:3:0)
[06:58:03.586] Timer tick, asking Buildbucket for the build status
[06:58:03.823] Build 8852623075483136976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:03.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:4:0) after 1m0s
[06:59:03.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:4:0)
[06:59:03.837] Timer tick, asking Buildbucket for the build status
[06:59:03.951] Build 8852623075483136976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:03.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:5:0) after 1m0s
[06:59:21.268] Received PubSub notification, asking Buildbucket for the build status
[06:59:21.361] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:04.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:5:0)
[07:00:04.008] Timer tick, asking Buildbucket for the build status
[07:00:04.307] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:04.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:7:0) after 1m0s
[07:01:04.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:7:0)
[07:01:04.437] Timer tick, asking Buildbucket for the build status
[07:01:04.518] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:04.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:8:0) after 1m0s
[07:02:04.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:8:0)
[07:02:04.589] Timer tick, asking Buildbucket for the build status
[07:02:04.665] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:04.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:9:0) after 1m0s
[07:03:04.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:9:0)
[07:03:04.704] Timer tick, asking Buildbucket for the build status
[07:03:04.794] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:04.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:10:0) after 1m0s
[07:04:04.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:10:0)
[07:04:04.845] Timer tick, asking Buildbucket for the build status
[07:04:04.920] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:04.920] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:11:0) after 1m0s
[07:05:04.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:11:0)
[07:05:04.925] Timer tick, asking Buildbucket for the build status
[07:05:05.241] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:05.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:12:0) after 1m0s
[07:06:05.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:12:0)
[07:06:05.225] Timer tick, asking Buildbucket for the build status
[07:06:05.893] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:05.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:13:0) after 1m0s
[07:07:05.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:13:0)
[07:07:05.969] Timer tick, asking Buildbucket for the build status
[07:07:06.236] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:06.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:14:0) after 1m0s
[07:08:06.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:14:0)
[07:08:06.277] Timer tick, asking Buildbucket for the build status
[07:08:06.355] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:06.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:15:0) after 1m0s
[07:09:06.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:15:0)
[07:09:06.419] Timer tick, asking Buildbucket for the build status
[07:09:06.514] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:06.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:16:0) after 1m0s
[07:10:06.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:16:0)
[07:10:06.484] Timer tick, asking Buildbucket for the build status
[07:10:06.559] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:06.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:17:0) after 1m0s
[07:11:06.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:17:0)
[07:11:06.554] Timer tick, asking Buildbucket for the build status
[07:11:06.648] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:06.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:18:0) after 1m0s
[07:12:06.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:18:0)
[07:12:06.646] Timer tick, asking Buildbucket for the build status
[07:12:06.773] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:06.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:19:0) after 1m0s
[07:13:06.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:19:0)
[07:13:06.834] Timer tick, asking Buildbucket for the build status
[07:13:07.885] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:07.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:20:0) after 1m0s
[07:14:07.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:20:0)
[07:14:07.900] Timer tick, asking Buildbucket for the build status
[07:14:08.005] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:08.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:21:0) after 1m0s
[07:15:08.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:21:0)
[07:15:08.061] Timer tick, asking Buildbucket for the build status
[07:15:08.727] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:08.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:22:0) after 1m0s
[07:16:08.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:22:0)
[07:16:08.776] Timer tick, asking Buildbucket for the build status
[07:16:08.874] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:08.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:23:0) after 1m0s
[07:17:08.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:23:0)
[07:17:08.935] Timer tick, asking Buildbucket for the build status
[07:17:08.998] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:08.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:24:0) after 1m0s
[07:18:09.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:24:0)
[07:18:09.083] Timer tick, asking Buildbucket for the build status
[07:18:09.171] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:09.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:25:0) after 1m0s
[07:19:09.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:25:0)
[07:19:09.760] Timer tick, asking Buildbucket for the build status
[07:19:09.890] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:09.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:26:0) after 1m0s
[07:20:10.055] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:26:0)
[07:20:10.055] Timer tick, asking Buildbucket for the build status
[07:20:10.185] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:10.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:27:0) after 1m0s
[07:21:10.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:27:0)
[07:21:10.376] Timer tick, asking Buildbucket for the build status
[07:21:10.484] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:10.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:28:0) after 1m0s
[07:22:10.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:28:0)
[07:22:10.521] Timer tick, asking Buildbucket for the build status
[07:22:10.647] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:10.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:29:0) after 1m0s
[07:23:10.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:29:0)
[07:23:10.757] Timer tick, asking Buildbucket for the build status
[07:23:10.926] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:10.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:30:0) after 1m0s
[07:24:11.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:30:0)
[07:24:11.154] Timer tick, asking Buildbucket for the build status
[07:24:11.404] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:11.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:31:0) after 1m0s
[07:25:11.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:31:0)
[07:25:11.464] Timer tick, asking Buildbucket for the build status
[07:25:11.523] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:11.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:32:0) after 1m0s
[07:26:11.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:32:0)
[07:26:11.595] Timer tick, asking Buildbucket for the build status
[07:26:11.773] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:11.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:33:0) after 1m0s
[07:27:11.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:33:0)
[07:27:11.834] Timer tick, asking Buildbucket for the build status
[07:27:11.952] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:11.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:34:0) after 1m0s
[07:28:12.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:34:0)
[07:28:12.009] Timer tick, asking Buildbucket for the build status
[07:28:12.394] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:12.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:35:0) after 1m0s
[07:29:12.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:35:0)
[07:29:12.483] Timer tick, asking Buildbucket for the build status
[07:29:12.582] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:12.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:36:0) after 1m0s
[07:30:12.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:36:0)
[07:30:12.793] Timer tick, asking Buildbucket for the build status
[07:30:12.887] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:12.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:37:0) after 1m0s
[07:31:13.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:37:0)
[07:31:13.123] Timer tick, asking Buildbucket for the build status
[07:31:13.370] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:13.370] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:38:0) after 1m0s
[07:32:13.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:38:0)
[07:32:13.551] Timer tick, asking Buildbucket for the build status
[07:32:13.641] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:13.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:39:0) after 1m0s
[07:33:13.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:39:0)
[07:33:13.701] Timer tick, asking Buildbucket for the build status
[07:33:13.774] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:13.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:40:0) after 1m0s
[07:34:13.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:40:0)
[07:34:13.811] Timer tick, asking Buildbucket for the build status
[07:34:13.885] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:13.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:41:0) after 1m0s
[07:35:14.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:41:0)
[07:35:14.008] Timer tick, asking Buildbucket for the build status
[07:35:14.085] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:14.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:42:0) after 1m0s
[07:36:14.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:42:0)
[07:36:14.177] Timer tick, asking Buildbucket for the build status
[07:36:14.264] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:14.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:43:0) after 1m0s
[07:37:14.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:43:0)
[07:37:14.302] Timer tick, asking Buildbucket for the build status
[07:37:14.511] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:14.511] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:44:0) after 1m0s
[07:38:14.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:44:0)
[07:38:14.580] Timer tick, asking Buildbucket for the build status
[07:38:14.637] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:14.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:45:0) after 1m0s
[07:39:14.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:45:0)
[07:39:14.637] Timer tick, asking Buildbucket for the build status
[07:39:14.703] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:14.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:46:0) after 1m0s
[07:40:14.772] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:46:0)
[07:40:14.772] Timer tick, asking Buildbucket for the build status
[07:40:14.843] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:14.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:47:0) after 1m0s
[07:41:14.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:47:0)
[07:41:14.913] Timer tick, asking Buildbucket for the build status
[07:41:15.134] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:15.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:48:0) after 1m0s
[07:42:16.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:48:0)
[07:42:16.729] Timer tick, asking Buildbucket for the build status
[07:42:16.868] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:16.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:49:0) after 1m0s
[07:43:17.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:49:0)
[07:43:17.472] Timer tick, asking Buildbucket for the build status
[07:43:17.727] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:17.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:50:0) after 1m0s
[07:44:17.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:50:0)
[07:44:17.742] Timer tick, asking Buildbucket for the build status
[07:44:17.815] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:17.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:51:0) after 1m0s
[07:45:17.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:51:0)
[07:45:17.966] Timer tick, asking Buildbucket for the build status
[07:45:18.045] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:18.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:52:0) after 1m0s
[07:46:18.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:52:0)
[07:46:18.410] Timer tick, asking Buildbucket for the build status
[07:46:18.572] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:18.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:53:0) after 1m0s
[07:47:19.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:53:0)
[07:47:19.867] Timer tick, asking Buildbucket for the build status
[07:47:26.268] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:26.268] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:54:0) after 1m0s
[07:48:27.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:54:0)
[07:48:27.389] Timer tick, asking Buildbucket for the build status
[07:48:28.351] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:28.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:55:0) after 1m0s
[07:49:28.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:55:0)
[07:49:28.371] Timer tick, asking Buildbucket for the build status
[07:49:28.536] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:28.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:56:0) after 1m0s
[07:50:28.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:56:0)
[07:50:28.554] Timer tick, asking Buildbucket for the build status
[07:50:28.642] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:28.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:57:0) after 1m0s
[07:51:28.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:57:0)
[07:51:28.656] Timer tick, asking Buildbucket for the build status
[07:51:28.795] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:28.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:58:0) after 1m0s
[07:52:28.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:58:0)
[07:52:28.814] Timer tick, asking Buildbucket for the build status
[07:52:28.891] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:28.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:59:0) after 1m0s
[07:53:28.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:59:0)
[07:53:28.907] Timer tick, asking Buildbucket for the build status
[07:53:28.983] Build 8852623075483136976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:28.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018053137750688800:60:0) after 1m0s
[07:53:48.468] Received PubSub notification, asking Buildbucket for the build status
[07:53:48.566] Build 8852623075483136976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:53:48.566] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615881227337998",
"created_by": "project:chromium",
"created_ts": "1615877762294294",
"id": "8852623075483136976",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3774cef9c44f02108e3b266be72c59c3605619ee\"}}",
"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\": \"3774cef9c44f02108e3b266be72c59c3605619ee\", \"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-10-sm6n\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181413, \"got_angle_revision\": \"687d3153197e5ef162e7bc75e931a09208ba3fe6\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3774cef9c44f02108e3b266be72c59c3605619ee\", \"got_revision_cp\": \"refs/heads/master@{#863176}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"4e2fbf2b6d4fc2e9bbf239e146973a15bb3a0d40\", \"got_v8_revision_cp\": \"refs/heads/9.1.129@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"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\": \"3774cef9c44f02108e3b266be72c59c3605619ee\", \"swarm_hashes_refs/heads/master(at){#863176}_without_patch\": {\"absl_hardening_tests\": \"0e2326ea5e18883ac1bc19cfeadf0c29184c36f0\", \"accessibility_unittests\": \"1c2b502f3190824497f291aa05a1369f8b4c4a77\", \"angle_unittests\": \"cd6b4e94333b3a726cb2e4e221dc8bc8e0364660\", \"app_shell_unittests\": \"f3ee23f748463dec2eb918b791b36a30b9c0b1ce\", \"aura_unittests\": \"983e95e72e481b59b2812d71906976e81e72cc74\", \"base_unittests\": \"cd0e0932e1d4a07a1a2d17fd2044e7087f1845f7\", \"base_util_unittests\": \"274c0a89abfa85781d8a395ffc020b6307891176\", \"blink_common_unittests\": \"27d74b2d4428e96cdd7a2a0a850ec67a6070399f\", \"blink_fuzzer_unittests\": \"8f4348359e2552fec6eb22bcb57a347bebd229a8\", \"blink_heap_unittests\": \"8f7fc2f1834151406756fe9a6cc5fc76a1c5862a\", \"blink_platform_unittests\": \"e6c6a81bf4b97419f3458cfac579d03bb3f562a5\", \"blink_python_tests\": \"dcc0cff91d58a40f7b05067708ca59f1597979eb\", \"blink_unittests\": \"29134823597168fc19e16a679b5b81d61a9b143f\", \"blink_web_tests\": \"83416a50e82277aa8c7d7e9050c7176907b4828c\", \"boringssl_crypto_tests\": \"85ea7cb5acaf4b7dc42c048b5748ea36977b9ba5\", \"boringssl_ssl_tests\": \"7332d957cd1bd8e8dc68ef1fcfa2ead7748ec4ab\", \"browser_tests\": \"eb82ec0c7e2b386975a8cf25b619c794ccd7e67d\", \"capture_unittests\": \"3eb9ae1f74591f6ae64f73939ec33f4387d430c8\", \"cast_unittests\": \"86f9a4f9b0982860fc0c296193db3b81f73b181d\", \"cc_unittests\": \"81e51f96c7013597278ee0765940782f67b52a77\", \"chrome_app_unittests\": \"047a2bca0d90ce4498a33e613b699542779e8b7a\", \"chromedriver_unittests\": \"58d6efd49f7dcf5f536328a4406fca22b2ffab5c\", \"color_unittests\": \"f90fbc2b034a6150bdfd94f5781bafb0bb0dc62a\", \"components_browsertests\": \"e89649d5452ce5a63602e24faa7bbf0b78719e60\", \"components_unittests\": \"17eb5ad0b337db4d2d71cc862d5a3bbf77ab3506\", \"compositor_unittests\": \"f31bca37a3f5ada049f5581c368b98f33ba283d4\", \"content_browsertests\": \"e8bc8c246737f09a7ddb9ad2a8d0e7ef1f78f4ce\", \"content_shell_crash_test\": \"fbce7deb329e36b9ad4ad57a3eb333bc71987875\", \"content_unittests\": \"8e8bd10b5fbab36b76351b9761ba494a17541cdd\", \"crashpad_tests\": \"da6de57f74e256553745c20954df421e54745c9c\", \"cronet_tests\": \"0c242c83112005ffe0ba26b809e79f9667101426\", \"cronet_unittests\": \"9fed48d7206553fe60a247ffd7ce48042aa8e6c1\", \"crypto_unittests\": \"22faf27abac404534b2c54a18911d113635024c9\", \"dbus_unittests\": \"e451ef2f10f8a7acee60c6b331e84ff7b6926f6d\", \"device_unittests\": \"9ecfa2fc062820e00d3169117cb097745ed8042b\", \"display_unittests\": \"ccc7b4312cd78711630c3654ff62a35804eab369\", \"events_unittests\": \"228a3b86071ee2006592e4ac29e9ff7981b15d2c\", \"extensions_browsertests\": \"8bb756cb15e3e933df885edaced56bab68e7cebf\", \"extensions_unittests\": \"660c57954f3ca0ba7b58ea13b0d04e82de7f7c86\", \"filesystem_service_unittests\": \"cbc61f209140d7df846b21a6c8622def427deff9\", \"flatbuffers_unittests\": \"33ce473c25487fefac09ff9e3c5c615fa4ca6686\", \"gcm_unit_tests\": \"2c67257183dc0fc55fbab20f14baa945e7ed6681\", \"gfx_unittests\": \"c4b2e7c16aaa91960a3baccc9fc735aa9f11e0f6\", \"gin_unittests\": \"57bb21b8b658ecbdf8bcdbcf871887f0bd79bee7\", \"google_apis_unittests\": \"3e4ee85502f774f3294f7672e631d24ebc116815\", \"gpu_unittests\": \"7e34e38494b26836053dbe7d30256787a513466d\", \"grit_python_unittests\": \"a6a82a347c5ac19643f71ece0eaa364aa5fca490\", \"gwp_asan_unittests\": \"199f4c06e9dfe2cda596c90474e9eca8af359e58\", \"headless_browsertests\": \"295b5a4c27c42e642611bd4b88596a4fad20b8f5\", \"headless_unittests\": \"b6024b7baba2bedc604697ac224f40461306cd5f\", \"interactive_ui_tests\": \"550f4f4d71787ad12690f6e599f077985326cc01\", \"ipc_tests\": \"5694ba5410dab0d70ff69d18415c3a7cf910415e\", \"jingle_unittests\": \"6204259b8a9cfba6bbed713ec448392979ddc510\", \"latency_unittests\": \"94f353b9ff0bdb393e43d6ec89551173ac92c2ea\", \"libjingle_xmpp_unittests\": \"1d62dce2b404d4c4cc8ae53c8a12e19d523c1d56\", \"liburlpattern_unittests\": \"2f90706d2ecd0e3b1a8f75385cc22f3e4ac63bda\", \"media_blink_unittests\": \"8c49cdb5a74e723da2f91ddaf3d6259d409e014d\", \"media_unittests\": \"db9a39176ded3eb3083e0964bd855bf2c08b4591\", \"message_center_unittests\": \"802dcf6cc61b8ca623b458d7fd9060373547d03c\", \"metrics_python_tests\": \"401b10dda3310f2b9d2838043560bccb95646f9f\", \"midi_unittests\": \"f93a59a446732e5f298b2e9a4973c73ce6f45ccc\", \"mojo_core_unittests\": \"5ee000032874b0a9dfe1b9da23fb96ac2440b178\", \"mojo_unittests\": \"f0479b600e8cce7a2ec166d86eb611b846cd5878\", \"nacl_helper_nonsfi_unittests\": \"8505cffdc946c044eb6db6cc697c9662fb31a317\", \"nacl_loader_unittests\": \"36f01d18082a91a9b7330f0d4fcd19549366ca18\", \"native_theme_unittests\": \"1e834acf057b4ed0821ed6c6f23aa1d8011d0746\", \"net_unittests\": \"efc6f0e377865e55bc867224762410dfe69c7715\", \"openscreen_unittests\": \"1827d8a3191e637f96ed562098353acfb24850d7\", \"pdf_unittests\": \"6286d3360e4d6e709c7aaf0ee38d40b81079e642\", \"perfetto_unittests\": \"c00625e1901d554563f87d154ef633da4d5874b7\", \"ppapi_unittests\": \"f3c89a2d68e6280f4449d0dfc4137f3b8cd0f58c\", \"printing_unittests\": \"58f9bc6661b8b78fa07da4962710d80145f0a2fc\", \"remoting_unittests\": \"46a48c702c125fc2e0cbd61a6caf6831fe78c6ce\", \"sandbox_linux_unittests\": \"a31b00932edf2ad0c24b0e2426b441d767a2e633\", \"service_manager_unittests\": \"db9c75551e52269b4d59a89ea8390e644b3b2d8c\", \"services_unittests\": \"8f372ecc885459660d65b20713195cacee708538\", \"shell_dialogs_unittests\": \"c5250cd6c64ab10cc0b6a5d7c069c032acb84e3f\", \"skia_unittests\": \"abedc1fd092c292c2d3693a9296f3e4e4e5bacb5\", \"snapshot_unittests\": \"70e055dd8e93f2ed17ac4a0aa71c4ce3b5065646\", \"sql_unittests\": \"224e032f4c29d88e3f7234540c8f046a656b0023\", \"storage_unittests\": \"289bd7f6e0e5d39ab26ed4ad136cac3820fe0f51\", \"sync_integration_tests\": \"4cd391663d3373b934f4986e501432d3d88f8006\", \"telemetry_gpu_unittests\": \"d26a7affb289aef03c74ebe20a94bfbc518ee9f6\", \"telemetry_perf_unittests\": \"5cc70ce7c46f13bab5c6a3056e8d47dc11328c9d\", \"telemetry_unittests\": \"04a68ce855eb75faab1dcd9d2203f0a6209a94d5\", \"traffic_annotation_auditor_unittests\": \"cb5add1f7cb87d0e4f59aa63b818982a660606c0\", \"ui_base_unittests\": \"56cbe0c139a7fc29deb448cc5de9bae30b1c9ee6\", \"ui_touch_selection_unittests\": \"83ac027e59b0ac531b25b10df920c4a08a2791b4\", \"unit_tests\": \"6d445092ac8b60e290c0b7bbec439c472f3f45fc\", \"url_unittests\": \"1144b2c00acb3c5926dcbc9af0f2d86b868d0f48\", \"views_perftests\": \"9ea92d92c17fe17c1dc87bb74650f6d0713c84b0\", \"views_unittests\": \"c42492ce0a4be10ce499a60adb0c789eac2a2b11\", \"viz_unittests\": \"f6f2c5b73fc042362042d3007b9988137563d029\", \"vr_common_unittests\": \"865c8f9bc24eddf0b96ea05c4378c43ea9e5f4e3\", \"vr_pixeltests\": \"59e0d8529176270e6b10d1f33e184f54e7cf6e61\", \"webdriver_wpt_tests\": \"2a30f9bfc82a8c047786f8ad198b76f25442884c\", \"weblayer_browsertests\": \"819180e269a7a5fe3443656b853679e8b9ed7573\", \"weblayer_unittests\": \"43cd5ead77147c983c43e80e1f1fcbb3ba7777bc\", \"wm_unittests\": \"a38eda65601eb9adb8bfce9f09e5971896b704cc\", \"wtf_unittests\": \"1e46e31823eeb4e5a68d3fbc05c5d3be74171560\", \"x11_unittests\": \"a8ee841b9fe967e4091a80cf234afa99b30d1b5a\", \"xr_browser_tests\": \"805b2df77d1fcc2f8ae2a3a840cb7ff1e03250d9\", \"zlib_unittests\": \"13339dc76e94a592be56bd0a5c4294072f1042b3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_53a369b6e7b3f2409540d71b27a68c8bcf1cb73633e70dba0b4b8b173a0aec5e_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_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-10-sm6n\"], \"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.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615877960409369",
"status": "COMPLETED",
"status_changed_ts": "1615881228066029",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181413",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3774cef9c44f02108e3b266be72c59c3605619ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3774cef9c44f02108e3b266be72c59c3605619ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018053137750688800",
"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/8852623075483136976/+/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:5252a3781fe20b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615881228071252",
"url": "https://ci.chromium.org/b/8852623075483136976",
"utcnow_ts": "1615881228557490"
}
}
[07:53:48.566] Invocation finished in 57m47.817799854s with status SUCCEEDED