Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.04.2022 10:37
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 16447572 kB
MemAvailable 31513184 kB
Buffers 0 kB
Cached 15228564 kB
SwapCached 2364 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-04-09 10:37:12
DTS_END                         2022-04-09 10:37:12.5600
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/43982
TRN_ID                          214004
ATT_ID                          617
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: 8357.30 22463 1002876
customer order: creation 169.47 477 20336
customer order: refuse 14.09 460 1691
order to supplier: creation 44.71 1368 5365
order to supplier: removal 3.18 160676 382
invoice (draft): creation 45.31 3587 5437
invoice (draft): removal 45.09 1044 5411
invoice accept: apply 41.66 29394 4999
invoice accept: cancel 40.61 1450 4873
customer reserve: creation 3725.20 291 447024
customer reserve: removal 3950.83 102 474099
realization accept: apply 83.16 301 9979
realization accept: cancel 68.38 175 8205
payment from customer: creation 57.63 198 6916
payment from customer: removal 5.63 122 676
payment to supplier: creation 47.14 214 5657
payment to supplier: removal 3.27 121 392
service: total inventory turnovers 3.72 4247 446
service: total monetary turnovers 7.26 390 871
service: aggregate perf. data 0.94 17241 113
service: refresh index statistics 0.03 227408 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-04-2022 10:38:09 to 09-04-2022 10:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7168
TEST_TIME 2 6585
TEST_TIME 3 7013
TEST_TIME 4 6435
TEST_TIME 5 6296
TEST_TIME 6 6258
TEST_TIME 7 6139
TEST_TIME 8 6151
TEST_TIME 9 6193
TEST_TIME 10 6317
TEST_TIME 11 6343
TEST_TIME 12 6462
TEST_TIME 13 6720
TEST_TIME 14 6737
TEST_TIME 15 6915
TEST_TIME 16 6967
TEST_TIME 17 7171
TEST_TIME 18 7153
TEST_TIME 19 7168
TEST_TIME 20 7382
TEST_TIME 21 7519
TEST_TIME 22 7706
TEST_TIME 23 7965
TEST_TIME 24 8502
TEST_TIME 25 8644
TEST_TIME 26 8599
TEST_TIME 27 8672
TEST_TIME 28 8963
TEST_TIME 29 8983
TEST_TIME 30 8987
TEST_TIME 31 9189
TEST_TIME 32 9427
TEST_TIME 33 9675
TEST_TIME 34 10020
TEST_TIME 35 10148
TEST_TIME 36 10227
TEST_TIME 37 10144
TEST_TIME 38 10187
TEST_TIME 39 10003
TEST_TIME 40 9876
TEST_TIME 41 9713
TEST_TIME 42 9606
TEST_TIME 43 9419
TEST_TIME 44 9356
TEST_TIME 45 9280
TEST_TIME 46 9185
TEST_TIME 47 9373
TEST_TIME 48 9290
TEST_TIME 49 9336
TEST_TIME 50 9313
TEST_TIME 51 9270
TEST_TIME 52 9128
TEST_TIME 53 9020
TEST_TIME 54 8989
TEST_TIME 55 8878
TEST_TIME 56 8842
TEST_TIME 57 8937
TEST_TIME 58 8904
TEST_TIME 59 8822
TEST_TIME 60 8693
TEST_TIME 61 8703
TEST_TIME 62 8636
TEST_TIME 63 8644
TEST_TIME 64 8596
TEST_TIME 65 8566
TEST_TIME 66 8552
TEST_TIME 67 8569
TEST_TIME 68 8565
TEST_TIME 69 8631
TEST_TIME 70 8629
TEST_TIME 71 8582
TEST_TIME 72 8632
TEST_TIME 73 8610
TEST_TIME 74 8630
TEST_TIME 75 8674
TEST_TIME 76 8750
TEST_TIME 77 8720
TEST_TIME 78 8642
TEST_TIME 79 8617
TEST_TIME 80 8617
TEST_TIME 81 8558
TEST_TIME 82 8571
TEST_TIME 83 8546
TEST_TIME 84 8522
TEST_TIME 85 8480
TEST_TIME 86 8505
TEST_TIME 87 8428
TEST_TIME 88 8421
TEST_TIME 89 8427
TEST_TIME 90 8396
TEST_TIME 91 8408
TEST_TIME 92 8421
TEST_TIME 93 8420
TEST_TIME 94 8464
TEST_TIME 95 8473
TEST_TIME 96 8494
TEST_TIME 97 8496
TEST_TIME 98 8497
TEST_TIME 99 8546
TEST_TIME 100 8560
TEST_TIME 101 8566
TEST_TIME 102 8630
TEST_TIME 103 8594
TEST_TIME 104 8536
TEST_TIME 105 8504
TEST_TIME 106 8496
TEST_TIME 107 8441
TEST_TIME 108 8389
TEST_TIME 109 8382
TEST_TIME 110 8378
TEST_TIME 111 8355
TEST_TIME 112 8384
TEST_TIME 113 8413
TEST_TIME 114 8421
TEST_TIME 115 8442
TEST_TIME 116 8471
TEST_TIME 117 8455
TEST_TIME 118 8438
TEST_TIME 119 8460
TEST_TIME 120 8391

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-04-2022 10:38:13 to 09-04-2022 10:38:16.

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 1414781 1414781 0 0.00 0 27611 15 0 0
doc_list_biud 1414765 1414765 0 0.00 0 73177 41 0 0
sp_add_invoice_to_stock 5101 4999 102 1.99 31 332684 29395 0 102
sp_cancel_adding_invoice 4873 4873 0 0.00 6 92042 1450 0 0
sp_cancel_client_order 1691 1691 0 0.00 5 14074 461 0 0
sp_cancel_customer_reserve 474099 474099 0 0.00 1 31106 103 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 5067 123 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 2179 121 0 0
sp_cancel_supplier_invoice 5423 5411 12 0.22 3 73186 1045 0 12
sp_cancel_supplier_order 382 382 0 0.00 19 1416036 160677 0 0
sp_cancel_write_off 8205 8205 0 0.00 2 19487 176 0 0
sp_client_order 20336 20336 0 0.00 6 46773 477 0 0
sp_customer_reserve 447024 447024 0 0.00 1 54501 292 0 0
sp_fill_shopping_cart 31130 31130 0 0.00 1 29505 94 0 0
sp_fill_shopping_cart_clo_res 446990 446990 0 0.00 0 11337 21 0 0
sp_get_clo_for_invoice 4954 4954 0 0.00 0 27031 323 0 0
sp_kill_cost_storno 14146 14146 0 0.00 0 27600 81 0 0
sp_kill_qstorno_ret_qs2qd 962196 962196 0 0.00 0 73175 46 0 0
sp_kill_qty_storno 509591 509591 0 0.00 0 73176 105 0 0
sp_lock_selected_doc 20546 20546 0 0.00 0 19424 8 0 0
sp_make_cost_storno 27505 27505 0 0.00 0 19562 82 0 0
sp_multiply_rows_for_pdistr 27502 27502 0 0.00 0 8612 40 0 0
sp_multiply_rows_for_qdistr 20335 20335 0 0.00 0 17394 178 0 0
sp_pay_from_customer 6916 6916 0 0.00 6 16076 199 0 0
sp_pay_to_supplier 5657 5657 0 0.00 7 16468 215 0 0
sp_payment_common 12572 12572 0 0.00 2 7565 45 0 0
sp_qd_handle_on_cancel_clo 1691 1691 0 0.00 0 6949 374 0 0
sp_qd_handle_on_invoice_upd_sts 9825 9825 0 0.00 0 23957 547 0 0
sp_qd_handle_on_reserve_upd_sts 9978 9978 0 0.00 0 16546 73 0 0
sp_reserve_write_off 9979 9979 0 0.00 8 16858 302 0 0
sp_supplier_invoice 5437 5437 0 0.00 9 103041 3588 0 0
sp_supplier_order 5365 5365 0 0.00 6 34166 1368 0 0
srv_aggregate_perf_data 113 113 0 0.00 2224 238772 17242 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 848 192738 34717 0 0
srv_find_qd_qs_mism 955375 955375 0 0.00 0 12283 13 0 0
srv_make_invnt_saldo 446 446 0 0.00 53 63705 4248 0 0
srv_make_money_saldo 871 871 0 0.00 1 11199 390 0 0
srv_recalc_idx_stat 4 4 0 0.00 86606 298671 227409 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5008 5008 0 0.00 1 11124 1017 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 9592 564 0 0
t$perf-norm:sp_cancel_client_order 1691 1691 0 0.00 0 4194 30 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 2040 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 2418 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 333 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 873 41 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 111687 7371 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1609 22 0 0
t$perf-norm:sp_client_order 20337 20337 0 0.00 0 13407 39 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 1157 17 0 0
t$perf-norm:sp_pay_from_customer 6916 6916 0 0.00 0 11536 22 0 0
t$perf-norm:sp_pay_to_supplier 5657 5657 0 0.00 0 4456 19 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 15979 24 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 4555 33 0 0
t$perf-norm:sp_supplier_order 5367 5367 0 0.00 0 3011 36 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 1146 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 114 5 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1571 20 0 0
t$perf-norm:srv_make_money_saldo 871 871 0 0.00 0 390 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 256 71 0 0
v_add_invoice_to_stock 10100 9896 204 2.01 0 17350 30 0 204
v_all_customers 36968 36968 0 0.00 0 107 4 0 0
v_all_suppliers 29530 29530 0 0.00 0 82 4 0 0
v_all_wares 40670 40670 0 0.00 0 79 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 2923 17 0 0
v_cancel_client_order 3382 3382 0 0.00 0 13828 16 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 4987 17 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 13408 28 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 2097 13 0 0
v_cancel_supplier_invoice 880 856 24 2.72 0 5570 30 0 24
v_cancel_supplier_order 764 764 0 0.00 0 397 11 0 0
v_cancel_write_off 1964 1964 0 0.00 0 5626 28 0 0
v_max_id_clo_ord 5358 5358 0 0.00 0 3288 6 0 0
v_max_id_clo_res 965 965 0 0.00 0 1734 10 0 0
v_max_id_ord_sup 5431 5431 0 0.00 0 5462 7 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 372 7 0 0
v_max_non_paid_realizn 3644 3644 0 0.00 0 922 7 0 0
v_min_id_clo_ord 5358 5358 0 0.00 0 2943 9 0 0
v_min_id_clo_res 965 965 0 0.00 0 1462 9 0 0
v_min_id_ord_sup 5431 5431 0 0.00 0 5041 9 0 0
v_min_non_paid_invoice 5657 5657 0 0.00 0 3840 14 0 0
v_min_non_paid_realizn 6915 6915 0 0.00 0 7561 16 0 0
v_our_firm 10242 10242 0 0.00 0 56 5 0 0
v_reserve_write_off 19954 19954 0 0.00 0 13939 14 0 0
x_lock_depdocs_on_canc_invoice 9856 9856 0 0.00 0 22821 149 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 18818 453 0 0
x_make_qstorno_1000_1200 5365 5365 0 0.00 2 34122 1178 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 5 102357 3312 0 0
x_make_qstorno_2100_3300 447024 447024 0 0.00 0 54467 257 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-04-2022 10:38:17 to 09-04-2022 10:38:21.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:17 21327 8192000000 9715696864 10013433856 794193584 8.837 100 88 176 0 822933120 7369872 21369664
08:09:45 27644 8192000000 9748626016 10053799936 824052768 9.139 100 87 174 0 856177824 11393408 20731648
08:11:21 26782 8192000000 9790104944 10106839040 853646240 9.437 100 83 166 0 894621552 21168224 19807088
08:12:49 13048 8192000000 9862812784 10196676608 912844416 10.025 100 80 160 0 972638576 42144336 17649824
08:14:08 6619 8192000000 9909836160 10252771328 959020624 10.479 100 87 174 0 1020767984 43699744 18047616
08:15:20 4969 8192000000 9963069312 10314199040 993231824 10.813 100 91 176 1 1081472960 70275616 17965520
08:16:29 2009 8192000000 10017099264 10374377472 1006773440 10.944 100 91 181 0 1128508240 104025552 17709248
08:17:33 6396 8192000000 9993013376 10358050816 1031999440 11.188 100 82 159 0 1108762640 61588032 15175168
08:18:43 976 8192000000 10030525920 10402758656 1055471280 11.413 100 97 188 0 1150209232 74435408 20302544
08:19:46 1546 8192000000 10032653392 10410201088 1064513680 11.500 100 94 178 1 1152552528 70347008 17691840
08:20:50 10627 8192000000 10030144304 10406084608 1073272096 11.583 100 82 155 4 1157031296 68106320 15652880
08:22:02 1037 8192000000 10046265216 10435842048 1075226880 11.602 100 82 162 0 1170871744 79371136 16273728
08:23:04 2027 8192000000 10025472768 10418028544 1080779632 11.655 100 79 144 2 1143246160 47230784 15235744
08:24:07 1144 8192000000 10050774480 10439942144 1082341088 11.670 100 97 187 4 1171408944 69286672 19781184
08:25:09 1047 8192000000 10048092800 10438270976 1089133728 11.734 100 88 174 0 1175146640 66302432 19710480
08:26:11 943 8192000000 10052149632 10443448320 1093164704 11.773 100 85 159 0 1183025952 73207680 16653568
08:27:13 886 8192000000 10070237632 10459979776 1093523600 11.776 100 85 163 1 1192408128 81814288 17070240
08:28:15 1089 8192000000 10085892416 10478739456 1099435168 11.832 100 94 183 1 1205807808 88610912 17761728
08:29:18 1088 8192000000 10070036576 10460078080 1092417696 11.766 100 95 183 1 1195382432 84503392 18461344
08:30:20 5374 8192000000 10056028832 10459238400 1094785600 11.788 100 70 136 0 1193565808 85726288 13053920
08:31:27 971 8192000000 10014182640 10395467776 1071330720 11.565 100 92 184 0 1137066224 47156736 18578768
08:32:29 1367 8192000000 10005639408 10382049280 1050336640 11.364 99 94 182 1 1130054880 61321744 18396496
08:33:32 1212 8192000000 10026963376 10404061184 1051860752 11.379 99 96 184 1 1148677376 78913200 17903424
08:34:34 1117 8192000000 10053574336 10429542400 1055828144 11.417 100 95 185 2 1167572912 93505296 18239472
08:35:36 1162 8192000000 9993300624 10371674112 1049522192 11.356 100 89 177 0 1116444880 47716944 19205744
08:36:39 1214 8192000000 10009672672 10389467136 1062473808 11.480 100 92 177 5 1131532928 51092688 17966432
08:37:44 33609 8192000000 10015686480 10397237248 1056090400 11.419 100 67 130 0 1135669328 66824944 12753984
08:39:28 14749 8192000000 10032066224 10409750528 1063584736 11.491 99 72 136 3 1162256592 85750080 12921776
08:40:47 8436 8192000000 10055127504 10441068544 1091409984 11.756 100 63 122 2 1180484016 77072512 12001520
08:42:08 1735 8192000000 10097212448 10482823168 1113296432 11.964 100 88 167 2 1211954240 81535312 17122496
08:43:12 10341 8192000000 10147314608 10547556352 1123403296 12.059 100 75 144 1 1263808272 123860656 16544320
08:44:34 2010 8192000000 10165965600 10553589760 1126327728 12.087 99 87 164 1 1284362144 140824064 17210352
08:45:41 4195 8192000000 10211157104 10609557504 1137322352 12.190 100 71 142 0 1335146752 182646944 15177456
08:46:49 2793 8192000000 10227874416 10632425472 1141162576 12.226 100 74 147 0 1344508496 187304160 16041760
08:47:57 2175 8192000000 10254105952 10654019584 1152092688 12.329 100 73 137 1 1371424080 204712256 14619136
08:49:05 894 8192000000 10302687632 10702229504 1160855456 12.411 100 94 180 0 1413942656 234336752 18750448
08:50:09 1364 8192000000 10322065168 10731573248 1162783152 12.429 100 93 182 2 1433228784 248247840 22197792
08:51:14 843 8192000000 10330362880 10734641152 1180698976 12.597 100 90 166 1 1440062048 241243056 18120016
08:52:18 1538 8192000000 10373927520 10780114944 1179891008 12.589 100 91 180 1 1483728576 283937184 19900384
08:53:24 1583 8192000000 10393432912 10800054272 1186882256 12.654 100 92 174 1 1503580160 296101648 20596256
08:54:29 877 8192000000 10394256832 10810695680 1176785968 12.560 100 92 183 3 1503218560 304346976 22085616
08:55:34 1105 8192000000 10417462480 10826145792 1176365584 12.556 100 91 176 0 1527425280 330194496 20865200
08:56:40 860 8192000000 10434718992 10861998080 1174992272 12.543 100 94 186 0 1542438224 345587968 21857984
08:57:46 1074 8192000000 10421102336 10868744192 1174988384 12.543 100 94 182 0 1529633232 333167184 21477664
08:58:52 2333 8192000000 10442570912 10897965056 1171066544 12.507 100 96 187 0 1545435200 352281568 22087088
08:59:57 1107 8192000000 10429260064 10886049792 1178021200 12.572 100 98 194 2 1530016480 329005088 22990192
09:01:00 1644 8192000000 10380874944 10855120896 1175027552 12.544 100 94 184 0 1484525056 288214368 21283136
09:02:04 1347 8192000000 10372680864 10795880448 1176655456 12.559 100 94 184 1 1479246768 280882496 21708816
09:03:08 7223 8192000000 10365898816 10799501312 1157639600 12.381 99 45 86 0 1465605744 296983408 10982736
09:04:45 2596 8192000000 10353234464 10811723776 1171039616 12.507 100 60 110 2 1477386608 291585952 14761040
09:05:50 8459 8192000000 10317100384 10766974976 1146345776 12.275 100 37 74 0 1432017664 276551296 9120592
09:07:00 7818 8192000000 10323294704 10750656512 1143859600 12.252 100 37 74 0 1423008896 269681456 9467840
09:08:11 2151 8192000000 10313625232 10765475840 1137424848 12.191 100 57 114 1 1414613392 264029680 13158864
09:09:15 22136 8192000000 10269414320 10762969088 1148007728 12.291 100 39 78 0 1391762064 234316080 9438256
09:10:56 43709 8192000000 10170505920 10624036864 1115493264 11.984 98 24 45 1 1294336336 173431088 5411984
09:12:40 38199 8192000000 10120905376 10575659008 1106623264 11.900 97 38 76 1 1202756192 87194000 8938928
09:14:24 21522 8192000000 10113193296 10542026752 1131652976 12.137 100 50 101 3 1215829728 74229488 9947264
09:15:48 25857 8192000000 10062581072 10530959360 1119863408 12.026 99 19 38 0 1173539360 49523328 4152624
09:17:24 71921 8192000000 10107607136 10558795776 1131255680 12.133 99 32 63 1 1217812496 80001152 6555664
09:19:37 109911 8192000000 10148039360 10611650560 1106603312 11.900 97 17 34 1 1225286512 115305696 3377504
09:22:27 81274 8192000000 10200515792 10661171200 1140258848 12.218 100 22 46 3 1299004768 153512272 5233648
09:25:15 192738 8192000000 10066226720 10580660224 1107228128 11.906 98 19 38 0 1199513152 87912320 4372704
09:31:02 43600 8192000000 10105439664 10559025152 1119887440 12.026 99 33 67 0 1208301680 81314768 7099472
09:32:47 27169 8192000000 10153786544 10591989760 1128858384 12.111 99 51 99 3 1231022928 90789680 11374864
09:34:21 33001 8192000000 10088593536 10610470912 1137488064 12.192 100 7 14 1 1198044480 59143856 1412560
09:35:55 54278 8192000000 10072415808 10549960704 1129716128 12.119 99 15 30 3 1158514384 25606112 3192144
09:37:50 56024 8192000000 10068004704 10543931392 1129681824 12.118 99 14 28 2 1174469856 41902512 2885520
09:39:48 119720 8192000000 10053552560 10504130560 1097980016 11.818 96 15 30 1 1147363824 46647856 2735952
09:42:59 68331 8192000000 10099930864 10554949632 1092260128 11.764 95 19 37 1 1140961536 44720240 3981168
09:45:07 36496 8192000000 10125480464 10563231744 1139848112 12.214 99 30 59 1 1200188480 54463952 5876416
09:46:48 28380 8192000000 10074651520 10500915200 1096735408 11.807 96 43 84 1 1149703120 44933360 8034352
09:48:19 64883 8192000000 10135166384 10597543936 1123966896 12.064 98 34 68 0 1231343440 100677520 6699024
09:50:37 50324 8192000000 10097485936 10569252864 1122605376 12.052 99 39 78 0 1189071728 57431568 9034784
09:52:28 52856 8192000000 10140205920 10577637376 1131330992 12.134 99 31 62 1 1219532592 82300320 5901280
09:54:23 48998 8192000000 10076492592 10512982016 1090240624 11.745 96 17 34 1 1133548480 39699696 3608160
09:56:12 95793 8192000000 10085001344 10529206272 1092086448 11.762 97 27 53 0 1169735808 71813440 5835920
09:58:55 8105 8192000000 10076392704 10500513792 1127285936 12.096 100 63 120 0 1176771408 36967408 12518064
10:00:09 69596 8192000000 10074235136 10524069888 1096006320 11.800 97 8 16 0 1139732336 42015424 1710592
10:02:19 76204 8192000000 10068616976 10557276160 1128757344 12.110 100 13 26 0 1204313456 72510320 3045792
10:04:48 63468 8192000000 10079894176 10549252096 1127828720 12.101 100 19 38 0 1200672752 68869680 3974352
10:07:21 47155 8192000000 10096006864 10547019776 1119605584 12.023 98 25 50 0 1173965536 49501136 4858816
10:09:12 32761 8192000000 10123163280 10561511424 1114685200 11.977 97 33 66 1 1197762816 75981280 7096336
10:10:47 42932 8192000000 10034585856 10470100992 1069182224 11.544 94 34 66 1 1106639088 30147760 7309104
10:12:32 23887 8192000000 10095215488 10525851648 1110928896 11.941 99 49 96 1 1192273872 71082048 10262928
10:14:00 16932 8192000000 10122130688 10547040256 1108430160 11.918 99 45 90 0 1204053584 86354336 9269088
10:15:23 60332 8192000000 10099593664 10566807552 1098774160 11.826 98 40 79 0 1202356848 95450560 8132128
10:17:31 22955 8192000000 10097445616 10530852864 1116842288 11.997 99 41 78 3 1186624496 61363600 8418608
10:18:57 41906 8192000000 10098098976 10581868544 1135617792 12.174 100 37 74 0 1217279424 74189856 7471776
10:20:43 49159 8192000000 10104780640 10520829952 1123700848 12.062 99 81 160 1 1190700656 49497472 17502336
10:22:35 19569 8192000000 10084320560 10512920576 1130204304 12.123 100 64 128 0 1186995808 43382400 13409104
10:23:58 70737 8192000000 10066711360 10516217856 1130274752 12.124 99 14 28 0 1177829744 44424608 3130384
10:26:09 93641 8192000000 10142671536 10623389696 1127609712 12.099 98 9 18 0 1224904464 95014752 2280000
10:28:51 72891 8192000000 10150987072 10603216896 1150587040 12.315 100 29 56 0 1232319680 75280464 6452176
10:31:10 63838 8192000000 10102158672 10568523776 1146025376 12.272 99 12 24 1 1231193488 82476352 2691760
10:33:19 42932 8192000000 10090629824 10561650688 1126271600 12.086 98 35 66 1 1176477344 43334816 6870928
10:35:11 12253 8192000000 10113566624 10528899072 1136336320 12.181 100 65 125 0 1195522816 46462720 12723776
10:36:23 21484 8192000000 10131541264 10579881984 1126672400 12.090 99 46 90 0 1208638240 73274352 8691488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-04-2022 10:38:23 to 09-04-2022 10:38:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-04-2022 10:38:40 to 09-04-2022 10:38:42.

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          210140
MON$OLDEST_ACTIVE               214543
MON$OLDEST_SNAPSHOT             214543
MON$NEXT_TRANSACTION            214543
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                       2058784
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.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Apr  9 10:37:14 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Apr  9 10:37:24 2022
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sat Apr  9 10:37:24 2022
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
--- end of diff output ---

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

09.04.22 10:38:43. 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-04-09 10:38:46 No severe PSQL-related problems occured
09.04.22 10:38:47. End of report.