[06:50:55.306] New invocation is queued and will start shortly
[06:50:56.427] Starting the invocation (attempt 1)
[06:50:56.458] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:50:56.458] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:50:56.458] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021677336702326880",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"723403b0abaf8000bbcefcfc5b27aa707a6c5a47\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021677336702326880",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/723403b0abaf8000bbcefcfc5b27aa707a6c5a47",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/723403b0abaf8000bbcefcfc5b27aa707a6c5a47",
"gitiles_ref:refs/heads/master"
]
}
[06:50:58.105] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612421456611736",
"id": "8856247274671166208",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"723403b0abaf8000bbcefcfc5b27aa707a6c5a47\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612421457781828",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45133",
"builder:linux-chromeos-rel",
"buildset:commit/git/723403b0abaf8000bbcefcfc5b27aa707a6c5a47",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/723403b0abaf8000bbcefcfc5b27aa707a6c5a47",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021677336702326880",
"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/8856247274671166208/+/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": "1612421457782455",
"url": "https://ci.chromium.org/b/8856247274671166208",
"utcnow_ts": "1612421458088702"
}
}
[06:50:58.105] Task URL: https://ci.chromium.org/b/8856247274671166208
[06:50:58.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:2:0) after 1m0s
[06:51:52.607] Received PubSub notification, asking Buildbucket for the build status
[06:51:52.665] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:58.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:2:0)
[06:51:58.163] Timer tick, asking Buildbucket for the build status
[06:51:58.488] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:58.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:4:0) after 1m0s
[06:52:58.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:4:0)
[06:52:58.553] Timer tick, asking Buildbucket for the build status
[06:52:58.686] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:5:0) after 1m0s
[06:53:58.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:5:0)
[06:53:58.762] Timer tick, asking Buildbucket for the build status
[06:53:58.828] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:58.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:6:0) after 1m0s
[06:54:58.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:6:0)
[06:54:58.890] Timer tick, asking Buildbucket for the build status
[06:54:58.994] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:58.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:7:0) after 1m0s
[06:55:59.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:7:0)
[06:55:59.047] Timer tick, asking Buildbucket for the build status
[06:55:59.484] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:59.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:8:0) after 1m0s
[06:56:59.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:8:0)
[06:56:59.507] Timer tick, asking Buildbucket for the build status
[06:56:59.631] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:59.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:9:0) after 1m0s
[06:57:59.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:9:0)
[06:57:59.675] Timer tick, asking Buildbucket for the build status
[06:58:00.255] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:00.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:10:0) after 1m0s
[06:59:00.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:10:0)
[06:59:00.375] Timer tick, asking Buildbucket for the build status
[06:59:00.806] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:00.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:11:0) after 1m0s
[07:00:00.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:11:0)
[07:00:00.835] Timer tick, asking Buildbucket for the build status
[07:00:00.904] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:00.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:12:0) after 1m0s
[07:01:00.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:12:0)
[07:01:00.987] Timer tick, asking Buildbucket for the build status
[07:01:01.139] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:01.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:13:0) after 1m0s
[07:02:01.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:13:0)
[07:02:01.222] Timer tick, asking Buildbucket for the build status
[07:02:01.342] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:01.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:14:0) after 1m0s
[07:03:01.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:14:0)
[07:03:01.404] Timer tick, asking Buildbucket for the build status
[07:03:01.498] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:01.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:15:0) after 1m0s
[07:04:01.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:15:0)
[07:04:01.530] Timer tick, asking Buildbucket for the build status
[07:04:01.594] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:01.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:16:0) after 1m0s
[07:05:01.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:16:0)
[07:05:01.622] Timer tick, asking Buildbucket for the build status
[07:05:01.804] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:01.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:17:0) after 1m0s
[07:06:01.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:17:0)
[07:06:01.865] Timer tick, asking Buildbucket for the build status
[07:06:01.972] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:01.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:18:0) after 1m0s
[07:07:02.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:18:0)
[07:07:02.046] Timer tick, asking Buildbucket for the build status
[07:07:02.255] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:02.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:19:0) after 1m0s
[07:08:02.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:19:0)
[07:08:02.316] Timer tick, asking Buildbucket for the build status
[07:08:02.381] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:02.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:20:0) after 1m0s
[07:09:02.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:20:0)
[07:09:02.400] Timer tick, asking Buildbucket for the build status
[07:09:02.499] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:02.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:21:0) after 1m0s
[07:10:02.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:21:0)
[07:10:02.641] Timer tick, asking Buildbucket for the build status
[07:10:02.712] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:02.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:22:0) after 1m0s
[07:11:02.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:22:0)
[07:11:02.804] Timer tick, asking Buildbucket for the build status
[07:11:02.926] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:02.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:23:0) after 1m0s
[07:12:02.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:23:0)
[07:12:02.927] Timer tick, asking Buildbucket for the build status
[07:12:02.993] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:02.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:24:0) after 1m0s
[07:13:03.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:24:0)
[07:13:03.009] Timer tick, asking Buildbucket for the build status
[07:13:03.071] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:03.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:25:0) after 1m0s
[07:14:03.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:25:0)
[07:14:03.244] Timer tick, asking Buildbucket for the build status
[07:14:03.315] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:03.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:26:0) after 1m0s
[07:15:03.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:26:0)
[07:15:03.360] Timer tick, asking Buildbucket for the build status
[07:15:03.447] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:03.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:27:0) after 1m0s
[07:16:03.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:27:0)
[07:16:03.545] Timer tick, asking Buildbucket for the build status
[07:16:03.710] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:03.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:28:0) after 1m0s
[07:17:03.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:28:0)
[07:17:03.795] Timer tick, asking Buildbucket for the build status
[07:17:03.854] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:03.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:29:0) after 1m0s
[07:18:03.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:29:0)
[07:18:03.881] Timer tick, asking Buildbucket for the build status
[07:18:03.940] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:03.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:30:0) after 1m0s
[07:19:04.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:30:0)
[07:19:04.059] Timer tick, asking Buildbucket for the build status
[07:19:04.225] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:04.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:31:0) after 1m0s
[07:20:04.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:31:0)
[07:20:04.328] Timer tick, asking Buildbucket for the build status
[07:20:04.394] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:04.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:32:0) after 1m0s
[07:21:04.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:32:0)
[07:21:04.596] Timer tick, asking Buildbucket for the build status
[07:21:04.695] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:04.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:33:0) after 1m0s
[07:22:04.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:33:0)
[07:22:04.757] Timer tick, asking Buildbucket for the build status
[07:22:04.874] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:04.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:34:0) after 1m0s
[07:23:04.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:34:0)
[07:23:04.905] Timer tick, asking Buildbucket for the build status
[07:23:04.977] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:04.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:35:0) after 1m0s
[07:24:05.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:35:0)
[07:24:05.033] Timer tick, asking Buildbucket for the build status
[07:24:05.323] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:05.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:36:0) after 1m0s
[07:25:05.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:36:0)
[07:25:05.397] Timer tick, asking Buildbucket for the build status
[07:25:05.461] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:05.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:37:0) after 1m0s
[07:26:05.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:37:0)
[07:26:05.462] Timer tick, asking Buildbucket for the build status
[07:26:05.600] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:05.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:38:0) after 1m0s
[07:27:05.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:38:0)
[07:27:05.589] Timer tick, asking Buildbucket for the build status
[07:27:05.665] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:05.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:39:0) after 1m0s
[07:28:05.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:39:0)
[07:28:05.716] Timer tick, asking Buildbucket for the build status
[07:28:05.771] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:05.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:40:0) after 1m0s
[07:29:05.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:40:0)
[07:29:05.833] Timer tick, asking Buildbucket for the build status
[07:29:05.908] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:05.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:41:0) after 1m0s
[07:30:05.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:41:0)
[07:30:05.926] Timer tick, asking Buildbucket for the build status
[07:30:06.036] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:06.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:42:0) after 1m0s
[07:31:06.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:42:0)
[07:31:06.078] Timer tick, asking Buildbucket for the build status
[07:31:06.229] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:06.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:43:0) after 1m0s
[07:32:06.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:43:0)
[07:32:06.252] Timer tick, asking Buildbucket for the build status
[07:32:06.326] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:06.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:44:0) after 1m0s
[07:33:06.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:44:0)
[07:33:06.415] Timer tick, asking Buildbucket for the build status
[07:33:06.547] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:06.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:45:0) after 1m0s
[07:34:06.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:45:0)
[07:34:06.669] Timer tick, asking Buildbucket for the build status
[07:34:06.790] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:06.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:46:0) after 1m0s
[07:35:06.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:46:0)
[07:35:06.904] Timer tick, asking Buildbucket for the build status
[07:35:07.002] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:07.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:47:0) after 1m0s
[07:36:07.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:47:0)
[07:36:07.088] Timer tick, asking Buildbucket for the build status
[07:36:07.197] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:07.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:48:0) after 1m0s
[07:37:07.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:48:0)
[07:37:07.315] Timer tick, asking Buildbucket for the build status
[07:37:07.413] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:07.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:49:0) after 1m0s
[07:38:07.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:49:0)
[07:38:07.479] Timer tick, asking Buildbucket for the build status
[07:38:07.660] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:07.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:50:0) after 1m0s
[07:39:07.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:50:0)
[07:39:07.918] Timer tick, asking Buildbucket for the build status
[07:39:08.055] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:08.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:51:0) after 1m0s
[07:40:08.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:51:0)
[07:40:08.207] Timer tick, asking Buildbucket for the build status
[07:40:08.631] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:08.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:52:0) after 1m0s
[07:41:09.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:52:0)
[07:41:09.210] Timer tick, asking Buildbucket for the build status
[07:41:09.308] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:09.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:53:0) after 1m0s
[07:42:09.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:53:0)
[07:42:09.502] Timer tick, asking Buildbucket for the build status
[07:42:09.584] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:09.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:54:0) after 1m0s
[07:43:09.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:54:0)
[07:43:09.713] Timer tick, asking Buildbucket for the build status
[07:43:10.319] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:10.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:55:0) after 1m0s
[07:44:10.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:55:0)
[07:44:10.438] Timer tick, asking Buildbucket for the build status
[07:44:10.624] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:10.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:56:0) after 1m0s
[07:45:10.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:56:0)
[07:45:10.772] Timer tick, asking Buildbucket for the build status
[07:45:10.926] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:10.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:57:0) after 1m0s
[07:46:11.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:57:0)
[07:46:11.026] Timer tick, asking Buildbucket for the build status
[07:46:12.495] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:12.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:58:0) after 1m0s
[07:47:12.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:58:0)
[07:47:12.554] Timer tick, asking Buildbucket for the build status
[07:47:12.666] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:12.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:59:0) after 1m0s
[07:48:12.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:59:0)
[07:48:12.740] Timer tick, asking Buildbucket for the build status
[07:48:12.858] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:12.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:60:0) after 1m0s
[07:49:13.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:60:0)
[07:49:13.008] Timer tick, asking Buildbucket for the build status
[07:49:13.196] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:13.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:61:0) after 1m0s
[07:50:13.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:61:0)
[07:50:13.288] Timer tick, asking Buildbucket for the build status
[07:50:13.467] Build 8856247274671166208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:13.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021677336702326880:62:0) after 1m0s
[07:50:15.421] Received PubSub notification, asking Buildbucket for the build status
[07:50:15.498] Build 8856247274671166208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:50:15.498] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612425014351315",
"created_by": "project:chromium",
"created_ts": "1612421456611736",
"id": "8856247274671166208",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"723403b0abaf8000bbcefcfc5b27aa707a6c5a47\"}}",
"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\": \"723403b0abaf8000bbcefcfc5b27aa707a6c5a47\", \"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-central1-b-8-47-mh2j\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45133, \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"623d80899b7ab1ae0936387f5ab34734f3dfba97\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"723403b0abaf8000bbcefcfc5b27aa707a6c5a47\", \"got_revision_cp\": \"refs/heads/master@{#850473}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"6114494c45ee29e02b0f40cc4536484ade39aa5b\", \"got_v8_revision_cp\": \"refs/heads/9.0.126@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"723403b0abaf8000bbcefcfc5b27aa707a6c5a47\", \"swarm_hashes_refs/heads/master(at){#850473}_without_patch\": {\"absl_hardening_tests\": \"504078b02956546840ebbe346ba09986dcc24625\", \"accessibility_unittests\": \"3e612a3e90d55a42af6009c798e955f38f61dcb7\", \"angle_unittests\": \"f6742f88e7535c149883d971c1e9a8a454171c8b\", \"app_list_unittests\": \"77dee7553dcbe4b6998b9f47a57eac3c9ad52aac\", \"app_shell_unittests\": \"7ab8358b6de798ef889917cf9620da5fbf373fe1\", \"ash_unittests\": \"558b30e699c982eae53c07167b3c09a57d476273\", \"aura_unittests\": \"549ccd13ea66b8b9686153c7151ee2e55d310df6\", \"base_unittests\": \"98e32945bbb31bececd358f517751210598a3863\", \"base_util_unittests\": \"01097452b641f3816eede6411a2a4bf86af4edce\", \"blink_common_unittests\": \"dd3b331630e8d78c9e27a2bd3842a02ead3d5730\", \"blink_fuzzer_unittests\": \"889daa1786f50836bdd00fcce1e7a58b8f81d45a\", \"blink_heap_unittests\": \"7b02a606e2bb9ae5fc60e4a3ec18c4ec4cde1261\", \"blink_platform_unittests\": \"44f5becfd2bdc7a318295d81e94a44e1edd0b387\", \"blink_unittests\": \"f96983684308f1e7e17264ff9bd43f8b39c1ba39\", \"boringssl_crypto_tests\": \"c87f1682fd0b330ee339214ac3e7d7f0f040d7d1\", \"boringssl_ssl_tests\": \"0b5266fd37e18990ae6e0eaca280bc72bd520890\", \"browser_tests\": \"e2647a856c0720155def1fc886ef4faeb004e6a9\", \"capture_unittests\": \"1da26fda5b4673a0a6b92088b1947c794b82270e\", \"cast_unittests\": \"ffc2b0e4f81c465a7906969b03b4555dba50a058\", \"cc_unittests\": \"bd46f04cbe39672fbaa51b81bee5cf3dfdb707e1\", \"chrome_app_unittests\": \"fb5c2ba6bea0848656c7eb26628fbe1b059c2b35\", \"chromedriver_unittests\": \"a3b42b520a133d7cc1724093022257110dc158eb\", \"chromeos_components_unittests\": \"6326f7d70ba38265b27d53543b19677eabe5e634\", \"chromeos_unittests\": \"b31c4bbeb0390c81ea94f77e44df3bdd5ba9360f\", \"components_browsertests\": \"8ca7c08b1106e1e1df0f7ac77ca561d8b316475e\", \"components_unittests\": \"b464ceb1cc9e81f5a6f23e7a993e755d5e15d01b\", \"compositor_unittests\": \"8f33169f609f1c103d024bd07535a1e2b6bc4987\", \"content_browsertests\": \"269123cdf1ae2af378c2656a34db0b309e507726\", \"content_unittests\": \"37f0c8dbe53d73c70018a325dc022d9f0b11a23d\", \"crashpad_tests\": \"17937f7fa8afdaf27c618c4b1b8e84f8d791abee\", \"crypto_unittests\": \"6ad6109a42a7370d48fedf0bab4ca0875b095943\", \"dbus_unittests\": \"41e6a3ea6bdfd4dcd774e5b96894fdadb05ec54c\", \"device_unittests\": \"589fcd623babded3933a05328c2253de2a5da177\", \"display_unittests\": \"4ac98383b6c488a8edbcc97ef3c6f44ed316934b\", \"events_unittests\": \"9dc633175c4225a8ddb56b862952b282248f8c34\", \"exo_unittests\": \"98fc50abc195a8afc1071e9e33c6b9d24824a1f0\", \"extensions_browsertests\": \"6592bceef71e81ac11ec25f5e2099423d737d91a\", \"extensions_unittests\": \"92b3633e1d8a74a1c9387555a308416f935de193\", \"filesystem_service_unittests\": \"849ed918a885c43c694b26ffcc855f446b76ec89\", \"gcm_unit_tests\": \"c1a7e2924c14390eb8fe51488d02978951e4233e\", \"gfx_unittests\": \"442adc91700a5b7851a0fbc4bc48c704fd344ce8\", \"gin_unittests\": \"b0429f948a0a462d1499a3fda1965dbcedebff8b\", \"gl_unittests_ozone\": \"716927754e085570e1eed849e0afa8001893bace\", \"google_apis_unittests\": \"43fb4d0c5a0ca6466d2fd639fc8021c52539a2c7\", \"gpu_unittests\": \"e2153fc043f9bf8e985306145ebbf63de0da1188\", \"gwp_asan_unittests\": \"ee1ce04601641912c68d7e35e9235e69961c797c\", \"interactive_ui_tests\": \"f4e478a11cc6d8b2951971a43e6435fd7f526fcc\", \"ipc_tests\": \"56073eeb8698a61fc058a5e4fdd7763e2756c960\", \"jingle_unittests\": \"e7fa5faadd5c351d9f5724d85f15a62e8e3dc7f0\", \"keyboard_unittests\": \"0569416882d3b8cd4ef87325b484d83d18a021ad\", \"latency_unittests\": \"a2a304a0808ba357b6250d16dd6458c8237e92ca\", \"libjingle_xmpp_unittests\": \"66fa9ec2ac24500ae2764ed815f8839b0815dc60\", \"liburlpattern_unittests\": \"0c3fef142705d12d9fd2bdb1062304ce8f230f95\", \"media_blink_unittests\": \"8fbce80bb3014b3ab6039b141f24bfbcdde07180\", \"media_unittests\": \"496612ccc52f3aa2b5c4659c5e7d34f3efe5dcff\", \"message_center_unittests\": \"6ac1c9412a476c55062d003533f21f767692eafa\", \"midi_unittests\": \"c3856b59c531a5d4b3892bb81ad2fd77cc4230e4\", \"mojo_core_unittests\": \"8b7039e5dae16f92ee50b80d114edadb759b5519\", \"mojo_unittests\": \"5f5af1e404bf57a152fa30c5aa18db2daee5c05d\", \"nacl_helper_nonsfi_unittests\": \"d059d78d4ebd0ad9b9e29498ef63345c926ee852\", \"nacl_loader_unittests\": \"595709e918820eb8057dcb662ac28cda18dbf762\", \"native_theme_unittests\": \"85130f2122827537868bdd728c60ee0ff8640757\", \"net_unittests\": \"a955ac3d5a966a08ee04a748af0c580771615435\", \"ozone_gl_unittests\": \"912661f0fe9c45d899d950dbfae7676f8be27835\", \"ozone_unittests\": \"8f9a13cab08b28eba608bc59014839b201b7b298\", \"ozone_x11_unittests\": \"f46982a67ac24454c36a02294cdc17ae8f346de4\", \"pdf_unittests\": \"e10abfc9582a234506b92af64db56702d91f6fe9\", \"perfetto_unittests\": \"7a9050b918df6ceab33665bc1743b4eba4914080\", \"ppapi_unittests\": \"74f9f5d55ea2205e3e693f62bf895bbb59ccdf4c\", \"printing_unittests\": \"31a8bb461b1df8593b5d9bab50210e9ac05e3157\", \"remoting_unittests\": \"441c90fc35f5c40b7e8fd280e831153bca74d38f\", \"sandbox_linux_unittests\": \"aa0ef66ce63e27c1f885ced382f3948cc9e006b2\", \"service_manager_unittests\": \"34496c12da5037479a7830406612d900e71b2a98\", \"services_unittests\": \"0429182435b8fafbfdf026ec88a5eeeba37128bb\", \"shell_dialogs_unittests\": \"22aee56a408f37d30fa69978fd44dc00708e3728\", \"shell_encryption_unittests\": \"958009f34ff2dc6e4fa30c7a2a5e95b685ab5e92\", \"skia_unittests\": \"cdfa64cc9af44eb2a40b0299365f6a1e3f75473e\", \"snapshot_unittests\": \"ee22f72ea07475a1efec67f4e67b7095d13dd90c\", \"sql_unittests\": \"79bac2dd0ad5f6c1ece811f166c4489ebfacab04\", \"storage_unittests\": \"f59a747fef65683ae4f32bae337f5eeedc41f9e1\", \"sync_integration_tests\": \"a6e5fb96bb1e3f6c5aaead42c02984ba6cf95def\", \"traffic_annotation_auditor_unittests\": \"787df9e1610c0d1865efae5e9c31a4f20b9e8ea9\", \"ui_base_unittests\": \"c1075796f05848d21f177bdf5993509c755fda74\", \"ui_chromeos_unittests\": \"40a4643a5bb8431a5026c15f412b1d48e3a44a31\", \"ui_touch_selection_unittests\": \"2154cb083028bb97eef9f9f1da2b797b59830ba2\", \"unit_tests\": \"1cc50f222de0ee8b53b8fa2d75531ced76aa4454\", \"url_unittests\": \"e8f5ba4fbd266f33cc7f6f22579060054e43e4e6\", \"usage_time_limit_unittests\": \"2064a0ab843c3738244cced6bbdb96e261fe865e\", \"views_unittests\": \"4a432b1bd92f349437f02ca0033b98d4cf94ffe2\", \"viz_unittests\": \"82dabdf7dba8bc1dcb53975a402c2d767caa9dcb\", \"wayland_client_perftests\": \"d83bab25c2b6b821430a821f841d87d2ba930811\", \"wm_unittests\": \"c2ae2e20146ac958d80d28cb892c4b480f71adb4\", \"wtf_unittests\": \"e493fbdc641a1dde4dd4576fee8452010421a7dc\", \"zlib_unittests\": \"a15e04b98915123b44a396d8e381d398bce8ce42\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3ee79eab7726d87d4f9bf92f4b58b9506b0a7a0d17f466f0c3d67bd50a4697d1_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_fe96da9c6a19fa2fc3ad0c2b7020b7cb68e4a92161f4cd6cb7dc1d1e2d5af5dc_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-central1-b-8-47-mh2j\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612421512009352",
"status": "COMPLETED",
"status_changed_ts": "1612425014637355",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45133",
"builder:linux-chromeos-rel",
"buildset:commit/git/723403b0abaf8000bbcefcfc5b27aa707a6c5a47",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/723403b0abaf8000bbcefcfc5b27aa707a6c5a47",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021677336702326880",
"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/8856247274671166208/+/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:5184a0713a880810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612425014646811",
"url": "https://ci.chromium.org/b/8856247274671166208",
"utcnow_ts": "1612425015487602"
}
}
[07:50:15.498] Invocation finished in 59m20.208047236s with status SUCCEEDED