Skip to content

Instantly share code, notes, and snippets.

@thomasrehm
Last active August 29, 2015 14:23
Show Gist options
  • Save thomasrehm/137f8af4ae002a87cd2a to your computer and use it in GitHub Desktop.
Save thomasrehm/137f8af4ae002a87cd2a to your computer and use it in GitHub Desktop.
Log of ARSnova Vagrant Performance Test on branch synced-folder-performance
$ time vagrant up
Bringing machine 'dev' up with 'virtualbox' provider...
==> dev: Importing base box 'commana/arsnova-debian-wheezy-puppet3-amd64'...
==> dev: Matching MAC address for NAT networking...
==> dev: Checking if box 'commana/arsnova-debian-wheezy-puppet3-amd64' is up to date...
==> dev: Setting the name of the VM: arsnova-vagrant_dev_1435043323534_26658
==> dev: Couldn't find Cheffile at ./Cheffile.
==> dev: Clearing any previously set network interfaces...
==> dev: Preparing network interfaces based on configuration...
dev: Adapter 1: nat
dev: Adapter 2: hostonly
==> dev: Forwarding ports...
dev: 8080 => 8080 (adapter 1)
dev: 10443 => 10443 (adapter 1)
dev: 5984 => 5984 (adapter 1)
dev: 9000 => 9000 (adapter 1)
dev: 9090 => 9090 (adapter 1)
dev: 22 => 2222 (adapter 1)
==> dev: Running 'pre-boot' VM customizations...
==> dev: Booting VM...
==> dev: Waiting for machine to boot. This may take a few minutes...
dev: SSH address: 127.0.0.1:2222
dev: SSH username: vagrant
dev: SSH auth method: private key
dev: Warning: Connection timeout. Retrying...
dev: Warning: Connection timeout. Retrying...
dev: Warning: Remote connection disconnect. Retrying...
dev:
dev: Vagrant insecure key detected. Vagrant will automatically replace
dev: this with a newly generated keypair for better security.
dev:
dev: Inserting generated public key within guest...
dev: Removing insecure key from the guest if its present...
dev: Key inserted! Disconnecting and reconnecting using new SSH key...
==> dev: Machine booted and ready!
GuestAdditions versions on your host (4.3.20) and guest (4.3.12) do not match.
stdin: is not a tty
Reading package lists...
Building dependency tree...
Reading state information...
dkms is already the newest version.
linux-headers-3.2.0-4-amd64 is already the newest version.
linux-headers-3.2.0-4-amd64 set to manually installed.
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Copy iso file /Applications/VirtualBox.app/Contents/MacOS/VBoxGuestAdditions.iso into the box /tmp/VBoxGuestAdditions.iso
stdin: is not a tty
mount: block device /tmp/VBoxGuestAdditions.iso is write-protected, mounting read-only
Installing Virtualbox Guest Additions 4.3.20 - guest version is 4.3.12
stdin: is not a tty
Verifying archive integrity... All good.
Uncompressing VirtualBox 4.3.20 Guest Additions for Linux............
VirtualBox Guest Additions installer
Removing installed version 4.3.12 of VirtualBox Guest Additions...
Copying additional installer modules ...
Installing additional modules ...
Removing existing VirtualBox DKMS kernel modules ...done.
Removing existing VirtualBox non-DKMS kernel modules ...done.
Building the VirtualBox Guest Additions kernel modules ...done.
Doing non-kernel setup of the Guest Additions ...done.
You should restart your guest to make sure the new modules are actually used
Installing the Window System drivers
Installing X.Org Server 1.12 modules ...done.
Setting up the Window System to use the Guest Additions ...done.
You may need to restart the hal service and the Window System (or just restart
the guest system) to enable the Guest Additions.
Installing graphics libraries and desktop services components ...done.
An error occurred during installation of VirtualBox Guest Additions 4.3.20. Some functionality may not work as intended.
In most cases it is OK that the "Window System drivers" installation failed.
stdin: is not a tty
==> dev: Checking for guest additions in VM...
==> dev: Setting hostname...
==> dev: Configuring and enabling network interfaces...
==> dev: Exporting NFS shared folders...
==> dev: Preparing to edit /etc/exports. Administrator privileges will be required...
Password:
==> dev: Mounting NFS shared folders...
==> dev: Mounting shared folders...
dev: /tmp/vagrant-puppet/modules-c4041952998610939b164e91d5487a6b => /Users/Thomas/Development/vagrant_boxes/arsnova-vagrant/puppet/modules
dev: /tmp/vagrant-puppet/manifests-846018e2aa141a5eb79a64b4015fc5f3 => /Users/Thomas/Development/vagrant_boxes/arsnova-vagrant/puppet/manifests
==> dev: Running provisioner: hosts...
==> dev: Running provisioner: puppet...
==> dev: Running Puppet with debian-wheezy.pp...
==> dev: stdin: is not a tty
==> dev: Warning: Setting manifestdir is deprecated. See http://links.puppetlabs.com/env-settings-deprecations
==> dev: (at /usr/lib/ruby/vendor_ruby/puppet/settings.rb:1095:in `block in issue_deprecations')
==> dev: Warning: Setting templatedir is deprecated. See http://links.puppetlabs.com/env-settings-deprecations
==> dev: (at /usr/lib/ruby/vendor_ruby/puppet/settings.rb:1095:in `block in issue_deprecations')
==> dev: Warning: Config file /etc/puppet/hiera.yaml not found, using Hiera defaults
==> dev: Warning: Scope(Git::Repo[arsnova-backend]): The use of the git::repo resource is depreciated. Please consider using the Puppetlabs vcsrepo class.
==> dev: Warning: Scope(Git::Repo[arsnova-mobile]): The use of the git::repo resource is depreciated. Please consider using the Puppetlabs vcsrepo class.
==> dev: Warning: Scope(Git::Repo[arsnova-setuptool]): The use of the git::repo resource is depreciated. Please consider using the Puppetlabs vcsrepo class.
==> dev: Warning: Scope(Class[Apt::Update]): Could not look up qualified variable 'apt::update_timeout'; class apt has not been evaluated
==> dev: Warning: Scope(Class[Apt::Update]): Could not look up qualified variable 'apt::update_tries'; class apt has not been evaluated
==> dev: Notice: Compiled catalog for arsnova-dev.localdomain in environment development in 8.18 seconds
==> dev: Notice: /Stage[main]/Main/Exec[apt-update]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Couchdb[couchdb-host-access]/Package[python-couchdb]/ensure: ensure changed 'purged' to 'latest'
==> dev: Notice: /Stage[main]/Main/File_line[pam]/ensure: created
==> dev: Notice: /Stage[main]/Motd/File[/etc/motd]/content: content changed '{md5}9830e3dbb6a828f2cc824db8db0ceaf7' to '{md5}cab6576be4846e40432dd44b0e67a286'
==> dev: Notice: /Stage[main]/Arsnova/Package[listen]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/User[sonar]/ensure: created
==> dev: Notice: /Stage[main]/Main/Package[chromium-browser]/ensure: ensure changed '35.0.1916.153-1~deb7u1' to '37.0.2062.120-1~deb7u1'
==> dev: Notice: /Stage[main]/Senchacmd/File[/etc/profile.d/senchacmd.sh]/ensure: defined content as '{md5}3927b595cd439163ccf25404f7fe0b46'
==> dev: Notice: /Stage[main]/Arsnova/File[/etc/arsnova]/ensure: created
==> dev: Notice: /Stage[main]/Senchacmd/Package[unzip]/ensure: ensure changed '6.0-8' to '6.0-8+deb7u2'
==> dev: Notice: /Stage[main]/Java::Config/Exec[update-java-alternatives]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Repo::Debian/Apt::Source[jenkins]/Apt::Key[Add key: D50582E6 from Apt::Source jenkins]/Apt_key[Add key: D50582E6 from Apt::Source jenkins]/ensure: created
==> dev: Notice: /Stage[main]/Jenkins::Repo::Debian/Apt::Source[jenkins]/File[jenkins.list]/ensure: created
==> dev: Notice: /Stage[main]/Apt::Update/Exec[apt_update]: Triggered 'refresh' from 1 events
==> dev: Notice: /Stage[main]/Jenkins::Package/Package[jenkins]/ensure: ensure changed 'purged' to 'present'
==> dev: Notice: /Stage[main]/Jenkins::Config/Jenkins::Sysconfig[HTTP_PORT]/File_line[Jenkins sysconfig setting HTTP_PORT]/ensure: created
==> dev: Notice: /Stage[main]/Jenkins::Config/Jenkins::Sysconfig[AJP_PORT]/File_line[Jenkins sysconfig setting AJP_PORT]/ensure: created
==> dev: Notice: /Stage[main]/Main/File[/usr/local/bin]/owner: owner changed 'root' to 'vagrant'
==> dev: Notice: /Stage[main]/Main/File[/usr/local/bin]/group: group changed 'staff' to 'vagrant'
==> dev: Notice: /Stage[main]/Main/File[/home/vagrant/.xsession]/ensure: defined content as '{md5}9141004e757e9e9786f22ce1ea5abccd'
==> dev: Notice: /Stage[main]/Arsnova/File[/home/vagrant/listen.rb]/ensure: defined content as '{md5}8a60a8367a9a8a4bb247eccb91846aa5'
==> dev: Notice: /Stage[main]/Arsnova/File[/home/vagrant/stop.sh]/ensure: defined content as '{md5}2ad257d344a0e5cbef039594b706a456'
==> dev: Notice: /Stage[main]/Arsnova/File[/home/vagrant/start.sh]/ensure: defined content as '{md5}a3340091bb8aa08eccb6db97c1174a78'
==> dev: Notice: /Stage[main]/Main/File[/usr/local/bin/listen]/owner: owner changed 'root' to 'vagrant'
==> dev: Notice: /Stage[main]/Main/File[/usr/local/bin/listen]/group: group changed 'staff' to 'vagrant'
==> dev: Notice: /Stage[main]/Git/Package[git]/ensure: ensure changed 'purged' to 'present'
==> dev: Notice: /Stage[main]/Git/Package[git-svn]/ensure: ensure changed 'purged' to 'present'
==> dev: Notice: /Stage[main]/Arsnova/Git::Repo[arsnova-backend]/File[/vagrant/arsnova-backend]/ensure: created
==> dev: Notice: /Stage[main]/Arsnova/Git::Repo[arsnova-backend]/Exec[git_repo_arsnova-backend]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Git::Repo[arsnova-setuptool]/File[/vagrant/arsnova-setuptool]/ensure: created
==> dev: Notice: /Stage[main]/Arsnova/Git::Repo[arsnova-setuptool]/Exec[git_repo_arsnova-setuptool]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Git::Repo[arsnova-mobile]/File[/vagrant/arsnova-mobile]/ensure: created
==> dev: Error: Command exceeded timeout
==> dev: Wrapped exception:
==> dev: execution expired
==> dev: Error: /Stage[main]/Arsnova/Git::Repo[arsnova-mobile]/Exec[git_repo_arsnova-mobile]/returns: change from notrun to 0 failed: Command exceeded timeout
==> dev: Notice: /Stage[main]/Arsnova/Git::Repo[arsnova-mobile]/Exec[git_arsnova-mobile_co_branch]: Dependency Exec[git_repo_arsnova-mobile] has failures: true
==> dev:
==> dev: Warning: /Stage[main]/Arsnova/Git::Repo[arsnova-mobile]/Exec[git_arsnova-mobile_co_branch]: Skipping because of failed dependencies
==> dev: Notice: /Stage[main]/Arsnova/File[/etc/arsnova/arsnova.properties]/ensure: defined content as '{md5}1dd5fb997bba6f315977ae0b6a0d3064'
==> dev: Notice: /Stage[main]/Arsnova/Config[arsnova-config]/File_line[arsnova-root-url]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Wget::Fetch[download-sonar]/Exec[wget-download-sonar]/returns: executed successfully
==> dev: Notice: /Stage[main]/Sonarqube/File[/var/local/sonar]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/File[/usr/local/sonarqube-4.1.2]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/File[/usr/local/sonar]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[data]/File[/var/local/sonar/data]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[data]/File[/usr/local/sonar/data]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[extras]/File[/var/local/sonar/extras]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[extras]/File[/usr/local/sonar/extras]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[extensions]/File[/var/local/sonar/extensions]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[extensions]/File[/usr/local/sonar/extensions]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/File[/var/local/sonar/extensions/plugins]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[logs]/File[/var/local/sonar/logs]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Sonarqube::Move_to_home[logs]/File[/usr/local/sonar/logs]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/Exec[untar]/returns: executed successfully
==> dev: Notice: /Stage[main]/Sonarqube/File[/usr/local/sonar/bin/linux-x86-64/sonar.sh]/content: content changed '{md5}d69c3bb633c3fcbda2f9911e2da829d9' to '{md5}5e3672ca26c9d5872dde84d16bf1961f'
==> dev: Notice: /Stage[main]/Sonarqube/File[/etc/init.d/sonar]/ensure: created
==> dev: Notice: /Stage[main]/Sonarqube/File[/usr/local/sonar/conf/sonar.properties]/content: content changed '{md5}74c9c457b0fa67ab0d6b967549cbb9f4' to '{md5}ffeb0f19fc76f550560cd4e658975cae'
==> dev: Notice: /Stage[main]/Sonarqube/File[/usr/local/sonar/conf/sonar.properties]/mode: mode changed '0644' to '0600'
==> dev: Notice: /Stage[main]/Sonarqube/Service[sonarqube]/ensure: ensure changed 'stopped' to 'running'
==> dev: Notice: /Stage[main]/Arsnova/Couchdb[couchdb-host-access]/File_line[couchdb-bind_address-0.0.0.0]/ensure: created
==> dev: Notice: /Stage[main]/Arsnova/Exec[initialize-couchdb]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Service[couchdb]: Triggered 'refresh' from 1 events
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[sonar]/Exec[download-sonar]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/File[/var/lib/jenkins/.sencha]/ensure: created
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[disk-usage]/Exec[download-disk-usage]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[git]/Exec[download-git]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[git]/File[/var/lib/jenkins/plugins/git.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[scm-api]/Exec[download-scm-api]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/File[/var/lib/jenkins/hudson.tasks.Maven.xml]/ensure: defined content as '{md5}cdff6756fbc041c46ae264bd81779ab6'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[git-client]/Exec[download-git-client]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[jquery]/Exec[download-jquery]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[sonar]/File[/var/lib/jenkins/plugins/sonar.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[deploy]/Exec[download-deploy]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[build-pipeline-plugin]/Exec[download-build-pipeline-plugin]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[git-client]/File[/var/lib/jenkins/plugins/git-client.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[log-parser]/Exec[download-log-parser]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[scm-api]/File[/var/lib/jenkins/plugins/scm-api.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[log-parser]/File[/var/lib/jenkins/plugins/log-parser.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[disk-usage]/File[/var/lib/jenkins/plugins/disk-usage.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Arsnova/File[/var/lib/jenkins/.sencha/cmd]/ensure: created
==> dev: Notice: /Stage[main]/Arsnova/File[/var/lib/jenkins/.sencha/cmd/sencha.cfg]/ensure: defined content as '{md5}5a75a13a5eacb03685a551ea1875ce82'
==> dev: Notice: /Stage[main]/Arsnova/Exec[jenkins-sencha-permissions]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[build-pipeline-plugin]/File[/var/lib/jenkins/plugins/build-pipeline-plugin.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Arsnova/File[/var/lib/jenkins/hudson.plugins.sonar.SonarPublisher.xml]/ensure: defined content as '{md5}586777d1b0b6e71d7fa766384c8ea387'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[deploy]/File[/var/lib/jenkins/plugins/deploy.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[parameterized-trigger]/Exec[download-parameterized-trigger]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[parameterized-trigger]/File[/var/lib/jenkins/plugins/parameterized-trigger.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[dashboard-view]/Exec[download-dashboard-view]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[dashboard-view]/File[/var/lib/jenkins/plugins/dashboard-view.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[build-blocker-plugin]/Exec[download-build-blocker-plugin]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[build-blocker-plugin]/File[/var/lib/jenkins/plugins/build-blocker-plugin.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[clone-workspace-scm]/Exec[download-clone-workspace-scm]/returns: executed successfully
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[clone-workspace-scm]/File[/var/lib/jenkins/plugins/clone-workspace-scm.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Plugins/Jenkins::Plugin[jquery]/File[/var/lib/jenkins/plugins/jquery.hpi]/owner: owner changed 'root' to 'jenkins'
==> dev: Notice: /Stage[main]/Jenkins::Service/Service[jenkins]: Triggered 'refresh' from 18 events
==> dev: Notice: /Stage[main]/Arsnova/Jenkins::Job[arsnova-jenkins-job-mobile]/Exec[arsnova-jenkins-job-ARSnova-Mobile]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Jenkins::Job[arsnova-jenkins-job-backend-deploy]/Exec[arsnova-jenkins-job-ARSnova-Backend.deploy]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Jenkins::Job[arsnova-jenkins-job-mobile-deploy]/Exec[arsnova-jenkins-job-ARSnova-Mobile.deploy]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Jenkins::Job[arsnova-jenkins-job-backend-sonar]/Exec[arsnova-jenkins-job-ARSnova-Backend.sonar]/returns: executed successfully
==> dev: Notice: /Stage[main]/Arsnova/Jenkins::Job[arsnova-jenkins-job-backend]/Exec[arsnova-jenkins-job-ARSnova-Backend]/returns: executed successfully
==> dev: Notice: Finished catalog run in 695.97 seconds
The SSH command responded with a non-zero exit status. Vagrant
assumes that this means the command failed. The output for this command
should be in the log above. Please read the output to determine what
went wrong.
real 16m41.628s
user 0m17.378s
sys 0m5.653s
$ time ./start.sh -v
Starting ARSnova. This could take a few minutes...Stopping ARSnova...done!
BUILD SUCCESSFUL
Total time: 48 seconds
..............................................Jun 23, 2015 7:35:39 AM org.apache.maven.wagon.providers.http.httpclient.impl.client.DefaultRequestDirector tryExecute
INFO: I/O exception (org.apache.maven.wagon.providers.http.httpclient.NoHttpResponseException) caught when processing request: The target server failed to respond
Jun 23, 2015 7:35:39 AM org.apache.maven.wagon.providers.http.httpclient.impl.client.DefaultRequestDirector tryExecute
INFO: Retrying request
..................................2015-06-23 07:38:36.004:INFO::main: Logging initialized @415757ms
2015-06-23 07:38:36.285:INFO:oejs.Server:main: jetty-9.2.10.v20150310
.............2015-06-23 07:39:42.284:INFO:/:main: No Spring WebApplicationInitializer types detected on classpath
2015-06-23 07:39:42.489:INFO:/:main: Set web app root system property: 'webapp.root' = [/vagrant/arsnova-backend/src/main/webapp]
2015-06-23 07:39:42.494:INFO:/:main: Initializing log4j from [/vagrant/arsnova-backend/target/classes/log4j-dev.properties]
2015-06-23 07:39:42.499:INFO:/:main: Initializing Spring root WebApplicationContext
07:39:42,501 INFO main context.ContextLoader:285 - Root WebApplicationContext: initialization started
07:39:42,623 INFO main support.XmlWebApplicationContext:510 - Refreshing Root WebApplicationContext: startup date [Tue Jun 23 07:39:42 UTC 2015]; root of context hierarchy
07:39:42,697 INFO main xml.XmlBeanDefinitionReader:317 - Loading XML bean definitions from ServletContext resource [/WEB-INF/spring/spring-main.xml]
.07:39:44,364 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'databaseDao': replacing [Generic bean: class [de.thm.arsnova.dao.CouchDBDao]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in file [/vagrant/arsnova-backend/target/classes/de/thm/arsnova/dao/CouchDBDao.class]] with [Generic bean: class [de.thm.arsnova.dao.CouchDBDao]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in ServletContext resource [/WEB-INF/spring/spring-main.xml]]
07:39:45,037 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'propertySourcesPlaceholderConfigurer': replacing [Root bean: class [de.thm.arsnova.config.ExtraConfig]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in de.thm.arsnova.config.ExtraConfig] with [Root bean: class [de.thm.arsnova.config.SecurityConfig]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in de.thm.arsnova.config.SecurityConfig]
07:39:45,041 INFO main annotation.ConfigurationClassBeanDefinitionReader:299 - Skipping bean definition for [BeanMethod:name=dbUserDetailsService,declaringClass=de.thm.arsnova.config.SecurityConfig]: a definition for bean 'dbUserDetailsService' already exists. This top-level bean definition is considered as an override.
07:39:45,569 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
07:39:45,578 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from URL [file:/etc/arsnova/arsnova.properties]
07:39:45,589 INFO main support.PropertySourcesPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
07:39:45,591 INFO main support.PropertySourcesPlaceholderConfigurer:172 - Loading properties file from file [/vagrant/arsnova-backend/file:/etc/arsnova/arsnova.properties]
07:39:45,598 WARN main support.PropertySourcesPlaceholderConfigurer:181 - Could not load properties from file [/vagrant/arsnova-backend/file:/etc/arsnova/arsnova.properties]: file:/etc/arsnova/arsnova.properties (No such file or directory)
07:39:45,629 INFO main annotation.AutowiredAnnotationBeanPostProcessor:153 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
.07:39:50,544 INFO main socketio.SocketIOServer:129 - Session store / pubsub factory used: MemoryStoreFactory (local session store only)
07:39:51,004 INFO nioEventLoopGroup-2-1 socketio.SocketIOServer:154 - SocketIO server started at port: 10443
07:39:51,356 INFO main config.SecurityConfig:167 - Enabled authentication providers: [user-db]
07:39:51,593 INFO main web.DefaultSecurityFilterChain:28 - Creating filter chain: org.springframework.security.web.util.matcher.AnyRequestMatcher@1, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@1c5e68ac, org.springframework.security.web.context.SecurityContextPersistenceFilter@4d6eb448, org.springframework.security.web.header.HeaderWriterFilter@2567ac5b, org.springframework.security.web.authentication.logout.LogoutFilter@2f97e3b7, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@4f08a367, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@612656aa, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@7a2a02c0, org.springframework.security.web.session.SessionManagementFilter@74437438, org.springframework.security.web.access.ExceptionTranslationFilter@3cea9137]
07:39:51,847 INFO main ldap.DefaultSpringSecurityContextSource:59 - URL 'ldap://example.com:33389/dc=example,dc=com', root DN is 'dc=example,dc=com'
07:39:51,947 INFO main support.AbstractContextSource:420 - Property 'userDn' not set - anonymous context will be used for read-write operations
07:39:52,052 INFO main userdetails.DefaultLdapAuthoritiesPopulator:168 - groupSearchBase is null. No group search will be performed.
07:39:52,572 INFO main context.ContextLoader:325 - Root WebApplicationContext: initialization completed in 10067 ms
2015-06-23 07:39:52.691:INFO:/:main: Initializing Spring FrameworkServlet 'arsnova'
07:39:52,693 INFO main servlet.DispatcherServlet:484 - FrameworkServlet 'arsnova': initialization started
07:39:52,697 INFO main support.XmlWebApplicationContext:510 - Refreshing WebApplicationContext for namespace 'arsnova-servlet': startup date [Tue Jun 23 07:39:52 UTC 2015]; parent: Root WebApplicationContext
07:39:52,698 INFO main xml.XmlBeanDefinitionReader:317 - Loading XML bean definitions from ServletContext resource [/WEB-INF/spring/arsnova-servlet.xml]
07:39:52,968 WARN main support.DefaultListableBeanFactory:815 - Overriding user-defined bean definition for bean 'mvcContentNegotiationManager' with a framework-generated bean definition: replacing [Generic bean: class [org.springframework.web.accept.ContentNegotiationManagerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in ServletContext resource [/WEB-INF/spring/arsnova-servlet.xml]] with [Root bean: class [org.springframework.web.accept.ContentNegotiationManagerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
07:39:52,969 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'mvcUriComponentsContributor': replacing [Root bean: class [org.springframework.web.servlet.config.AnnotationDrivenBeanDefinitionParser$CompositeUriComponentsContributorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.web.servlet.config.AnnotationDrivenBeanDefinitionParser$CompositeUriComponentsContributorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
07:39:53,107 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
07:39:53,113 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from URL [file:/etc/arsnova/arsnova.properties]
07:39:53,123 INFO main annotation.AutowiredAnnotationBeanPostProcessor:153 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
.07:39:56,866 INFO main handler.SimpleUrlHandlerMapping:314 - Mapped URL path [/**] onto handler 'org.springframework.web.servlet.resource.ResourceHttpRequestHandler#0'
07:39:57,226 INFO main servlet.DispatcherServlet:503 - FrameworkServlet 'arsnova': initialization completed in 4533 ms
2015-06-23 07:39:57.226:INFO:oejsh.ContextHandler:main: Started o.e.j.m.p.JettyWebAppContext@2b9835cb{/,file:/vagrant/arsnova-backend/src/main/webapp/,AVAILABLE}{file:/vagrant/arsnova-backend/src/main/webapp/}
2015-06-23 07:39:57.265:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@15f3b8c9{/mobile,file:/vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/,AVAILABLE}
2015-06-23 07:39:57.286:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@33e89f61{/customization,file:/vagrant/arsnova-customization/src/main/webapp,AVAILABLE}
2015-06-23 07:39:57.287:WARN:oejsh.RequestLogHandler:main: !RequestLog
2015-06-23 07:39:57.316:INFO:oejs.ServerConnector:main: Started ServerConnector@6b042d9d{HTTP/1.1}{0.0.0.0:8080}
2015-06-23 07:39:57.319:INFO:oejs.Server:main: Started @497073ms
..
ARSnova is now ready @ http://localhost:8080/
real 9m15.876s
user 0m39.982s
sys 0m20.713s
$ time ./start.sh -v
Starting ARSnova. This could take a few minutes...Stopping ARSnova...2015-06-23 07:52:34.064:INFO:oejs.ServerConnector:Thread-1: Stopped ServerConnector@6b042d9d{HTTP/1.1}{0.0.0.0:8080}
2015-06-23 07:52:34.079:INFO:oejsh.ContextHandler:Thread-1: Stopped o.e.j.w.WebAppContext@33e89f61{/customization,file:/vagrant/arsnova-customization/src/main/webapp,UNAVAILABLE}
2015-06-23 07:52:34.085:INFO:oejsh.ContextHandler:Thread-1: Stopped o.e.j.w.WebAppContext@15f3b8c9{/mobile,file:/vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/,UNAVAILABLE}
2015-06-23 07:52:34.603:INFO:/:Thread-1: Destroying Spring FrameworkServlet 'arsnova'
07:52:34,609 INFO Thread-1 support.XmlWebApplicationContext:862 - Closing WebApplicationContext for namespace 'arsnova-servlet': startup date [Tue Jun 23 07:39:52 UTC 2015]; parent: Root WebApplicationContext
2015-06-23 07:52:34.642:INFO:/:Thread-1: Closing Spring root WebApplicationContext
07:52:34,643 INFO Thread-1 support.XmlWebApplicationContext:862 - Closing Root WebApplicationContext: startup date [Tue Jun 23 07:39:42 UTC 2015]; root of context hierarchy
07:52:34,653 INFO Thread-1 socket.ARSnovaSocketIOServer:127 - Close all websockets due to @PreDestroy
07:52:34,671 INFO Thread-1 socket.ARSnovaSocketIOServer:137 - Pending websockets at @PreDestroy: 0
done!
07:52:39,164 INFO Thread-1 socketio.SocketIOServer:197 - SocketIO server stopped
07:52:39,167 INFO Thread-1 socketio.SocketIOServer:197 - SocketIO server stopped
07:52:39,169 ERROR Thread-1 services.UserService:311 - Destroy UserService
2015-06-23 07:52:39.181:INFO:/:Thread-1: Shutting down log4j
2015-06-23 07:52:39.216:INFO:oejsh.ContextHandler:Thread-1: Stopped o.e.j.m.p.JettyWebAppContext@2b9835cb{/,file:/vagrant/arsnova-backend/src/main/webapp/,UNAVAILABLE}{file:/vagrant/arsnova-backend/src/main/webapp/}
BUILD SUCCESSFUL
Total time: 52 seconds
.........2015-06-23 07:54:19.404:INFO::main: Logging initialized @49778ms
2015-06-23 07:54:20.151:INFO:oejs.Server:main: jetty-9.2.10.v20150310
.....................2015-06-23 07:56:12.004:INFO:/:main: No Spring WebApplicationInitializer types detected on classpath
2015-06-23 07:56:12.203:INFO:/:main: Set web app root system property: 'webapp.root' = [/vagrant/arsnova-backend/src/main/webapp]
2015-06-23 07:56:12.219:INFO:/:main: Initializing log4j from [/vagrant/arsnova-backend/target/classes/log4j-dev.properties]
2015-06-23 07:56:12.238:INFO:/:main: Initializing Spring root WebApplicationContext
07:56:12,255 INFO main context.ContextLoader:285 - Root WebApplicationContext: initialization started
07:56:12,454 INFO main support.XmlWebApplicationContext:510 - Refreshing Root WebApplicationContext: startup date [Tue Jun 23 07:56:12 UTC 2015]; root of context hierarchy
07:56:12,579 INFO main xml.XmlBeanDefinitionReader:317 - Loading XML bean definitions from ServletContext resource [/WEB-INF/spring/spring-main.xml]
07:56:14,126 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'databaseDao': replacing [Generic bean: class [de.thm.arsnova.dao.CouchDBDao]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in file [/vagrant/arsnova-backend/target/classes/de/thm/arsnova/dao/CouchDBDao.class]] with [Generic bean: class [de.thm.arsnova.dao.CouchDBDao]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in ServletContext resource [/WEB-INF/spring/spring-main.xml]]
.07:56:14,985 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'propertySourcesPlaceholderConfigurer': replacing [Root bean: class [de.thm.arsnova.config.ExtraConfig]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in de.thm.arsnova.config.ExtraConfig] with [Root bean: class [de.thm.arsnova.config.SecurityConfig]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in de.thm.arsnova.config.SecurityConfig]
07:56:14,988 INFO main annotation.ConfigurationClassBeanDefinitionReader:299 - Skipping bean definition for [BeanMethod:name=dbUserDetailsService,declaringClass=de.thm.arsnova.config.SecurityConfig]: a definition for bean 'dbUserDetailsService' already exists. This top-level bean definition is considered as an override.
07:56:15,605 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
07:56:15,635 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from URL [file:/etc/arsnova/arsnova.properties]
07:56:15,725 INFO main support.PropertySourcesPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
07:56:15,736 INFO main support.PropertySourcesPlaceholderConfigurer:172 - Loading properties file from file [/vagrant/arsnova-backend/file:/etc/arsnova/arsnova.properties]
07:56:15,737 WARN main support.PropertySourcesPlaceholderConfigurer:181 - Could not load properties from file [/vagrant/arsnova-backend/file:/etc/arsnova/arsnova.properties]: file:/etc/arsnova/arsnova.properties (No such file or directory)
07:56:15,828 INFO main annotation.AutowiredAnnotationBeanPostProcessor:153 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
07:56:19,460 INFO main socketio.SocketIOServer:129 - Session store / pubsub factory used: MemoryStoreFactory (local session store only)
.07:56:20,203 INFO nioEventLoopGroup-2-1 socketio.SocketIOServer:154 - SocketIO server started at port: 10443
07:56:20,666 INFO main config.SecurityConfig:167 - Enabled authentication providers: [user-db]
07:56:20,920 INFO main web.DefaultSecurityFilterChain:28 - Creating filter chain: org.springframework.security.web.util.matcher.AnyRequestMatcher@1, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@122f50d3, org.springframework.security.web.context.SecurityContextPersistenceFilter@243855da, org.springframework.security.web.header.HeaderWriterFilter@9b310a7, org.springframework.security.web.authentication.logout.LogoutFilter@156153cb, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@3d030fc5, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@b828198, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@606355f8, org.springframework.security.web.session.SessionManagementFilter@33c90f0b, org.springframework.security.web.access.ExceptionTranslationFilter@300592ad]
07:56:21,134 INFO main ldap.DefaultSpringSecurityContextSource:59 - URL 'ldap://example.com:33389/dc=example,dc=com', root DN is 'dc=example,dc=com'
07:56:21,173 INFO main support.AbstractContextSource:420 - Property 'userDn' not set - anonymous context will be used for read-write operations
07:56:21,246 INFO main userdetails.DefaultLdapAuthoritiesPopulator:168 - groupSearchBase is null. No group search will be performed.
07:56:21,818 INFO main context.ContextLoader:325 - Root WebApplicationContext: initialization completed in 9559 ms
2015-06-23 07:56:22.118:INFO:/:main: Initializing Spring FrameworkServlet 'arsnova'
07:56:22,118 INFO main servlet.DispatcherServlet:484 - FrameworkServlet 'arsnova': initialization started
07:56:22,124 INFO main support.XmlWebApplicationContext:510 - Refreshing WebApplicationContext for namespace 'arsnova-servlet': startup date [Tue Jun 23 07:56:22 UTC 2015]; parent: Root WebApplicationContext
07:56:22,126 INFO main xml.XmlBeanDefinitionReader:317 - Loading XML bean definitions from ServletContext resource [/WEB-INF/spring/arsnova-servlet.xml]
07:56:22,497 WARN main support.DefaultListableBeanFactory:815 - Overriding user-defined bean definition for bean 'mvcContentNegotiationManager' with a framework-generated bean definition: replacing [Generic bean: class [org.springframework.web.accept.ContentNegotiationManagerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in ServletContext resource [/WEB-INF/spring/arsnova-servlet.xml]] with [Root bean: class [org.springframework.web.accept.ContentNegotiationManagerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
07:56:22,499 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'mvcUriComponentsContributor': replacing [Root bean: class [org.springframework.web.servlet.config.AnnotationDrivenBeanDefinitionParser$CompositeUriComponentsContributorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.web.servlet.config.AnnotationDrivenBeanDefinitionParser$CompositeUriComponentsContributorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
07:56:22,661 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
07:56:22,674 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from URL [file:/etc/arsnova/arsnova.properties]
07:56:22,704 INFO main annotation.AutowiredAnnotationBeanPostProcessor:153 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
.07:56:27,465 INFO main handler.SimpleUrlHandlerMapping:314 - Mapped URL path [/**] onto handler 'org.springframework.web.servlet.resource.ResourceHttpRequestHandler#0'
07:56:29,113 INFO main servlet.DispatcherServlet:503 - FrameworkServlet 'arsnova': initialization completed in 6993 ms
2015-06-23 07:56:29.113:INFO:oejsh.ContextHandler:main: Started o.e.j.m.p.JettyWebAppContext@710bed9c{/,file:/vagrant/arsnova-backend/src/main/webapp/,AVAILABLE}{file:/vagrant/arsnova-backend/src/main/webapp/}
2015-06-23 07:56:29.223:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@461cd6bf{/mobile,file:/vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/,AVAILABLE}
2015-06-23 07:56:29.321:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@f56b98f{/customization,file:/vagrant/arsnova-customization/src/main/webapp,AVAILABLE}
2015-06-23 07:56:29.324:WARN:oejsh.RequestLogHandler:main: !RequestLog
2015-06-23 07:56:29.550:INFO:oejs.ServerConnector:main: Started ServerConnector@4fcba5c8{HTTP/1.1}{0.0.0.0:8080}
2015-06-23 07:56:29.550:INFO:oejs.Server:main: Started @179925ms
..
ARSnova is now ready @ http://localhost:8080/
real 4m2.302s
user 0m41.023s
sys 0m21.333s
$ time ./start.sh -v
Starting ARSnova. This could take a few minutes...Stopping ARSnova...2015-06-23 07:58:57.168:INFO:oejs.ServerConnector:Thread-1: Stopped ServerConnector@4fcba5c8{HTTP/1.1}{0.0.0.0:8080}
2015-06-23 07:58:57.205:INFO:oejsh.ContextHandler:Thread-1: Stopped o.e.j.w.WebAppContext@f56b98f{/customization,file:/vagrant/arsnova-customization/src/main/webapp,UNAVAILABLE}
2015-06-23 07:58:57.220:INFO:oejsh.ContextHandler:Thread-1: Stopped o.e.j.w.WebAppContext@461cd6bf{/mobile,file:/vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/,UNAVAILABLE}
2015-06-23 07:58:57.730:INFO:/:Thread-1: Destroying Spring FrameworkServlet 'arsnova'
07:58:57,734 INFO Thread-1 support.XmlWebApplicationContext:862 - Closing WebApplicationContext for namespace 'arsnova-servlet': startup date [Tue Jun 23 07:56:22 UTC 2015]; parent: Root WebApplicationContext
2015-06-23 07:58:57.771:INFO:/:Thread-1: Closing Spring root WebApplicationContext
07:58:57,779 INFO Thread-1 support.XmlWebApplicationContext:862 - Closing Root WebApplicationContext: startup date [Tue Jun 23 07:56:12 UTC 2015]; root of context hierarchy
07:58:57,790 INFO Thread-1 socket.ARSnovaSocketIOServer:127 - Close all websockets due to @PreDestroy
07:58:57,801 INFO Thread-1 socket.ARSnovaSocketIOServer:137 - Pending websockets at @PreDestroy: 0
done!
07:59:02,456 INFO Thread-1 socketio.SocketIOServer:197 - SocketIO server stopped
07:59:02,481 INFO Thread-1 socketio.SocketIOServer:197 - SocketIO server stopped
07:59:02,483 ERROR Thread-1 services.UserService:311 - Destroy UserService
2015-06-23 07:59:02.530:INFO:/:Thread-1: Shutting down log4j
2015-06-23 07:59:02.546:INFO:oejsh.ContextHandler:Thread-1: Stopped o.e.j.m.p.JettyWebAppContext@710bed9c{/,file:/vagrant/arsnova-backend/src/main/webapp/,UNAVAILABLE}{file:/vagrant/arsnova-backend/src/main/webapp/}
BUILD SUCCESSFUL
Total time: 44 seconds
............2015-06-23 08:00:48.583:INFO::main: Logging initialized @63513ms
2015-06-23 08:00:49.246:INFO:oejs.Server:main: jetty-9.2.10.v20150310
...................2015-06-23 08:02:31.347:INFO:/:main: No Spring WebApplicationInitializer types detected on classpath
2015-06-23 08:02:31.526:INFO:/:main: Set web app root system property: 'webapp.root' = [/vagrant/arsnova-backend/src/main/webapp]
2015-06-23 08:02:31.533:INFO:/:main: Initializing log4j from [/vagrant/arsnova-backend/target/classes/log4j-dev.properties]
2015-06-23 08:02:31.538:INFO:/:main: Initializing Spring root WebApplicationContext
08:02:31,540 INFO main context.ContextLoader:285 - Root WebApplicationContext: initialization started
08:02:31,731 INFO main support.XmlWebApplicationContext:510 - Refreshing Root WebApplicationContext: startup date [Tue Jun 23 08:02:31 UTC 2015]; root of context hierarchy
08:02:31,833 INFO main xml.XmlBeanDefinitionReader:317 - Loading XML bean definitions from ServletContext resource [/WEB-INF/spring/spring-main.xml]
08:02:33,226 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'databaseDao': replacing [Generic bean: class [de.thm.arsnova.dao.CouchDBDao]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in file [/vagrant/arsnova-backend/target/classes/de/thm/arsnova/dao/CouchDBDao.class]] with [Generic bean: class [de.thm.arsnova.dao.CouchDBDao]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in ServletContext resource [/WEB-INF/spring/spring-main.xml]]
.08:02:33,847 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'propertySourcesPlaceholderConfigurer': replacing [Root bean: class [de.thm.arsnova.config.ExtraConfig]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in de.thm.arsnova.config.ExtraConfig] with [Root bean: class [de.thm.arsnova.config.SecurityConfig]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in de.thm.arsnova.config.SecurityConfig]
08:02:33,858 INFO main annotation.ConfigurationClassBeanDefinitionReader:299 - Skipping bean definition for [BeanMethod:name=dbUserDetailsService,declaringClass=de.thm.arsnova.config.SecurityConfig]: a definition for bean 'dbUserDetailsService' already exists. This top-level bean definition is considered as an override.
08:02:35,080 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
08:02:35,098 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from URL [file:/etc/arsnova/arsnova.properties]
08:02:35,114 INFO main support.PropertySourcesPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
08:02:35,135 INFO main support.PropertySourcesPlaceholderConfigurer:172 - Loading properties file from file [/vagrant/arsnova-backend/file:/etc/arsnova/arsnova.properties]
08:02:35,136 WARN main support.PropertySourcesPlaceholderConfigurer:181 - Could not load properties from file [/vagrant/arsnova-backend/file:/etc/arsnova/arsnova.properties]: file:/etc/arsnova/arsnova.properties (No such file or directory)
08:02:35,172 INFO main annotation.AutowiredAnnotationBeanPostProcessor:153 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
.08:02:38,963 INFO main socketio.SocketIOServer:129 - Session store / pubsub factory used: MemoryStoreFactory (local session store only)
08:02:39,396 INFO nioEventLoopGroup-2-1 socketio.SocketIOServer:154 - SocketIO server started at port: 10443
08:02:39,894 INFO main config.SecurityConfig:167 - Enabled authentication providers: [user-db]
08:02:40,482 INFO main web.DefaultSecurityFilterChain:28 - Creating filter chain: org.springframework.security.web.util.matcher.AnyRequestMatcher@1, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@6fcef931, org.springframework.security.web.context.SecurityContextPersistenceFilter@182ef6ed, org.springframework.security.web.header.HeaderWriterFilter@65c7c2dc, org.springframework.security.web.authentication.logout.LogoutFilter@28a1638f, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@1e9c272e, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@504a305, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@7d4a394f, org.springframework.security.web.session.SessionManagementFilter@48bd795c, org.springframework.security.web.access.ExceptionTranslationFilter@2093ed89]
08:02:40,907 INFO main ldap.DefaultSpringSecurityContextSource:59 - URL 'ldap://example.com:33389/dc=example,dc=com', root DN is 'dc=example,dc=com'
08:02:40,955 INFO main support.AbstractContextSource:420 - Property 'userDn' not set - anonymous context will be used for read-write operations
08:02:41,024 INFO main userdetails.DefaultLdapAuthoritiesPopulator:168 - groupSearchBase is null. No group search will be performed.
08:02:41,489 INFO main context.ContextLoader:325 - Root WebApplicationContext: initialization completed in 9944 ms
2015-06-23 08:02:41.737:INFO:/:main: Initializing Spring FrameworkServlet 'arsnova'
08:02:41,740 INFO main servlet.DispatcherServlet:484 - FrameworkServlet 'arsnova': initialization started
08:02:41,751 INFO main support.XmlWebApplicationContext:510 - Refreshing WebApplicationContext for namespace 'arsnova-servlet': startup date [Tue Jun 23 08:02:41 UTC 2015]; parent: Root WebApplicationContext
08:02:41,753 INFO main xml.XmlBeanDefinitionReader:317 - Loading XML bean definitions from ServletContext resource [/WEB-INF/spring/arsnova-servlet.xml]
08:02:42,174 WARN main support.DefaultListableBeanFactory:815 - Overriding user-defined bean definition for bean 'mvcContentNegotiationManager' with a framework-generated bean definition: replacing [Generic bean: class [org.springframework.web.accept.ContentNegotiationManagerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in ServletContext resource [/WEB-INF/spring/arsnova-servlet.xml]] with [Root bean: class [org.springframework.web.accept.ContentNegotiationManagerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
08:02:42,175 INFO main support.DefaultListableBeanFactory:822 - Overriding bean definition for bean 'mvcUriComponentsContributor': replacing [Root bean: class [org.springframework.web.servlet.config.AnnotationDrivenBeanDefinitionParser$CompositeUriComponentsContributorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [org.springframework.web.servlet.config.AnnotationDrivenBeanDefinitionParser$CompositeUriComponentsContributorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
08:02:42,439 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from class path resource [arsnova.properties.example]
08:02:42,460 INFO main config.PropertyPlaceholderConfigurer:172 - Loading properties file from URL [file:/etc/arsnova/arsnova.properties]
08:02:42,484 INFO main annotation.AutowiredAnnotationBeanPostProcessor:153 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
.08:02:46,851 INFO main handler.SimpleUrlHandlerMapping:314 - Mapped URL path [/**] onto handler 'org.springframework.web.servlet.resource.ResourceHttpRequestHandler#0'
08:02:47,649 INFO main servlet.DispatcherServlet:503 - FrameworkServlet 'arsnova': initialization completed in 5908 ms
2015-06-23 08:02:47.660:INFO:oejsh.ContextHandler:main: Started o.e.j.m.p.JettyWebAppContext@f56b98f{/,file:/vagrant/arsnova-backend/src/main/webapp/,AVAILABLE}{file:/vagrant/arsnova-backend/src/main/webapp/}
2015-06-23 08:02:47.754:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@6b06327{/mobile,file:/vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/,AVAILABLE}
2015-06-23 08:02:47.802:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@263d98df{/customization,file:/vagrant/arsnova-customization/src/main/webapp,AVAILABLE}
2015-06-23 08:02:47.807:WARN:oejsh.RequestLogHandler:main: !RequestLog
2015-06-23 08:02:47.876:INFO:oejs.ServerConnector:main: Started ServerConnector@56db1a52{HTTP/1.1}{0.0.0.0:8080}
2015-06-23 08:02:47.877:INFO:oejs.Server:main: Started @182807ms
..
ARSnova is now ready @ http://localhost:8080/
real 3m58.245s
user 0m44.379s
sys 0m18.477s
vagrant@arsnova-dev:/vagrant/arsnova-mobile$ time ant sencha:build:testing
Buildfile: /vagrant/arsnova-mobile/build.xml
sencha:build:testing:
[exec] Sencha Cmd v4.0.4.84
[exec] [INF]
[exec] [INF] init-plugin:
[exec] [INF]
[exec] [INF] cmd-root-plugin.init-properties:
[exec] [INF]
[exec] [INF] init-properties:
[exec] [INF]
[exec] [INF] init-sencha-command:
[exec] [INF]
[exec] [INF] init:
[exec] [INF]
[exec] [INF] app-build-impl:
[exec] [INF]
[exec] [INF] -before-init-local:
[exec] [INF]
[exec] [INF] -init-local:
[exec] [INF]
[exec] [INF] -after-init-local:
[exec] [INF]
[exec] [INF] init-local:
[exec] [INF]
[exec] [INF] find-cmd-in-path:
[exec] [INF]
[exec] [INF] find-cmd-in-environment:
[exec] [INF]
[exec] [INF] find-cmd-in-shell:
[exec] [INF]
[exec] [INF] init-cmd:
[exec] [INF] [echo] Using Sencha Cmd from /usr/local/bin/Sencha/Cmd/4.0.4.84 for /vagrant/arsnova-mobile/src/main/webapp/build.xml
[exec] [INF]
[exec] [INF] -before-init:
[exec] [INF]
[exec] [INF] -init:
[exec] [INF] Initializing Sencha Cmd ant environment
[exec] [INF] Adding antlib taskdef for com/sencha/command/compass/ant/antlib.xml
[exec] [WRN] The application was last modified by a newer version of Sencha Cmd (4.0.5.87), current is 4.0.4.84. Please install a newer Sencha Cmd version comptible with 4.0.5.87.
[exec] [INF]
[exec] [INF] -after-init:
[exec] [INF]
[exec] [INF] -before-init-defaults:
[exec] [INF]
[exec] [INF] -init-defaults:
[exec] [INF]
[exec] [INF] -after-init-defaults:
[exec] [INF]
[exec] [INF] -init-compiler:
[exec] [INF]
[exec] [INF] init:
[exec] [INF]
[exec] [INF] -before-build:
[exec] [INF]
[exec] [INF] refresh:
[exec] [INF]
[exec] [INF] -before-refresh:
[exec] [INF]
[exec] [INF] -init:
[exec] [INF]
[exec] [INF] -init-compiler:
[exec] [INF]
[exec] [INF] -detect-app-build-properties:
[exec] [INF] Loading app json manifest...
[exec] [INF] Loading classpath entry /vagrant/arsnova-mobile/src/main/webapp/touch/src
[exec] [INF] Loading classpath entry /vagrant/arsnova-mobile/src/main/webapp/app/app.js
[exec] [INF] Loading classpath entry /vagrant/arsnova-mobile/src/main/webapp/app
[exec] [INF] Loading classpath entry /vagrant/arsnova-mobile/src/main/webapp/build/temp/testing/ARSnova/sencha-compiler/app
[exec] [INF] Loading classpath entry /vagrant/arsnova-mobile/src/main/webapp/build/temp/testing/ARSnova/sencha-compiler/app
[exec] [INF] Concatenating output to file /vagrant/arsnova-mobile/src/main/webapp/build/temp/testing/ARSnova/sencha-compiler/cmd-packages.js
[exec] [INF] Adding external reference : @full-page => @overrides
[exec] [INF] Loading classpath entry /vagrant/arsnova-mobile/src/main/webapp/build/temp/testing/ARSnova/sencha-compiler/cmd-packages.js
[exec] [INF] Adding external reference : Ext.util.Observable => /vagrant/arsnova-mobile/src/main/webapp/build/temp/testing/ARSnova/sencha-compiler/cmd-packages.js
[exec] [INF]
[exec] [INF] -refresh-app:
[exec] [INF] Appending concatenated output to file /vagrant/arsnova-mobile/src/main/webapp/bootstrap.js
[exec] [INF] Appending concatenated output to file /vagrant/arsnova-mobile/src/main/webapp/bootstrap.js
[exec] [INF] Appending concatenated output to file /vagrant/arsnova-mobile/src/main/webapp/bootstrap.js
[exec] [INF] Appending concatenated output to file /vagrant/arsnova-mobile/src/main/webapp/bootstrap.js
[exec] [INF] Appending concatenated output to file /vagrant/arsnova-mobile/src/main/webapp/bootstrap.js
[exec] [INF] Appending content to /vagrant/arsnova-mobile/src/main/webapp/bootstrap.json
[exec] [INF]
[exec] [INF] -refresh:
[exec] [INF]
[exec] [INF] -after-refresh:
[exec] [INF]
[exec] [INF] resolve:
[exec] [INF]
[exec] [INF] js:
[exec] [INF]
[exec] [INF] -before-js:
[exec] [INF]
[exec] [INF] -init:
[exec] [INF]
[exec] [INF] -init-compiler:
[exec] [INF]
[exec] [INF] -detect-app-build-properties:
[exec] [INF]
[exec] [INF] -compile-js:
[exec] [INF] Concatenating output to file /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/app.js
[exec] [INF]
[exec] [INF] -js:
[exec] [INF]
[exec] [INF] -after-js:
[exec] [INF]
[exec] [INF] resources:
[exec] [INF]
[exec] [INF] -before-resources:
[exec] [INF]
[exec] [INF] -before-inherit-resources:
[exec] [INF]
[exec] [INF] -before-copy-resources:
[exec] [INF]
[exec] [INF] -init:
[exec] [INF]
[exec] [INF] -init-compiler:
[exec] [INF]
[exec] [INF] -resources:
[exec] [INF] merging resources into /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/resources
[exec] [INF] merged 0 resources into /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/resources
[exec] [INF] merging resources into /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova
[exec] [INF] merged 0 resources into /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova
[exec] [INF]
[exec] [INF] -after-copy-resources:
[exec] [INF]
[exec] [INF] -after-inherit-resources:
[exec] [INF]
[exec] [INF] -after-resources:
[exec] [INF]
[exec] [INF] sass:
[exec] [INF]
[exec] [INF] -before-sass:
[exec] [INF]
[exec] [INF] -compass-compile-theme-package:
[exec] [INF]
[exec] [INF] -compass-compile-theme-folders:
[exec] [INF]
[exec] [INF] -compass-compile-sass-dir:
[exec] [INF] executing compass using system installed ruby runtime
[exec] identical ../css/app.css
[exec] identical ../css/index.css
[exec] [INF]
[exec] [INF] -compass-compile:
[exec] [INF]
[exec] [INF] -sass:
[exec] [INF]
[exec] [INF] -after-sass:
[exec] [INF]
[exec] [INF] slice:
[exec] [INF]
[exec] [INF] -before-slice:
[exec] [INF]
[exec] [INF] -slice-images:
[exec] [INF]
[exec] [INF] -slice:
[exec] [INF]
[exec] [INF] -after-slice:
[exec] [INF]
[exec] [INF] page:
[exec] [INF]
[exec] [INF] -before-page:
[exec] [INF]
[exec] [INF] -init:
[exec] [INF]
[exec] [INF] -init-compiler:
[exec] [INF]
[exec] [INF] -copy-app-resources:
[exec] [INF] Copying page resources to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/lib/markdown/markdown.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/lib/markdown/markdown.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/lib/moment.min.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/lib/moment.min.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/lib/moment.de.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/lib/moment.de.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/lib/rsvp.min.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/lib/rsvp.min.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/app/utils/Ext.util.TaskRunner.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/app/utils/Ext.util.TaskRunner.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/app/utils/Ext.Array.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/app/utils/Ext.Array.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/app/internationalization.js to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/app/internationalization.js
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/resources/css/app.css to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/resources/css/app.css
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/resources/css/index.css to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/resources/css/index.css
[exec] [INF] copying /vagrant/arsnova-mobile/src/main/webapp/resources/css/arsnova.css to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/resources/css/arsnova.css
[exec] [INF]
[exec] [INF] -generate-deltas:
[exec] [INF]
[exec] [INF] -detect-app-build-properties:
[exec] [INF]
[exec] [INF] -build-standalone-json-manifest:
[exec] [INF]
[exec] [INF] -build-output-page:
[exec] [INF]
[exec] [INF] -build-output-microload-page:
[exec] [INF] Writing content to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/microloader.js
[exec] [INF] Appending content to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/microloader.js
[exec] [INF] Building output markup to /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/index.html
[exec] [INF] [delete] Deleting: /vagrant/arsnova-mobile/src/main/webapp/build/testing/ARSnova/microloader.js
[exec] [INF]
[exec] [INF] -generate-cache-manifest:
[exec] [INF]
[exec] [INF] -page:
[exec] [INF]
[exec] [INF] -after-page:
[exec] [INF]
[exec] [INF] native-package:
[exec] [INF]
[exec] [INF] -build:
[exec] [INF]
[exec] [INF] -after-build:
[exec] [INF]
[exec] [INF] build:
[exec] [INF]
[exec] [INF] app-build:
[exec]
BUILD SUCCESSFUL
Total time: 1 minute 33 seconds
real 1m34.372s
user 0m47.423s
sys 0m23.133s
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment