[20:49:50.468] New invocation is queued and will start shortly
[20:49:51.710] Starting the invocation (attempt 1)
[20:49:51.745] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:49:51.745] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:49:51.745] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021533959491748336",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"25cfdde6d4242d128c8a49bd3dda7d980d4058d3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021533959491748336",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/25cfdde6d4242d128c8a49bd3dda7d980d4058d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25cfdde6d4242d128c8a49bd3dda7d980d4058d3",
"gitiles_ref:refs/heads/master"
]
}
[20:49:52.399] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612558191825999",
"id": "8856103897406865792",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25cfdde6d4242d128c8a49bd3dda7d980d4058d3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612558192274988",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45179",
"builder:linux-chromeos-rel",
"buildset:commit/git/25cfdde6d4242d128c8a49bd3dda7d980d4058d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25cfdde6d4242d128c8a49bd3dda7d980d4058d3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021533959491748336",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856103897406865792/+/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": "1612558192275109",
"url": "https://ci.chromium.org/b/8856103897406865792",
"utcnow_ts": "1612558192389956"
}
}
[20:49:52.399] Task URL: https://ci.chromium.org/b/8856103897406865792
[20:49:52.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:2:0) after 1m0s
[20:50:00.769] Received PubSub notification, asking Buildbucket for the build status
[20:50:00.874] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:52.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:2:0)
[20:50:52.494] Timer tick, asking Buildbucket for the build status
[20:50:52.593] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:52.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:4:0) after 1m0s
[20:51:52.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:4:0)
[20:51:52.646] Timer tick, asking Buildbucket for the build status
[20:51:52.915] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:52.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:5:0) after 1m0s
[20:52:52.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:5:0)
[20:52:52.927] Timer tick, asking Buildbucket for the build status
[20:52:53.023] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:53.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:6:0) after 1m0s
[20:53:53.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:6:0)
[20:53:53.045] Timer tick, asking Buildbucket for the build status
[20:53:53.526] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:53.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:7:0) after 1m0s
[20:54:53.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:7:0)
[20:54:53.545] Timer tick, asking Buildbucket for the build status
[20:54:53.800] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:53.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:8:0) after 1m0s
[20:55:53.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:8:0)
[20:55:53.829] Timer tick, asking Buildbucket for the build status
[20:55:53.890] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:53.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:9:0) after 1m0s
[20:56:53.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:9:0)
[20:56:53.979] Timer tick, asking Buildbucket for the build status
[20:56:54.213] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:54.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:10:0) after 1m0s
[20:57:54.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:10:0)
[20:57:54.277] Timer tick, asking Buildbucket for the build status
[20:57:54.436] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:54.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:11:0) after 1m0s
[20:58:54.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:11:0)
[20:58:54.453] Timer tick, asking Buildbucket for the build status
[20:58:54.632] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:54.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:12:0) after 1m0s
[20:59:54.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:12:0)
[20:59:54.725] Timer tick, asking Buildbucket for the build status
[20:59:55.219] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:55.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:13:0) after 1m0s
[21:00:55.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:13:0)
[21:00:55.344] Timer tick, asking Buildbucket for the build status
[21:00:55.531] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:55.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:14:0) after 1m0s
[21:01:55.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:14:0)
[21:01:55.547] Timer tick, asking Buildbucket for the build status
[21:01:55.806] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:55.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:15:0) after 1m0s
[21:02:55.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:15:0)
[21:02:55.925] Timer tick, asking Buildbucket for the build status
[21:02:55.983] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:55.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:16:0) after 1m0s
[21:03:56.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:16:0)
[21:03:56.057] Timer tick, asking Buildbucket for the build status
[21:03:56.150] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:56.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:17:0) after 1m0s
[21:04:56.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:17:0)
[21:04:56.231] Timer tick, asking Buildbucket for the build status
[21:04:56.619] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:56.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:18:0) after 1m0s
[21:05:56.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:18:0)
[21:05:56.661] Timer tick, asking Buildbucket for the build status
[21:05:56.825] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:56.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:19:0) after 1m0s
[21:06:56.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:19:0)
[21:06:56.853] Timer tick, asking Buildbucket for the build status
[21:06:58.160] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:58.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:20:0) after 1m0s
[21:07:58.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:20:0)
[21:07:58.149] Timer tick, asking Buildbucket for the build status
[21:07:58.405] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:58.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:21:0) after 1m0s
[21:08:58.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:21:0)
[21:08:58.442] Timer tick, asking Buildbucket for the build status
[21:08:58.624] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:58.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:22:0) after 1m0s
[21:09:58.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:22:0)
[21:09:58.646] Timer tick, asking Buildbucket for the build status
[21:09:58.757] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:58.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:23:0) after 1m0s
[21:10:58.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:23:0)
[21:10:58.802] Timer tick, asking Buildbucket for the build status
[21:10:59.093] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:59.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:24:0) after 1m0s
[21:11:59.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:24:0)
[21:11:59.376] Timer tick, asking Buildbucket for the build status
[21:11:59.468] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:59.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:25:0) after 1m0s
[21:12:59.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:25:0)
[21:12:59.509] Timer tick, asking Buildbucket for the build status
[21:12:59.570] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:59.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:26:0) after 1m0s
[21:13:59.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:26:0)
[21:13:59.575] Timer tick, asking Buildbucket for the build status
[21:13:59.633] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:59.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:27:0) after 1m0s
[21:14:59.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:27:0)
[21:14:59.676] Timer tick, asking Buildbucket for the build status
[21:14:59.767] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:59.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:28:0) after 1m0s
[21:15:59.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:28:0)
[21:15:59.778] Timer tick, asking Buildbucket for the build status
[21:16:00.002] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:00.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:29:0) after 1m0s
[21:17:00.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:29:0)
[21:17:00.026] Timer tick, asking Buildbucket for the build status
[21:17:00.151] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:00.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:30:0) after 1m0s
[21:18:00.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:30:0)
[21:18:00.195] Timer tick, asking Buildbucket for the build status
[21:18:00.334] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:00.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:31:0) after 1m0s
[21:19:00.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:31:0)
[21:19:00.847] Timer tick, asking Buildbucket for the build status
[21:19:01.005] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:01.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:32:0) after 1m0s
[21:20:01.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:32:0)
[21:20:01.370] Timer tick, asking Buildbucket for the build status
[21:20:01.642] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:01.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:33:0) after 1m0s
[21:21:01.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:33:0)
[21:21:01.981] Timer tick, asking Buildbucket for the build status
[21:21:02.052] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:02.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:34:0) after 1m0s
[21:22:02.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:34:0)
[21:22:02.112] Timer tick, asking Buildbucket for the build status
[21:22:02.442] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:02.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:35:0) after 1m0s
[21:23:02.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:35:0)
[21:23:02.454] Timer tick, asking Buildbucket for the build status
[21:23:02.579] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:02.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:36:0) after 1m0s
[21:24:02.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:36:0)
[21:24:02.656] Timer tick, asking Buildbucket for the build status
[21:24:02.761] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:02.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:37:0) after 1m0s
[21:25:02.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:37:0)
[21:25:02.885] Timer tick, asking Buildbucket for the build status
[21:25:03.027] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:03.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:38:0) after 1m0s
[21:26:03.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:38:0)
[21:26:03.144] Timer tick, asking Buildbucket for the build status
[21:26:04.302] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:04.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:39:0) after 1m0s
[21:27:04.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:39:0)
[21:27:04.333] Timer tick, asking Buildbucket for the build status
[21:27:04.449] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:04.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:40:0) after 1m0s
[21:28:04.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:40:0)
[21:28:04.471] Timer tick, asking Buildbucket for the build status
[21:28:04.567] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:04.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:41:0) after 1m0s
[21:29:04.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:41:0)
[21:29:04.673] Timer tick, asking Buildbucket for the build status
[21:29:04.807] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:04.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:42:0) after 1m0s
[21:30:04.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:42:0)
[21:30:04.875] Timer tick, asking Buildbucket for the build status
[21:30:05.131] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:05.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:43:0) after 1m0s
[21:31:05.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:43:0)
[21:31:05.239] Timer tick, asking Buildbucket for the build status
[21:31:05.707] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:05.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:44:0) after 1m0s
[21:32:05.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:44:0)
[21:32:05.732] Timer tick, asking Buildbucket for the build status
[21:32:06.119] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:06.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:45:0) after 1m0s
[21:33:06.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:45:0)
[21:33:06.145] Timer tick, asking Buildbucket for the build status
[21:33:06.265] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:06.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:46:0) after 1m0s
[21:34:06.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:46:0)
[21:34:06.289] Timer tick, asking Buildbucket for the build status
[21:34:06.435] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:06.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:47:0) after 1m0s
[21:35:06.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:47:0)
[21:35:06.520] Timer tick, asking Buildbucket for the build status
[21:35:06.631] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:06.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:48:0) after 1m0s
[21:36:06.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:48:0)
[21:36:06.711] Timer tick, asking Buildbucket for the build status
[21:36:06.830] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:06.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:49:0) after 1m0s
[21:37:06.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:49:0)
[21:37:06.852] Timer tick, asking Buildbucket for the build status
[21:37:07.009] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:07.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:50:0) after 1m0s
[21:38:07.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:50:0)
[21:38:07.054] Timer tick, asking Buildbucket for the build status
[21:38:07.176] Build 8856103897406865792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:07.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021533959491748336:51:0) after 1m0s
[21:38:44.443] Received PubSub notification, asking Buildbucket for the build status
[21:38:44.787] Build 8856103897406865792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:38:44.788] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612561123212029",
"created_by": "project:chromium",
"created_ts": "1612558191825999",
"id": "8856103897406865792",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25cfdde6d4242d128c8a49bd3dda7d980d4058d3\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"25cfdde6d4242d128c8a49bd3dda7d980d4058d3\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-11-1hhh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45179, \"got_angle_revision\": \"3bb5f1ff26bccc4f409cedd196e9749f76bd6869\", \"got_dawn_revision\": \"185c6a5b0f358c115e3d3ef43afbb157d4c4b591\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"25cfdde6d4242d128c8a49bd3dda7d980d4058d3\", \"got_revision_cp\": \"refs/heads/master@{#851265}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a8da847d13385bb1c4322ae3c456e4896976c93b\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25cfdde6d4242d128c8a49bd3dda7d980d4058d3\", \"swarm_hashes_refs/heads/master(at){#851265}_without_patch\": {\"absl_hardening_tests\": \"5ee658e7d42aaaadb375a4bc4478094a6596eaee\", \"accessibility_unittests\": \"840b010d70509bd637f176e361b2dd957fb5edd5\", \"angle_unittests\": \"47a87c7688957341ce7243e938934ad0a9d0b1cc\", \"app_list_unittests\": \"254066576d78d32a714af569aacafb2e2185e58d\", \"app_shell_unittests\": \"2c4f99b8868f1cf4ff1eddda79a5b4d875681879\", \"ash_unittests\": \"be3953550336ff682f500612682f317d1a47e5e3\", \"aura_unittests\": \"803b17061004d6ffe879ceb54238f6edc7f1142c\", \"base_unittests\": \"64751051e306ea8308bf0f2d01ff47ce7b046e30\", \"base_util_unittests\": \"232aba06a245ef8158203c300e8067221ab24d97\", \"blink_common_unittests\": \"8c09d005f8a0f7e5d0e08cb5d6c96b7a458a0abb\", \"blink_fuzzer_unittests\": \"3eee08f9b627525c24402b8d2e872aa9b16a077b\", \"blink_heap_unittests\": \"4dcd5a753048c6366bbc533cf8e5209d8005a03a\", \"blink_platform_unittests\": \"f4e1805ab23de1a89d21205952fcc42ae1036ad8\", \"blink_unittests\": \"1d602f031ed503b5a597ccc25e03d68523ea9293\", \"boringssl_crypto_tests\": \"32b5eb1bbf81dbb3c28168c900b9bc2bc2a6aebe\", \"boringssl_ssl_tests\": \"50d1b2a3caf5036ee221a2a6a0e19ad55e7b6f63\", \"browser_tests\": \"2e548e33093f1a1a1a4ce9a281206730db89c384\", \"capture_unittests\": \"0108567094b8987fe12851db4f75d4668830f8f9\", \"cast_unittests\": \"6d2ec0bd3d92de4560b46353831309d1fdae2adf\", \"cc_unittests\": \"d3bfeb3ebcea6652eed2366a604cfa4a9c2edc3f\", \"chrome_app_unittests\": \"8fb3f736a0d180116fe0974de52a69ffa1eca911\", \"chromedriver_unittests\": \"2696995a009986114260296b213aae6aa08e7566\", \"chromeos_components_unittests\": \"f0b321c73003163dfe98033a65926400b1d6fa8b\", \"chromeos_unittests\": \"e831456e8ac6388def451ca431819a9ade47af56\", \"components_browsertests\": \"20ce76c74f1918d709cfdc70ced8c255216bcc2f\", \"components_unittests\": \"8404a8a4df3adf154926ee79d4de648f000e6460\", \"compositor_unittests\": \"b6267f85ca079d70b681e0ac04b7589a25cf4770\", \"content_browsertests\": \"6000d773cbcfebd4c6ebc7bfe094d097d418d2d6\", \"content_unittests\": \"01e8b4e6ab401de17b3878a95eee79e24726cc80\", \"crashpad_tests\": \"815b9e405b124e772d75107fda66c6b82b97e5d9\", \"crypto_unittests\": \"f9d6ae7ae7bab5a0f1ad8d99468360c96d939cab\", \"dbus_unittests\": \"92463fcc6cf493ee3761d5f8a61cb2e1e2b6fd3a\", \"device_unittests\": \"c6ccb67a8ae9d9e5a557ca874fb68337653c5f1b\", \"display_unittests\": \"587b02f092c1a0a10691ecd1b7cc77716fa90fec\", \"events_unittests\": \"114912475c95831a91075bfb9afb51ea563b6d7e\", \"exo_unittests\": \"67f9970872a33a220efa5083df7b9b68994303f0\", \"extensions_browsertests\": \"73a48328f686a631c09adc8b66803f2f7cd09917\", \"extensions_unittests\": \"f05e63ca8c1d729a22445ffe1b2b90ba7132ec7a\", \"filesystem_service_unittests\": \"1a4fe585d7d575ac1d28580a729ccf87776b6151\", \"gcm_unit_tests\": \"b276987a0be96f6464b9e08a05768b90b30ed14a\", \"gfx_unittests\": \"918d2de71fc404520ace11fcfe13abfc99a7872d\", \"gin_unittests\": \"3c50408198f3fce0c6bf6b6db225d1b710efb41d\", \"gl_unittests_ozone\": \"9c06c58ad1fa779068a9c38cee29367cd3318ffe\", \"google_apis_unittests\": \"0caddc56dd7f05d71e98bec9e6d3aa199dcfc9a0\", \"gpu_unittests\": \"843ab127b9bf342fa567f730fff314e97516fb1c\", \"gwp_asan_unittests\": \"17761ed2ca649f0d7cd5303c40b37e832413db0e\", \"interactive_ui_tests\": \"fef4e7cb5741ed6e332cc86218bd036b26caaff3\", \"ipc_tests\": \"494dc6b189a7f88e0d328a2919044617a12a0c4e\", \"jingle_unittests\": \"9e365b2d91f99fd285ad155891139c9a423f8585\", \"keyboard_unittests\": \"f6bd9f38ba58885f657d4bf7b0adb6562e130853\", \"latency_unittests\": \"343b782120d09b6c82bb0618805b19e2db40a280\", \"libjingle_xmpp_unittests\": \"fe9c48d705040192d10b4b91d2b34d70cdd3de6f\", \"liburlpattern_unittests\": \"f81f792e246c9020377d6baea841f1ebc289ef6b\", \"media_blink_unittests\": \"b45f57a78a454afff069fee3c37009d0b7db8cf0\", \"media_unittests\": \"0cca113e187110e209aadb58efa9b092c45bd944\", \"message_center_unittests\": \"a44a2d9ec1e520df218b126b3da328ce2f844815\", \"midi_unittests\": \"501f1d067480f10f3f11686993ed03e3e1576804\", \"mojo_core_unittests\": \"bc6adc7b88d2819508929ffea26224523d6bd325\", \"mojo_unittests\": \"28d150e27e66f9c0b79ae354afaf74599eada22c\", \"nacl_helper_nonsfi_unittests\": \"b7aee1a8245bb638b8d6c57fa4687eba2e178a70\", \"nacl_loader_unittests\": \"23ae95fc7826e361b550d314da02b442003f5f96\", \"native_theme_unittests\": \"7635c9febf49cf25a3f65404348649ca41bee6bb\", \"net_unittests\": \"4729997d112db9371cdc787107b82d2becc6cc47\", \"ozone_gl_unittests\": \"1273cf5bf24fb76cb050e337791e5c56717fbfe7\", \"ozone_unittests\": \"2d9e13880ea8bdf428c5187d8d4156b05e01a489\", \"ozone_x11_unittests\": \"0519c51892800cb32900e1154b65c0af4eef9ea6\", \"pdf_unittests\": \"8793475b79c63196d35bf00cb19f835874e46920\", \"perfetto_unittests\": \"97b4849f08815db22725d9447560b80503e56ab4\", \"ppapi_unittests\": \"b7612491b369e477a24f648c47db222c95328797\", \"printing_unittests\": \"3ec9204989500bb592eb6736a110b8c3d59de65c\", \"remoting_unittests\": \"dcf28d5fb49e4a4065160e3f469980deb6f5612f\", \"sandbox_linux_unittests\": \"064e6b7ecd69470f227c4ea35569edf029980aa3\", \"service_manager_unittests\": \"942c874bf13d58fdeedc5edbcb987dd6a23cfde3\", \"services_unittests\": \"f417cdedec9d6508eb3219c244a4e8fcddc1ffbe\", \"shell_dialogs_unittests\": \"37cd3a3c80f2375c538dc507a948310a6dd37111\", \"shell_encryption_unittests\": \"665183d04cf43ed8abc58e9d3c5eea14d73c6168\", \"skia_unittests\": \"2afdd423f51aed2b889039585baaf69e143ab0e0\", \"snapshot_unittests\": \"343fb00f47509eba9492f59fd7c00fb168287f83\", \"sql_unittests\": \"487c7864540bc0e80e0bb92cb7da66b6b00f4522\", \"storage_unittests\": \"5ed61d7f04d0e614fc3401a6594bd45625e35ff3\", \"sync_integration_tests\": \"d401c693f62aeb0e3c5c034226b8b5b80881b06a\", \"traffic_annotation_auditor_unittests\": \"399ffdaf7cda74b9a0004e5a647139eaa6d74e8b\", \"ui_base_unittests\": \"20a4d6c5f61401cfac9f2ed1e2f57e305183f347\", \"ui_chromeos_unittests\": \"f90f87eae35292414c196aa9390570d9992e8905\", \"ui_touch_selection_unittests\": \"59de2908e7fdabb232601277f667b4d621c97762\", \"unit_tests\": \"af4dbdbf590fba33936b8a4b3828e0186969608f\", \"url_unittests\": \"16ed7adffe38d1df625efae3cb7d6bb2727ec49a\", \"usage_time_limit_unittests\": \"c9cb1188b16d5175761484b432715d359c7d4d27\", \"views_unittests\": \"b2901c49ee3654a00367a3ded29afa0eb814176d\", \"viz_unittests\": \"b7db55821f06611b605f968025043c934da3f8b1\", \"wayland_client_perftests\": \"5251ab74995905c2f7cf752b68e275cf067b625b\", \"wm_unittests\": \"d21048b70c86be7e645a5174db09eba548e746a8\", \"wtf_unittests\": \"9464dd2721ad96f68f4eefcdb8691f420b510b44\", \"zlib_unittests\": \"6f8ad9fabe5e39a6e9d77663e8b578ebf27e6a94\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4026221a727cf2e856e94035b8eeb49c4c65d62e8974e693170551540c198531_v2\", \"builder_47c3ec4238d3b3d0448e41826f5b2c1435e77a4255b8c93aa27077a59a7a5a67_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_b13f895f2fa532c56bcd54736f154d6ea330771f795358111cd0095a10831ab4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-11-1hhh\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612558199622265",
"status": "COMPLETED",
"status_changed_ts": "1612561123848728",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45179",
"builder:linux-chromeos-rel",
"buildset:commit/git/25cfdde6d4242d128c8a49bd3dda7d980d4058d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25cfdde6d4242d128c8a49bd3dda7d980d4058d3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021533959491748336",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856103897406865792/+/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:518cc6d833f2a010",
"user_agent:luci-scheduler"
],
"updated_ts": "1612561123853972",
"url": "https://ci.chromium.org/b/8856103897406865792",
"utcnow_ts": "1612561124720195"
}
}
[21:38:44.788] Invocation finished in 48m54.334123925s with status SUCCEEDED