[11:09:14.267] New invocation is queued and will start shortly
[11:09:15.319] Starting the invocation (attempt 1)
[11:09:15.350] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf75d4abe7953de34856c58eb722b5ddbb5e9df8"
[11:09:15.350] Popped gitiles commit info from properties and tags
[11:09:15.350] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:09:15.351] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:09:15.352] Buildbucket request:
{
"requestId": "8900351746846551728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bf75d4abe7953de34856c58eb722b5ddbb5e9df8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900351746846551728"
},
{
"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"
}
}
[11:09:15.965] Scheduled build:
{
"id": "8734921685008787105",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 10141,
"createdBy": "project:chromium",
"createTime": "2024-10-05T11:09:15.395987249Z",
"updateTime": "2024-10-05T11:09:15.395987249Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bf75d4abe7953de34856c58eb722b5ddbb5e9df8",
"ref": "refs/heads/main"
}
}
}
[11:09:15.965] Task URL: https://cr-buildbucket.appspot.com/build/8734921685008787105
[11:09:15.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:2:0) after 8m53s
[11:10:04.605] Received PubSub notification, asking Buildbucket for the build status
[11:10:04.637] Build status: SCHEDULED
[11:18:09.040] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:2:0)
[11:18:09.071] Build status: SCHEDULED
[11:18:09.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:4:0) after 7m19s
[11:25:28.086] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:4:0)
[11:25:28.107] Build status: SCHEDULED
[11:25:28.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:5:0) after 6m50s
[11:32:18.135] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:5:0)
[11:32:18.159] Build status: SCHEDULED
[11:32:18.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:6:0) after 8m7s
[11:40:25.190] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:6:0)
[11:40:25.207] Build status: SCHEDULED
[11:40:25.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:7:0) after 5m30s
[11:45:55.225] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:7:0)
[11:45:55.257] Build status: SCHEDULED
[11:45:55.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:8:0) after 8m34s
[11:51:25.724] Received PubSub notification, asking Buildbucket for the build status
[11:51:25.740] Build status: STARTED
[11:54:29.276] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:8:0)
[11:54:29.292] Build status: STARTED
[11:54:29.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:10:0) after 6m18s
[12:00:47.412] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:10:0)
[12:00:47.432] Build status: STARTED
[12:00:47.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:11:0) after 8m53s
[12:09:40.560] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:11:0)
[12:09:40.579] Build status: STARTED
[12:09:40.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:12:0) after 4m16s
[12:13:56.549] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:12:0)
[12:13:56.573] Build status: STARTED
[12:13:56.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:13:0) after 5m10s
[12:19:06.905] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:13:0)
[12:19:06.972] Build status: STARTED
[12:19:06.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:14:0) after 5m23s
[12:24:30.193] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:14:0)
[12:24:30.215] Build status: STARTED
[12:24:30.215] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:15:0) after 2m32s
[12:27:02.274] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:15:0)
[12:27:02.292] Build status: STARTED
[12:27:02.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:16:0) after 5m42s
[12:32:44.311] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:16:0)
[12:32:44.331] Build status: STARTED
[12:32:44.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:17:0) after 5m45s
[12:38:29.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:17:0)
[12:38:29.385] Build status: STARTED
[12:38:29.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:18:0) after 5m12s
[12:43:41.407] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:18:0)
[12:43:41.429] Build status: STARTED
[12:43:41.429] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:19:0) after 6m24s
[12:50:05.474] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:19:0)
[12:50:05.494] Build status: STARTED
[12:50:05.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:20:0) after 8m28s
[12:58:33.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:20:0)
[12:58:33.732] Build status: STARTED
[12:58:33.732] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:21:0) after 2m19s
[13:00:52.876] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:21:0)
[13:00:52.897] Build status: STARTED
[13:00:52.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:22:0) after 9m1s
[13:09:53.915] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:22:0)
[13:09:53.936] Build status: STARTED
[13:09:53.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:23:0) after 1m39s
[13:11:32.951] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:23:0)
[13:11:32.971] Build status: STARTED
[13:11:32.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:24:0) after 8m49s
[13:20:22.155] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:24:0)
[13:20:22.171] Build status: STARTED
[13:20:22.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:25:0) after 4m29s
[13:24:51.483] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:25:0)
[13:24:51.509] Build status: STARTED
[13:24:51.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:26:0) after 7m30s
[13:32:21.597] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:26:0)
[13:32:21.652] Build status: STARTED
[13:32:21.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:27:0) after 1m31s
[13:33:52.667] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:27:0)
[13:33:52.716] Build status: STARTED
[13:33:52.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:28:0) after 7m49s
[13:41:42.131] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:28:0)
[13:41:42.148] Build status: STARTED
[13:41:42.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:29:0) after 8m43s
[13:50:25.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:29:0)
[13:50:25.252] Build status: STARTED
[13:50:25.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:30:0) after 2m31s
[13:52:56.328] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:30:0)
[13:52:56.345] Build status: STARTED
[13:52:56.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:31:0) after 4m1s
[13:56:57.554] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:31:0)
[13:56:57.573] Build status: STARTED
[13:56:57.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:32:0) after 4m22s
[14:01:19.591] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:32:0)
[14:01:19.611] Build status: STARTED
[14:01:19.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:33:0) after 8m13s
[14:09:32.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:33:0)
[14:09:32.684] Build status: STARTED
[14:09:32.684] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8900351746846551728:34:0) after 7m44s
[14:16:57.636] Received PubSub notification, asking Buildbucket for the build status
[14:16:57.653] Build:
{
"id": "8734921685008787105",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 10141,
"createdBy": "project:chromium",
"createTime": "2024-10-05T11:09:15.395987249Z",
"startTime": "2024-10-05T11:51:25.565345547Z",
"endTime": "2024-10-05T14:16:57.244696340Z",
"updateTime": "2024-10-05T14:16:57.244696340Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bf75d4abe7953de34856c58eb722b5ddbb5e9df8",
"ref": "refs/heads/main"
}
}
}
[14:16:57.653] Invocation finished in 3h7m43.394730685s with status FAILED