[16:38:58.561] New invocation is queued and will start shortly
[16:38:59.676] Starting the invocation (attempt 1)
[16:38:59.709] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:38:59.709] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:38:59.709] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019828400199597648",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d573b30419661f88bba334f5127575725a425db9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9019828400199597648",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/d573b30419661f88bba334f5127575725a425db9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d573b30419661f88bba334f5127575725a425db9",
"gitiles_ref:refs/heads/master"
]
}
[16:39:01.381] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614184739907040",
"id": "8854398338125094816",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d573b30419661f88bba334f5127575725a425db9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614184740428714",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180894",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d573b30419661f88bba334f5127575725a425db9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d573b30419661f88bba334f5127575725a425db9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019828400199597648",
"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/8854398338125094816/+/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": "1614184740429070",
"url": "https://ci.chromium.org/b/8854398338125094816",
"utcnow_ts": "1614184741374108"
}
}
[16:39:01.381] Task URL: https://ci.chromium.org/b/8854398338125094816
[16:39:01.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:2:0) after 1m0s
[16:39:34.287] Received PubSub notification, asking Buildbucket for the build status
[16:39:34.350] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:01.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:2:0)
[16:40:01.491] Timer tick, asking Buildbucket for the build status
[16:40:01.587] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:01.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:4:0) after 1m0s
[16:41:02.091] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:4:0)
[16:41:02.091] Timer tick, asking Buildbucket for the build status
[16:41:02.163] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:02.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:5:0) after 1m0s
[16:42:02.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:5:0)
[16:42:02.487] Timer tick, asking Buildbucket for the build status
[16:42:02.701] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:02.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:6:0) after 1m0s
[16:43:02.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:6:0)
[16:43:02.884] Timer tick, asking Buildbucket for the build status
[16:43:02.949] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:02.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:7:0) after 1m0s
[16:44:03.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:7:0)
[16:44:03.036] Timer tick, asking Buildbucket for the build status
[16:44:03.199] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:03.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:8:0) after 1m0s
[16:45:03.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:8:0)
[16:45:03.431] Timer tick, asking Buildbucket for the build status
[16:45:03.551] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:03.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:9:0) after 1m0s
[16:46:03.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:9:0)
[16:46:03.612] Timer tick, asking Buildbucket for the build status
[16:46:03.696] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:03.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:10:0) after 1m0s
[16:47:03.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:10:0)
[16:47:03.780] Timer tick, asking Buildbucket for the build status
[16:47:03.894] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:03.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:11:0) after 1m0s
[16:48:03.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:11:0)
[16:48:03.876] Timer tick, asking Buildbucket for the build status
[16:48:03.961] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:03.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:12:0) after 1m0s
[16:49:03.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:12:0)
[16:49:03.992] Timer tick, asking Buildbucket for the build status
[16:49:04.088] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:04.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:13:0) after 1m0s
[16:50:04.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:13:0)
[16:50:04.093] Timer tick, asking Buildbucket for the build status
[16:50:04.187] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:04.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:14:0) after 1m0s
[16:51:04.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:14:0)
[16:51:04.273] Timer tick, asking Buildbucket for the build status
[16:51:04.367] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:04.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:15:0) after 1m0s
[16:52:04.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:15:0)
[16:52:04.530] Timer tick, asking Buildbucket for the build status
[16:52:04.633] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:04.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:16:0) after 1m0s
[16:53:04.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:16:0)
[16:53:04.761] Timer tick, asking Buildbucket for the build status
[16:53:04.859] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:04.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:17:0) after 1m0s
[16:54:04.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:17:0)
[16:54:04.993] Timer tick, asking Buildbucket for the build status
[16:54:05.259] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:05.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:18:0) after 1m0s
[16:55:05.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:18:0)
[16:55:05.519] Timer tick, asking Buildbucket for the build status
[16:55:05.600] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:05.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:19:0) after 1m0s
[16:56:05.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:19:0)
[16:56:05.865] Timer tick, asking Buildbucket for the build status
[16:56:05.948] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:05.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:20:0) after 1m0s
[16:57:06.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:20:0)
[16:57:06.137] Timer tick, asking Buildbucket for the build status
[16:57:06.214] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:06.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:21:0) after 1m0s
[16:58:06.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:21:0)
[16:58:06.450] Timer tick, asking Buildbucket for the build status
[16:58:06.543] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:06.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:22:0) after 1m0s
[16:59:06.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:22:0)
[16:59:06.793] Timer tick, asking Buildbucket for the build status
[16:59:06.875] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:06.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:23:0) after 1m0s
[17:00:07.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:23:0)
[17:00:07.200] Timer tick, asking Buildbucket for the build status
[17:00:07.607] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:07.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:24:0) after 1m0s
[17:01:07.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:24:0)
[17:01:07.972] Timer tick, asking Buildbucket for the build status
[17:01:08.101] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:08.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:25:0) after 1m0s
[17:02:08.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:25:0)
[17:02:08.295] Timer tick, asking Buildbucket for the build status
[17:02:08.402] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:08.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:26:0) after 1m0s
[17:03:08.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:26:0)
[17:03:08.498] Timer tick, asking Buildbucket for the build status
[17:03:08.658] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:08.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:27:0) after 1m0s
[17:04:08.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:27:0)
[17:04:08.666] Timer tick, asking Buildbucket for the build status
[17:04:08.782] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:08.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:28:0) after 1m0s
[17:05:08.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:28:0)
[17:05:08.791] Timer tick, asking Buildbucket for the build status
[17:05:09.203] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:09.203] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:29:0) after 1m0s
[17:06:09.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:29:0)
[17:06:09.274] Timer tick, asking Buildbucket for the build status
[17:06:09.480] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:09.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:30:0) after 1m0s
[17:07:09.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:30:0)
[17:07:09.513] Timer tick, asking Buildbucket for the build status
[17:07:09.593] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:09.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:31:0) after 1m0s
[17:08:09.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:31:0)
[17:08:09.756] Timer tick, asking Buildbucket for the build status
[17:08:09.840] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:09.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:32:0) after 1m0s
[17:09:09.905] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:32:0)
[17:09:09.905] Timer tick, asking Buildbucket for the build status
[17:09:10.304] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:10.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:33:0) after 1m0s
[17:10:10.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:33:0)
[17:10:10.427] Timer tick, asking Buildbucket for the build status
[17:10:10.533] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:10.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:34:0) after 1m0s
[17:11:10.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:34:0)
[17:11:10.704] Timer tick, asking Buildbucket for the build status
[17:11:10.851] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:10.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:35:0) after 1m0s
[17:12:10.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:35:0)
[17:12:10.976] Timer tick, asking Buildbucket for the build status
[17:12:11.074] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:11.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:36:0) after 1m0s
[17:13:11.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:36:0)
[17:13:11.223] Timer tick, asking Buildbucket for the build status
[17:13:11.303] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:11.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:37:0) after 1m0s
[17:14:11.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:37:0)
[17:14:11.403] Timer tick, asking Buildbucket for the build status
[17:14:11.519] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:11.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:38:0) after 1m0s
[17:15:11.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:38:0)
[17:15:11.972] Timer tick, asking Buildbucket for the build status
[17:15:12.227] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:12.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:39:0) after 1m0s
[17:16:12.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:39:0)
[17:16:12.261] Timer tick, asking Buildbucket for the build status
[17:16:12.349] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:12.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:40:0) after 1m0s
[17:17:12.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:40:0)
[17:17:12.429] Timer tick, asking Buildbucket for the build status
[17:17:12.509] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:12.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:41:0) after 1m0s
[17:18:13.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:41:0)
[17:18:13.004] Timer tick, asking Buildbucket for the build status
[17:18:13.106] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:13.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:42:0) after 1m0s
[17:19:13.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:42:0)
[17:19:13.437] Timer tick, asking Buildbucket for the build status
[17:19:13.915] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:13.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:43:0) after 1m0s
[17:20:14.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:43:0)
[17:20:14.054] Timer tick, asking Buildbucket for the build status
[17:20:14.132] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:14.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:44:0) after 1m0s
[17:21:14.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:44:0)
[17:21:14.240] Timer tick, asking Buildbucket for the build status
[17:21:14.321] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:14.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:45:0) after 1m0s
[17:22:14.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:45:0)
[17:22:14.312] Timer tick, asking Buildbucket for the build status
[17:22:14.597] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:14.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:46:0) after 1m0s
[17:23:14.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:46:0)
[17:23:14.659] Timer tick, asking Buildbucket for the build status
[17:23:14.748] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:14.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:47:0) after 1m0s
[17:24:14.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:47:0)
[17:24:14.814] Timer tick, asking Buildbucket for the build status
[17:24:14.955] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:14.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:48:0) after 1m0s
[17:25:15.010] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:48:0)
[17:25:15.010] Timer tick, asking Buildbucket for the build status
[17:25:15.137] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:15.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:49:0) after 1m0s
[17:26:15.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:49:0)
[17:26:15.171] Timer tick, asking Buildbucket for the build status
[17:26:15.259] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:15.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:50:0) after 1m0s
[17:27:15.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:50:0)
[17:27:15.321] Timer tick, asking Buildbucket for the build status
[17:27:15.485] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:15.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:51:0) after 1m0s
[17:28:15.572] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:51:0)
[17:28:15.572] Timer tick, asking Buildbucket for the build status
[17:28:15.657] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:15.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:52:0) after 1m0s
[17:29:15.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:52:0)
[17:29:15.725] Timer tick, asking Buildbucket for the build status
[17:29:15.813] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:15.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:53:0) after 1m0s
[17:30:15.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:53:0)
[17:30:15.856] Timer tick, asking Buildbucket for the build status
[17:30:15.953] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:15.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:54:0) after 1m0s
[17:31:15.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:54:0)
[17:31:15.956] Timer tick, asking Buildbucket for the build status
[17:31:16.070] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:16.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:55:0) after 1m0s
[17:32:16.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:55:0)
[17:32:16.124] Timer tick, asking Buildbucket for the build status
[17:32:16.224] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:16.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:56:0) after 1m0s
[17:33:16.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:56:0)
[17:33:16.265] Timer tick, asking Buildbucket for the build status
[17:33:16.334] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:16.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:57:0) after 1m0s
[17:34:16.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:57:0)
[17:34:16.422] Timer tick, asking Buildbucket for the build status
[17:34:16.533] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:16.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:58:0) after 1m0s
[17:35:16.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:58:0)
[17:35:16.555] Timer tick, asking Buildbucket for the build status
[17:35:16.697] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:16.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:59:0) after 1m0s
[17:36:16.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:59:0)
[17:36:16.708] Timer tick, asking Buildbucket for the build status
[17:36:16.824] Build 8854398338125094816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:16.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019828400199597648:60:0) after 1m0s
[17:36:59.932] Received PubSub notification, asking Buildbucket for the build status
[17:37:00.238] Build 8854398338125094816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:37:00.238] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614188219188106",
"created_by": "project:chromium",
"created_ts": "1614184739907040",
"id": "8854398338125094816",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d573b30419661f88bba334f5127575725a425db9\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"d573b30419661f88bba334f5127575725a425db9\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-174-0eo1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180894, \"got_angle_revision\": \"9b9d683e9cc3ceaa07cc501e5667779db791c0cd\", \"got_dawn_revision\": \"018b9b917c1d15accd3854b9248b8b6c1a91f6fe\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"d573b30419661f88bba334f5127575725a425db9\", \"got_revision_cp\": \"refs/heads/master@{#857213}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"7a343dccaf574db270f30b6dd6426c884d572649\", \"got_v8_revision_cp\": \"refs/heads/9.0.255@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d573b30419661f88bba334f5127575725a425db9\", \"swarm_hashes_refs/heads/master(at){#857213}_without_patch\": {\"absl_hardening_tests\": \"232c5f85007da5b596be781a313e76ee91e717b9\", \"accessibility_unittests\": \"c792cf4c54d8eb1880338c3e74d86d3516d0b674\", \"angle_unittests\": \"6620359888fa288b1d85a2d738d7bcc288677709\", \"app_shell_unittests\": \"0662f78ba05df4958ea68c34d99058d91baf4182\", \"aura_unittests\": \"89add39963539cf1d3400e02951a5bdf81a48b85\", \"base_unittests\": \"ba73415881cceec5363f1c5d0e9e69704b809ec3\", \"base_util_unittests\": \"936cabb548e77c6d21cad0f4e5b600b925adcc1f\", \"blink_common_unittests\": \"2dbe4663b3fed67945ec3ee41caa32f563afb42c\", \"blink_fuzzer_unittests\": \"88a373d5cac7e644c223c85234daeeca1014ac20\", \"blink_heap_unittests\": \"11f6c018f891e557ae13e933a31cfe7173612d7c\", \"blink_platform_unittests\": \"3353372752fe1b97685eb63b5045eaa597fcd0fd\", \"blink_python_tests\": \"aef53bbcfdf7f2db417f90703436541143c61e0e\", \"blink_unittests\": \"6090f6d852e26dbafce1320c781f32eccb0640d8\", \"blink_web_tests\": \"2b9d5108ab814f46ce650e3eb0f1e05d1f56cbb1\", \"boringssl_crypto_tests\": \"88995b9168e17f96e2926359f9f0bea496f2c3ee\", \"boringssl_ssl_tests\": \"0e89e0898f144fea623aeb608ab7011b171ffb1b\", \"browser_tests\": \"7a769b3d8556793023f34b3bc5d056aa49fffb9e\", \"capture_unittests\": \"8a66d0c1f5e91eb5398d95c1bbe789b73460f2a8\", \"cast_unittests\": \"865161e667731a1b57a96e21ccf89e0b5f45aea2\", \"cc_unittests\": \"5c7649fd31dfccf1838055ff9ae25ddd66aac664\", \"chrome_app_unittests\": \"d5089802f36408e8b091aa48208187588976141c\", \"chromedriver_unittests\": \"a1adf7b7b346f68528f16c05afe0d1a60eefd255\", \"color_unittests\": \"39acb4f3fca0909b2d65f9d8271cec329946f952\", \"components_browsertests\": \"2e2fb9ed691040eeeb767f1c29a5a177f4ce7065\", \"components_unittests\": \"437a2e312c5660c189dbc18987159658b119f6c9\", \"compositor_unittests\": \"3e39d51993d431a7d71cd84cdd366dbc254aa22c\", \"content_browsertests\": \"de4ad20a4f2e2f79436f076efe474d2b60d75034\", \"content_shell_crash_test\": \"b00f68b449f24f9fb71496d42eb9678e98e9f9e6\", \"content_unittests\": \"a69ad68b40f1ea740814eb98ac2c4944a2ce16fd\", \"crashpad_tests\": \"90bbb741cb11fada289f61deee0f60abfeb2a02e\", \"cronet_tests\": \"3b3c4ba6fbaaca42cd55f0ff185becbc21c45aec\", \"cronet_unittests\": \"16c78f155aa0239bd00b9fc40366da3454325db6\", \"crypto_unittests\": \"2223708de4dbb3b7032824f005b244821d60305d\", \"dbus_unittests\": \"ce565da23ad0e97b3d5de05e6747f35a5a2a12c5\", \"device_unittests\": \"caa9baac94d4bd76c8f971dc58ec4e77013f15ec\", \"display_unittests\": \"955231e0268e4ef02ae638e60dd7d2477baf4d69\", \"events_unittests\": \"26c9be623fc13285ad123cc0290c45a714488ce3\", \"extensions_browsertests\": \"d69682a696381c74cb89098c7026025d8e13f7da\", \"extensions_unittests\": \"6cf14ad212de6c955eb1c28aaa06a1e626314ca7\", \"filesystem_service_unittests\": \"ab019204b688d179cb6a720399ba860986a39491\", \"flatbuffers_unittests\": \"e8e54a71bdd869da6d885f6d9de43244c0b7bf8d\", \"gcm_unit_tests\": \"936f904d7f39984f0557a3e84a9a985f14912130\", \"gfx_unittests\": \"e6c71046074bf54858e2787e4cdd95a54b1217e4\", \"gin_unittests\": \"62be4ac712fb9a6ab5dd1c707e2a9530737ac392\", \"google_apis_unittests\": \"cd8e5e536634d87431a04aae05ec9d056e3be5a8\", \"gpu_unittests\": \"4cfa8f5b5acbd9c7332faf0ff62a85a335a7d99f\", \"grit_python_unittests\": \"00738482c42a9310ca0fd96f0709b3d808390403\", \"gwp_asan_unittests\": \"51adbd98e0a14a2c60407ee2638b6eb9c42663ad\", \"headless_browsertests\": \"38626ccd0aeb0586bf6367784ff60a365c02a737\", \"headless_unittests\": \"71bf50f2d0ce1f82c9c5cc1c38b000a1771c0e30\", \"interactive_ui_tests\": \"22b74fbc4e962bac4ce0b3166650666cf9474113\", \"ipc_tests\": \"0c97cb7c483fc717dcf6ecb6a753bd157ed6c09a\", \"jingle_unittests\": \"c33d19067bef8032dbf88f8409e7e7d199282403\", \"latency_unittests\": \"f9a179417ff435d02bb4215d0522cee98a5a4d49\", \"libjingle_xmpp_unittests\": \"896f96abb1c2f050fe4120e99b79191c7b565947\", \"liburlpattern_unittests\": \"8881a9195822260f9273278b57df733ccbf7f675\", \"media_blink_unittests\": \"9ea9c9c704be4d90bb699be596b1aaa2b397eceb\", \"media_unittests\": \"cdd3e8847dbd44130a0dc70f8f6786cd8488799e\", \"message_center_unittests\": \"ab50cace4c7e606cf534bc0edded309c88df75d1\", \"metrics_python_tests\": \"193aa9dfce4c26c260142fd2cfb5bf497cfb6fe2\", \"midi_unittests\": \"f1d10372bf1e485819d1df23b381e0ab0fcb4a50\", \"mojo_core_unittests\": \"2afefe9b37cee806b5b81242dad92383b594ada9\", \"mojo_unittests\": \"e3841504be5bb10155f09e3370e6470a802a9a0d\", \"nacl_helper_nonsfi_unittests\": \"0b197d43d142358587f862f42b0a5d4285db955c\", \"nacl_loader_unittests\": \"ae57c5a62b2b02b43358febd1875c64240758b15\", \"native_theme_unittests\": \"956ff656836a250887f5c04f5dceb5ca2a4d34a1\", \"net_unittests\": \"cd5470e73d969bfa91b41e3833f5c2f370c26b58\", \"openscreen_unittests\": \"7b226192de27322c6e030186a42b514a5a2d491d\", \"pdf_unittests\": \"bdc3f8f93db5657c2f7b3c8c1995bd403a61411f\", \"perfetto_unittests\": \"b50ea9d918ef65f0e41c82101f264e60caa5cd65\", \"ppapi_unittests\": \"96c613f563c70cbcdf96ee84bd03f3adfec8132d\", \"printing_unittests\": \"6ac1aea956f1470cdfb4f909ae60909a7c488581\", \"remoting_unittests\": \"ee6d53fc2e872a92cf8191f87786df15c09e1632\", \"sandbox_linux_unittests\": \"7cb855c24781e5ab88c0c29c4137b4296cd20a06\", \"service_manager_unittests\": \"276d247f03b5b329d575dcfd9b24a4f3d97576a3\", \"services_unittests\": \"4b3b5dde0b4f58d6ce294b6abbbcf9c1d5ea3df4\", \"shell_dialogs_unittests\": \"15fd092ce018c7654d33e36949e955dc6e0d1169\", \"skia_unittests\": \"86913b94c1ace3987028eee5a4e54656c1b7ea9a\", \"snapshot_unittests\": \"9dd4902cce067293b8719d6d01764fb6c5b6990c\", \"sql_unittests\": \"a7cdf8a08a5a9a03066f8ef014387d9bf56f00b7\", \"storage_unittests\": \"ec060db384724388ace5025b25dd2530193254d4\", \"sync_integration_tests\": \"9bcc5d138c3e31aef87037ef94ffc1858134f014\", \"telemetry_gpu_unittests\": \"aa762604f6dbbadd54576f5508a678ee34257dc6\", \"telemetry_perf_unittests\": \"6b9ea42f320fca680c19372e882b94d2d21161d9\", \"telemetry_unittests\": \"5a76114009cad6f218c39959ab2a4adde89fabb8\", \"traffic_annotation_auditor_unittests\": \"1c7f3edd0b3d2ed06b427fd85b88840d43e4bb99\", \"ui_base_unittests\": \"af2f093ae971ad5603acf72a9645f25287c62796\", \"ui_touch_selection_unittests\": \"0bd90d40d0c9ad947646dabc57b8dd5616df2cd5\", \"unit_tests\": \"7febc1008a4961ea044f46bec933f098e54b7904\", \"url_unittests\": \"c0d64045f13763f6dec2fbea408f8580385d23f0\", \"views_perftests\": \"36936e079dd1cc37ed85882ffeadb478af8e13f1\", \"views_unittests\": \"a6e55241bf082b5515708045ac8eb3742524cba0\", \"viz_unittests\": \"dea78ec06b940b9e28150366d274230fda6612f5\", \"vr_common_unittests\": \"85beff606d987a1aa3a6eab67bbcf293af2d911b\", \"vr_pixeltests\": \"9afdc1c8102603d718a6c1a8e8ace460a3c37f64\", \"webdriver_wpt_tests\": \"cdfaacc61c068107e75f25484e5cc2961e86bd3f\", \"weblayer_browsertests\": \"3da591acb8292e3324eae99362bf22ecea6f3e7e\", \"weblayer_unittests\": \"ace38e639c71422c16f5a8acbd8bb42dc3a1f41a\", \"wm_unittests\": \"1e06f5fed96383f6933aaaa3d3b3947d44f5792b\", \"wtf_unittests\": \"4e11e9f900f9212a6f1fd45ccd491c71eea9eae0\", \"x11_unittests\": \"6829a542479a983d0d4e01314f672783986b0222\", \"xr_browser_tests\": \"485fc239dd3af21262279db338417e016d33bb93\", \"zlib_unittests\": \"b6eb2ff289b680941b3ca10bbefddc60d95f4ddd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_53a369b6e7b3f2409540d71b27a68c8bcf1cb73633e70dba0b4b8b173a0aec5e_v2\", \"builder_bf60de3a2a061c3c4c67ad9afdde23d2342e42cdda6e8a4003b52312ca24fd40_v2\", \"builder_c66788656bdf2acb3179f9105d1e02eee4c98139f3cebcabd373c722039a64ae_v2\", \"builder_ce300ca2149b7ede2a11dc5f29b170d3e5c2bdd2b201177604ba7c6bb221adeb_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-174-0eo1\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5709-d3c4474\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614184773743016",
"status": "COMPLETED",
"status_changed_ts": "1614188219636546",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180894",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d573b30419661f88bba334f5127575725a425db9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d573b30419661f88bba334f5127575725a425db9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019828400199597648",
"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/8854398338125094816/+/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:51edba04747b8710",
"user_agent:luci-scheduler"
],
"updated_ts": "1614188219641130",
"url": "https://ci.chromium.org/b/8854398338125094816",
"utcnow_ts": "1614188220148437"
}
}
[17:37:00.238] Invocation finished in 58m1.692861978s with status SUCCEEDED