[05:26:11.936] New invocation is queued and will start shortly
[05:26:13.131] Starting the invocation (attempt 1)
[05:26:13.166] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:26:13.167] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:26:13.167] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028477439483351216",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fcf274222208752b27048083b92f04fbce289555\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9028477439483351216",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/fcf274222208752b27048083b92f04fbce289555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcf274222208752b27048083b92f04fbce289555",
"gitiles_ref:refs/heads/master"
]
}
[05:26:14.792] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605936373254153",
"id": "8863047377437323056",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fcf274222208752b27048083b92f04fbce289555\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605936374174144",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/177894",
"builder:Linux Builder (dbg)",
"buildset:commit/git/fcf274222208752b27048083b92f04fbce289555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcf274222208752b27048083b92f04fbce289555",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028477439483351216",
"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/8863047377437323056/+/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": "1605936374174325",
"url": "https://ci.chromium.org/b/8863047377437323056",
"utcnow_ts": "1605936374779895"
}
}
[05:26:14.792] Task URL: https://ci.chromium.org/b/8863047377437323056
[05:26:14.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:2:0) after 1m0s
[05:27:15.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:2:0)
[05:27:15.130] Timer tick, asking Buildbucket for the build status
[05:27:15.233] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:15.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:3:0) after 1m0s
[05:28:15.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:3:0)
[05:28:15.321] Timer tick, asking Buildbucket for the build status
[05:28:15.392] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:15.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:4:0) after 1m0s
[05:29:15.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:4:0)
[05:29:15.471] Timer tick, asking Buildbucket for the build status
[05:29:15.575] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:15.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:5:0) after 1m0s
[05:30:15.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:5:0)
[05:30:15.669] Timer tick, asking Buildbucket for the build status
[05:30:16.682] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:16.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:6:0) after 1m0s
[05:31:16.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:6:0)
[05:31:16.705] Timer tick, asking Buildbucket for the build status
[05:31:16.803] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:16.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:7:0) after 1m0s
[05:32:17.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:7:0)
[05:32:17.020] Timer tick, asking Buildbucket for the build status
[05:32:17.337] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:17.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:8:0) after 1m0s
[05:33:17.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:8:0)
[05:33:17.463] Timer tick, asking Buildbucket for the build status
[05:33:17.710] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:17.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:9:0) after 1m0s
[05:34:17.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:9:0)
[05:34:17.708] Timer tick, asking Buildbucket for the build status
[05:34:17.856] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:17.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:10:0) after 1m0s
[05:35:17.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:10:0)
[05:35:17.898] Timer tick, asking Buildbucket for the build status
[05:35:17.973] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:17.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:11:0) after 1m0s
[05:36:18.110] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:11:0)
[05:36:18.110] Timer tick, asking Buildbucket for the build status
[05:36:27.017] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:27.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:12:0) after 1m0s
[05:37:27.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:12:0)
[05:37:27.516] Timer tick, asking Buildbucket for the build status
[05:37:27.596] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:27.596] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:13:0) after 1m0s
[05:38:27.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:13:0)
[05:38:27.732] Timer tick, asking Buildbucket for the build status
[05:38:27.831] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:27.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:14:0) after 1m0s
[05:39:27.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:14:0)
[05:39:27.960] Timer tick, asking Buildbucket for the build status
[05:39:28.270] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:28.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:15:0) after 1m0s
[05:40:28.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:15:0)
[05:40:28.321] Timer tick, asking Buildbucket for the build status
[05:40:28.412] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:28.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:16:0) after 1m0s
[05:41:28.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:16:0)
[05:41:28.432] Timer tick, asking Buildbucket for the build status
[05:41:29.170] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:29.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:17:0) after 1m0s
[05:42:29.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:17:0)
[05:42:29.222] Timer tick, asking Buildbucket for the build status
[05:42:29.309] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:29.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:18:0) after 1m0s
[05:43:29.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:18:0)
[05:43:29.367] Timer tick, asking Buildbucket for the build status
[05:43:29.437] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:29.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:19:0) after 1m0s
[05:44:29.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:19:0)
[05:44:29.463] Timer tick, asking Buildbucket for the build status
[05:44:29.537] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:29.537] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:20:0) after 1m0s
[05:45:29.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:20:0)
[05:45:29.595] Timer tick, asking Buildbucket for the build status
[05:45:29.673] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:29.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:21:0) after 1m0s
[05:46:29.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:21:0)
[05:46:29.787] Timer tick, asking Buildbucket for the build status
[05:46:29.911] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:29.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:22:0) after 1m0s
[05:47:29.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:22:0)
[05:47:29.936] Timer tick, asking Buildbucket for the build status
[05:47:30.281] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:30.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:23:0) after 1m0s
[05:48:30.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:23:0)
[05:48:30.304] Timer tick, asking Buildbucket for the build status
[05:48:30.387] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:30.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:24:0) after 1m0s
[05:49:30.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:24:0)
[05:49:30.409] Timer tick, asking Buildbucket for the build status
[05:49:30.474] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:30.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:25:0) after 1m0s
[05:50:30.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:25:0)
[05:50:30.519] Timer tick, asking Buildbucket for the build status
[05:50:30.599] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:30.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:26:0) after 1m0s
[05:51:30.636] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:26:0)
[05:51:30.636] Timer tick, asking Buildbucket for the build status
[05:51:30.916] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:30.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:27:0) after 1m0s
[05:52:31.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:27:0)
[05:52:31.051] Timer tick, asking Buildbucket for the build status
[05:52:31.119] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:31.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:28:0) after 1m0s
[05:53:31.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:28:0)
[05:53:31.329] Timer tick, asking Buildbucket for the build status
[05:53:31.917] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:31.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:29:0) after 1m0s
[05:54:31.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:29:0)
[05:54:31.898] Timer tick, asking Buildbucket for the build status
[05:54:32.204] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:32.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:30:0) after 1m0s
[05:55:32.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:30:0)
[05:55:32.406] Timer tick, asking Buildbucket for the build status
[05:55:32.481] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:32.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:31:0) after 1m0s
[05:56:32.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:31:0)
[05:56:32.484] Timer tick, asking Buildbucket for the build status
[05:56:32.587] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:32.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:32:0) after 1m0s
[05:57:32.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:32:0)
[05:57:32.643] Timer tick, asking Buildbucket for the build status
[05:57:32.715] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:32.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:33:0) after 1m0s
[05:58:32.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:33:0)
[05:58:32.780] Timer tick, asking Buildbucket for the build status
[05:58:32.859] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:32.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:34:0) after 1m0s
[05:59:32.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:34:0)
[05:59:32.917] Timer tick, asking Buildbucket for the build status
[05:59:32.984] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:32.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:35:0) after 1m0s
[06:00:33.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:35:0)
[06:00:33.086] Timer tick, asking Buildbucket for the build status
[06:00:33.294] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:33.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:36:0) after 1m0s
[06:01:39.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:36:0)
[06:01:39.741] Timer tick, asking Buildbucket for the build status
[06:01:39.842] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:39.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:37:0) after 1m0s
[06:02:39.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:37:0)
[06:02:39.898] Timer tick, asking Buildbucket for the build status
[06:02:39.965] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:39.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:38:0) after 1m0s
[06:03:39.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:38:0)
[06:03:39.947] Timer tick, asking Buildbucket for the build status
[06:03:40.012] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:40.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:39:0) after 1m0s
[06:04:40.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:39:0)
[06:04:40.044] Timer tick, asking Buildbucket for the build status
[06:04:40.114] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:40.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:40:0) after 1m0s
[06:05:40.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:40:0)
[06:05:40.159] Timer tick, asking Buildbucket for the build status
[06:05:40.231] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:40.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:41:0) after 1m0s
[06:06:40.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:41:0)
[06:06:40.268] Timer tick, asking Buildbucket for the build status
[06:06:40.393] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:40.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:42:0) after 1m0s
[06:07:40.412] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:42:0)
[06:07:40.412] Timer tick, asking Buildbucket for the build status
[06:07:40.544] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:40.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:43:0) after 1m0s
[06:08:40.532] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:43:0)
[06:08:40.532] Timer tick, asking Buildbucket for the build status
[06:08:40.601] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:40.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:44:0) after 1m0s
[06:09:41.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:44:0)
[06:09:41.475] Timer tick, asking Buildbucket for the build status
[06:09:41.543] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:41.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:45:0) after 1m0s
[06:10:41.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:45:0)
[06:10:41.728] Timer tick, asking Buildbucket for the build status
[06:10:41.798] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:41.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:46:0) after 1m0s
[06:11:41.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:46:0)
[06:11:41.899] Timer tick, asking Buildbucket for the build status
[06:11:42.284] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:42.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:47:0) after 1m0s
[06:12:42.319] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:47:0)
[06:12:42.319] Timer tick, asking Buildbucket for the build status
[06:12:42.404] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:42.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:48:0) after 1m0s
[06:13:42.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:48:0)
[06:13:42.464] Timer tick, asking Buildbucket for the build status
[06:13:42.555] Build 8863047377437323056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:42.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:49:0) after 1m0s
[06:14:27.546] Received PubSub notification, asking Buildbucket for the build status
[06:14:27.715] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:42.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:49:0)
[06:14:42.755] Timer tick, asking Buildbucket for the build status
[06:14:42.832] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:42.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:51:0) after 1m0s
[06:15:43.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:51:0)
[06:15:43.007] Timer tick, asking Buildbucket for the build status
[06:15:43.077] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:43.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:52:0) after 1m0s
[06:16:43.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:52:0)
[06:16:43.123] Timer tick, asking Buildbucket for the build status
[06:16:43.271] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:43.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:53:0) after 1m0s
[06:17:43.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:53:0)
[06:17:43.290] Timer tick, asking Buildbucket for the build status
[06:17:43.419] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:43.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:54:0) after 1m0s
[06:18:43.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:54:0)
[06:18:43.522] Timer tick, asking Buildbucket for the build status
[06:18:43.593] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:43.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:55:0) after 1m0s
[06:19:43.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:55:0)
[06:19:43.617] Timer tick, asking Buildbucket for the build status
[06:19:43.701] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:43.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:56:0) after 1m0s
[06:20:43.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:56:0)
[06:20:43.686] Timer tick, asking Buildbucket for the build status
[06:20:43.788] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:43.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:57:0) after 1m0s
[06:21:43.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:57:0)
[06:21:43.800] Timer tick, asking Buildbucket for the build status
[06:21:43.907] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:43.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:58:0) after 1m0s
[06:22:43.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:58:0)
[06:22:43.933] Timer tick, asking Buildbucket for the build status
[06:22:44.170] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:44.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:59:0) after 1m0s
[06:23:44.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:59:0)
[06:23:44.255] Timer tick, asking Buildbucket for the build status
[06:23:44.316] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:44.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:60:0) after 1m0s
[06:24:44.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:60:0)
[06:24:44.413] Timer tick, asking Buildbucket for the build status
[06:24:44.589] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:44.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:61:0) after 1m0s
[06:25:44.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:61:0)
[06:25:44.789] Timer tick, asking Buildbucket for the build status
[06:25:44.912] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:44.912] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:62:0) after 1m0s
[06:26:44.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:62:0)
[06:26:44.956] Timer tick, asking Buildbucket for the build status
[06:26:45.066] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:45.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:63:0) after 1m0s
[06:27:45.110] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:63:0)
[06:27:45.110] Timer tick, asking Buildbucket for the build status
[06:27:45.184] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:45.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:64:0) after 1m0s
[06:28:45.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:64:0)
[06:28:45.209] Timer tick, asking Buildbucket for the build status
[06:28:45.421] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:45.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:65:0) after 1m0s
[06:29:45.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:65:0)
[06:29:45.481] Timer tick, asking Buildbucket for the build status
[06:29:45.588] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:45.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:66:0) after 1m0s
[06:30:45.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:66:0)
[06:30:45.695] Timer tick, asking Buildbucket for the build status
[06:30:45.798] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:45.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:67:0) after 1m0s
[06:31:45.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:67:0)
[06:31:45.890] Timer tick, asking Buildbucket for the build status
[06:31:45.980] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:45.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:68:0) after 1m0s
[06:32:46.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:68:0)
[06:32:46.042] Timer tick, asking Buildbucket for the build status
[06:32:46.100] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:46.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:69:0) after 1m0s
[06:33:46.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:69:0)
[06:33:46.180] Timer tick, asking Buildbucket for the build status
[06:33:46.242] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:46.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:70:0) after 1m0s
[06:34:46.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:70:0)
[06:34:46.346] Timer tick, asking Buildbucket for the build status
[06:34:46.529] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:71:0) after 1m0s
[06:35:46.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:71:0)
[06:35:46.583] Timer tick, asking Buildbucket for the build status
[06:35:46.639] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:46.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:72:0) after 1m0s
[06:36:46.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:72:0)
[06:36:46.916] Timer tick, asking Buildbucket for the build status
[06:36:47.218] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:47.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:73:0) after 1m0s
[06:37:47.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:73:0)
[06:37:47.370] Timer tick, asking Buildbucket for the build status
[06:37:47.449] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:47.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:74:0) after 1m0s
[06:38:47.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:74:0)
[06:38:47.511] Timer tick, asking Buildbucket for the build status
[06:38:47.577] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:47.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:75:0) after 1m0s
[06:39:47.665] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:75:0)
[06:39:47.665] Timer tick, asking Buildbucket for the build status
[06:39:47.779] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:47.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:76:0) after 1m0s
[06:40:47.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:76:0)
[06:40:47.845] Timer tick, asking Buildbucket for the build status
[06:40:47.928] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:47.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:77:0) after 1m0s
[06:41:47.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:77:0)
[06:41:47.955] Timer tick, asking Buildbucket for the build status
[06:41:48.049] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:48.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:78:0) after 1m0s
[06:42:48.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:78:0)
[06:42:48.130] Timer tick, asking Buildbucket for the build status
[06:42:48.187] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:48.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:79:0) after 1m0s
[06:43:48.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:79:0)
[06:43:48.191] Timer tick, asking Buildbucket for the build status
[06:43:48.259] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:48.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:80:0) after 1m0s
[06:44:48.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:80:0)
[06:44:48.347] Timer tick, asking Buildbucket for the build status
[06:44:48.406] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:48.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:81:0) after 1m0s
[06:45:48.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:81:0)
[06:45:48.485] Timer tick, asking Buildbucket for the build status
[06:45:48.545] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:48.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:82:0) after 1m0s
[06:46:48.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:82:0)
[06:46:48.617] Timer tick, asking Buildbucket for the build status
[06:46:48.719] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:48.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:83:0) after 1m0s
[06:47:48.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:83:0)
[06:47:48.800] Timer tick, asking Buildbucket for the build status
[06:47:48.859] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:48.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:84:0) after 1m0s
[06:48:49.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:84:0)
[06:48:49.013] Timer tick, asking Buildbucket for the build status
[06:48:49.081] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:49.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:85:0) after 1m0s
[06:49:49.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:85:0)
[06:49:49.210] Timer tick, asking Buildbucket for the build status
[06:49:49.275] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:49.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:86:0) after 1m0s
[06:50:49.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:86:0)
[06:50:49.329] Timer tick, asking Buildbucket for the build status
[06:50:49.845] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:49.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:87:0) after 1m0s
[06:51:49.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:87:0)
[06:51:49.903] Timer tick, asking Buildbucket for the build status
[06:51:49.993] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:49.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:88:0) after 1m0s
[06:52:50.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:88:0)
[06:52:50.051] Timer tick, asking Buildbucket for the build status
[06:52:50.112] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:50.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:89:0) after 1m0s
[06:53:50.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:89:0)
[06:53:50.199] Timer tick, asking Buildbucket for the build status
[06:53:50.330] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:50.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:90:0) after 1m0s
[06:54:50.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:90:0)
[06:54:50.339] Timer tick, asking Buildbucket for the build status
[06:54:50.407] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:50.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:91:0) after 1m0s
[06:55:50.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:91:0)
[06:55:50.442] Timer tick, asking Buildbucket for the build status
[06:55:50.504] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:50.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:92:0) after 1m0s
[06:56:50.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:92:0)
[06:56:50.587] Timer tick, asking Buildbucket for the build status
[06:56:50.748] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:50.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:93:0) after 1m0s
[06:57:50.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:93:0)
[06:57:50.766] Timer tick, asking Buildbucket for the build status
[06:57:51.155] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:51.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:94:0) after 1m0s
[06:58:51.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:94:0)
[06:58:51.353] Timer tick, asking Buildbucket for the build status
[06:58:51.443] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:51.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:95:0) after 1m0s
[06:59:51.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:95:0)
[06:59:51.554] Timer tick, asking Buildbucket for the build status
[06:59:51.618] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:51.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:96:0) after 1m0s
[07:00:51.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:96:0)
[07:00:51.644] Timer tick, asking Buildbucket for the build status
[07:00:52.253] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:52.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:97:0) after 1m0s
[07:01:52.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:97:0)
[07:01:52.297] Timer tick, asking Buildbucket for the build status
[07:01:52.407] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:52.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:98:0) after 1m0s
[07:02:52.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:98:0)
[07:02:52.526] Timer tick, asking Buildbucket for the build status
[07:02:52.588] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:52.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:99:0) after 1m0s
[07:03:52.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:99:0)
[07:03:52.652] Timer tick, asking Buildbucket for the build status
[07:03:52.708] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:52.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:100:0) after 1m0s
[07:04:52.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:100:0)
[07:04:52.785] Timer tick, asking Buildbucket for the build status
[07:04:52.905] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:52.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:101:0) after 1m0s
[07:05:52.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:101:0)
[07:05:52.931] Timer tick, asking Buildbucket for the build status
[07:05:52.985] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:52.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:102:0) after 1m0s
[07:06:53.041] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:102:0)
[07:06:53.041] Timer tick, asking Buildbucket for the build status
[07:06:53.143] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:53.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:103:0) after 1m0s
[07:07:53.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:103:0)
[07:07:53.233] Timer tick, asking Buildbucket for the build status
[07:07:53.336] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:53.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:104:0) after 1m0s
[07:08:53.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:104:0)
[07:08:53.407] Timer tick, asking Buildbucket for the build status
[07:08:53.469] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:53.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:105:0) after 1m0s
[07:09:53.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:105:0)
[07:09:53.587] Timer tick, asking Buildbucket for the build status
[07:09:53.656] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:53.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:106:0) after 1m0s
[07:10:53.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:106:0)
[07:10:53.832] Timer tick, asking Buildbucket for the build status
[07:10:53.939] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:53.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:107:0) after 1m0s
[07:11:54.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:107:0)
[07:11:54.098] Timer tick, asking Buildbucket for the build status
[07:11:54.228] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:54.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:108:0) after 1m0s
[07:12:54.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:108:0)
[07:12:54.304] Timer tick, asking Buildbucket for the build status
[07:12:54.967] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:54.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:109:0) after 1m0s
[07:13:55.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:109:0)
[07:13:55.000] Timer tick, asking Buildbucket for the build status
[07:13:55.407] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:55.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:110:0) after 1m0s
[07:14:55.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:110:0)
[07:14:55.384] Timer tick, asking Buildbucket for the build status
[07:14:55.448] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:55.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:111:0) after 1m0s
[07:15:55.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:111:0)
[07:15:55.446] Timer tick, asking Buildbucket for the build status
[07:15:56.026] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:56.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:112:0) after 1m0s
[07:16:56.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:112:0)
[07:16:56.034] Timer tick, asking Buildbucket for the build status
[07:16:56.144] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:56.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:113:0) after 1m0s
[07:17:56.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:113:0)
[07:17:56.147] Timer tick, asking Buildbucket for the build status
[07:17:56.310] Build 8863047377437323056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:56.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9028477439483351216:114:0) after 1m0s
[07:18:54.279] Received PubSub notification, asking Buildbucket for the build status
[07:18:55.193] Build 8863047377437323056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:18:55.193] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605943121373878",
"created_by": "project:chromium",
"created_ts": "1605936373254153",
"id": "8863047377437323056",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fcf274222208752b27048083b92f04fbce289555\"}}",
"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\": \"fcf274222208752b27048083b92f04fbce289555\", \"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-5-8wkt\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 177894, \"got_angle_revision\": \"040de9214830ab194b073f35596f70c0e965df91\", \"got_dawn_revision\": \"41b3f9c1e4aaaa3d56d205d81e61deaebdd40d75\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"fcf274222208752b27048083b92f04fbce289555\", \"got_revision_cp\": \"refs/heads/master@{#829956}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"fa2966f12624e498083c1f4cacd3f82d14f4eacb\", \"got_v8_revision_cp\": \"refs/heads/8.9.30@{#1}\", \"got_webrtc_revision\": \"7d75f2ca782f38006bb65bc67533e582190454e8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32659}\", \"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\": \"fcf274222208752b27048083b92f04fbce289555\", \"swarm_hashes_refs/heads/master(at){#829956}_without_patch\": {\"absl_hardening_tests\": \"1c39485bd57c25da70a56a59244c44da52abd741\", \"accessibility_unittests\": \"d35e60833849b3f677c418a158e087dc37785775\", \"angle_unittests\": \"69a8a5bb426a02a3ac0edc40279b27a831ff70f4\", \"app_shell_unittests\": \"adf118af410b24642e99d8f8f6cfc5832397ee28\", \"aura_unittests\": \"9d3b6fc24e341d0da584aafe461b5fc324f1ca7d\", \"base_unittests\": \"513374103ac41832c4053f3509432020a308460c\", \"base_util_unittests\": \"7a2edb3455e16dff2e89def488de0590218e7b66\", \"blink_common_unittests\": \"a616a1a174c75344bc338912d8802f923ab1822e\", \"blink_fuzzer_unittests\": \"a58719c326afb534ad40dbeed3dd40734b60c752\", \"blink_heap_unittests\": \"60bff832a6974f4798e130e65a890a20eae05550\", \"blink_platform_unittests\": \"8f664978ecf48aaf35efded3d7e20826aaccf0e0\", \"blink_python_tests\": \"2fa9b75fbaf2107042859b8faca203d6aa8df022\", \"blink_unittests\": \"54690a0878d04553c1e3a3fb5053246870fa874d\", \"blink_web_tests\": \"0efa9ac9a88a7831d01243bc200de1310b5b1fa3\", \"boringssl_crypto_tests\": \"3ab24600e1ceac8ac72c0b197749977b50cf0216\", \"boringssl_ssl_tests\": \"f975f0b3839d32b1da7e5ed08e0685a40256235a\", \"browser_tests\": \"e410cc170052892d862dde1719c48f60b9919ea2\", \"capture_unittests\": \"7f032e2e5b299075039618193245fc41689ef082\", \"cast_unittests\": \"65901dac006528a08c4d3b3336b9d28ddac7c3dd\", \"cc_unittests\": \"4ef0c6754f3ad9cbfda72c48d076df08adbdc258\", \"chrome_app_unittests\": \"8183295c411f7da7e75a77fadcf1d4732f872d30\", \"chromedriver_unittests\": \"f519e5f2bed9981301f3fbb336c0f510ef604ebe\", \"components_browsertests\": \"a3620aec32aeb904dd5c850ea940b30991821dec\", \"components_unittests\": \"6596a96e96e6fff263011c0077a3ee5c229ca4f1\", \"compositor_unittests\": \"d7a547a3d33c1d5cd8f6e8d478902f39452e0c02\", \"content_browsertests\": \"023e365c5c653fa0cb96cb4ff3220024bc4ecc47\", \"content_shell_crash_test\": \"8183804262729ada03ad453ebdc4da9e99d454a4\", \"content_unittests\": \"225bb3df3ebfe987cf0159f6761ba1b41bf884d1\", \"crashpad_tests\": \"fd2a3605f4f79438dff879d9bd0dc7a9088285f9\", \"cronet_tests\": \"9e3c9c4125a6990068d875fd19f09c0a4faabf93\", \"cronet_unittests\": \"4e3db808ddee30c5e08628601b90055e7d57f5f5\", \"crypto_unittests\": \"4842bcb419f83000dfef597f0fdf8b51c95d597a\", \"dbus_unittests\": \"9128b07f2198b169dce24aab16ce9510905b0b05\", \"device_unittests\": \"62c926a6f3981d8f9e9b5d6821b023ca57fa6e8f\", \"display_unittests\": \"6e28b4389ccdb8bc68ef870d802df84b7a4c1b0b\", \"events_unittests\": \"41b7f95f7ffe2e59a929987dd6c84409868af992\", \"extension_docserver_python_unittests\": \"9f1c8dd26f7aa0b895ae4885ffe8e34ee5e7a397\", \"extensions_browsertests\": \"bfe0a7354d3528fd2a6865f8ae381b315d893f6e\", \"extensions_unittests\": \"046dbe3ed7a171d107f9f66b07d679053ccab402\", \"filesystem_service_unittests\": \"67fcd1e72c20745d55d1a2edb5f5faca191c8616\", \"flatbuffers_unittests\": \"dd6267ccaa5ef4f5de95507aa468128ed4c0a477\", \"gcm_unit_tests\": \"9651115f75e169728ebd0333ddc338e1cb7639a9\", \"gfx_unittests\": \"2fe6b8a811d967a54ece40e53cacef01b8e0e44f\", \"gin_unittests\": \"7b534b31093ec18c9f6549c658bcecbbce787348\", \"google_apis_unittests\": \"1bf7a3d364f646c11cef9580db17e4024db35883\", \"gpu_unittests\": \"3763dc6e3e199baf7eddc4da937e0fd17b5d1782\", \"grit_python_unittests\": \"b20d4d77fd059e1da02f2886f0fe402b433cc81c\", \"gwp_asan_unittests\": \"bde7660e3c0f25c0b06115d61c16db1be948dee3\", \"headless_browsertests\": \"1a01283206ebbe34390f9a5bd8334904fd9cbadd\", \"headless_unittests\": \"cbca1510476e998bf668dd600c0db2f44c21b681\", \"interactive_ui_tests\": \"25cbbdb521f780ab30cd9e2e7757b064e30d9c9e\", \"ipc_tests\": \"4b5659fd372b1d84daab9c81283b1cdb2c03594b\", \"jingle_unittests\": \"b1be1d84ce70b46d237c82e6cf5774b6c7eb5749\", \"latency_unittests\": \"f507d2c768595b7ef92b9abceedf8ea089642f1d\", \"libjingle_xmpp_unittests\": \"68339f882464c928a849de187d9533abd2da473f\", \"liburlpattern_unittests\": \"00589fb61def8c7d0c8001cff95b5fa7a3f8a80f\", \"media_blink_unittests\": \"1c027a1aa070790ecd78fc191c3854a4fcc9771b\", \"media_unittests\": \"edb101c1bffabff355c66042726058f2439060d5\", \"message_center_unittests\": \"2eb37dbc28d301a90478aebae8ea2fc1355471c7\", \"metrics_python_tests\": \"9c0832b53ec6fbd80874a2022b04ddaf3eedfc6c\", \"midi_unittests\": \"2f7b4d6f5abb6add94d6a98302c4606e1140391d\", \"mojo_core_unittests\": \"e8bc1feab307af2894cdc7acfbddd2bf850bc948\", \"mojo_unittests\": \"78a57c63f61801d188a5db59d7e557b33d454b82\", \"nacl_helper_nonsfi_unittests\": \"231b8df9d99141bbfa432b28b1e4509ddc281082\", \"nacl_loader_unittests\": \"b9256de405c53bdd980d2e8f77b09d032ae97b5e\", \"native_theme_unittests\": \"171facdec2c0cf724b36549600dffa5425a4bb2a\", \"net_unittests\": \"e7d58177d5b2e7ccde1a0c00df25cdd39c09c64c\", \"openscreen_unittests\": \"553a194e97e7b695619e237d2287edbab8fe0803\", \"pdf_unittests\": \"c4b47e33ed034693cd4504f5ad855b49500b585c\", \"perfetto_unittests\": \"3c784e26a23d73e57fb521726b4edfffcacdba29\", \"ppapi_unittests\": \"e8f604895fc7c656899f9e0ba81644b6f48c17d1\", \"printing_unittests\": \"07bbd83d388bcdac60a0522c1b6eff579bab5a2f\", \"remoting_unittests\": \"6a213650d631984254704290e38c011f552bfd85\", \"sandbox_linux_unittests\": \"54917cee3de7be659f8d61fab6c249d5d9c5f30d\", \"service_manager_unittests\": \"e991979526f7eb3ec685991598736fa6a92d7f7b\", \"services_unittests\": \"57c8a8658ea1596112d7f1e129c11a14f26d167d\", \"shell_dialogs_unittests\": \"b88da64cd83f5cc884f4d63db34c1e050cc0822c\", \"skia_unittests\": \"b50fefb943706554577a6c809e9bfa958755984c\", \"snapshot_unittests\": \"54b4f8f806b41cde8c0a5f0be54e37e4998486ae\", \"sql_unittests\": \"45e01012f79c73cd69b20de9b6829fd4f28bd13d\", \"storage_unittests\": \"648b0476a545ef5b650847341f3cf49d8ab36d5a\", \"sync_integration_tests\": \"2da1f2aeab68aa0ef72580dfb1f724a06800daa1\", \"telemetry_gpu_unittests\": \"4ab9f21944859f8709672538e08530986d4b9c66\", \"telemetry_perf_unittests\": \"f31279a43b9966b39723bcf9fe6e86e18a79674a\", \"telemetry_unittests\": \"f528f2c86c840c814e38f79d2ec5abc2bf5349b3\", \"traffic_annotation_auditor_unittests\": \"9f5e3ecb45f0ff0e640d353f2b5ce8a817cd1f3b\", \"ui_base_unittests\": \"975b2afafec918ebd9242da285843f7407e23273\", \"ui_touch_selection_unittests\": \"facf1c50169c310147bf74d83686fc9224bedb3b\", \"unit_tests\": \"c45b7466380ccd5c8afd7ba94d5d9798bca18375\", \"url_unittests\": \"697387689ca09030cc41093623805894b3daec81\", \"views_perftests\": \"0af8cd10c0315a288b67530021fe52d4a080a35a\", \"views_unittests\": \"2ed4db9e2c2234c5d70f903be3349d4a0745b3f3\", \"viz_unittests\": \"22e7695da4decae93dcea86bad461544f3434ccd\", \"vr_common_unittests\": \"a518112d9dc8de47cbd5f5925b540d42ce1b3cd2\", \"vr_pixeltests\": \"2577d6f4ac6a742ee02c60301c0c0eec6e750cb7\", \"webdriver_wpt_tests\": \"75647c5f157ac25614a24ac0558e259b98d1698b\", \"weblayer_browsertests\": \"4e6dcc0823b76fbb27bbace1819dbee54febb591\", \"weblayer_unittests\": \"2b54dfc537e74c61bbfff953759d3f95fba1ff64\", \"wm_unittests\": \"6f955daa15207bbdef44899311b15363b49ae338\", \"wtf_unittests\": \"87e91b88d5add83de10e06d6a7f019958445b0d9\", \"x11_unittests\": \"44285d6d88626e7a52822993311c01e4164464fb\", \"xr_browser_tests\": \"795e09a6320c80e53cccfd814b37a2c790d1c124\", \"zlib_unittests\": \"fbdb749f4dd48f5e8830b07df03c3318a881f813\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_03bc629269a55e89b115ec4e4b472997df6f6f4caff479f65f24a3c64aef44c9_v2\", \"builder_2ee17c8d1dfa5b32c278d379d52d827fbc111b1f65e921f402aba0ecd2b3be7d_v2\", \"builder_7f66e4b9df9a4fde5765030780da31d05e9d75a20736158540de9973fc71d08c_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_c5a4a77f7d90ed750e2e3c196231723a8f9435afca7311d8fa2536a9dada12e3_v2\", \"builder_c66788656bdf2acb3179f9105d1e02eee4c98139f3cebcabd373c722039a64ae_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-5-8wkt\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605939266837861",
"status": "COMPLETED",
"status_changed_ts": "1605943123248611",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/177894",
"builder:Linux Builder (dbg)",
"buildset:commit/git/fcf274222208752b27048083b92f04fbce289555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcf274222208752b27048083b92f04fbce289555",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028477439483351216",
"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/8863047377437323056/+/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:500215f6a59f1710",
"user_agent:luci-scheduler"
],
"updated_ts": "1605943123277613",
"url": "https://ci.chromium.org/b/8863047377437323056",
"utcnow_ts": "1605943135175921"
}
}
[07:18:55.193] Invocation finished in 1h52m43.274430041s with status SUCCEEDED