{"action":"created","issue":{"url":"https://api.github.com/repos/saltstack/salt/issues/25991","labels_url":"https://api.github.com/repos/saltstack/salt/issues/25991/labels{/name}","comments_url":"https://api.github.com/repos/saltstack/salt/issues/25991/comments","events_url":"https://api.github.com/repos/saltstack/salt/issues/25991/events","html_url":"https://github.com/saltstack/salt/issues/25991","id":98850877,"number":25991,"title":"msgpack unpack error: unpack(b) received extra data","user":{"login":"jfindlay","id":702905,"avatar_url":"https://avatars.githubusercontent.com/u/702905?v=3","gravatar_id":"","url":"https://api.github.com/users/jfindlay","html_url":"https://github.com/jfindlay","followers_url":"https://api.github.com/users/jfindlay/followers","following_url":"https://api.github.com/users/jfindlay/following{/other_user}","gists_url":"https://api.github.com/users/jfindlay/gists{/gist_id}","starred_url":"https://api.github.com/users/jfindlay/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/jfindlay/subscriptions","organizations_url":"https://api.github.com/users/jfindlay/orgs","repos_url":"https://api.github.com/users/jfindlay/repos","events_url":"https://api.github.com/users/jfindlay/events{/privacy}","received_events_url":"https://api.github.com/users/jfindlay/received_events","type":"User","site_admin":false},"labels":[{"url":"https://api.github.com/repos/saltstack/salt/labels/Bug","name":"Bug","color":"e11d21"},{"url":"https://api.github.com/repos/saltstack/salt/labels/Core","name":"Core","color":"fef2c0"},{"url":"https://api.github.com/repos/saltstack/salt/labels/Critical","name":"Critical","color":"e95800"},{"url":"https://api.github.com/repos/saltstack/salt/labels/P4","name":"P4","color":"031a39"},{"url":"https://api.github.com/repos/saltstack/salt/labels/Transport","name":"Transport","color":"006b75"}],"state":"open","locked":false,"assignee":null,"milestone":{"url":"https://api.github.com/repos/saltstack/salt/milestones/13","html_url":"https://github.com/saltstack/salt/milestones/Approved","labels_url":"https://api.github.com/repos/saltstack/salt/milestones/13/labels","id":9265,"number":13,"title":"Approved","description":"All issues that are ready to be worked on, both bugs and features.","creator":{"login":"thatch45","id":507599,"avatar_url":"https://avatars.githubusercontent.com/u/507599?v=3","gravatar_id":"","url":"https://api.github.com/users/thatch45","html_url":"https://github.com/thatch45","followers_url":"https://api.github.com/users/thatch45/followers","following_url":"https://api.github.com/users/thatch45/following{/other_user}","gists_url":"https://api.github.com/users/thatch45/gists{/gist_id}","starred_url":"https://api.github.com/users/thatch45/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/thatch45/subscriptions","organizations_url":"https://api.github.com/users/thatch45/orgs","repos_url":"https://api.github.com/users/thatch45/repos","events_url":"https://api.github.com/users/thatch45/events{/privacy}","received_events_url":"https://api.github.com/users/thatch45/received_events","type":"User","site_admin":false},"open_issues":2459,"closed_issues":2388,"state":"open","created_at":"2011-05-14T04:00:56Z","updated_at":"2015-08-05T21:12:46Z","due_on":null,"closed_at":null},"comments":5,"created_at":"2015-08-03T22:34:41Z","updated_at":"2015-08-05T21:12:56Z","closed_at":null,"body":"Edit: I have confirmed this issue on 2015.8.0rc3 and @mimianddaniel on 2015.5.3, so I am increasing the priority and severity.  I have also seen this happen on a remote minion upon the event of the master accepting its key.\r\n\r\nOccasionally, when running the latest develop branch, I encounter these errors in the minion log.  The minion and master are running on the same system and the minion has been restarted a few seconds after the master was restarted.\r\n```python-traceback\r\n# salt-minion -l debug\r\n[DEBUG   ] Reading configuration from /etc/salt/minion\r\n[DEBUG   ] Including configuration from '/etc/salt/minion.d/_schedule.conf'\r\n[DEBUG   ] Reading configuration from /etc/salt/minion.d/_schedule.conf\r\n[DEBUG   ] Using cached minion ID from /etc/salt/minion_id: jmoney-main\r\n[DEBUG   ] Configuration file path: /etc/salt/minion\r\n[INFO    ] Setting up the Salt Minion \"jmoney-main\"\r\n[DEBUG   ] Created pidfile: /var/run/salt-minion.pid\r\n[DEBUG   ] Reading configuration from /etc/salt/minion\r\n[DEBUG   ] Including configuration from '/etc/salt/minion.d/_schedule.conf'\r\n[DEBUG   ] Reading configuration from /etc/salt/minion.d/_schedule.conf\r\n[DEBUG   ] Please install 'virt-what' to improve results of the 'virtual' grain.\r\n[INFO    ] The salt minion is starting up\r\n[INFO    ] Minion is starting as user 'root'\r\n[DEBUG   ] Minion 'jmoney-main' trying to tune in\r\n[DEBUG   ] Initializing new SAuth for ('/etc/salt/pki/minion', 'jmoney-main', 'tcp://127.0.0.1:4506')\r\n[DEBUG   ] Generated random reconnect delay between '1000ms' and '11000ms' (10435)\r\n[DEBUG   ] Setting zmq_reconnect_ivl to '10435ms'\r\n[DEBUG   ] Setting zmq_reconnect_ivl_max to '11000ms'\r\n[DEBUG   ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/minion', 'jmoney-main', 'tcp://127.0.0.1:4506', 'clear')\r\n[CRITICAL] Could not deserialize msgpack message: Unexpected Error in MworkerThis often happens when trying to read a file not in binary mode.Please open an issue and include the following error: unpack(b) received extra data.\r\n[ERROR   ] Uncaught exception, closing connection.\r\nTraceback (most recent call last):\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 407, in _run_callback\r\n    callback(*args, **kwargs)\r\n  File \"/usr/local/lib/python2.7/dist-packages/tornado/stack_context.py\", line 275, in null_wrapper\r\n    return fn(*args, **kwargs)\r\n  File \"/root/salt/salt/transport/zeromq.py\", line 789, in mark_future\r\n    future.set_result(self.serial.loads(msg[0]))\r\n  File \"/root/salt/salt/payload.py\", line 99, in loads\r\n    return msgpack.loads(msg, use_list=True)\r\n  File \"msgpack/_unpacker.pyx\", line 142, in msgpack._unpacker.unpackb (msgpack/_unpacker.cpp:142)\r\nExtraData: unpack(b) received extra data.\r\n[ERROR   ] Uncaught exception, closing connection.\r\nTraceback (most recent call last):\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 433, in _handle_events\r\n    self._handle_recv()\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 465, in _handle_recv\r\n    self._run_callback(callback, msg)\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 407, in _run_callback\r\n    callback(*args, **kwargs)\r\n  File \"/usr/local/lib/python2.7/dist-packages/tornado/stack_context.py\", line 275, in null_wrapper\r\n    return fn(*args, **kwargs)\r\n  File \"/root/salt/salt/transport/zeromq.py\", line 789, in mark_future\r\n    future.set_result(self.serial.loads(msg[0]))\r\n  File \"/root/salt/salt/payload.py\", line 99, in loads\r\n    return msgpack.loads(msg, use_list=True)\r\n  File \"msgpack/_unpacker.pyx\", line 142, in msgpack._unpacker.unpackb (msgpack/_unpacker.cpp:142)\r\nExtraData: unpack(b) received extra data.\r\n[ERROR   ] Exception in callback %r\r\nTraceback (most recent call last):\r\n  File \"/usr/local/lib/python2.7/dist-packages/tornado/ioloop.py\", line 840, in start\r\n    handler_func(fd_obj, events)\r\n  File \"/usr/local/lib/python2.7/dist-packages/tornado/stack_context.py\", line 275, in null_wrapper\r\n    return fn(*args, **kwargs)\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 433, in _handle_events\r\n    self._handle_recv()\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 465, in _handle_recv\r\n    self._run_callback(callback, msg)\r\n  File \"/usr/lib/python2.7/dist-packages/zmq/eventloop/zmqstream.py\", line 407, in _run_callback\r\n    callback(*args, **kwargs)\r\n  File \"/usr/local/lib/python2.7/dist-packages/tornado/stack_context.py\", line 275, in null_wrapper\r\n    return fn(*args, **kwargs)\r\n  File \"/root/salt/salt/transport/zeromq.py\", line 789, in mark_future\r\n    future.set_result(self.serial.loads(msg[0]))\r\n  File \"/root/salt/salt/payload.py\", line 99, in loads\r\n    return msgpack.loads(msg, use_list=True)\r\n  File \"msgpack/_unpacker.pyx\", line 142, in msgpack._unpacker.unpackb (msgpack/_unpacker.cpp:142)\r\nExtraData: unpack(b) received extra data.\r\nException AttributeError: \"'NoneType' object has no attribute 'close'\" in <bound method AsyncReqMessageClient.__del__ of <salt.transport.zeromq.AsyncReqMessageClient object at 0x7ff96aed58d0>> ignored\r\n[INFO    ] The salt minion is shut down\r\n```\r\n```yaml\r\n# salt --versions\r\nSalt Version:\r\n           Salt: 2015.8.0-201-g342e0c0\r\n\r\nDependency Versions:\r\n         Jinja2: 2.7.3\r\n       M2Crypto: 0.21.1\r\n           Mako: 1.0.0\r\n         PyYAML: 3.11\r\n          PyZMQ: 14.4.0\r\n         Python: 2.7.9 (default, Mar  1 2015, 12:57:24)\r\n           RAET: Not Installed\r\n        Tornado: 4.1\r\n            ZMQ: 4.0.5\r\n          ioflo: Not Installed\r\n        libnacl: Not Installed\r\n   msgpack-pure: Not Installed\r\n msgpack-python: 0.4.6\r\n       pycrypto: 2.6.1\r\n\r\nSystem Versions:\r\n           dist: debian 8.1\r\n        machine: x86_64\r\n        release: 3.16.0-4-amd64\r\n         system: debian 8.1\r\n```"},"comment":{"url":"https://api.github.com/repos/saltstack/salt/issues/comments/128150883","html_url":"https://github.com/saltstack/salt/issues/25991#issuecomment-128150883","issue_url":"https://api.github.com/repos/saltstack/salt/issues/25991","id":128150883,"user":{"login":"jfindlay","id":702905,"avatar_url":"https://avatars.githubusercontent.com/u/702905?v=3","gravatar_id":"","url":"https://api.github.com/users/jfindlay","html_url":"https://github.com/jfindlay","followers_url":"https://api.github.com/users/jfindlay/followers","following_url":"https://api.github.com/users/jfindlay/following{/other_user}","gists_url":"https://api.github.com/users/jfindlay/gists{/gist_id}","starred_url":"https://api.github.com/users/jfindlay/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/jfindlay/subscriptions","organizations_url":"https://api.github.com/users/jfindlay/orgs","repos_url":"https://api.github.com/users/jfindlay/repos","events_url":"https://api.github.com/users/jfindlay/events{/privacy}","received_events_url":"https://api.github.com/users/jfindlay/received_events","type":"User","site_admin":false},"created_at":"2015-08-05T21:12:56Z","updated_at":"2015-08-05T21:12:56Z","body":"I have been unable to reproduce this on a new system, so I am downgrading the severity."}}