[20:49:26.358] New invocation is queued and will start shortly
[20:49:28.467] Starting the invocation (attempt 1)
[20:49:28.506] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:49:28.507] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:49:28.507] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052971132115743632",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"64e434bf6ae36aae79120d595185c92fd446a9fd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052971132115743632",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/64e434bf6ae36aae79120d595185c92fd446a9fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64e434bf6ae36aae79120d595185c92fd446a9fd",
"gitiles_ref:refs/heads/master"
]
}
[20:49:29.426] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582577368604749",
"id": "8887541069097245968",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64e434bf6ae36aae79120d595185c92fd446a9fd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582577369143343",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168979",
"builder:Linux Builder (dbg)",
"buildset:commit/git/64e434bf6ae36aae79120d595185c92fd446a9fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64e434bf6ae36aae79120d595185c92fd446a9fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052971132115743632",
"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/8887541069097245968/+/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": "1582577369143501",
"url": "https://ci.chromium.org/b/8887541069097245968",
"utcnow_ts": "1582577369415010"
}
}
[20:49:29.426] Task URL: https://ci.chromium.org/b/8887541069097245968
[20:49:29.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:2:0) after 1m0s
[20:50:29.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:2:0)
[20:50:29.500] Timer tick, asking Buildbucket for the build status
[20:50:29.800] Build 8887541069097245968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:29.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:3:0) after 1m0s
[20:51:29.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:3:0)
[20:51:29.859] Timer tick, asking Buildbucket for the build status
[20:51:30.741] Build 8887541069097245968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:30.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:4:0) after 1m0s
[20:52:30.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:4:0)
[20:52:30.782] Timer tick, asking Buildbucket for the build status
[20:52:30.985] Build 8887541069097245968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:30.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:5:0) after 1m0s
[20:53:31.101] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:5:0)
[20:53:31.101] Timer tick, asking Buildbucket for the build status
[20:53:31.756] Build 8887541069097245968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:31.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:6:0) after 1m0s
[20:54:17.648] Received PubSub notification, asking Buildbucket for the build status
[20:54:17.708] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:31.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:6:0)
[20:54:31.797] Timer tick, asking Buildbucket for the build status
[20:54:31.935] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:31.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:8:0) after 1m0s
[20:55:32.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:8:0)
[20:55:32.147] Timer tick, asking Buildbucket for the build status
[20:55:32.702] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:32.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:9:0) after 1m0s
[20:56:32.743] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:9:0)
[20:56:32.743] Timer tick, asking Buildbucket for the build status
[20:56:33.564] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:33.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:10:0) after 1m0s
[20:57:33.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:10:0)
[20:57:33.635] Timer tick, asking Buildbucket for the build status
[20:57:33.820] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:33.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:11:0) after 1m0s
[20:58:33.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:11:0)
[20:58:33.895] Timer tick, asking Buildbucket for the build status
[20:58:34.139] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:34.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:12:0) after 1m0s
[20:59:34.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:12:0)
[20:59:34.165] Timer tick, asking Buildbucket for the build status
[20:59:34.298] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:34.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:13:0) after 1m0s
[21:00:34.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:13:0)
[21:00:34.482] Timer tick, asking Buildbucket for the build status
[21:00:34.657] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:34.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:14:0) after 1m0s
[21:01:34.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:14:0)
[21:01:34.663] Timer tick, asking Buildbucket for the build status
[21:01:35.715] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:35.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:15:0) after 1m0s
[21:02:35.739] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:15:0)
[21:02:35.739] Timer tick, asking Buildbucket for the build status
[21:02:36.194] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:36.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:16:0) after 1m0s
[21:03:36.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:16:0)
[21:03:36.300] Timer tick, asking Buildbucket for the build status
[21:03:36.486] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:36.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:17:0) after 1m0s
[21:04:36.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:17:0)
[21:04:36.498] Timer tick, asking Buildbucket for the build status
[21:04:36.646] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:36.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:18:0) after 1m0s
[21:05:36.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:18:0)
[21:05:36.668] Timer tick, asking Buildbucket for the build status
[21:05:36.907] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:36.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:19:0) after 1m0s
[21:06:36.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:19:0)
[21:06:36.995] Timer tick, asking Buildbucket for the build status
[21:06:37.716] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:37.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:20:0) after 1m0s
[21:07:37.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:20:0)
[21:07:37.770] Timer tick, asking Buildbucket for the build status
[21:07:37.978] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:37.978] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:21:0) after 1m0s
[21:08:38.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:21:0)
[21:08:38.005] Timer tick, asking Buildbucket for the build status
[21:08:38.213] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:38.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:22:0) after 1m0s
[21:09:38.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:22:0)
[21:09:38.279] Timer tick, asking Buildbucket for the build status
[21:09:38.509] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:38.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:23:0) after 1m0s
[21:10:38.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:23:0)
[21:10:38.580] Timer tick, asking Buildbucket for the build status
[21:10:38.789] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:38.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:24:0) after 1m0s
[21:11:38.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:24:0)
[21:11:38.810] Timer tick, asking Buildbucket for the build status
[21:11:39.008] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:39.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:25:0) after 1m0s
[21:12:39.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:25:0)
[21:12:39.096] Timer tick, asking Buildbucket for the build status
[21:12:39.470] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:39.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:26:0) after 1m0s
[21:13:39.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:26:0)
[21:13:39.494] Timer tick, asking Buildbucket for the build status
[21:13:39.682] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:39.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:27:0) after 1m0s
[21:14:39.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:27:0)
[21:14:39.696] Timer tick, asking Buildbucket for the build status
[21:14:39.825] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:39.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:28:0) after 1m0s
[21:15:39.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:28:0)
[21:15:39.852] Timer tick, asking Buildbucket for the build status
[21:15:39.955] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:39.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:29:0) after 1m0s
[21:16:39.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:29:0)
[21:16:39.983] Timer tick, asking Buildbucket for the build status
[21:16:40.271] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:40.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:30:0) after 1m0s
[21:17:40.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:30:0)
[21:17:40.288] Timer tick, asking Buildbucket for the build status
[21:17:40.465] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:40.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:31:0) after 1m0s
[21:18:40.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:31:0)
[21:18:40.445] Timer tick, asking Buildbucket for the build status
[21:18:40.568] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:40.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:32:0) after 1m0s
[21:19:40.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:32:0)
[21:19:40.620] Timer tick, asking Buildbucket for the build status
[21:19:40.766] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:40.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:33:0) after 1m0s
[21:20:40.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:33:0)
[21:20:40.786] Timer tick, asking Buildbucket for the build status
[21:20:42.350] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:42.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:34:0) after 1m0s
[21:21:42.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:34:0)
[21:21:42.379] Timer tick, asking Buildbucket for the build status
[21:21:42.539] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:42.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:35:0) after 1m0s
[21:22:42.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:35:0)
[21:22:42.556] Timer tick, asking Buildbucket for the build status
[21:22:42.681] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:42.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:36:0) after 1m0s
[21:23:42.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:36:0)
[21:23:42.702] Timer tick, asking Buildbucket for the build status
[21:23:42.935] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:42.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:37:0) after 1m0s
[21:24:42.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:37:0)
[21:24:42.975] Timer tick, asking Buildbucket for the build status
[21:24:43.118] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:43.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:38:0) after 1m0s
[21:25:43.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:38:0)
[21:25:43.169] Timer tick, asking Buildbucket for the build status
[21:25:43.362] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:43.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:39:0) after 1m0s
[21:26:43.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:39:0)
[21:26:43.415] Timer tick, asking Buildbucket for the build status
[21:26:46.438] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:46.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:40:0) after 1m0s
[21:27:46.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:40:0)
[21:27:46.452] Timer tick, asking Buildbucket for the build status
[21:27:46.621] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:46.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:41:0) after 1m0s
[21:28:46.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:41:0)
[21:28:46.726] Timer tick, asking Buildbucket for the build status
[21:28:46.947] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:46.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:42:0) after 1m0s
[21:29:47.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:42:0)
[21:29:47.011] Timer tick, asking Buildbucket for the build status
[21:29:47.207] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:47.207] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:43:0) after 1m0s
[21:30:47.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:43:0)
[21:30:47.261] Timer tick, asking Buildbucket for the build status
[21:30:47.553] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:47.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:44:0) after 1m0s
[21:31:47.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:44:0)
[21:31:47.662] Timer tick, asking Buildbucket for the build status
[21:31:47.796] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:47.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:45:0) after 1m0s
[21:32:47.897] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:45:0)
[21:32:47.897] Timer tick, asking Buildbucket for the build status
[21:32:48.180] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:48.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:46:0) after 1m0s
[21:33:48.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:46:0)
[21:33:48.346] Timer tick, asking Buildbucket for the build status
[21:33:48.510] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:48.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:47:0) after 1m0s
[21:34:48.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:47:0)
[21:34:48.663] Timer tick, asking Buildbucket for the build status
[21:34:48.793] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:48.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:48:0) after 1m0s
[21:35:48.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:48:0)
[21:35:48.866] Timer tick, asking Buildbucket for the build status
[21:35:49.016] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:49.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:49:0) after 1m0s
[21:36:49.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:49:0)
[21:36:49.084] Timer tick, asking Buildbucket for the build status
[21:36:49.560] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:49.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:50:0) after 1m0s
[21:37:49.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:50:0)
[21:37:49.788] Timer tick, asking Buildbucket for the build status
[21:37:49.883] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:49.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:51:0) after 1m0s
[21:38:50.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:51:0)
[21:38:50.067] Timer tick, asking Buildbucket for the build status
[21:38:50.320] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:50.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:52:0) after 1m0s
[21:39:50.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:52:0)
[21:39:50.422] Timer tick, asking Buildbucket for the build status
[21:39:50.549] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:50.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:53:0) after 1m0s
[21:40:50.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:53:0)
[21:40:50.651] Timer tick, asking Buildbucket for the build status
[21:40:51.057] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:51.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:54:0) after 1m0s
[21:41:51.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:54:0)
[21:41:51.096] Timer tick, asking Buildbucket for the build status
[21:41:51.289] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:51.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:55:0) after 1m0s
[21:42:51.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:55:0)
[21:42:51.381] Timer tick, asking Buildbucket for the build status
[21:42:51.549] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:51.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:56:0) after 1m0s
[21:43:51.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:56:0)
[21:43:51.694] Timer tick, asking Buildbucket for the build status
[21:43:51.853] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:51.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:57:0) after 1m0s
[21:44:51.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:57:0)
[21:44:51.845] Timer tick, asking Buildbucket for the build status
[21:44:51.963] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:51.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:58:0) after 1m0s
[21:45:51.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:58:0)
[21:45:51.993] Timer tick, asking Buildbucket for the build status
[21:45:52.224] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:52.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:59:0) after 1m0s
[21:46:52.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:59:0)
[21:46:52.338] Timer tick, asking Buildbucket for the build status
[21:46:52.507] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:52.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:60:0) after 1m0s
[21:47:52.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:60:0)
[21:47:52.563] Timer tick, asking Buildbucket for the build status
[21:47:52.667] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:52.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:61:0) after 1m0s
[21:48:52.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:61:0)
[21:48:52.759] Timer tick, asking Buildbucket for the build status
[21:48:52.965] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:52.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:62:0) after 1m0s
[21:49:53.081] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:62:0)
[21:49:53.081] Timer tick, asking Buildbucket for the build status
[21:49:53.201] Build 8887541069097245968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:53.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052971132115743632:63:0) after 1m0s
[21:50:18.534] Received PubSub notification, asking Buildbucket for the build status
[21:50:18.756] Build 8887541069097245968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:50:18.756] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582581017580912",
"created_by": "project:chromium",
"created_ts": "1582577368604749",
"id": "8887541069097245968",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64e434bf6ae36aae79120d595185c92fd446a9fd\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-23-goqw\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168979, \"got_angle_revision\": \"c7f63a01d34473a95514661841a20d19668356a8\", \"got_dawn_revision\": \"33466973bc4c52effb520832d538b48c38695ca5\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"64e434bf6ae36aae79120d595185c92fd446a9fd\", \"got_revision_cp\": \"refs/heads/master@{#744051}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"214ec337a0b2745e6c41fbb3d562fa517122812f\", \"got_v8_revision\": \"f6d87d0ff881338e5998a4c7177490292619d55b\", \"got_v8_revision_cp\": \"refs/heads/8.2.159@{#1}\", \"got_webrtc_revision\": \"1dea1ea412021819bddbc9400dcca7d9dd2eba74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30581}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64e434bf6ae36aae79120d595185c92fd446a9fd\", \"swarm_hashes_refs/heads/master(at){#744051}_without_patch\": {\"accessibility_unittests\": \"53c9412b17ffd2109ece7578c4b5eea9ca23c80e\", \"angle_unittests\": \"45b50d88f9943203a1ddf1204da60714a929288c\", \"app_shell_unittests\": \"7c0ecfe6c47e899f0625ece6030e7510ea0a4299\", \"aura_unittests\": \"ba2d5710badac755deacb085409d2f8eaccb95fb\", \"base_unittests\": \"19b4b28feb0fcdbfa0219fa1cf1825dd8311660e\", \"base_util_unittests\": \"d895d1151507c3ca3f59beb775e386b2fc179def\", \"blink_common_unittests\": \"4351890f9ee080e02ebe60d9068d2a9f22e4358a\", \"blink_fuzzer_unittests\": \"da83d25fb9dd6a59d71b601a8588fa6db185666e\", \"blink_heap_unittests\": \"0f9c6e77ae3a9fbaa8a7a533862fd3bbbc829989\", \"blink_platform_unittests\": \"777797dbc45b7f7b8cf5a58b20125c7c88413cf4\", \"blink_python_tests\": \"859aac7d0626a068237a6524440018c53e7babe9\", \"blink_unittests\": \"09dda45b164ad47e18690ea3ff4fa4418fb490b3\", \"blink_web_tests\": \"9b68e498b7d940bbd830d0906a91236f16594214\", \"boringssl_crypto_tests\": \"97a3dc1c99b47c2115076ba7f56fdd51ffd8670f\", \"boringssl_ssl_tests\": \"3599e53644d40bf11c32ef4dffd734a12566622c\", \"browser_tests\": \"ca14d65e521566bb777a91df9caaed81996cf192\", \"cacheinvalidation_unittests\": \"82a239912102b4c88536d7960e969be6ef0de62d\", \"capture_unittests\": \"08b3348714ba30a6d758b2b680aafcba31ba94d3\", \"cast_unittests\": \"94731fb2838e8c0f6a727f3dfa0151390b479aa7\", \"cc_unittests\": \"c6a096690ed3440603027cd07f5528d58d1aeabf\", \"chrome_app_unittests\": \"238c620fc64eb7b22ee12db637c7c19ab8f4ef1c\", \"chromedriver_unittests\": \"e1697c0af37812eb067d2fde8441dfd5163270c9\", \"components_browsertests\": \"6e84fbd0a071a621b86eedb4cb2628804fb1ae4a\", \"components_unittests\": \"a4d7353524e0356cf3448e6965ef816faf6cc774\", \"compositor_unittests\": \"5ace94891f64033a02a438853e646b8e01319791\", \"content_browsertests\": \"bce8cb92e9576c13c72ce618dc28f197a8077c9a\", \"content_shell_crash_test\": \"bc1321bf5604881db09caf18df36954f398241df\", \"content_unittests\": \"2da7970b07586a0cc9d9025a553d1645a2fa8357\", \"crashpad_tests\": \"513371200d1fd1f76a974b8677077fc1a71ee84a\", \"cronet_tests\": \"72c44ab303f0ce620967c2f39f48d87491623978\", \"cronet_unittests\": \"2b35fa24a053d6d204531672f0c5f21507f281f4\", \"crypto_unittests\": \"fe193777f94212058d79754e61ece9d3455888e0\", \"dbus_unittests\": \"192e8dd969ad3641b742b993ee43af0430713284\", \"device_unittests\": \"7b388519629d1f08c24ea0129864a5782f5da580\", \"display_unittests\": \"85038ad77a95ba799b2b80e51f141b145458aab8\", \"events_unittests\": \"76f57a4c9c59b507450f12b2b02b524462017e9b\", \"extensions_browsertests\": \"83f496b2460da24faa435c7dc77ae4faac6b4229\", \"extensions_unittests\": \"85adea673cc62bfd2b5b1a12f3d20fc784a9aff0\", \"filesystem_service_unittests\": \"bed5870fc1aa60ef16230d398c069c0d1040586c\", \"flatbuffers_unittests\": \"fddfee5d9c5c5357203fa42dff8f3c357607bc22\", \"gcm_unit_tests\": \"b72ef382cf62b0610793dd8fc3c072f8e680c480\", \"gfx_unittests\": \"b653b6b6fafec76d895f791785f0f564031c9da1\", \"gin_unittests\": \"d52b7b7aaac9211e0a855880454f2ab52e10699b\", \"google_apis_unittests\": \"0280e7996b44144eb1bc4437032a715ffdb4742f\", \"gpu_unittests\": \"dffb491cb3618179c77908d10c1c2238d40c27e9\", \"grit_python_unittests\": \"8d3af1ca6ce7611b3ef6d2516a78cd61f915c28b\", \"gwp_asan_unittests\": \"c2414da6c4aa47110ae5cbaf465b75ce4b64bec1\", \"headless_browsertests\": \"3d170ba8474a0705e43be078181ea62eed38ef7a\", \"headless_unittests\": \"b9f72e9a6e87051047f0b045d1c58821bc3925d7\", \"interactive_ui_tests\": \"6ca63ea3cffb84b68418d6d7c4bd36a3a87c4b25\", \"ipc_tests\": \"4a5e96b9b1eeae57de7172e2360d04f7f6f0aecc\", \"jingle_unittests\": \"252e91cdeba4800eaebdeaf749260e1bc3a9e7e0\", \"latency_unittests\": \"fccdceeff4aa37affca2cbefd2e5be37b6c56513\", \"libjingle_xmpp_unittests\": \"61ac6d9055af99695be197e179212d04b6d9a027\", \"media_blink_unittests\": \"755dd0ecad469b370632af8f6a5e808ad1930cd7\", \"media_service_unittests\": \"867dbc53b3b35152e07240b02965ecc5437c3f2b\", \"media_unittests\": \"c2ae586b85f7677920da23dc70ef593b56f098f6\", \"message_center_unittests\": \"63031eaea03719fda531e3504cec61a0503d64c6\", \"metrics_python_tests\": \"1c147adf21e8fc42b4dcefa41f42255896d74356\", \"midi_unittests\": \"e365025be7e7f152bf1b89b7f88dc1d0e8410b9a\", \"mojo_core_unittests\": \"f0e733e6dc289fe1405042229a47ffd627f39f3f\", \"mojo_unittests\": \"1bc6e2d9f6ae7368259ae69bf03df1043ed9a67c\", \"nacl_helper_nonsfi_unittests\": \"d7398ffa7b105f8f2a3c08e277fc16e8d3481819\", \"nacl_loader_unittests\": \"d7f769f306ffb67cdfe9a5b5ca4b29269d1d165a\", \"native_theme_unittests\": \"c98f783efffa384e0958c6ca4e1f4825285969ca\", \"net_unittests\": \"26a4eef7cac1b2b225ebc8ada7dc766bffa937f4\", \"pdf_unittests\": \"f687566279333c6da8200340e61438eee638224e\", \"perfetto_unittests\": \"d89102aea53468593dcbfaa522e72c2b4ee15f59\", \"ppapi_unittests\": \"c11e7ff5e8d55452a413a24f30913559b7b6f2c6\", \"printing_unittests\": \"1acd1a46e297beb7541e26ef40e445f7de9075a5\", \"remoting_unittests\": \"b87eae9d274854e32b1709a091c34913543bf018\", \"sandbox_linux_unittests\": \"24e3fd127b75d5660502da043ed446016c65fd51\", \"service_manager_unittests\": \"0efa7c3c77435da79ae01e2bf69392d32d1d2287\", \"services_unittests\": \"f47eaba9b405ec277fcda35eb98d6229c6ba1e58\", \"shell_dialogs_unittests\": \"b739215864a4d7bff5a9bd3e133e4e5e296c5b56\", \"skia_unittests\": \"d49000fa59bd7a9c44a82332c4067b81eb479c9f\", \"snapshot_unittests\": \"4f2caa8837ba01d491d3f46929d1df3ac9556eab\", \"sql_unittests\": \"fa2aac2fc090935168c79000a737f4d6b6d7a4d7\", \"storage_unittests\": \"b4de36a1a38bef9e7c9c535893b5b7945548216f\", \"sync_integration_tests\": \"69741279f23808c2da3712a0fd05e41aa77ee1b1\", \"telemetry_gpu_unittests\": \"a94b0213ebc4c6836ef82cbafdf4e50aac94cbf0\", \"telemetry_perf_unittests\": \"253980ba0d5351d62d5bcfb4ad43e76cf3be29ff\", \"telemetry_unittests\": \"d3c8dc3feca76b01cab59df80f1e4c256ef06cfc\", \"traffic_annotation_auditor_unittests\": \"55f7d6e95e3c2722ba466453ccf443a5423ec6e8\", \"ui_base_unittests\": \"a1cc38496328c7e67a47770c799031918fb61469\", \"ui_touch_selection_unittests\": \"72d628b08c0aaf55a5a7c8104f5751a1814f3073\", \"unit_tests\": \"08a74cf3286e18c687b05cbd5226a8be78c16e9a\", \"url_unittests\": \"3f1cd30a3a69bd1dd6aac9b54ea03b2f8ff29130\", \"views_perftests\": \"5060273712a22cf9e0c84bf230bd45c84f63d8d7\", \"views_unittests\": \"42bd0d048a0984c0e7af2111cbd2e3cd13577c5a\", \"viz_unittests\": \"0065128129ff382f29ee0f0a3a1d55e1efc85702\", \"vr_common_unittests\": \"92498787aa512d274cede2644c847c40a30ab754\", \"vr_pixeltests\": \"b772a70f9689841c797ccec346d0beafcc81fcba\", \"webdriver_wpt_tests\": \"53d02ed1c9de140bcead700977b1ecdd08834b1b\", \"weblayer_browsertests\": \"1ccff32d8b9cf65550337e1ee5e9c42abc204a42\", \"wm_unittests\": \"20dd56a6a2e18afa8226aa4be22bd2a2e2ec7028\", \"wtf_unittests\": \"eda526ee88107a8847a7bbf4dfd55404838159f7\", \"xr_browser_tests\": \"1c7f34d043aefe5f255b0dcac9756d3a2bf8a0e0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_756d7885c4e2fdf98a0ecf3a0240e3f6188de5be53188a09baa9eae15dedd66f_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_a050691f36f3646c8cc368108f8edcf844a0d1683dc4a3731c64b1c7527ccb94_v2\", \"builder_a3249012ab2e105b4f1a10f0dc352b422f692fd701d889292e5c6170d198db4b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-23-goqw\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582577655867598",
"status": "COMPLETED",
"status_changed_ts": "1582581017934291",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168979",
"builder:Linux Builder (dbg)",
"buildset:commit/git/64e434bf6ae36aae79120d595185c92fd446a9fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64e434bf6ae36aae79120d595185c92fd446a9fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052971132115743632",
"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/8887541069097245968/+/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:4a91c7c9e4ba4e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582581017939852",
"url": "https://ci.chromium.org/b/8887541069097245968",
"utcnow_ts": "1582581018728417"
}
}
[21:50:18.756] Invocation finished in 1h0m52.413882317s with status SUCCEEDED