[19:58:10.170] New invocation is queued and will start shortly
[19:58:11.378] Starting the invocation (attempt 1)
[19:58:11.440] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:58:11.440] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:58:11.440] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054514506162730256",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"804203607860f159fbb100b9a8351c472e6ba6b7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054514506162730256",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/804203607860f159fbb100b9a8351c472e6ba6b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/804203607860f159fbb100b9a8351c472e6ba6b7",
"gitiles_ref:refs/heads/master"
]
}
[19:58:13.147] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1581105491949966",
"id": "8889084443632578432",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"804203607860f159fbb100b9a8351c472e6ba6b7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581105492825378",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168351",
"builder:Linux Builder (dbg)",
"buildset:commit/git/804203607860f159fbb100b9a8351c472e6ba6b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/804203607860f159fbb100b9a8351c472e6ba6b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054514506162730256",
"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/8889084443632578432/+/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": "1581105492825781",
"url": "https://ci.chromium.org/b/8889084443632578432",
"utcnow_ts": "1581105493137637"
}
}
[19:58:13.147] Task URL: https://ci.chromium.org/b/8889084443632578432
[19:58:13.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:2:0) after 1m0s
[19:59:13.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:2:0)
[19:59:13.261] Timer tick, asking Buildbucket for the build status
[19:59:13.442] Build 8889084443632578432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:13.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:3:0) after 1m0s
[20:00:13.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:3:0)
[20:00:13.515] Timer tick, asking Buildbucket for the build status
[20:00:13.707] Build 8889084443632578432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:13.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:4:0) after 1m0s
[20:01:13.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:4:0)
[20:01:13.736] Timer tick, asking Buildbucket for the build status
[20:01:15.194] Build 8889084443632578432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:15.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:5:0) after 1m0s
[20:02:15.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:5:0)
[20:02:15.282] Timer tick, asking Buildbucket for the build status
[20:02:16.112] Build 8889084443632578432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:16.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:6:0) after 1m0s
[20:03:09.271] Received PubSub notification, asking Buildbucket for the build status
[20:03:09.427] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:16.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:6:0)
[20:03:16.138] Timer tick, asking Buildbucket for the build status
[20:03:16.310] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:16.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:8:0) after 1m0s
[20:04:16.360] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:8:0)
[20:04:16.360] Timer tick, asking Buildbucket for the build status
[20:04:16.543] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:16.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:9:0) after 1m0s
[20:05:16.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:9:0)
[20:05:16.625] Timer tick, asking Buildbucket for the build status
[20:05:17.478] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:17.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:10:0) after 1m0s
[20:06:17.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:10:0)
[20:06:17.534] Timer tick, asking Buildbucket for the build status
[20:06:17.928] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:17.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:11:0) after 1m0s
[20:07:17.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:11:0)
[20:07:17.974] Timer tick, asking Buildbucket for the build status
[20:07:18.129] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:18.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:12:0) after 1m0s
[20:08:18.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:12:0)
[20:08:18.125] Timer tick, asking Buildbucket for the build status
[20:08:18.404] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:18.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:13:0) after 1m0s
[20:09:18.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:13:0)
[20:09:18.430] Timer tick, asking Buildbucket for the build status
[20:09:18.673] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:18.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:14:0) after 1m0s
[20:10:18.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:14:0)
[20:10:18.725] Timer tick, asking Buildbucket for the build status
[20:10:18.931] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:18.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:15:0) after 1m0s
[20:11:19.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:15:0)
[20:11:19.052] Timer tick, asking Buildbucket for the build status
[20:11:19.291] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:19.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:16:0) after 1m0s
[20:12:19.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:16:0)
[20:12:19.381] Timer tick, asking Buildbucket for the build status
[20:12:19.533] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:19.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:17:0) after 1m0s
[20:13:19.571] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:17:0)
[20:13:19.571] Timer tick, asking Buildbucket for the build status
[20:13:19.838] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:19.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:18:0) after 1m0s
[20:14:19.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:18:0)
[20:14:19.882] Timer tick, asking Buildbucket for the build status
[20:14:20.309] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:20.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:19:0) after 1m0s
[20:15:20.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:19:0)
[20:15:20.358] Timer tick, asking Buildbucket for the build status
[20:15:20.675] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:20.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:20:0) after 1m0s
[20:16:20.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:20:0)
[20:16:20.721] Timer tick, asking Buildbucket for the build status
[20:16:21.151] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:21.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:21:0) after 1m0s
[20:17:21.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:21:0)
[20:17:21.294] Timer tick, asking Buildbucket for the build status
[20:17:21.509] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:21.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:22:0) after 1m0s
[20:18:21.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:22:0)
[20:18:21.558] Timer tick, asking Buildbucket for the build status
[20:18:22.968] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:23:0) after 1m0s
[20:19:23.074] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:23:0)
[20:19:23.074] Timer tick, asking Buildbucket for the build status
[20:19:23.485] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:23.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:24:0) after 1m0s
[20:20:23.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:24:0)
[20:20:23.593] Timer tick, asking Buildbucket for the build status
[20:20:23.761] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:23.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:25:0) after 1m0s
[20:21:23.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:25:0)
[20:21:23.871] Timer tick, asking Buildbucket for the build status
[20:21:24.395] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:24.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:26:0) after 1m0s
[20:22:24.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:26:0)
[20:22:24.446] Timer tick, asking Buildbucket for the build status
[20:22:24.595] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:24.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:27:0) after 1m0s
[20:23:24.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:27:0)
[20:23:24.644] Timer tick, asking Buildbucket for the build status
[20:23:24.905] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:24.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:28:0) after 1m0s
[20:24:24.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:28:0)
[20:24:24.984] Timer tick, asking Buildbucket for the build status
[20:24:25.295] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:25.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:29:0) after 1m0s
[20:25:25.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:29:0)
[20:25:25.322] Timer tick, asking Buildbucket for the build status
[20:25:25.510] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:25.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:30:0) after 1m0s
[20:26:25.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:30:0)
[20:26:25.598] Timer tick, asking Buildbucket for the build status
[20:26:25.998] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:25.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:31:0) after 1m0s
[20:27:26.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:31:0)
[20:27:26.067] Timer tick, asking Buildbucket for the build status
[20:27:26.253] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:26.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:32:0) after 1m0s
[20:28:26.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:32:0)
[20:28:26.283] Timer tick, asking Buildbucket for the build status
[20:28:26.812] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:26.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:33:0) after 1m0s
[20:29:26.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:33:0)
[20:29:26.887] Timer tick, asking Buildbucket for the build status
[20:29:27.098] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:27.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:34:0) after 1m0s
[20:30:27.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:34:0)
[20:30:27.194] Timer tick, asking Buildbucket for the build status
[20:30:27.430] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:27.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:35:0) after 1m0s
[20:31:27.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:35:0)
[20:31:27.491] Timer tick, asking Buildbucket for the build status
[20:31:28.109] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:28.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:36:0) after 1m0s
[20:32:28.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:36:0)
[20:32:28.229] Timer tick, asking Buildbucket for the build status
[20:32:28.762] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:28.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:37:0) after 1m0s
[20:33:28.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:37:0)
[20:33:28.801] Timer tick, asking Buildbucket for the build status
[20:33:28.959] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:28.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:38:0) after 1m0s
[20:34:29.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:38:0)
[20:34:29.075] Timer tick, asking Buildbucket for the build status
[20:34:29.249] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:29.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:39:0) after 1m0s
[20:35:29.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:39:0)
[20:35:29.294] Timer tick, asking Buildbucket for the build status
[20:35:29.421] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:29.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:40:0) after 1m0s
[20:36:29.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:40:0)
[20:36:29.424] Timer tick, asking Buildbucket for the build status
[20:36:29.852] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:29.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:41:0) after 1m0s
[20:37:29.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:41:0)
[20:37:29.916] Timer tick, asking Buildbucket for the build status
[20:37:30.082] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:30.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:42:0) after 1m0s
[20:38:30.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:42:0)
[20:38:30.158] Timer tick, asking Buildbucket for the build status
[20:38:30.362] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:30.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:43:0) after 1m0s
[20:39:30.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:43:0)
[20:39:30.411] Timer tick, asking Buildbucket for the build status
[20:39:30.591] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:30.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:44:0) after 1m0s
[20:40:30.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:44:0)
[20:40:30.625] Timer tick, asking Buildbucket for the build status
[20:40:30.794] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:30.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:45:0) after 1m0s
[20:41:30.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:45:0)
[20:41:30.878] Timer tick, asking Buildbucket for the build status
[20:41:31.037] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:31.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:46:0) after 1m0s
[20:42:31.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:46:0)
[20:42:31.078] Timer tick, asking Buildbucket for the build status
[20:42:31.740] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:31.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:47:0) after 1m0s
[20:43:31.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:47:0)
[20:43:31.806] Timer tick, asking Buildbucket for the build status
[20:43:32.077] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:32.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:48:0) after 1m0s
[20:44:32.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:48:0)
[20:44:32.117] Timer tick, asking Buildbucket for the build status
[20:44:32.265] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:32.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:49:0) after 1m0s
[20:45:32.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:49:0)
[20:45:32.326] Timer tick, asking Buildbucket for the build status
[20:45:32.621] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:32.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:50:0) after 1m0s
[20:46:32.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:50:0)
[20:46:32.719] Timer tick, asking Buildbucket for the build status
[20:46:32.903] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:32.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:51:0) after 1m0s
[20:47:32.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:51:0)
[20:47:32.954] Timer tick, asking Buildbucket for the build status
[20:47:33.169] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:33.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:52:0) after 1m0s
[20:48:33.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:52:0)
[20:48:33.200] Timer tick, asking Buildbucket for the build status
[20:48:33.331] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:33.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:53:0) after 1m0s
[20:49:33.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:53:0)
[20:49:33.342] Timer tick, asking Buildbucket for the build status
[20:49:33.571] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:33.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:54:0) after 1m0s
[20:50:33.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:54:0)
[20:50:33.629] Timer tick, asking Buildbucket for the build status
[20:50:33.816] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:33.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:55:0) after 1m0s
[20:51:33.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:55:0)
[20:51:33.859] Timer tick, asking Buildbucket for the build status
[20:51:34.898] Build 8889084443632578432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:34.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054514506162730256:56:0) after 1m0s
[20:51:49.607] Received PubSub notification, asking Buildbucket for the build status
[20:51:49.721] Build 8889084443632578432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:51:49.721] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1581108708537159",
"created_by": "project:chromium",
"created_ts": "1581105491949966",
"id": "8889084443632578432",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"804203607860f159fbb100b9a8351c472e6ba6b7\"}}",
"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\"}, \"$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-57-1ard\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168351, \"got_angle_revision\": \"51fd2958f9a27a70107ffbd2aec25c97c6daf1f1\", \"got_dawn_revision\": \"28e054e8cb7d593142d18742df9e337010357647\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"804203607860f159fbb100b9a8351c472e6ba6b7\", \"got_revision_cp\": \"refs/heads/master@{#739523}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"534d12cd423038c36b88e1a14e96e8a66e342900\", \"got_v8_revision\": \"8a8fa36a16213337b712dadb91de092d182ab9e1\", \"got_v8_revision_cp\": \"refs/heads/8.2.37@{#1}\", \"got_webrtc_revision\": \"8e8b36a94a7a7a1fd0f8093979a406afa56e18c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30484}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"804203607860f159fbb100b9a8351c472e6ba6b7\", \"swarm_hashes_refs/heads/master(at){#739523}_without_patch\": {\"accessibility_unittests\": \"d33a2ba649103ddc51b53740f614fef2e14835ee\", \"angle_unittests\": \"6873e51e73a84d2bc528c9dee5c41639b2a70b19\", \"app_shell_unittests\": \"87810ad646e7e6530cefc6bf340b4e98810da436\", \"aura_unittests\": \"2304e2923521a09f03ab0a24b8b45dd3dcf75586\", \"base_unittests\": \"48ef2fdffb6469d78f0499ac66921c629de68bf5\", \"base_util_unittests\": \"09e6c41524f2a514fcc89aa782529cabdb513aca\", \"blink_common_unittests\": \"3d04da4734383810c22a3d39bcf3d600ba8ee3fb\", \"blink_fuzzer_unittests\": \"f9c804504a3818561d0d6ceb1dd4316c5e51d148\", \"blink_heap_unittests\": \"23a720ec5b0438d34f149348e20f47dcd24a65ed\", \"blink_platform_unittests\": \"221031b662764c287a96ac0fc060a7b2c49e9337\", \"blink_python_tests\": \"bd1fc888639d90bb9d75abbacd4f5b4a18757932\", \"blink_unittests\": \"31325c2f6f66709e5ba7196f375ede95666a62c6\", \"blink_web_tests\": \"bc82ab3db4d109cf46988632ed7f6a651630191f\", \"boringssl_crypto_tests\": \"a10a169aa28122b4122bdeb0f64c700c8bf46507\", \"boringssl_ssl_tests\": \"bc5cd69267a8515d9e847f2b03d2067fdabc86ca\", \"browser_tests\": \"954286412de25d14bd78b04ad6f5aa4b4e9226c9\", \"cacheinvalidation_unittests\": \"f2964eee9064f6349c1800d9577e0d91db078200\", \"capture_unittests\": \"4b6a32f034f45652b865f7489a12cd04d54a7696\", \"cast_unittests\": \"7cff7e64c4b3ad4eab53d5de3a80bd02cee96eda\", \"cc_unittests\": \"dc4f7df07e39c5629e68c25135281780cb5f1d1f\", \"chrome_app_unittests\": \"47a7b5f0ffdbe6ffaee19be2c92fd3ea6c2229ad\", \"chromedriver_unittests\": \"a027558f77819b32125363f35a4b9b93ecd8e3ff\", \"components_browsertests\": \"8af0915f3040f0220d892a0eceec00c2d12213f8\", \"components_unittests\": \"1ef55d4ac3e8b9e7fc163a9fabe0e76fd9461d10\", \"compositor_unittests\": \"8fd9c97067f4dc8bd7269433f6e8ff11692720e9\", \"content_browsertests\": \"1a4c007214ddce08abbdbfc06f36c7bec6b77eb4\", \"content_shell_crash_test\": \"97015fea67b1ab7c0cac62c25258176cc0b888de\", \"content_unittests\": \"5db43a5c0a1313e84de374d87bb46018a30dda3e\", \"crashpad_tests\": \"51b1d37b0fd48ea4473d84f994fb1d1db7c77bac\", \"cronet_tests\": \"94e8afdd9c75609b56a3820f42eeb2b5ed456475\", \"cronet_unittests\": \"7423c78141203bbe0e941df0887dc33836902d08\", \"crypto_unittests\": \"da018043f50d9d3b7a233d656e6b612f675fb53e\", \"dbus_unittests\": \"7950e48e51f7f1989f606f49f7843e1395ef832d\", \"device_unittests\": \"ec383ebb0901966a8a1151d5a5e265d1d9e07a8f\", \"display_unittests\": \"05593e82afaf1041940fd948ffcc59081131f132\", \"events_unittests\": \"5cdfc2f6bfa956ac4a6b0a6fd2aa225ef0c7226b\", \"extensions_browsertests\": \"287e723d359d5e69062b912aa714165f8cf6964f\", \"extensions_unittests\": \"8f880db3cad8ac88dfecdb9049448cf05e7307a3\", \"filesystem_service_unittests\": \"8f4c8b5b7a5b3bf1f50870d5ec24549c7c3f40f3\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"050e23333f0b0eea53bbcdf16d74b1f461ce9a78\", \"gfx_unittests\": \"f0d24f9ad3a485906119b664c671693a3df0270f\", \"gin_unittests\": \"d88d792fb58645a7c76788974137cd700b2e2b68\", \"google_apis_unittests\": \"75c588a0b9498db2e0c3a36e3d86a6f88a6ea6ac\", \"gpu_unittests\": \"83d2795ed4df157cab3f6aa2fbdf205bce355f47\", \"grit_python_unittests\": \"8e4f4a4636015ac19fde39d2b2e52aa7150b26da\", \"gwp_asan_unittests\": \"d7fc741ea7c6bb3421d6034614fc16efa73dab04\", \"headless_browsertests\": \"0fd7e3e8ccd3dde1cebeeaf8d536dc8880240821\", \"headless_unittests\": \"38dfe0ecd38bc0df22aae7e8a0403afc08bf76ab\", \"interactive_ui_tests\": \"8fd0f20c031fbef721b18f65e281c2b561274d8e\", \"ipc_tests\": \"b34ce07924d447901f53ae744ae89461c50e31a2\", \"jingle_unittests\": \"b5440d81deb53692297a21fa9f79e0b4d679fb66\", \"latency_unittests\": \"2172e2826ffc06a580abd081bf0b684d9a0c0004\", \"libjingle_xmpp_unittests\": \"1ff1501a51e3cef0aff232eb096c1cd5c8f7b2a1\", \"media_blink_unittests\": \"10429b3f12ae811216ea010576818cc1e4caa152\", \"media_service_unittests\": \"f8ccc57dc8c920b1e0420023ab8fae969a9ff05a\", \"media_unittests\": \"f9de70c88ac47d82b44f6d43e11b855e6e3a89da\", \"message_center_unittests\": \"2163f6008a7076abec323b3d6c8f09024fdc8c4e\", \"metrics_python_tests\": \"a090c32348595dd9cc533b474057cb9235c4925c\", \"midi_unittests\": \"a4f370009c8b31f248c61d6f07fde26074c35f79\", \"mojo_core_unittests\": \"aa18c7f183e2d8bdce03bd7e444209f6601b69d4\", \"mojo_unittests\": \"e6bcc2495d7f3bb09aaa49f6bcb6cfa93a96aae1\", \"nacl_helper_nonsfi_unittests\": \"df474399098f1f79b0a952efcd82c6c3d09e145c\", \"nacl_loader_unittests\": \"9f11687699b9c79b12202d9bf7c60b3668f24005\", \"native_theme_unittests\": \"91e453d784724518a869b21df69f256f22e34cd8\", \"net_unittests\": \"6f40b122c564b502b06e78d771d8134e273b8996\", \"pdf_unittests\": \"86d1640639e57f91d3bf7c1783cafe95d4215781\", \"perfetto_unittests\": \"787612d83dae94926236fddc437dd2f3412907bb\", \"ppapi_unittests\": \"c0988d9fe9668cd478514fe3e5c28132957086ee\", \"printing_unittests\": \"d9764d297d93b780efe8cdaf756098a83c78b89c\", \"remoting_unittests\": \"686d45958951536b3ce41f77dd0c5649f8ed67f8\", \"sandbox_linux_unittests\": \"d89303ddbeb387c2412b01385e70a65d4e4ea8bd\", \"service_manager_unittests\": \"73cd9678561b8dd34c2424165b6dccd075da3689\", \"services_unittests\": \"695965be48c45363ee33bc6018c1f17c43079942\", \"shell_dialogs_unittests\": \"42a7a6c2a4dde70c9bdc5236c43968b552432ad2\", \"skia_unittests\": \"56ce43b9ec5eb15e72832c4084a4a21a3069ea42\", \"snapshot_unittests\": \"3055117bb72bb3119d7118ad8ea3a16f4fa44dde\", \"sql_unittests\": \"1847f8f72ab7a055794377234dd1bd991fb3c652\", \"storage_unittests\": \"443da5df38bdbb3f5e0d4d080b2216e7e7c140c1\", \"sync_integration_tests\": \"80c645bd321f29c78280191f9d286a0e4a781594\", \"telemetry_gpu_unittests\": \"cb22fd17161286af19cffd7cd91c4c53e1196d38\", \"telemetry_perf_unittests\": \"adc6e6cfa276e4aa19b057d95964cd01e9b34174\", \"telemetry_unittests\": \"6dd93e38a409cb423519ca405a4645acd42de856\", \"traffic_annotation_auditor_unittests\": \"36e660fbc2ecf08337ceaf6b7413d0640f310add\", \"ui_base_unittests\": \"ced1c24c7896c4185625e5cae8968cec1cdae75a\", \"ui_touch_selection_unittests\": \"5509b599222d20cf136e8eb6fe2e076cbb57463d\", \"unit_tests\": \"5290d3a5b5bb9ef2c1e88913a33783d434c36168\", \"url_unittests\": \"517a32b047945785664413f3d83367e7ab1c6544\", \"views_perftests\": \"e3b1079c1e7323527ae824cc0469ac49e6969240\", \"views_unittests\": \"08bf730ddb59a2262c216f0970c47addabea153b\", \"viz_unittests\": \"000ee12d7d999dd83a4aa16af1fc87013db9499c\", \"vr_common_unittests\": \"373d2a18b20067c92d72decc218043f925db66f8\", \"vr_pixeltests\": \"477e536673f21955cae0bcde34dfba9aad9114f5\", \"webdriver_wpt_tests\": \"ca116ffc25605af675e83aeac5080019aa161093\", \"weblayer_browsertests\": \"6970fe3a59b55d6d98af89c84ff27a8e8da05c43\", \"wm_unittests\": \"0dfcf9ed5741667e0477927bbdbe31104dcda590\", \"wtf_unittests\": \"f1c090a3a349d4b96f79f0b540bbdfcf1c8e3669\", \"xr_browser_tests\": \"59e5734085725de8aa137a0450ba2c0597c3151e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5ae174071c5d6730648bf4e0d5db68d27bfbde0d9c5b879a557aa1bc8e90c36b_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_ce52aa3ab9d9a450d9b5f3fb151dc5a055ee991845b7d9f6a360be4cd1eb07f1_v2\", \"builder_ddf530b99db2c48999a16b04f27f886d0f06df616543b36733eb8d0558bd23f5_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-57-1ard\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581105787992763",
"status": "COMPLETED",
"status_changed_ts": "1581108708995658",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168351",
"builder:Linux Builder (dbg)",
"buildset:commit/git/804203607860f159fbb100b9a8351c472e6ba6b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/804203607860f159fbb100b9a8351c472e6ba6b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054514506162730256",
"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/8889084443632578432/+/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:4a3a0cbcb2c32810",
"user_agent:luci-scheduler"
],
"updated_ts": "1581108709063695",
"url": "https://ci.chromium.org/b/8889084443632578432",
"utcnow_ts": "1581108709707801"
}
}
[20:51:49.721] Invocation finished in 53m39.568718649s with status SUCCEEDED