Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.01.2023 04: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 14974676 kB
MemAvailable 30372448 kB
Buffers 20 kB
Cached 16647480 kB
SwapCached 1756 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 2023-01-20 04:37:24
DTS_END                         2023-01-20 04:37:24.7940
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/58690
TRN_ID                          222192
ATT_ID                          596
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: 9123.65 22888 1094838
customer order: creation 173.18 466 20782
customer order: refuse 14.68 369 1762
order to supplier: creation 44.99 1402 5399
order to supplier: removal 3.61 156677 433
invoice (draft): creation 45.95 3201 5514
invoice (draft): removal 51.21 1003 6145
invoice accept: apply 42.30 25923 5076
invoice accept: cancel 45.75 1072 5490
customer reserve: creation 3890.18 252 466821
customer reserve: removal 4519.13 98 542296
realization accept: apply 83.57 265 10028
realization accept: cancel 78.33 172 9399
payment from customer: creation 59.27 167 7112
payment from customer: removal 5.46 122 655
payment to supplier: creation 49.63 185 5955
payment to supplier: removal 3.73 109 448
service: total inventory turnovers 3.87 3774 464
service: total monetary turnovers 7.78 383 933
service: aggregate perf. data 1.01 12695 121
service: refresh index statistics 0.04 249417 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-01-2023 04:38:11 to 20-01-2023 04:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4714
TEST_TIME 2 4634
TEST_TIME 3 5091
TEST_TIME 4 5592
TEST_TIME 5 6069
TEST_TIME 6 6119
TEST_TIME 7 6005
TEST_TIME 8 5973
TEST_TIME 9 6356
TEST_TIME 10 6362
TEST_TIME 11 6607
TEST_TIME 12 6775
TEST_TIME 13 6888
TEST_TIME 14 7053
TEST_TIME 15 7199
TEST_TIME 16 7405
TEST_TIME 17 7364
TEST_TIME 18 7311
TEST_TIME 19 7661
TEST_TIME 20 7635
TEST_TIME 21 7903
TEST_TIME 22 8021
TEST_TIME 23 7829
TEST_TIME 24 7964
TEST_TIME 25 8441
TEST_TIME 26 8730
TEST_TIME 27 8692
TEST_TIME 28 8972
TEST_TIME 29 9627
TEST_TIME 30 10031
TEST_TIME 31 10208
TEST_TIME 32 10822
TEST_TIME 33 10623
TEST_TIME 34 10910
TEST_TIME 35 10803
TEST_TIME 36 10762
TEST_TIME 37 10742
TEST_TIME 38 10706
TEST_TIME 39 10675
TEST_TIME 40 10581
TEST_TIME 41 10484
TEST_TIME 42 10349
TEST_TIME 43 10169
TEST_TIME 44 10027
TEST_TIME 45 10223
TEST_TIME 46 10297
TEST_TIME 47 10086
TEST_TIME 48 9899
TEST_TIME 49 9796
TEST_TIME 50 9747
TEST_TIME 51 9647
TEST_TIME 52 9605
TEST_TIME 53 9579
TEST_TIME 54 9591
TEST_TIME 55 9613
TEST_TIME 56 9622
TEST_TIME 57 9561
TEST_TIME 58 9669
TEST_TIME 59 9553
TEST_TIME 60 9491
TEST_TIME 61 9447
TEST_TIME 62 9498
TEST_TIME 63 9435
TEST_TIME 64 9403
TEST_TIME 65 9401
TEST_TIME 66 9427
TEST_TIME 67 9450
TEST_TIME 68 9404
TEST_TIME 69 9424
TEST_TIME 70 9449
TEST_TIME 71 9380
TEST_TIME 72 9366
TEST_TIME 73 9385
TEST_TIME 74 9507
TEST_TIME 75 9542
TEST_TIME 76 9515
TEST_TIME 77 9510
TEST_TIME 78 9474
TEST_TIME 79 9369
TEST_TIME 80 9389
TEST_TIME 81 9334
TEST_TIME 82 9351
TEST_TIME 83 9300
TEST_TIME 84 9243
TEST_TIME 85 9202
TEST_TIME 86 9116
TEST_TIME 87 9104
TEST_TIME 88 9059
TEST_TIME 89 9140
TEST_TIME 90 9179
TEST_TIME 91 9128
TEST_TIME 92 9165
TEST_TIME 93 9121
TEST_TIME 94 9158
TEST_TIME 95 9230
TEST_TIME 96 9215
TEST_TIME 97 9216
TEST_TIME 98 9242
TEST_TIME 99 9233
TEST_TIME 100 9270
TEST_TIME 101 9237
TEST_TIME 102 9182
TEST_TIME 103 9163
TEST_TIME 104 9169
TEST_TIME 105 9115
TEST_TIME 106 9092
TEST_TIME 107 9053
TEST_TIME 108 9024
TEST_TIME 109 8970
TEST_TIME 110 8960
TEST_TIME 111 8948
TEST_TIME 112 8933
TEST_TIME 113 9013
TEST_TIME 114 9012
TEST_TIME 115 9002
TEST_TIME 116 9039
TEST_TIME 117 9080
TEST_TIME 118 9052
TEST_TIME 119 9166
TEST_TIME 120 9139

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-01-2023 04:38:15 to 20-01-2023 04:38:18.

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 1526379 1526379 0 0.00 0 13904 13 0 0
doc_list_biud 1526373 1526373 0 0.00 0 25389 41 0 0
sp_add_invoice_to_stock 5200 5076 124 2.38 27 201500 25924 0 124
sp_cancel_adding_invoice 5490 5490 0 0.00 5 58945 1073 0 0
sp_cancel_client_order 1762 1762 0 0.00 5 15173 370 0 0
sp_cancel_customer_reserve 542296 542296 0 0.00 1 25392 98 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 6647 123 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 2770 109 0 0
sp_cancel_supplier_invoice 6156 6145 11 0.17 2 18585 1003 0 11
sp_cancel_supplier_order 433 433 0 0.00 20 1465721 156677 0 0
sp_cancel_write_off 9399 9399 0 0.00 2 9977 173 0 0
sp_client_order 20782 20782 0 0.00 6 33425 467 0 0
sp_customer_reserve 466821 466821 0 0.00 1 63594 252 0 0
sp_fill_shopping_cart 31678 31678 0 0.00 1 13459 77 0 0
sp_fill_shopping_cart_clo_res 466802 466802 0 0.00 0 23377 18 0 0
sp_get_clo_for_invoice 5054 5054 0 0.00 0 15618 238 0 0
sp_kill_cost_storno 15992 15992 0 0.00 0 12728 74 0 0
sp_kill_qstorno_ret_qs2qd 1100569 1100569 0 0.00 0 25383 44 0 0
sp_kill_qty_storno 580606 580606 0 0.00 0 25389 99 0 0
sp_lock_selected_doc 20838 20838 0 0.00 0 8596 7 0 0
sp_make_cost_storno 28148 28148 0 0.00 0 12203 75 0 0
sp_multiply_rows_for_pdistr 28148 28148 0 0.00 0 6165 35 0 0
sp_multiply_rows_for_qdistr 20782 20782 0 0.00 0 13804 187 0 0
sp_pay_from_customer 7112 7112 0 0.00 5 16714 168 0 0
sp_pay_to_supplier 5955 5955 0 0.00 6 10454 185 0 0
sp_payment_common 13067 13067 0 0.00 2 8686 42 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 15162 306 0 0
sp_qd_handle_on_invoice_upd_sts 10543 10543 0 0.00 0 18045 479 0 0
sp_qd_handle_on_reserve_upd_sts 10028 10028 0 0.00 0 21024 59 0 0
sp_reserve_write_off 10028 10028 0 0.00 8 21126 265 0 0
sp_supplier_invoice 5514 5514 0 0.00 11 91056 3201 0 0
sp_supplier_order 5399 5399 0 0.00 7 33449 1402 0 0
srv_aggregate_perf_data 121 121 0 0.00 2750 146731 12696 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 736 147533 33914 0 0
srv_find_qd_qs_mism 1046422 1046422 0 0.00 0 13901 10 0 0
srv_make_invnt_saldo 464 464 0 0.00 46 64821 3774 0 0
srv_make_money_saldo 933 933 0 0.00 1 19884 383 0 0
srv_recalc_idx_stat 5 5 0 0.00 137086 388550 249417 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5079 5079 0 0.00 1 16671 818 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 6681 365 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 6081 32 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 1364 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 976 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 4007 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 863 32 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 80397 5875 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 4742 27 0 0
t$perf-norm:sp_client_order 20782 20782 0 0.00 0 17059 35 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 1939 14 0 0
t$perf-norm:sp_pay_from_customer 7112 7112 0 0.00 0 4521 18 0 0
t$perf-norm:sp_pay_to_supplier 5955 5955 0 0.00 0 11321 23 0 0
t$perf-norm:sp_reserve_write_off 10028 10028 0 0.00 0 16841 25 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 7758 35 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 6280 32 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 2268 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 222 6 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 781 15 0 0
t$perf-norm:srv_make_money_saldo 933 933 0 0.00 0 1295 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 121 35 0 0
v_add_invoice_to_stock 10352 10104 248 2.39 0 4187 23 0 248
v_all_customers 38120 38120 0 0.00 0 160 3 0 0
v_all_suppliers 30564 30564 0 0.00 0 306 4 0 0
v_all_wares 41564 41564 0 0.00 0 156 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 701 14 0 0
v_cancel_client_order 3524 3524 0 0.00 0 1945 10 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 3910 16 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 1145 10 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 1712 12 0 0
v_cancel_supplier_invoice 874 852 22 2.51 0 1334 20 0 22
v_cancel_supplier_order 866 866 0 0.00 0 2005 18 0 0
v_cancel_write_off 1926 1926 0 0.00 0 3652 28 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 224 5 0 0
v_max_id_clo_res 987 987 0 0.00 0 1831 15 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 8667 10 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 119 8 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 2098 8 0 0
v_min_id_clo_ord 5397 5397 0 0.00 0 3079 8 0 0
v_min_id_clo_res 987 987 0 0.00 0 4435 15 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 7799 10 0 0
v_min_non_paid_invoice 5955 5955 0 0.00 0 3178 13 0 0
v_min_non_paid_realizn 7112 7112 0 0.00 0 5137 16 0 0
v_our_firm 10262 10262 0 0.00 0 93 4 0 0
v_reserve_write_off 20053 20053 0 0.00 0 7124 13 0 0
x_lock_depdocs_on_canc_invoice 11209 11209 0 0.00 0 17323 136 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 5546 295 0 0
x_make_qstorno_1000_1200 5399 5399 0 0.00 3 33440 1252 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 6 91024 2984 0 0
x_make_qstorno_2100_3300 466820 466820 0 0.00 0 59713 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-01-2023 04:38:19 to 20-01-2023 04:38:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:28 4 8192000000 8798431376 8809533440 4473472 0.054 2 1 2 0 4572864 63616 35776
02:08:29 14726 8192000000 9717406992 10015252480 800437360 8.901 100 84 166 1 827848928 7036624 20374944
02:09:52 26506 8192000000 9769828656 10076180480 839265552 9.292 100 89 178 1 872248272 11901904 21080816
02:11:25 11706 8192000000 9796028608 10114772992 862599888 9.526 100 82 164 0 907127840 25867920 18660032
02:12:44 6294 8192000000 9852354944 10185797632 919319664 10.089 100 79 157 0 970224096 34336896 16567536
02:13:56 3921 8192000000 9921374448 10263773184 959764496 10.487 100 88 174 0 1030738144 53028512 17945136
02:15:03 1760 8192000000 9977753248 10329661440 990766912 10.789 100 92 180 0 1091057712 82437808 17852992
02:16:07 1457 8192000000 9990678080 10349076480 1015388080 11.027 100 92 181 2 1107286464 73762000 18136384
02:17:10 1431 8192000000 10024581840 10394898432 1037577984 11.241 100 95 178 1 1150553856 94806352 18169520
02:18:13 1086 8192000000 10043838336 10416533504 1057288688 11.431 100 94 177 0 1160966800 87092256 16585856
02:19:15 7622 8192000000 10033139504 10419720192 1062406944 11.480 100 71 138 1 1164014448 88172400 13435104
02:20:24 3080 8192000000 10028570720 10407247872 1068969328 11.542 100 74 141 1 1146908000 63059824 14878848
02:21:28 8114 8192000000 10026901872 10421809152 1073006752 11.581 100 60 109 2 1143069920 59186336 10876832
02:22:39 4862 8192000000 10031238144 10419683328 1083697856 11.683 100 67 128 1 1152783248 56720720 12364672
02:23:44 6254 8192000000 10038288256 10424266752 1085075984 11.696 100 74 143 0 1164896976 65399328 14421664
02:24:52 1134 8192000000 10056239616 10443948032 1088224672 11.726 100 92 180 1 1180588976 74215504 18148800
02:25:54 975 8192000000 10042860752 10431275008 1091783856 11.760 100 95 184 0 1165522080 55192592 18545632
02:26:57 3220 8192000000 10028510800 10421972992 1089539904 11.738 100 68 124 3 1152929168 50715344 12673920
02:28:01 2303 8192000000 10017776368 10404831232 1081930192 11.666 99 76 141 2 1139060400 41586848 15543360
02:29:04 1062 8192000000 10029107984 10411487232 1084389312 11.689 100 91 175 1 1148178080 46113744 17675024
02:30:06 1068 8192000000 10018765024 10400059392 1068527456 11.538 99 96 189 1 1140333536 52147040 19659040
02:31:08 1047 8192000000 10031646448 10416078848 1071547568 11.567 100 91 175 0 1153694496 63542912 18604016
02:32:10 1430 8192000000 9998801440 10371416064 1036317872 11.229 99 95 185 2 1118453968 63141984 18994112
02:33:12 1021 8192000000 9998221344 10380185600 1053124032 11.391 100 87 151 6 1118992576 51054992 14813552
02:34:15 1105 8192000000 9998069440 10373447680 1043250736 11.296 99 85 168 2 1119724304 59894864 16578704
02:35:17 1036 8192000000 10010546624 10382229504 1041283472 11.277 100 91 174 1 1132659856 73688640 17687744
02:36:18 977 8192000000 9982922848 10349834240 1018082416 11.053 100 90 166 1 1104070912 69282736 16705760
02:37:20 6316 8192000000 9987347184 10371588096 1040155888 11.266 99 63 119 1 1106008992 52431904 13421200
02:38:37 21778 8192000000 10018156080 10409406464 1077525104 11.624 100 54 103 1 1134693456 46989456 10178896
02:40:06 3032 8192000000 10054071728 10439614464 1083381728 11.680 99 83 160 1 1175548720 75166064 17000928
02:41:12 4710 8192000000 10093302896 10486845440 1099973808 11.837 100 81 149 0 1210783584 95699744 15110032
02:42:20 1244 8192000000 10102244896 10492428288 1117350624 12.002 100 82 160 0 1221742144 88377584 16013936
02:43:26 5163 8192000000 10143375520 10544009216 1127568864 12.098 100 80 160 0 1259383664 114490320 17324480
02:44:36 1130 8192000000 10149234896 10547044352 1144641824 12.259 100 66 120 2 1261181040 103933744 12605472
02:45:41 1023 8192000000 10200645856 10598191104 1153028464 12.338 100 91 172 1 1315486832 142879280 19579088
02:46:46 1080 8192000000 10252308288 10659131392 1168458720 12.482 100 93 179 1 1364501968 175467792 20575456
02:47:49 2880 8192000000 10295256736 10695110656 1175326048 12.547 100 93 173 2 1407535376 212652016 19557312
02:48:55 1338 8192000000 10298910432 10703831040 1178471520 12.576 100 81 159 0 1409814336 213074560 18268256
02:50:01 1965 8192000000 10336395376 10744410112 1180682384 12.597 100 90 178 0 1445603792 243064944 21856464
02:51:06 860 8192000000 10372723008 10784329728 1185552352 12.642 100 97 186 0 1486251344 278923648 21775344
02:52:09 1125 8192000000 10404573088 10821767168 1190036848 12.684 100 95 183 1 1513292560 301966752 21288960
02:53:13 2025 8192000000 10430006112 10851581952 1189532592 12.679 100 82 162 0 1536512848 328103952 18876304
02:54:17 1820 8192000000 10455132016 10870501376 1192551792 12.707 100 82 162 0 1559857024 347786096 19519136
02:55:23 2477 8192000000 10497328672 10925236224 1203786352 12.811 100 89 176 0 1604042192 379371152 20884688
02:56:31 746 8192000000 10477467520 10900643840 1186128624 12.647 99 97 184 1 1584135936 375680912 22326400
02:57:34 2747 8192000000 10533434256 10978594816 1202133104 12.796 100 70 139 0 1657480144 437917552 17429488
02:58:50 4868 8192000000 10527534208 10961928192 1198179968 12.759 100 63 121 0 1631334192 416461712 16692512
03:00:09 3820 8192000000 10552462288 10977726464 1192613840 12.708 100 70 139 1 1659909632 449398320 17897472
03:01:17 5410 8192000000 10578633616 11047317504 1170926896 12.505 98 61 120 0 1672237872 484544128 16766848
03:02:41 10847 8192000000 10521029504 10986692608 1193768560 12.718 100 43 82 1 1627783040 422521584 11492896
03:04:02 15373 8192000000 10508737088 10971156480 1179715104 12.588 100 37 74 0 1621624672 432461632 9447936
03:05:24 18025 8192000000 10460105184 10955116544 1156879264 12.374 100 34 68 0 1595273904 429236480 9158160
03:06:43 12486 8192000000 10322271232 10834706432 1136247200 12.180 99 37 74 1 1447243792 301824304 9172288
03:08:12 14199 8192000000 10225494368 10735026176 1138769728 12.204 99 37 74 0 1346541728 199144720 8627280
03:09:32 40198 8192000000 10114270304 10551803904 1131456304 12.135 98 31 58 3 1225624240 87354480 6813456
03:11:14 57259 8192000000 10079361440 10608066560 1116981552 11.998 98 13 26 1 1212834624 93081888 2771184
03:13:12 26777 8192000000 10115515216 10559819776 1141655632 12.231 100 32 64 0 1200587248 52280560 6651056
03:14:55 117930 8192000000 10052883808 10512113664 1084129680 11.687 96 14 22 1 1121696672 35676560 1890432
03:18:02 57856 8192000000 10086852944 10540130304 1059690576 11.454 94 12 20 1 1102364224 40292896 2380752
03:20:04 44879 8192000000 10124844544 10552131584 1123025120 12.056 100 55 100 1 1220292288 86211328 11055840
03:21:54 15169 8192000000 10177736192 10620157952 1128204576 12.104 100 38 76 1 1272401136 134164736 10031824
03:23:12 147532 8192000000 10055669216 10483605504 1108214048 11.916 98 23 42 1 1131864176 18623728 5026400
03:26:41 96358 8192000000 10105065648 10537910272 1117513504 12.003 99 41 77 1 1191618048 65926288 8178256
03:29:55 24852 8192000000 10112289008 10562801664 1136097568 12.179 100 34 69 1 1215519392 71509344 7912480
03:31:23 17676 8192000000 10136995600 10581118976 1122568496 12.051 99 47 92 2 1221274176 87965328 10740352
03:32:44 46265 8192000000 10126503168 10616467456 1121960448 12.046 99 39 76 0 1241290544 110527952 8802144
03:34:34 27148 8192000000 10130689856 10596454400 1120523984 12.032 100 15 30 0 1217949360 94021120 3404256
03:36:03 86070 8192000000 10088232672 10557718528 1113647728 11.967 98 22 45 0 1167965008 49180976 5136304
03:38:29 73890 8192000000 10074873120 10606002176 1116955872 11.998 99 15 28 0 1216055936 96170240 2929824
03:40:47 46867 8192000000 10050976192 10513203200 1103318608 11.869 97 18 36 0 1140966960 34177168 3471184
03:42:35 43388 8192000000 10101496272 10564419584 1141195264 12.227 100 17 34 0 1188604496 44058720 3350512
03:44:21 46503 8192000000 10081432816 10530869248 1109824880 11.931 97 25 49 0 1141613328 27103312 4685136
03:46:09 22256 8192000000 10086880896 10517749760 1121555072 12.042 99 38 76 1 1180845536 51458912 7831552
03:48:31 8671 8192000000 10130732656 10552598528 1141797680 12.232 100 59 116 1 1210696240 57277840 11620720
03:49:43 59780 8192000000 10137029168 10591928320 1115566704 11.985 98 23 46 0 1234606128 113992464 5046960
03:51:53 20535 8192000000 10114125344 10554941440 1137340960 12.191 100 34 68 0 1187380528 43322800 6716768
03:53:20 9161 8192000000 10084199984 10502299648 1128250496 12.105 100 61 117 1 1166613008 26890080 11472432
03:54:29 23042 8192000000 10105089616 10523234304 1094978608 11.790 97 36 70 0 1169469360 67847936 6642816
03:55:57 79135 8192000000 10095567792 10530037760 1093236752 11.773 97 39 76 3 1175407264 73804480 8366032
03:58:42 728 8192000000 10105004112 10516099072 1127945584 12.102 100 98 184 4 1197527424 49602320 19979520
03:59:43 67269 8192000000 10045312240 10517278720 1124312016 12.068 100 12 22 0 1168192704 41723424 2157264
04:01:51 51094 8192000000 10090898752 10538602496 1116393856 11.993 98 26 53 0 1181039856 59012752 5633248
04:03:45 77890 8192000000 10130128032 10603053056 1126279552 12.086 99 19 38 0 1238350256 107910608 4160096
04:06:03 84876 8192000000 10092579424 10529546240 1102713200 11.863 97 13 26 0 1176754336 71148880 2892256
04:08:37 19555 8192000000 10110921648 10541977600 1124952464 12.074 100 53 103 0 1200926768 64891984 11082320
04:09:57 97866 8192000000 10147154016 10605498368 1123368112 12.059 98 27 54 0 1232193184 103764320 5060752
04:12:50 85084 8192000000 10087802144 10544943104 1127627392 12.099 98 22 45 0 1169998032 37564944 4805696
04:15:16 31229 8192000000 10119395568 10582573056 1112898736 11.960 97 41 82 0 1190680864 69833088 7949040
04:16:53 15874 8192000000 10095631584 10554064896 1126088656 12.084 98 45 90 1 1207803248 72693008 9021584
04:18:14 10232 8192000000 10138444080 10559356928 1151805280 12.326 100 56 111 0 1216877616 53593360 11478976
04:19:26 71880 8192000000 10125960512 10584223744 1151655408 12.325 100 53 98 2 1240931312 79169712 10106192
04:21:39 35138 8192000000 10077071920 10543423488 1131007056 12.131 98 17 26 0 1164975088 31197744 2770288
04:23:21 102910 8192000000 10104784976 10566758400 1141339216 12.228 99 15 30 0 1191599680 47121968 3138496
04:26:04 62798 8192000000 10130122496 10583257088 1124800464 12.072 97 10 20 0 1204719744 77889504 2029776
04:28:11 20188 8192000000 10180045568 10630537216 1144319968 12.256 99 30 58 1 1264330432 113565824 6444640
04:29:40 70489 8192000000 10155304160 10637205504 1137090048 12.188 99 29 58 0 1265282800 121107648 7085104
04:31:54 20554 8192000000 10189324992 10629640192 1162888800 12.430 100 45 87 0 1277150016 105056752 9204464
04:33:21 67439 8192000000 10109232032 10591637504 1111534864 11.947 96 21 40 0 1234905952 118738464 4632624
04:35:37 60784 8192000000 10171535776 10658447360 1125166384 12.076 98 34 68 0 1250438432 118179648 7092400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 20-01-2023 04:38:25 to 20-01-2023 04:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-01-2023 04:38:43 to 20-01-2023 04:38:45.

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          222546
MON$OLDEST_ACTIVE               222547
MON$OLDEST_SNAPSHOT             222547
MON$NEXT_TRANSACTION            222547
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                       2065312
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.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:883: 
:884: mscs-test-161.budzdorov.ru    Fri Jan 20 04:37:42 2023
:885:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:886: 
--- end of diff output ---

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

20.01.23 04:38:46. 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
2023-01-20 04:38:49 No severe PSQL-related problems occured
20.01.23 04:38:50. End of report.