[11:22:02.783] New invocation is queued and will start shortly
[11:22:04.019] Starting the invocation (attempt 1)
[11:22:04.065] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:22:04.065] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:22:04.065] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054818768718407200",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"22e4474fca1b292cc29b0af7c59cc0990e502980\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054818768718407200",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/22e4474fca1b292cc29b0af7c59cc0990e502980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/22e4474fca1b292cc29b0af7c59cc0990e502980",
"gitiles_ref:refs/heads/master"
]
}
[11:22:05.038] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580815324315277",
"id": "8889388706449042144",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"22e4474fca1b292cc29b0af7c59cc0990e502980\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580815324796912",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168215",
"builder:Linux Builder (dbg)",
"buildset:commit/git/22e4474fca1b292cc29b0af7c59cc0990e502980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/22e4474fca1b292cc29b0af7c59cc0990e502980",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054818768718407200",
"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/8889388706449042144/+/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": "1580815324797014",
"url": "https://ci.chromium.org/b/8889388706449042144",
"utcnow_ts": "1580815325021354"
}
}
[11:22:05.038] Task URL: https://ci.chromium.org/b/8889388706449042144
[11:22:05.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:2:0) after 1m0s
[11:23:05.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:2:0)
[11:23:05.102] Timer tick, asking Buildbucket for the build status
[11:23:05.703] Build 8889388706449042144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:23:05.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:3:0) after 1m0s
[11:24:05.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:3:0)
[11:24:05.837] Timer tick, asking Buildbucket for the build status
[11:24:07.687] Build 8889388706449042144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:24:07.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:4:0) after 1m0s
[11:25:07.710] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:4:0)
[11:25:07.710] Timer tick, asking Buildbucket for the build status
[11:25:07.884] Build 8889388706449042144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:25:07.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:5:0) after 1m0s
[11:26:07.924] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:5:0)
[11:26:07.924] Timer tick, asking Buildbucket for the build status
[11:26:09.706] Build 8889388706449042144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:09.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:6:0) after 1m0s
[11:27:07.454] Received PubSub notification, asking Buildbucket for the build status
[11:27:07.580] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:09.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:6:0)
[11:27:09.948] Timer tick, asking Buildbucket for the build status
[11:27:10.088] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:10.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:8:0) after 1m0s
[11:28:10.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:8:0)
[11:28:10.151] Timer tick, asking Buildbucket for the build status
[11:28:12.309] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:12.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:9:0) after 1m0s
[11:29:12.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:9:0)
[11:29:12.295] Timer tick, asking Buildbucket for the build status
[11:29:18.478] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:18.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:10:0) after 1m0s
[11:30:18.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:10:0)
[11:30:18.554] Timer tick, asking Buildbucket for the build status
[11:30:20.443] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:20.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:11:0) after 1m0s
[11:31:20.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:11:0)
[11:31:20.491] Timer tick, asking Buildbucket for the build status
[11:31:21.389] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:21.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:12:0) after 1m0s
[11:32:21.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:12:0)
[11:32:21.517] Timer tick, asking Buildbucket for the build status
[11:32:25.812] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:25.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:13:0) after 1m0s
[11:33:25.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:13:0)
[11:33:25.906] Timer tick, asking Buildbucket for the build status
[11:33:26.416] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:26.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:14:0) after 1m0s
[11:34:26.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:14:0)
[11:34:26.447] Timer tick, asking Buildbucket for the build status
[11:34:26.774] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:26.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:15:0) after 1m0s
[11:35:26.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:15:0)
[11:35:26.827] Timer tick, asking Buildbucket for the build status
[11:35:27.033] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:27.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:16:0) after 1m0s
[11:36:27.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:16:0)
[11:36:27.059] Timer tick, asking Buildbucket for the build status
[11:36:27.503] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:27.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:17:0) after 1m0s
[11:37:27.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:17:0)
[11:37:27.586] Timer tick, asking Buildbucket for the build status
[11:37:27.826] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:27.826] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:18:0) after 1m0s
[11:38:28.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:18:0)
[11:38:28.286] Timer tick, asking Buildbucket for the build status
[11:38:28.547] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:28.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:19:0) after 1m0s
[11:39:28.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:19:0)
[11:39:28.659] Timer tick, asking Buildbucket for the build status
[11:39:28.971] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:28.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:20:0) after 1m0s
[11:40:28.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:20:0)
[11:40:28.994] Timer tick, asking Buildbucket for the build status
[11:40:29.821] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:29.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:21:0) after 1m0s
[11:41:29.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:21:0)
[11:41:29.889] Timer tick, asking Buildbucket for the build status
[11:41:30.122] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:30.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:22:0) after 1m0s
[11:42:30.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:22:0)
[11:42:30.429] Timer tick, asking Buildbucket for the build status
[11:42:31.314] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:31.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:23:0) after 1m0s
[11:43:31.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:23:0)
[11:43:31.618] Timer tick, asking Buildbucket for the build status
[11:43:32.341] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:32.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:24:0) after 1m0s
[11:44:32.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:24:0)
[11:44:32.523] Timer tick, asking Buildbucket for the build status
[11:44:32.794] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:32.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:25:0) after 1m0s
[11:45:32.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:25:0)
[11:45:32.974] Timer tick, asking Buildbucket for the build status
[11:45:33.300] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:33.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:26:0) after 1m0s
[11:46:33.689] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:26:0)
[11:46:33.689] Timer tick, asking Buildbucket for the build status
[11:46:34.134] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:34.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:27:0) after 1m0s
[11:47:34.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:27:0)
[11:47:34.239] Timer tick, asking Buildbucket for the build status
[11:47:34.392] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:34.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:28:0) after 1m0s
[11:48:34.612] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:28:0)
[11:48:34.612] Timer tick, asking Buildbucket for the build status
[11:48:35.381] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:35.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:29:0) after 1m0s
[11:49:35.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:29:0)
[11:49:35.489] Timer tick, asking Buildbucket for the build status
[11:49:35.964] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:35.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:30:0) after 1m0s
[11:50:36.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:30:0)
[11:50:36.256] Timer tick, asking Buildbucket for the build status
[11:50:36.578] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:36.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:31:0) after 1m0s
[11:51:36.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:31:0)
[11:51:36.659] Timer tick, asking Buildbucket for the build status
[11:51:36.874] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:36.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:32:0) after 1m0s
[11:52:36.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:32:0)
[11:52:36.911] Timer tick, asking Buildbucket for the build status
[11:52:37.844] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:37.844] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:33:0) after 1m0s
[11:53:37.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:33:0)
[11:53:37.956] Timer tick, asking Buildbucket for the build status
[11:53:38.631] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:38.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:34:0) after 1m0s
[11:54:38.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:34:0)
[11:54:38.871] Timer tick, asking Buildbucket for the build status
[11:54:39.412] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:39.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:35:0) after 1m0s
[11:55:39.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:35:0)
[11:55:39.419] Timer tick, asking Buildbucket for the build status
[11:55:39.792] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:39.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:36:0) after 1m0s
[11:56:39.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:36:0)
[11:56:39.766] Timer tick, asking Buildbucket for the build status
[11:56:40.038] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:40.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:37:0) after 1m0s
[11:57:40.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:37:0)
[11:57:40.025] Timer tick, asking Buildbucket for the build status
[11:57:40.244] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:40.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:38:0) after 1m0s
[11:58:40.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:38:0)
[11:58:40.457] Timer tick, asking Buildbucket for the build status
[11:58:40.621] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:40.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:39:0) after 1m0s
[11:59:41.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:39:0)
[11:59:41.006] Timer tick, asking Buildbucket for the build status
[11:59:41.275] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:41.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:40:0) after 1m0s
[12:00:41.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:40:0)
[12:00:41.680] Timer tick, asking Buildbucket for the build status
[12:00:42.167] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:42.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:41:0) after 1m0s
[12:01:42.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:41:0)
[12:01:42.530] Timer tick, asking Buildbucket for the build status
[12:01:42.850] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:42.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:42:0) after 1m0s
[12:02:42.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:42:0)
[12:02:42.915] Timer tick, asking Buildbucket for the build status
[12:02:43.688] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:43.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:43:0) after 1m0s
[12:03:43.712] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:43:0)
[12:03:43.712] Timer tick, asking Buildbucket for the build status
[12:03:44.553] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:44.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:44:0) after 1m0s
[12:04:44.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:44:0)
[12:04:44.652] Timer tick, asking Buildbucket for the build status
[12:04:44.873] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:44.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:45:0) after 1m0s
[12:05:45.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:45:0)
[12:05:45.172] Timer tick, asking Buildbucket for the build status
[12:05:45.299] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:45.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:46:0) after 1m0s
[12:06:45.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:46:0)
[12:06:45.554] Timer tick, asking Buildbucket for the build status
[12:06:45.802] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:45.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:47:0) after 1m0s
[12:07:46.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:47:0)
[12:07:46.107] Timer tick, asking Buildbucket for the build status
[12:07:48.322] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:48.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:48:0) after 1m0s
[12:08:48.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:48:0)
[12:08:48.375] Timer tick, asking Buildbucket for the build status
[12:08:49.989] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:49.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:49:0) after 1m0s
[12:09:50.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:49:0)
[12:09:50.172] Timer tick, asking Buildbucket for the build status
[12:09:51.536] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:51.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:50:0) after 1m0s
[12:10:51.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:50:0)
[12:10:51.817] Timer tick, asking Buildbucket for the build status
[12:10:52.069] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:52.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:51:0) after 1m0s
[12:11:52.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:51:0)
[12:11:52.314] Timer tick, asking Buildbucket for the build status
[12:11:52.773] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:52.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:52:0) after 1m0s
[12:12:52.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:52:0)
[12:12:52.983] Timer tick, asking Buildbucket for the build status
[12:12:53.318] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:53.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:53:0) after 1m0s
[12:13:53.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:53:0)
[12:13:53.517] Timer tick, asking Buildbucket for the build status
[12:13:53.936] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:53.936] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:54:0) after 1m0s
[12:14:54.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:54:0)
[12:14:54.062] Timer tick, asking Buildbucket for the build status
[12:14:54.515] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:54.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:55:0) after 1m0s
[12:15:54.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:55:0)
[12:15:54.647] Timer tick, asking Buildbucket for the build status
[12:15:54.884] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:54.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:56:0) after 1m0s
[12:16:54.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:56:0)
[12:16:54.988] Timer tick, asking Buildbucket for the build status
[12:16:55.602] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:55.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:57:0) after 1m0s
[12:17:55.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:57:0)
[12:17:55.637] Timer tick, asking Buildbucket for the build status
[12:17:55.851] Build 8889388706449042144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:55.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054818768718407200:58:0) after 1m0s
[12:18:49.640] Received PubSub notification, asking Buildbucket for the build status
[12:18:49.771] Build 8889388706449042144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:18:49.771] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580818728833441",
"created_by": "project:chromium",
"created_ts": "1580815324315277",
"id": "8889388706449042144",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"22e4474fca1b292cc29b0af7c59cc0990e502980\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-72-ezj1\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168215, \"got_angle_revision\": \"4546c5cefa0eb11d423b60ac98802e671510a562\", \"got_dawn_revision\": \"43a0a815b8146d7ce596e281267ccd6565e9619e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"22e4474fca1b292cc29b0af7c59cc0990e502980\", \"got_revision_cp\": \"refs/heads/master@{#738127}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"41a7302e9c62c207e5ac5bcce40f16a1e418c42b\", \"got_v8_revision\": \"43c981c95dc071f5e1fd8392c8d735ab33fa7e13\", \"got_v8_revision_cp\": \"refs/heads/8.2.6@{#1}\", \"got_webrtc_revision\": \"3fa2b80e14fa8b9e05ca8efaf539e43a2cc46921\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30449}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"22e4474fca1b292cc29b0af7c59cc0990e502980\", \"swarm_hashes_refs/heads/master(at){#738127}_without_patch\": {\"accessibility_unittests\": \"6eb574d2900b49aa7250ae27d690cbd9f384da11\", \"angle_unittests\": \"27f957a79a8ea6482bf88008eb8c0a6a593b5e55\", \"app_shell_unittests\": \"2d043c3afc63c9f2bd8f470866f45a2ed4dae13c\", \"aura_unittests\": \"608968a97c321ab2058014fed5fc68b52e178855\", \"base_unittests\": \"4718780dc9383f7e50093dfc66909b0aa020cdcc\", \"base_util_unittests\": \"fc9db37de4a85c1339326f88b829e265ee0dbd46\", \"blink_common_unittests\": \"53a213e48a12aaeeb02c648f7d0a89d01f16731a\", \"blink_fuzzer_unittests\": \"fca55d0e9e81519a448e1553318720a14161b176\", \"blink_heap_unittests\": \"c3ff45a08907109870dddfa636aaabf6a2a6922e\", \"blink_platform_unittests\": \"69f4d063f04632f00c330d20cc8acc193415728f\", \"blink_python_tests\": \"9b8a8582f5e40c8ea187168de0ed76ba61238255\", \"blink_unittests\": \"186bb88ba97450106aff57d7943d8903be8c17e9\", \"blink_web_tests\": \"7d0e77dd1dd572cc5a0837fdea6a0a4c4a286c5b\", \"boringssl_crypto_tests\": \"8d11744e898aaaa0de4f3a891f519dcc1363c358\", \"boringssl_ssl_tests\": \"a3f9377aa1c6daee17bb770b04c73491f1b39f3b\", \"browser_tests\": \"92821b56c935b8e5aecaefb08e3ae79e2a01cb3b\", \"cacheinvalidation_unittests\": \"3915207468567d0fc827e85d11514203fc189bc8\", \"capture_unittests\": \"b5143fb6c29c4c4acc3466a0a90e85f8917125da\", \"cast_unittests\": \"3c06a87318101db622df3d838fe5223a71f75093\", \"cc_unittests\": \"29fd14cffeec6cee05c1437ee927bfb8e4f7c9ad\", \"chrome_app_unittests\": \"5e7e52681c0e3e099987438dc737568ac6b04284\", \"chromedriver_unittests\": \"1cbb705a51d154c5779f59c93fc7107e96373384\", \"components_browsertests\": \"90a4e265dcac353b01e0d855fab6c38a4d9d593c\", \"components_unittests\": \"fd19734c85498c4444048b0ca2e8da842192690d\", \"compositor_unittests\": \"e71c9d876b77939730e012eaf07692a985f69da9\", \"content_browsertests\": \"c7bf65d3e8984bedce4c26eb0e9004441d24a910\", \"content_shell_crash_test\": \"c49ca82a736dd877d673ed0f9abed10b16b43143\", \"content_unittests\": \"23c88f2ec49df5439504cad6d4f6f12a16ab55e8\", \"crashpad_tests\": \"033adeb2bdf950da2e2a9ad3247944afa7288380\", \"cronet_tests\": \"3e3f236da519c236203877ea2434787859ad52ee\", \"cronet_unittests\": \"4e345b1159c3000eff697bfa1b3e2d4681c61712\", \"crypto_unittests\": \"ee4b2ed39ab6423f3efbe8fe175c7abb2e333800\", \"dbus_unittests\": \"5821f0434a26159ea90312bb2afcfa481610335d\", \"device_unittests\": \"f16bcd8b4dc6629b490a7802a28e1a1c2191199d\", \"display_unittests\": \"46f3b0f346ee9d0d5fe91a5a6cbcd28e2c9f696b\", \"events_unittests\": \"2bf2277a490af687d9003c278294d3830c36d88f\", \"extensions_browsertests\": \"5c8294d4b8b0ea72b9d2e71eca29a91577f002ca\", \"extensions_unittests\": \"de8ed6dddbe0da233ae8efe213bf7b2325c9a312\", \"filesystem_service_unittests\": \"3eaf3cda007ec38e7c204b7e190cc971da8ec9b0\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"cd0256c1a73819c92bac8ade4d5e5b6d8800e93e\", \"gfx_unittests\": \"27b5989ccb0bdd18dd6bd44cdd28f2370a27d0fe\", \"gin_unittests\": \"2c0b49dd0e0ddeb53f0e941b65cb2ef09592a8e9\", \"google_apis_unittests\": \"ebb032b9e2e3c01cfe1bd3f5dc5cf7c652e428c6\", \"gpu_unittests\": \"218dd41878f5edc1caa507b419535de415eb4e97\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"4f63affe42245801f27dd67b7878233469c5501c\", \"headless_browsertests\": \"c24e43fa3921af0c150b10ab01f69d364f61d247\", \"headless_unittests\": \"22a20c09e2e0b8990d187ad4bb70407249093aae\", \"interactive_ui_tests\": \"21c413dd1d993a8944af21cbe5087efea661c447\", \"ipc_tests\": \"eab292e770ccca5d58da08118254cc8877541a2b\", \"jingle_unittests\": \"a3b0a4733997b57ac068b0894e77e5c0cb195906\", \"latency_unittests\": \"ee4037af5620add034e09b66594ee3cd19d15b55\", \"libjingle_xmpp_unittests\": \"99173efb94746ee2af3eedd8fea2b9e026ab9da4\", \"media_blink_unittests\": \"0429d3eab2ed46b197968fbe55abf880f0f695f2\", \"media_service_unittests\": \"8e240ed8cd87a09f4c8b2705426d3a175bc27b22\", \"media_unittests\": \"2a76501fbb41d85f7b437d903eb6d004903b2a0b\", \"message_center_unittests\": \"ce0d0963d7d8198c524260a2c380cefeed937c32\", \"metrics_python_tests\": \"a2cfdc2ebc7521f7395a65fa024aa6a0ff8d38d9\", \"midi_unittests\": \"b235bf93449be5d18aa38598a52bdb356103a13c\", \"mojo_core_unittests\": \"c16a191c0ed2aa71c9ba2316ffdeef193c9b5c08\", \"mojo_unittests\": \"3e957403100650bb3d98eba58ff7bd96eff7cbe4\", \"nacl_helper_nonsfi_unittests\": \"d194f0bff22ef61facaeff50cdde8b4ed34901ab\", \"nacl_loader_unittests\": \"9da10b228432c94a27a07e32fb75e19b7a460630\", \"native_theme_unittests\": \"5354b5c1610e2e900bc8ab6c41bfe25042e08583\", \"net_unittests\": \"2e051ca0e2a683dcd3c66e813cbf92afb88a2fda\", \"pdf_unittests\": \"ead30191dca887e314743fca312aa45ceb84373c\", \"perfetto_unittests\": \"6085efb81c1c31f652c1138cda6ea55b9d169d5c\", \"ppapi_unittests\": \"41ab66f501822c3ca4d5693dad37735f04dc364c\", \"printing_unittests\": \"babc65b9e6c9a9fafd8067f025fab4c2c5726f1d\", \"remoting_unittests\": \"56fc385f72c69d2c7cffa10436e50c3c4075da21\", \"sandbox_linux_unittests\": \"7876ff25c19764dbb801ab37fa1da7eaa914dc6b\", \"service_manager_unittests\": \"46baca99b8fe58d868e8a20417461376657a8daa\", \"services_unittests\": \"edf22e2adb625b5acfebb39ab064eef207e251a3\", \"shell_dialogs_unittests\": \"788855c7d680b2731942f86d16888f248013384c\", \"skia_unittests\": \"2ad9b1ffe0f2f6278e600a31fdd83a61c679191a\", \"snapshot_unittests\": \"b29ff7bcdfb96ede5a3393f5864aedef211d08c0\", \"sql_unittests\": \"42f5589ed2ca13933b76c8fc94632efa859ec471\", \"storage_unittests\": \"1a44bb687a9b395ffd4e1abc7555056de9bb1f4d\", \"sync_integration_tests\": \"d768ccf5de5ff7d1088d41bc8e3907bbe5ab8549\", \"telemetry_gpu_unittests\": \"c4a92deaf8f26c48fc2486fa5086846ade9f75d0\", \"telemetry_perf_unittests\": \"4a2f66c156ab9f7019f1979f3ca77cb53632d692\", \"telemetry_unittests\": \"f2fddda633afca4526d1fd8249bf9d2aa9681454\", \"traffic_annotation_auditor_unittests\": \"253a78c9a94ab6eddf27b0c5fcb281b30ba5b69d\", \"ui_base_unittests\": \"0ac935404136c48462e18e096289d9bce0343989\", \"ui_touch_selection_unittests\": \"65207da5d78d08fb4b6896d9c2aa004614f6e6cd\", \"unit_tests\": \"0ac3bbd5be2cf8ea2a6406e37bd22658867a6570\", \"url_unittests\": \"fbf6ac31efb3e85628d5edde14c829589f31ac07\", \"views_perftests\": \"e8a602ccbfdfb4845d5ef8df15754d1f767f16ea\", \"views_unittests\": \"cf0f7ace90fd564408456d60d6c436e43d63360d\", \"viz_unittests\": \"8f16b6568813cc788d0cd76fcd820a15f5a7544a\", \"vr_common_unittests\": \"d36e83301a90b68c65ceca44b0abcf647ab76b34\", \"vr_pixeltests\": \"bad7fb71c6587cedda349aa4659f0dd32830eb87\", \"webdriver_wpt_tests\": \"2e04add4f4d1013b7b487a10481fab7fb428c009\", \"weblayer_browsertests\": \"afaacba860a6f89c45c35873242d05b6f9dab9cf\", \"wm_unittests\": \"8831f34cdc5619855e065e118d0e5f7c0b064d26\", \"wtf_unittests\": \"93e10734a6e2bda65dbfce120d0e6d042cc6f18d\", \"xr_browser_tests\": \"54dc5287405c9bb3abdb3a5589744c11335ed8fd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_ce52aa3ab9d9a450d9b5f3fb151dc5a055ee991845b7d9f6a360be4cd1eb07f1_v2\", \"builder_eeb4aed1f5957a78d1f0fe1c00bdb778895591d08c1bb63c47ebb234324ef661_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-72-ezj1\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580815626732645",
"status": "COMPLETED",
"status_changed_ts": "1580818729304896",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168215",
"builder:Linux Builder (dbg)",
"buildset:commit/git/22e4474fca1b292cc29b0af7c59cc0990e502980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/22e4474fca1b292cc29b0af7c59cc0990e502980",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054818768718407200",
"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/8889388706449042144/+/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:4a28c1426da3fe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580818729312129",
"url": "https://ci.chromium.org/b/8889388706449042144",
"utcnow_ts": "1580818729758270"
}
}
[12:18:49.771] Invocation finished in 56m47.004017447s with status SUCCEEDED