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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14872624 kB
MemAvailable 30063152 kB
Buffers 0 kB
Cached 16809516 kB
SwapCached 3228 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-12-14 13:37:24
DTS_END                         2022-12-14 13:37:24.1030
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/35916
TRN_ID                          212866
ATT_ID                          630
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: 8802.67 23569 1056321
customer order: creation 159.52 489 19142
customer order: refuse 13.49 402 1619
order to supplier: creation 42.97 1440 5156
order to supplier: removal 3.23 158176 388
invoice (draft): creation 42.27 3337 5072
invoice (draft): removal 50.86 927 6103
invoice accept: apply 39.17 25840 4700
invoice accept: cancel 46.12 1087 5534
customer reserve: creation 3626.43 246 435172
customer reserve: removal 4496.02 88 539523
realization accept: apply 78.21 290 9385
realization accept: cancel 83.85 172 10062
payment from customer: creation 54.95 172 6594
payment from customer: removal 5.32 140 638
payment to supplier: creation 45.40 227 5448
payment to supplier: removal 3.22 160 386
service: total inventory turnovers 3.88 3663 466
service: total monetary turnovers 6.98 367 838
service: aggregate perf. data 0.76 19198 91
service: refresh index statistics 0.03 254967 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-12-2022 13:38:35 to 14-12-2022 13:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7590
TEST_TIME 2 6552
TEST_TIME 3 6530
TEST_TIME 4 6101
TEST_TIME 5 6034
TEST_TIME 6 6242
TEST_TIME 7 6843
TEST_TIME 8 7002
TEST_TIME 9 6821
TEST_TIME 10 6690
TEST_TIME 11 6971
TEST_TIME 12 7043
TEST_TIME 13 7134
TEST_TIME 14 7245
TEST_TIME 15 7249
TEST_TIME 16 7390
TEST_TIME 17 7321
TEST_TIME 18 7550
TEST_TIME 19 7625
TEST_TIME 20 7830
TEST_TIME 21 7933
TEST_TIME 22 8659
TEST_TIME 23 8477
TEST_TIME 24 8696
TEST_TIME 25 9234
TEST_TIME 26 9200
TEST_TIME 27 9196
TEST_TIME 28 9100
TEST_TIME 29 9484
TEST_TIME 30 10206
TEST_TIME 31 10602
TEST_TIME 32 10802
TEST_TIME 33 11146
TEST_TIME 34 10861
TEST_TIME 35 10739
TEST_TIME 36 10627
TEST_TIME 37 10520
TEST_TIME 38 10410
TEST_TIME 39 10358
TEST_TIME 40 10223
TEST_TIME 41 10219
TEST_TIME 42 10179
TEST_TIME 43 10033
TEST_TIME 44 9873
TEST_TIME 45 9883
TEST_TIME 46 9773
TEST_TIME 47 9731
TEST_TIME 48 9673
TEST_TIME 49 9512
TEST_TIME 50 9451
TEST_TIME 51 9358
TEST_TIME 52 9290
TEST_TIME 53 9300
TEST_TIME 54 9327
TEST_TIME 55 9317
TEST_TIME 56 9177
TEST_TIME 57 9166
TEST_TIME 58 9136
TEST_TIME 59 9064
TEST_TIME 60 9026
TEST_TIME 61 8978
TEST_TIME 62 9014
TEST_TIME 63 9232
TEST_TIME 64 9207
TEST_TIME 65 9247
TEST_TIME 66 9348
TEST_TIME 67 9355
TEST_TIME 68 9353
TEST_TIME 69 9303
TEST_TIME 70 9340
TEST_TIME 71 9465
TEST_TIME 72 9546
TEST_TIME 73 9429
TEST_TIME 74 9342
TEST_TIME 75 9330
TEST_TIME 76 9268
TEST_TIME 77 9225
TEST_TIME 78 9220
TEST_TIME 79 9200
TEST_TIME 80 9160
TEST_TIME 81 9216
TEST_TIME 82 9174
TEST_TIME 83 9105
TEST_TIME 84 9020
TEST_TIME 85 9073
TEST_TIME 86 9033
TEST_TIME 87 9003
TEST_TIME 88 8976
TEST_TIME 89 9031
TEST_TIME 90 9004
TEST_TIME 91 8978
TEST_TIME 92 8968
TEST_TIME 93 9085
TEST_TIME 94 9044
TEST_TIME 95 9012
TEST_TIME 96 9009
TEST_TIME 97 9106
TEST_TIME 98 9102
TEST_TIME 99 9125
TEST_TIME 100 9199
TEST_TIME 101 9196
TEST_TIME 102 9121
TEST_TIME 103 9064
TEST_TIME 104 9060
TEST_TIME 105 9042
TEST_TIME 106 8994
TEST_TIME 107 8946
TEST_TIME 108 8946
TEST_TIME 109 8948
TEST_TIME 110 8941
TEST_TIME 111 8903
TEST_TIME 112 8871
TEST_TIME 113 8834
TEST_TIME 114 8807
TEST_TIME 115 8824
TEST_TIME 116 8819
TEST_TIME 117 8797
TEST_TIME 118 8823
TEST_TIME 119 8823
TEST_TIME 120 8824

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2022 13:38:39 to 14-12-2022 13:38:42.

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 1457441 1457441 0 0.00 0 33752 13 0 0
doc_list_biud 1457427 1457427 0 0.00 0 66780 38 0 0
sp_add_invoice_to_stock 4776 4700 76 1.59 76 220298 25841 0 76
sp_cancel_adding_invoice 5534 5534 0 0.00 7 103528 1088 0 0
sp_cancel_client_order 1619 1619 0 0.00 5 8151 402 0 0
sp_cancel_customer_reserve 539523 539523 0 0.00 1 66784 89 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 4 4468 140 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 5353 161 0 0
sp_cancel_supplier_invoice 6108 6103 5 0.08 2 17170 927 0 5
sp_cancel_supplier_order 388 388 0 0.00 8 1296624 158176 0 0
sp_cancel_write_off 10062 10062 0 0.00 2 8298 173 0 0
sp_client_order 19142 19142 0 0.00 6 63256 489 0 0
sp_customer_reserve 435172 435172 0 0.00 1 44247 247 0 0
sp_fill_shopping_cart 29355 29355 0 0.00 1 11940 87 0 0
sp_fill_shopping_cart_clo_res 435148 435148 0 0.00 0 17140 20 0 0
sp_get_clo_for_invoice 4663 4663 0 0.00 0 24863 408 0 0
sp_kill_cost_storno 16620 16620 0 0.00 0 7696 72 0 0
sp_kill_qstorno_ret_qs2qd 1095599 1095599 0 0.00 0 66774 39 0 0
sp_kill_qty_storno 577273 577273 0 0.00 0 66779 89 0 0
sp_lock_selected_doc 19428 19428 0 0.00 0 5519 7 0 0
sp_make_cost_storno 26088 26088 0 0.00 0 33745 85 0 0
sp_multiply_rows_for_pdistr 26086 26086 0 0.00 0 11281 40 0 0
sp_multiply_rows_for_qdistr 19139 19139 0 0.00 0 63224 174 0 0
sp_pay_from_customer 6594 6594 0 0.00 5 12422 173 0 0
sp_pay_to_supplier 5448 5448 0 0.00 5 33758 228 0 0
sp_payment_common 12039 12039 0 0.00 2 9263 48 0 0
sp_qd_handle_on_cancel_clo 1619 1619 0 0.00 0 8142 318 0 0
sp_qd_handle_on_invoice_upd_sts 10193 10193 0 0.00 0 25584 475 0 0
sp_qd_handle_on_reserve_upd_sts 9385 9385 0 0.00 0 10904 64 0 0
sp_reserve_write_off 9385 9385 0 0.00 8 17126 290 0 0
sp_supplier_invoice 5072 5072 0 0.00 9 90124 3338 0 0
sp_supplier_order 5156 5156 0 0.00 7 49868 1441 0 0
srv_aggregate_perf_data 91 91 0 0.00 2135 139148 19199 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 801 128696 35453 0 0
srv_find_qd_qs_mism 1010837 1010837 0 0.00 0 18355 10 0 0
srv_make_invnt_saldo 466 466 0 0.00 50 80375 3663 0 0
srv_make_money_saldo 838 838 0 0.00 1 15745 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 39370 482582 254967 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4702 4702 0 0.00 1 11347 883 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 10882 460 0 0
t$perf-norm:sp_cancel_client_order 1619 1619 0 0.00 0 8658 32 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 2317 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 1478 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 491 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1631 35 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 93432 6654 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 705 16 0 0
t$perf-norm:sp_client_order 19142 19142 0 0.00 0 11199 38 0 0
t$perf-norm:sp_customer_reserve 892 892 0 0.00 0 1135 15 0 0
t$perf-norm:sp_pay_from_customer 6595 6595 0 0.00 0 9441 20 0 0
t$perf-norm:sp_pay_to_supplier 5448 5448 0 0.00 0 3955 19 0 0
t$perf-norm:sp_reserve_write_off 9385 9385 0 0.00 0 6649 22 0 0
t$perf-norm:sp_supplier_invoice 5072 5072 0 0.00 0 28505 46 0 0
t$perf-norm:sp_supplier_order 5156 5156 0 0.00 0 4932 30 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 205 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 5343 113 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 789 16 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 860 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 16 190 76 0 0
v_add_invoice_to_stock 9458 9306 152 1.60 0 16024 29 0 152
v_all_customers 35084 35084 0 0.00 0 122 4 0 0
v_all_suppliers 28522 28522 0 0.00 0 120 4 0 0
v_all_wares 38266 38266 0 0.00 0 68 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 535 14 0 0
v_cancel_client_order 3238 3238 0 0.00 0 6031 16 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 4396 25 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1780 13 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 3748 17 0 0
v_cancel_supplier_invoice 772 762 10 1.29 0 6781 41 0 10
v_cancel_supplier_order 776 776 0 0.00 0 1448 13 0 0
v_cancel_write_off 1918 1918 0 0.00 0 4604 30 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 1977 5 0 0
v_max_id_clo_res 892 892 0 0.00 0 1265 10 0 0
v_max_id_ord_sup 5065 5065 0 0.00 0 6010 7 0 0
v_max_non_paid_invoice 1405 1405 0 0.00 0 2898 10 0 0
v_max_non_paid_realizn 3413 3413 0 0.00 0 674 8 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 3956 12 0 0
v_min_id_clo_res 892 892 0 0.00 0 6948 26 0 0
v_min_id_ord_sup 5065 5065 0 0.00 0 7931 11 0 0
v_min_non_paid_invoice 5447 5447 0 0.00 0 7112 22 0 0
v_min_non_paid_realizn 6592 6592 0 0.00 0 8118 18 0 0
v_our_firm 9540 9540 0 0.00 0 66 5 0 0
v_reserve_write_off 18768 18768 0 0.00 0 16915 15 0 0
x_lock_depdocs_on_canc_invoice 11256 11256 0 0.00 0 11805 121 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 19482 392 0 0
x_make_qstorno_1000_1200 5156 5156 0 0.00 2 49848 1274 0 0
x_make_qstorno_1200_2000 5072 5072 0 0.00 4 89981 3095 0 0
x_make_qstorno_2100_3300 435172 435172 0 0.00 0 44239 216 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-12-2022 13:38:43 to 14-12-2022 13:38:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 31208 8192000000 9730343184 10029453312 800388032 8.900 100 87 174 1 828947632 7868608 20690992
11:10:01 22293 8192000000 9765871744 10072883200 839318096 9.293 100 90 180 0 874021520 13253488 21449936
11:11:34 10592 8192000000 9800812176 10121142272 868862224 9.589 100 76 151 0 910000112 24429648 16708240
11:12:48 6507 8192000000 9875181200 10206519296 916690384 10.063 100 88 175 0 983208960 47463408 19055168
11:13:59 3743 8192000000 9923911808 10265702400 957329952 10.463 100 82 159 0 1038574464 64950640 16293872
11:15:08 2068 8192000000 9986621008 10339819520 990340880 10.785 100 94 186 1 1104945440 95357440 19247120
11:16:12 1268 8192000000 9980228448 10344161280 1025828848 11.128 100 89 167 0 1103265216 60261664 17174704
11:17:16 1010 8192000000 10008904368 10379476992 1038878992 11.254 100 95 179 1 1133137744 75640064 18618688
11:18:18 1126 8192000000 10068922208 10444935168 1061130560 11.467 100 97 189 4 1191736704 112084688 18521456
11:19:20 1197 8192000000 10048405584 10427420672 1064956064 11.504 100 92 177 3 1168008704 85053872 17998768
11:20:23 1111 8192000000 10044705616 10426880000 1074419952 11.594 100 89 175 1 1168996112 76733072 17843088
11:21:25 931 8192000000 10029476544 10414059520 1081450096 11.661 100 94 176 0 1152701184 53704992 17546096
11:22:27 6687 8192000000 10038719632 10431123456 1084958128 11.695 100 64 126 2 1155998352 58696240 12343984
11:23:35 1052 8192000000 10060232704 10450944000 1088748320 11.731 100 86 168 0 1181981040 77250080 15982640
11:24:37 1293 8192000000 10085449920 10477363200 1094577680 11.786 100 98 186 0 1208922336 95362480 18982176
11:25:39 1005 8192000000 10061459136 10449330176 1097391024 11.813 100 94 186 2 1183184288 65969696 19823568
11:26:41 1166 8192000000 10092226704 10482966528 1102543904 11.862 100 95 186 1 1215824096 94376768 18903424
11:27:43 1231 8192000000 10069247728 10456346624 1090083600 11.743 100 98 184 0 1188542272 79494416 18964256
11:28:45 3384 8192000000 10013003520 10400276480 1085631776 11.701 100 82 142 2 1136207824 37676160 12899888
11:29:49 822 8192000000 10026732640 10416340992 1078755248 11.636 99 94 172 0 1149466976 52041312 18670416
11:30:51 1152 8192000000 10011173904 10395443200 1055948736 11.418 98 83 161 2 1131665936 58616656 17100544
11:31:53 2626 8192000000 10020126832 10407645184 1062732496 11.483 100 74 139 1 1141117152 64099824 14284832
11:33:00 1216 8192000000 10023651024 10400673792 1037055040 11.236 100 98 185 2 1149040496 93516464 18468992
11:34:02 1019 8192000000 9986075088 10359488512 1024638256 11.117 100 97 184 5 1109832816 65358032 19836528
11:35:04 1269 8192000000 9977686064 10349367296 1029723264 11.166 100 93 175 2 1099899152 52951248 17224640
11:36:07 1155 8192000000 10000025568 10374676480 1041648016 11.281 100 96 185 3 1121271216 61354816 18268384
11:37:09 964 8192000000 10006438448 10387468288 1056558512 11.424 100 92 165 0 1129105824 55304656 17242656
11:38:15 27160 8192000000 10037930656 10434621440 1075472064 11.604 100 58 115 0 1157768048 71241296 11054688
11:40:01 8808 8192000000 10058060496 10442887168 1084875104 11.694 100 74 137 2 1180445136 81237232 14332800
11:41:22 3645 8192000000 10079598304 10463870976 1096693680 11.806 100 86 164 3 1196624336 83141248 16789408
11:42:29 9993 8192000000 10116132032 10507501568 1114597232 11.976 100 71 138 0 1227774864 97964832 15212800
11:43:44 1440 8192000000 10159285872 10564427776 1123040768 12.056 100 90 176 0 1275345248 133662480 18642000
11:44:47 1156 8192000000 10147551792 10551103488 1131195328 12.133 100 87 164 0 1263041408 115093136 16752944
11:45:51 1954 8192000000 10176421360 10573717504 1141860048 12.233 100 85 148 4 1288264736 132090608 14314080
11:46:56 1599 8192000000 10204803232 10602811392 1140360272 12.219 100 84 168 1 1324876848 165762800 18753776
11:48:02 1223 8192000000 10241959792 10650894336 1153279328 12.340 100 97 179 1 1356148048 182567120 20301600
11:49:05 1639 8192000000 10269790544 10670637056 1160310160 12.406 100 85 161 1 1381510720 202935280 18265280
11:50:11 1670 8192000000 10302452368 10702815232 1168576752 12.484 100 90 173 1 1415507056 228227920 18702384
11:51:17 1136 8192000000 10346310432 10750828544 1182582720 12.614 100 90 178 1 1460597584 257023984 20990880
11:52:21 1045 8192000000 10376985472 10789335040 1190413200 12.687 100 88 164 0 1485645920 275117488 20115232
11:53:25 815 8192000000 10425478128 10834784256 1187792672 12.663 100 98 196 2 1532735296 322084800 22857824
11:54:30 1232 8192000000 10464561968 10870218752 1180847280 12.598 100 97 187 0 1576033200 373213760 21972160
11:55:35 793 8192000000 10462007904 10875617280 1192634992 12.708 100 95 188 0 1570652672 354791344 23226336
11:56:38 2630 8192000000 10503527296 10938544128 1183887248 12.626 100 73 146 1 1609060144 406661744 18511152
11:57:46 1904 8192000000 10488567312 10939314176 1178188032 12.573 99 88 173 0 1591062368 392306944 20567392
11:58:52 1120 8192000000 10512778784 10956881920 1180759328 12.597 100 90 172 0 1617508288 416489408 20259552
11:59:55 15284 8192000000 10459121040 10883149824 1165830128 12.458 98 43 85 0 1548487904 372190848 10466928
12:01:22 10119 8192000000 10447339696 10909458432 1154380720 12.351 99 43 85 0 1547081680 381568256 11132704
12:02:37 11378 8192000000 10413577184 10936197120 1177202816 12.564 100 48 96 1 1517256032 327855200 12198016
12:03:52 9272 8192000000 10463509024 10912833536 1193229296 12.713 100 43 84 0 1581009744 377877104 9903344
12:05:16 15760 8192000000 10508911568 10970943488 1171792896 12.514 100 39 76 0 1609513760 427392272 10328592
12:06:33 9939 8192000000 10397726912 10953650176 1164420544 12.445 100 33 67 0 1534335392 361190176 8724672
12:07:44 42582 8192000000 10321878528 10802446336 1141376016 12.228 99 23 46 0 1396731568 249673680 5681872
12:09:55 35666 8192000000 10068040256 10492719104 1138136336 12.198 100 44 73 3 1201679696 54161856 9381504
12:11:31 58114 8192000000 10152043760 10588094464 1141423504 12.229 100 48 96 0 1240860800 89117472 10319824
12:13:43 78021 8192000000 10109512128 10596376576 1132632464 12.146 99 13 26 0 1220469904 85118992 2718448
12:16:03 42116 8192000000 10084188800 10521985024 1110429328 11.936 98 27 53 1 1178666064 62091456 6145280
12:17:49 28951 8192000000 10073286384 10512060416 1119487904 12.022 99 34 67 0 1190885472 64438224 6959344
12:19:35 106446 8192000000 10084284144 10549665792 1068666624 11.539 94 11 22 0 1126058352 54931872 2459856
12:22:22 103172 8192000000 10083543360 10537562112 1105996832 11.895 98 21 41 0 1192770032 82612864 4160336
12:25:15 38246 8192000000 10037118368 10479792128 1082621040 11.672 97 23 45 0 1118505856 31396032 4488784
12:26:58 2547 8192000000 10067027824 10491109376 1124036048 12.065 100 60 120 1 1170797008 34409824 12351136
12:28:02 42325 8192000000 10101263680 10550607872 1131811680 12.138 100 24 48 0 1207585200 70710880 5062640
12:29:52 50332 8192000000 10141954752 10620846080 1134173616 12.161 100 21 42 0 1253802832 115123408 4505808
12:32:16 64803 8192000000 10067302240 10565754880 1133950064 12.159 100 12 24 0 1188981440 52448624 2582752
12:34:21 54413 8192000000 10066317520 10566774784 1119130400 12.019 99 7 14 0 1160563840 39941088 1492352
12:36:42 47574 8192000000 10122172160 10577760256 1104868112 11.884 97 36 64 1 1171427024 59592048 6966864
12:38:34 93185 8192000000 10128156832 10596446208 1101697472 11.854 97 17 32 0 1208007712 102646864 3663376
12:41:47 75865 8192000000 10104188688 10546163712 1112302688 11.954 98 12 24 0 1180638176 65519008 2816480
12:44:05 41982 8192000000 10079767136 10543562752 1098996752 11.828 97 19 36 0 1146558240 44220752 3340736
12:45:47 37603 8192000000 10090919968 10516869120 1107741344 11.911 98 43 86 0 1152632880 35841872 9049664
12:47:25 30487 8192000000 10150562096 10612838400 1140671840 12.222 100 28 56 0 1235126464 88888288 5566336
12:49:08 128695 8192000000 10091054976 10557403136 1144964992 12.262 100 16 32 0 1215446336 66213360 4267984
12:52:22 50780 8192000000 10096422816 10578706432 1138312512 12.200 99 24 48 0 1201110448 58192960 4604976
12:54:41 53023 8192000000 10107932464 10601619456 1120835392 12.035 99 23 47 0 1215293328 89025104 5432832
12:56:34 59605 8192000000 10096234752 10553630720 1110247280 11.935 98 10 20 0 1180749040 68347056 2154704
12:59:37 58569 8192000000 10057384976 10505019392 1098131536 11.820 96 13 23 0 1124794288 24324112 2338640
13:01:36 44285 8192000000 10051896032 10518118400 1081260736 11.659 96 29 54 0 1150333968 62839232 6234000
13:03:22 56310 8192000000 10048691424 10504015872 1124883504 12.073 100 17 34 0 1157869856 30007312 2979040
13:05:18 29217 8192000000 10078961712 10514776064 1124070880 12.065 100 39 78 0 1178723344 47034368 7618096
13:06:54 52490 8192000000 10038527472 10496241664 1072422672 11.575 95 25 50 0 1115409904 37932160 5055072
13:08:49 26368 8192000000 10096351568 10537693184 1122534032 12.051 100 31 62 0 1201755856 72795312 6426512
13:10:16 57314 8192000000 10055354176 10509377536 1125422800 12.078 100 20 38 0 1187080512 57431856 4225856
13:12:17 8972 8192000000 10086810352 10507423744 1120828320 12.035 100 52 101 0 1180939648 50476496 9634832
13:13:29 36782 8192000000 10118357520 10587361280 1115898544 11.988 99 42 84 0 1235110496 110838000 8373952
13:15:10 58729 8192000000 10105826448 10575654912 1110266304 11.935 98 35 70 0 1186815456 69530016 7019136
13:17:16 74605 8192000000 10070990768 10601553920 1124057312 12.065 99 11 22 1 1223311184 97070912 2182960
13:20:09 55831 8192000000 10095879520 10539528192 1140004096 12.216 100 44 82 1 1192833024 43698848 9130080
13:22:11 51677 8192000000 10077313856 10571137024 1111109696 11.943 98 9 18 0 1182434928 69724336 1600896
13:24:02 54983 8192000000 10086977664 10533851136 1097325024 11.812 97 15 30 0 1160180352 59789152 3066176
13:26:05 87889 8192000000 10078057216 10578309120 1121567392 12.042 99 17 34 0 1186660688 61154272 3939024
13:28:57 44427 8192000000 10072914192 10541199360 1133087984 12.150 100 29 58 0 1163399296 24571424 5739888
13:31:30 71368 8192000000 10061062208 10534924288 1136164672 12.179 99 26 47 0 1182096256 40885552 5046032
13:33:47 15928 8192000000 10110831888 10554568704 1132411008 12.144 99 37 72 0 1212393888 72487216 7495664
13:35:04 23115 8192000000 10140189408 10574643200 1122414192 12.050 99 32 63 0 1226774768 97595088 6765488
13:36:28 27899 8192000000 10119042112 10562109440 1082221472 11.669 96 35 69 0 1182709584 93052656 7435456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-12-2022 13:38:49 to 14-12-2022 13:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2022 13:39:06 to 14-12-2022 13:39:08.

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          210106
MON$OLDEST_ACTIVE               213360
MON$OLDEST_SNAPSHOT             213360
MON$NEXT_TRANSACTION            213360
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                       2049184
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Dec 14 13:37:33 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

14.12.22 13:39:10. 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-12-14 13:39:12 No severe PSQL-related problems occured
14.12.22 13:39:13. End of report.