Channels = 0

system · May 4, 2013, 9:55am

Today after many months of service, DataMine indicated 0 channels in its panel, and when going to graphing page, the device list was all empty. This happened once before, but after a few days the channels started to work again gradually.

Well, this time I figured I should do some investigation. The log says it is starting version .970, but in “My Apps” vera has .972* Auto update never worked for me on this plugin, so I figured that it was time to update manually (using Brientim’s great guide, that I always use for DataMine; [url=http://forum.micasaverde.com/index.php/topic,12877.msg95089.html#msg95089]http://forum.micasaverde.com/index.php/topic,12877.msg95089.html#msg95089[/url]

Well, after doing this, dataMine seams not to start at all, logs

#cat /var/log/cmh/LuaUPnP.log | grep -i DataMine
[..]
02      05/04/13 11:31:32.522   JobHandler_LuaUPnP::FetchMissingFiles need to download D_DataMine1.json <0x2add1000>
02      05/04/13 11:31:32.523   JobHandler_LuaUPnP::m_sMissingFile_set D_DataMine1.json <0x2add1000>
01      05/04/13 11:31:32.695   Device_LuaUPnP::LoadDeviceDoc can't load /etc/cmh-lu//D_DataMine1.xml <0x2add1000>
02      05/04/13 11:31:32.696   JobHandler_LuaUPnP::m_sMissingFile_set /etc/cmh-lu//D_DataMine1.xml <0x2add1000>
01      05/04/13 11:31:32.696   JobHandler_LuaUPnP::CreateDevice_LuaUPnP failed to load 96/D_DataMine1.xml so device 96 is offline <0x2add1000>
02      05/04/13 11:31:33.411   Device_Service::MatchupUpnpServices no upnp service for urn:cd-jackson-com:serviceId:DataMine1 <0x2add1000>
09      05/04/13 11:31:43.693   JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id  parent 0/0x1072520 upnp: 0 <0x2add1000>
03      05/04/13 11:31:43.723   JobHandler_LuaUPnP::Run ready 69 devices installation 30102043 missing /etc/cmh-lu//D_DataMine1.xml version *1.5.622*  allow_evt: 1 allow_ud: 1 upnp 1 netpnp: 1 reprovision: 0 tz:-3600 tnow:1367659903 dst: 1 gmt:7200 tz:CET / CEST changed: 0 pMem 0x148c000 start 14999552 diff: 6545408 <0x2add1000>
09      05/04/13 11:32:05.732   JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id  parent 0/0xf83480 upnp: 0 <0x2b6eb000>
50      05/04/13 11:32:08.934   luup_log:96: dataMine: Initialising dataMine System (0.976) <0x2c01f680>
06      05/04/13 11:32:08.935   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0x10d8288/NONE duplicate:1 <0x2c01f680>
06      05/04/13 11:32:08.935   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c01f680>
50      05/04/13 11:32:08.959   luup_log:96: dataMine: Mounted: /dev/sda2 on /mnt/datamine_logs type ext4 (rw,relatime,barrier=1,data=ordered) <0x2c01f680>
06      05/04/13 11:32:08.960   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountLocation was: /dev/sda2 now: /dev/sda2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c01f680>
06      05/04/13 11:32:08.960   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountType was: ext4 now: ext4 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c01f680>
50      05/04/13 11:32:08.978   luup_log:96: dataMine: Installing update of dataMine web application <0x2c01f680>
06      05/04/13 11:32:14.195   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0x10d8288/NONE duplicate:1 <0x2c01f680>
06      05/04/13 11:32:14.196   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c01f680>
50      05/04/13 11:32:14.197   luup_log:96: dataMine: Startup complete <0x2c01f680>
50      05/04/13 11:32:29.175   luup_log:96: dataMine: Delete backups complete - deleted 0 of 10 files <0x2d41f680>
06      05/04/13 11:32:29.194   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskTotal was: 3329520 now: 3329520 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d41f680>
06      05/04/13 11:32:29.194   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskUsed was: 76556 now: 76556 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d41f680>
06      05/04/13 11:32:29.195   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskFree was: 3083828 now: 3083828 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d41f680>
06      05/04/13 11:32:29.195   Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskUsedPcnt was: 2 now: 2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d41f680>

I tried to install again a couple of times, but same thing. When I look in “develop apps → luup files”, the missing files show up (D_DataMine1.json and D_DataMine1.xml).
In /etc/cmh-lu there’s no DataMine files, but in /etc/cmh-ludl I have:

# ls -al /etc/cmh-ludl/ | grep Mine -rw-r--r-- 1 root root 1210 May 4 11:31 D_DataMine1.json.lzo -rw-r--r-- 1 root root 378 May 4 11:31 D_DataMine1.xml.lzo -rw-r--r-- 1 root root 16 May 4 11:29 Data_Mine.png.lzo -rw-r--r-- 1 root root 192 May 4 11:31 I_DataMine1.xml.lzo -rw-r--r-- 1 root root 7 Mar 1 19:42 J_DataMine1.js.lzo -rw-r--r-- 1 root root 23885 May 4 11:31 L_DataMine1.lua.lzo -rw-r--r-- 1 root root 294 May 4 11:31 S_DataMine1.xml.lzo -rw-r--r-- 1 root root 11 May 4 11:29 dataMineWeb.tar.gz.lzo

I don’t want to mess around too much moving files around, so I figured I should ask here?

What is wrong with my Vera?

Edit:Not sure if DataMine has started even though log says it is offline. It seams so, because the panel has disk space information, which the old one did not. So maybe all is well, apart from the fact that I have no channels anymore. The USB seams ok, lots of channelfiles ([R].txt)

ChrisJ · May 4, 2013, 2:06pm

There were a few versions that report differently in dataMine version logging, and Veras version logging. This is because there are actually 3 version numbers, and they are all different (it’s designed for me to keep track of versions, and can be confusing!). I keep version numbers in the Lua, and the GUI - these are normally the same unless I do a Lua only update to fix something small (as I did recently) in which case they may be different. Then there’s the apps version number. I try to keep them all the same, but if there’s a problem in apps, then MCV can change version numbers, or if there’s an error when I’m checking the code in to apps, I may have to increment the version in apps. In these cases I don’t go back and change the version numbers in the code as I’d potentially end up in ever decreasing circles…

Hopefully you’re still with me…

Looking at the log, it looks like Vera is missing some files. I’ve seen this before when there’s been an incomplete update of a plugin - I suspect that the plugin download may not handle errors properly. In the early days of dataMine, I had some discussions with MCV about this as it was not always downloading the GUI file (which is about 600k) - they had a very short timeout, and as I have a slow internet connection at home, it was always timing out. There was no error other than the sort of thing you’re seeing. They fixed this by increasing the timeout, but my point is that the update system isn’t very tolerant of errors (in my opinion!).

Anyway, moving along…

[quote=“vespaman, post:1, topic:175257”]I tried to install again a couple of times, but same thing. When I look in “develop apps → luup files”, the missing files show up (D_DataMine1.json and D_DataMine1.xml).
In /etc/cmh-lu there’s no DataMine files, but in /etc/cmh-ludl I have:

# ls -al /etc/cmh-ludl/ | grep Mine -rw-r--r-- 1 root root 1210 May 4 11:31 D_DataMine1.json.lzo -rw-r--r-- 1 root root 378 May 4 11:31 D_DataMine1.xml.lzo -rw-r--r-- 1 root root 16 May 4 11:29 Data_Mine.png.lzo -rw-r--r-- 1 root root 192 May 4 11:31 I_DataMine1.xml.lzo -rw-r--r-- 1 root root 7 Mar 1 19:42 J_DataMine1.js.lzo -rw-r--r-- 1 root root 23885 May 4 11:31 L_DataMine1.lua.lzo -rw-r--r-- 1 root root 294 May 4 11:31 S_DataMine1.xml.lzo -rw-r--r-- 1 root root 11 May 4 11:29 dataMineWeb.tar.gz.lzo[/quote]
The main files look about ok - I can’t confirm the file size of the implementation file as I’ve got a newer version on my machine, but it looks about right. I also don’t have any files in my cmh-lu directory, so this is consistent with you.

However, what I do find interesting is that you have files in this directory that I don’t think should be there. Namely, the png.lzo, and the dataMineWeb file. These files aren’t in my directory, and they should be somewhere else (and they probably should be lzo either!).

The text for the control panel is in the json file - it doesn’t necessarily mean that the plugin is running as I suspect Vera will pick up the new json file even without the implementation file running.

It would be worth taking a look in the /www/dm directory and see if the dataMineWeb.tar.gz file is in there - if the plugin isn’t running, it should be a ~600k file, and it shouldn’t have an lzo extension.
Maybe also delete the file dataMineWeb.tar.gz.lzo and Data_Mine.png.lzo and see if they come back when you re-install the plugin. If they do come back, it may indicate that something strange is happening in apps. These files are configured to have non-standard directory entries (along with another file called json-dm.lua - is this also in this directory?).

Let me know how you get on…

Cheers
Chris

system · May 4, 2013, 3:27pm

[quote=“Chris, post:2, topic:175257”]It would be worth taking a look in the /www/dm directory and see if the dataMineWeb.tar.gz file is in there - if the plugin isn’t running, it should be a ~600k file, and it shouldn’t have an lzo extension.
Maybe also delete the file dataMineWeb.tar.gz.lzo and Data_Mine.png.lzo and see if they come back when you re-install the plugin. If they do come back, it may indicate that something strange is happening in apps. These files are configured to have non-standard directory entries (along with another file called json-dm.lua - is this also in this directory?).[/quote]

Hi Chris,

This is how my /www/dm looks like;

# ls -al /www/dm/ drwxr-xr-x 5 root root 0 May 4 16:56 . drwxr-xr-x 1 root root 0 Mar 10 11:21 .. -rw-r--r-- 1 root root 5754 Mar 16 22:27 dataMine.css -rw-r--r-- 1 root root 0 May 4 16:56 dataMineWeb.tar.gz drwxr-xr-x 3 root root 0 May 4 16:56 extjs drwxr-xr-x 2 root root 0 May 4 16:56 images -rw-r--r-- 1 root root 1765 Mar 21 23:28 index.html drwxr-xr-x 2 root root 0 May 4 16:56 js
dataMineWeb.tar.gz 0 bytes?

Also, I had the json-dm.lua.lzo in /etc/cmh-ludl which I removed along with the other two stray files. When I select install from aps.mios.com, all other dataMine file are removed, and after a few seconds repopulated in the same size as previously. The three wrong files never came back.

Looking in the log, after pressing the Reload button in Vera (up in the right corner), log fills with;

02 05/04/13 17:06:48.553 JobHandler_LuaUPnP::FetchMissingFiles need to download D_DataMine1.json <0x2abf7000> 02 05/04/13 17:06:48.554 JobHandler_LuaUPnP::m_sMissingFile_set D_DataMine1.json <0x2abf7000> 01 05/04/13 17:06:48.718 Device_LuaUPnP::LoadDeviceDoc can't load /etc/cmh-lu//D_DataMine1.xml <0x2abf7000> 02 05/04/13 17:06:48.718 JobHandler_LuaUPnP::m_sMissingFile_set /etc/cmh-lu//D_DataMine1.xml <0x2abf7000> 01 05/04/13 17:06:48.719 JobHandler_LuaUPnP::CreateDevice_LuaUPnP failed to load 96/D_DataMine1.xml so device 96 is offline <0x2abf7000> 02 05/04/13 17:06:49.433 Device_Service::MatchupUpnpServices no upnp service for urn:cd-jackson-com:serviceId:DataMine1 <0x2abf7000> 09 05/04/13 17:06:57.693 JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id parent 0/0xb92558 upnp: 0 <0x2abf7000> 03 05/04/13 17:06:57.722 JobHandler_LuaUPnP::Run ready 69 devices installation 30102043 missing /etc/cmh-lu//D_DataMine1.xml version *1.5.622* allow_evt: 1 allow_ud: 1 upnp 1 netpnp: 1 reprovision: 0 tz:-3600 tnow:1367680017 dst: 1 gmt:7200 tz:CET / CEST changed: 0 pMem 0xfad000 start 9887744 diff: 6549504 <0x2abf7000> 09 05/04/13 17:07:17.211 JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id parent 0/0xbe24c0 upnp: 0 <0x2b38e000> 50 05/04/13 17:07:19.456 luup_log:96: dataMine: Initialising dataMine System (0.976) __LEAK__ this:106496 start:1478656 to 0x11c7000 <0x2bcc1680> 06 05/04/13 17:07:19.457 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0xd36b30/NONE duplicate:1 <0x2bcc1680> 06 05/04/13 17:07:19.457 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2bcc1680> 50 05/04/13 17:07:19.508 luup_log:96: dataMine: Mounted: /dev/sda2 on /mnt/datamine_logs type ext4 (rw,relatime,barrier=1,data=ordered) __LEAK__ this:98304 start:1576960 to 0x11df000 <0x2bcc1680> 06 05/04/13 17:07:19.509 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountLocation was: /dev/sda2 now: /dev/sda2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2bcc1680> 06 05/04/13 17:07:19.509 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountType was: ext4 now: ext4 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2bcc1680> 50 05/04/13 17:07:19.550 luup_log:96: dataMine: Installing update of dataMine web application <0x2bcc1680> 06 05/04/13 17:07:24.884 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0xd36b30/NONE duplicate:1 <0x2bcc1680> 06 05/04/13 17:07:24.884 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2bcc1680> 50 05/04/13 17:07:24.885 luup_log:96: dataMine: Startup complete <0x2bcc1680> 50 05/04/13 17:07:39.176 luup_log:96: dataMine: Delete backups complete - deleted 0 of 10 files <0x2d0c1680> 06 05/04/13 17:07:39.194 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskTotal was: 3329520 now: 3329520 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d0c1680> 06 05/04/13 17:07:39.195 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskUsed was: 76556 now: 76556 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d0c1680> 06 05/04/13 17:07:39.195 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskFree was: 3083828 now: 3083828 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d0c1680> 06 05/04/13 17:07:39.196 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskUsedPcnt was: 2 now: 2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d0c1680> 09 05/04/13 17:07:54.878 JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id parent 0/0xeff4a8 upnp: 0 <0x2b769000> 50 05/04/13 17:07:57.543 luup_log:96: dataMine: Initialising dataMine System (0.976) <0x2c09d680> 06 05/04/13 17:07:57.554 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0x1053b30/NONE duplicate:1 <0x2c09d680> 06 05/04/13 17:07:57.554 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c09d680> 50 05/04/13 17:07:57.580 luup_log:96: dataMine: Mounted: /dev/sda2 on /mnt/datamine_logs type ext4 (rw,relatime,barrier=1,data=ordered) <0x2c09d680> 06 05/04/13 17:07:57.581 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountLocation was: /dev/sda2 now: /dev/sda2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c09d680> 06 05/04/13 17:07:57.581 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountType was: ext4 now: ext4 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c09d680> 06 05/04/13 17:07:57.618 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0x1053b30/NONE duplicate:1 <0x2c09d680> 06 05/04/13 17:07:57.618 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c09d680> 50 05/04/13 17:07:57.619 luup_log:96: dataMine: Startup complete <0x2c09d680> 50 05/04/13 17:08:12.483 luup_log:96: dataMine: Delete backups complete - deleted 0 of 10 files <0x2d49d680> 06 05/04/13 17:08:12.520 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskTotal was: 3329520 now: 3329520 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d49d680> 06 05/04/13 17:08:12.520 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskUsed was: 76556 now: 76556 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d49d680> 06 05/04/13 17:08:12.521 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskFree was: 3083828 now: 3083828 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d49d680> 06 05/04/13 17:08:12.521 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: diskUsedPcnt was: 2 now: 2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d49d680> 09 05/04/13 17:11:01.097 JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id parent 0/0xace4c0 upnp: 0 <0x2b9f9000> 50 05/04/13 17:11:03.340 luup_log:96: dataMine: Initialising dataMine System (0.976) <0x2c32d680> 06 05/04/13 17:11:03.340 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0xc22b30/NONE duplicate:1 <0x2c32d680> 06 05/04/13 17:11:03.341 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680> 50 05/04/13 17:11:03.365 luup_log:96: dataMine: Mounted: /dev/sda2 on /mnt/datamine_logs type ext4 (rw,relatime,barrier=1,data=ordered) <0x2c32d680> 06 05/04/13 17:11:03.366 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountLocation was: /dev/sda2 now: /dev/sda2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680> 06 05/04/13 17:11:03.366 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: mountType was: ext4 now: ext4 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680> 06 05/04/13 17:11:03.401 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelCnt was: 0 now: 0 #hooks: 0 upnp: 0 v:0xc22b30/NONE duplicate:1 <0x2c32d680> 06 05/04/13 17:11:03.401 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: ChannelRec was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680> 50 05/04/13 17:11:03.402 luup_log:96: dataMine: Startup complete <0x2c32d680>

I still get the errors after an install, but maybe that is a timing thing that happens. @17:11:01 I press the Reload button in the Vera UI, and from there on, it looks as if dataMine is OK(?).

So maybe I’m back to where I was this morning, but with a newer dataMine (which always is nice ) But still the channels are still 0.

The debug log page meanwhile looks like this;[code]
09 05/04/13 17:11:01.097 JobHandler_LuaUPnP::Run device 96 dataMine graphing and logging plugin room 0 type urn:schemas-cd-jackson-com:device:DataMine:1 id parent 0/0xace4c0 upnp: 0 <0x2b9f9000>
50 05/04/13 17:11:03.340 luup_log:96: dataMine: Initialising dataMine System (0.976) <0x2c32d680>
06 05/04/13 17:11:03.340 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mChannelCnte[0m was: 0 now: 0 #hooks: 0 upnp: 0 v:0xc22b30/NONE duplicate:1 <0x2c32d680>
06 05/04/13 17:11:03.341 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mChannelRece[0m was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680>
50 05/04/13 17:11:03.365 luup_log:96: dataMine: Mounted: /dev/sda2 on /mnt/datamine_logs type ext4 (rw,relatime,barrier=1,data=ordered) <0x2c32d680>
06 05/04/13 17:11:03.366 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mmountLocatione[0m was: /dev/sda2 now: /dev/sda2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680>
06 05/04/13 17:11:03.366 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mmountTypee[0m was: ext4 now: ext4 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680>
06 05/04/13 17:11:03.401 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mChannelCnte[0m was: 0 now: 0 #hooks: 0 upnp: 0 v:0xc22b30/NONE duplicate:1 <0x2c32d680>
06 05/04/13 17:11:03.401 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mChannelRece[0m was: 0 now: 0 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c32d680>
50 05/04/13 17:11:03.402 luup_log:96: dataMine: Startup complete <0x2c32d680>
50 05/04/13 17:11:18.175 luup_log:96: dataMine: Delete backups complete - deleted 0 of 10 files <0x2d72d680>
06 05/04/13 17:11:18.194 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mdiskTotale[0m was: 3329520 now: 3329520 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d72d680>
06 05/04/13 17:11:18.194 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mdiskUsede[0m was: 76556 now: 76556 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d72d680>
06 05/04/13 17:11:18.194 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mdiskFreee[0m was: 3083828 now: 3083828 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d72d680>
06 05/04/13 17:11:18.195 Device_Variable::m_szValue_set device: 96 service: urn:cd-jackson-com:serviceId:DataMine1 variable: e[35;1mdiskUsedPcnte[0m was: 2 now: 2 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2d72d680>
-2-===========================================================================
/dev/sda2: UUID=“88cd7ece-1b0c-48d1-9d0d-6bfed3b97f7c”
/dev/sda1: LABEL=“MiOS” UUID=“77d0bc9f-034f-4521-afb1-67e020843d17”
-3-===========================================================================
rootfs on / type rootfs (rw)
/dev/root on /rom type squashfs (ro,relatime)
proc on /proc type proc (rw,noatime)
sysfs on /sys type sysfs (rw,noatime)
tmpfs on /tmp type tmpfs (rw,nosuid,nodev,noatime)
tmpfs on /dev type tmpfs (rw,noatime,size=512k,mode=755)
devpts on /dev/pts type devpts (rw,noatime,mode=600)
/dev/mtdblock7 on /overlay type jffs2 (rw,noatime)
overlayfs:/overlay on / type overlayfs (rw,noatime,lowerdir=/,upperdir=/overlay)
debugfs on /sys/kernel/debug type debugfs (rw,relatime)
/dev/mtdblock8 on /mios type squashfs (ro,relatime)
none on /proc/bus/usb type usbfs (rw,relatime)
/dev/sda2 on /mnt/datamine_logs type ext4 (rw,relatime,barrier=1,data=ordered)
-4-===========================================================================

Disk /dev/mtdblock0: 0 MB, 524288 bytes
255 heads, 63 sectors/track, 0 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes

Disk /dev/mtdblock0 doesn’t contain a valid partition table

Disk /dev/sda: 4004 MB, 4004511744 bytes
124 heads, 62 sectors/track, 1017 cylinders
Units = cylinders of 7688 * 512 = 3936256 bytes

Device Boot Start End Blocks Id System
/dev/sda1 * 1 137 526627+ 83 Linux
/dev/sda2 138 1017 3382720 83 Linux
-5-===========================================================================
SetDataDirectory-/mnt/datamine_logs/
SetMountUUID -
SetMountPoint -/dev/sda2
SetManualMount -0
-6-===========================================================================
{“Version”:“0.976”,“Events”:{“last”:1367665824,“count”:830,“next”:1367627357},“guiConfig”:[],“Variables”:[],“Graphs”:[],“LastWrite”:1367665846,“nextId”:1}
-7-===========================================================================[/code]

Thanks,
Micael

ChrisJ · May 4, 2013, 3:56pm

This is correct. When apps.mios.com installs the file, it puts it into this directory, and its ~600k. I then unzip it, but I can’t delete it or Vera thinks the file is missing, and reinstalls the app! So, to mark that this GUI has been installed, I zero the filesize after installation.

So, this tells us one of two things - either the install didn’t install this file, or, dataMine is running (or ran at least once) after the upgrade. Looking at your log below, it is running…

So it looks to me like something bad/strange happened with the previous install…

Yes, your log looks normal, although as you say, no channels being logged.

Everything looks ok, but your config has been reset.

Before doing too much, take a backup (or maybe you have a recent backup since I think you were doing nightly backups?) - this is just for safety…

According to the logs, you have a backup of the config file (there are 10). You should try deleting the “dataMineConfig.json” file, and renaming the most recent dataMineConfig.json..backup file to dataMineConfig.json. Hopefully this will be ok, and you’ll be back up and running. If not, you could try a slightly older backup. dataMine generates quite a few backups of the config file - not just when you change configuration, but also when it does something internally…

Good luck

Cheers
Chris

system · May 4, 2013, 5:05pm

Thanks, now all is well again! Lost 3 days, it seams dataMine stopped at around 12 o’clock first of May for some reason.

New version looks nice, btw!

Thanks again,
Micael

ChrisJ · May 4, 2013, 5:23pm

I’m glad you’re back up again… I keep meaning to add notifications into dataMine so that if there’s a problem with the USB, it will let you know… Must take a look at that…

Cheers
Chris