[09:20:19.061] New invocation is queued and will start shortly
[09:20:21.017] Starting the invocation (attempt 1)
[09:20:21.058] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03ef6fbea97e14e4b4a09daa574518152cd95056"
[09:20:21.058] Popped gitiles commit info from properties and tags
[09:20:21.058] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:20:21.058] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:20:21.058] Buildbucket request:
{
"requestId": "8979449751181349152",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dll)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "03ef6fbea97e14e4b4a09daa574518152cd95056",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8979449751181349152"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin(dll)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:20:21.603] Scheduled build:
{
"id": "8814019688332427985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dll)"
},
"number": 10339,
"createdBy": "project:chromium",
"createTime": "2022-05-16T09:20:21.146071651Z",
"updateTime": "2022-05-16T09:20:21.146071651Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "03ef6fbea97e14e4b4a09daa574518152cd95056",
"ref": "refs/heads/main"
}
}
}
[09:20:21.603] Task URL: https://cr-buildbucket.appspot.com/build/8814019688332427985
[09:20:21.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:2:0) after 1m18s
[09:21:39.620] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:2:0)
[09:21:39.638] Build status: SCHEDULED
[09:21:39.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:3:0) after 1m38s
[09:23:16.192] Received PubSub notification, asking Buildbucket for the build status
[09:23:16.214] Build status: STARTED
[09:23:17.792] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:3:0)
[09:23:17.826] Build status: STARTED
[09:23:17.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:5:0) after 2m37s
[09:25:54.866] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:5:0)
[09:25:54.883] Build status: STARTED
[09:25:54.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:6:0) after 2m37s
[09:28:31.957] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:6:0)
[09:28:31.975] Build status: STARTED
[09:28:31.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:7:0) after 8m7s
[09:36:39.055] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:7:0)
[09:36:39.092] Build status: STARTED
[09:36:39.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:8:0) after 6m59s
[09:43:38.172] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:8:0)
[09:43:38.201] Build status: STARTED
[09:43:38.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:9:0) after 1m41s
[09:45:19.218] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:9:0)
[09:45:19.296] Build status: STARTED
[09:45:19.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:10:0) after 5m54s
[09:51:13.313] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:10:0)
[09:51:13.329] Build status: STARTED
[09:51:13.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:11:0) after 5m36s
[09:56:49.363] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:11:0)
[09:56:49.382] Build status: STARTED
[09:56:49.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:12:0) after 7m30s
[10:04:19.500] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:12:0)
[10:04:19.533] Build status: STARTED
[10:04:19.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:13:0) after 8m31s
[10:12:50.551] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:13:0)
[10:12:50.654] Build status: STARTED
[10:12:50.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:14:0) after 1m13s
[10:14:03.671] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:14:0)
[10:14:03.692] Build status: STARTED
[10:14:03.692] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:15:0) after 7m38s
[10:21:41.709] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:15:0)
[10:21:41.731] Build status: STARTED
[10:21:41.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:16:0) after 4m22s
[10:26:03.957] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:16:0)
[10:26:03.995] Build status: STARTED
[10:26:03.995] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:17:0) after 3m36s
[10:29:40.110] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:17:0)
[10:29:40.136] Build status: STARTED
[10:29:40.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:18:0) after 7m54s
[10:37:34.159] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:18:0)
[10:37:34.188] Build status: STARTED
[10:37:34.188] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:19:0) after 1m49s
[10:39:23.335] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:19:0)
[10:39:23.362] Build status: STARTED
[10:39:23.362] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:20:0) after 8m54s
[10:48:17.378] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:20:0)
[10:48:17.421] Build status: STARTED
[10:48:17.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:21:0) after 7m32s
[10:55:49.626] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:21:0)
[10:55:49.648] Build status: STARTED
[10:55:49.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:22:0) after 6m22s
[11:02:11.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:22:0)
[11:02:11.751] Build status: STARTED
[11:02:11.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:23:0) after 1m50s
[11:04:01.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:23:0)
[11:04:01.800] Build status: STARTED
[11:04:01.800] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:24:0) after 5m12s
[11:09:13.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:24:0)
[11:09:13.923] Build status: STARTED
[11:09:13.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:25:0) after 9m45s
[11:18:58.967] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:25:0)
[11:18:58.988] Build status: STARTED
[11:18:58.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:26:0) after 6m46s
[11:25:44.991] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:26:0)
[11:25:45.023] Build status: STARTED
[11:25:45.023] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:27:0) after 3m32s
[11:29:17.087] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:27:0)
[11:29:17.109] Build status: STARTED
[11:29:17.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:28:0) after 1m45s
[11:31:02.125] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:28:0)
[11:31:02.158] Build status: STARTED
[11:31:02.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:29:0) after 2m22s
[11:33:24.280] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:29:0)
[11:33:24.300] Build status: STARTED
[11:33:24.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8979449751181349152:30:0) after 8m5s
[11:37:42.371] Received PubSub notification, asking Buildbucket for the build status
[11:37:42.400] Build:
{
"id": "8814019688332427985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dll)"
},
"number": 10339,
"createdBy": "project:chromium",
"createTime": "2022-05-16T09:20:21.146071651Z",
"startTime": "2022-05-16T09:23:15.407626Z",
"endTime": "2022-05-16T11:37:41.708216535Z",
"updateTime": "2022-05-16T11:37:41.708216535Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "03ef6fbea97e14e4b4a09daa574518152cd95056",
"ref": "refs/heads/main"
}
}
}
[11:37:42.400] Invocation finished in 2h17m23.355083735s with status FAILED