Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2020 12:52
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.03
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32644004 kB
MemFree 16324680 kB
MemAvailable 30914948 kB
Buffers 0 kB
Cached 15308512 kB
SwapCached 6004 kB
Command: fdisk -l

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=fbc, val=/opt/fb40/bin
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3400
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs.oltp40
param=remove_isql_logs, val=if_no_severe_errors
param=working_mode, val=small_03
param=actions_todo_before_reconnect, val=300
param=max_cps, val=25
param=sleep_max, val=0
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=no_auto_undo, val=1
param=recalc_idx_min_interval, val=30
param=used_in_replication, val=0
param=separate_workers, val=1
param=update_conflict_percent, val=0
param=unit_selection_method, val=random
param=detailed_info, val=0
param=mon_unit_perf, val=2
param=mon_unit_list, val=//
param=mon_query_interval, val=60
param=halt_test_on_errors, val=/CK/
param=qmism_verify_bitset, val=1
param=warm_time, val=30
param=test_time, val=120
param=test_intervals, val=60
param=create_with_fw, val=sync
param=create_with_sweep, val=0
param=wait_if_not_exists, val=0
param=wait_after_create, val=0
param=init_docs, val=0
param=expected_workers, val=100
param=init_buff, val=10000
param=wait_for_copy, val=1
param=create_with_debug_objects, val=1
param=create_with_split_heavy_tabs, val=1
param=create_with_separate_qdistr_idx, val=0
param=create_with_compound_columns_order, val=most_selective_first
param=replace_instance, val=1
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=make_html, val=1
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=file_name_with_test_params, val=regular
param=file_name_this_host_info, val=no_host_info
param=gather_hardware_info, val=1
param=use_mtee, val=0
param=is_embed, val=0
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED.  
DTS_END                         2020-08-20 12:51:51.5580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/49330
TRN_ID                          216176
ATT_ID                          520
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8547.93 27388 1025752
customer order: creation 154.49 438 18539
customer order: refuse 13.60 479 1632
order to supplier: creation 40.39 1657 4847
order to supplier: removal 2.93 167792 351
invoice (draft): creation 42.12 4431 5054
invoice (draft): removal 48.72 1086 5846
invoice accept: apply 38.92 20518 4670
invoice accept: cancel 44.30 1139 5316
customer reserve: creation 3567.66 200 428119
customer reserve: removal 4325.99 91 519119
realization accept: apply 73.65 224 8838
realization accept: cancel 79.27 143 9512
payment from customer: creation 54.32 120 6518
payment from customer: removal 5.14 156 617
payment to supplier: creation 43.67 148 5240
payment to supplier: removal 3.19 102 383
service: total inventory turnovers 3.02 6063 362
service: total monetary turnovers 5.68 948 682
service: aggregate perf. data 0.86 20110 103
service: refresh index statistics 0.03 321914 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2020 12:53:13 to 20-08-2020 12:53:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1012
TEST_TIME 2 1946
TEST_TIME 3 3006
TEST_TIME 4 2849
TEST_TIME 5 3157
TEST_TIME 6 3385
TEST_TIME 7 3215
TEST_TIME 8 3371
TEST_TIME 9 3424
TEST_TIME 10 3643
TEST_TIME 11 3904
TEST_TIME 12 3877
TEST_TIME 13 4176
TEST_TIME 14 4115
TEST_TIME 15 4290
TEST_TIME 16 4278
TEST_TIME 17 4610
TEST_TIME 18 4906
TEST_TIME 19 4830
TEST_TIME 20 4952
TEST_TIME 21 4944
TEST_TIME 22 5222
TEST_TIME 23 5542
TEST_TIME 24 5548
TEST_TIME 25 5895
TEST_TIME 26 6244
TEST_TIME 27 6304
TEST_TIME 28 6451
TEST_TIME 29 6487
TEST_TIME 30 6454
TEST_TIME 31 7502
TEST_TIME 32 7457
TEST_TIME 33 7612
TEST_TIME 34 8036
TEST_TIME 35 7994
TEST_TIME 36 8067
TEST_TIME 37 8371
TEST_TIME 38 8693
TEST_TIME 39 9072
TEST_TIME 40 9102
TEST_TIME 41 9009
TEST_TIME 42 9006
TEST_TIME 43 8929
TEST_TIME 44 8933
TEST_TIME 45 9070
TEST_TIME 46 8996
TEST_TIME 47 8913
TEST_TIME 48 8756
TEST_TIME 49 8675
TEST_TIME 50 8597
TEST_TIME 51 8568
TEST_TIME 52 8519
TEST_TIME 53 8517
TEST_TIME 54 8521
TEST_TIME 55 8562
TEST_TIME 56 8505
TEST_TIME 57 8534
TEST_TIME 58 8528
TEST_TIME 59 8537
TEST_TIME 60 8505
TEST_TIME 61 8389
TEST_TIME 62 8546
TEST_TIME 63 8525
TEST_TIME 64 8578
TEST_TIME 65 8608
TEST_TIME 66 8576
TEST_TIME 67 8660
TEST_TIME 68 8637
TEST_TIME 69 8753
TEST_TIME 70 8841
TEST_TIME 71 8890
TEST_TIME 72 8866
TEST_TIME 73 8940
TEST_TIME 74 8967
TEST_TIME 75 8927
TEST_TIME 76 8829
TEST_TIME 77 8763
TEST_TIME 78 8727
TEST_TIME 79 8697
TEST_TIME 80 8662
TEST_TIME 81 8605
TEST_TIME 82 8592
TEST_TIME 83 8545
TEST_TIME 84 8553
TEST_TIME 85 8572
TEST_TIME 86 8585
TEST_TIME 87 8581
TEST_TIME 88 8602
TEST_TIME 89 8600
TEST_TIME 90 8696
TEST_TIME 91 8722
TEST_TIME 92 8685
TEST_TIME 93 8701
TEST_TIME 94 8711
TEST_TIME 95 8755
TEST_TIME 96 8714
TEST_TIME 97 8656
TEST_TIME 98 8655
TEST_TIME 99 8601
TEST_TIME 100 8563
TEST_TIME 101 8529
TEST_TIME 102 8555
TEST_TIME 103 8585
TEST_TIME 104 8564
TEST_TIME 105 8594
TEST_TIME 106 8563
TEST_TIME 107 8607
TEST_TIME 108 8632
TEST_TIME 109 8637
TEST_TIME 110 8673
TEST_TIME 111 8711
TEST_TIME 112 8775
TEST_TIME 113 8719
TEST_TIME 114 8648
TEST_TIME 115 8617
TEST_TIME 116 8605
TEST_TIME 117 8568
TEST_TIME 118 8565
TEST_TIME 119 8559
TEST_TIME 120 8549

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2020 12:53:16 to 20-08-2020 12:53:19.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1420832 1420832 0 0.00 0 31426 8 0 0
doc_list_biud 1420829 1420829 0 0.00 0 27914 41 0 0
sp_add_invoice_to_stock 4817 4670 147 3.05 30 214714 20519 0 147
sp_cancel_adding_invoice 5316 5316 0 0.00 5 84631 1139 0 0
sp_cancel_client_order 1632 1632 0 0.00 4 21390 480 0 0
sp_cancel_customer_reserve 519119 519119 0 0.00 1 21586 92 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 2 19326 156 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 2 4408 103 0 0
sp_cancel_supplier_invoice 5859 5846 13 0.22 2 27921 1087 0 13
sp_cancel_supplier_order 351 351 0 0.00 7 1554237 167793 0 0
sp_cancel_write_off 9512 9512 0 0.00 1 11207 144 0 0
sp_client_order 18539 18539 0 0.00 3 42302 439 0 0
sp_customer_reserve 428119 428119 0 0.00 0 82161 201 0 0
sp_fill_shopping_cart 28433 28433 0 0.00 1 21371 52 0 0
sp_fill_shopping_cart_clo_res 428116 428116 0 0.00 0 20276 18 0 0
sp_get_clo_for_invoice 4667 4667 0 0.00 0 44884 407 0 0
sp_kill_cost_storno 15828 15828 0 0.00 0 9662 88 0 0
sp_kill_qstorno_ret_qs2qd 1053947 1053947 0 0.00 0 27912 49 0 0
sp_kill_qty_storno 555279 555279 0 0.00 0 27913 104 0 0
sp_lock_selected_doc 18746 18746 0 0.00 0 5810 5 0 0
sp_make_cost_storno 25263 25263 0 0.00 0 15461 71 0 0
sp_multiply_rows_for_pdistr 25262 25262 0 0.00 0 10813 41 0 0
sp_multiply_rows_for_qdistr 18539 18539 0 0.00 0 9383 214 0 0
sp_pay_from_customer 6518 6518 0 0.00 4 11831 120 0 0
sp_pay_to_supplier 5240 5240 0 0.00 4 11095 149 0 0
sp_payment_common 11757 11757 0 0.00 1 4963 20 0 0
sp_qd_handle_on_cancel_clo 1632 1632 0 0.00 0 13968 433 0 0
sp_qd_handle_on_invoice_upd_sts 9982 9982 0 0.00 0 17266 459 0 0
sp_qd_handle_on_reserve_upd_sts 8837 8837 0 0.00 0 12009 66 0 0
sp_reserve_write_off 8838 8838 0 0.00 6 19557 225 0 0
sp_supplier_invoice 5054 5054 0 0.00 6 123071 4432 0 0
sp_supplier_order 4847 4847 0 0.00 5 108023 1658 0 0
srv_aggregate_perf_data 103 103 0 0.00 2528 454672 20111 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 613 155919 42701 0 0
srv_find_qd_qs_mism 981680 981680 0 0.00 0 31423 6 0 0
srv_make_invnt_saldo 362 362 0 0.00 41 146493 6064 0 0
srv_make_money_saldo 682 682 0 0.00 1 18191 949 0 0
srv_recalc_idx_stat 4 4 0 0.00 87738 636002 321915 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4670 4670 0 0.00 0 11123 257 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 3699 163 0 0
t$perf-norm:sp_cancel_client_order 1632 1632 0 0.00 0 1617 12 0 0
t$perf-norm:sp_cancel_customer_reserve 614 614 0 0.00 0 4094 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 2748 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 162 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 1787 24 0 0
t$perf-norm:sp_cancel_supplier_order 351 351 0 0.00 0 60327 3311 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 1732 9 0 0
t$perf-norm:sp_client_order 18539 18539 0 0.00 0 8642 24 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 1236 6 0 0
t$perf-norm:sp_pay_from_customer 6518 6518 0 0.00 0 4712 12 0 0
t$perf-norm:sp_pay_to_supplier 5240 5240 0 0.00 0 7865 16 0 0
t$perf-norm:sp_reserve_write_off 8838 8838 0 0.00 0 20249 17 0 0
t$perf-norm:sp_supplier_invoice 5054 5054 0 0.00 0 6296 25 0 0
t$perf-norm:sp_supplier_order 4847 4847 0 0.00 0 1956 21 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1140 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 785 15 0 0
t$perf-norm:srv_make_invnt_saldo 362 362 0 0.00 0 9477 52 0 0
t$perf-norm:srv_make_money_saldo 682 682 0 0.00 0 2744 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9624 9330 294 3.05 0 8950 20 0 294
v_all_customers 34360 34360 0 0.00 0 18 1 0 0
v_all_suppliers 27250 27250 0 0.00 0 24 1 0 0
v_all_wares 37078 37078 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 160 6 0 0
v_cancel_client_order 3264 3264 0 0.00 0 21158 11 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 19299 22 0 0
v_cancel_customer_reserve 1228 1228 0 0.00 0 1335 8 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1309 10 0 0
v_cancel_supplier_invoice 720 694 26 3.61 0 579 17 0 26
v_cancel_supplier_order 702 702 0 0.00 0 1830 10 0 0
v_cancel_write_off 1816 1816 0 0.00 0 10998 22 0 0
v_max_id_clo_ord 4846 4846 0 0.00 0 2730 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 1894 15 0 0
v_max_id_ord_sup 5048 5048 0 0.00 0 6736 8 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 1045 5 0 0
v_max_non_paid_realizn 3294 3294 0 0.00 0 1253 3 0 0
v_min_id_clo_ord 4846 4846 0 0.00 0 2313 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 3379 7 0 0
v_min_id_ord_sup 5048 5048 0 0.00 0 8620 9 0 0
v_min_non_paid_invoice 5240 5240 0 0.00 0 4961 13 0 0
v_min_non_paid_realizn 6517 6517 0 0.00 0 4909 14 0 0
v_our_firm 9164 9164 0 0.00 0 36 1 0 0
v_reserve_write_off 17674 17674 0 0.00 0 19300 9 0 0
x_lock_depdocs_on_canc_invoice 10815 10815 0 0.00 0 14245 156 0 0
x_lock_depdocs_on_canc_sup_ord 351 351 0 0.00 0 13787 599 0 0
x_make_qstorno_1000_1200 4847 4847 0 0.00 2 107765 1561 0 0
x_make_qstorno_1200_2000 5054 5054 0 0.00 2 123032 4219 0 0
x_make_qstorno_2100_3300 428119 428119 0 0.00 0 82158 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2020 12:53:20 to 20-08-2020 12:53:24.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:21:54 1 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
10:22:55 30422 8192000000 9722405344 10017890304 796088304 8.857 100 89 178 0 824293120 7035840 21168976
10:24:32 27775 8192000000 9758531936 10062712832 834593120 9.245 100 91 182 1 868165344 11454720 22117504
10:26:05 15790 8192000000 9797022272 10113478656 867806768 9.578 100 86 172 0 906458848 18617264 20034816
10:27:38 20013 8192000000 9872758848 10203779072 927714144 10.172 100 84 168 2 979444496 33453168 18277184
10:29:02 15728 8192000000 9933517568 10273837056 966050736 10.548 100 88 174 1 1041123648 56902128 18170784
10:30:23 7518 8192000000 9956552672 10307211264 991598272 10.797 100 71 141 2 1064579744 58772128 14209344
10:31:33 2417 8192000000 9992724304 10353487872 1019104288 11.063 100 85 168 0 1108324864 71781184 17439392
10:32:39 2892 8192000000 10007664640 10374066176 1039875312 11.263 100 76 151 1 1115278160 59541632 15861216
10:33:45 2186 8192000000 10024880880 10393296896 1052403440 11.384 100 89 177 0 1141711696 70719488 18588768
10:34:49 9961 8192000000 9985571792 10368626688 1056855392 11.426 100 53 106 0 1106886256 39565664 10465200
10:36:02 6583 8192000000 10017955408 10391293952 1065502672 11.509 100 66 128 1 1129301744 50465360 13333712
10:37:10 6258 8192000000 9980386304 10378321920 1070105360 11.553 100 32 63 4 1102900176 26314144 6480672
10:38:18 4180 8192000000 9997645728 10395750400 1073798832 11.588 100 30 60 2 1127692080 47990784 5902464
10:39:22 5125 8192000000 10003674416 10392903680 1079220576 11.640 100 42 84 0 1124071104 36254016 8596512
10:40:30 65368 8192000000 10015507360 10403078144 1078301296 11.631 100 50 99 2 1109165040 20057440 10806304
10:42:36 5355 8192000000 10015858016 10402299904 1076621568 11.615 100 55 110 1 1108351168 19634320 12095280
10:43:44 9603 8192000000 9977592944 10372186112 1048085504 11.342 97 31 60 3 1069769664 14586064 7098096
10:44:54 5629 8192000000 9931467568 10315952128 1022147120 11.093 98 20 40 0 1041856976 14971248 4738608
10:46:01 43029 8192000000 9901998496 10291589120 920988176 10.106 90 36 73 1 959823408 30808816 8026416
10:47:47 9152 8192000000 9939656144 10323181568 1008213008 10.958 99 34 67 1 1035678352 20070240 7395104
10:48:57 6796 8192000000 9945959664 10336436224 1029000592 11.159 100 32 64 1 1057383712 21830176 6552944
10:50:07 6162 8192000000 9955566496 10334244864 1044272896 11.306 100 42 82 3 1063756496 10101904 9381696
10:51:13 5484 8192000000 9964936432 10349166592 1056869168 11.427 100 61 117 2 1078089296 8942608 12277520
10:52:22 47207 8192000000 10000880720 10385985536 1077533792 11.624 100 51 102 1 1112475232 24595424 10346016
10:54:18 28102 8192000000 10048198960 10443997184 1097263200 11.812 100 49 98 1 1162564240 55165520 10135520
10:55:56 24662 8192000000 10100068176 10498015232 1105692704 11.892 100 53 106 3 1199940256 82980464 11267088
10:57:35 42325 8192000000 10124326016 10525077504 1115362368 11.983 100 35 70 2 1239628832 115942960 8323504
10:59:40 26278 8192000000 10162590080 10573000704 1133609344 12.155 100 39 76 3 1267384768 124564320 9211104
11:01:12 26322 8192000000 10197309024 10613260288 1142777808 12.242 100 40 80 1 1307812800 154645008 10389984
11:02:57 7843 8192000000 10239452304 10643042304 1162339152 12.425 100 52 104 1 1342342320 168023424 11979744
11:04:12 9550 8192000000 10290867024 10698989568 1167897152 12.477 100 62 123 3 1401914096 220288160 13728784
11:05:27 12656 8192000000 10325943232 10727190528 1168858128 12.486 100 70 140 1 1418424880 233972320 15594432
11:06:47 6796 8192000000 10339420000 10748444672 1172723648 12.522 100 70 140 2 1439510496 251068688 15718160
11:08:01 6478 8192000000 10370215024 10797473792 1183766864 12.625 100 72 143 0 1468196096 267218576 17210656
11:09:19 8500 8192000000 10374503024 10783830016 1177966752 12.571 100 77 151 0 1462821344 268208928 16645664
11:10:36 5570 8192000000 10397777184 10800648192 1181133472 12.601 100 79 156 1 1498474320 298699504 18641344
11:11:49 14961 8192000000 10391302688 10804326400 1180073024 12.591 100 74 144 2 1498031440 302002208 15956208
11:13:13 2867 8192000000 10415851856 10817003520 1153804800 12.345 98 68 134 1 1510716960 341577680 15334480
11:14:22 3132 8192000000 10417972576 10848165888 1149971872 12.309 98 78 155 1 1517100400 348456640 18671888
11:15:31 3480 8192000000 10447375488 10854199296 1161706896 12.419 99 82 162 1 1536209024 356327888 18174240
11:16:42 2151 8192000000 10418228800 10824613888 1171955184 12.515 100 86 171 1 1516227424 324664960 19607280
11:17:52 4110 8192000000 10420062560 10847113216 1160927504 12.412 100 61 122 1 1516567904 341289056 14351344
11:19:13 612 8192000000 10418629632 10820358144 1149136272 12.301 100 72 143 4 1510808800 344741408 16931120
11:20:23 8717 8192000000 10446679568 10850422784 1148151024 12.292 99 73 142 1 1533651968 369057712 16443232
11:21:41 3213 8192000000 10517128528 10922475520 1164361328 12.444 100 87 169 2 1614861136 429844240 20655568
11:22:48 12632 8192000000 10336878944 10811293696 1146284720 12.275 98 37 74 3 1439968064 284435984 9247360
11:24:17 5406 8192000000 10385426384 10830663680 1158357744 12.388 100 47 94 1 1473581824 304361296 10862784
11:25:28 9092 8192000000 10322099136 10773123072 1156008832 12.366 100 54 108 0 1427746096 258856560 12880704
11:26:41 11014 8192000000 10291177152 10727309312 1137617440 12.193 99 29 57 1 1388856128 244607008 6631680
11:28:08 18416 8192000000 10261238464 10753040384 1115603024 11.985 99 21 42 1 1391149040 270604896 4941120
11:30:02 29747 8192000000 10068345280 10576957440 1095889728 11.799 98 12 24 0 1198121600 98950688 3281184
11:31:35 45147 8192000000 10069689680 10505781248 1101140432 11.848 98 16 32 0 1160078192 54578544 4359216
11:33:21 14388 8192000000 10078567776 10495029248 1118038928 12.008 99 52 106 3 1165183600 36252016 10892656
11:34:36 14265 8192000000 10062099840 10487820288 1118959472 12.017 99 44 90 2 1149918400 20904672 10054256
11:35:52 86259 8192000000 10072904608 10575892480 1111676976 11.948 98 21 42 1 1197187520 80714784 4795760
11:38:45 147208 8192000000 10074794624 10501111808 1119720880 12.024 99 26 50 0 1166773472 41739856 5312736
11:42:24 19428 8192000000 10073498496 10506346496 1107158128 11.906 98 25 50 1 1151355952 39046592 5151232
11:43:50 22269 8192000000 10114843360 10541563904 1120921440 12.036 100 31 62 1 1198418224 70674112 6822672
11:45:14 29528 8192000000 10104968752 10533355520 1130513248 12.126 100 47 97 3 1194942320 54507248 9921824
11:46:45 23864 8192000000 10114769136 10574790656 1137224816 12.189 100 19 38 3 1207573312 65764272 4584224
11:48:24 36471 8192000000 10082718880 10559639552 1116171840 11.991 98 21 42 1 1207262368 86224640 4865888
11:50:04 81274 8192000000 10095706464 10563215360 1109258640 11.925 97 22 43 2 1202916256 88274160 5383456
11:53:05 70498 8192000000 10062193664 10587492352 1116006640 11.989 99 11 22 0 1167340416 49499600 1834176
11:55:22 56719 8192000000 10063762448 10535632896 1077146720 11.620 95 13 26 1 1136206912 56105344 2954848
11:57:21 50188 8192000000 10113938656 10551119872 1125106336 12.075 99 24 48 0 1178696496 48781408 4808752
11:59:19 76979 8192000000 10120501984 10596478976 1103508368 11.871 97 18 36 3 1176856496 69669568 3678560
12:01:41 155919 8192000000 10132523264 10615922688 1096297936 11.803 96 13 26 1 1205091072 106042160 2750976
12:05:26 128642 8192000000 10073639712 10521284608 1062530224 11.481 93 22 44 1 1114269744 47424928 4314592
12:09:23 65342 8192000000 10042960336 10492923904 1074638400 11.596 95 17 34 1 1122997056 44134944 4223712
12:11:45 64515 8192000000 10054935408 10504871936 1090556400 11.748 98 13 26 2 1138535264 45433632 2545232
12:13:51 65396 8192000000 10069061808 10545516544 1104394448 11.879 99 11 22 1 1163667392 57224096 2048848
12:15:57 71916 8192000000 10040044448 10461581312 1041088016 11.275 94 14 28 1 1119804352 75630672 3085664
12:18:10 56982 8192000000 10051268592 10493968384 1082061408 11.667 98 18 36 3 1137935984 51856128 4018448
12:20:10 93105 8192000000 10081187680 10534715392 1059084784 11.448 95 15 30 1 1121933904 59910784 2938336
12:23:06 58956 8192000000 10075735120 10492502016 1088742864 11.731 97 30 60 1 1137779168 43617040 5419264
12:25:07 23135 8192000000 10111769696 10533838848 1113364816 11.964 99 42 83 1 1177648448 55997840 8285792
12:26:37 97559 8192000000 10101598944 10565402624 1120592784 12.033 99 30 60 0 1178665312 51395888 6676640
12:31:37 77887 8192000000 10114274624 10570448896 1132935760 12.149 100 11 22 0 1196009776 60584480 2489536
12:33:57 77490 8192000000 10057459888 10510344192 1079697488 11.645 96 18 35 2 1138168384 54929488 3541408
12:36:17 58699 8192000000 10073148192 10519363584 1100687424 11.844 97 31 62 1 1165630736 57804048 7139264
12:38:25 59229 8192000000 10116641696 10560491520 1121136496 12.038 99 22 44 1 1194496288 68359504 5000288
12:40:34 43472 8192000000 10071461088 10550865920 1077646032 11.625 96 20 40 2 1143560496 61689696 4224768
12:42:18 101566 8192000000 10069242784 10591776768 1104171584 11.877 98 7 14 1 1175626400 70169024 1285792
12:45:01 126138 8192000000 10067704352 10517049344 1131408720 12.135 100 10 20 0 1178033552 44429568 2195264
12:49:15 73829 8192000000 10081641136 10528251904 1124239520 12.067 99 19 37 2 1152060896 23560672 4260704
12:51:29 33946 8192000000 9134512496 9421533184 277937968 3.281 25 4 6 1 299516112 20690752 887392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-08-2020 12:53:26 to 20-08-2020 12:53:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 147 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-08-2020 12:53:41 to 20-08-2020 12:53:42.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218751
MON$OLDEST_ACTIVE               218752
MON$OLDEST_SNAPSHOT             218752
MON$NEXT_TRANSACTION            218752
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2097088
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000003c06020000000000
MON$NEXT_ATTACHMENT             678
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2170 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2170 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2170 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2170 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs.oltp40 according to config parameter 'remove_isql_logs'

20.08.20 12:53:44. There are 3 logs in /var/tmp/logs.oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-08-20 12:53:47 NO_SEVERE_ERRORS_FOUND
20.08.20 12:53:49. End of report.