[21:49:28.341] New invocation is queued and will start shortly
[21:49:29.770] Starting the invocation (attempt 1)
[21:49:29.808] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e222d1c32b6082a9f4635f35cdaad65d9f382627"
[21:49:29.808] Popped gitiles commit info from properties and tags
[21:49:29.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:29.808] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:29.808] Buildbucket request:
{
"requestId": "8967262624965446960",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e222d1c32b6082a9f4635f35cdaad65d9f382627",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8967262624965446960"
},
{
"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"
}
}
[21:49:30.414] Scheduled build:
{
"id": "8801832562615057473",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 44353,
"createdBy": "project:chromium",
"createTime": "2022-09-27T21:49:29.933827971Z",
"updateTime": "2022-09-27T21:49:29.933827971Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e222d1c32b6082a9f4635f35cdaad65d9f382627",
"ref": "refs/heads/main"
}
}
}
[21:49:30.414] Task URL: https://cr-buildbucket.appspot.com/build/8801832562615057473
[21:49:30.414] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:2:0) after 9m42s
[21:59:12.402] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:2:0)
[21:59:12.427] Build status: SCHEDULED
[21:59:12.427] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:3:0) after 2m7s
[22:00:14.933] Received PubSub notification, asking Buildbucket for the build status
[22:00:15.044] Build status: STARTED
[22:01:19.480] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:3:0)
[22:01:19.508] Build status: STARTED
[22:01:19.508] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:5:0) after 5m26s
[22:06:45.540] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:5:0)
[22:06:45.576] Build status: STARTED
[22:06:45.576] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:6:0) after 1m49s
[22:08:34.606] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:6:0)
[22:08:34.631] Build status: STARTED
[22:08:34.631] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:7:0) after 2m20s
[22:10:54.866] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:7:0)
[22:10:55.087] Build status: STARTED
[22:10:55.087] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:8:0) after 8m53s
[22:19:48.074] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:8:0)
[22:19:48.101] Build status: STARTED
[22:19:48.101] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:9:0) after 5m9s
[22:24:57.188] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:9:0)
[22:24:57.224] Build status: STARTED
[22:24:57.224] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:10:0) after 1m49s
[22:26:46.918] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:10:0)
[22:26:46.947] Build status: STARTED
[22:26:46.947] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:11:0) after 9m18s
[22:36:05.163] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:11:0)
[22:36:05.272] Build status: STARTED
[22:36:05.272] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:12:0) after 4m40s
[22:40:45.327] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:12:0)
[22:40:45.361] Build status: STARTED
[22:40:45.361] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:13:0) after 5m14s
[22:45:59.378] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:13:0)
[22:45:59.421] Build status: STARTED
[22:45:59.421] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:14:0) after 4m19s
[22:50:18.445] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:14:0)
[22:50:18.475] Build status: STARTED
[22:50:18.475] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:15:0) after 2m5s
[22:52:23.498] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:15:0)
[22:52:23.527] Build status: STARTED
[22:52:23.527] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:16:0) after 9m14s
[23:01:37.551] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:16:0)
[23:01:37.576] Build status: STARTED
[23:01:37.576] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:17:0) after 3m12s
[23:04:49.819] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:17:0)
[23:04:49.850] Build status: STARTED
[23:04:49.850] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:18:0) after 9m43s
[23:14:32.987] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:18:0)
[23:14:33.015] Build status: STARTED
[23:14:33.015] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:19:0) after 6m16s
[23:20:49.053] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:19:0)
[23:20:49.087] Build status: STARTED
[23:20:49.087] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:20:0) after 2m42s
[23:23:31.119] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:20:0)
[23:23:31.144] Build status: STARTED
[23:23:31.144] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:21:0) after 4m58s
[23:28:29.380] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:21:0)
[23:28:29.404] Build status: STARTED
[23:28:29.404] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:22:0) after 9m54s
[23:38:23.473] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:22:0)
[23:38:23.501] Build status: STARTED
[23:38:23.501] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:23:0) after 4m58s
[23:43:21.602] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:23:0)
[23:43:21.631] Build status: STARTED
[23:43:21.631] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:24:0) after 6m18s
[23:49:39.651] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:24:0)
[23:49:39.683] Build status: STARTED
[23:49:39.683] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:25:0) after 4m2s
[23:53:41.815] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:25:0)
[23:53:41.880] Build status: STARTED
[23:53:41.880] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:26:0) after 2m32s
[23:56:14.091] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:26:0)
[23:56:14.156] Build status: STARTED
[23:56:14.156] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:27:0) after 1m48s
[23:58:02.268] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:27:0)
[23:58:02.292] Build status: STARTED
[23:58:02.292] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:28:0) after 4m7s
[00:02:09.647] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:28:0)
[00:02:09.689] Build status: STARTED
[00:02:09.689] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:29:0) after 1m11s
[00:03:20.755] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:29:0)
[00:03:20.861] Build status: STARTED
[00:03:20.861] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:30:0) after 9m42s
[00:13:02.909] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:30:0)
[00:13:02.940] Build status: STARTED
[00:13:02.940] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:31:0) after 4m42s
[00:17:45.014] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:31:0)
[00:17:45.090] Build status: STARTED
[00:17:45.090] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:32:0) after 9m12s
[00:26:57.121] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:32:0)
[00:26:57.168] Build status: STARTED
[00:26:57.168] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:33:0) after 2m56s
[00:29:53.262] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:33:0)
[00:29:53.283] Build status: STARTED
[00:29:53.283] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:34:0) after 6m10s
[00:36:03.305] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:34:0)
[00:36:03.396] Build status: STARTED
[00:36:03.396] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:35:0) after 5m7s
[00:41:10.480] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:35:0)
[00:41:10.576] Build status: STARTED
[00:41:10.576] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:36:0) after 6m32s
[00:47:43.683] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:36:0)
[00:47:43.710] Build status: STARTED
[00:47:43.710] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:37:0) after 5m33s
[00:53:16.809] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:37:0)
[00:53:16.848] Build status: STARTED
[00:53:16.848] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:38:0) after 5m0s
[00:58:17.555] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:38:0)
[00:58:17.616] Build status: STARTED
[00:58:17.616] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:39:0) after 2m57s
[01:01:14.715] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:39:0)
[01:01:14.743] Build status: STARTED
[01:01:14.743] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:40:0) after 5m15s
[01:06:29.752] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:40:0)
[01:06:29.776] Build status: STARTED
[01:06:29.776] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:41:0) after 6m44s
[01:13:13.777] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:41:0)
[01:13:13.814] Build status: STARTED
[01:13:13.815] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:42:0) after 2m40s
[01:15:54.047] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:42:0)
[01:15:54.073] Build status: STARTED
[01:15:54.073] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:43:0) after 3m26s
[01:19:20.143] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:43:0)
[01:19:20.168] Build status: STARTED
[01:19:20.168] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:44:0) after 9m25s
[01:28:45.218] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:44:0)
[01:28:45.241] Build status: STARTED
[01:28:45.241] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:45:0) after 2m41s
[01:31:26.592] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:45:0)
[01:31:26.616] Build status: STARTED
[01:31:26.616] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:46:0) after 1m1s
[01:32:27.642] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:46:0)
[01:32:27.677] Build status: STARTED
[01:32:27.677] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:47:0) after 6m56s
[01:39:23.759] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:47:0)
[01:39:23.783] Build status: STARTED
[01:39:23.783] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:48:0) after 6m52s
[01:46:16.181] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:48:0)
[01:46:16.204] Build status: STARTED
[01:46:16.204] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:49:0) after 1m9s
[01:47:25.536] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:49:0)
[01:47:25.564] Build status: STARTED
[01:47:25.564] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:50:0) after 7m42s
[01:55:07.612] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:50:0)
[01:55:07.638] Build status: STARTED
[01:55:07.638] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:51:0) after 5m32s
[02:00:39.887] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:51:0)
[02:00:39.977] Build status: STARTED
[02:00:39.977] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:52:0) after 8m41s
[02:09:21.062] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:52:0)
[02:09:21.091] Build status: STARTED
[02:09:21.091] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:53:0) after 3m24s
[02:12:45.637] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:53:0)
[02:12:45.662] Build status: STARTED
[02:12:45.662] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:54:0) after 5m39s
[02:18:24.690] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:54:0)
[02:18:24.717] Build status: STARTED
[02:18:24.717] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:55:0) after 4m1s
[02:22:25.890] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:55:0)
[02:22:25.918] Build status: STARTED
[02:22:25.918] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:56:0) after 2m59s
[02:25:25.117] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:56:0)
[02:25:25.143] Build status: STARTED
[02:25:25.143] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:8967262624965446960:57:0) after 7m42s
[02:31:07.781] Received PubSub notification, asking Buildbucket for the build status
[02:31:07.804] Build:
{
"id": "8801832562615057473",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 44353,
"createdBy": "project:chromium",
"createTime": "2022-09-27T21:49:29.933827971Z",
"startTime": "2022-09-27T22:00:14.335108Z",
"endTime": "2022-09-28T02:31:07.519930811Z",
"updateTime": "2022-09-28T02:31:07.519930811Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e222d1c32b6082a9f4635f35cdaad65d9f382627",
"ref": "refs/heads/main"
}
}
}
[02:31:07.804] Invocation finished in 4h41m39.496090543s with status SUCCEEDED