downingtownryan
Cadet
- Joined
- Dec 26, 2013
- Messages
- 5
I am very new to Free NAS and i am wondering if there is already a thread for this. I searched the errors i am getting but did not find anything.
I just upgraded to 9.2.1.1 on Friday morning and then noticed yesterday (monday) that the CPU load was slowly climbing. I was at 8 yesterday and now is at about 12. Usually at about 2 for us. Below is the error that i see constantly in the logs.
Feb 25 13:27:36 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:27:44 datastor smbd[19404]: [2014/02/25 13:27:44.122074, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:27:44 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:27:51 datastor smbd[19404]: [2014/02/25 13:27:51.073222, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:27:51 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:27:57 datastor smbd[19404]: [2014/02/25 13:27:57.862003, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:27:57 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:10 datastor smbd[19404]: [2014/02/25 13:28:10.963604, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:10 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:11 datastor smbd[33305]: [2014/02/25 13:28:11.715058, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:11 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:12 datastor smbd[33305]: [2014/02/25 13:28:12.000735, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:12 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:12 datastor smbd[33305]: [2014/02/25 13:28:12.126250, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:12 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:12 datastor smbd[33305]: [2014/02/25 13:28:12.152840, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:12 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:15 datastor smbd[33305]: [2014/02/25 13:28:15.736332, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:15 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:29:35 datastor smbd[31363]: [2014/02/25 13:29:35.892695, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:29:35 datastor smbd[31363]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:29:35 datastor smbd[31363]: [2014/02/25 13:29:35.910201, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:29:35 datastor smbd[31363]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:03 datastor smbd[35200]: [2014/02/25 13:30:03.107610, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:03 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:03 datastor smbd[35200]: [2014/02/25 13:30:03.301536, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:03 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:03 datastor smbd[35200]: [2014/02/25 13:30:03.524110, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:03 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:05 datastor smbd[35200]: [2014/02/25 13:30:05.679369, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:05 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:05 datastor smbd[35200]: [2014/02/25 13:30:05.860858, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:05 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:07 datastor smbd[35200]: [2014/02/25 13:30:07.055326, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:07 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:07 datastor smbd[35200]: [2014/02/25 13:30:07.362508, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:07 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:08 datastor smbd[35200]: [2014/02/25 13:30:08.048784, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:08 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.470422, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.645334, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.802922, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.964881, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:31:06 datastor smbd[21532]: [2014/02/25 13:31:06.613145, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:31:06 datastor smbd[21532]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:31:25 datastor smbd[35347]: [2014/02/25 13:31:25.425973, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:31:25 datastor smbd[35347]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
It just continues to happen every few seconds.
I just upgraded to 9.2.1.1 on Friday morning and then noticed yesterday (monday) that the CPU load was slowly climbing. I was at 8 yesterday and now is at about 12. Usually at about 2 for us. Below is the error that i see constantly in the logs.
Feb 25 13:27:36 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:27:44 datastor smbd[19404]: [2014/02/25 13:27:44.122074, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:27:44 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:27:51 datastor smbd[19404]: [2014/02/25 13:27:51.073222, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:27:51 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:27:57 datastor smbd[19404]: [2014/02/25 13:27:57.862003, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:27:57 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:10 datastor smbd[19404]: [2014/02/25 13:28:10.963604, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:10 datastor smbd[19404]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:11 datastor smbd[33305]: [2014/02/25 13:28:11.715058, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:11 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:12 datastor smbd[33305]: [2014/02/25 13:28:12.000735, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:12 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:12 datastor smbd[33305]: [2014/02/25 13:28:12.126250, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:12 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:12 datastor smbd[33305]: [2014/02/25 13:28:12.152840, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:12 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:28:15 datastor smbd[33305]: [2014/02/25 13:28:15.736332, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:28:15 datastor smbd[33305]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:29:35 datastor smbd[31363]: [2014/02/25 13:29:35.892695, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:29:35 datastor smbd[31363]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:29:35 datastor smbd[31363]: [2014/02/25 13:29:35.910201, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:29:35 datastor smbd[31363]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:03 datastor smbd[35200]: [2014/02/25 13:30:03.107610, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:03 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:03 datastor smbd[35200]: [2014/02/25 13:30:03.301536, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:03 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:03 datastor smbd[35200]: [2014/02/25 13:30:03.524110, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:03 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:05 datastor smbd[35200]: [2014/02/25 13:30:05.679369, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:05 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:05 datastor smbd[35200]: [2014/02/25 13:30:05.860858, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:05 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:07 datastor smbd[35200]: [2014/02/25 13:30:07.055326, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:07 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:07 datastor smbd[35200]: [2014/02/25 13:30:07.362508, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:07 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:08 datastor smbd[35200]: [2014/02/25 13:30:08.048784, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:08 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.470422, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.645334, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.802922, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:30:09 datastor smbd[35200]: [2014/02/25 13:30:09.964881, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:30:09 datastor smbd[35200]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:31:06 datastor smbd[21532]: [2014/02/25 13:31:06.613145, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:31:06 datastor smbd[21532]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
Feb 25 13:31:25 datastor smbd[35347]: [2014/02/25 13:31:25.425973, 0] ../lib/util/talloc_stack.c:104(talloc_pop)
Feb 25 13:31:25 datastor smbd[35347]: Freed frame ../source3/smbd/open.c:3510, expected ../source3/modules/nfs4_acls.c:936.
It just continues to happen every few seconds.