Manual sync of not imported file (with --debug)
./photostructure sync --debug /storage/A7C/A7402916.ARW
2022-02-15T09:56:16.223Z sync-2523899 info SettingsIO.importFileSettings(/home/sony/.config/PhotoStructure/settings.toml) loaded { imported:
{ copyAssetsToLibrary: false,
libraryDir: '/storage/photostructure/library',
scanAllDrives: false,
scanPaths: [ '/storage/A7C' ] } }
2022-02-15T09:56:16.229Z sync-2523899 info SettingsIO _libraryHasSettings { result: true,
settings: '/storage/photostructure/library',
librarySettingsFile: '/storage/photostructure/library/.photostructure/settings.toml' }
2022-02-15T09:56:16.233Z sync-2523899 info SettingsIO.importFileSettings(/storage/photostructure/library/.photostructure/settings.toml) loaded { imported: { reportErrors: false } }
2022-02-15T09:56:16.236Z sync-2523899 info Service(sync) setup() { version: '1.1.0',
start: 1644918975856,
argv:
[ '/usr/bin/node',
'/home/sony/photostructure/bin/sync.js',
'--debug',
'/storage/A7C/A7402916.ARW' ],
arch: 'x64',
platform: 'linux',
isDocker: false,
os: 'Ubuntu 21.04 on x64',
isPacked: true,
isElectron: false,
versions:
{ node: '16.14.0',
v8: '9.4.146.24-node.20',
uv: '1.43.0',
zlib: '1.2.11',
brotli: '1.0.9',
ares: '1.18.1',
modules: '93',
nghttp2: '1.45.1',
napi: '8',
llhttp: '6.0.4',
openssl: '1.1.1m+quic',
cldr: '40.0',
icu: '70.1',
tz: '2021a3',
unicode: '14.0',
ngtcp2: '0.1.0-DEV',
nghttp3: '0.1.0-DEV' },
settings:
{ logLevel: 'debug',
httpPort: 1787,
rpcPort: 1807,
libraryDir: '/storage/photostructure/library' },
NODE_ENV: 'production',
PS_CACHE_DIR: '/storage/photostructure/cache',
PS_HTTP_PORT: '1787',
PS_LIBRARY_DIR: '/storage/photostructure/library',
PS_LOCALHOST: '127.0.0.1',
PS_PREVIEWS_DIR: '/storage/photostructure/previews',
PS_RPC_PORT: '1807',
PS_SCAN_ALL_DRIVES: 'false',
PS_SCAN_MY_PICTURES: 'false',
PS_SCAN_PATHS: '/storage/A7C' }
2022-02-15T09:56:16.238Z sync-2523899 info Library(/storage/photostructure/library) new()
2022-02-15T09:56:16.239Z sync-2523899 debug Library(/storage/photostructure/library) setup() started
2022-02-15T09:56:16.239Z sync-2523899 debug OpenedByIO() throwIfAvailable() { from: '(library setup)' }
2022-02-15T09:56:16.242Z sync-2523899 debug JsonFileStore prior /home/sony/.config/PhotoStructure/.system-uid.json { uid: '1412-42bk-b0d3-040u',
version: '2.0.0-beta.1',
type: 'system',
createdAt: 1644774158801 }
2022-02-15T09:56:16.250Z sync-2523899 debug OpenedBy.maybeRemovePriorLock(/storage/photostructure/library/.photostructure/opened-by/34eju35-2476395.json) Retaining prior lockfile
2022-02-15T09:56:16.250Z sync-2523899 debug OpenedBy.maybeRemovePriorLock(/storage/photostructure/library/.photostructure/opened-by/34eju35-2523735.json) Retaining prior lockfile
2022-02-15T09:56:16.251Z sync-2523899 debug OpenedBy.maybeRemovePriorLock(/storage/photostructure/library/.photostructure/opened-by/34eju35-2523899.json) Retaining prior lockfile
2022-02-15T09:56:16.252Z sync-2523899 debug OpenedBy.maybeRemovePriorLock(/storage/photostructure/library/.photostructure/opened-by/34eju35-319059.json) Retaining prior lockfile
2022-02-15T09:56:16.253Z sync-2523899 debug OpenedBy.maybeRemovePriorLock(/storage/photostructure/library/.photostructure/opened-by/34eju35-319071.json) Retaining prior lockfile
2022-02-15T09:56:16.255Z sync-2523899 debug currentLibraryLockOwner(/storage/photostructure/library/.photostructure) read { jsons:
[ { file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-2476395.json' },
serviceName: 'sync',
createdAt: 1644911460877,
updatedAt: 1644915788205,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 2476395 },
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-2523735.json' },
serviceName: 'info',
createdAt: 1644918889930,
updatedAt: 1644918889931,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 2523735 },
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-2523899.json' },
serviceName: 'sync',
createdAt: 1644918976239,
updatedAt: 1644918976240,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 2523899 },
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-319059.json' },
serviceName: 'main',
createdAt: 1644831144095,
updatedAt: 1644917544115,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 319059 },
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-319071.json' },
serviceName: 'web',
createdAt: 1644831142914,
updatedAt: 1644916453776,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 319071 } ] }
2022-02-15T09:56:16.256Z sync-2523899 debug ChildProcess stdoutResult(): execFile { cmd: 'ps',
args: [ '-p', '2476395,2523735,2523899,319059,319071', '-wwwo', 'pid,lstart,command' ] }
2022-02-15T09:56:16.259Z sync-2523899 debug ChildEnv env { SHELL: '/bin/bash',
SUDO_GID: '1000',
PS_SCAN_ALL_DRIVES: 'false',
SUDO_COMMAND: '/usr/bin/su sony',
PWD: '/home/sony/photostructure',
LOGNAME: 'sony',
PS_CACHE_DIR: '/storage/photostructure/cache',
HOME: '/home/sony',
LANG: 'C',
LS_COLORS:
'rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=00:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arc=01;31:*.arj=01;31:*.taz=01;31:*.lha=01;31:*.lz4=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.tzo=01;31:*.t7z=01;31:*.zip=01;31:*.z=01;31:*.dz=01;31:*.gz=01;31:*.lrz=01;31:*.lz=01;31:*.lzo=01;31:*.xz=01;31:*.zst=01;31:*.tzst=01;31:*.bz2=01;31:*.bz=01;31:*.tbz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.war=01;31:*.ear=01;31:*.sar=01;31:*.rar=01;31:*.alz=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.cab=01;31:*.wim=01;31:*.swm=01;31:*.dwm=01;31:*.esd=01;31:*.jpg=01;35:*.jpeg=01;35:*.mjpg=01;35:*.mjpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.webm=01;35:*.webp=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=00;36:*.au=00;36:*.flac=00;36:*.m4a=00;36:*.mid=00;36:*.midi=00;36:*.mka=00;36:*.mp3=00;36:*.mpc=00;36:*.ogg=00;36:*.ra=00;36:*.wav=00;36:*.oga=00;36:*.opus=00;36:*.spx=00;36:*.xspf=00;36:',
PS_HTTP_PORT: '1787',
PS_LIBRARY_DIR: '/storage/photostructure/library',
LESSCLOSE: '/usr/bin/lesspipe %s %s',
TERM: 'xterm',
LESSOPEN: '| /usr/bin/lesspipe %s',
PS_RPC_PORT: '1807',
USER: 'sony',
SHLVL: '1',
PS_PREVIEWS_DIR: '/storage/photostructure/previews',
PS_LOCALHOST: '127.0.0.1',
PATH:
'/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/usr/lib/libraw:/opt/local/bin:/opt/local/sbin',
PS_SCAN_MY_PICTURES: 'false',
SUDO_UID: '1000',
MAIL: '/var/mail/sony',
PS_SCAN_PATHS: '["/storage/A7C"]',
OLDPWD: '/home/mexus',
_: './photostructure',
NODE_ENV: 'production',
VIPS_MIN_STACK_SIZE: '2m',
VIPSHOME: '/target',
LC_ALL: 'C',
PS_NEVER_IGNORED: '["/storage/A7C/A7402916.ARW"]' }
2022-02-15T09:56:16.259Z sync-2523899 debug ChildProcess execFile() { command: 'ps',
args: [ '-p', '2476395,2523735,2523899,319059,319071', '-wwwo', 'pid,lstart,command' ],
maxAgeMs: 15000 }
2022-02-15T09:56:16.276Z sync-2523899 debug Ps pidInfo() { pids: [ 2476395, 2523735, 2523899, 319059, 319071 ],
result:
[ { pid: 319059, start: 2022-02-14T09:32:03.000Z, cmd: 'PhotoStructure' },
{ pid: 319071, start: 2022-02-14T09:32:04.000Z, cmd: 'PhotoStructure web' },
{ pid: 2476395, start: 2022-02-15T07:51:00.000Z, cmd: 'PhotoStructure sync' },
{ pid: 2523899, start: 2022-02-15T09:56:15.000Z, cmd: 'PhotoStructure sync' } ] }
2022-02-15T09:56:16.277Z sync-2523899 debug currentLibraryLockOwner(/storage/photostructure/library/.photostructure) Unlinking zombie opened-by files: [ { file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-2523735.json' },
serviceName: 'info',
createdAt: 1644918889930,
updatedAt: 1644918889931,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 2523735 } ]
2022-02-15T09:56:16.279Z sync-2523899 info currentLibraryLockOwner(/storage/photostructure/library/.photostructure) currentLibraryLockOwner { result:
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-319059.json' },
serviceName: 'main',
createdAt: 1644831144095,
updatedAt: 1644917544115,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 319059 } }
2022-02-15T09:56:16.279Z sync-2523899 debug OpenedByIO() throwIfAvailable() { from: '(library setup)',
lockOwner:
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-319059.json' },
serviceName: 'main',
createdAt: 1644831144095,
updatedAt: 1644917544115,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 319059 } }
2022-02-15T09:56:16.280Z sync-2523899 debug OpenedByIO() throwIfAvailable() { from: '(library setup)',
json:
{ serviceName: 'sync',
createdAt: 1644918976239,
updatedAt: 1644918976240,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 2523899 } }
2022-02-15T09:56:16.289Z sync-2523899 info SettingsIO.importFileSettings(/storage/photostructure/library/.photostructure/settings.toml) loaded { imported: { reportErrors: false } }
2022-02-15T09:56:16.293Z sync-2523899 debug JsonFileStore prior /storage/photostructure/library/.photostructure/.library-uid.json { uid: 'nhkb-nutw-w5mp-7v4x',
version: '2.0.0-beta.1',
type: 'library',
createdAt: 1644774158800 }
2022-02-15T09:56:16.294Z sync-2523899 debug JsonFileStore prior /home/sony/.config/PhotoStructure/.system-uid.json { uid: '1412-42bk-b0d3-040u',
version: '2.0.0-beta.1',
type: 'system',
createdAt: 1644774158801 }
2022-02-15T09:56:16.295Z sync-2523899 debug BaseFile(/storage/photostructure/cache/sync-state-5kt2qphu1a/README.txt) applyIfEmpty(): non-empty
2022-02-15T09:56:16.296Z sync-2523899 info StatsDbDir Set up statsDbDir dir /storage/photostructure/cache/sync-state-5kt2qphu1a
2022-02-15T09:56:16.297Z sync-2523899 debug RpcClient Creating new RPC client to 1807
2022-02-15T09:56:16.301Z sync-2523899 debug RpcClient Connected to 1807
2022-02-15T09:56:16.302Z sync-2523899 debug rpc.Client(db@localhost:1807#0) Resolved 'pong to sync@2523899 from main@319059 at Tue Feb 15 2022 11:56:16 GMT+0200 (Eastern European Standard Time)'
2022-02-15T09:56:16.303Z sync-2523899 debug rpc.Client(db@localhost:1807#0) Resolved false
2022-02-15T09:56:16.303Z sync-2523899 info Vacuum checkRemoteVacuuming() received RPC value { value: false }
2022-02-15T09:56:16.303Z sync-2523899 debug Vacuum setVacuuming() { value: false }
2022-02-15T09:56:16.305Z sync-2523899 debug rpc.Client(db@localhost:1807#0) Resolved [ { filesystem: '/dev/mapper/ubuntu--vg-ubuntu--lv',
mountpoint: '/',
size: 124687908864,
used: 34835992576,
available: 83474059264,
ignorable: false,
remote: false,
uuid: 'ee4cc9e6-2fb2-4f59-8594-73eb73982ccd' },
{ filesystem: '/dev/mapper/ubuntu--vg-lv--0',
mountpoint: '/home',
size: 124687908864,
used: 104692084736,
available: 13617967104,
ignorable: false,
remote: false,
uuid: 'f5efccf9-e358-48fa-bcec-4e843da027a3' },
{ filesystem: '/dev/sda1',
mountpoint: '/storage',
size: 1967934717952,
used: 876896493568,
available: 991001559040,
ignorable: false,
remote: false,
label: 'Storage',
uuid: 'd52c5696-194c-4298-a599-92f2677ce8f3' } ]
2022-02-15T09:56:16.307Z sync-2523899 debug Library(/storage/photostructure/library) isOnRemoteVolume() { result: false }
2022-02-15T09:56:16.308Z sync-2523899 info ModelDbJanitor(/storage/photostructure/library/.photostructure) Finished setup { primaryDbDir: { nativePath: '/storage/photostructure/library/.photostructure' },
backupDir: { nativePath: '/storage/photostructure/library/.photostructure/models/backup' } }
2022-02-15T09:56:16.308Z sync-2523899 info ModelDbJanitor(/storage/photostructure/library/.photostructure) ModelDb reading from /storage/photostructure/library/.photostructure/models/db.sqlite3
2022-02-15T09:56:16.309Z sync-2523899 info Db(stats) setting up new db connection to /storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3 { priorWasNull: true }
2022-02-15T09:56:16.310Z sync-2523899 info mkdb() Dynamically setting dbCacheSize to 192 { db: '/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3',
dbFileSize: 147456 }
2022-02-15T09:56:16.312Z sync-2523899 info SQLite verifyDb(/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3): OK
2022-02-15T09:56:16.315Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) migration filter { migrationName: '20181030_stats',
migrationDate: 2018-10-29T22:00:00.000Z,
gitDate: 2021-08-16T01:54:24.000Z }
2022-02-15T09:56:16.315Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) migration filter { migrationName: '20190312_files_not_bytes',
migrationDate: 2019-03-11T22:00:00.000Z,
gitDate: 2021-08-16T01:54:24.000Z }
2022-02-15T09:56:16.315Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) migration filter { migrationName: '20190515_rebuild_queue',
migrationDate: 2019-05-14T21:00:00.000Z,
gitDate: 2021-08-16T01:54:24.000Z }
2022-02-15T09:56:16.315Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) migration filter { migrationName: '20200106_no_dir_stats',
migrationDate: 2020-01-05T22:00:00.000Z,
gitDate: 2021-08-16T01:54:24.000Z }
2022-02-15T09:56:16.317Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) latest(): already applied 20181030_stats.sql
2022-02-15T09:56:16.317Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) latest(): already applied 20190312_files_not_bytes.sql
2022-02-15T09:56:16.317Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) latest(): already applied 20190515_rebuild_queue.sql
2022-02-15T09:56:16.317Z sync-2523899 debug Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) latest(): already applied 20200106_no_dir_stats.sql
2022-02-15T09:56:16.318Z sync-2523899 info Migration({"schema":"stats","db":"/storage/photostructure/cache/sync-state-5kt2qphu1a/stats/db.sqlite3"}) up to latest! { appliedMigrations: [] }
2022-02-15T09:56:16.318Z sync-2523899 info Library(/storage/photostructure/library) Library.setup() finished.
2022-02-15T09:56:16.318Z sync-2523899 debug rpc.Client(db@localhost:1807#0) Resolved false
2022-02-15T09:56:16.321Z sync-2523899 debug ChildProcess stdoutResult(): execFile { cmd: 'cpuid', args: [ '-1' ] }
2022-02-15T09:56:16.322Z sync-2523899 debug ChildProcess execFile() { command: 'cpuid', args: [ '-1' ], maxAgeMs: 5000 }
2022-02-15T09:56:16.327Z sync-2523899 debug JsonFileStore prior /storage/photostructure/library/.photostructure/.library-uid.json { uid: 'nhkb-nutw-w5mp-7v4x',
version: '2.0.0-beta.1',
type: 'library',
createdAt: 1644774158800 }
2022-02-15T09:56:16.328Z sync-2523899 debug ChildProcess stdoutResult.errorIsRetriable() { result: false,
error:
Error: cpuid exec'ed without a pid
at /home/sony/photostructure/bin/sync.js:9:124685
at processTicksAndRejections (node:internal/process/task_queues:96:5),
cmd: 'cpuid',
args: [ '-1' ] }
2022-02-15T09:56:16.330Z sync-2523899 info SystemIds cpuid failed Error: cpuid exec'ed without a pid
at /home/sony/photostructure/bin/sync.js:9:124685
at processTicksAndRejections (node:internal/process/task_queues:96:5)
2022-02-15T09:56:16.333Z sync-2523899 debug SystemIds sids() { result:
[ 'lm:27PKfUjtkCZJWPF',
'cm:2QDwijBFy7iwE1U',
'bm:2C9D6FQudu89RWj',
'ma:26FxrJJCb1QBBFD',
'ma:4vG5bGSwFK35ZfM',
'ma:kSkvUg4F6W74Ee5',
'ma:v2Tw1eaXU1HCjiB',
'li:36EaLdADrZAah4C',
'vl:3MDC1yTZnhNAu6Z',
'vl:r3VdktTo6fMHPRM',
'vl:Z1d184amJoMHea7' ] }
2022-02-15T09:56:16.335Z sync-2523899 debug Licensing licenses() { result: [] }
2022-02-15T09:56:16.336Z sync-2523899 info SyncService setup(): library is at /storage/photostructure/library
2022-02-15T09:56:16.337Z sync-2523899 info ChildService pathToService() { result: Promise { <pending> },
cmd: 'sync-file.js',
isPacked: true,
dirs:
[ '/home/sony/photostructure/bin/sync-file.js',
'/home/sony/photostructure/app.asar/sync-file.js',
'/home/sony/photostructure/dist/app/sync-file.js' ] }
2022-02-15T09:56:16.337Z sync-2523899 info SyncService syncFileCluster(): sync-file found at /home/sony/photostructure/bin/sync-file.js
2022-02-15T09:56:16.361Z sync-2523899 info Db(models) setting up new db connection to /storage/photostructure/library/.photostructure/models/db.sqlite3 { priorWasNull: true }
2022-02-15T09:56:16.361Z sync-2523899 info mkdb() Dynamically setting dbCacheSize to 192 { db: '/storage/photostructure/library/.photostructure/models/db.sqlite3',
dbFileSize: 12746752 }
2022-02-15T09:56:16.401Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144193,
createdAt: 1644831144190,
id: 1,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144193,
value: 'psfile://Z1d184amJ/',
version: 3 } }
2022-02-15T09:56:16.404Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144198,
createdAt: 1644831144197,
id: 2,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144198,
value: 'psfile://r3VdktTo6/',
version: 3 } }
2022-02-15T09:56:16.406Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144201,
createdAt: 1644831144200,
id: 3,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144201,
value: 'psfile://3MDC1yTZn/',
version: 3 } }
2022-02-15T09:56:16.409Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144204,
createdAt: 1644831144203,
id: 4,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144204,
value: 'pslib:',
version: 3 } }
2022-02-15T09:56:16.411Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144208,
createdAt: 1644831144207,
id: 5,
name: 'enqueueAssetFileUpdates',
updatedAt: 1644831144208,
value: 'psfile://Z1d184amJ/',
version: 11 } }
2022-02-15T09:56:16.412Z sync-2523899 debug enqueueAssetFileUpdatesForUri(psfile://Z1d184amJ/) Added outdated assetFiles: { queue: 'psfile://Z1d184amJ/', assetFileCount: 0 }
2022-02-15T09:56:16.413Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144210,
createdAt: 1644831144210,
id: 6,
name: 'enqueueAssetFileUpdates',
updatedAt: 1644831144210,
value: 'psfile://r3VdktTo6/',
version: 11 } }
2022-02-15T09:56:16.413Z sync-2523899 debug enqueueAssetFileUpdatesForUri(psfile://r3VdktTo6/) Added outdated assetFiles: { queue: 'psfile://r3VdktTo6/', assetFileCount: 0 }
2022-02-15T09:56:16.415Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144213,
createdAt: 1644831144212,
id: 7,
name: 'enqueueAssetFileUpdates',
updatedAt: 1644831144213,
value: 'psfile://3MDC1yTZn/',
version: 11 } }
2022-02-15T09:56:16.415Z sync-2523899 debug enqueueAssetFileUpdatesForUri(psfile://3MDC1yTZn/) Added outdated assetFiles: { queue: 'psfile://3MDC1yTZn/', assetFileCount: 0 }
2022-02-15T09:56:16.418Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144215,
createdAt: 1644831144214,
id: 8,
name: 'enqueueAssetFileUpdates',
updatedAt: 1644831144215,
value: 'pslib:',
version: 11 } }
2022-02-15T09:56:16.419Z sync-2523899 debug enqueueAssetFileUpdatesForUri(pslib:) Added outdated assetFiles: { queue: 'pslib:', assetFileCount: 0 }
2022-02-15T09:56:16.420Z sync-2523899 debug OpenedByIO() throwIfAvailable() { from: '(library health check)' }
2022-02-15T09:56:16.420Z sync-2523899 debug OpenedByIO() throwIfAvailable() { from: '(library health check)',
lockOwner:
{ file:
{ nativePath: '/storage/photostructure/library/.photostructure/opened-by/34eju35-319059.json' },
serviceName: 'main',
createdAt: 1644831144095,
updatedAt: 1644917544115,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 319059 } }
2022-02-15T09:56:16.420Z sync-2523899 debug OpenedByIO() throwIfAvailable() { from: '(library health check)',
json:
{ serviceName: 'sync',
createdAt: 1644918976239,
updatedAt: 1644918976240,
hostname: 'somedomain.com',
systemUID: '1412-42bk-b0d3-040u',
v: '1.1.0',
pid: 2523899 } }
2022-02-15T09:56:16.423Z sync-2523899 debug LibraryHealthChecks checkDir() { dir: '/storage/photostructure/cache',
desc: 'Cache directory',
bestVolume: '/storage' }
2022-02-15T09:56:16.424Z sync-2523899 debug LibraryHealthChecks checkDir() { dir: '/storage/photostructure/library',
desc: 'Library directory',
bestVolume: '/storage' }
2022-02-15T09:56:16.424Z sync-2523899 debug LibraryHealthChecks checkDir() { dir: '/storage/photostructure/library',
desc: 'Library originals directory',
bestVolume: '/storage' }
2022-02-15T09:56:16.424Z sync-2523899 debug LibraryHealthChecks checkDir() { dir: '/storage/photostructure/library/.photostructure/models/db.sqlite3',
desc: 'Library model DB',
bestVolume: '/storage' }
2022-02-15T09:56:16.426Z sync-2523899 debug Heartbeat assertPing() { heartbeat:
{ '$ctor': 'models.Heartbeat',
createdAt: 1644831143730,
id: 1,
name: 'ping',
updatedAt: 1644918976425 } }
2022-02-15T09:56:16.427Z sync-2523899 info ModelDbUpdater Starting asset file updates { afqName: 'WorkQueue()',
afqEnded: false,
afqRunnable: true,
idleStats:
{ doNotRun: { isPaused: false, ending: false, doNotRunImpl: false },
idleListeners: [],
runnableIdleListeners: [],
pendingWorkItems: [] } }
2022-02-15T09:56:16.428Z sync-2523899 info ModelDbUpdater Completed asset file updates { afqName: 'WorkQueue()',
afqEnded: true,
afqRunnable: false,
idleStats:
{ doNotRun: { isPaused: false, ending: false, doNotRunImpl: false },
idleListeners: [],
runnableIdleListeners: [],
pendingWorkItems: [] } }
2022-02-15T09:56:16.430Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144193,
createdAt: 1644831144190,
id: 1,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144193,
value: 'psfile://Z1d184amJ/',
version: 3 } }
2022-02-15T09:56:16.432Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144198,
createdAt: 1644831144197,
id: 2,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144198,
value: 'psfile://r3VdktTo6/',
version: 3 } }
2022-02-15T09:56:16.433Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144201,
createdAt: 1644831144200,
id: 3,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144201,
value: 'psfile://3MDC1yTZn/',
version: 3 } }
2022-02-15T09:56:16.435Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144204,
createdAt: 1644831144203,
id: 4,
name: 'enqueueAssetUpdates',
updatedAt: 1644831144204,
value: 'pslib:',
version: 3 } }
2022-02-15T09:56:16.436Z sync-2523899 info ModelDbUpdater Starting asset updates { aqName: 'WorkQueue()',
aqEnded: false,
aqRunnable: true,
idleStats:
{ doNotRun: { isPaused: false, ending: false, doNotRunImpl: false },
idleListeners: [ 'WorkQueue(AssetPreview)', 'WorkQueue()' ],
runnableIdleListeners: [ 'WorkQueue(AssetPreview)', 'WorkQueue()' ],
pendingWorkItems: [] } }
2022-02-15T09:56:16.437Z sync-2523899 info ModelDbUpdater run(): finished
2022-02-15T09:56:16.438Z sync-2523899 info TagSql forceRunTagMaintenance()
2022-02-15T09:56:16.438Z sync-2523899 info Tag findByPath { tagPath: [ 'fs' ], dbIsNull: false }
2022-02-15T09:56:16.440Z sync-2523899 info displayNameForVolsha(3MDC1yTZn) find() { vol:
{ filesystem: '/dev/sda1',
mountpoint: '/storage',
size: 1967934717952,
used: 876896493568,
available: 991001559040,
ignorable: false,
remote: false,
label: 'Storage',
uuid: 'd52c5696-194c-4298-a599-92f2677ce8f3' } }
2022-02-15T09:56:16.440Z sync-2523899 info fixFileRootTag(Tag(18)) updated tag { id: 18, path: [ 'fs', '3MDC1yTZn' ], displayName: 'Storage' }
2022-02-15T09:56:16.457Z sync-2523899 debug TagSql vacuumLeafTags() { candidates: [] }
2022-02-15T09:56:16.458Z sync-2523899 info TagSql vacuumLeafTags() complete. { candidates: [] }
2022-02-15T09:56:16.458Z sync-2523899 info SyncService modelDbUpdater completed.
2022-02-15T09:56:16.459Z sync-2523899 debug Operation applyOnce(): already done { prior:
{ '$ctor': 'models.Operation',
completedAt: 1644831144235,
createdAt: 1644831144234,
id: 9,
name: 'applyNewTagger',
updatedAt: 1644831144235,
value: 'fs',
version: 1 } }