[17:48:54.217] New invocation is queued and will start shortly
[17:48:55.293] Starting the invocation (attempt 1)
[17:48:55.319] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9ce5f01c9a6bf7ad9a6d8092ba7f51edf928457"
[17:48:55.319] Popped gitiles commit info from properties and tags
[17:48:55.319] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:48:55.319] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:48:55.319] Buildbucket request:
{
"requestId": "8921798083107203776",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d9ce5f01c9a6bf7ad9a6d8092ba7f51edf928457",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921798083107203776"
},
{
"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"
}
}
[17:48:55.918] Scheduled build:
{
"id": "8756368021205762417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 52082,
"createdBy": "project:chromium",
"createTime": "2024-02-11T17:48:55.383736190Z",
"updateTime": "2024-02-11T17:48:55.383736190Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d9ce5f01c9a6bf7ad9a6d8092ba7f51edf928457",
"ref": "refs/heads/main"
}
}
}
[17:48:55.918] Task URL: https://cr-buildbucket.appspot.com/build/8756368021205762417
[17:48:55.918] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:2:0) after 9m22s
[17:48:56.843] Received PubSub notification, asking Buildbucket for the build status
[17:48:56.864] Build status: SCHEDULED
[17:49:03.268] Received PubSub notification, asking Buildbucket for the build status
[17:49:03.286] Build status: STARTED
[17:58:17.936] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:2:0)
[17:58:17.961] Build status: STARTED
[17:58:17.961] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:5:0) after 9m8s
[18:07:25.989] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:5:0)
[18:07:26.129] Build status: STARTED
[18:07:26.129] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:6:0) after 9m23s
[18:16:49.144] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:6:0)
[18:16:49.164] Build status: STARTED
[18:16:49.164] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:7:0) after 1m50s
[18:18:39.187] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:7:0)
[18:18:39.230] Build status: STARTED
[18:18:39.230] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:8:0) after 9m1s
[18:27:40.251] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:8:0)
[18:27:40.288] Build status: STARTED
[18:27:40.288] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:9:0) after 1m21s
[18:29:01.325] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:9:0)
[18:29:01.357] Build status: STARTED
[18:29:01.357] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:10:0) after 9m37s
[18:38:38.412] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:10:0)
[18:38:38.440] Build status: STARTED
[18:38:38.440] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:11:0) after 3m13s
[18:41:51.456] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:11:0)
[18:41:51.485] Build status: STARTED
[18:41:51.486] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:12:0) after 6m55s
[18:48:46.503] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:12:0)
[18:48:46.525] Build status: STARTED
[18:48:46.525] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:13:0) after 8m17s
[18:57:03.581] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:13:0)
[18:57:03.847] Build status: STARTED
[18:57:03.847] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:14:0) after 8m20s
[19:05:23.863] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:14:0)
[19:05:23.908] Build status: STARTED
[19:05:23.908] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:15:0) after 9m43s
[19:15:07.040] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:15:0)
[19:15:07.138] Build status: STARTED
[19:15:07.138] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:16:0) after 9m17s
[19:24:24.288] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:16:0)
[19:24:24.368] Build status: STARTED
[19:24:24.368] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:17:0) after 6m11s
[19:30:35.486] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:17:0)
[19:30:35.513] Build status: STARTED
[19:30:35.513] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:18:0) after 9m58s
[19:40:33.721] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:18:0)
[19:40:33.743] Build status: STARTED
[19:40:33.743] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:19:0) after 5m50s
[19:46:23.766] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:19:0)
[19:46:23.789] Build status: STARTED
[19:46:23.789] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:20:0) after 4m40s
[19:51:03.909] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:20:0)
[19:51:03.940] Build status: STARTED
[19:51:03.940] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:21:0) after 9m48s
[20:00:51.968] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:21:0)
[20:00:52.023] Build status: STARTED
[20:00:52.023] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:22:0) after 8m18s
[20:09:10.056] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:22:0)
[20:09:10.101] Build status: STARTED
[20:09:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:23:0) after 4m21s
[20:13:31.116] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:23:0)
[20:13:31.162] Build status: STARTED
[20:13:31.162] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:24:0) after 5m45s
[20:19:16.188] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:24:0)
[20:19:16.209] Build status: STARTED
[20:19:16.209] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:25:0) after 7m12s
[20:26:28.371] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:25:0)
[20:26:28.400] Build status: STARTED
[20:26:28.400] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:26:0) after 4m51s
[20:31:19.417] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:26:0)
[20:31:19.441] Build status: STARTED
[20:31:19.441] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:27:0) after 9m44s
[20:41:03.459] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:27:0)
[20:41:03.489] Build status: STARTED
[20:41:03.489] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:28:0) after 4m27s
[20:45:30.512] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:28:0)
[20:45:30.569] Build status: STARTED
[20:45:30.569] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:29:0) after 1m13s
[20:46:43.586] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:29:0)
[20:46:43.616] Build status: STARTED
[20:46:43.616] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:30:0) after 7m52s
[20:54:35.882] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:30:0)
[20:54:35.932] Build status: STARTED
[20:54:35.932] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:31:0) after 2m7s
[20:56:42.979] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:31:0)
[20:56:42.999] Build status: STARTED
[20:56:42.999] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:32:0) after 9m56s
[21:06:39.025] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:32:0)
[21:06:39.053] Build status: STARTED
[21:06:39.053] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:33:0) after 1m41s
[21:08:20.069] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:33:0)
[21:08:20.088] Build status: STARTED
[21:08:20.088] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:34:0) after 1m1s
[21:09:21.211] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:34:0)
[21:09:21.234] Build status: STARTED
[21:09:21.234] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:35:0) after 2m0s
[21:11:21.288] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:35:0)
[21:11:21.314] Build status: STARTED
[21:11:21.314] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:36:0) after 4m53s
[21:16:14.340] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:36:0)
[21:16:14.359] Build status: STARTED
[21:16:14.359] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:37:0) after 1m19s
[21:17:33.794] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:37:0)
[21:17:33.867] Build status: STARTED
[21:17:33.868] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:38:0) after 2m55s
[21:20:28.889] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:38:0)
[21:20:28.922] Build status: STARTED
[21:20:28.922] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:39:0) after 4m46s
[21:25:15.127] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:39:0)
[21:25:15.149] Build status: STARTED
[21:25:15.149] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:40:0) after 7m0s
[21:32:15.263] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:40:0)
[21:32:15.283] Build status: STARTED
[21:32:15.283] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:41:0) after 1m51s
[21:34:06.363] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:41:0)
[21:34:06.427] Build status: STARTED
[21:34:06.428] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:42:0) after 3m38s
[21:37:44.456] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:42:0)
[21:37:44.493] Build status: STARTED
[21:37:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:43:0) after 2m26s
[21:40:10.511] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:43:0)
[21:40:10.579] Build status: STARTED
[21:40:10.579] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:44:0) after 6m2s
[21:46:12.765] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:44:0)
[21:46:12.815] Build status: STARTED
[21:46:12.815] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:45:0) after 2m53s
[21:49:06.146] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:45:0)
[21:49:06.221] Build status: STARTED
[21:49:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:46:0) after 3m7s
[21:52:13.333] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:46:0)
[21:52:13.365] Build status: STARTED
[21:52:13.365] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:47:0) after 5m48s
[21:58:01.517] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:47:0)
[21:58:01.541] Build status: STARTED
[21:58:01.541] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:48:0) after 9m52s
[22:07:53.559] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:48:0)
[22:07:53.589] Build status: STARTED
[22:07:53.589] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:49:0) after 4m46s
[22:12:39.637] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:49:0)
[22:12:39.657] Build status: STARTED
[22:12:39.657] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:50:0) after 5m1s
[22:17:40.781] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:50:0)
[22:17:40.801] Build status: STARTED
[22:17:40.801] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:51:0) after 1m23s
[22:19:03.866] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:51:0)
[22:19:03.887] Build status: STARTED
[22:19:03.887] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:52:0) after 1m19s
[22:20:22.919] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:52:0)
[22:20:22.943] Build status: STARTED
[22:20:22.943] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:53:0) after 8m30s
[22:28:53.307] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:53:0)
[22:28:53.328] Build status: STARTED
[22:28:53.328] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:54:0) after 1m0s
[22:29:53.515] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:54:0)
[22:29:53.712] Build status: STARTED
[22:29:53.712] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:55:0) after 8m33s
[22:38:26.985] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:55:0)
[22:38:27.012] Build status: STARTED
[22:38:27.012] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:56:0) after 8m4s
[22:46:31.077] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:56:0)
[22:46:31.099] Build status: STARTED
[22:46:31.099] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:57:0) after 6m31s
[22:53:02.085] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:57:0)
[22:53:02.123] Build status: STARTED
[22:53:02.123] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8921798083107203776:58:0) after 3m10s
[22:55:40.507] Received PubSub notification, asking Buildbucket for the build status
[22:55:40.529] Build:
{
"id": "8756368021205762417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 52082,
"createdBy": "project:chromium",
"createTime": "2024-02-11T17:48:55.383736190Z",
"startTime": "2024-02-11T17:49:02.451089Z",
"endTime": "2024-02-11T22:55:39.860458Z",
"updateTime": "2024-02-11T22:55:39.860458Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d9ce5f01c9a6bf7ad9a6d8092ba7f51edf928457",
"ref": "refs/heads/main"
}
}
}
[22:55:40.529] Invocation finished in 5h6m46.343842439s with status SUCCEEDED