<div dir="ltr"><div>You just showed the trace logs from the controller, not the satellite, whereas the satellite is the one executing all interesting external commands <br></div></div><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">On Tue, Feb 4, 2020 at 9:48 AM Christophe NOUVEL &lt;<a href="mailto:christophe.nouvel@gmail.com">christophe.nouvel@gmail.com</a>&gt; wrote:<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr"><div>Ok, tried to trace.</div><div><br></div><div>No error so far, but long response time and no visible call to &#39;vgs&#39;, &#39;lvs&#39; and so on :</div><div>                                                                                                                                                                                  <br></div><div>linstor sp l generates following trace :<br></div><div><br></div><div>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.768 [grizzly-http-server-14] DEBUG LINSTOR/Controller - SYSTEM - REST access api &#39;LstStorPool&#39; from &#39;RestClient(127.0.0.1; &#39;PythonLinstor/1.0.11 (API1.0.4)&#39;)&#39;<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.769 [grizzly-http-server-14] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(127.0.0.1; &#39;PythonLinstor/1.0.11 (API1.0.4)&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Fetch thin capacity info&#39; start<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.769 [grizzly-http-server-14] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(127.0.0.1; &#39;PythonLinstor/1.0.11 (API1.0.4)&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Fetch thin capacity info&#39; end<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.769 [grizzly-http-server-14] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7070 &#39;RequestThinFreeSpace&#39; send<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.769 [grizzly-http-server-14] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7070 &#39;RequestThinFreeSpace&#39; send<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.770 [MainWorkerPool-8] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7070 answer received (seq 21247)<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.770 [MainWorkerPool-8] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7070 answer received (seq 21248)<br>Feb  4 09:37:01 px1 Controller[6784]: 09:37:01.770 [MainWorkerPool-8] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7070 complete received (seq 21249)<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.471 [grizzly-http-server-15] DEBUG LINSTOR/Controller - SYSTEM - REST access api &#39;LstStorPool&#39; from &#39;RestClient(192.168.1.1; &#39;REST::Client/273&#39;)&#39;<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.471 [grizzly-http-server-15] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.1; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Fetch thin capacity info&#39; start<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.471 [grizzly-http-server-15] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.1; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Fetch thin capacity info&#39; end<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.471 [grizzly-http-server-15] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7071 &#39;RequestThinFreeSpace&#39; send<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.472 [grizzly-http-server-15] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7071 &#39;RequestThinFreeSpace&#39; send<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.473 [MainWorkerPool-7] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7071 answer received (seq 21250)<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.473 [MainWorkerPool-7] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7071 answer received (seq 21251)<br>Feb  4 09:37:03 px1 Controller[6784]: 09:37:03.473 [MainWorkerPool-7] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7071 complete received (seq 21252)<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.039 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7069 answer received (seq 21249)<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.039 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7069 answer received (seq 21250)<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.039 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7069 complete received (seq 21251)<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.039 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.2; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Assemble storage pool list&#39; start<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.040 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.2; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Assemble storage pool list&#39; end<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.189 [grizzly-http-server-0] DEBUG LINSTOR/Controller - SYSTEM - REST access api &#39;LstStorPool&#39; from &#39;RestClient(192.168.1.2; &#39;REST::Client/273&#39;)&#39;<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.189 [grizzly-http-server-0] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.2; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Fetch thin capacity info&#39; start<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.189 [grizzly-http-server-0] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.2; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Fetch thin capacity info&#39; end<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.189 [grizzly-http-server-0] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7072 &#39;RequestThinFreeSpace&#39; send<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.189 [grizzly-http-server-0] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7072 &#39;RequestThinFreeSpace&#39; send<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.190 [MainWorkerPool-6] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7072 answer received (seq 21253)<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.190 [MainWorkerPool-6] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7072 answer received (seq 21254)<br>Feb  4 09:37:05 px1 Controller[6784]: 09:37:05.190 [MainWorkerPool-6] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px1&#39;, API call 7072 complete received (seq 21255)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.486 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7070 answer received (seq 21252)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.486 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7070 answer received (seq 21253)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.486 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7070 complete received (seq 21254)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.486 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(127.0.0.1; &#39;PythonLinstor/1.0.11 (API1.0.4)&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Assemble storage pool list&#39; start<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.487 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(127.0.0.1; &#39;PythonLinstor/1.0.11 (API1.0.4)&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Assemble storage pool list&#39; end<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.494 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7071 answer received (seq 21255)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.494 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7071 answer received (seq 21256)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.494 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer Node: &#39;px2&#39;, API call 7071 complete received (seq 21257)<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.494 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.1; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Assemble storage pool list&#39; start<br>Feb  4 09:37:13 px1 Controller[6784]: 09:37:13.494 [MainWorkerPool-2] TRACE LINSTOR/Controller - SYSTEM - Peer RestClient(192.168.1.1; &#39;REST::Client/273&#39;), Background operation &#39;LstStorPool&#39; scope &#39;Assemble storage pool list&#39; end<br></div><div><br></div><div>About 13 seconds.</div><div><br></div><div>Extract of /var/log/linstor-controller/rest-access.log :</div><div>localhost.localdomain - - [2020/Feb/04:09:37:01 +0100] &quot;GET /v1/controller/version HTTP/1.1&quot; 200 142 &quot;&quot; &quot;&quot;<br>localhost.localdomain - - [2020/Feb/04:09:37:01 +0100] &quot;GET /v1/controller/version HTTP/1.1&quot; 200 142 &quot;&quot; &quot;PythonLinstor/1.0.11 (API1.0.4)&quot;<br>192.168.1.1 - - [2020/Feb/04:09:36:43 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 1471 &quot;&quot; &quot;REST::Client/273&quot;<br>192.168.1.2 - - [2020/Feb/04:09:36:55 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 1471 &quot;&quot; &quot;REST::Client/273&quot;<br>localhost.localdomain - - [2020/Feb/04:09:37:01 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 - &quot;&quot; &quot;PythonLinstor/1.0.11 (API1.0.4)&quot;<br>192.168.1.1 - - [2020/Feb/04:09:37:03 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 1471 &quot;&quot; &quot;REST::Client/273&quot;<br>192.168.1.2 - - [2020/Feb/04:09:37:05 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 1471 &quot;&quot; &quot;REST::Client/273&quot;<br>192.168.1.1 - - [2020/Feb/04:09:37:15 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 1471 &quot;&quot; &quot;REST::Client/273&quot;<br>192.168.1.2 - - [2020/Feb/04:09:37:20 +0100] &quot;GET /v1/view/storage-pools HTTP/1.1&quot; 200 1471 &quot;&quot; &quot;REST::Client/273&quot;<br></div><div><br></div><div><br></div><div>And VM creation fails :</div><div>
<div id="gmail-m_-852444921341411965gmail-proxmoxLogView-1528-body" style="padding:0px;overflow:auto;width:794px;height:287px"><div id="gmail-m_-852444921341411965gmail-proxmoxLogView-1528-outerCt" style="width:100%;height:100%"><div id="gmail-m_-852444921341411965gmail-proxmoxLogView-1528-innerCt" style="padding:5px"><div style="font:11px/16px tahoma,arial,verdana,sans-serif;white-space:pre-wrap" id="gmail-m_-852444921341411965gmail-component-1529">TASK ERROR: unable to create VM 999 - error with cfs lock &#39;storage-drbdstorage&#39;: Could not autoplace resource vm-999-disk-1, because: [{&quot;ret_code&quot;:20185089,&quot;message&quot;:&quot;Resource &#39;vm-999-disk-1&#39; successfully autoplaced on 2 nodes&quot;,&quot;details&quot;:&quot;Used storage pool: &#39;drbdpool&#39;\nUsed nodes: &#39;px1-administration&#39;, &#39;px2-administration&#39;&quot;,&quot;obj_refs&quot;:{&quot;RscDfn&quot;:&quot;vm-999-disk-1&quot;}},{&quot;ret_code&quot;:-9223372036834589707,&quot;message&quot;:&quot;Could not find suitable node to automatically create a tie breaking resource for &#39;vm-999-disk-1&#39;.&quot;,&quot;obj_refs&quot;:{&quot;RscDfn&quot;:&quot;vm-999-disk-1&quot;}},{&quot;ret_code&quot;:20185091,&quot;message&quot;:&quot;Created resource &#39;vm-999-disk-1&#39; on &#39;px1-administration&#39;&quot;,&quot;obj_refs&quot;:{&quot;RscDfn&quot;:&quot;vm-999-disk-1&quot;}},{&quot;ret_code&quot;:20185091,&quot;message&quot;:&quot;Created resource &#39;vm-999-disk-1&#39; on &#39;px2-administration&#39;&quot;,&quot;obj_refs&quot;:{&quot;RscDfn&quot;:&quot;vm-999-disk-1&quot;}},{&quot;ret_code&quot;:20185089,&quot;message&quot;:&quot;Resource &#39;vm-999-disk-1&#39; on &#39;px2-administration&#39; ready&quot;,&quot;details&quot;:&quot;Auto-placing resource: vm-999-disk-1&quot;,&quot;obj_refs&quot;:{&quot;RscDfn&quot;:&quot;vm-999-disk-1&quot;}},{&quot;ret_code&quot;:-4611686018407201818,&quot;message&quot;:&quot;Deployed resource did not appear&quot;,&quot;details&quot;:&quot;Auto-placing resource: vm-999-disk-1&quot;,&quot;obj_refs&quot;:{&quot;RscDfn&quot;:&quot;vm-999-disk-1&quot;}}]  at /usr/share/perl5/PVE/Storage/Custom/LINSTORPlugin.pm line 285.         PVE::Storage::Custom::LINSTORPlugin::alloc_image(&quot;PVE::Storage::Custom::LINSTORPlugin&quot;, &quot;drbdstorage&quot;, HASH(0x559ccda2eae0), 999, &quot;raw&quot;, undef, 1048576) called at /usr/share/perl5/PVE/Storage.pm line 738         eval {...} called at /usr/share/perl5/PVE/Storage.pm line 738         PVE::Storage::__ANON__() called at /usr/share/perl5/PVE/Cluster.pm line 915         eval {...} called at /usr/share/perl5/PVE/Cluster.pm line 883         PVE::Cluster::__ANON__(&quot;storage-drbdstorage&quot;, undef, CODE(0x559ccda23250)) called at /usr/share/perl5/PVE/Cluster.pm line 954         PVE::Cluster::cfs_lock_storage(&quot;drbdstorage&quot;, undef, CODE(0x559ccda23250)) called at /usr/share/perl5/PVE/Storage/Plugin.pm line 390         PVE::Storage::Plugin::cluster_lock_storage(&quot;PVE::Storage::Custom::LINSTORPlugin&quot;, &quot;drbdstorage&quot;, 1, undef, CODE(0x559ccda23250)) called at /usr/share/perl5/PVE/Storage.pm line 743         PVE::Storage::vdisk_alloc(HASH(0x559ccda2ef18), &quot;drbdstorage&quot;, 999, &quot;raw&quot;, undef, 1048576) called at /usr/share/perl5/PVE/API2/Qemu.pm line 181         PVE::API2::Qemu::__ANON__(&quot;scsi0&quot;, HASH(0x559cc73adad8)) called at /usr/share/perl5/PVE/QemuServer.pm line 3288         PVE::QemuServer::foreach_drive(HASH(0x559ccd178788), CODE(0x559ccd9a8b70)) called at /usr/share/perl5/PVE/API2/Qemu.pm line 216         eval {...} called at /usr/share/perl5/PVE/API2/Qemu.pm line 216         PVE::API2::Qemu::__ANON__(PVE::RPCEnvironment=HASH(0x559cc73adf88), &quot;root\@pam&quot;, HASH(0x559ccd178788), &quot;x86_64&quot;, HASH(0x559ccda2ef18), 999, undef, HASH(0x559ccd178788), ...) called at /usr/share/perl5/PVE/API2/Qemu.pm line 604         eval {...} called at /usr/share/perl5/PVE/API2/Qemu.pm line 602         PVE::API2::Qemu::__ANON__() called at /usr/share/perl5/PVE/Tools.pm line 203         eval {...} called at /usr/share/perl5/PVE/Tools.pm line 203         PVE::Tools::lock_file_full(&quot;/var/lock/qemu-server/lock-999.conf&quot;, 1, 0, CODE(0x559cc73ad4c0)) called at /usr/share/perl5/PVE/Tools.pm line 212         PVE::Tools::lock_file(&quot;/var/lock/qemu-server/lock-999.conf&quot;, 1, CODE(0x559cc73ad4c0)) called at /usr/share/perl5/PVE/AbstractConfig.pm line 78         PVE::AbstractConfig::lock_config_full(&quot;PVE::QemuConfig&quot;, 999, 1, CODE(0x559cc73ad4c0)) called at /usr/share/perl5/PVE/API2/Qemu.pm line 636         PVE::API2::Qemu::__ANON__() called at /usr/share/perl5/PVE/API2/Qemu.pm line 659         eval {...} called at /usr/share/perl5/PVE/API2/Qemu.pm line 659         PVE::API2::Qemu::__ANON__(&quot;UPID:px1-administration:00007D8A:005B8802:5E392A60:qmcreate:9&quot;...) called at /usr/share/perl5/PVE/RESTEnvironment.pm line 610         eval {...} called at /usr/share/perl5/PVE/RESTEnvironment.pm line 601         PVE::RESTEnvironment::fork_worker(PVE::RPCEnvironment=HASH(0x559cc73adf88), &quot;qmcreate&quot;, 999, &quot;root\@pam&quot;, CODE(0x559ccda32b88)) called at /usr/share/perl5/PVE/API2/Qemu.pm line 671         PVE::API2::Qemu::__ANON__(HASH(0x559ccd178788)) called at /usr/share/perl5/PVE/RESTHandler.pm line 446         PVE::RESTHandler::handle(&quot;PVE::API2::Qemu&quot;, HASH(0x559ccae42c38), HASH(0x559ccd178788)) called at /usr/share/perl5/PVE/HTTPServer.pm line 167         eval {...} called at /usr/share/perl5/PVE/HTTPServer.pm line 133         PVE::HTTPServer::rest_handler(PVE::HTTPServer=HASH(0x559ccd9a9860), &quot;10.31.39.192&quot;, &quot;POST&quot;, &quot;/nodes/px1-administration/qemu&quot;, HASH(0x559ccd9f6dc8), HASH(0x559ccda3ce50), &quot;extjs&quot;) called at /usr/share/perl5/PVE/APIServer/AnyEvent.pm line 712         eval {...} called at /usr/share/perl5/PVE/APIServer/AnyEvent.pm line 686         PVE::APIServer::AnyEvent::handle_api2_request(PVE::HTTPServer=HASH(0x559ccd9a9860), HASH(0x559ccda45600), HASH(0x559ccd9f6dc8), &quot;POST&quot;, &quot;/api2/extjs/nodes/px1-administration/qemu&quot;) called at /usr/share/perl5/PVE/APIServer/AnyEvent.pm line 939         eval {...} called at /usr/share/perl5/PVE/APIServer/AnyEvent.pm line 931         PVE::APIServer::AnyEvent::handle_request(PVE::HTTPServer=HASH(0x559ccd9a9860), HASH(0x559ccda45600), HASH(0x559ccd9f6dc8), &quot;POST&quot;, &quot;/api2/extjs/nodes/px1-administration/qemu&quot;) called at /usr/share/perl5/PVE/APIServer/AnyEvent.pm line 1325         PVE::APIServer::AnyEvent::__ANON__(AnyEvent::Handle=HASH(0x559ccda3b460), &quot;sockets=1\x{26}numa=0\x{26}vmid=999\x{26}name=Test-lenteur-Linstor\x{26}agent=1\x{26}s&quot;...) called at /usr/lib/x86_64-linux-gnu/perl5/5.24/AnyEvent/Handle.pm line 1522         AnyEvent::Handle::__ANON__(AnyEvent::Handle=HASH(0x559ccda3b460)) called at /usr/lib/x86_64-linux-gnu/perl5/5.24/AnyEvent/Handle.pm line 1332         AnyEvent::Handle::_drain_rbuf(AnyEvent::Handle=HASH(0x559ccda3b460)) called at /usr/lib/x86_64-linux-gnu/perl5/5.24/AnyEvent/Handle.pm line 2032         AnyEvent::Handle::__ANON__(EV::IO=SCALAR(0x559ccda43678), 1) called at /usr/lib/x86_64-linux-gnu/perl5/5.24/AnyEvent/Impl/EV.pm line 88         eval {...} called at /usr/lib/x86_64-linux-gnu/perl5/5.24/AnyEvent/Impl/EV.pm line 88         AnyEvent::CondVar::Base::_wait(AnyEvent::CondVar=HASH(0x559ccd177f18)) called at /usr/lib/x86_64-linux-gnu/perl5/5.24/AnyEvent.pm line 2026         AnyEvent::CondVar::Base::recv(AnyEvent::CondVar=HASH(0x559ccd177f18)) called at /usr/share/perl5/PVE/APIServer/AnyEvent.pm line 1612         PVE::APIServer::AnyEvent::run(PVE::HTTPServer=HASH(0x559ccd9a9860)) called at /usr/share/perl5/PVE/Service/<a href="http://pvedaemon.pm" target="_blank">pvedaemon.pm</a> line 52         PVE::Service::pvedaemon::run(PVE::Service::pvedaemon=HASH(0x559cc73bb4d8)) called at /usr/share/perl5/PVE/Daemon.pm line 171         eval {...} called at /usr/share/perl5/PVE/Daemon.pm line 171         PVE::Daemon::__ANON__(PVE::Service::pvedaemon=HASH(0x559cc73bb4d8)) called at /usr/share/perl5/PVE/Daemon.pm line 391         eval {...} called at /usr/share/perl5/PVE/Daemon.pm line 380         PVE::Daemon::__ANON__(PVE::Service::pvedaemon=HASH(0x559cc73bb4d8), undef) called at /usr/share/perl5/PVE/Daemon.pm line 552         eval {...} called at /usr/share/perl5/PVE/Daemon.pm line 550         PVE::Daemon::start(PVE::Service::pvedaemon=HASH(0x559cc73bb4d8), undef) called at /usr/share/perl5/PVE/Daemon.pm line 661         PVE::Daemon::__ANON__(HASH(0x559cc73a1410)) called at /usr/share/perl5/PVE/RESTHandler.pm line 446         PVE::RESTHandler::handle(&quot;PVE::Service::pvedaemon&quot;, HASH(0x559cc73bb7f0), HASH(0x559cc73a1410)) called at /usr/share/perl5/PVE/RESTHandler.pm line 855         eval {...} called at /usr/share/perl5/PVE/RESTHandler.pm line 838         PVE::RESTHandler::cli_handler(&quot;PVE::Service::pvedaemon&quot;, &quot;pvedaemon start&quot;, &quot;start&quot;, ARRAY(0x559cc7660518), ARRAY(0x559cc73bbe68), undef, undef, undef) called at /usr/share/perl5/PVE/CLIHandler.pm line 580         PVE::CLIHandler::__ANON__(ARRAY(0x559cc73a1638), CODE(0x559cc76212d8), undef) called at /usr/share/perl5/PVE/CLIHandler.pm line 657         PVE::CLIHandler::run_cli_handler(&quot;PVE::Service::pvedaemon&quot;, &quot;prepare&quot;, CODE(0x559cc76212d8)) called at /usr/bin/pvedaemon line 27</div><div style="font-style:normal;font-variant:normal;font-weight:normal;font-stretch:normal;font-size:11px;line-height:16px;font-size-adjust:none;font-kerning:auto;font-feature-settings:normal;white-space:pre-wrap"><span style="font-family:arial,sans-serif"><br></span></div><div style="font-style:normal;font-variant:normal;font-weight:normal;font-stretch:normal;line-height:16px;font-size-adjust:none;font-kerning:auto;font-feature-settings:normal;white-space:pre-wrap"><font size="2"><span style="font-family:arial,sans-serif">DRBD resource is correctely created, and synced. drbdtop is ok.</span></font></div><div style="font:11px/16px tahoma,arial,verdana,sans-serif;white-space:pre-wrap"><font size="2"><span style="font-family:arial,sans-serif">But Proxmox does not finishes VM creation without any Linstor error report...</span></font></div><div style="font:11px/16px tahoma,arial,verdana,sans-serif;white-space:pre-wrap"><font size="2"><span style="font-family:arial,sans-serif"><br></span></font></div><div style="font:11px/16px tahoma,arial,verdana,sans-serif;white-space:pre-wrap"><font size="2"><span style="font-family:arial,sans-serif">  Christophe.</span></font><br></div></div></div></div>

</div></div><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">Le lun. 3 févr. 2020 à 15:10, Gábor Hernádi &lt;<a href="mailto:gabor.hernadi@linbit.com" target="_blank">gabor.hernadi@linbit.com</a>&gt; a écrit :<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr"><div dir="ltr"><br></div><div>In that case you might want to increase the log level on the satellite to TRACE by adding<br></div><div><br></div><div>[logging]</div><div>   level=&quot;TRACE&quot;</div><div><br></div><div>to the linstor_satellite.toml file <br></div><div><br></div><div>With trace logging you should see every external command (lvs, vgs, lvcreate, ...) linstor executes as well as the time linstor spent waiting for the external command to finish.</div><div><br></div><div>This might give you more insight about what is taking so long<br></div><div><br>-- <br><div dir="ltr"><div dir="ltr"><div><div dir="ltr"><div>Best regards,<br></div>Gabor Hernadi<br></div></div></div></div></div></div>
_______________________________________________<br>
Star us on GITHUB: <a href="https://github.com/LINBIT" rel="noreferrer" target="_blank">https://github.com/LINBIT</a><br>
drbd-user mailing list<br>
<a href="mailto:drbd-user@lists.linbit.com" target="_blank">drbd-user@lists.linbit.com</a><br>
<a href="https://lists.linbit.com/mailman/listinfo/drbd-user" rel="noreferrer" target="_blank">https://lists.linbit.com/mailman/listinfo/drbd-user</a><br>
</blockquote></div>
</blockquote></div><br clear="all"><br>-- <br><div dir="ltr" class="gmail_signature"><div dir="ltr"><div><div dir="ltr"><div>Best regards,<br></div>Gabor Hernadi<br></div></div></div></div>