Iocage Plugin Install Error

[Rusty!]

I thought I'd give 11.2 a try, and move some of my warden jails/plugins to iocage, for the most part it's gone well, but I cannot install the sabNZBd plugin, it fails with the error

Code:

[priority,] message string

and the more info option gives:

Code:

Error: concurrent.futures.process._RemoteTraceback: 
"""
Traceback (most recent call last):
  File "/usr/local/lib/python3.6/concurrent/futures/process.py", line 175, in _process_worker
	r = call_item.fn(*call_item.args, **call_item.kwargs)
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 122, in main_worker
	res = loop.run_until_complete(coro)
  File "/usr/local/lib/python3.6/asyncio/base_events.py", line 468, in run_until_complete
	return future.result()
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 82, in _run
	return await self._call(f'{service_name}.{method}', serviceobj, methodobj, params=args, job=job)
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 75, in _call
	return methodobj(*params)
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 75, in _call
	return methodobj(*params)
  File "/usr/local/lib/python3.6/site-packages/middlewared/schema.py", line 662, in nf
	return f(*args, **kwargs)
  File "/usr/local/lib/python3.6/site-packages/middlewared/plugins/jail.py", line 238, in fetch
	iocage.fetch(**options)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/iocage.py", line 909, in fetch
	props, accept_license=accept, official=official)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 689, in fetch_plugin_index
	props, 0, accept_license)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 115, in fetch_plugin
	self.__fetch_plugin_inform__(conf, num, plugins, accept_license)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 215, in __fetch_plugin_inform__
	silent=self.silent)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_common.py", line 82, in logit
	_callback({"level": level, "message": msg}, exit_on_error)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_common.py", line 55, in callback
	log.log(15, message)
  File "/usr/local/lib/python3.6/logging/__init__.py", line 1372, in log
	self._log(level, msg, args, **kwargs)
  File "/usr/local/lib/python3.6/logging/__init__.py", line 1442, in _log
	self.handle(record)
  File "/usr/local/lib/python3.6/logging/__init__.py", line 1452, in handle
	self.callHandlers(record)
  File "/usr/local/lib/python3.6/logging/__init__.py", line 1514, in callHandlers
	hdlr.handle(record)
  File "/usr/local/lib/python3.6/logging/__init__.py", line 863, in handle
	self.emit(record)
  File "/usr/local/lib/freenasOS/__init__.py", line 152, in emit
	_msg)
TypeError: [priority,] message string
"""

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/middlewared/job.py", line 332, in run
	await self.future
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
	return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/job.py", line 356, in __run_body
	rv = await self.middleware._call_worker(self.serviceobj, self.method_name, *self.args, job={'id': self.id})
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
	return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 977, in _call_worker
	job,
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
	return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 908, in run_in_proc
	return await self.run_in_executor(self.__procpool, method, *args, **kwargs)
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
	return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 902, in run_in_executor
	return await loop.run_in_executor(pool, functools.partial(method, *args, **kwargs))
TypeError: [priority,] message string

I've also read of some people having the same error on other plugins, will update if I come across them.

 

[Maro]

Hi Rusty,

Don't have a solution yet. But i am in the same boat as you. Just want to add my findings here as well. When I look at the console after attempting to install some of those plugins, I see the following error description displayed:

freenas /middlewared: [iocage:49] This plugin requires accepting a license to proceed:

I cannot find any way through the GUI to accept the license. Going to attempt to install the plugin through shell and see what happens.

 

[dlavigne]

This has been fixed for BETA2: https://redmine.ixsystems.com/issues/38355

 

[Rusty!]

Updated to Beta 2, still can't install sabNZBd plugin, but the error is different now:

Code:

unexpected http response 401 for https://github.com/freenas/iocage-plugin-sabnzbd/info/refs?service=git-upload-pack

Under more info:

Code:

Error: concurrent.futures.process._RemoteTraceback: 
"""
Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/dulwich/client.py", line 1261, in _http_request
    resp = self.opener.open(req)
  File "/usr/local/lib/python3.6/urllib/request.py", line 532, in open
    response = meth(req, response)
  File "/usr/local/lib/python3.6/urllib/request.py", line 642, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/local/lib/python3.6/urllib/request.py", line 570, in error
    return self._call_chain(*args)
  File "/usr/local/lib/python3.6/urllib/request.py", line 504, in _call_chain
    result = func(*args)
  File "/usr/local/lib/python3.6/urllib/request.py", line 650, in http_error_default
    raise HTTPError(req.full_url, code, msg, hdrs, fp)
urllib.error.HTTPError: HTTP Error 401: Authorization Required

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.6/concurrent/futures/process.py", line 175, in _process_worker
    r = call_item.fn(*call_item.args, **call_item.kwargs)
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 122, in main_worker
    res = loop.run_until_complete(coro)
  File "/usr/local/lib/python3.6/asyncio/base_events.py", line 468, in run_until_complete
    return future.result()
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 82, in _run
    return await self._call(f'{service_name}.{method}', serviceobj, methodobj, params=args, job=job)
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 75, in _call
    return methodobj(*params)
  File "/usr/local/lib/python3.6/site-packages/middlewared/worker.py", line 75, in _call
    return methodobj(*params)
  File "/usr/local/lib/python3.6/site-packages/middlewared/schema.py", line 662, in nf
    return f(*args, **kwargs)
  File "/usr/local/lib/python3.6/site-packages/middlewared/plugins/jail.py", line 248, in fetch
    iocage.fetch(**options)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/iocage.py", line 909, in fetch
    props, accept_license=accept, official=official)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 690, in fetch_plugin_index
    props, 0, accept_license)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 128, in fetch_plugin
    self.__fetch_plugin_post_install__(conf, _conf, jaildir, jail_name)
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 515, in __fetch_plugin_post_install__
    self.__clone_repo(conf['artifact'], f'{jaildir}/plugin')
  File "/usr/local/lib/python3.6/site-packages/iocage/lib/ioc_plugin.py", line 1233, in __clone_repo
    remote_refs = client.fetch(repo_url, local)
  File "/usr/local/lib/python3.6/site-packages/dulwich/client.py", line 363, in fetch
    progress)
  File "/usr/local/lib/python3.6/site-packages/dulwich/client.py", line 1398, in fetch_pack
    b"git-upload-pack", url)
  File "/usr/local/lib/python3.6/site-packages/dulwich/client.py", line 1282, in _discover_references
    resp, read = self._http_request(url, headers, allow_compression=True)
  File "/usr/local/lib/python3.6/site-packages/dulwich/client.py", line 1267, in _http_request
    (e.code, url))
dulwich.errors.GitProtocolError: unexpected http response 401 for https://github.com/freenas/iocage-plugin-sabnzbd/info/refs?service=git-upload-pack
"""

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/middlewared/job.py", line 332, in run
    await self.future
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
    return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/job.py", line 356, in __run_body
    rv = await self.middleware._call_worker(self.serviceobj, self.method_name, *self.args, job={'id': self.id})
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
    return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 977, in _call_worker
    job,
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
    return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 908, in run_in_proc
    return await self.run_in_executor(self.__procpool, method, *args, **kwargs)
  File "/usr/local/lib/python3.6/asyncio/coroutines.py", line 129, in throw
    return self.gen.throw(type, value, traceback)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 902, in run_in_executor
    return await loop.run_in_executor(pool, functools.partial(method, *args, **kwargs))
dulwich.errors.GitProtocolError: unexpected http response 401 for https://github.com/freenas/iocage-plugin-sabnzbd/info/refs?service=git-upload-pack

 

[dlavigne]

Please create a new ticket at bugs.freenas.org and post the issue number here.

 

[dlavigne]

Please create a new ticket at bugs.freenas.org and post the issue number here.

Looks like someone, possibly you, already did: https://redmine.ixsystems.com/issues/40416. Note that the ticket will be hidden until the dev has a chance to review and remove the reporter's debug.

 

[brunopereira81]

Bug #37806 seems to be used to track this an similar issues.