[19:25:58.505] New invocation is queued and will start shortly
[19:25:59.919] Starting the invocation (attempt 1)
[19:25:59.950] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fb2b3f9f7664f04bd705ee0e1e9d5e6edfaa7ae7"
[19:25:59.950] Popped gitiles commit info from properties and tags
[19:25:59.950] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:25:59.950] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:25:59.950] Buildbucket request:
{
"requestId": "8971439113459321328",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fb2b3f9f7664f04bd705ee0e1e9d5e6edfaa7ae7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8971439113459321328"
},
{
"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"
}
}
[19:26:00.457] Scheduled build:
{
"id": "8806009051227837185",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 43637,
"createdBy": "project:chromium",
"createTime": "2022-08-12T19:26:00.011055055Z",
"updateTime": "2022-08-12T19:26:00.011055055Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fb2b3f9f7664f04bd705ee0e1e9d5e6edfaa7ae7",
"ref": "refs/heads/main"
}
}
}
[19:26:00.457] Task URL: https://cr-buildbucket.appspot.com/build/8806009051227837185
[19:26:00.457] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:2:0) after 7m2s
[19:33:02.639] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:2:0)
[19:33:02.660] Build status: SCHEDULED
[19:33:02.660] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:3:0) after 4m41s
[19:36:59.965] Received PubSub notification, asking Buildbucket for the build status
[19:36:59.992] Build status: STARTED
[19:37:44.014] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:3:0)
[19:37:44.044] Build status: STARTED
[19:37:44.044] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:5:0) after 2m59s
[19:40:43.045] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:5:0)
[19:40:43.063] Build status: STARTED
[19:40:43.064] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:6:0) after 1m23s
[19:42:06.313] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:6:0)
[19:42:06.337] Build status: STARTED
[19:42:06.337] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:7:0) after 9m19s
[19:51:25.569] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:7:0)
[19:51:25.595] Build status: STARTED
[19:51:25.595] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:8:0) after 6m53s
[19:58:18.616] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:8:0)
[19:58:18.637] Build status: STARTED
[19:58:18.637] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:9:0) after 7m58s
[20:06:17.694] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:9:0)
[20:06:17.745] Build status: STARTED
[20:06:17.745] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:10:0) after 5m27s
[20:11:44.731] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:10:0)
[20:11:44.753] Build status: STARTED
[20:11:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:11:0) after 2m37s
[20:14:21.874] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:11:0)
[20:14:21.893] Build status: STARTED
[20:14:21.894] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:12:0) after 9m25s
[20:23:46.910] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:12:0)
[20:23:46.942] Build status: STARTED
[20:23:46.942] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:13:0) after 6m32s
[20:30:19.002] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:13:0)
[20:30:19.028] Build status: STARTED
[20:30:19.028] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:14:0) after 5m25s
[20:35:44.557] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:14:0)
[20:35:44.584] Build status: STARTED
[20:35:44.584] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:15:0) after 8m9s
[20:43:53.603] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:15:0)
[20:43:53.623] Build status: STARTED
[20:43:53.623] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:16:0) after 5m53s
[20:49:46.691] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:16:0)
[20:49:46.722] Build status: STARTED
[20:49:46.722] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:17:0) after 2m38s
[20:52:24.739] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:17:0)
[20:52:24.773] Build status: STARTED
[20:52:24.774] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:18:0) after 5m50s
[20:58:15.004] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:18:0)
[20:58:15.023] Build status: STARTED
[20:58:15.023] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:19:0) after 9m50s
[21:08:05.059] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:19:0)
[21:08:05.081] Build status: STARTED
[21:08:05.081] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:20:0) after 2m35s
[21:10:40.099] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:20:0)
[21:10:40.123] Build status: STARTED
[21:10:40.123] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:21:0) after 5m33s
[21:16:13.142] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:21:0)
[21:16:13.167] Build status: STARTED
[21:16:13.167] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:22:0) after 8m2s
[21:24:15.404] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:22:0)
[21:24:15.424] Build status: STARTED
[21:24:15.424] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:23:0) after 2m40s
[21:26:55.701] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:23:0)
[21:26:55.724] Build status: STARTED
[21:26:55.724] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:24:0) after 7m30s
[21:34:25.742] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:24:0)
[21:34:25.779] Build status: STARTED
[21:34:25.779] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:25:0) after 3m59s
[21:38:25.318] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:25:0)
[21:38:25.384] Build status: STARTED
[21:38:25.384] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:26:0) after 1m21s
[21:39:46.445] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:26:0)
[21:39:46.465] Build status: STARTED
[21:39:46.465] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:27:0) after 6m56s
[21:46:42.585] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:27:0)
[21:46:42.638] Build status: STARTED
[21:46:42.638] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:28:0) after 6m57s
[21:53:39.919] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:28:0)
[21:53:39.938] Build status: STARTED
[21:53:39.938] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:29:0) after 7m10s
[22:00:49.952] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:29:0)
[22:00:49.973] Build status: STARTED
[22:00:49.973] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:30:0) after 5m4s
[22:05:54.255] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:30:0)
[22:05:54.390] Build status: STARTED
[22:05:54.390] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:31:0) after 6m33s
[22:12:27.524] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:31:0)
[22:12:27.561] Build status: STARTED
[22:12:27.561] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:32:0) after 6m43s
[22:19:10.657] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:32:0)
[22:19:10.676] Build status: STARTED
[22:19:10.676] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:33:0) after 4m9s
[22:23:19.772] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:33:0)
[22:23:19.794] Build status: STARTED
[22:23:19.794] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:34:0) after 7m9s
[22:30:29.039] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:34:0)
[22:30:29.058] Build status: STARTED
[22:30:29.058] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:35:0) after 9m3s
[22:39:32.111] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:35:0)
[22:39:32.132] Build status: STARTED
[22:39:32.132] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:36:0) after 6m53s
[22:46:25.616] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:36:0)
[22:46:25.639] Build status: STARTED
[22:46:25.639] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:37:0) after 7m43s
[22:54:08.755] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:37:0)
[22:54:08.788] Build status: STARTED
[22:54:08.788] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:38:0) after 9m11s
[23:03:19.764] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:38:0)
[23:03:19.792] Build status: STARTED
[23:03:19.792] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:39:0) after 7m20s
[23:10:40.264] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:39:0)
[23:10:40.289] Build status: STARTED
[23:10:40.289] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:40:0) after 7m42s
[23:18:22.421] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:40:0)
[23:18:22.445] Build status: STARTED
[23:18:22.445] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:41:0) after 1m39s
[23:20:01.567] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:41:0)
[23:20:01.589] Build status: STARTED
[23:20:01.589] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:42:0) after 2m9s
[23:22:10.724] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:42:0)
[23:22:10.756] Build status: STARTED
[23:22:10.756] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:43:0) after 4m47s
[23:26:57.936] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:43:0)
[23:26:57.982] Build status: STARTED
[23:26:57.982] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:44:0) after 5m8s
[23:32:06.142] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:44:0)
[23:32:06.179] Build status: STARTED
[23:32:06.179] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:45:0) after 3m23s
[23:35:29.268] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:45:0)
[23:35:29.358] Build status: STARTED
[23:35:29.358] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8971439113459321328:46:0) after 6m6s
[23:37:42.406] Received PubSub notification, asking Buildbucket for the build status
[23:37:42.433] Build:
{
"id": "8806009051227837185",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 43637,
"createdBy": "project:chromium",
"createTime": "2022-08-12T19:26:00.011055055Z",
"startTime": "2022-08-12T19:36:59.305647Z",
"endTime": "2022-08-12T23:37:42.102575696Z",
"updateTime": "2022-08-12T23:37:42.102575696Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fb2b3f9f7664f04bd705ee0e1e9d5e6edfaa7ae7",
"ref": "refs/heads/main"
}
}
}
[23:37:42.433] Invocation finished in 4h11m43.93399114s with status ABORTED