[22:46:06.375] New invocation is queued and will start shortly
[22:46:07.453] Starting the invocation (attempt 1)
[22:46:07.478] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/04f3064d1681109a37b87ae0c1cb822be26076fc"
[22:46:07.478] Popped gitiles commit info from properties and tags
[22:46:07.478] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:46:07.479] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:46:07.479] Buildbucket request:
{
"requestId": "8894781488722796768",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04f3064d1681109a37b87ae0c1cb822be26076fc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894781488722796768"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin64PGO"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:46:08.065] Scheduled build:
{
"id": "8729351426845019793",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 10944,
"createdBy": "project:chromium",
"createTime": "2024-12-05T22:46:07.541860096Z",
"updateTime": "2024-12-05T22:46:07.541860096Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04f3064d1681109a37b87ae0c1cb822be26076fc",
"ref": "refs/heads/main"
}
}
}
[22:46:08.066] Task URL: https://cr-buildbucket.appspot.com/build/8729351426845019793
[22:46:08.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:2:0) after 2m29s
[22:46:09.154] Received PubSub notification, asking Buildbucket for the build status
[22:46:09.232] Build status: SCHEDULED
[22:48:37.091] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:2:0)
[22:48:37.168] Build status: SCHEDULED
[22:48:37.168] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:4:0) after 6m56s
[22:55:33.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:4:0)
[22:55:33.298] Build status: SCHEDULED
[22:55:33.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:5:0) after 5m24s
[23:00:57.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:5:0)
[23:00:57.374] Build status: SCHEDULED
[23:00:57.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:6:0) after 3m39s
[23:04:36.505] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:6:0)
[23:04:36.531] Build status: SCHEDULED
[23:04:36.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:7:0) after 9m18s
[23:13:54.601] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:7:0)
[23:13:54.681] Build status: SCHEDULED
[23:13:54.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:8:0) after 6m40s
[23:20:34.761] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:8:0)
[23:20:34.786] Build status: SCHEDULED
[23:20:34.786] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:9:0) after 3m16s
[23:23:50.808] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:9:0)
[23:23:50.840] Build status: SCHEDULED
[23:23:50.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:10:0) after 9m5s
[23:32:55.978] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:10:0)
[23:32:55.997] Build status: SCHEDULED
[23:32:55.997] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:11:0) after 6m12s
[23:39:08.136] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:11:0)
[23:39:08.158] Build status: SCHEDULED
[23:39:08.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:12:0) after 3m6s
[23:42:14.175] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:12:0)
[23:42:14.189] Build status: SCHEDULED
[23:42:14.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:13:0) after 3m20s
[23:45:34.256] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:13:0)
[23:45:34.276] Build status: SCHEDULED
[23:45:34.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:14:0) after 7m50s
[23:53:24.289] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:14:0)
[23:53:24.399] Build status: SCHEDULED
[23:53:24.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:15:0) after 7m50s
[00:01:14.487] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:15:0)
[00:01:14.504] Build status: SCHEDULED
[00:01:14.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:16:0) after 4m9s
[00:05:23.551] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:16:0)
[00:05:23.640] Build status: SCHEDULED
[00:05:23.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:17:0) after 7m27s
[00:12:50.726] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:17:0)
[00:12:50.778] Build status: SCHEDULED
[00:12:50.778] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:18:0) after 7m12s
[00:12:59.575] Received PubSub notification, asking Buildbucket for the build status
[00:12:59.600] Build status: STARTED
[00:20:02.799] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:18:0)
[00:20:02.819] Build status: STARTED
[00:20:02.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:20:0) after 3m37s
[00:23:39.816] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:20:0)
[00:23:39.840] Build status: STARTED
[00:23:39.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:21:0) after 5m25s
[00:29:04.860] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:21:0)
[00:29:04.886] Build status: STARTED
[00:29:04.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:22:0) after 5m53s
[00:34:57.910] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:22:0)
[00:34:57.932] Build status: STARTED
[00:34:57.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:23:0) after 4m4s
[00:39:01.959] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:23:0)
[00:39:01.990] Build status: STARTED
[00:39:01.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:24:0) after 9m24s
[00:48:26.054] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:24:0)
[00:48:26.077] Build status: STARTED
[00:48:26.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:25:0) after 2m49s
[00:51:15.104] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:25:0)
[00:51:15.122] Build status: STARTED
[00:51:15.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:26:0) after 1m41s
[00:52:56.109] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:26:0)
[00:52:56.131] Build status: STARTED
[00:52:56.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:27:0) after 7m59s
[01:00:55.160] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:27:0)
[01:00:55.175] Build status: STARTED
[01:00:55.175] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:28:0) after 6m3s
[01:06:58.198] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:28:0)
[01:06:58.221] Build status: STARTED
[01:06:58.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:29:0) after 4m8s
[01:11:06.243] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:29:0)
[01:11:06.271] Build status: STARTED
[01:11:06.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:30:0) after 5m21s
[01:16:27.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:30:0)
[01:16:27.349] Build status: STARTED
[01:16:27.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:31:0) after 1m50s
[01:18:17.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:31:0)
[01:18:17.428] Build status: STARTED
[01:18:17.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:32:0) after 2m1s
[01:20:18.706] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:32:0)
[01:20:18.725] Build status: STARTED
[01:20:18.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:33:0) after 7m23s
[01:27:41.797] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:33:0)
[01:27:41.820] Build status: STARTED
[01:27:41.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:34:0) after 8m48s
[01:36:29.840] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:34:0)
[01:36:29.862] Build status: STARTED
[01:36:29.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:35:0) after 5m24s
[01:41:53.880] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:35:0)
[01:41:53.900] Build status: STARTED
[01:41:53.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:36:0) after 7m38s
[01:49:31.969] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:36:0)
[01:49:31.986] Build status: STARTED
[01:49:31.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:37:0) after 2m41s
[01:52:13.072] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:37:0)
[01:52:13.094] Build status: STARTED
[01:52:13.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:38:0) after 2m14s
[01:54:27.176] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:38:0)
[01:54:27.192] Build status: STARTED
[01:54:27.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:39:0) after 3m9s
[01:57:36.212] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:39:0)
[01:57:36.231] Build status: STARTED
[01:57:36.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:40:0) after 5m24s
[02:03:00.255] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:40:0)
[02:03:00.273] Build status: STARTED
[02:03:00.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:41:0) after 1m7s
[02:04:07.306] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:41:0)
[02:04:07.325] Build status: STARTED
[02:04:07.325] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:42:0) after 1m41s
[02:05:48.362] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:42:0)
[02:05:48.385] Build status: STARTED
[02:05:48.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8894781488722796768:43:0) after 9m0s
[02:06:09.882] Received PubSub notification, asking Buildbucket for the build status
[02:06:09.904] Build:
{
"id": "8729351426845019793",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 10944,
"createdBy": "project:chromium",
"createTime": "2024-12-05T22:46:07.541860096Z",
"startTime": "2024-12-06T00:12:58.941474613Z",
"endTime": "2024-12-06T02:06:09.598021409Z",
"updateTime": "2024-12-06T02:06:09.598021409Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04f3064d1681109a37b87ae0c1cb822be26076fc",
"ref": "refs/heads/main"
}
}
}
[02:06:09.904] Invocation finished in 3h20m3.536878062s with status FAILED