Upgrade from 9.0 to 9.1 fails #336

Closed
jvillafanez opened this Issue Apr 26, 2016 · 31 comments

Projects

None yet

6 participants

@jvillafanez

Steps to reproduce

  1. Install daily stable9 tarball (9.0.1 (daily) Build:2016-04-26T03:20:01+00:00 6c861f71eb62e9cee2617a472bcd6d37bfd7b899)
  2. Make sure the updater downloads the daily master package (*)
  3. Run the updater

Expected behaviour

Updater upgrades to 9.1

Actual behaviour

Upgrade fails

Server configuration

Operating system: ubuntu 14.04

Web server: apache 2.4.7

Database: mysql

PHP version: 5.5.9

ownCloud version: 9.1.0 pre alpha 2016-04-26T03:08:09+00:00 d8f327831e6f64658c50131c2e7344ee6fa0e86a

Updated from an older ownCloud or fresh install: updated from 9.0.1 daily

Updater trace

ownCloud updater 1.0 - CLI based ownCloud server upgrades
Checking system health.
 - file permissions are ok.
Current version is 9.0.1.3
Online version is 100.0.0.0 [daily]
What would you do next?
  [0] download
  [1] upgrade
  [2] abort
 > 1
Downloaded 100% (35076540 of 35076540)
Created checkpoint 9.0.1.3-571f8686a86f2
 simulated (optionally, can be done online in advance)
real [danger, might take long]
Extracting source into /opt/owncloud/data/updater-data/_oc_upgrade/100.0.0.0
ownCloud or one of the apps require upgrade - only a limited number of commands are available
You may use your browser or the occ upgrade command to do the upgrade
Set log level to debug
Turned on maintenance mode
Checking whether the database schema can be updated (this can take a long time depending on the database size)


Checked database schema update
Checking updates of apps
Checked database schema update for apps
Updating database schema
Updated database
Starting code integrity check...
Finished code integrity check
Update successful
Turned off maintenance mode
Reset log level

Upgrading the application activity
PHP Fatal error:  Call to protected method Owncloud\Updater\Utils\FilesystemHelper::rmdirr() from context 'Owncloud\Updater\Command\UpgradeShippedAppsCommand' in /opt/owncloud/updater/src/Command/UpgradeShippedAppsCommand.php on line 75

Update.log

[info] Execution of upgrade:start command started
[info] Execution of upgrade:info command started
[info] Execution of upgrade:info command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.1.3 found
[info] Execution of upgrade:detect command stopped. Exit code is 4
[info] Execution of upgrade:start command stopped. Exit code is 0
[info] Execution of upgrade:start command started
[info] Execution of upgrade:info command started
[info] Execution of upgrade:info command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.1.3 found
[info] Execution of upgrade:detect command stopped. Exit code is 128
[info] Execution of upgrade:start command stopped. Exit code is 0
[info] Execution of upgrade:start command started
[info] Execution of upgrade:info command started
[info] Execution of upgrade:info command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.1.3 found
[info] Execution of upgrade:detect command stopped. Exit code is 0
[info] Execution of upgrade:backupDb command started
[info] Execution of upgrade:backupDb command stopped. Exit code is 0
[info] Execution of upgrade:backupData command started
[info] Execution of upgrade:backupData command stopped. Exit code is 0
[info] Execution of upgrade:checkpoint command started
[info] Execution of upgrade:checkpoint command stopped. Exit code is 0
[info] Execution of upgrade:preUpgradeRepair command started
[info] Execution of upgrade:preUpgradeRepair command stopped. Exit code is 0
[info] Execution of upgrade:dbUpgrade command started
[info] Execution of upgrade:dbUpgrade command stopped. Exit code is 0
[info] Execution of upgrade:dbUpgrade command started
[info] Execution of upgrade:dbUpgrade command stopped. Exit code is 0
[info] Execution of upgrade:disableNotShippedApps command started
[info] Execution of upgrade:disableNotShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:executeCoreUpgradeScripts command started
[debug] Replacing 3rdparty
[debug] Replacing config/config.sample.php
[debug] Replacing core
[debug] Replacing l10n
[debug] Replacing lib
[debug] Replacing ocs
[debug] Replacing ocs-provider
[debug] Replacing resources
[debug] Replacing settings
[debug] Replacing .htaccess
[debug] Replacing .mailmap
[debug] Replacing .tag
[debug] Replacing .user.ini
[debug] Replacing AUTHORS
[debug] Replacing console.php
[debug] Replacing COPYING-AGPL
[debug] Replacing cron.php
[debug] Replacing db_structure.xml
[debug] Replacing index.html
[debug] Replacing index.php
[debug] Replacing indie.json
[debug] Replacing occ
[debug] Replacing public.php
[debug] Replacing remote.php
[debug] Replacing robots.txt
[debug] Replacing status.php
[debug] Replacing version.php
[info] Execution of upgrade:executeCoreUpgradeScripts command stopped. Exit code is 0
[info] Execution of upgrade:upgradeShippedApps command started

(*) The url where the updater downloads the file changed to make sure the daily master package (9.1) is downloaded for the current version. In addition, the build date of the current version (9.0.1) was modified (it was set 2 days ago), probably needed for the server side.

Probably related to #335

@jvillafanez jvillafanez added this to the 9.0.2-current-maintenance milestone Apr 26, 2016
@VicDeo VicDeo was assigned by PVince81 Apr 26, 2016
@PVince81
Contributor

@VicDeo can you have a look ? Maybe changing the scope of rmdirr to public might do as a quickfix ?

@PVince81 PVince81 closed this in #337 Apr 26, 2016
@PVince81
Contributor

Reopening for testing in tomorrow's daily

@PVince81 PVince81 reopened this Apr 26, 2016
@PVince81
Contributor

but first we need to merge #338

If we had #334 we could have it tested today...

@PVince81 PVince81 assigned jvillafanez and unassigned VicDeo Apr 26, 2016
@PVince81
Contributor

@jvillafanez please retest with tomorrow's daily

@LukasReschke
Member

Updating to master-daily won't work from 9.0.1 or 9.0.0, please test stable9-daily. See:

21:00:32 <lukas> vincent: Mhm. Thinking about https://github.com/owncloud/updater/issues/336
21:00:41 <lukas> vincent: I think that actually will like never work and don't make sense to test.
21:00:55 <lukas> vincent: One can't directly update from 9.0.1 to master daily because of the updater issues
21:01:05 <lukas> vincent: That's why the update server will deliver 9.0.2 first 
21:01:17 <vincent> lukas: ok, then at least update to stable9 daily
21:01:21 <lukas> Yeah. That one should do.
@jvillafanez

Still failing due to other things, not really sure if it's the updater or the upgrade to 9.1

trace:

ownCloud updater 1.0 - CLI based ownCloud server upgrades
Checking system health.
 - file permissions are ok.
Current version is 9.0.2.0
Online version is 100.0.0.0 [daily]
What would you do next?
  [0] download
  [1] upgrade
  [2] abort
 > 1
Downloaded 100% (35085990 of 35085990)
Created checkpoint 9.0.2.0-572069a4c178f
 simulated (optionally, can be done online in advance)
real [danger, might take long]
Extracting source into /opt/owncloud/data/updater-data/_oc_upgrade/100.0.0.0
Set log level to debug
Turned on maintenance mode
Checking whether the database schema can be updated (this can take a long time depending on the database size)


Checked database schema update
Checking updates of apps
Checked database schema update for apps
Updating database schema
Updated database
Starting code integrity check...
Finished code integrity check
Update successful
Turned off maintenance mode
Reset log level

Upgrading the application activity



  [Symfony\Component\Process\Exception\ProcessFailedException]                 
  The command "php /opt/owncloud/occ --no-warnings upgrade" failed.            
  Exit Code: 5(Unknown error)                                                  
  Output:                                                                      
  ================                                                             
  Set log level to debug                                                       
  Turned on maintenance mode                                                   
  Checking whether the database schema can be updated (this can take a long t  
  ime depending on the database size)                                          
  Checked database schema update                                               
  Checking updates of apps                                                     
  Checking whether the database schema for <activity> can be updated (this ca  
  n take a long time depending on the database size)                           
  Checked database schema update for apps                                      
  Updating database schema                                                     
  Updated database                                                             
  Exception: files can't be disabled.                                          
  Update failed                                                                
  Maintenance mode is kept active                                              
  Reset log level                                                              
  Error Output:                                                                
   appconfig                                                                   
   appconfig                                                                   
   storages                                                                    
   mounts                                                                         
   mimetypes                                                                      
   filecache                                                                   
   group_user                                                                  
   group_admin                                                                           
   groups                                                                      
   preferences                                                                 
   properties                                                                  
   share                                                                       
   jobs                                                                        
   users                                                                       
   vcategory                                                                   
   vcategory_to_object                                                         
   systemtag                                                                          
   systemtag_object_mapping                                                           
   privatedata                                                                 
   file_locks                                                                  
   comments                                                                        
   comments_read_markers                                                           
   credentials                               activity                          

   activity                                                                    
   activity_mq                                                                            
   2/2 [============================] 100%                                     



upgrade:upgradeShippedApps

update.log

[info] Execution of upgrade:start command started
[info] Execution of upgrade:info command started
[info] Execution of upgrade:info command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.2.0 found
[info] Execution of upgrade:detect command stopped. Exit code is 4
[info] Execution of upgrade:start command stopped. Exit code is 0
[info] Execution of upgrade:start command started
[info] Execution of upgrade:info command started
[info] Execution of upgrade:info command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.2.0 found
[info] Execution of upgrade:detect command stopped. Exit code is 0
[info] Execution of upgrade:backupDb command started
[info] Execution of upgrade:backupDb command stopped. Exit code is 0
[info] Execution of upgrade:backupData command started
[info] Execution of upgrade:backupData command stopped. Exit code is 0
[info] Execution of upgrade:checkpoint command started
[info] Execution of upgrade:checkpoint command stopped. Exit code is 0
[info] Execution of upgrade:preUpgradeRepair command started
[info] Execution of upgrade:preUpgradeRepair command stopped. Exit code is 0
[info] Execution of upgrade:dbUpgrade command started
[info] Execution of upgrade:dbUpgrade command stopped. Exit code is 0
[info] Execution of upgrade:dbUpgrade command started
[info] Execution of upgrade:dbUpgrade command stopped. Exit code is 0
[info] Execution of upgrade:disableNotShippedApps command started
[info] Execution of upgrade:disableNotShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:executeCoreUpgradeScripts command started
[debug] Replacing 3rdparty
[debug] Replacing config/config.sample.php
[debug] Replacing core
[debug] Replacing l10n
[debug] Replacing lib
[debug] Replacing ocs
[debug] Replacing ocs-provider
[debug] Replacing resources
[debug] Replacing settings
[debug] Replacing .htaccess
[debug] Replacing .mailmap
[debug] Replacing .tag
[debug] Replacing .user.ini
[debug] Replacing AUTHORS
[debug] Replacing console.php
[debug] Replacing COPYING-AGPL
[debug] Replacing cron.php
[debug] Replacing db_structure.xml
[debug] Replacing index.html
[debug] Replacing index.php
[debug] Replacing indie.json
[debug] Replacing occ
[debug] Replacing public.php
[debug] Replacing remote.php
[debug] Replacing robots.txt
[debug] Replacing status.php
[debug] Replacing version.php
[info] Execution of upgrade:executeCoreUpgradeScripts command stopped. Exit code is 0
[info] Execution of upgrade:upgradeShippedApps command started
[error] 


  [Symfony\Component\Process\Exception\ProcessFailedException]                 
  The command "php /opt/owncloud/occ --no-warnings upgrade" failed.            
  Exit Code: 5(Unknown error)                                                  
  Output:                                                                      
  ================                                                             
  Set log level to debug                                                       
  Turned on maintenance mode                                                   
  Checking whether the database schema can be updated (this can take a long t  
  ime depending on the database size)                                          
  Checked database schema update                                               
  Checking updates of apps                                                     
  Checking whether the database schema for <activity> can be updated (this ca  
  n take a long time depending on the database size)                           
  Checked database schema update for apps                                      
  Updating database schema                                                     
  Updated database                                                             
  Exception: files can't be disabled.                                          
  Update failed                                                                
  Maintenance mode is kept active                                              
  Reset log level                                                              
  Error Output:                                                                
   appconfig                                                                   
   appconfig                                                                   
   storages                                                                    
   mounts                                                                         
   mimetypes                                                                      
   filecache                                                                   
   group_user                                                                  
   group_admin                                                                           
   groups                                                                      
   preferences                                                                 
   properties                                                                  
   share                                                                       
   jobs                                                                        
   users                                                                       
   vcategory                                                                   
   vcategory_to_object                                                         
   systemtag                                                                          
   systemtag_object_mapping                                                           
   privatedata                                                                 
   file_locks                                                                  
   comments                                                                        
   comments_read_markers                                                           
   credentials                               activity                          

   activity                                                                    
   activity_mq                                                                            
   2/2 [============================] 100%                                     



Exception trace:
 () at /opt/owncloud/updater/src/Utils/OccRunner.php:50
 Owncloud\Updater\Utils\OccRunner->run() at /opt/owncloud/updater/src/Command/UpgradeShippedAppsCommand.php:82
 Owncloud\Updater\Command\UpgradeShippedAppsCommand->execute() at /opt/owncloud/updater/vendor/symfony/console/Command/Command.php:256
 Symfony\Component\Console\Command\Command->run() at /opt/owncloud/updater/vendor/symfony/console/Application.php:838
 Symfony\Component\Console\Application->doRunCommand() at /opt/owncloud/updater/src/Console/Application.php:158
 Owncloud\Updater\Console\Application->doRunCommand() at /opt/owncloud/updater/vendor/symfony/console/Application.php:189
 Symfony\Component\Console\Application->doRun() at /opt/owncloud/updater/src/Console/Application.php:135
 Owncloud\Updater\Console\Application->doRun() at /opt/owncloud/updater/src/Command/StartCommand.php:66
 Owncloud\Updater\Command\StartCommand->execute() at /opt/owncloud/updater/vendor/symfony/console/Command/Command.php:256
 Symfony\Component\Console\Command\Command->run() at /opt/owncloud/updater/vendor/symfony/console/Application.php:838
 Symfony\Component\Console\Application->doRunCommand() at /opt/owncloud/updater/src/Console/Application.php:158
 Owncloud\Updater\Console\Application->doRunCommand() at /opt/owncloud/updater/vendor/symfony/console/Application.php:189
 Symfony\Component\Console\Application->doRun() at /opt/owncloud/updater/src/Console/Application.php:135
 Owncloud\Updater\Console\Application->doRun() at /opt/owncloud/updater/vendor/symfony/console/Application.php:120
 Symfony\Component\Console\Application->run() at /opt/owncloud/updater/application.php:35


upgrade:upgradeShippedApps



[error] 


  [Symfony\Component\Process\Exception\ProcessFailedException]                 
  The command "php /opt/owncloud/occ --no-warnings upgrade" failed.            
  Exit Code: 5(Unknown error)                                                  
  Output:                                                                      
  ================                                                             
  Set log level to debug                                                       
  Turned on maintenance mode                                                   
  Checking whether the database schema can be updated (this can take a long t  
  ime depending on the database size)                                          
  Checked database schema update                                               
  Checking updates of apps                                                     
  Checking whether the database schema for <activity> can be updated (this ca  
  n take a long time depending on the database size)                           
  Checked database schema update for apps                                      
  Updating database schema                                                     
  Updated database                                                             
  Exception: files can't be disabled.                                          
  Update failed                                                                
  Maintenance mode is kept active                                              
  Reset log level                                                              
  Error Output:                                                                
   appconfig                                                                   
   appconfig                                                                   
   storages                                                                    
   mounts                                                                         
   mimetypes                                                                      
   filecache                                                                   
   group_user                                                                  
   group_admin                                                                           
   groups                                                                      
   preferences                                                                 
   properties                                                                  
   share                                                                       
   jobs                                                                        
   users                                                                       
   vcategory                                                                   
   vcategory_to_object                                                         
   systemtag                                                                          
   systemtag_object_mapping                                                           
   privatedata                                                                 
   file_locks                                                                  
   comments                                                                        
   comments_read_markers                                                           
   credentials                               activity                          

   activity                                                                    
   activity_mq                                                                            
   2/2 [============================] 100%                                     



Exception trace:
 () at /opt/owncloud/updater/src/Utils/OccRunner.php:50
 Owncloud\Updater\Utils\OccRunner->run() at /opt/owncloud/updater/src/Command/UpgradeShippedAppsCommand.php:82
 Owncloud\Updater\Command\UpgradeShippedAppsCommand->execute() at /opt/owncloud/updater/vendor/symfony/console/Command/Command.php:256
 Symfony\Component\Console\Command\Command->run() at /opt/owncloud/updater/vendor/symfony/console/Application.php:838
 Symfony\Component\Console\Application->doRunCommand() at /opt/owncloud/updater/src/Console/Application.php:158
 Owncloud\Updater\Console\Application->doRunCommand() at /opt/owncloud/updater/vendor/symfony/console/Application.php:189
 Symfony\Component\Console\Application->doRun() at /opt/owncloud/updater/src/Console/Application.php:135
 Owncloud\Updater\Console\Application->doRun() at /opt/owncloud/updater/src/Command/StartCommand.php:66
 Owncloud\Updater\Command\StartCommand->execute() at /opt/owncloud/updater/vendor/symfony/console/Command/Command.php:256
 Symfony\Component\Console\Command\Command->run() at /opt/owncloud/updater/vendor/symfony/console/Application.php:838
 Symfony\Component\Console\Application->doRunCommand() at /opt/owncloud/updater/src/Console/Application.php:158
 Owncloud\Updater\Console\Application->doRunCommand() at /opt/owncloud/updater/vendor/symfony/console/Application.php:189
 Symfony\Component\Console\Application->doRun() at /opt/owncloud/updater/src/Console/Application.php:135
 Owncloud\Updater\Console\Application->doRun() at /opt/owncloud/updater/vendor/symfony/console/Application.php:120
 Symfony\Component\Console\Application->run() at /opt/owncloud/updater/application.php:35


upgrade:upgradeShippedApps

@jvillafanez

Rechecked today:

  • Automatic upgrade from stable9 to master (I had to tweek a bit the test) worked fine
  • Updater run from stable9 to master, the same test I did yesterday, fails.

I'm worried there could be an issue with the updater, not with the upgrade process itself ๐Ÿ˜Ÿ

@PVince81
Contributor

fails

looking forward to the details ๐Ÿ˜„

@davitol
davitol commented Apr 28, 2016 edited
sudo -u www-data php application.php
ownCloud updater 1.0 - CLI based ownCloud server upgrades
Checking system health.
 - file permissions are ok.
Current version is 9.0.2.0
Online version is 100.0.0.0 [daily]
What would you do next?
  [0] download
  [1] upgrade
  [2] abort
 > 1
Downloaded 100% (35122993 of 35122993)
Created checkpoint 9.0.2.0-5721c59d905d9
 simulated (optionally, can be done online in advance)
real [danger, might take long]
Extracting source into /opt/owncloud/data/updater-data/_oc_upgrade/100.0.0.0
Set log level to debug
Turned on maintenance mode
Checking whether the database schema can be updated (this can take a long time depending on the database size)


Checked database schema update
Checking updates of apps
Checked database schema update for apps
Updating database schema
Updated database
Starting code integrity check...
Finished code integrity check
Update successful
Turned off maintenance mode
Reset log level

Upgrading the application activity



  [Symfony\Component\Process\Exception\ProcessFailedException]                                                                   
  The command "php /opt/owncloud/occ --no-warnings upgrade" failed.                                                              
  Exit Code: 5(Unknown error)                                                                                                    
  Output:                                                                                                                        
  ================                                                                                                               
  Set log level to debug                                                                                                         
  Turned on maintenance mode                                                                                                     
  Checking whether the database schema can be updated (this can take a long time depending on the database size)                 
  Checked database schema update                                                                                                 
  Checking updates of apps                                                                                                       
  Checking whether the database schema for <activity> can be updated (this can take a long time depending on the database size)  
  Checked database schema update for apps                                                                                        
  Updating database schema                                                                                                       
  Updated database                                                                                                               
  Exception: files can't be disabled.                                                                                            
  Update failed                                                                                                                  
  Maintenance mode is kept active                                                                                                
  Reset log level                                                                                                                
  Error Output:                                                                                                                  
   appconfig                                                                                                                     
   appconfig                                                                                                                     
   storages                                                                                                                      
   mounts                                                                                                                          
   mimetypes                                                                                                                       
   filecache                                                                                                                     
   group_user                                                                                                                    
   group_admin                                                                                                                             
   groups                                                                                                                        
   preferences                                                                                                                   
   properties                                                                                                                    
   share                                                                                                                         
   jobs                                                                                                                          
   users                                                                                                                         
   vcategory                                                                                                                     
   vcategory_to_object                                                                                                           
   systemtag                                                                                                                            
   systemtag_object_mapping                                                                                                             
   privatedata                                                                                                                   
   file_locks                                                                                                                    
   comments                                                                                                                          
   comments_read_markers                                                                                                             
   credentials                               activity                                                                            
   activity                                                                                                                      
   activity_mq                                                                                                                              
   2/2 [============================] 100%                                                                                       



upgrade:upgradeShippedApps

That's the output from owncloud-daily-stable9 to 9.1

@jvillafanez

Debug trace of the automatic script (don't get lost ๐Ÿ˜„ )

INFO 2016-04-28 09:35:03,733 1wnKhMWR __main__ -------------------starting app-------------------
INFO 2016-04-28 09:35:03,973 1wnKhMWR __main__ -----------------actions started------------------
INFO 2016-04-28 09:35:03,973 1wnKhMWR __main__ create_container ('server1', <function server_func at 0x7f8d88904e60>, {'OC_URL': <function new_func at 0x7f8d88904f50>}, 'ocu_lamp_qjFUzrBP')
DEBUG 2016-04-28 09:35:03,973 1wnKhMWR servers get_property server1, docker_image_name
DEBUG 2016-04-28 09:35:03,973 1wnKhMWR servers run -> image : ubuntu-lamp, environment : {'OC_ADMIN_PASS': '0ndDsDW0TA', 'OC_ADMIN_USER': 'admin', 'OC_URL': u'https://download.owncloud.org/community/daily/owncloud-daily-stable9.tar.bz2', 'OC_FQDN': '10.0.2.6'}, container_name : ocu_lamp_qjFUzrBP
DEBUG 2016-04-28 09:35:04,143 1wnKhMWR servers {u'Id': u'875d3195a9492df6fbb698505b72c2e0d74a1bc823a6817be2d0a4712592306c', u'Warnings': None}
INFO 2016-04-28 09:35:04,143 1wnKhMWR __main__ start ('server1', 'ocu_lamp_qjFUzrBP')
DEBUG 2016-04-28 09:35:04,144 1wnKhMWR servers start -> ocu_lamp_qjFUzrBP
DEBUG 2016-04-28 09:35:04,436 1wnKhMWR servers None
DEBUG 2016-04-28 09:35:04,440 1wnKhMWR servers Ignoring removal of server1 from pyocclients
DEBUG 2016-04-28 09:35:04,440 1wnKhMWR servers Ignoring removal of server1 from server_shells
INFO 2016-04-28 09:35:04,440 1wnKhMWR __main__ wait_until_ssh_connection ('server1', 'pwd', 300)
DEBUG 2016-04-28 09:35:04,441 1wnKhMWR servers wait_ssh_connection -> retries 0
DEBUG 2016-04-28 09:35:04,441 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:35:04,584 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:35:14,592 1wnKhMWR servers wait_ssh_connection -> retries 1
DEBUG 2016-04-28 09:35:14,593 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:35:14,647 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:35:24,657 1wnKhMWR servers wait_ssh_connection -> retries 2
DEBUG 2016-04-28 09:35:24,657 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:35:24,711 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:35:34,721 1wnKhMWR servers wait_ssh_connection -> retries 3
DEBUG 2016-04-28 09:35:34,721 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:35:34,783 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:35:44,784 1wnKhMWR servers wait_ssh_connection -> retries 4
DEBUG 2016-04-28 09:35:44,785 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:35:44,835 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:35:54,846 1wnKhMWR servers wait_ssh_connection -> retries 5
DEBUG 2016-04-28 09:35:54,847 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:35:54,925 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:36:04,931 1wnKhMWR servers wait_ssh_connection -> retries 6
DEBUG 2016-04-28 09:36:04,932 1wnKhMWR servers run_remote -> pwd
DEBUG 2016-04-28 09:36:04,980 1wnKhMWR servers Error creating SSH connection
Original error: [Errno None] Unable to connect to port 32771 on  or 10.0.2.6
DEBUG 2016-04-28 09:36:14,989 1wnKhMWR servers wait_ssh_connection -> retries 7
DEBUG 2016-04-28 09:36:14,990 1wnKhMWR servers run_remote -> pwd
INFO 2016-04-28 09:36:15,452 1wnKhMWR __main__ deploy_ssh_key ('server1', <function server_func at 0x7f8d88901578>, <function server_func at 0x7f8d88901500>, 'owncloud')
DEBUG 2016-04-28 09:36:15,452 1wnKhMWR servers get_property server1, spur_params:public_key_file
DEBUG 2016-04-28 09:36:15,452 1wnKhMWR servers get_property server1, spur_params:username
DEBUG 2016-04-28 09:36:15,452 1wnKhMWR servers deploy_ssh_key
INFO 2016-04-28 09:36:15,874 1wnKhMWR __main__ wait_until_http_connection {'timeout': 60, 'server_name': 'server1'}
DEBUG 2016-04-28 09:36:15,874 1wnKhMWR servers wait_http_connection -> retries 0
DEBUG 2016-04-28 09:36:15,874 1wnKhMWR servers verify_server_version
DEBUG 2016-04-28 09:36:16,184 1wnKhMWR servers <Response [200]>
DEBUG 2016-04-28 09:36:16,223 1wnKhMWR servers {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}
INFO 2016-04-28 09:36:16,224 1wnKhMWR __main__ verify_server_version ('server1', <utils.utilities.Holder object at 0x7f8d888edb90>{}, 'server_v1')
DEBUG 2016-04-28 09:36:16,224 1wnKhMWR servers verify_server_version
DEBUG 2016-04-28 09:36:16,318 1wnKhMWR servers <Response [200]>
DEBUG 2016-04-28 09:36:16,360 1wnKhMWR servers {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}
INFO 2016-04-28 09:36:16,361 1wnKhMWR __main__ run_remote_custom_server ('smashbox', ('ssh-keygen', '-R', <function server_func at 0x7f8d88901938>))
DEBUG 2016-04-28 09:36:16,361 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:36:16,361 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:36:16,361 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['ssh-keygen', '-R', '[10.0.2.6]:32771']
INFO 2016-04-28 09:36:16,992 1wnKhMWR __main__ run_remote_custom_server ('smashbox', ('sshpass', '-p', 'owncloud', 'ssh-copy-id', '-p', <function server_func at 0x7f8d88901d70>, '-o', 'CheckHostIP=no', '-o', 'StrictHostKeyChecking=no', <function server_func at 0x7f8d88901de8>))
DEBUG 2016-04-28 09:36:16,992 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:36:16,992 1wnKhMWR servers get_property server1, spur_params:username
DEBUG 2016-04-28 09:36:16,992 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:36:16,992 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['sshpass', '-p', 'owncloud', 'ssh-copy-id', '-p', '32771', '-o', 'CheckHostIP=no', '-o', 'StrictHostKeyChecking=no', 'root@10.0.2.6']
INFO 2016-04-28 09:36:17,311 1wnKhMWR __main__ run_remote ('server1', ('rm', '-f', <function server_func at 0x7f8d888e1b18>))
DEBUG 2016-04-28 09:36:17,311 1wnKhMWR servers get_property server1, remote_owncloud_folder
DEBUG 2016-04-28 09:36:17,311 1wnKhMWR servers run_remote -> ['rm', '-f', '/opt/owncloud//container_status.php']
INFO 2016-04-28 09:36:17,579 1wnKhMWR __main__ run_remote_occ ('server1', ['integrity:check-core', '--output=json'], 20, 30, <utils.utilities.Holder object at 0x7f8d888edb90>{'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}, 'integrity_result')
DEBUG 2016-04-28 09:36:17,579 1wnKhMWR servers run_remote_occ -> ['integrity:check-core', '--output=json']
INFO 2016-04-28 09:36:18,923 1wnKhMWR servers []
INFO 2016-04-28 09:36:18,947 1wnKhMWR __main__ run_python ('utils.utilities', 'crash_if_false_value', (<function new_func at 0x7f8d88904b90>, 'integrity check failed'))
DEBUG 2016-04-28 09:36:18,953 1wnKhMWR servers run_python -> module utils.utilities , method crash_if_false_value , params [True, 'integrity check failed']
DEBUG 2016-04-28 09:36:18,953 1wnKhMWR servers None
INFO 2016-04-28 09:36:18,953 1wnKhMWR __main__ run_remote_custom_server ('smashbox', ('touch', <function server_func at 0x7f8d88901e60>))
DEBUG 2016-04-28 09:36:18,956 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:36:18,956 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['touch', 'smashbox/etc/smashbox.conf']
INFO 2016-04-28 09:36:18,980 1wnKhMWR __main__ run_remote_custom_server ('smashbox', (<function server_func at 0x7f8d88901ed8>, '-k', <function server_func at 0x7f8d88901f50>, '-k', 'oc_ssl_enabled=False', '-kt', 'oc_ssl_enabled=bool', '-k', <function server_func at 0x7f8d88904050>, '-k', <function server_func at 0x7f8d889040c8>, '-k', <function server_func at 0x7f8d88904140>, '-k', <function server_func at 0x7f8d889041b8>, '-k', 'oc_account_name=user', '-k', 'oc_account_password=demo', '-k', <function server_func at 0x7f8d88904230>, '-k', <function server_func at 0x7f8d889042a8>, '-k', 'smashdir=/tmp/smashdirnpT2ucrD', '-k', <function server_func at 0x7f8d88904320>, '-kt', 'scp_port=int', '-o', '/tmp/smashboxJcFsoZQj.conf', '--no-ask'))
DEBUG 2016-04-28 09:36:18,981 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:36:18,982 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:36:18,982 1wnKhMWR servers get_property server1, http_port
DEBUG 2016-04-28 09:36:18,982 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_sync_cmd
DEBUG 2016-04-28 09:36:18,982 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, spur_params:username
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_USER
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_PASS
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, owncloud_root_uri_path
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, owncloud_root_uri_path
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:36:18,983 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['smashbox/bin/config_gen', '-k', 'oc_server=10.0.2.6:32770', '-k', 'oc_ssl_enabled=False', '-kt', 'oc_ssl_enabled=bool', '-k', 'oc_sync_cmd=/usr/bin/owncloudcmd --trust', '-k', 'oc_server_shell_cmd="ssh -p 32771 -t -l root 10.0.2.6"', '-k', 'oc_admin_user=admin', '-k', 'oc_admin_password=0ndDsDW0TA', '-k', 'oc_account_name=user', '-k', 'oc_account_password=demo', '-k', 'oc_root=', '-k', 'oc_webdav_endpoint=remote.php/webdav', '-k', 'smashdir=/tmp/smashdirnpT2ucrD', '-k', 'scp_port=32771', '-kt', 'scp_port=int', '-o', '/tmp/smashboxJcFsoZQj.conf', '--no-ask']
INFO 2016-04-28 09:36:19,065 1wnKhMWR __main__ run_remote_custom_server ('smashbox', (<function server_func at 0x7f8d88904398>, '-c', '/tmp/smashboxJcFsoZQj.conf', <function server_func at 0x7f8d88904410>))
DEBUG 2016-04-28 09:36:19,065 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:36:19,066 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:36:19,066 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['smashbox/bin/smash', '-c', '/tmp/smashboxJcFsoZQj.conf', 'smashbox/lib/test_basicSync.py']
INFO 2016-04-28 09:36:35,396 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'login', (<function server_func at 0x7f8d88904488>, <function server_func at 0x7f8d88904500>))
DEBUG 2016-04-28 09:36:35,396 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_USER
DEBUG 2016-04-28 09:36:35,396 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_PASS
DEBUG 2016-04-28 09:36:35,396 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method login , params ['admin', '0ndDsDW0TA']
DEBUG 2016-04-28 09:36:35,580 1wnKhMWR servers None
INFO 2016-04-28 09:36:35,581 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'mkdir', ('/foolder',))
DEBUG 2016-04-28 09:36:35,581 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method mkdir , params ['/foolder']
DEBUG 2016-04-28 09:36:35,749 1wnKhMWR servers True
INFO 2016-04-28 09:36:35,749 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'mkdir', ('/mars1',))
DEBUG 2016-04-28 09:36:35,750 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method mkdir , params ['/mars1']
DEBUG 2016-04-28 09:36:35,858 1wnKhMWR servers True
INFO 2016-04-28 09:36:35,859 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'mkdir', ('/mars1/1-27-2016',))
DEBUG 2016-04-28 09:36:35,859 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method mkdir , params ['/mars1/1-27-2016']
DEBUG 2016-04-28 09:36:35,984 1wnKhMWR servers True
INFO 2016-04-28 09:36:35,984 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'put_file', ('/foolder/foo.txt', '/tmp/tmplTVkOM'))
DEBUG 2016-04-28 09:36:35,984 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method put_file , params ['/foolder/foo.txt', '/tmp/tmplTVkOM']
DEBUG 2016-04-28 09:36:36,142 1wnKhMWR servers True
INFO 2016-04-28 09:36:36,143 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'put_file', ('/qwerty.txt', '/tmp/tmplTVkOM'))
DEBUG 2016-04-28 09:36:36,143 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method put_file , params ['/qwerty.txt', '/tmp/tmplTVkOM']
DEBUG 2016-04-28 09:36:36,307 1wnKhMWR servers True
INFO 2016-04-28 09:36:36,307 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'put_file', ('/mars1/1-27-2016/secret.txt', '/tmp/tmpzDVCXP'))
DEBUG 2016-04-28 09:36:36,308 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method put_file , params ['/mars1/1-27-2016/secret.txt', '/tmp/tmpzDVCXP']
DEBUG 2016-04-28 09:36:36,487 1wnKhMWR servers True
INFO 2016-04-28 09:36:36,487 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'share_file_with_link', ('/foolder/foo.txt',))
DEBUG 2016-04-28 09:36:36,487 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method share_file_with_link , params ['/foolder/foo.txt']
DEBUG 2016-04-28 09:36:36,604 1wnKhMWR servers PublicShare(id=1,path=/foolder/foo.txt,link=http://10.0.2.6:32770/index.php/s/XNewtOHJlIXyLLH,token=XNewtOHJlIXyLLH)
INFO 2016-04-28 09:36:36,605 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'share_file_with_link', ('/qwerty.txt',), <utils.utilities.Holder object at 0x7f8d888edb90>{'integrity_result': ('[]', ''), 'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}, 'qwerty_share_data')
DEBUG 2016-04-28 09:36:36,605 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method share_file_with_link , params ['/qwerty.txt']
DEBUG 2016-04-28 09:36:36,700 1wnKhMWR servers PublicShare(id=2,path=/qwerty.txt,link=http://10.0.2.6:32770/index.php/s/2OeCswNAwWieW38,token=2OeCswNAwWieW38)
INFO 2016-04-28 09:36:36,700 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'create_user', ('foo_user', '11111'))
DEBUG 2016-04-28 09:36:36,700 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method create_user , params ['foo_user', '11111']
DEBUG 2016-04-28 09:36:36,909 1wnKhMWR servers True
INFO 2016-04-28 09:36:36,909 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'share_file_with_user', {'perms': 31, 'path': '/foolder/foo.txt', 'user': 'foo_user'})
DEBUG 2016-04-28 09:36:36,909 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method share_file_with_user , params {'perms': 31, 'path': '/foolder/foo.txt', 'user': 'foo_user'}
DEBUG 2016-04-28 09:36:37,041 1wnKhMWR servers UserShare(id=3,path='/foolder/foo.txt',perms=31)
INFO 2016-04-28 09:36:37,041 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'share_file_with_user', {'perms': 31, 'path': '/mars1', 'user': 'foo_user'})
DEBUG 2016-04-28 09:36:37,041 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method share_file_with_user , params {'perms': 31, 'path': '/mars1', 'user': 'foo_user'}
DEBUG 2016-04-28 09:36:37,178 1wnKhMWR servers UserShare(id=4,path='/mars1',perms=31)
INFO 2016-04-28 09:36:37,179 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'create_user', ('foo_user2', '11111'))
DEBUG 2016-04-28 09:36:37,180 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method create_user , params ['foo_user2', '11111']
DEBUG 2016-04-28 09:36:37,371 1wnKhMWR servers True
INFO 2016-04-28 09:36:37,371 1wnKhMWR __main__ run_pyocclient ('server1', 'sharee', 'login', ('foo_user', '11111'))
DEBUG 2016-04-28 09:36:37,371 1wnKhMWR servers run_pyocclient -> server_name server1, client_name sharee , method login , params ['foo_user', '11111']
DEBUG 2016-04-28 09:36:38,136 1wnKhMWR servers None
INFO 2016-04-28 09:36:38,137 1wnKhMWR __main__ run_pyocclient ('server1', 'sharee', 'share_file_with_user', ('/foo.txt', 'foo_user2'))
DEBUG 2016-04-28 09:36:38,137 1wnKhMWR servers run_pyocclient -> server_name server1, client_name sharee , method share_file_with_user , params ['/foo.txt', 'foo_user2']
DEBUG 2016-04-28 09:36:38,337 1wnKhMWR servers UserShare(id=5,path='/foo.txt',perms=1)
INFO 2016-04-28 09:36:38,337 1wnKhMWR __main__ run_pyocclient ('server1', 'sharee', 'share_file_with_user', ('/mars1/1-27-2016', 'foo_user2'))
DEBUG 2016-04-28 09:36:38,337 1wnKhMWR servers run_pyocclient -> server_name server1, client_name sharee , method share_file_with_user , params ['/mars1/1-27-2016', 'foo_user2']
DEBUG 2016-04-28 09:36:38,526 1wnKhMWR servers UserShare(id=6,path='/mars1/1-27-2016',perms=1)
INFO 2016-04-28 09:36:38,527 1wnKhMWR __main__ tarball_bz2_upgrade ('server1', <function new_func at 0x7f8d88904de8>)
DEBUG 2016-04-28 09:36:38,527 1wnKhMWR servers run_remote -> ['curl', '-o', '/tmp/oc.tar.bz2', u'https://download.owncloud.org/community/daily/owncloud-daily-master.tar.bz2']
DEBUG 2016-04-28 09:37:16,258 1wnKhMWR servers run_remote -> ['stat', '-c', '%U:%G', '/opt/owncloud//status.php']
DEBUG 2016-04-28 09:37:16,337 1wnKhMWR servers run_remote -> ['bash', '-O', 'extglob', '-c', 'rm -rf /opt/owncloud//!(data|config)']
DEBUG 2016-04-28 09:37:16,733 1wnKhMWR servers run_remote -> ['find', '/opt/owncloud/', '-iname', '.*', '-maxdepth', '1', '-type', 'f', '-exec', 'rm', '{}', ';']
DEBUG 2016-04-28 09:37:16,807 1wnKhMWR servers run_remote -> ['tar', '-xmf', '/tmp/oc.tar.bz2', '-C', '/opt/owncloud/', '--strip-components=1']
DEBUG 2016-04-28 09:37:22,828 1wnKhMWR servers run_remote -> ['chown', '-R', 'www-data:www-data', '/opt/owncloud/']
DEBUG 2016-04-28 09:37:23,002 1wnKhMWR servers run_remote -> ['chmod', 'a+x', '/opt/owncloud//occ']
DEBUG 2016-04-28 09:37:23,076 1wnKhMWR servers run_remote_occ -> ['upgrade']
INFO 2016-04-28 09:37:23,364 1wnKhMWR servers ownCloud or one of the apps require upgrade - only a limited number of commands are available
INFO 2016-04-28 09:37:23,365 1wnKhMWR servers You may use your browser or the occ upgrade command to do the upgrade
INFO 2016-04-28 09:37:23,370 1wnKhMWR servers Set log level to debug
INFO 2016-04-28 09:37:23,371 1wnKhMWR servers Turned on maintenance mode
INFO 2016-04-28 09:37:23,380 1wnKhMWR servers Checking whether the database schema can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:23,407 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,455 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,572 1wnKhMWR servers Checked database schema update
INFO 2016-04-28 09:37:25,574 1wnKhMWR servers Checking updates of apps
INFO 2016-04-28 09:37:25,578 1wnKhMWR servers Checking whether the database schema for <activity> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:25,579 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,750 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,899 1wnKhMWR servers Checking whether the database schema for <dav> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:25,901 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,718 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,838 1wnKhMWR servers Checking whether the database schema for <federation> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:26,839 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,842 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,965 1wnKhMWR servers Checking whether the database schema for <files_sharing> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:26,967 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,967 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,099 1wnKhMWR servers Checking whether the database schema for <files_trashbin> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:27,100 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,101 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,267 1wnKhMWR servers Checking whether the database schema for <notifications> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:27,270 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,273 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,434 1wnKhMWR servers Checked database schema update for apps
INFO 2016-04-28 09:37:27,435 1wnKhMWR servers Updating database schema
INFO 2016-04-28 09:37:27,660 1wnKhMWR servers Updated database
INFO 2016-04-28 09:37:27,660 1wnKhMWR servers Updating <federatedfilesharing> ...
INFO 2016-04-28 09:37:27,725 1wnKhMWR servers Updated <federatedfilesharing> to 0.2.0
INFO 2016-04-28 09:37:27,727 1wnKhMWR servers Updating <gallery> ...
INFO 2016-04-28 09:37:27,738 1wnKhMWR servers Updated <gallery> to 15.0.0
INFO 2016-04-28 09:37:27,739 1wnKhMWR servers Updating <provisioning_api> ...
INFO 2016-04-28 09:37:27,757 1wnKhMWR servers Updated <provisioning_api> to 0.5.0
INFO 2016-04-28 09:37:27,758 1wnKhMWR servers Updating <updatenotification> ...
INFO 2016-04-28 09:37:27,770 1wnKhMWR servers Updated <updatenotification> to 0.2.0
INFO 2016-04-28 09:37:27,774 1wnKhMWR servers Updating <federation> ...
INFO 2016-04-28 09:37:27,987 1wnKhMWR servers Updated <federation> to 0.1.0
INFO 2016-04-28 09:37:27,988 1wnKhMWR servers Updating <files> ...
INFO 2016-04-28 09:37:28,008 1wnKhMWR servers Updated <files> to 1.5.1
INFO 2016-04-28 09:37:28,009 1wnKhMWR servers Updating <activity> ...
INFO 2016-04-28 09:37:28,228 1wnKhMWR servers Updated <activity> to 2.3.2
INFO 2016-04-28 09:37:28,229 1wnKhMWR servers Updating <dav> ...
INFO 2016-04-28 09:37:28,384 1wnKhMWR servers Updated <dav> to 0.2.3
INFO 2016-04-28 09:37:28,384 1wnKhMWR servers Updating <files_sharing> ...
INFO 2016-04-28 09:37:28,499 1wnKhMWR servers Updated <files_sharing> to 0.10.0
INFO 2016-04-28 09:37:28,501 1wnKhMWR servers Updating <files_trashbin> ...
INFO 2016-04-28 09:37:28,597 1wnKhMWR servers Updated <files_trashbin> to 0.9.0
INFO 2016-04-28 09:37:28,598 1wnKhMWR servers Updating <files_versions> ...
INFO 2016-04-28 09:37:28,603 1wnKhMWR servers Updated <files_versions> to 1.3.0
INFO 2016-04-28 09:37:28,603 1wnKhMWR servers Updating <comments> ...
INFO 2016-04-28 09:37:28,607 1wnKhMWR servers Updated <comments> to 0.3.0
INFO 2016-04-28 09:37:28,609 1wnKhMWR servers Updating <notifications> ...
INFO 2016-04-28 09:37:28,709 1wnKhMWR servers Updated <notifications> to 0.3.0
INFO 2016-04-28 09:37:28,710 1wnKhMWR servers Updating <systemtags> ...
INFO 2016-04-28 09:37:28,714 1wnKhMWR servers Updated <systemtags> to 0.3.0
INFO 2016-04-28 09:37:28,770 1wnKhMWR servers Starting code integrity check...
INFO 2016-04-28 09:37:33,234 1wnKhMWR servers Finished code integrity check
INFO 2016-04-28 09:37:33,235 1wnKhMWR servers Update successful
INFO 2016-04-28 09:37:33,236 1wnKhMWR servers Turned off maintenance mode
INFO 2016-04-28 09:37:33,237 1wnKhMWR servers Reset log level
INFO 2016-04-28 09:37:33,265 1wnKhMWR __main__ verify_server_version ('server1', <utils.utilities.Holder object at 0x7f8d888edb90>{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}, 'server_v2')
DEBUG 2016-04-28 09:37:33,266 1wnKhMWR servers verify_server_version
DEBUG 2016-04-28 09:37:33,720 1wnKhMWR servers <Response [200]>
DEBUG 2016-04-28 09:37:33,743 1wnKhMWR servers {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}
INFO 2016-04-28 09:37:33,743 1wnKhMWR __main__ run_python ('utils.utilities', 'version_compare', (<function new_func at 0x7f8d889045f0>, <function new_func at 0x7f8d889046e0>, '<'))
DEBUG 2016-04-28 09:37:33,744 1wnKhMWR servers run_python -> module utils.utilities , method version_compare , params [u'9.0.2.0', u'9.1.0.0', '<']
DEBUG 2016-04-28 09:37:33,744 1wnKhMWR servers None
INFO 2016-04-28 09:37:33,745 1wnKhMWR __main__ run_python_object (<utils.utilities.Holder object at 0x7f8d888edb90>{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v2': {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}, 'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}, 'swap_values', ('server_v1', 'server_v2', True))
DEBUG 2016-04-28 09:37:33,746 1wnKhMWR servers run_python -> object <utils.utilities.Holder object at 0x7f8d888edb90>{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v2': {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}, 'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v2': {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}, 'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}} , method swap_values , params ['server_v1', 'server_v2', True]
DEBUG 2016-04-28 09:37:33,746 1wnKhMWR servers None
INFO 2016-04-28 09:37:33,746 1wnKhMWR __main__ run_remote_occ ('server1', ['integrity:check-core', '--output=json'], 20, 30, <utils.utilities.Holder object at 0x7f8d888edb90>{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v1': {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}}, 'integrity_result')
DEBUG 2016-04-28 09:37:33,747 1wnKhMWR servers run_remote_occ -> ['integrity:check-core', '--output=json']
INFO 2016-04-28 09:37:34,960 1wnKhMWR servers []
INFO 2016-04-28 09:37:34,983 1wnKhMWR __main__ run_python ('utils.utilities', 'crash_if_false_value', (<function new_func at 0x7f8d88904b90>, 'integrity check failed'))
DEBUG 2016-04-28 09:37:34,984 1wnKhMWR servers run_python -> module utils.utilities , method crash_if_false_value , params [True, 'integrity check failed']
DEBUG 2016-04-28 09:37:34,984 1wnKhMWR servers None
INFO 2016-04-28 09:37:34,984 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'login', (<function server_func at 0x7f8d88904938>, <function server_func at 0x7f8d889049b0>))
DEBUG 2016-04-28 09:37:34,984 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_USER
DEBUG 2016-04-28 09:37:34,984 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_PASS
DEBUG 2016-04-28 09:37:34,984 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method login , params ['admin', '0ndDsDW0TA']
DEBUG 2016-04-28 09:37:35,205 1wnKhMWR servers None
INFO 2016-04-28 09:37:35,205 1wnKhMWR __main__ run_pyocclient ('server1', 'admin1', 'get_file', ('/foolder/foo.txt', '/tmp/tmpwXUPgx'))
DEBUG 2016-04-28 09:37:35,205 1wnKhMWR servers run_pyocclient -> server_name server1, client_name admin1 , method get_file , params ['/foolder/foo.txt', '/tmp/tmpwXUPgx']
DEBUG 2016-04-28 09:37:35,400 1wnKhMWR servers True
INFO 2016-04-28 09:37:35,400 1wnKhMWR __main__ run_python ('utils.utilities', 'compare_hash', ('/tmp/tmplTVkOM', '/tmp/tmpwXUPgx'))
DEBUG 2016-04-28 09:37:35,401 1wnKhMWR servers run_python -> module utils.utilities , method compare_hash , params ['/tmp/tmplTVkOM', '/tmp/tmpwXUPgx']
DEBUG 2016-04-28 09:37:35,402 1wnKhMWR servers None
INFO 2016-04-28 09:37:35,402 1wnKhMWR __main__ run_python ('utils.utilities', 'download_to_file', ('/tmp/tmp2nZqZ3', {'url': <function new_func at 0x7f8d88904aa0>, 'verify': False, 'method': 'GET', 'stream': True}))
DEBUG 2016-04-28 09:37:35,402 1wnKhMWR servers run_python -> module utils.utilities , method download_to_file , params ['/tmp/tmp2nZqZ3', {'url': 'http://10.0.2.6:32770/index.php/s/2OeCswNAwWieW38/download', 'verify': False, 'method': 'GET', 'stream': True}]
DEBUG 2016-04-28 09:37:35,539 1wnKhMWR servers None
INFO 2016-04-28 09:37:35,540 1wnKhMWR __main__ run_python ('utils.utilities', 'compare_hash', ('/tmp/tmplTVkOM', '/tmp/tmp2nZqZ3'))
DEBUG 2016-04-28 09:37:35,540 1wnKhMWR servers run_python -> module utils.utilities , method compare_hash , params ['/tmp/tmplTVkOM', '/tmp/tmp2nZqZ3']
DEBUG 2016-04-28 09:37:35,541 1wnKhMWR servers None
INFO 2016-04-28 09:37:35,541 1wnKhMWR __main__ run_pyocclient ('server1', 'sharee', 'login', ('foo_user', '11111'))
DEBUG 2016-04-28 09:37:35,541 1wnKhMWR servers run_pyocclient -> server_name server1, client_name sharee , method login , params ['foo_user', '11111']
DEBUG 2016-04-28 09:37:35,732 1wnKhMWR servers None
INFO 2016-04-28 09:37:35,732 1wnKhMWR __main__ run_pyocclient ('server1', 'sharee', 'get_file', ('/foo.txt', '/tmp/tmp9Y0HoY'))
DEBUG 2016-04-28 09:37:35,733 1wnKhMWR servers run_pyocclient -> server_name server1, client_name sharee , method get_file , params ['/foo.txt', '/tmp/tmp9Y0HoY']
DEBUG 2016-04-28 09:37:35,875 1wnKhMWR servers True
INFO 2016-04-28 09:37:35,875 1wnKhMWR __main__ run_python ('utils.utilities', 'compare_hash', ('/tmp/tmplTVkOM', '/tmp/tmp9Y0HoY'))
DEBUG 2016-04-28 09:37:35,875 1wnKhMWR servers run_python -> module utils.utilities , method compare_hash , params ['/tmp/tmplTVkOM', '/tmp/tmp9Y0HoY']
DEBUG 2016-04-28 09:37:35,876 1wnKhMWR servers None
INFO 2016-04-28 09:37:35,876 1wnKhMWR __main__ run_pyocclient ('server1', 'resharee', 'login', ('foo_user2', '11111'))
DEBUG 2016-04-28 09:37:35,876 1wnKhMWR servers run_pyocclient -> server_name server1, client_name resharee , method login , params ['foo_user2', '11111']
DEBUG 2016-04-28 09:37:36,463 1wnKhMWR servers None
INFO 2016-04-28 09:37:36,463 1wnKhMWR __main__ run_pyocclient ('server1', 'resharee', 'get_file', ('/foo.txt', '/tmp/tmpp3I67G'))
DEBUG 2016-04-28 09:37:36,463 1wnKhMWR servers run_pyocclient -> server_name server1, client_name resharee , method get_file , params ['/foo.txt', '/tmp/tmpp3I67G']
DEBUG 2016-04-28 09:37:36,634 1wnKhMWR servers True
INFO 2016-04-28 09:37:36,634 1wnKhMWR __main__ run_python ('utils.utilities', 'compare_hash', ('/tmp/tmplTVkOM', '/tmp/tmpp3I67G'))
DEBUG 2016-04-28 09:37:36,634 1wnKhMWR servers run_python -> module utils.utilities , method compare_hash , params ['/tmp/tmplTVkOM', '/tmp/tmpp3I67G']
DEBUG 2016-04-28 09:37:36,635 1wnKhMWR servers None
INFO 2016-04-28 09:37:36,635 1wnKhMWR __main__ run_pyocclient ('server1', 'resharee', 'get_file', ('/1-27-2016/secret.txt', '/tmp/tmpnct5nQ'))
DEBUG 2016-04-28 09:37:36,636 1wnKhMWR servers run_pyocclient -> server_name server1, client_name resharee , method get_file , params ['/1-27-2016/secret.txt', '/tmp/tmpnct5nQ']
DEBUG 2016-04-28 09:37:36,728 1wnKhMWR servers True
INFO 2016-04-28 09:37:36,729 1wnKhMWR __main__ run_python ('utils.utilities', 'compare_hash', ('/tmp/tmpzDVCXP', '/tmp/tmpnct5nQ'))
DEBUG 2016-04-28 09:37:36,729 1wnKhMWR servers run_python -> module utils.utilities , method compare_hash , params ['/tmp/tmpzDVCXP', '/tmp/tmpnct5nQ']
DEBUG 2016-04-28 09:37:36,730 1wnKhMWR servers None
INFO 2016-04-28 09:37:36,730 1wnKhMWR __main__ run_remote_custom_server ('smashbox', ('touch', <function server_func at 0x7f8d88901e60>))
DEBUG 2016-04-28 09:37:36,730 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:37:36,730 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['touch', 'smashbox/etc/smashbox.conf']
INFO 2016-04-28 09:37:36,738 1wnKhMWR __main__ run_remote_custom_server ('smashbox', (<function server_func at 0x7f8d88901ed8>, '-k', <function server_func at 0x7f8d88901f50>, '-k', 'oc_ssl_enabled=False', '-kt', 'oc_ssl_enabled=bool', '-k', <function server_func at 0x7f8d88904050>, '-k', <function server_func at 0x7f8d889040c8>, '-k', <function server_func at 0x7f8d88904140>, '-k', <function server_func at 0x7f8d889041b8>, '-k', 'oc_account_name=user', '-k', 'oc_account_password=demo', '-k', <function server_func at 0x7f8d88904230>, '-k', <function server_func at 0x7f8d889042a8>, '-k', 'smashdir=/tmp/smashdirnpT2ucrD', '-k', <function server_func at 0x7f8d88904320>, '-kt', 'scp_port=int', '-o', '/tmp/smashboxJcFsoZQj.conf', '--no-ask'))
DEBUG 2016-04-28 09:37:36,739 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:37:36,741 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, http_port
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_sync_cmd
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, spur_params:username
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_USER
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, docker_env:OC_ADMIN_PASS
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, owncloud_root_uri_path
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, owncloud_root_uri_path
DEBUG 2016-04-28 09:37:36,746 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:37:36,747 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['smashbox/bin/config_gen', '-k', 'oc_server=10.0.2.6:32770', '-k', 'oc_ssl_enabled=False', '-kt', 'oc_ssl_enabled=bool', '-k', 'oc_sync_cmd=/usr/bin/owncloudcmd --trust', '-k', 'oc_server_shell_cmd="ssh -p 32771 -t -l root 10.0.2.6"', '-k', 'oc_admin_user=admin', '-k', 'oc_admin_password=0ndDsDW0TA', '-k', 'oc_account_name=user', '-k', 'oc_account_password=demo', '-k', 'oc_root=', '-k', 'oc_webdav_endpoint=remote.php/webdav', '-k', 'smashdir=/tmp/smashdirnpT2ucrD', '-k', 'scp_port=32771', '-kt', 'scp_port=int', '-o', '/tmp/smashboxJcFsoZQj.conf', '--no-ask']
INFO 2016-04-28 09:37:36,826 1wnKhMWR __main__ run_remote_custom_server ('smashbox', (<function server_func at 0x7f8d88904398>, '-c', '/tmp/smashboxJcFsoZQj.conf', <function server_func at 0x7f8d88904410>))
DEBUG 2016-04-28 09:37:36,826 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:37:36,827 1wnKhMWR servers get_remote_property smashbox, smashbox_remote_base_dir
DEBUG 2016-04-28 09:37:36,827 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['smashbox/bin/smash', '-c', '/tmp/smashboxJcFsoZQj.conf', 'smashbox/lib/test_basicSync.py']
INFO 2016-04-28 09:37:56,595 1wnKhMWR __main__ -----------------actions finished-----------------
INFO 2016-04-28 09:37:56,596 1wnKhMWR __main__ -------------running cleanup actions--------------
INFO 2016-04-28 09:37:56,596 1wnKhMWR __main__ run_remote ('server1', ('cat', <function server_func at 0x7f8d88904c08>), 30)
DEBUG 2016-04-28 09:37:56,596 1wnKhMWR servers get_property server1, remote_owncloud_folder
DEBUG 2016-04-28 09:37:56,596 1wnKhMWR servers run_remote -> ['cat', '/opt/owncloud/data/owncloud.log']
WARNING 2016-04-28 09:37:56,615 1wnKhMWR servers {"reqId":"3LTTxQkNChSgxN48P5Wl","remoteAddr":"::1","app":"no app in context","message":"Exception: {\"Exception\":\"DomainException\",\"Message\":\"Contacts tables are missing. Nothing to do.\",\"Code\":0,\"Trace\":\"#0 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/migration\\\/migrateaddressbooks.php(83): OCA\\\\Dav\\\\Migration\\\\AddressBookAdapter->setup()\\n#1 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/application.php(186): OCA\\\\Dav\\\\Migration\\\\MigrateAddressbooks->setup()\\n#2 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/install.php(26): OCA\\\\Dav\\\\AppInfo\\\\Application->migrateAddressbooks()\\n#3 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/installer.php(640): include('\\\/opt\\\/owncloud\\\/a...')\\n#4 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/installer.php(591): OC_Installer::includeAppScript('\\\/opt\\\/owncloud\\\/a...')\\n#5 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/installer.php(562): OC_Installer::installShippedApp('dav')\\n#6 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/setup.php(370): OC_Installer::installShippedApps()\\n#7 \\\/opt\\\/owncloud\\\/core\\\/controller\\\/setupcontroller.php(64): OC\\\\Setup->install(Array)\\n#8 \\\/opt\\\/owncloud\\\/lib\\\/base.php(832): OC\\\\Core\\\\Controller\\\\SetupController->run(Array)\\n#9 \\\/opt\\\/owncloud\\\/index.php(39): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/migration\\\/addressbookadapter.php\",\"Line\":72}","level":3,"time":"2016-04-28T07:35:58+00:00","method":"GET","url":"\/index.php","user":"--"}
WARNING 2016-04-28 09:37:56,631 1wnKhMWR servers {"reqId":"3LTTxQkNChSgxN48P5Wl","remoteAddr":"::1","app":"no app in context","message":"Exception: {\"Exception\":\"DomainException\",\"Message\":\"Calendar tables are missing. Nothing to do.\",\"Code\":0,\"Trace\":\"#0 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/migration\\\/migratecalendars.php(84): OCA\\\\Dav\\\\Migration\\\\CalendarAdapter->setup()\\n#1 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/application.php(202): OCA\\\\Dav\\\\Migration\\\\MigrateCalendars->setup()\\n#2 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/install.php(27): OCA\\\\Dav\\\\AppInfo\\\\Application->migrateCalendars()\\n#3 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/installer.php(640): include('\\\/opt\\\/owncloud\\\/a...')\\n#4 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/installer.php(591): OC_Installer::includeAppScript('\\\/opt\\\/owncloud\\\/a...')\\n#5 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/installer.php(562): OC_Installer::installShippedApp('dav')\\n#6 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/setup.php(370): OC_Installer::installShippedApps()\\n#7 \\\/opt\\\/owncloud\\\/core\\\/controller\\\/setupcontroller.php(64): OC\\\\Setup->install(Array)\\n#8 \\\/opt\\\/owncloud\\\/lib\\\/base.php(832): OC\\\\Core\\\\Controller\\\\SetupController->run(Array)\\n#9 \\\/opt\\\/owncloud\\\/index.php(39): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/migration\\\/calendaradapter.php\",\"Line\":68}","level":3,"time":"2016-04-28T07:35:58+00:00","method":"GET","url":"\/index.php","user":"--"}
WARNING 2016-04-28 09:37:56,659 1wnKhMWR servers {"reqId":"FjuDVANyk2Zcss9+G9uv","remoteAddr":"10.0.2.6","app":"no app in context","message":"Exception: {\"Exception\":\"OCP\\\\Lock\\\\LockedException\",\"Message\":\"\\\"TEST_FILE_MODIFIED_BOTH.dat\\\" is locked\",\"Code\":0,\"Trace\":\"#0 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php(1956): OC\\\\Files\\\\View->lockPath('\\\/files\\\/TEST_FIL...', 1, false)\\n#1 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php(803): OC\\\\Files\\\\View->lockFile('\\\/files\\\/TEST_FIL...', 1)\\n#2 \\\/opt\\\/owncloud\\\/apps\\\/files_versions\\\/lib\\\/storage.php(193): OC\\\\Files\\\\View->copy('files\\\/\\\/\\\/TEST_FI...', 'files_versions\\\/...')\\n#3 \\\/opt\\\/owncloud\\\/apps\\\/files_versions\\\/lib\\\/hooks.php(60): OCA\\\\Files_Versions\\\\Storage::store('\\\/\\\/TEST_FILE_MOD...')\\n#4 [internal function]: OCA\\\\Files_Versions\\\\Hooks::write_hook(Array)\\n#5 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/hook.php(105): call_user_func(Array, Array)\\n#6 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/filechunking.php(215): OC_Hook::emit('OC_Filesystem', 'write', Array)\\n#7 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/file.php(436): OC_FileChunking->file_assemble(Object(OCA\\\\Files_Trashbin\\\\Storage), 'files\\\/TEST_FILE...', '\\\/user\\\/files\\\/\\\/TE...')\\n#8 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/file.php(103): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\File->createFileChunked(Resource id #32)\\n#9 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/directory.php(134): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\File->put(Resource id #32)\\n#10 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(1036): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\Directory->createFile('TEST_FILE_MODIF...', Resource id #32)\\n#11 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/CorePlugin.php(523): Sabre\\\\DAV\\\\Server->createFile('TEST_FILE_MODIF...', Resource id #32, NULL)\\n#12 [internal function]: Sabre\\\\DAV\\\\CorePlugin->httpPut(Object(Sabre\\\\HTTP\\\\Request), Object(Sabre\\\\HTTP\\\\Response))\\n#13 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/event\\\/lib\\\/EventEmitterTrait.php(105): call_user_func_array(Array, Array)\\n#14 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(459): Sabre\\\\Event\\\\EventEmitter->emit('method:PUT', Array)\\n#15 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(248): Sabre\\\\DAV\\\\Server->invokeMethod(Object(Sabre\\\\HTTP\\\\Request), Object(Sabre\\\\HTTP\\\\Response))\\n#16 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/v1\\\/webdav.php(55): Sabre\\\\DAV\\\\Server->exec()\\n#17 \\\/opt\\\/owncloud\\\/remote.php(138): require_once('\\\/opt\\\/owncloud\\\/a...')\\n#18 {main}\",\"File\":\"\\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php\",\"Line\":1858}","level":3,"time":"2016-04-28T07:36:27+00:00","method":"PUT","url":"\/remote.php\/webdav\/TEST_FILE_MODIFIED_BOTH.dat-chunking-2499102007-2-1","user":"user"}
WARNING 2016-04-28 09:37:56,680 1wnKhMWR servers {"reqId":"kxMwGVMVXyQZU3oMJVFG","remoteAddr":"10.0.2.6","app":"no app in context","message":"Exception: {\"Exception\":\"OCP\\\\Lock\\\\LockedException\",\"Message\":\"\\\"TEST_FILE_MODIFIED_WINNER.dat\\\" is locked\",\"Code\":0,\"Trace\":\"#0 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php(1956): OC\\\\Files\\\\View->lockPath('\\\/files\\\/TEST_FIL...', 1, false)\\n#1 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php(803): OC\\\\Files\\\\View->lockFile('\\\/files\\\/TEST_FIL...', 1)\\n#2 \\\/opt\\\/owncloud\\\/apps\\\/files_versions\\\/lib\\\/storage.php(193): OC\\\\Files\\\\View->copy('files\\\/\\\/\\\/TEST_FI...', 'files_versions\\\/...')\\n#3 \\\/opt\\\/owncloud\\\/apps\\\/files_versions\\\/lib\\\/hooks.php(60): OCA\\\\Files_Versions\\\\Storage::store('\\\/\\\/TEST_FILE_MOD...')\\n#4 [internal function]: OCA\\\\Files_Versions\\\\Hooks::write_hook(Array)\\n#5 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/hook.php(105): call_user_func(Array, Array)\\n#6 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/filechunking.php(215): OC_Hook::emit('OC_Filesystem', 'write', Array)\\n#7 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/file.php(436): OC_FileChunking->file_assemble(Object(OCA\\\\Files_Trashbin\\\\Storage), 'files\\\/TEST_FILE...', '\\\/user\\\/files\\\/\\\/TE...')\\n#8 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/file.php(103): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\File->createFileChunked(Resource id #32)\\n#9 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/directory.php(134): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\File->put(Resource id #32)\\n#10 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(1036): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\Directory->createFile('TEST_FILE_MODIF...', Resource id #32)\\n#11 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/CorePlugin.php(523): Sabre\\\\DAV\\\\Server->createFile('TEST_FILE_MODIF...', Resource id #32, NULL)\\n#12 [internal function]: Sabre\\\\DAV\\\\CorePlugin->httpPut(Object(Sabre\\\\HTTP\\\\Request), Object(Sabre\\\\HTTP\\\\Response))\\n#13 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/event\\\/lib\\\/EventEmitterTrait.php(105): call_user_func_array(Array, Array)\\n#14 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(459): Sabre\\\\Event\\\\EventEmitter->emit('method:PUT', Array)\\n#15 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(248): Sabre\\\\DAV\\\\Server->invokeMethod(Object(Sabre\\\\HTTP\\\\Request), Object(Sabre\\\\HTTP\\\\Response))\\n#16 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/v1\\\/webdav.php(55): Sabre\\\\DAV\\\\Server->exec()\\n#17 \\\/opt\\\/owncloud\\\/remote.php(138): require_once('\\\/opt\\\/owncloud\\\/a...')\\n#18 {main}\",\"File\":\"\\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php\",\"Line\":1858}","level":3,"time":"2016-04-28T07:36:27+00:00","method":"PUT","url":"\/remote.php\/webdav\/TEST_FILE_MODIFIED_WINNER.dat-chunking-3867627276-2-1","user":"user"}
WARNING 2016-04-28 09:37:56,703 1wnKhMWR servers {"reqId":"wNK7Mj1TgICk0af0V59W","remoteAddr":"10.0.2.6","app":"no app in context","message":"Exception: {\"Exception\":\"OCP\\\\Lock\\\\LockedException\",\"Message\":\"\\\"TEST_FILE_MODIFIED_LOSER.dat\\\" is locked\",\"Code\":0,\"Trace\":\"#0 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php(1956): OC\\\\Files\\\\View->lockPath('\\\/files\\\/TEST_FIL...', 1, false)\\n#1 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php(803): OC\\\\Files\\\\View->lockFile('\\\/files\\\/TEST_FIL...', 1)\\n#2 \\\/opt\\\/owncloud\\\/apps\\\/files_versions\\\/lib\\\/storage.php(193): OC\\\\Files\\\\View->copy('files\\\/\\\/\\\/TEST_FI...', 'files_versions\\\/...')\\n#3 \\\/opt\\\/owncloud\\\/apps\\\/files_versions\\\/lib\\\/hooks.php(60): OCA\\\\Files_Versions\\\\Storage::store('\\\/\\\/TEST_FILE_MOD...')\\n#4 [internal function]: OCA\\\\Files_Versions\\\\Hooks::write_hook(Array)\\n#5 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/hook.php(105): call_user_func(Array, Array)\\n#6 \\\/opt\\\/owncloud\\\/lib\\\/private\\\/filechunking.php(215): OC_Hook::emit('OC_Filesystem', 'write', Array)\\n#7 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/file.php(436): OC_FileChunking->file_assemble(Object(OCA\\\\Files_Trashbin\\\\Storage), 'files\\\/TEST_FILE...', '\\\/user\\\/files\\\/\\\/TE...')\\n#8 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/file.php(103): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\File->createFileChunked(Resource id #32)\\n#9 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/lib\\\/connector\\\/sabre\\\/directory.php(134): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\File->put(Resource id #32)\\n#10 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(1036): OCA\\\\DAV\\\\Connector\\\\Sabre\\\\Directory->createFile('TEST_FILE_MODIF...', Resource id #32)\\n#11 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/CorePlugin.php(523): Sabre\\\\DAV\\\\Server->createFile('TEST_FILE_MODIF...', Resource id #32, NULL)\\n#12 [internal function]: Sabre\\\\DAV\\\\CorePlugin->httpPut(Object(Sabre\\\\HTTP\\\\Request), Object(Sabre\\\\HTTP\\\\Response))\\n#13 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/event\\\/lib\\\/EventEmitterTrait.php(105): call_user_func_array(Array, Array)\\n#14 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(459): Sabre\\\\Event\\\\EventEmitter->emit('method:PUT', Array)\\n#15 \\\/opt\\\/owncloud\\\/3rdparty\\\/sabre\\\/dav\\\/lib\\\/DAV\\\/Server.php(248): Sabre\\\\DAV\\\\Server->invokeMethod(Object(Sabre\\\\HTTP\\\\Request), Object(Sabre\\\\HTTP\\\\Response))\\n#16 \\\/opt\\\/owncloud\\\/apps\\\/dav\\\/appinfo\\\/v1\\\/webdav.php(55): Sabre\\\\DAV\\\\Server->exec()\\n#17 \\\/opt\\\/owncloud\\\/remote.php(138): require_once('\\\/opt\\\/owncloud\\\/a...')\\n#18 {main}\",\"File\":\"\\\/opt\\\/owncloud\\\/lib\\\/private\\\/files\\\/view.php\",\"Line\":1858}","level":3,"time":"2016-04-28T07:36:30+00:00","method":"PUT","url":"\/remote.php\/webdav\/TEST_FILE_MODIFIED_LOSER.dat-chunking-3073153824-2-1","user":"user"}
WARNING 2016-04-28 09:37:56,707 1wnKhMWR servers {"reqId":"Fm1j2SPH5osxcjJdQK64","remoteAddr":"","app":"core","message":"starting upgrade from 9.0.2.0 to 9.1.0.0","level":0,"time":"2016-04-28T07:37:23+00:00","method":"--","url":"--","user":"--"}
INFO 2016-04-28 09:37:56,709 1wnKhMWR __main__ run_remote_custom_server ('smashbox', ('ssh-keygen', '-R', <function server_func at 0x7f8d88904c80>))
DEBUG 2016-04-28 09:37:56,709 1wnKhMWR servers get_property server1, owncloud_server
DEBUG 2016-04-28 09:37:56,709 1wnKhMWR servers get_property server1, ssh_port
DEBUG 2016-04-28 09:37:56,709 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['ssh-keygen', '-R', '[10.0.2.6]:32771']
INFO 2016-04-28 09:37:56,754 1wnKhMWR __main__ run_remote_custom_server ('smashbox', ('rm', '-rf', '/tmp/smashboxJcFsoZQj.conf', '/tmp/smashdirnpT2ucrD'))
DEBUG 2016-04-28 09:37:56,754 1wnKhMWR servers run_remote_custom_server -> host : 10.0.2.6 - ['rm', '-rf', '/tmp/smashboxJcFsoZQj.conf', '/tmp/smashdirnpT2ucrD']
INFO 2016-04-28 09:37:56,875 1wnKhMWR __main__ stop ('server1', 'ocu_lamp_qjFUzrBP')
DEBUG 2016-04-28 09:37:56,875 1wnKhMWR servers stop -> ocu_lamp_qjFUzrBP
INFO 2016-04-28 09:38:07,113 1wnKhMWR __main__ remove_container ('server1', 'ocu_lamp_qjFUzrBP')
DEBUG 2016-04-28 09:38:07,113 1wnKhMWR servers remove_container -> ocu_lamp_qjFUzrBP
INFO 2016-04-28 09:38:07,705 1wnKhMWR __main__ ---------------cleanup actions done---------------
INFO 2016-04-28 09:38:07,745 1wnKhMWR __main__ ------------------finishing app-------------------

Some key points:

Integrity checks passes before and after the upgrade:

INFO 2016-04-28 09:36:17,579 1wnKhMWR __main__ run_remote_occ ('server1', ['integrity:check-core', '--output=json'], 20, 30, <utils.utilities.Holder object at 0x7f8d888edb90>{'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}, 'integrity_result')
DEBUG 2016-04-28 09:36:17,579 1wnKhMWR servers run_remote_occ -> ['integrity:check-core', '--output=json']
INFO 2016-04-28 09:36:18,923 1wnKhMWR servers []
INFO 2016-04-28 09:37:33,746 1wnKhMWR __main__ run_remote_occ ('server1', ['integrity:check-core', '--output=json'], 20, 30, <utils.utilities.Holder object at 0x7f8d888edb90>{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v1': {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}}, 'integrity_result')
DEBUG 2016-04-28 09:37:33,747 1wnKhMWR servers run_remote_occ -> ['integrity:check-core', '--output=json']
INFO 2016-04-28 09:37:34,960 1wnKhMWR servers []

Upgrade goes well:

DEBUG 2016-04-28 09:37:23,076 1wnKhMWR servers run_remote_occ -> ['upgrade']
INFO 2016-04-28 09:37:23,364 1wnKhMWR servers ownCloud or one of the apps require upgrade - only a limited number of commands are available
INFO 2016-04-28 09:37:23,365 1wnKhMWR servers You may use your browser or the occ upgrade command to do the upgrade
INFO 2016-04-28 09:37:23,370 1wnKhMWR servers Set log level to debug
INFO 2016-04-28 09:37:23,371 1wnKhMWR servers Turned on maintenance mode
INFO 2016-04-28 09:37:23,380 1wnKhMWR servers Checking whether the database schema can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:23,407 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,455 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,572 1wnKhMWR servers Checked database schema update
INFO 2016-04-28 09:37:25,574 1wnKhMWR servers Checking updates of apps
INFO 2016-04-28 09:37:25,578 1wnKhMWR servers Checking whether the database schema for <activity> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:25,579 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,750 1wnKhMWR servers 
INFO 2016-04-28 09:37:25,899 1wnKhMWR servers Checking whether the database schema for <dav> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:25,901 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,718 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,838 1wnKhMWR servers Checking whether the database schema for <federation> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:26,839 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,842 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,965 1wnKhMWR servers Checking whether the database schema for <files_sharing> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:26,967 1wnKhMWR servers 
INFO 2016-04-28 09:37:26,967 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,099 1wnKhMWR servers Checking whether the database schema for <files_trashbin> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:27,100 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,101 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,267 1wnKhMWR servers Checking whether the database schema for <notifications> can be updated (this can take a long time depending on the database size)
INFO 2016-04-28 09:37:27,270 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,273 1wnKhMWR servers 
INFO 2016-04-28 09:37:27,434 1wnKhMWR servers Checked database schema update for apps
INFO 2016-04-28 09:37:27,435 1wnKhMWR servers Updating database schema
INFO 2016-04-28 09:37:27,660 1wnKhMWR servers Updated database
INFO 2016-04-28 09:37:27,660 1wnKhMWR servers Updating <federatedfilesharing> ...
INFO 2016-04-28 09:37:27,725 1wnKhMWR servers Updated <federatedfilesharing> to 0.2.0
INFO 2016-04-28 09:37:27,727 1wnKhMWR servers Updating <gallery> ...
INFO 2016-04-28 09:37:27,738 1wnKhMWR servers Updated <gallery> to 15.0.0
INFO 2016-04-28 09:37:27,739 1wnKhMWR servers Updating <provisioning_api> ...
INFO 2016-04-28 09:37:27,757 1wnKhMWR servers Updated <provisioning_api> to 0.5.0
INFO 2016-04-28 09:37:27,758 1wnKhMWR servers Updating <updatenotification> ...
INFO 2016-04-28 09:37:27,770 1wnKhMWR servers Updated <updatenotification> to 0.2.0
INFO 2016-04-28 09:37:27,774 1wnKhMWR servers Updating <federation> ...
INFO 2016-04-28 09:37:27,987 1wnKhMWR servers Updated <federation> to 0.1.0
INFO 2016-04-28 09:37:27,988 1wnKhMWR servers Updating <files> ...
INFO 2016-04-28 09:37:28,008 1wnKhMWR servers Updated <files> to 1.5.1
INFO 2016-04-28 09:37:28,009 1wnKhMWR servers Updating <activity> ...
INFO 2016-04-28 09:37:28,228 1wnKhMWR servers Updated <activity> to 2.3.2
INFO 2016-04-28 09:37:28,229 1wnKhMWR servers Updating <dav> ...
INFO 2016-04-28 09:37:28,384 1wnKhMWR servers Updated <dav> to 0.2.3
INFO 2016-04-28 09:37:28,384 1wnKhMWR servers Updating <files_sharing> ...
INFO 2016-04-28 09:37:28,499 1wnKhMWR servers Updated <files_sharing> to 0.10.0
INFO 2016-04-28 09:37:28,501 1wnKhMWR servers Updating <files_trashbin> ...
INFO 2016-04-28 09:37:28,597 1wnKhMWR servers Updated <files_trashbin> to 0.9.0
INFO 2016-04-28 09:37:28,598 1wnKhMWR servers Updating <files_versions> ...
INFO 2016-04-28 09:37:28,603 1wnKhMWR servers Updated <files_versions> to 1.3.0
INFO 2016-04-28 09:37:28,603 1wnKhMWR servers Updating <comments> ...
INFO 2016-04-28 09:37:28,607 1wnKhMWR servers Updated <comments> to 0.3.0
INFO 2016-04-28 09:37:28,609 1wnKhMWR servers Updating <notifications> ...
INFO 2016-04-28 09:37:28,709 1wnKhMWR servers Updated <notifications> to 0.3.0
INFO 2016-04-28 09:37:28,710 1wnKhMWR servers Updating <systemtags> ...
INFO 2016-04-28 09:37:28,714 1wnKhMWR servers Updated <systemtags> to 0.3.0
INFO 2016-04-28 09:37:28,770 1wnKhMWR servers Starting code integrity check...
INFO 2016-04-28 09:37:33,234 1wnKhMWR servers Finished code integrity check
INFO 2016-04-28 09:37:33,235 1wnKhMWR servers Update successful
INFO 2016-04-28 09:37:33,236 1wnKhMWR servers Turned off maintenance mode
INFO 2016-04-28 09:37:33,237 1wnKhMWR servers Reset log level
INFO 2016-04-28 09:37:33,265 1wnKhMWR __main__ verify_server_version ('server1', <utils.utilities.Holder object at 0x7f8d888edb90>{'qwerty_share_data': <owncloud.owncloud.PublicShare instance at 0x7f8d86255290>, 'integrity_result': ('[]', ''), 'server_v1': {u'edition': u'', u'versionstring': u'9.0.2 RC1', u'version': u'9.0.2.0', u'maintenance': False, u'installed': True}}, 'server_v2')
DEBUG 2016-04-28 09:37:33,266 1wnKhMWR servers verify_server_version
DEBUG 2016-04-28 09:37:33,720 1wnKhMWR servers <Response [200]>
DEBUG 2016-04-28 09:37:33,743 1wnKhMWR servers {u'edition': u'', u'versionstring': u'9.1.0 pre alpha', u'version': u'9.1.0.0', u'maintenance': False, u'installed': True}
@VicDeo
Member
VicDeo commented Apr 28, 2016 edited

9.0 to 9.0.2 RC1 (daily) Build:2016-04-28T03:03:12+00:00 795f321a1985b682bff584f252dd6f2fd95dad65

Status - success

root@ccc820bea698:/opt/owncloud# cat updater/update.log 
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.0.19 found
[info] Execution of upgrade:detect command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:checkpoint command started
[info] Execution of upgrade:checkpoint command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.0.19 found
[info] Execution of upgrade:detect command stopped. Exit code is 0
[info] Execution of upgrade:disableNotShippedApps command started
[info] Execution of upgrade:disableNotShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:executeCoreUpgradeScripts command started
[debug] Moving config/config.sample.php
[debug] Moving core
[debug] Moving l10n
[debug] Moving lib
[debug] Moving ocs
[debug] Moving ocs-provider
[debug] Moving resources
[debug] Moving settings
[debug] Moving .htaccess
[debug] Moving .mailmap
[debug] Moving .tag
[debug] Moving .user.ini
[debug] Moving AUTHORS
[debug] Moving console.php
[debug] Moving COPYING-AGPL
[debug] Moving cron.php
[debug] Moving db_structure.xml
[debug] Moving index.html
[debug] Moving index.php
[debug] Moving indie.json
[debug] Moving occ
[debug] Moving public.php
[debug] Moving remote.php
[debug] Moving robots.txt
[debug] Moving status.php
[debug] Moving version.php
[info] Execution of upgrade:executeCoreUpgradeScripts command stopped. Exit code is 0
[info] Execution of upgrade:upgradeShippedApps command started
[info] Execution of upgrade:upgradeShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:enableNotShippedApps command started
[info] Execution of upgrade:enableNotShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:restartWebServer command started
[info] Execution of upgrade:restartWebServer command stopped. Exit code is 0
[info] Execution of upgrade:postUpgradeCleanup command started
[debug] Moving updater/app
[debug] Moving updater/application.php
[debug] Moving updater/box.json
[debug] Moving updater/composer.json
[debug] Moving updater/composer.lock
[debug] Moving updater/CONTRIBUTING.md
[debug] Moving updater/COPYING-AGPL
[debug] Moving updater/index.php
[debug] Moving updater/pub
[debug] Moving updater/src
[debug] Moving updater/vendor
[debug] Moving updater/README.md
[debug] Moving updater/.travis.yml
[debug] Moving updater/.scrutinizer.yml
[info] Execution of upgrade:postUpgradeCleanup command stopped. Exit code is 0
@VicDeo
Member
VicDeo commented Apr 29, 2016

ah. got it
upgrade:upgradeShippedApps iterates through the app list and the first app in the list is activity.

Core tries to disable "incompatible" files app that has not been replaced yet...
And only Chuck Norris is allowed to disable files app...
Arrrgh

@PVince81
Contributor
PVince81 commented May 2, 2016

@VicDeo any chance to get this fixed quickly ?

@PVince81 PVince81 assigned VicDeo and unassigned jvillafanez May 2, 2016
@PVince81
Contributor
PVince81 commented May 2, 2016

Is this easy to reproduce ?

@VicDeo
Member
VicDeo commented May 2, 2016

@PVince81 easy to reproduce:

  1. install https://download.owncloud.org/community/owncloud-daily-stable9.zip
  2. Patch Feed.php 9e6b836#diff-2086ce54d4cf0d70f761089f2c6e8ea6
  3. Switch channel to daily & Upgrade

Not sure about quickfix though. I spent Thursday and Friday on it already. :/

@PVince81
Contributor
PVince81 commented May 2, 2016

@VicDeo any insights at least ?

@PVince81
Contributor
PVince81 commented May 2, 2016

@VicDeo your patch is about upgrading to master / 9.1.
We talked with @cmonteroluque last week that at this point we should care about upgrading from 9.0.x to a higher 9.0.y version, not 9.1. We can always fix the updater in 9.0.3 for 9.1 once 9.1 is beta.

Can you confirm whether upgrading works within 9.0.x versions or is it the same problem ?

@DeepDiver1975
Member

ah. got it
upgrade:upgradeShippedApps iterates through the app list and the first app in the list is activity.

Core tries to disable "incompatible" files app that has not been replaced yet...
And only Chuck Norris is allowed to disable files app...
Arrrgh

From my POV we should replace all shipped apps at once and run upgrade once - doing this step by step is wrong

@PVince81
Contributor
PVince81 commented May 2, 2016

@VicDeo right, makes sense. We should have informed you ๐Ÿ˜„

If upgrading to any 9.0.x version works then I think this is not a blocker for the 9.0.2 release any more.

The updater code can then be fixed as part of 9.0.3 to work with the future 9.1 update.

@PVince81
Contributor
PVince81 commented May 2, 2016
  • Tested v9.0.1 tarball -> change to daily channel -> run upgrade: success
@PVince81
Contributor
PVince81 commented May 2, 2016

@VicDeo please do confirm that I understood it correctly

@VicDeo
Member
VicDeo commented May 2, 2016

@PVince81 you did understand it correctly, but it's better to check by updating ownCloud 9.0.2 RC1 built on 27.04.2016 to stable9-daily:

It succeeds too:
Before:

# cat version.php
<?php 
$OC_Version = array(9,0,2,0);
$OC_VersionString = '9.0.2 RC1';
$OC_Edition = '';
$OC_Channel = 'testing';
$OC_VersionCanBeUpgradedFrom = array(8,2);
$OC_Build = '2016-04-27T01:48:38+00:00 795f321a1985b682bff584f252dd6f2fd95dad65';

After:

# cat version.php 
<?php 
$OC_Version = array(9,0,2,0);
$OC_VersionString = '9.0.2 RC1';
$OC_Edition = '';
$OC_Channel = 'daily';
$OC_VersionCanBeUpgradedFrom = array(8,2);
$OC_Build = '2016-05-02T03:03:15+00:00 ec1a66b8a6cf57e421a9799b5f10359891af29ec';
# sudo -u wwwrun php occ integr:check-core
#

update.log (no errors):

# cat data/update.log 
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.2.0 found
[info] Execution of upgrade:detect command stopped. Exit code is 0
[info] Execution of upgrade:checkSystem command started
[info] Execution of upgrade:checkSystem command stopped. Exit code is 0
[info] Execution of upgrade:checkpoint command started
[info] Execution of upgrade:checkpoint command stopped. Exit code is 0
[info] Execution of upgrade:detect command started
[info] ownCloud 9.0.2.0 found
[info] Execution of upgrade:detect command stopped. Exit code is 0
[info] Execution of upgrade:disableNotShippedApps command started
[info] Execution of upgrade:disableNotShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:executeCoreUpgradeScripts command started
[debug] Replacing 3rdparty
[debug] Replacing config/config.sample.php
[debug] Replacing core
[debug] Replacing l10n
[debug] Replacing lib
[debug] Replacing ocs
[debug] Replacing ocs-provider
[debug] Replacing resources
[debug] Replacing settings
[debug] Replacing .htaccess
[debug] Replacing .mailmap
[debug] Replacing .tag
[debug] Replacing .user.ini
[debug] Replacing AUTHORS
[debug] Replacing console.php
[debug] Replacing COPYING-AGPL
[debug] Replacing cron.php
[debug] Replacing db_structure.xml
[debug] Replacing index.html
[debug] Replacing index.php
[debug] Replacing indie.json
[debug] Replacing occ
[debug] Replacing public.php
[debug] Replacing remote.php
[debug] Replacing robots.txt
[debug] Replacing status.php
[debug] Replacing version.php
[info] Execution of upgrade:executeCoreUpgradeScripts command stopped. Exit code is 0
[info] Execution of upgrade:upgradeShippedApps command started
[info] Execution of upgrade:upgradeShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:enableNotShippedApps command started
[info] Execution of upgrade:enableNotShippedApps command stopped. Exit code is 0
[info] Execution of upgrade:restartWebServer command started
[info] Execution of upgrade:restartWebServer command stopped. Exit code is 0
[info] Execution of upgrade:postUpgradeCleanup command started
[debug] Moving updater/app
[debug] Moving updater/application.php
[debug] Moving updater/box.json
[debug] Moving updater/composer.json
[debug] Moving updater/composer.lock
[debug] Moving updater/CONTRIBUTING.md
[debug] Moving updater/COPYING-AGPL
[debug] Moving updater/index.php
[debug] Moving updater/pub
[debug] Moving updater/src
[debug] Moving updater/vendor
[debug] Moving updater/README.md
[debug] Moving updater/.travis.yml
[debug] Moving updater/.scrutinizer.yml
[info] Execution of upgrade:postUpgradeCleanup command stopped. Exit code is 0
@PVince81
Contributor
PVince81 commented May 2, 2016

@VicDeo thanks.

I'm currently trying to test updating "9.0.1" to "9.0.2RC1". Unfortunately it seems 9.0.2RC1 isn't available in any channels so I tried to make "Fetcher.php" point at a local tarball. However the updater still says "there is no update available". It would be really useful if you could spend some time on #334 to make testing easier.

@VicDeo
Member
VicDeo commented May 2, 2016

@PVince81 For now you can just switch the upgrade channel to daily and override the new package URL to your localhost e.g. http://localhost/custom-oc.zip like this 9e6b836#diff-2086ce54d4cf0d70f761089f2c6e8ea6R109

@PVince81
Contributor
PVince81 commented May 2, 2016

Ah, I had it set to "beta", not "daily". With "daily" it works.

@PVince81
Contributor
PVince81 commented May 2, 2016
  • 9.0.1 from tarball => 9.0.2RC1 tarball (with hacked Fetcher): success
@PVince81
Contributor
PVince81 commented May 2, 2016
  • 9.0.1 from tarball => 9.0.2RC2 tarball (with hacked Fecther): success

Also the integrity check warnings have disappeared in RC2.

@davitol
davitol commented May 3, 2016

Tested again updating from owncloud-daily-stable9.tar to owncloud-daily-master via daily and it keeps failing

@PVince81
Contributor
PVince81 commented May 3, 2016

@davitol this wasn't fixed

@VicDeo VicDeo referenced this issue May 3, 2016
Merged

Do not create __apps dir. #347

1 of 1 task complete
@VicDeo
Member
VicDeo commented May 3, 2016

@jvillafanez please test #347

@PVince81 PVince81 closed this in #347 May 12, 2016
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment