[22:40:52.798] New invocation is queued and will start shortly
[22:40:53.909] Starting the invocation (attempt 1)
[22:40:53.942] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2b9916925bf96d864cd5d64d0e9e96c40342763"
[22:40:53.943] Popped gitiles commit info from properties and tags
[22:40:53.943] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:53.943] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:53.943] Buildbucket request:
{
"requestId": "8897227935627827232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e2b9916925bf96d864cd5d64d0e9e96c40342763",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897227935627827232"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:40:54.439] Scheduled build:
{
"id": "8731797873701677761",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17480,
"createdBy": "project:chromium",
"createTime": "2024-11-08T22:40:54.008548099Z",
"updateTime": "2024-11-08T22:40:54.008548099Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e2b9916925bf96d864cd5d64d0e9e96c40342763",
"ref": "refs/heads/main"
}
}
}
[22:40:54.439] Task URL: https://cr-buildbucket.appspot.com/build/8731797873701677761
[22:40:54.439] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:2:0) after 7m28s
[22:40:55.446] Received PubSub notification, asking Buildbucket for the build status
[22:40:55.473] Build status: SCHEDULED
[22:48:22.464] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:2:0)
[22:48:22.551] Build status: SCHEDULED
[22:48:22.551] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:4:0) after 1m51s
[22:50:13.749] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:4:0)
[22:50:13.817] Build status: SCHEDULED
[22:50:13.817] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:5:0) after 4m16s
[22:54:29.794] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:5:0)
[22:54:29.811] Build status: SCHEDULED
[22:54:29.811] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:6:0) after 5m31s
[22:54:32.978] Received PubSub notification, asking Buildbucket for the build status
[22:54:33.009] Build status: STARTED
[23:00:01.400] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:6:0)
[23:00:01.433] Build status: STARTED
[23:00:01.433] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:8:0) after 9m42s
[23:09:43.600] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:8:0)
[23:09:43.647] Build status: STARTED
[23:09:43.647] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:9:0) after 9m7s
[23:18:50.673] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:9:0)
[23:18:50.713] Build status: STARTED
[23:18:50.713] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:10:0) after 9m34s
[23:28:24.866] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:10:0)
[23:28:24.887] Build status: STARTED
[23:28:24.887] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:11:0) after 5m42s
[23:34:06.895] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:11:0)
[23:34:06.915] Build status: STARTED
[23:34:06.915] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:12:0) after 4m22s
[23:38:29.048] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:12:0)
[23:38:29.065] Build status: STARTED
[23:38:29.065] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:13:0) after 5m41s
[23:44:10.442] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:13:0)
[23:44:10.465] Build status: STARTED
[23:44:10.465] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:14:0) after 4m9s
[23:48:19.587] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:14:0)
[23:48:19.608] Build status: STARTED
[23:48:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:15:0) after 7m41s
[23:56:00.946] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:15:0)
[23:56:00.963] Build status: STARTED
[23:56:00.963] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:16:0) after 4m25s
[00:00:26.438] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:16:0)
[00:00:26.456] Build status: STARTED
[00:00:26.456] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:17:0) after 9m30s
[00:09:56.438] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:17:0)
[00:09:56.457] Build status: STARTED
[00:09:56.457] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:18:0) after 2m10s
[00:12:06.520] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:18:0)
[00:12:06.541] Build status: STARTED
[00:12:06.541] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:19:0) after 8m17s
[00:20:23.708] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:19:0)
[00:20:23.728] Build status: STARTED
[00:20:23.728] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:20:0) after 1m2s
[00:21:25.785] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:20:0)
[00:21:25.808] Build status: STARTED
[00:21:25.809] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:21:0) after 7m49s
[00:29:14.982] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:21:0)
[00:29:14.999] Build status: STARTED
[00:29:14.999] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:22:0) after 1m28s
[00:30:43.456] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:22:0)
[00:30:43.474] Build status: STARTED
[00:30:43.474] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:23:0) after 2m53s
[00:33:36.496] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:23:0)
[00:33:36.512] Build status: STARTED
[00:33:36.512] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:24:0) after 3m52s
[00:37:28.583] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:24:0)
[00:37:28.599] Build status: STARTED
[00:37:28.599] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:25:0) after 3m18s
[00:40:46.634] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:25:0)
[00:40:46.657] Build status: STARTED
[00:40:46.657] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:26:0) after 8m16s
[00:49:02.755] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:26:0)
[00:49:02.770] Build status: STARTED
[00:49:02.770] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:27:0) after 5m20s
[00:54:22.900] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:27:0)
[00:54:22.916] Build status: STARTED
[00:54:22.916] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:28:0) after 5m28s
[00:59:51.083] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:28:0)
[00:59:51.184] Build status: STARTED
[00:59:51.184] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:29:0) after 6m38s
[01:06:29.298] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:29:0)
[01:06:29.314] Build status: STARTED
[01:06:29.314] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:30:0) after 8m2s
[01:14:31.382] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:30:0)
[01:14:31.465] Build status: STARTED
[01:14:31.465] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:31:0) after 4m51s
[01:19:22.490] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:31:0)
[01:19:22.579] Build status: STARTED
[01:19:22.579] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:32:0) after 5m9s
[01:24:31.656] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:32:0)
[01:24:31.672] Build status: STARTED
[01:24:31.672] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:33:0) after 3m56s
[01:28:27.741] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:33:0)
[01:28:27.763] Build status: STARTED
[01:28:27.763] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:34:0) after 9m56s
[01:38:23.798] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:34:0)
[01:38:23.811] Build status: STARTED
[01:38:23.811] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:35:0) after 8m16s
[01:46:39.839] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:35:0)
[01:46:39.857] Build status: STARTED
[01:46:39.857] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:36:0) after 3m8s
[01:49:47.882] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:36:0)
[01:49:47.897] Build status: STARTED
[01:49:47.897] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:37:0) after 9m23s
[01:59:11.209] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:37:0)
[01:59:11.224] Build status: STARTED
[01:59:11.224] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:38:0) after 6m21s
[02:05:32.803] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:38:0)
[02:05:32.821] Build status: STARTED
[02:05:32.821] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8897227935627827232:39:0) after 7m31s
[02:09:30.849] Received PubSub notification, asking Buildbucket for the build status
[02:09:30.870] Build:
{
"id": "8731797873701677761",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17480,
"createdBy": "project:chromium",
"createTime": "2024-11-08T22:40:54.008548099Z",
"startTime": "2024-11-08T22:54:32.756724103Z",
"endTime": "2024-11-09T02:09:30.539763297Z",
"updateTime": "2024-11-09T02:09:30.539763297Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e2b9916925bf96d864cd5d64d0e9e96c40342763",
"ref": "refs/heads/main"
}
}
}
[02:09:30.870] Invocation finished in 3h28m38.082941794s with status FAILED