[18:59:37.067] New invocation is queued and will start shortly
[18:59:38.216] Starting the invocation (attempt 1)
[18:59:38.242] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6b9682c374b48b026a3898195d17b5bfe5d793a"
[18:59:38.242] Popped gitiles commit info from properties and tags
[18:59:38.242] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:59:38.242] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:59:38.242] Buildbucket request:
{
"requestId": "8949788096756359264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Linux ASan Debug"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6b9682c374b48b026a3898195d17b5bfe5d793a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8949788096756359264"
},
{
"key": "scheduler_job_id",
"value": "chromium/Libfuzzer Upload Linux ASan Debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:59:38.675] Scheduled build:
{
"id": "8784358034760951489",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Linux ASan Debug"
},
"number": 135998,
"createdBy": "project:chromium",
"createTime": "2023-04-08T18:59:38.339733315Z",
"updateTime": "2023-04-08T18:59:38.339733315Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6b9682c374b48b026a3898195d17b5bfe5d793a",
"ref": "refs/heads/main"
}
}
}
[18:59:38.675] Task URL: https://cr-buildbucket.appspot.com/build/8784358034760951489
[18:59:38.675] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:2:0) after 4m45s
[19:04:23.780] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:2:0)
[19:04:23.800] Build status: SCHEDULED
[19:04:23.800] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:3:0) after 5m31s
[19:04:54.789] Received PubSub notification, asking Buildbucket for the build status
[19:04:54.811] Build status: STARTED
[19:09:54.817] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:3:0)
[19:09:54.843] Build status: STARTED
[19:09:54.843] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:5:0) after 1m11s
[19:11:05.887] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:5:0)
[19:11:05.916] Build status: STARTED
[19:11:05.916] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:6:0) after 1m0s
[19:12:05.933] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:6:0)
[19:12:05.954] Build status: STARTED
[19:12:05.954] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:7:0) after 5m51s
[19:17:56.970] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:7:0)
[19:17:56.992] Build status: STARTED
[19:17:56.992] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:8:0) after 4m27s
[19:22:24.010] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:8:0)
[19:22:24.138] Build status: STARTED
[19:22:24.138] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:9:0) after 4m24s
[19:26:48.682] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:9:0)
[19:26:48.704] Build status: STARTED
[19:26:48.704] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:10:0) after 8m55s
[19:35:43.724] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:10:0)
[19:35:43.744] Build status: STARTED
[19:35:43.744] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:11:0) after 3m55s
[19:39:38.765] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:11:0)
[19:39:38.802] Build status: STARTED
[19:39:38.802] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:12:0) after 4m45s
[19:44:23.822] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:12:0)
[19:44:23.845] Build status: STARTED
[19:44:23.845] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:13:0) after 9m18s
[19:53:41.868] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:13:0)
[19:53:41.888] Build status: STARTED
[19:53:41.888] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:14:0) after 7m4s
[20:00:45.942] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:14:0)
[20:00:45.960] Build status: STARTED
[20:00:45.960] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:15:0) after 2m0s
[20:02:46.082] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:15:0)
[20:02:46.126] Build status: STARTED
[20:02:46.126] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:16:0) after 7m10s
[20:09:56.154] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:16:0)
[20:09:56.175] Build status: STARTED
[20:09:56.175] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:17:0) after 2m50s
[20:12:46.170] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:17:0)
[20:12:46.190] Build status: STARTED
[20:12:46.191] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:18:0) after 3m34s
[20:16:20.189] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:18:0)
[20:16:20.218] Build status: STARTED
[20:16:20.218] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:19:0) after 2m27s
[20:18:47.240] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:19:0)
[20:18:47.268] Build status: STARTED
[20:18:47.268] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:20:0) after 3m8s
[20:21:55.291] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:20:0)
[20:21:55.319] Build status: STARTED
[20:21:55.319] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:21:0) after 2m14s
[20:24:09.448] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:21:0)
[20:24:09.498] Build status: STARTED
[20:24:09.498] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:22:0) after 9m4s
[20:33:13.492] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:22:0)
[20:33:13.516] Build status: STARTED
[20:33:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:23:0) after 7m25s
[20:40:38.548] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:23:0)
[20:40:38.567] Build status: STARTED
[20:40:38.567] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:24:0) after 3m9s
[20:43:47.626] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:24:0)
[20:43:47.651] Build status: STARTED
[20:43:47.651] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:25:0) after 1m18s
[20:45:05.629] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:25:0)
[20:45:05.651] Build status: STARTED
[20:45:05.651] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:26:0) after 4m49s
[20:49:54.669] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:26:0)
[20:49:54.688] Build status: STARTED
[20:49:54.688] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:27:0) after 1m55s
[20:51:50.043] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:27:0)
[20:51:50.067] Build status: STARTED
[20:51:50.067] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:28:0) after 9m49s
[21:01:39.084] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:28:0)
[21:01:39.104] Build status: STARTED
[21:01:39.104] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:29:0) after 2m57s
[21:04:36.123] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:29:0)
[21:04:36.148] Build status: STARTED
[21:04:36.148] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:30:0) after 3m43s
[21:08:19.267] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:30:0)
[21:08:19.302] Build status: STARTED
[21:08:19.302] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:31:0) after 9m52s
[21:18:11.336] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:31:0)
[21:18:11.364] Build status: STARTED
[21:18:11.364] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:32:0) after 6m51s
[21:25:02.498] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:32:0)
[21:25:02.548] Build status: STARTED
[21:25:02.549] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:33:0) after 3m38s
[21:28:40.677] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:33:0)
[21:28:40.705] Build status: STARTED
[21:28:40.705] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:34:0) after 2m7s
[21:30:47.766] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:34:0)
[21:30:47.790] Build status: STARTED
[21:30:47.790] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:35:0) after 6m34s
[21:37:21.811] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:35:0)
[21:37:21.835] Build status: STARTED
[21:37:21.835] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:36:0) after 7m8s
[21:44:29.857] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:36:0)
[21:44:29.878] Build status: STARTED
[21:44:29.878] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:37:0) after 4m42s
[21:49:11.897] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:37:0)
[21:49:11.920] Build status: STARTED
[21:49:11.920] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:38:0) after 2m13s
[21:51:24.987] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:38:0)
[21:51:25.008] Build status: STARTED
[21:51:25.008] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux ASan Debug:8949788096756359264:39:0) after 7m47s
[21:56:01.858] Received PubSub notification, asking Buildbucket for the build status
[21:56:01.879] Build:
{
"id": "8784358034760951489",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Linux ASan Debug"
},
"number": 135998,
"createdBy": "project:chromium",
"createTime": "2023-04-08T18:59:38.339733315Z",
"startTime": "2023-04-08T19:04:54.324842Z",
"endTime": "2023-04-08T21:56:01.456508276Z",
"updateTime": "2023-04-08T21:56:01.456508276Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6b9682c374b48b026a3898195d17b5bfe5d793a",
"ref": "refs/heads/main"
}
}
}
[21:56:01.879] Invocation finished in 2h56m24.825973536s with status SUCCEEDED