server change + upgrade from schooltool 2.1 to 2.6

Asked by Rob Willis on 2014-04-19

Hello...

We are removing from service the server that schooltool is currently running on, and I've prepared a new server and installed schooltool (version 2.6). I'm trying to import the data from the previous install by copying the Data.fs file and blobs directory, but no joy (which is to say, launching schooltool doesn't show the data from the database that I copied over). I don't see any specific discussion of upgrading versions that are on different machines, and am a bit at a loss... how would I go about getting the data from 2.1 to 2.6 but on a different machine?

Thanks for your help.

Rob

Question information

Language:
English Edit question
Status:
Solved
For:
SchoolTool Edit question
Assignee:
No assignee Edit question
Solved by:
Gediminas Paulauskas
Solved:
2014-04-21
Last query:
2014-04-21
Last reply:
2014-04-20

I'm not as much of a noob as that message sounds... if this is an export / import process that's fine. I just need a point in the right direction.

Gediminas Paulauskas (menesis) said : #2

Hello,

It sounds like you have read the documentation http://book.schooltool.org/database.html

but the data is not used?

One thing to check is the permissions of data directories, it must be writable to schooltool user or group.

$ sudo ls -ld /var/lib/schooltool/
drwxr-x--- 3 schooltool schooltool 4096 Bal 20 01:26 /var/lib/schooltool/

$ sudo ls -l /var/lib/schooltool/
drwx------ 4 schooltool schooltool 4096 Rgs 25 2013 blobs
-rw-r--r-- 1 schooltool schooltool 1287528 Bal 17 21:01 Data.fs
-rw-r--r-- 1 schooltool schooltool 1287528 Bal 17 21:01 Data.fs.0
-rw-r--r-- 1 schooltool schooltool 153514 Bal 17 20:43 Data.fs.1.gz
-rw-r--r-- 1 schooltool schooltool 13596 Lap 17 18:09 Data.fs.index
-rw-r--r-- 1 schooltool schooltool 6 Bal 19 13:56 Data.fs.lock
-rw-r--r-- 1 schooltool schooltool 0 Bal 19 13:56 Data.fs.tmp
-rw-r--r-- 1 schooltool schooltool 870 Bal 20 01:26 redis-dump.rdb

To make the files owned by schooltool user and group,

$ sudo chown schooltool:schooltool -R /var/lib/schooltool

To change permissions

$ man chmod

Ok...

Did as suggested, and now the schooltool process is exiting.
It launches runs for about 20 seconds, and exits

This server is brand new, I can wipe anything. Any ideas?

Rob
Rob Willis
Founder, Lakeside International School
Guanacaste | Costa Rica | T: +011 506 2667 0066
<email address hidden> <mailto:<email address hidden>>
www.lakesideschoolcr.com <http://www.lakesideschoolcr.com/>

  www.facebook.com/lakesideschoolcr
<http://www.facebook.com/lakesideschoolcr>

Please consider the environment before printing this e-mail

On 4/19/14 4:36 PM, "Gediminas Paulauskas"
<email address hidden> wrote:

>Your question #247263 on SchoolTool changed:
>https://answers.launchpad.net/schooltool/+question/247263
>
> Status: Open => Answered
>
>Gediminas Paulauskas proposed the following answer:
>Hello,
>
>It sounds like you have read the documentation
>http://book.schooltool.org/database.html
>
>but the data is not used?
>
>One thing to check is the permissions of data directories, it must be
>writable to schooltool user or group.
>
>$ sudo ls -ld /var/lib/schooltool/
>drwxr-x--- 3 schooltool schooltool 4096 Bal 20 01:26 /var/lib/schooltool/
>
>$ sudo ls -l /var/lib/schooltool/
>drwx------ 4 schooltool schooltool 4096 Rgs 25 2013 blobs
>-rw-r--r-- 1 schooltool schooltool 1287528 Bal 17 21:01 Data.fs
>-rw-r--r-- 1 schooltool schooltool 1287528 Bal 17 21:01 Data.fs.0
>-rw-r--r-- 1 schooltool schooltool 153514 Bal 17 20:43 Data.fs.1.gz
>-rw-r--r-- 1 schooltool schooltool 13596 Lap 17 18:09 Data.fs.index
>-rw-r--r-- 1 schooltool schooltool 6 Bal 19 13:56 Data.fs.lock
>-rw-r--r-- 1 schooltool schooltool 0 Bal 19 13:56 Data.fs.tmp
>-rw-r--r-- 1 schooltool schooltool 870 Bal 20 01:26 redis-dump.rdb
>
>To make the files owned by schooltool user and group,
>
>$ sudo chown schooltool:schooltool -R /var/lib/schooltool
>
>To change permissions
>
>$ man chmod
>
>--
>If this answers your question, please go to the following page to let us
>know that it is solved:
>https://answers.launchpad.net/schooltool/+question/247263/+confirm?answer_
>id=1
>
>If you still need help, you can reply to this email or go to the
>following page to enter your feedback:
>https://answers.launchpad.net/schooltool/+question/247263
>
>You received this question notification because you asked the question.

Ignas-pow (ignas-pow) said : #4

Gediminas will probably want the stuff you see in /var/log/schooltool/paste.log probably.

Gediminas Paulauskas (menesis) said : #5

Yes the log files at /var/log/schooltool would help understand what is happening.

See

http://book.schooltool.org/troubleshooting.html

and copy error.log or paste.log here

Thank you for the help... .I'm getting this status about 10 seconds after starting schooltool...

schooltool EXITED Apr 20 10:08 AM
services:celery_report RUNNING pid 5443, uptime 0:21:25
services:redis RUNNING pid 5441, uptime 0:21:25
services:zeo RUNNING pid 5442, uptime 0:21:25

Output of various requested commands follows...

root@LAKESERVE:/media/usb0/schooltool# sudo ls -ld /var/lib/schooltooldrwxr-x--- 3 schooltool schooltool 4096 Apr 20 10:02 /var/lib/schooltool

root@LAKESERVE:/media/usb0/schooltool# ls -l /var/lib/schooltooltotal 662924
drwxr-xr-x 4 schooltool schooltool 4096 Oct 5 2012 blobs
-rw-r--r-- 1 schooltool schooltool 674467176 Apr 20 09:53 Data.fs
-rw-r--r-- 1 schooltool schooltool 4159783 Apr 20 09:48 Data.fs.index
-rw-r--r-- 1 schooltool schooltool 6 Apr 20 09:47 Data.fs.lock
-rw-r--r-- 1 schooltool schooltool 184825 Apr 20 09:53 Data.fs.tmp
-rw-r--r-- 1 schooltool schooltool 152 Apr 20 10:02 redis-dump.rdb

error.log
2014-04-20 07:49:09,155 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=12565) created RW/normal for storage: '1'
2014-04-20 07:49:09,156 created temporary cache file '<fdopen>'
2014-04-20 07:49:09,262 ['/var/run/schooltool/zeo.sock'] Testing connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
2014-04-20 07:49:09,263 (/var/run/schooltool/zeo.sock) received handshake 'Z3101'
2014-04-20 07:49:09,363 ['/var/run/schooltool/zeo.sock'] Server authentication protocol None
2014-04-20 07:49:09,364 ['/var/run/schooltool/zeo.sock'] Connected to storage: /var/run/schooltool/zeo.sock
2014-04-20 07:49:09,365 ['/var/run/schooltool/zeo.sock'] No verification necessary -- empty cache
2014-04-20 07:49:09,611 main db: evolving in mode EVOLVEMINIMUM
2014-04-20 07:49:09,681 main db/schooltool: running install generation
2014-04-20 08:01:03,000 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=15256) created RW/normal for storage: '1'
2014-04-20 08:01:03,001 created temporary cache file '<fdopen>'
2014-04-20 08:01:03,056 ['/var/run/schooltool/zeo.sock'] Testing connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
2014-04-20 08:01:03,056 (/var/run/schooltool/zeo.sock) received handshake 'Z3101'
2014-04-20 08:01:03,157 ['/var/run/schooltool/zeo.sock'] Server authentication protocol None
2014-04-20 08:01:03,158 ['/var/run/schooltool/zeo.sock'] Connected to storage: /var/run/schooltool/zeo.sock
2014-04-20 08:01:03,158 ['/var/run/schooltool/zeo.sock'] No verification necessary -- empty cache
2014-04-20 08:01:03,171 main db: evolving in mode EVOLVEMINIMUM
2014-04-20 08:35:42,141 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=2347) created RW/normal for storage: '1'
2014-04-20 08:35:42,154 (2347) Blob dir /var/lib/schooltool/blobs/ has insecure mode setting
2014-04-20 08:35:42,154 created temporary cache file '<fdopen>'
2014-04-20 08:35:42,207 (2347) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:35:47,213 (2347) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:35:57,223 (2347) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:36:17,237 (2347) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:36:57,243 (2347) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:38:17,311 (2347) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:39:24,726 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=4635) created RW/normal for storage: '1'
2014-04-20 08:39:24,728 (4635) Blob dir /var/lib/schooltool/blobs/ has insecure mode setting
2014-04-20 08:39:24,728 created temporary cache file '<fdopen>'
2014-04-20 08:39:24,788 (4635) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:39:29,793 (4635) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:39:39,794 (4635) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:39:59,809 (4635) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:40:39,845 (4635) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:41:09,893 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=4726) created RW/normal for storage: '1'
2014-04-20 08:41:09,894 (4726) Blob dir /var/lib/schooltool/blobs/ has insecure mode setting
2014-04-20 08:41:09,894 created temporary cache file '<fdopen>'
2014-04-20 08:41:09,955 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:41:14,961 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:41:24,971 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:41:44,986 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:42:25,006 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:43:45,085 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:46:25,143 (4726) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:50:28,426 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=4931) created RW/normal for storage: '1'
2014-04-20 08:50:28,430 (4931) Blob dir /var/lib/schooltool/blobs/ has insecure mode setting
2014-04-20 08:50:28,431 created temporary cache file '<fdopen>'
2014-04-20 08:50:28,524 (4931) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:50:33,529 (4931) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:50:43,539 (4931) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:51:03,542 (4931) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:51:43,559 (4931) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:53:03,616 (4931) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 08:53:59,724 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=5141) created RW/normal for storage: '1'
2014-04-20 08:53:59,725 created temporary cache file '<fdopen>'
2014-04-20 08:53:59,767 ['/var/run/schooltool/zeo.sock'] Testing connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
2014-04-20 08:53:59,767 (/var/run/schooltool/zeo.sock) received handshake 'Z3101'
2014-04-20 08:53:59,868 ['/var/run/schooltool/zeo.sock'] Server authentication protocol None
2014-04-20 08:53:59,872 ['/var/run/schooltool/zeo.sock'] Connected to storage: /var/run/schooltool/zeo.sock
2014-04-20 08:53:59,873 ['/var/run/schooltool/zeo.sock'] No verification necessary -- empty cache
2014-04-20 08:54:00,134 main db: evolving in mode EVOLVEMINIMUM
2014-04-20 08:54:00,176 main db/schooltool: running install generation
2014-04-20 09:47:57,929 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=5444) created RW/normal for storage: '1'
2014-04-20 09:47:57,931 created temporary cache file '<fdopen>'
2014-04-20 09:47:57,966 (5444) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 09:48:02,971 (5444) CW: error connecting to /var/run/schooltool/zeo.sock: ENOENT
2014-04-20 09:48:12,981 ['/var/run/schooltool/zeo.sock'] Testing connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
2014-04-20 09:48:12,982 (/var/run/schooltool/zeo.sock) received handshake 'Z3101'
2014-04-20 09:48:13,082 ['/var/run/schooltool/zeo.sock'] Server authentication protocol None
2014-04-20 09:48:13,083 ['/var/run/schooltool/zeo.sock'] Connected to storage: /var/run/schooltool/zeo.sock
2014-04-20 09:48:13,084 ['/var/run/schooltool/zeo.sock'] No verification necessary -- empty cache
2014-04-20 09:48:13,112 main db: evolving in mode EVOLVEMINIMUM
2014-04-20 09:48:13,114 main db/schooltool: currently at generation 38, targetting generation 41
2014-04-20 09:48:13,231 Couldn't load state for 0x07550a
Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 860, in setstate
    self._setstate(obj)
  File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 922, in _setstate
    obj._p_blob_committed = self._storage.loadBlob(obj._p_oid, serial)
  File "/usr/lib/python2.7/dist-packages/ZEO/ClientStorage.py", line 1020, in loadBlob
    raise POSException.POSKeyError("No blob file", oid, serial)
POSKeyError: 'No blob file'
2014-04-20 09:48:13,261 main db/schooltool: failed to evolve to generation 39
Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py", line 494, in evolve
    manager.evolve(context, generation)
  File "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py", line 145, in evolve
    evolver.evolve(context)
  File "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve39.py", line 55, in evolve
    evolvePerson(person)
  File "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve39.py", line 39, in evolvePerson
    fin = photo.open("r")
  File "/usr/lib/python2.7/dist-packages/zope/file/file.py", line 52, in open
    return self._data.open(mode)
  File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 860, in setstate
    self._setstate(obj)
  File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 922, in _setstate
    obj._p_blob_committed = self._storage.loadBlob(obj._p_oid, serial)
  File "/usr/lib/python2.7/dist-packages/ZEO/ClientStorage.py", line 1020, in loadBlob
    raise POSException.POSKeyError("No blob file", oid, serial)
POSKeyError: 'No blob file'
2014-04-20 09:53:35,230 ['/var/run/schooltool/zeo.sock'] ClientStorage (pid=5520) created RW/normal for storage: '1'
2014-04-20 09:53:35,231 (5520) Blob dir /var/lib/schooltool/blobs/ has insecure mode setting
2014-04-20 09:53:35,232 created temporary cache file '<fdopen>'
2014-04-20 09:53:35,274 ['/var/run/schooltool/zeo.sock'] Testing connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
2014-04-20 09:53:35,274 (/var/run/schooltool/zeo.sock) received handshake 'Z3101'
2014-04-20 09:53:35,374 ['/var/run/schooltool/zeo.sock'] Server authentication protocol None
2014-04-20 09:53:35,375 ['/var/run/schooltool/zeo.sock'] Connected to storage: /var/run/schooltool/zeo.sock
2014-04-20 09:53:35,376 ['/var/run/schooltool/zeo.sock'] No verification necessary -- empty cache
2014-04-20 09:53:35,389 main db: evolving in mode EVOLVEMINIMUM
2014-04-20 09:53:35,390 main db/schooltool: currently at generation 38, targetting generation 41
2014-04-20 09:53:44,491 main db/schooltool: failed to evolve to generation 40
Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py", line 494, in evolve
    manager.evolve(context, generation)
  File "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py", line 145, in evolve
    evolver.evolve(context)
  File "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve40.py", line 63, in evolve
    evolvePerson(app, sy, person)
  File "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve40.py", line 34, in evolvePerson
    groups = IGroupContainer(schoolyear)
  File "/usr/lib/python2.7/dist-packages/zope/component/hooks.py", line 119, in adapter_hook
    return siteinfo.adapter_hook(interface, object, name, default)
  File "/usr/lib/python2.7/dist-packages/schooltool/group/group.py", line 88, in getGroupContainer
    addIntIdSubscriber(sy, ObjectAddedEvent(sy))
  File "/usr/lib/python2.7/dist-packages/zope/intid/__init__.py", line 173, in addIntIdSubscriber
    notify(IntIdAddedEvent(ob, event, idmap))
  File "/usr/lib/python2.7/dist-packages/zope/event/__init__.py", line 31, in notify
    subscriber(event)
  File "/usr/lib/python2.7/dist-packages/zope/component/event.py", line 27, in dispatch
    component_subscribers(event, None)
  File "/usr/lib/python2.7/dist-packages/zope/component/_api.py", line 139, in subscribers
    return sitemanager.subscribers(objects, interface)
  File "/usr/lib/python2.7/dist-packages/zope/interface/registry.py", line 323, in subscribers
    return self.adapters.subscribers(objects, provided)
  File "/usr/lib/python2.7/dist-packages/zope/interface/adapter.py", line 601, in subscribers
    subscription(*objects)
  File "/usr/lib/python2.7/dist-packages/schooltool/app/catalog.py", line 184, in indexDocSubscriber
    entry.catalog.index_doc(obj_id, obj)
  File "/usr/lib/python2.7/dist-packages/zc/catalog/extentcatalog.py", line 179, in index_doc
    self.extent.add(docid, texts)
  File "/usr/lib/python2.7/dist-packages/zc/catalog/extentcatalog.py", line 119, in add
    if not self.addable(uid, obj):
  File "/usr/lib/python2.7/dist-packages/zc/catalog/extentcatalog.py", line 125, in addable
    return self.filter(self, uid, obj)
  File "/usr/lib/python2.7/dist-packages/schooltool/table/catalog.py", line 54, in __call__
    return bool(self.interface.providedBy(item))
AttributeError: type object 'IInterventionMarker' has no attribute 'providedBy'

Best Gediminas Paulauskas (menesis) said : #7

I see two problems:
1) blobs directory was not copied from original server.
2) intervention plugin is not installed:

$ sudo apt-get install python-schooltool.intervention

The last exception should not happen. Will have to take a look.
2014.04.20 19:11, "Rob Willis" <email address hidden> rašė:

> Question #247263 on SchoolTool changed:
> https://answers.launchpad.net/schooltool/+question/247263
>
> Status: Needs information => Open
>
> Rob Willis gave more information on the question:
> Thank you for the help... .I'm getting this status about 10 seconds
> after starting schooltool...
>
> schooltool EXITED Apr 20 10:08 AM
> services:celery_report RUNNING pid 5443, uptime 0:21:25
> services:redis RUNNING pid 5441, uptime 0:21:25
> services:zeo RUNNING pid 5442, uptime 0:21:25
>
> Output of various requested commands follows...
>
> root@LAKESERVE:/media/usb0/schooltool# sudo ls -ld
> /var/lib/schooltooldrwxr-x--- 3 schooltool schooltool 4096 Apr 20 10:02
> /var/lib/schooltool
>
> root@LAKESERVE:/media/usb0/schooltool# ls -l /var/lib/schooltooltotal
> 662924
> drwxr-xr-x 4 schooltool schooltool 4096 Oct 5 2012 blobs
> -rw-r--r-- 1 schooltool schooltool 674467176 Apr 20 09:53 Data.fs
> -rw-r--r-- 1 schooltool schooltool 4159783 Apr 20 09:48 Data.fs.index
> -rw-r--r-- 1 schooltool schooltool 6 Apr 20 09:47 Data.fs.lock
> -rw-r--r-- 1 schooltool schooltool 184825 Apr 20 09:53 Data.fs.tmp
> -rw-r--r-- 1 schooltool schooltool 152 Apr 20 10:02 redis-dump.rdb
>
> error.log
> 2014-04-20 07:49:09,155 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=12565) created RW/normal for storage: '1'
> 2014-04-20 07:49:09,156 created temporary cache file '<fdopen>'
> 2014-04-20 07:49:09,262 ['/var/run/schooltool/zeo.sock'] Testing
> connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
> 2014-04-20 07:49:09,263 (/var/run/schooltool/zeo.sock) received handshake
> 'Z3101'
> 2014-04-20 07:49:09,363 ['/var/run/schooltool/zeo.sock'] Server
> authentication protocol None
> 2014-04-20 07:49:09,364 ['/var/run/schooltool/zeo.sock'] Connected to
> storage: /var/run/schooltool/zeo.sock
> 2014-04-20 07:49:09,365 ['/var/run/schooltool/zeo.sock'] No verification
> necessary -- empty cache
> 2014-04-20 07:49:09,611 main db: evolving in mode EVOLVEMINIMUM
> 2014-04-20 07:49:09,681 main db/schooltool: running install generation
> 2014-04-20 08:01:03,000 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=15256) created RW/normal for storage: '1'
> 2014-04-20 08:01:03,001 created temporary cache file '<fdopen>'
> 2014-04-20 08:01:03,056 ['/var/run/schooltool/zeo.sock'] Testing
> connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
> 2014-04-20 08:01:03,056 (/var/run/schooltool/zeo.sock) received handshake
> 'Z3101'
> 2014-04-20 08:01:03,157 ['/var/run/schooltool/zeo.sock'] Server
> authentication protocol None
> 2014-04-20 08:01:03,158 ['/var/run/schooltool/zeo.sock'] Connected to
> storage: /var/run/schooltool/zeo.sock
> 2014-04-20 08:01:03,158 ['/var/run/schooltool/zeo.sock'] No verification
> necessary -- empty cache
> 2014-04-20 08:01:03,171 main db: evolving in mode EVOLVEMINIMUM
> 2014-04-20 08:35:42,141 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=2347) created RW/normal for storage: '1'
> 2014-04-20 08:35:42,154 (2347) Blob dir /var/lib/schooltool/blobs/ has
> insecure mode setting
> 2014-04-20 08:35:42,154 created temporary cache file '<fdopen>'
> 2014-04-20 08:35:42,207 (2347) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:35:47,213 (2347) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:35:57,223 (2347) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:36:17,237 (2347) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:36:57,243 (2347) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:38:17,311 (2347) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:39:24,726 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=4635) created RW/normal for storage: '1'
> 2014-04-20 08:39:24,728 (4635) Blob dir /var/lib/schooltool/blobs/ has
> insecure mode setting
> 2014-04-20 08:39:24,728 created temporary cache file '<fdopen>'
> 2014-04-20 08:39:24,788 (4635) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:39:29,793 (4635) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:39:39,794 (4635) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:39:59,809 (4635) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:40:39,845 (4635) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:41:09,893 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=4726) created RW/normal for storage: '1'
> 2014-04-20 08:41:09,894 (4726) Blob dir /var/lib/schooltool/blobs/ has
> insecure mode setting
> 2014-04-20 08:41:09,894 created temporary cache file '<fdopen>'
> 2014-04-20 08:41:09,955 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:41:14,961 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:41:24,971 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:41:44,986 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:42:25,006 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:43:45,085 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:46:25,143 (4726) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:50:28,426 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=4931) created RW/normal for storage: '1'
> 2014-04-20 08:50:28,430 (4931) Blob dir /var/lib/schooltool/blobs/ has
> insecure mode setting
> 2014-04-20 08:50:28,431 created temporary cache file '<fdopen>'
> 2014-04-20 08:50:28,524 (4931) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:50:33,529 (4931) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:50:43,539 (4931) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:51:03,542 (4931) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:51:43,559 (4931) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:53:03,616 (4931) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 08:53:59,724 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=5141) created RW/normal for storage: '1'
> 2014-04-20 08:53:59,725 created temporary cache file '<fdopen>'
> 2014-04-20 08:53:59,767 ['/var/run/schooltool/zeo.sock'] Testing
> connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
> 2014-04-20 08:53:59,767 (/var/run/schooltool/zeo.sock) received handshake
> 'Z3101'
> 2014-04-20 08:53:59,868 ['/var/run/schooltool/zeo.sock'] Server
> authentication protocol None
> 2014-04-20 08:53:59,872 ['/var/run/schooltool/zeo.sock'] Connected to
> storage: /var/run/schooltool/zeo.sock
> 2014-04-20 08:53:59,873 ['/var/run/schooltool/zeo.sock'] No verification
> necessary -- empty cache
> 2014-04-20 08:54:00,134 main db: evolving in mode EVOLVEMINIMUM
> 2014-04-20 08:54:00,176 main db/schooltool: running install generation
> 2014-04-20 09:47:57,929 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=5444) created RW/normal for storage: '1'
> 2014-04-20 09:47:57,931 created temporary cache file '<fdopen>'
> 2014-04-20 09:47:57,966 (5444) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 09:48:02,971 (5444) CW: error connecting to
> /var/run/schooltool/zeo.sock: ENOENT
> 2014-04-20 09:48:12,981 ['/var/run/schooltool/zeo.sock'] Testing
> connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
> 2014-04-20 09:48:12,982 (/var/run/schooltool/zeo.sock) received handshake
> 'Z3101'
> 2014-04-20 09:48:13,082 ['/var/run/schooltool/zeo.sock'] Server
> authentication protocol None
> 2014-04-20 09:48:13,083 ['/var/run/schooltool/zeo.sock'] Connected to
> storage: /var/run/schooltool/zeo.sock
> 2014-04-20 09:48:13,084 ['/var/run/schooltool/zeo.sock'] No verification
> necessary -- empty cache
> 2014-04-20 09:48:13,112 main db: evolving in mode EVOLVEMINIMUM
> 2014-04-20 09:48:13,114 main db/schooltool: currently at generation 38,
> targetting generation 41
> 2014-04-20 09:48:13,231 Couldn't load state for 0x07550a
> Traceback (most recent call last):
> File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 860, in
> setstate
> self._setstate(obj)
> File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 922, in
> _setstate
> obj._p_blob_committed = self._storage.loadBlob(obj._p_oid, serial)
> File "/usr/lib/python2.7/dist-packages/ZEO/ClientStorage.py", line 1020,
> in loadBlob
> raise POSException.POSKeyError("No blob file", oid, serial)
> POSKeyError: 'No blob file'
> 2014-04-20 09:48:13,261 main db/schooltool: failed to evolve to generation
> 39
> Traceback (most recent call last):
> File
> "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py",
> line 494, in evolve
> manager.evolve(context, generation)
> File
> "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py",
> line 145, in evolve
> evolver.evolve(context)
> File
> "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve39.py", line
> 55, in evolve
> evolvePerson(person)
> File
> "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve39.py", line
> 39, in evolvePerson
> fin = photo.open("r")
> File "/usr/lib/python2.7/dist-packages/zope/file/file.py", line 52, in
> open
> return self._data.open(mode)
> File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 860, in
> setstate
> self._setstate(obj)
> File "/usr/lib/python2.7/dist-packages/ZODB/Connection.py", line 922, in
> _setstate
> obj._p_blob_committed = self._storage.loadBlob(obj._p_oid, serial)
> File "/usr/lib/python2.7/dist-packages/ZEO/ClientStorage.py", line 1020,
> in loadBlob
> raise POSException.POSKeyError("No blob file", oid, serial)
> POSKeyError: 'No blob file'
> 2014-04-20 09:53:35,230 ['/var/run/schooltool/zeo.sock'] ClientStorage
> (pid=5520) created RW/normal for storage: '1'
> 2014-04-20 09:53:35,231 (5520) Blob dir /var/lib/schooltool/blobs/ has
> insecure mode setting
> 2014-04-20 09:53:35,232 created temporary cache file '<fdopen>'
> 2014-04-20 09:53:35,274 ['/var/run/schooltool/zeo.sock'] Testing
> connection <ManagedClientConnection /var/run/schooltool/zeo.sock>
> 2014-04-20 09:53:35,274 (/var/run/schooltool/zeo.sock) received handshake
> 'Z3101'
> 2014-04-20 09:53:35,374 ['/var/run/schooltool/zeo.sock'] Server
> authentication protocol None
> 2014-04-20 09:53:35,375 ['/var/run/schooltool/zeo.sock'] Connected to
> storage: /var/run/schooltool/zeo.sock
> 2014-04-20 09:53:35,376 ['/var/run/schooltool/zeo.sock'] No verification
> necessary -- empty cache
> 2014-04-20 09:53:35,389 main db: evolving in mode EVOLVEMINIMUM
> 2014-04-20 09:53:35,390 main db/schooltool: currently at generation 38,
> targetting generation 41
> 2014-04-20 09:53:44,491 main db/schooltool: failed to evolve to generation
> 40
> Traceback (most recent call last):
> File
> "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py",
> line 494, in evolve
> manager.evolve(context, generation)
> File
> "/usr/lib/python2.7/dist-packages/zope/app/generations/generations.py",
> line 145, in evolve
> evolver.evolve(context)
> File
> "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve40.py", line
> 63, in evolve
> evolvePerson(app, sy, person)
> File
> "/usr/lib/python2.7/dist-packages/schooltool/generations/evolve40.py", line
> 34, in evolvePerson
> groups = IGroupContainer(schoolyear)
> File "/usr/lib/python2.7/dist-packages/zope/component/hooks.py", line
> 119, in adapter_hook
> return siteinfo.adapter_hook(interface, object, name, default)
> File "/usr/lib/python2.7/dist-packages/schooltool/group/group.py", line
> 88, in getGroupContainer
> addIntIdSubscriber(sy, ObjectAddedEvent(sy))
> File "/usr/lib/python2.7/dist-packages/zope/intid/__init__.py", line
> 173, in addIntIdSubscriber
> notify(IntIdAddedEvent(ob, event, idmap))
> File "/usr/lib/python2.7/dist-packages/zope/event/__init__.py", line 31,
> in notify
> subscriber(event)
> File "/usr/lib/python2.7/dist-packages/zope/component/event.py", line
> 27, in dispatch
> component_subscribers(event, None)
> File "/usr/lib/python2.7/dist-packages/zope/component/_api.py", line
> 139, in subscribers
> return sitemanager.subscribers(objects, interface)
> File "/usr/lib/python2.7/dist-packages/zope/interface/registry.py", line
> 323, in subscribers
> return self.adapters.subscribers(objects, provided)
> File "/usr/lib/python2.7/dist-packages/zope/interface/adapter.py", line
> 601, in subscribers
> subscription(*objects)
> File "/usr/lib/python2.7/dist-packages/schooltool/app/catalog.py", line
> 184, in indexDocSubscriber
> entry.catalog.index_doc(obj_id, obj)
> File "/usr/lib/python2.7/dist-packages/zc/catalog/extentcatalog.py",
> line 179, in index_doc
> self.extent.add(docid, texts)
> File "/usr/lib/python2.7/dist-packages/zc/catalog/extentcatalog.py",
> line 119, in add
> if not self.addable(uid, obj):
> File "/usr/lib/python2.7/dist-packages/zc/catalog/extentcatalog.py",
> line 125, in addable
> return self.filter(self, uid, obj)
> File "/usr/lib/python2.7/dist-packages/schooltool/table/catalog.py",
> line 54, in __call__
> return bool(self.interface.providedBy(item))
> AttributeError: type object 'IInterventionMarker' has no attribute
> 'providedBy'
>
> --
> You received this question notification because you are an answer
> contact for SchoolTool.
>

Hey... .thank you so much for your help.

1) The blobs directory was copied over with the backup of the Data.fs file. It struck me as odd to see that message in the error log as well.

2) You're right.!!!!! I installed it, and Schooltool launches and "stays launched".

I have to stop out for about an hour, but when I get back I'll give it a good once over... but on quick check (log in and the data is there) everything looks great.

I really appreciate that you answered this on the weekend... these vacation weeks are the precious few moments I get to work on anything without disrupting users, and it's sure appreciated to have the support there when I needed it.

I'll reply back and close this when it's tested.

Thanks again,

Rob

All is well in schooltool land... thanks for the help!

Rob

Thanks Gediminas Paulauskas, that solved my question.