search

umd-dslam / FileScale

FileScale: Fast and Elastic Metadata Management for Distributed File Systems
https://dslam-umd.github.io/docs/filescale
Apache License 2.0
3 stars 2 forks source link

[BUG] Open OP VOLT #103

Closed gangliao closed 5 years ago

gangliao commented 5 years ago 
./bin/hadoop org.apache.hadoop.hdfs.server.namenode.NNThroughputBenchmark -fs hdfs://localhost:9000 -op open -threads 1 -files 10
2019-04-29 04:11:47,701 WARN util.NativeCodeLoader: Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2019-04-29 04:11:52,115 INFO namenode.NNThroughputBenchmark: Starting benchmark: open
2019-04-29 04:11:52,251 INFO namenode.NNThroughputBenchmark: Generate 10 intputs for create
2019-04-29 04:11:52,251 ERROR namenode.NNThroughputBenchmark: Log level = ERROR
2019-04-29 04:11:52,659 INFO namenode.NNThroughputBenchmark: Starting 10 create(s).
2019-04-29 04:11:55,381 INFO namenode.NNThroughputBenchmark: Created 10 files.
2019-04-29 04:11:55,385 INFO namenode.NNThroughputBenchmark: Generate 10 intputs for open
2019-04-29 04:11:55,852 ERROR namenode.NNThroughputBenchmark: Log level = ERROR
2019-04-29 04:11:55,852 INFO namenode.NNThroughputBenchmark: Starting 10 open(s).
2019-04-29 04:11:56,182 INFO namenode.NNThroughputBenchmark: Memory Used: -4771560
2019-04-29 04:11:56,556 INFO namenode.NNThroughputBenchmark:
2019-04-29 04:11:56,556 INFO namenode.NNThroughputBenchmark: --- open inputs ---
2019-04-29 04:11:56,556 INFO namenode.NNThroughputBenchmark: nrFiles = 10
2019-04-29 04:11:56,557 INFO namenode.NNThroughputBenchmark: nrThreads = 1
2019-04-29 04:11:56,557 INFO namenode.NNThroughputBenchmark: nrFilesPerDir = 4
2019-04-29 04:11:56,557 INFO namenode.NNThroughputBenchmark: --- open stats  ---
2019-04-29 04:11:56,557 INFO namenode.NNThroughputBenchmark: # operations: 10
2019-04-29 04:11:56,558 INFO namenode.NNThroughputBenchmark: Elapsed Time: 330
2019-04-29 04:11:56,558 INFO namenode.NNThroughputBenchmark:  Ops per sec: 30.303030303030305
2019-04-29 04:11:56,558 INFO namenode.NNThroughputBenchmark: Average Time: 32
gangl@linuxkit-025000000001:~/hadoop/hadoop-dist/target/hadoop-3.3.0-SNAPSHOT$ sqlcmd
SQL Command :: localhost:21212
1> select * from inodes;
ID     PARENT  NAME                   ACCESSTIME     MODIFICATIONTIME  HEADER           PERMISSION
------ ------- ---------------------- -------------- ----------------- ---------------- --------------
 16385       0 NULL                                0     1556511112779                0  1099511693805
 16386   16385 nnThroughputBenchmark               0     1556511116223                0  1099511693805
 16388       0 ThroughputBenchDir0                 0     1556511113986                0  1099511693805
 16389       0 ThroughputBench0        1556511112888     1556511113380  281474976710672  1099511693823
 16390       0 ThroughputBench1        1556511113439     1556511113658  281474976710672  1099511693823
 16391       0 ThroughputBench2        1556511113714     1556511113934  281474976710672  1099511693823
 16392       0 ThroughputBench3        1556511113986     1556511114167  281474976710672  1099511693823
 16393       0 ThroughputBenchDir1                 0     1556511114887                0  1099511693805
 16394       0 ThroughputBench4        1556511114254     1556511114392  281474976710672  1099511693823
 16395       0 ThroughputBench5        1556511114428     1556511114569  281474976710672  1099511693823
 16396       0 ThroughputBench6        1556511114605     1556511114836  281474976710672  1099511693823
 16397       0 ThroughputBench7        1556511114887     1556511115026  281474976710672  1099511693823
 16398       0 ThroughputBenchDir2                 0     1556511115244                0  1099511693805
 16399       0 ThroughputBench8        1556511115095     1556511115206  281474976710672  1099511693823
 16400       0 ThroughputBench9        1556511115244     1556511115367  281474976710672  1099511693823
gangliao commented 5 years ago 
1> select * from inodes;
ID     PARENT  NAME                   ACCESSTIME     MODIFICATIONTIME  HEADER           PERMISSION
------ ------- ---------------------- -------------- ----------------- ---------------- --------------
 16385       0 NULL                                0     1556754638631                0  1099511693805
 16386   16385 nnThroughputBenchmark               0     1556754645668                0  1099511693805
 16387   16386 open                                0     1556754642906                0  1099511693805
 16388   16387 ThroughputBenchDir0                 0     1556754644904                0  1099511693805
 16389   16388 ThroughputBench0        1556754638785     1556754639424  281474976710672  1099511693823
 16390   16388 ThroughputBench1        1556754639525     1556754639720  281474976710672  1099511693823
 16391   16388 ThroughputBench2        1556754639788     1556754640037  281474976710672  1099511693823
 16392   16388 ThroughputBench3        1556754640116     1556754640401  281474976710672  1099511693823
 16393   16387 ThroughputBenchDir1                 0     1556754641470                0  1099511693805
 16394   16393 ThroughputBench4        1556754640509     1556754640764  281474976710672  1099511693823
 16395   16393 ThroughputBench5        1556754640833     1556754641046  281474976710672  1099511693823
 16396   16393 ThroughputBench6        1556754641116     1556754641425  281474976710672  1099511693823
 16397   16393 ThroughputBench7        1556754641470     1556754641685  281474976710672  1099511693823
 16398   16387 ThroughputBenchDir2                 0     1556754642664                0  1099511693805
 16399   16398 ThroughputBench8        1556754641793     1556754642023  281474976710672  1099511693823
 16400   16398 ThroughputBench9        1556754642094     1556754642389  281474976710672  1099511693823
 16401   16398 ThroughputBench10       1556754642456     1556754642623  281474976710672  1099511693823
 16402   16398 ThroughputBench11       1556754642664     1556754642853  281474976710672  1099511693823
 16403   16387 ThroughputBenchDir3                 0     1556754643642                0  1099511693805
 16404   16403 ThroughputBench12       1556754642944     1556754643121  281474976710672  1099511693823
 16405   16403 ThroughputBench13       1556754643177     1556754643364  281474976710672  1099511693823
 16406   16403 ThroughputBench14       1556754643409     1556754643586  281474976710672  1099511693823
 16407   16403 ThroughputBench15       1556754643642     1556754643808  281474976710672  1099511693823
 16408   16388 ThroughputBenchDir0                 0     1556754644624                0  1099511693805
 16409   16408 ThroughputBench16       1556754643899     1556754644063  281474976710672  1099511693823
 16410   16408 ThroughputBench17       1556754644118     1556754644320  281474976710672  1099511693823
 16411   16408 ThroughputBench18       1556754644378     1556754644574  281474976710672  1099511693823
 16412   16408 ThroughputBench19       1556754644624     1556754644844  281474976710672  1099511693823
 16413   16388 ThroughputBenchDir1                 0     1556754645298                0  1099511693805
 16414   16413 ThroughputBench20       1556754644982     1556754645201  281474976710672  1099511693823
 16415   16413 ThroughputBench21       1556754645298     1556754645508  281474976710672  1099511693823
gangliao commented 5 years ago 
gangl@linuxkit-025000000001:~/hadoop$ sqlcmd
SQL Command :: localhost:21212
1> select * from inodes;
ID     PARENT  NAME                   ACCESSTIME     MODIFICATIONTIME  HEADER           PERMISSION
------ ------- ---------------------- -------------- ----------------- ---------------- --------------
 16385       0 NULL                                0     1556755868260                0  1099511693805
 16386   16385 nnThroughputBenchmark               0     1556755870749                0  1099511693805
 16388       0 ThroughputBenchDir0                 0     1556755869668                0  1099511693805
 16389   16388 ThroughputBench0        1556755868420     1556755868972  281474976710672  1099511693823
 16390   16388 ThroughputBench1        1556755869104     1556755869362  281474976710672  1099511693823
 16391   16388 ThroughputBench2        1556755869423     1556755869622  281474976710672  1099511693823
 16392   16388 ThroughputBench3        1556755869668     1556755869808  281474976710672  1099511693823
 16393       0 ThroughputBenchDir1                 0     1556755869875                0  1099511693805
 16394   16393 ThroughputBench4        1556755869875     1556755870003  281474976710672  1099511693823
gangliao commented 5 years ago 
2019-05-02 00:06:48,459 INFO org.apache.hadoop.ipc.Server: IPC Server Responder: starting
2019-05-02 00:06:48,460 INFO org.apache.hadoop.ipc.Server: IPC Server listener on 9000: starting
2019-05-02 00:06:48,507 INFO org.apache.hadoop.hdfs.server.namenode.NameNode: NameNode RPC up at: localhost/127.0.0.1:9000
2019-05-02 00:06:48,612 INFO org.apache.hadoop.hdfs.server.namenode.FSNamesystem: Starting services required for active state
2019-05-02 00:06:48,613 INFO org.apache.hadoop.hdfs.server.namenode.FSDirectory: Initializing quota with 4 thread(s)
2019-05-02 00:06:48,682 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16385)
2019-05-02 00:06:48,709 INFO org.apache.hadoop.hdfs.server.namenode.FSDirectory: Quota initialization completed in 95 milliseconds
name space=1
storage space=0
storage types=RAM_DISK=0, SSD=0, DISK=0, ARCHIVE=0, PROVIDED=0
2019-05-02 00:06:48,755 INFO org.apache.hadoop.hdfs.server.blockmanagement.CacheReplicationMonitor: Starting CacheReplicationMonitor with interval 30000 milliseconds
2019-05-02 00:06:55,172 INFO org.apache.hadoop.hdfs.StateChange: BLOCK* registerDatanode: from DatanodeRegistration(127.0.0.1:9866, datanodeUuid=3b56cc5a-f64f-44b3-a66b-b9591dd8b8f4, infoPort=9864, infoSecurePort=0, ipcPort=9867, storageInfo=lv=-57;cid=CID-c4b19309-c45f-47dc-9533-97b6736aefcf;nsid=605021586;c=1556755538524) storage 3b56cc5a-f64f-44b3-a66b-b9591dd8b8f4
2019-05-02 00:06:55,188 INFO org.apache.hadoop.net.NetworkTopology: Adding a new node: /default-rack/127.0.0.1:9866
2019-05-02 00:06:55,196 INFO org.apache.hadoop.hdfs.server.blockmanagement.BlockReportLeaseManager: Registered DN 3b56cc5a-f64f-44b3-a66b-b9591dd8b8f4 (127.0.0.1:9866).
2019-05-02 00:06:56,700 INFO org.apache.hadoop.hdfs.server.blockmanagement.DatanodeDescriptor: Adding new storage ID DS-302a5c34-3881-4181-b525-9ecba0c13b26 for DN 127.0.0.1:9866
2019-05-02 00:06:57,534 INFO BlockStateChange: BLOCK* processReport 0x4ad3e9298bbb6a2a: Processing first storage report for DS-302a5c34-3881-4181-b525-9ecba0c13b26 from datanode 3b56cc5a-f64f-44b3-a66b-b9591dd8b8f4
2019-05-02 00:06:57,621 INFO BlockStateChange: BLOCK* processReport 0x4ad3e9298bbb6a2a: from storage DS-302a5c34-3881-4181-b525-9ecba0c13b26 node DatanodeRegistration(127.0.0.1:9866, datanodeUuid=3b56cc5a-f64f-44b3-a66b-b9591dd8b8f4, infoPort=9864, infoSecurePort=0, ipcPort=9867, storageInfo=lv=-57;cid=CID-c4b19309-c45f-47dc-9533-97b6736aefcf;nsid=605021586;c=1556755538524), blocks: 0, hasStaleStorage: false, processing time: 84 msecs, invalidatedBlocks: 0
2019-05-02 00:08:09,507 INFO org.apache.hadoop.hdfs.server.namenode.FSNamesystem: Roll Edit Log from 127.0.0.1
2019-05-02 00:08:09,509 INFO org.apache.hadoop.hdfs.server.namenode.FSEditLog: Rolling edit logs
2019-05-02 00:08:09,511 INFO org.apache.hadoop.hdfs.server.namenode.FSEditLog: Ending log segment 1, 1
2019-05-02 00:08:09,513 INFO org.apache.hadoop.hdfs.server.namenode.FSEditLog: Number of transactions: 2 Total time for transactions(ms): 18 Number of transactions batched in Syncs: 0 Number of syncs: 2 SyncTimes(ms): 60 
2019-05-02 00:08:09,515 INFO org.apache.hadoop.hdfs.server.namenode.FSEditLog: Number of transactions: 2 Total time for transactions(ms): 18 Number of transactions batched in Syncs: 0 Number of syncs: 3 SyncTimes(ms): 61 
2019-05-02 00:08:09,532 INFO org.apache.hadoop.hdfs.server.namenode.FileJournalManager: Finalizing edits file /home/gangl/hadoop/name/current/edits_inprogress_0000000000000000001 -> /home/gangl/hadoop/name/current/edits_0000000000000000001-0000000000000000002
2019-05-02 00:08:09,548 INFO org.apache.hadoop.hdfs.server.namenode.FSEditLog: Starting log segment at 3
2019-05-02 00:08:10,468 INFO org.apache.hadoop.hdfs.server.namenode.TransferFsImage: Sending fileName: /home/gangl/hadoop/name/current/fsimage_0000000000000000000, fileSize: 98. Sent total: 98 bytes. Size of last segment intended to send: -1 bytes.
2019-05-02 00:08:10,731 INFO org.apache.hadoop.hdfs.server.namenode.TransferFsImage: Sending fileName: /home/gangl/hadoop/name/current/edits_0000000000000000001-0000000000000000002, fileSize: 42. Sent total: 42 bytes. Size of last segment intended to send: -1 bytes.
2019-05-02 00:08:11,872 INFO org.apache.hadoop.hdfs.server.common.Util: Combined time for file download and fsync to all disks took 0.01s. The file download took 0.01s at 0.00 KB/s. Synchronous (fsync) write to disk of /home/gangl/hadoop/name/current/fsimage.ckpt_0000000000000000002 took 0.00s.
2019-05-02 00:08:11,874 INFO org.apache.hadoop.hdfs.server.namenode.TransferFsImage: Downloaded file fsimage.ckpt_0000000000000000002 size 98 bytes.
2019-05-02 00:08:11,973 INFO org.apache.hadoop.hdfs.server.namenode.NNStorageRetentionManager: Going to retain 2 images with txid >= 0
2019-05-02 00:11:07,722 INFO org.apache.hadoop.hdfs.StateChange: STATE* Safe mode is already OFF
2019-05-02 00:11:08,219 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:08,228 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@7514369
2019-05-02 00:11:08,239 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385]
2019-05-02 00:11:08,266 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16386)
2019-05-02 00:11:08,270 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16386],16385)
2019-05-02 00:11:08,273 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:08,278 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16386)
2019-05-02 00:11:08,280 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16386,16385,nnThroughputBenchmark)
2019-05-02 00:11:08,293 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16386,1099511693805)
2019-05-02 00:11:08,301 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16385)
2019-05-02 00:11:08,309 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16387)
2019-05-02 00:11:08,312 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16387],16386)
2019-05-02 00:11:08,315 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:08,319 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16387)
2019-05-02 00:11:08,320 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16387,16386,create)
2019-05-02 00:11:08,329 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16387,1099511693805)
2019-05-02 00:11:08,338 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16386)
2019-05-02 00:11:08,347 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16388)
2019-05-02 00:11:08,350 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388],16387)
2019-05-02 00:11:08,354 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:08,369 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16388)
2019-05-02 00:11:08,370 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16388,16387,ThroughputBenchDir0)
2019-05-02 00:11:08,389 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16388,1099511693805)
2019-05-02 00:11:08,394 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16387)
2019-05-02 00:11:08,438 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16389)
2019-05-02 00:11:08,462 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [UPDATE]: (16389,281474976710672)
2019-05-02 00:11:08,479 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [UPDATE]: (16389,ThroughputBench0)
2019-05-02 00:11:08,510 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertUc [UPDATE]: (16389, create-client-0, 127.0.0.1)
2019-05-02 00:11:08,526 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16388)
2019-05-02 00:11:08,533 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16389,0)
2019-05-02 00:11:08,534 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16389,16388,ThroughputBench0)
2019-05-02 00:11:08,545 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16389,1099511693823)
2019-05-02 00:11:08,551 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16388)
2019-05-02 00:11:08,570 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16389, create-client-0)
2019-05-02 00:11:08,593 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16389,0)
2019-05-02 00:11:08,598 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16389,1556755868420)
2019-05-02 00:11:08,602 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16389,1556755868420)
2019-05-02 00:11:08,853 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:08,854 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@59ba09d8
2019-05-02 00:11:08,859 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16389]
2019-05-02 00:11:08,862 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:08,864 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:08,866 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:08,869 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:08,873 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:08,875 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16389,16388)
2019-05-02 00:11:08,877 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16389,ThroughputBench0)
2019-05-02 00:11:08,881 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16389,16388,ThroughputBench0)
2019-05-02 00:11:08,883 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:08,885 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16388,ThroughputBenchDir0)
2019-05-02 00:11:08,888 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16388,16387)
2019-05-02 00:11:08,890 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16388,16387,ThroughputBenchDir0)
2019-05-02 00:11:08,893 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:08,895 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16387,create)
2019-05-02 00:11:08,897 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:08,899 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16387,16386,create)
2019-05-02 00:11:08,911 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:08,913 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16386,nnThroughputBenchmark)
2019-05-02 00:11:08,922 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16386,16385)
2019-05-02 00:11:08,934 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16386,16385,nnThroughputBenchmark)
2019-05-02 00:11:08,937 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:08,939 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:08,952 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16389, create-client-0)
2019-05-02 00:11:08,961 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16389, create-client-0)
2019-05-02 00:11:08,988 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeUc [UPDATE]: (16389)
2019-05-02 00:11:08,997 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16389,1556755868972)
2019-05-02 00:11:09,009 INFO org.apache.hadoop.hdfs.StateChange: DIR* completeFile: /nnThroughputBenchmark/create/ThroughputBenchDir0/ThroughputBench0 is closed by create-client-0
2019-05-02 00:11:09,071 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,072 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@29be51e6
2019-05-02 00:11:09,077 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388]
2019-05-02 00:11:09,080 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,083 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,086 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,180 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16390)
2019-05-02 00:11:09,194 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [UPDATE]: (16390,281474976710672)
2019-05-02 00:11:09,205 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [UPDATE]: (16390,ThroughputBench1)
2019-05-02 00:11:09,218 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertUc [UPDATE]: (16390, create-client-0, 127.0.0.1)
2019-05-02 00:11:09,222 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389],16388)
2019-05-02 00:11:09,235 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:09,239 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16390,0)
2019-05-02 00:11:09,240 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16390,16388,ThroughputBench1)
2019-05-02 00:11:09,248 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16388,1099511693805)
2019-05-02 00:11:09,254 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16390,1099511693823)
2019-05-02 00:11:09,260 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16388)
2019-05-02 00:11:09,264 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16390, create-client-0)
2019-05-02 00:11:09,267 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16390,0)
2019-05-02 00:11:09,270 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16390,1556755869104)
2019-05-02 00:11:09,272 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16390,1556755869104)
2019-05-02 00:11:09,289 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,290 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@48c59805
2019-05-02 00:11:09,295 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16390]
2019-05-02 00:11:09,298 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,300 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,302 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,304 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:09,308 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:09,311 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16390,16388)
2019-05-02 00:11:09,313 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16390,ThroughputBench1)
2019-05-02 00:11:09,316 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16390,16388,ThroughputBench1)
2019-05-02 00:11:09,318 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:09,321 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16388,ThroughputBenchDir0)
2019-05-02 00:11:09,323 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16388,16387)
2019-05-02 00:11:09,325 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16388,16387,ThroughputBenchDir0)
2019-05-02 00:11:09,328 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,330 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16387,create)
2019-05-02 00:11:09,332 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:09,334 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16387,16386,create)
2019-05-02 00:11:09,336 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,339 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16386,nnThroughputBenchmark)
2019-05-02 00:11:09,340 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16386,16385)
2019-05-02 00:11:09,342 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16386,16385,nnThroughputBenchmark)
2019-05-02 00:11:09,345 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,347 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,349 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16390, create-client-0)
2019-05-02 00:11:09,361 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16390, create-client-0)
2019-05-02 00:11:09,369 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeUc [UPDATE]: (16390)
2019-05-02 00:11:09,375 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16390,1556755869362)
2019-05-02 00:11:09,380 INFO org.apache.hadoop.hdfs.StateChange: DIR* completeFile: /nnThroughputBenchmark/create/ThroughputBenchDir0/ThroughputBench1 is closed by create-client-0
2019-05-02 00:11:09,388 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,389 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@396360a3
2019-05-02 00:11:09,395 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388]
2019-05-02 00:11:09,398 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,410 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,414 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,449 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16391)
2019-05-02 00:11:09,454 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [UPDATE]: (16391,281474976710672)
2019-05-02 00:11:09,468 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [UPDATE]: (16391,ThroughputBench2)
2019-05-02 00:11:09,479 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertUc [UPDATE]: (16391, create-client-0, 127.0.0.1)
2019-05-02 00:11:09,481 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389, 16390],16388)
2019-05-02 00:11:09,484 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:09,487 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:09,492 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16391,0)
2019-05-02 00:11:09,493 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16391,16388,ThroughputBench2)
2019-05-02 00:11:09,510 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16388,1099511693805)
2019-05-02 00:11:09,516 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16391,1099511693823)
2019-05-02 00:11:09,525 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16388)
2019-05-02 00:11:09,530 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16391, create-client-0)
2019-05-02 00:11:09,532 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16391,0)
2019-05-02 00:11:09,535 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16391,1556755869423)
2019-05-02 00:11:09,537 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16391,1556755869423)
2019-05-02 00:11:09,545 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,546 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@431edd05
2019-05-02 00:11:09,552 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16391]
2019-05-02 00:11:09,561 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,563 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,566 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,568 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:09,572 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:09,574 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16391,16388)
2019-05-02 00:11:09,577 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16391,ThroughputBench2)
2019-05-02 00:11:09,579 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16391,16388,ThroughputBench2)
2019-05-02 00:11:09,581 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:09,583 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16388,ThroughputBenchDir0)
2019-05-02 00:11:09,586 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16388,16387)
2019-05-02 00:11:09,588 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16388,16387,ThroughputBenchDir0)
2019-05-02 00:11:09,590 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,592 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16387,create)
2019-05-02 00:11:09,594 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:09,596 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16387,16386,create)
2019-05-02 00:11:09,598 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,600 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16386,nnThroughputBenchmark)
2019-05-02 00:11:09,603 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16386,16385)
2019-05-02 00:11:09,605 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16386,16385,nnThroughputBenchmark)
2019-05-02 00:11:09,608 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,609 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,611 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16391, create-client-0)
2019-05-02 00:11:09,621 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16391, create-client-0)
2019-05-02 00:11:09,628 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeUc [UPDATE]: (16391)
2019-05-02 00:11:09,633 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16391,1556755869622)
2019-05-02 00:11:09,637 INFO org.apache.hadoop.hdfs.StateChange: DIR* completeFile: /nnThroughputBenchmark/create/ThroughputBenchDir0/ThroughputBench2 is closed by create-client-0
2019-05-02 00:11:09,642 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,643 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@12372036
2019-05-02 00:11:09,650 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388]
2019-05-02 00:11:09,653 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,658 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,661 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,679 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16392)
2019-05-02 00:11:09,683 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [UPDATE]: (16392,281474976710672)
2019-05-02 00:11:09,688 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [UPDATE]: (16392,ThroughputBench3)
2019-05-02 00:11:09,694 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertUc [UPDATE]: (16392, create-client-0, 127.0.0.1)
2019-05-02 00:11:09,696 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389, 16390, 16391],16388)
2019-05-02 00:11:09,699 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:09,701 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:09,704 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:09,707 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16392,0)
2019-05-02 00:11:09,708 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16392,16388,ThroughputBench3)
2019-05-02 00:11:09,715 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16388,1099511693805)
2019-05-02 00:11:09,720 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16392,1099511693823)
2019-05-02 00:11:09,725 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16388)
2019-05-02 00:11:09,728 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16392, create-client-0)
2019-05-02 00:11:09,731 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16392,0)
2019-05-02 00:11:09,733 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16392,1556755869668)
2019-05-02 00:11:09,736 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16392,1556755869668)
2019-05-02 00:11:09,741 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,742 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@127a2772
2019-05-02 00:11:09,746 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16392]
2019-05-02 00:11:09,748 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,751 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,753 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,755 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:09,758 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:09,761 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16392,16388)
2019-05-02 00:11:09,763 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16392,ThroughputBench3)
2019-05-02 00:11:09,766 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16392,16388,ThroughputBench3)
2019-05-02 00:11:09,768 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:09,770 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16388,ThroughputBenchDir0)
2019-05-02 00:11:09,772 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16388,16387)
2019-05-02 00:11:09,774 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16388,16387,ThroughputBenchDir0)
2019-05-02 00:11:09,777 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,779 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16387,create)
2019-05-02 00:11:09,781 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:09,783 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16387,16386,create)
2019-05-02 00:11:09,786 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,787 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16386,nnThroughputBenchmark)
2019-05-02 00:11:09,789 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16386,16385)
2019-05-02 00:11:09,792 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16386,16385,nnThroughputBenchmark)
2019-05-02 00:11:09,794 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,796 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,798 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16392, create-client-0)
2019-05-02 00:11:09,807 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16392, create-client-0)
2019-05-02 00:11:09,814 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeUc [UPDATE]: (16392)
2019-05-02 00:11:09,819 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16392,1556755869808)
2019-05-02 00:11:09,823 INFO org.apache.hadoop.hdfs.StateChange: DIR* completeFile: /nnThroughputBenchmark/create/ThroughputBenchDir0/ThroughputBench3 is closed by create-client-0
2019-05-02 00:11:09,828 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,829 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@74ef3113
2019-05-02 00:11:09,833 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387]
2019-05-02 00:11:09,835 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,837 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,843 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16387,1099511693805)
2019-05-02 00:11:09,849 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16393)
2019-05-02 00:11:09,852 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:09,853 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:09,855 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:09,859 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16393)
2019-05-02 00:11:09,860 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16393,16387,ThroughputBenchDir1)
2019-05-02 00:11:09,868 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16393,1099511693805)
2019-05-02 00:11:09,873 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16387)
2019-05-02 00:11:09,886 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertInode: (16394)
2019-05-02 00:11:09,891 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [UPDATE]: (16394,281474976710672)
2019-05-02 00:11:09,895 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [UPDATE]: (16394,ThroughputBench4)
2019-05-02 00:11:09,901 INFO org.apache.hadoop.hdfs.db.DatabaseINode: insertUc [UPDATE]: (16394, create-client-0, 127.0.0.1)
2019-05-02 00:11:09,903 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([],16393)
2019-05-02 00:11:09,907 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16394,0)
2019-05-02 00:11:09,908 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16394,16393,ThroughputBench4)
2019-05-02 00:11:09,916 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [UPDATE]: (16394,1099511693823)
2019-05-02 00:11:09,921 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16393)
2019-05-02 00:11:09,924 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16394, create-client-0)
2019-05-02 00:11:09,927 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16394,0)
2019-05-02 00:11:09,929 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16394,1556755869875)
2019-05-02 00:11:09,932 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16394,1556755869875)
2019-05-02 00:11:09,937 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,937 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@1306ec05
2019-05-02 00:11:09,942 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16393, 16394]
2019-05-02 00:11:09,944 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,946 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,948 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:09,950 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:09,954 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:09,956 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16394,16393)
2019-05-02 00:11:09,958 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16394,ThroughputBench4)
2019-05-02 00:11:09,960 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16394,16393,ThroughputBench4)
2019-05-02 00:11:09,963 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:09,965 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16393,ThroughputBenchDir1)
2019-05-02 00:11:09,967 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16393,16387)
2019-05-02 00:11:09,970 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16393,16387,ThroughputBenchDir1)
2019-05-02 00:11:09,972 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:09,974 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16387,create)
2019-05-02 00:11:09,976 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:09,978 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16387,16386,create)
2019-05-02 00:11:09,980 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:09,983 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16386,nnThroughputBenchmark)
2019-05-02 00:11:09,985 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16386,16385)
2019-05-02 00:11:09,988 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChild: (16386,16385,nnThroughputBenchmark)
2019-05-02 00:11:09,990 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:09,993 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:09,995 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16394, create-client-0)
2019-05-02 00:11:10,002 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getUcClientName [GET]: (16394, create-client-0)
2019-05-02 00:11:10,009 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeUc [UPDATE]: (16394)
2019-05-02 00:11:10,014 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16394,1556755870003)
2019-05-02 00:11:10,019 INFO org.apache.hadoop.hdfs.StateChange: DIR* completeFile: /nnThroughputBenchmark/create/ThroughputBenchDir1/ThroughputBench4 is closed by create-client-0
2019-05-02 00:11:10,024 INFO org.apache.hadoop.hdfs.StateChange: STATE* Safe mode is already OFF
2019-05-02 00:11:10,065 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,066 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@66b4fd5
2019-05-02 00:11:10,070 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387]
2019-05-02 00:11:10,072 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,074 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,090 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,093 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,095 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,097 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16387,1556755869844)
2019-05-02 00:11:10,100 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16387,0)
2019-05-02 00:11:10,102 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16387,1099511693805)
2019-05-02 00:11:10,119 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,120 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@46027f6d
2019-05-02 00:11:10,123 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386]
2019-05-02 00:11:10,126 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,147 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,148 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@37ee212b
2019-05-02 00:11:10,152 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387]
2019-05-02 00:11:10,154 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,158 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,161 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,162 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@22bd0315
2019-05-02 00:11:10,166 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386]
2019-05-02 00:11:10,168 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,180 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16387],16386)
2019-05-02 00:11:10,182 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,186 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,188 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,192 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389, 16390, 16391, 16392],16388)
2019-05-02 00:11:10,196 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,198 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,202 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,204 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,207 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,209 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,212 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,214 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,217 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,221 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16394],16393)
2019-05-02 00:11:10,223 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,225 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,238 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16387,create)
2019-05-02 00:11:10,243 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,245 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,248 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389, 16390, 16391, 16392],16388)
2019-05-02 00:11:10,250 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,253 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,256 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,258 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,262 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,264 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,267 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,269 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,273 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,276 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16394],16393)
2019-05-02 00:11:10,279 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,281 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,285 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,288 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,291 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389, 16390, 16391, 16392],16388)
2019-05-02 00:11:10,293 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,295 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,299 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,301 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,304 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,307 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,310 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,313 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,317 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,320 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16394],16393)
2019-05-02 00:11:10,323 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,325 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,328 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:10,329 INFO org.apache.hadoop.hdfs.db.DatabaseINode: addChild: [OK] UPSERT (16387,16386,open)
2019-05-02 00:11:10,336 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16387,1099511693805)
2019-05-02 00:11:10,344 INFO org.apache.hadoop.hdfs.db.DatabaseINode: updateModificationTime [UPDATE]: (16386)
2019-05-02 00:11:10,349 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16386,1556755869844)
2019-05-02 00:11:10,354 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16386,1556755870169)
2019-05-02 00:11:10,357 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16386,1556755870169)
2019-05-02 00:11:10,360 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16387,1556755869844)
2019-05-02 00:11:10,363 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16387,0)
2019-05-02 00:11:10,400 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,401 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@5c725beb
2019-05-02 00:11:10,404 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387]
2019-05-02 00:11:10,406 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,409 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,419 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,422 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,424 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,430 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16387,1556755869844)
2019-05-02 00:11:10,433 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16387,0)
2019-05-02 00:11:10,435 INFO org.apache.hadoop.hdfs.db.DatabaseINode: permission [GET]: (16387,1099511693805)
2019-05-02 00:11:10,456 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,457 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@79b80b61
2019-05-02 00:11:10,466 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16389]
2019-05-02 00:11:10,469 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,471 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,473 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,476 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,512 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16389,0)
2019-05-02 00:11:10,516 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,521 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16389,1556755868420)
2019-05-02 00:11:10,552 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,553 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@1fc2d46
2019-05-02 00:11:10,558 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16390]
2019-05-02 00:11:10,581 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,584 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,587 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,589 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,593 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16390,0)
2019-05-02 00:11:10,599 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,603 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16390,1556755869104)
2019-05-02 00:11:10,609 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,610 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@56ed59f5
2019-05-02 00:11:10,613 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16391]
2019-05-02 00:11:10,615 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,618 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,620 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,623 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,627 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16391,0)
2019-05-02 00:11:10,631 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,635 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16391,1556755869423)
2019-05-02 00:11:10,640 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,641 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@ed76bc1
2019-05-02 00:11:10,645 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16388, 16392]
2019-05-02 00:11:10,648 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,650 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,652 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,654 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,658 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16392,0)
2019-05-02 00:11:10,661 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,665 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16392,1556755869668)
2019-05-02 00:11:10,671 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,672 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@67f7dd2e
2019-05-02 00:11:10,676 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387, 16393, 16394]
2019-05-02 00:11:10,678 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,681 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,683 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,686 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,690 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: getNumBlocks: (16394,0)
2019-05-02 00:11:10,694 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,698 INFO org.apache.hadoop.hdfs.db.DatabaseINode: accessTime [GET]: (16394,1556755869875)
2019-05-02 00:11:10,702 INFO org.apache.hadoop.hdfs.StateChange: STATE* Safe mode is already OFF
2019-05-02 00:11:10,727 INFO org.apache.hadoop.hdfs.db.DatabaseINode: name [GET]: (16385,null)
2019-05-02 00:11:10,728 INFO org.apache.hadoop.hdfs.server.namenode.INodesInPath: components:[[B@4cead69c
2019-05-02 00:11:10,731 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildIdsByPath: 16385 : [16385, 16386, 16387]
2019-05-02 00:11:10,734 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16386,0)
2019-05-02 00:11:10,736 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16387,0)
2019-05-02 00:11:10,739 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,741 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,747 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,763 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [GET]: (16387,16386)
2019-05-02 00:11:10,765 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [GET]: (16386,1556755870169)
2019-05-02 00:11:10,770 INFO org.apache.hadoop.hdfs.db.DatabaseINode: modificationTime [UPDATE]: (16386,1556755870749)
2019-05-02 00:11:10,772 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16388, 16393],16387)
2019-05-02 00:11:10,775 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16388,0)
2019-05-02 00:11:10,777 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16393,0)
2019-05-02 00:11:10,779 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16389, 16390, 16391, 16392],16388)
2019-05-02 00:11:10,781 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,784 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,786 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,789 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,792 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16389,281474976710672)
2019-05-02 00:11:10,801 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: deleteViaBcId: (16389,DELETE FROM inode2block WHERE id = ?;)
2019-05-02 00:11:10,805 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16390,281474976710672)
2019-05-02 00:11:10,813 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: deleteViaBcId: (16390,DELETE FROM inode2block WHERE id = ?;)
2019-05-02 00:11:10,817 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16391,281474976710672)
2019-05-02 00:11:10,825 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: deleteViaBcId: (16391,DELETE FROM inode2block WHERE id = ?;)
2019-05-02 00:11:10,838 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16392,281474976710672)
2019-05-02 00:11:10,845 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: deleteViaBcId: (16392,DELETE FROM inode2block WHERE id = ?;)
2019-05-02 00:11:10,853 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [UPDATE]: (16388,0)
2019-05-02 00:11:10,856 INFO org.apache.hadoop.hdfs.db.DatabaseINode: getChildrenIds: ([16394],16393)
2019-05-02 00:11:10,858 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,860 INFO org.apache.hadoop.hdfs.db.DatabaseINode: header [GET]: (16394,281474976710672)
2019-05-02 00:11:10,868 INFO org.apache.hadoop.hdfs.db.DatabaseINode2Block: deleteViaBcId: (16394,DELETE FROM inode2block WHERE id = ?;)
2019-05-02 00:11:10,874 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [UPDATE]: (16393,0)
2019-05-02 00:11:10,879 INFO org.apache.hadoop.hdfs.db.DatabaseINode: parent [UPDATE]: (16387,0)
2019-05-02 00:11:10,902 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeChild Return: 1
2019-05-02 00:11:10,903 INFO org.apache.hadoop.hdfs.db.DatabaseINode: removeChild: 16387
2019-05-02 00:11:17,232 ERROR org.apache.hadoop.hdfs.server.namenode.NameNode: RECEIVED SIGNAL 15: SIGTERM
2019-05-02 00:11:17,259 INFO org.apache.hadoop.hdfs.server.namenode.NameNode: SHUTDOWN_MSG: 
/************************************************************