CIFS File copying error

[david_labbe]

Hi all,

Config: Alfresco CE 3.3g on OpenSuse 11.2

After getting the CIFS "shared drive" to show on a windows client, and after successfully accessing it, I tried to copy a couple of very small files in a test space. I can see the windows dialog "Copying X items…" poping up but nothing gets copied and the dialog remains forever up, showing a transfer speed of 0 bytes/sec. Trying to cancel the copy results in nothing, I have to actually kill explorer to stop it! Not good.

I have the following debug flags in the log4j properties:

log4j.logger.org.alfresco.smb.protocol=debug
log4j.logger.org.alfresco.smb.protocol.auth=debug
log4j.logger.org.alfresco.acegi=debug
log4j.logger.org.alfresco.fileserver=debug
‍‍‍‍‍

In my alfresco.log file, I see an incredible amount of the following lines:

13:16:21,936 DEBUG [org.alfresco.smb.protocol.auth] Using Write transaction‍

followed by:

13:16:49,787 DEBUG [org.alfresco.fileserver] [T1] Closing session due to exception
13:16:49,787 DEBUG [org.alfresco.smb.protocol.auth] Using Write transaction‍‍

and a rather remarkable number of the following errors:

13:16:49,788 ERROR [org.alfresco.fileserver] java.lang.NullPointerException
13:16:49,788 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.rule.ruletrigger.CreateNodeRuleTrigger.onAddAspect(CreateNodeRuleTrigger.java:152)
13:16:49,788 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,788 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,788 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,788 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,788 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.policy.JavaBehaviour$JavaMethodInvocationHandler.invoke(JavaBehaviour.java:173)
13:16:49,788 ERROR [org.alfresco.fileserver]    at $Proxy10.onAddAspect(Unknown Source)
13:16:49,788 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,788 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,788 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,788 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,788 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.policy.TransactionInvocationHandlerFactory$TransactionHandler.invoke(TransactionInvocationHandlerFactory.java:128)
13:16:49,788 ERROR [org.alfresco.fileserver]    at $Proxy10.onAddAspect(Unknown Source)
13:16:49,789 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,789 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,789 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,789 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.policy.PolicyFactory$MultiHandler.invoke(PolicyFactory.java:300)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.policy.$Proxy201.onAddAspect(Unknown Source)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.AbstractNodeServiceImpl.invokeOnAddAspect(AbstractNodeServiceImpl.java:481)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.db.DbNodeServiceImpl.addAspectsAndProperties(DbNodeServiceImpl.java:513)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.db.DbNodeServiceImpl.addAspectsAndProperties(DbNodeServiceImpl.java:421)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.db.DbNodeServiceImpl.addAspect(DbNodeServiceImpl.java:718)
13:16:49,789 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,789 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,789 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,789 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:104)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,789 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
13:16:49,790 ERROR [org.alfresco.fileserver]    at $Proxy7.addAspect(Unknown Source)
13:16:49,790 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,790 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,790 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,790 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
13:16:49,790 ERROR [org.alfresco.fileserver]    at $Proxy8.addAspect(Unknown Source)
13:16:49,790 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,790 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,790 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,790 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:303)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:303)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:135)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,790 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:135)
13:16:49,791 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,791 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
13:16:49,791 ERROR [org.alfresco.fileserver]    at $Proxy7.addAspect(Unknown Source)
13:16:49,791 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
13:16:49,791 ERROR [org.alfresco.fileserver]    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
13:16:49,791 ERROR [org.alfresco.fileserver]    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
13:16:49,791 ERROR [org.alfresco.fileserver]    at java.lang.reflect.Method.invoke(Method.java:597)
13:16:49,791 ERROR [org.alfresco.fileserver]    at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
13:16:49,791 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
13:16:49,791 DEBUG [org.alfresco.smb.protocol.auth] Using Write transaction
13:16:49,791 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
13:16:49,805 ERROR [org.alfresco.fileserver]    at net.sf.acegisecurity.intercept.method.aopalliance.MethodSecurityInterceptor.invoke(MethodSecurityInterceptor.java:80)
13:16:49,805 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,805 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:44)
13:16:49,805 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:135)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:107)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
13:16:49,806 ERROR [org.alfresco.fileserver]    at $Proxy7.addAspect(Unknown Source)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.filesys.repo.ContentDiskDriver$1.call(ContentDiskDriver.java:1965)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.filesys.repo.ContentDiskDriver$1.call(ContentDiskDriver.java:1923)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.filesys.alfresco.AlfrescoDiskDriver$1.execute(AlfrescoDiskDriver.java:188)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:325)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:232)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.filesys.alfresco.AlfrescoDiskDriver.doInWriteTransaction(AlfrescoDiskDriver.java:181)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.filesys.repo.ContentDiskDriver.createFile(ContentDiskDriver.java:1923)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.NTProtocolHandler.procNTCreateAndX(NTProtocolHandler.java:5720)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.NTProtocolHandler.runProtocol(NTProtocolHandler.java:311)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.runHandler(SMBSrvSession.java:1366)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.processPacket(SMBSrvSession.java:1458)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.NIOCIFSThreadRequest.runRequest(NIOCIFSThreadRequest.java:105)
13:16:49,806 ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.server.thread.ThreadRequestPool$ThreadWorker.run(ThreadRequestPool.java:153)
13:16:49,806 ERROR [org.alfresco.fileserver]    at java.lang.Thread.run(Thread.java:619)‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

And it goes on and on and on and on and on….. until I have to actually kill tomcat otherwise it hangs my client machine!

Anybody has any idea?

Cheers

David

[dward]

See https://issues.alfresco.com/jira/browse/ALF-3782