[18:29:24.440] New invocation is queued and will start shortly
[18:29:25.575] Starting the invocation (attempt 1)
[18:29:25.631] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:29:25.631] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:29:25.631] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052889345198226656",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"edb1aeb93c7134baab33a0458aaeced4260cd381\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052889345198226656",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/edb1aeb93c7134baab33a0458aaeced4260cd381",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb1aeb93c7134baab33a0458aaeced4260cd381",
"gitiles_ref:refs/heads/master"
]
}
[18:29:26.591] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582655365865103",
"id": "8887459283041450960",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"edb1aeb93c7134baab33a0458aaeced4260cd381\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582655366373678",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169016",
"builder:Linux Builder (dbg)",
"buildset:commit/git/edb1aeb93c7134baab33a0458aaeced4260cd381",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb1aeb93c7134baab33a0458aaeced4260cd381",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052889345198226656",
"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/8887459283041450960/+/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": "1582655366373826",
"url": "https://ci.chromium.org/b/8887459283041450960",
"utcnow_ts": "1582655366563137"
}
}
[18:29:26.591] Task URL: https://ci.chromium.org/b/8887459283041450960
[18:29:26.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:2:0) after 1m0s
[18:30:26.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:2:0)
[18:30:26.613] Timer tick, asking Buildbucket for the build status
[18:30:27.049] Build 8887459283041450960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:30:27.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:3:0) after 1m0s
[18:31:27.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:3:0)
[18:31:27.068] Timer tick, asking Buildbucket for the build status
[18:31:27.216] Build 8887459283041450960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:27.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:4:0) after 1m0s
[18:32:27.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:4:0)
[18:32:27.236] Timer tick, asking Buildbucket for the build status
[18:32:27.402] Build 8887459283041450960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:27.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:5:0) after 1m0s
[18:33:27.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:5:0)
[18:33:27.489] Timer tick, asking Buildbucket for the build status
[18:33:27.632] Build 8887459283041450960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:27.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:6:0) after 1m0s
[18:34:04.914] Received PubSub notification, asking Buildbucket for the build status
[18:34:05.036] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:27.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:6:0)
[18:34:27.722] Timer tick, asking Buildbucket for the build status
[18:34:27.805] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:27.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:8:0) after 1m0s
[18:35:27.875] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:8:0)
[18:35:27.875] Timer tick, asking Buildbucket for the build status
[18:35:28.049] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:28.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:9:0) after 1m0s
[18:36:28.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:9:0)
[18:36:28.049] Timer tick, asking Buildbucket for the build status
[18:36:28.263] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:28.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:10:0) after 1m0s
[18:37:28.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:10:0)
[18:37:28.349] Timer tick, asking Buildbucket for the build status
[18:37:28.556] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:28.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:11:0) after 1m0s
[18:38:28.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:11:0)
[18:38:28.606] Timer tick, asking Buildbucket for the build status
[18:38:28.704] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:28.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:12:0) after 1m0s
[18:39:28.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:12:0)
[18:39:28.833] Timer tick, asking Buildbucket for the build status
[18:39:29.042] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:29.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:13:0) after 1m0s
[18:40:29.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:13:0)
[18:40:29.063] Timer tick, asking Buildbucket for the build status
[18:40:29.379] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:29.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:14:0) after 1m0s
[18:41:29.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:14:0)
[18:41:29.367] Timer tick, asking Buildbucket for the build status
[18:41:29.485] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:29.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:15:0) after 1m0s
[18:42:29.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:15:0)
[18:42:29.483] Timer tick, asking Buildbucket for the build status
[18:42:29.715] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:29.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:16:0) after 1m0s
[18:43:29.753] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:16:0)
[18:43:29.753] Timer tick, asking Buildbucket for the build status
[18:43:29.871] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:29.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:17:0) after 1m0s
[18:44:29.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:17:0)
[18:44:29.890] Timer tick, asking Buildbucket for the build status
[18:44:30.086] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:30.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:18:0) after 1m0s
[18:45:30.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:18:0)
[18:45:30.154] Timer tick, asking Buildbucket for the build status
[18:45:30.299] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:30.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:19:0) after 1m0s
[18:46:30.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:19:0)
[18:46:30.373] Timer tick, asking Buildbucket for the build status
[18:46:30.552] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:30.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:20:0) after 1m0s
[18:47:30.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:20:0)
[18:47:30.643] Timer tick, asking Buildbucket for the build status
[18:47:30.780] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:30.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:21:0) after 1m0s
[18:48:30.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:21:0)
[18:48:30.860] Timer tick, asking Buildbucket for the build status
[18:48:31.615] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:31.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:22:0) after 1m0s
[18:49:31.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:22:0)
[18:49:31.715] Timer tick, asking Buildbucket for the build status
[18:49:31.803] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:31.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:23:0) after 1m0s
[18:50:31.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:23:0)
[18:50:31.879] Timer tick, asking Buildbucket for the build status
[18:50:32.012] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:32.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:24:0) after 1m0s
[18:51:32.122] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:24:0)
[18:51:32.122] Timer tick, asking Buildbucket for the build status
[18:51:32.229] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:32.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:25:0) after 1m0s
[18:52:32.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:25:0)
[18:52:32.248] Timer tick, asking Buildbucket for the build status
[18:52:32.529] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:32.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:26:0) after 1m0s
[18:53:32.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:26:0)
[18:53:32.607] Timer tick, asking Buildbucket for the build status
[18:53:32.691] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:32.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:27:0) after 1m0s
[18:54:32.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:27:0)
[18:54:32.730] Timer tick, asking Buildbucket for the build status
[18:54:33.005] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:33.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:28:0) after 1m0s
[18:55:32.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:28:0)
[18:55:32.984] Timer tick, asking Buildbucket for the build status
[18:55:33.563] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:33.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:29:0) after 1m0s
[18:56:33.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:29:0)
[18:56:33.632] Timer tick, asking Buildbucket for the build status
[18:56:33.769] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:33.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:30:0) after 1m0s
[18:57:33.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:30:0)
[18:57:33.802] Timer tick, asking Buildbucket for the build status
[18:57:33.966] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:33.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:31:0) after 1m0s
[18:58:34.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:31:0)
[18:58:34.004] Timer tick, asking Buildbucket for the build status
[18:58:34.095] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:34.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:32:0) after 1m0s
[18:59:34.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:32:0)
[18:59:34.185] Timer tick, asking Buildbucket for the build status
[18:59:34.320] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:34.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:33:0) after 1m0s
[19:00:34.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:33:0)
[19:00:34.382] Timer tick, asking Buildbucket for the build status
[19:00:34.609] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:34.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:34:0) after 1m0s
[19:01:34.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:34:0)
[19:01:34.680] Timer tick, asking Buildbucket for the build status
[19:01:35.036] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:35.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:35:0) after 1m0s
[19:02:35.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:35:0)
[19:02:35.082] Timer tick, asking Buildbucket for the build status
[19:02:35.284] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:35.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:36:0) after 1m0s
[19:03:35.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:36:0)
[19:03:35.341] Timer tick, asking Buildbucket for the build status
[19:03:35.429] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:35.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:37:0) after 1m0s
[19:04:35.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:37:0)
[19:04:35.509] Timer tick, asking Buildbucket for the build status
[19:04:35.813] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:35.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:38:0) after 1m0s
[19:05:35.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:38:0)
[19:05:35.878] Timer tick, asking Buildbucket for the build status
[19:05:36.276] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:36.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:39:0) after 1m0s
[19:06:36.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:39:0)
[19:06:36.309] Timer tick, asking Buildbucket for the build status
[19:06:37.688] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:37.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:40:0) after 1m0s
[19:07:37.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:40:0)
[19:07:37.673] Timer tick, asking Buildbucket for the build status
[19:07:37.765] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:37.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:41:0) after 1m0s
[19:08:37.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:41:0)
[19:08:37.784] Timer tick, asking Buildbucket for the build status
[19:08:37.868] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:37.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:42:0) after 1m0s
[19:09:37.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:42:0)
[19:09:37.893] Timer tick, asking Buildbucket for the build status
[19:09:37.994] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:37.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:43:0) after 1m0s
[19:10:37.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:43:0)
[19:10:37.979] Timer tick, asking Buildbucket for the build status
[19:10:38.073] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:38.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:44:0) after 1m0s
[19:11:38.110] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:44:0)
[19:11:38.110] Timer tick, asking Buildbucket for the build status
[19:11:38.224] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:38.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:45:0) after 1m0s
[19:12:38.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:45:0)
[19:12:38.278] Timer tick, asking Buildbucket for the build status
[19:12:38.645] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:38.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:46:0) after 1m0s
[19:13:38.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:46:0)
[19:13:38.860] Timer tick, asking Buildbucket for the build status
[19:13:38.988] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:38.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:47:0) after 1m0s
[19:14:39.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:47:0)
[19:14:39.304] Timer tick, asking Buildbucket for the build status
[19:14:39.409] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:39.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:48:0) after 1m0s
[19:15:39.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:48:0)
[19:15:39.584] Timer tick, asking Buildbucket for the build status
[19:15:39.840] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:39.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:49:0) after 1m0s
[19:16:39.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:49:0)
[19:16:39.959] Timer tick, asking Buildbucket for the build status
[19:16:40.084] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:40.084] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:50:0) after 1m0s
[19:17:40.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:50:0)
[19:17:40.105] Timer tick, asking Buildbucket for the build status
[19:17:40.437] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:40.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:51:0) after 1m0s
[19:18:40.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:51:0)
[19:18:40.501] Timer tick, asking Buildbucket for the build status
[19:18:40.667] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:40.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:52:0) after 1m0s
[19:19:40.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:52:0)
[19:19:40.664] Timer tick, asking Buildbucket for the build status
[19:19:40.754] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:40.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:53:0) after 1m0s
[19:20:40.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:53:0)
[19:20:40.829] Timer tick, asking Buildbucket for the build status
[19:20:40.989] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:40.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:54:0) after 1m0s
[19:21:41.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:54:0)
[19:21:41.016] Timer tick, asking Buildbucket for the build status
[19:21:41.129] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:41.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:55:0) after 1m0s
[19:22:41.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:55:0)
[19:22:41.209] Timer tick, asking Buildbucket for the build status
[19:22:41.304] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:41.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:56:0) after 1m0s
[19:23:41.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:56:0)
[19:23:41.309] Timer tick, asking Buildbucket for the build status
[19:23:41.404] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:41.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:57:0) after 1m0s
[19:24:41.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:57:0)
[19:24:41.531] Timer tick, asking Buildbucket for the build status
[19:24:41.731] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:41.731] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:58:0) after 1m0s
[19:25:41.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:58:0)
[19:25:41.745] Timer tick, asking Buildbucket for the build status
[19:25:41.847] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:41.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:59:0) after 1m0s
[19:26:41.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:59:0)
[19:26:41.877] Timer tick, asking Buildbucket for the build status
[19:26:42.001] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:42.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:60:0) after 1m0s
[19:27:42.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:60:0)
[19:27:42.049] Timer tick, asking Buildbucket for the build status
[19:27:42.298] Build 8887459283041450960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:42.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052889345198226656:61:0) after 1m0s
[19:27:56.974] Received PubSub notification, asking Buildbucket for the build status
[19:27:57.045] Build 8887459283041450960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:27:57.045] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582658876187705",
"created_by": "project:chromium",
"created_ts": "1582655365865103",
"id": "8887459283041450960",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"edb1aeb93c7134baab33a0458aaeced4260cd381\"}}",
"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-5-f6xx\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169016, \"got_angle_revision\": \"b185002d559b22e404f0c1a9c218fab874e4ee45\", \"got_dawn_revision\": \"5a921f8fadbaef0660024f6ac2fe3b4f9bdb02ea\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"edb1aeb93c7134baab33a0458aaeced4260cd381\", \"got_revision_cp\": \"refs/heads/master@{#744318}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"b1dd9acac569ceaf33803172f155f2a6aff57b6e\", \"got_v8_revision\": \"57083a22efbe63d75af8adb979583fa3aa0ff0d4\", \"got_v8_revision_cp\": \"refs/heads/8.2.173@{#1}\", \"got_webrtc_revision\": \"1dea1ea412021819bddbc9400dcca7d9dd2eba74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30581}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"edb1aeb93c7134baab33a0458aaeced4260cd381\", \"swarm_hashes_refs/heads/master(at){#744318}_without_patch\": {\"accessibility_unittests\": \"04bd0cec246ed2737af5b72966ff866bceee630d\", \"angle_unittests\": \"aa29768e6325aaf48502e91d5f1badacee1989ee\", \"app_shell_unittests\": \"07091b9a324704e02d5bdd3dfd94e18229970837\", \"aura_unittests\": \"c6ef33edf3498260b3ea699633139d2d379882e1\", \"base_unittests\": \"4d10b2e155cc3701d9ef7e36c3926e7e2406e681\", \"base_util_unittests\": \"b6008aadd938e6fc966a6c857adfb985cc68234c\", \"blink_common_unittests\": \"461c3a4859ab736e181eff5c42c0f679b6a4578f\", \"blink_fuzzer_unittests\": \"bdc8157fbb8fd93890f07355e9eb0c4d35a1f3ed\", \"blink_heap_unittests\": \"261c8dd12f7ed221d26acb59f5a7d38beb586735\", \"blink_platform_unittests\": \"7ba2de6a04d9783356429b6609c9e5d15765fecc\", \"blink_python_tests\": \"7a6ad02e5a060cbc0014b66e8ecdf6f82d82f012\", \"blink_unittests\": \"5e98fd63722dc77d1d2420b38bff80ffc2276ae3\", \"blink_web_tests\": \"f05e2a714eddd3472ac3abee7a0d6d1152e23af7\", \"boringssl_crypto_tests\": \"1fb99229fe54080ef848f70ebb8888d71364f007\", \"boringssl_ssl_tests\": \"1dd30eb402b2fc2a4ccd7461cd13aff9a9b38d13\", \"browser_tests\": \"421bc5450c9517b46740740a64f33832c309bd43\", \"cacheinvalidation_unittests\": \"8fb6ae8fce556ca2c25699bddd650768258b81ab\", \"capture_unittests\": \"f7cae2d1743e7268691f3eb2375831870a87dc13\", \"cast_unittests\": \"98af08ef7c4271faeb25352258b8b204f0d40081\", \"cc_unittests\": \"ac7e829bee255c0db72a7198b8d1dcb37d8dc721\", \"chrome_app_unittests\": \"027c5f7688ce20ec4106bb1f6e6a546bc148edd3\", \"chromedriver_unittests\": \"690a57b3d9a451d70e5d6a404d6560e88ed57e8b\", \"components_browsertests\": \"615017ee865d063d9fa56f46311f6123fac1af73\", \"components_unittests\": \"f54790b023b22c79e3aa257b8b8713e4fdb1dd8b\", \"compositor_unittests\": \"c7e5f6a9d3964a47610c7662a9c91630ba916ac9\", \"content_browsertests\": \"ae79f5dd30e96045e84309310ce7656f8450966c\", \"content_shell_crash_test\": \"665952e365face1196c42fac7b0f2d1ec6dda064\", \"content_unittests\": \"e4dadc241d438005c399288762e287c3fe95bfe1\", \"crashpad_tests\": \"d916bbb255b64fd72ce381600ab86025b3e3323c\", \"cronet_tests\": \"bfdbc1ed45fbe40e18a021968ecbd31dd7af15ba\", \"cronet_unittests\": \"689e1fd61b0afbc5fbf94948d146d8618725c09c\", \"crypto_unittests\": \"fd6b6e703e57ba45d92aa53273147708188047df\", \"dbus_unittests\": \"1c254b26dd59bad9670f40d5dbd9022d45c449f6\", \"device_unittests\": \"87cbe041664ef03962bf07e94e82605d506c7c8e\", \"display_unittests\": \"233d7d6d222f654866e492b98d412b7fce4a26c2\", \"events_unittests\": \"71c364eb83cd90ecf56ae6dd16bd3faf67f97418\", \"extensions_browsertests\": \"f9f6a57415d0dfc9734b6a30fa3aa95e09a6d6d0\", \"extensions_unittests\": \"ef0d7f15281f134c38c8a0efe9222f2d6d94eb1f\", \"filesystem_service_unittests\": \"0fca3ed1c056e129922ab62f5ec416baebdcd4be\", \"flatbuffers_unittests\": \"fddfee5d9c5c5357203fa42dff8f3c357607bc22\", \"gcm_unit_tests\": \"d92ff3b826e24e871598f4a6379dca69b049a557\", \"gfx_unittests\": \"44579a8fdd32c34d35d6cd6d71e95f60741d3afb\", \"gin_unittests\": \"98dda6af8055669f7f0226bd1abc8e66c627be0f\", \"google_apis_unittests\": \"2a15ed2de6863cc786afb664e45d28692cb24548\", \"gpu_unittests\": \"353c8e48f3461f792f9d2a1e631fc1e5d617694f\", \"grit_python_unittests\": \"8d3af1ca6ce7611b3ef6d2516a78cd61f915c28b\", \"gwp_asan_unittests\": \"e0521d1f80a51726f22ac9fe30a8043ae000d11e\", \"headless_browsertests\": \"d2cf17df054613501d722ba627aefc81d318d417\", \"headless_unittests\": \"2fe24044ab11e3f6665dd8fc5180d674458103de\", \"interactive_ui_tests\": \"165de7e0f814c4796f5cfce580a31faabfa5214e\", \"ipc_tests\": \"a4d0bd55d066a3c78e1bab8c3652d4848001aa7d\", \"jingle_unittests\": \"d2a01456fcb33c15947ca5ef6a44d712be41bfb4\", \"latency_unittests\": \"ccd2ec5ffbe352bbef34ef055f5ae89a1873bf5b\", \"libjingle_xmpp_unittests\": \"52789749bba7ab1665c734a2270bcb4e79a045a6\", \"media_blink_unittests\": \"be3c2458c69e827deac02224184185163c9b8758\", \"media_service_unittests\": \"da527f2df377b27eea81e832584f5fd4e9b8ac32\", \"media_unittests\": \"64476581c833a7b040c65a3dbdfe52f07afd34f5\", \"message_center_unittests\": \"1c14dbe3a7e7a39e38b285d605fd83c0ad5d8b19\", \"metrics_python_tests\": \"0bb161c37b7a069c4bb636a87683f0a44a9489e1\", \"midi_unittests\": \"552717e250e52f66ec49d29980294c2d5b973e82\", \"mojo_core_unittests\": \"a275845952a8cbddaa5c54d603a1d2c7c53cfbdc\", \"mojo_unittests\": \"ae1c82cb778390ec03d26e5c9d38c2780dbd1181\", \"nacl_helper_nonsfi_unittests\": \"a2a7e9df07d8f8c3f3e005126e18e0049c0f1f56\", \"nacl_loader_unittests\": \"01f6fbbc61ea1c5c0e59842cc51bb415b5f5719a\", \"native_theme_unittests\": \"b0f4147aecbfa5ba5663a293424d4766a6aafed0\", \"net_unittests\": \"4af615ac3743e664084bdb9d74b3f3dc8e65498d\", \"pdf_unittests\": \"83d3987087b30c5be9a53e5246e118ac36ab9949\", \"perfetto_unittests\": \"97e9ea30175ba41268cd65004a39c2ed8d5adc02\", \"ppapi_unittests\": \"f7464ffecb3054d233c92e6ac8a589489257bd56\", \"printing_unittests\": \"af7fe71c08e246756024a0b3d85fff897fa43b1e\", \"remoting_unittests\": \"cf92f16b239cb4a15bc203b665485ff636e45415\", \"sandbox_linux_unittests\": \"af8f782e5629c817ce0dbbc5eabb0b9d67a9b343\", \"service_manager_unittests\": \"49c8308b82681d71e601f5634efba9bc4de81c1c\", \"services_unittests\": \"94e2f22ac77fd1fb7c28b317d9ca2e1912691d56\", \"shell_dialogs_unittests\": \"fc6b5cf27407c7de2a9f1443e972e696071093cf\", \"skia_unittests\": \"3459faef5909612ebc1b8aa269118f05f3c6ed41\", \"snapshot_unittests\": \"79ac57b1eea6a52a7ca3470047d5ddfd44353409\", \"sql_unittests\": \"ed709721771464d53eb23e113f044f1fc8498d0c\", \"storage_unittests\": \"edae9662efcea51129308ab81c3c4da7287f4709\", \"sync_integration_tests\": \"76c7ed9775ed404b6a3634b51c8973bf1bd07ee2\", \"telemetry_gpu_unittests\": \"8eb613f12f492abf07d42d472ad1012ba5e5987e\", \"telemetry_perf_unittests\": \"73b533520e2155c813b375aa3c43c101a4c6af29\", \"telemetry_unittests\": \"6263bbfc461fa423121ee9b4389df0f7667b625c\", \"traffic_annotation_auditor_unittests\": \"5b10615903b89b655bebf36884cc1b973c4acd2d\", \"ui_base_unittests\": \"9efb455d781e605042dcfab790670c07c9312f3b\", \"ui_touch_selection_unittests\": \"0895e0c02625d94166406158b8cb3c4006485fca\", \"unit_tests\": \"857125916143854b34ab2478c77f1c4f78a385b4\", \"url_unittests\": \"de83ee60a6d38bcdb056fed6b7821f81f0fd03b6\", \"views_perftests\": \"27feff7002cae53277606247311a214c73dde9ef\", \"views_unittests\": \"92c0d78e3ab70b029e746502d253d017137bc810\", \"viz_unittests\": \"3a8a17f12ea97725067d40fe42ca8870b2c65fea\", \"vr_common_unittests\": \"b299c16b4b8b05be5ef96ba5bdc2cb0f6029d633\", \"vr_pixeltests\": \"b7918299c633947ccdee3bdb6f85736efc4101fd\", \"webdriver_wpt_tests\": \"b1b64d8f0807551c5c524838b084e0f2b3b481c4\", \"weblayer_browsertests\": \"1228f9db9d472299ed7efaf54d110390c51cfbf2\", \"wm_unittests\": \"f34b763fe419b2b6faea9decd07af602ec806d18\", \"wtf_unittests\": \"e67ed4fc17bd7e67b0d224102618daec2582333b\", \"xr_browser_tests\": \"d8d6472552a184a019194862deb52d72fb922bd9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_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-5-f6xx\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582655644090788",
"status": "COMPLETED",
"status_changed_ts": "1582658876666420",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169016",
"builder:Linux Builder (dbg)",
"buildset:commit/git/edb1aeb93c7134baab33a0458aaeced4260cd381",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb1aeb93c7134baab33a0458aaeced4260cd381",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052889345198226656",
"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/8887459283041450960/+/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:4a966deea9e96c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582658876670010",
"url": "https://ci.chromium.org/b/8887459283041450960",
"utcnow_ts": "1582658877035848"
}
}
[19:27:57.045] Invocation finished in 58m32.621077089s with status SUCCEEDED