Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15272340 kB
MemAvailable 30558004 kB
Buffers 0 kB
Cached 16408212 kB
SwapCached 2028 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.3
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-09-28 10:37:20
DTS_END                         2022-09-28 10:37:20.4380
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/59752
TRN_ID                          223892
ATT_ID                          655
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: 8926.80 22562 1071216
customer order: creation 173.73 517 20848
customer order: refuse 15.13 406 1815
order to supplier: creation 45.41 1432 5449
order to supplier: removal 3.48 166586 418
invoice (draft): creation 45.42 3252 5450
invoice (draft): removal 50.01 1096 6001
invoice accept: apply 41.25 26573 4950
invoice accept: cancel 45.18 1307 5421
customer reserve: creation 3814.60 254 457752
customer reserve: removal 4396.46 103 527575
realization accept: apply 83.06 282 9967
realization accept: cancel 82.83 163 9940
payment from customer: creation 59.15 175 7098
payment from customer: removal 5.68 121 681
payment to supplier: creation 49.08 200 5889
payment to supplier: removal 3.43 108 412
service: total inventory turnovers 4.08 3923 490
service: total monetary turnovers 7.78 348 934
service: aggregate perf. data 1.02 15557 122
service: refresh index statistics 0.03 228829 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-09-2022 10:38:37 to 28-09-2022 10:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9287
TEST_TIME 2 7038
TEST_TIME 3 6433
TEST_TIME 4 6201
TEST_TIME 5 6081
TEST_TIME 6 6421
TEST_TIME 7 6056
TEST_TIME 8 6212
TEST_TIME 9 6172
TEST_TIME 10 6394
TEST_TIME 11 6665
TEST_TIME 12 6958
TEST_TIME 13 7052
TEST_TIME 14 7043
TEST_TIME 15 7095
TEST_TIME 16 7156
TEST_TIME 17 7359
TEST_TIME 18 7496
TEST_TIME 19 7832
TEST_TIME 20 7773
TEST_TIME 21 7835
TEST_TIME 22 7748
TEST_TIME 23 7798
TEST_TIME 24 7895
TEST_TIME 25 7805
TEST_TIME 26 8117
TEST_TIME 27 8538
TEST_TIME 28 8805
TEST_TIME 29 9112
TEST_TIME 30 9504
TEST_TIME 31 9542
TEST_TIME 32 9715
TEST_TIME 33 10224
TEST_TIME 34 10999
TEST_TIME 35 10923
TEST_TIME 36 10814
TEST_TIME 37 10645
TEST_TIME 38 10588
TEST_TIME 39 10449
TEST_TIME 40 10321
TEST_TIME 41 10235
TEST_TIME 42 10058
TEST_TIME 43 9907
TEST_TIME 44 9870
TEST_TIME 45 9754
TEST_TIME 46 9711
TEST_TIME 47 9783
TEST_TIME 48 9744
TEST_TIME 49 9881
TEST_TIME 50 9924
TEST_TIME 51 9770
TEST_TIME 52 9638
TEST_TIME 53 9655
TEST_TIME 54 9561
TEST_TIME 55 9637
TEST_TIME 56 9650
TEST_TIME 57 9616
TEST_TIME 58 9634
TEST_TIME 59 9581
TEST_TIME 60 9682
TEST_TIME 61 9664
TEST_TIME 62 9716
TEST_TIME 63 9691
TEST_TIME 64 9715
TEST_TIME 65 9692
TEST_TIME 66 9749
TEST_TIME 67 9733
TEST_TIME 68 9653
TEST_TIME 69 9572
TEST_TIME 70 9558
TEST_TIME 71 9491
TEST_TIME 72 9434
TEST_TIME 73 9437
TEST_TIME 74 9382
TEST_TIME 75 9392
TEST_TIME 76 9393
TEST_TIME 77 9348
TEST_TIME 78 9351
TEST_TIME 79 9310
TEST_TIME 80 9346
TEST_TIME 81 9296
TEST_TIME 82 9291
TEST_TIME 83 9362
TEST_TIME 84 9344
TEST_TIME 85 9425
TEST_TIME 86 9397
TEST_TIME 87 9430
TEST_TIME 88 9480
TEST_TIME 89 9463
TEST_TIME 90 9403
TEST_TIME 91 9331
TEST_TIME 92 9323
TEST_TIME 93 9295
TEST_TIME 94 9246
TEST_TIME 95 9198
TEST_TIME 96 9172
TEST_TIME 97 9143
TEST_TIME 98 9145
TEST_TIME 99 9169
TEST_TIME 100 9158
TEST_TIME 101 9145
TEST_TIME 102 9138
TEST_TIME 103 9177
TEST_TIME 104 9150
TEST_TIME 105 9170
TEST_TIME 106 9203
TEST_TIME 107 9227
TEST_TIME 108 9192
TEST_TIME 109 9202
TEST_TIME 110 9144
TEST_TIME 111 9128
TEST_TIME 112 9118
TEST_TIME 113 9088
TEST_TIME 114 9047
TEST_TIME 115 9018
TEST_TIME 116 9017
TEST_TIME 117 8970
TEST_TIME 118 8961
TEST_TIME 119 8970
TEST_TIME 120 8941

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2022 10:38:41 to 28-09-2022 10:38:44.

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 1493959 1493959 0 0.00 0 14925 14 0 0
doc_list_biud 1493957 1493957 0 0.00 0 33112 43 0 0
sp_add_invoice_to_stock 5043 4950 93 1.84 47 254995 26573 0 93
sp_cancel_adding_invoice 5421 5421 0 0.00 4 83417 1307 0 0
sp_cancel_client_order 1815 1815 0 0.00 6 21740 406 0 0
sp_cancel_customer_reserve 527575 527575 0 0.00 1 25349 103 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 4 8092 121 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 1924 108 0 0
sp_cancel_supplier_invoice 6007 6001 6 0.09 2 28114 1097 0 6
sp_cancel_supplier_order 418 418 0 0.00 11 1445633 166587 0 0
sp_cancel_write_off 9940 9940 0 0.00 2 12678 163 0 0
sp_client_order 20848 20848 0 0.00 5 21856 517 0 0
sp_customer_reserve 457752 457752 0 0.00 0 71667 255 0 0
sp_fill_shopping_cart 31726 31726 0 0.00 1 17851 83 0 0
sp_fill_shopping_cart_clo_res 457733 457733 0 0.00 0 17629 19 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 35288 321 0 0
sp_kill_cost_storno 16454 16454 0 0.00 0 12668 72 0 0
sp_kill_qstorno_ret_qs2qd 1071509 1071509 0 0.00 0 28069 45 0 0
sp_kill_qty_storno 566066 566066 0 0.00 0 33111 104 0 0
sp_lock_selected_doc 20687 20687 0 0.00 0 12361 7 0 0
sp_make_cost_storno 27883 27883 0 0.00 0 14817 81 0 0
sp_multiply_rows_for_pdistr 27883 27883 0 0.00 0 10977 39 0 0
sp_multiply_rows_for_qdistr 20848 20848 0 0.00 0 16809 213 0 0
sp_pay_from_customer 7098 7098 0 0.00 5 13651 175 0 0
sp_pay_to_supplier 5889 5889 0 0.00 6 13623 200 0 0
sp_payment_common 12987 12987 0 0.00 2 13640 48 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 1 21720 339 0 0
sp_qd_handle_on_invoice_upd_sts 10350 10350 0 0.00 1 33110 543 0 0
sp_qd_handle_on_reserve_upd_sts 9967 9967 0 0.00 0 25125 69 0 0
sp_reserve_write_off 9967 9967 0 0.00 8 25211 283 0 0
sp_supplier_invoice 5450 5450 0 0.00 8 101261 3253 0 0
sp_supplier_order 5449 5449 0 0.00 8 38747 1432 0 0
srv_aggregate_perf_data 122 122 0 0.00 2490 146388 15558 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 755 125122 35041 0 0
srv_find_qd_qs_mism 1022989 1022989 0 0.00 0 12870 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 85 70376 3924 0 0
srv_make_money_saldo 934 934 0 0.00 1 16023 348 0 0
srv_recalc_idx_stat 4 4 0 0.00 63964 392234 228830 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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 4953 4953 0 0.00 0 11193 920 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 8515 493 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 2894 29 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 856 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 255 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1187 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1200 33 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 92924 6667 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 637 16 0 0
t$perf-norm:sp_client_order 20849 20849 0 0.00 0 13252 37 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 1614 16 0 0
t$perf-norm:sp_pay_from_customer 7098 7098 0 0.00 0 6472 18 0 0
t$perf-norm:sp_pay_to_supplier 5889 5889 0 0.00 0 10785 22 0 0
t$perf-norm:sp_reserve_write_off 9967 9967 0 0.00 0 10637 25 0 0
t$perf-norm:sp_supplier_invoice 5450 5450 0 0.00 0 2973 31 0 0
t$perf-norm:sp_supplier_order 5449 5449 0 0.00 0 3823 33 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1289 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 580 18 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 468 11 0 0
t$perf-norm:srv_make_money_saldo 934 934 0 0.00 0 999 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 183 65 0 0
v_add_invoice_to_stock 10032 9846 186 1.85 0 11814 24 0 186
v_all_customers 38172 38172 0 0.00 0 65 4 0 0
v_all_suppliers 30647 30647 0 0.00 0 116 4 0 0
v_all_wares 41696 41696 0 0.00 0 184 4 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1180 17 0 0
v_cancel_client_order 3630 3630 0 0.00 0 2372 11 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 921 11 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 1 1877 13 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1845 14 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 570 21 0 12
v_cancel_supplier_order 836 836 0 0.00 0 1949 12 0 0
v_cancel_write_off 1948 1948 0 0.00 0 450 22 0 0
v_max_id_clo_ord 5444 5444 0 0.00 0 2120 6 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2575 17 0 0
v_max_id_ord_sup 5434 5434 0 0.00 0 7638 8 0 0
v_max_non_paid_invoice 1443 1443 0 0.00 0 908 8 0 0
v_max_non_paid_realizn 3652 3652 0 0.00 0 3575 9 0 0
v_min_id_clo_ord 5444 5444 0 0.00 0 3802 9 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 11633 33 0 0
v_min_id_ord_sup 5434 5434 0 0.00 0 3818 7 0 0
v_min_non_paid_invoice 5888 5888 0 0.00 0 9397 19 0 0
v_min_non_paid_realizn 7098 7098 0 0.00 0 10060 17 0 0
v_our_firm 10416 10416 0 0.00 0 62 5 0 0
v_reserve_write_off 19934 19934 0 0.00 0 4191 10 0 0
x_lock_depdocs_on_canc_invoice 11032 11032 0 0.00 0 14963 128 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 3729 322 0 0
x_make_qstorno_1000_1200 5449 5449 0 0.00 2 38733 1267 0 0
x_make_qstorno_1200_2000 5450 5450 0 0.00 4 91436 3022 0 0
x_make_qstorno_2100_3300 457752 457752 0 0.00 0 71665 223 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 28-09-2022 10:38:45 to 28-09-2022 10:38:50.

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:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:25 22693 8192000000 9722870416 10019827712 799404432 8.890 100 88 176 0 828823872 7647552 21771888
08:09:52 15317 8192000000 9756716816 10062405632 831212240 9.211 100 89 178 0 864240960 11610704 21418016
08:11:09 9882 8192000000 9782848352 10097315840 854906624 9.449 100 82 164 0 891524784 17113712 19504448
08:12:29 9348 8192000000 9855362896 10189283328 915672544 10.053 100 84 168 0 973139408 38862176 18604688
08:13:42 4694 8192000000 9909214624 10253209600 958904064 10.478 100 83 164 1 1029463408 53605904 16953440
08:14:51 2442 8192000000 9972351776 10325213184 993900144 10.819 100 90 173 0 1082056000 70166992 17988864
08:15:55 1520 8192000000 9986201568 10351525888 1021537184 11.087 100 97 188 0 1110486160 69563648 19385328
08:16:58 1285 8192000000 10015130736 10380664832 1038131824 11.247 100 94 180 1 1128916544 72684496 18100224
08:18:01 963 8192000000 10015012832 10392678400 1047408496 11.336 100 97 189 1 1138190032 73476336 17305200
08:19:04 1064 8192000000 10044528736 10422276096 1055977904 11.418 100 97 188 1 1166277472 91010192 19289376
08:20:06 1105 8192000000 10032814768 10413293568 1063173600 11.487 100 93 177 2 1155506880 73991344 18341936
08:21:08 1145 8192000000 10053219344 10441932800 1065102960 11.505 100 85 162 0 1171269968 89304192 16862816
08:22:10 1032 8192000000 10043314064 10430578688 1073094144 11.582 100 94 180 4 1167178880 75711216 18373520
08:23:12 7583 8192000000 10035754816 10420957184 1080460528 11.652 100 70 128 0 1148476576 55050512 12965536
08:24:24 1014 8192000000 10020276656 10410934272 1082010080 11.667 100 83 158 1 1141609216 43457376 16141760
08:25:25 914 8192000000 10037292128 10427715584 1086367360 11.708 100 90 175 1 1159435104 55191456 17876288
08:26:27 1947 8192000000 10028595040 10421989376 1088826480 11.731 100 68 131 0 1146130736 43232352 14071904
08:27:30 1014 8192000000 10046868992 10439299072 1087067840 11.715 100 96 181 1 1171530208 65302752 19159616
08:28:32 1106 8192000000 10032786736 10420645888 1087201040 11.716 100 98 187 3 1154001712 48408256 18392416
08:29:34 1224 8192000000 10042571712 10430050304 1089004160 11.733 100 93 174 1 1164090160 57820352 17265648
08:30:36 1275 8192000000 10018309808 10397495296 1060096768 11.457 100 98 183 0 1144570000 65898144 18575088
08:31:38 1021 8192000000 9988043552 10361544704 1035739232 11.224 100 87 154 2 1112636480 60983872 15913376
08:32:40 1102 8192000000 9992603408 10366443520 1033515840 11.202 100 90 170 2 1114660448 62745184 18399424
08:33:42 1142 8192000000 9982300560 10359234560 1027575616 11.145 100 94 175 2 1108221744 62968544 17677584
08:34:44 1044 8192000000 9999620448 10378174464 1046925472 11.331 100 87 165 1 1122337792 55966016 19446304
08:35:46 1028 8192000000 9983617824 10360647680 1032014688 11.188 99 90 168 2 1103653984 52919344 18719952
08:36:48 1334 8192000000 9984794032 10363445248 1037910080 11.245 100 80 155 3 1105658944 51761120 15987744
08:37:53 2047 8192000000 10007073344 10390061056 1073351952 11.584 100 66 130 2 1123518480 37768672 12397856
08:38:59 17663 8192000000 10047374064 10446688256 1091075552 11.753 100 66 129 0 1166758800 61655984 14027264
08:40:23 4390 8192000000 10053727360 10446393344 1104508368 11.880 100 68 133 0 1178820304 60620752 13691184
08:41:30 5982 8192000000 10085897232 10494341120 1109745600 11.930 100 83 161 1 1211900000 82078400 20076000
08:42:54 3015 8192000000 10098089472 10508316672 1123508016 12.060 100 48 94 0 1226036112 92709632 9818464
08:44:04 18718 8192000000 10123984800 10529890304 1138351440 12.200 100 61 120 1 1239197472 88164064 12681968
08:45:28 1998 8192000000 10169145072 10567217152 1150996256 12.319 100 76 148 4 1285332816 118761376 15575184
08:46:33 899 8192000000 10197261888 10593423360 1145668048 12.269 99 86 170 0 1307242880 143965600 17609232
08:47:37 797 8192000000 10228899440 10632810496 1164949408 12.450 100 97 186 1 1344393136 158765424 20678304
08:48:39 1237 8192000000 10249064032 10661060608 1162419568 12.426 100 96 184 0 1367764416 183681376 21663472
08:49:45 874 8192000000 10244376480 10654441472 1172354176 12.519 100 89 175 1 1361102128 167523680 21224272
08:50:48 924 8192000000 10296207904 10702311424 1177978240 12.571 100 93 175 1 1405390752 206489168 20923344
08:51:52 3857 8192000000 10321295136 10735489024 1178294112 12.574 100 82 162 0 1431888112 235036384 18557616
08:53:01 1698 8192000000 10349478256 10763595776 1184431616 12.632 100 83 154 3 1458129616 256314208 17383792
08:54:10 1194 8192000000 10390634464 10812022784 1186473424 12.651 100 98 188 0 1498789936 289578256 22738256
08:55:15 1906 8192000000 10386762832 10819948544 1179333056 12.584 100 97 192 2 1495583504 292678304 23572144
08:56:19 960 8192000000 10443753616 10879676416 1197709600 12.755 100 94 187 2 1551029728 329849920 23470208
08:57:22 1801 8192000000 10466615568 10870276096 1202820432 12.803 100 98 187 0 1572021168 347910640 21290096
08:58:27 744 8192000000 10514199776 10931453952 1198380320 12.761 100 99 191 0 1616992656 396880224 21732112
08:59:32 1215 8192000000 10592865344 11001081856 1206672448 12.838 100 92 182 0 1694796880 466539584 21584848
09:00:35 1010 8192000000 10602621312 11037413376 1202328736 12.798 100 96 187 2 1707839552 481680096 23830720
09:01:39 838 8192000000 10646933632 11069140992 1202738144 12.802 100 94 186 1 1750130640 524095440 23297056
09:02:53 1229 8192000000 10600718016 11071045632 1175277824 12.546 99 82 162 0 1701881872 506011440 20592608
09:03:59 3568 8192000000 10580666304 11055607808 1172809200 12.523 100 77 150 0 1683643632 491267296 19567136
09:05:08 8853 8192000000 10575899328 11040215040 1167641664 12.475 99 50 100 1 1670763216 489820992 13300560
09:06:26 13224 8192000000 10486218704 10968211456 1154709120 12.354 99 49 94 0 1578814208 412040160 12064928
09:07:44 25842 8192000000 10421600384 10913054720 1159320160 12.397 100 36 70 1 1518550080 350553968 8675952
09:09:31 28282 8192000000 10316116000 10827563008 1141824512 12.233 99 29 58 0 1443907456 295092704 6990240
09:11:00 28666 8192000000 10127969664 10644000768 1115674592 11.986 98 13 24 1 1243049744 124360000 3015152
09:12:39 16549 8192000000 10078050608 10512863232 1139589808 12.212 100 27 52 0 1171319680 26494496 5235376
09:13:56 45421 8192000000 10148303392 10588721152 1142205040 12.236 99 23 46 0 1226407968 79235568 4967360
09:16:06 30951 8192000000 10142651920 10584195072 1144398992 12.257 99 30 51 3 1234953696 84664176 5890528
09:17:38 60661 8192000000 10156949408 10600710144 1155961280 12.365 100 38 63 2 1237270272 74500176 6808816
09:19:40 43004 8192000000 10134431808 10587959296 1144662736 12.259 98 41 79 0 1212108368 60102320 7343312
09:21:26 49119 8192000000 10223922832 10672824320 1161658000 12.419 99 39 78 0 1295808688 125215968 8934720
09:23:34 38362 8192000000 10270322352 10731593728 1167176160 12.470 99 43 85 1 1362373104 184999488 10197456
09:25:24 58450 8192000000 10199885728 10691317760 1159817600 12.402 99 18 36 0 1327622800 163262736 4542464
09:27:22 43736 8192000000 10093182832 10579468288 1132619344 12.146 98 12 24 0 1203605440 68114400 2871696
09:29:25 54936 8192000000 10111344048 10585985024 1134324000 12.162 99 16 32 0 1216347056 78329264 3693792
09:32:26 54647 8192000000 10071994528 10553835520 1129913968 12.121 99 14 28 0 1181596592 48732384 2950240
09:34:22 43119 8192000000 10122513328 10568114176 1124253744 12.067 98 38 76 0 1202604816 70065696 8285376
09:36:06 41781 8192000000 10116758000 10561228800 1118208480 12.010 98 32 64 0 1179547920 55067584 6271856
09:37:50 100209 8192000000 10117615648 10596192256 1125118704 12.075 98 14 26 0 1170951024 42652496 3179824
09:40:33 56252 8192000000 10135348256 10583416832 1133890256 12.158 99 12 24 0 1191689792 55386224 2413312
09:42:36 52548 8192000000 10092318752 10547466240 1136162400 12.179 99 23 46 0 1190549552 49575664 4811488
09:44:29 34848 8192000000 10088657264 10523234304 1135779712 12.176 99 26 51 0 1166994112 26420448 4793952
09:46:05 101738 8192000000 10098291456 10545401856 1136950528 12.187 99 55 110 0 1224265920 76156528 11158864
09:48:54 20691 8192000000 10124716432 10562666496 1149192000 12.302 100 45 90 0 1215802096 56332304 10277792
09:50:16 85695 8192000000 10118131744 10582650880 1135625552 12.174 99 20 38 0 1222092016 82503760 3962704
09:52:43 69575 8192000000 10091407792 10576203776 1109015584 11.923 97 12 24 0 1181336032 69633984 2686464
09:54:57 75928 8192000000 10093142672 10547142656 1106615632 11.900 97 11 22 0 1183425296 74166256 2643408
09:57:16 56528 8192000000 10089128864 10546188288 1045745280 11.320 91 25 47 0 1109404912 58636480 5023152
10:00:05 38311 8192000000 10102685728 10575917056 1129026176 12.112 100 35 68 0 1236715920 100204656 7485088
10:01:52 26596 8192000000 10131374656 10578391040 1117666960 12.005 99 30 60 0 1211048512 87291744 6089808
10:03:22 48609 8192000000 10131778336 10571812864 1105167392 11.887 98 17 34 0 1192642816 83855248 3620176
10:05:16 54053 8192000000 10079948800 10513047552 1100804416 11.845 98 26 52 0 1174340704 68452800 5083488
10:07:15 98475 8192000000 10042333920 10460794880 1091531216 11.757 97 36 66 0 1116685392 18342048 6812128
10:09:58 55574 8192000000 10041380944 10507702272 1103509088 11.871 98 13 26 0 1154568624 48475840 2583696
10:11:55 37325 8192000000 10039095888 10514886656 1118789840 12.016 100 4 8 0 1159609968 40136256 683872
10:13:33 37129 8192000000 10091113072 10536759296 1130186704 12.123 99 34 64 1 1185795264 49421632 6186928
10:15:23 43110 8192000000 10096884416 10583011328 1139353808 12.209 100 27 57 0 1231588656 86034480 6200368
10:17:11 9521 8192000000 10081237200 10528591872 1143660432 12.250 100 59 111 1 1177791120 22528864 11601824
10:18:23 51252 8192000000 10102276352 10573320192 1138103184 12.198 99 39 76 1 1207221712 62211520 6907008
10:20:21 34770 8192000000 10081669648 10536767488 1115078208 11.980 98 42 82 1 1185451072 62203984 8168880
10:21:58 23569 8192000000 10119775568 10570977280 1126815104 12.091 99 38 76 1 1212690000 78144048 7730848
10:23:29 117221 8192000000 10108479552 10568073216 1097689840 11.816 97 15 30 0 1139226416 38832464 2704112
10:26:27 125122 8192000000 10063812064 10535444480 1137202176 12.189 100 27 54 0 1221176000 77453760 6520064
10:29:39 80232 8192000000 10077704768 10550685696 1110456912 11.937 98 22 42 0 1167212976 52263040 4493024
10:32:04 95505 8192000000 10052287872 10550992896 1120314736 12.030 99 14 28 0 1185360304 61884048 3161520
10:34:41 66249 8192000000 10110594288 10574893056 1114775632 11.978 98 23 42 1 1181249168 61645536 4828000
10:36:50 26773 8192000000 10092087680 10556465152 1124380944 12.068 98 27 53 0 1199473456 69600208 5492304

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-09-2022 10:38:52 to 28-09-2022 10:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 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 28-09-2022 10:39:09 to 28-09-2022 10:39:11.

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          219741
MON$OLDEST_ACTIVE               224075
MON$OLDEST_SNAPSHOT             224075
MON$NEXT_TRANSACTION            224075
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                       2063264
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:59: 
:60: mscs-test-161.budzdorov.ru    Wed Sep 28 10:37:30 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Wed Sep 28 10:37:30 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

28.09.22 10:39:12. 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-09-28 10:39:14 No severe PSQL-related problems occured
28.09.22 10:39:16. End of report.