[19:20:28.206] New invocation is queued and will start shortly
[19:20:29.263] Starting the invocation (attempt 1)
[19:20:29.351] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:20:29.352] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:20:29.352] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061583441389141440",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e45c46526358c37ef088187e9ea5c83f2ba85c33\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9061583441389141440",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e45c46526358c37ef088187e9ea5c83f2ba85c33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e45c46526358c37ef088187e9ea5c83f2ba85c33",
"gitiles_ref:refs/heads/master"
]
}
[19:20:30.315] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1574364029509798",
"id": "8896153379351976384",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e45c46526358c37ef088187e9ea5c83f2ba85c33\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574364030001455",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165120",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e45c46526358c37ef088187e9ea5c83f2ba85c33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e45c46526358c37ef088187e9ea5c83f2ba85c33",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061583441389141440",
"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/8896153379351976384/+/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": "1574364030001666",
"url": "https://ci.chromium.org/b/8896153379351976384",
"utcnow_ts": "1574364030255032"
}
}
[19:20:30.315] Task URL: https://ci.chromium.org/b/8896153379351976384
[19:20:30.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:2:0) after 1m0s
[19:21:30.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:2:0)
[19:21:30.389] Timer tick, asking Buildbucket for the build status
[19:21:31.096] Build 8896153379351976384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:31.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:3:0) after 1m0s
[19:22:31.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:3:0)
[19:22:31.148] Timer tick, asking Buildbucket for the build status
[19:22:32.036] Build 8896153379351976384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:32.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:4:0) after 1m0s
[19:23:32.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:4:0)
[19:23:32.098] Timer tick, asking Buildbucket for the build status
[19:23:32.449] Build 8896153379351976384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:32.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:5:0) after 1m0s
[19:24:32.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:5:0)
[19:24:32.498] Timer tick, asking Buildbucket for the build status
[19:24:32.749] Build 8896153379351976384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:32.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:6:0) after 1m0s
[19:25:08.434] Received PubSub notification, asking Buildbucket for the build status
[19:25:09.621] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:32.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:6:0)
[19:25:32.850] Timer tick, asking Buildbucket for the build status
[19:25:33.429] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:33.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:8:0) after 1m0s
[19:26:33.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:8:0)
[19:26:33.524] Timer tick, asking Buildbucket for the build status
[19:26:34.571] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:34.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:9:0) after 1m0s
[19:27:34.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:9:0)
[19:27:34.605] Timer tick, asking Buildbucket for the build status
[19:27:36.529] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:36.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:10:0) after 1m0s
[19:28:36.590] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:10:0)
[19:28:36.590] Timer tick, asking Buildbucket for the build status
[19:28:36.781] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:36.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:11:0) after 1m0s
[19:29:36.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:11:0)
[19:29:36.809] Timer tick, asking Buildbucket for the build status
[19:29:37.014] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:37.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:12:0) after 1m0s
[19:30:37.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:12:0)
[19:30:37.158] Timer tick, asking Buildbucket for the build status
[19:30:38.050] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:38.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:13:0) after 1m0s
[19:31:38.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:13:0)
[19:31:38.156] Timer tick, asking Buildbucket for the build status
[19:31:39.168] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:39.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:14:0) after 1m0s
[19:32:39.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:14:0)
[19:32:39.156] Timer tick, asking Buildbucket for the build status
[19:32:39.568] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:39.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:15:0) after 1m0s
[19:33:39.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:15:0)
[19:33:39.615] Timer tick, asking Buildbucket for the build status
[19:33:40.480] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:40.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:16:0) after 1m0s
[19:34:40.545] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:16:0)
[19:34:40.545] Timer tick, asking Buildbucket for the build status
[19:34:40.956] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:40.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:17:0) after 1m0s
[19:35:41.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:17:0)
[19:35:41.017] Timer tick, asking Buildbucket for the build status
[19:35:42.412] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:42.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:18:0) after 1m0s
[19:36:42.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:18:0)
[19:36:42.434] Timer tick, asking Buildbucket for the build status
[19:36:43.545] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:43.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:19:0) after 1m0s
[19:37:43.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:19:0)
[19:37:43.565] Timer tick, asking Buildbucket for the build status
[19:37:45.470] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:45.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:20:0) after 1m0s
[19:38:45.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:20:0)
[19:38:45.538] Timer tick, asking Buildbucket for the build status
[19:38:45.952] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:45.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:21:0) after 1m0s
[19:39:45.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:21:0)
[19:39:45.997] Timer tick, asking Buildbucket for the build status
[19:39:47.182] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:47.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:22:0) after 1m0s
[19:40:47.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:22:0)
[19:40:47.285] Timer tick, asking Buildbucket for the build status
[19:40:48.053] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:48.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:23:0) after 1m0s
[19:41:48.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:23:0)
[19:41:48.142] Timer tick, asking Buildbucket for the build status
[19:41:48.321] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:48.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:24:0) after 1m0s
[19:42:48.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:24:0)
[19:42:48.465] Timer tick, asking Buildbucket for the build status
[19:42:48.705] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:48.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:25:0) after 1m0s
[19:43:48.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:25:0)
[19:43:48.755] Timer tick, asking Buildbucket for the build status
[19:43:48.917] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:48.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:26:0) after 1m0s
[19:44:48.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:26:0)
[19:44:48.940] Timer tick, asking Buildbucket for the build status
[19:44:49.145] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:49.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:27:0) after 1m0s
[19:45:49.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:27:0)
[19:45:49.151] Timer tick, asking Buildbucket for the build status
[19:45:51.525] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:51.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:28:0) after 1m0s
[19:46:51.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:28:0)
[19:46:51.635] Timer tick, asking Buildbucket for the build status
[19:46:52.615] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:52.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:29:0) after 1m0s
[19:47:52.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:29:0)
[19:47:52.792] Timer tick, asking Buildbucket for the build status
[19:47:54.051] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:54.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:30:0) after 1m0s
[19:48:54.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:30:0)
[19:48:54.296] Timer tick, asking Buildbucket for the build status
[19:48:54.602] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:54.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:31:0) after 1m0s
[19:49:54.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:31:0)
[19:49:54.738] Timer tick, asking Buildbucket for the build status
[19:49:54.921] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:54.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:32:0) after 1m0s
[19:50:54.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:32:0)
[19:50:54.947] Timer tick, asking Buildbucket for the build status
[19:50:55.137] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:55.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:33:0) after 1m0s
[19:51:55.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:33:0)
[19:51:55.268] Timer tick, asking Buildbucket for the build status
[19:51:55.530] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:55.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:34:0) after 1m0s
[19:52:55.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:34:0)
[19:52:55.744] Timer tick, asking Buildbucket for the build status
[19:52:57.637] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:57.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:35:0) after 1m0s
[19:53:57.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:35:0)
[19:53:57.685] Timer tick, asking Buildbucket for the build status
[19:53:58.924] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:58.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:36:0) after 1m0s
[19:54:58.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:36:0)
[19:54:58.938] Timer tick, asking Buildbucket for the build status
[19:54:59.265] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:59.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:37:0) after 1m0s
[19:55:59.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:37:0)
[19:55:59.263] Timer tick, asking Buildbucket for the build status
[19:55:59.455] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:59.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:38:0) after 1m0s
[19:56:59.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:38:0)
[19:56:59.497] Timer tick, asking Buildbucket for the build status
[19:56:59.707] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:59.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:39:0) after 1m0s
[19:57:59.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:39:0)
[19:57:59.699] Timer tick, asking Buildbucket for the build status
[19:57:59.938] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:59.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:40:0) after 1m0s
[19:59:00.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:40:0)
[19:59:00.204] Timer tick, asking Buildbucket for the build status
[19:59:00.350] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:00.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:41:0) after 1m0s
[20:00:00.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:41:0)
[20:00:00.505] Timer tick, asking Buildbucket for the build status
[20:00:02.928] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:02.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:42:0) after 1m0s
[20:01:02.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:42:0)
[20:01:02.985] Timer tick, asking Buildbucket for the build status
[20:01:03.934] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:03.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:43:0) after 1m0s
[20:02:04.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:43:0)
[20:02:04.045] Timer tick, asking Buildbucket for the build status
[20:02:04.621] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:04.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:44:0) after 1m0s
[20:03:04.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:44:0)
[20:03:04.833] Timer tick, asking Buildbucket for the build status
[20:03:04.940] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:04.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:45:0) after 1m0s
[20:04:04.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:45:0)
[20:04:04.999] Timer tick, asking Buildbucket for the build status
[20:04:06.309] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:06.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:46:0) after 1m0s
[20:05:06.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:46:0)
[20:05:06.434] Timer tick, asking Buildbucket for the build status
[20:05:06.814] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:06.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:47:0) after 1m0s
[20:06:06.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:47:0)
[20:06:06.869] Timer tick, asking Buildbucket for the build status
[20:06:06.995] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:06.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:48:0) after 1m0s
[20:07:07.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:48:0)
[20:07:07.554] Timer tick, asking Buildbucket for the build status
[20:07:07.806] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:07.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:49:0) after 1m0s
[20:08:07.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:49:0)
[20:08:07.935] Timer tick, asking Buildbucket for the build status
[20:08:08.263] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:08.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:50:0) after 1m0s
[20:09:08.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:50:0)
[20:09:08.723] Timer tick, asking Buildbucket for the build status
[20:09:08.889] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:08.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:51:0) after 1m0s
[20:10:09.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:51:0)
[20:10:09.721] Timer tick, asking Buildbucket for the build status
[20:10:10.102] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:52:0) after 1m0s
[20:11:11.055] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:52:0)
[20:11:11.055] Timer tick, asking Buildbucket for the build status
[20:11:11.450] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:11.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:53:0) after 1m0s
[20:12:11.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:53:0)
[20:12:11.568] Timer tick, asking Buildbucket for the build status
[20:12:12.056] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:12.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:54:0) after 1m0s
[20:13:12.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:54:0)
[20:13:12.086] Timer tick, asking Buildbucket for the build status
[20:13:12.646] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:12.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:55:0) after 1m0s
[20:14:12.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:55:0)
[20:14:12.741] Timer tick, asking Buildbucket for the build status
[20:14:13.032] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:13.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:56:0) after 1m0s
[20:15:13.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:56:0)
[20:15:13.056] Timer tick, asking Buildbucket for the build status
[20:15:13.714] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:13.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:57:0) after 1m0s
[20:16:13.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:57:0)
[20:16:13.852] Timer tick, asking Buildbucket for the build status
[20:16:14.209] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:14.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:58:0) after 1m0s
[20:17:14.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:58:0)
[20:17:14.353] Timer tick, asking Buildbucket for the build status
[20:17:14.560] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:14.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:59:0) after 1m0s
[20:18:14.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:59:0)
[20:18:14.577] Timer tick, asking Buildbucket for the build status
[20:18:15.418] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:15.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:60:0) after 1m0s
[20:19:15.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:60:0)
[20:19:15.455] Timer tick, asking Buildbucket for the build status
[20:19:16.816] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:16.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:61:0) after 1m0s
[20:20:16.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:61:0)
[20:20:16.850] Timer tick, asking Buildbucket for the build status
[20:20:20.300] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:20.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:62:0) after 1m0s
[20:21:20.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:62:0)
[20:21:20.605] Timer tick, asking Buildbucket for the build status
[20:21:20.885] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:20.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:63:0) after 1m0s
[20:22:20.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:63:0)
[20:22:20.995] Timer tick, asking Buildbucket for the build status
[20:22:21.372] Build 8896153379351976384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:21.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061583441389141440:64:0) after 1m0s
[20:23:18.948] Received PubSub notification, asking Buildbucket for the build status
[20:23:19.156] Build 8896153379351976384: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:23:19.156] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1574367797996815",
"created_by": "project:chromium",
"created_ts": "1574364029509798",
"id": "8896153379351976384",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e45c46526358c37ef088187e9ea5c83f2ba85c33\"}}",
"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-east1-d-8-34-7cvm\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165120, \"got_angle_revision\": \"1898c08274eb3cf630999799f939c345e1cef813\", \"got_dawn_revision\": \"0d4a7b0ba5b73425c1c5aa641843f971f0bdda4d\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"e45c46526358c37ef088187e9ea5c83f2ba85c33\", \"got_revision_cp\": \"refs/heads/master@{#717751}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"d34bb293b205c32bcb9c2c918c18854ee09c1824\", \"got_v8_revision\": \"96bd79e5ddf97d6e48b1774ca953bf4084d1e93a\", \"got_v8_revision_cp\": \"refs/heads/8.0.329@{#1}\", \"got_webrtc_revision\": \"58a3210823a3faaad347b4e54606d493ec005fb1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29865}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e45c46526358c37ef088187e9ea5c83f2ba85c33\", \"swarm_hashes_refs/heads/master(at){#717751}_without_patch\": {\"accessibility_unittests\": \"aee6dbdc7714df34ddbed60135b9100b936c7e50\", \"angle_unittests\": \"b400ce574f50258ce33d6819fd1cfbaf319c1b6e\", \"app_shell_unittests\": \"e5ba3e88f7283ed915a3bac5881a43ec9987f5bb\", \"aura_unittests\": \"83c9a0178e7bac714c839275e4f4860a72ec029d\", \"base_unittests\": \"56a1f1266f87002d6db53dbd4bef87a760e94322\", \"base_util_unittests\": \"3ce148564ac849dd54323f47ffbaf73feb7198fa\", \"blink_common_unittests\": \"e6e0bd089091de1510c6224010758403febee023\", \"blink_fuzzer_unittests\": \"f03b3126fcc0539b863d61c25140663c3fafedad\", \"blink_heap_unittests\": \"160c3c8f5b950bfee2987764dd73766cb275383a\", \"blink_platform_unittests\": \"2556a8e7852130bf9b15af2db8bd9a71ee3267d0\", \"blink_python_tests\": \"855840e4c0de6fa28e9debefe55cd09f742854f0\", \"blink_unittests\": \"846a00cf3e448f76af0d434d84a72d2c9cf80b34\", \"blink_web_tests_exparchive\": \"bfec607f90c101786804d9573044b936a66c035a\", \"boringssl_crypto_tests\": \"8245e775fcfbda8aba6d201f9c7fc8a8d0947a37\", \"boringssl_ssl_tests\": \"8fe67dd6fd9beb4f4ad8cc8c85d0bc92126b2087\", \"browser_tests\": \"c1af1591740bbf49d7af3683397d4b55771cfff8\", \"cacheinvalidation_unittests\": \"91b5c4f0290125e8820921f9f0501dbedce804fb\", \"capture_unittests\": \"b028470271ff183cbf491dea7a4ad7f56ff9ae66\", \"cast_unittests\": \"5d493796358ecd1bdad9aaba5c185727d1193d2e\", \"cc_unittests\": \"f2e3030b2551b8f3a2892a1308e588ff1bd40123\", \"chrome_app_unittests\": \"ed9449f8607ee566e787aa10015568c717e337d8\", \"chromedriver_unittests\": \"61d76cdbc4ad404787a3da7eb6b9f45c34f4bd7b\", \"color_unittests\": \"23bf043771b461bc05462ccbbd4a34d8dae43d55\", \"components_browsertests\": \"ba6595aba282a9af97f7ee733d3502861ed5237c\", \"components_unittests\": \"9aae5b6f5afe29e627ce636e17d67bf530150f3b\", \"compositor_unittests\": \"e73a274374d4820a5a0d5d767c46ef5feb9fba0c\", \"content_browsertests\": \"653d19f9104423e3b59114ff1f1a867041ea7ab1\", \"content_shell_crash_test\": \"c221dae6940d082de9ae34daa74b8fd21107b1e4\", \"content_unittests\": \"77aa6f76a9006e1e282ed0db82ff4ef3a880d551\", \"crashpad_tests\": \"42c8f6d38eb51731ccd39cc07080d64205c5e8b3\", \"cronet_tests\": \"2ce0c96b35a42bafec72f9926db3a8cc74a13012\", \"cronet_unittests\": \"5c5bfc28649fa10da032002ccbd31e50087ccbf6\", \"crypto_unittests\": \"aef26864bbe7b64f32c801d2ab72dc3e3aa5d6d9\", \"dbus_unittests\": \"15b9308c79f0abc4e67a2cfc3142b87aef63e37f\", \"device_unittests\": \"7f48d3cfe1a335eda3412282f8c4c4be68add9c4\", \"devtools_lint_check\": \"cd702703f13611c997d560afdb86217d27a15fb5\", \"devtools_type_check\": \"5591eb775c4e5382154c74bb0b755154e4a48316\", \"display_unittests\": \"3e7d9e76bb7af45f2f7ae285bf1b2b6f43091b9f\", \"events_unittests\": \"5d5a77837406cc26f4b855037586027020f41b59\", \"extensions_browsertests\": \"9b9231ecbe4f739254ccf7664620e3ca981f9a5b\", \"extensions_unittests\": \"08ac271310027b792df0e565799808d577ff66a1\", \"filesystem_service_unittests\": \"97bc61d8aff7502c2ea9d763c8b4fd968d15d82b\", \"flatbuffers_unittests\": \"68877d8b1262b0aa771438df1015a16a10098d5e\", \"gcm_unit_tests\": \"1a9dd436645181b8e4947b11dfd53efb4ad9707c\", \"gfx_unittests\": \"61c4268a2b008f0a4dd5f35e8e4540bf6cbb794c\", \"gin_unittests\": \"1c431ed3ae0f469e4ec7ce51907cce3a2761f8c7\", \"google_apis_unittests\": \"d927cbecc4d17613ea30d124892b3fbe69d7a03c\", \"gpu_unittests\": \"501d4d104643969baed04727ae8ac414ab37e8cd\", \"grit_python_unittests\": \"4267cdfe96cbb5881cc25cc5b15aab4b2f28c5f5\", \"gwp_asan_unittests\": \"3454441992a960dcd5e24e1f2724c61d8570b451\", \"headless_browsertests\": \"fb08a17834fe30c44a44b9833df051a3a6738718\", \"headless_unittests\": \"03bc3f51ffdf42eec98edaa260af2f0f42c5cbee\", \"interactive_ui_tests\": \"d6877235ad83dcf2e7dc9133fa293674b1575f02\", \"ipc_tests\": \"8d643780673828abc1b73888aca0d194f5bb0e5f\", \"jingle_unittests\": \"656bc46fd8f6dfd2c4f5cde7ff6c11b6ab6faefd\", \"latency_unittests\": \"fc1d41deb500cfb28413fe31b36e9016fbe6916e\", \"libjingle_xmpp_unittests\": \"35be439a314b6892995d6df0e426c081ee5ac17c\", \"media_blink_unittests\": \"f0fe78ef159cce28b37fa8370f5d0cb261ac20e6\", \"media_service_unittests\": \"7d538a0fcf492b8b90cb365dcc932782946b7824\", \"media_unittests\": \"4927ad31f81bad48c74429637a9ce1abf24b7a31\", \"message_center_unittests\": \"e5ef4ae53e81682997c211bc0af02a028a18d4f1\", \"metrics_python_tests\": \"981e9705468dcd538f100c2d8192004e6bdefbaa\", \"midi_unittests\": \"f1971ac322827378e2cb694ee4146028a17d6c7e\", \"mojo_core_unittests\": \"c252dd8672d1784303f80d2438de5b309df738ad\", \"mojo_unittests\": \"80093ce2425bee205bd81a040de9e849221cf856\", \"nacl_helper_nonsfi_unittests\": \"68fce67f17ade6d2683d93df1c44cd730b4336a4\", \"nacl_loader_unittests\": \"3dc7975c0cfb2d29f15f506f627de4feddf777ab\", \"native_theme_unittests\": \"ddf24bb0c38da35270dfbce2bf486764ab20d5f5\", \"net_unittests\": \"94026cff0d0eb940c9599c61dce9f6998f005467\", \"pdf_unittests\": \"7850129e10d64379d53fe93e6a4b46581346d862\", \"perfetto_unittests\": \"ae26ee6de7f9367153c8efc4e15e6529eeb0606c\", \"ppapi_unittests\": \"54a1056895d26d19b17bd1deb5595ffaac01b43d\", \"printing_unittests\": \"9f853251d5e82858c09cf374849b6ab72c905564\", \"remoting_unittests\": \"e4d48d1de2beb1796a730a5bef3cda99e3b2feaf\", \"sandbox_linux_unittests\": \"413692bc8cb65a9313a9e55541eab41c22d5959b\", \"service_manager_unittests\": \"fb7b0a2de638aef6b4e997867127ba99b042930e\", \"services_unittests\": \"3fbe246197d51433e608db74ffe27c44d2e9f7cb\", \"shell_dialogs_unittests\": \"88914c9ef6aa6e3e5ba6d152db61e398ce746b6d\", \"skia_unittests\": \"aefe41063369c36cf42eb626f92101a621c8d3a5\", \"snapshot_unittests\": \"1f28e8b45cfd6fb1817eacd1fb74852722acf32e\", \"sql_unittests\": \"10c632e2284c2b1ba33aac9e423eb48c5abd1154\", \"storage_unittests\": \"5b533031d3e156e5846ffd0b4123ffffef26f5fd\", \"sync_integration_tests\": \"67ebc8376acf2c404cb1eecf8e86fe4ee5c74e3f\", \"telemetry_gpu_unittests\": \"1e62fd2a5915bf431f87f58c6b568dfe7a7bf40b\", \"telemetry_perf_unittests\": \"22012889b9d1d9486fbf7808928472eea80dcf04\", \"telemetry_unittests\": \"4e3de6c0ff5294c834889186fcfa01eddad8787f\", \"traffic_annotation_auditor_unittests\": \"6a31616653da03330dbe4a81ca6c3e7c1357a434\", \"ui_base_unittests\": \"962e31b42231fcbad27d51a034834c926c3c7c13\", \"ui_touch_selection_unittests\": \"acb7716132e205ff41e85365bcf2bca8908465c7\", \"unit_tests\": \"0e519ecca74f40a397e740f710c06359b50e1f98\", \"url_unittests\": \"4f5088cbd6fb381db233162b9014f53213cced1a\", \"views_perftests\": \"f6026948a00406119e10e29b3e06bf5c85ab9df7\", \"views_unittests\": \"35113fbe74f9f8de354a7116ba06aa71e297d107\", \"viz_unittests\": \"56c5850dba3c7976a95778061e0487292791bb80\", \"vr_common_unittests\": \"7ffa1c9f22f3dad88bf5dbb6215ed96e52d66f56\", \"vr_pixeltests\": \"180ecbfd45e409eca6a93bed7b3a0c201e921e02\", \"webdriver_wpt_tests\": \"0b55299eb22953b7d63602c0aaea433a2e23cf41\", \"weblayer_browsertests\": \"ca92cdbfa6d9d0e15e8b19cc95d7ccebc62a40de\", \"wm_unittests\": \"04632159d86dbf4837dfc9e9f2ab220fdbfee816\", \"wtf_unittests\": \"5ed5f337484d3ee3e3ec81e103203c3343236027\", \"xr_browser_tests\": \"d3031ff5408f215b0107c81c62051088b1a7b12a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a432d290b72bc0f3cae401fbd647c33c14340f64e89770e9c9c39d6230495526_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-east1-d-8-34-7cvm\"], \"image\": [\"chrome-xenial-19112000-160fdd580f1\"], \"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\": [\"4645-7f6a85d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574364307535037",
"status": "COMPLETED",
"status_changed_ts": "1574367798357359",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165120",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e45c46526358c37ef088187e9ea5c83f2ba85c33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e45c46526358c37ef088187e9ea5c83f2ba85c33",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061583441389141440",
"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/8896153379351976384/+/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:48a83a8fd077f810",
"user_agent:luci-scheduler"
],
"updated_ts": "1574367798409364",
"url": "https://ci.chromium.org/b/8896153379351976384",
"utcnow_ts": "1574367799144833"
}
}
[20:23:19.156] Invocation finished in 1h2m50.973117762s with status SUCCEEDED