[00:48:02.138] New invocation is queued and will start shortly
[00:48:03.225] Starting the invocation (attempt 1)
[00:48:03.271] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b67ad93f853eae2cad0185539d15bbf60b4fd530"
[00:48:03.271] Popped gitiles commit info from properties and tags
[00:48:03.271] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:48:03.273] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:48:03.273] Buildbucket request:
{
"requestId": "8976492281289326784",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b67ad93f853eae2cad0185539d15bbf60b4fd530",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8976492281289326784"
},
{
"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"
}
}
[00:48:03.945] Scheduled build:
{
"id": "8811062219364119233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 42659,
"createdBy": "project:chromium",
"createTime": "2022-06-18T00:48:03.342671607Z",
"updateTime": "2022-06-18T00:48:03.342671607Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b67ad93f853eae2cad0185539d15bbf60b4fd530",
"ref": "refs/heads/main"
}
}
}
[00:48:03.945] Task URL: https://cr-buildbucket.appspot.com/build/8811062219364119233
[00:48:03.945] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:2:0) after 4m29s
[00:52:32.965] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:2:0)
[00:52:32.997] Build status: SCHEDULED
[00:52:32.997] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:3:0) after 8m10s
[00:55:09.293] Received PubSub notification, asking Buildbucket for the build status
[00:55:09.322] Build status: STARTED
[01:00:43.019] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:3:0)
[01:00:43.049] Build status: STARTED
[01:00:43.049] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:5:0) after 4m30s
[01:05:13.084] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:5:0)
[01:05:13.133] Build status: STARTED
[01:05:13.133] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:6:0) after 3m53s
[01:09:06.165] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:6:0)
[01:09:06.244] Build status: STARTED
[01:09:06.244] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:7:0) after 2m54s
[01:12:00.219] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:7:0)
[01:12:00.248] Build status: STARTED
[01:12:00.248] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:8:0) after 5m56s
[01:17:56.565] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:8:0)
[01:17:56.593] Build status: STARTED
[01:17:56.593] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:9:0) after 5m33s
[01:23:29.906] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:9:0)
[01:23:30.018] Build status: STARTED
[01:23:30.018] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:10:0) after 2m7s
[01:25:37.062] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:10:0)
[01:25:37.104] Build status: STARTED
[01:25:37.104] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:11:0) after 2m39s
[01:28:16.160] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:11:0)
[01:28:16.192] Build status: STARTED
[01:28:16.192] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:12:0) after 2m57s
[01:31:13.768] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:12:0)
[01:31:13.798] Build status: STARTED
[01:31:13.798] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:13:0) after 7m37s
[01:38:50.820] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:13:0)
[01:38:50.842] Build status: STARTED
[01:38:50.842] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:14:0) after 9m17s
[01:48:07.942] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:14:0)
[01:48:07.965] Build status: STARTED
[01:48:07.965] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:15:0) after 2m48s
[01:50:56.465] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:15:0)
[01:50:56.490] Build status: STARTED
[01:50:56.490] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:16:0) after 3m59s
[01:54:55.508] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:16:0)
[01:54:55.527] Build status: STARTED
[01:54:55.527] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:17:0) after 3m40s
[01:58:35.649] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:17:0)
[01:58:35.672] Build status: STARTED
[01:58:35.672] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:18:0) after 4m15s
[02:02:50.791] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:18:0)
[02:02:50.830] Build status: STARTED
[02:02:50.830] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:19:0) after 2m44s
[02:05:35.153] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:19:0)
[02:05:35.275] Build status: STARTED
[02:05:35.275] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:20:0) after 3m58s
[02:09:33.411] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:20:0)
[02:09:33.437] Build status: STARTED
[02:09:33.437] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:21:0) after 7m39s
[02:17:12.474] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:21:0)
[02:17:12.515] Build status: STARTED
[02:17:12.516] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:22:0) after 1m26s
[02:18:38.713] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:22:0)
[02:18:38.747] Build status: STARTED
[02:18:38.748] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:23:0) after 6m38s
[02:25:16.866] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:23:0)
[02:25:16.888] Build status: STARTED
[02:25:16.888] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:24:0) after 6m16s
[02:31:32.886] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:24:0)
[02:31:32.911] Build status: STARTED
[02:31:32.911] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:25:0) after 2m7s
[02:33:39.984] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:25:0)
[02:33:40.005] Build status: STARTED
[02:33:40.005] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:26:0) after 6m10s
[02:39:50.027] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:26:0)
[02:39:50.053] Build status: STARTED
[02:39:50.053] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:27:0) after 7m49s
[02:47:39.073] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:27:0)
[02:47:39.106] Build status: STARTED
[02:47:39.106] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:28:0) after 9m52s
[02:57:31.237] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:28:0)
[02:57:31.263] Build status: STARTED
[02:57:31.263] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:29:0) after 7m58s
[03:05:29.424] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:29:0)
[03:05:29.464] Build status: STARTED
[03:05:29.464] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:30:0) after 1m48s
[03:07:17.452] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:30:0)
[03:07:17.477] Build status: STARTED
[03:07:17.477] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:31:0) after 7m42s
[03:14:59.502] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:31:0)
[03:14:59.523] Build status: STARTED
[03:14:59.523] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:32:0) after 7m32s
[03:22:31.546] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:32:0)
[03:22:31.663] Build status: STARTED
[03:22:31.663] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:33:0) after 9m52s
[03:32:23.695] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:33:0)
[03:32:23.720] Build status: STARTED
[03:32:23.720] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:34:0) after 4m38s
[03:37:01.841] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:34:0)
[03:37:01.877] Build status: STARTED
[03:37:01.877] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:35:0) after 3m29s
[03:40:30.950] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:35:0)
[03:40:30.976] Build status: STARTED
[03:40:30.976] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:36:0) after 3m35s
[03:44:06.195] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:36:0)
[03:44:06.220] Build status: STARTED
[03:44:06.220] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:37:0) after 6m17s
[03:50:23.265] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:37:0)
[03:50:23.290] Build status: STARTED
[03:50:23.290] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:38:0) after 7m23s
[03:57:46.436] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:38:0)
[03:57:46.466] Build status: STARTED
[03:57:46.466] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:39:0) after 2m18s
[04:00:04.474] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:39:0)
[04:00:04.514] Build status: STARTED
[04:00:04.514] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:40:0) after 6m3s
[04:06:07.536] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:40:0)
[04:06:07.557] Build status: STARTED
[04:06:07.557] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:41:0) after 5m21s
[04:11:28.579] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:41:0)
[04:11:28.613] Build status: STARTED
[04:11:28.613] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:42:0) after 3m24s
[04:14:52.643] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:42:0)
[04:14:52.669] Build status: STARTED
[04:14:52.669] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:43:0) after 7m27s
[04:22:19.699] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:43:0)
[04:22:19.725] Build status: STARTED
[04:22:19.725] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:44:0) after 8m5s
[04:30:24.798] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:44:0)
[04:30:24.824] Build status: STARTED
[04:30:24.824] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:45:0) after 4m45s
[04:35:09.858] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:45:0)
[04:35:09.932] Build status: STARTED
[04:35:09.932] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8976492281289326784:46:0) after 8m56s
[04:42:03.090] Received PubSub notification, asking Buildbucket for the build status
[04:42:03.561] Build:
{
"id": "8811062219364119233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 42659,
"createdBy": "project:chromium",
"createTime": "2022-06-18T00:48:03.342671607Z",
"startTime": "2022-06-18T00:55:08.600323Z",
"endTime": "2022-06-18T04:42:02.226477475Z",
"updateTime": "2022-06-18T04:42:02.226477475Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b67ad93f853eae2cad0185539d15bbf60b4fd530",
"ref": "refs/heads/main"
}
}
}
[04:42:03.561] Invocation finished in 3h54m1.437940857s with status SUCCEEDED