search

amaxwell / tlutility

TeX Live Utility, a Mac OS GUI for the TeX Live Manager
http://amaxwell.github.io/tlutility/
BSD 3-Clause "New" or "Revised" License
307 stars 17 forks source link

Error with critical update #11

Closed eg9 closed 9 years ago

eg9 commented 9 years ago

The last infrastructure update fails to install with TeX Live Utility; here's the relevant part of the log

2014-12-24 11:19:27 +0000 Notice -[TLMMainWindowController _handleListUpdatesFinishedNotification:][26114]  Critical updates detected: (
    "texlive.infra"
)
2014-12-24 11:19:30 +0000 Notice -[TLMMainWindowController _isCorrectDatabaseVersionAtURL:][26114]  Checking database version in case preferences have been changed…
2014-12-24 11:19:30 +0000 Notice -[TLMMainWindowController _updateAllPackagesFromRepository:][26114]    Beginning infrastructure update from http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet
2014-12-24 11:19:30 +0000 Notice -[TLMInfraUpdateOperation _synchronouslyDownloadURL:toPath:][26114]    Downloading URL: http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet/update-tlmgr-latest.sh
2014-12-24 11:19:30 +0000 Notice -[TLMInfraUpdateOperation download:willSendRequest:redirectResponse:][26114]   Download redirected to http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet/update-tlmgr-latest.sh, expecting 0 bytes.
2014-12-24 11:19:30 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveResponse:][26114] Will download 4794067 bytes…
2014-12-24 11:19:31 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveDataOfLength:][26114] Received 21% of 4794067 bytes…
2014-12-24 11:19:32 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveDataOfLength:][26114] Received 41% of 4794067 bytes…
2014-12-24 11:19:33 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveDataOfLength:][26114] Received 62% of 4794067 bytes…
2014-12-24 11:19:35 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveDataOfLength:][26114] Received 83% of 4794067 bytes…
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation downloadDidFinish:][26114]   Download of 4794067 bytes complete
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation _downloadUpdateScript][26114]    First line of downloaded file is: "#!/bin/sh"…good!
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation _synchronouslyDownloadURL:toPath:][26114]    Downloading URL: http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet/update-tlmgr-latest.sh.sha256
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation download:willSendRequest:redirectResponse:][26114]   Download redirected to http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet/update-tlmgr-latest.sh.sha256, expecting 0 bytes.
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveResponse:][26114] Will download 89 bytes…
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation download:didReceiveDataOfLength:][26114] Received 100% of 89 bytes…
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation downloadDidFinish:][26114]   Download of 89 bytes complete
2014-12-24 11:19:37 +0000 Notice -[TLMInfraUpdateOperation _downloadAndCheckHash][26114]    SHA256 signature looks okay
2014-12-24 11:19:37 +0000 Notice -[TLMAuthorizedOperation main][26114]  Checking code signature before running tlu_ipctask as root…
2014-12-24 11:19:37 +0000 Notice /usr/bin/codesign[26114]   /Applications/TeX/TeX Live Utility.app/Contents/MacOS/tlu_ipctask: valid on disk
/Applications/TeX/TeX Live Utility.app/Contents/MacOS/tlu_ipctask: satisfies its Designated Requirement
2014-12-24 11:19:37 +0000 Notice -[TLMAuthorizedOperation main][26114]  Signature was valid, okay to run tlu_ipctask
2014-12-24 11:19:41 +0000 Notice -[TLMAuthorizedOperation main][26114]  Invoking privileged task via AuthorizationExecuteWithPrivileges
2014-12-24 11:19:41 +0000 Notice tlu_ipctask[26275] dropped privileges to user nobody
2014-12-24 11:19:42 +0000 Notice -[TLMAuthorizedOperation setWrapperPID:][26114]    tlu_ipctask checking in:  tlu_ipctask pid = 26275
2014-12-24 11:19:42 +0000 Notice -[TLMAuthorizedOperation setUnderlyingPID:][26114] tlu_ipctask checking in: pid = 26276 (/var/folders/8b/hx9v58ln1txgzv4v32_vpbr40000gn/T/TLMInfraUpdateOperation.WoQAWX/update-tlmgr-latest.sh --nox11)
2014-12-24 11:19:42 +0000 Notice tlu_ipctask[26275] tlu_ipctask: child HOME = '/var/root'
2014-12-24 11:19:42 +0000 Notice tlu_ipctask[26275] tlu_ipctask: current HOME = '/var/empty'
2014-12-24 11:19:43 +0000 Notice tlu_ipctask[26275] Verifying archive integrity... All good.
2014-12-24 11:19:43 +0000 Notice tlu_ipctask[26275] Uncompressing TeX Live Manager Updater......................................................................................................................................................................................
2014-12-24 11:19:43 +0000 Warning tlu_ipctask[26275]    ./runme.sh: Cannot find TeX Live root using kpsewhich --var-value=SELFAUTOPARENT.
2014-12-24 11:19:43 +0000 Warning tlu_ipctask[26275]    ./runme.sh: Please set your PATH as needed, otherwise it's hopeless.
2014-12-24 11:19:43 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26114]    kqueue noted that pid 26276 exited (/var/folders/8b/hx9v58ln1txgzv4v32_vpbr40000gn/T/TLMInfraUpdateOperation.WoQAWX/update-tlmgr-latest.sh --nox11)
2014-12-24 11:19:43 +0000 Notice tlu_ipctask[26275] child process pid = 26276 exited
2014-12-24 11:19:44 +0000 Error tlu_ipctask[26275]  Value of errno is Operation not permitted
2014-12-24 11:19:44 +0000 Error tlu_ipctask[26275]  *** ERROR *** exit status of pid = 26276 was 1
2014-12-24 11:19:44 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26114]    waitpid returned 26275, WIFEXITED(256) = 1, errno = 93 (Attribute not found)
2014-12-24 11:19:44 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26114]    kqueue noted that tlu_ipctask (pid = 26275) exited with status 1
2014-12-24 11:19:44 +0000 Notice -[TLMInfraUpdateOperation main][26114] Removed temp directory "/var/folders/8b/hx9v58ln1txgzv4v32_vpbr40000gn/T/TLMInfraUpdateOperation.WoQAWX"
2014-12-24 11:20:08 +0000 Notice -[TLMMainWindowController dockableWindowWillClose:][26114] Undocking log window
2014-12-24 11:20:11 +0000 Notice -[TLMMainWindowController _isCorrectDatabaseVersionAtURL:][26114]  Checking database version in case preferences have been changed…
2014-12-24 11:20:11 +0000 Notice -[TLMMainWindowController _updateAllPackagesFromRepository:][26114]    Beginning update of all packages from http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet
2014-12-24 11:20:11 +0000 Notice -[TLMAuthorizedOperation main][26114]  Checking code signature before running tlu_ipctask as root…
2014-12-24 11:20:12 +0000 Notice /usr/bin/codesign[26114]   /Applications/TeX/TeX Live Utility.app/Contents/MacOS/tlu_ipctask: valid on disk
/Applications/TeX/TeX Live Utility.app/Contents/MacOS/tlu_ipctask: satisfies its Designated Requirement
2014-12-24 11:20:12 +0000 Notice -[TLMAuthorizedOperation main][26114]  Signature was valid, okay to run tlu_ipctask
2014-12-24 11:20:16 +0000 Notice -[TLMAuthorizedOperation main][26114]  Invoking privileged task via AuthorizationExecuteWithPrivileges
2014-12-24 11:20:16 +0000 Notice tlu_ipctask[26345] dropped privileges to user nobody
2014-12-24 11:20:17 +0000 Notice -[TLMAuthorizedOperation setWrapperPID:][26114]    tlu_ipctask checking in:  tlu_ipctask pid = 26345
2014-12-24 11:20:17 +0000 Notice -[TLMAuthorizedOperation setUnderlyingPID:][26114] tlu_ipctask checking in: pid = 26346 (/usr/texbin/tlmgr --repository http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet --machine-readable --persistent-downloads update --all)
2014-12-24 11:20:17 +0000 Notice tlu_ipctask[26345] tlu_ipctask: child HOME = '/var/root'
2014-12-24 11:20:17 +0000 Notice tlu_ipctask[26345] tlu_ipctask: current HOME = '/var/empty'
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    tlmgr: package repository http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    ===============================================================================
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    tlmgr itself needs to be updated.
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    Please do this via either
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    tlmgr update --self
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    or by getting the latest updater for Unix-ish systems:
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    http://mirror.ctan.org/systems/texlive/tlnet/update-tlmgr-latest.sh
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    and/or Windows systems:
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    http://mirror.ctan.org/systems/texlive/tlnet/update-tlmgr-latest.exe
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    Then continue with other updates as usual.
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    ===============================================================================
2014-12-24 11:20:22 +0000 Warning tlu_ipctask[26345]    tlmgr: Exiting, please read above warning.
2014-12-24 11:20:22 +0000 Notice tlu_ipctask[26345] location-url    http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet
2014-12-24 11:20:22 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26114]    kqueue noted that pid 26346 exited (/usr/texbin/tlmgr --repository http://ctan.mirror.garr.it/mirrors/CTAN/systems/texlive/tlnet --machine-readable --persistent-downloads update --all)
2014-12-24 11:20:22 +0000 Notice tlu_ipctask[26345] child process pid = 26346 exited
2014-12-24 11:20:23 +0000 Error tlu_ipctask[26345]  Value of errno is Operation not permitted
2014-12-24 11:20:23 +0000 Error tlu_ipctask[26345]  *** ERROR *** exit status of pid = 26346 was 25
2014-12-24 11:20:23 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26114]    waitpid returned 26345, WIFEXITED(6400) = 1, errno = 0 (No error)
2014-12-24 11:20:23 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26114]    kqueue noted that tlu_ipctask (pid = 26345) exited with status 25

Trying from the command line with

sudo tlmgr update --self

goes well

The most important lines in the log seem to be

2014-12-24 11:19:43 +0000 Notice tlu_ipctask[26275] Uncompressing TeX Live Manager Updater......................................................................................................................................................................................
2014-12-24 11:19:43 +0000 Warning tlu_ipctask[26275]    ./runme.sh: Cannot find TeX Live root using kpsewhich --var-value=SELFAUTOPARENT.
2014-12-24 11:19:43 +0000 Warning tlu_ipctask[26275]    ./runme.sh: Please set your PATH as needed, otherwise it's hopeless.
amaxwell commented 9 years ago

This is a bug in the TeX Live updater script, and it needs to be fixed server-side.

amaxwell commented 9 years ago

Norbert says this is fixed, but other users are reporting various issues. Regardless, there's nothing I can do.

amaxwell commented 9 years ago

Apparently this is only fixed in tlcritical. If you choose the Actions menu and hold down the option key, you'll see an item that lets you install TeX Live Manager from the critical repository. Do that, and it'll work around the problem.

ronbannon commented 9 years ago

I tried amaxwell's suggestion, but it's not working on my system. Certainly not an emergency, but would love to see`this fixed.

amaxwell commented 9 years ago

@ronbannon Can you be more specific? What kind of failure message do you see after trying to install from the critical repository?

ronbannon commented 9 years ago

2014-12-29 19:10:01 +0000 Notice -[TLMMainWindowController _updateAllPackagesFromRepository:][4836] Beginning infrastructure update from ftp://tug.org/texlive/tlcritical 2014-12-29 19:10:01 +0000 Notice -[TLMInfraUpdateOperation _synchronouslyDownloadURL:toPath:][4836] Downloading URL: ftp://tug.org/texlive/tlcritical/update-tlmgr-latest.sh 2014-12-29 19:10:01 +0000 Notice -[TLMInfraUpdateOperation download:willSendRequest:redirectResponse:][4836] Download redirected to ftp://tug.org/texlive/tlcritical/update-tlmgr-latest.sh, expecting 0 bytes. 2014-12-29 19:11:01 +0000 Notice -[TLMInfraUpdateOperation download:didFailWithError:][4836] Download failed: Error Domain=NSURLErrorDomain Code=-1001 "The request timed out." UserInfo=0x6000000fc280 {NSUnderlyingError=0x600001c4a680 "The request timed out.", NSErrorFailingURLStringKey=ftp://tug.org/texlive/tlcritical/update-tlmgr-latest.sh, NSErrorFailingURLKey=ftp://tug.org/texlive/tlcritical/update-tlmgr-latest.sh, NSLocalizedDescription=The request timed out.} Failed URL was: ftp://tug.org/texlive/tlcritical/update-tlmgr-latest.sh 2014-12-29 19:11:01 +0000 Notice -[TLMInfraUpdateOperation main][4836] Removed temp directory "/var/folders/h1/my0r1x991bgd9_cdwbjy1sm00000gp/T/TLMInfraUpdateOperation.q7whPc" 2014-12-29 19:11:06 +0000 Notice -[TLMLogWindowController awakeFromNib][4836] Loaded log window controller

amaxwell commented 9 years ago

@ronbannon yeah, I'm seeing reports of this from others, too. Might be too many connections on the ftp server, but I'm just guessing now. Sorry.

ronbannon commented 9 years ago

Looks like someone fixed the problem. The update is now working fine. Thanks!

themightyoarfish commented 6 years ago

Had the same problem with the new update to mactex 2018. Then upon trying again, this error:

2018-08-30 09:10:26 +0000 Notice -[TLMInfraUpdateOperation main][26883] Removed temp directory "/var/folders/y0/w1n1qyf14_bb880ysmh1qk400000gn/T/TLMInfraUpdateOperation.4OaOh8"
2018-08-30 09:10:51 +0000 Notice -[TLMLogWindowController awakeFromNib][26883]  Loaded log window controller
2018-08-30 09:13:56 +0000 Notice -[TLMMainWindowController dockableWindowWillClose:][26883] Undocking log window
2018-08-30 09:14:03 +0000 Notice -[TLMMainWindowController _isCorrectDatabaseVersionAtURL:][26883]  Checking database version in case preferences have been changed…
2018-08-30 09:14:03 +0000 Notice -[TLMMainWindowController _updateAllPackagesFromRepository:][26883]    Beginning update of all packages from ftp://ctan.mirror.norbert-ruehl.de/systems/texlive/tlnet
2018-08-30 09:14:03 +0000 Notice -[TLMAuthorizedOperation main][26883]  Checking code signature before running tlu_ipctask as root…
2018-08-30 09:14:03 +0000 Notice /usr/bin/codesign[26883]   /Applications/TeX/TeX Live Utility.app/Contents/MacOS/tlu_ipctask: valid on disk
/Applications/TeX/TeX Live Utility.app/Contents/MacOS/tlu_ipctask: satisfies its Designated Requirement
2018-08-30 09:14:03 +0000 Notice -[TLMAuthorizedOperation main][26883]  Signature was valid, okay to run tlu_ipctask
2018-08-30 09:14:07 +0000 Notice -[TLMAuthorizedOperation main][26883]  Invoking privileged task via AuthorizationExecuteWithPrivileges
2018-08-30 09:14:07 +0000 Notice tlu_ipctask[27294] dropped privileges to user nobody
2018-08-30 09:14:08 +0000 Notice -[TLMAuthorizedOperation setWrapperPID:][26883]    tlu_ipctask checking in:  tlu_ipctask pid = 27294
2018-08-30 09:14:09 +0000 Notice -[TLMAuthorizedOperation setUnderlyingPID:][26883] tlu_ipctask checking in: pid = 27295 (/Library/TeX/texbin/tlmgr --repository ftp://ctan.mirror.norbert-ruehl.de/systems/texlive/tlnet --machine-readable --persistent-downloads update --all)
2018-08-30 09:14:09 +0000 Notice tlu_ipctask[27294] tlu_ipctask: child HOME = '/var/root'
2018-08-30 09:14:09 +0000 Notice tlu_ipctask[27294] tlu_ipctask: current HOME = '/var/empty'
2018-08-30 09:14:20 +0000 Warning tlu_ipctask[27294]    tlmgr: An error has occurred. See above messages. Exiting.
2018-08-30 09:14:20 +0000 Notice tlu_ipctask[27294] location-url    ftp://ctan.mirror.norbert-ruehl.de/systems/texlive/tlnet
2018-08-30 09:14:21 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26883]    kqueue noted that pid 27295 exited (/Library/TeX/texbin/tlmgr --repository ftp://ctan.mirror.norbert-ruehl.de/systems/texlive/tlnet --machine-readable --persistent-downloads update --all)
2018-08-30 09:14:21 +0000 Notice tlu_ipctask[27294] child process pid = 27295 exited
2018-08-30 09:14:22 +0000 Error tlu_ipctask[27294]  Value of errno is Operation not permitted
2018-08-30 09:14:22 +0000 Error tlu_ipctask[27294]  *** ERROR *** exit status of pid = 27295 was 2
2018-08-30 09:14:22 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26883]    waitpid returned 27294, WIFEXITED(512) = 1, errno = 9 (No error)
2018-08-30 09:14:22 +0000 Notice -[TLMAuthorizedOperation _runUntilChildExit][26883]    kqueue noted that tlu_ipctask (pid = 27294) exited with status 2
themightyoarfish commented 6 years ago

Update: Changing the default mirror in Configure > Manage Repositories seems to make it go away.