Skip to content

Instantly share code, notes, and snippets.

@lvca
Created May 10, 2016 21:45
Show Gist options
  • Save lvca/407d1b519ec5a19a60e9d00ca475c8a8 to your computer and use it in GitHub Desktop.
Save lvca/407d1b519ec5a19a60e9d00ca475c8a8 to your computer and use it in GitHub Desktop.
/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/bin/java -server -XX:MaxDirectMemorySize=512g -DORIENTDB_HOME=target -Dorientdb.www.path=src/site -Djava.util.logging.config.file=src/resources/orientdb-server0-log.properties -Dorientdb.config.file=${ORIENTDB_HOME}/config/orientdb-dserver-config-0.xml -Drhino.opt.level=9 -Dhazelcast.local.localAddress=127.0.0.1 -Dhazelcast.operation.thread.count=1 -Dhazelcast.operation.generic.thread.count=1 -Dhazelcast.event.thread.count=1 -Dhazelcast.client.event.thread.count=1 -Didea.launcher.port=7534 "-Didea.launcher.bin.path=/Applications/IntelliJ IDEA CE.app/Contents/bin" -Dfile.encoding=UTF-8 -classpath "/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/lib/ant-javafx.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/lib/dt.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/lib/javafx-mx.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/lib/jconsole.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/lib/sa-jdi.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/lib/tools.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/charsets.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/deploy.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/javaws.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/jce.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/jfr.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/jfxswt.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/jsse.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/management-agent.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/plugin.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/resources.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/rt.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/cldrdata.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/dnsns.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/jfxrt.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/localedata.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/nashorn.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/sunec.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/sunjce_provider.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/sunpkcs11.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_20.jdk/Contents/Home/jre/lib/ext/zipfs.jar:/Users/luca/Downloads/pitney-orientdb-cluster-test/target/test-classes:/Users/luca/Downloads/pitney-orientdb-cluster-test/target/classes:/Users/luca/work/dev/orientechnologies/orientdb/community/distributed/target/classes:/Users/luca/work/dev/orientechnologies/orientdb/community/tools/target/classes:/Users/luca/work/dev/orientechnologies/orientdb/community/core/target/classes:/Users/luca/.m2/repository/org/xerial/snappy/snappy-java/1.1.0.1/snappy-java-1.1.0.1.jar:/Users/luca/.m2/repository/com/googlecode/concurrentlinkedhashmap/concurrentlinkedhashmap-lru/1.4.1/concurrentlinkedhashmap-lru-1.4.1.jar:/Users/luca/work/dev/orientechnologies/orientdb/community/graphdb/target/classes:/Users/luca/.m2/repository/commons-collections/commons-collections/3.2.2/commons-collections-3.2.2.jar:/Users/luca/.m2/repository/com/tinkerpop/blueprints/blueprints-core/2.6.0/blueprints-core-2.6.0.jar:/Users/luca/.m2/repository/org/codehaus/jettison/jettison/1.3.3/jettison-1.3.3.jar:/Users/luca/.m2/repository/stax/stax-api/1.0.1/stax-api-1.0.1.jar:/Users/luca/.m2/repository/com/fasterxml/jackson/core/jackson-databind/2.2.3/jackson-databind-2.2.3.jar:/Users/luca/.m2/repository/com/fasterxml/jackson/core/jackson-annotations/2.2.3/jackson-annotations-2.2.3.jar:/Users/luca/.m2/repository/com/fasterxml/jackson/core/jackson-core/2.2.3/jackson-core-2.2.3.jar:/Users/luca/.m2/repository/com/carrotsearch/hppc/0.6.0/hppc-0.6.0.jar:/Users/luca/.m2/repository/commons-configuration/commons-configuration/1.6/commons-configuration-1.6.jar:/Users/luca/.m2/repository/commons-lang/commons-lang/2.4/commons-lang-2.4.jar:/Users/luca/.m2/repository/commons-digester/commons-digester/1.8/commons-digester-1.8.jar:/Users/luca/.m2/repository/commons-beanutils/commons-beanutils/1.7.0/commons-beanutils-1.7.0.jar:/Users/luca/.m2/repository/commons-beanutils/commons-beanutils-core/1.8.0/commons-beanutils-core-1.8.0.jar:/Users/luca/.m2/repository/commons-logging/commons-logging/1.1.1/commons-logging-1.1.1.jar:/Users/luca/.m2/repository/com/hazelcast/hazelcast-cloud/3.6.2/hazelcast-cloud-3.6.2.jar:/Users/luca/.m2/repository/com/hazelcast/hazelcast/3.6/hazelcast-3.6.jar:/Users/luca/.m2/repository/commons-io/commons-io/2.4/commons-io-2.4.jar:/Users/luca/work/dev/orientechnologies/orientdb/community/server/target/classes:/Users/luca/work/dev/orientechnologies/orientdb/community/client/target/classes:/Users/luca/.m2/repository/javax/mail/mail/1.4.7/mail-1.4.7.jar:/Users/luca/.m2/repository/javax/activation/activation/1.1/activation-1.1.jar:/Applications/IntelliJ IDEA CE.app/Contents/lib/idea_rt.jar" com.intellij.rt.execution.application.AppMain test.OrientDBCluster execute 0 1000000
Current directory: /Users/luca/Downloads/pitney-orientdb-cluster-test/.
2016-05-10 23:43:00:810 INFO OrientDB auto-config DISKCACHE=10,695MB (heap=3,641MB direct=524,288MB os=16,384MB) [orientechnologies]
2016-05-10 23:43:00:961 INFO Loading configuration from input stream [OServerConfigurationLoaderXml]
2016-05-10 23:43:01:244 INFO OrientDB Server v2.2.0-SNAPSHOT is starting up... [OServer]
2016-05-10 23:43:01:254 INFO Databases directory: /Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases [OServer]
2016-05-10 23:43:01:393 INFO {db=OSystem} Creating the system database 'OSystem' for current server [OServer]
2016-05-10 23:43:01:625 INFO {db=OSystem} Saving Command Cache config for db: OSystem [OCommandCacheSoftRefs]
2016-05-10 23:43:02:273 SEVER ODefaultServerSecurity.loadConfig() Could not access the security JSON file: /Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/config/security.json [ODefaultServerSecurity]
2016-05-10 23:43:02:291 INFO Listening binary connections on 0.0.0.0:2424 (protocol v.36, socket=default) [OServerNetworkListener]
2016-05-10 23:43:02:295 INFO Listening http connections on 0.0.0.0:2480 (protocol v.10, socket=default) [OServerNetworkListener]
2016-05-10 23:43:02:298 WARNI Found ORIENTDB_ROOT_PASSWORD variable, using this value as root's password [OServer]
2016-05-10 23:43:02:534 INFO Configuring Hazelcast from '/Users/luca/Downloads/pitney-orientdb-cluster-test/src/test/resources/hazelcast-0.xml'. [FileSystemXmlConfig]
2016-05-10 23:43:02:610 WARNI Name of the hazelcast schema location incorrect using default [AbstractXmlConfigHelper]
2016-05-10 23:43:02:875 INFO [LOCAL] [orientdb] [3.6] Picked Address[127.0.0.1]:2434, using socket ServerSocket[addr=/0:0:0:0:0:0:0:0,localport=2434], bind any local is true [DefaultAddressPicker]
2016-05-10 23:43:03:151 INFO [127.0.0.1]:2434 [orientdb] [3.6] Backpressure is disabled [OperationService]
2016-05-10 23:43:03:174 INFO [127.0.0.1]:2434 [orientdb] [3.6] Starting with 1 generic operation threads and 1 partition operation threads. [ClassicOperationExecutor]
2016-05-10 23:43:03:637 INFO [127.0.0.1]:2434 [orientdb] [3.6] Hazelcast 3.6 (20160125 - 13fea63) starting at Address[127.0.0.1]:2434 [system]
2016-05-10 23:43:03:638 INFO [127.0.0.1]:2434 [orientdb] [3.6] Copyright (c) 2008-2015, Hazelcast, Inc. All Rights Reserved. [system]
2016-05-10 23:43:03:638 INFO [127.0.0.1]:2434 [orientdb] [3.6] Configured Hazelcast Serialization version : 1 [system]
2016-05-10 23:43:03:643 INFO [127.0.0.1]:2434 [orientdb] [3.6] Creating TcpIpJoiner [Node]
2016-05-10 23:43:03:649 INFO [127.0.0.1]:2434 [orientdb] [3.6] Address[127.0.0.1]:2434 is STARTING [LifecycleService]
2016-05-10 23:43:03:850 INFO [127.0.0.1]:2434 [orientdb] [3.6] TcpIpConnectionManager configured with Non Blocking IO-threading model: 3 input threads and 3 output threads [NonBlockingIOThreadingModel]
2016-05-10 23:43:03:878 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2436, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:03:878 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2435, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:03:878 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2437, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:03:882 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2437. Reason: SocketException[Connection refused to address /127.0.0.1:2437] [InitConnectionTask]
2016-05-10 23:43:03:882 INFO [127.0.0.1]:2434 [orientdb] [3.6] Address[127.0.0.1]:2437 is added to the blacklist. [TcpIpJoiner]
2016-05-10 23:43:03:882 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2435. Reason: SocketException[Connection refused to address /127.0.0.1:2435] [InitConnectionTask]
2016-05-10 23:43:03:883 INFO [127.0.0.1]:2434 [orientdb] [3.6] Address[127.0.0.1]:2435 is added to the blacklist. [TcpIpJoiner]
2016-05-10 23:43:03:883 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2436. Reason: SocketException[Connection refused to address /127.0.0.1:2436] [InitConnectionTask]
2016-05-10 23:43:03:883 INFO [127.0.0.1]:2434 [orientdb] [3.6] Address[127.0.0.1]:2436 is added to the blacklist. [TcpIpJoiner]
2016-05-10 23:43:04:872 INFO [127.0.0.1]:2434 [orientdb] [3.6]
Members [1] {
Member [127.0.0.1]:2434 this
}
[TcpIpJoiner]
2016-05-10 23:43:04:904 INFO [127.0.0.1]:2434 [orientdb] [3.6] Hazelcast JMX agent enabled. [ManagementService]
2016-05-10 23:43:04:935 INFO [127.0.0.1]:2434 [orientdb] [3.6] Address[127.0.0.1]:2434 is STARTED [LifecycleService]
2016-05-10 23:43:04:935 INFO Starting distributed server 'europe0' (hzID=-1)... [OHazelcastPlugin]
2016-05-10 23:43:04:990 INFO [127.0.0.1]:2434 [orientdb] [3.6] Initializing cluster partition table arrangement... [InternalPartitionService]
2016-05-10 23:43:05:082 INFO [europe0] Opening database 'platform'... [OHazelcastPlugin]
2016-05-10 23:43:05:096 INFO [europe0] loaded database configuration from disk: src/test/resources/default-distributed-db-config.json [OHazelcastPlugin]
2016-05-10 23:43:05:107 INFO [europe0] New distributed configuration for database: platform (version=1)
LEGEND: X = Owner, o = Copy
+--------+-----------+----------+
|CLUSTER |writeQuorum|readQuorum|
+--------+-----------+----------+
|* | 1 | 1 |
|internal| 1 | 1 |
+--------+-----------+----------+
[OHazelcastPlugin]
2016-05-10 23:43:05:107 INFO [europe0] Saving distributed configuration file for database 'platform' to: /Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform/distributed-config.json [OHazelcastPlugin]
2016-05-10 23:43:05:113 INFO [europe0] Distributed servers status:
+--------+--------+---------+-----+---------+----------------+----------------+----------------------+
|Name |Status |Databases|Conns|StartedOn|Binary |HTTP |UsedMemory |
+--------+--------+---------+-----+---------+----------------+----------------+----------------------+
|europe0*|STARTING| |0 |23:43:02 |192.168.1.5:2424|192.168.1.5:2480|56.92MB/3.56GB (1.56%)|
+--------+--------+---------+-----+---------+----------------+----------------+----------------------+
[OHazelcastPlugin]
2016-05-10 23:43:05:115 INFO [europe0] Current node started as MASTER for database 'platform' [OHazelcastPlugin$2]
2016-05-10 23:43:05:116 INFO [europe0] New distributed configuration for database: platform (version=1)
LEGEND: X = Owner, o = Copy
+--------+-----------+----------+
|CLUSTER |writeQuorum|readQuorum|
+--------+-----------+----------+
|* | 1 | 1 |
|internal| 1 | 1 |
+--------+-----------+----------+
[OHazelcastPlugin]
2016-05-10 23:43:05:127 INFO [europe0] Adding node 'europe0' in partition: db=platform [*] [ODistributedDatabaseImpl$1]
2016-05-10 23:43:05:128 INFO [europe0] Publishing ONLINE status for database europe0.platform... [ODistributedDatabaseImpl]
2016-05-10 23:43:05:131 INFO [europe0] New distributed configuration for database: platform (version=2)
LEGEND: X = Owner, o = Copy
+--------+-----------+----------+-------+
| | | |MASTER |
| | | |ONLINE |
+--------+-----------+----------+-------+
|CLUSTER |writeQuorum|readQuorum|europe0|
+--------+-----------+----------+-------+
|* | 1 | 1 | X |
|internal| 1 | 1 | |
+--------+-----------+----------+-------+
[OHazelcastPlugin]
2016-05-10 23:43:05:131 INFO [europe0] Saving distributed configuration file for database 'platform' to: /Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform/distributed-config.json [OHazelcastPlugin]
2016-05-10 23:43:05:136 WARNI [europe0] Updated node status to 'ONLINE' [OHazelcastPlugin]
2016-05-10 23:43:05:139 INFO [europe0] Distributed servers status:
+--------+------+------------------------+-----+---------+----------------+----------------+----------------------+
|Name |Status|Databases |Conns|StartedOn|Binary |HTTP |UsedMemory |
+--------+------+------------------------+-----+---------+----------------+----------------+----------------------+
|europe0*|ONLINE|platform=ONLINE (MASTER)|0 |23:43:02 |192.168.1.5:2424|192.168.1.5:2480|69.19MB/3.56GB (1.90%)|
+--------+------+------------------------+-----+---------+----------------+----------------+----------------------+
[OHazelcastPlugin]
2016-05-10 23:43:05:140 SEVER ODefaultServerSecurity.onAfterActivate() Configuration document is empty [ODefaultServerSecurity]
2016-05-10 23:43:05:140 INFO OrientDB Server is active v2.2.0-SNAPSHOT. [OServer]
2016-05-10 23:43:05:626 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:626 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:626 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:627 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:627 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:627 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:627 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:628 INFO Starting Writer... [OrientDBCluster]
2016-05-10 23:43:05:628 INFO Starting Reader... [OrientDBCluster]
2016-05-10 23:43:05:693 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:134} counting class: 136 counting cluster: 18 = 136 insert/sec [OrientDBCluster]
2016-05-10 23:43:06:696 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:4559} counting class: 4559 counting cluster: 570 = 4423 insert/sec [OrientDBCluster]
2016-05-10 23:43:07:700 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:12113} counting class: 12124 counting cluster: 1516 = 7565 insert/sec [OrientDBCluster]
2016-05-10 23:43:08:705 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:20766} counting class: 20769 counting cluster: 2597 = 8645 insert/sec [OrientDBCluster]
2016-05-10 23:43:09:710 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:28616} counting class: 28616 counting cluster: 3577 = 7847 insert/sec [OrientDBCluster]
2016-05-10 23:43:10:715 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:37731} counting class: 37732 counting cluster: 4717 = 9116 insert/sec [OrientDBCluster]
2016-05-10 23:43:11:718 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:48644} counting class: 48644 counting cluster: 6081 = 10912 insert/sec [OrientDBCluster]
2016-05-10 23:43:12:721 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:59805} counting class: 59809 counting cluster: 7476 = 11165 insert/sec [OrientDBCluster]
2016-05-10 23:43:13:737 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:71948} counting class: 71949 counting cluster: 8994 = 12140 insert/sec [OrientDBCluster]
2016-05-10 23:43:14:739 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:84008} counting class: 84010 counting cluster: 10502 = 12061 insert/sec [OrientDBCluster]
2016-05-10 23:43:15:745 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:92816} counting class: 92816 counting cluster: 11602 = 8806 insert/sec [OrientDBCluster]
2016-05-10 23:43:16:750 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:105857} counting class: 105864 counting cluster: 13234 = 13048 insert/sec [OrientDBCluster]
2016-05-10 23:43:17:295 INFO [127.0.0.1]:2434 [orientdb] [3.6] Accepting socket connection from /127.0.0.1:60524 [SocketAcceptorThread]
2016-05-10 23:43:17:304 INFO [127.0.0.1]:2434 [orientdb] [3.6] Established socket connection between /127.0.0.1:2434 and /127.0.0.1:60524 [TcpIpConnectionManager]
2016-05-10 23:43:17:755 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:119414} counting class: 119423 counting cluster: 14929 = 13559 insert/sec [OrientDBCluster]
2016-05-10 23:43:18:757 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:135031} counting class: 135033 counting cluster: 16880 = 15610 insert/sec [OrientDBCluster]
2016-05-10 23:43:19:760 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:153708} counting class: 153713 counting cluster: 19215 = 18680 insert/sec [OrientDBCluster]
2016-05-10 23:43:20:766 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:173131} counting class: 173133 counting cluster: 21643 = 19420 insert/sec [OrientDBCluster]
2016-05-10 23:43:21:768 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:192933} counting class: 192937 counting cluster: 24118 = 19804 insert/sec [OrientDBCluster]
2016-05-10 23:43:22:770 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:213327} counting class: 213332 counting cluster: 26667 = 20395 insert/sec [OrientDBCluster]
2016-05-10 23:43:23:308 INFO [127.0.0.1]:2434 [orientdb] [3.6]
Members [2] {
Member [127.0.0.1]:2434 this
Member [127.0.0.1]:2435
}
[ClusterService]
2016-05-10 23:43:23:310 WARNI [europe0] Added new node id=Member [127.0.0.1]:2435 name=ext:0b562ff4-0854-4c11-b099-369f49d8837b [OHazelcastPlugin]
2016-05-10 23:43:23:565 INFO [127.0.0.1]:2434 [orientdb] [3.6] Re-partitioning cluster data... Migration queue size: 135 [InternalPartitionService]
2016-05-10 23:43:23:777 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:232014} counting class: 232026 counting cluster: 29004 = 18694 insert/sec [OrientDBCluster]
2016-05-10 23:43:24:780 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:250398} counting class: 250407 counting cluster: 31302 = 18381 insert/sec [OrientDBCluster]
2016-05-10 23:43:24:954 INFO [127.0.0.1]:2434 [orientdb] [3.6] All migration tasks have been completed, queues are empty. [InternalPartitionService]
2016-05-10 23:43:25:425 INFO [europe0]<-[ext:0b562ff4-0854-4c11-b099-369f49d8837b] Received updated about registered nodes [OHazelcastPlugin]
2016-05-10 23:43:25:454 INFO [europe0]<-[europe1] Added node configuration id=Member [127.0.0.1]:2435 name=europe1, now 2 nodes are configured [OHazelcastPlugin]
2016-05-10 23:43:25:457 INFO [europe0] Distributed servers status:
+--------+--------+------------------------+-----+---------+----------------+----------------+-----------------------+
|Name |Status |Databases |Conns|StartedOn|Binary |HTTP |UsedMemory |
+--------+--------+------------------------+-----+---------+----------------+----------------+-----------------------+
|europe0*|ONLINE |platform=ONLINE (MASTER)|0 |23:43:02 |192.168.1.5:2424|192.168.1.5:2480|117.12MB/3.56GB (3.22%)|
|europe1 |STARTING| |0 |23:43:13 |192.168.1.5:2425|192.168.1.5:2481|63.37MB/3.56GB (1.74%) |
+--------+--------+------------------------+-----+---------+----------------+----------------+-----------------------+
[OHazelcastPlugin]
2016-05-10 23:43:25:485 INFO [europe0]<-[europe1] Received new status europe1.platform=SYNCHRONIZING [OHazelcastPlugin]
2016-05-10 23:43:25:492 INFO [europe0]<-[europe1] Updated configuration db=platform [OHazelcastPlugin]
2016-05-10 23:43:25:497 INFO [europe0] New distributed configuration for database: platform (version=3)
LEGEND: X = Owner, o = Copy
+--------+-----------+----------+-------+-------------+
| | | |MASTER | MASTER |
| | | |ONLINE |SYNCHRONIZING|
+--------+-----------+----------+-------+-------------+
|CLUSTER |writeQuorum|readQuorum|europe0| europe1 |
+--------+-----------+----------+-------+-------------+
|* | 2 | 1 | X | o |
|internal| 2 | 1 | | |
+--------+-----------+----------+-------+-------------+
[OHazelcastPlugin]
2016-05-10 23:43:25:498 INFO [europe0] Saving distributed configuration file for database 'platform' to: /Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform/distributed-config.json [OHazelcastPlugin]
2016-05-10 23:43:25:501 INFO [europe0] Current node started as MASTER for database 'platform' [OHazelcastPlugin]
2016-05-10 23:43:25:514 INFO [europe0] Received updated status europe1.platform=SYNCHRONIZING [OHazelcastPlugin]
2016-05-10 23:43:25:515 INFO [europe0]->[europe1] Deploying database platform... [OSyncDatabaseTask]
2016-05-10 23:43:25:515 INFO [europe0]->[europe1] Creating backup of database 'platform' (compressionRate=7) in directory: /var/folders/zc/y34429014c3bt_x1587qblth0000gn/T/orientdb/backup_platform.zip... [OSyncDatabaseTask]
2016-05-10 23:43:25:516 INFO [europe0] Distributed servers status:
+--------+--------+-------------------------------+-----+---------+----------------+----------------+-----------------------+
|Name |Status |Databases |Conns|StartedOn|Binary |HTTP |UsedMemory |
+--------+--------+-------------------------------+-----+---------+----------------+----------------+-----------------------+
|europe0*|ONLINE |platform=SYNCHRONIZING (MASTER)|0 |23:43:02 |192.168.1.5:2424|192.168.1.5:2480|117.12MB/3.56GB (3.22%)|
|europe1 |STARTING| |0 |23:43:13 |192.168.1.5:2425|192.168.1.5:2481|63.37MB/3.56GB (1.74%) |
+--------+--------+-------------------------------+-----+---------+----------------+----------------+-----------------------+
[OHazelcastPlugin]
2016-05-10 23:43:25:516 INFO [europe0] Received updated status europe0.platform=SYNCHRONIZING [OHazelcastPlugin]
2016-05-10 23:43:25:784 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 12840 insert/sec [OrientDBCluster]
2016-05-10 23:43:25:878 INFO - Compressing file cache.stt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:882 INFO ok size=27.13KB compressedSize=4161 ratio=86% elapsed=5ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:883 INFO - Compressing file command-cache.json... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:883 INFO ok size=114b compressedSize=98 ratio=15% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:883 INFO - Compressing file data.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:884 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:884 INFO - Compressing file data.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:885 INFO ok size=65.00KB compressedSize=123 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:885 INFO - Compressing file data_1.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:885 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:885 INFO - Compressing file data_1.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:886 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:886 INFO - Compressing file data_2.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:887 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:887 INFO - Compressing file data_2.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:887 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:887 INFO - Compressing file data_3.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:888 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:888 INFO - Compressing file data_3.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:888 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:888 INFO - Compressing file data_4.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:889 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:890 INFO - Compressing file data_4.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:891 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:891 INFO - Compressing file data_5.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:891 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:891 INFO - Compressing file data_5.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:892 INFO ok size=65.00KB compressedSize=123 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:892 INFO - Compressing file data_6.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:892 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:892 INFO - Compressing file data_6.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:893 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:893 INFO - Compressing file data_7.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:893 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:893 INFO - Compressing file data_7.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:894 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:894 INFO - Compressing file database.ocf... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:895 INFO ok size=3.62KB compressedSize=559 ratio=85% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:895 INFO - Compressing file default.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:895 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:895 INFO - Compressing file default.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:896 INFO ok size=65.00KB compressedSize=121 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:896 INFO - Compressing file dictionary.nbt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:896 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:896 INFO - Compressing file dictionary.sbt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO ok size=65.00KB compressedSize=128 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO - Compressing file dirty.fl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO ok size=1b compressedSize=3 ratio=-200% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO - Compressing file distributed-config.json... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO ok size=350b compressedSize=179 ratio=49% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO - Compressing file e.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:897 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:898 INFO - Compressing file e.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:898 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:898 INFO - Compressing file e_1.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:899 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:899 INFO - Compressing file e_1.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:899 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:899 INFO - Compressing file e_2.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:899 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:899 INFO - Compressing file e_2.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:900 INFO ok size=65.00KB compressedSize=123 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:900 INFO - Compressing file e_3.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:900 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:900 INFO - Compressing file e_3.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:901 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:901 INFO - Compressing file e_4.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:901 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:901 INFO - Compressing file e_4.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:902 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:902 INFO - Compressing file e_5.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:902 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:902 INFO - Compressing file e_5.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:903 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:903 INFO - Compressing file e_6.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:903 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:903 INFO - Compressing file e_6.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:904 INFO ok size=65.00KB compressedSize=123 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:904 INFO - Compressing file e_7.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:904 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:904 INFO - Compressing file e_7.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:905 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:905 INFO - Compressing file id_pk.asmm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:905 INFO ok size=41b compressedSize=41 ratio=0% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:25:905 INFO - Compressing file id_pk_0.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:353 INFO ok size=16.00MB compressedSize=1685632 ratio=90% elapsed=448ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:354 INFO - Compressing file id_pk_0.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:354 INFO ok size=65.00KB compressedSize=131 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:354 INFO - Compressing file id_pk_0.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:354 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:354 INFO - Compressing file id_pk_0.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:355 INFO ok size=65.00KB compressedSize=646 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:355 INFO - Compressing file id_pk_1.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:785 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:26:821 INFO ok size=16.00MB compressedSize=1689225 ratio=90% elapsed=466ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:821 INFO - Compressing file id_pk_1.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:822 INFO ok size=65.00KB compressedSize=132 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:822 INFO - Compressing file id_pk_1.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:822 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:822 INFO - Compressing file id_pk_1.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:823 INFO ok size=65.00KB compressedSize=645 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:26:823 INFO - Compressing file id_pk_2.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:272 INFO ok size=16.00MB compressedSize=1694881 ratio=90% elapsed=449ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:272 INFO - Compressing file id_pk_2.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:273 INFO ok size=65.00KB compressedSize=132 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:273 INFO - Compressing file id_pk_2.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:273 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:273 INFO - Compressing file id_pk_2.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:274 INFO ok size=65.00KB compressedSize=646 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:274 INFO - Compressing file id_pk_3.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:705 INFO ok size=16.00MB compressedSize=1689885 ratio=90% elapsed=430ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:705 INFO - Compressing file id_pk_3.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:705 INFO ok size=65.00KB compressedSize=132 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:705 INFO - Compressing file id_pk_3.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:705 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:705 INFO - Compressing file id_pk_3.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:706 INFO ok size=65.00KB compressedSize=645 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:706 INFO - Compressing file id_pk_4.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:27:789 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:28:130 INFO ok size=16.00MB compressedSize=1692979 ratio=90% elapsed=424ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:130 INFO - Compressing file id_pk_4.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:130 INFO ok size=65.00KB compressedSize=130 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:130 INFO - Compressing file id_pk_4.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:131 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:131 INFO - Compressing file id_pk_4.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:131 INFO ok size=65.00KB compressedSize=647 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:131 INFO - Compressing file id_pk_5.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:248 INFO [europe0]->[europe1] - transferring chunk #1 offset=0 size=8388173... [OSyncDatabaseTask]
2016-05-10 23:43:28:249 INFO [europe0]->[europe1] Deploy database task completed [OSyncDatabaseTask]
2016-05-10 23:43:28:574 INFO ok size=16.00MB compressedSize=1683376 ratio=90% elapsed=443ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:574 INFO - Compressing file id_pk_5.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:575 INFO ok size=65.00KB compressedSize=132 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:575 INFO - Compressing file id_pk_5.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:575 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:575 INFO - Compressing file id_pk_5.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:576 INFO ok size=65.00KB compressedSize=646 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:576 INFO - Compressing file id_pk_6.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:28:795 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:29:072 INFO ok size=16.00MB compressedSize=1686787 ratio=90% elapsed=496ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:072 INFO - Compressing file id_pk_6.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:073 INFO ok size=65.00KB compressedSize=130 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:073 INFO - Compressing file id_pk_6.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:073 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:073 INFO - Compressing file id_pk_6.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:074 INFO ok size=65.00KB compressedSize=645 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:074 INFO - Compressing file id_pk_7.asb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:521 INFO ok size=16.00MB compressedSize=1682685 ratio=90% elapsed=447ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:521 INFO - Compressing file id_pk_7.asm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:521 INFO ok size=65.00KB compressedSize=129 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:521 INFO - Compressing file id_pk_7.asn... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:522 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:522 INFO - Compressing file id_pk_7.ast... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:522 INFO ok size=65.00KB compressedSize=647 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:523 INFO - Compressing file index.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:523 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:523 INFO - Compressing file index.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:523 INFO ok size=65.00KB compressedSize=122 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:524 INFO - Compressing file internal.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:524 INFO ok size=65.00KB compressedSize=133 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:524 INFO - Compressing file internal.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:526 INFO ok size=129.00KB compressedSize=7370 ratio=95% elapsed=2ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:526 INFO - Compressing file manindex.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:526 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:526 INFO - Compressing file manindex.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:527 INFO ok size=65.00KB compressedSize=122 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:527 INFO - Compressing file name_id_map.cm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:527 INFO ok size=4.42KB compressedSize=892 ratio=81% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:527 INFO - Compressing file ofunction.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:527 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:527 INFO - Compressing file OFunction.name.hib... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:630 INFO ok size=16.00MB compressedSize=21748 ratio=100% elapsed=103ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:630 INFO - Compressing file OFunction.name.him... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:631 INFO ok size=65.00KB compressedSize=127 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:631 INFO - Compressing file OFunction.name.hit... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:632 INFO ok size=65.00KB compressedSize=646 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:632 INFO - Compressing file OFunction.name.hnb... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:632 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:632 INFO - Compressing file ofunction.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:633 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:633 INFO - Compressing file orole.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:633 INFO ok size=65.00KB compressedSize=132 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:634 INFO - Compressing file ORole.name.nbt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:634 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:634 INFO - Compressing file ORole.name.sbt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:634 INFO ok size=65.00KB compressedSize=185 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:634 INFO - Compressing file orole.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:636 INFO ok size=129.00KB compressedSize=629 ratio=100% elapsed=2ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:636 INFO - Compressing file oschedule.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:636 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:636 INFO - Compressing file oschedule.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:636 INFO ok size=65.00KB compressedSize=119 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:636 INFO - Compressing file osequence.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:637 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:637 INFO - Compressing file osequence.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:637 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:637 INFO - Compressing file ouser.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:638 INFO ok size=65.00KB compressedSize=131 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:638 INFO - Compressing file OUser.name.nbt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:638 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:638 INFO - Compressing file OUser.name.sbt... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:638 INFO ok size=65.00KB compressedSize=183 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:638 INFO - Compressing file ouser.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:639 INFO ok size=129.00KB compressedSize=643 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:639 INFO - Compressing file person.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:682 INFO ok size=449.00KB compressedSize=55909 ratio=88% elapsed=43ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:683 INFO - Compressing file person.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:801 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:29:905 INFO ok size=14.88MB compressedSize=1533868 ratio=91% elapsed=222ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:905 INFO - Compressing file person_1.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:951 INFO ok size=449.00KB compressedSize=55915 ratio=88% elapsed=46ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:29:951 INFO - Compressing file person_1.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:184 INFO ok size=14.88MB compressedSize=1534291 ratio=91% elapsed=233ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:184 INFO - Compressing file person_2.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:229 INFO [europe0]->[europe1] - transferring chunk #2 offset=8388173 size=8388436... [OCopyDatabaseChunkTask]
2016-05-10 23:43:30:236 INFO ok size=449.00KB compressedSize=55895 ratio=88% elapsed=52ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:237 INFO - Compressing file person_2.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:453 INFO ok size=14.88MB compressedSize=1534246 ratio=91% elapsed=215ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:453 INFO - Compressing file person_3.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:495 INFO ok size=449.00KB compressedSize=55915 ratio=88% elapsed=42ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:495 INFO - Compressing file person_3.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:702 INFO ok size=14.88MB compressedSize=1534542 ratio=91% elapsed=207ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:702 INFO - Compressing file person_4.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:743 INFO ok size=449.00KB compressedSize=55902 ratio=88% elapsed=41ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:743 INFO - Compressing file person_4.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:805 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:30:950 INFO ok size=14.88MB compressedSize=1534150 ratio=91% elapsed=207ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:950 INFO - Compressing file person_5.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:993 INFO ok size=449.00KB compressedSize=55903 ratio=88% elapsed=43ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:30:994 INFO - Compressing file person_5.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:192 INFO ok size=14.88MB compressedSize=1534211 ratio=91% elapsed=198ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:192 INFO - Compressing file person_6.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:233 INFO ok size=449.00KB compressedSize=55911 ratio=88% elapsed=40ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:233 INFO - Compressing file person_6.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:433 INFO ok size=14.88MB compressedSize=1534002 ratio=91% elapsed=200ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:433 INFO - Compressing file person_7.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:473 INFO ok size=449.00KB compressedSize=55915 ratio=88% elapsed=40ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:474 INFO - Compressing file person_7.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:688 INFO ok size=14.88MB compressedSize=1533838 ratio=91% elapsed=214ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:688 INFO - Compressing file platform.wmr... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO ok size=40b compressedSize=18 ratio=55% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO - Compressing file v.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO - Compressing file v.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO ok size=65.00KB compressedSize=124 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO - Compressing file v_1.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:689 INFO - Compressing file v_1.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:690 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:690 INFO - Compressing file v_2.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:690 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:690 INFO - Compressing file v_2.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO ok size=65.00KB compressedSize=123 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO - Compressing file v_3.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO - Compressing file v_3.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO - Compressing file v_4.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:691 INFO - Compressing file v_4.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:692 INFO ok size=65.00KB compressedSize=123 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:692 INFO - Compressing file v_5.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:692 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:692 INFO - Compressing file v_5.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:693 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=1ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:693 INFO - Compressing file v_6.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:693 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:693 INFO - Compressing file v_6.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:693 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:693 INFO - Compressing file v_7.cpm... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:694 INFO ok size=1024b compressedSize=16 ratio=99% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:694 INFO - Compressing file v_7.pcl... [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:694 INFO ok size=65.00KB compressedSize=125 ratio=100% elapsed=0ms [OSyncDatabaseTask$1$1]
2016-05-10 23:43:31:699 INFO [europe0]->[europe1] Backup of database 'platform' completed. lastOperationId=1.0... [OSyncDatabaseTask$1]
2016-05-10 23:43:31:807 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:32:812 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:33:818 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:34:824 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:35:383 INFO [europe0]->[europe1] - transferring chunk #3 offset=16776609 size=8388608... [OCopyDatabaseChunkTask]
2016-05-10 23:43:35:829 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:36:834 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:37:839 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:38:844 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:39:848 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:40:487 INFO [europe0]->[europe1] - transferring chunk #4 offset=25165217 size=1124224... [OCopyDatabaseChunkTask]
2016-05-10 23:43:40:487 INFO [europe0] Received updated status europe0.platform=ONLINE [OHazelcastPlugin]
2016-05-10 23:43:40:488 INFO [europe0] Distributed servers status:
+--------+--------+------------------------+-----+---------+----------------+----------------+------------------------+
|Name |Status |Databases |Conns|StartedOn|Binary |HTTP |UsedMemory |
+--------+--------+------------------------+-----+---------+----------------+----------------+------------------------+
|europe0*|ONLINE |platform=ONLINE (MASTER)|2 |23:43:02 |192.168.1.5:2424|192.168.1.5:2480|953.70MB/3.56GB (26.19%)|
|europe1 |STARTING| |0 |23:43:13 |192.168.1.5:2425|192.168.1.5:2481|63.37MB/3.56GB (1.74%) |
+--------+--------+------------------------+-----+---------+----------------+----------------+------------------------+
[OHazelcastPlugin]
2016-05-10 23:43:40:852 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:263247} counting class: 263247 counting cluster: 32906 = 0 insert/sec [OrientDBCluster]
2016-05-10 23:43:41:173 INFO [europe0]<-[europe1] Received updated status europe1.platform=ONLINE [OHazelcastPlugin]
2016-05-10 23:43:41:184 INFO [europe0]<-[europe1] Updated configuration db=platform [OHazelcastPlugin]
2016-05-10 23:43:41:189 INFO [europe0] New distributed configuration for database: platform (version=24)
LEGEND: X = Owner, o = Copy
+-----------+-----------+----------+-------+-------+
| | | |MASTER |MASTER |
| | | |ONLINE |ONLINE |
+-----------+-----------+----------+-------+-------+
|CLUSTER |writeQuorum|readQuorum|europe0|europe1|
+-----------+-----------+----------+-------+-------+
|* | 2 | 1 | X | o |
|data_1 | 2 | 1 | o | X |
|data_2 | 2 | 1 | o | X |
|data_3 | 2 | 1 | o | X |
|data_4 | 2 | 1 | o | X |
|e_4 | 2 | 1 | o | X |
|e_5 | 2 | 1 | o | X |
|e_6 | 2 | 1 | o | X |
|e_7 | 2 | 1 | o | X |
|internal | 2 | 1 | | |
|ofunction_0| 2 | 1 | o | X |
|orole_0 | 2 | 1 | o | X |
|oschedule_0| 2 | 1 | o | X |
|osequence_0| 2 | 1 | o | X |
|ouser_0 | 2 | 1 | o | X |
|person | 2 | 1 | o | X |
|person_1 | 2 | 1 | o | X |
|person_6 | 2 | 1 | o | X |
|person_7 | 2 | 1 | o | X |
|v_3 | 2 | 1 | o | X |
|v_5 | 2 | 1 | o | X |
|v_6 | 2 | 1 | o | X |
|v_7 | 2 | 1 | o | X |
+-----------+-----------+----------+-------+-------+
[OHazelcastPlugin]
2016-05-10 23:43:41:189 INFO [europe0] Saving distributed configuration file for database 'platform' to: /Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform/distributed-config.json [OHazelcastPlugin]
2016-05-10 23:43:41:193 INFO [europe0] Current node started as MASTER for database 'platform' [OHazelcastPlugin]
2016-05-10 23:43:41:853 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:264261} counting class: 264261 counting cluster: 33077 = 1014 insert/sec [OrientDBCluster]
2016-05-10 23:43:42:855 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:267530} counting class: 267531 counting cluster: 33598 = 3270 insert/sec [OrientDBCluster]
2016-05-10 23:43:43:857 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:271598} counting class: 271598 counting cluster: 34246 = 4067 insert/sec [OrientDBCluster]
2016-05-10 23:43:44:858 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:276813} counting class: 276813 counting cluster: 35044 = 5215 insert/sec [OrientDBCluster]
2016-05-10 23:43:45:860 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:282136} counting class: 282137 counting cluster: 35858 = 5324 insert/sec [OrientDBCluster]
2016-05-10 23:43:46:862 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:286173} counting class: 286174 counting cluster: 36460 = 4037 insert/sec [OrientDBCluster]
2016-05-10 23:43:47:867 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:290784} counting class: 290784 counting cluster: 37152 = 4610 insert/sec [OrientDBCluster]
2016-05-10 23:43:48:870 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:296384} counting class: 296385 counting cluster: 37971 = 5601 insert/sec [OrientDBCluster]
2016-05-10 23:43:49:358 WARNI [europe0]->[europe1] Error on sending message to distributed node (java.net.SocketException: Broken pipe) retrying (1/3) [ORemoteServerChannel]
2016-05-10 23:43:49:362 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connection [Address[127.0.0.1]:2435] lost. Reason: java.io.EOFException[Remote socket closed!] [TcpIpConnection]
2016-05-10 23:43:49:363 WARNI [127.0.0.1]:2434 [orientdb] [3.6] hz._hzInstance_1_orientdb.IO.thread-in-0 Closing socket to endpoint Address[127.0.0.1]:2435, Cause:java.io.EOFException: Remote socket closed! [NonBlockingSocketReader]
2016-05-10 23:43:49:561 WARNI [europe0]->[europe1] Error on reconnecting to distributed node (java.net.ConnectException: Connection refused) [ORemoteServerChannel]
2016-05-10 23:43:49:562 WARNI [europe0]->[europe1] Error on sending message to distributed node (java.net.SocketException: Socket closed) retrying (2/3) [ORemoteServerChannel]
2016-05-10 23:43:49:699 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2435, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:49:700 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2435. Reason: SocketException[Connection refused to address /127.0.0.1:2435] [InitConnectionTask]
2016-05-10 23:43:49:872 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:299450} counting class: 299450 counting cluster: 38399 = 3065 insert/sec [OrientDBCluster]
2016-05-10 23:43:49:967 WARNI [europe0]->[europe1] Error on reconnecting to distributed node (java.net.ConnectException: Connection refused) [ORemoteServerChannel]
2016-05-10 23:43:49:968 WARNI [europe0]->[europe1] Error on sending message to distributed node (java.net.SocketException: Socket closed) retrying (3/3) [ORemoteServerChannel]
2016-05-10 23:43:50:162 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2435, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:50:163 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2435. Reason: SocketException[Connection refused to address /127.0.0.1:2435] [InitConnectionTask]
2016-05-10 23:43:50:164 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2435, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:50:164 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2435. Reason: SocketException[Connection refused to address /127.0.0.1:2435] [InitConnectionTask]
2016-05-10 23:43:50:571 WARNI [europe0]->[europe1] Error on reconnecting to distributed node (java.net.ConnectException: Connection refused) [ORemoteServerChannel]
2016-05-10 23:43:50:573 WARNI [europe0]->[europe1] Error on sending message to distributed node (java.net.SocketException: Socket closed) retrying (1/3) [ORemoteServerChannel]
2016-05-10 23:43:50:668 INFO [127.0.0.1]:2434 [orientdb] [3.6] Connecting to /127.0.0.1:2435, timeout: 0, bind-any: true [InitConnectionTask]
2016-05-10 23:43:50:668 INFO [127.0.0.1]:2434 [orientdb] [3.6] Could not connect to: /127.0.0.1:2435. Reason: SocketException[Connection refused to address /127.0.0.1:2435] [InitConnectionTask]
2016-05-10 23:43:50:668 WARNI [127.0.0.1]:2434 [orientdb] [3.6] Removing connection to endpoint Address[127.0.0.1]:2435 Cause => java.net.SocketException {Connection refused to address /127.0.0.1:2435}, Error-Count: 5 [TcpIpConnectionMonitor]
2016-05-10 23:43:50:669 INFO [127.0.0.1]:2434 [orientdb] [3.6] Removing Member [127.0.0.1]:2435 [ClusterService]
2016-05-10 23:43:50:669 INFO [127.0.0.1]:2434 [orientdb] [3.6] Removing Member [127.0.0.1]:2435 [InternalPartitionService]
2016-05-10 23:43:50:671 INFO [127.0.0.1]:2434 [orientdb] [3.6]
Members [1] {
Member [127.0.0.1]:2434 this
}
[ClusterService]
2016-05-10 23:43:50:672 INFO [127.0.0.1]:2434 [orientdb] [3.6] Committing/rolling-back alive transactions of Member [127.0.0.1]:2435, UUID: 0b562ff4-0854-4c11-b099-369f49d8837b [TransactionManagerService]
2016-05-10 23:43:50:680 WARNI [europe1] Node removed id=Member [127.0.0.1]:2435 name=europe1 [OHazelcastPlugin]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14234 task=record_create(#28:36462 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14233 task=record_create(#27:36462 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14231 task=record_create(#29:36461 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14236 task=record_create(#30:36463 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14238 task=record_create(#28:36463 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:681 INFO [europe0] Distributed servers status:
+--------+------+------------------------+-----+---------+----------------+----------------+------------------------+
|Name |Status|Databases |Conns|StartedOn|Binary |HTTP |UsedMemory |
+--------+------+------------------------+-----+---------+----------------+----------------+------------------------+
|europe0*|ONLINE|platform=ONLINE (MASTER)|2 |23:43:02 |192.168.1.5:2424|192.168.1.5:2480|830.09MB/3.56GB (22.80%)|
+--------+------+------------------------+-----+---------+----------------+----------------+------------------------+
[OHazelcastPlugin]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14235 task=record_create(#29:36462 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:681 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14237 task=record_create(#27:36463 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:682 WARNI [europe0] Detected 1 node(s) in timeout or in conflict and quorum (2) has not been reached, rolling back changes for request (id=0.14232 task=record_create(#30:36462 v.0) user=#5:0) [ODistributedResponseManager]
2016-05-10 23:43:50:712 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.5-a5d26e44-667f-4b5b-80ae-10d269b433b0, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #30:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #30:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14232 task=record_create(#30:36462 v.0) user=#5:0). Elapsed=1332ms No server in conflict. Received:
- europe0: #30:36462 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:715 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:715 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.4-a52dbf6e-d7c1-4bc0-b3be-b79923432a56, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #29:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #29:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14235 task=record_create(#29:36462 v.0) user=#5:0). Elapsed=1331ms No server in conflict. Received:
- europe0: #29:36462 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:716 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:716 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:716 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:716 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:716 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.3-4d9be7bf-0346-499c-b8c1-6b770e6f1ced, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #28:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #28:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14234 task=record_create(#28:36462 v.0) user=#5:0). Elapsed=1332ms No server in conflict. Received:
- europe0: #28:36462 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:717 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:717 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:717 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.1-a7573039-c5d0-424a-9b18-563d0cf2875b, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #30:36463
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #30:36463
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14236 task=record_create(#30:36463 v.0) user=#5:0). Elapsed=1330ms No server in conflict. Received:
- europe0: #30:36463 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:717 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.7-9e74a741-39db-4c34-bed0-891a85602be1, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #27:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #27:36462
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14233 task=record_create(#27:36462 v.0) user=#5:0). Elapsed=1332ms No server in conflict. Received:
- europe0: #27:36462 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:718 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.0-bf82e805-d16d-4890-a59a-33635f8073e8, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #28:36463
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #28:36463
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14238 task=record_create(#28:36463 v.0) user=#5:0). Elapsed=1326ms No server in conflict. Received:
- europe0: #28:36463 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:718 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.6-c1a14b8c-5055-4e87-bb23-e6c687aa5d77, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #27:36463
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #27:36463
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14237 task=record_create(#27:36463 v.0) user=#5:0). Elapsed=1327ms No server in conflict. Received:
- europe0: #27:36463 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:719 SEVER ******************************** [OrientDBCluster]Create Iteration=0, id=plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform.2-9123e05f-e102-431f-8170-e9d8d84cc220, com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #29:36461
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
com.orientechnologies.orient.core.exception.ODatabaseException: Error during saving of record with rid #29:36461
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:205)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2529)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.save(ODatabaseDocumentTx.java:2404)
at test.OrientDBCluster$Writer.createRecord(OrientDBCluster.java:374)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:203)
at test.OrientDBCluster$Writer.call(OrientDBCluster.java:184)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: com.orientechnologies.orient.server.distributed.task.ODistributedOperationException: Quorum 2 not reached for request (id=0.14231 task=record_create(#29:36461 v.0) user=#5:0). Elapsed=1333ms No server in conflict. Received:
- europe0: #29:36461 v.1
- europe1: waiting-for-response
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.manageConflicts(ODistributedResponseManager.java:596)
at com.orientechnologies.orient.server.distributed.ODistributedResponseManager.getFinalResponse(ODistributedResponseManager.java:336)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.waitForResponse(ODistributedDatabaseImpl.java:555)
at com.orientechnologies.orient.server.distributed.ODistributedDatabaseImpl.send2Nodes(ODistributedDatabaseImpl.java:290)
at com.orientechnologies.orient.server.distributed.ODistributedAbstractPlugin.sendRequest(ODistributedAbstractPlugin.java:569)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:605)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$4.call(ODistributedStorage.java:585)
at com.orientechnologies.orient.server.distributed.ODistributedStorage$12.call(ODistributedStorage.java:1059)
at com.orientechnologies.orient.core.db.OScenarioThreadLocal.executeAsDistributed(OScenarioThreadLocal.java:70)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.executeRecordOperationInLock(ODistributedStorage.java:1056)
at com.orientechnologies.orient.server.distributed.ODistributedStorage.createRecord(ODistributedStorage.java:584)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.executeSaveRecord(ODatabaseDocumentTx.java:2015)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveNew(OTransactionNoTx.java:243)
at com.orientechnologies.orient.core.tx.OTransactionNoTx.saveRecord(OTransactionNoTx.java:176)
... 9 more
2016-05-10 23:43:50:720 SEVER ******************************** [OrientDBCluster]
2016-05-10 23:43:50:877 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:301574} counting class: 301575 counting cluster: 38667 = 2125 insert/sec [OrientDBCluster]
2016-05-10 23:43:51:880 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:315666} counting class: 315668 counting cluster: 40429 = 14093 insert/sec [OrientDBCluster]
2016-05-10 23:43:52:883 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:336478} counting class: 336484 counting cluster: 43031 = 20816 insert/sec [OrientDBCluster]
2016-05-10 23:43:53:888 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:355842} counting class: 355848 counting cluster: 45451 = 19364 insert/sec [OrientDBCluster]
2016-05-10 23:43:54:894 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:374450} counting class: 374456 counting cluster: 47777 = 18608 insert/sec [OrientDBCluster]
2016-05-10 23:43:55:895 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:395315} counting class: 395322 counting cluster: 50385 = 20866 insert/sec [OrientDBCluster]
2016-05-10 23:43:56:896 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:414245} counting class: 414247 counting cluster: 52751 = 18925 insert/sec [OrientDBCluster]
2016-05-10 23:43:57:897 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:430614} counting class: 430615 counting cluster: 54797 = 16368 insert/sec [OrientDBCluster]
2016-05-10 23:43:58:899 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:447532} counting class: 447533 counting cluster: 56912 = 16918 insert/sec [OrientDBCluster]
2016-05-10 23:43:59:901 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:462716} counting class: 462721 counting cluster: 58811 = 15188 insert/sec [OrientDBCluster]
2016-05-10 23:44:00:903 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:477349} counting class: 477351 counting cluster: 60639 = 14630 insert/sec [OrientDBCluster]
2016-05-10 23:44:01:907 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:491839} counting class: 491841 counting cluster: 62450 = 14490 insert/sec [OrientDBCluster]
2016-05-10 23:44:02:911 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:506570} counting class: 506572 counting cluster: 64292 = 14731 insert/sec [OrientDBCluster]
2016-05-10 23:44:03:913 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:523176} counting class: 523179 counting cluster: 66368 = 16607 insert/sec [OrientDBCluster]
2016-05-10 23:44:04:916 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:540259} counting class: 540260 counting cluster: 68503 = 17081 insert/sec [OrientDBCluster]
2016-05-10 23:44:05:789 INFO [127.0.0.1]:2434 [orientdb] [3.6] Partition balance is ok, no need to re-partition cluster data... [InternalPartitionService]
2016-05-10 23:44:05:919 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:557218} counting class: 557220 counting cluster: 70623 = 16960 insert/sec [OrientDBCluster]
2016-05-10 23:44:06:921 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:573469} counting class: 573471 counting cluster: 72654 = 16251 insert/sec [OrientDBCluster]
2016-05-10 23:44:07:926 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:591465} counting class: 591465 counting cluster: 74903 = 17994 insert/sec [OrientDBCluster]
2016-05-10 23:44:08:929 INFO Count(/Users/luca/Downloads/pitney-orientdb-cluster-test): {count:607659} counting class: 607659 counting cluster: 76928 = 16194 insert/sec [OrientDBCluster]
2016-05-10 23:44:09:686 WARNI Received signal: SIGINT [OSignalHandler]
2016-05-10 23:44:09:686 INFO [127.0.0.1]:2434 [orientdb] [3.6] Running shutdown hook... Current state: ACTIVE [Node]
2016-05-10 23:44:09:686 INFO OrientDB Server is shutting down... [OServer]com.orientechnologies.orient.core.exception.ODatabaseException: Database 'plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform' is closed
Storage URL="plocal:/Users/luca/Downloads/pitney-orientdb-cluster-test/servers/0/databases/platform"
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.checkOpeness(ODatabaseDocumentTx.java:2972)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTxPooled.checkOpeness(ODatabaseDocumentTxPooled.java:154)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.getMetadata(ODatabaseDocumentTx.java:754)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.getMetadata(ODatabaseDocumentTx.java:99)
at com.orientechnologies.orient.core.sql.query.OSQLQuery.run(OSQLQuery.java:75)
at com.orientechnologies.orient.core.sql.query.OSQLAsynchQuery.run(OSQLAsynchQuery.java:74)
at com.orientechnologies.orient.core.sql.query.OSQLSynchQuery.run(OSQLSynchQuery.java:85)
at com.orientechnologies.orient.core.query.OQueryAbstract.execute(OQueryAbstract.java:33)
at com.orientechnologies.orient.core.db.document.ODatabaseDocumentTx.query(ODatabaseDocumentTx.java:672)
at test.OrientDBCluster$Reader.call(OrientDBCluster.java:592)
at test.OrientDBCluster$Reader.call(OrientDBCluster.java:550)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Process finished with exit code 130
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment