[22:16:27.168] New invocation is queued and will start shortly
[22:16:28.298] Starting the invocation (attempt 1)
[22:16:28.344] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:16:28.344] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:16:28.344] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029863431271430464",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d65e99075824eef3c95f12c1975b4179c51e309\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029863431271430464",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4d65e99075824eef3c95f12c1975b4179c51e309",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d65e99075824eef3c95f12c1975b4179c51e309",
"gitiles_ref:refs/heads/master"
]
}
[22:16:28.958] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604614588473357",
"id": "8864433369235306848",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d65e99075824eef3c95f12c1975b4179c51e309\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604614588824473",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42615",
"builder:linux-chromeos-rel",
"buildset:commit/git/4d65e99075824eef3c95f12c1975b4179c51e309",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d65e99075824eef3c95f12c1975b4179c51e309",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029863431271430464",
"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/8864433369235306848/+/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": "1604614588824647",
"url": "https://ci.chromium.org/b/8864433369235306848",
"utcnow_ts": "1604614588943204"
}
}
[22:16:28.959] Task URL: https://ci.chromium.org/b/8864433369235306848
[22:16:28.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:2:0) after 1m0s
[22:16:31.935] Received PubSub notification, asking Buildbucket for the build status
[22:16:32.153] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:28.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:2:0)
[22:17:28.949] Timer tick, asking Buildbucket for the build status
[22:17:29.099] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:29.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:4:0) after 1m0s
[22:18:29.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:4:0)
[22:18:29.166] Timer tick, asking Buildbucket for the build status
[22:18:29.635] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:29.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:5:0) after 1m0s
[22:19:29.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:5:0)
[22:19:29.688] Timer tick, asking Buildbucket for the build status
[22:19:29.811] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:29.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:6:0) after 1m0s
[22:20:29.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:6:0)
[22:20:29.891] Timer tick, asking Buildbucket for the build status
[22:20:30.119] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:30.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:7:0) after 1m0s
[22:21:30.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:7:0)
[22:21:30.148] Timer tick, asking Buildbucket for the build status
[22:21:30.609] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:30.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:8:0) after 1m0s
[22:22:30.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:8:0)
[22:22:30.631] Timer tick, asking Buildbucket for the build status
[22:22:30.812] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:30.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:9:0) after 1m0s
[22:23:30.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:9:0)
[22:23:30.844] Timer tick, asking Buildbucket for the build status
[22:23:31.024] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:31.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:10:0) after 1m0s
[22:24:31.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:10:0)
[22:24:31.025] Timer tick, asking Buildbucket for the build status
[22:24:31.240] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:31.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:11:0) after 1m0s
[22:25:31.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:11:0)
[22:25:31.275] Timer tick, asking Buildbucket for the build status
[22:25:31.350] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:31.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:12:0) after 1m0s
[22:26:31.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:12:0)
[22:26:31.468] Timer tick, asking Buildbucket for the build status
[22:26:31.641] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:31.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:13:0) after 1m0s
[22:27:31.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:13:0)
[22:27:31.721] Timer tick, asking Buildbucket for the build status
[22:27:31.852] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:31.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:14:0) after 1m0s
[22:28:31.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:14:0)
[22:28:31.912] Timer tick, asking Buildbucket for the build status
[22:28:31.977] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:31.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:15:0) after 1m0s
[22:29:32.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:15:0)
[22:29:32.007] Timer tick, asking Buildbucket for the build status
[22:29:32.083] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:32.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:16:0) after 1m0s
[22:30:32.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:16:0)
[22:30:32.108] Timer tick, asking Buildbucket for the build status
[22:30:32.170] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:32.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:17:0) after 1m0s
[22:31:32.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:17:0)
[22:31:32.224] Timer tick, asking Buildbucket for the build status
[22:31:32.536] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:32.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:18:0) after 1m0s
[22:32:32.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:18:0)
[22:32:32.602] Timer tick, asking Buildbucket for the build status
[22:32:33.117] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:33.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:19:0) after 1m0s
[22:33:33.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:19:0)
[22:33:33.137] Timer tick, asking Buildbucket for the build status
[22:33:33.203] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:33.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:20:0) after 1m0s
[22:34:33.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:20:0)
[22:34:33.259] Timer tick, asking Buildbucket for the build status
[22:34:33.332] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:33.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:21:0) after 1m0s
[22:35:33.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:21:0)
[22:35:33.339] Timer tick, asking Buildbucket for the build status
[22:35:33.452] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:33.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:22:0) after 1m0s
[22:36:33.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:22:0)
[22:36:33.507] Timer tick, asking Buildbucket for the build status
[22:36:33.620] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:33.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:23:0) after 1m0s
[22:37:33.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:23:0)
[22:37:33.659] Timer tick, asking Buildbucket for the build status
[22:37:33.791] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:33.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:24:0) after 1m0s
[22:38:33.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:24:0)
[22:38:33.855] Timer tick, asking Buildbucket for the build status
[22:38:34.048] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:34.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:25:0) after 1m0s
[22:39:34.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:25:0)
[22:39:34.116] Timer tick, asking Buildbucket for the build status
[22:39:34.210] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:34.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:26:0) after 1m0s
[22:40:34.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:26:0)
[22:40:34.240] Timer tick, asking Buildbucket for the build status
[22:40:36.181] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:36.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:27:0) after 1m0s
[22:41:36.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:27:0)
[22:41:36.216] Timer tick, asking Buildbucket for the build status
[22:41:36.337] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:36.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:28:0) after 1m0s
[22:42:36.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:28:0)
[22:42:36.391] Timer tick, asking Buildbucket for the build status
[22:42:36.458] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:36.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:29:0) after 1m0s
[22:43:36.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:29:0)
[22:43:36.514] Timer tick, asking Buildbucket for the build status
[22:43:36.836] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:36.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:30:0) after 1m0s
[22:44:36.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:30:0)
[22:44:36.883] Timer tick, asking Buildbucket for the build status
[22:44:36.944] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:36.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:31:0) after 1m0s
[22:45:37.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:31:0)
[22:45:37.062] Timer tick, asking Buildbucket for the build status
[22:45:37.143] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:37.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:32:0) after 1m0s
[22:46:37.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:32:0)
[22:46:37.264] Timer tick, asking Buildbucket for the build status
[22:46:37.400] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:37.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:33:0) after 1m0s
[22:47:37.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:33:0)
[22:47:37.465] Timer tick, asking Buildbucket for the build status
[22:47:37.539] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:37.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:34:0) after 1m0s
[22:48:37.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:34:0)
[22:48:37.801] Timer tick, asking Buildbucket for the build status
[22:48:37.880] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:37.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:35:0) after 1m0s
[22:49:38.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:35:0)
[22:49:38.039] Timer tick, asking Buildbucket for the build status
[22:49:38.118] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:36:0) after 1m0s
[22:50:38.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:36:0)
[22:50:38.125] Timer tick, asking Buildbucket for the build status
[22:50:38.324] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:38.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:37:0) after 1m0s
[22:51:38.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:37:0)
[22:51:38.440] Timer tick, asking Buildbucket for the build status
[22:51:38.547] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:38.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:38:0) after 1m0s
[22:52:38.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:38:0)
[22:52:38.688] Timer tick, asking Buildbucket for the build status
[22:52:38.784] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:38.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:39:0) after 1m0s
[22:53:38.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:39:0)
[22:53:38.928] Timer tick, asking Buildbucket for the build status
[22:53:39.006] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:39.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:40:0) after 1m0s
[22:54:39.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:40:0)
[22:54:39.034] Timer tick, asking Buildbucket for the build status
[22:54:39.120] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:39.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:41:0) after 1m0s
[22:55:39.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:41:0)
[22:55:39.131] Timer tick, asking Buildbucket for the build status
[22:55:39.231] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:39.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:42:0) after 1m0s
[22:56:39.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:42:0)
[22:56:39.262] Timer tick, asking Buildbucket for the build status
[22:56:39.646] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:39.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:43:0) after 1m0s
[22:57:39.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:43:0)
[22:57:39.672] Timer tick, asking Buildbucket for the build status
[22:57:39.799] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:39.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:44:0) after 1m0s
[22:58:39.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:44:0)
[22:58:39.848] Timer tick, asking Buildbucket for the build status
[22:58:39.922] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:39.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:45:0) after 1m0s
[22:59:39.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:45:0)
[22:59:39.964] Timer tick, asking Buildbucket for the build status
[22:59:40.164] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:40.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:46:0) after 1m0s
[23:00:40.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:46:0)
[23:00:40.203] Timer tick, asking Buildbucket for the build status
[23:00:40.367] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:40.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:47:0) after 1m0s
[23:01:40.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:47:0)
[23:01:40.485] Timer tick, asking Buildbucket for the build status
[23:01:40.878] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:40.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:48:0) after 1m0s
[23:02:41.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:48:0)
[23:02:41.020] Timer tick, asking Buildbucket for the build status
[23:02:41.515] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:41.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:49:0) after 1m0s
[23:03:41.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:49:0)
[23:03:41.596] Timer tick, asking Buildbucket for the build status
[23:03:41.866] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:41.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:50:0) after 1m0s
[23:04:41.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:50:0)
[23:04:41.908] Timer tick, asking Buildbucket for the build status
[23:04:42.031] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:42.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:51:0) after 1m0s
[23:05:42.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:51:0)
[23:05:42.072] Timer tick, asking Buildbucket for the build status
[23:05:42.198] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:42.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:52:0) after 1m0s
[23:06:42.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:52:0)
[23:06:42.241] Timer tick, asking Buildbucket for the build status
[23:06:42.959] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:42.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:53:0) after 1m0s
[23:07:43.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:53:0)
[23:07:43.074] Timer tick, asking Buildbucket for the build status
[23:07:43.580] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:43.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:54:0) after 1m0s
[23:08:43.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:54:0)
[23:08:43.597] Timer tick, asking Buildbucket for the build status
[23:08:43.748] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:43.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:55:0) after 1m0s
[23:09:43.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:55:0)
[23:09:43.846] Timer tick, asking Buildbucket for the build status
[23:09:43.993] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:43.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:56:0) after 1m0s
[23:10:44.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:56:0)
[23:10:44.021] Timer tick, asking Buildbucket for the build status
[23:10:44.166] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:44.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:57:0) after 1m0s
[23:11:44.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:57:0)
[23:11:44.190] Timer tick, asking Buildbucket for the build status
[23:11:44.331] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:44.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:58:0) after 1m0s
[23:12:44.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:58:0)
[23:12:44.358] Timer tick, asking Buildbucket for the build status
[23:12:44.562] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:44.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:59:0) after 1m0s
[23:13:44.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:59:0)
[23:13:44.650] Timer tick, asking Buildbucket for the build status
[23:13:44.801] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:44.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:60:0) after 1m0s
[23:14:44.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:60:0)
[23:14:44.822] Timer tick, asking Buildbucket for the build status
[23:14:44.955] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:44.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:61:0) after 1m0s
[23:15:44.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:61:0)
[23:15:44.967] Timer tick, asking Buildbucket for the build status
[23:15:45.126] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:45.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:62:0) after 1m0s
[23:16:45.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:62:0)
[23:16:45.161] Timer tick, asking Buildbucket for the build status
[23:16:45.297] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:45.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:63:0) after 1m0s
[23:17:45.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:63:0)
[23:17:45.321] Timer tick, asking Buildbucket for the build status
[23:17:45.461] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:45.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:64:0) after 1m0s
[23:18:45.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:64:0)
[23:18:45.439] Timer tick, asking Buildbucket for the build status
[23:18:45.714] Build 8864433369235306848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:45.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029863431271430464:65:0) after 1m0s
[23:18:46.590] Received PubSub notification, asking Buildbucket for the build status
[23:18:46.993] Build 8864433369235306848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:18:46.993] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604618324412825",
"created_by": "project:chromium",
"created_ts": "1604614588473357",
"id": "8864433369235306848",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d65e99075824eef3c95f12c1975b4179c51e309\"}}",
"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\": \"4d65e99075824eef3c95f12c1975b4179c51e309\", \"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-76-1638\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42615, \"got_angle_revision\": \"56ea854ec5e74b2f8e9b4ed7967b56c4c2a67bc9\", \"got_dawn_revision\": \"d42713de7aa3227f1feb405beddac4e37051b679\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"4d65e99075824eef3c95f12c1975b4179c51e309\", \"got_revision_cp\": \"refs/heads/master@{#824606}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"368dd750e2f684f0cb1f7527494676d69d7b8f76\", \"got_v8_revision_cp\": \"refs/heads/8.8.240@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d65e99075824eef3c95f12c1975b4179c51e309\", \"swarm_hashes_refs/heads/master(at){#824606}_without_patch\": {\"absl_hardening_tests\": \"81c346e525eafd45c6c5a3ac9da13c728b2b753e\", \"accessibility_unittests\": \"2e2ec52329504832c7d782837d99bb2d180921e1\", \"angle_unittests\": \"58805771ed9d106d89ff1a2544e7948fd374d6d8\", \"app_list_unittests\": \"093bdc77a04cb4bc109ec6d2cb041d75a44192e3\", \"app_shell_unittests\": \"e921aa639854ddff31e77d61c16449d174987edb\", \"ash_unittests\": \"e77e65886e1f7138e7d90f8a657510b43f014d00\", \"aura_unittests\": \"205f4cebb030312e8a86713f43a0a1f369e41b98\", \"base_unittests\": \"4656f0771cfdbc354ffd8146c04672cf4c9cd46e\", \"base_util_unittests\": \"513986a7595d8d4eaaeea0efc139cfd6288eb327\", \"blink_common_unittests\": \"74f4a374a8747385a437b2e1ea625dff4a82b92b\", \"blink_fuzzer_unittests\": \"6d1aa25d9114cd4681d800b8066fa48606ad2bc0\", \"blink_heap_unittests\": \"5abb7d4e93ea1c2e2c5e2bd45967295b6a8c5317\", \"blink_platform_unittests\": \"b91d641fe2c19acbba0b5417e8c90abfd09bba72\", \"blink_unittests\": \"67c085bf83887d8a54e1ae0b4b160975534b7ae1\", \"boringssl_crypto_tests\": \"6b4606b63ab07ff5b489792421d9324a0a707d17\", \"boringssl_ssl_tests\": \"e19d70869d8d209b7c59c15155e1a491921abef5\", \"browser_tests\": \"6d099544112e3eed71ca6ba3431c12154cc4bdd7\", \"capture_unittests\": \"c1727ad7d8beb495c44f0fa064b2ea67454fd70c\", \"cast_unittests\": \"f8e920149f8b00c9e07311698845747b0f7a53c7\", \"cc_unittests\": \"3cb33caa38cd32086275d7acd27792e7354ec449\", \"chrome_app_unittests\": \"496c61d62e183e47c3da1a046b63bbfdf148e26b\", \"chromedriver_unittests\": \"4ed7804aee8fb3322ab049c7911bdd3299d088df\", \"chromeos_components_unittests\": \"2f17ca5f6b2a3bf1f0ab75dd0ba5add677075b85\", \"chromeos_unittests\": \"94cc3bdd752162c9794f71fd189835c6abf1100d\", \"components_browsertests\": \"1061759e01de5a36b990d1dea12ee9415bdf93b5\", \"components_unittests\": \"3d5245f921b203c43e6bab859f7a22839006fdda\", \"compositor_unittests\": \"12a8ebeddfaaf166158a87f3921d6e7f436652fc\", \"content_browsertests\": \"bfd68464aa36c0a21de1a7bacc7b68a346d909ae\", \"content_unittests\": \"60d3692ff58e0337eca211093ff68741df3403fa\", \"crypto_unittests\": \"1c3d9cd75801804d4f7724bcfe6ff86c121688ba\", \"dbus_unittests\": \"07327001f631ee6a36b5f75e52fe4e585ff3dc82\", \"device_unittests\": \"a9a9cb927c70237c1ff49da9b8420786ddf5c8b2\", \"display_unittests\": \"fc74cb7f5de5b99ef8832df32c5e075dd68c6de0\", \"events_unittests\": \"2d81108ea4bccec53c429b43022cf52c5f0ed0aa\", \"exo_unittests\": \"11da9cc6b8d42918b3fe99bfb462e51b4b9f32ad\", \"extensions_browsertests\": \"16291dc061cc02045286faac64bd0a8b9dda1cb6\", \"extensions_unittests\": \"44018d8c464f459072126e8bc07316209e909839\", \"filesystem_service_unittests\": \"5be4f07b78c1b83838e1fe1878dac1366d37c892\", \"gcm_unit_tests\": \"1d762777ee7cda924f6d95e085b4500914372084\", \"gfx_unittests\": \"65d1330887ca522f1cf9564662c7865622a1b0a8\", \"gin_unittests\": \"09dfb1acaf4687db7a4b03ce61f5b7ae24d2a0ff\", \"gl_unittests_ozone\": \"035458c20ce6468b7c7d7586a0b5caf0f8989ec4\", \"google_apis_unittests\": \"2aaeeb51ad62279f0a4c12af68d6babc9193477a\", \"gpu_unittests\": \"4cdb2a04f649faee525d17b13ea078b1be80cb58\", \"gwp_asan_unittests\": \"120b873323038b351c59e2a00e22a941c511ee80\", \"interactive_ui_tests\": \"3bc587e18f066dcbc3452775c3c9c190d0b0fd5f\", \"ipc_tests\": \"76375780a377ef697eff9523697fb4bd64d184c8\", \"jingle_unittests\": \"a55b974e8050f7fc149466f8c6a93378a24e4fd8\", \"keyboard_unittests\": \"be08197c714466a7f90b3b07ae501d1ae552ede3\", \"latency_unittests\": \"a27c509ea8645aee69ef971579eae51ead94af1a\", \"libjingle_xmpp_unittests\": \"813a3218b1786f9323da73b6f3bc8d7ca5058ab6\", \"liburlpattern_unittests\": \"66e2748468b91d22a2943075c484dce377b7cb28\", \"media_blink_unittests\": \"928cb110c7b981bbb403eb7804be68f0ae54b6bf\", \"media_unittests\": \"cafd153ca46749d052d7301df99b38a485b8f86d\", \"message_center_unittests\": \"0c9864f01fbb95c86e54dda6daa654b064aadb82\", \"midi_unittests\": \"bb7f4bdc889063d13dba17f034f31f825541e94e\", \"mojo_core_unittests\": \"40edcf912ef97eb0a0bc91f2395c2d243cfc2d34\", \"mojo_unittests\": \"a8fc1765cbd2e6878ac8d757384c570259c5a81e\", \"nacl_helper_nonsfi_unittests\": \"7776cb3c41d26ede9a21b62b7f3295b1e38f0846\", \"nacl_loader_unittests\": \"8b6c9362ebfc6e3383073f19a1a880527034ae56\", \"native_theme_unittests\": \"7b903b7843dcac43aa65a79ce15a363434e91441\", \"net_unittests\": \"fb73217ffcb37d41d0e63d270b3b3c5501bd9d17\", \"ozone_gl_unittests\": \"db625ea0043e8a632cb1e1ddebed5898dce23256\", \"ozone_unittests\": \"1edb95018fc048bc8e4ddb6bc425c790d8376028\", \"ozone_x11_unittests\": \"42241d032664947efff1e99541ec94985abe799b\", \"pdf_unittests\": \"12672e4678b9490cf02b6e92fff338459b88c5ec\", \"perfetto_unittests\": \"3b8d30a86e8ba694a0449403c4e7b64cc18582c4\", \"ppapi_unittests\": \"5ce9dcc3e3a6dd26a72c624dd67d306672a3106e\", \"printing_unittests\": \"ae5bb55b303bce50d5f888a212b501c66b5f7e3e\", \"remoting_unittests\": \"c44c0f865cbf8fb54683f7061077636c0219c776\", \"sandbox_linux_unittests\": \"e72754b73b099fb1b9922f75617606359f1ff1ad\", \"service_manager_unittests\": \"9c8be06781a1f95f15d90400c9052f93bf07504d\", \"services_unittests\": \"4f8eedb6e2649009b2fbf9684f35214b572a177b\", \"shell_dialogs_unittests\": \"fa69887b7efcd75c6c5e214211988f0e33de72f2\", \"skia_unittests\": \"ebd926c8acb8664b4cdfadaef36bfed534a2bf45\", \"snapshot_unittests\": \"934f5a1001a74c0aa2b0c13f8b48bd05b3d0de6a\", \"sql_unittests\": \"742cb650f385ecf3660f5ea8f1659fb18a45dd2c\", \"storage_unittests\": \"53832bb1e04345e71fe6390a8a0702ee2e0a7344\", \"sync_integration_tests\": \"48ba766e5a922efe1b58def4f1b84a573418a405\", \"traffic_annotation_auditor_unittests\": \"ee132c8cdc83959a716bbfde3bea5db547a6d799\", \"ui_base_unittests\": \"f21a18971efe75f810c6adc9fcdcfbc1bb7c0b8f\", \"ui_chromeos_unittests\": \"b9a236231b4033aed9a532d66a71d000279aa91f\", \"ui_touch_selection_unittests\": \"231373d033cea951b85d4e88320591d749cdeeea\", \"unit_tests\": \"30b2bb6adb4c89d3ea9f8003ef53d46909ddecc1\", \"url_unittests\": \"7d56d94095f8ed06561a41fd7ee1c730b6592914\", \"usage_time_limit_unittests\": \"342cbff4aa22a120d87d2645634048d73a0dd145\", \"views_unittests\": \"ba83b64281f1af1a05fe1600a0f064cbeecd5340\", \"viz_unittests\": \"4eaf064be17baf24802ab6859946c8df2d3bac76\", \"wayland_client_perftests\": \"532bc1c0190ebdbd53c262480d19f321f7fa21ad\", \"wm_unittests\": \"8af62843b19869178432f2cd3b2215e0f6f64488\", \"wtf_unittests\": \"65f68ddd4cd45a348e6d783a48b1a10336fa41b0\", \"zlib_unittests\": \"498a49e98d045a2547134bdfc239f511fae18913\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_7ed5e98f023cd9e0abec02df990f9772adc652ea96b625245e768b064914efaf_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_953e900270162ec95f568ea264af47d43b9a29a15e01ede040ffa4d15b188919_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_e47700e9abf1b62c06bb1feb4592b8e752d42e12f6359b4e93db4847c43379d6_v2\", \"builder_ee5c11e7a332f55fd7a24d7319a67a6829cd4b81b48e997032d91622b214e82b_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-76-1638\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604614590511244",
"status": "COMPLETED",
"status_changed_ts": "1604618324800808",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42615",
"builder:linux-chromeos-rel",
"buildset:commit/git/4d65e99075824eef3c95f12c1975b4179c51e309",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d65e99075824eef3c95f12c1975b4179c51e309",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029863431271430464",
"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/8864433369235306848/+/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:4fb34d141fc8b310",
"user_agent:luci-scheduler"
],
"updated_ts": "1604618324808337",
"url": "https://ci.chromium.org/b/8864433369235306848",
"utcnow_ts": "1604618326974676"
}
}
[23:18:46.993] Invocation finished in 1h2m19.844726027s with status SUCCEEDED