[04:28:52.736] New invocation is queued and will start shortly
[04:28:53.836] Starting the invocation (attempt 1)
[04:28:53.873] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:28:53.873] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:28:53.873] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019330752137882320",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b9b8f4bd0210b49aa0deb21e60dec944313840f3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9019330752137882320",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b9b8f4bd0210b49aa0deb21e60dec944313840f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9b8f4bd0210b49aa0deb21e60dec944313840f3",
"gitiles_ref:refs/heads/master"
]
}
[04:28:55.155] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614659334143952",
"id": "8853900689999589616",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9b8f4bd0210b49aa0deb21e60dec944313840f3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614659335000459",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181029",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b9b8f4bd0210b49aa0deb21e60dec944313840f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9b8f4bd0210b49aa0deb21e60dec944313840f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019330752137882320",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853900689999589616/+/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": "1614659335001280",
"url": "https://ci.chromium.org/b/8853900689999589616",
"utcnow_ts": "1614659335137414"
}
}
[04:28:55.155] Task URL: https://ci.chromium.org/b/8853900689999589616
[04:28:55.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:2:0) after 1m0s
[04:29:45.718] Received PubSub notification, asking Buildbucket for the build status
[04:29:45.841] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:55.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:2:0)
[04:29:55.176] Timer tick, asking Buildbucket for the build status
[04:29:55.264] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:55.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:4:0) after 1m0s
[04:30:55.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:4:0)
[04:30:55.306] Timer tick, asking Buildbucket for the build status
[04:30:55.433] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:55.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:5:0) after 1m0s
[04:31:55.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:5:0)
[04:31:55.472] Timer tick, asking Buildbucket for the build status
[04:31:55.536] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:55.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:6:0) after 1m0s
[04:32:55.575] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:6:0)
[04:32:55.575] Timer tick, asking Buildbucket for the build status
[04:32:55.693] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:55.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:7:0) after 1m0s
[04:33:55.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:7:0)
[04:33:55.711] Timer tick, asking Buildbucket for the build status
[04:33:55.841] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:55.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:8:0) after 1m0s
[04:34:55.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:8:0)
[04:34:55.818] Timer tick, asking Buildbucket for the build status
[04:34:55.893] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:55.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:9:0) after 1m0s
[04:35:56.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:9:0)
[04:35:56.052] Timer tick, asking Buildbucket for the build status
[04:35:56.127] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:56.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:10:0) after 1m0s
[04:36:56.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:10:0)
[04:36:56.169] Timer tick, asking Buildbucket for the build status
[04:36:56.240] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:56.240] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:11:0) after 1m0s
[04:37:56.287] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:11:0)
[04:37:56.287] Timer tick, asking Buildbucket for the build status
[04:37:56.407] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:56.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:12:0) after 1m0s
[04:38:56.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:12:0)
[04:38:56.450] Timer tick, asking Buildbucket for the build status
[04:38:56.549] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:56.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:13:0) after 1m0s
[04:39:56.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:13:0)
[04:39:56.833] Timer tick, asking Buildbucket for the build status
[04:39:56.938] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:56.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:14:0) after 1m0s
[04:40:57.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:14:0)
[04:40:57.048] Timer tick, asking Buildbucket for the build status
[04:40:57.125] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:57.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:15:0) after 1m0s
[04:41:57.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:15:0)
[04:41:57.309] Timer tick, asking Buildbucket for the build status
[04:41:58.690] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:58.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:16:0) after 1m0s
[04:42:59.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:16:0)
[04:42:59.137] Timer tick, asking Buildbucket for the build status
[04:42:59.202] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:59.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:17:0) after 1m0s
[04:43:59.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:17:0)
[04:43:59.350] Timer tick, asking Buildbucket for the build status
[04:43:59.428] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:59.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:18:0) after 1m0s
[04:44:59.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:18:0)
[04:44:59.551] Timer tick, asking Buildbucket for the build status
[04:44:59.667] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:59.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:19:0) after 1m0s
[04:45:59.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:19:0)
[04:45:59.728] Timer tick, asking Buildbucket for the build status
[04:45:59.850] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:59.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:20:0) after 1m0s
[04:46:59.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:20:0)
[04:46:59.885] Timer tick, asking Buildbucket for the build status
[04:47:00.103] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:00.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:21:0) after 1m0s
[04:48:00.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:21:0)
[04:48:00.103] Timer tick, asking Buildbucket for the build status
[04:48:00.270] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:00.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:22:0) after 1m0s
[04:49:00.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:22:0)
[04:49:00.293] Timer tick, asking Buildbucket for the build status
[04:49:00.368] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:00.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:23:0) after 1m0s
[04:50:00.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:23:0)
[04:50:00.381] Timer tick, asking Buildbucket for the build status
[04:50:00.468] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:00.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:24:0) after 1m0s
[04:51:00.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:24:0)
[04:51:00.493] Timer tick, asking Buildbucket for the build status
[04:51:00.577] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:00.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:25:0) after 1m0s
[04:52:00.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:25:0)
[04:52:00.596] Timer tick, asking Buildbucket for the build status
[04:52:01.005] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:01.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:26:0) after 1m0s
[04:53:01.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:26:0)
[04:53:01.191] Timer tick, asking Buildbucket for the build status
[04:53:01.305] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:01.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:27:0) after 1m0s
[04:54:01.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:27:0)
[04:54:01.531] Timer tick, asking Buildbucket for the build status
[04:54:01.604] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:01.604] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:28:0) after 1m0s
[04:55:01.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:28:0)
[04:55:01.853] Timer tick, asking Buildbucket for the build status
[04:55:01.948] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:01.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:29:0) after 1m0s
[04:56:01.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:29:0)
[04:56:01.966] Timer tick, asking Buildbucket for the build status
[04:56:02.055] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:02.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:30:0) after 1m0s
[04:57:02.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:30:0)
[04:57:02.366] Timer tick, asking Buildbucket for the build status
[04:57:02.540] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:02.540] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:31:0) after 1m0s
[04:58:02.579] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:31:0)
[04:58:02.579] Timer tick, asking Buildbucket for the build status
[04:58:02.707] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:02.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:32:0) after 1m0s
[04:59:02.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:32:0)
[04:59:02.741] Timer tick, asking Buildbucket for the build status
[04:59:03.173] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:03.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:33:0) after 1m0s
[05:00:03.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:33:0)
[05:00:03.214] Timer tick, asking Buildbucket for the build status
[05:00:04.515] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:04.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:34:0) after 1m0s
[05:01:04.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:34:0)
[05:01:04.535] Timer tick, asking Buildbucket for the build status
[05:01:04.811] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:04.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:35:0) after 1m0s
[05:02:04.861] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:35:0)
[05:02:04.861] Timer tick, asking Buildbucket for the build status
[05:02:05.305] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:05.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:36:0) after 1m0s
[05:03:05.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:36:0)
[05:03:05.309] Timer tick, asking Buildbucket for the build status
[05:03:05.435] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:05.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:37:0) after 1m0s
[05:04:05.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:37:0)
[05:04:05.479] Timer tick, asking Buildbucket for the build status
[05:04:05.669] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:05.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:38:0) after 1m0s
[05:05:05.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:38:0)
[05:05:05.736] Timer tick, asking Buildbucket for the build status
[05:05:05.820] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:05.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:39:0) after 1m0s
[05:06:05.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:39:0)
[05:06:05.865] Timer tick, asking Buildbucket for the build status
[05:06:05.924] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:05.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:40:0) after 1m0s
[05:07:06.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:40:0)
[05:07:06.018] Timer tick, asking Buildbucket for the build status
[05:07:06.133] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:06.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:41:0) after 1m0s
[05:08:06.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:41:0)
[05:08:06.171] Timer tick, asking Buildbucket for the build status
[05:08:06.254] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:06.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:42:0) after 1m0s
[05:09:06.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:42:0)
[05:09:06.256] Timer tick, asking Buildbucket for the build status
[05:09:06.323] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:06.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:43:0) after 1m0s
[05:10:06.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:43:0)
[05:10:06.452] Timer tick, asking Buildbucket for the build status
[05:10:06.542] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:06.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:44:0) after 1m0s
[05:11:06.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:44:0)
[05:11:06.967] Timer tick, asking Buildbucket for the build status
[05:11:07.036] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:07.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:45:0) after 1m0s
[05:12:07.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:45:0)
[05:12:07.158] Timer tick, asking Buildbucket for the build status
[05:12:07.221] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:07.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:46:0) after 1m0s
[05:13:07.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:46:0)
[05:13:07.582] Timer tick, asking Buildbucket for the build status
[05:13:07.668] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:07.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:47:0) after 1m0s
[05:14:07.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:47:0)
[05:14:07.827] Timer tick, asking Buildbucket for the build status
[05:14:08.409] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:08.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:48:0) after 1m0s
[05:15:08.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:48:0)
[05:15:08.578] Timer tick, asking Buildbucket for the build status
[05:15:08.968] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:08.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:49:0) after 1m0s
[05:16:09.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:49:0)
[05:16:09.006] Timer tick, asking Buildbucket for the build status
[05:16:09.156] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:50:0) after 1m0s
[05:17:09.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:50:0)
[05:17:09.170] Timer tick, asking Buildbucket for the build status
[05:17:10.426] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:10.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:51:0) after 1m0s
[05:18:10.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:51:0)
[05:18:10.481] Timer tick, asking Buildbucket for the build status
[05:18:10.563] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:10.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:52:0) after 1m0s
[05:19:10.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:52:0)
[05:19:10.562] Timer tick, asking Buildbucket for the build status
[05:19:11.128] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:11.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:53:0) after 1m0s
[05:20:11.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:53:0)
[05:20:11.151] Timer tick, asking Buildbucket for the build status
[05:20:12.256] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:12.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:54:0) after 1m0s
[05:21:12.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:54:0)
[05:21:12.321] Timer tick, asking Buildbucket for the build status
[05:21:12.454] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:12.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:55:0) after 1m0s
[05:22:12.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:55:0)
[05:22:12.475] Timer tick, asking Buildbucket for the build status
[05:22:12.848] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:12.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:56:0) after 1m0s
[05:23:12.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:56:0)
[05:23:12.881] Timer tick, asking Buildbucket for the build status
[05:23:13.098] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:13.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:57:0) after 1m0s
[05:24:13.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:57:0)
[05:24:13.117] Timer tick, asking Buildbucket for the build status
[05:24:13.181] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:13.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:58:0) after 1m0s
[05:25:13.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:58:0)
[05:25:13.186] Timer tick, asking Buildbucket for the build status
[05:25:13.342] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:13.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:59:0) after 1m0s
[05:26:13.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:59:0)
[05:26:13.359] Timer tick, asking Buildbucket for the build status
[05:26:13.460] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:13.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:60:0) after 1m0s
[05:27:13.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:60:0)
[05:27:13.508] Timer tick, asking Buildbucket for the build status
[05:27:13.907] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:13.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:61:0) after 1m0s
[05:28:13.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:61:0)
[05:28:13.958] Timer tick, asking Buildbucket for the build status
[05:28:14.626] Build 8853900689999589616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:14.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019330752137882320:62:0) after 1m0s
[05:28:24.479] Received PubSub notification, asking Buildbucket for the build status
[05:28:24.569] Build 8853900689999589616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:28:24.569] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614662903713602",
"created_by": "project:chromium",
"created_ts": "1614659334143952",
"id": "8853900689999589616",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9b8f4bd0210b49aa0deb21e60dec944313840f3\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"b9b8f4bd0210b49aa0deb21e60dec944313840f3\", \"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-73-4ksf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181029, \"got_angle_revision\": \"6af0c03f9eadf548a42623a4692565b577142141\", \"got_dawn_revision\": \"ec3f48242250668ca510ceef7336cf93ba80faa5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"b9b8f4bd0210b49aa0deb21e60dec944313840f3\", \"got_revision_cp\": \"refs/heads/master@{#858876}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"740e24e201f80287f4151c15f61fa684ea53dd3c\", \"got_v8_revision_cp\": \"refs/heads/9.1.9@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9b8f4bd0210b49aa0deb21e60dec944313840f3\", \"swarm_hashes_refs/heads/master(at){#858876}_without_patch\": {\"absl_hardening_tests\": \"64c129346d47bf960cc7a7a3631437b788188428\", \"accessibility_unittests\": \"3654e13442fdb527628bf1021e3860ccc59b1179\", \"angle_unittests\": \"6867e52081fd1fb24e519b071f53282f1608c2ba\", \"app_shell_unittests\": \"318fce088c11f1f2500919270e826a649b8774f6\", \"aura_unittests\": \"870721edd485b5b1b8db69e7f2b578d157757fec\", \"base_unittests\": \"454ee6f7a09cc3ddc2e97bc960319c176d1cfd2b\", \"base_util_unittests\": \"fb60303a2dc6044ab470a49511a46849fd04b3b8\", \"blink_common_unittests\": \"a4be4e1cf8a4869e922ddb4173b9856554d2446a\", \"blink_fuzzer_unittests\": \"9de2752bc4beb48901dae2e956a8852e013e9110\", \"blink_heap_unittests\": \"d84903b65cd8004fd31e32d9c8926c8ee7882323\", \"blink_platform_unittests\": \"8e590466433b6f810e98634524e7f49e36d93b06\", \"blink_python_tests\": \"c8c7523a5e2d902cd56675ffd943a0a6a8add7e5\", \"blink_unittests\": \"e75badeba70709278912f359a2bf3e329c171367\", \"blink_web_tests\": \"cab99548dd1c60703e035a3d358685618e34d88a\", \"boringssl_crypto_tests\": \"fd72d4423725a5766556646a6c63fd6e43649e97\", \"boringssl_ssl_tests\": \"10e00827b0ffb9f9296becc4f3d360a07ad5edcc\", \"browser_tests\": \"0e3c453d425ade2ae33a34e315901d2cb0a15d0c\", \"capture_unittests\": \"f72810a3320d9f4d51734320adb4995d31b73c48\", \"cast_unittests\": \"e7ccbccf5cc9252b90804862b9072a9b40d2da0a\", \"cc_unittests\": \"2b363c1620e234ed20bd53c276ed7e4ac89f7395\", \"chrome_app_unittests\": \"65baaccf3dc966fade338f11afeb594a31a6d3af\", \"chromedriver_unittests\": \"515fde015d279ef7d226ec85258c8b68f45788e6\", \"color_unittests\": \"eef43796b7f0f1a96cbb4847252dd29ea3a10e30\", \"components_browsertests\": \"20eb5a74326d7c6cf26a631031f30ee878cc4e28\", \"components_unittests\": \"e232e80a103a685b2c0ffd90c8f081c25f164a66\", \"compositor_unittests\": \"14a64eb7b2a85c28cee255aab0c0ae53c6d7fe1a\", \"content_browsertests\": \"67cd0e06cad727456761dcb09a94afa81cb77c31\", \"content_shell_crash_test\": \"cc6f9049b53454bd801f75efefa36d0af17386f4\", \"content_unittests\": \"93f840c00fca519e8ea27b3049601c4141d9e493\", \"crashpad_tests\": \"dbf41534e3a0f3f176b091f2f8ddb1cdfd0eb122\", \"cronet_tests\": \"c208602292cbc5e624a5fca0735a3fc8d81f4e58\", \"cronet_unittests\": \"fb590560afa42f46a44fb4ef7265ea1867557853\", \"crypto_unittests\": \"5a86806c73e0f8a739d81a298c64e399e12c090a\", \"dbus_unittests\": \"441e6e751b8a9526297c68ae6bb2cd7c8bfc5a1a\", \"device_unittests\": \"65e67a64c8f273ee86d35d60bdc1d7358bfe66c3\", \"display_unittests\": \"e604a02f6ca07d9170f5c0ae091b8d85d547bd66\", \"events_unittests\": \"c1e7586ed9300a86928d303c3a20583f3d6703b6\", \"extensions_browsertests\": \"b6fb2bff2cc2a3e1721a560611b3745668bbad17\", \"extensions_unittests\": \"878504162336c5575d06f464394c88c3be9ec8c0\", \"filesystem_service_unittests\": \"77846d7b00d1a7d1c965b93097c97588eb67d1bf\", \"flatbuffers_unittests\": \"51ca3d0f331c0b735f966bf9eb546bfaaaf2e81f\", \"gcm_unit_tests\": \"4c4b286a4325d5ddbff09967e8434c7576be0691\", \"gfx_unittests\": \"8c0ee2d049ea67061e7172880740b24fea9243ad\", \"gin_unittests\": \"5b7bf9aa5f833b7ed1d60015651909e78e990203\", \"google_apis_unittests\": \"df853cba674ddb7926f6549c1748bc878ad71285\", \"gpu_unittests\": \"90f491db7e1815b0f7e593d1f5de0ca0c4482104\", \"grit_python_unittests\": \"00738482c42a9310ca0fd96f0709b3d808390403\", \"gwp_asan_unittests\": \"02ac7226c67f0e52ad4510de937769b12a41a8a6\", \"headless_browsertests\": \"1e45772d3b5abfc2dc81f0f4e7c193350686d0b3\", \"headless_unittests\": \"278e04482ea8341dab50b039f2f55fb55c12e4be\", \"interactive_ui_tests\": \"965ceb1b5cd0fa39a332011753139eb139cb1f55\", \"ipc_tests\": \"0bc69ce338b872d32cdaf760da37ceb84570745d\", \"jingle_unittests\": \"d2b0e9f46638d9234bc2d880b2b0b6a898d67b0e\", \"latency_unittests\": \"5b28890a875250ef1033689be7872b736d126388\", \"libjingle_xmpp_unittests\": \"43056f9329ad6acf057b557090689159285276e9\", \"liburlpattern_unittests\": \"08a42140021e989d5e096b46d646d8530a9b8f64\", \"media_blink_unittests\": \"8d9cb5f444abb8c2a9b23cbd1602d2437f81de7f\", \"media_unittests\": \"85020f081a75072c66c65927b874366792927c40\", \"message_center_unittests\": \"2ec65eae8f72d7fde4c3dd9b705f930e7e32c6cd\", \"metrics_python_tests\": \"8ed186194552903795dbbda27f7d858172516095\", \"midi_unittests\": \"4c3f651e57d6ad9efbfa5fded52af596454074b8\", \"mojo_core_unittests\": \"ff4ce8ea6dd7594ba03f72e3be90a1b37f0c78b4\", \"mojo_unittests\": \"aeac4077a6d2df3cfd9e0eb1f37e8450b5a51abb\", \"nacl_helper_nonsfi_unittests\": \"a644c8fbcd6b9f624d14c1a6aa5d02b038565189\", \"nacl_loader_unittests\": \"3b3ec058bf3fbd44c4121ae7708da33d9ca574f8\", \"native_theme_unittests\": \"1e754d2d885b740e1f385b13df09018476d4ce88\", \"net_unittests\": \"c0d20ceb2190c17dc109f3a082cd2029da2db152\", \"openscreen_unittests\": \"28f5eb5dcac9c3f7fcf07c115fdcaf2ca9c3b3cb\", \"pdf_unittests\": \"724da38f17f5e7c99cbc66b5914ba9f6eaa64985\", \"perfetto_unittests\": \"3320d391f847a229c5ffac2d37db95e7b0dd28ef\", \"ppapi_unittests\": \"6d9b804fe901a914ef8de914ebff4589dc5bd2bc\", \"printing_unittests\": \"a7fc85a3b7d4bda8bc87cab3501867bb7991c6da\", \"remoting_unittests\": \"75fe0a4216d03b2ba67bd1d0395e3e2a69c0076f\", \"sandbox_linux_unittests\": \"c90c5268e5e1bb05986e1c66fcfb0cb1e834e677\", \"service_manager_unittests\": \"79fb34329730ce43bef2d310772fbdb673a81a5c\", \"services_unittests\": \"1c95e8a8f32bab73f5813271e7152f6dceb29cca\", \"shell_dialogs_unittests\": \"9346916c67e52ce947f25405d88fcc9cfad53cf2\", \"skia_unittests\": \"82996a233e212f5561d155db9b2eec99a762d3be\", \"snapshot_unittests\": \"1743761c0fac7149f23e580548ee44f8356be81f\", \"sql_unittests\": \"bc1daeaabc8f12eb7116802a411a79451e60b3a8\", \"storage_unittests\": \"dc3128ff86d0656c6efc0ca16a9dce91c7d93b54\", \"sync_integration_tests\": \"3fb9f03e68f9b3ca7eebc22a9ebf2cb433987f29\", \"telemetry_gpu_unittests\": \"474d86bb6a5c478311f830e9349a3452bece2418\", \"telemetry_perf_unittests\": \"3f1d4712d78b4dd2e76ede19a80a6f269db9d4f2\", \"telemetry_unittests\": \"c24387ac14200a25e7c84f485377998c4638aa3f\", \"traffic_annotation_auditor_unittests\": \"70718ff1baa712d6343241730e694e666bcae08d\", \"ui_base_unittests\": \"a914056805acd7e49375ff804f3531021f142267\", \"ui_touch_selection_unittests\": \"1b322c287d6911fdfcf8b972637b93b7fadba129\", \"unit_tests\": \"8b5ad75d6af0571a1354d3897d182e754277b01c\", \"url_unittests\": \"22e4eb45e1a3cacf183555a17a9a83168563d2dd\", \"views_perftests\": \"b10df456d37f8061eedf0c73638ac4ca1914b2e4\", \"views_unittests\": \"2c079ee7e13f49d7c2b5a64b88484c16981b0cf4\", \"viz_unittests\": \"df77d6d5b20ac6779d0c88fedf253c1920d5cd2d\", \"vr_common_unittests\": \"ff16bfd819781c1af7ae2e65831cf140a111884d\", \"vr_pixeltests\": \"e11bf464fe0a54e4e08fb0bb18cde36324d3af85\", \"webdriver_wpt_tests\": \"36322625c6c35dd5638b861176e56122d822e926\", \"weblayer_browsertests\": \"84afef12812783f5ebc1b78affee12eecdec6665\", \"weblayer_unittests\": \"45a0c1c8deb6c3cf53aa31d0022d06e7d63217cb\", \"wm_unittests\": \"d2f02ac50eba64d8f2776a90b27e4278c4316352\", \"wtf_unittests\": \"4aa405c3d623fe674919940698bd7c75eccbc15f\", \"x11_unittests\": \"7b2cb22703ed726cfa27f141435d78667321a67a\", \"xr_browser_tests\": \"a0558af9d182a1809f1ae1d527aae2f998880ba2\", \"zlib_unittests\": \"3738d0f729fb85d1cbad2dc6df0f8a48eb754178\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1143bf8a0c86d96d189f99a4f0f749b4f3d1e46c849c82cdd2c6ebe168938b3a_v2\", \"builder_780c9145325dc86747733fbdb99013a37dc03cbcb79ce91d7b045cb0873240e8_v2\", \"builder_a6d905fb3e765cf980c6458181cc20495ddb667b30aa758edddbed3b46166dd6_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-73-4ksf\"], \"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\": [\"5724-4bacdc7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614659385211588",
"status": "COMPLETED",
"status_changed_ts": "1614662904209521",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181029",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b9b8f4bd0210b49aa0deb21e60dec944313840f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9b8f4bd0210b49aa0deb21e60dec944313840f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019330752137882320",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853900689999589616/+/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:520a03bc09e8fd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614662904263734",
"url": "https://ci.chromium.org/b/8853900689999589616",
"utcnow_ts": "1614662904561067"
}
}
[05:28:24.569] Invocation finished in 59m31.848396695s with status SUCCEEDED