Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2020 03:53
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 e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
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.00
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 32643792 kB
MemFree 16873840 kB
MemAvailable 31571908 kB
Buffers 20 kB
Cached 14745560 kB
SwapCached 4448 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=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-06 03:52:33
DTS_END                         2020-10-06 03:51:53.3490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/33874
TRN_ID                          226702
ATT_ID                          574
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: 9030.35 23978 1083642
customer order: creation 161.24 382 19349
customer order: refuse 13.65 353 1638
order to supplier: creation 43.08 1419 5170
order to supplier: removal 3.41 124965 409
invoice (draft): creation 43.15 3533 5178
invoice (draft): removal 52.58 916 6309
invoice accept: apply 40.19 18572 4823
invoice accept: cancel 47.23 870 5667
customer reserve: creation 3710.28 183 445233
customer reserve: removal 4629.26 72 555511
realization accept: apply 78.41 175 9409
realization accept: cancel 87.05 140 10446
payment from customer: creation 56.01 93 6721
payment from customer: removal 5.44 78 653
payment to supplier: creation 46.23 112 5548
payment to supplier: removal 3.14 111 377
service: total inventory turnovers 3.19 4077 383
service: total monetary turnovers 5.89 682 707
service: aggregate perf. data 0.88 13417 106
service: refresh index statistics 0.04 309408 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-10-2020 03:54:05 to 06-10-2020 03:54:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4352
TEST_TIME 2 5199
TEST_TIME 3 5739
TEST_TIME 4 5422
TEST_TIME 5 5227
TEST_TIME 6 5346
TEST_TIME 7 5506
TEST_TIME 8 5680
TEST_TIME 9 6283
TEST_TIME 10 6168
TEST_TIME 11 6227
TEST_TIME 12 6414
TEST_TIME 13 6742
TEST_TIME 14 6978
TEST_TIME 15 7137
TEST_TIME 16 7890
TEST_TIME 17 8151
TEST_TIME 18 8472
TEST_TIME 19 8690
TEST_TIME 20 8732
TEST_TIME 21 8951
TEST_TIME 22 8895
TEST_TIME 23 9143
TEST_TIME 24 9343
TEST_TIME 25 9659
TEST_TIME 26 9804
TEST_TIME 27 9728
TEST_TIME 28 11776
TEST_TIME 29 11542
TEST_TIME 30 11423
TEST_TIME 31 11344
TEST_TIME 32 11187
TEST_TIME 33 10890
TEST_TIME 34 10603
TEST_TIME 35 10560
TEST_TIME 36 10624
TEST_TIME 37 10491
TEST_TIME 38 10480
TEST_TIME 39 10581
TEST_TIME 40 10417
TEST_TIME 41 10346
TEST_TIME 42 10249
TEST_TIME 43 10198
TEST_TIME 44 10175
TEST_TIME 45 10090
TEST_TIME 46 10076
TEST_TIME 47 9999
TEST_TIME 48 10000
TEST_TIME 49 9974
TEST_TIME 50 9936
TEST_TIME 51 9981
TEST_TIME 52 9986
TEST_TIME 53 10048
TEST_TIME 54 10048
TEST_TIME 55 9992
TEST_TIME 56 9980
TEST_TIME 57 9983
TEST_TIME 58 9836
TEST_TIME 59 9694
TEST_TIME 60 9612
TEST_TIME 61 9579
TEST_TIME 62 9499
TEST_TIME 63 9532
TEST_TIME 64 9473
TEST_TIME 65 9513
TEST_TIME 66 9447
TEST_TIME 67 9422
TEST_TIME 68 9398
TEST_TIME 69 9396
TEST_TIME 70 9673
TEST_TIME 71 9644
TEST_TIME 72 9550
TEST_TIME 73 9540
TEST_TIME 74 9721
TEST_TIME 75 9681
TEST_TIME 76 9661
TEST_TIME 77 9662
TEST_TIME 78 9738
TEST_TIME 79 9799
TEST_TIME 80 9678
TEST_TIME 81 9571
TEST_TIME 82 9521
TEST_TIME 83 9485
TEST_TIME 84 9435
TEST_TIME 85 9371
TEST_TIME 86 9431
TEST_TIME 87 9389
TEST_TIME 88 9385
TEST_TIME 89 9387
TEST_TIME 90 9389
TEST_TIME 91 9387
TEST_TIME 92 9357
TEST_TIME 93 9343
TEST_TIME 94 9322
TEST_TIME 95 9536
TEST_TIME 96 9515
TEST_TIME 97 9506
TEST_TIME 98 9480
TEST_TIME 99 9399
TEST_TIME 100 9365
TEST_TIME 101 9299
TEST_TIME 102 9246
TEST_TIME 103 9229
TEST_TIME 104 9213
TEST_TIME 105 9172
TEST_TIME 106 9192
TEST_TIME 107 9169
TEST_TIME 108 9172
TEST_TIME 109 9151
TEST_TIME 110 9136
TEST_TIME 111 9188
TEST_TIME 112 9171
TEST_TIME 113 9178
TEST_TIME 114 9156
TEST_TIME 115 9242
TEST_TIME 116 9204
TEST_TIME 117 9150
TEST_TIME 118 9103
TEST_TIME 119 9049
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2020 03:54:09 to 06-10-2020 03:54:12.

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 1495790 1495790 0 0.00 0 13471 7 0 0
doc_list_biud 1495790 1495790 0 0.00 0 86812 34 0 0
sp_add_invoice_to_stock 4931 4823 108 2.19 26 204415 18572 0 108
sp_cancel_adding_invoice 5667 5667 0 0.00 5 62055 870 0 0
sp_cancel_client_order 1638 1638 0 0.00 4 9969 353 0 0
sp_cancel_customer_reserve 555511 555511 0 0.00 1 57197 73 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 6280 78 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 5435 112 0 0
sp_cancel_supplier_invoice 6320 6309 11 0.17 2 86824 917 0 11
sp_cancel_supplier_order 409 409 0 0.00 5 1324688 124966 0 0
sp_cancel_write_off 10446 10446 0 0.00 1 20034 141 0 0
sp_client_order 19349 19349 0 0.00 4 27250 382 0 0
sp_customer_reserve 445233 445233 0 0.00 0 59192 183 0 0
sp_fill_shopping_cart 29685 29685 0 0.00 1 24802 44 0 0
sp_fill_shopping_cart_clo_res 445225 445225 0 0.00 0 23548 18 0 0
sp_get_clo_for_invoice 4814 4814 0 0.00 0 34075 380 0 0
sp_kill_cost_storno 17143 17143 0 0.00 0 9483 63 0 0
sp_kill_qstorno_ret_qs2qd 1128186 1128186 0 0.00 0 86811 39 0 0
sp_kill_qty_storno 594202 594202 0 0.00 0 86812 84 0 0
sp_lock_selected_doc 19653 19653 0 0.00 0 8726 5 0 0
sp_make_cost_storno 26491 26491 0 0.00 0 13465 48 0 0
sp_multiply_rows_for_pdistr 26491 26491 0 0.00 0 7139 31 0 0
sp_multiply_rows_for_qdistr 19349 19349 0 0.00 0 27238 220 0 0
sp_pay_from_customer 6721 6721 0 0.00 4 15741 93 0 0
sp_pay_to_supplier 5548 5548 0 0.00 5 8539 113 0 0
sp_payment_common 12269 12269 0 0.00 1 15694 16 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 9876 321 0 0
sp_qd_handle_on_invoice_upd_sts 10480 10480 0 0.00 0 45857 409 0 0
sp_qd_handle_on_reserve_upd_sts 9409 9409 0 0.00 0 16971 59 0 0
sp_reserve_write_off 9409 9409 0 0.00 6 17017 175 0 0
sp_supplier_invoice 5178 5178 0 0.00 6 101026 3534 0 0
sp_supplier_order 5170 5170 0 0.00 6 43874 1419 0 0
srv_aggregate_perf_data 106 106 0 0.00 2482 111831 13418 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 2372 108928 39699 0 0
srv_find_qd_qs_mism 1038353 1038353 0 0.00 0 10115 6 0 0
srv_make_invnt_saldo 383 383 0 0.00 61 114967 4078 0 0
srv_make_money_saldo 707 707 0 0.00 1 12957 682 0 0
srv_recalc_idx_stat 5 5 0 0.00 91476 383529 309408 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4823 4823 0 0.00 0 10222 183 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 0 4053 104 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 4006 15 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 1324 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 4477 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2715 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 565 11 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 15427 1524 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 938 5 0 0
t$perf-norm:sp_client_order 19349 19349 0 0.00 0 13283 20 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 4632 17 0 0
t$perf-norm:sp_pay_from_customer 6721 6721 0 0.00 0 11219 13 0 0
t$perf-norm:sp_pay_to_supplier 5548 5548 0 0.00 0 5631 6 0 0
t$perf-norm:sp_reserve_write_off 9409 9409 0 0.00 0 6855 12 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 4294 20 0 0
t$perf-norm:sp_supplier_order 5170 5170 0 0.00 0 2939 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 199 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 117 4 0 0
t$perf-norm:srv_make_invnt_saldo 383 383 0 0.00 0 304 5 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 9392 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9842 9626 216 2.19 0 6030 16 0 216
v_all_customers 35402 35402 0 0.00 0 109 1 0 0
v_all_suppliers 28884 28884 0 0.00 0 48 1 0 0
v_all_wares 38690 38690 0 0.00 0 111 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 1315 10 0 0
v_cancel_client_order 3276 3276 0 0.00 0 1799 5 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 2054 6 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 0 1478 5 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3564 10 0 0
v_cancel_supplier_invoice 824 802 22 2.66 0 4757 20 0 22
v_cancel_supplier_order 818 818 0 0.00 0 670 5 0 0
v_cancel_write_off 1940 1940 0 0.00 0 1831 14 0 0
v_max_id_clo_ord 5166 5166 0 0.00 0 1143 2 0 0
v_max_id_clo_res 936 936 0 0.00 0 1840 11 0 0
v_max_id_ord_sup 5174 5174 0 0.00 0 5479 5 0 0
v_max_non_paid_invoice 1446 1446 0 0.00 0 152 2 0 0
v_max_non_paid_realizn 3577 3577 0 0.00 0 2354 3 0 0
v_min_id_clo_ord 5166 5166 0 0.00 0 1018 5 0 0
v_min_id_clo_res 936 936 0 0.00 0 2057 8 0 0
v_min_id_ord_sup 5174 5174 0 0.00 0 14857 12 0 0
v_min_non_paid_invoice 5548 5548 0 0.00 0 5593 8 0 0
v_min_non_paid_realizn 6721 6721 0 0.00 0 15690 10 0 0
v_our_firm 9576 9576 0 0.00 0 17 1 0 0
v_reserve_write_off 18818 18818 0 0.00 0 1967 5 0 0
x_lock_depdocs_on_canc_invoice 11575 11575 0 0.00 0 25440 112 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 4570 304 0 0
x_make_qstorno_1000_1200 5170 5170 0 0.00 2 43867 1339 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 3 94365 3368 0 0
x_make_qstorno_2100_3300 445233 445233 0 0.00 0 59176 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2020 03:54:13 to 06-10-2020 03:54:17.

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
01:21:57 15 8192000000 8799242576 8810713088 4422672 0.053 2 1 2 0 4521792 63264 35856
01:22:58 39963 8192000000 9732698544 10029977600 810906864 9.007 100 86 172 0 840628528 8466928 21254736
01:24:49 8293 8192000000 9768387392 10077913088 847139568 9.371 100 90 180 0 882251968 14249584 20862816
01:26:08 19478 8192000000 9808932192 10131636224 875465472 9.655 100 77 154 0 916896944 24239952 17191520
01:27:30 12603 8192000000 9878325360 10213048320 921032272 10.106 100 73 146 2 989437184 52571616 15833296
01:28:45 12037 8192000000 9920690576 10266501120 957143920 10.461 100 82 163 1 1036500304 62148624 17207760
01:30:01 5876 8192000000 9949847872 10305171456 997143968 10.851 100 82 162 0 1065637984 51435568 17058448
01:31:09 3074 8192000000 9994108784 10356072448 1026291216 11.133 100 90 181 0 1109642016 64930480 18420320
01:32:14 1156 8192000000 10134039408 10500030464 1044062128 11.304 100 87 174 2 1137179200 74687824 18429248
01:33:15 2787 8192000000 10029509248 10411491328 1062664320 11.482 100 59 119 2 1139969760 65355808 11949632
01:34:18 4612 8192000000 10001071440 10388942848 1068705392 11.540 100 52 103 1 1116473616 37333392 10434832
01:35:23 45476 8192000000 9995962528 10405572608 1079788352 11.645 100 49 99 1 1110890368 20443456 10658560
01:37:12 3698 8192000000 10017173840 10408869888 1090192976 11.744 100 51 100 3 1127874496 26839280 10842240
01:38:19 7418 8192000000 10005313328 10397204480 1094847328 11.789 100 19 35 4 1115065280 15590288 4627664
01:39:27 6577 8192000000 9996251760 10400206848 1088485072 11.728 99 32 66 5 1100990128 5420816 7084240
01:40:39 48552 8192000000 10008587856 10421854208 1098511872 11.824 100 29 59 0 1120382688 15230192 6640624
01:42:29 6780 8192000000 10007485120 10407632896 1062807872 11.483 98 41 82 0 1105109424 32885488 9416064
01:43:36 7155 8192000000 9970680352 10363326464 1051945760 11.379 99 34 70 0 1091215552 31243056 8026736
01:44:45 5578 8192000000 10355325520 10721792000 943113088 10.324 93 47 90 1 972820464 19641008 10066368
01:45:51 21368 8192000000 10126399472 10518745088 963395600 10.522 95 28 56 1 1003008592 34504912 5108080
01:47:13 5741 8192000000 9940828912 10315563008 1020894736 11.081 100 50 99 0 1048001216 16250496 10855984
01:48:22 12942 8192000000 9946277088 10339602432 1031207840 11.180 99 32 64 1 1052018496 13388528 7422128
01:49:37 9055 8192000000 9986148880 10380599296 1058603232 11.443 100 51 103 4 1084557328 13735936 12218160
01:50:47 29589 8192000000 9970707536 10373259264 1064902544 11.503 100 14 28 1 1086976032 19545104 2528384
01:52:22 8217 8192000000 10258049856 10650128384 1091628576 11.758 100 51 102 2 1129627984 27656192 10343216
01:53:34 14284 8192000000 10078922048 10477469696 1107377376 11.908 100 59 117 4 1425305328 306318208 11609744
01:54:52 8360 8192000000 10079811536 10477846528 1120342960 12.030 100 42 83 3 1189925520 60442496 9140064
01:56:05 7610 8192000000 10124248896 10521722880 1138423072 12.201 100 57 110 0 1229375504 78851232 12101200
01:57:15 5925 8192000000 10189038640 10582319104 1145247744 12.265 100 62 124 0 1294720272 135624384 13848144
01:58:24 8828 8192000000 10226236640 10630270976 1153245616 12.340 100 57 110 1 1334577152 169006640 12324896
01:59:38 3354 8192000000 10252777280 10656423936 1162729200 12.429 100 68 135 0 1600677504 422716736 15231568
02:00:47 10034 8192000000 10233092400 10651910144 1150136400 12.311 99 47 94 0 1329042736 167762224 11144112
02:02:01 5760 8192000000 10266410480 10676805632 1161855888 12.421 100 68 135 4 1371806592 194492800 15457904
02:03:12 3479 8192000000 10295976496 10699612160 1169453584 12.492 100 71 143 3 1400845552 215773872 15618096
02:04:19 2469 8192000000 10587539312 10993451008 1174408160 12.538 100 80 160 1 1448328176 256809968 17110048
02:05:25 4775 8192000000 10349363744 10756145152 1160071680 12.404 100 64 128 0 1456214384 281662976 14479728
02:06:36 2866 8192000000 10384362096 10779967488 1145050048 12.263 99 84 168 1 1478378080 313460032 19868000
02:07:43 2555 8192000000 10352778448 10755579904 1151967104 12.328 100 72 144 1 1456028816 287226128 16835584
02:08:51 3935 8192000000 10339829008 10736103424 1148045728 12.291 100 80 157 2 1438301360 271786000 18469632
02:10:01 5378 8192000000 10333877568 10747420672 1148173616 12.292 99 71 142 0 1429106544 264094848 16838080
02:11:13 2724 8192000000 10597543600 10992672768 1146138400 12.273 99 74 148 2 1691880784 528628144 17114240
02:12:24 7246 8192000000 10392929680 10803150848 1143153184 12.245 99 55 111 1 1479147808 322421728 13572896
02:13:37 12009 8192000000 10425509344 10857066496 1152191664 12.330 100 39 78 2 1524021712 361092496 10737552
02:14:52 2370 8192000000 10437052608 10847690752 1169101312 12.488 100 66 131 1 1534953968 350327248 15525408
02:16:00 13269 8192000000 10428810464 10888155136 1122162224 12.047 98 39 78 0 1487577200 355267776 10147200
02:17:16 31111 8192000000 10450628816 10894233600 1123636000 12.061 100 25 50 3 1528432816 398168480 6628336
02:18:54 22851 8192000000 10386401200 10854821888 1098075600 11.819 100 20 40 0 1505751632 401675648 6000384
02:20:59 6121 8192000000 10057246400 10453143552 1098128672 11.820 99 72 143 0 1139145920 25473248 15544000
02:22:06 60375 8192000000 10091279760 10556252160 1125319120 12.077 100 18 37 2 1237520544 107839584 4361840
02:25:28 54193 8192000000 10111218848 10540695552 1113900624 11.969 99 21 42 1 1423787776 306023856 3863296
02:27:24 31187 8192000000 10137984784 10619760640 1111001344 11.942 98 15 30 1 1218715232 104381040 3332848
02:29:04 5996 8192000000 10370721776 10794668032 1120005344 12.027 99 57 111 4 1237591664 105685776 11900544
02:30:11 61776 8192000000 10074136384 10521153536 1124438800 12.069 100 15 31 1 1189022016 60966864 3616352
02:32:13 54679 8192000000 10335408480 10802237440 1127877984 12.101 99 16 32 1 1195197488 63787904 3531600
02:34:09 73503 8192000000 10063376512 10545991680 1118094752 12.009 99 13 26 0 1429793984 309225312 2473920
02:36:24 28278 8192000000 10070813424 10506444800 1128124672 12.104 100 23 47 1 1147096592 14406336 4565584
02:37:56 44306 8192000000 10314592192 10753318912 1132311424 12.143 99 22 44 1 1177647872 41087072 4249376
02:39:46 66057 8192000000 10076894672 10529837056 1118335248 12.011 98 17 34 1 1148003056 26456624 3211184
02:41:53 86688 8192000000 10115697888 10580484096 1136238448 12.180 99 22 44 0 1223191664 81520608 5432608
02:44:25 48279 8192000000 10069774032 10505732096 1133007296 12.150 99 14 28 0 1173899680 37842592 3049792
02:46:18 63254 8192000000 10109030768 10536972288 1075169216 11.601 94 27 54 1 1109662176 29139312 5353648
02:48:23 90968 8192000000 10050191648 10499555328 1120807136 12.035 99 15 30 3 1158993008 35382240 2803632
02:51:08 37593 8192000000 10068278944 10500296704 1107388288 11.908 98 32 63 3 1133953200 19691408 6873504
02:52:49 65486 8192000000 10108884736 10592845824 1124998320 12.074 99 12 24 0 1216267424 88430608 2838496
02:54:55 76069 8192000000 10089767072 10554728448 1106886640 11.903 97 17 34 1 1155466144 45231968 3347536
02:57:11 18585 8192000000 10054304048 10485915648 1103022000 11.866 97 35 69 0 1357052208 246843104 7187104
02:58:32 26474 8192000000 10344315968 10781007872 1130503248 12.126 99 26 52 1 1204653296 68404448 5745600
03:00:12 48898 8192000000 10198311456 10650619904 1117483024 12.003 97 21 40 1 1248407200 126479488 4444688
03:02:02 62931 8192000000 10085289680 10516475904 1103875872 11.874 98 12 24 3 1150292112 44117536 2298704
03:04:17 60418 8192000000 10077646384 10602549248 1094935328 11.790 97 23 47 2 1230892048 130360960 5595760
03:06:24 46858 8192000000 10054375856 10463068160 1026636592 11.136 92 27 54 0 1061459680 29461312 5361776
03:08:12 43648 8192000000 10099679840 10542751744 1081424112 11.661 99 34 68 1 1158235568 70371648 6439808
03:10:09 98381 8192000000 10274355088 10811834368 1090194608 11.745 99 9 18 0 1158563360 66792352 1576400
03:12:48 38547 8192000000 10066985568 10501353472 1107838976 11.912 100 29 58 3 1161613344 47670240 6104128
03:14:27 36624 8192000000 10307323744 10766110720 1113010736 11.961 100 15 29 1 1407769552 292013840 2744976
03:16:04 52932 8192000000 10094041488 10544619520 1101025344 11.847 99 15 30 2 1182232256 78162496 3044416
03:17:58 82542 8192000000 10097873552 10530668544 1110467568 11.937 99 15 30 0 1167497856 53999264 3031024
03:20:22 51882 8192000000 10059518336 10502062080 1103029552 11.866 98 18 36 0 1158070624 51165680 3875392
03:22:14 40481 8192000000 10329826624 10758336512 1116696816 11.996 99 34 68 0 1176872848 53020560 7155472
03:23:56 75330 8192000000 10171462272 10617638912 1119790816 12.025 98 21 42 1 1224085616 99987392 4307408
03:26:14 95654 8192000000 10091360688 10590121984 1100895536 11.846 97 14 28 0 1222718432 118897696 2925200
03:28:51 44085 8192000000 10110376720 10536284160 1125097024 12.075 99 42 83 2 1182321504 49212384 8012096
03:30:36 108928 8192000000 10069321936 10529648640 1113425680 11.965 98 22 44 0 1178513856 60908000 4180176
03:33:25 57324 8192000000 10287803760 10766991360 1105596816 11.891 98 6 12 0 1150086736 43524608 965312
03:35:23 61885 8192000000 10087515600 10554511360 1133135008 12.151 100 19 38 1 1185599904 48372864 4092032
03:37:25 41925 8192000000 10058746240 10521169920 1118350048 12.011 99 13 26 0 1147207408 26377504 2479856
03:39:08 27075 8192000000 10120297936 10541355008 1140036656 12.216 100 53 107 0 1195301392 44143920 11120816
03:40:37 35685 8192000000 10103603904 10533838848 1141960752 12.234 100 44 88 0 1186392864 35216432 9215680
03:42:32 37853 8192000000 10097220960 10532114432 1144395056 12.257 100 19 38 3 1186112880 38319968 3397856
03:44:11 40392 8192000000 10075016832 10496647168 1114984496 11.980 97 18 36 0 1153282528 34852992 3445040
03:45:54 107221 8192000000 10091495280 10570399744 1121424720 12.040 98 14 28 1 1189042224 64879184 2738320
03:48:48 63424 8192000000 10079877376 10532069376 1125237296 12.076 98 23 45 1 1390129456 259429344 5462816
03:51:04 88948 8192000000 9958477824 10380726272 1021548416 11.087 90 16 31 2 1082147728 58566800 2032512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-10-2020 03:54:19 to 06-10-2020 03:54:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 108 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2020 03:54:35 to 06-10-2020 03:54:37.

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          226701
MON$OLDEST_ACTIVE               233121
MON$OLDEST_SNAPSHOT             233121
MON$NEXT_TRANSACTION            233121
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                       2102656
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                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             750
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2214 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2214 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2214 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.2214 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Tue Oct  6 03:52:34 2020
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

06.10.20 03:54:39. 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-10-06 03:54:41 NO_SEVERE_ERRORS_FOUND
06.10.20 03:54:44. End of report.