[15:52:22.859] New invocation is queued and will start shortly
[15:52:23.929] Starting the invocation (attempt 1)
[15:52:23.974] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20e740d7f1ed81ab50797b8cd984e3dd9f92a71a"
[15:52:23.974] Popped gitiles commit info from properties and tags
[15:52:23.974] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:52:23.974] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:52:23.975] Buildbucket request:
{
"requestId": "8942733313294055808",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20e740d7f1ed81ab50797b8cd984e3dd9f92a71a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8942733313294055808"
},
{
"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:52:24.396] Scheduled build:
{
"id": "8777303251382472417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 48754,
"createdBy": "project:chromium",
"createTime": "2023-06-25T15:52:24.055021983Z",
"updateTime": "2023-06-25T15:52:24.055021983Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20e740d7f1ed81ab50797b8cd984e3dd9f92a71a",
"ref": "refs/heads/main"
}
}
}
[15:52:24.396] Task URL: https://cr-buildbucket.appspot.com/build/8777303251382472417
[15:52:24.396] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:2:0) after 3m59s
[15:56:23.929] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:2:0)
[15:56:23.950] Build status: SCHEDULED
[15:56:23.950] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:3:0) after 1m29s
[15:57:54.226] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:3:0)
[15:57:54.255] Build status: SCHEDULED
[15:57:54.255] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:4:0) after 4m41s
[16:02:35.273] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:4:0)
[16:02:35.336] Build status: SCHEDULED
[16:02:35.336] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:5:0) after 3m9s
[16:03:07.921] Received PubSub notification, asking Buildbucket for the build status
[16:03:07.941] Build status: STARTED
[16:05:44.706] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:5:0)
[16:05:44.735] Build status: STARTED
[16:05:44.735] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:7:0) after 3m58s
[16:09:42.937] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:7:0)
[16:09:42.959] Build status: STARTED
[16:09:42.959] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:8:0) after 5m25s
[16:15:08.172] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:8:0)
[16:15:08.189] Build status: STARTED
[16:15:08.189] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:9:0) after 5m0s
[16:20:08.649] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:9:0)
[16:20:08.669] Build status: STARTED
[16:20:08.669] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:10:0) after 8m36s
[16:28:44.686] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:10:0)
[16:28:44.710] Build status: STARTED
[16:28:44.710] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:11:0) after 8m24s
[16:37:09.174] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:11:0)
[16:37:09.201] Build status: STARTED
[16:37:09.201] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:12:0) after 6m25s
[16:43:45.764] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:12:0)
[16:43:45.787] Build status: STARTED
[16:43:45.787] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:13:0) after 9m56s
[16:53:41.801] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:13:0)
[16:53:41.820] Build status: STARTED
[16:53:41.820] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:14:0) after 2m8s
[16:55:49.861] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:14:0)
[16:55:49.876] Build status: STARTED
[16:55:49.876] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:15:0) after 9m14s
[17:05:03.882] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:15:0)
[17:05:03.901] Build status: STARTED
[17:05:03.901] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:16:0) after 1m6s
[17:06:11.404] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:16:0)
[17:06:11.424] Build status: STARTED
[17:06:11.424] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:17:0) after 1m39s
[17:07:50.487] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:17:0)
[17:07:50.506] Build status: STARTED
[17:07:50.506] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:18:0) after 9m13s
[17:17:03.524] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:18:0)
[17:17:03.549] Build status: STARTED
[17:17:03.549] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:19:0) after 1m57s
[17:19:00.663] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:19:0)
[17:19:00.678] Build status: STARTED
[17:19:00.678] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:20:0) after 1m7s
[17:20:07.703] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:20:0)
[17:20:07.724] Build status: STARTED
[17:20:07.724] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:21:0) after 6m58s
[17:27:05.793] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:21:0)
[17:27:05.819] Build status: STARTED
[17:27:05.819] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:22:0) after 1m1s
[17:28:06.989] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:22:0)
[17:28:07.020] Build status: STARTED
[17:28:07.020] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:23:0) after 3m33s
[17:31:40.034] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:23:0)
[17:31:40.060] Build status: STARTED
[17:31:40.060] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:24:0) after 7m23s
[17:39:03.075] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:24:0)
[17:39:03.095] Build status: STARTED
[17:39:03.095] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:25:0) after 4m51s
[17:43:54.112] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:25:0)
[17:43:54.146] Build status: STARTED
[17:43:54.146] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:26:0) after 5m9s
[17:49:03.227] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:26:0)
[17:49:03.254] Build status: STARTED
[17:49:03.254] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:27:0) after 8m55s
[17:57:58.696] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:27:0)
[17:57:58.717] Build status: STARTED
[17:57:58.717] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:28:0) after 3m29s
[18:01:27.737] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:28:0)
[18:01:27.766] Build status: STARTED
[18:01:27.766] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:29:0) after 3m42s
[18:05:09.933] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:29:0)
[18:05:09.957] Build status: STARTED
[18:05:09.957] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:30:0) after 8m54s
[18:14:04.145] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:30:0)
[18:14:04.162] Build status: STARTED
[18:14:04.162] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:31:0) after 7m14s
[18:21:18.854] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:31:0)
[18:21:18.874] Build status: STARTED
[18:21:18.874] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:32:0) after 1m26s
[18:22:45.228] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:32:0)
[18:22:45.278] Build status: STARTED
[18:22:45.278] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:33:0) after 4m45s
[18:27:30.280] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:33:0)
[18:27:30.356] Build status: STARTED
[18:27:30.356] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:34:0) after 6m32s
[18:34:02.402] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:34:0)
[18:34:02.419] Build status: STARTED
[18:34:02.419] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8942733313294055808:35:0) after 9m8s
[18:42:07.339] Received PubSub notification, asking Buildbucket for the build status
[18:42:07.434] Build:
{
"id": "8777303251382472417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 48754,
"createdBy": "project:chromium",
"createTime": "2023-06-25T15:52:24.055021983Z",
"startTime": "2023-06-25T16:03:07.203167Z",
"endTime": "2023-06-25T18:42:06.981865665Z",
"updateTime": "2023-06-25T18:42:06.981865665Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20e740d7f1ed81ab50797b8cd984e3dd9f92a71a",
"ref": "refs/heads/main"
}
}
}
[18:42:07.434] Invocation finished in 2h49m44.586006101s with status SUCCEEDED