[02:38:34.453] New invocation is queued and will start shortly
[02:38:35.524] Starting the invocation (attempt 1)
[02:38:35.565] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:38:35.565] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:38:35.565] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019700079774333232",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4f0630b83f2ca0aac183f1fa431d9134f13cfbf8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019700079774333232",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4f0630b83f2ca0aac183f1fa431d9134f13cfbf8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f0630b83f2ca0aac183f1fa431d9134f13cfbf8",
"gitiles_ref:refs/heads/master"
]
}
[02:38:37.329] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614307115663027",
"id": "8854270017844554960",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f0630b83f2ca0aac183f1fa431d9134f13cfbf8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614307116159715",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45744",
"builder:linux-chromeos-rel",
"buildset:commit/git/4f0630b83f2ca0aac183f1fa431d9134f13cfbf8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f0630b83f2ca0aac183f1fa431d9134f13cfbf8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019700079774333232",
"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/8854270017844554960/+/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": "1614307116159863",
"url": "https://ci.chromium.org/b/8854270017844554960",
"utcnow_ts": "1614307117318149"
}
}
[02:38:37.329] Task URL: https://ci.chromium.org/b/8854270017844554960
[02:38:37.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:2:0) after 1m0s
[02:38:38.164] Received PubSub notification, asking Buildbucket for the build status
[02:38:38.272] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:37.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:2:0)
[02:39:37.394] Timer tick, asking Buildbucket for the build status
[02:39:37.464] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:37.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:4:0) after 1m0s
[02:40:37.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:4:0)
[02:40:37.487] Timer tick, asking Buildbucket for the build status
[02:40:37.613] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:37.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:5:0) after 1m0s
[02:41:37.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:5:0)
[02:41:37.645] Timer tick, asking Buildbucket for the build status
[02:41:37.787] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:37.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:6:0) after 1m0s
[02:42:37.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:6:0)
[02:42:37.843] Timer tick, asking Buildbucket for the build status
[02:42:37.966] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:37.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:7:0) after 1m0s
[02:43:38.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:7:0)
[02:43:38.054] Timer tick, asking Buildbucket for the build status
[02:43:38.202] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:38.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:8:0) after 1m0s
[02:44:38.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:8:0)
[02:44:38.384] Timer tick, asking Buildbucket for the build status
[02:44:38.636] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:38.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:9:0) after 1m0s
[02:45:38.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:9:0)
[02:45:38.811] Timer tick, asking Buildbucket for the build status
[02:45:38.881] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:38.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:10:0) after 1m0s
[02:46:38.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:10:0)
[02:46:38.960] Timer tick, asking Buildbucket for the build status
[02:46:39.114] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:39.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:11:0) after 1m0s
[02:47:39.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:11:0)
[02:47:39.146] Timer tick, asking Buildbucket for the build status
[02:47:39.411] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:39.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:12:0) after 1m0s
[02:48:39.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:12:0)
[02:48:39.472] Timer tick, asking Buildbucket for the build status
[02:48:39.969] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:39.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:13:0) after 1m0s
[02:49:40.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:13:0)
[02:49:40.003] Timer tick, asking Buildbucket for the build status
[02:49:40.442] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:40.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:14:0) after 1m0s
[02:50:40.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:14:0)
[02:50:40.459] Timer tick, asking Buildbucket for the build status
[02:50:40.751] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:40.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:15:0) after 1m0s
[02:51:40.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:15:0)
[02:51:40.801] Timer tick, asking Buildbucket for the build status
[02:51:41.007] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:41.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:16:0) after 1m0s
[02:52:41.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:16:0)
[02:52:41.017] Timer tick, asking Buildbucket for the build status
[02:52:41.138] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:41.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:17:0) after 1m0s
[02:53:41.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:17:0)
[02:53:41.110] Timer tick, asking Buildbucket for the build status
[02:53:41.212] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:41.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:18:0) after 1m0s
[02:54:41.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:18:0)
[02:54:41.258] Timer tick, asking Buildbucket for the build status
[02:54:41.351] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:41.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:19:0) after 1m0s
[02:55:41.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:19:0)
[02:55:41.398] Timer tick, asking Buildbucket for the build status
[02:55:41.714] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:41.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:20:0) after 1m0s
[02:56:41.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:20:0)
[02:56:41.712] Timer tick, asking Buildbucket for the build status
[02:56:41.791] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:41.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:21:0) after 1m0s
[02:57:41.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:21:0)
[02:57:41.814] Timer tick, asking Buildbucket for the build status
[02:57:41.888] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:41.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:22:0) after 1m0s
[02:58:41.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:22:0)
[02:58:41.908] Timer tick, asking Buildbucket for the build status
[02:58:42.007] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:42.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:23:0) after 1m0s
[02:59:42.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:23:0)
[02:59:42.014] Timer tick, asking Buildbucket for the build status
[02:59:42.094] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:42.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:24:0) after 1m0s
[03:00:42.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:24:0)
[03:00:42.122] Timer tick, asking Buildbucket for the build status
[03:00:42.424] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:42.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:25:0) after 1m0s
[03:01:42.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:25:0)
[03:01:42.835] Timer tick, asking Buildbucket for the build status
[03:01:43.006] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:43.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:26:0) after 1m0s
[03:02:43.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:26:0)
[03:02:43.078] Timer tick, asking Buildbucket for the build status
[03:02:43.199] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:43.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:27:0) after 1m0s
[03:03:43.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:27:0)
[03:03:43.265] Timer tick, asking Buildbucket for the build status
[03:03:43.450] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:43.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:28:0) after 1m0s
[03:04:43.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:28:0)
[03:04:43.510] Timer tick, asking Buildbucket for the build status
[03:04:43.578] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:43.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:29:0) after 1m0s
[03:05:43.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:29:0)
[03:05:43.811] Timer tick, asking Buildbucket for the build status
[03:05:43.877] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:43.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:30:0) after 1m0s
[03:06:44.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:30:0)
[03:06:44.036] Timer tick, asking Buildbucket for the build status
[03:06:44.134] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:44.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:31:0) after 1m0s
[03:07:44.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:31:0)
[03:07:44.181] Timer tick, asking Buildbucket for the build status
[03:07:44.398] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:44.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:32:0) after 1m0s
[03:08:44.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:32:0)
[03:08:44.430] Timer tick, asking Buildbucket for the build status
[03:08:44.670] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:44.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:33:0) after 1m0s
[03:09:44.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:33:0)
[03:09:44.741] Timer tick, asking Buildbucket for the build status
[03:09:44.817] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:44.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:34:0) after 1m0s
[03:10:44.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:34:0)
[03:10:44.890] Timer tick, asking Buildbucket for the build status
[03:10:45.283] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:45.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:35:0) after 1m0s
[03:11:45.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:35:0)
[03:11:45.340] Timer tick, asking Buildbucket for the build status
[03:11:45.447] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:45.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:36:0) after 1m0s
[03:12:45.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:36:0)
[03:12:45.431] Timer tick, asking Buildbucket for the build status
[03:12:45.659] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:45.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:37:0) after 1m0s
[03:13:45.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:37:0)
[03:13:45.679] Timer tick, asking Buildbucket for the build status
[03:13:45.813] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:45.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:38:0) after 1m0s
[03:14:45.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:38:0)
[03:14:45.830] Timer tick, asking Buildbucket for the build status
[03:14:45.905] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:45.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:39:0) after 1m0s
[03:15:46.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:39:0)
[03:15:46.027] Timer tick, asking Buildbucket for the build status
[03:15:46.509] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:46.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:40:0) after 1m0s
[03:16:46.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:40:0)
[03:16:46.563] Timer tick, asking Buildbucket for the build status
[03:16:46.814] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:46.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:41:0) after 1m0s
[03:17:46.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:41:0)
[03:17:46.909] Timer tick, asking Buildbucket for the build status
[03:17:46.990] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:46.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:42:0) after 1m0s
[03:18:47.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:42:0)
[03:18:47.008] Timer tick, asking Buildbucket for the build status
[03:18:47.726] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:47.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:43:0) after 1m0s
[03:19:47.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:43:0)
[03:19:47.771] Timer tick, asking Buildbucket for the build status
[03:19:47.914] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:47.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:44:0) after 1m0s
[03:20:48.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:44:0)
[03:20:48.085] Timer tick, asking Buildbucket for the build status
[03:20:48.200] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:48.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:45:0) after 1m0s
[03:21:48.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:45:0)
[03:21:48.303] Timer tick, asking Buildbucket for the build status
[03:21:48.401] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:48.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:46:0) after 1m0s
[03:22:48.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:46:0)
[03:22:48.577] Timer tick, asking Buildbucket for the build status
[03:22:48.687] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:48.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:47:0) after 1m0s
[03:23:48.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:47:0)
[03:23:48.707] Timer tick, asking Buildbucket for the build status
[03:23:49.167] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:49.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:48:0) after 1m0s
[03:24:49.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:48:0)
[03:24:49.209] Timer tick, asking Buildbucket for the build status
[03:24:49.306] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:49.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:49:0) after 1m0s
[03:25:49.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:49:0)
[03:25:49.349] Timer tick, asking Buildbucket for the build status
[03:25:49.753] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:49.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:50:0) after 1m0s
[03:26:49.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:50:0)
[03:26:49.770] Timer tick, asking Buildbucket for the build status
[03:26:49.858] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:49.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:51:0) after 1m0s
[03:27:49.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:51:0)
[03:27:49.876] Timer tick, asking Buildbucket for the build status
[03:27:50.126] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:50.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:52:0) after 1m0s
[03:28:50.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:52:0)
[03:28:50.143] Timer tick, asking Buildbucket for the build status
[03:28:50.246] Build 8854270017844554960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:50.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019700079774333232:53:0) after 1m0s
[03:28:58.379] Received PubSub notification, asking Buildbucket for the build status
[03:28:58.729] Build 8854270017844554960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:28:58.730] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614310137239660",
"created_by": "project:chromium",
"created_ts": "1614307115663027",
"id": "8854270017844554960",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f0630b83f2ca0aac183f1fa431d9134f13cfbf8\"}}",
"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\": \"4f0630b83f2ca0aac183f1fa431d9134f13cfbf8\", \"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-66-e7rm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45744, \"got_angle_revision\": \"77637f2dd222530c721018185a90e24a51e7a745\", \"got_dawn_revision\": \"545a6970cbe9e986dd25e01f7ecf2f9a1b17c756\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"4f0630b83f2ca0aac183f1fa431d9134f13cfbf8\", \"got_revision_cp\": \"refs/heads/master@{#857988}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"bb52bdf09516ca548c4aff50526eda561f239bc0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33341}\", \"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\": \"4f0630b83f2ca0aac183f1fa431d9134f13cfbf8\", \"swarm_hashes_refs/heads/master(at){#857988}_without_patch\": {\"absl_hardening_tests\": \"5fc0ad562c99899cf31c9f76eb5747976d65aa3b\", \"accessibility_unittests\": \"4c9beb18bb2476ddd589454bf891ca714f028176\", \"angle_unittests\": \"e2f992ab333c3af349322cb5bd73317bb34e8502\", \"app_list_unittests\": \"90e63d81b2b86e19baf0a52057de31432ac56063\", \"app_shell_unittests\": \"9b32d0cfa2904bc3aab5c4bd11df0e8190877c08\", \"ash_unittests\": \"588a751ce33eea56aee01c5e2f31ae61b66fe088\", \"aura_unittests\": \"1744da96685034d543073b64bc49dd193e880ead\", \"base_unittests\": \"8d6f514e442c666a01e2ce18ac6d84b34f9a936d\", \"base_util_unittests\": \"0d6421149bef8b60ab7ef933a3bb5ab899e42f27\", \"blink_common_unittests\": \"6d740312ff4dae491e4a69263f21f60551794627\", \"blink_fuzzer_unittests\": \"bafa47ea37c1142428f5f0af4cd0c02c9e875eca\", \"blink_heap_unittests\": \"abe2b42f31caac9b4344410d0dc79588a875c65a\", \"blink_platform_unittests\": \"f3b637cdbd7658b31f019629d20d61a978b5ce83\", \"blink_unittests\": \"dd2cbce9cdacdb54a2c3083bf974cf176ad541f7\", \"boringssl_crypto_tests\": \"afa394d2a893d11bd4981df06160eac4c4cb5ad5\", \"boringssl_ssl_tests\": \"ee698c33a9ce4944886a562721d9fd8b353b79ff\", \"browser_tests\": \"2cdf4500fe268eaaa69f8609a43efe0fcf49af8e\", \"capture_unittests\": \"183e4452bb2c9e59344d30e976dea60577711127\", \"cast_unittests\": \"7daf12b469b59d8ab4d21686fda98f66c0da729b\", \"cc_unittests\": \"74ca953d5ca9edcc5916c1e1b6afa3efc8906a5f\", \"chrome_app_unittests\": \"587e75f31b9ba9d0cafb5422dd47aa5237ba0a9d\", \"chromedriver_unittests\": \"69ad25bf2c1cb047169a8043070b31210d4e001f\", \"chromeos_components_unittests\": \"62236419281278970148a28a7866f33c5ca63036\", \"chromeos_unittests\": \"320d5f294a399d14431e3a9980751527f01dfb58\", \"color_unittests\": \"525c54b6974c1564efc87ff5354cd7c50487cfff\", \"components_browsertests\": \"e3182dd288cf1bfbdb18722b3e03f822b275536d\", \"components_unittests\": \"5499c630a80654dc902ed3b9cbcf22f31584bffb\", \"compositor_unittests\": \"2b0fa5d4da6125499f3d41e10f65964d7e198cf8\", \"content_browsertests\": \"cce8d90db897e91d203b0c0f75bff061a3bb08f7\", \"content_unittests\": \"efc3d022d00a0928fb85e9cb2dde5bcc709fe3bb\", \"crashpad_tests\": \"07dda6507a318a918fb9bbd6d5b492777498e23a\", \"crypto_unittests\": \"aa8832f5f3a0692855ce196dafb1a67d8b9b6abb\", \"dbus_unittests\": \"399bfde8113b564b01ba458c6e80f13a27f5a798\", \"device_unittests\": \"224678c05b858e92ee242de0613b2fd551574345\", \"display_unittests\": \"d03f3cc5d2259f50010864b64b92d757a7aa8efc\", \"events_unittests\": \"7473be9569d3e2324036f5fa6b5c8e7a22617d46\", \"exo_unittests\": \"ee8ea33888af349730452c334519798f6039ba35\", \"extensions_browsertests\": \"a368ecd59a1041f6090cfd22e08b2c3de5a948e0\", \"extensions_unittests\": \"de081c7b22ac5dd0d209624127c671f6c06a6dd9\", \"filesystem_service_unittests\": \"e44afec1ac39fa3c1461ce9cb215dd05fd7dd25c\", \"gcm_unit_tests\": \"353dac692ce27af9f47b7cbabde25b745c004ac8\", \"gfx_unittests\": \"05c9f36b05de4f892dc89cec51f40f42bbe666d3\", \"gin_unittests\": \"84c49dc8e2ccca4c8b74d4bf562d070f936e39c3\", \"gl_unittests_ozone\": \"691456827c1414eb8ca74cea8b515d5eca51846f\", \"google_apis_unittests\": \"7a07953b915c0bff8baa206fad17a57ac7d9d97a\", \"gpu_unittests\": \"f793381ac3f8f48f4d55a4161456df9c8f1362e2\", \"gwp_asan_unittests\": \"e1d3d4558b321c257d0b7134bdac6362f75a7ced\", \"interactive_ui_tests\": \"2a1e32015a7abb7c2c929e418e029bb06472b115\", \"ipc_tests\": \"3bd348bd2a9384510314b4f436604529e930cf8a\", \"jingle_unittests\": \"b8557decc6530ae222634b04aa9af72666fef660\", \"keyboard_unittests\": \"16c46648e9af125e601110aaf780d7e85123b596\", \"latency_unittests\": \"27ec29790b6494dd4a0869c92cc8a64404c727eb\", \"libjingle_xmpp_unittests\": \"66b426d82ec9d55ed3192c2199c0a63d7a6327e5\", \"liburlpattern_unittests\": \"868a4d3a68da851b404ee722d177430bc86ef40c\", \"media_blink_unittests\": \"7b661a8fb3bb65c2a49d8584991472190a716806\", \"media_unittests\": \"40e86b319acaec8c9df81973bdfe5853d26d1670\", \"message_center_unittests\": \"1d27c9677c3bc04dcec6f98f20a5ae8623af5d03\", \"midi_unittests\": \"3febc61788466dc9c481cb9bf7e1e0aacfa7fb95\", \"mojo_core_unittests\": \"9597335a2c5b67f3c831140e3cf4a2e02f2ad135\", \"mojo_unittests\": \"f06db23dd596738f91ff0e80b6f0b4974e355cf0\", \"nacl_helper_nonsfi_unittests\": \"78898c1cee4fa0e9f97e358307adbe84164280d4\", \"nacl_loader_unittests\": \"f9338a570221ace768549719e40e2eba3fb16d81\", \"native_theme_unittests\": \"7214d9c9d445e1717baf564023f15cffe0aadedf\", \"net_unittests\": \"69d6dd910e93610fdfd55a8ffd37b2ad1a14fe3c\", \"ozone_gl_unittests\": \"0815907ad3ca3b72ef26770171f4edfa8eddf42a\", \"ozone_unittests\": \"13e5534d43b322432caf94ee2ca38181c3ae7fbf\", \"ozone_x11_unittests\": \"a79a9ccec3138355e91c7ad3b82872ffb7cb5a0f\", \"pdf_unittests\": \"6e75a463788c7304c74adf13379e850dde2900e3\", \"perfetto_unittests\": \"66cd2f89b9d3a522952e3d8494b202c0934393f4\", \"ppapi_unittests\": \"b451381b96041a99a549a531174146d9c91ee607\", \"printing_unittests\": \"7f9d249cd247aa08302b2f99e2b8b35ec1433d10\", \"remoting_unittests\": \"272dcc6bd3284715a832014f13ea1817ff5843b5\", \"sandbox_linux_unittests\": \"af9e41d86da0b84c6bf1c7974a5f1d2dd6f3c3ff\", \"service_manager_unittests\": \"59647d0b44b7a2ccc9d64d057d3ed6eafbb22c14\", \"services_unittests\": \"3a3eb46eb08aa56e6c459d32cd9251dc8af550e8\", \"shell_dialogs_unittests\": \"064f71fe8e5c1e126c37eee8f2d9b5c908cc6703\", \"shell_encryption_unittests\": \"afb61535330ac669da3e07932bbb5b78c6e7ae2e\", \"skia_unittests\": \"23caf522266c36507e3a3cfb930fcb380a29ab29\", \"snapshot_unittests\": \"346cec5d3a93c8666309312cee54adfc5325d9c1\", \"sql_unittests\": \"dcbcb9c131e501ecf13487c1fa6aefd55bd134e4\", \"storage_unittests\": \"944572d29c828ebce6baaf60d662b99a724246a9\", \"sync_integration_tests\": \"5b5828e5e471cde7a3d396b00ffd0b591242b7ec\", \"traffic_annotation_auditor_unittests\": \"35b2d7823099fb5e08c1c273574e66074c97dc3d\", \"ui_base_unittests\": \"a14de54e240d9842e7e89cb78be942a26932d744\", \"ui_chromeos_unittests\": \"f4c9d3d1abc7da78580c3ca597ea212ba5298225\", \"ui_touch_selection_unittests\": \"6f378d00bd53996f924f17588679cf75c38592cb\", \"unit_tests\": \"83d7b43d2090793a16f132444ed3c7efdee52e0f\", \"url_unittests\": \"3d0315a176d64bb1aa24eb6561b8debde5b3e3c8\", \"usage_time_limit_unittests\": \"005fb93373366295a1814df5f231a7a8d527acf4\", \"views_unittests\": \"c8d927060fb92099abd32b9ee4b6f503ef246a06\", \"viz_unittests\": \"cd317f10dccdaec0cc2e3d63dee31efeec4ab1bf\", \"wayland_client_perftests\": \"cf8cd18877ba05566e67f7996f98507be664707a\", \"wayland_client_tests\": \"8c7d99866a3a45c40e6f3d6c0854b6d871738975\", \"wm_unittests\": \"6f6a9ccdfcb95255ec27167c02dbdd7873dc306a\", \"wtf_unittests\": \"cafbf607098dc50b1b92f048d59c812f0befac78\", \"zlib_unittests\": \"0d105847e165d3204ee716a9e02b0f84e5904f77\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-66-e7rm\"], \"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\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614307117495135",
"status": "COMPLETED",
"status_changed_ts": "1614310137655819",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45744",
"builder:linux-chromeos-rel",
"buildset:commit/git/4f0630b83f2ca0aac183f1fa431d9134f13cfbf8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f0630b83f2ca0aac183f1fa431d9134f13cfbf8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019700079774333232",
"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/8854270017844554960/+/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:51f5054dc604fd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614310137660708",
"url": "https://ci.chromium.org/b/8854270017844554960",
"utcnow_ts": "1614310138673884"
}
}
[03:28:58.731] Invocation finished in 50m24.292653376s with status SUCCEEDED