[02:22:42.443] New invocation is queued and will start shortly
[02:22:43.763] Starting the invocation (attempt 1)
[02:22:43.809] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8c3c7f80a11cde75fb92bddd9375bcf04943f53f"
[02:22:43.809] Popped gitiles commit info from properties and tags
[02:22:43.809] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:22:43.809] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:22:43.809] Buildbucket request:
{
"requestId": "8990075870019178448",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8c3c7f80a11cde75fb92bddd9375bcf04943f53f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8990075870019178448"
},
{
"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"
}
}
[02:22:44.343] Scheduled build:
{
"id": "8824645807862472833",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 40010,
"createdBy": "project:chromium",
"createTime": "2022-01-19T02:22:43.867986061Z",
"updateTime": "2022-01-19T02:22:43.867986061Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8c3c7f80a11cde75fb92bddd9375bcf04943f53f",
"ref": "refs/heads/main"
}
}
}
[02:22:44.343] Task URL: https://cr-buildbucket.appspot.com/build/8824645807862472833
[02:22:44.343] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:2:0) after 4m9s
[02:26:53.366] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:2:0)
[02:26:53.402] Build status: SCHEDULED
[02:26:53.402] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:3:0) after 5m37s
[02:28:42.516] Received PubSub notification, asking Buildbucket for the build status
[02:28:42.535] Build status: STARTED
[02:32:30.622] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:3:0)
[02:32:30.642] Build status: STARTED
[02:32:30.642] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:5:0) after 4m48s
[02:37:18.762] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:5:0)
[02:37:18.781] Build status: STARTED
[02:37:18.781] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:6:0) after 5m49s
[02:43:07.801] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:6:0)
[02:43:07.829] Build status: STARTED
[02:43:07.829] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:7:0) after 4m15s
[02:47:22.939] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:7:0)
[02:47:22.972] Build status: STARTED
[02:47:22.972] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:8:0) after 6m33s
[02:53:55.964] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:8:0)
[02:53:55.984] Build status: STARTED
[02:53:55.984] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:9:0) after 7m46s
[03:01:42.004] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:9:0)
[03:01:42.025] Build status: STARTED
[03:01:42.025] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:10:0) after 8m16s
[03:09:58.150] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:10:0)
[03:09:58.173] Build status: STARTED
[03:09:58.173] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:11:0) after 5m34s
[03:15:32.194] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:11:0)
[03:15:32.218] Build status: STARTED
[03:15:32.218] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:12:0) after 2m0s
[03:17:32.349] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:12:0)
[03:17:32.384] Build status: STARTED
[03:17:32.384] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:13:0) after 2m21s
[03:19:53.416] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:13:0)
[03:19:53.444] Build status: STARTED
[03:19:53.444] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:14:0) after 4m53s
[03:24:46.508] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:14:0)
[03:24:46.535] Build status: STARTED
[03:24:46.535] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:15:0) after 8m31s
[03:33:17.621] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:15:0)
[03:33:17.644] Build status: STARTED
[03:33:17.644] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:16:0) after 3m40s
[03:36:57.663] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:16:0)
[03:36:57.754] Build status: STARTED
[03:36:57.754] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:17:0) after 2m11s
[03:39:08.729] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:17:0)
[03:39:08.752] Build status: STARTED
[03:39:08.752] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:18:0) after 8m41s
[03:47:49.767] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:18:0)
[03:47:49.791] Build status: STARTED
[03:47:49.791] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:19:0) after 5m30s
[03:53:20.033] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:19:0)
[03:53:20.052] Build status: STARTED
[03:53:20.052] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:20:0) after 6m41s
[04:00:01.219] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:20:0)
[04:00:01.249] Build status: STARTED
[04:00:01.249] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:21:0) after 8m10s
[04:08:11.273] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:21:0)
[04:08:11.297] Build status: STARTED
[04:08:11.297] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:22:0) after 5m39s
[04:13:50.320] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:22:0)
[04:13:50.338] Build status: STARTED
[04:13:50.338] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:23:0) after 2m2s
[04:15:52.584] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:23:0)
[04:15:52.604] Build status: STARTED
[04:15:52.604] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:24:0) after 5m20s
[04:21:12.623] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:24:0)
[04:21:12.731] Build status: STARTED
[04:21:12.731] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:25:0) after 1m58s
[04:23:10.899] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:25:0)
[04:23:10.919] Build status: STARTED
[04:23:10.919] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:26:0) after 2m38s
[04:25:49.096] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:26:0)
[04:25:49.122] Build status: STARTED
[04:25:49.122] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:27:0) after 1m4s
[04:26:53.150] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:27:0)
[04:26:53.174] Build status: STARTED
[04:26:53.174] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:28:0) after 5m4s
[04:31:57.525] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:28:0)
[04:31:57.556] Build status: STARTED
[04:31:57.556] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:29:0) after 7m47s
[04:39:44.675] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:29:0)
[04:39:44.705] Build status: STARTED
[04:39:44.705] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:30:0) after 4m20s
[04:44:04.733] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:30:0)
[04:44:04.762] Build status: STARTED
[04:44:04.762] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:31:0) after 9m55s
[04:53:59.932] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:31:0)
[04:53:59.955] Build status: STARTED
[04:53:59.955] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:32:0) after 6m16s
[05:00:15.991] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:32:0)
[05:00:16.019] Build status: STARTED
[05:00:16.019] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:33:0) after 9m6s
[05:09:22.041] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:33:0)
[05:09:22.073] Build status: STARTED
[05:09:22.073] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:34:0) after 5m54s
[05:15:16.212] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:34:0)
[05:15:16.233] Build status: STARTED
[05:15:16.233] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:35:0) after 3m12s
[05:18:28.358] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:35:0)
[05:18:28.402] Build status: STARTED
[05:18:28.402] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:36:0) after 8m29s
[05:26:57.425] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:36:0)
[05:26:57.456] Build status: STARTED
[05:26:57.456] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:37:0) after 6m9s
[05:33:06.462] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:37:0)
[05:33:06.497] Build status: STARTED
[05:33:06.497] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:38:0) after 2m5s
[05:35:11.550] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:38:0)
[05:35:11.594] Build status: STARTED
[05:35:11.594] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:39:0) after 6m2s
[05:41:13.618] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:39:0)
[05:41:13.638] Build status: STARTED
[05:41:13.638] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8990075870019178448:40:0) after 5m0s
[05:43:41.625] Received PubSub notification, asking Buildbucket for the build status
[05:43:41.643] Build:
{
"id": "8824645807862472833",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 40010,
"createdBy": "project:chromium",
"createTime": "2022-01-19T02:22:43.867986061Z",
"startTime": "2022-01-19T02:28:41.905138Z",
"endTime": "2022-01-19T05:43:41.239676994Z",
"updateTime": "2022-01-19T05:43:41.239676994Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8c3c7f80a11cde75fb92bddd9375bcf04943f53f",
"ref": "refs/heads/main"
}
}
}
[05:43:41.643] Invocation finished in 3h20m59.216124168s with status SUCCEEDED