[04:00:49.146] New invocation is queued and will start shortly
[04:00:50.675] Starting the invocation (attempt 1)
[04:00:50.758] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:00:50.759] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:00:50.759] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9051947425419532272",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2714fec9be58a87e591bb1a859de72679ada1a4a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9051947425419532272",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/2714fec9be58a87e591bb1a859de72679ada1a4a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2714fec9be58a87e591bb1a859de72679ada1a4a",
"gitiles_ref:refs/heads/master"
]
}
[04:00:51.566] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1583553650894333",
"id": "8886517362918566240",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2714fec9be58a87e591bb1a859de72679ada1a4a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583553651390833",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169440",
"builder:Linux Builder (dbg)",
"buildset:commit/git/2714fec9be58a87e591bb1a859de72679ada1a4a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2714fec9be58a87e591bb1a859de72679ada1a4a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051947425419532272",
"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/8886517362918566240/+/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": "1583553651391048",
"url": "https://ci.chromium.org/b/8886517362918566240",
"utcnow_ts": "1583553651558454"
}
}
[04:00:51.566] Task URL: https://ci.chromium.org/b/8886517362918566240
[04:00:51.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:2:0) after 1m0s
[04:01:51.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:2:0)
[04:01:51.607] Timer tick, asking Buildbucket for the build status
[04:01:51.747] Build 8886517362918566240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:51.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:3:0) after 1m0s
[04:02:51.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:3:0)
[04:02:51.769] Timer tick, asking Buildbucket for the build status
[04:02:51.848] Build 8886517362918566240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:51.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:4:0) after 1m0s
[04:03:51.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:4:0)
[04:03:51.869] Timer tick, asking Buildbucket for the build status
[04:03:51.989] Build 8886517362918566240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:51.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:5:0) after 1m0s
[04:04:52.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:5:0)
[04:04:52.102] Timer tick, asking Buildbucket for the build status
[04:04:52.253] Build 8886517362918566240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:52.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:6:0) after 1m0s
[04:05:52.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:6:0)
[04:05:52.282] Timer tick, asking Buildbucket for the build status
[04:05:52.703] Build 8886517362918566240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:52.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:7:0) after 1m0s
[04:05:58.869] Received PubSub notification, asking Buildbucket for the build status
[04:05:59.071] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:52.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:7:0)
[04:06:52.755] Timer tick, asking Buildbucket for the build status
[04:06:52.826] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:52.826] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:9:0) after 1m0s
[04:07:52.861] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:9:0)
[04:07:52.861] Timer tick, asking Buildbucket for the build status
[04:07:53.168] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:53.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:10:0) after 1m0s
[04:08:53.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:10:0)
[04:08:53.189] Timer tick, asking Buildbucket for the build status
[04:08:53.306] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:53.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:11:0) after 1m0s
[04:09:53.362] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:11:0)
[04:09:53.363] Timer tick, asking Buildbucket for the build status
[04:09:53.652] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:53.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:12:0) after 1m0s
[04:10:53.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:12:0)
[04:10:53.704] Timer tick, asking Buildbucket for the build status
[04:10:54.573] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:54.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:13:0) after 1m0s
[04:11:54.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:13:0)
[04:11:54.591] Timer tick, asking Buildbucket for the build status
[04:11:54.689] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:54.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:14:0) after 1m0s
[04:12:54.748] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:14:0)
[04:12:54.748] Timer tick, asking Buildbucket for the build status
[04:12:54.906] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:54.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:15:0) after 1m0s
[04:13:54.926] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:15:0)
[04:13:54.926] Timer tick, asking Buildbucket for the build status
[04:13:55.055] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:55.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:16:0) after 1m0s
[04:14:55.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:16:0)
[04:14:55.059] Timer tick, asking Buildbucket for the build status
[04:14:55.203] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:55.203] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:17:0) after 1m0s
[04:15:55.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:17:0)
[04:15:55.224] Timer tick, asking Buildbucket for the build status
[04:15:55.332] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:55.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:18:0) after 1m0s
[04:16:55.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:18:0)
[04:16:55.354] Timer tick, asking Buildbucket for the build status
[04:16:55.453] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:55.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:19:0) after 1m0s
[04:17:55.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:19:0)
[04:17:55.485] Timer tick, asking Buildbucket for the build status
[04:17:55.595] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:55.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:20:0) after 1m0s
[04:18:55.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:20:0)
[04:18:55.704] Timer tick, asking Buildbucket for the build status
[04:18:55.817] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:55.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:21:0) after 1m0s
[04:19:55.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:21:0)
[04:19:55.877] Timer tick, asking Buildbucket for the build status
[04:19:55.984] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:55.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:22:0) after 1m0s
[04:20:56.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:22:0)
[04:20:56.048] Timer tick, asking Buildbucket for the build status
[04:20:56.142] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:56.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:23:0) after 1m0s
[04:21:56.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:23:0)
[04:21:56.216] Timer tick, asking Buildbucket for the build status
[04:21:56.327] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:24:0) after 1m0s
[04:22:56.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:24:0)
[04:22:56.535] Timer tick, asking Buildbucket for the build status
[04:22:56.871] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:56.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:25:0) after 1m0s
[04:23:56.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:25:0)
[04:23:56.963] Timer tick, asking Buildbucket for the build status
[04:23:57.199] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:57.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:26:0) after 1m0s
[04:24:57.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:26:0)
[04:24:57.225] Timer tick, asking Buildbucket for the build status
[04:24:57.335] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:57.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:27:0) after 1m0s
[04:25:57.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:27:0)
[04:25:57.393] Timer tick, asking Buildbucket for the build status
[04:25:57.542] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:28:0) after 1m0s
[04:26:57.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:28:0)
[04:26:57.574] Timer tick, asking Buildbucket for the build status
[04:26:57.681] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:29:0) after 1m0s
[04:27:57.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:29:0)
[04:27:57.702] Timer tick, asking Buildbucket for the build status
[04:27:57.798] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:30:0) after 1m0s
[04:28:57.824] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:30:0)
[04:28:57.824] Timer tick, asking Buildbucket for the build status
[04:28:57.917] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:57.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:31:0) after 1m0s
[04:29:58.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:31:0)
[04:29:58.104] Timer tick, asking Buildbucket for the build status
[04:29:58.283] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:32:0) after 1m0s
[04:30:58.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:32:0)
[04:30:58.385] Timer tick, asking Buildbucket for the build status
[04:30:58.504] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:58.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:33:0) after 1m0s
[04:31:58.546] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:33:0)
[04:31:58.546] Timer tick, asking Buildbucket for the build status
[04:31:58.646] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:34:0) after 1m0s
[04:32:58.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:34:0)
[04:32:58.641] Timer tick, asking Buildbucket for the build status
[04:32:58.732] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:58.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:35:0) after 1m0s
[04:33:58.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:35:0)
[04:33:58.761] Timer tick, asking Buildbucket for the build status
[04:33:58.873] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:58.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:36:0) after 1m0s
[04:34:58.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:36:0)
[04:34:58.892] Timer tick, asking Buildbucket for the build status
[04:34:59.038] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:59.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:37:0) after 1m0s
[04:35:59.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:37:0)
[04:35:59.058] Timer tick, asking Buildbucket for the build status
[04:35:59.146] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:59.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:38:0) after 1m0s
[04:36:59.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:38:0)
[04:36:59.194] Timer tick, asking Buildbucket for the build status
[04:36:59.293] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:39:0) after 1m0s
[04:37:59.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:39:0)
[04:37:59.299] Timer tick, asking Buildbucket for the build status
[04:37:59.351] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:40:0) after 1m0s
[04:38:59.330] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:40:0)
[04:38:59.330] Timer tick, asking Buildbucket for the build status
[04:38:59.445] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:41:0) after 1m0s
[04:39:59.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:41:0)
[04:39:59.508] Timer tick, asking Buildbucket for the build status
[04:39:59.629] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:59.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:42:0) after 1m0s
[04:40:59.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:42:0)
[04:40:59.642] Timer tick, asking Buildbucket for the build status
[04:40:59.773] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:59.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:43:0) after 1m0s
[04:41:59.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:43:0)
[04:41:59.823] Timer tick, asking Buildbucket for the build status
[04:41:59.922] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:59.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:44:0) after 1m0s
[04:42:59.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:44:0)
[04:42:59.983] Timer tick, asking Buildbucket for the build status
[04:43:00.095] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:00.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:45:0) after 1m0s
[04:44:00.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:45:0)
[04:44:00.115] Timer tick, asking Buildbucket for the build status
[04:44:00.234] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:00.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:46:0) after 1m0s
[04:45:00.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:46:0)
[04:45:00.273] Timer tick, asking Buildbucket for the build status
[04:45:00.478] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:00.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:47:0) after 1m0s
[04:46:00.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:47:0)
[04:46:00.554] Timer tick, asking Buildbucket for the build status
[04:46:00.662] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:00.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:48:0) after 1m0s
[04:47:00.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:48:0)
[04:47:00.848] Timer tick, asking Buildbucket for the build status
[04:47:01.054] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:01.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:49:0) after 1m0s
[04:48:01.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:49:0)
[04:48:01.197] Timer tick, asking Buildbucket for the build status
[04:48:01.502] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:01.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:50:0) after 1m0s
[04:49:01.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:50:0)
[04:49:01.668] Timer tick, asking Buildbucket for the build status
[04:49:01.831] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:01.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:51:0) after 1m0s
[04:50:01.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:51:0)
[04:50:01.913] Timer tick, asking Buildbucket for the build status
[04:50:02.138] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:02.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:52:0) after 1m0s
[04:51:02.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:52:0)
[04:51:02.268] Timer tick, asking Buildbucket for the build status
[04:51:02.388] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:02.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:53:0) after 1m0s
[04:52:02.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:53:0)
[04:52:02.601] Timer tick, asking Buildbucket for the build status
[04:52:02.962] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:02.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:54:0) after 1m0s
[04:53:03.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:54:0)
[04:53:03.049] Timer tick, asking Buildbucket for the build status
[04:53:03.194] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:03.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:55:0) after 1m0s
[04:54:03.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:55:0)
[04:54:03.252] Timer tick, asking Buildbucket for the build status
[04:54:03.407] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:03.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:56:0) after 1m0s
[04:55:03.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:56:0)
[04:55:03.401] Timer tick, asking Buildbucket for the build status
[04:55:03.568] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:03.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:57:0) after 1m0s
[04:56:03.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:57:0)
[04:56:03.587] Timer tick, asking Buildbucket for the build status
[04:56:03.786] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:03.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:58:0) after 1m0s
[04:57:03.798] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:58:0)
[04:57:03.798] Timer tick, asking Buildbucket for the build status
[04:57:04.203] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:04.203] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:59:0) after 1m0s
[04:58:04.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:59:0)
[04:58:04.262] Timer tick, asking Buildbucket for the build status
[04:58:04.949] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:04.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:60:0) after 1m0s
[04:59:05.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:60:0)
[04:59:05.035] Timer tick, asking Buildbucket for the build status
[04:59:05.198] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:05.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:61:0) after 1m0s
[05:00:05.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:61:0)
[05:00:05.193] Timer tick, asking Buildbucket for the build status
[05:00:05.435] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:05.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:62:0) after 1m0s
[05:01:05.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:62:0)
[05:01:05.531] Timer tick, asking Buildbucket for the build status
[05:01:05.841] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:05.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:63:0) after 1m0s
[05:02:05.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:63:0)
[05:02:05.941] Timer tick, asking Buildbucket for the build status
[05:02:07.245] Build 8886517362918566240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:07.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051947425419532272:64:0) after 1m0s
[05:02:07.915] Received PubSub notification, asking Buildbucket for the build status
[05:02:07.964] Build 8886517362918566240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:02:07.964] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1583557326891144",
"created_by": "project:chromium",
"created_ts": "1583553650894333",
"id": "8886517362918566240",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2714fec9be58a87e591bb1a859de72679ada1a4a\"}}",
"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-central1-b-8-2-5cxy\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169440, \"got_angle_revision\": \"03ed5f6c4698e7d158448b8845dd1be1b53b02fc\", \"got_dawn_revision\": \"7d20b4450182b8e25dfd315019623d7a746cc290\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"2714fec9be58a87e591bb1a859de72679ada1a4a\", \"got_revision_cp\": \"refs/heads/master@{#747990}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"b44162fdbb25996e4710026007bc98b7201f2a1d\", \"got_v8_revision\": \"d4ed59c4ed609eacc9062bd22ebca409ef7c6d1c\", \"got_v8_revision_cp\": \"refs/heads/8.2.263@{#1}\", \"got_webrtc_revision\": \"04f7bfc4192f5ce444b312afd287203ea53c99b6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30715}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2714fec9be58a87e591bb1a859de72679ada1a4a\", \"swarm_hashes_refs/heads/master(at){#747990}_without_patch\": {\"accessibility_unittests\": \"6710fa4859fcfba7e51b81364b0fe68d43fe7a09\", \"angle_unittests\": \"086d89bd4d93da2b6bf81b7f4d112be548627b12\", \"app_shell_unittests\": \"027cb7560c88a015f6572885279e8540342c01d9\", \"aura_unittests\": \"3273c8f7dacc5272ecaff58ca91d18fcb96bee5c\", \"base_unittests\": \"6bb56b05de13d7e7568f580042da398023d86a74\", \"base_util_unittests\": \"89d67c33a6e863a2c11890efbd53982662ffed59\", \"blink_common_unittests\": \"bedee91f23792081f87fa1914673d5f7cc365b9f\", \"blink_fuzzer_unittests\": \"8b7d848f83a1ab29adfef3187e73c07fa1ec722b\", \"blink_heap_unittests\": \"4db81c6b4e60855d2b2aee61c36cde12a9417815\", \"blink_platform_unittests\": \"4fd2625415c2de0e5707d377432819fc92ea80b9\", \"blink_python_tests\": \"439ea6a51f6818f040ebdbe584118ea5d4bc76fe\", \"blink_unittests\": \"1a8a045e218512fe9901e89eade8e181b1cb60b6\", \"blink_web_tests\": \"296a4a364347b7d32f260bb24ad5390dfe46e883\", \"boringssl_crypto_tests\": \"bce4c20f512adb80fc49721dc4ce926e8a96f190\", \"boringssl_ssl_tests\": \"a19f235dade6ca5140eee1ff3ce41fe23c2496e2\", \"browser_tests\": \"b52995f2db7fc84bf6c581a76656d21fa414f371\", \"cacheinvalidation_unittests\": \"7e66322c76a91628949cee9debed0d0f9b1aaf86\", \"capture_unittests\": \"b17e35cd67e17de57410b576e756a980aaa30e5c\", \"cast_unittests\": \"b9753bb04ef6357b16b37e2a27a312828d6ea141\", \"cc_unittests\": \"e3387ae9acfb38fadd3e526a5034601f1d688cd9\", \"chrome_app_unittests\": \"43480e47538ba269b2278148aa6c5b2c6a36e136\", \"chromedriver_unittests\": \"c52acd6102934a82d4eb732ad4a45409e7091c2e\", \"components_browsertests\": \"1a9d203f20c81cca991cac48874f41d717342d0d\", \"components_unittests\": \"e17e835caab98224a03ed26c977e5c5a52a20082\", \"compositor_unittests\": \"daaf3a9abde6a3c86c7df4982ed94e9eba9f3d7d\", \"content_browsertests\": \"f02573c5b77d26cdc0ef0af26767f191bb3126fc\", \"content_shell_crash_test\": \"50f0b50b81f0c158120a321939093f4f6307a945\", \"content_unittests\": \"c83320b6c0189124dc7b96f24fe1c7430fc2aac9\", \"crashpad_tests\": \"3f75bf2d1ed713ca0b910503d14f6cf5baf08dff\", \"cronet_tests\": \"5a8cec5f6e4acf81979b8555faa7277792c6ddaa\", \"cronet_unittests\": \"6fcf7cda9697b765fdaafa50f747ae7ab07c3c9e\", \"crypto_unittests\": \"65d9f84514012d727c2ede69788a4d77d7d8a234\", \"dbus_unittests\": \"fc36b049d8a3277bc0d60262795af238002c1f43\", \"device_unittests\": \"3ce2bead6c520d437735f6b41714fc22df59902d\", \"display_unittests\": \"fbe363a8ea7cb63ac1638df972e5ffb1965f6b0f\", \"events_unittests\": \"0870681cbdfe4657c0baca5cf1c612471f33bf64\", \"extensions_browsertests\": \"e5cf29f75f899641f517fa6ef3fcee499e12df3e\", \"extensions_unittests\": \"67e7b056345ea6cbe8a18ed899d369772ef590e5\", \"filesystem_service_unittests\": \"1e8a750f03c1e1036ec1b3ed481bfe0d5238af5e\", \"flatbuffers_unittests\": \"050844e0d26621703adfe93dfd2f97054fb85d6c\", \"gcm_unit_tests\": \"eed5473c7dce6f52ff0676c8e357036d292f51c2\", \"gfx_unittests\": \"2d0ce566335de7faadb8f01cbe472546142d370f\", \"gin_unittests\": \"dd35a36f8dd3eea91decb2f2216ec06dca5fc784\", \"google_apis_unittests\": \"24cdb06942c9cad7f1b4af32b33c9aaf1c185ea3\", \"gpu_unittests\": \"74391c809c0961022b7a716aade4ca980c7d56f5\", \"grit_python_unittests\": \"300ea8455288eab00cd459e887bd99a4f6468e8a\", \"gwp_asan_unittests\": \"0c8d12a9ad1ecc9aadcad6c0d8660be3d0e8d5dd\", \"headless_browsertests\": \"01df94720d788655ad62b541102cf5b8069a42aa\", \"headless_unittests\": \"5a833b2aa99740718d0013aa6a50027438e9f0b8\", \"interactive_ui_tests\": \"aadb72ca3629cc609285278eaebaa225d42062a8\", \"ipc_tests\": \"2837ed82f7d6c5b124e0dd6d2c5d0ea32592e205\", \"jingle_unittests\": \"1b5ced4d6b672415b22a646456c4efd339ec80ae\", \"latency_unittests\": \"9ec3390c4b476770ca96b7444c5cf59d16312817\", \"libjingle_xmpp_unittests\": \"e4f3f358a75aa3a0aff25185fc7216ff287af00d\", \"media_blink_unittests\": \"6f7432ab01ad1f2a10795958e373e1dff9b97a52\", \"media_service_unittests\": \"f3273d644baadecd5413d1c7006fa9706d98fded\", \"media_unittests\": \"296583ba4b8924196d0f7c6d245cebfe99035687\", \"message_center_unittests\": \"a66d43f1276f6acf5761822b58aa9cb27160754b\", \"metrics_python_tests\": \"0d012106d5ae5572abbaca43d0b20ab0985c0a14\", \"midi_unittests\": \"4b0e3e97faaa9f3f6e097959ffc9a2e685e14581\", \"mojo_core_unittests\": \"6ab644e9c3478a18086b4706cde8fd0f73c5f49d\", \"mojo_unittests\": \"74c8b80ddf577fe16c8a91795ac9369cc39eb802\", \"nacl_helper_nonsfi_unittests\": \"efcfbea146ddac0e8013005b68ff92e0c3efa25c\", \"nacl_loader_unittests\": \"e3406940a336ca6ffbd6c9009311508fde26148d\", \"native_theme_unittests\": \"a503b078ca597467253eb1f2d0e7b8ed077f924b\", \"net_unittests\": \"9fa6a61dc5aa9af7924f22b7a13411b575b2dac8\", \"pdf_unittests\": \"da84cdc47d278778e0b25610aa937a7c6c0ebdfd\", \"perfetto_unittests\": \"d13cc8bbaf650ab49ce6d731aec73b06611459d7\", \"ppapi_unittests\": \"e6cbe052fe9a3371aae2e4ab01171f0aafdfc768\", \"printing_unittests\": \"0181caf75faaa2642915f90dbc2469730016cb97\", \"remoting_unittests\": \"72e07a6e84ac099eb965a5b4f013eab2b85b0991\", \"sandbox_linux_unittests\": \"88b337a6d5498c874c371028e1ccda08d40287d4\", \"service_manager_unittests\": \"00d2cd9a18c069c41727ec143e100fa74f11172d\", \"services_unittests\": \"8beabc2edfe2e075086b4ba01ab451a771c09bc8\", \"shell_dialogs_unittests\": \"00c25d87c25156b8c0ffe7b449fb14756f494bf6\", \"skia_unittests\": \"fe3a3342e8f06daf3d5b0cee0dc5b22eaaf12c4a\", \"snapshot_unittests\": \"90184b33c95bba37dbd516af7af94c643e634024\", \"sql_unittests\": \"862fc570a19d697787ca7ef9855028981db008c8\", \"storage_unittests\": \"6c34252864da1728e80cf5de78e2f82bdaec72b5\", \"sync_integration_tests\": \"fcf504db464d825fa7e464482eda4f45886639b0\", \"telemetry_gpu_unittests\": \"f5cf017356189da05b368cf930d584c77b6d6806\", \"telemetry_perf_unittests\": \"dc59b99cbe92f8f0de9be55b40e5ed31f429e9e0\", \"telemetry_unittests\": \"d557f7f415ca93c5439a23bf6deafa6e4dd35f5d\", \"traffic_annotation_auditor_unittests\": \"7aca2f71cbad559c0885833524a06650a6530a41\", \"ui_base_unittests\": \"156d3aba2c897f782c5e67dbea495df971d264ff\", \"ui_touch_selection_unittests\": \"27e35eb33888aa5effb68f8c2c7f2bab8cba0e77\", \"unit_tests\": \"a8c5382b8a1d43f37016ee3ae8f608c1fc5829ce\", \"url_unittests\": \"74b80c62d9bca33be43c16f112de01fc2e0589ae\", \"views_perftests\": \"ee0ddbb8014151d5d903e189a0ab5315a24bccd1\", \"views_unittests\": \"ebe7d3b620b0aadcd97b0ead7050499df4e82012\", \"viz_unittests\": \"93598b16a8c91f5f7a750feb65c52f8b32ada375\", \"vr_common_unittests\": \"b75e81f8a6b688a32a8447e6adf6eff6b4a069a3\", \"vr_pixeltests\": \"91ed7e738cd7899c53bebac45fd8bfa7fa032192\", \"webdriver_wpt_tests\": \"bee168dc7315ca035380fbc0bd8792bc7449967f\", \"weblayer_browsertests\": \"f1a719da85da8308b72a0cda51c5a394439232f0\", \"wm_unittests\": \"32ef4c6dab3d3e7a07ad857da431ad6d5a8978a7\", \"wtf_unittests\": \"5f393eb6dfdc4e941a8c73e6fb24238d0ee1e6b3\", \"xr_browser_tests\": \"cb64503f35506adc01acbbf6b00d9015170bbe90\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_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-central1-b-8-2-5cxy\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"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\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583553957795904",
"status": "COMPLETED",
"status_changed_ts": "1583557327489099",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169440",
"builder:Linux Builder (dbg)",
"buildset:commit/git/2714fec9be58a87e591bb1a859de72679ada1a4a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2714fec9be58a87e591bb1a859de72679ada1a4a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051947425419532272",
"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/8886517362918566240/+/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:4acbf8ac12d1ef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1583557327494313",
"url": "https://ci.chromium.org/b/8886517362918566240",
"utcnow_ts": "1583557327956788"
}
}
[05:02:07.964] Invocation finished in 1h1m18.838752173s with status SUCCEEDED