[21:47:20.672] New invocation is queued and will start shortly
[21:47:22.275] Starting the invocation (attempt 1)
[21:47:22.376] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:47:22.376] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:47:22.376] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053420473865432400",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e79945b709b8d99594dea67f4cdc081296819df8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053420473865432400",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e79945b709b8d99594dea67f4cdc081296819df8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e79945b709b8d99594dea67f4cdc081296819df8",
"gitiles_ref:refs/heads/master"
]
}
[21:47:23.609] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582148842497991",
"id": "8887990411288349136",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e79945b709b8d99594dea67f4cdc081296819df8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582148843361105",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168802",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e79945b709b8d99594dea67f4cdc081296819df8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e79945b709b8d99594dea67f4cdc081296819df8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053420473865432400",
"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/8887990411288349136/+/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": "1582148843361304",
"url": "https://ci.chromium.org/b/8887990411288349136",
"utcnow_ts": "1582148843600970"
}
}
[21:47:23.609] Task URL: https://ci.chromium.org/b/8887990411288349136
[21:47:23.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:2:0) after 1m0s
[21:48:23.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:2:0)
[21:48:23.628] Timer tick, asking Buildbucket for the build status
[21:48:23.851] Build 8887990411288349136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:23.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:3:0) after 1m0s
[21:49:23.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:3:0)
[21:49:23.912] Timer tick, asking Buildbucket for the build status
[21:49:24.005] Build 8887990411288349136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:24.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:4:0) after 1m0s
[21:50:24.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:4:0)
[21:50:24.005] Timer tick, asking Buildbucket for the build status
[21:50:24.948] Build 8887990411288349136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:24.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:5:0) after 1m0s
[21:51:24.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:5:0)
[21:51:24.974] Timer tick, asking Buildbucket for the build status
[21:51:25.066] Build 8887990411288349136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:25.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:6:0) after 1m0s
[21:52:25.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:6:0)
[21:52:25.092] Timer tick, asking Buildbucket for the build status
[21:52:25.188] Build 8887990411288349136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:25.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:7:0) after 1m0s
[21:52:34.772] Received PubSub notification, asking Buildbucket for the build status
[21:52:34.836] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:25.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:7:0)
[21:53:25.171] Timer tick, asking Buildbucket for the build status
[21:53:25.246] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:25.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:9:0) after 1m0s
[21:54:25.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:9:0)
[21:54:25.335] Timer tick, asking Buildbucket for the build status
[21:54:25.557] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:25.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:10:0) after 1m0s
[21:55:25.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:10:0)
[21:55:25.626] Timer tick, asking Buildbucket for the build status
[21:55:25.777] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:25.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:11:0) after 1m0s
[21:56:25.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:11:0)
[21:56:25.901] Timer tick, asking Buildbucket for the build status
[21:56:26.433] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:26.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:12:0) after 1m0s
[21:57:26.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:12:0)
[21:57:26.477] Timer tick, asking Buildbucket for the build status
[21:57:26.655] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:26.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:13:0) after 1m0s
[21:58:26.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:13:0)
[21:58:26.690] Timer tick, asking Buildbucket for the build status
[21:58:26.921] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:26.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:14:0) after 1m0s
[21:59:26.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:14:0)
[21:59:26.958] Timer tick, asking Buildbucket for the build status
[21:59:27.075] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:27.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:15:0) after 1m0s
[22:00:27.110] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:15:0)
[22:00:27.110] Timer tick, asking Buildbucket for the build status
[22:00:27.226] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:27.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:16:0) after 1m0s
[22:01:27.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:16:0)
[22:01:27.222] Timer tick, asking Buildbucket for the build status
[22:01:27.396] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:27.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:17:0) after 1m0s
[22:02:27.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:17:0)
[22:02:27.414] Timer tick, asking Buildbucket for the build status
[22:02:27.516] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:27.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:18:0) after 1m0s
[22:03:27.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:18:0)
[22:03:27.864] Timer tick, asking Buildbucket for the build status
[22:03:27.995] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:27.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:19:0) after 1m0s
[22:04:28.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:19:0)
[22:04:28.161] Timer tick, asking Buildbucket for the build status
[22:04:28.307] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:28.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:20:0) after 1m0s
[22:05:29.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:20:0)
[22:05:29.395] Timer tick, asking Buildbucket for the build status
[22:05:29.516] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:29.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:21:0) after 1m0s
[22:06:29.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:21:0)
[22:06:29.837] Timer tick, asking Buildbucket for the build status
[22:06:30.186] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:30.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:22:0) after 1m0s
[22:07:30.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:22:0)
[22:07:30.223] Timer tick, asking Buildbucket for the build status
[22:07:30.513] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:30.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:23:0) after 1m0s
[22:08:30.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:23:0)
[22:08:30.614] Timer tick, asking Buildbucket for the build status
[22:08:30.801] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:30.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:24:0) after 1m0s
[22:09:30.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:24:0)
[22:09:30.932] Timer tick, asking Buildbucket for the build status
[22:09:31.079] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:31.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:25:0) after 1m0s
[22:10:31.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:25:0)
[22:10:31.147] Timer tick, asking Buildbucket for the build status
[22:10:31.386] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:31.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:26:0) after 1m0s
[22:11:31.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:26:0)
[22:11:31.433] Timer tick, asking Buildbucket for the build status
[22:11:31.586] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:31.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:27:0) after 1m0s
[22:12:31.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:27:0)
[22:12:31.684] Timer tick, asking Buildbucket for the build status
[22:12:31.817] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:31.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:28:0) after 1m0s
[22:13:31.835] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:28:0)
[22:13:31.835] Timer tick, asking Buildbucket for the build status
[22:13:31.998] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:31.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:29:0) after 1m0s
[22:14:32.076] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:29:0)
[22:14:32.076] Timer tick, asking Buildbucket for the build status
[22:14:32.205] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:32.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:30:0) after 1m0s
[22:15:32.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:30:0)
[22:15:32.294] Timer tick, asking Buildbucket for the build status
[22:15:32.437] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:32.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:31:0) after 1m0s
[22:16:32.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:31:0)
[22:16:32.598] Timer tick, asking Buildbucket for the build status
[22:16:32.724] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:32.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:32:0) after 1m0s
[22:17:32.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:32:0)
[22:17:32.809] Timer tick, asking Buildbucket for the build status
[22:17:32.902] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:32.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:33:0) after 1m0s
[22:18:33.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:33:0)
[22:18:33.006] Timer tick, asking Buildbucket for the build status
[22:18:33.358] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:33.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:34:0) after 1m0s
[22:19:33.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:34:0)
[22:19:33.387] Timer tick, asking Buildbucket for the build status
[22:19:33.484] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:33.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:35:0) after 1m0s
[22:20:33.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:35:0)
[22:20:33.599] Timer tick, asking Buildbucket for the build status
[22:20:33.696] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:33.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:36:0) after 1m0s
[22:21:33.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:36:0)
[22:21:33.725] Timer tick, asking Buildbucket for the build status
[22:21:33.907] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:33.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:37:0) after 1m0s
[22:22:33.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:37:0)
[22:22:33.953] Timer tick, asking Buildbucket for the build status
[22:22:34.081] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:34.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:38:0) after 1m0s
[22:23:34.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:38:0)
[22:23:34.117] Timer tick, asking Buildbucket for the build status
[22:23:34.265] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:34.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:39:0) after 1m0s
[22:24:34.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:39:0)
[22:24:34.318] Timer tick, asking Buildbucket for the build status
[22:24:34.722] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:34.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:40:0) after 1m0s
[22:25:34.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:40:0)
[22:25:34.876] Timer tick, asking Buildbucket for the build status
[22:25:35.172] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:35.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:41:0) after 1m0s
[22:26:35.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:41:0)
[22:26:35.200] Timer tick, asking Buildbucket for the build status
[22:26:35.339] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:35.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:42:0) after 1m0s
[22:27:35.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:42:0)
[22:27:35.401] Timer tick, asking Buildbucket for the build status
[22:27:35.504] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:35.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:43:0) after 1m0s
[22:28:35.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:43:0)
[22:28:35.538] Timer tick, asking Buildbucket for the build status
[22:28:35.922] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:35.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:44:0) after 1m0s
[22:29:35.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:44:0)
[22:29:35.944] Timer tick, asking Buildbucket for the build status
[22:29:36.038] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:36.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:45:0) after 1m0s
[22:30:36.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:45:0)
[22:30:36.082] Timer tick, asking Buildbucket for the build status
[22:30:36.275] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:36.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:46:0) after 1m0s
[22:31:36.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:46:0)
[22:31:36.289] Timer tick, asking Buildbucket for the build status
[22:31:36.477] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:36.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:47:0) after 1m0s
[22:32:36.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:47:0)
[22:32:36.512] Timer tick, asking Buildbucket for the build status
[22:32:36.722] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:36.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:48:0) after 1m0s
[22:33:36.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:48:0)
[22:33:36.700] Timer tick, asking Buildbucket for the build status
[22:33:36.882] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:36.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:49:0) after 1m0s
[22:34:36.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:49:0)
[22:34:36.921] Timer tick, asking Buildbucket for the build status
[22:34:37.033] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:37.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:50:0) after 1m0s
[22:35:37.053] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:50:0)
[22:35:37.053] Timer tick, asking Buildbucket for the build status
[22:35:37.290] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:37.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:51:0) after 1m0s
[22:36:37.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:51:0)
[22:36:37.379] Timer tick, asking Buildbucket for the build status
[22:36:37.474] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:37.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:52:0) after 1m0s
[22:37:37.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:52:0)
[22:37:37.513] Timer tick, asking Buildbucket for the build status
[22:37:37.679] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:37.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:53:0) after 1m0s
[22:38:37.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:53:0)
[22:38:37.662] Timer tick, asking Buildbucket for the build status
[22:38:38.002] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:38.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:54:0) after 1m0s
[22:39:37.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:54:0)
[22:39:37.997] Timer tick, asking Buildbucket for the build status
[22:39:38.293] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:38.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:55:0) after 1m0s
[22:40:38.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:55:0)
[22:40:38.338] Timer tick, asking Buildbucket for the build status
[22:40:38.525] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:38.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:56:0) after 1m0s
[22:41:38.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:56:0)
[22:41:38.559] Timer tick, asking Buildbucket for the build status
[22:41:39.125] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:39.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:57:0) after 1m0s
[22:42:39.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:57:0)
[22:42:39.144] Timer tick, asking Buildbucket for the build status
[22:42:39.281] Build 8887990411288349136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:39.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053420473865432400:58:0) after 1m0s
[22:42:52.771] Received PubSub notification, asking Buildbucket for the build status
[22:42:54.061] Build 8887990411288349136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:42:54.061] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582152172004518",
"created_by": "project:chromium",
"created_ts": "1582148842497991",
"id": "8887990411288349136",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e79945b709b8d99594dea67f4cdc081296819df8\"}}",
"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-70-rx4l\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168802, \"got_angle_revision\": \"38bb9fdf36e8d38ab0ae966fa6b652025ebc08a5\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"e79945b709b8d99594dea67f4cdc081296819df8\", \"got_revision_cp\": \"refs/heads/master@{#742804}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8def9063c4fdefa8e644198d2254ef279952c88b\", \"got_v8_revision\": \"07a0ee92dfd89a66d57b9fb242d90e33607e3c44\", \"got_v8_revision_cp\": \"refs/heads/8.2.120@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e79945b709b8d99594dea67f4cdc081296819df8\", \"swarm_hashes_refs/heads/master(at){#742804}_without_patch\": {\"accessibility_unittests\": \"021de60130a17dc1ceab7e61ef74106edd9ea371\", \"angle_unittests\": \"7034d9124e0fcb7a9e0adc2febbdf0c404fe3282\", \"app_shell_unittests\": \"1d8f613e46ac830135df1e196e3f718c0ffe3a35\", \"aura_unittests\": \"abde8974c89751bbe19f157e4eaa525b453d619e\", \"base_unittests\": \"39ce390b30129401aeb96df46690c15a5ae2e84d\", \"base_util_unittests\": \"049d7956c8cce36cce70d29303a629abeb648a56\", \"blink_common_unittests\": \"f7324db8fe8f3acd80623edce399ead9a00c5eca\", \"blink_fuzzer_unittests\": \"7cb2c37a213c621f9018649b3d8ea387e09665bb\", \"blink_heap_unittests\": \"062af4d7b7b79961af1644ad5eb66e2da6bb8f1b\", \"blink_platform_unittests\": \"090d65a8fff1ce953658b198ef37e8e0e1455909\", \"blink_python_tests\": \"f28b82d0abff59b194e311615684dce731b3d58a\", \"blink_unittests\": \"840518ec5cd7a42f65253fb694f1960c3acad9f7\", \"blink_web_tests\": \"411043a56a7c46b19874649fd20620b540600a8f\", \"boringssl_crypto_tests\": \"f192c68ea367d3b81c785f4275a33ff0ded61722\", \"boringssl_ssl_tests\": \"245e199ce2e0765cbb41e11b09b752ebae9091c8\", \"browser_tests\": \"c15164a1320804d46ec25969893b92c028fe1785\", \"cacheinvalidation_unittests\": \"b8bf0c8c650ee6719490344af22af078d740537a\", \"capture_unittests\": \"b2ac33cf162e5c6e5e207cb4dc83cf3b3a0a9fd1\", \"cast_unittests\": \"05b8741d4d468a53f30f67382fa04e52658b5344\", \"cc_unittests\": \"336fc25126959aa363f7ec5a3b32c4613cff8e70\", \"chrome_app_unittests\": \"a461d670846326218db5b144fc29fbf5995c3fb7\", \"chromedriver_unittests\": \"22ca75ccea6aea738249fce1ed6daf8a28f02e1e\", \"components_browsertests\": \"8da07a815b38cb5599cd97cfdc55f1b1089c690b\", \"components_unittests\": \"f66d8fcc7fee80cd35008b98837ede401bb832b3\", \"compositor_unittests\": \"4a5fc88b06ccf7c27f8d2de44f969a8f86cb933b\", \"content_browsertests\": \"7b051c10efb56a48fa2057dfd2421cc1bb3728fd\", \"content_shell_crash_test\": \"e3b99259bc55002622c51809f99d46df3de2521f\", \"content_unittests\": \"82a7b356a904240a1c94a59843a1e5fadc572e24\", \"crashpad_tests\": \"77909bad09bf0b6045e7afcf2b4ff0ae4f626078\", \"cronet_tests\": \"458215b919a5d9f42bffd8d053676f7d5d50a7f1\", \"cronet_unittests\": \"3dc7d4f48b8a57f07aaf4d52a9198c60a869295e\", \"crypto_unittests\": \"31e9de9118b70eb4562bdce4de035f102472e281\", \"dbus_unittests\": \"22ca27009c947f154511f7da0a91983d4081b412\", \"device_unittests\": \"adbbb608732435b960a5a9b3fa5a9377a65d7bdd\", \"display_unittests\": \"dca5ad9ce68f92e4f7c1fe4c380d78ef3869a082\", \"events_unittests\": \"f9ee996675e7ffd1f50144ebe20fba08de68c113\", \"extensions_browsertests\": \"c629831ea0d1f3b5fb3702964c16017a6ce4ca76\", \"extensions_unittests\": \"1260be18307d74a3beac9aeea6934fa5509e2ff8\", \"filesystem_service_unittests\": \"c59579d1cf2adcc92ff3b19a103d28c5bee85fd8\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"60f7ac5c0be0d6e45cc56f92e8dda632ac465e54\", \"gfx_unittests\": \"d2b1a4c96ea5fc251ba0284759585354a509aae5\", \"gin_unittests\": \"58cef8cc217c63e3676d2932eeb993d3cffaf134\", \"google_apis_unittests\": \"8c460f9c6bce263be9e8219f529b4d443c7a5c06\", \"gpu_unittests\": \"6b076f95eb69c06993f4b9365137f97611fb7ae8\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"a348b70cd39baef1e4dc4d14a5f74b77015eb058\", \"headless_browsertests\": \"d73abf5db0aecfe7a32c7c4ae297b06b09d149cc\", \"headless_unittests\": \"d2fd680914cf91484c778a0c57cc7f1d4f3e3b4d\", \"interactive_ui_tests\": \"e4dd2032807a7d853fcca8813f37bca0a9eb5987\", \"ipc_tests\": \"075bb69f64b92725b1fffdc27e236cc6e25b30dc\", \"jingle_unittests\": \"946ff16a08a2a44876899955fc8281d7da6a4273\", \"latency_unittests\": \"7e5979ccad0c3b3c80664d04fee1ab56278c4e9a\", \"libjingle_xmpp_unittests\": \"3e73548449027ae29afa32c2d318b96e3a8dd8e6\", \"media_blink_unittests\": \"f03b82c70660fc9f2aa62143eb9b1bdb06a16d57\", \"media_service_unittests\": \"be852f632318e1bdc4dc6dec2a4bdaf75bb4ac19\", \"media_unittests\": \"b930ab3197e671845dc43e3034c2f2f1b58c40a3\", \"message_center_unittests\": \"677d13f02381566310b43288bafd40ca71253afc\", \"metrics_python_tests\": \"185c304e97dac226aea03d84dd9b57fedc06e28d\", \"midi_unittests\": \"18ac03d4aca6a8ea9fc268391327e56ae54ddb38\", \"mojo_core_unittests\": \"b2e7883da84bdaa0752641f4f18ba12c164f8cbe\", \"mojo_unittests\": \"d3ae31233c4a91b18e781e86388d276ed209acf0\", \"nacl_helper_nonsfi_unittests\": \"cab3683f1531a83b5d5e7cedac2336798f5c8948\", \"nacl_loader_unittests\": \"97c36486b46927a8bc8cd281c4b2960fe70c6d36\", \"native_theme_unittests\": \"da80186bfcc0b61883d42664405c2c68c8c51117\", \"net_unittests\": \"dec095210a16208ed66c876cde67dec879fa575f\", \"pdf_unittests\": \"1d20f262cb32bf0034d268bf7df8e2b434e54b45\", \"perfetto_unittests\": \"f849a1de62ce58b3ed38e045435b817c767d7442\", \"ppapi_unittests\": \"388d6e67b54e6b7b87fa177706a455421279c7dd\", \"printing_unittests\": \"2ae174fec1d6fb506a3d3369c56b8649831303aa\", \"remoting_unittests\": \"f7c9b62c64d2a0463cf05c5f4fc1f0501138f927\", \"sandbox_linux_unittests\": \"80aefc26c4becfb03ccf117f3c2165a8bb15eeb1\", \"service_manager_unittests\": \"4ba316d9621fc1c9b9f14efa21cf6a7f639544ce\", \"services_unittests\": \"a82c28b7bca47b9381fbdfb884a9a194ef98a9cf\", \"shell_dialogs_unittests\": \"3305f3dd8302ebe5b1f7c462d9ae060e8b397d9d\", \"skia_unittests\": \"723ea8bbeb9b801b1543f115e80036c4a291fa81\", \"snapshot_unittests\": \"7af815e9d1c4b8b104c01fc193d4e47c6e4af164\", \"sql_unittests\": \"9c6a97e0d07d8c302c8ad0abfecde1345dc2b0d0\", \"storage_unittests\": \"c4e7b305fdfc883d2de446f00d8052e1b3cabd60\", \"sync_integration_tests\": \"04131e0bc21280b5ba98dce4f9d67a67d1ecd27f\", \"telemetry_gpu_unittests\": \"a94efdbbaf00336ac8a7f37b078739f36cf94c03\", \"telemetry_perf_unittests\": \"fc10fa2c41fca650f1a040c139435a86a6f3af98\", \"telemetry_unittests\": \"16dfce2f9eded4c3637d0a4cc56cc7de052b6128\", \"traffic_annotation_auditor_unittests\": \"e3db5b39b7444d56255acaa28150359efdfb4887\", \"ui_base_unittests\": \"506fecc2a41a83c3dd3e3c91719df136a016b7ea\", \"ui_touch_selection_unittests\": \"33868d786b13f6977825b745aa40eb1c65d50533\", \"unit_tests\": \"2d48007f14b50c171f43ce26000f80de2aa69e1e\", \"url_unittests\": \"19c7acc38d9529bb8cc27e086ee98351401d6b17\", \"views_perftests\": \"3f9e71339080180e3087b2c6f0d0757c58ee04dc\", \"views_unittests\": \"f7767a6394c32fd11b659c3213eadd275654b649\", \"viz_unittests\": \"aded9e53189327ea0c6926b036f3ac38b72d8d8a\", \"vr_common_unittests\": \"aaf2e27fc67c7044c1a0fb4f4d93b44092941b9b\", \"vr_pixeltests\": \"d4d3c55bd72125aba7fae45faeca70fb7b97a865\", \"webdriver_wpt_tests\": \"8bf2053945562ff86c96a243ba0772f85b0f41b3\", \"weblayer_browsertests\": \"ce0eb292fd12a743f26b52fc5706deb58f4d268b\", \"wm_unittests\": \"ff12df96d942bb03413ccb94e652ed33c2c5a052\", \"wtf_unittests\": \"8488d93df7bec1cbabd61bd81eb49556728f600e\", \"xr_browser_tests\": \"82fcb2e630d690b4a4928d6c1ea72cc5c78b058d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_016577e073ce9abf0fa10d80f88bc2caf8f81662b409bd6e59fa52089e44a3df_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_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-70-rx4l\"], \"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": "1582149153899761",
"status": "COMPLETED",
"status_changed_ts": "1582152172432216",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168802",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e79945b709b8d99594dea67f4cdc081296819df8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e79945b709b8d99594dea67f4cdc081296819df8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053420473865432400",
"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/8887990411288349136/+/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:4a783d00c1440210",
"user_agent:luci-scheduler"
],
"updated_ts": "1582152172442264",
"url": "https://ci.chromium.org/b/8887990411288349136",
"utcnow_ts": "1582152174048877"
}
}
[22:42:54.061] Invocation finished in 55m33.404742247s with status SUCCEEDED