[01:54:32.308] New invocation is queued and will start shortly
[01:54:33.446] Starting the invocation (attempt 1)
[01:54:33.479] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd5d16c8b6ba9c3585dd90478ef1a26a14f3dc32"
[01:54:33.479] Popped gitiles commit info from properties and tags
[01:54:33.479] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:54:33.479] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:54:33.479] Buildbucket request:
{
"requestId": "8935538268469671664",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Linux32 ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd5d16c8b6ba9c3585dd90478ef1a26a14f3dc32",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935538268469671664"
},
{
"key": "scheduler_job_id",
"value": "chromium/Libfuzzer Upload Linux32 ASan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:54:34.160] Scheduled build:
{
"id": "8770108206509696689",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Linux32 ASan"
},
"number": 105821,
"createdBy": "project:chromium",
"createTime": "2023-09-13T01:54:33.556732413Z",
"updateTime": "2023-09-13T01:54:33.556732413Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd5d16c8b6ba9c3585dd90478ef1a26a14f3dc32",
"ref": "refs/heads/main"
}
}
}
[01:54:34.160] Task URL: https://cr-buildbucket.appspot.com/build/8770108206509696689
[01:54:34.160] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:2:0) after 8m58s
[01:54:35.027] Received PubSub notification, asking Buildbucket for the build status
[01:54:35.076] Build status: SCHEDULED
[01:56:58.735] Received PubSub notification, asking Buildbucket for the build status
[01:56:58.762] Build status: STARTED
[02:03:32.545] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:2:0)
[02:03:32.564] Build status: STARTED
[02:03:32.564] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:5:0) after 2m23s
[02:05:56.053] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:5:0)
[02:05:56.087] Build status: STARTED
[02:05:56.087] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:6:0) after 8m42s
[02:14:38.117] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:6:0)
[02:14:38.160] Build status: STARTED
[02:14:38.160] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:7:0) after 5m3s
[02:19:41.179] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:7:0)
[02:19:41.211] Build status: STARTED
[02:19:41.211] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:8:0) after 2m27s
[02:22:08.235] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:8:0)
[02:22:08.253] Build status: STARTED
[02:22:08.253] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:9:0) after 7m16s
[02:29:24.270] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:9:0)
[02:29:24.295] Build status: STARTED
[02:29:24.295] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:10:0) after 5m3s
[02:34:27.361] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:10:0)
[02:34:27.378] Build status: STARTED
[02:34:27.378] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:11:0) after 2m10s
[02:36:37.399] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:11:0)
[02:36:37.419] Build status: STARTED
[02:36:37.419] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:12:0) after 2m8s
[02:38:45.533] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:12:0)
[02:38:45.550] Build status: STARTED
[02:38:45.550] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:13:0) after 5m9s
[02:43:54.566] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:13:0)
[02:43:54.585] Build status: STARTED
[02:43:54.585] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:14:0) after 2m14s
[02:46:08.605] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:14:0)
[02:46:08.624] Build status: STARTED
[02:46:08.624] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:15:0) after 9m27s
[02:55:35.730] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:15:0)
[02:55:35.754] Build status: STARTED
[02:55:35.754] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:16:0) after 3m29s
[02:59:05.055] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:16:0)
[02:59:05.209] Build status: STARTED
[02:59:05.209] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:17:0) after 4m6s
[03:03:11.255] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:17:0)
[03:03:11.291] Build status: STARTED
[03:03:11.291] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:18:0) after 3m28s
[03:06:39.412] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:18:0)
[03:06:39.432] Build status: STARTED
[03:06:39.432] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:19:0) after 4m39s
[03:11:18.662] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:19:0)
[03:11:18.681] Build status: STARTED
[03:11:18.681] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:20:0) after 2m50s
[03:14:08.697] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:20:0)
[03:14:08.729] Build status: STARTED
[03:14:08.729] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:21:0) after 5m43s
[03:19:51.929] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:21:0)
[03:19:51.972] Build status: STARTED
[03:19:51.972] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:22:0) after 7m49s
[03:27:40.986] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:22:0)
[03:27:41.002] Build status: STARTED
[03:27:41.002] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:23:0) after 9m42s
[03:37:23.024] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:23:0)
[03:37:23.043] Build status: STARTED
[03:37:23.043] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:24:0) after 1m18s
[03:38:41.077] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:24:0)
[03:38:41.101] Build status: STARTED
[03:38:41.101] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:25:0) after 4m26s
[03:43:07.412] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:25:0)
[03:43:07.435] Build status: STARTED
[03:43:07.435] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:26:0) after 7m0s
[03:50:07.550] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:26:0)
[03:50:07.575] Build status: STARTED
[03:50:07.575] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:27:0) after 9m21s
[03:59:28.616] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:27:0)
[03:59:28.924] Build status: STARTED
[03:59:28.924] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:28:0) after 4m16s
[04:03:44.949] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:28:0)
[04:03:44.976] Build status: STARTED
[04:03:44.976] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:29:0) after 2m26s
[04:06:11.085] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:29:0)
[04:06:11.104] Build status: STARTED
[04:06:11.104] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:30:0) after 2m6s
[04:08:17.131] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:30:0)
[04:08:17.174] Build status: STARTED
[04:08:17.174] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:31:0) after 1m22s
[04:09:39.202] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:31:0)
[04:09:39.234] Build status: STARTED
[04:09:39.234] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:32:0) after 6m25s
[04:16:04.249] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:32:0)
[04:16:04.268] Build status: STARTED
[04:16:04.268] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:33:0) after 2m32s
[04:18:36.291] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:33:0)
[04:18:36.575] Build status: STARTED
[04:18:36.575] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:34:0) after 8m13s
[04:26:49.620] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:34:0)
[04:26:49.639] Build status: STARTED
[04:26:49.639] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux32 ASan:8935538268469671664:35:0) after 9m5s
[04:34:51.471] Received PubSub notification, asking Buildbucket for the build status
[04:34:51.490] Build:
{
"id": "8770108206509696689",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Linux32 ASan"
},
"number": 105821,
"createdBy": "project:chromium",
"createTime": "2023-09-13T01:54:33.556732413Z",
"startTime": "2023-09-13T01:56:58.015097Z",
"endTime": "2023-09-13T04:34:51.242268209Z",
"updateTime": "2023-09-13T04:34:51.242268209Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd5d16c8b6ba9c3585dd90478ef1a26a14f3dc32",
"ref": "refs/heads/main"
}
}
}
[04:34:51.490] Invocation finished in 2h40m19.186332383s with status SUCCEEDED