[18:13:01.764] New invocation is queued and will start shortly
[18:13:02.909] Starting the invocation (attempt 1)
[18:13:02.949] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/18c6dc1f9968cd70c922ae8369ce2bad9504a186"
[18:13:02.949] Popped gitiles commit info from properties and tags
[18:13:02.949] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:13:02.949] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:13:02.949] Buildbucket request:
{
"requestId": "8963652363712999616",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "18c6dc1f9968cd70c922ae8369ce2bad9504a186",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8963652363712999616"
},
{
"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"
}
}
[18:13:03.396] Scheduled build:
{
"id": "8798222301714202433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 44913,
"createdBy": "project:chromium",
"createTime": "2022-11-06T18:13:03.030324073Z",
"updateTime": "2022-11-06T18:13:03.030324073Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "18c6dc1f9968cd70c922ae8369ce2bad9504a186",
"ref": "refs/heads/main"
}
}
}
[18:13:03.396] Task URL: https://cr-buildbucket.appspot.com/build/8798222301714202433
[18:13:03.396] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:2:0) after 2m47s
[18:15:50.449] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:2:0)
[18:15:50.470] Build status: SCHEDULED
[18:15:50.470] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:3:0) after 3m49s
[18:19:39.489] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:3:0)
[18:19:39.526] Build status: SCHEDULED
[18:19:39.526] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:4:0) after 7m16s
[18:23:44.925] Received PubSub notification, asking Buildbucket for the build status
[18:23:44.948] Build status: STARTED
[18:26:55.543] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:4:0)
[18:26:55.589] Build status: STARTED
[18:26:55.589] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:6:0) after 7m38s
[18:34:33.612] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:6:0)
[18:34:33.639] Build status: STARTED
[18:34:33.639] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:7:0) after 9m27s
[18:44:00.657] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:7:0)
[18:44:00.682] Build status: STARTED
[18:44:00.683] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:8:0) after 7m24s
[18:51:24.672] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:8:0)
[18:51:24.697] Build status: STARTED
[18:51:24.697] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:9:0) after 4m5s
[18:55:30.038] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:9:0)
[18:55:30.064] Build status: STARTED
[18:55:30.065] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:10:0) after 3m11s
[18:58:41.344] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:10:0)
[18:58:41.375] Build status: STARTED
[18:58:41.375] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:11:0) after 2m44s
[19:01:25.402] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:11:0)
[19:01:25.424] Build status: STARTED
[19:01:25.424] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:12:0) after 8m8s
[19:09:33.745] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:12:0)
[19:09:33.770] Build status: STARTED
[19:09:33.770] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:13:0) after 3m2s
[19:12:35.859] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:13:0)
[19:12:35.884] Build status: STARTED
[19:12:35.884] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:14:0) after 4m0s
[19:16:36.003] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:14:0)
[19:16:36.050] Build status: STARTED
[19:16:36.050] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:15:0) after 9m26s
[19:26:02.221] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:15:0)
[19:26:02.247] Build status: STARTED
[19:26:02.247] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:16:0) after 2m31s
[19:28:33.271] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:16:0)
[19:28:33.296] Build status: STARTED
[19:28:33.296] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:17:0) after 7m6s
[19:35:39.317] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:17:0)
[19:35:39.346] Build status: STARTED
[19:35:39.346] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:18:0) after 6m2s
[19:41:41.552] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:18:0)
[19:41:41.579] Build status: STARTED
[19:41:41.579] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:19:0) after 7m45s
[19:49:27.440] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:19:0)
[19:49:27.461] Build status: STARTED
[19:49:27.461] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:20:0) after 7m19s
[19:56:46.483] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:20:0)
[19:56:46.506] Build status: STARTED
[19:56:46.506] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:21:0) after 4m39s
[20:01:25.536] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:21:0)
[20:01:25.559] Build status: STARTED
[20:01:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:22:0) after 3m7s
[20:04:32.539] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:22:0)
[20:04:32.566] Build status: STARTED
[20:04:32.566] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:23:0) after 3m50s
[20:08:22.644] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:23:0)
[20:08:22.681] Build status: STARTED
[20:08:22.681] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:24:0) after 4m51s
[20:13:13.703] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:24:0)
[20:13:13.729] Build status: STARTED
[20:13:13.729] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:25:0) after 3m6s
[20:16:19.919] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:25:0)
[20:16:19.944] Build status: STARTED
[20:16:19.944] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:26:0) after 5m23s
[20:21:42.967] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:26:0)
[20:21:42.990] Build status: STARTED
[20:21:42.990] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:27:0) after 7m44s
[20:29:27.224] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:27:0)
[20:29:27.244] Build status: STARTED
[20:29:27.245] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:28:0) after 3m53s
[20:33:20.276] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:28:0)
[20:33:20.318] Build status: STARTED
[20:33:20.318] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:29:0) after 8m50s
[20:42:10.351] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:29:0)
[20:42:10.376] Build status: STARTED
[20:42:10.376] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:30:0) after 6m38s
[20:48:48.398] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:30:0)
[20:48:48.467] Build status: STARTED
[20:48:48.467] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:31:0) after 4m11s
[20:52:59.512] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:31:0)
[20:52:59.536] Build status: STARTED
[20:52:59.536] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:32:0) after 9m22s
[21:02:21.560] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:32:0)
[21:02:21.581] Build status: STARTED
[21:02:21.581] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:33:0) after 8m1s
[21:10:22.605] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:33:0)
[21:10:22.626] Build status: STARTED
[21:10:22.626] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:34:0) after 6m2s
[21:16:24.648] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:34:0)
[21:16:24.671] Build status: STARTED
[21:16:24.671] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:35:0) after 9m34s
[21:25:58.701] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:35:0)
[21:25:58.734] Build status: STARTED
[21:25:58.734] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:36:0) after 7m1s
[21:32:59.762] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:36:0)
[21:32:59.788] Build status: STARTED
[21:32:59.788] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:37:0) after 5m26s
[21:38:25.952] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:37:0)
[21:38:25.973] Build status: STARTED
[21:38:25.973] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:38:0) after 8m54s
[21:47:19.997] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:38:0)
[21:47:20.020] Build status: STARTED
[21:47:20.020] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:39:0) after 2m34s
[21:49:54.121] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:39:0)
[21:49:54.146] Build status: STARTED
[21:49:54.146] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:40:0) after 7m2s
[21:56:56.220] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:40:0)
[21:56:56.245] Build status: STARTED
[21:56:56.245] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:41:0) after 8m23s
[22:05:19.269] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:41:0)
[22:05:19.295] Build status: STARTED
[22:05:19.295] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:42:0) after 3m53s
[22:09:12.328] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:42:0)
[22:09:12.353] Build status: STARTED
[22:09:12.353] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:43:0) after 1m19s
[22:10:31.712] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:43:0)
[22:10:31.737] Build status: STARTED
[22:10:31.738] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:44:0) after 1m25s
[22:11:56.761] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:44:0)
[22:11:56.789] Build status: STARTED
[22:11:56.789] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:45:0) after 1m7s
[22:13:03.827] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:45:0)
[22:13:03.854] Build status: STARTED
[22:13:03.854] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:46:0) after 7m24s
[22:20:27.983] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:46:0)
[22:20:28.006] Build status: STARTED
[22:20:28.006] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:47:0) after 5m53s
[22:26:21.038] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:47:0)
[22:26:21.062] Build status: STARTED
[22:26:21.062] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:48:0) after 9m25s
[22:35:46.148] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:48:0)
[22:35:46.171] Build status: STARTED
[22:35:46.171] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:49:0) after 3m4s
[22:38:50.193] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:49:0)
[22:38:50.226] Build status: STARTED
[22:38:50.226] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:50:0) after 6m7s
[22:44:57.358] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:50:0)
[22:44:57.380] Build status: STARTED
[22:44:57.380] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:51:0) after 2m49s
[22:47:46.478] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:51:0)
[22:47:46.503] Build status: STARTED
[22:47:46.503] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:52:0) after 1m5s
[22:48:51.621] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:52:0)
[22:48:51.701] Build status: STARTED
[22:48:51.702] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:53:0) after 4m20s
[22:53:11.735] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:53:0)
[22:53:11.756] Build status: STARTED
[22:53:11.756] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:54:0) after 7m2s
[23:00:14.071] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:54:0)
[23:00:14.097] Build status: STARTED
[23:00:14.097] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:55:0) after 2m38s
[23:02:52.124] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:55:0)
[23:02:52.148] Build status: STARTED
[23:02:52.148] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:56:0) after 5m44s
[23:08:36.412] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:56:0)
[23:08:36.437] Build status: STARTED
[23:08:36.437] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:57:0) after 5m31s
[23:14:07.977] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:57:0)
[23:14:08.001] Build status: STARTED
[23:14:08.001] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:58:0) after 3m9s
[23:17:17.598] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:58:0)
[23:17:17.623] Build status: STARTED
[23:17:17.623] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:59:0) after 2m37s
[23:19:54.750] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:59:0)
[23:19:54.774] Build status: STARTED
[23:19:54.774] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8963652363712999616:60:0) after 8m17s
[23:26:33.148] Received PubSub notification, asking Buildbucket for the build status
[23:26:33.170] Build:
{
"id": "8798222301714202433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 44913,
"createdBy": "project:chromium",
"createTime": "2022-11-06T18:13:03.030324073Z",
"startTime": "2022-11-06T18:23:44.375371Z",
"endTime": "2022-11-06T23:26:32.874855357Z",
"updateTime": "2022-11-06T23:26:32.874855357Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "18c6dc1f9968cd70c922ae8369ce2bad9504a186",
"ref": "refs/heads/main"
}
}
}
[23:26:33.170] Invocation finished in 5h13m31.430034242s with status SUCCEEDED