[05:31:57.243] New invocation is queued and will start shortly
[05:31:58.310] Starting the invocation (attempt 1)
[05:31:58.337] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddba83089948e9fa5e9f4b39735e6e9ced315b5e"
[05:31:58.337] Popped gitiles commit info from properties and tags
[05:31:58.337] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:31:58.339] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:31:58.339] Buildbucket request:
{
"requestId": "8958264428440146336",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ddba83089948e9fa5e9f4b39735e6e9ced315b5e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8958264428440146336"
},
{
"key": "scheduler_job_id",
"value": "chromium/Libfuzzer Upload Windows ASan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:31:59.230] Scheduled build:
{
"id": "8792834366560962177",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 45691,
"createdBy": "project:chromium",
"createTime": "2023-01-05T05:31:58.404681904Z",
"updateTime": "2023-01-05T05:31:58.404681904Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ddba83089948e9fa5e9f4b39735e6e9ced315b5e",
"ref": "refs/heads/main"
}
}
}
[05:31:59.230] Task URL: https://cr-buildbucket.appspot.com/build/8792834366560962177
[05:31:59.231] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:2:0) after 9m7s
[05:41:06.345] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:2:0)
[05:41:06.370] Build status: SCHEDULED
[05:41:06.370] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:3:0) after 2m26s
[05:43:32.459] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:3:0)
[05:43:32.484] Build status: SCHEDULED
[05:43:32.484] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:4:0) after 8m1s
[05:43:34.854] Received PubSub notification, asking Buildbucket for the build status
[05:43:34.876] Build status: STARTED
[05:51:33.873] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:4:0)
[05:51:34.024] Build status: STARTED
[05:51:34.024] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:6:0) after 6m15s
[05:57:49.157] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:6:0)
[05:57:49.178] Build status: STARTED
[05:57:49.178] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:7:0) after 9m49s
[06:07:38.205] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:7:0)
[06:07:38.228] Build status: STARTED
[06:07:38.228] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:8:0) after 6m55s
[06:14:33.243] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:8:0)
[06:14:33.266] Build status: STARTED
[06:14:33.267] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:9:0) after 4m13s
[06:18:46.283] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:9:0)
[06:18:46.307] Build status: STARTED
[06:18:46.307] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:10:0) after 6m54s
[06:25:40.393] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:10:0)
[06:25:40.415] Build status: STARTED
[06:25:40.415] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:11:0) after 5m57s
[06:31:37.388] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:11:0)
[06:31:37.409] Build status: STARTED
[06:31:37.409] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:12:0) after 3m0s
[06:34:37.429] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:12:0)
[06:34:37.452] Build status: STARTED
[06:34:37.452] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:13:0) after 5m30s
[06:40:07.442] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:13:0)
[06:40:07.469] Build status: STARTED
[06:40:07.469] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:14:0) after 1m50s
[06:41:57.557] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:14:0)
[06:41:57.576] Build status: STARTED
[06:41:57.576] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:15:0) after 6m9s
[06:48:06.692] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:15:0)
[06:48:06.716] Build status: STARTED
[06:48:06.716] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:16:0) after 1m51s
[06:49:57.788] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:16:0)
[06:49:57.810] Build status: STARTED
[06:49:57.810] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:17:0) after 9m19s
[06:59:16.826] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:17:0)
[06:59:16.847] Build status: STARTED
[06:59:16.847] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:18:0) after 2m44s
[07:02:00.861] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:18:0)
[07:02:00.887] Build status: STARTED
[07:02:00.887] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:19:0) after 7m20s
[07:09:20.903] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:19:0)
[07:09:20.928] Build status: STARTED
[07:09:20.928] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:20:0) after 3m30s
[07:12:50.941] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:20:0)
[07:12:50.963] Build status: STARTED
[07:12:50.963] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:21:0) after 5m45s
[07:18:35.984] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:21:0)
[07:18:36.007] Build status: STARTED
[07:18:36.007] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:22:0) after 6m10s
[07:24:46.170] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:22:0)
[07:24:46.195] Build status: STARTED
[07:24:46.195] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:23:0) after 4m33s
[07:29:19.228] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:23:0)
[07:29:19.276] Build status: STARTED
[07:29:19.276] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:24:0) after 6m11s
[07:35:30.335] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:24:0)
[07:35:30.361] Build status: STARTED
[07:35:30.361] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:25:0) after 2m14s
[07:37:44.432] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:25:0)
[07:37:44.491] Build status: STARTED
[07:37:44.491] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:26:0) after 4m59s
[07:42:43.608] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:26:0)
[07:42:43.688] Build status: STARTED
[07:42:43.694] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:27:0) after 4m54s
[07:47:37.711] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:27:0)
[07:47:37.736] Build status: STARTED
[07:47:37.736] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:28:0) after 2m10s
[07:49:47.751] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:28:0)
[07:49:47.776] Build status: STARTED
[07:49:47.776] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:29:0) after 1m25s
[07:51:12.915] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:29:0)
[07:51:12.958] Build status: STARTED
[07:51:12.958] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:30:0) after 9m42s
[08:00:55.024] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:30:0)
[08:00:55.046] Build status: STARTED
[08:00:55.046] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:31:0) after 1m53s
[08:02:48.066] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:31:0)
[08:02:48.092] Build status: STARTED
[08:02:48.092] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:32:0) after 2m49s
[08:05:37.205] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:32:0)
[08:05:37.232] Build status: STARTED
[08:05:37.232] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:33:0) after 6m46s
[08:12:23.799] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:33:0)
[08:12:23.841] Build status: STARTED
[08:12:23.841] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:34:0) after 6m19s
[08:18:43.058] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:34:0)
[08:18:43.085] Build status: STARTED
[08:18:43.085] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:35:0) after 3m5s
[08:21:48.105] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:35:0)
[08:21:48.138] Build status: STARTED
[08:21:48.138] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:36:0) after 9m35s
[08:31:23.214] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:36:0)
[08:31:23.240] Build status: STARTED
[08:31:23.240] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:37:0) after 1m58s
[08:33:21.463] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:37:0)
[08:33:21.486] Build status: STARTED
[08:33:21.486] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:38:0) after 6m40s
[08:40:01.513] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:38:0)
[08:40:01.543] Build status: STARTED
[08:40:01.543] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:39:0) after 8m53s
[08:48:54.701] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:39:0)
[08:48:54.735] Build status: STARTED
[08:48:54.735] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:40:0) after 5m49s
[08:54:43.853] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:40:0)
[08:54:43.877] Build status: STARTED
[08:54:43.877] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:41:0) after 5m49s
[09:00:33.118] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:41:0)
[09:00:33.149] Build status: STARTED
[09:00:33.149] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:42:0) after 9m21s
[09:09:54.334] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:42:0)
[09:09:54.364] Build status: STARTED
[09:09:54.364] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:43:0) after 4m9s
[09:14:03.799] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:43:0)
[09:14:03.844] Build status: STARTED
[09:14:03.845] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:44:0) after 4m42s
[09:18:46.006] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:44:0)
[09:18:46.056] Build status: STARTED
[09:18:46.056] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:45:0) after 3m45s
[09:22:31.828] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:45:0)
[09:22:31.848] Build status: STARTED
[09:22:31.848] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:46:0) after 6m55s
[09:29:26.887] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:46:0)
[09:29:26.909] Build status: STARTED
[09:29:26.909] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:47:0) after 1m20s
[09:30:46.938] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:47:0)
[09:30:47.034] Build status: STARTED
[09:30:47.034] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:48:0) after 2m29s
[09:33:16.107] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:48:0)
[09:33:16.131] Build status: STARTED
[09:33:16.131] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:49:0) after 4m16s
[09:37:32.218] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:49:0)
[09:37:32.292] Build status: STARTED
[09:37:32.292] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:50:0) after 3m29s
[09:41:01.310] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:50:0)
[09:41:01.328] Build status: STARTED
[09:41:01.328] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:51:0) after 8m50s
[09:49:51.346] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:51:0)
[09:49:51.368] Build status: STARTED
[09:49:51.368] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:52:0) after 9m42s
[09:59:33.389] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:52:0)
[09:59:33.410] Build status: STARTED
[09:59:33.410] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:53:0) after 7m39s
[10:07:12.451] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:53:0)
[10:07:12.493] Build status: STARTED
[10:07:12.493] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:54:0) after 3m14s
[10:10:26.737] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:54:0)
[10:10:26.778] Build status: STARTED
[10:10:26.778] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:55:0) after 9m48s
[10:20:14.856] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:55:0)
[10:20:14.878] Build status: STARTED
[10:20:14.879] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:56:0) after 4m43s
[10:24:57.977] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:56:0)
[10:24:57.998] Build status: STARTED
[10:24:57.998] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:57:0) after 2m15s
[10:27:13.076] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:57:0)
[10:27:13.119] Build status: STARTED
[10:27:13.119] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:58:0) after 4m46s
[10:31:59.135] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:58:0)
[10:31:59.156] Build status: STARTED
[10:31:59.156] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8958264428440146336:59:0) after 8m41s
[10:38:20.204] Received PubSub notification, asking Buildbucket for the build status
[10:38:20.291] Build:
{
"id": "8792834366560962177",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 45691,
"createdBy": "project:chromium",
"createTime": "2023-01-05T05:31:58.404681904Z",
"startTime": "2023-01-05T05:43:34.092009Z",
"endTime": "2023-01-05T10:38:19.702360442Z",
"updateTime": "2023-01-05T10:38:19.702360442Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ddba83089948e9fa5e9f4b39735e6e9ced315b5e",
"ref": "refs/heads/main"
}
}
}
[10:38:20.291] Invocation finished in 5h6m23.062488435s with status SUCCEEDED