ended_at: 2023-08-08 17:31:52.038525
env:
  YNH_APP_ACTION: upgrade
  YNH_APP_BASEDIR: /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r
  YNH_APP_CURRENT_VERSION: 3.3.2~ynh3
  YNH_APP_ID: grocy
  YNH_APP_INSTANCE_NAME: grocy
  YNH_APP_INSTANCE_NUMBER: '1'
  YNH_APP_MANIFEST_VERSION: 4.0.1~ynh1
  YNH_APP_PACKAGING_FORMAT: '2.0'
  YNH_APP_UPGRADE_TYPE: UPGRADE_FULL
  YNH_ARCH: amd64
  YNH_DEBIAN_VERSION: bullseye
error: null
interface: api
operation: app_upgrade
parent: null
related_to:
- - app
  - grocy
started_at: 2023-08-08 17:31:19.812231
success: true
yunohost_version: 11.2.3

============

2023-08-08 19:31:19,822: INFO - Updating sources...
2023-08-08 19:31:19,823: DEBUG - Prefetching asset main: https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip ...
2023-08-08 19:31:31,102: INFO - Updating system_user...
2023-08-08 19:31:31,135: INFO - Updating install_dir...
2023-08-08 19:31:31,145: INFO - Updating permissions...
2023-08-08 19:31:31,532: DEBUG - Nothing to update in LDAP
2023-08-08 19:31:31,716: DEBUG - Permission 'grocy.main' updated
2023-08-08 19:31:31,716: DEBUG - Full log of this operation: '<a href="#/tools/logs/20230808-173131-user_permission_update-grocy" style="text-decoration:underline">Update accesses for permission 'grocy'</a>'
2023-08-08 19:31:32,146: DEBUG - Nothing to update in LDAP
2023-08-08 19:31:32,147: DEBUG - Permission 'grocy.main' updated
2023-08-08 19:31:32,329: DEBUG - Full log of this operation: '<a href="#/tools/logs/20230808-173132-permission_url-grocy" style="text-decoration:underline">Update URL related to permission 'grocy'</a>'
2023-08-08 19:31:32,778: DEBUG - Permission 'grocy.api' updated
2023-08-08 19:31:32,962: DEBUG - Full log of this operation: '<a href="#/tools/logs/20230808-173132-permission_url-grocy" style="text-decoration:underline">Update URL related to permission 'grocy'</a>'
2023-08-08 19:31:33,382: DEBUG - Executing command '['sh', '-c', '/bin/bash -x "./50-freshrss" grocy \'\' api visitors 7>&1']'
2023-08-08 19:31:33,390: DEBUG - + set -a
2023-08-08 19:31:33,390: DEBUG - + source /usr/share/yunohost/helpers
2023-08-08 19:31:33,390: DEBUG - +++ set +o
2023-08-08 19:31:33,390: DEBUG - +++ grep xtrace
2023-08-08 19:31:33,391: DEBUG - ++ readonly 'XTRACE_ENABLE=set -o xtrace'
2023-08-08 19:31:33,391: DEBUG - ++ XTRACE_ENABLE='set -o xtrace'
2023-08-08 19:31:33,409: DEBUG - + app=grocy
2023-08-08 19:31:33,409: DEBUG - + new_users=
2023-08-08 19:31:33,409: DEBUG - + [[ freshrss = \g\r\o\c\y ]]
2023-08-08 19:31:34,410: DEBUG - Executing command '['sh', '-c', '/bin/bash -x "./50-calibreweb" grocy \'\' api visitors 7>&1']'
2023-08-08 19:31:34,416: DEBUG - + source /usr/share/yunohost/helpers
2023-08-08 19:31:34,417: DEBUG - +++ set +o
2023-08-08 19:31:34,417: DEBUG - +++ grep xtrace
2023-08-08 19:31:34,418: DEBUG - ++ readonly 'XTRACE_ENABLE=set -o xtrace'
2023-08-08 19:31:34,418: DEBUG - ++ XTRACE_ENABLE='set -o xtrace'
2023-08-08 19:31:34,432: DEBUG - + app=calibreweb
2023-08-08 19:31:34,433: DEBUG - + app_trigger=grocy
2023-08-08 19:31:34,433: DEBUG - + users=
2023-08-08 19:31:34,433: DEBUG - + permission=api
2023-08-08 19:31:34,433: DEBUG - + group=visitors
2023-08-08 19:31:34,433: DEBUG - ++ echo visitors
2023-08-08 19:31:34,433: DEBUG - ++ grep visitors
2023-08-08 19:31:34,434: DEBUG - + '[' visitors ']'
2023-08-08 19:31:34,434: DEBUG - + '[' api = main ']'
2023-08-08 19:31:35,435: DEBUG - Permission 'grocy.api' created
2023-08-08 19:31:35,436: DEBUG - Full log of this operation: '<a href="#/tools/logs/20230808-173132-permission_create-grocy" style="text-decoration:underline">Create permission 'grocy'</a>'
2023-08-08 19:31:36,002: DEBUG - Permission 'grocy.api' updated
2023-08-08 19:31:36,003: DEBUG - Full log of this operation: '<a href="#/tools/logs/20230808-173135-user_permission_update-grocy" style="text-decoration:underline">Update accesses for permission 'grocy'</a>'
2023-08-08 19:31:36,432: DEBUG - Nothing to update in LDAP
2023-08-08 19:31:36,432: DEBUG - Permission 'grocy.api' updated
2023-08-08 19:31:36,615: DEBUG - Full log of this operation: '<a href="#/tools/logs/20230808-173136-permission_url-grocy" style="text-decoration:underline">Update URL related to permission 'grocy'</a>'
2023-08-08 19:31:36,805: DEBUG - The permission database has been resynchronized
2023-08-08 19:31:37,061: DEBUG - SSOwat configuration regenerated
2023-08-08 19:31:37,067: INFO - Updating apt...
2023-08-08 19:31:37,084: DEBUG - Executing command '['sh', '-c', '/bin/bash -x "./provision_or_update_apt"  7>&1']'
2023-08-08 19:31:37,090: DEBUG - + source /usr/share/yunohost/helpers
2023-08-08 19:31:37,091: DEBUG - +++ set +o
2023-08-08 19:31:37,091: DEBUG - +++ grep xtrace
2023-08-08 19:31:37,092: DEBUG - ++ readonly 'XTRACE_ENABLE=set -o xtrace'
2023-08-08 19:31:37,092: DEBUG - ++ XTRACE_ENABLE='set -o xtrace'
2023-08-08 19:31:37,105: DEBUG - + ynh_abort_if_errors
2023-08-08 19:31:37,106: DEBUG - + trap ynh_exit_properly EXIT
2023-08-08 19:31:37,106: DEBUG - + ynh_install_app_dependencies php8.2-ldap php8.2-mbstring php8.2-fileinfo php8.2-sqlite3 php8.2-gd php8.2-intl
2023-08-08 19:31:37,106: DEBUG - + local 'dependencies=php8.2-ldap php8.2-mbstring php8.2-fileinfo php8.2-sqlite3 php8.2-gd php8.2-intl'
2023-08-08 19:31:37,106: DEBUG - ++ echo 'php8.2-ldap php8.2-mbstring php8.2-fileinfo php8.2-sqlite3 php8.2-gd php8.2-intl'
2023-08-08 19:31:37,106: DEBUG - ++ sed 's/\([^\<=\>]\)\ \([^(]\)/\1, \2/g'
2023-08-08 19:31:37,107: DEBUG - + dependencies='php8.2-ldap, php8.2-mbstring, php8.2-fileinfo, php8.2-sqlite3, php8.2-gd, php8.2-intl'
2023-08-08 19:31:37,108: DEBUG - + local 'dependencies=php8.2-ldap, php8.2-mbstring, php8.2-fileinfo, php8.2-sqlite3, php8.2-gd, php8.2-intl'
2023-08-08 19:31:37,108: DEBUG - ++ ynh_read_manifest --manifest_key=version
2023-08-08 19:31:37,119: DEBUG - ++ '[' '!' -e '' ']'
2023-08-08 19:31:37,119: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_i3la_v_8/manifest.json ']'
2023-08-08 19:31:37,119: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_i3la_v_8/manifest.toml ']'
2023-08-08 19:31:37,119: DEBUG - ++ manifest=/var/cache/yunohost/app_tmp_work_dirs/app_i3la_v_8/manifest.toml
2023-08-08 19:31:37,119: DEBUG - ++ echo /var/cache/yunohost/app_tmp_work_dirs/app_i3la_v_8/manifest.toml
2023-08-08 19:31:37,120: DEBUG - ++ grep -q '\.json$'
2023-08-08 19:31:37,121: DEBUG - ++ cat /var/cache/yunohost/app_tmp_work_dirs/app_i3la_v_8/manifest.toml
2023-08-08 19:31:37,121: DEBUG - ++ jq .version --raw-output
2023-08-08 19:31:37,121: DEBUG - ++ python3 -c 'import json, toml, sys; print(json.dumps(toml.load(sys.stdin)))'
2023-08-08 19:31:37,155: DEBUG - + local version=3.3.2~ynh3
2023-08-08 19:31:37,155: DEBUG - + '[' -z 3.3.2~ynh3 ']'
2023-08-08 19:31:37,155: DEBUG - + '[' 3.3.2~ynh3 == null ']'
2023-08-08 19:31:37,155: DEBUG - + local dep_app=grocy
2023-08-08 19:31:37,155: DEBUG - + [[ php8.2-ldap, php8.2-mbstring, php8.2-fileinfo, php8.2-sqlite3, php8.2-gd, php8.2-intl =~ [<=>] ]]
2023-08-08 19:31:37,155: DEBUG - ++ echo php8.2-ldap, php8.2-mbstring, php8.2-fileinfo, php8.2-sqlite3, php8.2-gd, php8.2-intl
2023-08-08 19:31:37,156: DEBUG - ++ grep -oP '(?<=php)[0-9.]+(?=-|\>)'
2023-08-08 19:31:37,156: DEBUG - ++ sort -u
2023-08-08 19:31:37,157: DEBUG - + local specific_php_version=8.2
2023-08-08 19:31:37,157: DEBUG - + [[ -n 8.2 ]]
2023-08-08 19:31:37,157: DEBUG - ++ echo 8.2
2023-08-08 19:31:37,158: DEBUG - ++ wc -l
2023-08-08 19:31:37,159: DEBUG - + [[ 1 -eq 1 ]]
2023-08-08 19:31:37,159: DEBUG - + dependencies+=', php8.2, php8.2-fpm, php8.2-common'
2023-08-08 19:31:37,159: DEBUG - ++ ynh_app_setting_get --app=grocy --key=phpversion
2023-08-08 19:31:37,159: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,179: DEBUG - ++ app=grocy
2023-08-08 19:31:37,179: DEBUG - ++ [[ phpversion =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,179: DEBUG - ++ ynh_app_setting get grocy phpversion
2023-08-08 19:31:37,220: DEBUG - + local old_phpversion=8.0
2023-08-08 19:31:37,220: DEBUG - + '[' -n 8.0 ']'
2023-08-08 19:31:37,221: DEBUG - + '[' 8.0 '!=' 8.2 ']'
2023-08-08 19:31:37,221: DEBUG - ++ ynh_app_setting_get --app=grocy --key=fpm_config_dir
2023-08-08 19:31:37,221: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,241: DEBUG - ++ app=grocy
2023-08-08 19:31:37,241: DEBUG - ++ [[ fpm_config_dir =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,241: DEBUG - ++ ynh_app_setting get grocy fpm_config_dir
2023-08-08 19:31:37,283: DEBUG - + local old_php_fpm_config_dir=/etc/php/8.0/fpm
2023-08-08 19:31:37,283: DEBUG - + local old_php_finalphpconf=/etc/php/8.0/fpm/pool.d/grocy.conf
2023-08-08 19:31:37,283: DEBUG - + [[ -f /etc/php/8.0/fpm/pool.d/grocy.conf ]]
2023-08-08 19:31:37,283: DEBUG - + ynh_backup_if_checksum_is_different --file=/etc/php/8.0/fpm/pool.d/grocy.conf
2023-08-08 19:31:37,289: DEBUG - + local checksum_setting_name=checksum__etc_php_8.0_fpm_pool.d_grocy.conf
2023-08-08 19:31:37,290: DEBUG - ++ ynh_app_setting_get --app=grocy --key=checksum__etc_php_8.0_fpm_pool.d_grocy.conf
2023-08-08 19:31:37,290: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,310: DEBUG - ++ app=grocy
2023-08-08 19:31:37,310: DEBUG - ++ [[ checksum__etc_php_8.0_fpm_pool.d_grocy.conf =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,310: DEBUG - ++ ynh_app_setting get grocy checksum__etc_php_8.0_fpm_pool.d_grocy.conf
2023-08-08 19:31:37,351: DEBUG - + local checksum_value=0c4ce86fbc2c7cdadd7d34087b6547b2
2023-08-08 19:31:37,351: DEBUG - + backup_file_checksum=
2023-08-08 19:31:37,351: DEBUG - + '[' -n 0c4ce86fbc2c7cdadd7d34087b6547b2 ']'
2023-08-08 19:31:37,351: DEBUG - + '[' -e /etc/php/8.0/fpm/pool.d/grocy.conf ']'
2023-08-08 19:31:37,352: DEBUG - + md5sum --check --status
2023-08-08 19:31:37,352: DEBUG - + ynh_remove_fpm_config
2023-08-08 19:31:37,353: DEBUG - ++ ynh_app_setting_get --app=grocy --key=fpm_config_dir
2023-08-08 19:31:37,353: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,373: DEBUG - ++ app=grocy
2023-08-08 19:31:37,374: DEBUG - ++ [[ fpm_config_dir =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,374: DEBUG - ++ ynh_app_setting get grocy fpm_config_dir
2023-08-08 19:31:37,414: DEBUG - + local fpm_config_dir=/etc/php/8.0/fpm
2023-08-08 19:31:37,414: DEBUG - ++ ynh_app_setting_get --app=grocy --key=fpm_service
2023-08-08 19:31:37,415: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,435: DEBUG - ++ app=grocy
2023-08-08 19:31:37,435: DEBUG - ++ [[ fpm_service =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,435: DEBUG - ++ ynh_app_setting get grocy fpm_service
2023-08-08 19:31:37,477: DEBUG - + local fpm_service=php8.0-fpm
2023-08-08 19:31:37,477: DEBUG - ++ ynh_app_setting_get --app=grocy --key=fpm_dedicated_service
2023-08-08 19:31:37,477: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,498: DEBUG - ++ app=grocy
2023-08-08 19:31:37,498: DEBUG - ++ [[ fpm_dedicated_service =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,498: DEBUG - ++ ynh_app_setting get grocy fpm_dedicated_service
2023-08-08 19:31:37,539: DEBUG - + local dedicated_service=0
2023-08-08 19:31:37,539: DEBUG - + dedicated_service=0
2023-08-08 19:31:37,539: DEBUG - ++ ynh_app_setting_get --app=grocy --key=phpversion
2023-08-08 19:31:37,540: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:37,560: DEBUG - ++ app=grocy
2023-08-08 19:31:37,560: DEBUG - ++ [[ phpversion =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,560: DEBUG - ++ ynh_app_setting get grocy phpversion
2023-08-08 19:31:37,601: DEBUG - + local phpversion=8.0
2023-08-08 19:31:37,602: DEBUG - + phpversion=8.0
2023-08-08 19:31:37,602: DEBUG - + '[' -z /etc/php/8.0/fpm ']'
2023-08-08 19:31:37,602: DEBUG - + ynh_secure_remove --file=/etc/php/8.0/fpm/pool.d/grocy.conf
2023-08-08 19:31:37,615: DEBUG - + '[' -e /etc/php/8.0/fpm/conf.d/20-grocy.ini ']'
2023-08-08 19:31:37,615: DEBUG - + ynh_package_is_installed --package=php8.0-fpm
2023-08-08 19:31:37,622: DEBUG - + ynh_wait_dpkg_free
2023-08-08 19:31:37,807: DEBUG - + return 0
2023-08-08 19:31:37,807: DEBUG - + dpkg-query --show '--showformat=${Status}' php8.0-fpm
2023-08-08 19:31:37,807: DEBUG - + grep --count 'ok installed'
2023-08-08 19:31:37,819: DEBUG - + ynh_systemd_action --service_name=php8.0-fpm --action=reload
2023-08-08 19:31:37,877: DEBUG - + service_name=php8.0-fpm
2023-08-08 19:31:37,878: DEBUG - + action=reload
2023-08-08 19:31:37,878: DEBUG - + line_match=
2023-08-08 19:31:37,878: DEBUG - + length=20
2023-08-08 19:31:37,878: DEBUG - + log_path=/var/log/php8.0-fpm/php8.0-fpm.log
2023-08-08 19:31:37,878: DEBUG - + timeout=300
2023-08-08 19:31:37,878: DEBUG - + '[' reload == stop ']'
2023-08-08 19:31:37,878: DEBUG - + '[' reload == reload ']'
2023-08-08 19:31:37,878: DEBUG - + action=reload-or-restart
2023-08-08 19:31:37,879: DEBUG - ++ date --utc --rfc-3339=seconds
2023-08-08 19:31:37,879: DEBUG - ++ cut -d+ -f1
2023-08-08 19:31:37,879: DEBUG - + local 'time_start=2023-08-08 17:31:37 UTC'
2023-08-08 19:31:37,879: DEBUG - + systemctl reload-or-restart php8.0-fpm
2023-08-08 19:31:37,887: DEBUG - + '[' 8.0 '!=' 7.4 ']'
2023-08-08 19:31:37,887: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:37,887: DEBUG - + ynh_app_setting_set --app=grocy --key=phpversion --value=8.2
2023-08-08 19:31:37,887: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:37,932: DEBUG - + app=grocy
2023-08-08 19:31:37,932: DEBUG - + [[ phpversion =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:37,932: DEBUG - + ynh_app_setting set grocy phpversion 8.2
2023-08-08 19:31:37,975: DEBUG - + test -e /usr/bin/php7.4
2023-08-08 19:31:37,975: DEBUG - + update-alternatives --set php /usr/bin/php7.4
2023-08-08 19:31:37,977: DEBUG - ++ ynh_package_is_installed postgresql-13
2023-08-08 19:31:37,984: DEBUG - ++ ynh_wait_dpkg_free
2023-08-08 19:31:38,153: DEBUG - ++ return 0
2023-08-08 19:31:38,153: DEBUG - ++ dpkg-query --show '--showformat=${Status}' postgresql-13
2023-08-08 19:31:38,153: DEBUG - ++ grep --count 'ok installed'
2023-08-08 19:31:38,165: DEBUG - ++ echo yes
2023-08-08 19:31:38,165: DEBUG - + local psql_installed=yes
2023-08-08 19:31:38,165: DEBUG - + [[ true == \t\r\u\e ]]
2023-08-08 19:31:38,165: DEBUG - + YNH_INSTALL_APP_DEPENDENCIES_REPLACE=false
2023-08-08 19:31:38,166: DEBUG - + cat
2023-08-08 19:31:38,166: DEBUG - + ynh_package_install_from_equivs /tmp/grocy-ynh-deps.control
2023-08-08 19:31:38,167: DEBUG - + local controlfile=/tmp/grocy-ynh-deps.control
2023-08-08 19:31:38,167: DEBUG - ++ grep '^Package: ' /tmp/grocy-ynh-deps.control
2023-08-08 19:31:38,167: DEBUG - ++ cut '--delimiter= ' --fields=2
2023-08-08 19:31:38,169: DEBUG - + local pkgname=grocy-ynh-deps
2023-08-08 19:31:38,169: DEBUG - ++ grep '^Version: ' /tmp/grocy-ynh-deps.control
2023-08-08 19:31:38,169: DEBUG - ++ cut '--delimiter= ' --fields=2
2023-08-08 19:31:38,170: DEBUG - + local pkgversion=3.3.2~ynh3
2023-08-08 19:31:38,171: DEBUG - + [[ -z grocy-ynh-deps ]]
2023-08-08 19:31:38,171: DEBUG - + [[ -z 3.3.2~ynh3 ]]
2023-08-08 19:31:38,171: DEBUG - + ynh_package_update
2023-08-08 19:31:38,171: DEBUG - + ynh_apt update
2023-08-08 19:31:38,171: DEBUG - + ynh_wait_dpkg_free
2023-08-08 19:31:38,322: DEBUG - + return 0
2023-08-08 19:31:38,322: DEBUG - + LC_ALL=C
2023-08-08 19:31:38,322: DEBUG - + DEBIAN_FRONTEND=noninteractive
2023-08-08 19:31:38,322: DEBUG - + apt-get --assume-yes --quiet -o=Acquire::Retries=3 -o=Dpkg::Use-Pty=0 update
2023-08-08 19:31:38,446: DEBUG - Hit:1 http://security.debian.org bullseye-security InRelease
2023-08-08 19:31:38,455: DEBUG - Hit:2 http://ftp.debian.org/debian bullseye InRelease
2023-08-08 19:31:38,457: DEBUG - Hit:3 http://forge.yunohost.org/debian bullseye InRelease
2023-08-08 19:31:38,470: DEBUG - Hit:4 http://ftp.debian.org/debian bullseye-updates InRelease
2023-08-08 19:31:38,493: DEBUG - Hit:5 https://packages.sury.org/php bullseye InRelease
2023-08-08 19:31:39,420: DEBUG - Reading package lists...
2023-08-08 19:31:39,432: DEBUG - ++ mktemp --directory
2023-08-08 19:31:39,433: DEBUG - + local TMPDIR=/tmp/tmp.ivMSS9JFBp
2023-08-08 19:31:39,433: DEBUG - + rm -f /usr/share/equivs/template/debian/compat
2023-08-08 19:31:39,434: DEBUG - + ynh_wait_dpkg_free
2023-08-08 19:31:39,624: DEBUG - + return 0
2023-08-08 19:31:39,624: DEBUG - + cp /tmp/grocy-ynh-deps.control /tmp/tmp.ivMSS9JFBp/control
2023-08-08 19:31:39,625: DEBUG - + cd /tmp/tmp.ivMSS9JFBp
2023-08-08 19:31:39,625: DEBUG - + LC_ALL=C
2023-08-08 19:31:39,626: DEBUG - + equivs-build ./control
2023-08-08 19:31:39,755: DEBUG - dpkg-buildpackage: info: source package grocy-ynh-deps
2023-08-08 19:31:39,755: DEBUG - dpkg-buildpackage: info: source version 3.3.2~ynh3
2023-08-08 19:31:39,755: DEBUG - dpkg-buildpackage: info: source distribution unstable
2023-08-08 19:31:39,755: DEBUG - dpkg-buildpackage: info: source changed by Equivs Dummy Package Generator <root@yunohost.xxx.loc>
2023-08-08 19:31:39,788: DEBUG -  dpkg-source --before-build .
2023-08-08 19:31:39,788: DEBUG - dpkg-buildpackage: info: host architecture amd64
2023-08-08 19:31:39,882: DEBUG -  debian/rules clean
2023-08-08 19:31:39,883: DEBUG - dh clean
2023-08-08 19:31:39,956: DEBUG -    dh_clean
2023-08-08 19:31:39,987: DEBUG -  debian/rules binary
2023-08-08 19:31:39,988: DEBUG - dh binary
2023-08-08 19:31:40,050: DEBUG -    dh_update_autotools_config
2023-08-08 19:31:40,076: DEBUG -    dh_autoreconf
2023-08-08 19:31:40,114: DEBUG -    create-stamp debian/debhelper-build-stamp
2023-08-08 19:31:40,114: DEBUG -    dh_prep
2023-08-08 19:31:40,140: DEBUG -    dh_install
2023-08-08 19:31:40,171: DEBUG -    dh_installdocs
2023-08-08 19:31:40,207: DEBUG -    dh_installchangelogs
2023-08-08 19:31:40,283: DEBUG -    dh_perl
2023-08-08 19:31:40,317: DEBUG -    dh_link
2023-08-08 19:31:40,348: DEBUG -    dh_strip_nondeterminism
2023-08-08 19:31:40,404: DEBUG -    dh_compress
2023-08-08 19:31:40,442: DEBUG -    dh_fixperms
2023-08-08 19:31:40,480: DEBUG -    dh_missing
2023-08-08 19:31:40,504: DEBUG -    dh_installdeb
2023-08-08 19:31:40,535: DEBUG -    dh_gencontrol
2023-08-08 19:31:40,644: DEBUG -    dh_md5sums
2023-08-08 19:31:40,676: DEBUG -    dh_builddeb
2023-08-08 19:31:40,700: DEBUG - dpkg-deb: building package 'grocy-ynh-deps' in '../grocy-ynh-deps_3.3.2~ynh3_all.deb'.
2023-08-08 19:31:40,832: DEBUG -  dpkg-genbuildinfo --build=binary
2023-08-08 19:31:41,377: DEBUG -  dpkg-genchanges --build=binary >../grocy-ynh-deps_3.3.2~ynh3_amd64.changes
2023-08-08 19:31:41,450: DEBUG - dpkg-genchanges: info: binary-only upload (no source code included)
2023-08-08 19:31:41,453: DEBUG -  dpkg-source --after-build .
2023-08-08 19:31:41,547: DEBUG - dpkg-buildpackage: info: binary-only upload (no source included)
2023-08-08 19:31:41,551: DEBUG - 
2023-08-08 19:31:41,551: DEBUG - The package has been created.
2023-08-08 19:31:41,551: DEBUG - Attention, the package has been created in the current directory,
2023-08-08 19:31:41,551: DEBUG - not in ".." as indicated by the message above!
2023-08-08 19:31:41,552: DEBUG - + LC_ALL=C
2023-08-08 19:31:41,553: DEBUG - + dpkg --force-depends --install ./grocy-ynh-deps_3.3.2~ynh3_all.deb
2023-08-08 19:31:41,553: DEBUG - + tee ./dpkg_log
2023-08-08 19:31:41,630: DEBUG - (Reading database ... 57474 files and directories currently installed.)
2023-08-08 19:31:41,631: DEBUG - Preparing to unpack .../grocy-ynh-deps_3.3.2~ynh3_all.deb ...
2023-08-08 19:31:41,642: DEBUG - Unpacking grocy-ynh-deps (3.3.2~ynh3) over (3.3.2~ynh3) ...
2023-08-08 19:31:41,666: DEBUG - Setting up grocy-ynh-deps (3.3.2~ynh3) ...
2023-08-08 19:31:41,690: DEBUG - + ynh_package_install --fix-broken
2023-08-08 19:31:41,690: DEBUG - + ynh_apt --no-remove --option Dpkg::Options::=--force-confdef --option Dpkg::Options::=--force-confold install --fix-broken
2023-08-08 19:31:41,690: DEBUG - + ynh_wait_dpkg_free
2023-08-08 19:31:41,884: DEBUG - + return 0
2023-08-08 19:31:41,884: DEBUG - + LC_ALL=C
2023-08-08 19:31:41,884: DEBUG - + DEBIAN_FRONTEND=noninteractive
2023-08-08 19:31:41,884: DEBUG - + apt-get --assume-yes --quiet -o=Acquire::Retries=3 -o=Dpkg::Use-Pty=0 --no-remove --option Dpkg::Options::=--force-confdef --option Dpkg::Options::=--force-confold install --fix-broken
2023-08-08 19:31:42,040: DEBUG - Reading package lists...
2023-08-08 19:31:42,162: DEBUG - Building dependency tree...
2023-08-08 19:31:42,163: DEBUG - Reading state information...
2023-08-08 19:31:42,282: DEBUG - The following package was automatically installed and is no longer required:
2023-08-08 19:31:42,282: DEBUG -   php8.0-sqlite3
2023-08-08 19:31:42,282: DEBUG - Use 'apt autoremove' to remove it.
2023-08-08 19:31:42,300: DEBUG - 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
2023-08-08 19:31:42,301: DEBUG - + [[ -n /tmp/tmp.ivMSS9JFBp ]]
2023-08-08 19:31:42,301: DEBUG - + rm --recursive --force /tmp/tmp.ivMSS9JFBp
2023-08-08 19:31:42,302: DEBUG - + ynh_package_is_installed grocy-ynh-deps
2023-08-08 19:31:42,308: DEBUG - + ynh_wait_dpkg_free
2023-08-08 19:31:42,497: DEBUG - + return 0
2023-08-08 19:31:42,497: DEBUG - + dpkg-query --show '--showformat=${Status}' grocy-ynh-deps
2023-08-08 19:31:42,497: DEBUG - + grep --count 'ok installed'
2023-08-08 19:31:42,509: DEBUG - + rm /tmp/grocy-ynh-deps.control
2023-08-08 19:31:42,510: DEBUG - ++ ynh_package_is_installed postgresql-13
2023-08-08 19:31:42,517: DEBUG - ++ ynh_wait_dpkg_free
2023-08-08 19:31:42,669: DEBUG - ++ return 0
2023-08-08 19:31:42,670: DEBUG - ++ dpkg-query --show '--showformat=${Status}' postgresql-13
2023-08-08 19:31:42,670: DEBUG - ++ grep --count 'ok installed'
2023-08-08 19:31:42,681: DEBUG - ++ echo yes
2023-08-08 19:31:42,681: DEBUG - + local psql_installed2=yes
2023-08-08 19:31:42,681: DEBUG - + [[ yes != \y\e\s ]]
2023-08-08 19:31:42,682: DEBUG - + ynh_exit_properly
2023-08-08 19:31:42,682: DEBUG - + [[ provision_or_update_apt =~ ^install$|^upgrade$|^restore$ ]]
2023-08-08 19:31:43,696: DEBUG - Executing command '['sh', '-c', '/bin/bash -x "./upgrade"  7>&1']'
2023-08-08 19:31:43,701: DEBUG - + source _common.sh
2023-08-08 19:31:43,701: DEBUG - + source /usr/share/yunohost/helpers
2023-08-08 19:31:43,701: DEBUG - +++ set +o
2023-08-08 19:31:43,702: DEBUG - +++ grep xtrace
2023-08-08 19:31:43,702: DEBUG - ++ readonly 'XTRACE_ENABLE=set -o xtrace'
2023-08-08 19:31:43,703: DEBUG - ++ XTRACE_ENABLE='set -o xtrace'
2023-08-08 19:31:43,717: DEBUG - ++ ynh_check_app_version_changed
2023-08-08 19:31:43,717: DEBUG - ++ local return_value=UPGRADE_FULL
2023-08-08 19:31:43,717: DEBUG - ++ '[' UPGRADE_FULL == UPGRADE_FULL ']'
2023-08-08 19:31:43,717: DEBUG - ++ return_value=UPGRADE_APP
2023-08-08 19:31:43,717: DEBUG - ++ echo UPGRADE_APP
2023-08-08 19:31:43,717: DEBUG - + upgrade_type=UPGRADE_APP
2023-08-08 19:31:43,774: INFO - [++..................] > Ensuring downward compatibility...
2023-08-08 19:31:43,774: DEBUG - + '[' -z low ']'
2023-08-08 19:31:43,774: DEBUG - + '[' -z 0 ']'
2023-08-08 19:31:43,774: DEBUG - + '[' -z low ']'
2023-08-08 19:31:43,775: DEBUG - + '[' UPGRADE_APP == UPGRADE_APP ']'
2023-08-08 19:31:43,821: INFO - [##+++++++++++.......] > Upgrading source files...
2023-08-08 19:31:43,821: DEBUG - + ynh_setup_source --dest_dir=/var/www/grocy --keep=data
2023-08-08 19:31:43,858: DEBUG - + keep=data
2023-08-08 19:31:43,858: DEBUG - + full_replace=0
2023-08-08 19:31:43,859: DEBUG - + test -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:43,859: DEBUG - + cat /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:43,859: DEBUG - + toml_to_json
2023-08-08 19:31:43,859: DEBUG - + python3 -c 'import toml, json, sys; print(json.dumps(toml.load(sys.stdin)))'
2023-08-08 19:31:43,859: DEBUG - + jq -e .resources.sources
2023-08-08 19:31:43,893: DEBUG - + source_id=main
2023-08-08 19:31:43,893: DEBUG - ++ cat /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:43,893: DEBUG - ++ toml_to_json
2023-08-08 19:31:43,894: DEBUG - ++ python3 -c 'import toml, json, sys; print(json.dumps(toml.load(sys.stdin)))'
2023-08-08 19:31:43,894: DEBUG - ++ jq '.resources.sources["main"]'
2023-08-08 19:31:43,927: DEBUG - + local 'sources_json={
2023-08-08 19:31:43,927: DEBUG -   "url": "https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip",
2023-08-08 19:31:43,927: DEBUG -   "sha256": "1deb09382eef102b591c4500b68e4027829dbb21d71ae8637b692daa5ec33a09",
2023-08-08 19:31:43,927: DEBUG -   "in_subdir": false,
2023-08-08 19:31:43,927: DEBUG -   "autoupdate": {
2023-08-08 19:31:43,927: DEBUG -     "strategy": "latest_github_release",
2023-08-08 19:31:43,928: DEBUG -     "asset": "grocy_.*.zip"
2023-08-08 19:31:43,928: DEBUG -   }
2023-08-08 19:31:43,928: DEBUG - }'
2023-08-08 19:31:43,928: DEBUG - + jq -re .url
2023-08-08 19:31:43,958: DEBUG - https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip
2023-08-08 19:31:43,958: DEBUG - + local arch_prefix=
2023-08-08 19:31:43,959: DEBUG - ++ jq -r .url
2023-08-08 19:31:43,959: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:43,993: DEBUG - + local src_url=https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip
2023-08-08 19:31:43,993: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:43,995: DEBUG - ++ jq -r .sha256
2023-08-08 19:31:44,029: DEBUG - + local src_sum=1deb09382eef102b591c4500b68e4027829dbb21d71ae8637b692daa5ec33a09
2023-08-08 19:31:44,030: DEBUG - + local src_sumprg=sha256sum
2023-08-08 19:31:44,030: DEBUG - ++ jq -r .format
2023-08-08 19:31:44,030: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:44,064: DEBUG - + local src_format=
2023-08-08 19:31:44,065: DEBUG - ++ jq -r .in_subdir
2023-08-08 19:31:44,065: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:44,098: DEBUG - + local src_in_subdir=false
2023-08-08 19:31:44,099: DEBUG - ++ jq -r .extract
2023-08-08 19:31:44,099: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:44,130: DEBUG - + local src_extract=
2023-08-08 19:31:44,131: DEBUG - ++ jq -r .platform
2023-08-08 19:31:44,131: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:44,163: DEBUG - + local src_platform=
2023-08-08 19:31:44,164: DEBUG - ++ jq -r .rename
2023-08-08 19:31:44,164: DEBUG - ++ sed 's/^null$//'
2023-08-08 19:31:44,196: DEBUG - + local src_rename=
2023-08-08 19:31:44,196: DEBUG - + [[ -n https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip ]]
2023-08-08 19:31:44,196: DEBUG - + [[ -n 1deb09382eef102b591c4500b68e4027829dbb21d71ae8637b692daa5ec33a09 ]]
2023-08-08 19:31:44,197: DEBUG - + [[ -z '' ]]
2023-08-08 19:31:44,197: DEBUG - + [[ https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip =~ ^.*\.zip$ ]]
2023-08-08 19:31:44,197: DEBUG - + src_format=zip
2023-08-08 19:31:44,197: DEBUG - + src_sumprg=sha256sum
2023-08-08 19:31:44,197: DEBUG - + src_in_subdir=false
2023-08-08 19:31:44,197: DEBUG - + src_format=zip
2023-08-08 19:31:44,197: DEBUG - ++ echo zip
2023-08-08 19:31:44,197: DEBUG - ++ tr '[:upper:]' '[:lower:]'
2023-08-08 19:31:44,198: DEBUG - + src_format=zip
2023-08-08 19:31:44,198: DEBUG - + src_extract=true
2023-08-08 19:31:44,198: DEBUG - + [[ true != \t\r\u\e ]]
2023-08-08 19:31:44,198: DEBUG - + local local_src=/opt/yunohost-apps-src/grocy/main
2023-08-08 19:31:44,198: DEBUG - ++ dirname /var/cache/yunohost/download/grocy/main
2023-08-08 19:31:44,199: DEBUG - + mkdir -p /var/cache/yunohost/download/grocy
2023-08-08 19:31:44,200: DEBUG - + src_filename=/var/cache/yunohost/download/grocy/main
2023-08-08 19:31:44,200: DEBUG - + '[' zip = docker ']'
2023-08-08 19:31:44,201: DEBUG - + test -e /opt/yunohost-apps-src/grocy/main
2023-08-08 19:31:44,201: DEBUG - + '[' -n https://github.com/grocy/grocy/releases/download/v4.0.1/grocy_4.0.1.zip ']'
2023-08-08 19:31:44,201: DEBUG - + '[' -e /var/cache/yunohost/download/grocy/main ']'
2023-08-08 19:31:44,201: DEBUG - + sha256sum --check --status
2023-08-08 19:31:44,366: DEBUG - + '[' '!' -e /var/cache/yunohost/download/grocy/main ']'
2023-08-08 19:31:44,367: DEBUG - + sha256sum --check --status
2023-08-08 19:31:44,530: DEBUG - + rm -rf /var/cache/yunohost/files_to_keep_during_setup_source/
2023-08-08 19:31:44,531: DEBUG - + '[' -n data ']'
2023-08-08 19:31:44,531: DEBUG - + '[' -e /var/www/grocy ']'
2023-08-08 19:31:44,532: DEBUG - + local keep_dir=/var/cache/yunohost/files_to_keep_during_setup_source/grocy
2023-08-08 19:31:44,532: DEBUG - + mkdir -p /var/cache/yunohost/files_to_keep_during_setup_source/grocy
2023-08-08 19:31:44,533: DEBUG - + for stuff_to_keep in $keep
2023-08-08 19:31:44,533: DEBUG - + '[' -e /var/www/grocy/data ']'
2023-08-08 19:31:44,533: DEBUG - ++ dirname /var/cache/yunohost/files_to_keep_during_setup_source/grocy/data
2023-08-08 19:31:44,534: DEBUG - + mkdir --parents /var/cache/yunohost/files_to_keep_during_setup_source/grocy
2023-08-08 19:31:44,535: DEBUG - + cp --archive /var/www/grocy/data /var/cache/yunohost/files_to_keep_during_setup_source/grocy/data
2023-08-08 19:31:44,607: DEBUG - + mkdir --parents /var/www/grocy
2023-08-08 19:31:44,608: DEBUG - + '[' -n /var/www/grocy ']'
2023-08-08 19:31:44,608: DEBUG - + '[' /var/www/grocy == /var/www/grocy ']'
2023-08-08 19:31:44,608: DEBUG - + _ynh_apply_default_permissions /var/www/grocy
2023-08-08 19:31:44,609: DEBUG - + local target=/var/www/grocy
2023-08-08 19:31:44,609: DEBUG - ++ ynh_read_manifest --manifest_key=requirements.yunohost
2023-08-08 19:31:44,609: DEBUG - ++ tr -d '<>= '
2023-08-08 19:31:44,620: DEBUG - ++ '[' '!' -e '' ']'
2023-08-08 19:31:44,621: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.json ']'
2023-08-08 19:31:44,621: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml ']'
2023-08-08 19:31:44,621: DEBUG - ++ manifest=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:44,621: DEBUG - ++ echo /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:44,621: DEBUG - ++ grep -q '\.json$'
2023-08-08 19:31:44,622: DEBUG - ++ cat /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:44,622: DEBUG - ++ python3 -c 'import json, toml, sys; print(json.dumps(toml.load(sys.stdin)))'
2023-08-08 19:31:44,623: DEBUG - ++ jq .requirements.yunohost --raw-output
2023-08-08 19:31:44,656: DEBUG - + local ynh_requirement=null
2023-08-08 19:31:44,656: DEBUG - + dpkg --compare-versions 2.0 ge 2
2023-08-08 19:31:44,658: DEBUG - + chmod o-rwx /var/www/grocy
2023-08-08 19:31:44,658: DEBUG - + chmod g-w /var/www/grocy
2023-08-08 19:31:44,659: DEBUG - + chown -R root:root /var/www/grocy
2023-08-08 19:31:44,840: DEBUG - + ynh_system_user_exists grocy
2023-08-08 19:31:44,846: DEBUG - + getent passwd grocy
2023-08-08 19:31:44,849: DEBUG - + chown grocy:grocy /var/www/grocy
2023-08-08 19:31:44,853: DEBUG - + echo /var/www/grocy
2023-08-08 19:31:44,853: DEBUG - + grep -q '^/etc/cron\|/etc/php\|/etc/nginx/conf.d\|/etc/fail2ban\|/etc/systemd/system'
2023-08-08 19:31:44,854: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:44,854: DEBUG - + [[ true == \f\a\l\s\e ]]
2023-08-08 19:31:44,854: DEBUG - + [[ zip == \d\o\c\k\e\r ]]
2023-08-08 19:31:44,854: DEBUG - + [[ zip == \z\i\p ]]
2023-08-08 19:31:44,854: DEBUG - + false
2023-08-08 19:31:44,854: DEBUG - + unzip -quo /var/cache/yunohost/download/grocy/main -d /var/www/grocy
2023-08-08 19:31:46,714: DEBUG - + ynh_secure_remove --file=/var/cache/yunohost/download/grocy/main
2023-08-08 19:31:46,729: DEBUG - + '[' -d /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/sources/patches/ ']'
2023-08-08 19:31:46,729: DEBUG - + test -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/sources/extra_files/main
2023-08-08 19:31:46,729: DEBUG - + '[' -n data ']'
2023-08-08 19:31:46,729: DEBUG - + local keep_dir=/var/cache/yunohost/files_to_keep_during_setup_source/grocy
2023-08-08 19:31:46,729: DEBUG - + for stuff_to_keep in $keep
2023-08-08 19:31:46,729: DEBUG - + '[' -e /var/cache/yunohost/files_to_keep_during_setup_source/grocy/data ']'
2023-08-08 19:31:46,729: DEBUG - ++ dirname /var/www/grocy/data
2023-08-08 19:31:46,730: DEBUG - + mkdir --parents /var/www/grocy
2023-08-08 19:31:46,731: DEBUG - + cp --archive /var/cache/yunohost/files_to_keep_during_setup_source/grocy/data /var/www/grocy/data
2023-08-08 19:31:46,795: DEBUG - + rm -rf /var/cache/yunohost/files_to_keep_during_setup_source/
2023-08-08 19:31:46,817: DEBUG - + chmod -R o-rwx /var/www/grocy
2023-08-08 19:31:47,232: DEBUG - + chown -R grocy:www-data /var/www/grocy
2023-08-08 19:31:47,551: INFO - [#############++.....] > Upgrading NGINX web server configuration...
2023-08-08 19:31:47,552: DEBUG - + ynh_add_nginx_config
2023-08-08 19:31:47,552: DEBUG - + local finalnginxconf=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,552: DEBUG - + ynh_add_config --template=nginx.conf --destination=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,573: DEBUG - + '[' -f /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/nginx.conf ']'
2023-08-08 19:31:47,573: DEBUG - + template_path=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/nginx.conf
2023-08-08 19:31:47,573: DEBUG - + ynh_backup_if_checksum_is_different --file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,580: DEBUG - + local checksum_setting_name=checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf
2023-08-08 19:31:47,580: DEBUG - ++ ynh_app_setting_get --app=grocy --key=checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf
2023-08-08 19:31:47,580: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:47,602: DEBUG - ++ app=grocy
2023-08-08 19:31:47,602: DEBUG - ++ [[ checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:47,602: DEBUG - ++ ynh_app_setting get grocy checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf
2023-08-08 19:31:47,643: DEBUG - + local checksum_value=
2023-08-08 19:31:47,643: DEBUG - + backup_file_checksum=
2023-08-08 19:31:47,644: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:47,644: DEBUG - + touch /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,644: DEBUG - + chown root:root /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,646: DEBUG - + chmod 640 /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,647: DEBUG - + cp -f /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/nginx.conf /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,648: DEBUG - + _ynh_apply_default_permissions /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,648: DEBUG - + local target=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,648: DEBUG - ++ ynh_read_manifest --manifest_key=requirements.yunohost
2023-08-08 19:31:47,649: DEBUG - ++ tr -d '<>= '
2023-08-08 19:31:47,661: DEBUG - ++ '[' '!' -e '' ']'
2023-08-08 19:31:47,661: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.json ']'
2023-08-08 19:31:47,662: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml ']'
2023-08-08 19:31:47,662: DEBUG - ++ manifest=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:47,662: DEBUG - ++ echo /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:47,662: DEBUG - ++ grep -q '\.json$'
2023-08-08 19:31:47,663: DEBUG - ++ cat /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:47,663: DEBUG - ++ python3 -c 'import json, toml, sys; print(json.dumps(toml.load(sys.stdin)))'
2023-08-08 19:31:47,663: DEBUG - ++ jq .requirements.yunohost --raw-output
2023-08-08 19:31:47,700: DEBUG - + local ynh_requirement=null
2023-08-08 19:31:47,700: DEBUG - + dpkg --compare-versions 2.0 ge 2
2023-08-08 19:31:47,701: DEBUG - + chmod o-rwx /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,702: DEBUG - + chmod g-w /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,703: DEBUG - + chown -R root:root /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,704: DEBUG - + ynh_system_user_exists grocy
2023-08-08 19:31:47,711: DEBUG - + getent passwd grocy
2023-08-08 19:31:47,712: DEBUG - + chown grocy:grocy /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,713: DEBUG - + echo /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,713: DEBUG - + grep -q '^/etc/cron\|/etc/php\|/etc/nginx/conf.d\|/etc/fail2ban\|/etc/systemd/system'
2023-08-08 19:31:47,715: DEBUG - + chmod 400 /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,715: DEBUG - + chown root:root /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,717: DEBUG - + ynh_replace_vars --file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,723: DEBUG - + test -n /
2023-08-08 19:31:47,724: DEBUG - + local path_url_slash_less=
2023-08-08 19:31:47,724: DEBUG - + ynh_replace_string --match_string=__PATH__/ --replace_string=/ --target_file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,769: DEBUG - + sed --in-place $'s\001__PATH__/\001/\001g' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,770: DEBUG - + ynh_replace_string --match_string=__PATH__ --replace_string=/ --target_file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,815: DEBUG - + sed --in-place $'s\001__PATH__\001/\001g' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,817: DEBUG - + test -n grocy
2023-08-08 19:31:47,817: DEBUG - + ynh_replace_string --match_string=__NAME__ --replace_string=grocy --target_file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,861: DEBUG - + sed --in-place $'s\001__NAME__\001grocy\001g' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,863: DEBUG - + ynh_replace_string --match_string=__NAMETOCHANGE__ --replace_string=grocy --target_file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,910: DEBUG - + sed --in-place $'s\001__NAMETOCHANGE__\001grocy\001g' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,911: DEBUG - + ynh_replace_string --match_string=__USER__ --replace_string=grocy --target_file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,956: DEBUG - + sed --in-place $'s\001__USER__\001grocy\001g' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,957: DEBUG - + test -n ''
2023-08-08 19:31:47,957: DEBUG - + dpkg --compare-versions 2.0 lt 2
2023-08-08 19:31:47,958: DEBUG - + test -n ''
2023-08-08 19:31:47,959: DEBUG - ++ grep -oP '__[A-Z0-9]+?[A-Z0-9_]*?[A-Z0-9]*?__' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,959: DEBUG - ++ sort --unique
2023-08-08 19:31:47,959: DEBUG - ++ sed 's@__\([^.]*\)__@\L\1@g'
2023-08-08 19:31:47,961: DEBUG - + uniques_vars=('install_dir' 'phpversion')
2023-08-08 19:31:47,961: DEBUG - + local delimit=@
2023-08-08 19:31:47,961: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:47,961: DEBUG - + [[ -v install_dir ]]
2023-08-08 19:31:47,961: DEBUG - + match_string=__INSTALL_DIR__
2023-08-08 19:31:47,961: DEBUG - + match_string=__INSTALL_DIR__
2023-08-08 19:31:47,962: DEBUG - + replace_string=/var/www/grocy
2023-08-08 19:31:47,962: DEBUG - + replace_string=/var/www/grocy
2023-08-08 19:31:47,962: DEBUG - + replace_string=/var/www/grocy
2023-08-08 19:31:47,962: DEBUG - + sed --in-place s@__INSTALL_DIR__@/var/www/grocy@g /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,962: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:47,962: DEBUG - + [[ -v phpversion ]]
2023-08-08 19:31:47,963: DEBUG - + match_string=__PHPVERSION__
2023-08-08 19:31:47,963: DEBUG - + match_string=__PHPVERSION__
2023-08-08 19:31:47,963: DEBUG - + replace_string=8.2
2023-08-08 19:31:47,963: DEBUG - + replace_string=8.2
2023-08-08 19:31:47,963: DEBUG - + replace_string=8.2
2023-08-08 19:31:47,963: DEBUG - + sed --in-place s@__PHPVERSION__@8.2@g /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,964: DEBUG - + ynh_store_file_checksum --file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,965: DEBUG - + update_only=0
2023-08-08 19:31:47,977: DEBUG - + local checksum_setting_name=checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf
2023-08-08 19:31:47,977: DEBUG - ++ md5sum /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:47,977: DEBUG - ++ cut '--delimiter= ' --fields=1
2023-08-08 19:31:47,978: DEBUG - + ynh_app_setting_set --app=grocy --key=checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf --value=796c65ebbbfd696b4dcfda79a88c7545
2023-08-08 19:31:47,979: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:48,023: DEBUG - + app=grocy
2023-08-08 19:31:48,023: DEBUG - + [[ checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,023: DEBUG - + ynh_app_setting set grocy checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf 796c65ebbbfd696b4dcfda79a88c7545
2023-08-08 19:31:48,065: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:48,066: DEBUG - + unset backup_file_checksum
2023-08-08 19:31:48,066: DEBUG - + '[' / '!=' / ']'
2023-08-08 19:31:48,066: DEBUG - + ynh_replace_string '--match_string=^#root_path_only' --replace_string= --target_file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:48,111: DEBUG - + sed --in-place 's
^#root_path_only

g' /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:48,113: DEBUG - + ynh_store_file_checksum --file=/etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:48,113: DEBUG - + update_only=0
2023-08-08 19:31:48,125: DEBUG - + local checksum_setting_name=checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf
2023-08-08 19:31:48,125: DEBUG - ++ md5sum /etc/nginx/conf.d/grocy.maindomain.tld.d/grocy.conf
2023-08-08 19:31:48,126: DEBUG - ++ cut '--delimiter= ' --fields=1
2023-08-08 19:31:48,127: DEBUG - + ynh_app_setting_set --app=grocy --key=checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf --value=796c65ebbbfd696b4dcfda79a88c7545
2023-08-08 19:31:48,127: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:48,173: DEBUG - + app=grocy
2023-08-08 19:31:48,173: DEBUG - + [[ checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,173: DEBUG - + ynh_app_setting set grocy checksum__etc_nginx_conf.d_grocy.maindomain.tld.d_grocy.conf 796c65ebbbfd696b4dcfda79a88c7545
2023-08-08 19:31:48,216: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:48,216: DEBUG - + unset backup_file_checksum
2023-08-08 19:31:48,216: DEBUG - + ynh_systemd_action --service_name=nginx --action=reload
2023-08-08 19:31:48,275: DEBUG - + service_name=nginx
2023-08-08 19:31:48,275: DEBUG - + action=reload
2023-08-08 19:31:48,275: DEBUG - + line_match=
2023-08-08 19:31:48,275: DEBUG - + length=20
2023-08-08 19:31:48,276: DEBUG - + log_path=/var/log/nginx/nginx.log
2023-08-08 19:31:48,276: DEBUG - + timeout=300
2023-08-08 19:31:48,276: DEBUG - + '[' reload == stop ']'
2023-08-08 19:31:48,276: DEBUG - + '[' reload == reload ']'
2023-08-08 19:31:48,276: DEBUG - + action=reload-or-restart
2023-08-08 19:31:48,276: DEBUG - ++ date --utc --rfc-3339=seconds
2023-08-08 19:31:48,276: DEBUG - ++ cut -d+ -f1
2023-08-08 19:31:48,277: DEBUG - + local 'time_start=2023-08-08 17:31:48 UTC'
2023-08-08 19:31:48,277: DEBUG - + systemctl reload-or-restart nginx
2023-08-08 19:31:48,474: INFO - [###############++...] > Upgrading PHP-FPM configuration...
2023-08-08 19:31:48,474: DEBUG - + ynh_add_fpm_config --phpversion=8.2 --usage=low --footprint=low
2023-08-08 19:31:48,474: DEBUG - + local _globalphpversion=8.2
2023-08-08 19:31:48,546: DEBUG - + package=
2023-08-08 19:31:48,546: DEBUG - + local autogenconf=false
2023-08-08 19:31:48,547: DEBUG - + usage=low
2023-08-08 19:31:48,547: DEBUG - + footprint=low
2023-08-08 19:31:48,547: DEBUG - + '[' -n low ']'
2023-08-08 19:31:48,547: DEBUG - + autogenconf=true
2023-08-08 19:31:48,547: DEBUG - ++ ynh_app_setting_get --app=grocy --key=fpm_usage
2023-08-08 19:31:48,547: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:48,568: DEBUG - ++ app=grocy
2023-08-08 19:31:48,569: DEBUG - ++ [[ fpm_usage =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,569: DEBUG - ++ ynh_app_setting get grocy fpm_usage
2023-08-08 19:31:48,610: DEBUG - + local fpm_usage_in_setting=low
2023-08-08 19:31:48,610: DEBUG - + '[' -z low ']'
2023-08-08 19:31:48,610: DEBUG - ++ ynh_app_setting_get --app=grocy --key=fpm_footprint
2023-08-08 19:31:48,611: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:48,634: DEBUG - ++ app=grocy
2023-08-08 19:31:48,634: DEBUG - ++ [[ fpm_footprint =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,634: DEBUG - ++ ynh_app_setting get grocy fpm_footprint
2023-08-08 19:31:48,675: DEBUG - + local fpm_footprint_in_setting=low
2023-08-08 19:31:48,676: DEBUG - + '[' -z low ']'
2023-08-08 19:31:48,676: DEBUG - + dedicated_service=0
2023-08-08 19:31:48,676: DEBUG - + dpkg --compare-versions 2.0 lt 2
2023-08-08 19:31:48,677: DEBUG - + phpversion=8.2
2023-08-08 19:31:48,677: DEBUG - ++ ynh_app_setting_get --app=grocy --key=phpversion
2023-08-08 19:31:48,678: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:48,699: DEBUG - ++ app=grocy
2023-08-08 19:31:48,700: DEBUG - ++ [[ phpversion =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,700: DEBUG - ++ ynh_app_setting get grocy phpversion
2023-08-08 19:31:48,741: DEBUG - + local old_phpversion=8.2
2023-08-08 19:31:48,741: DEBUG - + '[' -n 8.2 ']'
2023-08-08 19:31:48,741: DEBUG - + '[' 8.2 '!=' 8.2 ']'
2023-08-08 19:31:48,741: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:48,741: DEBUG - + local fpm_service=php8.2-fpm
2023-08-08 19:31:48,741: DEBUG - + local fpm_config_dir=/etc/php/8.2/fpm
2023-08-08 19:31:48,741: DEBUG - + mkdir --parents /etc/php/8.2/fpm/pool.d
2023-08-08 19:31:48,742: DEBUG - + ynh_app_setting_set --app=grocy --key=fpm_config_dir --value=/etc/php/8.2/fpm
2023-08-08 19:31:48,742: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:48,785: DEBUG - + app=grocy
2023-08-08 19:31:48,785: DEBUG - + [[ fpm_config_dir =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,786: DEBUG - + ynh_app_setting set grocy fpm_config_dir /etc/php/8.2/fpm
2023-08-08 19:31:48,828: DEBUG - + ynh_app_setting_set --app=grocy --key=fpm_service --value=php8.2-fpm
2023-08-08 19:31:48,828: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:48,872: DEBUG - + app=grocy
2023-08-08 19:31:48,872: DEBUG - + [[ fpm_service =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,872: DEBUG - + ynh_app_setting set grocy fpm_service php8.2-fpm
2023-08-08 19:31:48,914: DEBUG - + ynh_app_setting_set --app=grocy --key=fpm_dedicated_service --value=0
2023-08-08 19:31:48,914: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:48,959: DEBUG - + app=grocy
2023-08-08 19:31:48,959: DEBUG - + [[ fpm_dedicated_service =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:48,959: DEBUG - + ynh_app_setting set grocy fpm_dedicated_service 0
2023-08-08 19:31:49,001: DEBUG - + ynh_app_setting_set --app=grocy --key=phpversion --value=8.2
2023-08-08 19:31:49,001: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:49,046: DEBUG - + app=grocy
2023-08-08 19:31:49,046: DEBUG - + [[ phpversion =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:49,046: DEBUG - + ynh_app_setting set grocy phpversion 8.2
2023-08-08 19:31:49,088: DEBUG - + '[' true == false ']'
2023-08-08 19:31:49,088: DEBUG - + ynh_get_scalable_phpfpm --usage=low --footprint=low
2023-08-08 19:31:49,130: DEBUG - + footprint=low
2023-08-08 19:31:49,130: DEBUG - + usage=low
2023-08-08 19:31:49,130: DEBUG - + print=0
2023-08-08 19:31:49,130: DEBUG - + '[' low = low ']'
2023-08-08 19:31:49,130: DEBUG - + footprint=20
2023-08-08 19:31:49,130: DEBUG - + '[' 20 -le 20 ']'
2023-08-08 19:31:49,130: DEBUG - + min_spare_servers_factor=8
2023-08-08 19:31:49,131: DEBUG - + '[' low = low ']'
2023-08-08 19:31:49,131: DEBUG - + php_pm=ondemand
2023-08-08 19:31:49,131: DEBUG - ++ ynh_get_ram --total --ignore_swap
2023-08-08 19:31:49,228: DEBUG - ++ ignore_swap=1
2023-08-08 19:31:49,228: DEBUG - ++ only_swap=0
2023-08-08 19:31:49,228: DEBUG - ++ free=0
2023-08-08 19:31:49,228: DEBUG - ++ total=1
2023-08-08 19:31:49,229: DEBUG - +++ LC_ALL=C
2023-08-08 19:31:49,229: DEBUG - +++ vmstat --stats --unit M
2023-08-08 19:31:49,229: DEBUG - +++ grep 'total memory'
2023-08-08 19:31:49,229: DEBUG - +++ awk '{print $1}'
2023-08-08 19:31:49,232: DEBUG - ++ local total_ram=3504
2023-08-08 19:31:49,232: DEBUG - +++ LC_ALL=C
2023-08-08 19:31:49,233: DEBUG - +++ vmstat --stats --unit M
2023-08-08 19:31:49,233: DEBUG - +++ grep 'total swap'
2023-08-08 19:31:49,233: DEBUG - +++ awk '{print $1}'
2023-08-08 19:31:49,236: DEBUG - ++ local total_swap=512
2023-08-08 19:31:49,237: DEBUG - ++ local total_ram_swap=4016
2023-08-08 19:31:49,237: DEBUG - ++ local ram=4016
2023-08-08 19:31:49,237: DEBUG - ++ ram=3504
2023-08-08 19:31:49,237: DEBUG - ++ echo 3504
2023-08-08 19:31:49,237: DEBUG - + local max_ram=3504
2023-08-08 19:31:49,237: DEBUG - + php_max_children=87
2023-08-08 19:31:49,237: DEBUG - + '[' ondemand = static ']'
2023-08-08 19:31:49,237: DEBUG - ++ at_least_one 87
2023-08-08 19:31:49,238: DEBUG - ++ '[' 87 -le 0 ']'
2023-08-08 19:31:49,238: DEBUG - ++ echo 87
2023-08-08 19:31:49,238: DEBUG - + php_max_children=87
2023-08-08 19:31:49,238: DEBUG - ++ nproc
2023-08-08 19:31:49,239: DEBUG - + local core_number=4
2023-08-08 19:31:49,239: DEBUG - + local max_proc=16
2023-08-08 19:31:49,239: DEBUG - + '[' 87 -gt 16 ']'
2023-08-08 19:31:49,239: DEBUG - + php_max_children=16
2023-08-08 19:31:49,240: DEBUG - ++ ynh_app_setting_get --app=grocy --key=php_forced_max_children
2023-08-08 19:31:49,240: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:49,262: DEBUG - ++ app=grocy
2023-08-08 19:31:49,262: DEBUG - ++ [[ php_forced_max_children =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:49,263: DEBUG - ++ ynh_app_setting get grocy php_forced_max_children
2023-08-08 19:31:49,306: DEBUG - + local php_forced_max_children=
2023-08-08 19:31:49,306: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:49,306: DEBUG - + '[' ondemand = dynamic ']'
2023-08-08 19:31:49,306: DEBUG - + php_min_spare_servers=0
2023-08-08 19:31:49,307: DEBUG - + php_max_spare_servers=0
2023-08-08 19:31:49,307: DEBUG - + php_start_servers=0
2023-08-08 19:31:49,307: DEBUG - + local phpfpm_path=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.conf
2023-08-08 19:31:49,307: DEBUG - [__APP__]
2023-08-08 19:31:49,307: DEBUG - 
2023-08-08 19:31:49,307: DEBUG - user = __APP__
2023-08-08 19:31:49,307: DEBUG - group = __APP__
2023-08-08 19:31:49,307: DEBUG - 
2023-08-08 19:31:49,308: DEBUG - chdir = __INSTALL_DIR__
2023-08-08 19:31:49,308: DEBUG - 
2023-08-08 19:31:49,308: DEBUG - listen = /var/run/php/php__PHPVERSION__-fpm-__APP__.sock
2023-08-08 19:31:49,308: DEBUG - listen.owner = www-data
2023-08-08 19:31:49,308: DEBUG - listen.group = www-data
2023-08-08 19:31:49,308: DEBUG - 
2023-08-08 19:31:49,308: DEBUG - pm = __PHP_PM__
2023-08-08 19:31:49,308: DEBUG - pm.max_children = __PHP_MAX_CHILDREN__
2023-08-08 19:31:49,308: DEBUG - pm.max_requests = 500
2023-08-08 19:31:49,308: DEBUG - request_terminate_timeout = 1d
2023-08-08 19:31:49,309: DEBUG - '
2023-08-08 19:31:49,309: DEBUG - + '[' ondemand = dynamic ']'
2023-08-08 19:31:49,309: DEBUG - + '[' ondemand = ondemand ']'
2023-08-08 19:31:49,309: DEBUG - pm.process_idle_timeout = 10s
2023-08-08 19:31:49,309: DEBUG - '
2023-08-08 19:31:49,309: DEBUG - + '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/extra_php-fpm.conf ']'
2023-08-08 19:31:49,309: DEBUG - + cat /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/extra_php-fpm.conf
2023-08-08 19:31:49,309: DEBUG - + local finalphpconf=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,309: DEBUG - + ynh_add_config --template=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.conf --destination=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,330: DEBUG - + '[' -f /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf//var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.conf ']'
2023-08-08 19:31:49,331: DEBUG - + '[' -f /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.conf ']'
2023-08-08 19:31:49,331: DEBUG - + template_path=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.conf
2023-08-08 19:31:49,331: DEBUG - + ynh_backup_if_checksum_is_different --file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,337: DEBUG - + local checksum_setting_name=checksum__etc_php_8.2_fpm_pool.d_grocy.conf
2023-08-08 19:31:49,338: DEBUG - ++ ynh_app_setting_get --app=grocy --key=checksum__etc_php_8.2_fpm_pool.d_grocy.conf
2023-08-08 19:31:49,338: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:49,361: DEBUG - ++ app=grocy
2023-08-08 19:31:49,361: DEBUG - ++ [[ checksum__etc_php_8.2_fpm_pool.d_grocy.conf =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:49,361: DEBUG - ++ ynh_app_setting get grocy checksum__etc_php_8.2_fpm_pool.d_grocy.conf
2023-08-08 19:31:49,404: DEBUG - + local checksum_value=
2023-08-08 19:31:49,404: DEBUG - + backup_file_checksum=
2023-08-08 19:31:49,404: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:49,404: DEBUG - + touch /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,405: DEBUG - + chown root:root /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,406: DEBUG - + chmod 640 /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,407: DEBUG - + cp -f /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.conf /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,408: DEBUG - + _ynh_apply_default_permissions /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,408: DEBUG - + local target=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,409: DEBUG - ++ ynh_read_manifest --manifest_key=requirements.yunohost
2023-08-08 19:31:49,409: DEBUG - ++ tr -d '<>= '
2023-08-08 19:31:49,422: DEBUG - ++ '[' '!' -e '' ']'
2023-08-08 19:31:49,422: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.json ']'
2023-08-08 19:31:49,422: DEBUG - ++ '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml ']'
2023-08-08 19:31:49,423: DEBUG - ++ manifest=/var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:49,423: DEBUG - ++ echo /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:49,423: DEBUG - ++ grep -q '\.json$'
2023-08-08 19:31:49,424: DEBUG - ++ cat /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/manifest.toml
2023-08-08 19:31:49,424: DEBUG - ++ python3 -c 'import json, toml, sys; print(json.dumps(toml.load(sys.stdin)))'
2023-08-08 19:31:49,424: DEBUG - ++ jq .requirements.yunohost --raw-output
2023-08-08 19:31:49,459: DEBUG - + local ynh_requirement=null
2023-08-08 19:31:49,459: DEBUG - + dpkg --compare-versions 2.0 ge 2
2023-08-08 19:31:49,460: DEBUG - + chmod o-rwx /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,461: DEBUG - + chmod g-w /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,462: DEBUG - + chown -R root:root /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,463: DEBUG - + ynh_system_user_exists grocy
2023-08-08 19:31:49,470: DEBUG - + getent passwd grocy
2023-08-08 19:31:49,472: DEBUG - + chown grocy:grocy /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,473: DEBUG - + echo /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,473: DEBUG - + grep -q '^/etc/cron\|/etc/php\|/etc/nginx/conf.d\|/etc/fail2ban\|/etc/systemd/system'
2023-08-08 19:31:49,474: DEBUG - + chmod 400 /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,475: DEBUG - + chown root:root /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,477: DEBUG - + ynh_replace_vars --file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,484: DEBUG - + test -n /
2023-08-08 19:31:49,485: DEBUG - + local path_url_slash_less=
2023-08-08 19:31:49,485: DEBUG - + ynh_replace_string --match_string=__PATH__/ --replace_string=/ --target_file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,532: DEBUG - + sed --in-place $'s\001__PATH__/\001/\001g' /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,533: DEBUG - + ynh_replace_string --match_string=__PATH__ --replace_string=/ --target_file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,582: DEBUG - + sed --in-place $'s\001__PATH__\001/\001g' /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,583: DEBUG - + test -n grocy
2023-08-08 19:31:49,583: DEBUG - + ynh_replace_string --match_string=__NAME__ --replace_string=grocy --target_file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,630: DEBUG - + sed --in-place $'s\001__NAME__\001grocy\001g' /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,632: DEBUG - + ynh_replace_string --match_string=__NAMETOCHANGE__ --replace_string=grocy --target_file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,681: DEBUG - + sed --in-place $'s\001__NAMETOCHANGE__\001grocy\001g' /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,682: DEBUG - + ynh_replace_string --match_string=__USER__ --replace_string=grocy --target_file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,731: DEBUG - + sed --in-place $'s\001__USER__\001grocy\001g' /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,732: DEBUG - + test -n ''
2023-08-08 19:31:49,732: DEBUG - + dpkg --compare-versions 2.0 lt 2
2023-08-08 19:31:49,733: DEBUG - + test -n ''
2023-08-08 19:31:49,734: DEBUG - ++ grep -oP '__[A-Z0-9]+?[A-Z0-9_]*?[A-Z0-9]*?__' /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,734: DEBUG - ++ sed 's@__\([^.]*\)__@\L\1@g'
2023-08-08 19:31:49,734: DEBUG - ++ sort --unique
2023-08-08 19:31:49,736: DEBUG - + uniques_vars=('app' 'install_dir' 'phpversion' 'php_max_children' 'php_pm')
2023-08-08 19:31:49,736: DEBUG - + local delimit=@
2023-08-08 19:31:49,736: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:49,736: DEBUG - + [[ -v app ]]
2023-08-08 19:31:49,736: DEBUG - + match_string=__APP__
2023-08-08 19:31:49,736: DEBUG - + match_string=__APP__
2023-08-08 19:31:49,736: DEBUG - + replace_string=grocy
2023-08-08 19:31:49,737: DEBUG - + replace_string=grocy
2023-08-08 19:31:49,737: DEBUG - + replace_string=grocy
2023-08-08 19:31:49,737: DEBUG - + sed --in-place s@__APP__@grocy@g /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,737: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:49,737: DEBUG - + [[ -v install_dir ]]
2023-08-08 19:31:49,738: DEBUG - + match_string=__INSTALL_DIR__
2023-08-08 19:31:49,738: DEBUG - + match_string=__INSTALL_DIR__
2023-08-08 19:31:49,738: DEBUG - + replace_string=/var/www/grocy
2023-08-08 19:31:49,738: DEBUG - + replace_string=/var/www/grocy
2023-08-08 19:31:49,738: DEBUG - + replace_string=/var/www/grocy
2023-08-08 19:31:49,738: DEBUG - + sed --in-place s@__INSTALL_DIR__@/var/www/grocy@g /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,739: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:49,739: DEBUG - + [[ -v phpversion ]]
2023-08-08 19:31:49,739: DEBUG - + match_string=__PHPVERSION__
2023-08-08 19:31:49,739: DEBUG - + match_string=__PHPVERSION__
2023-08-08 19:31:49,739: DEBUG - + replace_string=8.2
2023-08-08 19:31:49,739: DEBUG - + replace_string=8.2
2023-08-08 19:31:49,739: DEBUG - + replace_string=8.2
2023-08-08 19:31:49,739: DEBUG - + sed --in-place s@__PHPVERSION__@8.2@g /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,740: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:49,741: DEBUG - + [[ -v php_max_children ]]
2023-08-08 19:31:49,741: DEBUG - + match_string=__PHP_MAX_CHILDREN__
2023-08-08 19:31:49,741: DEBUG - + match_string=__PHP_MAX_CHILDREN__
2023-08-08 19:31:49,741: DEBUG - + replace_string=16
2023-08-08 19:31:49,741: DEBUG - + replace_string=16
2023-08-08 19:31:49,741: DEBUG - + replace_string=16
2023-08-08 19:31:49,741: DEBUG - + sed --in-place s@__PHP_MAX_CHILDREN__@16@g /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,742: DEBUG - + for one_var in "${uniques_vars[@]}"
2023-08-08 19:31:49,742: DEBUG - + [[ -v php_pm ]]
2023-08-08 19:31:49,742: DEBUG - + match_string=__PHP_PM__
2023-08-08 19:31:49,742: DEBUG - + match_string=__PHP_PM__
2023-08-08 19:31:49,742: DEBUG - + replace_string=ondemand
2023-08-08 19:31:49,743: DEBUG - + replace_string=ondemand
2023-08-08 19:31:49,743: DEBUG - + replace_string=ondemand
2023-08-08 19:31:49,743: DEBUG - + sed --in-place s@__PHP_PM__@ondemand@g /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,744: DEBUG - + ynh_store_file_checksum --file=/etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,744: DEBUG - + update_only=0
2023-08-08 19:31:49,757: DEBUG - + local checksum_setting_name=checksum__etc_php_8.2_fpm_pool.d_grocy.conf
2023-08-08 19:31:49,757: DEBUG - ++ md5sum /etc/php/8.2/fpm/pool.d/grocy.conf
2023-08-08 19:31:49,757: DEBUG - ++ cut '--delimiter= ' --fields=1
2023-08-08 19:31:49,759: DEBUG - + ynh_app_setting_set --app=grocy --key=checksum__etc_php_8.2_fpm_pool.d_grocy.conf --value=aa2c0aef331de794cfff16bae951cd82
2023-08-08 19:31:49,759: DEBUG - + local _globalapp=grocy
2023-08-08 19:31:49,808: DEBUG - + app=grocy
2023-08-08 19:31:49,809: DEBUG - + [[ checksum__etc_php_8.2_fpm_pool.d_grocy.conf =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:49,809: DEBUG - + ynh_app_setting set grocy checksum__etc_php_8.2_fpm_pool.d_grocy.conf aa2c0aef331de794cfff16bae951cd82
2023-08-08 19:31:49,853: DEBUG - + '[' -n '' ']'
2023-08-08 19:31:49,853: DEBUG - + unset backup_file_checksum
2023-08-08 19:31:49,853: DEBUG - + '[' -e /var/cache/yunohost/app_tmp_work_dirs/app_uehyuq2r/conf/php-fpm.ini ']'
2023-08-08 19:31:49,854: DEBUG - + php-fpm8.2 --test
2023-08-08 19:31:49,971: DEBUG - + ynh_systemd_action --service_name=php8.2-fpm --action=reload
2023-08-08 19:31:50,034: DEBUG - + service_name=php8.2-fpm
2023-08-08 19:31:50,034: DEBUG - + action=reload
2023-08-08 19:31:50,034: DEBUG - + line_match=
2023-08-08 19:31:50,034: DEBUG - + length=20
2023-08-08 19:31:50,034: DEBUG - + log_path=/var/log/php8.2-fpm/php8.2-fpm.log
2023-08-08 19:31:50,034: DEBUG - + timeout=300
2023-08-08 19:31:50,034: DEBUG - + '[' reload == stop ']'
2023-08-08 19:31:50,034: DEBUG - + '[' reload == reload ']'
2023-08-08 19:31:50,035: DEBUG - + action=reload-or-restart
2023-08-08 19:31:50,035: DEBUG - ++ date --utc --rfc-3339=seconds
2023-08-08 19:31:50,035: DEBUG - ++ cut -d+ -f1
2023-08-08 19:31:50,036: DEBUG - + local 'time_start=2023-08-08 17:31:50 UTC'
2023-08-08 19:31:50,036: DEBUG - + systemctl reload-or-restart php8.2-fpm
2023-08-08 19:31:50,043: DEBUG - ++ ynh_app_setting_get --app=grocy --key=phpversion
2023-08-08 19:31:50,043: DEBUG - ++ local _globalapp=grocy
2023-08-08 19:31:50,067: DEBUG - ++ app=grocy
2023-08-08 19:31:50,067: DEBUG - ++ [[ phpversion =~ (unprotected|protected|skipped)_ ]]
2023-08-08 19:31:50,067: DEBUG - ++ ynh_app_setting get grocy phpversion
2023-08-08 19:31:50,110: DEBUG - + phpversion=8.2
2023-08-08 19:31:50,161: INFO - [####################] > Upgrade of grocy completed
2023-08-08 19:31:50,162: DEBUG - + ynh_exit_properly
2023-08-08 19:31:50,162: DEBUG - + [[ upgrade =~ ^install$|^upgrade$|^restore$ ]]
2023-08-08 19:31:51,164: DEBUG - Checking that required services are up and running...
2023-08-08 19:31:52,035: SUCCESS - grocy upgraded