itch icon indicating copy to clipboard operation
itch copied to clipboard

🏁 Issue v25.4.0(?) - Timed out waiting for butlerd

Open ElTipejoLoco opened this issue 4 years ago • 6 comments

itch App Version: v25.4.0 (presumed due to log- cannot reach Preferences/Advanced menu currently to check) OS: 🏁 Windows 10 Pro v1909 (18363.720 Build) Upon attempting to start the itch app after my computer crashed, it prevented me from seeing my users list and reports a "Timed out while waiting for butlerd" error. The status.itch.ovh site suggests that butler is not down, however. Attached is the log blurb after I retried setup a few times:

{"time":1586644753879,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586644753881,"level":30,"msg":"Creating broth manager","name":"🔧 setup"} {"time":1586644753884,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586644753888,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586644756341,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586644756344,"level":30,"msg":"Switching to (15.17.0)","name":"📦 butler"} {"time":1586644756353,"level":20,"msg":"Package (butler) got version prefix","name":"🔧 setup"} {"time":1586644756357,"level":30,"msg":"Refreshing butlerd!","name":"🔧 setup"} {"time":1586644756357,"level":30,"msg":"Spinning up butlerd instance 1...","name":"🔧 setup"} {"time":1586644756388,"level":30,"msg":"...waiting for endpoint from butlerd instance 1...","name":"🔧 setup"} {"time":1586644756514,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586644761891,"level":20,"msg":"Downloading fresh locale file from https://locales.itch.ovh/itch/en.json","name":"m/reac/locales"} {"time":1586644762020,"level":50,"msg":"while reacting to packageGotVersionPrefix: Error: timed out waiting for butlerd to listen\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:37379:24)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)"} {"time":1586644762059,"level":20,"msg":"HTTP GET https://locales.itch.ovh/itch/en.json: 200","name":"m/reac/locales"} {"time":1586644762064,"level":20,"msg":"Saving fresh en locale to C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\locales\\en.json","name":"m/reac/locales"} {"time":1586644762226,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586644762226,"level":30,"msg":"Switching to (1.18.0)","name":"📦 itch-setup"} {"time":1586644762227,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586644762228,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586644762228,"level":20,"msg":"Package (itch-setup) got version prefix","name":"🔧 setup"} {"time":1586644767230,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586644779925,"level":30,"msg":"Regularly scheduled check for game updates...","name":"m/reac/updater"} {"time":1586644779927,"level":30,"msg":"Scheduling next game update check for Sat Apr 11 2020 18:03:54 GMT-0500 (Central Daylight Time)","name":"m/reac/updater"} {"time":1586644860416,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586644860416,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586644860417,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586644861084,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586644861090,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586644861304,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586644861304,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586644861305,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586644866306,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586644884900,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586644884900,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586644884902,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586644885530,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586644885536,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586644885769,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586644885772,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586644885772,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586644890774,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586644979215,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586644979216,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586644979217,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586644979815,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586644979822,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586644979988,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586644979989,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586644979989,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586644984992,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645033276,"level":20,"msg":"On window.close: close to tray enabled","name":"m/reac/winds"} {"time":1586645062271,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645062275,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645062278,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645062820,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645062824,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645063004,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645063005,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645063005,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645068007,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645095231,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645095233,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645095233,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645095711,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645095720,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645095901,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645095904,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645095905,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645100907,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645155826,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645155828,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645155828,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645156354,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645156360,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645156519,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645156520,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645156520,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645161523,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645164801,"level":20,"msg":"On window.close: close to tray enabled","name":"m/reac/winds"} {"time":1586645223312,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645223312,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645223312,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645223676,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645223678,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645223820,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645223820,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645223820,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645228822,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645648841,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645648842,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645648843,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645649361,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645649366,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645649538,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645649538,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645649538,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645654540,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645660240,"level":20,"msg":"On window.close: close to tray enabled","name":"m/reac/winds"} {"time":1586645684980,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645684980,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645684982,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645685572,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645685578,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645685771,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645685771,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645685771,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645690771,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"} {"time":1586645693754,"level":20,"msg":"On window.close: close to tray enabled","name":"m/reac/winds"} {"time":1586645818494,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586645818494,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586645818497,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586645818907,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586645818912,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586645819077,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586645819079,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586645819080,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586645824082,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n at Timeout.setTimeout [as _onTimeout] (C:\\Users\\WinUserNameHere\\AppData\\Local\\itch\\app-25.4.0\\resources\\app.asar\\dist\\main\\main.bundle.js:16708:28)\n at ontimeout (timers.js:475:11)\n at tryOnTimeout (timers.js:310:5)\n at Timer.listOnTimeout (timers.js:270:5)","name":"🔧 setup"}

Should I simply try to overwrite the app with the installer, or is there something else that might be causing this issue that I should try first? Edit: I used Task Manager>Details to find the itch executables running in the background and ended their tasks. Re-opening itch then remedied the problem. Still, it'd be nice to know what caused this, but this issue can safely be closed if this behavior is working as expected. I've attached the log blurb created by the app after the error ceased, up until its "Setup done" message:

{"time":1586648432784,"level":20,"msg":"On window.close: close to tray enabled","name":"m/reac/winds"} {"time":1586648485938,"level":30,"msg":"[email protected] on [email protected] in production"} {"time":1586648485952,"level":20,"msg":"imported preferences: {\"downloadSelfUpdates\":true,\"offlineMode\":false,\"installLocations\":{\"8551500f-a27e-465b-951c-2e637f170f7c\":{\"path\":\"D:\\\\itch\\\\apps\"}},\"defaultInstallLocation\":\"8551500f-a27e-465b-951c-2e637f170f7c\",\"isolateApps\":false,\"closeToTray\":true,\"readyNotification\":true,\"showAdvanced\":false,\"openAtLogin\":false,\"openAsHidden\":false,\"manualGameUpdates\":false,\"preventDisplaySleep\":true,\"preferOptimizedPatches\":false,\"disableBrowser\":false,\"enableTabs\":false,\"sidebarWidth\":240,\"onlyCompatibleGames\":false,\"onlyOwnedGames\":false,\"onlyInstalledGames\":false,\"layout\":\"table\",\"gotMinimizeNotification\":true,\"lastSuccessfulSetupVersion\":\"25.4.0\",\"importedOldInstallLocations\":true}","name":"m/reac/preb/load-preferences"} {"time":1586648486454,"level":20,"msg":"imported preferences: {\"downloadSelfUpdates\":true,\"offlineMode\":false,\"installLocations\":{\"8551500f-a27e-465b-951c-2e637f170f7c\":{\"path\":\"D:\\\\itch\\\\apps\"}},\"defaultInstallLocation\":\"8551500f-a27e-465b-951c-2e637f170f7c\",\"isolateApps\":false,\"closeToTray\":true,\"readyNotification\":true,\"showAdvanced\":false,\"openAtLogin\":false,\"openAsHidden\":false,\"manualGameUpdates\":false,\"preventDisplaySleep\":true,\"preferOptimizedPatches\":false,\"disableBrowser\":false,\"enableTabs\":false,\"sidebarWidth\":240,\"onlyCompatibleGames\":false,\"onlyOwnedGames\":false,\"onlyInstalledGames\":false,\"layout\":\"table\",\"gotMinimizeNotification\":true,\"lastSuccessfulSetupVersion\":\"25.4.0\",\"importedOldInstallLocations\":true}","name":"m/reac/preb/load-preferences"} {"time":1586648486473,"level":20,"msg":"Registering itch: protocol for session with user agent Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) itch/25.4.0 Chrome/61.0.3163.100 Electron/2.0.8 Safari/537.36","name":"m/net/register-itch-protocol"} {"time":1586648486971,"level":20,"msg":"Updating login item settings, open=false, hidden=false","name":"m/reac/open-at-login"} {"time":1586648486993,"level":30,"msg":"preboot ran in 545.00ms","name":"m/reac/preboot"} {"time":1586648487081,"level":20,"msg":"Waiting on root window to dispatch boot","name":"m/reac/winds"} {"time":1586648489820,"level":20,"msg":"Downloading fresh locale file from https://locales.itch.ovh/itch/en.json","name":"m/reac/locales"} {"time":1586648490593,"level":20,"msg":"HTTP GET https://locales.itch.ovh/itch/en.json: 200","name":"m/reac/locales"} {"time":1586648490600,"level":20,"msg":"Saving fresh en locale to C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\locales\\en.json","name":"m/reac/locales"} {"time":1586648492079,"level":30,"msg":"Setup starting...","name":"🔧 setup"} {"time":1586648492082,"level":30,"msg":"Creating broth manager","name":"🔧 setup"} {"time":1586648492091,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"} {"time":1586648492099,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"} {"time":1586648493902,"level":30,"msg":"(15.17.0) is chosen and valid","name":"📦 butler"} {"time":1586648493903,"level":30,"msg":"Switching to (15.17.0)","name":"📦 butler"} {"time":1586648493908,"level":20,"msg":"Package (butler) got version prefix","name":"🔧 setup"} {"time":1586648493911,"level":30,"msg":"Refreshing butlerd!","name":"🔧 setup"} {"time":1586648493911,"level":30,"msg":"Spinning up butlerd instance 1...","name":"🔧 setup"} {"time":1586648493927,"level":30,"msg":"...waiting for endpoint from butlerd instance 1...","name":"🔧 setup"} {"time":1586648493961,"level":20,"msg":"spawning C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.18.0\\itch-setup :: --version","name":"📦 itch-setup"} {"time":1586648494239,"level":20,"msg":"Downloading fresh locale file from https://locales.itch.ovh/itch/en.json","name":"m/reac/locales"} {"time":1586648494256,"level":20,"msg":"HTTP GET https://locales.itch.ovh/itch/en.json: 200","name":"m/reac/locales"} {"time":1586648494257,"level":20,"msg":"Saving fresh en locale to C:\\Users\\WinUserNameHere\\AppData\\Roaming\\itch\\locales\\en.json","name":"m/reac/locales"} {"time":1586648494445,"level":30,"msg":"(1.18.0) is chosen and valid","name":"📦 itch-setup"} {"time":1586648494447,"level":30,"msg":"Switching to (1.18.0)","name":"📦 itch-setup"} {"time":1586648494451,"level":30,"msg":"Already set up 25.4.0 once, delaying components upgrade check","name":"🔧 setup"} {"time":1586648494452,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"} {"time":1586648494458,"level":20,"msg":"Package (itch-setup) got version prefix","name":"🔧 setup"} {"time":1586648496157,"level":30,"msg":"...for butlerd instance 1 got endpoint 127.0.0.1:50997","name":"🔧 setup"} {"time":1586648496180,"level":30,"msg":"Now speaking with butlerd instance 1, version v15.17.0, built on Aug 13 2019 @ 13:35:08, ref 94c194ef1888b0363e399983355bf981dee38ff8, endpoint 127.0.0.1:50997","name":"🔧 setup"} {"time":1586648496182,"level":20,"msg":"Syncing install locations...","name":"🔧 setup"} {"time":1586648496190,"level":20,"msg":"🙏 Install.Locations.List","name":"mcall"} {"time":1586648496192,"level":20,"msg":"Downloads driver reacting to gotButlerdEndpoint, cancelling state...","name":"m/reac/down/driver"} {"time":1586648496193,"level":20,"msg":"Not cancelling, current phase is 0","name":"m/reac/down/driver-persistent-state"} {"time":1586648496193,"level":20,"msg":"Downloads driver reacting to gotButlerdEndpoint, state is cancelled...","name":"m/reac/down/driver"} {"time":1586648496194,"level":20,"msg":"Download driver polling...","name":"m/reac/down/driver"} {"time":1586648496194,"level":20,"msg":"🙏 Downloads.List","name":"mcall"} {"time":1586648496299,"level":20,"msg":"Dispatching setup done!","name":"🔧 setup"} {"time":1586648496304,"level":30,"msg":"Setup done","name":"🔧 setup"}

ElTipejoLoco avatar Apr 11 '20 23:04 ElTipejoLoco

This means that either butler is not able to listen on a local TCP port, or that itch is not able to connect to it. Either way it's usually because of some antivirus.

fasterthanlime avatar Apr 12 '20 00:04 fasterthanlime

The only antivirus I have is Windows Defender and it didn't look like it was causing any issues, so I don't think that's the case here. Otherwise I imagine I would've had to take more steps than "find the background processes, end them, and retry."

ElTipejoLoco avatar Apr 12 '20 00:04 ElTipejoLoco

So there was a running butler.exe instance? And killing it made it work? Does it work for you now?

fasterthanlime avatar Apr 12 '20 00:04 fasterthanlime

Yes, there was, but the instance I killed was one of the trio of itch.exe, which ended the rest (2 other itch.exe + butler.exe) alongside it. It's currently working for me.

Edit: I suppose I should mention the the tray icon had not appeared before this, despite the background processes' persistence. During the functioning run, it did.

ElTipejoLoco avatar Apr 12 '20 00:04 ElTipejoLoco

This happens to me consistently the first time I launch Itch after a reboot. Killing butler.exe solves the problem. Potentially relevant: this on a Windows 11 ARM system.

itch.txt
{"time":1650489584895,"level":30,"msg":"[email protected] on [email protected] in production"}
{"time":1650489584900,"level":20,"msg":"imported preferences: {\"downloadSelfUpdates\":true,\"offlineMode\":false,\"installLocations\":{},\"defaultInstallLocation\":\"appdata\",\"isolateApps\":false,\"closeToTray\":true,\"readyNotification\":true,\"showAdvanced\":false,\"openAtLogin\":false,\"openAsHidden\":false,\"manualGameUpdates\":false,\"preventDisplaySleep\":true,\"preferOptimizedPatches\":false,\"disableBrowser\":false,\"enableTabs\":false,\"lastSuccessfulSetupVersion\":\"25.5.1\",\"importedOldInstallLocations\":true,\"gotMinimizeNotification\":true}","name":"m/reac/preb/load-preferences"}
{"time":1650489585036,"level":20,"msg":"imported preferences: {\"downloadSelfUpdates\":true,\"offlineMode\":false,\"installLocations\":{},\"defaultInstallLocation\":\"appdata\",\"isolateApps\":false,\"closeToTray\":true,\"readyNotification\":true,\"showAdvanced\":false,\"openAtLogin\":false,\"openAsHidden\":false,\"manualGameUpdates\":false,\"preventDisplaySleep\":true,\"preferOptimizedPatches\":false,\"disableBrowser\":false,\"enableTabs\":false,\"lastSuccessfulSetupVersion\":\"25.5.1\",\"importedOldInstallLocations\":true,\"gotMinimizeNotification\":true}","name":"m/reac/preb/load-preferences"}
{"time":1650489585071,"level":20,"msg":"Registering itch: protocol for session with user agent Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) itch/25.5.1 Chrome/85.0.4183.121 Electron/10.4.5 Safari/537.36","name":"m/net/register-itch-protocol"}
{"time":1650489585307,"level":30,"msg":"preboot ran in 272.00ms","name":"m/reac/preboot"}
{"time":1650489585309,"level":20,"msg":"Updating login item settings, open=false, hidden=false","name":"m/reac/open-at-login"}
{"time":1650489585310,"level":20,"msg":"Waiting on root window to dispatch boot","name":"m/reac/winds"}
{"time":1650489586072,"level":30,"msg":"Language settings: preference undefined, sniffed en-US, fallback en","name":"m/reac/i18n"}
{"time":1650489586078,"level":20,"msg":"Reading local locale file C:\\Users\\elyscape\\AppData\\Local\\itch\\app-25.5.1\\resources\\app\\src/static/locales/en.json","name":"m/reac/locales"}
{"time":1650489586106,"level":20,"msg":"Reading remote locale file C:\\Users\\elyscape\\AppData\\Local\\itch\\app-25.5.1\\resources\\app\\src/static/locales/en.json","name":"m/reac/locales"}
{"time":1650489586326,"level":30,"msg":"Setup starting...","name":"🔧 setup"}
{"time":1650489586336,"level":30,"msg":"Creating broth manager","name":"🔧 setup"}
{"time":1650489586337,"level":30,"msg":"Ensuring broth dependencies, for startup","name":"🔧 setup"}
{"time":1650489586340,"level":20,"msg":"Package (itch) got version prefix","name":"🔧 setup"}
{"time":1650489586343,"level":20,"msg":"Reading local locale file C:\\Users\\elyscape\\AppData\\Local\\itch\\app-25.5.1\\resources\\app\\src/static/locales/en.json","name":"m/reac/locales"}
{"time":1650489601246,"level":20,"msg":"Reading remote locale file C:\\Users\\elyscape\\AppData\\Local\\itch\\app-25.5.1\\resources\\app\\src/static/locales/en.json","name":"m/reac/locales"}
{"time":1650489601282,"level":20,"msg":"Downloading fresh locale file from https://locales.itch.ovh/itch/en.json","name":"m/reac/locales"}
{"time":1650489601295,"level":20,"msg":"HTTP get https://locales.itch.ovh/itch/en.json","name":"r"}
{"time":1650489601305,"level":40,"msg":"Sanity check failed: timed out waiting for butlerd to listen","name":"📦 butler"}
{"time":1650489601305,"level":30,"msg":"(15.21.0) is chosen but not valid, attempting install...","name":"📦 butler"}
{"time":1650489601306,"level":20,"msg":"Trying to satisfy semver constraint (^15.20.0)","name":"📦 butler"}
{"time":1650489601307,"level":20,"msg":"GET (https://broth.itch.ovh/butler/windows-386/versions)","name":"📦 butler"}
{"time":1650489601307,"level":20,"msg":"HTTP get https://broth.itch.ovh/butler/windows-386/versions","name":"r"}
{"time":1650489602201,"level":20,"msg":"Got HTTP 200, content-length: 0 B","name":"r"}
{"time":1650489602221,"level":20,"msg":"HTTP GET https://locales.itch.ovh/itch/en.json: 200","name":"m/reac/locales"}
{"time":1650489602223,"level":20,"msg":"Saving fresh en locale to C:\\Users\\elyscape\\AppData\\Roaming\\itch\\locales\\en.json","name":"m/reac/locales"}
{"time":1650489602397,"level":20,"msg":"Got HTTP 200, content-length: 0 B","name":"r"}
{"time":1650489602404,"level":30,"msg":"Latest is (15.21.0)","name":"📦 butler"}
{"time":1650489602636,"level":30,"msg":"Validated!","name":"📦 butler"}
{"time":1650489602637,"level":30,"msg":"Marking (15.21.0) as chosen version","name":"📦 butler"}
{"time":1650489602639,"level":30,"msg":"Switching to (15.21.0)","name":"📦 butler"}
{"time":1650489602644,"level":20,"msg":"Package (butler) got version prefix","name":"🔧 setup"}
{"time":1650489602645,"level":30,"msg":"Refreshing butlerd!","name":"🔧 setup"}
{"time":1650489602645,"level":30,"msg":"Spinning up butlerd instance 1...","name":"🔧 setup"}
{"time":1650489602650,"level":30,"msg":"...waiting for endpoint from butlerd instance 1...","name":"🔧 setup"}
{"time":1650489602653,"level":20,"msg":"Present versions: 15.21.0","name":"📦 butler"}
{"time":1650489602659,"level":20,"msg":"spawning C:\\Users\\elyscape\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.26.0\\itch-setup :: --version","name":"📦 itch-setup"}
{"time":1650489619204,"level":20,"msg":"Downloading fresh locale file from https://locales.itch.ovh/itch/en.json","name":"m/reac/locales"}
{"time":1650489619204,"level":20,"msg":"HTTP get https://locales.itch.ovh/itch/en.json","name":"r"}
{"time":1650489619206,"level":50,"msg":"while reacting to packageGotVersionPrefix: Error: timed out waiting for butlerd to listen\n    at Timeout._onTimeout (C:\\Users\\elyscape\\AppData\\Local\\itch\\app-25.5.1\\resources\\app\\dist\\main\\main.bundle.js:38512:24)\n    at listOnTimeout (internal/timers.js:549:17)\n    at processTimers (internal/timers.js:492:7)"}
{"time":1650489619218,"level":30,"msg":"Regularly scheduled check for game updates...","name":"m/reac/updater"}
{"time":1650489619224,"level":30,"msg":"Scheduling next game update check for Wed Apr 20 2022 14:44:02 GMT-0700 (Pacific Daylight Time)","name":"m/reac/updater"}
{"time":1650489619256,"level":30,"msg":"(1.26.0) is chosen and valid","name":"📦 itch-setup"}
{"time":1650489619256,"level":30,"msg":"Switching to (1.26.0)","name":"📦 itch-setup"}
{"time":1650489619257,"level":30,"msg":"Already set up 25.5.1 once, delaying components upgrade check","name":"🔧 setup"}
{"time":1650489619257,"level":20,"msg":"Waiting for butler promise...","name":"🔧 setup"}
{"time":1650489619257,"level":20,"msg":"Package (itch-setup) got version prefix","name":"🔧 setup"}
{"time":1650489619336,"level":20,"msg":"Got HTTP 200, content-length: 0 B","name":"r"}
{"time":1650489619389,"level":20,"msg":"HTTP GET https://locales.itch.ovh/itch/en.json: 200","name":"m/reac/locales"}
{"time":1650489619390,"level":20,"msg":"Saving fresh en locale to C:\\Users\\elyscape\\AppData\\Roaming\\itch\\locales\\en.json","name":"m/reac/locales"}
{"time":1650489624271,"level":50,"msg":"setup got error: Error: Timed out while connecting to butlerd\n    at Timeout._onTimeout (C:\\Users\\elyscape\\AppData\\Local\\itch\\app-25.5.1\\resources\\app\\dist\\main\\main.bundle.js:17728:28)\n    at listOnTimeout (internal/timers.js:549:17)\n    at processTimers (internal/timers.js:492:7)","name":"🔧 setup"}
itch-setup-log.txt
2022/04/20 14:20:19 =========================================
2022/04/20 14:20:19 itch-setup "v1.26.0, built on Apr 21 2021 @ 18:48:11, ref 48f97b3e7b0b065a2478811b8d0ebcae414845fd" starting up at "2022-04-20 14:20:19.2523398 -0700 PDT m=+0.012047901" with arguments:
2022/04/20 14:20:19 "C:\\Users\\elyscape\\AppData\\Roaming\\itch\\broth\\itch-setup\\versions\\1.26.0\\itch-setup"
2022/04/20 14:20:19 "--version"
2022/04/20 14:20:19 =========================================

elyscape avatar Apr 20 '22 21:04 elyscape

See also: #2563, possibly #2306 and #2180.

elyscape avatar Apr 20 '22 21:04 elyscape