Problems synchronizing new VE

Discussion in 'Troubleshooting' started by Maciej@, Dec 8, 2006.

  1. Maciej@

    Maciej@ Guest

    Hi,
    I have installed latest Virtuozzo/HSPc with all updated VPS/OS/App templates.

    I'm trying to install VPS (VE) with latest Plesk and Site Builder inside. So I made it in 2 ways - both failed in synchronization HSPc <-> Virtuozzo

    1 way:
    ------
    I prepared VPS template with all necesarry application teplates for running Plesk 8.1.0 and SiteBuilder 3.0.2.
    It runs on Fedora Core 4.
    Then I created VPS with such template. It has been created smoothly so I decided to created 1 client account and synchronize it in HSPc.
    I created hosting plan, added all necesary app templates and so on...
    I created Hardware Node in Service Manager/Virtuozzo and it shown me a 'Conflict' -> 'VE is not registered'.
    So i tried to register this VE. Everything went fine until request of new IP address.
    My VE already has a new IP address but HSP didn't allow to use it. So I tried: not to use IP at all, assign a new IP.
    All those actions made my VPS spin down with exhausted "System" resource, specially 'privvmpages' (There was a black cross in QoS).
    I tried it many times - there was always the same problem.

    2 way
    -----
    I supposed that it will be better to create Plesk VE directly from HSP.
    Unfortunatelly I went to the same problem.

    The error log is in second thread.
     
  2. Maciej@

    Maciej@ Guest

    Here is a error log from HSP

    Here is a log:

    --------------------- CALL STACK ---------------------
    HSPC::MT::OM::VE::_report_problem(...)
    HSPC::MT::OM::VE::_operate_ve(...)
    HSPC::MT::OM::VE::sync(...)
    eval {...} at /usr/lib/perl5/site_perl/5.8.8/HSPC/Taskman/Comm.pm:322
    HSPC::Taskman::Comm::backend_execute_task(...)
    eval {...} at /usr/lib/perl5/site_perl/5.8.8/SOAP/Lite.pm:2557
    eval {...} at /usr/lib/perl5/site_perl/5.8.8/SOAP/Lite.pm:2526
    SOAP::Server::handle(...)
    SOAP::Transport::HTTP::Server::handle(...)
    SOAP::Transport::HTTP::Apache::handler(...)
    HSPC::XMLAPI::handler(...)
    eval {...} at /dev/null:0
    --------------------- /CALL STACK ---------------------

    --------------------- DEBUG_LOG ---------------------
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::Taskman::Comm::backend_execute_task] Executing task 241
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::Application::db_connection] SQL Connection got for 'MAIN': 0 => 20313
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::MT::OM::Action::write_log] Add new record to action log
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::MT::OM::Action::write_log] Task args: node_name => Plesk, node_type => HSPC::MT::OM::VE, node2_id => , module_id => 1, action_id => 2, username => Maciej Przybecki, email => mp@sylaba.pl, account_no => 1, comment => synchronizing VE Plesk, node2_type => , node_id => 104, ip_address => 193.151.36.17, node2_name =>
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::Transaction::new] TRANS [01] open Abstract->save
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::Transaction::commit] TRANS [01] commit Abstract->save
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::sync] TRACE [01]: +> HSPC::MT::OM::VE->sync ()
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::_operate_ve] TRACE [02]: . +> HSPC::MT::OM::VE->_operate_ve (operation => sync ; migr_type => ; change_ips => ; keep target => no)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_ve] TRACE [03]: . . +> HSPC::MT::OM->find_ve (ve_id=>104)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_ve] TRACE [03]: . . -> HSPC::MT::OM->find_ve (ve_id=>104) => found
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::MT::OM::VE::_operate_ve] VE: Operate VE # 104: sync, async => 0
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::_can] TRACE [03]: . . +> HSPC::MT::OM::VE->_can (operation=>sync, ve_id=>104)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::_can] TRACE [03]: . . -> HSPC::MT::OM::VE->_can (operation=>sync, ve_id=>104) => allow
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::_operate_ve] VE: Operate VE # 104, action : sync
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::Action::AUTOLOAD] [VZI/Action]: HSPC::Action: Called sync_ve('ve_id','104')
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_ve] TRACE [03]: . . +> HSPC::MT::OM->find_ve (ve_id=>104)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_ve] TRACE [03]: . . -> HSPC::MT::OM->find_ve (ve_id=>104) => found
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_hwnode] TRACE [03]: . . +> HSPC::MT::OM->find_hwnode (id=>5, ip_address=>)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_hwnode] TRACE [03]: . . -> HSPC::MT::OM->find_hwnode (id=>5, ip_address=>) => found id=>5
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::Action::call_by_hw_obj] [VZI/Action]: Calling HSPC::Action::VZAgent::sync_ve HN#5 (2)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_ve] TRACE [03]: . . +> HSPC::MT::OM->find_ve (ve_id=>104)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_ve] TRACE [03]: . . -> HSPC::MT::OM->find_ve (ve_id=>104) => found
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::VZAgent::vza_get_ve] [VZI/Common]: Requested VE object for veid=HSPC::MT::OM::VE=HASH(0x1bb1f388), hwid=
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::VZAgent::vza_get_connection] [VZI/Common]: Requested connection for hwid=5
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_hwnode] TRACE [03]: . . +> HSPC::MT::OM->find_hwnode (id=>5, ip_address=>)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_hwnode] TRACE [03]: . . -> HSPC::MT::OM->find_hwnode (id=>5, ip_address=>) => found id=>5
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::Action::VZAgent::sync_ve] [VZA]: Syncing ve 104 with database
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::Action::VZAgent::_setup] TRACE [03]: . . +> HSPC::Action::VZAgent->_setup ()
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_hwnode] TRACE [04]: . . . +> HSPC::MT::OM->find_hwnode (id=>5, ip_address=>)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::find_hwnode] TRACE [04]: . . . -> HSPC::MT::OM->find_hwnode (id=>5, ip_address=>) => found id=>5
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::Action::VZAgent::_setup] [VZA]: Set parameters for VE 104
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::get_ips] TRACE [04]: . . . +> HSPC::MT::OM::VE->get_ips (read_only=1, with_plesk_ips=1)
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::MT::OM::VE::ip_address] VE: Clear default IP () on VE # 104
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::MT::OM::VE::get_ips] Check if Plesk is installed in the VE #104
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::OM::VE::get_ips] TRACE [04]: . . . -> HSPC::MT::OM::VE->get_ips (read_only=1, with_plesk_ips=1)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_by_ve] TRACE [04]: . . . +> HSPC::MT::BillingFactory->subscr_by_ve (ve_id=>104)
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] TRACE [05]: . . . . +> HSPC::MT::BillingFactory->subscr_ve_id_by_ve (ve_id=>104)
    [2006/12/08 12:25:20] [DEBUG] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] HSPC::MT::BillingFactory->Subscr_by_ve VE_ID=>104
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] TRACE [05]: . . . . -> HSPC::MT::BillingFactory->subscr_ve_id_by_ve (ve_id=>104) => No records found
    [2006/12/08 12:25:20] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_by_ve] TRACE [04]: . . . -> HSPC::MT::BillingFactory->subscr_by_ve (ve_id=>104) => not found
    [2006/12/08 12:25:27] [WARN] [11879] [HSPC::Action::VZAgent::_setup] [VZA]: can't setup VE#104 parameters: Error invoking vzctl utility: Service http not configured
    Bad parameter for --nbh_service_add: http

    [2006/12/08 12:25:27] [DEBUG] [11879] [HSPC::VZAgent::vza_error] [VZI/Common]: VZAgent operation error: Error invoking vzctl utility: Service http not configured
    Bad parameter for --nbh_service_add: http

    [2006/12/08 12:25:27] [DEBUG] [11879] [HSPC::ErrorStack::set_last_error] [ErrorStack]: 131327 VZAgent provisioning error.
    [2006/12/08 12:25:27] [WARN] [11879] [HSPC::VZAgent::_report_problem] Error invoking vzctl utility: Service http not configured
    Bad parameter for --nbh_service_add: http

    [2006/12/08 12:25:27] [ERROR] [11879] [HSPC::VZAgent::_report_problem] Error invoking vzctl utility: Service http not configured
    Bad parameter for --nbh_service_add: http

    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::Action::VZAgent::_setup] TRACE [03]: . . -> HSPC::Action::VZAgent->_setup ()
    [2006/12/08 12:25:27] [DEBUG] [11879] [HSPC::Action::call_by_hw_obj] [VZI/Action]: Call HSPC::Action::VZAgent::sync_ve HN#5() RETURNED:[255]
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_by_ve] TRACE [03]: . . +> HSPC::MT::BillingFactory->subscr_by_ve (ve_id=>104)
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] TRACE [04]: . . . +> HSPC::MT::BillingFactory->subscr_ve_id_by_ve (ve_id=>104)
    [2006/12/08 12:25:27] [DEBUG] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] HSPC::MT::BillingFactory->Subscr_by_ve VE_ID=>104
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] TRACE [04]: . . . -> HSPC::MT::BillingFactory->subscr_ve_id_by_ve (ve_id=>104) => No records found
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_by_ve] TRACE [03]: . . -> HSPC::MT::BillingFactory->subscr_by_ve (ve_id=>104) => not found
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_by_ve] TRACE [03]: . . +> HSPC::MT::BillingFactory->subscr_by_ve (ve_id=>104)
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] TRACE [04]: . . . +> HSPC::MT::BillingFactory->subscr_ve_id_by_ve (ve_id=>104)
    [2006/12/08 12:25:27] [DEBUG] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] HSPC::MT::BillingFactory->Subscr_by_ve VE_ID=>104
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_ve_id_by_ve] TRACE [04]: . . . -> HSPC::MT::BillingFactory->subscr_ve_id_by_ve (ve_id=>104) => No records found
    [2006/12/08 12:25:27] [INFO] [11879] [HSPC::MT::BillingFactory::subscr_by_ve] TRACE [03]: . . -> HSPC::MT::BillingFactory->subscr_by_ve (ve_id=>104) => not found
    [2006/12/08 12:25:27] [DEBUG] [11879] [HSPC::ErrorStack::set_last_error] [ErrorStack]: 65791 VZAgent provisioning error.
    [2006/12/08 12:25:27] [WARN] [11879] [HSPC::MT::OM::VE::_report_problem] VE: sync on VE # 104 fails with code 255
    [2006/12/08 12:25:27] [ERROR] [11879] [HSPC::MT::OM::VE::_report_problem] Error occured while operating (sync) with VE 104
    --------------------- /DEBUG_LOG ---------------------
     
  3. blinov

    blinov Guest

    Maciej,

    It's very likely that you're trying to operate VPS with Name based hosting enabled on Virtuozzo node which doesn't have it configured.

    Could you open a support ticket in order our team to take a look on your system, please?

    Regards,
    Alex.
     
  4. Maciej@

    Maciej@ Guest

    Sure, but I didn't find a link to open support ticket on SWsoft site ;-). Sorry for dumb question... but could you please point it to me ?
     

Share This Page