На этом сервере работает Debian 7, и я столкнулся с большой загадкой.
Вот мои задачи cron:
$ sudo crontab -e
42 15 * * * apt-get -y update >> /var/log/my-apt-update.txt
52 15 * * * apt-get -y upgrade >> /var/log/my-apt-upgrade.txt
Я добавил ">> /var/log/my-apt-upgrade.txt" отчасти потому, что я пытаюсь понять, почему моя система никогда не обновляется.
Запустятся задачи cron. Каждый день в / var / log / syslog у меня есть такие строки:
Nov 14 15:42:01 myhostname /USR/SBIN/CRON[3374]: (root) CMD (apt-get -y update >> /var/log/my-apt-update.txt)
Nov 14 15:52:01 myhostname /USR/SBIN/CRON[3394]: (root) CMD (apt-get -y upgrade >> /var/log/my-apt-upgrade.txt)
А в /var/log/my-apt-upgrade.txt есть такие абзацы (я показываю только последние два дня):
Reading package lists...
Building dependency tree...
Reading state information...
The following packages will be upgraded:
file libmagic1
2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B/256 kB of archives.
After this operation, 110 kB disk space will be freed.
Reading package lists...
Building dependency tree...
Reading state information...
The following packages will be upgraded:
file libmagic1
2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B/256 kB of archives.
After this operation, 110 kB disk space will be freed.
Как видите, пакеты "file" и "libmagic1" должны были быть обновлены в первый же день. Но это не так. Итак, на второй день они снова упоминаются. Но не модернизируется.
И сегодня, если я сбегу
$ sudo apt-get -y upgrade
пакеты "file" и "libmagic1" упоминаются снова, и они (наконец) обновляются.
Итак, как видите, я могу обновиться вручную. Но эти пакеты следовало обновить раньше, когда запускалась задача cron.
Есть ключ к разгадке этой загадки?
Дополнение Сб, 15 ноя, 11:48:
вот что появляется в моем /var/log/apt/history.log во время работы cron.
Start-Date: 2014-11-13 15:52:03
Commandline: apt-get -y upgrade
Upgrade: file:amd64 (5.11-2+deb7u5, 5.11-2+deb7u6), libmagic1:amd64 (5.11-2+deb7u5, 5.11-2+deb7u6)
Error: Sub-process /usr/bin/dpkg returned an error code (2)
End-Date: 2014-11-13 15:52:03
Start-Date: 2014-11-14 15:52:03
Commandline: apt-get -y upgrade
Upgrade: file:amd64 (5.11-2+deb7u5, 5.11-2+deb7u6), libmagic1:amd64 (5.11-2+deb7u5, 5.11-2+deb7u6)
Error: Sub-process /usr/bin/dpkg returned an error code (2)
End-Date: 2014-11-14 15:52:03
Такое же сообщение появляется в /var/log/apt/history.log для разных пакетов. Например, ранее в этом месяце пакет «wget» нуждался в некотором обновлении (на этот раз я пробовал использовать параметр -qq, но этот параметр, похоже, не имеет никакого значения).
Start-Date: 2014-11-03 15:52:02
Commandline: apt-get -y -qq upgrade
Upgrade: wget:amd64 (1.13.4-3+deb7u1, 1.13.4-3+deb7u2)
Error: Sub-process /usr/bin/dpkg returned an error code (2)
End-Date: 2014-11-03 15:52:02
Start-Date: 2014-11-04 15:52:02
Commandline: apt-get -y -qq upgrade
Upgrade: wget:amd64 (1.13.4-3+deb7u1, 1.13.4-3+deb7u2)
Error: Sub-process /usr/bin/dpkg returned an error code (2)
End-Date: 2014-11-04 15:52:03
Start-Date: 2014-11-05 15:52:03
Commandline: apt-get -y -qq upgrade
Upgrade: wget:amd64 (1.13.4-3+deb7u1, 1.13.4-3+deb7u2)
Error: Sub-process /usr/bin/dpkg returned an error code (2)
End-Date: 2014-11-05 15:52:03
Кажется, я нашел причину проблемы.
Чтобы обнаружить ошибку, мне пришлось поймать stderr в моем пользовательском журнале. Оказалось, что это было полезно, потому что некоторые ошибки не отправлялись по почте root и не записывались в другие журналы.
Чтобы поймать stderr в моем журнале, я сначала изменил задачу cron на:
52 15 * * * apt-get -y upgrade >> /var/log/my-apt-upgrade.txt 2>&1
Сегодня должен был быть обновлен пакет "wlibgcrypt11". На этот раз мой журнал обнаружил ошибку. Это было более явным, чем расплывчатое сообщение об ошибке, появившееся ранее в /var/log/apt/history.log.
Сегодня в /var/log/my-apt-upgrade.txt:
Reading package lists...
Building dependency tree...
Reading state information...
The following packages will be upgraded:
libgcrypt11
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
dpkg-preconfigure: unable to re-open stdin:
1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B/300 kB of archives.
After this operation, 35.8 kB of additional disk space will be used.
dpkg: warning: 'ldconfig' not found in PATH or not executable
dpkg: warning: 'start-stop-daemon' not found in PATH or not executable
dpkg: error: 2 expected programs not found in PATH or not executable
Note: root's PATH should usually contain /usr/local/sbin, /usr/sbin and /sbin
E: Sub-process /usr/bin/dpkg returned an error code (2)
Значит, это была ошибка ПУТИ.
Путь корневого каталога в моей системе содержит все необходимые каталоги. То же самое и с переменной secure_path в visudo. Вот почему все работает, когда я запускаю sudo apt-get вручную.
Но cron не устанавливает переменные среды. Поэтому я добавил переменную среды PATH для каждой задачи cron.
$ sudo crontab -e
22 16 * * * PATH='/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin' apt-get -y update >> /var/log/my-new-apt-update.txt 2>&1
32 16 * * * PATH='/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin' apt-get -y upgrade >> /var/log/my-new-apt-upgrade.txt 2>&1
И это сработало! Пакет успешно обновлен задачей cron.