root@scientist-Latitude-D630:/home/scientist/dSS_Installation# ./dSS_start.sh [2011-06-14 18:46:31][Info][System] Loading config file /usr/local/share/dss/data/config.xml [2011-06-14 18:46:31][Info][System] Loading config directory /usr/local/share/dss/data/config.d [2011-06-14 18:46:31][Info][System] No logfile configured, logging to stdout [2011-06-14 18:46:31][Info][System] DSS starting up.... [2011-06-14 18:46:31][Info][System] DSS v1.0.2 (git:0e1c0179681a18b6c0d0aa5419dd3c064b119b2c) (root@scientist-Latitude-D630) [2011-06-14 18:46:31][Info][System] Configuration: [2011-06-14 18:46:31][Info][System] data: '/usr/local/share/dss/data/' [2011-06-14 18:46:31][Info][System] config: '/usr/local/share/dss/data/' [2011-06-14 18:46:31][Info][System] webroot: '/usr/local/share/dss/data/webroot/' [2011-06-14 18:46:31][Info][System] log dir: '/usr/local/share/dss/data/logs/' [2011-06-14 18:46:31][Info][System] props dir: '/usr/local/share/dss/data/savedprops/' [2011-06-14 18:46:31][Info][Apartment] Found /usr/local/share/dss/data/apartment.xml [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: Found device with id: example.js-device [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: adding script-file: /usr/local/share/dss/data/simulated_device.js [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: Found device with id: example.slim_player [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: adding script-file: /etc/dss/dss-mainline/jslib/dsid.js [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: adding script-file: /etc/dss/dss-mainline/examples/plugins/js/slimplayer.js [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: Found device with id: example.vlc_player [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: adding script-file: /etc/dss/dss-mainline/jslib/dsid.js [2011-06-14 18:46:31][Info][DSSim] DSSim::createJSPluginFrom: adding script-file: /etc/dss/dss-mainline/examples/plugins/js/vlc.js [2011-06-14 18:46:31][Debug][DSSim] LoadZones: found zone (4) [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSSim] LoadZones: found zone (1) [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSSim] LoadGroups: Adding device 4 to group 1 in zone 1 [2011-06-14 18:46:31][Debug][DSSim] LoadGroups: Adding device 13 to group 1 in zone 1 [2011-06-14 18:46:31][Debug][DSSim] LoadZones: found zone (4) [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSSim] LoadGroups: Adding device 5 to group 1 in zone 4 [2011-06-14 18:46:31][Debug][DSSim] LoadZones: found zone (172) [2011-06-14 18:46:31][Debug][DSSim] LoadDevices: found device [2011-06-14 18:46:31][Debug][DSBusInterface] initializing DSBusInterface [2011-06-14 18:46:41][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:46:41][Info][WebServer] Webserver: Listening on 0.0.0.0:8080s [2011-06-14 18:46:41][Info][WebServer] Webserver: Configured webroot: /usr/local/share/dss/data/webroot/ [2011-06-14 18:46:41][Info][WebServer] Webserver: Configured SSL certificate: /usr/local/share/dss/data/dsscert.pem [2011-06-14 18:46:41][Info][WebServer] Webserver started [2011-06-14 18:46:41][Debug][WebServices] initializing WebServices [2011-06-14 18:46:41][Debug][System] EventInterpreter: Loading subscriptions from '/usr/local/share/dss/data/subscriptions.xml' [2011-06-14 18:46:41][Error][System] PropertySystem::loadFromXML: Could not open file /usr/local/share/dss/data/security.xml [2011-06-14 18:46:41][Info][System] Logged in as system user (Main thread needs system privileges) [2011-06-14 18:46:41][Debug][System] creating thread for "Apartment" [2011-06-14 18:46:41][Debug][System] creating thread for "DSBusInterface" [2011-06-14 18:46:41][Debug][System] creating thread for "WebServices" [2011-06-14 18:46:41][Debug][System] creating thread for "EventInterpreter" [2011-06-14 18:46:41][Debug][System] creating thread for "BonjourHandler" [2011-06-14 18:46:41][Info][System] EventQueue: New event 'running' in queue... [2011-06-14 18:46:41][Info][System] Logged in as system user (ModelMaintenance needs system-rights) [2011-06-14 18:46:41][Info][Apartment] ModelMaintenance::execute: Enumerating model [2011-06-14 18:46:41][Debug][Apartment] Discovered device with DSID: 3504175fe0000000ffc00013 [2011-06-14 18:46:41][Debug][Apartment] dSM present [2011-06-14 18:46:41][Debug][Apartment] Discovered device with DSID: 3504175fe0000000ffc00010 [2011-06-14 18:46:41][Debug][Apartment] dSM present [2011-06-14 18:46:41][Info][System] Logged in as system user (EventInterpreter needs to run as system user (for now)) [2011-06-14 18:46:41][Info][System] EventInterpreter: Got event from queue: 'running' [2011-06-14 18:46:41][Info][System] EventInterpreter: Done processing event 'running' [2011-06-14 18:46:41][Info][System] Logged in as system user (Bonjour needs system rights) [2011-06-14 18:46:41][Info][System] Adding service 'dSS' [2011-06-14 18:46:41][Debug][System] creating thread for "WebServicesWorker" [2011-06-14 18:46:41][Debug][System] creating thread for "WebServicesWorker" [2011-06-14 18:46:41][Debug][System] creating thread for "WebServicesWorker" [2011-06-14 18:46:41][Debug][System] creating thread for "WebServicesWorker" [2011-06-14 18:46:42][Info][System] Service 'dSS' successfully established. [2011-06-14 18:46:42][Info][Apartment] DSMeter with DSID: 3504175fe0000000ffc00013 is ready [2011-06-14 18:46:42][Info][System] scanDSMeter: Start 3504175fe0000000ffc00013 [2011-06-14 18:46:42][Debug][System] scanDSMeter: Found zone with id: 4 [2011-06-14 18:46:42][Debug][System] scanDeviceOnBus: Found device with address: 11 [2011-06-14 18:46:42][Debug][System] scanDeviceOnBus: DSID: 3504175fe0000000ffc00011 [2011-06-14 18:46:42][Debug][System] scanDeviceOnBus: Function-ID: 0, Product-ID: 0, Revision-ID: 0 [2011-06-14 18:46:42][Info][System] EventQueue: New event 'new_device' in queue... [2011-06-14 18:46:42][Info][System] EventQueue: New event 'dsMeter_ready' in queue... [2011-06-14 18:46:42][Info][System] EventInterpreter: Got event from queue: 'new_device' [2011-06-14 18:46:42][Debug][System] EventInterpreter: Parameter 'device' = '3504175fe0000000ffc00011' [2011-06-14 18:46:42][Debug][System] EventInterpreter: Parameter 'zone' = '4' [2011-06-14 18:46:42][Info][System] EventInterpreter: Done processing event 'new_device' [2011-06-14 18:46:42][Info][System] EventInterpreter: Got event from queue: 'dsMeter_ready' [2011-06-14 18:46:42][Debug][System] EventInterpreter: Parameter 'dsMeter' = '3504175fe0000000ffc00013' [2011-06-14 18:46:42][Info][System] EventInterpreter: Done processing event 'dsMeter_ready' [2011-06-14 18:46:43][Info][Apartment] DSMeter with DSID: 3504175fe0000000ffc00010 is ready [2011-06-14 18:46:43][Info][System] scanDSMeter: Start 3504175fe0000000ffc00010 [2011-06-14 18:46:43][Debug][System] scanDSMeter: Found zone with id: 1 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Found device with address: 4 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: DSID: 3504175fe0000000ffc00004 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Function-ID: 0, Product-ID: 0, Revision-ID: 0 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: adding device 4 to group 1 [2011-06-14 18:46:43][Info][System] EventQueue: New event 'new_device' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'new_device' [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'device' = '3504175fe0000000ffc00004' [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'zone' = '1' [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'new_device' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Found device with address: 13 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: DSID: 3504175fe0000000ffc00013 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Function-ID: 0, Product-ID: 0, Revision-ID: 0 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: adding device 13 to group 1 [2011-06-14 18:46:43][Info][System] EventQueue: New event 'new_device' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'new_device' [2011-06-14 18:46:43][Debug][System] scanDSMeter: Found group with id: 1 [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'device' = '3504175fe0000000ffc00013' [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'zone' = '1' [2011-06-14 18:46:43][Debug][Apartment] OnGroupCallScene: group-id '1' in Zone '1' scene: 0 [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'new_device' [2011-06-14 18:46:43][Info][System] EventQueue: New event 'callScene' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'callScene' [2011-06-14 18:46:43][Debug][System] scanDSMeter: Found zone with id: 4 [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'sceneID' = '0' [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'callScene' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Found device with address: 3 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: DSID: 3504175fe0000000ffc00003 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Function-ID: 0, Product-ID: 0, Revision-ID: 0 [2011-06-14 18:46:43][Info][System] EventQueue: New event 'new_device' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'new_device' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Found device with address: 5 [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'device' = '3504175fe0000000ffc00003' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: DSID: 3504175fe0000000ffc00005 [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'zone' = '4' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Function-ID: 0, Product-ID: 0, Revision-ID: 0 [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'new_device' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: adding device 5 to group 1 [2011-06-14 18:46:43][Info][System] EventQueue: New event 'new_device' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'new_device' [2011-06-14 18:46:43][Debug][System] scanDSMeter: Found group with id: 1 [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'device' = '3504175fe0000000ffc00005' [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'zone' = '4' [2011-06-14 18:46:43][Debug][Apartment] OnGroupCallScene: group-id '1' in Zone '4' scene: 0 [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'new_device' [2011-06-14 18:46:43][Info][System] EventQueue: New event 'callScene' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'callScene' [2011-06-14 18:46:43][Debug][System] scanDSMeter: Found zone with id: 172 [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'sceneID' = '0' [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'callScene' [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Found device with address: 7 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: DSID: 3504175fe0000000ffc00007 [2011-06-14 18:46:43][Debug][System] scanDeviceOnBus: Function-ID: 0, Product-ID: 0, Revision-ID: 0 [2011-06-14 18:46:43][Info][System] EventQueue: New event 'new_device' in queue... [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'new_device' [2011-06-14 18:46:43][Info][System] EventQueue: New event 'dsMeter_ready' in queue... [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'device' = '3504175fe0000000ffc00007' [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'zone' = '172' [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'new_device' [2011-06-14 18:46:43][Info][System] EventInterpreter: Got event from queue: 'dsMeter_ready' [2011-06-14 18:46:43][Debug][System] EventInterpreter: Parameter 'dsMeter' = '3504175fe0000000ffc00010' [2011-06-14 18:46:43][Info][System] EventInterpreter: Done processing event 'dsMeter_ready' [2011-06-14 18:46:44][Info][Apartment] ******** Finished loading model from dSM(s)... [2011-06-14 18:46:44][Info][System] EventQueue: New event 'model_ready' in queue... [2011-06-14 18:46:44][Info][System] EventInterpreter: Got event from queue: 'model_ready' [2011-06-14 18:46:44][Info][System] EventInterpreter: Done processing event 'model_ready' [2011-06-14 18:46:51][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:47:01][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:47:11][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:47:21][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:47:31][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:47:41][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:47:51][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:48:01][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:48:11][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:48:21][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3 [2011-06-14 18:48:23][Debug][WebServer] Processing call to system/login [2011-06-14 18:48:23][Info][System] EventQueue: New event 'webSessionCleanup' in queue... [2011-06-14 18:48:23][Info][System] EventQueue::scheduleFromEvent: Event has a valid time, rescheduling at 2011-06-14 17:03:23 [2011-06-14 18:48:23][Warning][System] SessionManager: No salt specified, sessions ids might not be secure [2011-06-14 18:48:23][Debug][System] RequestHandler: Registered new JSON session [2011-06-14 18:48:24][Debug][WebServer] Processing call to apartment/getCircuits [2011-06-14 18:48:24][Debug][WebServer] Processing call to property/getChildren [2011-06-14 18:48:24][Debug][System] JSON call failed: 'Could not find node named '/system/js/settings'' [2011-06-14 18:48:24][Debug][WebServer] Processing call to metering/getLatest [2011-06-14 18:48:24][Debug][WebServer] Processing call to system/time [2011-06-14 18:48:24][Debug][WebServer] Processing call to system/version [2011-06-14 18:48:24][Debug][WebServer] Processing call to apartment/getStructure [2011-06-14 18:48:24][Debug][WebServer] Processing call to property/query [2011-06-14 18:48:24][Debug][WebServer] Processing call to property/query [2011-06-14 18:48:31][Debug][DSBusInterface] Couldn't open dsmapi connection to 'tcp://localhost:8442' result: -3