Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    trying stored connection with userAgent: OMERO.webgateway  ckey: S:925ce1dd-1f2c-4507-8e1f-c99d39b79770#1
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    [('S:925ce1dd-1f2c-4507-8e1f-c99d39b79770#1', <webclient.webclient_gateway.OmeroWebGateway object at 0x05F9C390>)]
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    c=1|162:885$FF0000
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    [1],[[162.0, 885.0]],[u'FF0000']
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    c=1|162:885$FF0000
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    [1],[[162.0, 885.0]],[u'FF0000']
Wed, 08 Jun 2011 13:55:04 cache        DEBUG       set: img_None/3574/0x0-c1-162:885-FF0000-mg-q0.9-r-t0,10,12,256,256
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Ice.Exception (1) on safe call load((),{})
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Traceback (most recent call last):
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2882, in inner
    return f(*args, **kwargs)
  File "c:\OMERO-CURRENT\lib\python\omero_api_RenderingEngine_ice.py", line 235, in load
    return _M_omero.api.RenderingEngine._op_load.invoke(self, ((), _ctx))
LockTimeout: exception ::omero::LockTimeout
{
    serverStackTrace = ome.conditions.LockTimeout: c:\OMERO\Pixels\Dir-001\1077_pyramid is locked by others
	at ome.io.bioformats.BfPyramidPixelBuffer.initializeReader(BfPyramidPixelBuffer.java:161)
	at ome.io.bioformats.BfPyramidPixelBuffer.<init>(BfPyramidPixelBuffer.java:145)
	at ome.io.nio.PixelsService.createPyramidPixelBuffer(PixelsService.java:614)
	at ome.io.nio.PixelsService.getPixelBuffer(PixelsService.java:389)
	at ome.services.RenderingBean$14.doWork(RenderingBean.java:1820)
	at sun.reflect.GeneratedMethodAccessor251.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
	at ome.services.util.Executor$Impl$Interceptor.invoke(Executor.java:439)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at ome.security.basic.EventHandler.invoke(EventHandler.java:150)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:231)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
	at $Proxy64.doWork(Unknown Source)
	at ome.services.util.Executor$Impl.execute(Executor.java:369)
	at ome.services.RenderingBean.getPixelBuffer(RenderingBean.java:1817)
	at ome.services.RenderingBean.load(RenderingBean.java:389)
	at sun.reflect.GeneratedMethodAccessor593.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
	at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
	at $Proxy81.load(Unknown Source)
	at sun.reflect.GeneratedMethodAccessor593.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
	at ome.security.basic.BasicSecurityWiring.invoke(BasicSecurityWiring.java:83)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at ome.services.blitz.fire.AopContextInitializer.invoke(AopContextInitializer.java:43)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
	at $Proxy81.load(Unknown Source)
	at sun.reflect.GeneratedMethodAccessor759.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at ome.services.blitz.util.IceMethodInvoker.invoke(IceMethodInvoker.java:179)
	at ome.services.throttling.Callback.run(Callback.java:56)
	at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
	at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:136)
	at ome.services.blitz.impl.RenderingEngineI.load_async(RenderingEngineI.java:307)
	at omero.api._RenderingEngineTie.load_async(_RenderingEngineTie.java:246)
	at omero.api._RenderingEngineDisp.___load(_RenderingEngineDisp.java:726)
	at omero.api._RenderingEngineDisp.__dispatch(_RenderingEngineDisp.java:1650)
	at IceInternal.Incoming.invoke(Incoming.java:159)
	at Ice.ConnectionI.invokeAll(ConnectionI.java:2037)
	at Ice.ConnectionI.message(ConnectionI.java:972)
	at IceInternal.ThreadPool.run(ThreadPool.java:577)
	at IceInternal.ThreadPool.access$100(ThreadPool.java:12)
	at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:971)

    serverExceptionClass = ome.conditions.LockTimeout
    message = c:\OMERO\Pixels\Dir-001\1077_pyramid is locked by others
    backOff = 15000
    seconds = 0
}

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Ice.Exception (2) on safe call load((),{})
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Traceback (most recent call last):
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2904, in inner
    return func(*args, **kwargs)
  File "c:\OMERO-CURRENT\lib\python\omero_api_RenderingEngine_ice.py", line 235, in load
    return _M_omero.api.RenderingEngine._op_load.invoke(self, ((), _ctx))
ApiUsageException: exception ::omero::ApiUsageException
{
    serverStackTrace = ome.conditions.ApiUsageException: RenderingEngine not ready: Pixels object not set.

	at ome.services.RenderingBean.errorIfNullPixels(RenderingBean.java:1471)

	at ome.services.RenderingBean.load(RenderingBean.java:378)

	at sun.reflect.GeneratedMethodAccessor593.invoke(Unknown Source)

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

	at java.lang.reflect.Method.invoke(Unknown Source)

	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)

	at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)

	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)

	at $Proxy81.load(Unknown Source)

	at sun.reflect.GeneratedMethodAccessor593.invoke(Unknown Source)

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

	at java.lang.reflect.Method.invoke(Unknown Source)

	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)

	at ome.security.basic.BasicSecurityWiring.invoke(BasicSecurityWiring.java:83)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)

	at ome.services.blitz.fire.AopContextInitializer.invoke(AopContextInitializer.java:43)

	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)

	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)

	at $Proxy81.load(Unknown Source)

	at sun.reflect.GeneratedMethodAccessor759.invoke(Unknown Source)

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

	at java.lang.reflect.Method.invoke(Unknown Source)

	at ome.services.blitz.util.IceMethodInvoker.invoke(IceMethodInvoker.java:179)

	at ome.services.throttling.Callback.run(Callback.java:56)

	at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)

	at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:136)

	at ome.services.blitz.impl.RenderingEngineI.load_async(RenderingEngineI.java:307)

	at omero.api._RenderingEngineTie.load_async(_RenderingEngineTie.java:246)

	at omero.api._RenderingEngineDisp.___load(_RenderingEngineDisp.java:726)

	at omero.api._RenderingEngineDisp.__dispatch(_RenderingEngineDisp.java:1650)

	at IceInternal.Incoming.invoke(Incoming.java:159)

	at Ice.ConnectionI.invokeAll(ConnectionI.java:2037)

	at Ice.ConnectionI.message(ConnectionI.java:972)

	at IceInternal.ThreadPool.run(ThreadPool.java:577)

	at IceInternal.ThreadPool.access$100(ThreadPool.java:12)

	at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:971)


    serverExceptionClass = ome.conditions.ApiUsageException
    message = RenderingEngine not ready: Pixels object not set.
}

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    proxy_connect: a
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Connect attempt, sUuid=925ce1dd-1f2c-4507-8e1f-c99d39b79770, group=None, self.sUuid=925ce1dd-1f2c-4507-8e1f-c99d39b79770
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    connected? True
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    was connected, creating new omero.client
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Ice.Exception (1) on safe call setModel((object #0 (::omero::model::RenderingModel)
{
    _id = object #1 (::omero::RLong)
    {
        _val = 2
    }
    _details = object #2 (::omero::model::Details)
    {
        _owner = <nil>
        _group = <nil>
        _creationEvent = <nil>
        _updateEvent = <nil>
        _permissions = object #3 (::omero::model::Permissions)
        {
            _perm1 = -35
        }
        _externalInfo = <nil>
    }
    _loaded = True
    _value = object #4 (::omero::RString)
    {
        _val = greyscale
    }
},),{})
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Traceback (most recent call last):
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2882, in inner
    return f(*args, **kwargs)
  File "c:\OMERO-CURRENT\lib\python\omero_api_RenderingEngine_ice.py", line 241, in setModel
    return _M_omero.api.RenderingEngine._op_setModel.invoke(self, ((model, ), _ctx))
ConnectionLostException: Ice.ConnectionLostException:
recv() returned zero

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Ice.Exception (2) on safe call setModel((object #0 (::omero::model::RenderingModel)
{
    _id = object #1 (::omero::RLong)
    {
        _val = 2
    }
    _details = object #2 (::omero::model::Details)
    {
        _owner = <nil>
        _group = <nil>
        _creationEvent = <nil>
        _updateEvent = <nil>
        _permissions = object #3 (::omero::model::Permissions)
        {
            _perm1 = -35
        }
        _externalInfo = <nil>
    }
    _loaded = True
    _value = object #4 (::omero::RString)
    {
        _val = greyscale
    }
},),{})
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Traceback (most recent call last):
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2902, in inner
    self._obj = self._create_func()
  File "c:\OMERO-CURRENT\lib\python\omero_API_ice.py", line 273, in createRenderingEngine
    return _M_omero.api.ServiceFactory._op_createRenderingEngine.invoke(self, ((), _ctx))
CommunicatorDestroyedException: exception ::Ice::CommunicatorDestroyedException
{
}

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    proxy_connect: a
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Connect attempt, sUuid=925ce1dd-1f2c-4507-8e1f-c99d39b79770, group=None, self.sUuid=925ce1dd-1f2c-4507-8e1f-c99d39b79770
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    connected? False
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Error: Session already active. Create a new omero.client or closeSession()
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    connect failed
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    joinSession(None)
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    Uncaptured sUuid failure!
Wed, 08 Jun 2011 13:55:04 blitz_gateway INFO     BlitzGateway.connect().createSession(): Traceback (most recent call last):
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 1518, in connect
    self._createSession()
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 1423, in _createSession
    self._ic_props[omero.constants.PASSWORD])
  File "c:\OMERO-CURRENT\lib\python\omero\clients.py", line 417, in createSession
    raise ClientError("Session already active. Create a new omero.client or closeSession()")
ClientError: Session already active. Create a new omero.client or closeSession()

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    connect(): Traceback (most recent call last):
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 1548, in connect
    self._createSession()
  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 1423, in _createSession
    self._ic_props[omero.constants.PASSWORD])
  File "c:\OMERO-CURRENT\lib\python\omero\clients.py", line 417, in createSession
    raise ClientError("Session already active. Create a new omero.client or closeSession()")
ClientError: Session already active. Create a new omero.client or closeSession()

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    connect failed
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG      File "C:\Python25\Lib\site-packages\isapi_wsgi.py", line 471, in HttpExtensionProc
    _run_app(self.rootapp, self.apps, ecb)
/n  File "C:\Python25\Lib\site-packages\isapi_wsgi.py", line 444, in _run_app
    handler.run(application)
/n  File "C:\Python25\lib\wsgiref\handlers.py", line 92, in run
    self.result = application(self.environ, self.start_response)
/n  File "c:\OMERO-CURRENT\lib\python\django\core\handlers\wsgi.py", line 241, in __call__
    response = self.get_response(request)
/n  File "c:\OMERO-CURRENT\lib\python\django\core\handlers\base.py", line 92, in get_response
    response = callback(request, *callback_args, **callback_kwargs)
/n  File "c:\omero-current\lib\python\omeroweb\webgateway\views.py", line 603, in render_image_region
    pi = _get_prepared_image(request, iid, server_id=server_id, _conn=_conn, with_session=USE_SESSION)
/n  File "c:\omero-current\lib\python\omeroweb\webgateway\views.py", line 555, in _get_prepared_image
    img.setGreyscaleRenderingModel()
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 5560, in setGreyscaleRenderingModel
    self._re.setModel(self._rm.get('greyscale', rm[0])._obj)
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2929, in wrapped
    return inner(*args, **kwargs)
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2914, in inner
    self._connect(forcejoin=True)
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 3052, in _connect
    logger.debug('/n'.join(traceback.format_stack()))

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    last try for setModel
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG      File "C:\Python25\Lib\site-packages\isapi_wsgi.py", line 471, in HttpExtensionProc
    _run_app(self.rootapp, self.apps, ecb)
/n  File "C:\Python25\Lib\site-packages\isapi_wsgi.py", line 444, in _run_app
    handler.run(application)
/n  File "C:\Python25\lib\wsgiref\handlers.py", line 92, in run
    self.result = application(self.environ, self.start_response)
/n  File "c:\OMERO-CURRENT\lib\python\django\core\handlers\wsgi.py", line 241, in __call__
    response = self.get_response(request)
/n  File "c:\OMERO-CURRENT\lib\python\django\core\handlers\base.py", line 92, in get_response
    response = callback(request, *callback_args, **callback_kwargs)
/n  File "c:\omero-current\lib\python\omeroweb\webgateway\views.py", line 603, in render_image_region
    pi = _get_prepared_image(request, iid, server_id=server_id, _conn=_conn, with_session=USE_SESSION)
/n  File "c:\omero-current\lib\python\omeroweb\webgateway\views.py", line 552, in _get_prepared_image
    if not img.setActiveChannels(channels, windows, colors):
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 5380, in setActiveChannels
    for c in range(len(self.getChannels())):
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 4784, in wrapped
    if not self._prepareRenderingEngine():
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 4990, in _prepareRenderingEngine
    self._re = self._prepareRE()
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 4971, in _prepareRE
    re.load()
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2929, in wrapped
    return inner(*args, **kwargs)
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 2914, in inner
    self._connect(forcejoin=True)
/n  File "c:\omero-current\lib\python\omero\gateway\__init__.py", line 3052, in _connect
    logger.debug('/n'.join(traceback.format_stack()))

Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    ConnectionLostException, bailing out
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    last try for load
Wed, 08 Jun 2011 13:55:04 blitz_gateway DEBUG    ConnectionLostException, bailing out
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    :: (session) crasher localhost 4064
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    :: (session) crasher localhost 4064
Wed, 08 Jun 2011 13:55:04 webgateway   DEBUG    :: (request) None None None