[09:24:00.930] New invocation is queued and will start shortly
[09:24:03.806] Starting the invocation (attempt 1)
[09:24:03.834] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2945ad609c4742fff4b8d744875df0caf24350a1"
[09:24:03.834] Popped gitiles commit info from properties and tags
[09:24:03.834] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:24:03.834] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:24:03.834] Buildbucket request:
{
"requestId": "8924457159919695744",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2945ad609c4742fff4b8d744875df0caf24350a1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8924457159919695744"
},
{
"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"
}
}
[09:24:04.397] Scheduled build:
{
"id": "8759027096156127153",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 51710,
"createdBy": "project:chromium",
"createTime": "2024-01-13T09:24:03.897132930Z",
"updateTime": "2024-01-13T09:24:03.897132930Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2945ad609c4742fff4b8d744875df0caf24350a1",
"ref": "refs/heads/main"
}
}
}
[09:24:04.397] Task URL: https://cr-buildbucket.appspot.com/build/8759027096156127153
[09:24:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:2:0) after 8m36s
[09:24:05.304] Received PubSub notification, asking Buildbucket for the build status
[09:24:05.339] Build status: SCHEDULED
[09:25:39.623] Received PubSub notification, asking Buildbucket for the build status
[09:25:39.645] Build status: STARTED
[09:32:40.509] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:2:0)
[09:32:40.527] Build status: STARTED
[09:32:40.527] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:5:0) after 3m43s
[09:36:23.650] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:5:0)
[09:36:23.677] Build status: STARTED
[09:36:23.677] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:6:0) after 6m46s
[09:43:09.806] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:6:0)
[09:43:09.875] Build status: STARTED
[09:43:09.875] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:7:0) after 2m14s
[09:45:23.894] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:7:0)
[09:45:23.914] Build status: STARTED
[09:45:23.914] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:8:0) after 6m21s
[09:51:44.932] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:8:0)
[09:51:44.955] Build status: STARTED
[09:51:44.955] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:9:0) after 5m27s
[09:57:11.974] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:9:0)
[09:57:11.988] Build status: STARTED
[09:57:11.988] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:10:0) after 1m18s
[09:58:30.005] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:10:0)
[09:58:30.026] Build status: STARTED
[09:58:30.026] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:11:0) after 3m1s
[10:01:31.056] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:11:0)
[10:01:31.082] Build status: STARTED
[10:01:31.083] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:12:0) after 1m7s
[10:02:38.253] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:12:0)
[10:02:38.271] Build status: STARTED
[10:02:38.271] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:13:0) after 7m7s
[10:09:45.826] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:13:0)
[10:09:45.855] Build status: STARTED
[10:09:45.855] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:14:0) after 8m10s
[10:17:56.574] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:14:0)
[10:17:56.591] Build status: STARTED
[10:17:56.591] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:15:0) after 6m3s
[10:23:59.606] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:15:0)
[10:23:59.782] Build status: STARTED
[10:23:59.782] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:16:0) after 6m51s
[10:30:50.803] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:16:0)
[10:30:50.829] Build status: STARTED
[10:30:50.829] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:17:0) after 3m57s
[10:34:48.019] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:17:0)
[10:34:48.062] Build status: STARTED
[10:34:48.062] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:18:0) after 6m55s
[10:41:43.104] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:18:0)
[10:41:43.125] Build status: STARTED
[10:41:43.125] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:19:0) after 2m54s
[10:44:37.435] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:19:0)
[10:44:37.452] Build status: STARTED
[10:44:37.452] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:20:0) after 2m1s
[10:46:38.680] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:20:0)
[10:46:38.735] Build status: STARTED
[10:46:38.735] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:21:0) after 5m52s
[10:52:31.299] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:21:0)
[10:52:31.415] Build status: STARTED
[10:52:31.415] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:22:0) after 3m49s
[10:56:20.433] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:22:0)
[10:56:20.451] Build status: STARTED
[10:56:20.451] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:23:0) after 9m1s
[11:05:21.729] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:23:0)
[11:05:21.751] Build status: STARTED
[11:05:21.751] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:24:0) after 1m56s
[11:07:17.787] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:24:0)
[11:07:17.808] Build status: STARTED
[11:07:17.808] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:25:0) after 2m10s
[11:09:27.791] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:25:0)
[11:09:27.809] Build status: STARTED
[11:09:27.809] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:26:0) after 2m52s
[11:12:20.171] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:26:0)
[11:12:20.193] Build status: STARTED
[11:12:20.193] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:27:0) after 4m59s
[11:17:19.474] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:27:0)
[11:17:19.491] Build status: STARTED
[11:17:19.491] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:28:0) after 9m30s
[11:26:49.508] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:28:0)
[11:26:49.547] Build status: STARTED
[11:26:49.547] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:29:0) after 8m18s
[11:35:07.569] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:29:0)
[11:35:07.608] Build status: STARTED
[11:35:07.608] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:30:0) after 4m47s
[11:39:54.624] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:30:0)
[11:39:54.641] Build status: STARTED
[11:39:54.641] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:31:0) after 9m8s
[11:49:02.657] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:31:0)
[11:49:02.673] Build status: STARTED
[11:49:02.674] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:32:0) after 3m29s
[11:52:31.799] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:32:0)
[11:52:31.828] Build status: STARTED
[11:52:31.828] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:33:0) after 5m32s
[11:58:03.911] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:33:0)
[11:58:03.928] Build status: STARTED
[11:58:03.928] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:34:0) after 1m33s
[11:59:36.950] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:34:0)
[11:59:36.967] Build status: STARTED
[11:59:36.967] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:35:0) after 4m14s
[12:03:51.017] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:35:0)
[12:03:51.044] Build status: STARTED
[12:03:51.044] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:36:0) after 7m36s
[12:11:27.193] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:36:0)
[12:11:27.219] Build status: STARTED
[12:11:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:37:0) after 9m36s
[12:21:03.238] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:37:0)
[12:21:03.259] Build status: STARTED
[12:21:03.259] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:38:0) after 8m6s
[12:29:09.243] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:38:0)
[12:29:09.272] Build status: STARTED
[12:29:09.272] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:39:0) after 7m21s
[12:36:30.342] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:39:0)
[12:36:30.362] Build status: STARTED
[12:36:30.362] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:40:0) after 5m46s
[12:42:16.505] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:40:0)
[12:42:16.521] Build status: STARTED
[12:42:16.521] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:41:0) after 2m0s
[12:44:16.494] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:41:0)
[12:44:16.511] Build status: STARTED
[12:44:16.511] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:42:0) after 9m11s
[12:53:27.529] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:42:0)
[12:53:27.546] Build status: STARTED
[12:53:27.546] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:43:0) after 9m40s
[13:03:07.566] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:43:0)
[13:03:07.583] Build status: STARTED
[13:03:07.583] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:44:0) after 6m50s
[13:09:57.698] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:44:0)
[13:09:57.717] Build status: STARTED
[13:09:57.717] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:45:0) after 1m43s
[13:11:40.765] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:45:0)
[13:11:40.783] Build status: STARTED
[13:11:40.783] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:46:0) after 6m40s
[13:18:20.850] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:46:0)
[13:18:20.893] Build status: STARTED
[13:18:20.893] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:47:0) after 4m23s
[13:22:43.910] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:47:0)
[13:22:43.928] Build status: STARTED
[13:22:43.928] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:48:0) after 1m44s
[13:24:27.933] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:48:0)
[13:24:27.991] Build status: STARTED
[13:24:27.991] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:49:0) after 8m15s
[13:32:43.021] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:49:0)
[13:32:43.037] Build status: STARTED
[13:32:43.037] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:50:0) after 6m31s
[13:39:14.056] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:50:0)
[13:39:14.078] Build status: STARTED
[13:39:14.078] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:51:0) after 2m52s
[13:42:06.633] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:51:0)
[13:42:06.655] Build status: STARTED
[13:42:06.655] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:52:0) after 4m11s
[13:46:17.680] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:52:0)
[13:46:17.699] Build status: STARTED
[13:46:17.700] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:53:0) after 5m23s
[13:51:40.727] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:53:0)
[13:51:40.749] Build status: STARTED
[13:51:40.749] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:54:0) after 9m52s
[14:01:32.773] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:54:0)
[14:01:32.802] Build status: STARTED
[14:01:32.802] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:55:0) after 4m2s
[14:05:35.752] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:55:0)
[14:05:35.821] Build status: STARTED
[14:05:35.821] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:56:0) after 4m51s
[14:10:26.849] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:56:0)
[14:10:26.872] Build status: STARTED
[14:10:26.872] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:57:0) after 2m46s
[14:13:12.941] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:57:0)
[14:13:12.972] Build status: STARTED
[14:13:12.973] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:58:0) after 1m48s
[14:15:01.045] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:58:0)
[14:15:01.076] Build status: STARTED
[14:15:01.076] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8924457159919695744:59:0) after 6m10s
[14:19:25.521] Received PubSub notification, asking Buildbucket for the build status
[14:19:25.546] Build:
{
"id": "8759027096156127153",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 51710,
"createdBy": "project:chromium",
"createTime": "2024-01-13T09:24:03.897132930Z",
"startTime": "2024-01-13T09:25:38.342560Z",
"endTime": "2024-01-13T14:19:24.737097Z",
"updateTime": "2024-01-13T14:19:24.737097Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2945ad609c4742fff4b8d744875df0caf24350a1",
"ref": "refs/heads/main"
}
}
}
[14:19:25.546] Invocation finished in 4h55m24.622052793s with status SUCCEEDED