[23:42:32.275] New invocation is queued and will start shortly
[23:42:33.297] Starting the invocation (attempt 1)
[23:42:33.325] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aa5fda07031241c6806d6eef9fd58ac4ba73dfcf"
[23:42:33.325] Popped gitiles commit info from properties and tags
[23:42:33.325] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:42:33.325] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:42:33.325] Buildbucket request:
{
"requestId": "8937539706492664944",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aa5fda07031241c6806d6eef9fd58ac4ba73dfcf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937539706492664944"
},
{
"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"
}
}
[23:42:33.896] Scheduled build:
{
"id": "8772109644670280673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 49738,
"createdBy": "project:chromium",
"createTime": "2023-08-21T23:42:33.386471166Z",
"updateTime": "2023-08-21T23:42:33.386471166Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aa5fda07031241c6806d6eef9fd58ac4ba73dfcf",
"ref": "refs/heads/main"
}
}
}
[23:42:33.896] Task URL: https://cr-buildbucket.appspot.com/build/8772109644670280673
[23:42:33.897] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:2:0) after 6m55s
[23:42:34.827] Received PubSub notification, asking Buildbucket for the build status
[23:42:34.858] Build status: SCHEDULED
[23:49:30.271] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:2:0)
[23:49:30.294] Build status: SCHEDULED
[23:49:30.294] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:4:0) after 9m51s
[23:59:21.565] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:4:0)
[23:59:21.580] Build status: SCHEDULED
[23:59:21.580] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:5:0) after 2m11s
[00:01:33.130] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:5:0)
[00:01:33.149] Build status: SCHEDULED
[00:01:33.149] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:6:0) after 4m9s
[00:03:13.603] Received PubSub notification, asking Buildbucket for the build status
[00:03:13.640] Build status: STARTED
[00:05:42.172] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:6:0)
[00:05:42.198] Build status: STARTED
[00:05:42.198] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:8:0) after 2m1s
[00:07:43.282] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:8:0)
[00:07:43.301] Build status: STARTED
[00:07:43.301] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:9:0) after 2m28s
[00:10:11.677] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:9:0)
[00:10:11.734] Build status: STARTED
[00:10:11.734] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:10:0) after 4m59s
[00:15:10.737] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:10:0)
[00:15:10.756] Build status: STARTED
[00:15:10.756] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:11:0) after 6m32s
[00:21:43.031] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:11:0)
[00:21:43.061] Build status: STARTED
[00:21:43.062] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:12:0) after 7m19s
[00:29:02.280] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:12:0)
[00:29:02.307] Build status: STARTED
[00:29:02.307] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:13:0) after 7m6s
[00:36:08.338] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:13:0)
[00:36:08.381] Build status: STARTED
[00:36:08.381] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:14:0) after 3m42s
[00:39:51.358] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:14:0)
[00:39:51.376] Build status: STARTED
[00:39:51.377] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:15:0) after 7m53s
[00:47:44.599] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:15:0)
[00:47:44.646] Build status: STARTED
[00:47:44.646] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:16:0) after 4m8s
[00:51:53.166] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:16:0)
[00:51:53.217] Build status: STARTED
[00:51:53.217] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:17:0) after 9m49s
[01:01:42.375] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:17:0)
[01:01:42.396] Build status: STARTED
[01:01:42.396] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:18:0) after 2m2s
[01:03:44.448] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:18:0)
[01:03:44.466] Build status: STARTED
[01:03:44.466] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:19:0) after 8m35s
[01:12:19.686] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:19:0)
[01:12:19.707] Build status: STARTED
[01:12:19.708] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:20:0) after 8m16s
[01:20:35.730] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:20:0)
[01:20:35.870] Build status: STARTED
[01:20:35.870] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:21:0) after 4m33s
[01:25:08.890] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:21:0)
[01:25:08.905] Build status: STARTED
[01:25:08.905] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:22:0) after 9m13s
[01:34:22.316] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:22:0)
[01:34:22.330] Build status: STARTED
[01:34:22.330] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:23:0) after 3m29s
[01:37:51.757] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:23:0)
[01:37:51.781] Build status: STARTED
[01:37:51.781] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:24:0) after 1m44s
[01:39:36.032] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:24:0)
[01:39:36.061] Build status: STARTED
[01:39:36.061] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:25:0) after 8m28s
[01:48:04.252] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:25:0)
[01:48:04.270] Build status: STARTED
[01:48:04.270] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:26:0) after 5m50s
[01:53:54.355] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:26:0)
[01:53:54.371] Build status: STARTED
[01:53:54.371] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:27:0) after 4m23s
[01:58:17.524] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:27:0)
[01:58:17.558] Build status: STARTED
[01:58:17.558] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:28:0) after 1m16s
[01:59:33.591] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:28:0)
[01:59:33.616] Build status: STARTED
[01:59:33.616] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:29:0) after 6m25s
[02:05:58.941] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:29:0)
[02:05:58.995] Build status: STARTED
[02:05:58.995] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:30:0) after 8m16s
[02:14:15.219] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:30:0)
[02:14:15.309] Build status: STARTED
[02:14:15.309] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:31:0) after 5m10s
[02:19:25.402] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:31:0)
[02:19:25.423] Build status: STARTED
[02:19:25.423] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:32:0) after 3m6s
[02:22:31.512] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:32:0)
[02:22:31.530] Build status: STARTED
[02:22:31.530] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:33:0) after 8m53s
[02:31:24.752] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:33:0)
[02:31:24.769] Build status: STARTED
[02:31:24.769] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:34:0) after 5m15s
[02:36:39.888] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:34:0)
[02:36:39.912] Build status: STARTED
[02:36:39.912] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:35:0) after 5m0s
[02:41:39.931] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:35:0)
[02:41:39.949] Build status: STARTED
[02:41:39.949] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:36:0) after 9m57s
[02:51:36.980] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:36:0)
[02:51:37.001] Build status: STARTED
[02:51:37.001] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:37:0) after 5m29s
[02:57:06.120] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:37:0)
[02:57:06.137] Build status: STARTED
[02:57:06.137] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:38:0) after 2m39s
[02:59:45.292] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:38:0)
[02:59:45.313] Build status: STARTED
[02:59:45.313] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:39:0) after 5m33s
[03:05:18.467] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:39:0)
[03:05:18.488] Build status: STARTED
[03:05:18.488] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:40:0) after 8m47s
[03:14:05.605] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:40:0)
[03:14:05.634] Build status: STARTED
[03:14:05.634] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:41:0) after 6m11s
[03:20:16.764] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:41:0)
[03:20:16.783] Build status: STARTED
[03:20:16.783] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:42:0) after 4m58s
[03:25:14.801] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:42:0)
[03:25:14.822] Build status: STARTED
[03:25:14.822] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:43:0) after 6m26s
[03:31:40.963] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:43:0)
[03:31:41.017] Build status: STARTED
[03:31:41.017] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:44:0) after 2m28s
[03:34:09.034] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:44:0)
[03:34:09.058] Build status: STARTED
[03:34:09.058] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:45:0) after 4m54s
[03:39:03.254] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:45:0)
[03:39:03.269] Build status: STARTED
[03:39:03.269] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:46:0) after 7m21s
[03:46:24.276] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:46:0)
[03:46:24.446] Build status: STARTED
[03:46:24.446] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:47:0) after 7m19s
[03:53:43.692] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:47:0)
[03:53:43.707] Build status: STARTED
[03:53:43.707] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:48:0) after 1m38s
[03:55:21.727] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:48:0)
[03:55:21.747] Build status: STARTED
[03:55:21.747] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:49:0) after 2m4s
[03:57:25.764] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:49:0)
[03:57:25.782] Build status: STARTED
[03:57:25.782] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:50:0) after 1m46s
[03:59:11.942] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:50:0)
[03:59:11.960] Build status: STARTED
[03:59:11.960] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:51:0) after 6m15s
[04:05:27.135] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:51:0)
[04:05:27.221] Build status: STARTED
[04:05:27.221] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:52:0) after 8m33s
[04:14:00.323] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:52:0)
[04:14:00.350] Build status: STARTED
[04:14:00.350] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:53:0) after 7m46s
[04:21:46.331] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:53:0)
[04:21:46.350] Build status: STARTED
[04:21:46.350] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:54:0) after 7m12s
[04:28:58.472] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:54:0)
[04:28:58.570] Build status: STARTED
[04:28:58.570] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:55:0) after 1m40s
[04:30:38.589] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:55:0)
[04:30:38.707] Build status: STARTED
[04:30:38.707] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:56:0) after 6m17s
[04:36:55.776] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:56:0)
[04:36:55.805] Build status: STARTED
[04:36:55.805] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:57:0) after 6m0s
[04:42:55.819] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:57:0)
[04:42:55.842] Build status: STARTED
[04:42:55.842] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:58:0) after 8m1s
[04:50:56.862] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:58:0)
[04:50:56.882] Build status: STARTED
[04:50:56.882] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:59:0) after 1m40s
[04:52:37.076] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:59:0)
[04:52:37.094] Build status: STARTED
[04:52:37.094] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:60:0) after 3m1s
[04:55:38.133] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:60:0)
[04:55:38.151] Build status: STARTED
[04:55:38.151] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8937539706492664944:61:0) after 1m14s
[04:55:53.685] Received PubSub notification, asking Buildbucket for the build status
[04:55:53.711] Build:
{
"id": "8772109644670280673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 49738,
"createdBy": "project:chromium",
"createTime": "2023-08-21T23:42:33.386471166Z",
"startTime": "2023-08-22T00:03:12.759018Z",
"endTime": "2023-08-22T04:55:53.473002004Z",
"updateTime": "2023-08-22T04:55:53.473002004Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aa5fda07031241c6806d6eef9fd58ac4ba73dfcf",
"ref": "refs/heads/main"
}
}
}
[04:55:53.711] Invocation finished in 5h13m21.446931303s with status SUCCEEDED