[16:05:34.816] New invocation is queued and will start shortly
[16:05:35.884] Starting the invocation (attempt 1)
[16:05:35.922] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a59378e42b8fcba155fe66b2b1b54d25338ac0af"
[16:05:35.922] Popped gitiles commit info from properties and tags
[16:05:35.922] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:05:35.922] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:05:35.923] Buildbucket request:
{
"requestId": "8938746216341698304",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a59378e42b8fcba155fe66b2b1b54d25338ac0af",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938746216341698304"
},
{
"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"
}
}
[16:05:36.668] Scheduled build:
{
"id": "8773316154438337857",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 49541,
"createdBy": "project:chromium",
"createTime": "2023-08-08T16:05:36.008315679Z",
"updateTime": "2023-08-08T16:05:36.008315679Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a59378e42b8fcba155fe66b2b1b54d25338ac0af",
"ref": "refs/heads/main"
}
}
}
[16:05:36.668] Task URL: https://cr-buildbucket.appspot.com/build/8773316154438337857
[16:05:36.668] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:2:0) after 2m17s
[16:05:37.597] Received PubSub notification, asking Buildbucket for the build status
[16:05:37.624] Build status: SCHEDULED
[16:07:53.694] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:2:0)
[16:07:53.714] Build status: SCHEDULED
[16:07:53.714] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:4:0) after 8m28s
[16:16:21.894] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:4:0)
[16:16:21.962] Build status: SCHEDULED
[16:16:21.962] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:5:0) after 3m43s
[16:20:05.067] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:5:0)
[16:20:05.092] Build status: SCHEDULED
[16:20:05.092] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:6:0) after 1m8s
[16:20:16.517] Received PubSub notification, asking Buildbucket for the build status
[16:20:16.550] Build status: STARTED
[16:21:13.140] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:6:0)
[16:21:13.182] Build status: STARTED
[16:21:13.182] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:8:0) after 3m27s
[16:24:40.220] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:8:0)
[16:24:40.255] Build status: STARTED
[16:24:40.255] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:9:0) after 6m50s
[16:31:30.274] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:9:0)
[16:31:30.299] Build status: STARTED
[16:31:30.299] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:10:0) after 8m14s
[16:39:44.320] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:10:0)
[16:39:44.337] Build status: STARTED
[16:39:44.337] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:11:0) after 6m1s
[16:45:45.506] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:11:0)
[16:45:45.526] Build status: STARTED
[16:45:45.526] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:12:0) after 7m2s
[16:52:47.547] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:12:0)
[16:52:47.618] Build status: STARTED
[16:52:47.618] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:13:0) after 3m6s
[16:55:53.688] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:13:0)
[16:55:53.713] Build status: STARTED
[16:55:53.713] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:14:0) after 2m10s
[16:58:03.736] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:14:0)
[16:58:03.759] Build status: STARTED
[16:58:03.759] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:15:0) after 5m1s
[17:03:04.794] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:15:0)
[17:03:04.931] Build status: STARTED
[17:03:04.931] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:16:0) after 4m8s
[17:07:12.975] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:16:0)
[17:07:12.998] Build status: STARTED
[17:07:12.998] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:17:0) after 7m9s
[17:14:22.259] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:17:0)
[17:14:22.324] Build status: STARTED
[17:14:22.324] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:18:0) after 8m42s
[17:23:05.036] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:18:0)
[17:23:05.091] Build status: STARTED
[17:23:05.091] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:19:0) after 1m53s
[17:24:58.152] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:19:0)
[17:24:58.189] Build status: STARTED
[17:24:58.189] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:20:0) after 3m36s
[17:28:34.235] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:20:0)
[17:28:34.254] Build status: STARTED
[17:28:34.255] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:21:0) after 4m48s
[17:33:22.262] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:21:0)
[17:33:22.292] Build status: STARTED
[17:33:22.292] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:22:0) after 7m38s
[17:41:00.306] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:22:0)
[17:41:00.325] Build status: STARTED
[17:41:00.325] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:23:0) after 6m17s
[17:47:17.348] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:23:0)
[17:47:17.379] Build status: STARTED
[17:47:17.379] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:24:0) after 5m9s
[17:52:27.734] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:24:0)
[17:52:27.763] Build status: STARTED
[17:52:27.763] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:25:0) after 7m13s
[17:59:40.786] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:25:0)
[17:59:40.808] Build status: STARTED
[17:59:40.808] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:26:0) after 8m24s
[18:08:04.877] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:26:0)
[18:08:04.929] Build status: STARTED
[18:08:04.929] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:27:0) after 2m30s
[18:10:34.998] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:27:0)
[18:10:35.019] Build status: STARTED
[18:10:35.019] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:28:0) after 3m58s
[18:14:33.092] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:28:0)
[18:14:33.116] Build status: STARTED
[18:14:33.116] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:29:0) after 1m18s
[18:15:51.204] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:29:0)
[18:15:51.236] Build status: STARTED
[18:15:51.236] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:30:0) after 5m47s
[18:21:38.531] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:30:0)
[18:21:38.549] Build status: STARTED
[18:21:38.549] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:31:0) after 8m12s
[18:29:50.671] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:31:0)
[18:29:50.698] Build status: STARTED
[18:29:50.698] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:32:0) after 3m15s
[18:33:05.716] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:32:0)
[18:33:05.772] Build status: STARTED
[18:33:05.772] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:33:0) after 1m4s
[18:34:09.793] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:33:0)
[18:34:09.821] Build status: STARTED
[18:34:09.821] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:34:0) after 7m40s
[18:41:49.839] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:34:0)
[18:41:49.858] Build status: STARTED
[18:41:49.858] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:35:0) after 7m15s
[18:49:04.874] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:35:0)
[18:49:04.909] Build status: STARTED
[18:49:04.909] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:36:0) after 2m8s
[18:51:12.972] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:36:0)
[18:51:12.994] Build status: STARTED
[18:51:12.994] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:37:0) after 9m6s
[19:00:19.131] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:37:0)
[19:00:19.162] Build status: STARTED
[19:00:19.162] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:38:0) after 6m39s
[19:06:58.291] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:38:0)
[19:06:58.324] Build status: STARTED
[19:06:58.324] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:39:0) after 4m39s
[19:11:37.381] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:39:0)
[19:11:37.399] Build status: STARTED
[19:11:37.399] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:40:0) after 6m44s
[19:18:22.052] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:40:0)
[19:18:22.089] Build status: STARTED
[19:18:22.089] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:41:0) after 5m41s
[19:24:03.490] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:41:0)
[19:24:03.511] Build status: STARTED
[19:24:03.511] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:42:0) after 6m7s
[19:30:10.534] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:42:0)
[19:30:10.565] Build status: STARTED
[19:30:10.565] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:43:0) after 7m32s
[19:37:42.673] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:43:0)
[19:37:42.694] Build status: STARTED
[19:37:42.695] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:44:0) after 7m58s
[19:45:40.737] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:44:0)
[19:45:40.835] Build status: STARTED
[19:45:40.835] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:45:0) after 4m15s
[19:49:56.047] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:45:0)
[19:49:56.064] Build status: STARTED
[19:49:56.064] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:46:0) after 8m18s
[19:58:14.451] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:46:0)
[19:58:14.477] Build status: STARTED
[19:58:14.477] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:47:0) after 3m34s
[20:01:48.644] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:47:0)
[20:01:48.714] Build status: STARTED
[20:01:48.714] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:48:0) after 4m7s
[20:05:57.047] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:48:0)
[20:05:57.065] Build status: STARTED
[20:05:57.065] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:49:0) after 4m40s
[20:10:37.089] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:49:0)
[20:10:37.114] Build status: STARTED
[20:10:37.114] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:50:0) after 5m37s
[20:16:14.234] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:50:0)
[20:16:14.298] Build status: STARTED
[20:16:14.298] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:51:0) after 2m45s
[20:18:59.352] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:51:0)
[20:18:59.367] Build status: STARTED
[20:18:59.367] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:52:0) after 4m1s
[20:23:00.787] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:52:0)
[20:23:00.810] Build status: STARTED
[20:23:00.810] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:53:0) after 2m37s
[20:25:38.029] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:53:0)
[20:25:38.047] Build status: STARTED
[20:25:38.047] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:54:0) after 1m38s
[20:27:16.221] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:54:0)
[20:27:16.256] Build status: STARTED
[20:27:16.256] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:55:0) after 3m25s
[20:30:41.404] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:55:0)
[20:30:41.428] Build status: STARTED
[20:30:41.428] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:56:0) after 2m9s
[20:32:50.467] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:56:0)
[20:32:50.512] Build status: STARTED
[20:32:50.512] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:57:0) after 5m7s
[20:37:57.536] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:57:0)
[20:37:57.553] Build status: STARTED
[20:37:57.553] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:58:0) after 9m3s
[20:47:00.572] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:58:0)
[20:47:00.601] Build status: STARTED
[20:47:00.601] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:59:0) after 6m8s
[20:53:08.624] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:59:0)
[20:53:08.646] Build status: STARTED
[20:53:08.646] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8938746216341698304:60:0) after 7m58s
[20:57:42.158] Received PubSub notification, asking Buildbucket for the build status
[20:57:42.178] Build:
{
"id": "8773316154438337857",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 49541,
"createdBy": "project:chromium",
"createTime": "2023-08-08T16:05:36.008315679Z",
"startTime": "2023-08-08T16:20:15.500237Z",
"endTime": "2023-08-08T20:57:41.792960166Z",
"updateTime": "2023-08-08T20:57:41.792960166Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a59378e42b8fcba155fe66b2b1b54d25338ac0af",
"ref": "refs/heads/main"
}
}
}
[20:57:42.178] Invocation finished in 4h52m7.369249578s with status SUCCEEDED