[00:36:09.427] New invocation is queued and will start shortly
[00:36:10.610] Starting the invocation (attempt 1)
[00:36:10.682] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:36:10.682] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:36:10.682] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059661043806199488",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fc8d82eea75a24d6978cdc97b43109d094415fde\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059661043806199488",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/fc8d82eea75a24d6978cdc97b43109d094415fde",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc8d82eea75a24d6978cdc97b43109d094415fde",
"gitiles_ref:refs/heads/master"
]
}
[00:36:11.677] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576197370864343",
"id": "8894230981607223328",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fc8d82eea75a24d6978cdc97b43109d094415fde\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576197371386231",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166020",
"builder:Linux Builder (dbg)",
"buildset:commit/git/fc8d82eea75a24d6978cdc97b43109d094415fde",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc8d82eea75a24d6978cdc97b43109d094415fde",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059661043806199488",
"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/8894230981607223328/+/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": "1576197371386394",
"url": "https://ci.chromium.org/b/8894230981607223328",
"utcnow_ts": "1576197371667009"
}
}
[00:36:11.677] Task URL: https://ci.chromium.org/b/8894230981607223328
[00:36:11.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:2:0) after 1m0s
[00:37:11.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:2:0)
[00:37:11.697] Timer tick, asking Buildbucket for the build status
[00:37:11.878] Build 8894230981607223328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:11.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:3:0) after 1m0s
[00:38:11.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:3:0)
[00:38:11.989] Timer tick, asking Buildbucket for the build status
[00:38:13.330] Build 8894230981607223328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:13.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:4:0) after 1m0s
[00:39:13.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:4:0)
[00:39:13.358] Timer tick, asking Buildbucket for the build status
[00:39:14.227] Build 8894230981607223328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:14.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:5:0) after 1m0s
[00:40:14.249] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:5:0)
[00:40:14.249] Timer tick, asking Buildbucket for the build status
[00:40:14.948] Build 8894230981607223328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:14.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:6:0) after 1m0s
[00:41:03.829] Received PubSub notification, asking Buildbucket for the build status
[00:41:04.205] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:14.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:6:0)
[00:41:14.979] Timer tick, asking Buildbucket for the build status
[00:41:15.572] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:15.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:8:0) after 1m0s
[00:42:15.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:8:0)
[00:42:15.700] Timer tick, asking Buildbucket for the build status
[00:42:15.923] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:15.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:9:0) after 1m0s
[00:43:16.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:9:0)
[00:43:16.017] Timer tick, asking Buildbucket for the build status
[00:43:16.290] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:16.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:10:0) after 1m0s
[00:44:16.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:10:0)
[00:44:16.433] Timer tick, asking Buildbucket for the build status
[00:44:16.636] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:16.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:11:0) after 1m0s
[00:45:16.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:11:0)
[00:45:16.701] Timer tick, asking Buildbucket for the build status
[00:45:16.920] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:16.920] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:12:0) after 1m0s
[00:46:16.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:12:0)
[00:46:16.945] Timer tick, asking Buildbucket for the build status
[00:46:17.276] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:17.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:13:0) after 1m0s
[00:47:17.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:13:0)
[00:47:17.317] Timer tick, asking Buildbucket for the build status
[00:47:17.723] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:17.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:14:0) after 1m0s
[00:48:17.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:14:0)
[00:48:17.790] Timer tick, asking Buildbucket for the build status
[00:48:18.186] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:18.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:15:0) after 1m0s
[00:49:18.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:15:0)
[00:49:18.266] Timer tick, asking Buildbucket for the build status
[00:49:19.318] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:19.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:16:0) after 1m0s
[00:50:19.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:16:0)
[00:50:19.415] Timer tick, asking Buildbucket for the build status
[00:50:19.693] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:19.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:17:0) after 1m0s
[00:51:19.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:17:0)
[00:51:19.765] Timer tick, asking Buildbucket for the build status
[00:51:20.792] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:20.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:18:0) after 1m0s
[00:52:20.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:18:0)
[00:52:20.836] Timer tick, asking Buildbucket for the build status
[00:52:21.151] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:21.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:19:0) after 1m0s
[00:53:21.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:19:0)
[00:53:21.211] Timer tick, asking Buildbucket for the build status
[00:53:22.499] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:22.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:20:0) after 1m0s
[00:54:22.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:20:0)
[00:54:22.540] Timer tick, asking Buildbucket for the build status
[00:54:22.902] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:22.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:21:0) after 1m0s
[00:55:22.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:21:0)
[00:55:22.946] Timer tick, asking Buildbucket for the build status
[00:55:23.463] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:23.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:22:0) after 1m0s
[00:56:23.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:22:0)
[00:56:23.490] Timer tick, asking Buildbucket for the build status
[00:56:24.694] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:24.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:23:0) after 1m0s
[00:57:24.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:23:0)
[00:57:24.790] Timer tick, asking Buildbucket for the build status
[00:57:25.562] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:25.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:24:0) after 1m0s
[00:58:47.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:24:0)
[00:58:47.626] Timer tick, asking Buildbucket for the build status
[00:58:48.009] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:48.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:25:0) after 1m0s
[00:59:49.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:25:0)
[00:59:49.084] Timer tick, asking Buildbucket for the build status
[00:59:49.536] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:49.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:26:0) after 1m0s
[01:00:50.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:26:0)
[01:00:50.284] Timer tick, asking Buildbucket for the build status
[01:00:50.485] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:50.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:27:0) after 1m0s
[01:01:51.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:27:0)
[01:01:51.107] Timer tick, asking Buildbucket for the build status
[01:01:51.430] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:51.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:28:0) after 1m0s
[01:02:52.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:28:0)
[01:02:52.188] Timer tick, asking Buildbucket for the build status
[01:02:53.765] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:53.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:29:0) after 1m0s
[01:03:53.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:29:0)
[01:03:53.800] Timer tick, asking Buildbucket for the build status
[01:03:54.630] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:54.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:30:0) after 1m0s
[01:04:54.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:30:0)
[01:04:54.968] Timer tick, asking Buildbucket for the build status
[01:04:55.246] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:55.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:31:0) after 1m0s
[01:05:55.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:31:0)
[01:05:55.278] Timer tick, asking Buildbucket for the build status
[01:05:55.801] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:55.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:32:0) after 1m0s
[01:06:56.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:32:0)
[01:06:56.098] Timer tick, asking Buildbucket for the build status
[01:06:56.347] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:56.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:33:0) after 1m0s
[01:07:56.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:33:0)
[01:07:56.567] Timer tick, asking Buildbucket for the build status
[01:07:56.733] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:56.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:34:0) after 1m0s
[01:08:56.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:34:0)
[01:08:56.773] Timer tick, asking Buildbucket for the build status
[01:08:57.598] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:35:0) after 1m0s
[01:09:57.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:35:0)
[01:09:57.813] Timer tick, asking Buildbucket for the build status
[01:09:58.106] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:58.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:36:0) after 1m0s
[01:10:59.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:36:0)
[01:10:59.073] Timer tick, asking Buildbucket for the build status
[01:11:02.812] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:02.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:37:0) after 1m0s
[01:12:02.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:37:0)
[01:12:02.916] Timer tick, asking Buildbucket for the build status
[01:12:03.923] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:03.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:38:0) after 1m0s
[01:13:34.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:38:0)
[01:13:34.614] Timer tick, asking Buildbucket for the build status
[01:13:35.147] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:35.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:39:0) after 1m0s
[01:14:35.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:39:0)
[01:14:35.204] Timer tick, asking Buildbucket for the build status
[01:14:35.927] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:35.927] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:40:0) after 1m0s
[01:15:35.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:40:0)
[01:15:35.941] Timer tick, asking Buildbucket for the build status
[01:15:36.870] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:36.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:41:0) after 1m0s
[01:16:36.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:41:0)
[01:16:36.927] Timer tick, asking Buildbucket for the build status
[01:16:37.544] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:37.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:42:0) after 1m0s
[01:17:37.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:42:0)
[01:17:37.647] Timer tick, asking Buildbucket for the build status
[01:17:37.947] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:37.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:43:0) after 1m0s
[01:18:38.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:43:0)
[01:18:38.004] Timer tick, asking Buildbucket for the build status
[01:18:38.903] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:38.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:44:0) after 1m0s
[01:19:38.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:44:0)
[01:19:38.910] Timer tick, asking Buildbucket for the build status
[01:19:39.925] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:39.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:45:0) after 1m0s
[01:20:39.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:45:0)
[01:20:39.949] Timer tick, asking Buildbucket for the build status
[01:20:40.454] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:40.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:46:0) after 1m0s
[01:21:40.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:46:0)
[01:21:40.529] Timer tick, asking Buildbucket for the build status
[01:21:40.803] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:40.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:47:0) after 1m0s
[01:22:40.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:47:0)
[01:22:40.855] Timer tick, asking Buildbucket for the build status
[01:22:41.077] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:41.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:48:0) after 1m0s
[01:23:41.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:48:0)
[01:23:41.171] Timer tick, asking Buildbucket for the build status
[01:23:41.574] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:41.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:49:0) after 1m0s
[01:24:41.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:49:0)
[01:24:41.593] Timer tick, asking Buildbucket for the build status
[01:24:42.874] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:42.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:50:0) after 1m0s
[01:25:42.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:50:0)
[01:25:42.910] Timer tick, asking Buildbucket for the build status
[01:25:43.435] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:43.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:51:0) after 1m0s
[01:26:43.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:51:0)
[01:26:43.459] Timer tick, asking Buildbucket for the build status
[01:26:43.736] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:43.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:52:0) after 1m0s
[01:27:43.803] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:52:0)
[01:27:43.803] Timer tick, asking Buildbucket for the build status
[01:27:43.987] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:43.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:53:0) after 1m0s
[01:28:43.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:53:0)
[01:28:43.996] Timer tick, asking Buildbucket for the build status
[01:28:44.714] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:44.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:54:0) after 1m0s
[01:29:44.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:54:0)
[01:29:44.758] Timer tick, asking Buildbucket for the build status
[01:29:44.969] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:44.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:55:0) after 1m0s
[01:30:44.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:55:0)
[01:30:44.999] Timer tick, asking Buildbucket for the build status
[01:30:45.308] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:45.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:56:0) after 1m0s
[01:31:45.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:56:0)
[01:31:45.378] Timer tick, asking Buildbucket for the build status
[01:31:45.582] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:45.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:57:0) after 1m0s
[01:32:45.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:57:0)
[01:32:45.614] Timer tick, asking Buildbucket for the build status
[01:32:46.610] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:46.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:58:0) after 1m0s
[01:33:46.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:58:0)
[01:33:46.718] Timer tick, asking Buildbucket for the build status
[01:33:47.279] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:47.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:59:0) after 1m0s
[01:34:47.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:59:0)
[01:34:47.296] Timer tick, asking Buildbucket for the build status
[01:34:49.218] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:49.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:60:0) after 1m0s
[01:35:49.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:60:0)
[01:35:49.266] Timer tick, asking Buildbucket for the build status
[01:35:49.478] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:49.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:61:0) after 1m0s
[01:36:49.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:61:0)
[01:36:49.504] Timer tick, asking Buildbucket for the build status
[01:36:50.399] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:50.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:62:0) after 1m0s
[01:37:50.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:62:0)
[01:37:50.485] Timer tick, asking Buildbucket for the build status
[01:37:50.981] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:50.981] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:63:0) after 1m0s
[01:38:51.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:63:0)
[01:38:51.071] Timer tick, asking Buildbucket for the build status
[01:38:51.825] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:51.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:64:0) after 1m0s
[01:39:51.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:64:0)
[01:39:51.872] Timer tick, asking Buildbucket for the build status
[01:39:52.315] Build 8894230981607223328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:52.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059661043806199488:65:0) after 1m0s
[01:40:28.846] Received PubSub notification, asking Buildbucket for the build status
[01:40:29.070] Build 8894230981607223328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:40:29.070] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576201227776655",
"created_by": "project:chromium",
"created_ts": "1576197370864343",
"id": "8894230981607223328",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fc8d82eea75a24d6978cdc97b43109d094415fde\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-46-bppc\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166020, \"got_angle_revision\": \"e0f8b5d083541298360a38108e5f3fc487cea8fe\", \"got_dawn_revision\": \"5c413afdc7f8ae44071b7520e828bc75f0e4a4ed\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"fc8d82eea75a24d6978cdc97b43109d094415fde\", \"got_revision_cp\": \"refs/heads/master@{#724428}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"a384afc6b9db23eff12c68db480574d9d68ba722\", \"got_v8_revision\": \"7a24b354c073a6a9bc0ef197f58c79db0b0a9246\", \"got_v8_revision_cp\": \"refs/heads/8.1.28@{#1}\", \"got_webrtc_revision\": \"26fe811623627e1089a8df2c86a89dda2cd9d338\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30068}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fc8d82eea75a24d6978cdc97b43109d094415fde\", \"swarm_hashes_refs/heads/master(at){#724428}_without_patch\": {\"accessibility_unittests\": \"be5be379bf66c1350a4913c4f69443c8ad354753\", \"angle_unittests\": \"fe832493d7d3a0e168925ea0f5b588575bc57c43\", \"app_shell_unittests\": \"efedc441404937c6d6989c3853121606659effff\", \"aura_unittests\": \"d35e5471f6b9bfd0ff960d58bccec69a9e7a2adb\", \"base_unittests\": \"9532b530904e4ff74dd2b969887243e196c5e43d\", \"base_util_unittests\": \"3f4a8ddc9768b5cdcfb93fa532814fde425da7ca\", \"blink_common_unittests\": \"3df1ca1392533f01f047351ab848e79a9e1ed091\", \"blink_fuzzer_unittests\": \"5712a4435dbced9d069d7250c2a4c8d219f89d35\", \"blink_heap_unittests\": \"c23ac8d27bb158531e02fa79cf14562e3434d811\", \"blink_platform_unittests\": \"6fa0f722aef20eb261643532ef5a1c7ad337449a\", \"blink_python_tests\": \"4f9ec9f8b259545c4c13f1c64a6406b5f6a959fd\", \"blink_unittests\": \"c2f44b3fd3453cef95bd3f26277a002015b9af26\", \"blink_web_tests\": \"2cf1aa1cbc35528f598d7cfcf734c66b38603973\", \"boringssl_crypto_tests\": \"e4b14083ab2bb9f72b26a68f3ca839a979e7b9f9\", \"boringssl_ssl_tests\": \"586f4cdca1cc15fdbc971d930c1ce420b45ab5b8\", \"browser_tests\": \"c33e959c17830d2853eae33067509a9bfa625e92\", \"cacheinvalidation_unittests\": \"3c959a661153aca6c9f34f06350ce47cc0eb1003\", \"capture_unittests\": \"0899cacee2592b1fe08e9f6bd2f9b217a60aee7c\", \"cast_unittests\": \"ef3e865a2b26c94388b7711abd995f58a11481f9\", \"cc_unittests\": \"3b0dd857708c44aba141576bb091528c449abf52\", \"chrome_app_unittests\": \"6a3c7eedbb1c3b4ca4843d7e4bc313a9b0151480\", \"chromedriver_unittests\": \"6bf0970d8d99b356d7c3a75dcbfcf99e25e7ae3e\", \"components_browsertests\": \"ef57c08f19708c1b9be0fa7e0768bbbefad648d2\", \"components_unittests\": \"5a2be89c8fa15a5e5493acd9f999f0047805e1e4\", \"compositor_unittests\": \"5c63fd79db1ed3bac1af5c6ace2afba9a3a2f3a9\", \"content_browsertests\": \"120b80b18b55dc67405dc120c528c1cf38ae0d67\", \"content_shell_crash_test\": \"aa32608dc5d9481cadcb4eb3902653763c64bf42\", \"content_unittests\": \"261813a45957c34e3588aad78ccebbf8a2397143\", \"crashpad_tests\": \"28c9ddeb4fb5ea7b13bdcccfd0662c8bbdb90dd8\", \"cronet_tests\": \"c4bfb3af8f7f2463775ab631f28b42e8f735e47e\", \"cronet_unittests\": \"b74329ffd45065b6826985557c1f2f6671793bb3\", \"crypto_unittests\": \"8d4386b722cc9f7712b771fe7d7aa821a78770c6\", \"dbus_unittests\": \"f8f0e29bd851455c47be21313782e78fcc0d3c0f\", \"device_unittests\": \"c9ad92a11e160bca1f6f38f1ee66f103172d3422\", \"devtools_lint_check\": \"a4aeb18f450d9fdb600cf2432359024f2737793d\", \"devtools_type_check\": \"51d3096e55da2372da54cb0ec573bff159cbf30d\", \"display_unittests\": \"a767b240ba1d77b9816b5f854eb595c8abccc8fc\", \"events_unittests\": \"922ef70178a0e506be8ea27551c923de4a5a9346\", \"extensions_browsertests\": \"005121fd7eb3850699972857e46a12873fc380db\", \"extensions_unittests\": \"1fd01f0419a8e6ec23168f58df6bf487ee8f1e15\", \"filesystem_service_unittests\": \"59742c74b67704ad678412a8b9d7b99f04ac18ec\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"730cbb0c9180a0134d0cf5de0cd6625cea93cb6c\", \"gfx_unittests\": \"1558abd453364cab2c63c10801dbbe8a6c687402\", \"gin_unittests\": \"cd0dbea2cd97debd4dd1f212b487334040a9e463\", \"google_apis_unittests\": \"4e4b897766d1041a7145ee281b5dffe9478cce25\", \"gpu_unittests\": \"408d5dafad9502d962c4db632bb4fe405b9e77d9\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"8f8d888dffdeb1fd43b38593fcc979621931a896\", \"headless_browsertests\": \"008c6cd6293abbc52a4f42073423236bc5ddc51b\", \"headless_unittests\": \"e3612614c89b99f097cc97c769f83d517c9f21e5\", \"interactive_ui_tests\": \"b0c4d12b66c7246d59b8d33e6442eb282f0e9048\", \"ipc_tests\": \"1a878348d9beb3b11d76779b59d266e1b352ba71\", \"jingle_unittests\": \"2562afb97ef8bb33def40ccaf6452ae47a5d9d81\", \"latency_unittests\": \"58cd339811bfe7006204ecf80eecf9f94456fd16\", \"libjingle_xmpp_unittests\": \"2b45ad08a8834741da563f57cac8ea41735910e0\", \"media_blink_unittests\": \"18d9427216beacf50e71b9061f0e1068be57b0b9\", \"media_service_unittests\": \"96fbb379d8ad95c95638796d23558f72ec71b633\", \"media_unittests\": \"32a51f8c52b8488509f3bc71340c648d5bfd3bb8\", \"message_center_unittests\": \"4132188b3d7da5fe7b39e7293e1760612be68b33\", \"metrics_python_tests\": \"9e6c1e9bb954bb538d9ca5df20f29a77243a9095\", \"midi_unittests\": \"8055be88af67ed7a881c28b580b55be4e276b762\", \"mojo_core_unittests\": \"7bfdaa1f062463fcac794719732d0ebb96126d86\", \"mojo_unittests\": \"94bbc9d0a6abff4b58e86e5c805e4d67638e4ae8\", \"nacl_helper_nonsfi_unittests\": \"ddec84fc9ec0bcc0c34b9a26e4efbc6e128666b9\", \"nacl_loader_unittests\": \"daad7f5161de6463919067b48466b766a71a4b0f\", \"native_theme_unittests\": \"8feccb4540d622961ec5fa67f19e39c603a9ca11\", \"net_unittests\": \"5f8af3ddf6d9ac5d85943c5462995509f9a3d70c\", \"pdf_unittests\": \"d7158a9884ce473ac826865381b9416693471d1f\", \"perfetto_unittests\": \"90c522fd6e1ee1b394b5e3d062866691b667f067\", \"ppapi_unittests\": \"c61dde81fb97ab10cdfac58f83b9232423fa114c\", \"printing_unittests\": \"4b66bebdfe9b373f58c0a1f6891db3686bb10086\", \"remoting_unittests\": \"3539c16787b2844477f3df48539ab6e9bd81bb20\", \"sandbox_linux_unittests\": \"5e3aac58544fbcb14043f6d58ae6d99c763d763f\", \"service_manager_unittests\": \"b431a00f417b4eaf7cc993b6d80711a41e32bfda\", \"services_unittests\": \"3df3afd132ac4819c2cdaa584eb78b4b4e5d3a6c\", \"shell_dialogs_unittests\": \"31c9f919dbdb7a9723792d0b2fd9dd78f6461634\", \"skia_unittests\": \"53727c811cb963ccfd4b08a25db268d46cd696f8\", \"snapshot_unittests\": \"6edc022fe4578e5b47b3addafee9137f051bbbfe\", \"sql_unittests\": \"908b75195a407a10497bc30c768a8227fd17fdeb\", \"storage_unittests\": \"f3912813ad0eed253199474ed5780fc8ad90ec73\", \"sync_integration_tests\": \"aff1c84c818953b8b4325144b81bc5f21ecbd815\", \"telemetry_gpu_unittests\": \"54535d07832d8d065948e9da6c1e6e74f731b6ea\", \"telemetry_perf_unittests\": \"dc4b89ff83a76f90540c3117be23a8fd3a4257b2\", \"telemetry_unittests\": \"d44721c9daafbea465f845781cd9c92fa590706c\", \"traffic_annotation_auditor_unittests\": \"b7ccfe94b7e4fe30fcbaecd6c307b00584947df7\", \"ui_base_unittests\": \"a1c4f1f98ceeecae9a7e00549cf4600d039ccb70\", \"ui_touch_selection_unittests\": \"626e80c28e3da8bba5a40a2492d883bfd18463f8\", \"unit_tests\": \"33db47d87e05e86c27767720bed9a115343f71c3\", \"url_unittests\": \"14260a12a03e1abc564f3715c9c45b9b66167bfe\", \"views_perftests\": \"8e88c8bd521fdbbe816733c1a0ee20056095c03e\", \"views_unittests\": \"6f85ce3f2fa39e05cb398c7d4beab7bbaf942af5\", \"viz_unittests\": \"3e6cc1a29d218cd95eef275d8b13ed9d149bc639\", \"vr_common_unittests\": \"f4b6b35dae292c10e596c1958a692aeaa044a072\", \"vr_pixeltests\": \"5f9c2f100794d1c7e6806f183b380fc4b0cd36ad\", \"webdriver_wpt_tests\": \"b7276973cd0b42f3637ae804e43d88a3ecbb83f0\", \"weblayer_browsertests\": \"fc1732dfea925d07765902640961cf095aba85a8\", \"wm_unittests\": \"5fc1a321135aeef3754b7f18c015de6db00d591b\", \"wtf_unittests\": \"9f45f854d2c8bc5050cf77641395eb540fe2ebb9\", \"xr_browser_tests\": \"9091305fcada423d5cf4b76c423e195b534447d4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_16895d03945fefa0a0694b70850ae78b1bb8ca61113b6e89bc39b6edfabe662c_v2\", \"builder_2bbcd421f4eb1113f3a23522b280d70ced47ef846cc2e40eada60721dff8d17e_v2\", \"builder_8116c80c2882afcd67a910417bbd6fba6f94de5911d922ccd599199b9d07f827_v2\", \"builder_a3b3a06131dfa9924971b27d0acd4e18e80a2242b6d6ae41f94b770d7c996d6f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-46-bppc\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4713-a14a20b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576197662184785",
"status": "COMPLETED",
"status_changed_ts": "1576201228255203",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166020",
"builder:Linux Builder (dbg)",
"buildset:commit/git/fc8d82eea75a24d6978cdc97b43109d094415fde",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc8d82eea75a24d6978cdc97b43109d094415fde",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059661043806199488",
"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/8894230981607223328/+/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:491580e62b572910",
"user_agent:luci-scheduler"
],
"updated_ts": "1576201228259691",
"url": "https://ci.chromium.org/b/8894230981607223328",
"utcnow_ts": "1576201229051541"
}
}
[01:40:29.070] Invocation finished in 1h4m19.687161145s with status SUCCEEDED