[03:36:20.225] New invocation is queued and will start shortly
[03:36:21.472] Starting the invocation (attempt 1)
[03:36:21.537] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:36:21.537] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:36:21.537] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059015529070773360",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cb57107ee83150ae38ec04c4bdf4eca152841526\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059015529070773360",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/cb57107ee83150ae38ec04c4bdf4eca152841526",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb57107ee83150ae38ec04c4bdf4eca152841526",
"gitiles_ref:refs/heads/master"
]
}
[03:36:26.422] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576812985324781",
"id": "8893585463059086912",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cb57107ee83150ae38ec04c4bdf4eca152841526\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576812986165034",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166340",
"builder:Linux Builder (dbg)",
"buildset:commit/git/cb57107ee83150ae38ec04c4bdf4eca152841526",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb57107ee83150ae38ec04c4bdf4eca152841526",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059015529070773360",
"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/8893585463059086912/+/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": "1576812986165215",
"url": "https://ci.chromium.org/b/8893585463059086912",
"utcnow_ts": "1576812986397610"
}
}
[03:36:26.422] Task URL: https://ci.chromium.org/b/8893585463059086912
[03:36:26.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:2:0) after 1m0s
[03:37:26.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:2:0)
[03:37:26.522] Timer tick, asking Buildbucket for the build status
[03:37:27.260] Build 8893585463059086912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:27.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:3:0) after 1m0s
[03:38:27.348] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:3:0)
[03:38:27.348] Timer tick, asking Buildbucket for the build status
[03:38:27.669] Build 8893585463059086912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:27.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:4:0) after 1m0s
[03:39:27.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:4:0)
[03:39:27.769] Timer tick, asking Buildbucket for the build status
[03:39:28.018] Build 8893585463059086912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:28.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:5:0) after 1m0s
[03:40:28.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:5:0)
[03:40:28.107] Timer tick, asking Buildbucket for the build status
[03:40:28.620] Build 8893585463059086912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:28.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:6:0) after 1m0s
[03:41:05.484] Received PubSub notification, asking Buildbucket for the build status
[03:41:05.837] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:28.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:6:0)
[03:41:28.623] Timer tick, asking Buildbucket for the build status
[03:41:28.954] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:28.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:8:0) after 1m0s
[03:42:29.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:8:0)
[03:42:29.082] Timer tick, asking Buildbucket for the build status
[03:42:29.758] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:9:0) after 1m0s
[03:43:29.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:9:0)
[03:43:29.947] Timer tick, asking Buildbucket for the build status
[03:43:30.568] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:30.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:10:0) after 1m0s
[03:44:30.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:10:0)
[03:44:30.617] Timer tick, asking Buildbucket for the build status
[03:44:30.876] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:30.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:11:0) after 1m0s
[03:45:30.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:11:0)
[03:45:30.902] Timer tick, asking Buildbucket for the build status
[03:45:31.146] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:31.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:12:0) after 1m0s
[03:46:31.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:12:0)
[03:46:31.242] Timer tick, asking Buildbucket for the build status
[03:46:32.835] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:32.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:13:0) after 1m0s
[03:47:32.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:13:0)
[03:47:32.968] Timer tick, asking Buildbucket for the build status
[03:47:33.460] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:14:0) after 1m0s
[03:48:33.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:14:0)
[03:48:33.588] Timer tick, asking Buildbucket for the build status
[03:48:34.494] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:34.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:15:0) after 1m0s
[03:49:34.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:15:0)
[03:49:34.673] Timer tick, asking Buildbucket for the build status
[03:49:34.862] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:34.862] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:16:0) after 1m0s
[03:50:35.307] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:16:0)
[03:50:35.307] Timer tick, asking Buildbucket for the build status
[03:50:35.828] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:35.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:17:0) after 1m0s
[03:51:35.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:17:0)
[03:51:35.870] Timer tick, asking Buildbucket for the build status
[03:51:36.183] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:36.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:18:0) after 1m0s
[03:52:36.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:18:0)
[03:52:36.291] Timer tick, asking Buildbucket for the build status
[03:52:36.602] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:36.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:19:0) after 1m0s
[03:53:36.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:19:0)
[03:53:36.647] Timer tick, asking Buildbucket for the build status
[03:53:37.709] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:37.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:20:0) after 1m0s
[03:54:37.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:20:0)
[03:54:37.716] Timer tick, asking Buildbucket for the build status
[03:54:38.292] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:38.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:21:0) after 1m0s
[03:55:38.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:21:0)
[03:55:38.346] Timer tick, asking Buildbucket for the build status
[03:55:38.677] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:38.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:22:0) after 1m0s
[03:56:38.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:22:0)
[03:56:38.655] Timer tick, asking Buildbucket for the build status
[03:56:38.807] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:38.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:23:0) after 1m0s
[03:57:38.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:23:0)
[03:57:38.827] Timer tick, asking Buildbucket for the build status
[03:57:39.065] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:39.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:24:0) after 1m0s
[03:58:39.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:24:0)
[03:58:39.218] Timer tick, asking Buildbucket for the build status
[03:58:39.424] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:39.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:25:0) after 1m0s
[03:59:39.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:25:0)
[03:59:39.906] Timer tick, asking Buildbucket for the build status
[03:59:40.156] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:26:0) after 1m0s
[04:00:40.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:26:0)
[04:00:40.188] Timer tick, asking Buildbucket for the build status
[04:00:40.535] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:40.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:27:0) after 1m0s
[04:01:40.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:27:0)
[04:01:40.660] Timer tick, asking Buildbucket for the build status
[04:01:41.321] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:41.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:28:0) after 1m0s
[04:02:41.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:28:0)
[04:02:41.410] Timer tick, asking Buildbucket for the build status
[04:02:41.960] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:41.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:29:0) after 1m0s
[04:03:41.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:29:0)
[04:03:41.937] Timer tick, asking Buildbucket for the build status
[04:03:43.003] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:43.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:30:0) after 1m0s
[04:04:43.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:30:0)
[04:04:43.117] Timer tick, asking Buildbucket for the build status
[04:04:43.346] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:43.346] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:31:0) after 1m0s
[04:05:43.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:31:0)
[04:05:43.422] Timer tick, asking Buildbucket for the build status
[04:05:45.305] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:45.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:32:0) after 1m0s
[04:06:45.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:32:0)
[04:06:45.358] Timer tick, asking Buildbucket for the build status
[04:06:47.295] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:47.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:33:0) after 1m0s
[04:07:47.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:33:0)
[04:07:47.508] Timer tick, asking Buildbucket for the build status
[04:07:47.658] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:47.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:34:0) after 1m0s
[04:08:47.707] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:34:0)
[04:08:47.707] Timer tick, asking Buildbucket for the build status
[04:08:47.901] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:47.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:35:0) after 1m0s
[04:09:47.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:35:0)
[04:09:47.949] Timer tick, asking Buildbucket for the build status
[04:09:48.143] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:48.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:36:0) after 1m0s
[04:10:48.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:36:0)
[04:10:48.516] Timer tick, asking Buildbucket for the build status
[04:10:48.700] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:48.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:37:0) after 1m0s
[04:11:48.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:37:0)
[04:11:48.823] Timer tick, asking Buildbucket for the build status
[04:11:49.012] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:49.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:38:0) after 1m0s
[04:12:49.081] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:38:0)
[04:12:49.081] Timer tick, asking Buildbucket for the build status
[04:12:49.351] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:49.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:39:0) after 1m0s
[04:13:49.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:39:0)
[04:13:49.411] Timer tick, asking Buildbucket for the build status
[04:13:49.896] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:49.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:40:0) after 1m0s
[04:14:50.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:40:0)
[04:14:50.057] Timer tick, asking Buildbucket for the build status
[04:14:50.603] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:50.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:41:0) after 1m0s
[04:15:50.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:41:0)
[04:15:50.623] Timer tick, asking Buildbucket for the build status
[04:15:51.430] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:51.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:42:0) after 1m0s
[04:16:51.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:42:0)
[04:16:51.452] Timer tick, asking Buildbucket for the build status
[04:16:51.711] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:51.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:43:0) after 1m0s
[04:17:51.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:43:0)
[04:17:51.882] Timer tick, asking Buildbucket for the build status
[04:17:52.743] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:52.743] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:44:0) after 1m0s
[04:18:52.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:44:0)
[04:18:52.775] Timer tick, asking Buildbucket for the build status
[04:18:53.238] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:53.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:45:0) after 1m0s
[04:19:53.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:45:0)
[04:19:53.255] Timer tick, asking Buildbucket for the build status
[04:19:53.972] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:53.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:46:0) after 1m0s
[04:20:53.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:46:0)
[04:20:53.995] Timer tick, asking Buildbucket for the build status
[04:20:54.544] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:54.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:47:0) after 1m0s
[04:21:54.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:47:0)
[04:21:54.637] Timer tick, asking Buildbucket for the build status
[04:21:55.451] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:55.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:48:0) after 1m0s
[04:22:55.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:48:0)
[04:22:55.538] Timer tick, asking Buildbucket for the build status
[04:22:55.907] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:55.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:49:0) after 1m0s
[04:23:55.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:49:0)
[04:23:55.907] Timer tick, asking Buildbucket for the build status
[04:23:56.187] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:56.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:50:0) after 1m0s
[04:24:56.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:50:0)
[04:24:56.174] Timer tick, asking Buildbucket for the build status
[04:24:56.418] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:56.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:51:0) after 1m0s
[04:25:56.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:51:0)
[04:25:56.446] Timer tick, asking Buildbucket for the build status
[04:25:57.037] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:52:0) after 1m0s
[04:26:57.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:52:0)
[04:26:57.146] Timer tick, asking Buildbucket for the build status
[04:26:57.480] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:53:0) after 1m0s
[04:27:57.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:53:0)
[04:27:57.713] Timer tick, asking Buildbucket for the build status
[04:27:57.998] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:54:0) after 1m0s
[04:28:58.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:54:0)
[04:28:58.208] Timer tick, asking Buildbucket for the build status
[04:28:59.811] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:59.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:55:0) after 1m0s
[04:29:59.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:55:0)
[04:29:59.890] Timer tick, asking Buildbucket for the build status
[04:30:00.311] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:00.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:56:0) after 1m0s
[04:31:00.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:56:0)
[04:31:00.353] Timer tick, asking Buildbucket for the build status
[04:31:00.740] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:00.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:57:0) after 1m0s
[04:32:00.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:57:0)
[04:32:00.950] Timer tick, asking Buildbucket for the build status
[04:32:01.230] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:01.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:58:0) after 1m0s
[04:33:01.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:58:0)
[04:33:01.294] Timer tick, asking Buildbucket for the build status
[04:33:01.488] Build 8893585463059086912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:01.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059015529070773360:59:0) after 1m0s
[04:33:45.095] Received PubSub notification, asking Buildbucket for the build status
[04:33:45.332] Build 8893585463059086912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:33:45.333] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576816423770324",
"created_by": "project:chromium",
"created_ts": "1576812985324781",
"id": "8893585463059086912",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cb57107ee83150ae38ec04c4bdf4eca152841526\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-central1-b-8-102-6sks\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166340, \"got_angle_revision\": \"fe2ed33a56ddb5884e2093d4d627fbd4e10a1d5b\", \"got_dawn_revision\": \"d28b578b6b0927d79ebee58e407031dff3b93d9f\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"cb57107ee83150ae38ec04c4bdf4eca152841526\", \"got_revision_cp\": \"refs/heads/master@{#726678}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"6d29348ddb7e9c77f75406c40845e6c450573055\", \"got_v8_revision\": \"3a72085df6900a9d27cda15696d37938d8ce761a\", \"got_v8_revision_cp\": \"refs/heads/8.1.61@{#1}\", \"got_webrtc_revision\": \"768c5f438cad7b65a05f8a9fe79d4049329e5475\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30108}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cb57107ee83150ae38ec04c4bdf4eca152841526\", \"swarm_hashes_refs/heads/master(at){#726678}_without_patch\": {\"accessibility_unittests\": \"9dcc9985f20573fd6bbb904bb026028f7b81604d\", \"angle_unittests\": \"7848db9fe7202e9b0b57637c8a5e8cec2a928918\", \"app_shell_unittests\": \"b2fc34a5e56a1beb4cc4c5c0e7358f090f626e1f\", \"aura_unittests\": \"98528d0bcb0aa503a587d5f55f928dd6e5103c40\", \"base_unittests\": \"0a7110bb126bbf65af4ccbd39a8ee1665f7f8979\", \"base_util_unittests\": \"ee6a8c278885f959c8422b453c7dfe55496015df\", \"blink_common_unittests\": \"39abce78d6d1175348504beb85d7d8b9aac2a3fe\", \"blink_fuzzer_unittests\": \"0d84e1961e63a6b6fb47b989219359000bc4b38d\", \"blink_heap_unittests\": \"5de0f50c97cf3956dc8549378a6d70bef51ae4e9\", \"blink_platform_unittests\": \"52381d8e1e583a9cd01651429968b5c977b3097c\", \"blink_python_tests\": \"40ed3056e49d603f35b0d05d997504004f64af86\", \"blink_unittests\": \"3287b031d913c36cf50e347f139937f323879568\", \"blink_web_tests\": \"27afc973803c61a3c0639b642d83e0a3a61023e9\", \"boringssl_crypto_tests\": \"315f7be48dba60e0706b26a2c452059954e2e0c9\", \"boringssl_ssl_tests\": \"72eb44209dec44ff4a268960cfa9e42157e3e537\", \"browser_tests\": \"642c6d99b73124a6e39c293c076fe14e71bc7d78\", \"cacheinvalidation_unittests\": \"7631ee9aa85c21e2c5eccd1181f836a7d1d243c3\", \"capture_unittests\": \"8895d6fc7d6d45460123d8e379019280e41da291\", \"cast_unittests\": \"c21b8f0dd986fc2aabf9868fbc3e63a455bd0155\", \"cc_unittests\": \"af9327adcaa50a3a407ef83950b8ee6cd2da1493\", \"chrome_app_unittests\": \"f09397a9fcb4dc676471137d5c139b53b9e79755\", \"chromedriver_unittests\": \"0beee6b828db5eedba31d46aa4b4e2a9ff845fe4\", \"components_browsertests\": \"3dbb99f0ed9c6c7b023e170cf575c8b6b3df4901\", \"components_unittests\": \"cb251d93bcd239c555eb765df3a43ffb19024287\", \"compositor_unittests\": \"e090ea5335a1739fc75b87b15be72c4324ece1a8\", \"content_browsertests\": \"bad863836f396f9c80dd5e4b3ae9c2dc00c4237f\", \"content_shell_crash_test\": \"d21cd699f21f32342d1d0c90bbcefeacdb5a3b56\", \"content_unittests\": \"186b753d5fd18c7e3ff85fb324243a4e74b2bc8d\", \"crashpad_tests\": \"09486f4b4227e012c3d7a76f0a8544d48abaee47\", \"cronet_tests\": \"c71d3aae5b085c2aa45d6e5e52c8799ad9334cd9\", \"cronet_unittests\": \"4dc80d07e5073b216b786e8f09cae5d37a334cb2\", \"crypto_unittests\": \"127ee2d945b94649d06cd8cb708a3ae0555db338\", \"dbus_unittests\": \"1781af9b6b9bc0c8c1ffcca58b1c4a3bf5000d98\", \"device_unittests\": \"bf05f5f166febb2148511111eddfb2bd8d226ff4\", \"devtools_lint_check\": \"6b09e7ea9c78ba508661237ba624d4cdfe3194fd\", \"devtools_type_check\": \"f1b9bb6723b68913c7b7dee5b213d7497f8434d8\", \"display_unittests\": \"66cf6638851318a5c98efa82387d42c87215db54\", \"events_unittests\": \"564814b9f6ae9eb1420bfd292d9422af4ac9ccb4\", \"extensions_browsertests\": \"bef3b3eacf34368cb09eb972b101275985aa71aa\", \"extensions_unittests\": \"6b0456bec34e2ca9475b0970968258bde37bc04c\", \"filesystem_service_unittests\": \"7cc4177b42ee3b37b6c60736e5b86ff355f0d55e\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"e8eb6272fea4a25ffae23054e6eaecf176e62139\", \"gfx_unittests\": \"5408adfac8e2b6f8a1296aac61e0652c83bdcf4b\", \"gin_unittests\": \"d219c0f8434a3f0d040d4dbb51c41f4684e02f25\", \"google_apis_unittests\": \"e0050b7c03255b7db3ed60c7e87b140c36273f74\", \"gpu_unittests\": \"c35a585bdee9fa62164fada23ac7afc95aeef495\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"cae3f3a84d151c8a3764152895985b80852fcd82\", \"headless_browsertests\": \"2916cc84febe7636dfe7388836d35dbc32930e32\", \"headless_unittests\": \"37baa2d40713b860648efe8c9470a5da316bf2f1\", \"interactive_ui_tests\": \"b90f5244d7fd7cc5f3438d5d9edcb86bae9a8883\", \"ipc_tests\": \"26ef636d63916ab10dab5d77b4424a7c652f3389\", \"jingle_unittests\": \"e336d7bff14989e31ae6f3c6cd42e0a7a15f9bc3\", \"latency_unittests\": \"b455c9fe97cd6317389971fcef222e0e2c067e8c\", \"libjingle_xmpp_unittests\": \"cef70bb7926905bd17482fea6cf3dad2685bb599\", \"media_blink_unittests\": \"963dba08f2a76c747ed07096afb4c3d6c7c0aeab\", \"media_service_unittests\": \"af0d4e3eac948e423500ce5131410965e4be1f01\", \"media_unittests\": \"bd0f7788f1cba857ae3c009153a7271774959394\", \"message_center_unittests\": \"4c6d8698e695e7b7cabe17e90e9ab1877a3d069b\", \"metrics_python_tests\": \"d41c03356a8ad44a89adf7ae8db5b855bc029801\", \"midi_unittests\": \"48258738bbc49dabca7f399952af835af43ba636\", \"mojo_core_unittests\": \"7a7d31ff4c50f2b7440afbb8cd8d0b82cd3dbe07\", \"mojo_unittests\": \"b3edfe837e66e2aefb6d76cf5ff2d1e2562b5e90\", \"nacl_helper_nonsfi_unittests\": \"f8e73980dfed9beff7bf189c752644b288caa05f\", \"nacl_loader_unittests\": \"5e321d20d09f096f0d76b2d4aafee603eefe893d\", \"native_theme_unittests\": \"34d9d2f1663f0ea8284291a72e30c924fb1bad48\", \"net_unittests\": \"b92c189627c0e00a8cc686982a375405973167c5\", \"pdf_unittests\": \"8594bd0d7e3068996fa0df7214c1ef17a7d49f17\", \"perfetto_unittests\": \"581602cc447533025aa71c3d07f0d1eb82239821\", \"ppapi_unittests\": \"3ecafc52c7bdc082d4b1bbb0f0fe37b94a3ca447\", \"printing_unittests\": \"6df771bb75ebecb1cf3f1880ad86e0174cbdb833\", \"remoting_unittests\": \"ce52983e2963bd265d12cac93659475e459e41c6\", \"sandbox_linux_unittests\": \"590f582672f747870b66d231d3f2497d0b19e9a0\", \"service_manager_unittests\": \"96631ce862dd7630fd8ed36dd6c04de2f5ee3fbf\", \"services_unittests\": \"cd227d00b3c5ac67d2d0ba7b79365eb8c0e8b8b9\", \"shell_dialogs_unittests\": \"b1e77e14a2ddc31c2054b0cf7d67cf0c950d4191\", \"skia_unittests\": \"507a8b88560da3c6fd6b82d33092c479c4a4bb52\", \"snapshot_unittests\": \"6ce540003ed7201134a1447f237da67074742849\", \"sql_unittests\": \"1157de5998aa0f7570a3e5a3d68975cc517c0416\", \"storage_unittests\": \"98a412eace6d743e26bdae3a6a43cdd6b105ad4d\", \"sync_integration_tests\": \"93e9b5455e0959880e19e97a2facd1ae753dcf98\", \"telemetry_gpu_unittests\": \"9822c4f3917cda643f7885d8598ff1291e85bfb7\", \"telemetry_perf_unittests\": \"cd70b150f8f4f8446c121198ba30f50e0c32b680\", \"telemetry_unittests\": \"86ceb2a52da2df605ea9e888ca82cb85b1178a15\", \"traffic_annotation_auditor_unittests\": \"25d3dc75eb5dfcde44699c162bff0c4c0b657900\", \"ui_base_unittests\": \"c28c3553ab8026645006334b15235e56978ce9fa\", \"ui_touch_selection_unittests\": \"1c86178893c7f217d1dca978349ba3b473eedaa1\", \"unit_tests\": \"5f5f5a5bdaedf896c9ca8e45e4248f364743521e\", \"url_unittests\": \"00562b9a965bba8a814dcb2285e440fa36017fe7\", \"views_perftests\": \"ddb5e634f1b84d54990354608575ac78524437bc\", \"views_unittests\": \"90c1be1f7d54827a58a0b2cdc5b6b2a604174ef6\", \"viz_unittests\": \"1ad8ac35c6980542586c623d1f4394cf9d1dc52a\", \"vr_common_unittests\": \"e0aa9c3f6f0434e0251580c9f5196b76906e6076\", \"vr_pixeltests\": \"67f654565030ba4552c814bc4e3cde47ba1c2efe\", \"webdriver_wpt_tests\": \"9c0de05133fd84d22d1d57ea766efc741414edbf\", \"weblayer_browsertests\": \"b4c719ba5f19a0d72ac0266a988be4ce634c8007\", \"wm_unittests\": \"cd8cd8719a7bcb160d2e1c8ddfbd936055637f34\", \"wtf_unittests\": \"81bc08533550fa7c93131c33efe84c39206298da\", \"xr_browser_tests\": \"b9473fa7c30796ab56c0a81abf6503f6004daa75\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_53a0dbabd2cebac1b8e64bcbfab336eb0021d1dc40b2d4ba5321eb0a070d508c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-102-6sks\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4733-6bd8c41\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576813264434945",
"status": "COMPLETED",
"status_changed_ts": "1576816424428077",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166340",
"builder:Linux Builder (dbg)",
"buildset:commit/git/cb57107ee83150ae38ec04c4bdf4eca152841526",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb57107ee83150ae38ec04c4bdf4eca152841526",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059015529070773360",
"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/8893585463059086912/+/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:493a3249c62af210",
"user_agent:luci-scheduler"
],
"updated_ts": "1576816424509801",
"url": "https://ci.chromium.org/b/8893585463059086912",
"utcnow_ts": "1576816425318680"
}
}
[04:33:45.333] Invocation finished in 57m25.125473519s with status SUCCEEDED