[2013/10/17 17:05:05.905352,  5] rpc_server/samr/srv_samr_nt.c:4757(set_user_info_pw)
  Attempting administrator password change for user mm-macassidy$
[2013/10/17 17:05:05.905399, 11] passdb/pdb_get_set.c:462(pdb_set_init_flags)
  element 34 -> now CHANGED
[2013/10/17 17:05:05.905472, 11] passdb/pdb_get_set.c:462(pdb_set_init_flags)
  element 33 -> now CHANGED
[2013/10/17 17:05:05.905505, 11] passdb/pdb_get_set.c:462(pdb_set_init_flags)
  element 11 -> now CHANGED
[2013/10/17 17:05:05.905536, 11] passdb/pdb_get_set.c:462(pdb_set_init_flags)
  element 21 -> now CHANGED
[2013/10/17 17:05:05.905567,  5] lib/username.c:171(Get_Pwnam_alloc)
  Finding user mm-macassidy$
[2013/10/17 17:05:05.905597,  5] lib/username.c:116(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is mm-macassidy$
[2013/10/17 17:05:05.905631,  5] lib/username.c:149(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [mm-macassidy$]!
[2013/10/17 17:05:05.905668,  3] rpc_server/samr/srv_samr_chgpasswd.c:513(chgpasswd)
  chgpasswd: Password change (as_root=Yes) for user: mm-macassidy$
[2013/10/17 17:05:05.909312, 10] rpc_server/samr/srv_samr_chgpasswd.c:93(findpty)
  findpty: Allocated slave pty /dev/pts/6
[2013/10/17 17:05:05.910838,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2013/10/17 17:05:05.911429,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 1
[2013/10/17 17:05:05.911466,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2013/10/17 17:05:05.911595,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2013/10/17 17:05:05.911637,  5] auth/token_util.c:527(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2013/10/17 17:05:05.911717,  3] rpc_server/samr/srv_samr_chgpasswd.c:480(chat_with_program)
  chat_with_program: Dochild for user mm-macassidy$ (uid=0,gid=0) (as_root = Yes)
[2013/10/17 17:05:05.912825, 10] rpc_server/samr/srv_samr_chgpasswd.c:237(dochild)
  Invoking '/usr/bin/passwd mm-macassidy_' as password change program.
[2013/10/17 17:05:05.921335,  5] lib/util_sock.c:361(read_fd_with_timeout)
  read_fd_with_timeout: timeout read. EOF from client.
[2013/10/17 17:05:05.921427,  2] rpc_server/samr/srv_samr_chgpasswd.c:284(expect)
  expect: read error NT_STATUS_END_OF_FILE
[2013/10/17 17:05:05.921463,  2] rpc_server/samr/srv_samr_chgpasswd.c:315(expect)
  expect: NT_STATUS_END_OF_FILE
[2013/10/17 17:05:05.921495,  3] rpc_server/samr/srv_samr_chgpasswd.c:350(talktochild)
  Response 1 incorrect
[2013/10/17 17:05:05.921527,  3] rpc_server/samr/srv_samr_chgpasswd.c:422(chat_with_program)
  chat_with_program: Child failed to change password: mm-macassidy$
[2013/10/17 17:05:05.922101,  3] rpc_server/samr/srv_samr_chgpasswd.c:454(chat_with_program)
  chat_with_program: The process exited with status 1 while we were waiting
[2013/10/17 17:05:05.922165,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 501) - sec_ctx_stack_ndx = 0
[2013/10/17 17:05:05.922215,  1] ../librpc/ndr/ndr.c:284(ndr_print_function_debug)
       samr_SetUserInfo2: struct samr_SetUserInfo2
          out: struct samr_SetUserInfo2
              result                   : NT_STATUS_WRONG_PASSWORD
[2013/10/17 17:05:05.922350,  0] rpc_server/srv_pipe.c:88(dump_pdu_region)
  created /tmp/out_\samr_58.1.prs
[2013/10/17 17:05:05.922385,  5] rpc_server/srv_pipe.c:1679(api_rpcTNP)
  api_rpcTNP: called \samr successfully
[2013/10/17 17:05:05.922433, 10] rpc_server/srv_pipe_hnd.c:247(write_to_internal_pipe)
  write_to_pipe: data_used = 798
[2013/10/17 17:05:05.922481, 11] lib/events.c:445(s3_event_debug)
  s3_event: Schedule immediate event "tevent_req_trigger": 8a0f510
[2013/10/17 17:05:05.922546, 11] lib/events.c:445(s3_event_debug)
  s3_event: Run immediate event "tevent_req_trigger": 8a0f510