Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.06.2021 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
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 4793.92
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 17018636 kB
MemAvailable 31401948 kB
Buffers 0 kB
Cached 14611372 kB
SwapCached 24 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
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_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
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 2021-06-09 10:27:00
DTS_END                         2021-06-09 10:27:00.9480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52738
TRN_ID                          226669
ATT_ID                          587
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: 8844.92 26471 1061390
customer order: creation 160.92 418 19310
customer order: refuse 13.73 307 1647
order to supplier: creation 43.28 1412 5193
order to supplier: removal 3.39 111410 407
invoice (draft): creation 43.44 3428 5213
invoice (draft): removal 51.01 842 6121
invoice accept: apply 40.10 17757 4812
invoice accept: cancel 45.63 870 5476
customer reserve: creation 3727.30 173 447276
customer reserve: removal 4435.27 69 532232
realization accept: apply 77.95 153 9354
realization accept: cancel 80.53 138 9663
payment from customer: creation 55.25 91 6630
payment from customer: removal 5.46 105 655
payment to supplier: creation 47.61 110 5713
payment to supplier: removal 3.30 81 396
service: total inventory turnovers 3.87 2972 464
service: total monetary turnovers 6.02 507 722
service: aggregate perf. data 0.85 17664 102
service: refresh index statistics 0.03 370918 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-06-2021 10:27:42 to 09-06-2021 10:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6795
TEST_TIME 2 5893
TEST_TIME 3 5436
TEST_TIME 4 5034
TEST_TIME 5 5127
TEST_TIME 6 5172
TEST_TIME 7 5436
TEST_TIME 8 5380
TEST_TIME 9 5786
TEST_TIME 10 5980
TEST_TIME 11 6112
TEST_TIME 12 6869
TEST_TIME 13 7109
TEST_TIME 14 7284
TEST_TIME 15 7300
TEST_TIME 16 7705
TEST_TIME 17 7781
TEST_TIME 18 8308
TEST_TIME 19 8733
TEST_TIME 20 9069
TEST_TIME 21 9272
TEST_TIME 22 9321
TEST_TIME 23 10272
TEST_TIME 24 10468
TEST_TIME 25 11256
TEST_TIME 26 11184
TEST_TIME 27 10812
TEST_TIME 28 10597
TEST_TIME 29 10418
TEST_TIME 30 10355
TEST_TIME 31 10176
TEST_TIME 32 9974
TEST_TIME 33 9824
TEST_TIME 34 9756
TEST_TIME 35 9558
TEST_TIME 36 9528
TEST_TIME 37 9485
TEST_TIME 38 9340
TEST_TIME 39 9651
TEST_TIME 40 9595
TEST_TIME 41 9516
TEST_TIME 42 9582
TEST_TIME 43 9454
TEST_TIME 44 9443
TEST_TIME 45 9688
TEST_TIME 46 9641
TEST_TIME 47 9636
TEST_TIME 48 9587
TEST_TIME 49 9775
TEST_TIME 50 9801
TEST_TIME 51 9619
TEST_TIME 52 9460
TEST_TIME 53 9365
TEST_TIME 54 9311
TEST_TIME 55 9243
TEST_TIME 56 9186
TEST_TIME 57 9181
TEST_TIME 58 9129
TEST_TIME 59 9181
TEST_TIME 60 9205
TEST_TIME 61 9305
TEST_TIME 62 9272
TEST_TIME 63 9237
TEST_TIME 64 9219
TEST_TIME 65 9300
TEST_TIME 66 9298
TEST_TIME 67 9301
TEST_TIME 68 9327
TEST_TIME 69 9285
TEST_TIME 70 9224
TEST_TIME 71 9143
TEST_TIME 72 9104
TEST_TIME 73 9007
TEST_TIME 74 8952
TEST_TIME 75 8963
TEST_TIME 76 8987
TEST_TIME 77 8979
TEST_TIME 78 8970
TEST_TIME 79 8989
TEST_TIME 80 9041
TEST_TIME 81 9012
TEST_TIME 82 9053
TEST_TIME 83 9068
TEST_TIME 84 9137
TEST_TIME 85 9200
TEST_TIME 86 9202
TEST_TIME 87 9113
TEST_TIME 88 9041
TEST_TIME 89 9013
TEST_TIME 90 8966
TEST_TIME 91 8952
TEST_TIME 92 8911
TEST_TIME 93 9004
TEST_TIME 94 8955
TEST_TIME 95 8929
TEST_TIME 96 8942
TEST_TIME 97 8961
TEST_TIME 98 8919
TEST_TIME 99 9043
TEST_TIME 100 9053
TEST_TIME 101 9113
TEST_TIME 102 9091
TEST_TIME 103 9095
TEST_TIME 104 9102
TEST_TIME 105 9022
TEST_TIME 106 9006
TEST_TIME 107 8968
TEST_TIME 108 8920
TEST_TIME 109 8884
TEST_TIME 110 8843
TEST_TIME 111 8838
TEST_TIME 112 8824
TEST_TIME 113 8834
TEST_TIME 114 8832
TEST_TIME 115 8820
TEST_TIME 116 8820
TEST_TIME 117 8862
TEST_TIME 118 8878
TEST_TIME 119 8922
TEST_TIME 120 8848

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-06-2021 10:27:45 to 09-06-2021 10:27:49.

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 1474119 1474119 0 0.00 0 26386 6 0 0
doc_list_biud 1474119 1474119 0 0.00 0 65112 31 0 0
sp_add_invoice_to_stock 4929 4812 117 2.37 20 168833 17758 0 117
sp_cancel_adding_invoice 5476 5476 0 0.00 5 81648 871 0 0
sp_cancel_client_order 1647 1647 0 0.00 4 8996 307 0 0
sp_cancel_customer_reserve 532232 532232 0 0.00 1 65114 69 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 12254 105 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 5910 82 0 0
sp_cancel_supplier_invoice 6130 6121 9 0.14 2 22989 843 0 9
sp_cancel_supplier_order 407 407 0 0.00 7 1125115 111410 0 0
sp_cancel_write_off 9663 9663 0 0.00 1 12042 138 0 0
sp_client_order 19310 19310 0 0.00 4 91563 418 0 0
sp_customer_reserve 447276 447276 0 0.00 0 62149 174 0 0
sp_fill_shopping_cart 29702 29702 0 0.00 1 14308 47 0 0
sp_fill_shopping_cart_clo_res 447268 447268 0 0.00 0 20619 19 0 0
sp_get_clo_for_invoice 4804 4804 0 0.00 0 40565 426 0 0
sp_kill_cost_storno 16190 16190 0 0.00 0 13551 59 0 0
sp_kill_qstorno_ret_qs2qd 1080655 1080655 0 0.00 0 63138 37 0 0
sp_kill_qty_storno 569704 569704 0 0.00 0 65112 79 0 0
sp_lock_selected_doc 19688 19688 0 0.00 0 10114 6 0 0
sp_make_cost_storno 26501 26501 0 0.00 0 13277 47 0 0
sp_multiply_rows_for_pdistr 26501 26501 0 0.00 0 6336 28 0 0
sp_multiply_rows_for_qdistr 19310 19310 0 0.00 0 91542 261 0 0
sp_pay_from_customer 6630 6630 0 0.00 4 12766 92 0 0
sp_pay_to_supplier 5713 5713 0 0.00 5 13285 110 0 0
sp_payment_common 12343 12343 0 0.00 1 8632 18 0 0
sp_qd_handle_on_cancel_clo 1647 1647 0 0.00 0 7421 273 0 0
sp_qd_handle_on_invoice_upd_sts 10280 10280 0 0.00 0 21839 351 0 0
sp_qd_handle_on_reserve_upd_sts 9354 9354 0 0.00 0 6149 37 0 0
sp_reserve_write_off 9354 9354 0 0.00 7 10895 154 0 0
sp_supplier_invoice 5213 5213 0 0.00 7 90339 3428 0 0
sp_supplier_order 5193 5193 0 0.00 6 25726 1412 0 0
srv_aggregate_perf_data 102 102 0 0.00 2776 313005 17664 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1395 156047 50599 0 0
srv_find_qd_qs_mism 1015219 1015219 0 0.00 0 26384 6 0 0
srv_make_invnt_saldo 464 464 0 0.00 56 89769 2973 0 0
srv_make_money_saldo 722 722 0 0.00 1 11234 508 0 0
srv_recalc_idx_stat 4 4 0 0.00 196682 688203 370918 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4813 4813 0 0.00 0 11907 153 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 4224 83 0 0
t$perf-norm:sp_cancel_client_order 1647 1647 0 0.00 0 3329 18 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 877 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 758 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1518 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 2559 18 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 16579 1409 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 808 5 0 0
t$perf-norm:sp_client_order 19310 19310 0 0.00 0 8018 16 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 4024 14 0 0
t$perf-norm:sp_pay_from_customer 6630 6630 0 0.00 0 4266 8 0 0
t$perf-norm:sp_pay_to_supplier 5713 5713 0 0.00 0 8039 10 0 0
t$perf-norm:sp_reserve_write_off 9354 9354 0 0.00 0 9601 10 0 0
t$perf-norm:sp_supplier_invoice 5213 5213 0 0.00 0 6653 20 0 0
t$perf-norm:sp_supplier_order 5193 5193 0 0.00 0 3447 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 289 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2595 88 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 1424 13 0 0
t$perf-norm:srv_make_money_saldo 722 722 0 0.00 0 956 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 8 0 0
v_add_invoice_to_stock 9842 9608 234 2.37 0 6455 20 0 234
v_all_customers 35304 35304 0 0.00 0 14 1 0 0
v_all_suppliers 29348 29348 0 0.00 0 43 1 0 0
v_all_wares 38620 38620 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 614 6 0 0
v_cancel_client_order 3294 3294 0 0.00 0 3580 6 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 4361 12 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2710 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2541 6 0 0
v_cancel_supplier_invoice 840 822 18 2.14 0 5936 25 0 18
v_cancel_supplier_order 814 814 0 0.00 0 2542 7 0 0
v_cancel_write_off 1912 1912 0 0.00 0 4355 15 0 0
v_max_id_clo_ord 5187 5187 0 0.00 0 4216 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 2227 14 0 0
v_max_id_ord_sup 5204 5204 0 0.00 0 3283 5 0 0
v_max_non_paid_invoice 1430 1430 0 0.00 0 814 3 0 0
v_max_non_paid_realizn 3488 3488 0 0.00 0 2983 3 0 0
v_min_id_clo_ord 5187 5187 0 0.00 0 4910 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 2969 6 0 0
v_min_id_ord_sup 5204 5204 0 0.00 0 7421 9 0 0
v_min_non_paid_invoice 5713 5713 0 0.00 0 8626 12 0 0
v_min_non_paid_realizn 6630 6630 0 0.00 0 5600 12 0 0
v_our_firm 9600 9600 0 0.00 0 60 1 0 0
v_reserve_write_off 18708 18708 0 0.00 0 6553 7 0 0
x_lock_depdocs_on_canc_invoice 11186 11186 0 0.00 0 11926 102 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 7808 337 0 0
x_make_qstorno_1000_1200 5193 5193 0 0.00 2 25699 1338 0 0
x_make_qstorno_1200_2000 5213 5213 0 0.00 2 88196 3245 0 0
x_make_qstorno_2100_3300 447276 447276 0 0.00 0 62141 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-06-2021 10:27:49 to 09-06-2021 10:27:54.

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
07:57:04 8 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
07:58:05 29455 8192000000 9728324480 10025238528 810941408 9.007 100 92 184 0 840854048 7844016 22068624
07:59:42 24819 8192000000 9758021952 10065571840 831830448 9.218 100 90 180 1 866699776 13100720 21768608
08:01:09 13389 8192000000 9785058368 10101444608 856377088 9.464 100 78 156 0 892647536 18185520 18084928
08:02:25 16522 8192000000 9854323488 10185744384 907477728 9.972 100 75 150 1 961068672 37141840 16449104
08:03:43 7397 8192000000 9922506480 10267111424 959111504 10.480 100 81 161 3 1030784032 54956224 16716304
08:04:55 5856 8192000000 9978567472 10332995584 989919616 10.781 100 77 154 1 1085690048 80643312 15127120
08:06:04 3547 8192000000 9974236768 10343108608 1022927632 11.100 100 71 142 1 1097864896 60877936 14059328
08:07:09 3417 8192000000 10023435872 10390888448 1047943264 11.341 100 88 176 1 1144428960 78473872 18011824
08:08:14 1989 8192000000 10033555008 10401730560 1064301424 11.498 100 89 177 1 1135211312 52452192 18457696
08:09:17 1798 8192000000 10059448384 10431922176 1071533504 11.567 100 89 176 0 1169325296 80316464 17475328
08:10:20 5336 8192000000 10003994320 10401927168 1073748736 11.588 100 42 84 2 1123147600 41184992 8213872
08:11:26 7200 8192000000 9998691808 10396667904 1077830608 11.627 100 46 92 1 1109120304 20700176 10589520
08:12:34 4140 8192000000 9992424160 10384408576 1084679664 11.692 100 43 85 1 1108722208 14512928 9529616
08:13:39 4291 8192000000 10009854208 10404319232 1089255152 11.736 100 54 110 2 1125722352 24696944 11770256
08:14:46 53728 8192000000 10010186528 10418966528 1087561136 11.719 100 29 58 1 1108314704 14625568 6128000
08:16:40 5755 8192000000 10016040352 10416914432 1077261712 11.621 99 44 88 2 1114150080 27389696 9498672
08:17:46 6646 8192000000 9992974576 10399055872 1067761408 11.531 98 32 65 0 1091301408 16284384 7255616
08:18:57 5151 8192000000 9974285696 10361249792 1040144736 11.266 98 42 83 1 1073415216 24319488 8950992
08:20:05 47066 8192000000 9954380320 10351202304 952440912 10.415 91 35 71 2 980525904 20456304 7628688
08:21:56 5766 8192000000 9950396832 10341543936 1024023952 11.111 99 49 98 3 1044345632 8854656 11467024
08:23:02 13701 8192000000 9975837296 10359504896 1035375296 11.220 100 32 64 0 1057760336 14916640 7468400
08:24:21 13007 8192000000 9953320336 10365292544 1049778032 11.359 100 18 36 2 1075991504 21870032 4343440
08:25:35 29647 8192000000 9974110272 10379354112 1055865776 11.417 100 12 24 1 1072138864 13814208 2458880
08:27:05 15208 8192000000 9996517152 10387513344 1075328528 11.603 100 54 107 1 1105705696 19363216 11013952
08:28:25 16178 8192000000 10053718272 10449539072 1093852624 11.779 100 52 104 1 1155865888 51731280 10281984
08:29:45 12868 8192000000 10088094640 10487398400 1113148272 11.962 100 46 92 2 1187461664 64876096 9437296
08:31:02 20284 8192000000 10138007136 10543415296 1128248848 12.105 100 53 106 0 1237072880 97682976 11141056
08:32:25 49426 8192000000 10154277600 10588073984 1137298480 12.190 100 38 76 1 1254541888 108375120 8868288
08:34:23 8710 8192000000 10183502128 10608271360 1149605408 12.306 100 41 82 2 1304677920 145900640 9171872
08:35:35 6672 8192000000 10231880640 10645553152 1158008688 12.385 100 55 109 0 1338463728 168725504 11729536
08:36:49 4196 8192000000 10257352224 10671067136 1163775184 12.439 100 66 130 1 1367532208 189184000 14573024
08:37:56 8610 8192000000 10282429440 10706903040 1165835136 12.458 100 66 131 0 1400379568 220038288 14506144
08:39:07 4001 8192000000 10320393680 10746585088 1172028048 12.516 100 82 162 0 1433477392 243196496 18252848
08:40:18 1890 8192000000 10317855392 10734129152 1176647760 12.559 100 77 152 1 1424686768 230445760 17593248
08:41:25 3172 8192000000 10362628288 10775060480 1180809312 12.598 100 82 164 1 1457899616 258556032 18534272
08:42:35 2322 8192000000 10382639888 10796404736 1186124560 12.647 100 80 160 3 1478980944 274323456 18532928
08:43:43 1395 8192000000 10394566992 10810167296 1177178368 12.564 100 76 151 0 1489802576 294343328 18280880
08:44:49 2077 8192000000 10429244048 10848747520 1174455808 12.538 100 83 167 1 1521870912 326164000 21251104
08:45:54 5802 8192000000 10363658080 10778132480 1144562208 12.258 99 46 90 3 1462720112 307260880 10897024
08:47:04 17099 8192000000 10350697552 10814136320 1156508848 12.371 100 47 94 0 1456903056 288474096 11920112
08:48:23 25660 8192000000 10372008480 10831044608 1125490864 12.079 98 34 68 0 1450389008 315414176 9483968
08:50:16 19303 8192000000 10288805664 10785140736 1122579776 12.051 99 19 38 0 1388253200 260679696 4993728
08:51:44 57089 8192000000 10085913760 10599870464 1079198960 11.640 96 10 20 0 1249086256 167278400 2608896
08:53:42 139938 8192000000 10051238032 10486358016 1045726080 11.320 93 32 64 2 1097439824 45048560 6665184
08:57:08 33602 8192000000 10038340432 10507177984 1091472672 11.757 98 13 26 0 1136560272 42655792 2431808
08:58:42 43920 8192000000 10060112720 10489630720 1078911888 11.637 97 16 31 1 1144079600 61712608 3455104
09:00:27 20578 8192000000 10090416688 10539495424 1120503296 12.032 100 17 34 1 1201903408 77650096 3750016
09:01:50 114299 8192000000 10093029296 10574417920 1091018080 11.752 98 17 34 1 1201938576 106977680 3942816
09:05:44 59599 8192000000 10042080368 10563137536 1103130816 11.867 99 17 35 0 1206776208 99776656 3868736
09:07:46 47805 8192000000 10071136240 10514243584 1106835888 11.902 99 11 22 0 1144034768 34623424 2575456
09:09:45 49486 8192000000 10096915072 10514472960 1119097280 12.018 99 49 98 1 1169770608 40367344 10305984
09:11:37 58788 8192000000 10078432640 10592714752 1104530640 11.881 99 29 57 0 1255406816 144869280 6006896
09:13:41 107159 8192000000 10089546688 10543022080 1096555328 11.805 97 15 30 1 1155629776 55746368 3328080
09:16:29 112487 8192000000 10091594064 10605359104 1141084256 12.226 100 7 14 0 1204402384 61950928 1367200
09:19:31 59701 8192000000 10080238528 10533322752 1149788272 12.308 100 21 37 0 1205513088 52131232 3593584
09:22:47 74102 8192000000 10102523616 10564169728 1143501648 12.248 99 13 26 0 1192549008 46460208 2587152
09:25:17 72083 8192000000 10128360784 10623168512 1156890784 12.374 100 17 34 0 1239538784 78699728 3948272
09:27:31 59890 8192000000 10139251264 10636193792 1167881792 12.477 100 14 28 2 1256736704 85946784 2908128
09:29:34 82201 8192000000 10101063888 10550747136 1123814848 12.063 96 15 30 0 1158657840 31904720 2938272
09:31:57 54249 8192000000 10079434624 10534072320 1123931456 12.064 97 26 52 0 1166022800 36093552 5997792
09:34:00 33846 8192000000 10109849248 10538676224 1139089600 12.207 99 26 52 1 1190704944 45829648 5785696
09:35:34 63864 8192000000 10081295712 10523734016 1109759872 11.930 97 16 32 2 1180684608 67703280 3221456
09:37:49 50541 8192000000 10071927584 10536337408 1140676176 12.222 100 20 39 1 1178058176 33295728 4086272
09:39:39 85676 8192000000 10099839296 10566086656 1150901792 12.318 100 7 14 1 1193170544 40438560 1830192
09:42:14 84858 8192000000 10084685648 10554781696 1115154928 11.981 97 20 40 0 1201816976 82177056 4484992
09:44:39 103947 8192000000 10142169264 10604601344 1132794608 12.148 99 23 46 0 1244277952 106497808 4985536
09:47:24 62833 8192000000 10089674048 10525126656 1102729296 11.864 97 30 60 1 1173278944 64053152 6496496
09:49:32 74611 8192000000 10128702384 10588712960 1094513744 11.786 96 17 34 0 1179934368 81599760 3820864
09:51:53 48754 8192000000 10074878704 10542211072 1102284320 11.859 99 16 32 0 1167721552 62086448 3350784
09:53:43 156046 8192000000 10060680912 10521903104 1088496128 11.728 97 21 42 0 1131167712 38316176 4355408
09:57:47 50256 8192000000 10032471728 10518171648 1053634896 11.396 94 6 12 0 1092211776 37019424 1557456
09:59:38 115039 8192000000 10050076832 10519814144 1091368208 11.756 97 15 30 0 1130970048 37244368 2357472
10:02:39 45010 8192000000 10106806144 10549288960 1098357216 11.822 97 31 59 1 1176727872 72376992 5993664
10:04:28 40013 8192000000 10121419568 10555875328 1114090224 11.971 98 28 56 3 1184127808 63627296 6410288
10:06:20 31913 8192000000 10129585344 10620276736 1115374880 11.983 99 33 66 1 1244977040 122454192 7147968
10:07:59 12785 8192000000 10104424400 10534273024 1136059440 12.178 100 49 99 1 1176970816 30035040 10876336
10:09:18 35162 8192000000 10088615216 10539646976 1134350816 12.162 100 8 16 0 1175465808 39707152 1407840
10:10:57 43184 8192000000 10121782608 10571456512 1142009392 12.234 100 28 55 1 1212340720 64864000 5467328
10:12:42 140780 8192000000 10066512240 10544099328 1145599584 12.268 100 14 28 0 1192465040 44066208 2799248
10:16:03 82135 8192000000 10081801760 10538340352 1136852688 12.186 100 17 34 1 1178833664 38176848 3804128
10:18:41 98306 8192000000 10093756832 10546663424 1127714416 12.100 99 17 34 1 1180589584 48966960 3908208
10:21:20 33862 8192000000 10119810048 10569355264 1138364576 12.200 99 34 67 0 1183571920 38607088 6600256
10:23:01 68572 8192000000 10146857920 10598404096 1148179472 12.292 99 39 78 0 1229940640 74036112 7725056
10:25:10 52532 8192000000 10072908864 10510434304 1072545408 11.576 93 16 32 1 1137181328 61641568 2994352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-06-2021 10:27:55 to 09-06-2021 10:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-06-2021 10:28:11 to 09-06-2021 10:28:13.

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          226266
MON$OLDEST_ACTIVE               226801
MON$OLDEST_SNAPSHOT             226801
MON$NEXT_TRANSACTION            226801
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                       2065184
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 Firebird 4.0/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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jun  9 10:27:07 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Jun  9 10:27:09 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

09.06.21 10:28:14. 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: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 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
2021-06-09 10:28:16 No severe PSQL-related problems occured
09.06.21 10:28:17. End of report.