[18:34:04.557] New invocation is queued and will start shortly
[18:34:06.007] Starting the invocation (attempt 1)
[18:34:06.053] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b075ea1a2a6c302d756b367c501a41c4a6d48904"
[18:34:06.053] Popped gitiles commit info from properties and tags
[18:34:06.054] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:34:06.054] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:34:06.054] Buildbucket request:
{
"requestId": "9008496537950823264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxASanLibfuzzer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b075ea1a2a6c302d756b367c501a41c4a6d48904",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9008496537950823264"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTLinuxASanLibfuzzer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:34:10.330] Scheduled build:
{
"id": "8843066475088634304",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxASanLibfuzzer"
},
"number": 11688,
"createdBy": "project:chromium",
"createTime": "2021-06-29T18:34:06.642501Z",
"updateTime": "2021-06-29T18:34:09.882726Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b075ea1a2a6c302d756b367c501a41c4a6d48904",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:34:10.330] Task URL: https://cr-buildbucket.appspot.com/build/8843066475088634304
[18:34:10.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:2:0) after 7m15s
[18:35:16.133] Received PubSub notification, asking Buildbucket for the build status
[18:35:16.169] Build status: STARTED
[18:41:25.421] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:2:0)
[18:41:25.450] Build status: STARTED
[18:41:25.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:4:0) after 1m21s
[18:42:46.768] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:4:0)
[18:42:46.793] Build status: STARTED
[18:42:46.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:5:0) after 9m19s
[18:52:06.062] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:5:0)
[18:52:06.097] Build status: STARTED
[18:52:06.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:6:0) after 1m11s
[18:53:17.118] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:6:0)
[18:53:17.147] Build status: STARTED
[18:53:17.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:7:0) after 4m40s
[18:57:57.208] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:7:0)
[18:57:57.235] Build status: STARTED
[18:57:57.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:8:0) after 5m28s
[19:03:25.517] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:8:0)
[19:03:25.568] Build status: STARTED
[19:03:25.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:9:0) after 3m25s
[19:06:50.650] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:9:0)
[19:06:50.683] Build status: STARTED
[19:06:50.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:10:0) after 3m49s
[19:10:39.725] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:10:0)
[19:10:39.755] Build status: STARTED
[19:10:39.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:11:0) after 3m32s
[19:14:11.832] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:11:0)
[19:14:11.856] Build status: STARTED
[19:14:11.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:12:0) after 7m18s
[19:21:29.937] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:12:0)
[19:21:29.978] Build status: STARTED
[19:21:29.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:13:0) after 6m46s
[19:28:16.099] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:13:0)
[19:28:16.162] Build status: STARTED
[19:28:16.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:14:0) after 6m42s
[19:34:58.184] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:14:0)
[19:34:58.230] Build status: STARTED
[19:34:58.230] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:15:0) after 5m31s
[19:40:29.443] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:15:0)
[19:40:29.574] Build status: STARTED
[19:40:29.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:16:0) after 5m18s
[19:45:47.689] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:16:0)
[19:45:47.723] Build status: STARTED
[19:45:47.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:17:0) after 6m17s
[19:52:04.910] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:17:0)
[19:52:04.943] Build status: STARTED
[19:52:04.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:18:0) after 7m19s
[19:59:24.006] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:18:0)
[19:59:24.036] Build status: STARTED
[19:59:24.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:19:0) after 9m34s
[20:08:58.713] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:19:0)
[20:08:58.755] Build status: STARTED
[20:08:58.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:20:0) after 4m59s
[20:13:58.004] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:20:0)
[20:13:58.048] Build status: STARTED
[20:13:58.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:21:0) after 1m59s
[20:15:57.078] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:21:0)
[20:15:57.116] Build status: STARTED
[20:15:57.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:22:0) after 2m56s
[20:18:53.142] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:22:0)
[20:18:53.178] Build status: STARTED
[20:18:53.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:23:0) after 9m22s
[20:28:15.288] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:23:0)
[20:28:15.319] Build status: STARTED
[20:28:15.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:24:0) after 4m19s
[20:32:34.344] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:24:0)
[20:32:34.431] Build status: STARTED
[20:32:34.431] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:25:0) after 5m5s
[20:37:39.612] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:25:0)
[20:37:39.647] Build status: STARTED
[20:37:39.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:26:0) after 2m6s
[20:39:45.679] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:26:0)
[20:39:45.708] Build status: STARTED
[20:39:45.708] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:27:0) after 7m14s
[20:46:59.858] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:27:0)
[20:46:59.891] Build status: STARTED
[20:46:59.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:28:0) after 9m33s
[20:56:32.895] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:28:0)
[20:56:32.941] Build status: STARTED
[20:56:32.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxASanLibfuzzer:9008496537950823264:29:0) after 7m19s
[20:58:43.505] Received PubSub notification, asking Buildbucket for the build status
[20:58:43.541] Build:
{
"id": "8843066475088634304",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxASanLibfuzzer"
},
"number": 11688,
"createdBy": "project:chromium",
"createTime": "2021-06-29T18:34:06.642501Z",
"startTime": "2021-06-29T18:35:15.179020Z",
"endTime": "2021-06-29T20:58:43.127509805Z",
"updateTime": "2021-06-29T20:58:43.127883564Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b075ea1a2a6c302d756b367c501a41c4a6d48904",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:58:43.541] Invocation finished in 2h24m39.011893165s with status FAILED