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

[david_labbe]

Has nobody ever encountered the above problem?

[david_labbe]

The funny thing is that if I use the downloadable war file (3.3g) it seems to work. If I build from the Head, I get the above error… What happened???

[mrogers]

Sounds like a recently introduced bug.   Please raise in JIRA with as much info as possible.

[david_labbe]

Sounds like a recently introduced bug.   Please raise in JIRA with as much info as possible.

Thanks. I've just done so on ALF-3782. Hopefully someone will know what to do with this!

Regards

David

[david_labbe]

Just a simple question that might sound stupid: I know that this is a "community" bug and as such this is not given priority over the paying Entreprise version (I accept that fully), but generally, how long does it take before a reported bug gets looked into?

Cheers

David

[david_labbe]

Waou… that was fast… I hadn't finished posting the last reply that I saw a couple of email poping up in my inbox about the JIRA bug… Nice … 🙂

[mrogers]

It depends on which Engineers see it whether they think its important to rush out a patch.  It does happen from time to time.   However we are in the middle of developing Cheetah and Swift rather than bug fixing so attention is elsewhere.  

What does not happen with community issues at this time is the management of community issues over development tasks.

However I suspect this is a simple case of incorrect error handling that can be investigated and fixed within the community.   So please do not sit back and wait for Alfresco Engineering. If someone contributes a fix then I'll make sure it gets onto HEAD so it will be part of community 3.4.

[david_labbe]

thanks for you reply. Any chance of knowing which was the last revision without this issue?

By the way, although I am not a Java expert at all and also quite a newb in Alfresco, If I wanted to try to figure out a solutions, where should I look (files, etc…) in the Alfresco build, and roughly what should I look for?

Thanks

David

[david_labbe]

I can see in JIRA that the issue seem to have been solved! But no comments or anything appart from the fact it's a fix in 3.3SP3. Any idea when 3.3 SP3 will be available? Is this fix in the new HEAD? Thanks for resolving the issue.

Kind regards

David