Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2022 04: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 16250820 kB
MemAvailable 31613048 kB
Buffers 20 kB
Cached 15426736 kB
SwapCached 2184 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.2
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 2022-03-29 04:37:14
DTS_END                         2022-03-29 04:37:14.5900
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/54178
TRN_ID                          212058
ATT_ID                          637
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: 8386.39 23871 1006367
customer order: creation 161.37 459 19364
customer order: refuse 13.47 466 1616
order to supplier: creation 42.59 1454 5111
order to supplier: removal 3.17 180669 380
invoice (draft): creation 43.43 3691 5212
invoice (draft): removal 45.98 1130 5517
invoice accept: apply 39.34 27943 4721
invoice accept: cancel 41.53 1486 4983
customer reserve: creation 3622.16 263 434659
customer reserve: removal 4090.18 109 490821
realization accept: apply 78.53 306 9424
realization accept: cancel 81.92 174 9830
payment from customer: creation 55.55 192 6666
payment from customer: removal 5.62 130 674
payment to supplier: creation 46.31 227 5557
payment to supplier: removal 3.44 169 413
service: total inventory turnovers 3.35 5035 402
service: total monetary turnovers 7.56 422 907
service: aggregate perf. data 0.88 16400 105
service: refresh index statistics 0.04 236703 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-03-2022 04:38:52 to 29-03-2022 04:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6662
TEST_TIME 2 5930
TEST_TIME 3 5275
TEST_TIME 4 4765
TEST_TIME 5 4872
TEST_TIME 6 5468
TEST_TIME 7 5660
TEST_TIME 8 5929
TEST_TIME 9 6064
TEST_TIME 10 6149
TEST_TIME 11 6189
TEST_TIME 12 6376
TEST_TIME 13 6546
TEST_TIME 14 6578
TEST_TIME 15 6589
TEST_TIME 16 6843
TEST_TIME 17 7004
TEST_TIME 18 7151
TEST_TIME 19 7380
TEST_TIME 20 7760
TEST_TIME 21 7879
TEST_TIME 22 7996
TEST_TIME 23 7930
TEST_TIME 24 8146
TEST_TIME 25 8127
TEST_TIME 26 8169
TEST_TIME 27 8514
TEST_TIME 28 8767
TEST_TIME 29 8871
TEST_TIME 30 8959
TEST_TIME 31 9057
TEST_TIME 32 9230
TEST_TIME 33 9380
TEST_TIME 34 9787
TEST_TIME 35 9940
TEST_TIME 36 10082
TEST_TIME 37 10384
TEST_TIME 38 10300
TEST_TIME 39 10393
TEST_TIME 40 10380
TEST_TIME 41 10177
TEST_TIME 42 10277
TEST_TIME 43 10215
TEST_TIME 44 10108
TEST_TIME 45 9948
TEST_TIME 46 9907
TEST_TIME 47 9754
TEST_TIME 48 9622
TEST_TIME 49 9538
TEST_TIME 50 9559
TEST_TIME 51 9405
TEST_TIME 52 9352
TEST_TIME 53 9217
TEST_TIME 54 9187
TEST_TIME 55 9180
TEST_TIME 56 9124
TEST_TIME 57 9096
TEST_TIME 58 9021
TEST_TIME 59 8915
TEST_TIME 60 8865
TEST_TIME 61 8800
TEST_TIME 62 8783
TEST_TIME 63 8774
TEST_TIME 64 8683
TEST_TIME 65 8582
TEST_TIME 66 8475
TEST_TIME 67 8702
TEST_TIME 68 8753
TEST_TIME 69 8719
TEST_TIME 70 8744
TEST_TIME 71 8772
TEST_TIME 72 8773
TEST_TIME 73 8715
TEST_TIME 74 8726
TEST_TIME 75 8786
TEST_TIME 76 8737
TEST_TIME 77 8724
TEST_TIME 78 8775
TEST_TIME 79 8768
TEST_TIME 80 8834
TEST_TIME 81 8828
TEST_TIME 82 8747
TEST_TIME 83 8741
TEST_TIME 84 8663
TEST_TIME 85 8618
TEST_TIME 86 8615
TEST_TIME 87 8616
TEST_TIME 88 8569
TEST_TIME 89 8520
TEST_TIME 90 8447
TEST_TIME 91 8441
TEST_TIME 92 8524
TEST_TIME 93 8525
TEST_TIME 94 8502
TEST_TIME 95 8484
TEST_TIME 96 8491
TEST_TIME 97 8472
TEST_TIME 98 8448
TEST_TIME 99 8446
TEST_TIME 100 8575
TEST_TIME 101 8546
TEST_TIME 102 8571
TEST_TIME 103 8704
TEST_TIME 104 8757
TEST_TIME 105 8734
TEST_TIME 106 8725
TEST_TIME 107 8716
TEST_TIME 108 8664
TEST_TIME 109 8666
TEST_TIME 110 8660
TEST_TIME 111 8612
TEST_TIME 112 8625
TEST_TIME 113 8571
TEST_TIME 114 8554
TEST_TIME 115 8505
TEST_TIME 116 8512
TEST_TIME 117 8516
TEST_TIME 118 8512
TEST_TIME 119 8482
TEST_TIME 120 8419

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-03-2022 04:38:56 to 29-03-2022 04:38:59.

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 1407501 1407501 0 0.00 0 17958 14 0 0
doc_list_biud 1407486 1407486 0 0.00 0 50093 45 0 0
sp_add_invoice_to_stock 4833 4721 112 2.31 54 196526 27943 0 112
sp_cancel_adding_invoice 4983 4983 0 0.00 6 103866 1487 0 0
sp_cancel_client_order 1616 1616 0 0.00 6 8348 466 0 0
sp_cancel_customer_reserve 490821 490821 0 0.00 1 26952 109 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 4 2587 131 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 8541 170 0 0
sp_cancel_supplier_invoice 5523 5517 6 0.10 3 24234 1131 0 6
sp_cancel_supplier_order 380 380 0 0.00 9 1516546 180669 0 0
sp_cancel_write_off 9830 9830 0 0.00 2 10257 175 0 0
sp_client_order 19364 19364 0 0.00 5 25126 460 0 0
sp_customer_reserve 434659 434659 0 0.00 1 109123 264 0 0
sp_fill_shopping_cart 29672 29672 0 0.00 1 16145 91 0 0
sp_fill_shopping_cart_clo_res 434624 434624 0 0.00 0 17138 20 0 0
sp_get_clo_for_invoice 4673 4673 0 0.00 0 37502 330 0 0
sp_kill_cost_storno 15900 15900 0 0.00 0 12287 85 0 0
sp_kill_qstorno_ret_qs2qd 997369 997369 0 0.00 0 26901 48 0 0
sp_kill_qty_storno 527240 527240 0 0.00 0 50090 111 0 0
sp_lock_selected_doc 19624 19624 0 0.00 0 8739 9 0 0
sp_make_cost_storno 26319 26319 0 0.00 0 17385 91 0 0
sp_multiply_rows_for_pdistr 26316 26316 0 0.00 0 9043 45 0 0
sp_multiply_rows_for_qdistr 19364 19364 0 0.00 0 10553 157 0 0
sp_pay_from_customer 6666 6666 0 0.00 5 11334 192 0 0
sp_pay_to_supplier 5557 5557 0 0.00 7 17471 228 0 0
sp_payment_common 12222 12222 0 0.00 2 10479 52 0 0
sp_qd_handle_on_cancel_clo 1616 1616 0 0.00 0 6655 368 0 0
sp_qd_handle_on_invoice_upd_sts 9654 9654 0 0.00 0 50086 614 0 0
sp_qd_handle_on_reserve_upd_sts 9422 9422 0 0.00 0 15186 72 0 0
sp_reserve_write_off 9424 9424 0 0.00 8 18590 306 0 0
sp_supplier_invoice 5212 5212 0 0.00 11 90504 3692 0 0
sp_supplier_order 5111 5111 0 0.00 8 49209 1455 0 0
srv_aggregate_perf_data 105 105 0 0.00 2322 199699 16401 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 747 212313 37581 0 0
srv_find_qd_qs_mism 960727 960727 0 0.00 0 17956 11 0 0
srv_make_invnt_saldo 402 402 0 0.00 67 81471 5035 0 0
srv_make_money_saldo 907 907 0 0.00 1 21117 422 0 0
srv_recalc_idx_stat 5 5 0 0.00 134317 501307 236703 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4725 4725 0 0.00 1 12003 1093 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 8551 592 0 0
t$perf-norm:sp_cancel_client_order 1616 1616 0 0.00 0 8878 33 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 9047 48 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 6680 37 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1840 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1499 54 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 107194 8315 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 9404 37 0 0
t$perf-norm:sp_client_order 19364 19364 0 0.00 0 10950 44 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 3239 22 0 0
t$perf-norm:sp_pay_from_customer 6666 6666 0 0.00 0 10230 25 0 0
t$perf-norm:sp_pay_to_supplier 5557 5557 0 0.00 0 11702 27 0 0
t$perf-norm:sp_reserve_write_off 9425 9425 0 0.00 0 9957 29 0 0
t$perf-norm:sp_supplier_invoice 5212 5212 0 0.00 0 10741 39 0 0
t$perf-norm:sp_supplier_order 5111 5111 0 0.00 0 10280 37 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 684 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 447 24 0 0
t$perf-norm:srv_make_invnt_saldo 402 402 0 0.00 0 396 12 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 736 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 43 254 131 0 0
v_add_invoice_to_stock 9562 9338 224 2.34 0 8477 27 0 224
v_all_customers 35322 35322 0 0.00 0 200 4 0 0
v_all_suppliers 28614 28614 0 0.00 0 197 4 0 0
v_all_wares 38719 38719 0 0.00 0 111 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 744 15 0 0
v_cancel_client_order 3232 3232 0 0.00 0 2355 13 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 1080 13 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 786 13 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 8496 28 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 5342 28 0 12
v_cancel_supplier_order 760 760 0 0.00 0 948 11 0 0
v_cancel_write_off 1966 1966 0 0.00 0 6201 25 0 0
v_max_id_clo_ord 5108 5108 0 0.00 0 831 5 0 0
v_max_id_clo_res 942 942 0 0.00 0 1736 10 0 0
v_max_id_ord_sup 5203 5203 0 0.00 0 4107 7 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 925 8 0 0
v_max_non_paid_realizn 3489 3489 0 0.00 0 6112 13 0 0
v_min_id_clo_ord 5108 5108 0 0.00 0 1278 8 0 0
v_min_id_clo_res 942 942 0 0.00 0 482 8 0 0
v_min_id_ord_sup 5202 5202 0 0.00 0 6346 11 0 0
v_min_non_paid_invoice 5557 5557 0 0.00 0 5660 18 0 0
v_min_non_paid_realizn 6665 6665 0 0.00 0 5926 18 0 0
v_our_firm 9748 9748 0 0.00 0 83 5 0 0
v_reserve_write_off 18840 18840 0 0.00 0 11190 11 0 0
x_lock_depdocs_on_canc_invoice 10110 10110 0 0.00 0 23497 175 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 5125 377 0 0
x_make_qstorno_1000_1200 5111 5111 0 0.00 2 49194 1268 0 0
x_make_qstorno_1200_2000 5212 5212 0 0.00 6 89010 3436 0 0
x_make_qstorno_2100_3300 434656 434656 0 0.00 0 109092 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-03-2022 04:39:00 to 29-03-2022 04:39:04.

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
02:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:18 32297 8192000000 9720628944 10022801408 803187216 8.929 100 86 172 0 832156816 7773056 21196544
02:09:55 17805 8192000000 9772979904 10082365440 843722624 9.337 100 90 180 1 877845456 12734592 21388240
02:11:20 19388 8192000000 9804836256 10127712256 872764032 9.628 100 79 156 3 912970256 22540400 17665824
02:12:53 12753 8192000000 9870984912 10202226688 917344928 10.070 100 83 165 1 975889536 40243936 18300672
02:14:09 8868 8192000000 9900366240 10247716864 952490592 10.416 100 80 160 0 1017358304 48333280 16534432
02:15:21 2665 8192000000 9961182752 10319032320 991894672 10.800 100 90 177 1 1081727792 71121936 18711184
02:16:27 2012 8192000000 9995171648 10352701440 1016921984 11.042 100 95 183 0 1106166432 70848688 18395760
02:17:31 793 8192000000 10004511216 10372022272 1029115472 11.160 100 94 176 2 1126173904 80071024 16987408
02:18:33 1077 8192000000 10016825200 10390745088 1050777088 11.368 100 96 184 3 1131976112 63454768 17744256
02:19:35 2207 8192000000 10038970000 10415685632 1059151344 11.448 100 78 152 0 1162546608 88991728 14403536
02:20:39 1321 8192000000 10023006224 10401460224 1067612592 11.529 100 97 189 1 1139441952 52890640 18938720
02:21:41 1061 8192000000 10037008448 10416381952 1079310576 11.641 100 95 187 0 1160897136 61313792 20272768
02:22:44 7242 8192000000 10048230336 10433847296 1081238368 11.659 100 68 136 0 1168427296 73354816 13834112
02:23:52 899 8192000000 10063342656 10451038208 1086101824 11.706 100 95 187 1 1179630304 75296576 18231904
02:24:55 1061 8192000000 10073743264 10464333824 1095198768 11.792 100 92 182 1 1193168000 79264064 18705168
02:25:56 788 8192000000 10066588768 10454441984 1095356176 11.794 100 93 180 2 1183248928 69468176 18424576
02:26:58 1222 8192000000 10081404848 10472988672 1096422576 11.804 100 92 184 1 1198887440 84852480 17612384
02:28:00 1218 8192000000 10053891392 10439819264 1090802896 11.750 100 94 182 2 1173586880 63828784 18955200
02:29:02 3568 8192000000 10049497440 10438348800 1098811360 11.826 100 81 140 3 1170333936 58439024 13083552
02:30:08 1158 8192000000 10043562096 10432249856 1083761728 11.683 100 77 151 1 1161694576 63646704 14286144
02:31:11 1230 8192000000 10050605536 10436009984 1091634512 11.758 100 92 181 1 1172086304 62263504 18188288
02:32:13 1086 8192000000 10033541648 10414796800 1074553296 11.596 100 93 176 2 1155734400 62332672 18848432
02:33:15 1245 8192000000 10004320976 10378522624 1052584288 11.385 100 97 183 1 1123591584 53017952 17989344
02:34:18 1284 8192000000 9998526640 10368573440 1028910080 11.158 100 92 175 2 1123069088 74733232 19425776
02:35:20 984 8192000000 9982238736 10351923200 1028211376 11.151 100 91 171 3 1105624464 58368656 19044432
02:36:23 5515 8192000000 9977136752 10358366208 1019017888 11.063 98 67 125 5 1089257776 56025472 14214416
02:37:34 32805 8192000000 10024052672 10414993408 1060090320 11.457 100 59 113 3 1149957232 79385568 10481344
02:39:13 1342 8192000000 10030807344 10409082880 1069667744 11.549 100 81 158 0 1150356144 64354032 16334368
02:40:22 5264 8192000000 10049907600 10436083712 1079693872 11.645 100 63 120 2 1165213760 73566624 11953264
02:41:38 741 8192000000 10093216752 10475048960 1101318320 11.850 100 91 168 2 1208696272 90197648 17180304
02:42:48 1683 8192000000 10165845232 10559606784 1119877280 12.026 100 88 173 2 1276709696 138461584 18370832
02:43:55 5146 8192000000 10144657632 10546204672 1126687024 12.090 100 88 172 0 1261979984 117232320 18060640
02:45:12 1663 8192000000 10194747392 10595479552 1140018960 12.216 100 77 150 2 1316028704 158831424 17178320
02:46:19 1225 8192000000 10227163312 10621280256 1145262288 12.265 100 96 186 1 1338097824 173749792 19085744
02:47:26 793 8192000000 10247134656 10641592320 1151622880 12.325 100 95 178 1 1358107808 186611008 19873920
02:48:31 4351 8192000000 10285823312 10682814464 1161435264 12.417 100 89 175 1 1397855296 216455024 19965008
02:49:38 2557 8192000000 10291081568 10713169920 1161381936 12.416 100 89 171 1 1402572704 221802720 19388048
02:50:44 980 8192000000 10314415440 10733408256 1171072288 12.507 100 91 178 0 1426326816 235878128 19376400
02:51:52 2567 8192000000 10346163888 10746572800 1181167888 12.601 100 94 178 1 1451616080 250490064 19958128
02:52:59 830 8192000000 10395116768 10810040320 1183551104 12.623 100 99 196 0 1507147696 301568112 22028480
02:54:02 2387 8192000000 10383315248 10803732480 1185853008 12.645 100 93 184 0 1490014064 282518224 21642832
02:55:07 987 8192000000 10412121808 10829590528 1189550512 12.679 100 97 193 0 1522529584 310424624 22554448
02:56:12 1923 8192000000 10423818112 10868039680 1194070256 12.721 100 96 189 1 1532039936 315965024 22004656
02:57:17 1258 8192000000 10405404144 10850897920 1180507760 12.595 100 95 180 0 1509166768 306191968 22467040
02:58:21 12793 8192000000 10362794576 10815057920 1170014784 12.497 99 45 87 0 1464755824 283517936 11223104
02:59:37 842 8192000000 10424792768 10850422784 1194884896 12.729 100 78 153 1 1530675584 317779104 18011584
03:00:49 1097 8192000000 10418334976 10855104512 1184260432 12.630 100 93 186 1 1521991472 315961248 21769792
03:01:53 1638 8192000000 10424489248 10841329664 1177725232 12.569 100 90 178 1 1528760752 328823504 22212016
03:02:57 996 8192000000 10482445008 10897489920 1173884000 12.533 100 84 163 0 1584001728 391171792 18945936
03:04:03 7729 8192000000 10454576000 10927480832 1172077168 12.516 99 55 105 2 1541983216 356073632 13832416
03:05:14 8866 8192000000 10408840592 10885988352 1154475440 12.351 99 56 112 1 1489532720 321055168 14002112
03:06:25 14436 8192000000 10374745440 10795339776 1145698864 12.269 99 55 102 1 1468231216 309650096 12882256
03:07:45 9754 8192000000 10365407344 10815496192 1143559984 12.249 99 38 74 1 1472216896 319512656 9144256
03:08:57 12164 8192000000 10372069104 10827141120 1129579968 12.117 99 34 67 1 1467410704 328751488 9079248
03:10:13 4161 8192000000 10330619664 10778046464 1130963968 12.130 100 63 126 1 1434203120 289080672 14158480
03:11:22 4418 8192000000 10229907088 10684878848 1118612480 12.014 99 59 111 3 1338847552 207464976 12770096
03:12:29 1857 8192000000 10237423232 10678898688 1138612576 12.202 100 70 137 3 1336761600 182297840 15851184
03:13:32 38462 8192000000 10198629312 10689597440 1137254512 12.190 99 30 61 1 1329246256 184876304 7115440
03:15:12 72282 8192000000 10122452240 10559926272 1090577680 11.748 96 22 43 0 1175539232 80417040 4544512
03:17:25 82603 8192000000 10146570064 10590351360 1143302896 12.247 100 56 107 0 1246694688 91805488 11586304
03:19:52 26915 8192000000 10110747616 10576736256 1138870416 12.205 100 17 34 0 1215317568 72690704 3756448
03:22:31 212311 8192000000 10138508640 10582417408 1132171296 12.142 99 21 44 0 1211804592 75186224 4447072
03:27:04 107502 8192000000 10098561904 10548412416 1106874560 11.903 97 16 33 1 1205289104 94673792 3740752
03:29:57 65285 8192000000 10104368432 10551791616 1129403456 12.116 100 18 36 4 1197389216 64076112 3909648
03:32:27 90716 8192000000 10088713616 10580127744 1132844960 12.148 99 36 72 0 1189085216 48916352 7323904
03:35:01 91719 8192000000 10084800512 10546733056 1112332640 11.954 98 41 76 1 1169359792 49216864 7810288
03:37:37 34794 8192000000 10081236096 10522210304 1105401904 11.889 98 31 61 0 1165110496 53161744 6546848
03:39:14 23789 8192000000 10104355264 10546176000 1135297216 12.171 100 39 78 1 1200816096 57893488 7625392
03:40:45 58412 8192000000 10116018848 10563829760 1131192064 12.133 99 16 30 1 1215168656 80394448 3582144
03:42:52 71576 8192000000 10174325488 10665345024 1140287280 12.218 100 15 30 1 1293473952 149647760 3538912
03:45:16 82069 8192000000 10057619840 10534871040 1110649776 11.939 97 10 20 0 1168169632 55348880 2170976
03:47:38 57956 8192000000 10075012864 10538430464 1129782352 12.119 99 8 16 1 1180925360 49511232 1631776
03:49:36 45863 8192000000 10095748528 10540658688 1126839696 12.092 99 46 82 1 1172437808 37019664 8578448
03:51:26 97750 8192000000 10068531728 10529992704 1109069920 11.924 97 9 18 0 1165014784 53887280 2057584
03:54:07 45452 8192000000 10140873600 10595749888 1141877856 12.233 100 45 90 1 1229059744 78254000 8927888
03:55:57 46850 8192000000 10075537152 10519867392 1090408032 11.747 96 26 51 0 1157328720 61929952 4990736
03:58:06 39149 8192000000 10076901264 10516840448 1100917632 11.846 99 39 76 1 1167276176 59398160 6960384
03:59:50 115792 8192000000 10033467744 10457350144 1087406032 11.718 97 34 69 0 1138039728 43155536 7478160
04:02:58 13527 8192000000 10076640560 10501877760 1113947024 11.970 99 54 99 0 1175185584 48817248 12421312
04:04:15 53522 8192000000 10019113824 10498142208 1072334800 11.574 96 10 19 0 1116259600 41914784 2010016
04:06:09 103849 8192000000 10112124688 10559954944 1104741568 11.883 98 15 30 1 1195468336 87426368 3300400
04:09:58 24498 8192000000 10029817360 10463821824 1099808064 11.836 99 36 68 3 1134750864 28129248 6813552
04:11:23 38112 8192000000 10084112016 10509828096 1105782800 11.892 98 32 64 1 1169650608 57237680 6630128
04:13:08 25698 8192000000 10135094208 10572120064 1124139616 12.066 100 36 72 3 1229996704 97738192 8118896
04:14:37 34105 8192000000 10188534960 10624016384 1124574704 12.070 99 41 82 3 1275315040 141853696 8886640
04:16:21 31628 8192000000 10246059712 10699505664 1139225568 12.208 100 44 88 1 1317623184 168925312 9472304
04:18:10 31682 8192000000 10182952672 10606907392 1099849616 11.836 97 38 74 1 1241392240 132918368 8624256
04:19:47 60140 8192000000 10113812912 10636824576 1107125760 11.905 99 15 30 0 1283437568 172749456 3562352
04:21:48 51094 8192000000 10121063520 10537766912 1129869888 12.120 100 36 70 2 1179420400 42194848 7355664
04:23:44 158539 8192000000 10101591024 10608549888 1135811600 12.176 100 30 60 0 1235988512 93891664 6285248
04:27:24 58837 8192000000 10066654176 10536374272 1100861664 11.846 97 11 22 0 1144016704 40811728 2343312
04:29:30 55911 8192000000 10056651232 10545537024 1104461248 11.880 98 11 22 0 1168219088 61396992 2360848
04:31:31 74013 8192000000 10106241824 10585223168 1124924144 12.073 100 13 26 0 1212123712 84295952 2903616
04:33:46 61763 8192000000 10070959200 10524647424 1131770048 12.138 100 21 39 2 1193105072 56481152 4853872
04:35:53 59327 8192000000 10109468160 10562707456 1141267152 12.227 100 12 24 0 1187360832 43596576 2497104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-03-2022 04:39:06 to 29-03-2022 04:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-03-2022 04:39:23 to 29-03-2022 04:39:25.

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          210245
MON$OLDEST_ACTIVE               212447
MON$OLDEST_SNAPSHOT             212447
MON$NEXT_TRANSACTION            212447
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                       2093312
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:125: 
:126: mscs-test-161.budzdorov.ru    Tue Mar 29 04:37:15 2022
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Tue Mar 29 04:37:16 2022
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Tue Mar 29 04:37:17 2022
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Tue Mar 29 04:37:21 2022
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Tue Mar 29 04:37:21 2022
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
--- end of diff output ---

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

29.03.22 04:39:26. 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
2022-03-29 04:39:28 No severe PSQL-related problems occured
29.03.22 04:39:29. End of report.