[16:57:54.863] New invocation is queued and will start shortly
[16:57:55.933] Starting the invocation (attempt 1)
[16:57:55.962] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/692106dfdc1fd45a2bde47db75d47feb20b93009"
[16:57:55.962] Popped gitiles commit info from properties and tags
[16:57:55.962] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:57:55.964] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:57:55.964] Buildbucket request:
{
"requestId": "9016475120943668144",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "692106dfdc1fd45a2bde47db75d47feb20b93009",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9016475120943668144"
},
{
"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"
}
}
[16:57:56.527] Scheduled build:
{
"id": "8851045059039223984",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 32902,
"createdBy": "project:chromium",
"createTime": "2021-04-02T16:57:56.047836Z",
"updateTime": "2021-04-02T16:57:56.452097Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "692106dfdc1fd45a2bde47db75d47feb20b93009",
"ref": "refs/heads/master"
}
}
}
[16:57:56.527] Task URL: https://cr-buildbucket.appspot.com/build/8851045059039223984
[16:57:56.527] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:2:0) after 8m29s
[16:58:27.998] Received PubSub notification, asking Buildbucket for the build status
[16:58:28.040] Build status: STARTED
[17:06:25.547] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:2:0)
[17:06:25.574] Build status: STARTED
[17:06:25.574] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:4:0) after 3m15s
[17:09:40.809] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:4:0)
[17:09:40.833] Build status: STARTED
[17:09:40.833] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:5:0) after 9m35s
[17:19:15.865] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:5:0)
[17:19:15.966] Build status: STARTED
[17:19:15.966] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:6:0) after 9m34s
[17:28:49.985] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:6:0)
[17:28:50.018] Build status: STARTED
[17:28:50.018] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:7:0) after 8m52s
[17:37:42.066] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:7:0)
[17:37:42.105] Build status: STARTED
[17:37:42.105] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:8:0) after 7m15s
[17:44:57.123] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:8:0)
[17:44:57.208] Build status: STARTED
[17:44:57.208] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:9:0) after 8m14s
[17:53:11.198] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:9:0)
[17:53:11.233] Build status: STARTED
[17:53:11.233] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:10:0) after 3m41s
[17:56:52.570] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:10:0)
[17:56:52.602] Build status: STARTED
[17:56:52.602] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:11:0) after 2m13s
[17:59:05.723] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:11:0)
[17:59:05.800] Build status: STARTED
[17:59:05.800] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:12:0) after 3m3s
[18:02:08.816] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:12:0)
[18:02:08.845] Build status: STARTED
[18:02:08.845] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:13:0) after 5m59s
[18:08:08.002] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:13:0)
[18:08:08.031] Build status: STARTED
[18:08:08.031] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:14:0) after 4m31s
[18:12:39.069] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:14:0)
[18:12:39.103] Build status: STARTED
[18:12:39.103] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:15:0) after 2m34s
[18:15:13.305] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:15:0)
[18:15:13.336] Build status: STARTED
[18:15:13.336] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:16:0) after 5m46s
[18:20:59.683] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:16:0)
[18:20:59.781] Build status: STARTED
[18:20:59.781] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:17:0) after 6m12s
[18:27:11.801] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:17:0)
[18:27:11.837] Build status: STARTED
[18:27:11.838] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:18:0) after 7m36s
[18:34:47.838] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:18:0)
[18:34:47.863] Build status: STARTED
[18:34:47.864] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:19:0) after 1m42s
[18:36:29.882] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:19:0)
[18:36:29.914] Build status: STARTED
[18:36:29.914] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:20:0) after 3m53s
[18:40:22.939] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:20:0)
[18:40:22.982] Build status: STARTED
[18:40:22.982] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:21:0) after 9m36s
[18:49:59.001] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:21:0)
[18:49:59.034] Build status: STARTED
[18:49:59.034] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:22:0) after 2m20s
[18:52:19.059] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:22:0)
[18:52:19.113] Build status: STARTED
[18:52:19.113] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:23:0) after 1m39s
[18:53:58.135] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:23:0)
[18:53:58.170] Build status: STARTED
[18:53:58.170] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:24:0) after 4m33s
[18:58:31.188] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:24:0)
[18:58:31.219] Build status: STARTED
[18:58:31.219] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:25:0) after 9m33s
[19:08:04.247] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:25:0)
[19:08:04.276] Build status: STARTED
[19:08:04.276] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:26:0) after 6m43s
[19:14:47.371] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:26:0)
[19:14:47.395] Build status: STARTED
[19:14:47.395] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:27:0) after 1m25s
[19:16:12.639] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:27:0)
[19:16:12.665] Build status: STARTED
[19:16:12.665] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:28:0) after 2m43s
[19:18:55.929] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:28:0)
[19:18:55.964] Build status: STARTED
[19:18:55.964] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:29:0) after 8m47s
[19:27:42.985] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:29:0)
[19:27:43.013] Build status: STARTED
[19:27:43.013] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:30:0) after 6m35s
[19:34:18.030] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:30:0)
[19:34:18.060] Build status: STARTED
[19:34:18.060] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:31:0) after 1m2s
[19:35:20.036] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:31:0)
[19:35:20.063] Build status: STARTED
[19:35:20.063] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:32:0) after 6m6s
[19:41:26.183] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:32:0)
[19:41:26.213] Build status: STARTED
[19:41:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:33:0) after 1m50s
[19:43:16.351] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:33:0)
[19:43:16.382] Build status: STARTED
[19:43:16.382] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:34:0) after 6m18s
[19:49:34.637] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:34:0)
[19:49:34.663] Build status: STARTED
[19:49:34.663] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:35:0) after 4m57s
[19:54:31.683] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:35:0)
[19:54:31.726] Build status: STARTED
[19:54:31.726] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:36:0) after 9m41s
[20:04:12.882] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:36:0)
[20:04:12.910] Build status: STARTED
[20:04:12.910] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:37:0) after 1m12s
[20:05:24.956] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:37:0)
[20:05:24.994] Build status: STARTED
[20:05:24.994] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Windows ASan:9016475120943668144:38:0) after 6m11s
[20:06:59.482] Received PubSub notification, asking Buildbucket for the build status
[20:06:59.515] Build:
{
"id": "8851045059039223984",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Libfuzzer Upload Windows ASan"
},
"number": 32902,
"createdBy": "project:chromium",
"createTime": "2021-04-02T16:57:56.047836Z",
"startTime": "2021-04-02T16:58:27.483842Z",
"endTime": "2021-04-02T20:06:58.311649Z",
"updateTime": "2021-04-02T20:06:59.149363Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "692106dfdc1fd45a2bde47db75d47feb20b93009",
"ref": "refs/heads/master"
}
}
}
[20:06:59.515] Invocation finished in 3h9m4.667451468s with status SUCCEEDED