Deployment Freezes after JIT Build finishes

I have a configuration that is running a back to back deployment. The first deployment pulls a file (data) from one server on a schedule. This always succeeds within about 13 minutes.
30 Minutes later, a schedule starts the 2nd deployment to pull a schema file from another server. The JIT completes it’s clone and zip in about 25 seconds and then hangs until the 30 minute time out.

The only way to correct is a dev has to restart Otto and manually push the Schema deployment. The manual push then completes inside of 13 minutes.

All 3 servers are on v4.15.1 running on Ubuntu.

Hello,

Thats a bit of a bummer.

Can you get us the debug info from the deployment that is failing.

OttoFMS - Troubleshooting OttoFMS.

Todd

Hey @fmgenius ,

This sounds like a persistent issue I’ve been trying to hunt down with builds hanging when they’re almost finished. The debug logs from both the source and destination server would be helpful. If it is happening consistently I would also appreciate if you toggle on debug logging in the ottofms settings before it runs and getting the full otto-debug.log after it completes.

As a side note, it sounds like you’re doing something like a Staging refresh. Is there a specific reason you’re doing it in two separate deployments? OttoFMS supports doing this in one deployment with two sub-deployments.

-Kyle

Nailed it @kduval. It’s a weird situation where we have a dedicated server for an OData connect that was causing performance slowdowns and even crashes. I need the data from one server but we pull schema that has a deactivated account on another and then run a post deployment script to enable it. So we’re dealing with 3 different servers. I haven’t looked at sub-deployments deeply but I thought it wasn’t possible because files were coming from different servers.

TBH, I leave the deployment details up to the respective Tech Leads and I am usually just the one managing infrastructure. So, I catch the heat when deployments fail.

I will turn on the debug and try to catch details from the logs and get that over to you.

1 Like

I’m looking at the deployment history and it appears this inconsistent. We get frequent failures but it’s not every day. I’ve turned on the debug logging. I’ll keep you posted and try to catch it in the act.

Ok. Now have got a few days worth of errors. When I try to upload the log I get “Sorry, new users can’t upload files.” LOL–“new”.

2026-02-24T01:01:31.807Z debug Found 0 uploads in configstore
2026-02-24T03:30:04.700Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 POST: /otto/api/build
2026-02-24T03:30:05.034Z debug local admin api: GET /fmi/admin/api/v2/server/metadata (timeout: 30000ms)
2026-02-24T03:30:05.055Z debug local admin api: GET /fmi/admin/api/v2/server/metadata 401
2026-02-24T03:30:05.077Z debug local admin api: DELETE /fmi/admin/api/v2/user/auth/****** (timeout: 30000ms)
2026-02-24T03:30:05.089Z debug local admin api: DELETE /fmi/admin/api/v2/user/auth/****** 401
2026-02-24T03:30:05.090Z debug local admin api: POST /fmi/admin/api/v2/user/auth (timeout: 30000ms)
2026-02-24T03:30:05.132Z debug local admin api: POST /fmi/admin/api/v2/user/auth 200
2026-02-24T03:30:05.162Z debug Admin API token refreshed
2026-02-24T03:30:05.228Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 locking build
2026-02-24T03:30:05.504Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 max outbox folders is 1. There are 0 folders in the outbox
2026-02-24T03:30:05.505Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 creating outbox folder /fmsbackups/Backups/OttoFMS/outbox/jit_build_268e008d4dc8b002c038424a
2026-02-24T03:30:05.509Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 writing initial manifest for build jit_build_268e008d4dc8b002c038424a
2026-02-24T03:30:05.529Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - starting: starting build “jit_build_268e008d4dc8b002c038424a” with 1 files
2026-02-24T03:30:05.552Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - starting: files in build are FalconMaster.fmp12
2026-02-24T03:30:05.553Z debug listDatabases: Adding list databases to queue
2026-02-24T03:30:05.555Z debug Getting credentials for fmsadmin command LIST FILES -s
2026-02-24T03:30:05.566Z debug Executing fmsadmin command LIST FILES -s
2026-02-24T03:30:07.287Z debug listDatabases: Formatting output
2026-02-24T03:30:07.338Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - starting: files are in valid statuses
2026-02-24T03:30:07.359Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 started build jit_build_268e008d4dc8b002c038424a
2026-02-24T03:30:07.373Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - pre-build script: no pre build script to run
2026-02-24T03:30:07.476Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping: creating zip archive
2026-02-24T03:30:07.527Z debug listDatabases: Adding list databases to queue
2026-02-24T03:30:07.527Z debug Getting credentials for fmsadmin command LIST FILES -s
2026-02-24T03:30:07.528Z debug Executing fmsadmin command LIST FILES -s
2026-02-24T03:30:07.655Z debug 983c9697-73ba-4c51-a576-369e3e585a30 GET: /otto/api/build/jit_build_268e008d4dc8b002c038424a/status
2026-02-24T03:30:07.679Z info 983c9697-73ba-4c51-a576-369e3e585a30 Getting build status for build jit_build_268e008d4dc8b002c038424a
2026-02-24T03:30:08.212Z debug listDatabases: Formatting output
2026-02-24T03:30:08.454Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - backing up - FalconMaster.fmp12: creating clone of FalconMaster.fmp12
2026-02-24T03:30:08.481Z debug listDatabases: Adding list databases to queue
2026-02-24T03:30:08.482Z debug Getting credentials for fmsadmin command LIST FILES -s
2026-02-24T03:30:08.488Z debug Executing fmsadmin command LIST FILES -s
2026-02-24T03:30:09.230Z debug listDatabases: Formatting output
2026-02-24T03:30:09.468Z debug Getting credentials for fmsadmin command BACKUP FalconMaster.fmp12 -d filelinux:/opt/FileMaker/FileMaker Server/Data/ClonesOnly/OttoFMS_Temp_Backups/FalconMaster_2026-02-24T03-30-09-462Z/ --cloneonly
2026-02-24T03:30:09.468Z debug Executing fmsadmin command BACKUP FalconMaster.fmp12 -d filelinux:/opt/FileMaker/FileMaker Server/Data/ClonesOnly/OttoFMS_Temp_Backups/FalconMaster_2026-02-24T03-30-09-462Z/ --cloneonly
2026-02-24T03:30:15.714Z debug 36e37d04-15a3-4d90-99d3-946812cc11dc GET: /otto/api/build/jit_build_268e008d4dc8b002c038424a/status
2026-02-24T03:30:15.735Z info 36e37d04-15a3-4d90-99d3-946812cc11dc Getting build status for build jit_build_268e008d4dc8b002c038424a
2026-02-24T03:30:23.760Z debug bcf6d2e4-4f0f-4996-839f-7776d9417a9d GET: /otto/api/build/jit_build_268e008d4dc8b002c038424a/status
2026-02-24T03:30:23.806Z info bcf6d2e4-4f0f-4996-839f-7776d9417a9d Getting build status for build jit_build_268e008d4dc8b002c038424a
2026-02-24T03:30:26.152Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - backing up - FalconMaster.fmp12: clone of file FalconMaster.fmp12 is done
2026-02-24T03:30:26.189Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - moving files - FalconMaster.fmp12: moving file to outbox folder
2026-02-24T03:30:26.299Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping - FalconMaster.fmp12: adding file to zip: FalconMaster.fmp12
2026-02-24T03:30:26.323Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - backing up - FalconMaster.fmp12: deleting temp folder
2026-02-24T03:30:26.341Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping: finalizing zip archive
2026-02-24T03:30:26.366Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping - manifest.json: adding file to zip: manifest.json
2026-02-24T03:30:27.469Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping - FalconMaster.fmp12: done adding to zip: FalconMaster.fmp12
2026-02-24T03:30:27.551Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping - manifest.json: done adding to zip: manifest.json
2026-02-24T03:30:27.576Z info efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - zipping: zip file finished: /fmsbackups/Backups/OttoFMS/outbox/jit_build_268e008d4dc8b002c038424a/jit_build_268e008d4dc8b002c038424a.build.zip
2026-02-24T03:30:27.629Z debug efdfb9a2-026f-4ecc-9b35-509dab716855 Build jit_build_268e008d4dc8b002c038424a - post-build steps: starting post-build actions
2026-02-24T03:30:31.825Z debug f8b9fca2-1d67-41ed-b78a-8359ba07b45b GET: /otto/api/build/jit_build_268e008d4dc8b002c038424a/status
2026-02-24T03:30:31.845Z info f8b9fca2-1d67-41ed-b78a-8359ba07b45b Getting build status for build jit_build_268e008d4dc8b002c038424a

2026-02-24T04:00:01.349Z debug f29b061b-180e-49c3-a219-e5a35952fb81 GET: /otto/api/build/jit_build_268e008d4dc8b002c038424a/status
2026-02-24T04:00:01.373Z info f29b061b-180e-49c3-a219-e5a35952fb81 Getting build status for build jit_build_268e008d4dc8b002c038424a
2026-02-24T04:00:09.408Z debug c7c47969-4627-428c-afa4-553114fe53c3 POST: /otto/api/build/abort
2026-02-24T04:00:09.429Z info c7c47969-4627-428c-afa4-553114fe53c3 Aborting build
2026-02-24T04:00:09.449Z debug cc86d303-f76a-4660-964b-36de47a7555a DELETE: /otto/api/build/jit_build_268e008d4dc8b002c038424a
2026-02-24T04:00:09.459Z info cc86d303-f76a-4660-964b-36de47a7555a Deleting build jit_build_268e008d4dc8b002c038424a from outbox
2026-02-24T05:01:40.159Z info Debug logging enabled
2026-02-24T05:01:40.165Z info Listening on http://localhost:3061
2026-02-24T05:01:40.166Z info environment “production”
2026-02-24T05:01:40.166Z info version “4.15.1”
2026-02-24T05:01:40.166Z info node version v22.21.1
2026-02-24T05:01:40.166Z debug NODE_EXTRA_CA_CERTS is not set
2026-02-24T05:01:40.168Z debug Recreating Symlink to Otto CLI
2026-02-24T05:01:40.170Z info started watching offsite backup
2026-02-24T05:01:40.173Z info started watching for hostname changes
2026-02-24T05:01:40.174Z info Reverse Proxy already installed
2026-02-24T05:01:40.335Z debug Cleaning up stuck build in database: 242
2026-02-24T05:01:40.372Z debug local admin api: GET /fmi/admin/api/v2/schedules (timeout: 30000ms)
2026-02-24T05:01:40.380Z error Error getting Server ID: fetch failed
2026-02-24T05:01:40.380Z error Error checking installed license Error: No Server ID found
2026-02-24T05:01:40.380Z debug Local Admin API error: fetch failed; Error: connect ECONNREFUSED 127.0.0.1:16001; Path: /fmi/admin/api/v2/schedules; Waiting 15 seconds and trying again
2026-02-24T05:01:40.382Z debug Found 0 uploads in configstore
2026-02-24T05:01:55.384Z debug localAdminApi: Refreshing token
2026-02-24T05:01:55.389Z debug local admin api: DELETE /fmi/admin/api/v2/user/auth/****** (timeout: 30000ms)
2026-02-24T05:01:55.394Z debug local admin api: POST /fmi/admin/api/v2/user/auth (timeout: 30000ms)
2026-02-24T05:01:55.399Z error Error cleaning up offsite schedules Local Admin API error: fetch failed; Error: connect ECONNREFUSED 127.0.0.1:16001; Path: /fmi/admin/api/v2/user/auth

any ideas @toddgeist or @kduval

Hey Rob,

This is getting me closer to an idea of what is going on, I’m going to add some more debug logging to target the issue better and hopefully get us more information. I’ve also put in a couple more safeguards. I’ll have those released in a new version in the next day or two.

-Kyle

Hey Rob,

4.16.1 was just released which includes more debug logging and some safeguards for this. Could you install it and see if the issue is still happening? If so could you send along the pertinent section of the debug log again? Thanks for working with me on this!

-Kyle

ok. updated. I’ll check logs mañana.

1 Like

Grr. @kduval I need another day. Deployments failed for stupid reason on my part – lack of disk space. Need another day.

1 Like

@kduval - so this is interesting. Ever since installing the 16.1 update and adding more space to the drive (who know which thing fixed the problem), I have consistent successes since.

Were you running with a small margin of space available on the server? I guess that’s a possibility, I’d have to look into why it would fail because of space concerns in that part of the build, seems like a weird place to stop for space reasons. Alternatively, 4.16.1 added some more logging there and a new safeguard. could you send me the debug log from the source server for one of those deployments? I’d be interested to see if my new logs show anything interesting.

-Kyle

Hey Rob,

Thanks for sending along your logs. It looks like your builds started succeeding before you installed OttoFMS 4.16, and your logs help me target the spot where it might be hanging better. Did you add more disk space to the source server or the destination server?

-Kyle

Destination server. This was intended to be a low traffic reporting server that the client did not want to commit to much in resources to.