Channel doesn't exist?

[rafaeln]

After upgrading to the latest commit, I started getting this error...

Error detected while processing function <SNR>94_check_changes[4]..<SNR>94_on_changed[13]..<SNR>94_notify_core_channel:
line    6:
E475: Invalid argument: Channel doesn't exist

[rafaeln]

I reverted back to 5928696 and stopped getting the error.

[roxma]

It's not happening here, would you provide the content's of nvim.log_py3_cm_core after starting neovim with these environment setting?

NVIM_PYTHON_LOG_FILE=nvim.log NVIM_NCM_LOG_LEVEL=INFO nvim

[rafaeln]

Sure. This generated a few files:

nvim.log_py3_cm_core

2017-03-03 09:23:31,282 [INFO @ cm_start.py:start_channel:55] 9850 - start_channel for cm_core
2017-03-03 09:23:31,283 [INFO @ cm_start.py:setup_neovim:106] 9850 - connecting to neovim server: /tmp/nvim0TZi4P/0
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for html
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for xhtml
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for php
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for blade
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for jinja
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for jinja2
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for vue.html.javascript.css
2017-03-03 09:23:31,319 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.html_scoper> imported for vue
2017-03-03 09:23:31,320 [INFO @ cm_core.py:__init__:69] 9850 - scoper <cm_scopers.markdown_scoper> imported for markdown
2017-03-03 09:23:31,322 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 5, 'name': 'cm-bufkeyword', 'channel': {'events': ['CursorHold', 'CursorHoldI', 'BufEnter', 'BufWritePost', 'TextChangedI'], 'type': 'python3', 'module': 'cm_sources.cm_bufkeyword'}, 'enable': True, 'abbreviation': 'Key'}
2017-03-03 09:23:31,323 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_bufkeyword> registered
2017-03-03 09:23:31,323 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 6, 'name': 'cm-filepath', 'enable': True, 'cm_refresh_patterns': ['[0-9a-zA-Z_\\-\\.\\\\\\/~\\$]{4,}$', '(\\.[\\/\\\\]|[a-zA-Z]:\\\\|~\\/)[0-9a-zA-Z_\\-\\.\\\\\\/~\\$]*$'], 'channel': {'events': [], 'type': 'python3', 'module': 'cm_sources.cm_filepath'}, 'abbreviation': 'path'}
2017-03-03 09:23:31,324 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_filepath> registered
2017-03-03 09:23:31,324 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 9, 'name': 'cm-gocode', 'enable': True, 'scopes': ['go'], 'cm_refresh_patterns': ['\\.(\\w*)$'], 'channel': {'events': [], 'type': 'python3', 'module': 'cm_sources.cm_gocode'}, 'scoping': True, 'abbreviation': 'Go'}
2017-03-03 09:23:31,325 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_gocode> registered
2017-03-03 09:23:31,325 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 9, 'name': 'cm-jedi', 'enable': True, 'scopes': ['python'], 'cm_refresh_patterns': ['^(import|from).*?\\s(\\w*)$', '\\.\\w*$', '\\(\\s?(\\w*)$', ',\\s?(\\w*)$'], 'channel': {'events': [], 'type': 'python3', 'module': 'cm_sources.cm_jedi'}, 'scoping': True, 'abbreviation': 'Py'}
2017-03-03 09:23:31,326 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_jedi> registered
2017-03-03 09:23:31,328 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 4, 'name': 'cm-keyword-continue', 'cm_refresh_patterns': ['\\s+$', '^$'], 'word_pattern': '\\S+', 'enable': True, 'sort': 0, 'channel': {'events': [], 'type': 'python3', 'module': 'cm_sources.cm_keyword_continue'}, 'abbreviation': ''}
2017-03-03 09:23:31,328 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_keyword_continue> registered
2017-03-03 09:23:31,329 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 6, 'name': 'cm-tags', 'channel': {'events': ['WinEnter'], 'type': 'python3', 'module': 'cm_sources.cm_tags'}, 'enable': True, 'abbreviation': 'Tag'}
2017-03-03 09:23:31,329 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_tags> registered
2017-03-03 09:23:31,330 [INFO @ cm_core.py:register_source:99] 9850 - registering source: {'priority': 4, 'name': 'cm-tmux', 'channel': {'events': ['CursorHold', 'CursorHoldI', 'FocusGained', 'BufEnter'], 'type': 'python3', 'module': 'cm_sources.cm_tmux'}, 'enable': True, 'abbreviation': 'Tmux'}
2017-03-03 09:23:31,330 [INFO @ cm_core.py:__init__:113] 9850 - source <cm_sources.cm_tmux> registered
2017-03-03 09:23:31,331 [INFO @ cm_core.py:__init__:120] 9850 - _subscope_detectors: {'xhtml': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'blade': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'vue': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'jinja': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'markdown': [<cm_scopers.markdown_scoper.Scoper object at 0x7fa11f8f2b70>], 'html': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'vue.html.javascript.css': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'php': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>], 'jinja2': [<cm_scopers.html_scoper.Scoper object at 0x7fa11f8f2b38>]}
2017-03-03 09:23:31,331 [INFO @ cm_start.py:start_channel:74] 9850 - starting core, enter event loop
2017-03-03 09:23:31,331 [INFO @ cm_core.py:_start_channel:564] 9850 - starting channels for cm-bufkeyword: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-bufkeyword', 'cm_sources.cm_bufkeyword', '/tmp/nvim0TZi4P/0']
2017-03-03 09:23:31,332 [INFO @ cm_core.py:_start_channel:570] 9850 - pid: 9852
2017-03-03 09:23:31,334 [INFO @ cm_core.py:_start_channel:564] 9850 - starting channels for cm-filepath: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-filepath', 'cm_sources.cm_filepath', '/tmp/nvim0TZi4P/0']
2017-03-03 09:23:31,336 [INFO @ cm_core.py:_start_channel:570] 9850 - pid: 9853
2017-03-03 09:23:31,339 [INFO @ cm_core.py:_start_channel:564] 9850 - starting channels for cm-keyword-continue: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-keyword-continue', 'cm_sources.cm_keyword_continue', '/tmp/nvim0TZi4P/0']
2017-03-03 09:23:31,344 [INFO @ cm_core.py:_start_channel:570] 9850 - pid: 9854
2017-03-03 09:23:31,345 [INFO @ cm_core.py:_start_channel:564] 9850 - starting channels for cm-tags: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-tags', 'cm_sources.cm_tags', '/tmp/nvim0TZi4P/0']
2017-03-03 09:23:31,348 [INFO @ cm_core.py:_start_channel:570] 9850 - pid: 9855
2017-03-03 09:23:31,350 [INFO @ cm_core.py:_start_channel:564] 9850 - starting channels for cm-tmux: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-tmux', 'cm_sources.cm_tmux', '/tmp/nvim0TZi4P/0']
2017-03-03 09:23:31,384 [INFO @ cm_core.py:_start_channel:570] 9850 - pid: 9856
2017-03-03 09:23:32,672 [INFO @ cm_core.py:cm_refresh:335] 9850 - notify_sources_to_refresh calls cnt [0], channels cnt [1]
2017-03-03 09:23:32,725 [INFO @ cm_core.py:_refresh_completions:415] 9850 - _refresh_completions names: [], startcol: 1, matches: []
2017-03-03 09:23:32,725 [INFO @ cm_core.py:_complete:525] 9850 - matches==0, _last_matches==0, ignore
2017-03-03 09:23:33,166 [INFO @ cm_core.py:cm_refresh:335] 9850 - notify_sources_to_refresh calls cnt [0], channels cnt [5]
2017-03-03 09:23:33,218 [INFO @ cm_core.py:_refresh_completions:479] 9850 - _refresh_completions names: ['cm-filepath', 'cm-bufkeyword'], startcol: 1, matches cnt: 0
2017-03-03 09:23:33,218 [INFO @ cm_core.py:_complete:525] 9850 - matches==0, _last_matches==0, ignore
2017-03-03 09:23:33,250 [INFO @ cm_core.py:cm_refresh:335] 9850 - notify_sources_to_refresh calls cnt [0], channels cnt [3]
2017-03-03 09:23:33,301 [INFO @ cm_core.py:_refresh_completions:479] 9850 - _refresh_completions names: ['cm-filepath', 'cm-bufkeyword'], startcol: 2, matches cnt: 0
2017-03-03 09:23:33,302 [INFO @ cm_core.py:_complete:525] 9850 - matches==0, _last_matches==0, ignore
2017-03-03 09:23:33,354 [INFO @ cm_core.py:cm_refresh:335] 9850 - notify_sources_to_refresh calls cnt [1], channels cnt [3]
2017-03-03 09:23:33,405 [INFO @ cm_core.py:_refresh_completions:479] 9850 - _refresh_completions names: ['cm-filepath', 'cm-bufkeyword'], startcol: 3, matches cnt: 0
2017-03-03 09:23:33,405 [INFO @ cm_core.py:_complete:525] 9850 - matches==0, _last_matches==0, ignore
2017-03-03 09:23:33,445 [INFO @ cm_core.py:cm_refresh:335] 9850 - notify_sources_to_refresh calls cnt [1], channels cnt [3]
2017-03-03 09:23:33,501 [INFO @ cm_core.py:_refresh_completions:479] 9850 - _refresh_completions names: ['cm-filepath', 'cm-bufkeyword'], startcol: 1, matches cnt: 1
2017-03-03 09:23:33,745 [INFO @ cm_core.py:cm_refresh:335] 9850 - notify_sources_to_refresh calls cnt [1], channels cnt [3]
2017-03-03 09:23:34,790 [INFO @ cm_core.py:cm_shutdown:586] 9850 - terminating channel cm-filepath
2017-03-03 09:23:34,790 [INFO @ cm_core.py:cm_shutdown:586] 9850 - terminating channel cm-keyword-continue
2017-03-03 09:23:34,790 [INFO @ cm_core.py:cm_shutdown:586] 9850 - terminating channel cm-bufkeyword
2017-03-03 09:23:34,790 [INFO @ cm_core.py:cm_shutdown:586] 9850 - terminating channel cm-tmux
2017-03-03 09:23:34,790 [INFO @ cm_core.py:cm_shutdown:586] 9850 - terminating channel cm-tags
2017-03-03 09:23:35,795 [INFO @ cm_core.py:cm_shutdown:601] 9850 - channel cm-filepath has terminated
2017-03-03 09:23:35,796 [INFO @ cm_core.py:cm_shutdown:601] 9850 - channel cm-keyword-continue has terminated
2017-03-03 09:23:35,796 [INFO @ cm_core.py:cm_shutdown:601] 9850 - channel cm-bufkeyword has terminated
2017-03-03 09:23:35,796 [INFO @ cm_core.py:cm_shutdown:601] 9850 - channel cm-tmux has terminated
2017-03-03 09:23:35,796 [INFO @ cm_core.py:cm_shutdown:601] 9850 - channel cm-tags has terminated

nvim.log_py3_cm_core

2017-03-03 09:23:31,626 [INFO @ cm_start.py:start_channel:55] 9852 - start_channel for cm_sources.cm_bufkeyword
2017-03-03 09:23:31,627 [INFO @ cm_start.py:setup_neovim:106] 9852 - connecting to neovim server: /tmp/nvim0TZi4P/0
2017-03-03 09:23:31,645 [INFO @ cm_bufkeyword.py:refresh_keyword:44] 9852 - refresh_keyword
2017-03-03 09:23:31,648 [INFO @ cm_bufkeyword.py:refresh_keyword:51] 9852 - keyword refresh complete, count: 8
2017-03-03 09:23:31,648 [INFO @ cm_start.py:start_channel:94] 9852 - handler created, entering event loop

nvim.log_py3_cm_core

2017-03-03 09:23:31,666 [INFO @ cm_start.py:start_channel:55] 9853 - start_channel for cm_sources.cm_filepath
2017-03-03 09:23:31,667 [INFO @ cm_start.py:setup_neovim:106] 9853 - connecting to neovim server: /tmp/nvim0TZi4P/0
2017-03-03 09:23:31,681 [INFO @ cm_start.py:start_channel:94] 9853 - handler created, entering event loop

nvim.log_py3_cm_core

2017-03-03 09:23:31,588 [INFO @ cm_start.py:start_channel:55] 9854 - start_channel for cm_sources.cm_keyword_continue
2017-03-03 09:23:31,591 [INFO @ cm_start.py:setup_neovim:106] 9854 - connecting to neovim server: /tmp/nvim0TZi4P/0
2017-03-03 09:23:31,611 [INFO @ cm_start.py:start_channel:94] 9854 - handler created, entering event loop

nvim.log_py3_cm_core

2017-03-03 09:23:31,528 [INFO @ cm_start.py:start_channel:55] 9855 - start_channel for cm_sources.cm_tags
2017-03-03 09:23:31,528 [INFO @ cm_start.py:setup_neovim:106] 9855 - connecting to neovim server: /tmp/nvim0TZi4P/0
2017-03-03 09:23:31,546 [INFO @ cm_start.py:start_channel:94] 9855 - handler created, entering event loop
2017-03-03 09:23:33,168 [INFO @ cm_tags.py:cm_refresh:55] 9855 - matches len 0
2017-03-03 09:23:33,253 [INFO @ cm_tags.py:cm_refresh:55] 9855 - matches len 0
2017-03-03 09:23:33,395 [INFO @ cm_tags.py:cm_refresh:55] 9855 - matches len 0
2017-03-03 09:23:33,489 [INFO @ cm_tags.py:cm_refresh:55] 9855 - matches len 0
2017-03-03 09:23:33,782 [INFO @ cm_tags.py:cm_refresh:55] 9855 - matches len 0

nvim.log_py3_cm_core

2017-03-03 09:23:31,661 [INFO @ cm_start.py:start_channel:55] 9856 - start_channel for cm_sources.cm_tmux
2017-03-03 09:23:31,662 [INFO @ cm_start.py:setup_neovim:106] 9856 - connecting to neovim server: /tmp/nvim0TZi4P/0
2017-03-03 09:23:31,692 [INFO @ cm_start.py:start_channel:94] 9856 - handler created, entering event loop

[roxma]

These log files looks normal. I'm expecting the logs when you reproduce the previous error.

[rafaeln]

Oh, and I started nvim with NVIM_PYTHON_LOG_FILE=nvim.log NVIM_NCM_LOG_LEVEL=INFO nvim test.txt, where test.txt was:

something I would like to tell you gentlemen

|

(with the cursor being the |)

[rafaeln]

These are the logs when I reproduce the error. Weird that they look normal... :-/

[roxma]

Oh it's not normal, the NCM process starts and exits in 3 seconds, I'll look into that, thanks

[roxma]

I've add some extra logs, need your help, update to the latest version, and provide the generated log files

[rafaeln]

nvim.log_py3_cm_core

2017-03-03 10:01:07,563 [INFO @ cm_start.py:start_channel:54] 1220 - start_channel for cm_core
2017-03-03 10:01:07,564 [INFO @ cm_start.py:setup_neovim:105] 1220 - connecting to neovim server: /tmp/nvimgHNKK7/0
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for html
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for xhtml
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for php
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for blade
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for jinja
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for jinja2
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for vue.html.javascript.css
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.html_scoper> imported for vue
2017-03-03 10:01:07,610 [INFO @ cm_core.py:__init__:69] 1220 - scoper <cm_scopers.markdown_scoper> imported for markdown
2017-03-03 10:01:07,613 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 5, 'enable': True, 'abbreviation': 'Key', 'name': 'cm-bufkeyword', 'channel': {'module': 'cm_sources.cm_bufkeyword', 'events': ['CursorHold', 'CursorHoldI', 'BufEnter', 'BufWritePost', 'TextChangedI'], 'type': 'python3'}}
2017-03-03 10:01:07,614 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_bufkeyword> registered
2017-03-03 10:01:07,614 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 6, 'cm_refresh_patterns': ['[0-9a-zA-Z_\\-\\.\\\\\\/~\\$]{4,}$', '(\\.[\\/\\\\]|[a-zA-Z]:\\\\|~\\/)[0-9a-zA-Z_\\-\\.\\\\\\/~\\$]*$'], 'enable': True, 'abbreviation': 'path', 'name': 'cm-filepath', 'channel': {'module': 'cm_sources.cm_filepath', 'events': [], 'type': 'python3'}}
2017-03-03 10:01:07,616 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_filepath> registered
2017-03-03 10:01:07,616 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 9, 'cm_refresh_patterns': ['\\.(\\w*)$'], 'scopes': ['go'], 'enable': True, 'scoping': True, 'abbreviation': 'Go', 'name': 'cm-gocode', 'channel': {'module': 'cm_sources.cm_gocode', 'events': [], 'type': 'python3'}}
2017-03-03 10:01:07,617 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_gocode> registered
2017-03-03 10:01:07,618 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 9, 'cm_refresh_patterns': ['^(import|from).*?\\s(\\w*)$', '\\.\\w*$', '\\(\\s?(\\w*)$', ',\\s?(\\w*)$'], 'scopes': ['python'], 'enable': True, 'scoping': True, 'abbreviation': 'Py', 'name': 'cm-jedi', 'channel': {'module': 'cm_sources.cm_jedi', 'events': [], 'type': 'python3'}}
2017-03-03 10:01:07,618 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_jedi> registered
2017-03-03 10:01:07,621 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 4, 'cm_refresh_patterns': ['\\s+$', '^$'], 'enable': True, 'word_pattern': '\\S+', 'sort': 0, 'abbreviation': '', 'name': 'cm-keyword-continue', 'channel': {'module': 'cm_sources.cm_keyword_continue', 'events': [], 'type': 'python3'}}
2017-03-03 10:01:07,622 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_keyword_continue> registered
2017-03-03 10:01:07,622 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 6, 'enable': True, 'abbreviation': 'Tag', 'name': 'cm-tags', 'channel': {'module': 'cm_sources.cm_tags', 'events': ['WinEnter'], 'type': 'python3'}}
2017-03-03 10:01:07,623 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_tags> registered
2017-03-03 10:01:07,624 [INFO @ cm_core.py:register_source:99] 1220 - registering source: {'priority': 4, 'enable': True, 'abbreviation': 'Tmux', 'name': 'cm-tmux', 'channel': {'module': 'cm_sources.cm_tmux', 'events': ['CursorHold', 'CursorHoldI', 'FocusGained', 'BufEnter'], 'type': 'python3'}}
2017-03-03 10:01:07,624 [INFO @ cm_core.py:__init__:113] 1220 - source <cm_sources.cm_tmux> registered
2017-03-03 10:01:07,625 [INFO @ cm_core.py:__init__:120] 1220 - _subscope_detectors: {'markdown': [<cm_scopers.markdown_scoper.Scoper object at 0x7fad947ccf98>], 'jinja': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'xhtml': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'vue': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'vue.html.javascript.css': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'php': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'jinja2': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'html': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>], 'blade': [<cm_scopers.html_scoper.Scoper object at 0x7fad947ccb70>]}
2017-03-03 10:01:07,625 [INFO @ cm_start.py:start_channel:73] 1220 - starting core, enter event loop
2017-03-03 10:01:07,625 [INFO @ cm_core.py:_start_channel:564] 1220 - starting channels for cm-bufkeyword: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-bufkeyword', 'cm_sources.cm_bufkeyword', '/tmp/nvimgHNKK7/0']
2017-03-03 10:01:07,627 [INFO @ cm_core.py:_start_channel:570] 1220 - pid: 1221
2017-03-03 10:01:07,628 [INFO @ cm_core.py:_start_channel:564] 1220 - starting channels for cm-filepath: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-filepath', 'cm_sources.cm_filepath', '/tmp/nvimgHNKK7/0']
2017-03-03 10:01:07,637 [INFO @ cm_core.py:_start_channel:570] 1220 - pid: 1222
2017-03-03 10:01:07,639 [INFO @ cm_core.py:_start_channel:564] 1220 - starting channels for cm-keyword-continue: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-keyword-continue', 'cm_sources.cm_keyword_continue', '/tmp/nvimgHNKK7/0']
2017-03-03 10:01:07,649 [INFO @ cm_core.py:_start_channel:570] 1220 - pid: 1223
2017-03-03 10:01:07,650 [INFO @ cm_core.py:_start_channel:564] 1220 - starting channels for cm-tags: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-tags', 'cm_sources.cm_tags', '/tmp/nvimgHNKK7/0']
2017-03-03 10:01:07,656 [INFO @ cm_core.py:_start_channel:570] 1220 - pid: 1224
2017-03-03 10:01:07,658 [INFO @ cm_core.py:_start_channel:564] 1220 - starting channels for cm-tmux: ['python3', '/home/rafael/.config/nvim/bundle/nvim-completion-manager/pythonx/cm_start.py', 'channel', 'cm-tmux', 'cm_sources.cm_tmux', '/tmp/nvimgHNKK7/0']
2017-03-03 10:01:07,668 [INFO @ cm_core.py:_start_channel:570] 1220 - pid: 1225
2017-03-03 10:01:09,042 [INFO @ cm_core.py:cm_refresh:335] 1220 - notify_sources_to_refresh calls cnt [0], channels cnt [5]
2017-03-03 10:01:09,091 [INFO @ cm_core.py:cm_refresh:335] 1220 - notify_sources_to_refresh calls cnt [1], channels cnt [3]
2017-03-03 10:01:09,129 [INFO @ cm_start.py:run_event_loop:158] 1220 - received falsy value[None], exitting
2017-03-03 10:01:10,132 [INFO @ cm_core.py:cm_shutdown:586] 1220 - terminating channel cm-keyword-continue
2017-03-03 10:01:10,132 [INFO @ cm_core.py:cm_shutdown:586] 1220 - terminating channel cm-tmux
2017-03-03 10:01:10,132 [INFO @ cm_core.py:cm_shutdown:586] 1220 - terminating channel cm-filepath
2017-03-03 10:01:10,132 [INFO @ cm_core.py:cm_shutdown:586] 1220 - terminating channel cm-bufkeyword
2017-03-03 10:01:10,132 [INFO @ cm_core.py:cm_shutdown:586] 1220 - terminating channel cm-tags
2017-03-03 10:01:11,138 [INFO @ cm_core.py:cm_shutdown:601] 1220 - channel cm-keyword-continue has terminated
2017-03-03 10:01:11,138 [INFO @ cm_core.py:cm_shutdown:601] 1220 - channel cm-tmux has terminated
2017-03-03 10:01:11,138 [INFO @ cm_core.py:cm_shutdown:601] 1220 - channel cm-filepath has terminated
2017-03-03 10:01:11,138 [INFO @ cm_core.py:cm_shutdown:601] 1220 - channel cm-bufkeyword has terminated
2017-03-03 10:01:11,138 [INFO @ cm_core.py:cm_shutdown:601] 1220 - channel cm-tags has terminated

nvim.log_py3_cm_core

2017-03-03 10:01:07,803 [INFO @ cm_start.py:start_channel:54] 1221 - start_channel for cm_sources.cm_bufkeyword
2017-03-03 10:01:07,804 [INFO @ cm_start.py:setup_neovim:105] 1221 - connecting to neovim server: /tmp/nvimgHNKK7/0
2017-03-03 10:01:07,821 [INFO @ cm_bufkeyword.py:refresh_keyword:44] 1221 - refresh_keyword
2017-03-03 10:01:07,825 [INFO @ cm_bufkeyword.py:refresh_keyword:51] 1221 - keyword refresh complete, count: 9
2017-03-03 10:01:07,825 [INFO @ cm_start.py:start_channel:93] 1221 - handler created, entering event loop
2017-03-03 10:01:10,148 [INFO @ cm_start.py:run_event_loop:158] 1221 - received falsy value[None], exitting

nvim.log_py3_cm_core

2017-03-03 10:01:07,961 [INFO @ cm_start.py:start_channel:54] 1222 - start_channel for cm_sources.cm_filepath
2017-03-03 10:01:07,962 [INFO @ cm_start.py:setup_neovim:105] 1222 - connecting to neovim server: /tmp/nvimgHNKK7/0
2017-03-03 10:01:07,984 [INFO @ cm_start.py:start_channel:93] 1222 - handler created, entering event loop
2017-03-03 10:01:10,133 [INFO @ cm_start.py:run_event_loop:158] 1222 - received falsy value[None], exitting

nvim.log_py3_cm_core

2017-03-03 10:01:07,997 [INFO @ cm_start.py:start_channel:54] 1223 - start_channel for cm_sources.cm_keyword_continue
2017-03-03 10:01:07,997 [INFO @ cm_start.py:setup_neovim:105] 1223 - connecting to neovim server: /tmp/nvimgHNKK7/0
2017-03-03 10:01:08,009 [INFO @ cm_start.py:start_channel:93] 1223 - handler created, entering event loop
2017-03-03 10:01:10,132 [INFO @ cm_start.py:run_event_loop:158] 1223 - received falsy value[None], exitting

nvim.log_py3_cm_core

2017-03-03 10:01:07,941 [INFO @ cm_start.py:start_channel:54] 1224 - start_channel for cm_sources.cm_tags
2017-03-03 10:01:07,945 [INFO @ cm_start.py:setup_neovim:105] 1224 - connecting to neovim server: /tmp/nvimgHNKK7/0
2017-03-03 10:01:07,977 [INFO @ cm_start.py:start_channel:93] 1224 - handler created, entering event loop
2017-03-03 10:01:09,045 [INFO @ cm_tags.py:cm_refresh:55] 1224 - matches len 0
2017-03-03 10:01:09,126 [INFO @ cm_tags.py:cm_refresh:55] 1224 - matches len 0
2017-03-03 10:01:10,133 [INFO @ cm_start.py:run_event_loop:158] 1224 - received falsy value[None], exitting

nvim.log_py3_cm_core

2017-03-03 10:01:07,952 [INFO @ cm_start.py:start_channel:54] 1225 - start_channel for cm_sources.cm_tmux
2017-03-03 10:01:07,952 [INFO @ cm_start.py:setup_neovim:105] 1225 - connecting to neovim server: /tmp/nvimgHNKK7/0
2017-03-03 10:01:08,006 [INFO @ cm_start.py:start_channel:93] 1225 - handler created, entering event loop
2017-03-03 10:01:10,136 [INFO @ cm_start.py:run_event_loop:158] 1225 - received falsy value[None], exitting

[roxma]

Change the command to NVIM_PYTHON_LOG_FILE=nvim.log NVIM_PYTHON_LOG_LEVEL=DEBUG nvim test.txt to get more verbose logging,

It's somehow related to how neovim's python-client, I think

[rafaeln]

Ok. I used the new command and I'm liking to the logs. I couldn't just copy them here because Github said there were too many characters (I'm glad we're not discussing this over twitter, though).

https://www.dropbox.com/sh/cq41u8afyop4yth/AABwC1bmBLNWzgRk2JgA2WLXa?dl=0]

[roxma]

Thanks!

[rafaeln]

By the way, the most recent commit that still works fine for me is 03d4c. Which means that whatever is breaking the plugin in my set up was added in d259f

[roxma]

That's kind of a big change on async architecture,

I've reverted the changes for now.

[roxma]

I'll stick to the old style which was used before this issue happened. It should be more widely used, and well tested.

@rafaeln
Would you help me confirm that NCM is working after I've now pushed the updates?

[rafaeln]

The latest commit solved it for me! Thanks!