[15:35:06.838] New invocation is queued and will start shortly
[15:35:08.549] Starting the invocation (attempt 1)
[15:35:08.590] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/4a7e620bd4ac1d2d513c71128b24044b8b669f25"
[15:35:08.590] Popped gitiles commit info from properties and tags
[15:35:08.590] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:35:08.590] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:35:08.590] Buildbucket request:
{
"requestId": "8985043182998165184",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-run-on-app-dump"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "26a495c65a0d966a82ad0c807dac6adb3c2556cd"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@26a495c65a0d966a82ad0c807dac6adb3c2556cd",
"title": "26a495c65a0d966a82ad0c807dac6adb3c2556cd",
"url": "https://r8.googlesource.com/r8/+/26a495c65a0d966a82ad0c807dac6adb3c2556cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "4a7e620bd4ac1d2d513c71128b24044b8b669f25"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@4a7e620bd4ac1d2d513c71128b24044b8b669f25",
"title": "4a7e620bd4ac1d2d513c71128b24044b8b669f25",
"url": "https://r8.googlesource.com/r8/+/4a7e620bd4ac1d2d513c71128b24044b8b669f25"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "4a7e620bd4ac1d2d513c71128b24044b8b669f25",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8985043182998165184"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-run-on-app-dump"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:35:09.844] Scheduled build:
{
"id": "8819613120264068001",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-run-on-app-dump"
},
"number": 2004,
"createdBy": "project:r8",
"createTime": "2022-03-15T15:35:08.772369093Z",
"updateTime": "2022-03-15T15:35:08.772369093Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "4a7e620bd4ac1d2d513c71128b24044b8b669f25",
"ref": "refs/heads/main"
}
}
}
[15:35:09.844] Task URL: https://cr-buildbucket.appspot.com/build/8819613120264068001
[15:35:09.844] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:2:0) after 6m15s
[15:41:24.966] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:2:0)
[15:41:25.005] Build status: SCHEDULED
[15:41:25.005] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:3:0) after 1m51s
[15:43:16.027] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:3:0)
[15:43:16.065] Build status: SCHEDULED
[15:43:16.065] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:4:0) after 6m12s
[15:49:28.057] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:4:0)
[15:49:28.164] Build status: SCHEDULED
[15:49:28.164] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:5:0) after 9m32s
[15:59:00.375] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:5:0)
[15:59:00.398] Build status: SCHEDULED
[15:59:00.398] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:6:0) after 9m52s
[16:08:52.466] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:6:0)
[16:08:52.499] Build status: SCHEDULED
[16:08:52.499] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:7:0) after 2m9s
[16:11:01.695] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:7:0)
[16:11:01.727] Build status: SCHEDULED
[16:11:01.727] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:8:0) after 4m44s
[16:15:45.744] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:8:0)
[16:15:45.781] Build status: SCHEDULED
[16:15:45.781] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:9:0) after 1m26s
[16:17:12.065] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:9:0)
[16:17:12.320] Build status: SCHEDULED
[16:17:12.320] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:10:0) after 8m18s
[16:25:30.684] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:10:0)
[16:25:30.714] Build status: SCHEDULED
[16:25:30.714] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:11:0) after 5m51s
[16:31:22.069] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:11:0)
[16:31:22.097] Build status: SCHEDULED
[16:31:22.097] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:12:0) after 3m27s
[16:34:49.116] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:12:0)
[16:34:49.260] Build status: SCHEDULED
[16:34:49.260] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:13:0) after 2m7s
[16:36:56.372] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:13:0)
[16:36:56.457] Build status: SCHEDULED
[16:36:56.457] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:14:0) after 4m36s
[16:41:32.638] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:14:0)
[16:41:32.692] Build status: SCHEDULED
[16:41:32.692] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:15:0) after 7m16s
[16:43:47.410] Received PubSub notification, asking Buildbucket for the build status
[16:43:47.438] Build status: STARTED
[16:48:48.712] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:15:0)
[16:48:48.763] Build status: STARTED
[16:48:48.763] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:17:0) after 4m50s
[16:53:38.782] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:17:0)
[16:53:38.812] Build status: STARTED
[16:53:38.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:18:0) after 1m59s
[16:55:37.829] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:18:0)
[16:55:37.857] Build status: STARTED
[16:55:37.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:19:0) after 6m7s
[17:01:45.127] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:19:0)
[17:01:45.284] Build status: STARTED
[17:01:45.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:20:0) after 1m3s
[17:02:48.325] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:20:0)
[17:02:48.364] Build status: STARTED
[17:02:48.364] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:21:0) after 6m5s
[17:08:53.688] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:21:0)
[17:08:53.719] Build status: STARTED
[17:08:53.719] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:22:0) after 4m18s
[17:13:11.720] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:22:0)
[17:13:11.738] Build status: STARTED
[17:13:11.738] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:23:0) after 9m45s
[17:22:56.824] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:23:0)
[17:22:56.860] Build status: STARTED
[17:22:56.860] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:24:0) after 9m31s
[17:32:28.088] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:24:0)
[17:32:28.118] Build status: STARTED
[17:32:28.118] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:25:0) after 3m52s
[17:36:20.185] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:25:0)
[17:36:20.220] Build status: STARTED
[17:36:20.220] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:26:0) after 1m54s
[17:38:14.339] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:26:0)
[17:38:14.367] Build status: STARTED
[17:38:14.367] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:27:0) after 4m53s
[17:43:07.383] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:27:0)
[17:43:07.414] Build status: STARTED
[17:43:07.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:28:0) after 3m40s
[17:46:47.608] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:28:0)
[17:46:48.058] Build status: STARTED
[17:46:48.058] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:29:0) after 6m16s
[17:53:04.093] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:29:0)
[17:53:04.183] Build status: STARTED
[17:53:04.183] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:30:0) after 3m36s
[17:56:40.250] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:30:0)
[17:56:40.293] Build status: STARTED
[17:56:40.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:31:0) after 7m49s
[18:04:29.317] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:31:0)
[18:04:29.359] Build status: STARTED
[18:04:29.359] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:32:0) after 5m48s
[18:10:17.448] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:32:0)
[18:10:17.488] Build status: STARTED
[18:10:17.488] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:33:0) after 8m21s
[18:18:38.777] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:33:0)
[18:18:38.805] Build status: STARTED
[18:18:38.805] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:34:0) after 8m50s
[18:27:28.825] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:34:0)
[18:27:28.857] Build status: STARTED
[18:27:28.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:35:0) after 9m46s
[18:37:15.055] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:35:0)
[18:37:15.272] Build status: STARTED
[18:37:15.272] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:36:0) after 4m42s
[18:41:57.290] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:36:0)
[18:41:57.318] Build status: STARTED
[18:41:57.318] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:37:0) after 7m43s
[18:49:40.342] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:37:0)
[18:49:40.383] Build status: STARTED
[18:49:40.383] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8985043182998165184:38:0) after 5m5s
[18:53:09.794] Received PubSub notification, asking Buildbucket for the build status
[18:53:09.847] Build:
{
"id": "8819613120264068001",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-run-on-app-dump"
},
"number": 2004,
"createdBy": "project:r8",
"createTime": "2022-03-15T15:35:08.772369093Z",
"startTime": "2022-03-15T16:43:46.794516Z",
"endTime": "2022-03-15T18:53:09.568662493Z",
"updateTime": "2022-03-15T18:53:09.568662493Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "4a7e620bd4ac1d2d513c71128b24044b8b669f25",
"ref": "refs/heads/main"
}
}
}
[18:53:09.847] Invocation finished in 3h18m3.065958005s with status FAILED