[15:03:20.521] New invocation is queued and will start shortly
[15:03:21.652] Starting the invocation (attempt 1)
[15:03:21.692] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/867fcd5e067f6b2cf9ba854a9e798a5e6a2ee780"
[15:03:21.692] Popped gitiles commit info from properties and tags
[15:03:21.692] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:03:21.692] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:03:21.692] Buildbucket request:
{
"requestId": "8946813262052687888",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "867fcd5e067f6b2cf9ba854a9e798a5e6a2ee780",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8946813262052687888"
},
{
"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"
}
}
[15:03:22.165] Scheduled build:
{
"id": "8781383200064600049",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 47827,
"createdBy": "project:chromium",
"createTime": "2023-05-11T15:03:21.785715599Z",
"updateTime": "2023-05-11T15:03:21.785715599Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "867fcd5e067f6b2cf9ba854a9e798a5e6a2ee780",
"ref": "refs/heads/main"
}
}
}
[15:03:22.165] Task URL: https://cr-buildbucket.appspot.com/build/8781383200064600049
[15:03:22.165] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:2:0) after 1m1s
[15:04:23.221] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:2:0)
[15:04:23.325] Build status: SCHEDULED
[15:04:23.325] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:3:0) after 5m49s
[15:10:12.477] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:3:0)
[15:10:12.501] Build status: SCHEDULED
[15:10:12.501] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:4:0) after 4m23s
[15:14:17.324] Received PubSub notification, asking Buildbucket for the build status
[15:14:17.343] Build status: STARTED
[15:14:35.608] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:4:0)
[15:14:35.627] Build status: STARTED
[15:14:35.627] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:6:0) after 2m19s
[15:16:54.734] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:6:0)
[15:16:54.779] Build status: STARTED
[15:16:54.779] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:7:0) after 7m30s
[15:24:24.943] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:7:0)
[15:24:24.988] Build status: STARTED
[15:24:24.988] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:8:0) after 6m51s
[15:31:16.137] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:8:0)
[15:31:16.158] Build status: STARTED
[15:31:16.158] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:9:0) after 9m8s
[15:40:24.294] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:9:0)
[15:40:24.313] Build status: STARTED
[15:40:24.313] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:10:0) after 1m40s
[15:42:04.440] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:10:0)
[15:42:04.559] Build status: STARTED
[15:42:04.559] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:11:0) after 1m55s
[15:43:59.633] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:11:0)
[15:43:59.656] Build status: STARTED
[15:43:59.656] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:12:0) after 6m4s
[15:50:03.671] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:12:0)
[15:50:03.704] Build status: STARTED
[15:50:03.704] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:13:0) after 6m57s
[15:57:00.797] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:13:0)
[15:57:00.820] Build status: STARTED
[15:57:00.820] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:14:0) after 2m18s
[15:59:18.998] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:14:0)
[15:59:19.016] Build status: STARTED
[15:59:19.016] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:15:0) after 5m12s
[16:04:31.378] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:15:0)
[16:04:31.409] Build status: STARTED
[16:04:31.409] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:16:0) after 8m48s
[16:13:19.511] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:16:0)
[16:13:19.664] Build status: STARTED
[16:13:19.664] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:17:0) after 7m43s
[16:21:02.784] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:17:0)
[16:21:02.830] Build status: STARTED
[16:21:02.830] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:18:0) after 3m52s
[16:24:54.865] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:18:0)
[16:24:54.894] Build status: STARTED
[16:24:54.894] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:19:0) after 2m30s
[16:27:24.995] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:19:0)
[16:27:25.015] Build status: STARTED
[16:27:25.015] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:20:0) after 7m33s
[16:34:58.196] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:20:0)
[16:34:58.280] Build status: STARTED
[16:34:58.280] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:21:0) after 6m53s
[16:41:51.296] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:21:0)
[16:41:51.319] Build status: STARTED
[16:41:51.319] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:22:0) after 6m9s
[16:48:00.404] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:22:0)
[16:48:00.492] Build status: STARTED
[16:48:00.492] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:23:0) after 1m8s
[16:49:08.609] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:23:0)
[16:49:08.641] Build status: STARTED
[16:49:08.641] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:24:0) after 4m40s
[16:53:48.668] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:24:0)
[16:53:48.690] Build status: STARTED
[16:53:48.690] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:25:0) after 3m45s
[16:57:33.808] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:25:0)
[16:57:33.882] Build status: STARTED
[16:57:33.882] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:26:0) after 5m51s
[17:03:24.921] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:26:0)
[17:03:24.946] Build status: STARTED
[17:03:24.946] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:27:0) after 1m59s
[17:05:23.981] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:27:0)
[17:05:24.014] Build status: STARTED
[17:05:24.014] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:28:0) after 8m3s
[17:13:27.056] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:28:0)
[17:13:27.073] Build status: STARTED
[17:13:27.073] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:29:0) after 2m16s
[17:15:43.695] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:29:0)
[17:15:43.732] Build status: STARTED
[17:15:43.732] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:30:0) after 5m20s
[17:21:03.750] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:30:0)
[17:21:03.907] Build status: STARTED
[17:21:03.907] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:31:0) after 6m46s
[17:27:49.926] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:31:0)
[17:27:49.947] Build status: STARTED
[17:27:49.947] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:32:0) after 9m38s
[17:37:28.131] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:32:0)
[17:37:28.153] Build status: STARTED
[17:37:28.153] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:33:0) after 6m37s
[17:44:05.182] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:33:0)
[17:44:05.211] Build status: STARTED
[17:44:05.211] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:34:0) after 3m34s
[17:47:39.602] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:34:0)
[17:47:39.695] Build status: STARTED
[17:47:39.695] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:35:0) after 5m14s
[17:52:53.747] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:35:0)
[17:52:53.767] Build status: STARTED
[17:52:53.767] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:36:0) after 3m39s
[17:56:33.568] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:36:0)
[17:56:33.612] Build status: STARTED
[17:56:33.612] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:37:0) after 5m12s
[18:01:45.812] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:37:0)
[18:01:45.828] Build status: STARTED
[18:01:45.828] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:38:0) after 1m5s
[18:02:50.898] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:38:0)
[18:02:50.916] Build status: STARTED
[18:02:50.916] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:39:0) after 5m37s
[18:08:27.985] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:39:0)
[18:08:28.015] Build status: STARTED
[18:08:28.015] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:40:0) after 7m53s
[18:16:21.035] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:40:0)
[18:16:21.055] Build status: STARTED
[18:16:21.055] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:41:0) after 3m28s
[18:19:49.074] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:41:0)
[18:19:49.101] Build status: STARTED
[18:19:49.101] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:42:0) after 6m39s
[18:26:28.180] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:42:0)
[18:26:28.205] Build status: STARTED
[18:26:28.205] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:43:0) after 6m52s
[18:33:20.228] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:43:0)
[18:33:20.248] Build status: STARTED
[18:33:20.248] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:44:0) after 9m59s
[18:43:19.306] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:44:0)
[18:43:19.343] Build status: STARTED
[18:43:19.343] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8946813262052687888:45:0) after 6m55s
[18:46:41.044] Received PubSub notification, asking Buildbucket for the build status
[18:46:41.064] Build:
{
"id": "8781383200064600049",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 47827,
"createdBy": "project:chromium",
"createTime": "2023-05-11T15:03:21.785715599Z",
"startTime": "2023-05-11T15:14:13.929120Z",
"endTime": "2023-05-11T18:46:40.590925572Z",
"updateTime": "2023-05-11T18:46:40.590925572Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "867fcd5e067f6b2cf9ba854a9e798a5e6a2ee780",
"ref": "refs/heads/main"
}
}
}
[18:46:41.064] Invocation finished in 3h43m20.559902677s with status SUCCEEDED