Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2021 10:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16403908 kB
MemAvailable 31460500 kB
Buffers 0 kB
Cached 15305252 kB
SwapCached 0 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-12-19 10:37:09
DTS_END                         2021-12-19 10:37:09.8670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52864
TRN_ID                          235781
ATT_ID                          635
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: 9196.20 18773 1103544
customer order: creation 176.43 355 21171
customer order: refuse 15.30 363 1836
order to supplier: creation 46.87 1371 5624
order to supplier: removal 3.34 118641 401
invoice (draft): creation 48.22 3424 5786
invoice (draft): removal 52.99 941 6359
invoice accept: apply 42.11 18400 5053
invoice accept: cancel 47.34 859 5681
customer reserve: creation 3850.33 184 462040
customer reserve: removal 4616.78 66 554014
realization accept: apply 86.23 189 10347
realization accept: cancel 78.41 126 9409
payment from customer: creation 60.50 132 7260
payment from customer: removal 5.76 76 691
payment to supplier: creation 51.01 127 6121
payment to supplier: removal 3.66 72 439
service: total inventory turnovers 3.92 3944 470
service: total monetary turnovers 6.17 561 740
service: aggregate perf. data 0.82 16738 98
service: refresh index statistics 0.03 208881 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2021 10:38:43 to 19-12-2021 10:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4794
TEST_TIME 2 5444
TEST_TIME 3 4800
TEST_TIME 4 4601
TEST_TIME 5 4631
TEST_TIME 6 4396
TEST_TIME 7 4634
TEST_TIME 8 4945
TEST_TIME 9 5143
TEST_TIME 10 5586
TEST_TIME 11 5875
TEST_TIME 12 5880
TEST_TIME 13 6539
TEST_TIME 14 6680
TEST_TIME 15 6792
TEST_TIME 16 7031
TEST_TIME 17 7369
TEST_TIME 18 7604
TEST_TIME 19 7762
TEST_TIME 20 7846
TEST_TIME 21 7707
TEST_TIME 22 7953
TEST_TIME 23 8212
TEST_TIME 24 8563
TEST_TIME 25 8917
TEST_TIME 26 9045
TEST_TIME 27 9686
TEST_TIME 28 10036
TEST_TIME 29 10410
TEST_TIME 30 10601
TEST_TIME 31 10510
TEST_TIME 32 10383
TEST_TIME 33 10394
TEST_TIME 34 10250
TEST_TIME 35 10191
TEST_TIME 36 10219
TEST_TIME 37 10217
TEST_TIME 38 10294
TEST_TIME 39 10278
TEST_TIME 40 10246
TEST_TIME 41 10133
TEST_TIME 42 10387
TEST_TIME 43 10168
TEST_TIME 44 10011
TEST_TIME 45 10085
TEST_TIME 46 9945
TEST_TIME 47 9877
TEST_TIME 48 9790
TEST_TIME 49 9709
TEST_TIME 50 9747
TEST_TIME 51 9939
TEST_TIME 52 9902
TEST_TIME 53 9880
TEST_TIME 54 9786
TEST_TIME 55 9704
TEST_TIME 56 9603
TEST_TIME 57 9608
TEST_TIME 58 9551
TEST_TIME 59 9557
TEST_TIME 60 9507
TEST_TIME 61 9512
TEST_TIME 62 9551
TEST_TIME 63 9528
TEST_TIME 64 9556
TEST_TIME 65 9595
TEST_TIME 66 9601
TEST_TIME 67 9623
TEST_TIME 68 9627
TEST_TIME 69 9682
TEST_TIME 70 9659
TEST_TIME 71 9679
TEST_TIME 72 9683
TEST_TIME 73 9743
TEST_TIME 74 9741
TEST_TIME 75 9712
TEST_TIME 76 9629
TEST_TIME 77 9596
TEST_TIME 78 9533
TEST_TIME 79 9536
TEST_TIME 80 9495
TEST_TIME 81 9461
TEST_TIME 82 9387
TEST_TIME 83 9392
TEST_TIME 84 9399
TEST_TIME 85 9361
TEST_TIME 86 9433
TEST_TIME 87 9429
TEST_TIME 88 9397
TEST_TIME 89 9439
TEST_TIME 90 9445
TEST_TIME 91 9516
TEST_TIME 92 9564
TEST_TIME 93 9590
TEST_TIME 94 9653
TEST_TIME 95 9623
TEST_TIME 96 9644
TEST_TIME 97 9549
TEST_TIME 98 9497
TEST_TIME 99 9477
TEST_TIME 100 9421
TEST_TIME 101 9372
TEST_TIME 102 9315
TEST_TIME 103 9373
TEST_TIME 104 9349
TEST_TIME 105 9296
TEST_TIME 106 9251
TEST_TIME 107 9280
TEST_TIME 108 9258
TEST_TIME 109 9233
TEST_TIME 110 9252
TEST_TIME 111 9237
TEST_TIME 112 9276
TEST_TIME 113 9257
TEST_TIME 114 9281
TEST_TIME 115 9282
TEST_TIME 116 9265
TEST_TIME 117 9314
TEST_TIME 118 9337
TEST_TIME 119 9277
TEST_TIME 120 9202

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-12-2021 10:38:46 to 19-12-2021 10:38:50.

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 1531014 1531014 0 0.00 0 18308 8 0 0
doc_list_biud 1531014 1531014 0 0.00 0 58315 31 0 0
sp_add_invoice_to_stock 5173 5053 120 2.31 43 200085 18401 0 120
sp_cancel_adding_invoice 5681 5681 0 0.00 5 84125 860 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 8569 363 0 0
sp_cancel_customer_reserve 554014 554014 0 0.00 1 58316 67 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 4667 77 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 1512 72 0 0
sp_cancel_supplier_invoice 6374 6359 15 0.23 2 23766 941 0 15
sp_cancel_supplier_order 401 401 0 0.00 13 1190678 118641 0 0
sp_cancel_write_off 9409 9409 0 0.00 1 16391 126 0 0
sp_client_order 21171 21171 0 0.00 4 19418 355 0 0
sp_customer_reserve 462040 462040 0 0.00 0 55406 185 0 0
sp_fill_shopping_cart 32561 32561 0 0.00 1 23638 42 0 0
sp_fill_shopping_cart_clo_res 462035 462035 0 0.00 0 20479 15 0 0
sp_get_clo_for_invoice 5048 5048 0 0.00 0 20763 295 0 0
sp_kill_cost_storno 16220 16220 0 0.00 0 11926 64 0 0
sp_kill_qstorno_ret_qs2qd 1124197 1124197 0 0.00 0 58310 36 0 0
sp_kill_qty_storno 593095 593095 0 0.00 0 58314 79 0 0
sp_lock_selected_doc 21382 21382 0 0.00 0 10062 5 0 0
sp_make_cost_storno 28776 28776 0 0.00 0 17730 61 0 0
sp_multiply_rows_for_pdistr 28776 28776 0 0.00 0 10587 34 0 0
sp_multiply_rows_for_qdistr 21171 21171 0 0.00 0 13145 165 0 0
sp_pay_from_customer 7260 7260 0 0.00 4 15920 132 0 0
sp_pay_to_supplier 6121 6121 0 0.00 5 15908 128 0 0
sp_payment_common 13381 13381 0 0.00 1 12109 25 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 8384 335 0 0
sp_qd_handle_on_invoice_upd_sts 10729 10729 0 0.00 0 27628 374 0 0
sp_qd_handle_on_reserve_upd_sts 10347 10347 0 0.00 0 20298 59 0 0
sp_reserve_write_off 10347 10347 0 0.00 7 20313 190 0 0
sp_supplier_invoice 5786 5786 0 0.00 7 89248 3424 0 0
sp_supplier_order 5624 5624 0 0.00 6 26660 1371 0 0
srv_aggregate_perf_data 98 98 0 0.00 2399 157654 16739 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1023 116443 43667 0 0
srv_find_qd_qs_mism 1054809 1054809 0 0.00 0 18307 7 0 0
srv_make_invnt_saldo 470 470 0 0.00 60 89416 3944 0 0
srv_make_money_saldo 740 740 0 0.00 1 15195 561 0 0
srv_recalc_idx_stat 4 4 0 0.00 144109 319323 208882 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5054 5054 0 0.00 0 8818 160 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 14275 174 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 2151 13 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 1713 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 3408 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1746 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 4421 22 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 19489 1951 0 0
t$perf-norm:sp_cancel_write_off 1086 1086 0 0.00 0 3999 13 0 0
t$perf-norm:sp_client_order 21172 21172 0 0.00 0 8904 16 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 2551 6 0 0
t$perf-norm:sp_pay_from_customer 7260 7260 0 0.00 0 6867 11 0 0
t$perf-norm:sp_pay_to_supplier 6121 6121 0 0.00 0 8524 10 0 0
t$perf-norm:sp_reserve_write_off 10347 10347 0 0.00 0 4324 11 0 0
t$perf-norm:sp_supplier_invoice 5786 5786 0 0.00 0 5125 17 0 0
t$perf-norm:sp_supplier_order 5624 5624 0 0.00 0 5771 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 593 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 803 14 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 5842 27 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 4330 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10336 10096 240 2.32 0 6813 17 0 240
v_all_customers 38426 38426 0 0.00 0 88 1 0 0
v_all_suppliers 31806 31806 0 0.00 0 83 1 0 0
v_all_wares 42342 42342 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1137 7 0 0
v_cancel_client_order 3672 3672 0 0.00 0 8556 7 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4537 8 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 3929 10 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 313 4 0 0
v_cancel_supplier_invoice 918 888 30 3.26 0 1457 17 0 30
v_cancel_supplier_order 802 802 0 0.00 0 1474 5 0 0
v_cancel_write_off 2172 2172 0 0.00 0 557 10 0 0
v_max_id_clo_ord 5617 5617 0 0.00 0 1851 2 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 2249 11 0 0
v_max_id_ord_sup 5772 5772 0 0.00 0 7006 4 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 3735 5 0 0
v_max_non_paid_realizn 3875 3875 0 0.00 0 1285 4 0 0
v_min_id_clo_ord 5617 5617 0 0.00 0 4296 9 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 2255 5 0 0
v_min_id_ord_sup 5772 5772 0 0.00 0 6414 12 0 0
v_min_non_paid_invoice 6121 6121 0 0.00 0 8069 15 0 0
v_min_non_paid_realizn 7260 7260 0 0.00 0 8716 18 0 0
v_our_firm 10686 10686 0 0.00 0 16 1 0 0
v_reserve_write_off 20692 20692 0 0.00 0 6816 6 0 0
x_lock_depdocs_on_canc_invoice 11596 11596 0 0.00 0 23317 108 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 4642 326 0 0
x_make_qstorno_1000_1200 5624 5624 0 0.00 2 26603 1290 0 0
x_make_qstorno_1200_2000 5786 5786 0 0.00 4 89226 3271 0 0
x_make_qstorno_2100_3300 462040 462040 0 0.00 0 55404 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2021 10:38:51 to 19-12-2021 10:38:55.

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
08:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 23543 8192000000 9720598432 10020962304 804833024 8.945 100 86 172 0 832863104 7179728 20850352
08:09:43 18905 8192000000 9763285568 10068828160 836373216 9.263 100 94 187 1 871713424 12553648 22786560
08:11:07 14554 8192000000 9792483104 10109931520 861218816 9.512 100 79 158 0 900013504 20166080 18628608
08:12:29 12674 8192000000 9857651840 10190508032 908686752 9.984 100 79 158 0 964020928 38172592 17161584
08:13:44 10351 8192000000 9908460672 10250072064 953218704 10.423 100 79 155 1 1019497968 50367120 15912144
08:14:57 6693 8192000000 9966396112 10319863808 988805568 10.770 100 81 162 0 1083335968 78157680 16372720
08:16:08 3423 8192000000 10002592720 10363478016 1020194560 11.074 100 84 169 2 1119094656 82257072 16643024
08:17:14 7353 8192000000 10009753152 10380099584 1039670768 11.262 100 66 132 0 1125728704 72765872 13292064
08:18:23 2296 8192000000 10050466640 10421338112 1057653216 11.434 100 93 184 1 1154751648 78535520 18562912
08:19:27 22151 8192000000 10025684544 10406346752 1070663296 11.558 100 75 149 0 1166372448 80039472 15669680
08:20:50 7430 8192000000 10035115680 10436476928 1082552784 11.672 100 52 103 2 1153992768 61118000 10321984
08:21:59 5036 8192000000 10001871040 10391781376 1091383648 11.756 100 59 120 0 1115874192 11352048 13138496
08:23:06 8058 8192000000 10013156592 10415681536 1101377696 11.851 100 30 60 2 1144384064 36690960 6315408
08:24:18 17958 8192000000 10026124016 10419613696 1102595440 11.862 100 72 142 1 1132204112 14139840 15468832
08:25:36 7130 8192000000 10038470096 10447716352 1104222432 11.878 100 46 92 2 1144340080 29919232 10198416
08:26:45 16921 8192000000 10030257936 10448474112 1106409008 11.898 100 25 49 0 1152838192 41434128 4995056
08:28:03 3570 8192000000 10041781952 10439659520 1083219904 11.678 98 59 118 2 1137827488 41950192 12657392
08:29:08 6429 8192000000 9980934384 10374213632 1031749664 11.185 97 42 84 2 1067379920 26367376 9262880
08:30:17 65073 8192000000 9950968016 10347421696 973040768 10.616 92 22 44 0 998674896 21375152 4258976
08:32:22 5827 8192000000 9949052048 10323722240 997217968 10.852 97 25 50 0 1043023296 40336480 5468848
08:33:29 13000 8192000000 9952015760 10349461504 1024460464 11.115 98 41 82 0 1057116832 22728816 9927552
08:34:42 10630 8192000000 9973047888 10367688704 1041148112 11.276 99 25 50 3 1067768560 21011984 5608464
08:35:53 32136 8192000000 9984133408 10391896064 1060621088 11.462 100 31 62 1 1109314384 42422880 6270416
08:37:31 25969 8192000000 10010349312 10402246656 1086729664 11.712 100 40 80 0 1111682736 15785792 9167280
08:39:13 9300 8192000000 10049566896 10451910656 1110345792 11.936 100 42 84 1 1163339168 43802656 9190720
08:40:25 9963 8192000000 10093840272 10495856640 1123462112 12.060 100 62 124 1 1204293968 67565456 13266400
08:41:41 38328 8192000000 10112825824 10536992768 1131982704 12.140 100 37 74 1 1225243584 84680544 8580336
08:43:39 27720 8192000000 10156779312 10561814528 1150278880 12.312 100 46 92 1 1249889968 89192528 10418560
08:45:17 14225 8192000000 10197427072 10621386752 1163730992 12.438 100 56 110 0 1304840224 128509936 12599296
08:46:40 5748 8192000000 10231208576 10641817600 1166594960 12.465 100 64 127 1 1334765600 154540112 13630528
08:47:50 8615 8192000000 10224415824 10650660864 1171444560 12.510 100 53 106 1 1334904160 151527072 11932528
08:49:12 6133 8192000000 10251203600 10664144896 1179256192 12.583 100 64 126 1 1351814480 157821808 14736480
08:50:25 2624 8192000000 10308156992 10736033792 1189504560 12.679 100 72 143 2 1409704912 203562416 16637936
08:51:33 11388 8192000000 10289257984 10711093248 1197196240 12.750 100 59 118 0 1392592464 182482384 12913840
08:52:54 8483 8192000000 10331031744 10747990016 1192713728 12.709 100 68 136 1 1428700192 220213984 15772480
08:54:10 3471 8192000000 10330661664 10744451072 1192929072 12.711 100 73 146 0 1427922368 218684416 16308880
08:55:19 5741 8192000000 10355438256 10769960960 1174399952 12.538 100 65 130 0 1449700192 260143952 15156288
08:56:26 6674 8192000000 10316563904 10747727872 1145306976 12.265 97 66 132 0 1399651680 238568496 15776208
08:57:40 4112 8192000000 10426358208 10833731584 1171033904 12.506 100 67 134 0 1517060912 330104256 15922752
08:58:48 6778 8192000000 10452929088 10867478528 1159397936 12.398 100 54 108 1 1546502864 372964032 14140896
09:00:03 1018 8192000000 10453313584 10868019200 1152048480 12.329 99 84 168 0 1542897968 369197680 21651808
09:01:11 14394 8192000000 10435318528 10890731520 1165473504 12.455 100 37 74 2 1542903024 367502960 9926560
09:02:28 14822 8192000000 10426134144 10914603008 1151337712 12.322 99 41 82 1 1553053792 391216144 10499936
09:04:35 19179 8192000000 10294364960 10811158528 1145560032 12.268 100 18 36 0 1430331136 279689104 5082000
09:06:30 49782 8192000000 10064014064 10559234048 1105075872 11.886 99 4 8 1 1197062640 90782656 1204112
09:08:20 73764 8192000000 10059150800 10491727872 1042402496 11.288 93 22 45 1 1088347952 40550112 5395344
09:10:51 42430 8192000000 10039871936 10461069312 1111354256 11.945 100 21 42 1 1133163056 17711216 4097584
09:12:36 66201 8192000000 10074662720 10536554496 1092442880 11.766 97 26 52 3 1160391744 62571216 5377648
09:14:52 116442 8192000000 10064622272 10534965248 1099411040 11.832 97 12 24 1 1140725536 38869920 2444576
09:17:49 56301 8192000000 10133939488 10577735680 1127697344 12.100 100 15 30 1 1180813632 50082672 3033616
09:19:53 105552 8192000000 10093463616 10528624640 1100247936 11.840 97 30 60 2 1148654880 41724304 6682640
09:22:41 77374 8192000000 10095953280 10538299392 1129675872 12.118 99 31 62 1 1187202992 50621520 6905600
09:25:15 29018 8192000000 10171751328 10602127360 1143486880 12.248 100 26 52 0 1248508096 99334400 5686816
09:26:49 31806 8192000000 10204577152 10653433856 1145033392 12.263 100 39 73 1 1304785936 151364480 8388064
09:28:22 35729 8192000000 10098235008 10637025280 1130494128 12.126 99 12 24 0 1186737600 53777600 2465872
09:29:57 26526 8192000000 10095837840 10574295040 1133928064 12.158 99 26 52 1 1179471520 39928672 5614784
09:31:25 29621 8192000000 10070643936 10539663360 1120305072 12.030 98 11 22 2 1170232496 47312592 2614832
09:33:02 92560 8192000000 10118683040 10584174592 1124412512 12.069 98 20 40 0 1193174336 63488672 5273152
09:35:35 71382 8192000000 10079097968 10584907776 1143370640 12.247 100 5 10 1 1207931168 63410896 1149632
09:37:55 43866 8192000000 10091806896 10569990144 1128129152 12.104 98 10 20 0 1180379808 50314368 1936288
09:39:39 22670 8192000000 10066594448 10506510336 1136197984 12.180 99 18 37 1 1158459472 18458080 3803408
09:41:02 27488 8192000000 10117099232 10566729728 1138541744 12.202 100 26 52 1 1227649856 83342528 5765584
09:42:39 17898 8192000000 10079088832 10512887808 1116707120 11.996 99 41 82 0 1161712368 36681568 8323680
09:43:59 50904 8192000000 10052957808 10491199488 1071373680 11.565 95 18 36 0 1121325680 46520576 3431424
09:45:56 99799 8192000000 10057795760 10509398016 1082887024 11.675 96 20 40 1 1128491056 41852368 3751664
09:48:37 39201 8192000000 10093114144 10532454400 1090948336 11.752 97 18 36 0 1151287632 56655152 3684144
09:50:17 53193 8192000000 10116154688 10580090880 1122206032 12.048 100 24 48 1 1211956880 84863808 4887040
09:52:11 102015 8192000000 10052402032 10488397824 1096316176 11.803 98 26 52 0 1124459520 22263568 5879776
09:54:56 38736 8192000000 10034206336 10460295168 1087831792 11.722 98 32 64 1 1127556464 32696960 7027712
09:56:35 39647 8192000000 10050376240 10480275456 1080107696 11.648 97 29 58 2 1133791584 47657232 6026656
09:58:16 39806 8192000000 10049029872 10505527296 1099589680 11.834 99 14 28 1 1152030080 49175744 3264656
09:59:59 55788 8192000000 10098471072 10586828800 1114034944 11.971 100 12 24 2 1191585216 74615472 2934800
10:02:10 34754 8192000000 10116312352 10552578048 1123539552 12.060 100 29 58 0 1200413232 70459792 6413888
10:03:47 28213 8192000000 10092291472 10522927104 1108698128 11.920 99 19 38 0 1152709184 40079616 3931440
10:05:17 70021 8192000000 10131430992 10583498752 1102453968 11.861 98 30 60 0 1191229088 82088864 6686256
10:07:28 73717 8192000000 10044522864 10471882752 1044035088 11.303 93 13 26 1 1122409744 75530896 2843760
10:09:43 94593 8192000000 10094483696 10648842240 1118321584 12.011 98 10 20 0 1209487712 89566784 1599344
10:13:13 90668 8192000000 10089048448 10584436736 1127064448 12.094 99 13 26 0 1191361248 61758000 2538800
10:16:34 49050 8192000000 10065315696 10546802688 1137511936 12.192 100 10 18 0 1181333904 42266480 1555488
10:18:30 113280 8192000000 10135348224 10611118080 1130709408 12.128 98 16 32 0 1201841376 67391344 3740624
10:21:23 59181 8192000000 10070138224 10568724480 1116356480 11.993 98 7 14 0 1151871792 34038944 1476368
10:23:24 62996 8192000000 10097049136 10583941120 1135210160 12.170 99 9 18 0 1187728192 50585040 1932992
10:25:30 33450 8192000000 10071633760 10519175168 1123174112 12.057 98 25 50 1 1152833248 24628448 5030688
10:27:04 70331 8192000000 10100999600 10539433984 1121767136 12.044 98 28 56 0 1161624288 34011776 5845376
10:29:19 79218 8192000000 10047196512 10486829056 1041017168 11.274 91 11 22 0 1073764624 30713696 2033760
10:31:38 70539 8192000000 10039881696 10489192448 1074126544 11.591 96 19 38 1 1130211568 52433504 3651520
10:33:49 28304 8192000000 10110618224 10540122112 1104337456 11.879 99 43 86 0 1171141632 58129488 8674688
10:35:18 99802 8192000000 10061987952 10547134464 1090026320 11.743 98 17 34 0 1146361376 52490544 3844512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2021 10:38:57 to 19-12-2021 10:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2021 10:39:13 to 19-12-2021 10:39:15.

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          235059
MON$OLDEST_ACTIVE               236028
MON$OLDEST_SNAPSHOT             236028
MON$NEXT_TRANSACTION            236028
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                       2063904
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2692 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2692 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2692 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2692 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Sun Dec 19 10:37:20 2021
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Sun Dec 19 10:37:23 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Sun Dec 19 10:37:23 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sun Dec 19 10:37:24 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
--- end of diff output ---

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

19.12.21 10:39:16. 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-12-19 10:39:18 No severe PSQL-related problems occured
19.12.21 10:39:19. End of report.