2025-09-16T11:00:41.175106125Z /package/admin/s6-overlay/libexec/preinit: info: container permissions: uid=0 (root), euid=0, gid=0 (root), egid=0
2025-09-16T11:00:41.175152539Z /package/admin/s6-overlay/libexec/preinit: info: /run permissions: uid=0 (root), gid=0 (root), perms=oxorgxgruxuwur
2025-09-16T11:00:41.273935395Z [mod-init] Running Docker Modification Logic
2025-09-16T11:00:43.262003153Z [mod-init] Adding linuxserver/mods:universal-redis to container
2025-09-16T11:00:50.031193213Z [mod-init] Downloading linuxserver/mods:universal-redis from lscr.io
2025-09-16T11:00:51.415780167Z [mod-init] Installing linuxserver/mods:universal-redis
2025-09-16T11:00:51.440266011Z [mod-init] linuxserver/mods:universal-redis applied to container
2025-09-16T11:00:51.441051262Z /run/s6/basedir/scripts/rc.init: info: hook /docker-mods exited 0
2025-09-16T11:00:51.465657860Z s6-rc: info: service s6rc-oneshot-runner: starting
2025-09-16T11:00:51.470593107Z s6-rc: info: service s6rc-oneshot-runner successfully started
2025-09-16T11:00:51.470975226Z s6-rc: info: service fix-attrs: starting
2025-09-16T11:00:51.471199418Z s6-rc: info: service init-migrations: starting
2025-09-16T11:00:51.471380072Z s6-rc: info: service init-envfile: starting
2025-09-16T11:00:51.478828630Z s6-rc: info: service fix-attrs successfully started
2025-09-16T11:00:51.478954654Z s6-rc: info: service legacy-cont-init: starting
2025-09-16T11:00:51.479121169Z [migrations] started
2025-09-16T11:00:51.479203545Z [migrations] no migrations found
2025-09-16T11:00:51.479609673Z s6-rc: info: service init-migrations successfully started
2025-09-16T11:00:51.479772801Z s6-rc: info: service init-adduser: starting
2025-09-16T11:00:51.479919737Z s6-rc: info: service init-envfile successfully started
2025-09-16T11:00:51.485636378Z s6-rc: info: service legacy-cont-init successfully started
2025-09-16T11:00:51.616894205Z usermod: no changes
2025-09-16T11:00:51.624020314Z ───────────────────────────────────────
2025-09-16T11:00:51.624054242Z 
2025-09-16T11:00:51.624064393Z       ██╗     ███████╗██╗ ██████╗
2025-09-16T11:00:51.624073331Z       ██║     ██╔════╝██║██╔═══██╗
2025-09-16T11:00:51.624081818Z       ██║     ███████╗██║██║   ██║
2025-09-16T11:00:51.624090275Z       ██║     ╚════██║██║██║   ██║
2025-09-16T11:00:51.624098992Z       ███████╗███████║██║╚██████╔╝
2025-09-16T11:00:51.624107409Z       ╚══════╝╚══════╝╚═╝ ╚═════╝
2025-09-16T11:00:51.624116147Z 
2025-09-16T11:00:51.624124243Z    Brought to you by linuxserver.io
2025-09-16T11:00:51.624164614Z ───────────────────────────────────────
2025-09-16T11:00:51.624598227Z 
2025-09-16T11:00:51.624623368Z To support LSIO projects visit:
2025-09-16T11:00:51.624633158Z https://www.linuxserver.io/donate/
2025-09-16T11:00:51.624641575Z 
2025-09-16T11:00:51.624649791Z ───────────────────────────────────────
2025-09-16T11:00:51.624659240Z GID/UID
2025-09-16T11:00:51.624667447Z ───────────────────────────────────────
2025-09-16T11:00:51.630453347Z 
2025-09-16T11:00:51.630477355Z User UID:    911
2025-09-16T11:00:51.630485903Z User GID:    911
2025-09-16T11:00:51.630494149Z ───────────────────────────────────────
2025-09-16T11:00:51.633557117Z Linuxserver.io version: v0.123.0-ls103
2025-09-16T11:00:51.634042935Z Build-date: 2025-09-16T10:48:47+00:00
2025-09-16T11:00:51.634064328Z ───────────────────────────────────────
2025-09-16T11:00:51.634075170Z     
2025-09-16T11:00:51.657975114Z s6-rc: info: service init-adduser successfully started
2025-09-16T11:00:51.658194887Z s6-rc: info: service init-device-perms: starting
2025-09-16T11:00:51.666212751Z s6-rc: info: service init-device-perms successfully started
2025-09-16T11:00:51.666525902Z s6-rc: info: service init-os-end: starting
2025-09-16T11:00:51.669159796Z s6-rc: info: service init-os-end successfully started
2025-09-16T11:00:51.669578058Z s6-rc: info: service init-manyfold-config: starting
2025-09-16T11:00:51.670917495Z s6-rc: info: service init-config: starting
2025-09-16T11:00:51.672346242Z s6-rc: info: service init-config successfully started
2025-09-16T11:00:51.672359418Z s6-rc: info: service init-crontab-config: starting
2025-09-16T11:00:51.689822982Z s6-rc: info: service init-crontab-config successfully started
2025-09-16T11:00:51.701635027Z **** Running Manyfold database init. ****
2025-09-16T11:00:54.347551588Z `/root` is not writable.
2025-09-16T11:00:54.347734917Z Bundler will use `/tmp/bundler20250916-173-t95dr3173' as your home directory temporarily.
2025-09-16T11:00:59.818620482Z /app/www/vendor/bundle/ruby/3.4.0/gems/rswag-ui-2.16.0/lib/rswag/ui/configuration.rb:1: warning: /usr/lib/ruby/3.4.0/ostruct.rb was loaded from the standard library, but will no longer be part of the default gems starting from Ruby 3.5.0.
2025-09-16T11:00:59.818652797Z You can add ostruct to your Gemfile or gemspec to silence this warning.
2025-09-16T11:00:59.818658298Z Also please contact the author of rswag-ui-2.16.0 to request adding ostruct into its gemspec.
2025-09-16T11:01:03.539370074Z Server: f09130fa3d8f, Context: rails, Role: web, data: {cpu: {one_min: 5.07, five_min: 3.76, fifteen_min: 2.84}, memory: 0.201756672e9, disk: {available: 841846853632, total: 938656133120, used: 96809279488}}
2025-09-16T11:01:03.539408341Z Monitor error: Connection refused - connect(2) for 127.0.0.1:6379 (redis://localhost:6379)
2025-09-16T11:01:03.539418050Z INFO  2025-09-16T11:01:01.708Z pid=173 tid=28l: Sidekiq 8.0.7 connecting to Redis with options {size: 10, pool_name: "internal", url: nil}
2025-09-16T11:01:03.539427609Z WARNING: table: "settings" does not exist or not database connection, `SiteSettings.anonymous_usage_id` fallback to returns the default value.
2025-09-16T11:01:03.539436287Z Migrating to CreateLibraries (20210130201037)
2025-09-16T11:01:03.539444844Z == 20210130201037 CreateLibraries: migrating ==================================
2025-09-16T11:01:03.539453151Z -- create_table(:libraries)
2025-09-16T11:01:03.539461077Z    -> 0.0028s
2025-09-16T11:01:03.539469604Z == 20210130201037 CreateLibraries: migrated (0.0029s) =========================
2025-09-16T11:01:03.539477760Z 
2025-09-16T11:01:03.539485747Z Migrating to CreateModels (20210130203243)
2025-09-16T11:01:03.539493803Z == 20210130203243 CreateModels: migrating =====================================
2025-09-16T11:01:03.539501869Z -- create_table(:models)
2025-09-16T11:01:03.539509875Z    -> 0.0010s
2025-09-16T11:01:03.539517801Z == 20210130203243 CreateModels: migrated (0.0010s) ============================
2025-09-16T11:01:03.539525867Z 
2025-09-16T11:01:03.539533753Z Migrating to CreateParts (20210205230918)
2025-09-16T11:01:03.539541709Z == 20210205230918 CreateParts: migrating ======================================
2025-09-16T11:01:03.539549745Z -- create_table(:parts)
2025-09-16T11:01:03.539557631Z    -> 0.0032s
2025-09-16T11:01:03.539565627Z == 20210205230918 CreateParts: migrated (0.0033s) =============================
2025-09-16T11:01:03.539573794Z 
2025-09-16T11:01:03.539581559Z Migrating to AddFieldsToParts (20210210153744)
2025-09-16T11:01:03.539589556Z == 20210210153744 AddFieldsToParts: migrating =================================
2025-09-16T11:01:03.539597742Z -- add_column(:parts, :presupported, :boolean, {default: false, null: false})
2025-09-16T11:01:03.539605838Z    -> 0.0008s
2025-09-16T11:01:03.539613694Z -- add_column(:parts, :printed, :boolean, {default: false, null: false})
2025-09-16T11:01:03.539621730Z    -> 0.0003s
2025-09-16T11:01:03.539629626Z == 20210210153744 AddFieldsToParts: migrated (0.0011s) ========================
2025-09-16T11:01:03.539637723Z 
2025-09-16T11:01:03.539645458Z Migrating to ActsAsTaggableOnMigration (20210212143001)
2025-09-16T11:01:03.539653494Z == 20210212143001 ActsAsTaggableOnMigration: migrating ========================
2025-09-16T11:01:03.539680178Z -- create_table(:tags, {id: :integer})
2025-09-16T11:01:03.539690248Z    -> 0.0037s
2025-09-16T11:01:03.539698184Z -- create_table(:taggings, {id: :integer})
2025-09-16T11:01:03.539706170Z    -> 0.0017s
2025-09-16T11:01:03.539714106Z -- add_index(:taggings, :tag_id, {name: "index_taggings_on_tag_id"})
2025-09-16T11:01:03.539722453Z    -> 0.0002s
2025-09-16T11:01:03.539731622Z -- add_index(:taggings, [:taggable_id, :taggable_type, :context], {name: "taggings_taggable_context_idx"})
2025-09-16T11:01:03.539740119Z    -> 0.0019s
2025-09-16T11:01:03.539748015Z == 20210212143001 ActsAsTaggableOnMigration: migrated (0.0079s) ===============
2025-09-16T11:01:03.539756101Z 
2025-09-16T11:01:03.539763826Z Migrating to AddMissingUniqueIndices (20210212143002)
2025-09-16T11:01:03.539771843Z == 20210212143002 AddMissingUniqueIndices: migrating ==========================
2025-09-16T11:01:03.539780039Z -- add_index(:tags, :name, {unique: true, name: "index_tags_on_name"})
2025-09-16T11:01:03.539788306Z    -> 0.0003s
2025-09-16T11:01:03.539796202Z -- index_exists?(:taggings, :tag_id, {name: "index_taggings_on_tag_id"})
2025-09-16T11:01:03.539804318Z    -> 0.0004s
2025-09-16T11:01:03.539812114Z -- remove_foreign_key(:taggings, :tags)
2025-09-16T11:01:03.539820080Z    -> 0.1605s
2025-09-16T11:01:03.539827986Z -- remove_index(:taggings, :tag_id, {name: "index_taggings_on_tag_id"})
2025-09-16T11:01:03.539836132Z    -> 0.0146s
2025-09-16T11:01:03.539843968Z -- remove_index(:taggings, nil, {name: "taggings_taggable_context_idx"})
2025-09-16T11:01:03.539871173Z    -> 0.0002s
2025-09-16T11:01:03.539879109Z -- add_index(:taggings, [:tag_id, :taggable_id, :taggable_type, :context, :tagger_id, :tagger_type], {unique: true, name: "taggings_idx"})
2025-09-16T11:01:03.539887606Z    -> 0.0048s
2025-09-16T11:01:03.539895482Z -- add_foreign_key(:taggings, :tags, {column: "tag_id"})
2025-09-16T11:01:03.539903688Z    -> 0.0659s
2025-09-16T11:01:03.539911524Z == 20210212143002 AddMissingUniqueIndices: migrated (0.2777s) =================
2025-09-16T11:01:03.539919590Z 
2025-09-16T11:01:03.539927316Z Migrating to AddTaggingsCounterCacheToTags (20210212143003)
2025-09-16T11:01:03.539935362Z == 20210212143003 AddTaggingsCounterCacheToTags: migrating ====================
2025-09-16T11:01:03.539943418Z -- add_column(:tags, :taggings_count, :integer, {default: 0})
2025-09-16T11:01:03.539951454Z    -> 0.0338s
2025-09-16T11:01:03.539959300Z == 20210212143003 AddTaggingsCounterCacheToTags: migrated (0.1084s) ===========
2025-09-16T11:01:03.539967376Z 
2025-09-16T11:01:03.539975112Z Migrating to AddMissingTaggableIndex (20210212143004)
2025-09-16T11:01:03.539993188Z == 20210212143004 AddMissingTaggableIndex: migrating ==========================
2025-09-16T11:01:03.540001385Z -- add_index(:taggings, [:taggable_id, :taggable_type, :context], {name: "taggings_taggable_context_idx"})
2025-09-16T11:01:03.540009802Z    -> 0.0002s
2025-09-16T11:01:03.540017637Z == 20210212143004 AddMissingTaggableIndex: migrated (0.0003s) =================
2025-09-16T11:01:03.540025944Z 
2025-09-16T11:01:03.540033690Z Migrating to ChangeCollationForTagNames (20210212143005)
2025-09-16T11:01:03.540041746Z == 20210212143005 ChangeCollationForTagNames: migrating =======================
2025-09-16T11:01:03.540049752Z == 20210212143005 ChangeCollationForTagNames: migrated (0.0008s) ==============
2025-09-16T11:01:03.540057869Z 
2025-09-16T11:01:03.540065654Z Migrating to AddMissingIndexesOnTaggings (20210212143006)
2025-09-16T11:01:03.540073781Z == 20210212143006 AddMissingIndexesOnTaggings: migrating ======================
2025-09-16T11:01:03.540082358Z -- index_exists?(:taggings, :tag_id, {name: "index_taggings_on_tag_id"})
2025-09-16T11:01:03.540090624Z    -> 0.0074s
2025-09-16T11:01:03.540098601Z -- add_index(:taggings, :tag_id, {name: "index_taggings_on_tag_id"})
2025-09-16T11:01:03.540106787Z    -> 0.0002s
2025-09-16T11:01:03.540114683Z -- index_exists?(:taggings, :taggable_id, {name: "index_taggings_on_taggable_id"})
2025-09-16T11:01:03.540122879Z    -> 0.0056s
2025-09-16T11:01:03.540130795Z -- add_index(:taggings, :taggable_id, {name: "index_taggings_on_taggable_id"})
2025-09-16T11:01:03.540139062Z    -> 0.0014s
2025-09-16T11:01:03.540147018Z -- index_exists?(:taggings, :taggable_type, {name: "index_taggings_on_taggable_type"})
2025-09-16T11:01:03.540155265Z    -> 0.0004s
2025-09-16T11:01:03.540163120Z -- add_index(:taggings, :taggable_type, {name: "index_taggings_on_taggable_type"})
2025-09-16T11:01:03.540171297Z    -> 0.0001s
2025-09-16T11:01:03.540179213Z -- index_exists?(:taggings, :tagger_id, {name: "index_taggings_on_tagger_id"})
2025-09-16T11:01:03.540187389Z    -> 0.0037s
2025-09-16T11:01:03.540195245Z -- add_index(:taggings, :tagger_id, {name: "index_taggings_on_tagger_id"})
2025-09-16T11:01:03.540203502Z    -> 0.0001s
2025-09-16T11:01:03.540211418Z -- index_exists?(:taggings, :context, {name: "index_taggings_on_context"})
2025-09-16T11:01:03.540219524Z    -> 0.0005s
2025-09-16T11:01:03.540227400Z -- add_index(:taggings, :context, {name: "index_taggings_on_context"})
2025-09-16T11:01:03.540235556Z    -> 0.0001s
2025-09-16T11:01:03.540243442Z -- index_exists?(:taggings, [:tagger_id, :tagger_type], {name: "index_taggings_on_tagger_id_and_tagger_type"})
2025-09-16T11:01:03.540251779Z    -> 0.0006s
2025-09-16T11:01:03.540268873Z -- add_index(:taggings, [:tagger_id, :tagger_type], {name: "index_taggings_on_tagger_id_and_tagger_type"})
2025-09-16T11:01:03.540277320Z    -> 0.0001s
2025-09-16T11:01:03.540285186Z -- index_exists?(:taggings, [:taggable_id, :taggable_type, :tagger_id, :context], {name: "taggings_idy"})
2025-09-16T11:01:03.540293543Z    -> 0.0015s
2025-09-16T11:01:03.540301449Z -- add_index(:taggings, [:taggable_id, :taggable_type, :tagger_id, :context], {name: "taggings_idy"})
2025-09-16T11:01:03.540309736Z    -> 0.0002s
2025-09-16T11:01:03.540317762Z == 20210212143006 AddMissingIndexesOnTaggings: migrated (0.0223s) =============
2025-09-16T11:01:03.540325838Z 
2025-09-16T11:01:03.540333574Z Migrating to AddPreviewPartToModel (20210220204055)
2025-09-16T11:01:03.540341590Z == 20210220204055 AddPreviewPartToModel: migrating ============================
2025-09-16T11:01:03.540349626Z -- add_reference(:models, :preview_part, {null: true, foreign_key: {to_table: :parts}})
2025-09-16T11:01:03.540357772Z    -> 0.2293s
2025-09-16T11:01:03.540365668Z == 20210220204055 AddPreviewPartToModel: migrated (0.2293s) ===================
2025-09-16T11:01:03.540373684Z 
2025-09-16T11:01:03.540381450Z Migrating to CreateCreators (20210225232756)
2025-09-16T11:01:03.540389446Z == 20210225232756 CreateCreators: migrating ===================================
2025-09-16T11:01:03.540397502Z -- create_table(:creators)
2025-09-16T11:01:03.540405478Z    -> 0.0132s
2025-09-16T11:01:03.540413344Z == 20210225232756 CreateCreators: migrated (0.0133s) ==========================
2025-09-16T11:01:03.540421491Z 
2025-09-16T11:01:03.540429697Z Migrating to AddCreatorToModels (20210225232841)
2025-09-16T11:01:03.540437774Z == 20210225232841 AddCreatorToModels: migrating ===============================
2025-09-16T11:01:03.540445800Z -- add_reference(:models, :creator, {null: true, foreign_key: true})
2025-09-16T11:01:03.540453846Z    -> 0.0491s
2025-09-16T11:01:03.540461792Z == 20210225232841 AddCreatorToModels: migrated (0.0492s) ======================
2025-09-16T11:01:03.540469868Z 
2025-09-16T11:01:03.540477614Z Migrating to CreateLinks (20210301230017)
2025-09-16T11:01:03.540485580Z == 20210301230017 CreateLinks: migrating ======================================
2025-09-16T11:01:03.540493596Z -- create_table(:links)
2025-09-16T11:01:03.540501512Z    -> 0.0009s
2025-09-16T11:01:03.540509418Z == 20210301230017 CreateLinks: migrated (0.0010s) =============================
2025-09-16T11:01:03.540517464Z 
2025-09-16T11:01:03.540525119Z Migrating to DropLinkFieldsFromCreators (20210302161202)
2025-09-16T11:01:03.540533166Z == 20210302161202 DropLinkFieldsFromCreators: migrating =======================
2025-09-16T11:01:03.540550300Z -- remove_column(:creators, :thingiverse_user, :string)
2025-09-16T11:01:03.540558416Z    -> 0.0620s
2025-09-16T11:01:03.540566382Z -- remove_column(:creators, :cults3d_user, :string)
2025-09-16T11:01:03.540574439Z    -> 0.0041s
2025-09-16T11:01:03.540582475Z -- remove_column(:creators, :mmf_user, :string)
2025-09-16T11:01:03.540591473Z    -> 0.0133s
2025-09-16T11:01:03.540599349Z -- remove_column(:creators, :cgtrader_user, :string)
2025-09-16T11:01:03.540607385Z    -> 0.0034s
2025-09-16T11:01:03.540615271Z == 20210302161202 DropLinkFieldsFromCreators: migrated (0.0847s) ==============
2025-09-16T11:01:03.540623277Z 
2025-09-16T11:01:03.540630993Z Migrating to RemoveFkConstraintFromModelPreviewPart (20210314185822)
2025-09-16T11:01:03.540639019Z == 20210314185822 RemoveFkConstraintFromModelPreviewPart: migrating ===========
2025-09-16T11:01:03.540647085Z -- remove_foreign_key(:models, :parts, {column: :preview_part_id})
2025-09-16T11:01:03.540655061Z    -> 0.0526s
2025-09-16T11:01:03.540662897Z == 20210314185822 RemoveFkConstraintFromModelPreviewPart: migrated (0.0526s) ==
2025-09-16T11:01:03.540670973Z 
2025-09-16T11:01:03.540678679Z Migrating to AddYUpToParts (20210318202638)
2025-09-16T11:01:03.540686595Z == 20210318202638 AddYUpToParts: migrating ====================================
2025-09-16T11:01:03.540694561Z -- add_column(:parts, :y_up, :boolean, {default: false, null: false})
2025-09-16T11:01:03.540702607Z    -> 0.0139s
2025-09-16T11:01:03.540710473Z == 20210318202638 AddYUpToParts: migrated (0.0139s) ===========================
2025-09-16T11:01:03.540718489Z 
2025-09-16T11:01:03.540726194Z Migrating to CreateImages (20210321164508)
2025-09-16T11:01:03.540734130Z == 20210321164508 CreateImages: migrating =====================================
2025-09-16T11:01:03.540742196Z -- create_table(:images)
2025-09-16T11:01:03.540750092Z    -> 0.0150s
2025-09-16T11:01:03.540757918Z == 20210321164508 CreateImages: migrated (0.0150s) ============================
2025-09-16T11:01:03.540765964Z 
2025-09-16T11:01:03.540773730Z Migrating to CreateDelayedJobs (20210330215825)
2025-09-16T11:01:03.540781766Z == 20210330215825 CreateDelayedJobs: migrating ================================
2025-09-16T11:01:03.540789832Z -- create_table(:delayed_jobs)
2025-09-16T11:01:03.540797758Z    -> 0.0083s
2025-09-16T11:01:04.532414488Z -- add_index(:delayed_jobs, [:priority, :run_at], {name: "delayed_jobs_priority"})
2025-09-16T11:01:04.532451773Z    -> 0.0002s
2025-09-16T11:01:04.532461272Z == 20210330215825 CreateDelayedJobs: migrated (0.0085s) =======================
2025-09-16T11:01:04.532468938Z 
2025-09-16T11:01:04.532490641Z Migrating to RenamePartsToModelFiles (20220105233138)
2025-09-16T11:01:04.532498016Z == 20220105233138 RenamePartsToModelFiles: migrating ==========================
2025-09-16T11:01:04.532505682Z -- rename_table(:parts, :model_files)
2025-09-16T11:01:04.532512746Z    -> 0.0016s
2025-09-16T11:01:04.532519810Z -- rename_column(:models, :preview_part_id, :preview_file_id)
2025-09-16T11:01:04.532526995Z    -> 0.0324s
2025-09-16T11:01:04.532534019Z == 20220105233138 RenamePartsToModelFiles: migrated (0.0341s) =================
2025-09-16T11:01:04.532541143Z 
2025-09-16T11:01:04.532548017Z Migrating to RemoveImagesTable (20220106220519)
2025-09-16T11:01:04.532555051Z == 20220106220519 RemoveImagesTable: migrating ================================
2025-09-16T11:01:04.532562266Z -- drop_table(:images)
2025-09-16T11:01:04.532569250Z    -> 0.0003s
2025-09-16T11:01:04.532576274Z == 20220106220519 RemoveImagesTable: migrated (0.0003s) =======================
2025-09-16T11:01:04.532583398Z 
2025-09-16T11:01:04.532590242Z Migrating to AddServiceNameToActiveStorageBlobs (20220612220115)
2025-09-16T11:01:04.532597276Z == 20220612220115 AddServiceNameToActiveStorageBlobs: migrating ===============
2025-09-16T11:01:04.532604370Z -- table_exists?(:active_storage_blobs)
2025-09-16T11:01:04.532611415Z    -> 0.0001s
2025-09-16T11:01:04.532618429Z == 20220612220115 AddServiceNameToActiveStorageBlobs: migrated (0.0001s) ======
2025-09-16T11:01:04.532626044Z 
2025-09-16T11:01:04.532632978Z Migrating to CreateActiveStorageVariantRecords (20220612220116)
2025-09-16T11:01:04.532640072Z == 20220612220116 CreateActiveStorageVariantRecords: migrating ================
2025-09-16T11:01:04.532647267Z -- table_exists?(:active_storage_blobs)
2025-09-16T11:01:04.532654291Z    -> 0.0001s
2025-09-16T11:01:04.532661305Z == 20220612220116 CreateActiveStorageVariantRecords: migrated (0.0001s) =======
2025-09-16T11:01:04.532668450Z 
2025-09-16T11:01:04.532675383Z Migrating to RemoveNotNullOnActiveStorageBlobsChecksum (20220612220117)
2025-09-16T11:01:04.532682548Z == 20220612220117 RemoveNotNullOnActiveStorageBlobsChecksum: migrating ========
2025-09-16T11:01:04.532689702Z -- table_exists?(:active_storage_blobs)
2025-09-16T11:01:04.532696726Z    -> 0.0001s
2025-09-16T11:01:04.532703791Z == 20220612220117 RemoveNotNullOnActiveStorageBlobsChecksum: migrated (0.0001s) 
2025-09-16T11:01:04.532710995Z 
2025-09-16T11:01:04.532717839Z Migrating to DeviseCreateUsers (20220614210023)
2025-09-16T11:01:04.532724863Z == 20220614210023 DeviseCreateUsers: migrating ================================
2025-09-16T11:01:04.532732078Z -- create_table(:users)
2025-09-16T11:01:04.532739032Z    -> 0.0012s
2025-09-16T11:01:04.532754513Z -- add_index(:users, :email, {unique: true, name: "index_users_on_email"})
2025-09-16T11:01:04.532761998Z    -> 0.0016s
2025-09-16T11:01:04.532769132Z == 20220614210023 DeviseCreateUsers: migrated (0.0029s) =======================
2025-09-16T11:01:04.532776327Z 
2025-09-16T11:01:04.532783231Z Migrating to AddUsernameToUsers (20220614211256)
2025-09-16T11:01:04.532790255Z == 20220614211256 AddUsernameToUsers: migrating ===============================
2025-09-16T11:01:04.532798551Z -- add_column(:users, :username, :string, {null: false})
2025-09-16T11:01:04.532805786Z    -> 0.0229s
2025-09-16T11:01:04.532812800Z -- add_index(:users, :username, {unique: true, name: "index_users_on_username"})
2025-09-16T11:01:04.532820105Z    -> 0.0001s
2025-09-16T11:01:04.532827109Z == 20220614211256 AddUsernameToUsers: migrated (0.0231s) ======================
2025-09-16T11:01:04.532834243Z 
2025-09-16T11:01:04.532841077Z Migrating to AddAdminToUsers (20220614213902)
2025-09-16T11:01:04.532848973Z == 20220614213902 AddAdminToUsers: migrating ==================================
2025-09-16T11:01:04.532856999Z -- add_column(:users, :admin, :boolean, {default: false, null: false})
2025-09-16T11:01:04.532865015Z    -> 0.0005s
2025-09-16T11:01:04.532872861Z == 20220614213902 AddAdminToUsers: migrated (0.0005s) =========================
2025-09-16T11:01:04.532880897Z 
2025-09-16T11:01:04.532888553Z Migrating to AddPaginationSettingsToUser (20220617122809)
2025-09-16T11:01:04.532896459Z == 20220617122809 AddPaginationSettingsToUser: migrating ======================
2025-09-16T11:01:04.532904545Z -- add_column(:users, :pagination_settings, :json, {default: {"models" => true, "creators" => true, "collections" => true, "per_page" => 12}})
2025-09-16T11:01:04.532913753Z    -> 0.0013s
2025-09-16T11:01:04.532921740Z == 20220617122809 AddPaginationSettingsToUser: migrated (0.0013s) =============
2025-09-16T11:01:04.532929836Z 
2025-09-16T11:01:04.532937521Z Migrating to CreateSettings (20220619174307)
2025-09-16T11:01:04.532945397Z == 20220619174307 CreateSettings: migrating ===================================
2025-09-16T11:01:04.532953443Z -- create_table(:settings)
2025-09-16T11:01:04.532961309Z    -> 0.0028s
2025-09-16T11:01:04.532971530Z -- add_index(:settings, [:var], {unique: true, name: "index_settings_on_var"})
2025-09-16T11:01:04.532979736Z    -> 0.0002s
2025-09-16T11:01:04.532987672Z == 20220619174307 CreateSettings: migrated (0.0031s) ==========================
2025-09-16T11:01:04.532995698Z 
2025-09-16T11:01:04.533003414Z Migrating to AddScaleFactorToModels (20220626152444)
2025-09-16T11:01:04.533011390Z == 20220626152444 AddScaleFactorToModels: migrating ===========================
2025-09-16T11:01:04.533028805Z -- add_column(:models, :scale_factor, :decimal, {null: false, default: 100.0})
2025-09-16T11:01:04.533036952Z    -> 0.0112s
2025-09-16T11:01:04.533044847Z == 20220626152444 AddScaleFactorToModels: migrated (0.0137s) ==================
2025-09-16T11:01:04.533052874Z 
2025-09-16T11:01:04.533060659Z Migrating to AddDigestToModelFiles (20220702202932)
2025-09-16T11:01:04.533068605Z == 20220702202932 AddDigestToModelFiles: migrating ============================
2025-09-16T11:01:04.533076651Z -- add_column(:model_files, :digest, :string)
2025-09-16T11:01:04.533084577Z    -> 0.0026s
2025-09-16T11:01:04.533092433Z -- add_index(:model_files, :digest, {name: "index_model_files_on_digest"})
2025-09-16T11:01:04.533100550Z    -> 0.0001s
2025-09-16T11:01:04.533108626Z == 20220702202932 AddDigestToModelFiles: migrated (0.0048s) ===================
2025-09-16T11:01:04.533116652Z 
2025-09-16T11:01:04.533124458Z Migrating to AddRendererSettingsToUsers (20221128165903)
2025-09-16T11:01:04.533132424Z == 20221128165903 AddRendererSettingsToUsers: migrating =======================
2025-09-16T11:01:04.533140901Z -- add_column(:users, :renderer_settings, :json, {default: {"grid_width" => 200, "grid_depth" => 200, "show_grid" => true, "enable_pan_zoom" => false, "background_colour" => "#000000", "object_colour" => "#ffffff", "render_style" => "original"}})
2025-09-16T11:01:04.533152655Z    -> 0.0008s
2025-09-16T11:01:04.533160771Z == 20221128165903 AddRendererSettingsToUsers: migrated (0.0011s) ==============
2025-09-16T11:01:04.533168807Z 
2025-09-16T11:01:04.533176513Z Migrating to CreateProblems (20221210001132)
2025-09-16T11:01:04.533184469Z == 20221210001132 CreateProblems: migrating ===================================
2025-09-16T11:01:04.533192575Z -- create_table(:problems)
2025-09-16T11:01:04.533200431Z    -> 0.0009s
2025-09-16T11:01:04.533208297Z == 20221210001132 CreateProblems: migrated (0.0012s) ==========================
2025-09-16T11:01:04.533216343Z 
2025-09-16T11:01:04.533224028Z Migrating to AddNewDefaultsToRendererSettings (20221214230757)
2025-09-16T11:01:04.533232004Z == 20221214230757 AddNewDefaultsToRendererSettings: migrating =================
2025-09-16T11:01:04.533239980Z == 20221214230757 AddNewDefaultsToRendererSettings: migrated (0.0173s) ========
2025-09-16T11:01:04.533248037Z 
2025-09-16T11:01:04.533255802Z Migrating to ActsAsFavoritorMigration (20221219204414)
2025-09-16T11:01:04.533263879Z == 20221219204414 ActsAsFavoritorMigration: migrating =========================
2025-09-16T11:01:04.533271905Z -- create_table(:favorites, {force: true})
2025-09-16T11:01:04.533279791Z    -> 0.0043s
2025-09-16T11:01:04.533297055Z -- add_index(:favorites, ["favoritor_id", "favoritor_type"], {name: "fk_favorites"})
2025-09-16T11:01:04.533305693Z    -> 0.0001s
2025-09-16T11:01:04.533313549Z -- add_index(:favorites, ["favoritable_id", "favoritable_type"], {name: "fk_favoritables"})
2025-09-16T11:01:04.533321996Z    -> 0.0001s
2025-09-16T11:01:04.533329952Z -- add_index(:favorites, ["favoritable_type", "favoritable_id", "favoritor_type", "favoritor_id", "scope"], {name: "uniq_favorites__and_favoritables", unique: true})
2025-09-16T11:01:04.533339010Z    -> 0.0251s
2025-09-16T11:01:04.533346956Z == 20221219204414 ActsAsFavoritorMigration: migrated (0.0302s) ================
2025-09-16T11:01:04.533354982Z 
2025-09-16T11:01:04.533362677Z Migrating to MovePrintedToFavorites (20221220223040)
2025-09-16T11:01:04.533370623Z == 20221220223040 MovePrintedToFavorites: migrating ===========================
2025-09-16T11:01:04.533378610Z == 20221220223040 MovePrintedToFavorites: migrated (0.3367s) ==================
2025-09-16T11:01:04.533386636Z 
2025-09-16T11:01:04.533394401Z Migrating to DropPrintedFromModelFiles (20221220223340)
2025-09-16T11:01:04.533402327Z == 20221220223340 DropPrintedFromModelFiles: migrating ========================
2025-09-16T11:01:04.533410293Z -- remove_column(:model_files, :printed, :boolean)
2025-09-16T11:01:04.533418309Z    -> 0.0299s
2025-09-16T11:01:04.533426286Z == 20221220223340 DropPrintedFromModelFiles: migrated (0.0303s) ===============
2025-09-16T11:01:04.533434252Z 
2025-09-16T11:01:04.533441907Z Migrating to AddNotesExcerptToModels (20230202210000)
2025-09-16T11:01:04.533450384Z == 20230202210000 AddNotesExcerptToModels: migrating ==========================
2025-09-16T11:01:04.533458551Z -- add_column(:models, :notes, :text)
2025-09-16T11:01:04.533466426Z    -> 0.0019s
2025-09-16T11:01:04.533474292Z -- add_column(:models, :excerpt, :text)
2025-09-16T11:01:04.533482178Z    -> 0.0023s
2025-09-16T11:01:04.533490014Z == 20230202210000 AddNotesExcerptToModels: migrated (0.0042s) =================
2025-09-16T11:01:04.533498020Z 
2025-09-16T11:01:04.533505986Z Migrating to AddNotesExcerptToCreators (20230202210001)
2025-09-16T11:01:04.533515024Z == 20230202210001 AddNotesExcerptToCreators: migrating ========================
2025-09-16T11:01:04.533523111Z -- add_column(:creators, :notes, :text)
2025-09-16T11:01:04.533531016Z    -> 0.0005s
2025-09-16T11:01:04.533538912Z -- add_column(:creators, :excerpt, :text)
2025-09-16T11:01:04.533546808Z    -> 0.0005s
2025-09-16T11:01:04.533554644Z == 20230202210001 AddNotesExcerptToCreators: migrated (0.0010s) ===============
2025-09-16T11:01:04.533562680Z 
2025-09-16T11:01:04.533570406Z Migrating to AddNotesExcerptToModelFiles (20230203150000)
2025-09-16T11:01:04.533588061Z == 20230203150000 AddNotesExcerptToModelFiles: migrating ======================
2025-09-16T11:01:04.533596208Z -- add_column(:model_files, :notes, :text)
2025-09-16T11:01:04.533604134Z    -> 0.0005s
2025-09-16T11:01:04.533612010Z -- add_column(:model_files, :excerpt, :text)
2025-09-16T11:01:04.533619925Z    -> 0.0004s
2025-09-16T11:01:04.533627791Z == 20230203150000 AddNotesExcerptToModelFiles: migrated (0.0010s) =============
2025-09-16T11:01:04.533635807Z 
2025-09-16T11:01:04.533643493Z Migrating to MoveScaleFactorIntoNote (20230221174212)
2025-09-16T11:01:04.533651439Z == 20230221174212 MoveScaleFactorIntoNote: migrating ==========================
2025-09-16T11:01:04.533659515Z == 20230221174212 MoveScaleFactorIntoNote: migrated (0.0214s) =================
2025-09-16T11:01:04.533667511Z 
2025-09-16T11:01:04.533675207Z Migrating to RemoveScaleFactorFromModels (20230221180921)
2025-09-16T11:01:04.533683133Z == 20230221180921 RemoveScaleFactorFromModels: migrating ======================
2025-09-16T11:01:04.533691199Z -- remove_column(:models, :scale_factor, :decimal, {default: "100.0", null: false})
2025-09-16T11:01:04.533699416Z    -> 0.0447s
2025-09-16T11:01:04.533707251Z == 20230221180921 RemoveScaleFactorFromModels: migrated (0.0447s) =============
2025-09-16T11:01:04.533715277Z 
2025-09-16T11:01:04.533723023Z Migrating to RenameExcerptToCaption (20230222155910)
2025-09-16T11:01:04.533730929Z == 20230222155910 RenameExcerptToCaption: migrating ===========================
2025-09-16T11:01:04.533738945Z -- rename_column(:creators, :excerpt, :caption)
2025-09-16T11:01:04.533746861Z    -> 0.0228s
2025-09-16T11:01:04.533754747Z -- rename_column(:models, :excerpt, :caption)
2025-09-16T11:01:04.533762653Z    -> 0.0215s
2025-09-16T11:01:04.533770519Z -- rename_column(:model_files, :excerpt, :caption)
2025-09-16T11:01:04.533778445Z    -> 0.0202s
2025-09-16T11:01:04.533786361Z == 20230222155910 RenameExcerptToCaption: migrated (0.0669s) ==================
2025-09-16T11:01:04.533794377Z 
2025-09-16T11:01:04.533802243Z Migrating to AddNotesNameCaptionToLibrary (20230303150029)
2025-09-16T11:01:04.533810219Z == 20230303150029 AddNotesNameCaptionToLibrary: migrating =====================
2025-09-16T11:01:04.533818295Z -- add_column(:libraries, :notes, :string)
2025-09-16T11:01:04.533826221Z    -> 0.0021s
2025-09-16T11:01:04.533834117Z -- add_column(:libraries, :caption, :string)
2025-09-16T11:01:05.072618356Z    -> 0.0005s
2025-09-16T11:01:05.072662986Z -- add_column(:libraries, :name, :string)
2025-09-16T11:01:05.072672596Z    -> 0.0005s
2025-09-16T11:01:05.072714019Z == 20230303150029 AddNotesNameCaptionToLibrary: migrated (0.0126s) ============
2025-09-16T11:01:05.072722476Z 
2025-09-16T11:01:05.072730332Z Migrating to AddTagregexToLibrary (20230305180823)
2025-09-16T11:01:05.072738308Z == 20230305180823 AddTagregexToLibrary: migrating =============================
2025-09-16T11:01:05.072746324Z -- add_column(:libraries, :tag_regex, :text)
2025-09-16T11:01:05.072754591Z    -> 0.0019s
2025-09-16T11:01:05.072762527Z == 20230305180823 AddTagregexToLibrary: migrated (0.0036s) ====================
2025-09-16T11:01:05.072770553Z 
2025-09-16T11:01:05.072778499Z Migrating to AddIconToLibrary (20230307215826)
2025-09-16T11:01:05.072786415Z == 20230307215826 AddIconToLibrary: migrating =================================
2025-09-16T11:01:05.072794411Z -- add_column(:libraries, :icon, :text)
2025-09-16T11:01:05.072802277Z    -> 0.0020s
2025-09-16T11:01:05.072810123Z == 20230307215826 AddIconToLibrary: migrated (0.0036s) ========================
2025-09-16T11:01:05.072818219Z 
2025-09-16T11:01:05.072825934Z Migrating to CreateCollection (20230308004237)
2025-09-16T11:01:05.072833840Z == 20230308004237 CreateCollection: migrating =================================
2025-09-16T11:01:05.072841897Z -- create_table(:collections)
2025-09-16T11:01:05.072850033Z    -> 0.0029s
2025-09-16T11:01:05.072858129Z == 20230308004237 CreateCollection: migrated (0.0072s) ========================
2025-09-16T11:01:05.072866085Z 
2025-09-16T11:01:05.072873741Z Migrating to AddCollectionToModels (20230308005021)
2025-09-16T11:01:05.072881667Z == 20230308005021 AddCollectionToModels: migrating ============================
2025-09-16T11:01:05.072889643Z -- add_reference(:models, :collection, {null: true, foreign_key: true})
2025-09-16T11:01:05.072897589Z    -> 0.0183s
2025-09-16T11:01:05.072905394Z == 20230308005021 AddCollectionToModels: migrated (0.0222s) ===================
2025-09-16T11:01:05.072913381Z 
2025-09-16T11:01:05.072921056Z Migrating to MoveCollectionTagIntoObject (20230308006000)
2025-09-16T11:01:05.072928962Z == 20230308006000 MoveCollectionTagIntoObject: migrating ======================
2025-09-16T11:01:05.072936948Z == 20230308006000 MoveCollectionTagIntoObject: migrated (0.0014s) =============
2025-09-16T11:01:05.072944974Z 
2025-09-16T11:01:05.072952650Z Migrating to RenameCollectionExcerptToCaption (20230313000000)
2025-09-16T11:01:05.072960576Z == 20230313000000 RenameCollectionExcerptToCaption: migrating =================
2025-09-16T11:01:05.072968592Z -- rename_column(:collections, :excerpt, :caption)
2025-09-16T11:01:05.072980436Z    -> 0.0040s
2025-09-16T11:01:05.072988352Z == 20230313000000 RenameCollectionExcerptToCaption: migrated (0.0040s) ========
2025-09-16T11:01:05.073006628Z 
2025-09-16T11:01:05.073014454Z Migrating to ChangePaginationSettingsOnUser (20230313000001)
2025-09-16T11:01:05.073022420Z == 20230313000001 ChangePaginationSettingsOnUser: migrating ===================
2025-09-16T11:01:05.073030386Z == 20230313000001 ChangePaginationSettingsOnUser: migrated (0.0000s) ==========
2025-09-16T11:01:05.073038332Z 
2025-09-16T11:01:05.073045988Z Migrating to AddTagCloudSettingsToUser (20230316184012)
2025-09-16T11:01:05.073055166Z == 20230316184012 AddTagCloudSettingsToUser: migrating ========================
2025-09-16T11:01:05.073063312Z -- add_column(:users, :tag_cloud_settings, :json, {default: {"threshold" => 2, "heatmap" => true, "keypair" => true, "sorting" => "frequency"}})
2025-09-16T11:01:05.073072922Z    -> 0.0019s
2025-09-16T11:01:05.073081038Z == 20230316184012 AddTagCloudSettingsToUser: migrated (0.0020s) ===============
2025-09-16T11:01:05.073089225Z 
2025-09-16T11:01:05.073096970Z Migrating to AddCollectionToCollections (20230324000000)
2025-09-16T11:01:05.073104916Z == 20230324000000 AddCollectionToCollections: migrating =======================
2025-09-16T11:01:05.073112892Z -- add_reference(:collections, :collection, {null: true, foreign_key: true})
2025-09-16T11:01:05.073120928Z    -> 0.0065s
2025-09-16T11:01:05.073128794Z == 20230324000000 AddCollectionToCollections: migrated (0.0087s) ==============
2025-09-16T11:01:05.073136750Z 
2025-09-16T11:01:05.073144446Z Migrating to RemoveDestinationExistsProblems (20230612080306)
2025-09-16T11:01:05.073152422Z == 20230612080306 RemoveDestinationExistsProblems: migrating ==================
2025-09-16T11:01:05.073160388Z == 20230612080306 RemoveDestinationExistsProblems: migrated (0.0565s) =========
2025-09-16T11:01:05.073168364Z 
2025-09-16T11:01:05.073176070Z Migrating to RemoveLeadingSeparatorsFromModelFilenames (20230613134254)
2025-09-16T11:01:05.073184046Z == 20230613134254 RemoveLeadingSeparatorsFromModelFilenames: migrating ========
2025-09-16T11:01:05.073192152Z == 20230613134254 RemoveLeadingSeparatorsFromModelFilenames: migrated (0.0071s) 
2025-09-16T11:01:05.073200188Z 
2025-09-16T11:01:05.073207833Z Migrating to AddSlugs (20230615135601)
2025-09-16T11:01:05.073215709Z == 20230615135601 AddSlugs: migrating =========================================
2025-09-16T11:01:05.073223936Z -- add_column(:models, :slug, :string)
2025-09-16T11:01:05.073231862Z    -> 0.0028s
2025-09-16T11:01:05.073239728Z -- add_index(:models, :slug, {name: "index_models_on_slug"})
2025-09-16T11:01:05.073247924Z    -> 0.0021s
2025-09-16T11:01:05.073255830Z -- add_column(:collections, :slug, :string)
2025-09-16T11:01:05.073263816Z    -> 0.0005s
2025-09-16T11:01:05.073281532Z -- add_index(:collections, :slug, {name: "index_collections_on_slug"})
2025-09-16T11:01:05.073289879Z    -> 0.0001s
2025-09-16T11:01:05.073297785Z -- add_column(:creators, :slug, :string)
2025-09-16T11:01:05.073305660Z    -> 0.0005s
2025-09-16T11:01:05.073313526Z -- add_index(:creators, :slug, {name: "index_creators_on_slug"})
2025-09-16T11:01:05.073321633Z    -> 0.0001s
2025-09-16T11:01:05.073329468Z == 20230615135601 AddSlugs: migrated (0.0066s) ================================
2025-09-16T11:01:05.073337755Z 
2025-09-16T11:01:05.073345461Z Migrating to GenerateSlugs (20230617222353)
2025-09-16T11:01:05.073353397Z == 20230617222353 GenerateSlugs: migrating ====================================
2025-09-16T11:01:05.073361353Z == 20230617222353 GenerateSlugs: migrated (0.0563s) ===========================
2025-09-16T11:01:05.073369359Z 
2025-09-16T11:01:05.073377024Z Migrating to MakeNamesUnique (20230628194944)
2025-09-16T11:01:05.073384870Z == 20230628194944 MakeNamesUnique: migrating ==================================
2025-09-16T11:01:05.073393317Z == 20230628194944 MakeNamesUnique: migrated (0.0031s) =========================
2025-09-16T11:01:05.073401373Z 
2025-09-16T11:01:05.073409049Z Migrating to AddUniqueConstraintToNamesAndSlugs (20230628195018)
2025-09-16T11:01:05.073417265Z == 20230628195018 AddUniqueConstraintToNamesAndSlugs: migrating ===============
2025-09-16T11:01:05.073425201Z -- add_index(:creators, :name, {unique: true, name: "index_creators_on_name"})
2025-09-16T11:01:05.073433307Z    -> 0.0022s
2025-09-16T11:01:05.073441153Z -- remove_index(:creators, :slug)
2025-09-16T11:01:05.073448979Z    -> 0.0004s
2025-09-16T11:01:05.073456815Z -- add_index(:creators, :slug, {unique: true, name: "index_creators_on_slug"})
2025-09-16T11:01:05.073464951Z    -> 0.0001s
2025-09-16T11:01:05.073472777Z -- add_index(:collections, :name, {unique: true, name: "index_collections_on_name"})
2025-09-16T11:01:05.073480994Z    -> 0.0001s
2025-09-16T11:01:05.073488819Z -- remove_index(:collections, :slug)
2025-09-16T11:01:05.073496916Z    -> 0.0249s
2025-09-16T11:01:05.073504821Z -- add_index(:collections, :slug, {unique: true, name: "index_collections_on_slug"})
2025-09-16T11:01:05.073512938Z    -> 0.0006s
2025-09-16T11:01:05.073520744Z == 20230628195018 AddUniqueConstraintToNamesAndSlugs: migrated (0.0289s) ======
2025-09-16T11:01:05.073528740Z 
2025-09-16T11:01:05.073536415Z Migrating to AddSizeToModelFiles (20230707082403)
2025-09-16T11:01:05.073544341Z == 20230707082403 AddSizeToModelFiles: migrating ==============================
2025-09-16T11:01:05.073552287Z -- add_column(:model_files, :size, :integer)
2025-09-16T11:01:05.073569381Z    -> 0.0006s
2025-09-16T11:01:05.073577428Z == 20230707082403 AddSizeToModelFiles: migrated (0.0009s) =====================
2025-09-16T11:01:05.073585444Z 
2025-09-16T11:01:05.073593109Z Migrating to ChangeFileSizeToBigInt (20230710102250)
2025-09-16T11:01:05.073600995Z == 20230710102250 ChangeFileSizeToBigInt: migrating ===========================
2025-09-16T11:01:05.073608941Z -- change_column(:model_files, :size, :bigint)
2025-09-16T11:01:05.073616857Z    -> 0.0084s
2025-09-16T11:01:05.073624673Z == 20230710102250 ChangeFileSizeToBigInt: migrated (0.0087s) ==================
2025-09-16T11:01:05.073632649Z 
2025-09-16T11:01:05.073640304Z Migrating to AddNoteToProblems (20230711111009)
2025-09-16T11:01:05.073648140Z == 20230711111009 AddNoteToProblems: migrating ================================
2025-09-16T11:01:05.073656196Z -- add_column(:problems, :note, :string, {default: nil})
2025-09-16T11:01:05.073664042Z    -> 0.0012s
2025-09-16T11:01:05.073671868Z == 20230711111009 AddNoteToProblems: migrated (0.0022s) =======================
2025-09-16T11:01:05.073679904Z 
2025-09-16T11:01:05.073687559Z Migrating to AddProblemSettingsToUser (20240122114207)
2025-09-16T11:01:05.073695435Z == 20240122114207 AddProblemSettingsToUser: migrating =========================
2025-09-16T11:01:05.073704975Z -- add_column(:users, :problem_settings, :json, {default: {"missing" => :danger, "empty" => :info, "nesting" => :warning, "inefficient" => :info, "duplicate" => :warning, "no_image" => :silent, "no_3d_model" => :silent, "non_manifold" => :warning, "inside_out" => :warning, "no_license" => :silent, "no_links" => :silent, "no_creator" => :silent, "no_tags" => :silent, "http_error" => :info, "file_naming" => :warning}})
2025-09-16T11:01:05.073718351Z    -> 0.0006s
2025-09-16T11:01:05.073726227Z == 20240122114207 AddProblemSettingsToUser: migrated (0.0006s) ================
2025-09-16T11:01:05.073734243Z 
2025-09-16T11:01:05.073741899Z Migrating to AddLicenseToModel (20240127143358)
2025-09-16T11:01:05.073749805Z == 20240127143358 AddLicenseToModel: migrating ================================
2025-09-16T11:01:05.073757731Z -- add_column(:models, :license, :string)
2025-09-16T11:01:05.073765597Z    -> 0.0006s
2025-09-16T11:01:05.073773402Z == 20240127143358 AddLicenseToModel: migrated (0.0006s) =======================
2025-09-16T11:01:05.073781368Z 
2025-09-16T11:01:05.073789024Z Migrating to AddIgnoredToProblems (20240131134832)
2025-09-16T11:01:05.073796920Z == 20240131134832 AddIgnoredToProblems: migrating =============================
2025-09-16T11:01:05.073804966Z -- add_column(:problems, :ignored, :boolean, {default: false, null: false})
2025-09-16T11:01:05.073813102Z    -> 0.0006s
2025-09-16T11:01:05.073830137Z == 20240131134832 AddIgnoredToProblems: migrated (0.0006s) ====================
2025-09-16T11:01:05.073838213Z 
2025-09-16T11:01:05.073845898Z Migrating to AddPresupportedVersionToModelFiles (20240208143319)
2025-09-16T11:01:05.073853844Z == 20240208143319 AddPresupportedVersionToModelFiles: migrating ===============
2025-09-16T11:01:05.073861770Z -- add_reference(:model_files, :presupported_version, {foreign_key: {to_table: :model_files}})
2025-09-16T11:01:05.073869887Z    -> 0.0334s
2025-09-16T11:01:05.073877702Z == 20240208143319 AddPresupportedVersionToModelFiles: migrated (0.0334s) ======
2025-09-16T11:01:05.073885769Z 
2025-09-16T11:01:05.073893464Z Migrating to AddFileListSettingsToUser (20240209125409)
2025-09-16T11:01:05.073901350Z == 20240209125409 AddFileListSettingsToUser: migrating ========================
2025-09-16T11:01:05.073909336Z -- add_column(:users, :file_list_settings, :json, {default: {"hide_presupported_versions" => true}})
2025-09-16T11:01:05.073917703Z    -> 0.0007s
2025-09-16T11:01:05.073925509Z == 20240209125409 AddFileListSettingsToUser: migrated (0.0007s) ===============
2025-09-16T11:01:05.073933475Z 
2025-09-16T11:01:05.073941140Z Migrating to AddMissingUniqueIndexes (20240228130246)
2025-09-16T11:01:05.073949096Z == 20240228130246 AddMissingUniqueIndexes: migrating ==========================
2025-09-16T11:01:05.073958235Z -- add_index(:libraries, :path, {unique: true, name: "index_libraries_on_path"})
2025-09-16T11:01:05.073967273Z    -> 0.0003s
2025-09-16T11:01:05.073975339Z -- add_index(:models, [:path, :library_id], {unique: true, name: "index_models_on_path_and_library_id"})
2025-09-16T11:01:05.073983766Z    -> 0.0010s
2025-09-16T11:01:05.073991772Z -- add_index(:model_files, [:filename, :model_id], {unique: true, name: "index_model_files_on_filename_and_model_id"})
2025-09-16T11:01:05.074000269Z    -> 0.0001s
2025-09-16T11:01:05.074008305Z -- add_index(:problems, [:category, :problematic_id, :problematic_type], {unique: true, name: "index_problems_on_category_and_problematic_id_and_type"})
2025-09-16T11:01:05.074017053Z    -> 0.0047s
2025-09-16T11:01:05.550986825Z == 20240228130246 AddMissingUniqueIndexes: migrated (0.0062s) =================
2025-09-16T11:01:05.551024040Z 
2025-09-16T11:01:05.551033188Z Migrating to CreateFlipperTables (20240306095646)
2025-09-16T11:01:05.551041545Z == 20240306095646 CreateFlipperTables: migrating ==============================
2025-09-16T11:01:05.551049722Z -- create_table(:flipper_features)
2025-09-16T11:01:05.551057758Z    -> 0.0030s
2025-09-16T11:01:05.551066265Z -- add_index(:flipper_features, :key, {unique: true, name: "index_flipper_features_on_key"})
2025-09-16T11:01:05.551074862Z    -> 0.0050s
2025-09-16T11:01:05.551082919Z -- create_table(:flipper_gates)
2025-09-16T11:01:05.551121496Z    -> 0.0002s
2025-09-16T11:01:05.551130164Z -- add_index(:flipper_gates, [:feature_key, :key, :value], {unique: true, length: {value: 255}, name: "index_flipper_gates_on_feature_key_and_key_and_value"})
2025-09-16T11:01:05.551138691Z    -> 0.0001s
2025-09-16T11:01:05.551146637Z == 20240306095646 CreateFlipperTables: migrated (0.0085s) =====================
2025-09-16T11:01:05.551154793Z 
2025-09-16T11:01:05.551162679Z Migrating to RolifyCreateRoles (20240319155251)
2025-09-16T11:01:05.551170936Z == 20240319155251 RolifyCreateRoles: migrating ================================
2025-09-16T11:01:05.551179353Z -- create_table(:roles)
2025-09-16T11:01:05.551187229Z    -> 0.0417s
2025-09-16T11:01:05.551195265Z -- create_table(:users_roles, {id: false})
2025-09-16T11:01:05.551203181Z    -> 0.0005s
2025-09-16T11:01:05.551211227Z -- add_index(:roles, :name, {name: "index_roles_on_name"})
2025-09-16T11:01:05.551219363Z    -> 0.0053s
2025-09-16T11:01:05.551227349Z -- add_index(:roles, [:name, :resource_type, :resource_id], {name: "index_roles_on_name_and_resource_type_and_resource_id"})
2025-09-16T11:01:05.551235887Z    -> 0.0002s
2025-09-16T11:01:05.551243913Z -- add_index(:users_roles, [:user_id, :role_id], {name: "index_users_roles_on_user_id_and_role_id"})
2025-09-16T11:01:05.551252209Z    -> 0.0001s
2025-09-16T11:01:05.551260105Z == 20240319155251 RolifyCreateRoles: migrated (0.0651s) =======================
2025-09-16T11:01:05.551268112Z 
2025-09-16T11:01:05.551275867Z Migrating to ConvertAdminFlagToRole (20240319155526)
2025-09-16T11:01:05.551283873Z == 20240319155526 ConvertAdminFlagToRole: migrating ===========================
2025-09-16T11:01:05.551292010Z == 20240319155526 ConvertAdminFlagToRole: migrated (0.0095s) ==================
2025-09-16T11:01:05.551300066Z 
2025-09-16T11:01:05.551307852Z Migrating to RemoveAdminFromUsers (20240319155903)
2025-09-16T11:01:05.551315838Z == 20240319155903 RemoveAdminFromUsers: migrating =============================
2025-09-16T11:01:05.551323844Z -- remove_column(:users, :admin, :boolean, {default: false, null: false})
2025-09-16T11:01:05.551332000Z    -> 0.0246s
2025-09-16T11:01:05.551339946Z == 20240319155903 RemoveAdminFromUsers: migrated (0.0260s) ====================
2025-09-16T11:01:05.551347952Z 
2025-09-16T11:01:05.551355758Z Migrating to AddResetPasswordTokenToUsers (20240322143621)
2025-09-16T11:01:05.551363754Z == 20240322143621 AddResetPasswordTokenToUsers: migrating =====================
2025-09-16T11:01:05.551371790Z -- add_column(:users, :reset_password_token, :string)
2025-09-16T11:01:05.551379756Z    -> 0.0012s
2025-09-16T11:01:05.551387682Z -- add_index(:users, :reset_password_token, {unique: true, name: "index_users_on_reset_password_token"})
2025-09-16T11:01:05.551406590Z    -> 0.0002s
2025-09-16T11:01:05.551415007Z == 20240322143621 AddResetPasswordTokenToUsers: migrated (0.0034s) ============
2025-09-16T11:01:05.551423164Z 
2025-09-16T11:01:05.551431010Z Migrating to SetAdminPasswordResetToken (20240322150022)
2025-09-16T11:01:05.551439006Z == 20240322150022 SetAdminPasswordResetToken: migrating =======================
2025-09-16T11:01:05.551446972Z == 20240322150022 SetAdminPasswordResetToken: migrated (0.0263s) ==============
2025-09-16T11:01:05.551454998Z 
2025-09-16T11:01:05.551462753Z Migrating to AddRememberableToUsers (20240410221112)
2025-09-16T11:01:05.551470699Z == 20240410221112 AddRememberableToUsers: migrating ===========================
2025-09-16T11:01:05.551478686Z -- add_column(:users, :remember_created_at, :datetime)
2025-09-16T11:01:05.551486611Z    -> 0.0035s
2025-09-16T11:01:05.551494588Z == 20240410221112 AddRememberableToUsers: migrated (0.0049s) ==================
2025-09-16T11:01:05.551502624Z 
2025-09-16T11:01:05.551510329Z Migrating to AddResetPasswordSentAtToUsers (20240410222922)
2025-09-16T11:01:05.551518295Z == 20240410222922 AddResetPasswordSentAtToUsers: migrating ====================
2025-09-16T11:01:05.551526291Z -- add_column(:users, :reset_password_sent_at, :datetime)
2025-09-16T11:01:05.551534237Z    -> 0.0007s
2025-09-16T11:01:05.551542153Z == 20240410222922 AddResetPasswordSentAtToUsers: migrated (0.0019s) ===========
2025-09-16T11:01:05.551552454Z 
2025-09-16T11:01:05.551560310Z Migrating to DropDelayedJobs (20240418112821)
2025-09-16T11:01:05.551568336Z == 20240418112821 DropDelayedJobs: migrating ==================================
2025-09-16T11:01:05.551576432Z -- drop_table(:delayed_jobs)
2025-09-16T11:01:05.551584378Z    -> 0.0019s
2025-09-16T11:01:05.551592274Z == 20240418112821 DropDelayedJobs: migrated (0.0129s) =========================
2025-09-16T11:01:05.551600571Z 
2025-09-16T11:01:05.551608286Z Migrating to AddInterfaceLanguageToUsers (20240423102250)
2025-09-16T11:01:05.551616243Z == 20240423102250 AddInterfaceLanguageToUsers: migrating ======================
2025-09-16T11:01:05.551624359Z -- add_column(:users, :interface_language, :string)
2025-09-16T11:01:05.551632355Z    -> 0.0007s
2025-09-16T11:01:05.551640241Z == 20240423102250 AddInterfaceLanguageToUsers: migrated (0.0043s) =============
2025-09-16T11:01:05.551648257Z 
2025-09-16T11:01:05.551656083Z Migrating to AddLockableToUsers (20240610120318)
2025-09-16T11:01:05.551663999Z == 20240610120318 AddLockableToUsers: migrating ===============================
2025-09-16T11:01:05.551672035Z -- change_table(:users)
2025-09-16T11:01:05.551689049Z    -> 0.0017s
2025-09-16T11:01:05.551697085Z == 20240610120318 AddLockableToUsers: migrated (0.0021s) ======================
2025-09-16T11:01:05.551705101Z 
2025-09-16T11:01:05.551712827Z Migrating to AddAttachmentDataToModelFiles (20240614085913)
2025-09-16T11:01:05.551720793Z == 20240614085913 AddAttachmentDataToModelFiles: migrating ====================
2025-09-16T11:01:05.551728889Z -- add_column(:model_files, :attachment_data, :json)
2025-09-16T11:01:05.551736815Z    -> 0.0006s
2025-09-16T11:01:05.551744711Z == 20240614085913 AddAttachmentDataToModelFiles: migrated (0.0009s) ===========
2025-09-16T11:01:05.551753369Z 
2025-09-16T11:01:05.551761164Z Migrating to MoveFileDataIntoShrine (20240615085913)
2025-09-16T11:01:05.551769601Z == 20240615085913 MoveFileDataIntoShrine: migrating ===========================
2025-09-16T11:01:05.551777688Z == 20240615085913 MoveFileDataIntoShrine: migrated (0.0142s) ==================
2025-09-16T11:01:05.551785714Z 
2025-09-16T11:01:05.551793439Z Migrating to AddStorageServiceToLibraries (20240701142651)
2025-09-16T11:01:05.551801405Z == 20240701142651 AddStorageServiceToLibraries: migrating =====================
2025-09-16T11:01:05.551809371Z -- add_column(:libraries, :storage_service, :string, {null: false, default: "filesystem"})
2025-09-16T11:01:05.551817618Z    -> 0.0014s
2025-09-16T11:01:05.551825554Z == 20240701142651 AddStorageServiceToLibraries: migrated (0.0032s) ============
2025-09-16T11:01:05.551833710Z 
2025-09-16T11:01:05.551841476Z Migrating to AddS3FieldsToLibrary (20240703103707)
2025-09-16T11:01:05.551864893Z == 20240703103707 AddS3FieldsToLibrary: migrating =============================
2025-09-16T11:01:05.551875605Z -- add_column(:libraries, :s3_endpoint, :string, {default: nil})
2025-09-16T11:01:05.551883711Z    -> 0.0007s
2025-09-16T11:01:05.551891657Z -- add_column(:libraries, :s3_region, :string, {default: nil})
2025-09-16T11:01:05.551899653Z    -> 0.0006s
2025-09-16T11:01:05.551907659Z -- add_column(:libraries, :s3_bucket, :string, {default: nil})
2025-09-16T11:01:05.551915846Z    -> 0.0006s
2025-09-16T11:01:05.551923722Z -- add_column(:libraries, :s3_access_key_id, :string, {default: nil})
2025-09-16T11:01:05.551931798Z    -> 0.0049s
2025-09-16T11:01:05.551939694Z -- add_column(:libraries, :s3_secret_access_key, :string, {default: nil})
2025-09-16T11:01:05.551947780Z    -> 0.0006s
2025-09-16T11:01:05.551955776Z == 20240703103707 AddS3FieldsToLibrary: migrated (0.0085s) ====================
2025-09-16T11:01:05.551963873Z 
2025-09-16T11:01:05.551971698Z Migrating to ChangeIndexOnLibraryPath (20240703160732)
2025-09-16T11:01:05.551979674Z == 20240703160732 ChangeIndexOnLibraryPath: migrating =========================
2025-09-16T11:01:05.551997280Z -- remove_index(:libraries, :path)
2025-09-16T11:01:05.552005306Z    -> 0.0010s
2025-09-16T11:01:05.552013192Z == 20240703160732 ChangeIndexOnLibraryPath: migrated (0.0018s) ================
2025-09-16T11:01:05.552021258Z 
2025-09-16T11:01:05.552028984Z Migrating to CreateFederailsActors (20240727113215)
2025-09-16T11:01:05.552036960Z == 20240727113215 CreateFederailsActors: migrating ============================
2025-09-16T11:01:05.552045146Z -- create_table(:federails_actors)
2025-09-16T11:01:05.552053072Z    -> 0.0008s
2025-09-16T11:01:05.552060988Z -- foreign_key_exists?(:federails_actors, :users)
2025-09-16T11:01:05.552068954Z    -> 0.0017s
2025-09-16T11:01:05.552076880Z -- remove_foreign_key(:federails_actors, :users)
2025-09-16T11:01:05.552084816Z    -> 0.0121s
2025-09-16T11:01:05.552092682Z -- remove_index(:federails_actors, :user_id)
2025-09-16T11:01:05.552100628Z    -> 0.0004s
2025-09-16T11:01:05.552108604Z -- add_index(:federails_actors, :user_id, {unique: true, name: "index_federails_actors_on_user_id"})
2025-09-16T11:01:05.552116901Z    -> 0.0001s
2025-09-16T11:01:05.552124877Z -- add_foreign_key(:federails_actors, :users)
2025-09-16T11:01:05.552132793Z    -> 0.0099s
2025-09-16T11:01:05.552140709Z == 20240727113215 CreateFederailsActors: migrated (0.0259s) ===================
2025-09-16T11:01:05.552148745Z 
2025-09-16T11:01:05.552156490Z Migrating to CreateFederailsFollowings (20240727113216)
2025-09-16T11:01:05.552165018Z == 20240727113216 CreateFederailsFollowings: migrating ========================
2025-09-16T11:01:05.552173194Z -- create_table(:federails_followings)
2025-09-16T11:01:05.552181130Z    -> 0.0012s
2025-09-16T11:01:05.552189076Z == 20240727113216 CreateFederailsFollowings: migrated (0.0013s) ===============
2025-09-16T11:01:05.552197192Z 
2025-09-16T11:01:05.552204968Z Migrating to CreateFederailsActivities (20240727113217)
2025-09-16T11:01:05.552212954Z == 20240727113217 CreateFederailsActivities: migrating ========================
2025-09-16T11:01:05.552221010Z -- create_table(:federails_activities)
2025-09-16T11:01:05.552228926Z    -> 0.0090s
2025-09-16T11:01:05.552236862Z == 20240727113217 CreateFederailsActivities: migrated (0.0090s) ===============
2025-09-16T11:01:05.552244968Z 
2025-09-16T11:01:05.552252694Z Migrating to ChangeActorEntityRelToPolymorphic (20240731165646)
2025-09-16T11:01:05.552260680Z == 20240731165646 ChangeActorEntityRelToPolymorphic: migrating ================
2025-09-16T11:01:05.552268706Z -- remove_foreign_key(:federails_actors, {column: :user_id, to_table: "users"})
2025-09-16T11:01:05.552276913Z    -> 0.0083s
2025-09-16T11:01:05.552294017Z -- remove_index(:federails_actors, :user_id, {unique: true})
2025-09-16T11:01:05.552302063Z    -> 0.0004s
2025-09-16T11:01:05.552310009Z -- change_table(:federails_actors)
2025-09-16T11:01:05.552317895Z    -> 0.0073s
2025-09-16T11:01:05.552325761Z == 20240731165646 ChangeActorEntityRelToPolymorphic: migrated (0.0162s) =======
2025-09-16T11:01:05.552333837Z 
2025-09-16T11:01:05.552341553Z Migrating to CreateFederailsActors (20240731165647)
2025-09-16T11:01:05.552349509Z == 20240731165647 CreateFederailsActors: migrating ============================
2025-09-16T11:01:05.552357505Z == 20240731165647 CreateFederailsActors: migrated (0.0000s) ===================
2025-09-16T11:01:05.552365591Z 
2025-09-16T11:01:05.552373367Z Migrating to CreateMoreFederailsActors (20240802094448)
2025-09-16T11:01:05.552381323Z == 20240802094448 CreateMoreFederailsActors: migrating ========================
2025-09-16T11:01:05.552389359Z == 20240802094448 CreateMoreFederailsActors: migrated (0.0000s) ===============
2025-09-16T11:01:05.552397445Z 
2025-09-16T11:01:05.552405161Z Migrating to BackfillActivities (20240805111500)
2025-09-16T11:01:05.552413157Z == 20240805111500 BackfillActivities: migrating ===============================
2025-09-16T11:01:05.552421905Z == 20240805111500 BackfillActivities: migrated (0.0000s) ======================
2025-09-16T11:01:05.552429961Z 
2025-09-16T11:01:05.552437757Z Migrating to CreateCaberRelations (20240827155630)
2025-09-16T11:01:05.552445733Z == 20240827155630 CreateCaberRelations: migrating =============================
2025-09-16T11:01:05.552453749Z -- create_table(:caber_relations)
2025-09-16T11:01:05.552461675Z    -> 0.0414s
2025-09-16T11:01:05.552469521Z == 20240827155630 CreateCaberRelations: migrated (0.0414s) ====================
2025-09-16T11:01:05.552477607Z 
2025-09-16T11:01:05.552485332Z Migrating to AddDefaultAccessControls (20240830121749)
2025-09-16T11:01:05.552493288Z == 20240830121749 AddDefaultAccessControls: migrating =========================
2025-09-16T11:01:05.552501345Z == 20240830121749 AddDefaultAccessControls: migrated (0.0236s) ================
2025-09-16T11:01:05.552509381Z 
2025-09-16T11:01:05.552517507Z Migrating to RenameDefaultRoles (20240830151650)
2025-09-16T11:01:06.218725203Z == 20240830151650 RenameDefaultRoles: migrating ===============================
2025-09-16T11:01:06.218758290Z == 20240830151650 RenameDefaultRoles: migrated (0.0435s) ======================
2025-09-16T11:01:06.218767769Z 
2025-09-16T11:01:06.218776156Z Migrating to AddPublicIDsToModels (20240904151944)
2025-09-16T11:01:06.218784462Z == 20240904151944 AddPublicIDsToModels: migrating =============================
2025-09-16T11:01:06.218833100Z -- add_column(:models, :public_id, :string)
2025-09-16T11:01:06.218841477Z    -> 0.0008s
2025-09-16T11:01:06.218849844Z -- add_index(:models, :public_id)
2025-09-16T11:01:06.218857900Z    -> 0.0026s
2025-09-16T11:01:06.218865937Z -- add_column(:model_files, :public_id, :string)
2025-09-16T11:01:06.218873913Z    -> 0.0010s
2025-09-16T11:01:06.218881768Z -- add_index(:model_files, :public_id)
2025-09-16T11:01:06.218889855Z    -> 0.0001s
2025-09-16T11:01:06.218897700Z -- add_column(:problems, :public_id, :string)
2025-09-16T11:01:06.218905646Z    -> 0.0007s
2025-09-16T11:01:06.218913462Z -- add_index(:problems, :public_id)
2025-09-16T11:01:06.218921368Z    -> 0.0001s
2025-09-16T11:01:06.218929204Z -- add_column(:creators, :public_id, :string)
2025-09-16T11:01:06.218937120Z    -> 0.0006s
2025-09-16T11:01:06.218945176Z -- add_index(:creators, :public_id)
2025-09-16T11:01:06.218953132Z    -> 0.0001s
2025-09-16T11:01:06.218960978Z -- add_column(:collections, :public_id, :string)
2025-09-16T11:01:06.218968964Z    -> 0.0006s
2025-09-16T11:01:06.218976800Z -- add_index(:collections, :public_id)
2025-09-16T11:01:06.218984726Z    -> 0.0001s
2025-09-16T11:01:06.218992592Z -- add_column(:libraries, :public_id, :string)
2025-09-16T11:01:06.219000497Z    -> 0.0006s
2025-09-16T11:01:06.219008323Z -- add_index(:libraries, :public_id)
2025-09-16T11:01:06.219016299Z    -> 0.0001s
2025-09-16T11:01:06.219024155Z == 20240904151944 AddPublicIDsToModels: migrated (0.0078s) ====================
2025-09-16T11:01:06.219032271Z 
2025-09-16T11:01:06.219039997Z Migrating to GeneratePublicIDsForModels (20240904152358)
2025-09-16T11:01:06.219048153Z == 20240904152358 GeneratePublicIDsForModels: migrating =======================
2025-09-16T11:01:06.219056210Z Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:06.219064316Z == 20240904152358 GeneratePublicIDsForModels: migrated (0.0362s) ==============
2025-09-16T11:01:06.219072382Z 
2025-09-16T11:01:06.219080178Z Migrating to BackfillActivitiesAfterPermissions (20240909100000)
2025-09-16T11:01:06.219088134Z == 20240909100000 BackfillActivitiesAfterPermissions: migrating ===============
2025-09-16T11:01:06.219096320Z == 20240909100000 BackfillActivitiesAfterPermissions: migrated (0.0000s) ======
2025-09-16T11:01:06.219104347Z 
2025-09-16T11:01:06.219112052Z Migrating to DeduplicateRoles (20240923114515)
2025-09-16T11:01:06.219119958Z == 20240923114515 DeduplicateRoles: migrating =================================
2025-09-16T11:01:06.219127944Z == 20240923114515 DeduplicateRoles: migrated (0.1465s) ========================
2025-09-16T11:01:06.219145469Z 
2025-09-16T11:01:06.219153335Z Migrating to AddVirtualColumnsForSorting (20240924122004)
2025-09-16T11:01:06.219161281Z == 20240924122004 AddVirtualColumnsForSorting: migrating ======================
2025-09-16T11:01:06.219169257Z -- add_column(:models, :name_lower, :virtual, {type: :string, as: "LOWER(name)", stored: true})
2025-09-16T11:01:06.219177654Z    -> 0.1618s
2025-09-16T11:01:06.219186732Z -- add_index(:models, :name_lower)
2025-09-16T11:01:06.219194809Z    -> 0.0002s
2025-09-16T11:01:06.219202715Z -- add_column(:creators, :name_lower, :virtual, {type: :string, as: "LOWER(name)", stored: true})
2025-09-16T11:01:06.219211041Z    -> 0.0068s
2025-09-16T11:01:06.219218977Z -- add_index(:creators, :name_lower)
2025-09-16T11:01:06.219226873Z    -> 0.0001s
2025-09-16T11:01:06.219234739Z -- add_column(:collections, :name_lower, :virtual, {type: :string, as: "LOWER(name)", stored: true})
2025-09-16T11:01:06.219242956Z    -> 0.0083s
2025-09-16T11:01:06.219250801Z -- add_index(:collections, :name_lower)
2025-09-16T11:01:06.219258707Z    -> 0.0001s
2025-09-16T11:01:06.219266593Z == 20240924122004 AddVirtualColumnsForSorting: migrated (0.1776s) =============
2025-09-16T11:01:06.219274760Z 
2025-09-16T11:01:06.219282555Z Migrating to AddKeypairToActors (20240926162407)
2025-09-16T11:01:06.219290481Z == 20240926162407 AddKeypairToActors: migrating ===============================
2025-09-16T11:01:06.219298457Z -- change_table(:federails_actors)
2025-09-16T11:01:06.219306293Z    -> 0.0015s
2025-09-16T11:01:06.219314139Z == 20240926162407 AddKeypairToActors: migrated (0.0015s) ======================
2025-09-16T11:01:06.219322325Z 
2025-09-16T11:01:06.219330001Z Migrating to AddUuids (20241004194445)
2025-09-16T11:01:06.219337857Z == 20241004194445 AddUuids: migrating =========================================
2025-09-16T11:01:06.219345933Z -- change_table(:federails_actors)
2025-09-16T11:01:06.219353789Z    -> 0.0034s
2025-09-16T11:01:06.219361645Z -- change_table(:federails_activities)
2025-09-16T11:01:06.219369541Z    -> 0.0009s
2025-09-16T11:01:06.219377396Z -- change_table(:federails_followings)
2025-09-16T11:01:06.219385252Z    -> 0.0009s
2025-09-16T11:01:06.219393108Z == 20241004194445 AddUuids: migrated (0.0053s) ================================
2025-09-16T11:01:06.219401104Z 
2025-09-16T11:01:06.219408860Z Migrating to CreateComments (20241007182824)
2025-09-16T11:01:06.219416766Z == 20241007182824 CreateComments: migrating ===================================
2025-09-16T11:01:06.219424752Z -- create_table(:comments)
2025-09-16T11:01:06.219441626Z    -> 0.0022s
2025-09-16T11:01:06.219449512Z == 20241007182824 CreateComments: migrated (0.0023s) ==========================
2025-09-16T11:01:06.219460544Z 
2025-09-16T11:01:06.219468410Z Migrating to AddSystemToComments (20241009122540)
2025-09-16T11:01:06.219476446Z == 20241009122540 AddSystemToComments: migrating ==============================
2025-09-16T11:01:06.219484622Z -- add_column(:comments, :system, :boolean, {null: false, default: false})
2025-09-16T11:01:06.219492668Z    -> 0.0011s
2025-09-16T11:01:06.219500554Z == 20241009122540 AddSystemToComments: migrated (0.0011s) =====================
2025-09-16T11:01:06.219508570Z 
2025-09-16T11:01:06.219516296Z Migrating to BackfillActivitiesAfterUuids (20241013215000)
2025-09-16T11:01:06.219524242Z == 20241013215000 BackfillActivitiesAfterUuids: migrating =====================
2025-09-16T11:01:06.219532238Z == 20241013215000 BackfillActivitiesAfterUuids: migrated (0.0017s) ============
2025-09-16T11:01:06.219540254Z 
2025-09-16T11:01:06.219548310Z Migrating to AddOmniauthToUsers (20241015090803)
2025-09-16T11:01:06.219556267Z == 20241015090803 AddOmniauthToUsers: migrating ===============================
2025-09-16T11:01:06.219564293Z -- add_column(:users, :auth_provider, :string)
2025-09-16T11:01:06.219572259Z    -> 0.0010s
2025-09-16T11:01:06.219580225Z -- add_column(:users, :auth_uid, :string)
2025-09-16T11:01:06.219588642Z    -> 0.0008s
2025-09-16T11:01:06.219596868Z == 20241015090803 AddOmniauthToUsers: migrated (0.0018s) ======================
2025-09-16T11:01:06.219604985Z 
2025-09-16T11:01:06.219612720Z Migrating to AddSensitiveFlagToModelsAndComments (20241017093301)
2025-09-16T11:01:06.219620796Z == 20241017093301 AddSensitiveFlagToModelsAndComments: migrating ==============
2025-09-16T11:01:06.219628783Z -- add_column(:models, :sensitive, :boolean, {null: false, default: false})
2025-09-16T11:01:06.219636799Z    -> 0.0009s
2025-09-16T11:01:06.219644685Z -- add_column(:comments, :sensitive, :boolean, {null: false, default: false})
2025-09-16T11:01:06.219652711Z    -> 0.0008s
2025-09-16T11:01:06.219660567Z == 20241017093301 AddSensitiveFlagToModelsAndComments: migrated (0.0018s) =====
2025-09-16T11:01:06.219668643Z 
2025-09-16T11:01:06.219676489Z Migrating to AddSensitiveContentHandlingToUsers (20241017113112)
2025-09-16T11:01:06.219684445Z == 20241017113112 AddSensitiveContentHandlingToUsers: migrating ===============
2025-09-16T11:01:06.219692521Z -- add_column(:users, :sensitive_content_handling, :string, {default: nil})
2025-09-16T11:01:06.219700838Z    -> 0.0009s
2025-09-16T11:01:06.219708693Z == 20241017113112 AddSensitiveContentHandlingToUsers: migrated (0.0009s) ======
2025-09-16T11:01:06.219725868Z 
2025-09-16T11:01:06.219733634Z Migrating to AddPublicIDsToUsers (20241021124608)
2025-09-16T11:01:06.219741530Z == 20241021124608 AddPublicIDsToUsers: migrating ==============================
2025-09-16T11:01:06.219749706Z -- change_table(:users)
2025-09-16T11:01:06.219757532Z    -> 0.0011s
2025-09-16T11:01:06.219765398Z == 20241021124608 AddPublicIDsToUsers: migrated (0.0011s) =====================
2025-09-16T11:01:06.219773504Z 
2025-09-16T11:01:06.219781159Z Migrating to CreatePublicIDsForUsers (20241021125322)
2025-09-16T11:01:06.219789105Z == 20241021125322 CreatePublicIDsForUsers: migrating ==========================
2025-09-16T11:01:06.219797192Z == 20241021125322 CreatePublicIDsForUsers: migrated (0.0073s) =================
2025-09-16T11:01:06.219805208Z 
2025-09-16T11:01:06.219812953Z Migrating to RemoveUnneededIndexes (20241025110218)
2025-09-16T11:01:06.219820829Z == 20241025110218 RemoveUnneededIndexes: migrating ============================
2025-09-16T11:01:06.219828835Z -- remove_index(:favorites, {name: "index_favorites_on_favoritable", column: [:favoritable_type, :favoritable_id]})
2025-09-16T11:01:06.219837232Z    -> 0.0015s
2025-09-16T11:01:06.219865008Z -- remove_index(:federails_followings, {name: "index_federails_followings_on_actor_id", column: :actor_id})
2025-09-16T11:01:06.219879377Z    -> 0.0013s
2025-09-16T11:01:06.219889227Z -- remove_index(:roles, {name: "index_roles_on_name", column: :name})
2025-09-16T11:01:06.219899197Z    -> 0.0004s
2025-09-16T11:01:06.219907153Z -- remove_index(:taggings, {name: "index_taggings_on_tag_id", column: :tag_id})
2025-09-16T11:01:06.219915360Z    -> 0.0009s
2025-09-16T11:01:06.219923235Z -- remove_index(:taggings, {name: "index_taggings_on_taggable_id", column: :taggable_id})
2025-09-16T11:01:06.219931412Z    -> 0.0008s
2025-09-16T11:01:06.219939238Z -- remove_index(:taggings, {name: "index_taggings_on_tagger_id", column: :tagger_id})
2025-09-16T11:01:06.219947464Z    -> 0.0007s
2025-09-16T11:01:06.219955991Z -- remove_index(:users_roles, {name: "index_users_roles_on_user_id", column: :user_id})
2025-09-16T11:01:06.219964388Z    -> 0.0004s
2025-09-16T11:01:06.219972334Z == 20241025110218 RemoveUnneededIndexes: migrated (0.0063s) ===================
2025-09-16T11:01:06.219980390Z 
2025-09-16T11:01:06.219988096Z Migrating to AddInProgressToProblems (20241105121830)
2025-09-16T11:01:06.219996132Z == 20241105121830 AddInProgressToProblems: migrating ==========================
2025-09-16T11:01:06.220004108Z -- add_column(:problems, :in_progress, :boolean, {default: false, null: false})
2025-09-16T11:01:06.220012164Z    -> 0.0009s
2025-09-16T11:01:06.220019990Z == 20241105121830 AddInProgressToProblems: migrated (0.0010s) =================
2025-09-16T11:01:06.220037806Z 
2025-09-16T11:01:06.220045612Z Migrating to ClearStuckProblems (20241118155027)
2025-09-16T11:01:06.220053528Z == 20241118155027 ClearStuckProblems: migrating ===============================
2025-09-16T11:01:06.220061524Z == 20241118155027 ClearStuckProblems: migrated (0.0286s) ======================
2025-09-16T11:01:06.220069710Z 
2025-09-16T11:01:06.220077446Z Migrating to AddApprovedToUser (20241122121621)
2025-09-16T11:01:06.220085352Z == 20241122121621 AddApprovedToUser: migrating ================================
2025-09-16T11:01:06.220093608Z -- change_table(:users)
2025-09-16T11:01:06.220101424Z    -> 0.0015s
2025-09-16T11:01:06.220109260Z == 20241122121621 AddApprovedToUser: migrated (0.0015s) =======================
2025-09-16T11:01:06.220117256Z 
2025-09-16T11:01:06.220124942Z Migrating to CreateFederailsModerationReports (20241128162213)
2025-09-16T11:01:06.220132908Z == 20241128162213 CreateFederailsModerationReports: migrating =================
2025-09-16T11:01:06.220140874Z -- create_table(:federails_moderation_reports)
2025-09-16T11:01:06.220148780Z    -> 0.0030s
2025-09-16T11:01:06.220156605Z == 20241128162213 CreateFederailsModerationReports: migrated (0.0030s) ========
2025-09-16T11:01:06.220164662Z 
2025-09-16T11:01:06.220173580Z Migrating to CreateFederailsModerationDomainBlocks (20241128162214)
2025-09-16T11:01:06.220181576Z == 20241128162214 CreateFederailsModerationDomainBlocks: migrating ============
2025-09-16T11:01:06.220189582Z -- create_table(:federails_moderation_domain_blocks)
2025-09-16T11:01:06.220197508Z    -> 0.0004s
2025-09-16T11:01:06.220205364Z == 20241128162214 CreateFederailsModerationDomainBlocks: migrated (0.0005s) ===
2025-09-16T11:01:06.220213350Z 
2025-09-16T11:01:06.220221115Z Migrating to AddFederationAttributesToComments (20250114105808)
2025-09-16T11:01:06.220229101Z == 20250114105808 AddFederationAttributesToComments: migrating ================
2025-09-16T11:01:06.220237178Z -- change_column_null(:comments, :commenter_id, true)
2025-09-16T11:01:06.220245434Z    -> 0.0077s
2025-09-16T11:01:06.220253330Z -- change_column_null(:comments, :commenter_type, true)
2025-09-16T11:01:06.220261316Z    -> 0.0075s
2025-09-16T11:01:06.220269182Z -- add_column(:comments, :federated_url, :string, {null: true, default: nil})
2025-09-16T11:01:06.220277198Z    -> 0.0008s
2025-09-16T11:01:06.220285064Z -- add_reference(:comments, :federails_actor, {null: true, foreign_key: true})
2025-09-16T11:01:06.220293080Z    -> 0.0104s
2025-09-16T11:01:07.069793308Z == 20250114105808 AddFederationAttributesToComments: migrated (0.0281s) =======
2025-09-16T11:01:07.069856946Z 
2025-09-16T11:01:07.069866436Z Migrating to AddS3PathStyleToLibrary (20250121105010)
2025-09-16T11:01:07.069874702Z == 20250121105010 AddS3PathStyleToLibrary: migrating ==========================
2025-09-16T11:01:07.069883099Z -- add_column(:libraries, :s3_path_style, :boolean, {default: true, null: false})
2025-09-16T11:01:07.069891406Z    -> 0.0009s
2025-09-16T11:01:07.069899863Z == 20250121105010 AddS3PathStyleToLibrary: migrated (0.0009s) =================
2025-09-16T11:01:07.069908310Z 
2025-09-16T11:01:07.069916186Z Migrating to MakePublicIDsLowercase (20250121164452)
2025-09-16T11:01:07.069924132Z == 20250121164452 MakePublicIDsLowercase: migrating ===========================
2025-09-16T11:01:07.069932098Z == 20250121164452 MakePublicIDsLowercase: migrated (0.1709s) ==================
2025-09-16T11:01:07.069940094Z 
2025-09-16T11:01:07.069948080Z Migrating to AddExtensionsToFederailsActors (20250122171731)
2025-09-16T11:01:07.069956076Z == 20250122171731 AddExtensionsToFederailsActors: migrating ===================
2025-09-16T11:01:07.069964132Z -- add_column(:federails_actors, :extensions, :json, {default: nil, null: true})
2025-09-16T11:01:07.069972249Z    -> 0.0021s
2025-09-16T11:01:07.069980114Z == 20250122171731 AddExtensionsToFederailsActors: migrated (0.0021s) ==========
2025-09-16T11:01:07.069988080Z 
2025-09-16T11:01:07.069995806Z Migrating to AddCreatorToCollections (20250222000000)
2025-09-16T11:01:07.070003732Z == 20250222000000 AddCreatorToCollections: migrating ==========================
2025-09-16T11:01:07.070011658Z -- add_reference(:collections, :creator, {null: true, foreign_key: true})
2025-09-16T11:01:07.070019634Z    -> 0.0244s
2025-09-16T11:01:07.070027460Z == 20250222000000 AddCreatorToCollections: migrated (0.0245s) =================
2025-09-16T11:01:07.070035466Z 
2025-09-16T11:01:07.070043252Z Migrating to AddLocalToActors (20250305171530)
2025-09-16T11:01:07.070051328Z == 20250305171530 AddLocalToActors: migrating =================================
2025-09-16T11:01:07.070059334Z -- add_column(:federails_actors, :local, :boolean, {null: false, default: false})
2025-09-16T11:01:07.070067450Z    -> 0.0009s
2025-09-16T11:01:07.070075306Z -- exec_update("UPDATE federails_actors SET local=true WHERE entity_type IS NOT NULL")
2025-09-16T11:01:07.070083883Z    -> 0.0019s
2025-09-16T11:01:07.070091960Z    -> 0 rows
2025-09-16T11:01:07.070100096Z == 20250305171530 AddLocalToActors: migrated (0.0062s) ========================
2025-09-16T11:01:07.070108142Z 
2025-09-16T11:01:07.070116028Z Migrating to AddActorTypeToActors (20250407160721)
2025-09-16T11:01:07.070124074Z == 20250407160721 AddActorTypeToActors: migrating =============================
2025-09-16T11:01:07.070142622Z -- add_column(:federails_actors, :actor_type, :string, {null: true})
2025-09-16T11:01:07.070150758Z    -> 0.0009s
2025-09-16T11:01:07.070158624Z == 20250407160721 AddActorTypeToActors: migrated (0.0009s) ====================
2025-09-16T11:01:07.070166610Z 
2025-09-16T11:01:07.070174366Z Migrating to AddTombstonedAtToActors (20250407160722)
2025-09-16T11:01:07.070182312Z == 20250407160722 AddTombstonedAtToActors: migrating ==========================
2025-09-16T11:01:07.070191400Z -- add_column(:federails_actors, :tombstoned_at, :datetime, {default: nil})
2025-09-16T11:01:07.070199546Z    -> 0.0014s
2025-09-16T11:01:07.070207512Z == 20250407160722 AddTombstonedAtToActors: migrated (0.0014s) =================
2025-09-16T11:01:07.070215498Z 
2025-09-16T11:01:07.070223194Z Migrating to CreateDoorkeeperTables (20250408111644)
2025-09-16T11:01:07.070231140Z == 20250408111644 CreateDoorkeeperTables: migrating ===========================
2025-09-16T11:01:07.070239236Z -- create_table(:oauth_applications)
2025-09-16T11:01:07.070247102Z    -> 0.0004s
2025-09-16T11:01:07.070254978Z -- add_index(:oauth_applications, :uid, {unique: true})
2025-09-16T11:01:07.070262964Z    -> 0.0002s
2025-09-16T11:01:07.070270780Z -- create_table(:oauth_access_grants)
2025-09-16T11:01:07.070278676Z    -> 0.0006s
2025-09-16T11:01:07.070286571Z -- add_index(:oauth_access_grants, :token, {unique: true})
2025-09-16T11:01:07.070294558Z    -> 0.0001s
2025-09-16T11:01:07.070302443Z -- add_foreign_key(:oauth_access_grants, :oauth_applications, {column: :application_id})
2025-09-16T11:01:07.070310520Z    -> 0.0073s
2025-09-16T11:01:07.070318376Z -- create_table(:oauth_access_tokens)
2025-09-16T11:01:07.070326231Z    -> 0.0006s
2025-09-16T11:01:07.070334017Z -- add_index(:oauth_access_tokens, :token, {unique: true})
2025-09-16T11:01:07.070344548Z    -> 0.0001s
2025-09-16T11:01:07.070352554Z -- add_index(:oauth_access_tokens, :refresh_token, {unique: true})
2025-09-16T11:01:07.070360611Z    -> 0.0001s
2025-09-16T11:01:07.070368667Z -- add_foreign_key(:oauth_access_tokens, :oauth_applications, {column: :application_id})
2025-09-16T11:01:07.070376733Z    -> 0.0087s
2025-09-16T11:01:07.070384549Z -- add_foreign_key(:oauth_access_grants, :users, {column: :resource_owner_id})
2025-09-16T11:01:07.070392555Z    -> 0.0072s
2025-09-16T11:01:07.070400351Z -- add_foreign_key(:oauth_access_tokens, :users, {column: :resource_owner_id})
2025-09-16T11:01:07.070408347Z    -> 0.0088s
2025-09-16T11:01:07.070416152Z == 20250408111644 CreateDoorkeeperTables: migrated (0.0343s) ==================
2025-09-16T11:01:07.070424128Z 
2025-09-16T11:01:07.070441042Z Migrating to AddOwnerToApplication (20250408145956)
2025-09-16T11:01:07.070449259Z == 20250408145956 AddOwnerToApplication: migrating ============================
2025-09-16T11:01:07.070457195Z -- add_column(:oauth_applications, :owner_id, :bigint, {null: true})
2025-09-16T11:01:07.070465111Z    -> 0.0010s
2025-09-16T11:01:07.070472957Z -- add_column(:oauth_applications, :owner_type, :string, {null: true})
2025-09-16T11:01:07.070480893Z    -> 0.0009s
2025-09-16T11:01:07.070488729Z -- add_index(:oauth_applications, [:owner_id, :owner_type])
2025-09-16T11:01:07.070496725Z    -> 0.0002s
2025-09-16T11:01:07.070504580Z == 20250408145956 AddOwnerToApplication: migrated (0.0074s) ===================
2025-09-16T11:01:07.070512546Z 
2025-09-16T11:01:07.070520232Z Migrating to RemoveDefaultActorEntityType (20250409120155)
2025-09-16T11:01:07.070528499Z == 20250409120155 RemoveDefaultActorEntityType: migrating =====================
2025-09-16T11:01:07.070536435Z -- change_column_default(:federails_actors, :entity_type, nil)
2025-09-16T11:01:07.070544391Z    -> 0.0102s
2025-09-16T11:01:07.070552196Z == 20250409120155 RemoveDefaultActorEntityType: migrated (0.0116s) ============
2025-09-16T11:01:07.070560142Z 
2025-09-16T11:01:07.070567868Z Migrating to AddQuotaToUsers (20250409125753)
2025-09-16T11:01:07.070576175Z == 20250409125753 AddQuotaToUsers: migrating ==================================
2025-09-16T11:01:07.070584211Z -- add_column(:users, :quota, :integer, {default: 1, null: false})
2025-09-16T11:01:07.070592107Z    -> 0.0013s
2025-09-16T11:01:07.070599892Z -- add_column(:users, :quota_use_site_default, :boolean, {default: true, null: false})
2025-09-16T11:01:07.070608259Z    -> 0.0010s
2025-09-16T11:01:07.070616045Z == 20250409125753 AddQuotaToUsers: migrated (0.0038s) =========================
2025-09-16T11:01:07.070623981Z 
2025-09-16T11:01:07.070631666Z Migrating to AddLowercaseFilenameVirtualFieldToModelFiles (20250423094525)
2025-09-16T11:01:07.070639612Z == 20250423094525 AddLowercaseFilenameVirtualFieldToModelFiles: migrating =====
2025-09-16T11:01:07.070647588Z -- add_column(:model_files, :filename_lower, :virtual, {type: :string, as: "LOWER(filename)", stored: true})
2025-09-16T11:01:07.070655865Z    -> 0.0137s
2025-09-16T11:01:07.070663641Z -- add_index(:model_files, :filename_lower)
2025-09-16T11:01:07.070671527Z    -> 0.0002s
2025-09-16T11:01:07.070679473Z == 20250423094525 AddLowercaseFilenameVirtualFieldToModelFiles: migrated (0.0154s) 
2025-09-16T11:01:07.070687549Z 
2025-09-16T11:01:07.070695254Z Migrating to AddUniqueIndexToLinks (20250425104619)
2025-09-16T11:01:07.070703170Z == 20250425104619 AddUniqueIndexToLinks: migrating ============================
2025-09-16T11:01:07.070720034Z -- add_index(:links, [:linkable_id, :linkable_type, :url])
2025-09-16T11:01:07.070728070Z    -> 0.0002s
2025-09-16T11:01:07.070735906Z == 20250425104619 AddUniqueIndexToLinks: migrated (0.0012s) ===================
2025-09-16T11:01:07.070743862Z 
2025-09-16T11:01:07.070751528Z Migrating to AddPreviewToModelFile (20250520111046)
2025-09-16T11:01:07.070759424Z == 20250520111046 AddPreviewToModelFile: migrating ============================
2025-09-16T11:01:07.070767790Z -- add_column(:model_files, :previewable, :boolean, {default: false, null: false})
2025-09-16T11:01:07.070775797Z    -> 0.0012s
2025-09-16T11:01:07.070783562Z == 20250520111046 AddPreviewToModelFile: migrated (0.0021s) ===================
2025-09-16T11:01:07.070791558Z 
2025-09-16T11:01:07.070799204Z Migrating to CreateAltchaSolutions (20250609210440)
2025-09-16T11:01:07.070807069Z == 20250609210440 CreateAltchaSolutions: migrating ============================
2025-09-16T11:01:07.070815066Z -- create_table(:altcha_solutions)
2025-09-16T11:01:07.070822911Z    -> 0.0004s
2025-09-16T11:01:07.070830837Z -- add_index(:altcha_solutions, [:algorithm, :challenge, :salt, :signature, :number], {unique: true, name: "index_altcha_solutions"})
2025-09-16T11:01:07.070839294Z    -> 0.0002s
2025-09-16T11:01:07.070847431Z == 20250609210440 CreateAltchaSolutions: migrated (0.0018s) ===================
2025-09-16T11:01:07.070855427Z 
2025-09-16T11:01:07.070863112Z Migrating to AddIndexableFields (20250620141805)
2025-09-16T11:01:07.070871008Z == 20250620141805 AddIndexableFields: migrating ===============================
2025-09-16T11:01:07.070878994Z -- change_table(:models)
2025-09-16T11:01:07.070886800Z    -> 0.0025s
2025-09-16T11:01:07.070894696Z -- change_table(:creators)
2025-09-16T11:01:07.070902522Z    -> 0.0018s
2025-09-16T11:01:07.070910327Z -- change_table(:collections)
2025-09-16T11:01:07.070918143Z    -> 0.0017s
2025-09-16T11:01:07.070926029Z == 20250620141805 AddIndexableFields: migrated (0.0060s) ======================
2025-09-16T11:01:07.070934095Z 
2025-09-16T11:01:07.070942232Z Migrating to ChangeIndexableFromIntegerToString (20250621223410)
2025-09-16T11:01:07.070950258Z == 20250621223410 ChangeIndexableFromIntegerToString: migrating ===============
2025-09-16T11:01:07.070958304Z -- change_column(:models, :indexable, :string)
2025-09-16T11:01:07.070966210Z    -> 0.0262s
2025-09-16T11:01:07.070974156Z -- change_column(:models, :ai_indexable, :string)
2025-09-16T11:01:07.070982062Z    -> 0.0197s
2025-09-16T11:01:07.070989868Z -- change_column(:creators, :indexable, :string)
2025-09-16T11:01:07.070997743Z    -> 0.0094s
2025-09-16T11:01:07.071014467Z -- change_column(:creators, :ai_indexable, :string)
2025-09-16T11:01:07.071022443Z    -> 0.0093s
2025-09-16T11:01:07.071030279Z -- change_column(:collections, :indexable, :string)
2025-09-16T11:01:07.071038185Z    -> 0.0548s
2025-09-16T11:01:07.071045981Z -- change_column(:collections, :ai_indexable, :string)
2025-09-16T11:01:07.071053886Z    -> 0.0188s
2025-09-16T11:01:07.071061692Z == 20250621223410 ChangeIndexableFromIntegerToString: migrated (0.1388s) ======
2025-09-16T11:01:07.071069638Z 
2025-09-16T11:01:07.071077223Z Migrating to AddOrderIndexesToTables (20250629212656)
2025-09-16T11:01:07.071085189Z == 20250629212656 AddOrderIndexesToTables: migrating ==========================
2025-09-16T11:01:07.071093196Z -- change_table(:models)
2025-09-16T11:01:07.071100961Z    -> 0.0004s
2025-09-16T11:01:07.071108747Z -- change_table(:creators)
2025-09-16T11:01:07.071116513Z    -> 0.0003s
2025-09-16T11:01:07.071124298Z -- change_table(:collections)
2025-09-16T11:01:07.071132024Z    -> 0.0003s
2025-09-16T11:01:07.071139819Z == 20250629212656 AddOrderIndexesToTables: migrated (0.0010s) =================
2025-09-16T11:01:07.071147735Z 
2025-09-16T11:01:07.071155431Z Migrating to AddIndexOnLinkUrl (20250716093106)
2025-09-16T11:01:07.071163407Z == 20250716093106 AddIndexOnLinkUrl: migrating ================================
2025-09-16T11:01:07.071171393Z -- change_table(:links)
2025-09-16T11:01:07.071179129Z    -> 0.0002s
2025-09-16T11:01:07.071186924Z == 20250716093106 AddIndexOnLinkUrl: migrated (0.0002s) =======================
2025-09-16T11:01:07.071194850Z 
2025-09-16T11:01:07.071202546Z Migrating to AddSyncedAtToLinks (20250724094951)
2025-09-16T11:01:07.071210472Z == 20250724094951 AddSyncedAtToLinks: migrating ===============================
2025-09-16T11:01:07.071218548Z -- add_column(:links, :synced_at, :datetime)
2025-09-16T11:01:07.071226454Z    -> 0.0010s
2025-09-16T11:01:07.071234290Z == 20250724094951 AddSyncedAtToLinks: migrated (0.0011s) ======================
2025-09-16T11:01:07.071242356Z 
2025-09-16T11:01:07.071250051Z Migrating to CreateFaspClientProviders (20250806142734)
2025-09-16T11:01:07.071258007Z == 20250806142734 CreateFaspClientProviders: migrating ========================
2025-09-16T11:01:07.071265963Z -- create_table(:fasp_client_providers)
2025-09-16T11:01:07.071273829Z    -> 0.0477s
2025-09-16T11:01:07.071281725Z == 20250806142734 CreateFaspClientProviders: migrated (0.0478s) ===============
2025-09-16T11:01:07.071289711Z 
2025-09-16T11:01:07.071297367Z Migrating to CreateFaspClientEventSubscriptions (20250908112415)
2025-09-16T11:01:07.071314672Z == 20250908112415 CreateFaspClientEventSubscriptions: migrating ===============
2025-09-16T11:01:07.071322778Z -- create_table(:fasp_client_event_subscriptions)
2025-09-16T11:01:07.071330674Z    -> 0.0141s
2025-09-16T11:01:07.071338490Z == 20250908112415 CreateFaspClientEventSubscriptions: migrated (0.0141s) ======
2025-09-16T11:01:07.071346486Z 
2025-09-16T11:01:07.071354632Z Migrating to CreateFaspClientBackfillRequests (20250908165009)
2025-09-16T11:01:07.094554036Z == 20250908165009 CreateFaspClientBackfillRequests: migrating =================
2025-09-16T11:01:07.094594667Z -- create_table(:fasp_client_backfill_requests)
2025-09-16T11:01:07.094603816Z    -> 0.0055s
2025-09-16T11:01:07.094612413Z == 20250908165009 CreateFaspClientBackfillRequests: migrated (0.0056s) ========
2025-09-16T11:01:07.094620570Z 
2025-09-16T11:01:07.155914550Z s6-rc: info: service init-manyfold-config successfully started
2025-09-16T11:01:07.156276719Z s6-rc: info: service init-config-end: starting
2025-09-16T11:01:07.158803476Z s6-rc: info: service init-config-end successfully started
2025-09-16T11:01:07.159134052Z s6-rc: info: service init-mods: starting
2025-09-16T11:01:07.161044208Z s6-rc: info: service init-mods successfully started
2025-09-16T11:01:07.161387650Z s6-rc: info: service init-mod-universal-redis-add-package: starting
2025-09-16T11:01:07.170543636Z s6-rc: info: service init-mod-universal-redis-add-package successfully started
2025-09-16T11:01:07.170741735Z s6-rc: info: service init-mods-package-install: starting
2025-09-16T11:01:07.183490195Z [pkg-install-init] **** Installing all mod packages ****
2025-09-16T11:01:07.195808950Z fetch http://dl-cdn.alpinelinux.org/alpine/v3.22/main/x86_64/APKINDEX.tar.gz
2025-09-16T11:01:07.274817559Z fetch http://dl-cdn.alpinelinux.org/alpine/v3.22/community/x86_64/APKINDEX.tar.gz
2025-09-16T11:01:07.595629747Z (1/1) Installing redis (8.0.3-r0)
2025-09-16T11:01:07.606774738Z Executing redis-8.0.3-r0.pre-install
2025-09-16T11:01:07.647919807Z Executing redis-8.0.3-r0.post-install
2025-09-16T11:01:07.655882640Z Executing busybox-1.37.0-r19.trigger
2025-09-16T11:01:07.669960026Z OK: 427 MiB in 116 packages
2025-09-16T11:01:07.758094982Z s6-rc: info: service init-mods-package-install successfully started
2025-09-16T11:01:07.758402411Z s6-rc: info: service init-mods-end: starting
2025-09-16T11:01:07.760544254Z s6-rc: info: service init-mods-end successfully started
2025-09-16T11:01:07.760742453Z s6-rc: info: service init-custom-files: starting
2025-09-16T11:01:07.767819613Z [custom-init] No custom files found, skipping...
2025-09-16T11:01:07.768452317Z s6-rc: info: service init-custom-files successfully started
2025-09-16T11:01:07.768647630Z s6-rc: info: service init-services: starting
2025-09-16T11:01:07.770291831Z s6-rc: info: service init-services successfully started
2025-09-16T11:01:07.770443336Z s6-rc: info: service svc-mod-universal-redis: starting
2025-09-16T11:01:07.770710975Z s6-rc: info: service svc-manyfold: starting
2025-09-16T11:01:07.771001470Z s6-rc: info: service svc-cron: starting
2025-09-16T11:01:07.773544500Z s6-rc: info: service svc-mod-universal-redis successfully started
2025-09-16T11:01:07.773902501Z s6-rc: info: service svc-cron successfully started
2025-09-16T11:01:07.788257696Z 282:C 16 Sep 2025 11:01:07.788 # WARNING Memory overcommit must be enabled! Without it, a background save or replication may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
2025-09-16T11:01:07.788288168Z 282:C 16 Sep 2025 11:01:07.788 * oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
2025-09-16T11:01:07.788296605Z 282:C 16 Sep 2025 11:01:07.788 * Redis version=8.0.3, bits=64, commit=00000000, modified=1, pid=282, just started
2025-09-16T11:01:07.788303839Z 282:C 16 Sep 2025 11:01:07.788 # Warning: no config file specified, using the default config. In order to specify a config file use redis-server /path/to/redis.conf
2025-09-16T11:01:07.789284704Z 282:M 16 Sep 2025 11:01:07.789 * monotonic clock: POSIX clock_gettime
2025-09-16T11:01:07.789953321Z 282:M 16 Sep 2025 11:01:07.789 * Running mode=standalone, port=6379.
2025-09-16T11:01:07.790413347Z 282:M 16 Sep 2025 11:01:07.790 * Server initialized
2025-09-16T11:01:07.790437205Z 282:M 16 Sep 2025 11:01:07.790 * Ready to accept connections tcp
2025-09-16T11:01:07.949747417Z 11:01:07 rails.1              | started with pid 301
2025-09-16T11:01:07.949788149Z 11:01:07 default_worker.1     | started with pid 302
2025-09-16T11:01:07.949817799Z 11:01:07 performance_worker.1 | started with pid 303
2025-09-16T11:01:09.179454609Z 11:01:09 rails.1              | `/root` is not writable.
2025-09-16T11:01:09.179487405Z 11:01:09 rails.1              | Bundler will use `/tmp/bundler20250916-301-u2d09j301' as your home directory temporarily.
2025-09-16T11:01:09.681314148Z 11:01:09 default_worker.1     | `/root` is not writable.
2025-09-16T11:01:09.681358197Z 11:01:09 default_worker.1     | Bundler will use `/tmp/bundler20250916-302-6wjtd3302' as your home directory temporarily.
2025-09-16T11:01:09.681389740Z 11:01:09 performance_worker.1 | `/root` is not writable.
2025-09-16T11:01:09.681846610Z 11:01:09 performance_worker.1 | Bundler will use `/tmp/bundler20250916-303-e5nfxx303' as your home directory temporarily.
2025-09-16T11:01:10.215034681Z 11:01:10 rails.1              | /app/www/vendor/bundle/ruby/3.4.0/gems/rswag-ui-2.16.0/lib/rswag/ui/configuration.rb:1: warning: /usr/lib/ruby/3.4.0/ostruct.rb was loaded from the standard library, but will no longer be part of the default gems starting from Ruby 3.5.0.
2025-09-16T11:01:10.215084371Z 11:01:10 rails.1              | You can add ostruct to your Gemfile or gemspec to silence this warning.
2025-09-16T11:01:10.215132769Z 11:01:10 rails.1              | Also please contact the author of rswag-ui-2.16.0 to request adding ostruct into its gemspec.
2025-09-16T11:01:10.506560378Z 11:01:10 rails.1              | => Booting Puma
2025-09-16T11:01:10.506587834Z 11:01:10 rails.1              | => Rails 8.0.2.1 application starting in production 
2025-09-16T11:01:10.506597513Z 11:01:10 rails.1              | => Run `bin/rails server --help` for more startup options
2025-09-16T11:01:10.657102716Z 11:01:10 default_worker.1     | /app/www/vendor/bundle/ruby/3.4.0/gems/rswag-ui-2.16.0/lib/rswag/ui/configuration.rb:1: warning: /usr/lib/ruby/3.4.0/ostruct.rb was loaded from the standard library, but will no longer be part of the default gems starting from Ruby 3.5.0.
2025-09-16T11:01:10.657128358Z 11:01:10 default_worker.1     | You can add ostruct to your Gemfile or gemspec to silence this warning.
2025-09-16T11:01:10.657140853Z 11:01:10 default_worker.1     | Also please contact the author of rswag-ui-2.16.0 to request adding ostruct into its gemspec.
2025-09-16T11:01:10.665692602Z 11:01:10 performance_worker.1 | /app/www/vendor/bundle/ruby/3.4.0/gems/rswag-ui-2.16.0/lib/rswag/ui/configuration.rb:1: warning: /usr/lib/ruby/3.4.0/ostruct.rb was loaded from the standard library, but will no longer be part of the default gems starting from Ruby 3.5.0.
2025-09-16T11:01:10.665718144Z 11:01:10 performance_worker.1 | You can add ostruct to your Gemfile or gemspec to silence this warning.
2025-09-16T11:01:10.665819638Z 11:01:10 performance_worker.1 | Also please contact the author of rswag-ui-2.16.0 to request adding ostruct into its gemspec.
2025-09-16T11:01:11.106888711Z 11:01:11 default_worker.1     | Server: f09130fa3d8f, Context: rails, Role: web, data: {cpu: {one_min: 5.06, five_min: 3.81, fifteen_min: 2.86}, memory: 0.182472704e9, disk: {available: 841157242880, total: 938656133120, used: 97498890240}}
2025-09-16T11:01:11.114752385Z 11:01:11 performance_worker.1 | Server: f09130fa3d8f, Context: rails, Role: web, data: {cpu: {one_min: 5.06, five_min: 3.81, fifteen_min: 2.86}, memory: 0.17956864e9, disk: {available: 841154699264, total: 938656133120, used: 97501433856}}
2025-09-16T11:01:13.227673819Z 11:01:13 default_worker.1     | Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.229147767Z 11:01:13 performance_worker.1 | Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.229203640Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.228Z pid=302 tid=21i: Sidekiq 8.0.7 connecting to Redis with options {size: 10, pool_name: "internal", url: nil}
2025-09-16T11:01:13.230246640Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.229Z pid=303 tid=21j: Sidekiq 8.0.7 connecting to Redis with options {size: 10, pool_name: "internal", url: nil}
2025-09-16T11:01:13.234219520Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.233Z pid=302 tid=21i: Cron Jobs - added job with name clear-shrine-cache in the namespace default
2025-09-16T11:01:13.234361956Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.234Z pid=303 tid=21j: Cron Jobs - added job with name clear-shrine-cache in the namespace default
2025-09-16T11:01:13.235493485Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.235Z pid=302 tid=21i: Cron Jobs - added job with name clear-downloads in the namespace default
2025-09-16T11:01:13.287709606Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.287Z pid=302 tid=21i: Booted Rails 8.0.2.1 application in production environment
2025-09-16T11:01:13.287795779Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.287Z pid=302 tid=21i: Running in ruby 3.4.4 (2025-05-14 revision a38531fd3f) +YJIT +PRISM [x86_64-linux-musl]
2025-09-16T11:01:13.287808144Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.287Z pid=303 tid=21j: Booted Rails 8.0.2.1 application in production environment
2025-09-16T11:01:13.288029881Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.287Z pid=302 tid=21i: See LICENSE and the LGPL-3.0 for licensing details.
2025-09-16T11:01:13.288056945Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.287Z pid=303 tid=21j: Running in ruby 3.4.4 (2025-05-14 revision a38531fd3f) +YJIT +PRISM [x86_64-linux-musl]
2025-09-16T11:01:13.288172829Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.287Z pid=302 tid=21i: Upgrade to Sidekiq Pro for more features and support: https://sidekiq.org
2025-09-16T11:01:13.288194713Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.287Z pid=303 tid=21j: See LICENSE and the LGPL-3.0 for licensing details.
2025-09-16T11:01:13.288346378Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.287Z pid=303 tid=21j: Upgrade to Sidekiq Pro for more features and support: https://sidekiq.org
2025-09-16T11:01:13.290891002Z 11:01:13 performance_worker.1 | INFO  2025-09-16T11:01:13.290Z pid=303 tid=21j: Sidekiq 8.0.7 connecting to Redis with options {size: 1, pool_name: "default", url: nil}
2025-09-16T11:01:13.291001324Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.290Z pid=302 tid=21i: Sidekiq 8.0.7 connecting to Redis with options {size: 4, pool_name: "default", url: nil}
2025-09-16T11:01:13.400623466Z 11:01:13 rails.1              | Server: f09130fa3d8f, Context: rails, Role: web, data: {cpu: {one_min: 5.06, five_min: 3.81, fifteen_min: 2.86}, memory: 0.173666304e9, disk: {available: 841326845952, total: 938656133120, used: 97329287168}}
2025-09-16T11:01:13.400688026Z 11:01:13 rails.1              | Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.400717425Z 11:01:13 rails.1              | INFO  2025-09-16T11:01:13.230Z pid=301 tid=2bp: Sidekiq 8.0.7 connecting to Redis with options {size: 10, pool_name: "internal", url: nil}
2025-09-16T11:01:13.400752225Z 11:01:13 rails.1              | INFO  2025-09-16T11:01:13.235Z pid=301 tid=2bp: Cron Jobs - added job with name clear-downloads in the namespace default
2025-09-16T11:01:13.400802236Z 11:01:13 rails.1              | [ActiveJob] Enqueued Upgrade::FixNilFileSizeValues (Job ID: f3268989-eef7-4c05-9de1-300ec5f7f4ef) to Sidekiq(upgrade)
2025-09-16T11:01:13.400845703Z 11:01:13 rails.1              | [ActiveJob] Enqueued Upgrade::BackfillDataPackages (Job ID: 9b3f1bbc-588b-4fd5-b02d-f51add08e2a4) to Sidekiq(upgrade)
2025-09-16T11:01:13.400892137Z 11:01:13 rails.1              | [ActiveJob] Enqueued Upgrade::DisambiguateUsernamesJob (Job ID: d4cc7f6a-f852-4cb8-89e0-3653598b1845) to Sidekiq(upgrade)
2025-09-16T11:01:13.400943360Z 11:01:13 rails.1              | [ActiveJob] Enqueued Upgrade::UpdateActorsJob (Job ID: f8787cf1-3605-428a-b0ad-88d19d1d2c44) to Sidekiq(upgrade)
2025-09-16T11:01:13.400985875Z 11:01:13 rails.1              | [ActiveJob] Enqueued Upgrade::FixParentCollections (Job ID: e6e81264-0094-4fa2-9bde-dd482df4cc70) to Sidekiq(upgrade)
2025-09-16T11:01:13.401030916Z 11:01:13 rails.1              | [ActiveJob] Enqueued Upgrade::PruneOrphanedProblems (Job ID: 60c5f18a-68be-4e24-90aa-4178de8f0ae1) to Sidekiq(upgrade)
2025-09-16T11:01:13.401075476Z 11:01:13 rails.1              | [301] Puma starting in cluster mode...
2025-09-16T11:01:13.401122130Z 11:01:13 rails.1              | [301] * Puma version: 7.0.3 ("Romantic Warrior")
2025-09-16T11:01:13.401169365Z 11:01:13 rails.1              | [301] * Ruby version: ruby 3.4.4 (2025-05-14 revision a38531fd3f) +YJIT +PRISM [x86_64-linux-musl]
2025-09-16T11:01:13.401216860Z 11:01:13 rails.1              | [301] *  Min threads: 4
2025-09-16T11:01:13.401258645Z 11:01:13 rails.1              | [301] *  Max threads: 16
2025-09-16T11:01:13.401311681Z 11:01:13 rails.1              | [301] *  Environment: production
2025-09-16T11:01:13.401353375Z 11:01:13 rails.1              | [301] *   Master PID: 301
2025-09-16T11:01:13.401402204Z 11:01:13 rails.1              | [301] *      Workers: 4
2025-09-16T11:01:13.401442294Z 11:01:13 rails.1              | [301] *     Restarts: (✔) hot (✖) phased (✖) refork
2025-09-16T11:01:13.401487696Z 11:01:13 rails.1              | [301] * Preloading application
2025-09-16T11:01:13.401541384Z 11:01:13 rails.1              | [301] * Listening on http://[::]:3214
2025-09-16T11:01:13.401590222Z 11:01:13 rails.1              | [301] Use Ctrl-C to stop
2025-09-16T11:01:13.419938347Z 11:01:13 rails.1              | [301] - Worker 0 (PID: 377) booted in 0.02s, phase: 0
2025-09-16T11:01:13.420089111Z 11:01:13 rails.1              | [301] - Worker 1 (PID: 389) booted in 0.01s, phase: 0
2025-09-16T11:01:13.420110795Z 11:01:13 rails.1              | [301] - Worker 2 (PID: 403) booted in 0.01s, phase: 0
2025-09-16T11:01:13.421894356Z 11:01:13 rails.1              | [301] - Worker 3 (PID: 416) booted in 0.0s, phase: 0
2025-09-16T11:01:13.566893141Z 11:01:13 performance_worker.1 | Server: f09130fa3d8f, Context: sidekiq, Role: background, data: {cpu: {one_min: 5.3, five_min: 3.88, fifteen_min: 2.89}, memory: 0.230162432e9, disk: {available: 840529203200, total: 938656133120, used: 98126929920}}
2025-09-16T11:01:13.568360877Z 11:01:13 default_worker.1     | Server: f09130fa3d8f, Context: sidekiq, Role: background, data: {cpu: {one_min: 5.3, five_min: 3.88, fifteen_min: 2.89}, memory: 0.229220352e9, disk: {available: 840528912384, total: 938656133120, used: 98127220736}}
2025-09-16T11:01:13.648911761Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.648Z pid=302 tid=h0u jid=aa1b42ae38887d9132ce48e0 class=Upgrade::FixNilFileSizeValues: start
2025-09-16T11:01:13.650139022Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixNilFileSizeValues] [f3268989-eef7-4c05-9de1-300ec5f7f4ef] Performing Upgrade::FixNilFileSizeValues (Job ID: f3268989-eef7-4c05-9de1-300ec5f7f4ef) from Sidekiq(upgrade) enqueued at 2025-09-16T11:01:13.247682659Z
2025-09-16T11:01:13.650459187Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.648Z pid=302 tid=h0m jid=b49bd121b5e21dfe02f40db8 class=Upgrade::BackfillDataPackages: start
2025-09-16T11:01:13.654099818Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::BackfillDataPackages] [9b3f1bbc-588b-4fd5-b02d-f51add08e2a4] Performing Upgrade::BackfillDataPackages (Job ID: 9b3f1bbc-588b-4fd5-b02d-f51add08e2a4) from Sidekiq(upgrade) enqueued at 2025-09-16T11:01:13.248675518Z
2025-09-16T11:01:13.664196839Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.648Z pid=302 tid=h1a jid=dd03ff2822d43bcd98af7d25 class=Upgrade::DisambiguateUsernamesJob: start
2025-09-16T11:01:13.664839693Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::DisambiguateUsernamesJob] [d4cc7f6a-f852-4cb8-89e0-3653598b1845] Performing Upgrade::DisambiguateUsernamesJob (Job ID: d4cc7f6a-f852-4cb8-89e0-3653598b1845) from Sidekiq(upgrade) enqueued at 2025-09-16T11:01:13.249343844Z
2025-09-16T11:01:13.666031243Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.649Z pid=302 tid=h2m jid=dea9994802f7256a342a38c7 class=Upgrade::UpdateActorsJob: start
2025-09-16T11:01:13.669369895Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::UpdateActorsJob] [f8787cf1-3605-428a-b0ad-88d19d1d2c44] Performing Upgrade::UpdateActorsJob (Job ID: f8787cf1-3605-428a-b0ad-88d19d1d2c44) from Sidekiq(upgrade) enqueued at 2025-09-16T11:01:13.249949914Z
2025-09-16T11:01:13.676048383Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::UpdateActorsJob] [f8787cf1-3605-428a-b0ad-88d19d1d2c44] Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.681045244Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::BackfillDataPackages] [9b3f1bbc-588b-4fd5-b02d-f51add08e2a4] Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.695259526Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::DisambiguateUsernamesJob] [d4cc7f6a-f852-4cb8-89e0-3653598b1845] Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.810036728Z Connection to localhost (::1) 3214 port [tcp/*] succeeded!
2025-09-16T11:01:13.811340994Z s6-rc: info: service svc-manyfold successfully started
2025-09-16T11:01:13.811561939Z s6-rc: info: service legacy-services: starting
2025-09-16T11:01:13.823678496Z s6-rc: info: service legacy-services successfully started
2025-09-16T11:01:13.823787596Z s6-rc: info: service ci-service-check: starting
2025-09-16T11:01:13.827584191Z [ls.io-init] done.
2025-09-16T11:01:13.828271334Z s6-rc: info: service ci-service-check successfully started
2025-09-16T11:01:13.913171637Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixNilFileSizeValues] [f3268989-eef7-4c05-9de1-300ec5f7f4ef] Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:13.952325175Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixNilFileSizeValues] [f3268989-eef7-4c05-9de1-300ec5f7f4ef] [JobIteration::Iteration] Enumerator found nothing to iterate! times_interrupted=0 cursor_position=
2025-09-16T11:01:13.962056059Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixNilFileSizeValues] [f3268989-eef7-4c05-9de1-300ec5f7f4ef] [JobIteration::Iteration] Completed iterating. times_interrupted=0 total_time=0.034
2025-09-16T11:01:13.977678406Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::UpdateActorsJob] [f8787cf1-3605-428a-b0ad-88d19d1d2c44] Performed Upgrade::UpdateActorsJob (Job ID: f8787cf1-3605-428a-b0ad-88d19d1d2c44) from Sidekiq(upgrade) in 308.29ms
2025-09-16T11:01:13.978267983Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::DisambiguateUsernamesJob] [d4cc7f6a-f852-4cb8-89e0-3653598b1845] Performed Upgrade::DisambiguateUsernamesJob (Job ID: d4cc7f6a-f852-4cb8-89e0-3653598b1845) from Sidekiq(upgrade) in 313.44ms
2025-09-16T11:01:13.979336875Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::BackfillDataPackages] [9b3f1bbc-588b-4fd5-b02d-f51add08e2a4] Performed Upgrade::BackfillDataPackages (Job ID: 9b3f1bbc-588b-4fd5-b02d-f51add08e2a4) from Sidekiq(upgrade) in 327.59ms
2025-09-16T11:01:13.985862906Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixNilFileSizeValues] [f3268989-eef7-4c05-9de1-300ec5f7f4ef] Performed Upgrade::FixNilFileSizeValues (Job ID: f3268989-eef7-4c05-9de1-300ec5f7f4ef) from Sidekiq(upgrade) in 335.87ms
2025-09-16T11:01:13.987179146Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.978Z pid=302 tid=h2m jid=dea9994802f7256a342a38c7 class=Upgrade::UpdateActorsJob elapsed=0.329: done
2025-09-16T11:01:13.987211551Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.985Z pid=302 tid=h1a jid=dd03ff2822d43bcd98af7d25 class=Upgrade::DisambiguateUsernamesJob elapsed=0.337: done
2025-09-16T11:01:13.987537538Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.987Z pid=302 tid=h2m jid=1d0d2b9f64dc9165b089aec9 class=Upgrade::FixParentCollections: start
2025-09-16T11:01:13.988188098Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixParentCollections] [e6e81264-0094-4fa2-9bde-dd482df4cc70] Performing Upgrade::FixParentCollections (Job ID: e6e81264-0094-4fa2-9bde-dd482df4cc70) from Sidekiq(upgrade) enqueued at 2025-09-16T11:01:13.250557678Z
2025-09-16T11:01:13.988486348Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.987Z pid=302 tid=h0u jid=aa1b42ae38887d9132ce48e0 class=Upgrade::FixNilFileSizeValues elapsed=0.339: done
2025-09-16T11:01:13.988518202Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.986Z pid=302 tid=h0m jid=b49bd121b5e21dfe02f40db8 class=Upgrade::BackfillDataPackages elapsed=0.338: done
2025-09-16T11:01:13.988894761Z 11:01:13 default_worker.1     | INFO  2025-09-16T11:01:13.987Z pid=302 tid=h1a jid=2c6e34af46f7b1875592c807 class=Upgrade::PruneOrphanedProblems: start
2025-09-16T11:01:13.992928273Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::PruneOrphanedProblems] [60c5f18a-68be-4e24-90aa-4178de8f0ae1] Performing Upgrade::PruneOrphanedProblems (Job ID: 60c5f18a-68be-4e24-90aa-4178de8f0ae1) from Sidekiq(upgrade) enqueued at 2025-09-16T11:01:13.251193017Z
2025-09-16T11:01:13.994869451Z 11:01:13 default_worker.1     | [ActiveJob] [Upgrade::FixParentCollections] [e6e81264-0094-4fa2-9bde-dd482df4cc70] Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:14.072581780Z 11:01:14 default_worker.1     | [ActiveJob] [Upgrade::PruneOrphanedProblems] [60c5f18a-68be-4e24-90aa-4178de8f0ae1] Scoped order is ignored, use :cursor with :order to configure custom order.
2025-09-16T11:01:14.283832598Z 11:01:14 default_worker.1     | [ActiveJob] [Upgrade::FixParentCollections] [e6e81264-0094-4fa2-9bde-dd482df4cc70] Performed Upgrade::FixParentCollections (Job ID: e6e81264-0094-4fa2-9bde-dd482df4cc70) from Sidekiq(upgrade) in 295.49ms
2025-09-16T11:01:14.287240130Z 11:01:14 default_worker.1     | [ActiveJob] [Upgrade::PruneOrphanedProblems] [60c5f18a-68be-4e24-90aa-4178de8f0ae1] [JobIteration::Iteration] Enumerator found nothing to iterate! times_interrupted=0 cursor_position=
2025-09-16T11:01:14.287265371Z 11:01:14 default_worker.1     | [ActiveJob] [Upgrade::PruneOrphanedProblems] [60c5f18a-68be-4e24-90aa-4178de8f0ae1] [JobIteration::Iteration] Completed iterating. times_interrupted=0 total_time=0.102
2025-09-16T11:01:14.311071897Z 11:01:14 default_worker.1     | INFO  2025-09-16T11:01:14.310Z pid=302 tid=h2m jid=1d0d2b9f64dc9165b089aec9 class=Upgrade::FixParentCollections elapsed=0.323: done
2025-09-16T11:01:14.315118916Z 11:01:14 default_worker.1     | [ActiveJob] [Upgrade::PruneOrphanedProblems] [60c5f18a-68be-4e24-90aa-4178de8f0ae1] Performed Upgrade::PruneOrphanedProblems (Job ID: 60c5f18a-68be-4e24-90aa-4178de8f0ae1) from Sidekiq(upgrade) in 325.28ms
2025-09-16T11:01:14.331137921Z 11:01:14 default_worker.1     | INFO  2025-09-16T11:01:14.330Z pid=302 tid=h1a jid=2c6e34af46f7b1875592c807 class=Upgrade::PruneOrphanedProblems elapsed=0.343: done
2025-09-16T11:01:32.511091563Z 11:01:32 rails.1              | [7184b462-159b-49a3-92a2-44c87c5cfd57] {"method":"GET","path":"/","format":"*/*","controller":"HomeController","action":"index","status":0,"allocations":1392,"duration":29.71,"view":0.0,"db":3.72,"@timestamp":"2025-09-16T11:01:32.509Z","@version":"1","message":"[0] GET / (HomeController#index)"}
2025-09-16T11:01:33.565966796Z 11:01:33 rails.1              | [537802e7-9bf7-4a3c-9099-ecdbb5237abe] {"method":"GET","path":"/users/sign_in","format":"*/*","controller":"Users::SessionsController","action":"new","status":302,"allocations":82497,"duration":995.04,"view":0.0,"db":26.12,"location":"http://172.17.0.4:3214/","@timestamp":"2025-09-16T11:01:33.565Z","@version":"1","message":"[302] GET /users/sign_in (Users::SessionsController#new)"}
2025-09-16T11:01:33.658780151Z 11:01:33 rails.1              | [625dddea-c5b3-486c-999a-e04c97ec7d53] {"method":"GET","path":"/","format":"*/*","controller":"HomeController","action":"index","status":302,"allocations":1059,"duration":58.92,"view":0.0,"db":3.66,"location":"http://172.17.0.4:3214/users/edit","@timestamp":"2025-09-16T11:01:33.636Z","@version":"1","message":"[302] GET / (HomeController#index)"}
2025-09-16T11:01:34.398843138Z 11:01:34 rails.1              | [4c74950a-87fd-4fb2-a39b-2f488d63a54f] {"method":"GET","path":"/users/edit","format":"*/*","controller":"Users::RegistrationsController","action":"edit","status":200,"allocations":23649,"duration":702.04,"view":541.92,"db":67.0,"@timestamp":"2025-09-16T11:01:34.398Z","@version":"1","message":"[200] GET /users/edit (Users::RegistrationsController#edit)"}
2025-09-16T11:01:34.457748518Z 11:01:34 rails.1              | [fe10c4c2-5819-4bae-a617-4f1502d3eaa1] {"method":"GET","path":"/","format":"html","controller":"HomeController","action":"index","status":0,"allocations":1180,"duration":21.03,"view":0.0,"db":3.07,"@timestamp":"2025-09-16T11:01:34.456Z","@version":"1","message":"[0] GET / (HomeController#index)"}
2025-09-16T11:01:34.802095144Z 11:01:34 rails.1              | [b856b8c6-26b0-4188-a2a7-652939dd9898] {"method":"GET","path":"/users/sign_in","format":"html","controller":"Users::SessionsController","action":"new","status":302,"allocations":14507,"duration":235.99,"view":0.0,"db":1.44,"location":"http://172.17.0.4:3214/","@timestamp":"2025-09-16T11:01:34.766Z","@version":"1","message":"[302] GET /users/sign_in (Users::SessionsController#new)"}
2025-09-16T11:01:34.855182879Z 11:01:34 rails.1              | [7d089c33-2808-4b39-bd24-b36f932cec8a] {"method":"GET","path":"/","format":"html","controller":"HomeController","action":"index","status":302,"allocations":1121,"duration":43.0,"view":0.0,"db":3.87,"location":"http://172.17.0.4:3214/users/edit","@timestamp":"2025-09-16T11:01:34.854Z","@version":"1","message":"[302] GET / (HomeController#index)"}
2025-09-16T11:01:35.220274616Z 11:01:35 rails.1              | [70329bd1-aab0-4951-a613-a67f33e91419] {"method":"GET","path":"/users/edit","format":"html","controller":"Users::RegistrationsController","action":"edit","status":200,"allocations":23864,"duration":337.02,"view":207.05,"db":1.16,"@timestamp":"2025-09-16T11:01:35.215Z","@version":"1","message":"[200] GET /users/edit (Users::RegistrationsController#edit)"}