[05:33:05.889] New invocation is queued and will start shortly
[05:33:06.989] Starting the invocation (attempt 1)
[05:33:07.029] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:33:07.029] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:33:07.030] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022316411758806224",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"74608b5a12a251fa4828859c24fa0bf9c3a1d537\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9022316411758806224",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/74608b5a12a251fa4828859c24fa0bf9c3a1d537",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74608b5a12a251fa4828859c24fa0bf9c3a1d537",
"gitiles_ref:refs/heads/master"
]
}
[05:33:08.305] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611811987335350",
"id": "8856886349526542368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"74608b5a12a251fa4828859c24fa0bf9c3a1d537\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611811988024651",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180052",
"builder:Linux Builder (dbg)",
"buildset:commit/git/74608b5a12a251fa4828859c24fa0bf9c3a1d537",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74608b5a12a251fa4828859c24fa0bf9c3a1d537",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022316411758806224",
"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/8856886349526542368/+/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": "1611811988024812",
"url": "https://ci.chromium.org/b/8856886349526542368",
"utcnow_ts": "1611811988290094"
}
}
[05:33:08.305] Task URL: https://ci.chromium.org/b/8856886349526542368
[05:33:08.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:2:0) after 1m0s
[05:33:22.176] Received PubSub notification, asking Buildbucket for the build status
[05:33:22.242] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:08.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:2:0)
[05:34:08.785] Timer tick, asking Buildbucket for the build status
[05:34:09.060] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:09.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:4:0) after 1m0s
[05:35:09.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:4:0)
[05:35:09.154] Timer tick, asking Buildbucket for the build status
[05:35:09.861] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:09.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:5:0) after 1m0s
[05:36:09.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:5:0)
[05:36:09.896] Timer tick, asking Buildbucket for the build status
[05:36:10.043] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:10.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:6:0) after 1m0s
[05:37:10.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:6:0)
[05:37:10.116] Timer tick, asking Buildbucket for the build status
[05:37:10.217] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:10.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:7:0) after 1m0s
[05:38:10.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:7:0)
[05:38:10.346] Timer tick, asking Buildbucket for the build status
[05:38:10.515] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:10.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:8:0) after 1m0s
[05:39:10.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:8:0)
[05:39:10.534] Timer tick, asking Buildbucket for the build status
[05:39:10.637] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:10.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:9:0) after 1m0s
[05:40:11.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:9:0)
[05:40:11.343] Timer tick, asking Buildbucket for the build status
[05:40:11.472] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:11.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:10:0) after 1m0s
[05:41:11.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:10:0)
[05:41:11.604] Timer tick, asking Buildbucket for the build status
[05:41:13.276] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:13.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:11:0) after 1m0s
[05:42:13.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:11:0)
[05:42:13.299] Timer tick, asking Buildbucket for the build status
[05:42:13.389] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:13.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:12:0) after 1m0s
[05:43:13.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:12:0)
[05:43:13.409] Timer tick, asking Buildbucket for the build status
[05:43:13.729] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:13.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:13:0) after 1m0s
[05:44:13.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:13:0)
[05:44:13.764] Timer tick, asking Buildbucket for the build status
[05:44:13.923] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:13.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:14:0) after 1m0s
[05:45:13.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:14:0)
[05:45:13.977] Timer tick, asking Buildbucket for the build status
[05:45:14.044] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:14.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:15:0) after 1m0s
[05:46:14.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:15:0)
[05:46:14.065] Timer tick, asking Buildbucket for the build status
[05:46:14.122] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:14.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:16:0) after 1m0s
[05:47:14.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:16:0)
[05:47:14.153] Timer tick, asking Buildbucket for the build status
[05:47:14.234] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:14.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:17:0) after 1m0s
[05:48:14.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:17:0)
[05:48:14.256] Timer tick, asking Buildbucket for the build status
[05:48:14.321] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:14.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:18:0) after 1m0s
[05:49:14.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:18:0)
[05:49:14.341] Timer tick, asking Buildbucket for the build status
[05:49:14.434] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:14.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:19:0) after 1m0s
[05:50:14.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:19:0)
[05:50:14.477] Timer tick, asking Buildbucket for the build status
[05:50:14.991] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:14.991] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:20:0) after 1m0s
[05:51:15.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:20:0)
[05:51:15.039] Timer tick, asking Buildbucket for the build status
[05:51:15.470] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:15.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:21:0) after 1m0s
[05:52:15.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:21:0)
[05:52:15.533] Timer tick, asking Buildbucket for the build status
[05:52:15.607] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:15.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:22:0) after 1m0s
[05:53:15.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:22:0)
[05:53:15.608] Timer tick, asking Buildbucket for the build status
[05:53:15.696] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:15.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:23:0) after 1m0s
[05:54:15.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:23:0)
[05:54:15.754] Timer tick, asking Buildbucket for the build status
[05:54:16.624] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:16.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:24:0) after 1m0s
[05:55:16.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:24:0)
[05:55:16.648] Timer tick, asking Buildbucket for the build status
[05:55:16.954] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:16.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:25:0) after 1m0s
[05:56:16.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:25:0)
[05:56:16.998] Timer tick, asking Buildbucket for the build status
[05:56:17.173] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:17.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:26:0) after 1m0s
[05:57:17.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:26:0)
[05:57:17.214] Timer tick, asking Buildbucket for the build status
[05:57:17.265] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:17.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:27:0) after 1m0s
[05:58:17.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:27:0)
[05:58:17.284] Timer tick, asking Buildbucket for the build status
[05:58:17.415] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:17.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:28:0) after 1m0s
[05:59:17.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:28:0)
[05:59:17.434] Timer tick, asking Buildbucket for the build status
[05:59:17.485] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:17.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:29:0) after 1m0s
[06:00:17.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:29:0)
[06:00:17.511] Timer tick, asking Buildbucket for the build status
[06:00:17.626] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:17.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:30:0) after 1m0s
[06:01:18.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:30:0)
[06:01:18.596] Timer tick, asking Buildbucket for the build status
[06:01:18.728] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:18.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:31:0) after 1m0s
[06:02:18.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:31:0)
[06:02:18.900] Timer tick, asking Buildbucket for the build status
[06:02:18.980] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:18.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:32:0) after 1m0s
[06:03:19.040] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:32:0)
[06:03:19.040] Timer tick, asking Buildbucket for the build status
[06:03:19.153] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:19.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:33:0) after 1m0s
[06:04:19.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:33:0)
[06:04:19.236] Timer tick, asking Buildbucket for the build status
[06:04:19.602] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:19.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:34:0) after 1m0s
[06:05:19.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:34:0)
[06:05:19.786] Timer tick, asking Buildbucket for the build status
[06:05:19.999] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:19.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:35:0) after 1m0s
[06:06:20.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:35:0)
[06:06:20.469] Timer tick, asking Buildbucket for the build status
[06:06:20.543] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:20.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:36:0) after 1m0s
[06:07:20.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:36:0)
[06:07:20.563] Timer tick, asking Buildbucket for the build status
[06:07:21.093] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:21.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:37:0) after 1m0s
[06:08:21.091] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:37:0)
[06:08:21.091] Timer tick, asking Buildbucket for the build status
[06:08:21.198] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:21.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:38:0) after 1m0s
[06:09:21.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:38:0)
[06:09:21.238] Timer tick, asking Buildbucket for the build status
[06:09:22.670] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:22.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:39:0) after 1m0s
[06:10:22.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:39:0)
[06:10:22.737] Timer tick, asking Buildbucket for the build status
[06:10:22.857] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:22.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:40:0) after 1m0s
[06:11:22.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:40:0)
[06:11:22.880] Timer tick, asking Buildbucket for the build status
[06:11:23.099] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:23.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:41:0) after 1m0s
[06:12:23.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:41:0)
[06:12:23.112] Timer tick, asking Buildbucket for the build status
[06:12:23.210] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:23.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:42:0) after 1m0s
[06:13:23.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:42:0)
[06:13:23.195] Timer tick, asking Buildbucket for the build status
[06:13:23.346] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:23.346] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:43:0) after 1m0s
[06:14:23.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:43:0)
[06:14:23.580] Timer tick, asking Buildbucket for the build status
[06:14:23.725] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:23.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:44:0) after 1m0s
[06:15:23.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:44:0)
[06:15:23.797] Timer tick, asking Buildbucket for the build status
[06:15:23.873] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:23.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:45:0) after 1m0s
[06:16:23.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:45:0)
[06:16:23.969] Timer tick, asking Buildbucket for the build status
[06:16:24.066] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:24.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:46:0) after 1m0s
[06:17:24.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:46:0)
[06:17:24.148] Timer tick, asking Buildbucket for the build status
[06:17:24.273] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:47:0) after 1m0s
[06:18:24.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:47:0)
[06:18:24.320] Timer tick, asking Buildbucket for the build status
[06:18:24.423] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:24.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:48:0) after 1m0s
[06:19:24.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:48:0)
[06:19:24.442] Timer tick, asking Buildbucket for the build status
[06:19:24.493] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:24.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:49:0) after 1m0s
[06:20:24.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:49:0)
[06:20:24.541] Timer tick, asking Buildbucket for the build status
[06:20:24.599] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:24.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:50:0) after 1m0s
[06:21:24.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:50:0)
[06:21:24.637] Timer tick, asking Buildbucket for the build status
[06:21:24.785] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:24.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:51:0) after 1m0s
[06:22:24.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:51:0)
[06:22:24.836] Timer tick, asking Buildbucket for the build status
[06:22:24.912] Build 8856886349526542368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:24.912] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022316411758806224:52:0) after 1m0s
[06:23:07.277] Received PubSub notification, asking Buildbucket for the build status
[06:23:07.389] Build 8856886349526542368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:23:07.389] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611814986371438",
"created_by": "project:chromium",
"created_ts": "1611811987335350",
"id": "8856886349526542368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"74608b5a12a251fa4828859c24fa0bf9c3a1d537\"}}",
"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\": \"74608b5a12a251fa4828859c24fa0bf9c3a1d537\", \"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-142-x7y6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180052, \"got_angle_revision\": \"351e6e8c7b4952f7d01facdb322ac765d876bae2\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"74608b5a12a251fa4828859c24fa0bf9c3a1d537\", \"got_revision_cp\": \"refs/heads/master@{#847987}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"54a4e0518d25fab76af6ec1d99b860b0b53b47cf\", \"got_v8_revision_cp\": \"refs/heads/9.0.90@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\", \"prev_got_revision\": \"830fa1d1f25064ac1fdfda8e9d9b2a6dcc2e60e3\", \"prev_got_revision_cp\": \"refs/heads/master@{#847964}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"74608b5a12a251fa4828859c24fa0bf9c3a1d537\", \"swarm_hashes_refs/heads/master(at){#847987}_without_patch\": {\"absl_hardening_tests\": \"28c56c84cbfb7d3d924c0e5c714ce75272f305b5\", \"accessibility_unittests\": \"91705fd909a51b75c2a2de46490f895659688d91\", \"angle_unittests\": \"6c46f345cb152fe45e4daa5b1de5a4462e1e4be2\", \"app_shell_unittests\": \"f8e4b8694b213d0f7f05ca2085b4ce40ce7b1d27\", \"aura_unittests\": \"89ac66f623c15ffce948ad3b4fb19bd31624799c\", \"base_unittests\": \"f0ed2346c11054adcaad4b2668103714eb8ca746\", \"base_util_unittests\": \"cd5fca2aead4a29b2403ba20ea07073e92e9e80e\", \"blink_common_unittests\": \"5be901ca4c572de4def2df438224dcaf39adddbe\", \"blink_fuzzer_unittests\": \"02e4784831b7f0b186f8ac6a5df147f64d7b968e\", \"blink_heap_unittests\": \"24be821c3e85b07d38b276fa2018cd4b6ead12e2\", \"blink_platform_unittests\": \"7b0b0c4a3e3c02710933e9d60c22d1d74fac964b\", \"blink_python_tests\": \"1ac83a103ea06873b02eaeeeaa679a1077eeedde\", \"blink_unittests\": \"b9a803a61a3cef4d3c91c9508241568574e30a50\", \"blink_web_tests\": \"cfcbe9d9bd530502565b3549060d2a0aafabdba3\", \"boringssl_crypto_tests\": \"7c13d8defd5676826071967e6e107c1b2f3e8afd\", \"boringssl_ssl_tests\": \"e287a2e931c3580a67b7f5e2110481ecbd92dfe5\", \"browser_tests\": \"f53ff8910975d12793c81ff9b613f7bd6a067e2a\", \"capture_unittests\": \"c9572c7d7a9a221fe7aacb955f78cb5c0b1d0ff4\", \"cast_unittests\": \"7c6703c7befbd46afdaefcab4dc1df4a8a3b5aaf\", \"cc_unittests\": \"017ca00f77d01358c56bda88ef229a9eb7a0ac1a\", \"chrome_app_unittests\": \"9a0b5783cd15348f89ae3080a6778e820cdff456\", \"chromedriver_unittests\": \"343596875adce561404bacfb6933a285f779b5e8\", \"components_browsertests\": \"4bad63fa705824eed61c891f9021f731af9a40da\", \"components_unittests\": \"0843f0c8197e9c061bc9a0607e8b9cfb2909ccec\", \"compositor_unittests\": \"86e3632808f2a7c8a36f6116774eb5743f67475a\", \"content_browsertests\": \"5388ccb5243c405d47ad22c0279541edaea1e017\", \"content_shell_crash_test\": \"9b6a80ec3673304f9f1e01adc7c12e881140be2c\", \"content_unittests\": \"7ebda4f8c3cbe159d3d75957386c94f73b1d1085\", \"crashpad_tests\": \"168819bd4cd8e03501bedb4a3d012d3a4c1a5db3\", \"cronet_tests\": \"2e842baa5c1c519cef329cf84e7e1fddf4796707\", \"cronet_unittests\": \"523a98ef039bce8f87f6fbe70a070fbb5350f84e\", \"crypto_unittests\": \"efcffa564dfd98fefbeb7b3d566ac24d2f9ea44a\", \"dbus_unittests\": \"46ec35495578e07a2204656b63a08c2b1bbd1ea9\", \"device_unittests\": \"8291d5d8e9ea4a3fb38e5a60d956437ee7d90ea1\", \"display_unittests\": \"33738929772982dfb4c0cf7dc5c934d3d5594eff\", \"events_unittests\": \"6b4531562fa5923735d24b967a8c0d71bab4b22c\", \"extensions_browsertests\": \"2bd2012add8077d2ddec3cf8dbb7ff0c20d848fa\", \"extensions_unittests\": \"5314e04fe9cb13f8362d222fb63c8afd44c06701\", \"filesystem_service_unittests\": \"622e35b8d15c36e8417b8df41fd11c19708f0cf4\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"ef8e23be7bc336692e3a0857eb8448202511f57e\", \"gfx_unittests\": \"9e4027b9cbfee653b02b500a61ac5c386aaa808e\", \"gin_unittests\": \"3b9a186d18862c8a0ae4c087fae10bd668978dcb\", \"google_apis_unittests\": \"a1bdd34962e27a18a258f577da81c303ee7b1f26\", \"gpu_unittests\": \"9ab0d29ec33cefdd71ab679137afe9962e765d5b\", \"grit_python_unittests\": \"b0890b035c0d25a1641db23ae376539c3f64cf64\", \"gwp_asan_unittests\": \"78bd7ac9ca46dd318727fb8ce1cbd234b79be125\", \"headless_browsertests\": \"0889427f3b7629c2cfc7bb6339b29e1dd5661225\", \"headless_unittests\": \"ae07b0461d868ae0e3437cfa613288e33592dc82\", \"interactive_ui_tests\": \"66a2a092ed9bdaf698e75b42200cb07d8b0c2bd5\", \"ipc_tests\": \"30bf8732cac5f86eb5265812d7cf10572cd1c05a\", \"jingle_unittests\": \"ff4da91b12fcc1252930407ea3d55898c5828f72\", \"latency_unittests\": \"95b643be3a21b04bb41e2e35741462211e185a41\", \"libjingle_xmpp_unittests\": \"6f68b5ee22e921d27360dfa78799767ed1c7d0be\", \"liburlpattern_unittests\": \"9720983034414e13ac2bfdb3038b6eeb1bf3dee1\", \"media_blink_unittests\": \"a3999096e0b7b7ae66197a3c267034d78a60d003\", \"media_unittests\": \"42b7e28ed0a90743553131d2e5edd9ae1682dc56\", \"message_center_unittests\": \"b2b429844a4bb8313b07170bcbbd1a7617018a8e\", \"metrics_python_tests\": \"bbe84bfdd7fe3edb4d20a002895e4c406b02ee8c\", \"midi_unittests\": \"41fa2940a58389761394c2eb5383c00e05f6e2a2\", \"mojo_core_unittests\": \"f8681aa9f45525d5919e2a95b9ca3c0503214ae5\", \"mojo_unittests\": \"453622530c9f029ce15d81e5b2f63289835981fb\", \"nacl_helper_nonsfi_unittests\": \"a66187f4999592fc15d9830c06d05ce594f41d6e\", \"nacl_loader_unittests\": \"d0e680db503e9a7228f8871c9902c1c4d7c4f1f4\", \"native_theme_unittests\": \"059609cb8b7e82faee52b213d6613dbbf4ae38ae\", \"net_unittests\": \"d7a4f0b7e6dbe7d3c8685dd3c412419d36fc3ebf\", \"openscreen_unittests\": \"b89e47ace1b817968ac59a19f4e55c888fbb2115\", \"pdf_unittests\": \"c56a8dd7316a44c7145be1b45b8d4b28e66b3961\", \"perfetto_unittests\": \"52a3ffebe08bc0d8f9fe6c0eeaeba997d955529f\", \"ppapi_unittests\": \"05fb7aaa7f2fd006395bf6f9edd582f165f33bb0\", \"printing_unittests\": \"37758c9feb3d3154e72b31711331d99cd4ea5160\", \"remoting_unittests\": \"935fd033d5bdebbb7619a5a9b5157ef271dca9c3\", \"sandbox_linux_unittests\": \"8c5cea002bc7f3bad3236d8aa3084d0ec53c4c02\", \"service_manager_unittests\": \"159428198729436d3f8ba87bf9c9497a8f6b180e\", \"services_unittests\": \"df7cb091d25bf344fafb98fa9eeef6633e5966e0\", \"shell_dialogs_unittests\": \"8622665e7a8686afb8499137b7da39b4b4444ca5\", \"skia_unittests\": \"f0977ac09e5bf33dc3d84675139c1365e7371934\", \"snapshot_unittests\": \"552b4383703e027378246b152b629d17f9b81afe\", \"sql_unittests\": \"cb828eb756150317e9cd5b8bd7daa1f44627324e\", \"storage_unittests\": \"9ce362ae51f1bbb05f4836442a22dacfc969c2d0\", \"sync_integration_tests\": \"f128f5d4eabe3f7927640d7a622b48c530d7e71a\", \"telemetry_gpu_unittests\": \"0288eeb990c2559dcfba6c5626e88e90939450db\", \"telemetry_perf_unittests\": \"1ebce48e1bae434bf5d5f0a7b23afde24978771a\", \"telemetry_unittests\": \"5f3bf04bdc0e5f8e7d8883fca91e7c98d9a5c94e\", \"traffic_annotation_auditor_unittests\": \"a91281d1c6129f83ac5479830017521eec3940af\", \"ui_base_unittests\": \"2311b03c280dd61aa772f3ee850df35be2974c93\", \"ui_touch_selection_unittests\": \"d1bd98509141ee3ef9e7a95992394372eda95fac\", \"unit_tests\": \"6b1eac65a9172e113322975a80351cec12254d05\", \"url_unittests\": \"341048309eacf622522bf1bb0f9836eb006c2c9c\", \"views_perftests\": \"fc4af7ba7672927a64bbef8c587148dd1cb6499c\", \"views_unittests\": \"8bda5826b3338f7dba16f72e1a59e3a13d69ebcf\", \"viz_unittests\": \"c18cf6529f9b6acaaec651a14fdf722886c01936\", \"vr_common_unittests\": \"21ec0d7edcb510c919e583b77f48c521e2d26ec9\", \"vr_pixeltests\": \"abb6021506b0245590bfe7d261c7360780888885\", \"webdriver_wpt_tests\": \"fe06e3af6d577bb3f98c27fa7a482d04eddc1b3b\", \"weblayer_browsertests\": \"b6242950cdad9a317f3064a394c4fee1de2dcbe6\", \"weblayer_unittests\": \"fc29b8b3dca495f245e4252f5262a03426131bbc\", \"wm_unittests\": \"fa635d4808cc7c611fab384b642720f4fad44520\", \"wtf_unittests\": \"cc7b7da238381a09790a7201c67f8c9345315736\", \"x11_unittests\": \"00f2d6d2897d1edaa2dc5a69cef44ab682b83788\", \"xr_browser_tests\": \"64e761dc3ad21b2e37143a25c1fb9d62b9499fa1\", \"zlib_unittests\": \"e42845725c426ad64fc2018f992581a310267266\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_7a9f94695aa83f7478015e01762e75301e67c95c681ec0592232bd343861e191_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_v2\", \"builder_fad65aa1181b0cae2430b762f4f30b6d09779e5e6c0bbaecfe62f78c8fc0ada8_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-142-x7y6\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611812001024988",
"status": "COMPLETED",
"status_changed_ts": "1611814986848902",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180052",
"builder:Linux Builder (dbg)",
"buildset:commit/git/74608b5a12a251fa4828859c24fa0bf9c3a1d537",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74608b5a12a251fa4828859c24fa0bf9c3a1d537",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022316411758806224",
"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/8856886349526542368/+/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:51604cc7051e4210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611814986859652",
"url": "https://ci.chromium.org/b/8856886349526542368",
"utcnow_ts": "1611814987348918"
}
}
[06:23:07.391] Invocation finished in 50m1.567760355s with status SUCCEEDED