Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2022 13: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 16656876 kB
MemAvailable 31647156 kB
Buffers 0 kB
Cached 15086308 kB
SwapCached 2176 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-28 13:37:14
DTS_END                         2022-03-28 13:37:14.8310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44484
TRN_ID                          220681
ATT_ID                          664
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: 8919.36 27443 1070323
customer order: creation 167.97 484 20156
customer order: refuse 14.37 385 1724
order to supplier: creation 45.62 1345 5474
order to supplier: removal 3.53 159530 423
invoice (draft): creation 45.63 2960 5476
invoice (draft): removal 49.93 964 5992
invoice accept: apply 41.09 27090 4931
invoice accept: cancel 45.02 1157 5402
customer reserve: creation 3812.80 261 457536
customer reserve: removal 4399.16 101 527899
realization accept: apply 81.41 253 9769
realization accept: cancel 87.08 159 10450
payment from customer: creation 57.43 183 6891
payment from customer: removal 5.88 128 705
payment to supplier: creation 46.45 196 5574
payment to supplier: removal 3.41 105 409
service: total inventory turnovers 4.24 3469 509
service: total monetary turnovers 7.37 341 884
service: aggregate perf. data 0.96 14746 115
service: refresh index statistics 0.03 334999 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2022 13:39:19 to 28-03-2022 13:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11354
TEST_TIME 2 8388
TEST_TIME 3 8110
TEST_TIME 4 8144
TEST_TIME 5 8137
TEST_TIME 6 7453
TEST_TIME 7 7119
TEST_TIME 8 6891
TEST_TIME 9 6788
TEST_TIME 10 6977
TEST_TIME 11 7071
TEST_TIME 12 7228
TEST_TIME 13 7142
TEST_TIME 14 7432
TEST_TIME 15 7576
TEST_TIME 16 7597
TEST_TIME 17 7675
TEST_TIME 18 7809
TEST_TIME 19 7866
TEST_TIME 20 7879
TEST_TIME 21 8177
TEST_TIME 22 8086
TEST_TIME 23 8253
TEST_TIME 24 8254
TEST_TIME 25 8417
TEST_TIME 26 8816
TEST_TIME 27 9206
TEST_TIME 28 9186
TEST_TIME 29 9466
TEST_TIME 30 10252
TEST_TIME 31 10631
TEST_TIME 32 10763
TEST_TIME 33 11347
TEST_TIME 34 11129
TEST_TIME 35 10937
TEST_TIME 36 10642
TEST_TIME 37 10449
TEST_TIME 38 10350
TEST_TIME 39 10177
TEST_TIME 40 10343
TEST_TIME 41 10228
TEST_TIME 42 10125
TEST_TIME 43 10064
TEST_TIME 44 9986
TEST_TIME 45 9849
TEST_TIME 46 9798
TEST_TIME 47 9809
TEST_TIME 48 9838
TEST_TIME 49 9806
TEST_TIME 50 9779
TEST_TIME 51 9817
TEST_TIME 52 9756
TEST_TIME 53 9812
TEST_TIME 54 9797
TEST_TIME 55 9702
TEST_TIME 56 9773
TEST_TIME 57 9771
TEST_TIME 58 9725
TEST_TIME 59 9732
TEST_TIME 60 9645
TEST_TIME 61 9590
TEST_TIME 62 9488
TEST_TIME 63 9470
TEST_TIME 64 9379
TEST_TIME 65 9362
TEST_TIME 66 9265
TEST_TIME 67 9289
TEST_TIME 68 9230
TEST_TIME 69 9269
TEST_TIME 70 9215
TEST_TIME 71 9245
TEST_TIME 72 9315
TEST_TIME 73 9271
TEST_TIME 74 9214
TEST_TIME 75 9199
TEST_TIME 76 9317
TEST_TIME 77 9443
TEST_TIME 78 9418
TEST_TIME 79 9408
TEST_TIME 80 9373
TEST_TIME 81 9377
TEST_TIME 82 9323
TEST_TIME 83 9251
TEST_TIME 84 9198
TEST_TIME 85 9178
TEST_TIME 86 9125
TEST_TIME 87 9108
TEST_TIME 88 9047
TEST_TIME 89 9044
TEST_TIME 90 9013
TEST_TIME 91 9017
TEST_TIME 92 8999
TEST_TIME 93 8966
TEST_TIME 94 8969
TEST_TIME 95 8973
TEST_TIME 96 9047
TEST_TIME 97 9031
TEST_TIME 98 9054
TEST_TIME 99 9087
TEST_TIME 100 9052
TEST_TIME 101 9063
TEST_TIME 102 8990
TEST_TIME 103 9040
TEST_TIME 104 9019
TEST_TIME 105 8976
TEST_TIME 106 8954
TEST_TIME 107 8931
TEST_TIME 108 8893
TEST_TIME 109 8893
TEST_TIME 110 8879
TEST_TIME 111 8850
TEST_TIME 112 8859
TEST_TIME 113 8864
TEST_TIME 114 8925
TEST_TIME 115 8935
TEST_TIME 116 8939
TEST_TIME 117 8959
TEST_TIME 118 9025
TEST_TIME 119 9002
TEST_TIME 120 8943

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-03-2022 13:39:23 to 28-03-2022 13:39:26.

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 1492791 1492791 0 0.00 0 23953 14 0 0
doc_list_biud 1492780 1492780 0 0.00 0 52292 41 0 0
sp_add_invoice_to_stock 5071 4931 140 2.76 48 247101 27091 0 140
sp_cancel_adding_invoice 5402 5402 0 0.00 7 117744 1158 0 0
sp_cancel_client_order 1724 1724 0 0.00 6 17562 385 0 0
sp_cancel_customer_reserve 527899 527899 0 0.00 1 52295 102 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 4 8892 128 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 2788 106 0 0
sp_cancel_supplier_invoice 6001 5992 9 0.14 3 29902 964 0 9
sp_cancel_supplier_order 423 423 0 0.00 13 1374539 159531 0 0
sp_cancel_write_off 10450 10450 0 0.00 2 8158 159 0 0
sp_client_order 20156 20156 0 0.00 6 29590 485 0 0
sp_customer_reserve 457536 457536 0 0.00 1 64277 262 0 0
sp_fill_shopping_cart 31088 31088 0 0.00 1 13410 78 0 0
sp_fill_shopping_cart_clo_res 457510 457510 0 0.00 0 18389 21 0 0
sp_get_clo_for_invoice 4897 4897 0 0.00 0 16529 300 0 0
sp_kill_cost_storno 16966 16966 0 0.00 0 6219 63 0 0
sp_kill_qstorno_ret_qs2qd 1072663 1072663 0 0.00 0 52286 44 0 0
sp_kill_qty_storno 566553 566553 0 0.00 0 52292 101 0 0
sp_lock_selected_doc 20392 20392 0 0.00 0 7632 8 0 0
sp_make_cost_storno 27131 27131 0 0.00 0 14163 77 0 0
sp_multiply_rows_for_pdistr 27129 27129 0 0.00 0 23892 36 0 0
sp_multiply_rows_for_qdistr 20155 20155 0 0.00 0 12070 206 0 0
sp_pay_from_customer 6891 6891 0 0.00 6 31724 184 0 0
sp_pay_to_supplier 5574 5574 0 0.00 6 31726 196 0 0
sp_payment_common 12465 12465 0 0.00 2 5790 40 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 8937 290 0 0
sp_qd_handle_on_invoice_upd_sts 10297 10297 0 0.00 0 40301 475 0 0
sp_qd_handle_on_reserve_upd_sts 9768 9768 0 0.00 0 29302 59 0 0
sp_reserve_write_off 9769 9769 0 0.00 9 29444 253 0 0
sp_supplier_invoice 5476 5476 0 0.00 10 132303 2960 0 0
sp_supplier_order 5474 5474 0 0.00 8 70775 1345 0 0
srv_aggregate_perf_data 115 115 0 0.00 2482 105083 14747 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 812 127621 38416 0 0
srv_find_qd_qs_mism 1022894 1022894 0 0.00 0 21121 11 0 0
srv_make_invnt_saldo 509 509 0 0.00 38 81592 3469 0 0
srv_make_money_saldo 884 884 0 0.00 1 11250 341 0 0
srv_recalc_idx_stat 4 4 0 0.00 125028 432417 335000 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 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 4940 4940 0 0.00 0 35547 895 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 7479 443 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 1893 26 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 1857 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 1278 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1593 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 909 33 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 83103 5572 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 3658 23 0 0
t$perf-norm:sp_client_order 20157 20157 0 0.00 0 32805 32 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 4530 16 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 8684 19 0 0
t$perf-norm:sp_pay_to_supplier 5574 5574 0 0.00 0 23838 25 0 0
t$perf-norm:sp_reserve_write_off 9769 9769 0 0.00 0 28689 20 0 0
t$perf-norm:sp_supplier_invoice 5476 5476 0 0.00 0 5532 28 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 1627 26 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 475 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 81 4 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 3583 20 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 1556 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 238 64 0 0
v_add_invoice_to_stock 10059 9779 280 2.78 0 15902 28 0 280
v_all_customers 36567 36567 0 0.00 0 138 3 0 0
v_all_suppliers 30212 30212 0 0.00 0 130 4 0 0
v_all_wares 40306 40306 0 0.00 0 128 3 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 4953 21 0 0
v_cancel_client_order 3448 3448 0 0.00 0 17332 21 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 8821 18 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 2100 13 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 2719 13 0 0
v_cancel_supplier_invoice 836 818 18 2.15 0 1317 27 0 18
v_cancel_supplier_order 846 846 0 0.00 0 5632 20 0 0
v_cancel_write_off 1972 1972 0 0.00 0 4492 24 0 0
v_max_id_clo_ord 5471 5471 0 0.00 0 6277 6 0 0
v_max_id_clo_res 965 965 0 0.00 0 1432 12 0 0
v_max_id_ord_sup 5461 5461 0 0.00 0 7018 7 0 0
v_max_non_paid_invoice 1398 1398 0 0.00 0 2667 9 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 2667 7 0 0
v_min_id_clo_ord 5471 5471 0 0.00 0 1854 8 0 0
v_min_id_clo_res 965 965 0 0.00 0 2940 12 0 0
v_min_id_ord_sup 5461 5461 0 0.00 0 1688 5 0 0
v_min_non_paid_invoice 5573 5573 0 0.00 0 3385 12 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 5005 13 0 0
v_our_firm 10192 10192 0 0.00 0 131 4 0 0
v_reserve_write_off 19536 19536 0 0.00 0 4104 10 0 0
x_lock_depdocs_on_canc_invoice 10985 10985 0 0.00 0 14404 123 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 9530 373 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 2 70764 1177 0 0
x_make_qstorno_1200_2000 5476 5476 0 0.00 5 131851 2761 0 0
x_make_qstorno_2100_3300 457534 457534 0 0.00 0 57233 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2022 13:39:27 to 28-03-2022 13:39:31.

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
11:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 24895 8192000000 9733622448 10034495488 812524016 9.023 100 85 169 1 840529632 7354528 20651088
11:09:51 21148 8192000000 9778281504 10089308160 852892832 9.429 100 90 180 0 886220416 12339920 20987664
11:11:19 15208 8192000000 9810421920 10134732800 887045280 9.770 100 90 180 0 928568736 21399408 20124048
11:12:37 9937 8192000000 9883224944 10218082304 924335296 10.139 100 82 164 0 987632576 45644016 17653264
11:13:53 4020 8192000000 9940204256 10286256128 962590720 10.514 100 88 170 0 1054852320 74294592 17967008
11:15:00 3827 8192000000 9961772976 10315124736 992709552 10.808 100 86 169 0 1079102784 69057376 17335856
11:16:06 1616 8192000000 9989110128 10348269568 1024762240 11.118 100 92 181 0 1104868608 61628560 18477808
11:17:10 1199 8192000000 10023475456 10389217280 1036630752 11.232 100 92 177 0 1145176576 91077440 17468384
11:18:12 738 8192000000 10044832880 10419023872 1056636768 11.424 100 95 188 0 1162214288 85743376 19834144
11:19:14 1277 8192000000 10049371808 10427797504 1065384560 11.508 100 94 181 2 1168119584 84769744 17965280
11:20:16 987 8192000000 10030610144 10411352064 1070702784 11.559 100 88 166 1 1151562736 64278640 16581312
11:21:19 880 8192000000 10025604816 10410606592 1080463856 11.652 100 86 164 1 1148976048 50266624 18245568
11:22:20 1071 8192000000 10059190688 10443673600 1086277312 11.707 100 95 187 0 1177971712 73112368 18582032
11:23:24 1213 8192000000 10070919472 10454753280 1088617056 11.730 100 93 178 1 1187565872 81748016 17200800
11:24:26 1245 8192000000 10039339120 10425106432 1091863536 11.760 100 93 182 0 1160617472 48838864 19915072
11:25:28 4717 8192000000 10067180512 10462728192 1094441840 11.785 100 74 148 0 1190042896 80868944 14732112
11:26:33 1244 8192000000 10068148544 10455789568 1101791600 11.855 100 96 189 2 1187041968 66448304 18802064
11:27:36 1378 8192000000 10077621712 10465497088 1100297104 11.840 100 99 192 2 1198561616 78574112 19690400
11:28:39 1979 8192000000 10048350224 10435104768 1074541152 11.595 100 83 163 1 1164896784 74410400 15945232
11:29:43 1072 8192000000 10032503456 10419511296 1076209296 11.611 100 85 164 0 1152152496 58946016 16997184
11:30:45 1280 8192000000 10021161792 10406752256 1073135072 11.582 99 87 160 2 1147241600 57304576 16801952
11:31:47 6751 8192000000 9991950304 10370310144 1031051248 11.179 97 65 125 0 1096730480 54471216 11208016
11:32:54 1093 8192000000 9986279744 10361663488 1034631344 11.213 100 89 170 2 1108606176 56573264 17401568
11:33:56 1706 8192000000 9999571504 10379767808 1052675072 11.386 100 80 157 0 1118419088 50438784 15305232
11:34:59 1813 8192000000 10008382848 10384990208 1046430192 11.326 100 82 155 2 1125602560 63876704 15295664
11:36:02 1116 8192000000 9972249360 10339778560 1029079600 11.160 99 93 180 1 1094023504 47394640 17549264
11:37:03 2881 8192000000 9975177312 10353328128 1031013760 11.178 98 64 112 3 1086349616 44503072 10832784
11:38:12 1826 8192000000 10022060496 10398736384 1063382560 11.489 100 96 183 1 1139076944 56192448 19501936
11:39:20 1030 8192000000 10039482320 10418651136 1076505840 11.614 100 98 196 1 1159242128 62572672 20163616
11:40:25 807 8192000000 10067211456 10450903040 1091035504 11.753 100 96 185 0 1186898208 76442528 19420176
11:41:30 9704 8192000000 10078316864 10476355584 1108713872 11.920 100 77 137 2 1198307456 74644624 14948960
11:42:45 11558 8192000000 10119309680 10515054592 1115093120 11.981 100 73 142 1 1238412896 108993776 14326000
11:43:59 13024 8192000000 10143374736 10557026304 1128615904 12.108 100 56 110 0 1264085968 123551600 11918464
11:45:15 4261 8192000000 10155304976 10564374528 1117148288 12.000 99 60 120 0 1268605328 138548160 12908880
11:46:28 1001 8192000000 10186502080 10591645696 1141849184 12.233 100 75 148 0 1299857008 140788560 17219264
11:47:32 1010 8192000000 10232680112 10629742592 1155284000 12.359 100 88 172 1 1346746608 172977280 18485328
11:48:38 3055 8192000000 10247004624 10655313920 1169947056 12.496 100 88 165 2 1354156320 165856624 18352640
11:49:44 1886 8192000000 10294401504 10695786496 1182037936 12.609 100 88 167 2 1404678624 203973936 18666752
11:50:49 5273 8192000000 10347269952 10762862592 1190682352 12.690 100 80 157 0 1465926736 256809184 18435200
11:51:58 798 8192000000 10356069616 10773766144 1195705952 12.736 100 97 192 0 1463839696 246046192 22087552
11:53:02 1291 8192000000 10393317888 10801876992 1202687392 12.801 100 89 171 0 1496904240 274527232 19689616
11:54:07 1081 8192000000 10435464064 10854404096 1199206752 12.769 100 95 188 1 1545643808 324631760 21805296
11:55:11 843 8192000000 10446073760 10856693760 1203955072 12.813 100 99 195 2 1555066176 328423616 22687488
11:56:15 862 8192000000 10472050448 10877239296 1183184912 12.620 100 94 181 3 1577209760 372490672 21534176
11:57:18 1178 8192000000 10457533936 10889056256 1173738352 12.532 99 91 174 2 1567505552 371949568 21817632
11:58:21 1417 8192000000 10515080832 10931847168 1181550560 12.605 100 100 198 1 1614619792 409600896 23468336
11:59:24 1588 8192000000 10561819328 10977284096 1190606848 12.689 100 91 176 1 1669308528 456848608 21853072
12:00:29 4309 8192000000 10550400864 10998702080 1189334416 12.677 100 63 126 0 1660128144 454897072 15896656
12:01:37 4090 8192000000 10593171328 11017363456 1180535248 12.595 99 64 127 0 1696746320 500356352 15854720
12:02:52 9162 8192000000 10603235920 11051704320 1186683456 12.652 100 50 99 0 1702665696 502832032 13150208
12:04:06 9167 8192000000 10555609920 11029098496 1192127024 12.703 100 42 82 1 1671154080 468133552 10893504
12:05:22 10011 8192000000 10523642448 10995707904 1181482416 12.604 100 36 72 0 1638669232 447564800 9622016
12:06:44 10619 8192000000 10467739888 10967842816 1162416496 12.426 100 33 66 0 1567664528 396649264 8598768
12:08:47 38391 8192000000 10197835216 10683109376 1144858144 12.261 99 18 35 1 1301557408 152512672 4186592
12:10:27 65024 8192000000 10071298624 10515869696 1085282032 11.698 95 12 24 1 1149757200 62310240 2164928
12:12:37 127619 8192000000 10120234528 10554892288 1111527440 11.947 97 53 101 0 1197740656 74421264 11791952
12:15:53 83620 8192000000 10108102608 10599116800 1117809120 12.006 98 19 37 2 1206605968 84379232 4417616
12:18:39 62499 8192000000 10101656688 10548494336 1117392608 12.002 98 26 50 1 1167331808 44422336 5516864
12:20:44 31032 8192000000 10086993472 10568245248 1118993584 12.017 98 13 25 2 1198645392 76246272 3405536
12:22:16 59276 8192000000 10104287072 10592849920 1140091568 12.216 100 17 34 2 1228779136 84832384 3855184
12:24:16 63711 8192000000 10117207984 10580381696 1132630768 12.146 99 18 36 1 1216395152 79847648 3916736
12:26:43 86981 8192000000 10116164816 10586480640 1139324240 12.209 99 25 50 0 1204052880 59385568 5343072
12:29:16 49126 8192000000 10129641184 10586169344 1139502096 12.211 99 36 72 2 1215309008 68218128 7588784
12:31:15 45170 8192000000 10090382496 10531860480 1121864224 12.045 98 21 41 0 1185512912 59529296 4119392
12:33:13 90219 8192000000 10031769184 10479218688 1011399728 10.989 89 24 44 0 1053305376 37371072 4534576
12:35:47 51280 8192000000 10110352960 10572034048 1119323312 12.021 100 43 86 1 1199930192 71907776 8699104
12:39:04 42207 8192000000 10092036848 10550804480 1137920928 12.196 100 29 58 0 1207499440 63793200 5785312
12:40:50 76135 8192000000 10090633552 10556448768 1127394000 12.097 99 11 23 0 1182634016 52695088 2544928
12:43:15 43352 8192000000 10106298160 10554056704 1144805136 12.261 100 14 28 1 1222058896 73254208 3999552
12:45:27 55899 8192000000 10103374336 10538987520 1102202000 11.859 97 35 70 2 1171373568 62205920 6965648
12:47:33 100433 8192000000 10115780704 10556784640 1093668400 11.778 95 19 38 1 1140250560 42791840 3790320
12:50:14 40368 8192000000 10094267552 10527182848 1099603856 11.834 96 32 65 5 1147363680 40574752 7185072
12:51:59 80037 8192000000 10173731328 10668072960 1124939376 12.074 98 36 70 2 1248882912 116702800 7240736
12:54:29 72472 8192000000 10088276064 10516418560 1117478096 12.003 98 17 31 2 1165769008 44937040 3353872
12:56:42 36073 8192000000 10082843648 10501996544 1114420256 11.974 99 67 129 0 1178320368 50652000 13248112
12:58:22 14898 8192000000 10131838560 10563567616 1131639552 12.137 100 57 114 0 1219960560 77169904 11151104
12:59:42 68306 8192000000 10069449920 10525966336 1126928288 12.092 99 23 46 0 1177663904 46127392 4608224
13:01:54 32209 8192000000 10085863024 10536513536 1121732752 12.043 99 39 79 1 1189600608 60054784 7813072
13:03:33 72532 8192000000 10051752656 10542235648 1134188400 12.161 100 4 8 0 1165818624 31180160 450064
13:05:47 71174 8192000000 10055792864 10528223232 1099130016 11.829 97 20 40 2 1137007936 33732176 4145744
13:08:00 43555 8192000000 10101941312 10553561088 1116001824 11.989 98 23 44 2 1184760608 63478976 5279808
13:09:45 52167 8192000000 10111759280 10546397184 1092503904 11.766 97 34 68 2 1174291984 74807008 6981072
13:11:38 33915 8192000000 10135231360 10578186240 1125682960 12.081 100 30 59 0 1227607696 95516176 6408560
13:13:23 83116 8192000000 10098968320 10540560384 1119371152 12.021 99 20 38 1 1196599424 73329408 3898864
13:16:00 30582 8192000000 10109748960 10580983808 1126797648 12.091 99 45 90 0 1206882912 70902144 9183120
13:17:39 13111 8192000000 10155205168 10583871488 1133033168 12.150 100 63 119 1 1252883680 107203024 12647488
13:18:55 84793 8192000000 10143600208 10603323392 1131030800 12.131 99 48 96 1 1272634448 131404624 10199024
13:21:37 59034 8192000000 10061590464 10597535744 1109053392 11.923 98 7 14 0 1166667088 56664032 949664
13:23:37 73314 8192000000 10044592176 10500771840 1085401296 11.699 96 18 37 1 1128493040 39630256 3461488
13:25:50 38658 8192000000 10086973472 10531889152 1117421760 12.003 99 39 79 1 1175829600 50441952 7965888
13:27:36 74271 8192000000 10124556672 10557165568 1064370096 11.498 93 34 68 2 1149196464 77473920 7352448
13:30:04 89552 8192000000 10109467600 10585944064 1114954368 11.979 99 26 53 1 1231910416 110986976 5969072
13:32:40 66607 8192000000 10124237904 10589343744 1121668256 12.043 99 16 32 2 1223362672 98093376 3601040
13:35:40 60030 8192000000 10081073728 10510471168 1096184128 11.801 97 26 51 3 1121796768 20254992 5357648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-03-2022 13:39:33 to 28-03-2022 13:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 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 28-03-2022 13:39:50 to 28-03-2022 13:39:52.

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          219325
MON$OLDEST_ACTIVE               221143
MON$OLDEST_SNAPSHOT             221143
MON$NEXT_TRANSACTION            221143
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                       2043872
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             777
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 ---
:108: 
:109: mscs-test-161.budzdorov.ru    Mon Mar 28 13:37:16 2022
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Mon Mar 28 13:37:21 2022
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Mon Mar 28 13:37:34 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
--- end of diff output ---

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

28.03.22 13:39:53. 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-28 13:39:55 No severe PSQL-related problems occured
28.03.22 13:39:57. End of report.