[solved]Pimatic not starting after recent update

sebster6

Hi there,

just like I always do from time to time, I checked for updates yesterday, and since there was one for pimatic-shell-execute, I ran the update and clicked the Restart button after it finished. Unfortunately, Pimatic won’t start anymore now (there’s probably no connection to the plugin, but I can’t be sure).
When I run “sudo service pimatic start” or “sudo node_modules/pimatic/pimatic.js start”, only the current date/time is written to /home/pi/pimatic-app/pimatic-daemon.log, before the process seems to die almost instantaneously. Since there are no further log infos, I’m not sure where to start analyzing.
What I did so far: Restarting, sudo apt-get update/upgrade, power cycling the Pi, remove pimatic-shell-execute from config, remove Pimatic database file - no changes. Help please

pi@raspberrypi:~/pimatic-app $ sudo tail -f -n 200 /home/pi/pimatic-app/pimatic-daemon.log
[...]
22:24:53.280 [pimatic] info: Installing: "pimatic-shell-execute@0.9.10" from npm-registry.
22:25:42.405 [pimatic, ppm] info: WARN unmet dependency /home/pi/pimatic-app/node_modules/pimatic-echo/node_modules/hapi/node_modules/subtext/node_modules/pez requires boom@'3.x.x' but will load
22:25:42.422 [pimatic, ppm] info: WARN unmet dependency /home/pi/pimatic-app/node_modules/pimatic-echo/node_modules/hapi/node_modules/boom,
22:25:42.425 [pimatic, ppm] info: WARN unmet dependency which is version 4.0.0
22:25:43.023 [pimatic, ppm] info: pimatic-shell-execute@0.9.10 node_modules/pimatic-shell-execute
22:25:43.028 [pimatic, ppm] info: └── pimatic-plugin-commons@0.9.4
22:36:02.983 [pimatic] info: Restarting...
22:36:04.577 [pimatic] info: Flushing database to disk, please wait...
22:36:06.218 [pimatic] info: Flushing database to disk, please wait... Done.
22:36:06.223 [pimatic] info: exiting...
22:42:10.105 2017-04-20 Thursday
22:48:32.904 2017-04-20 Thursday
22:55:06.350 2017-04-20 Thursday
23:00:05.925 2017-04-20 Thursday
06:32:12.868 2017-04-21 Friday
pi@raspberrypi:~/pimatic-app sudo node_modules/pimatic/pimatic.js status
Pidfile exists, but process is dead.

sebster6

Okay, I get some error message when not running Pimatic as a daemon. Any idea what this means?

I checked ~/pimatic-app/node_modules/pimatic/node_modules/knex/node_modules/debug/package.json and it’s just a file of unreadable garbage characters… can I “reinstall” this part of the installation?

pi@raspberrypi:~/pimatic-app $ sudo node /home/pi/pimatic-app/node_modules/pimatic/pimatic.js
07:29:34.005 2017-04-21 Friday
module.js:85
    throw e;
    ^

SyntaxError: Error parsing /home/pi/pimatic-app/node_modules/pimatic/node_modules/knex/node_modules/debug/package.json: Unexpected token �
    at Object.parse (native)
    at readPackage (module.js:81:52)
    at tryPackage (module.js:91:13)
    at Function.Module._findPath (module.js:153:20)
    at Function.Module._resolveFilename (module.js:323:25)
    at Function.Module._load (module.js:276:25)
    at Module.require (module.js:353:17)
    at require (internal/module.js:12:17)
    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/node_modules/knex/lib/raw.js:39:14)
    at Module._compile (module.js:409:26)
    at Object.Module._extensions..js (module.js:416:10)
    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
    at Function.Module._load (module.js:300:12)
    at Module.require (module.js:353:17)
    at require (internal/module.js:12:17)
    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/node_modules/knex/lib/index.js:15:12)
    at Module._compile (module.js:409:26)
    at Object.Module._extensions..js (module.js:416:10)
    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
    at Function.Module._load (module.js:300:12)
    at Module.require (module.js:353:17)
    at require (internal/module.js:12:17)
    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/node_modules/knex/knex.js:8:18)
    at Module._compile (module.js:409:26)
    at Object.Module._extensions..js (module.js:416:10)
    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
    at Function.Module._load (module.js:300:12)
    at Module.require (module.js:353:17)
    at require (internal/module.js:12:17)
    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/lib/database.coffee:11:8)
    at Module._compile (module.js:409:26)
    at Object.compile (/home/pi/pimatic-app/node_modules/pimatic/coffee-cache.js:169:17)
    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
    at Function.Module._load (module.js:300:12)
    at Module.require (module.js:353:17)
    at require (internal/module.js:12:17)
    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/startup.coffee:22:16)
    at Module._compile (module.js:409:26)
    at Object.compile (/home/pi/pimatic-app/node_modules/pimatic/coffee-cache.js:169:17)
    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
    at Function.Module._load (module.js:300:12)
    at Module.require (module.js:353:17)
    at require (internal/module.js:12:17)
    at run (/home/pi/pimatic-app/node_modules/pimatic/pimatic.js:15:3)
    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/pimatic.js:20:3)
    at Module._compile (module.js:409:26)
    at Object.Module._extensions..js (module.js:416:10)
    at Module.load (module.js:343:32)
    at Function.Module._load (module.js:300:12)
    at Function.Module.runMain (module.js:441:10)
    at startup (node.js:139:18)
    at node.js:974:3
pi@raspberrypi:~/pimatic-app $

rikki78

This is the content of the one from my installation

{
  "name": "debug",
  "version": "2.6.0",
  "repository": {
    "type": "git",
    "url": "git://github.com/visionmedia/debug.git"
  },
  "description": "small debugging utility",
  "keywords": [
    "debug",
    "log",
    "debugger"
  ],
  "author": {
    "name": "TJ Holowaychuk",
    "email": "tj@vision-media.ca"
  },
  "contributors": [
    {
      "name": "Nathan Rajlich",
      "email": "nathan@tootallnate.net",
      "url": "http://n8.io"
    },
    {
      "name": "Andrew Rhyne",
      "email": "rhyneandrew@gmail.com"
    }
  ],
  "license": "MIT",
  "dependencies": {
    "ms": "0.7.2"
  },
  "devDependencies": {
    "browserify": "9.0.3",
    "chai": "^3.5.0",
    "concurrently": "^3.1.0",
    "coveralls": "^2.11.15",
    "eslint": "^3.12.1",
    "istanbul": "^0.4.5",
    "karma": "^1.3.0",
    "karma-chai": "^0.1.0",
    "karma-mocha": "^1.3.0",
    "karma-phantomjs-launcher": "^1.0.2",
    "karma-sinon": "^1.0.5",
    "mocha": "^3.2.0",
    "mocha-lcov-reporter": "^1.2.0",
    "rimraf": "^2.5.4",
    "sinon": "^1.17.6",
    "sinon-chai": "^2.8.0"
  },
  "main": "./src/index.js",
  "browser": "./src/browser.js",
  "component": {
    "scripts": {
      "debug/index.js": "browser.js",
      "debug/debug.js": "debug.js"
    }
  },
  "gitHead": "ac5ccae70358a2bccc71d288e5f9c656a7678748",
  "bugs": {
    "url": "https://github.com/visionmedia/debug/issues"
  },
  "homepage": "https://github.com/visionmedia/debug#readme",
  "_id": "debug@2.6.0",
  "scripts": {},
  "_shasum": "bc596bcabe7617f11d9fa15361eded5608b8499b",
  "_from": "debug@>=2.1.3 <3.0.0",
  "_npmVersion": "3.10.9",
  "_nodeVersion": "6.9.2",
  "_npmUser": {
    "name": "thebigredgeek",
    "email": "rhyneandrew@gmail.com"
  },
  "dist": {
    "shasum": "bc596bcabe7617f11d9fa15361eded5608b8499b",
    "tarball": "https://registry.npmjs.org/debug/-/debug-2.6.0.tgz"
  },
  "maintainers": [
    {
      "name": "thebigredgeek",
      "email": "rhyneandrew@gmail.com"
    }
  ],
  "_npmOperationalInternal": {
    "host": "packages-12-west.internal.npmjs.com",
    "tmp": "tmp/debug-2.6.0.tgz_1482990633625_0.042889281176030636"
  },
  "directories": {},
  "_resolved": "https://registry.npmjs.org/debug/-/debug-2.6.0.tgz",
  "readme": "ERROR: No README data found!"
}

mwittig

@sebster6 Sorry about that. Note, however, I don’t think this is not issue with the recent update, but most likely a stability issue with your Raspi. Please check your SD-Card (e.g. perform a file system check) and check the power supply of your Raspi (e.g. generate some high load on the Raspi to check if still runs stable).

Make a backup of your configuration and make sure there are no more node processes running on the system.
Then you may want to try the following

sudo bash
cd /home/pi/pimatic-app/node_modules/pimatic
rm -rf node_modules
npm install --unsafe-perm
# if this run without critical errors (warnings are fine) you're done and you can exit the root shell
exit

Now, start pimatic and see what happens.

sebster6

Thanks @rikki78, I rebuilt the file and startup continues a little step further. But I guess @mwittig is right and there’s a problem with the SD card:

pi@raspberrypi:~/pimatic-app $ sudo node /home/pi/pimatic-app/node_modules/pimatic/pimatic.js
12:31:18.374 2017-04-21 Friday
12:31:27.432 [pimatic] Starting pimatic version 0.9.39
12:31:30.017 [pimatic] Startup error: SyntaxError: Unexpected token ILLEGAL
12:31:30.017 [pimatic]>    at exports.runInThisContext (vm.js:53:16)
12:31:30.017 [pimatic]>    at Module._compile (module.js:373:25)
12:31:30.017 [pimatic]>    at Object.Module._extensions..js (module.js:416:10)
12:31:30.017 [pimatic]>    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
12:31:30.017 [pimatic]>    at Function.Module._load (module.js:300:12)
12:31:30.017 [pimatic]>    at Module.require (module.js:353:17)
12:31:30.017 [pimatic]>    at require (internal/module.js:12:17)
12:31:30.017 [pimatic]>    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/node_modules/body-parser/node_modules/http-errors/index.js:17:16)
12:31:30.017 [pimatic]>    at Module._compile (module.js:409:26)
12:31:30.017 [pimatic]>    at Object.Module._extensions..js (module.js:416:10)
12:31:30.017 [pimatic]>    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
12:31:30.017 [pimatic]>    at Function.Module._load (module.js:300:12)
12:31:30.017 [pimatic]>    at Module.require (module.js:353:17)
12:31:30.017 [pimatic]>    at require (internal/module.js:12:17)
12:31:30.017 [pimatic]>    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/node_modules/body-parser/lib/types/urlencoded.js:17:19)
12:31:30.017 [pimatic]>    at Module._compile (module.js:409:26)
12:31:30.017 [pimatic]>    at Object.Module._extensions..js (module.js:416:10)
12:31:30.017 [pimatic]>    at Module.load (/home/pi/pimatic-app/node_modules/pimatic/node_modules/coffee-script/lib/coffee-script/register.js:45:36)
12:31:30.017 [pimatic]>    at Function.Module._load (module.js:300:12)
12:31:30.017 [pimatic]>    at Module.require (module.js:353:17)
12:31:30.017 [pimatic]>    at require (internal/module.js:12:17)
12:31:30.017 [pimatic]>    at loadParser (/home/pi/pimatic-app/node_modules/pimatic/node_modules/body-parser/index.js:151:16)
12:31:30.017 [pimatic]>    at Function.get [as urlencoded] (/home/pi/pimatic-app/node_modules/pimatic/node_modules/body-parser/index.js:123:12)
12:31:30.017 [pimatic]>    at Framework._setupExpressApp (/home/pi/pimatic-app/node_modules/pimatic/lib/framework.coffee:268:26)
12:31:30.017 [pimatic]>    at new Framework (/home/pi/pimatic-app/node_modules/pimatic/lib/framework.coffee:87:8)
12:31:30.017 [pimatic]>    at /home/pi/pimatic-app/node_modules/pimatic/startup.coffee:94:17
12:31:30.017 [pimatic]>    at tryCatcher (/home/pi/pimatic-app/node_modules/pimatic/node_modules/bluebird/js/release/util.js:16:23)
12:31:30.017 [pimatic]>    at Function.Promise.attempt.Promise.try (/home/pi/pimatic-app/node_modules/pimatic/node_modules/bluebird/js/release/method.js:39:29)
12:31:30.017 [pimatic]>    at Object.startup (/home/pi/pimatic-app/node_modules/pimatic/startup.coffee:93:10)
12:31:30.017 [pimatic]>    at run (/home/pi/pimatic-app/node_modules/pimatic/pimatic.js:15:24)
12:31:30.017 [pimatic]>    at Object.<anonymous> (/home/pi/pimatic-app/node_modules/pimatic/pimatic.js:20:3)
12:31:30.017 [pimatic]>    at Module._compile (module.js:409:26)
12:31:30.017 [pimatic]>    at Object.Module._extensions..js (module.js:416:10)
12:31:30.017 [pimatic]>    at Module.load (module.js:343:32)
12:31:30.017 [pimatic]>    at Function.Module._load (module.js:300:12)
12:31:30.017 [pimatic]>    at Function.Module.runMain (module.js:441:10)
12:31:30.017 [pimatic]>    at startup (node.js:139:18)
12:31:30.017 [pimatic]>    at node.js:974:3
12:31:30.027 [pimatic] exiting...

Trying the solution mentioned above now…

sebster6

It’s working! :D
Thanks so much, Pimatic started successfully after following the instructions above! Nevertheless, ordered a new SD card to clone my installation to…

leader21

I recommend to install the Linux filesystem on a USB HDD or better SSD drive. A SD card is always a possible source of error and has only a limited life cycle. Even a new SD card will begin to make trouble some day. Running raspian on a SSD for more then 2 years now without issues!

Check here for a howto
https://www.raspberrypi.org/forums/viewtopic.php?f=29&t=44177