Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.01.2023 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 14756240 kB
MemAvailable 30376472 kB
Buffers 0 kB
Cached 16918216 kB
SwapCached 2384 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-16 13:38:22
DTS_END                         2023-01-16 13:37:24.1160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57822
TRN_ID                          223182
ATT_ID                          259
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: 9029.31 20500 1083517
customer order: creation 179.64 469 21557
customer order: refuse 14.98 482 1798
order to supplier: creation 46.73 1417 5608
order to supplier: removal 3.42 176057 410
invoice (draft): creation 47.33 3299 5679
invoice (draft): removal 47.87 1079 5744
invoice accept: apply 43.41 26665 5209
invoice accept: cancel 43.33 1369 5200
customer reserve: creation 4025.77 251 483092
customer reserve: removal 4278.72 109 513446
realization accept: apply 87.04 306 10445
realization accept: cancel 76.76 216 9211
payment from customer: creation 61.71 185 7405
payment from customer: removal 5.75 168 690
payment to supplier: creation 50.28 202 6034
payment to supplier: removal 3.46 124 415
service: total inventory turnovers 4.00 4639 480
service: total monetary turnovers 7.93 372 952
service: aggregate perf. data 1.14 13403 137
service: refresh index statistics 0.04 179178 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-01-2023 13:38:51 to 16-01-2023 13:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7736
TEST_TIME 2 7035
TEST_TIME 3 6125
TEST_TIME 4 5999
TEST_TIME 5 5967
TEST_TIME 6 6127
TEST_TIME 7 5841
TEST_TIME 8 5779
TEST_TIME 9 5807
TEST_TIME 10 5983
TEST_TIME 11 6106
TEST_TIME 12 6378
TEST_TIME 13 6424
TEST_TIME 14 6544
TEST_TIME 15 6571
TEST_TIME 16 6749
TEST_TIME 17 6984
TEST_TIME 18 7306
TEST_TIME 19 7389
TEST_TIME 20 7550
TEST_TIME 21 7788
TEST_TIME 22 7852
TEST_TIME 23 7993
TEST_TIME 24 7957
TEST_TIME 25 7920
TEST_TIME 26 8048
TEST_TIME 27 8152
TEST_TIME 28 8391
TEST_TIME 29 8513
TEST_TIME 30 8510
TEST_TIME 31 8765
TEST_TIME 32 8736
TEST_TIME 33 8961
TEST_TIME 34 8949
TEST_TIME 35 9479
TEST_TIME 36 9508
TEST_TIME 37 9786
TEST_TIME 38 10030
TEST_TIME 39 10284
TEST_TIME 40 10262
TEST_TIME 41 10283
TEST_TIME 42 10405
TEST_TIME 43 10422
TEST_TIME 44 10484
TEST_TIME 45 10442
TEST_TIME 46 10278
TEST_TIME 47 10220
TEST_TIME 48 10193
TEST_TIME 49 10264
TEST_TIME 50 10143
TEST_TIME 51 10102
TEST_TIME 52 10159
TEST_TIME 53 10104
TEST_TIME 54 10027
TEST_TIME 55 9913
TEST_TIME 56 9799
TEST_TIME 57 9816
TEST_TIME 58 9759
TEST_TIME 59 9640
TEST_TIME 60 9605
TEST_TIME 61 9572
TEST_TIME 62 9553
TEST_TIME 63 9534
TEST_TIME 64 9623
TEST_TIME 65 9544
TEST_TIME 66 9491
TEST_TIME 67 9451
TEST_TIME 68 9432
TEST_TIME 69 9491
TEST_TIME 70 9434
TEST_TIME 71 9381
TEST_TIME 72 9409
TEST_TIME 73 9411
TEST_TIME 74 9399
TEST_TIME 75 9472
TEST_TIME 76 9479
TEST_TIME 77 9482
TEST_TIME 78 9499
TEST_TIME 79 9487
TEST_TIME 80 9498
TEST_TIME 81 9477
TEST_TIME 82 9381
TEST_TIME 83 9312
TEST_TIME 84 9266
TEST_TIME 85 9312
TEST_TIME 86 9259
TEST_TIME 87 9224
TEST_TIME 88 9168
TEST_TIME 89 9181
TEST_TIME 90 9197
TEST_TIME 91 9139
TEST_TIME 92 9137
TEST_TIME 93 9166
TEST_TIME 94 9192
TEST_TIME 95 9220
TEST_TIME 96 9262
TEST_TIME 97 9304
TEST_TIME 98 9303
TEST_TIME 99 9377
TEST_TIME 100 9365
TEST_TIME 101 9354
TEST_TIME 102 9426
TEST_TIME 103 9377
TEST_TIME 104 9311
TEST_TIME 105 9311
TEST_TIME 106 9243
TEST_TIME 107 9228
TEST_TIME 108 9237
TEST_TIME 109 9209
TEST_TIME 110 9166
TEST_TIME 111 9130
TEST_TIME 112 9100
TEST_TIME 113 9138
TEST_TIME 114 9124
TEST_TIME 115 9099
TEST_TIME 116 9093
TEST_TIME 117 9082
TEST_TIME 118 9051
TEST_TIME 119 9051
TEST_TIME 120 9056

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-01-2023 13:38:55 to 16-01-2023 13:38:59.

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 1527892 1527892 0 0.00 0 15231 15 0 0
doc_list_biud 1527881 1527881 0 0.00 0 113752 43 0 0
sp_add_invoice_to_stock 5309 5209 100 1.88 40 159535 26665 0 100
sp_cancel_adding_invoice 5200 5200 0 0.00 5 85290 1369 0 0
sp_cancel_client_order 1798 1798 0 0.00 5 15610 482 0 0
sp_cancel_customer_reserve 513446 513446 0 0.00 1 37400 109 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 4 10187 169 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 1590 124 0 0
sp_cancel_supplier_invoice 5750 5744 6 0.10 3 16728 1080 0 6
sp_cancel_supplier_order 410 410 0 0.00 9 1682542 176058 0 0
sp_cancel_write_off 9211 9211 0 0.00 2 113798 217 0 0
sp_client_order 21557 21557 0 0.00 5 20593 470 0 0
sp_customer_reserve 483092 483092 0 0.00 1 117977 251 0 0
sp_fill_shopping_cart 32834 32834 0 0.00 1 26615 84 0 0
sp_fill_shopping_cart_clo_res 483065 483065 0 0.00 0 15454 18 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 31357 228 0 0
sp_kill_cost_storno 15516 15516 0 0.00 0 11866 89 0 0
sp_kill_qstorno_ret_qs2qd 1042257 1042257 0 0.00 0 113742 47 0 0
sp_kill_qty_storno 551417 551417 0 0.00 0 113748 109 0 0
sp_lock_selected_doc 21506 21506 0 0.00 0 5848 8 0 0
sp_make_cost_storno 29049 29049 0 0.00 0 15117 81 0 0
sp_multiply_rows_for_pdistr 29047 29047 0 0.00 0 11836 40 0 0
sp_multiply_rows_for_qdistr 21557 21557 0 0.00 0 11487 158 0 0
sp_pay_from_customer 7405 7405 0 0.00 5 18980 186 0 0
sp_pay_to_supplier 6034 6034 0 0.00 6 15241 202 0 0
sp_payment_common 13439 13439 0 0.00 2 7854 45 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 12507 378 0 0
sp_qd_handle_on_invoice_upd_sts 10363 10363 0 0.00 0 18659 579 0 0
sp_qd_handle_on_reserve_upd_sts 10445 10445 0 0.00 0 19364 81 0 0
sp_reserve_write_off 10445 10445 0 0.00 8 19807 306 0 0
sp_supplier_invoice 5679 5679 0 0.00 9 100840 3300 0 0
sp_supplier_order 5608 5608 0 0.00 9 28219 1418 0 0
srv_aggregate_perf_data 137 137 0 0.00 2344 110579 13404 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 689 158053 32523 0 0
srv_find_qd_qs_mism 1032391 1032391 0 0.00 0 13243 11 0 0
srv_make_invnt_saldo 480 480 0 0.00 52 60907 4640 0 0
srv_make_money_saldo 952 952 0 0.00 1 15560 373 0 0
srv_recalc_idx_stat 5 5 0 0.00 53115 328182 179178 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 5214 5214 0 0.00 1 13308 1028 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 8213 488 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 1247 29 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 2699 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 1685 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 3513 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1932 43 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 99215 8418 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 5187 24 0 0
t$perf-norm:sp_client_order 21557 21557 0 0.00 0 7306 37 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 1384 17 0 0
t$perf-norm:sp_pay_from_customer 7405 7405 0 0.00 0 5588 21 0 0
t$perf-norm:sp_pay_to_supplier 6034 6034 0 0.00 0 2290 19 0 0
t$perf-norm:sp_reserve_write_off 10446 10446 0 0.00 0 4492 23 0 0
t$perf-norm:sp_supplier_invoice 5679 5679 0 0.00 0 13599 37 0 0
t$perf-norm:sp_supplier_order 5609 5609 0 0.00 0 4391 35 0 0
t$perf-norm:srv_aggregate_perf_data 137 137 0 0.00 0 340 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 762 28 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 1770 17 0 0
t$perf-norm:srv_make_money_saldo 952 952 0 0.00 0 512 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 208 98 0 0
v_add_invoice_to_stock 10522 10322 200 1.90 0 10029 29 0 200
v_all_customers 39874 39874 0 0.00 0 97 4 0 0
v_all_suppliers 31782 31782 0 0.00 0 152 5 0 0
v_all_wares 43112 43112 0 0.00 0 133 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 947 15 0 0
v_cancel_client_order 3596 3596 0 0.00 0 15380 24 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 9782 25 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 8920 19 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 686 12 0 0
v_cancel_supplier_invoice 846 834 12 1.41 0 8831 52 0 12
v_cancel_supplier_order 820 820 0 0.00 0 1575 15 0 0
v_cancel_write_off 2066 2066 0 0.00 0 19743 40 0 0
v_max_id_clo_ord 5605 5605 0 0.00 0 3793 6 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 1177 10 0 0
v_max_id_ord_sup 5671 5671 0 0.00 0 7170 9 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 264 8 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 1354 8 0 0
v_min_id_clo_ord 5605 5605 0 0.00 0 3934 10 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 3895 15 0 0
v_min_id_ord_sup 5671 5671 0 0.00 0 6085 10 0 0
v_min_non_paid_invoice 6034 6034 0 0.00 0 3657 13 0 0
v_min_non_paid_realizn 7405 7405 0 0.00 0 4229 16 0 0
v_our_firm 10614 10614 0 0.00 0 74 5 0 0
v_reserve_write_off 20888 20888 0 0.00 0 15144 15 0 0
x_lock_depdocs_on_canc_invoice 10527 10527 0 0.00 0 26992 131 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 13583 411 0 0
x_make_qstorno_1000_1200 5608 5608 0 0.00 3 28206 1240 0 0
x_make_qstorno_1200_2000 5679 5679 0 0.00 5 92541 3068 0 0
x_make_qstorno_2100_3300 483091 483091 0 0.00 0 117972 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2023 13:39:00 to 16-01-2023 13:39:04.

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 8 8192000000 8808783296 8823828480 12807184 0.156 4 4 5 0 13070000 150272 112544
11:08:29 15065 8192000000 9706041904 10000146432 780487040 8.698 100 88 176 0 810049040 7437568 22124432
11:09:51 21490 8192000000 9751910416 10058907648 828648000 9.186 100 82 164 0 858521904 10295728 19578176
11:11:16 5157 8192000000 9794782320 10112114688 873125184 9.631 100 82 164 0 906641072 14677856 18838032
11:12:32 14442 8192000000 9842648624 10176172032 905210880 9.950 100 74 148 0 951330720 29812128 16307712
11:13:50 3260 8192000000 9904680384 10242166784 947446144 10.366 100 88 175 0 1016330480 50482528 18401808
11:14:56 2833 8192000000 9966386432 10313768960 980645984 10.690 100 86 170 2 1076759040 78634496 17478560
11:16:04 2326 8192000000 9989498848 10348789760 1007081088 10.947 100 84 167 0 1104477856 80756768 16640000
11:17:09 1236 8192000000 10021848064 10385571840 1028996960 11.159 100 93 183 0 1140889216 93427008 18465248
11:18:14 1070 8192000000 10016559552 10391482368 1053537840 11.395 100 94 177 2 1142492384 71855664 17098880
11:19:18 1276 8192000000 10034474400 10406940672 1060992288 11.466 100 94 182 0 1145895424 66090880 18812256
11:20:23 6444 8192000000 10013546544 10388635648 1065342704 11.508 100 79 146 2 1136693184 58451104 12899376
11:21:32 1198 8192000000 10061926096 10443063296 1067306048 11.526 100 93 180 1 1182154560 97387040 17461472
11:22:36 1279 8192000000 10041052336 10426220544 1070710592 11.559 100 98 188 2 1162406032 73490960 18204480
11:23:38 1285 8192000000 10056777264 10442653696 1077441664 11.623 100 95 179 2 1178748224 82857568 18448992
11:24:41 1489 8192000000 10056106736 10439630848 1078693168 11.635 100 97 185 2 1170164112 72717776 18753168
11:25:43 1334 8192000000 10041920880 10427285504 1083421056 11.680 100 96 188 1 1164652096 62583024 18648016
11:26:46 945 8192000000 10038517696 10423549952 1081921968 11.666 100 97 189 1 1160087072 58497408 19667696
11:27:48 1113 8192000000 10029190752 10411151360 1084899472 11.694 100 99 195 0 1149306656 44711600 19695584
11:28:51 2184 8192000000 10039049040 10428559360 1083517168 11.681 100 80 147 1 1163994160 66290240 14186752
11:29:55 1016 8192000000 10031725360 10418626560 1087749904 11.721 100 95 172 0 1153478448 48858416 16870128
11:30:58 969 8192000000 10048647440 10436923392 1086536160 11.710 100 82 158 0 1171538816 68972464 16030192
11:32:00 1347 8192000000 9997140912 10374815744 1055711424 11.415 99 95 185 2 1121629248 45461536 20456288
11:33:02 1326 8192000000 10032512704 10412781568 1060669712 11.463 99 95 181 1 1155234304 75987200 18577392
11:34:05 1044 8192000000 10003262496 10377388032 1038034480 11.246 99 92 172 1 1125685232 70485568 17165184
11:35:06 4725 8192000000 9991756768 10374987776 1043489024 11.298 100 60 114 1 1126584496 71433840 11661632
11:36:12 1299 8192000000 9968535184 10335920128 1024016736 11.111 100 93 174 0 1091406624 48155392 19234496
11:37:14 1272 8192000000 9962188496 10331865088 1025713792 11.127 100 80 150 1 1085686176 45249568 14722816
11:38:25 1090 8192000000 10011596480 10385477632 1051811152 11.378 100 82 146 0 1121833424 56528848 13493424
11:39:39 7255 8192000000 10023421056 10399956992 1070932560 11.561 100 89 172 4 1140857312 52587360 17337392
11:40:55 16598 8192000000 10058612032 10451173376 1089143488 11.735 100 64 119 0 1180941440 79405328 12392624
11:42:14 1430 8192000000 10085300224 10474917888 1102257232 11.859 100 86 166 0 1199615552 80957600 16400720
11:43:19 2646 8192000000 10124595456 10520276992 1111080736 11.943 100 75 136 1 1246506944 121332592 14093616
11:44:32 6084 8192000000 10144479632 10537185280 1111650624 11.948 100 76 152 0 1260899600 132854624 16394352
11:45:46 876 8192000000 10191800592 10586886144 1132876064 12.148 100 94 180 3 1310506656 156937872 20692720
11:46:50 1594 8192000000 10215698720 10609491968 1142009088 12.234 100 94 182 2 1333737968 170419856 21309024
11:47:54 1077 8192000000 10231347296 10637295616 1146879472 12.280 100 91 181 2 1348231488 180929536 20422480
11:49:00 804 8192000000 10277426768 10679877632 1157480400 12.380 100 99 192 1 1388623136 210954944 20187792
11:50:06 864 8192000000 10299999776 10699964416 1159410064 12.398 100 97 188 0 1410925776 231043344 20472368
11:51:11 1002 8192000000 10335805568 10741637120 1167137568 12.470 100 94 188 1 1446276048 258433888 20704592
11:52:15 4350 8192000000 10373905584 10785521664 1171089488 12.507 100 92 182 2 1480665744 289155296 20420960
11:53:23 1665 8192000000 10392074960 10795577344 1179544880 12.586 100 92 177 1 1502940752 303055296 20340576
11:54:28 1049 8192000000 10391810016 10814128128 1181510256 12.604 100 91 176 0 1499797872 297744368 20543248
11:55:35 1286 8192000000 10370035952 10797518848 1183468800 12.623 100 89 176 1 1480728192 276794256 20465136
11:56:40 1617 8192000000 10409157392 10835058688 1186192720 12.648 100 88 171 0 1518215536 311354128 20668688
11:57:46 1140 8192000000 10388294096 10849755136 1191446448 12.697 100 98 182 1 1492432144 279326656 21659040
11:58:50 1018 8192000000 10427575600 10853175296 1206560176 12.837 100 95 182 1 1531878832 304070640 21248016
11:59:58 870 8192000000 10470383664 10894893056 1201274496 12.788 100 94 181 0 1571054496 347290096 22489904
12:01:01 1374 8192000000 10490025040 10896850944 1195877280 12.738 100 93 185 0 1590864112 373346992 21639840
12:02:09 1665 8192000000 10499931280 10915807232 1179544608 12.586 100 93 175 1 1607718240 405642416 22531216
12:03:13 678 8192000000 10547682960 10988220416 1193199584 12.713 100 97 190 0 1651438064 434404096 23834384
12:04:18 820 8192000000 10522349520 10969354240 1189297824 12.677 100 84 166 0 1620721136 411930208 19493104
12:05:23 1391 8192000000 10523484608 10962186240 1173701072 12.531 100 73 143 1 1623355216 431964336 17689808
12:06:27 9908 8192000000 10509272592 10959056896 1171780992 12.513 100 47 94 0 1627278336 443390848 12106496
12:07:41 8138 8192000000 10468527104 10969387008 1173092240 12.526 100 53 104 1 1595051104 408699472 13259392
12:08:52 1093 8192000000 10466587136 10906992640 1169338192 12.491 100 64 123 0 1565448160 382264592 13845376
12:10:00 6459 8192000000 10492868304 10951118848 1171074816 12.507 99 53 106 0 1597992864 413990336 12927712
12:11:27 13094 8192000000 10441449440 10958299136 1182808960 12.616 100 39 78 0 1570119616 377735200 9575456
12:12:42 11678 8192000000 10371747184 10837139456 1170962896 12.506 100 35 66 2 1469746304 289369344 9414064
12:13:56 37711 8192000000 10333424288 10809520128 1169386192 12.491 100 29 56 0 1433674864 257440464 6848208
12:15:35 40811 8192000000 10219824240 10733133824 1140431456 12.220 99 26 51 1 1341997520 195480752 6085312
12:17:18 20698 8192000000 10201309120 10621263872 1151345040 12.322 100 61 108 1 1300043488 137713200 10985248
12:18:41 27069 8192000000 10217604576 10678349824 1154067264 12.348 100 38 72 0 1308958016 146189456 8701296
12:21:10 60909 8192000000 10092874560 10570895360 1115647648 11.986 98 27 54 1 1189164336 66972688 6544000
12:23:16 69998 8192000000 10125384240 10564808704 1126321872 12.087 99 29 57 0 1205127840 72359184 6446784
12:25:32 62581 8192000000 10094419616 10597089280 1105899824 11.894 97 12 23 0 1186210016 78310400 1999792
12:27:37 40604 8192000000 10092649632 10522755072 1127136736 12.094 99 33 62 1 1157722848 24178944 6407168
12:29:18 77571 8192000000 10062481744 10505814016 1069753088 11.550 94 20 36 0 1094868768 21270400 3845280
12:31:42 158052 8192000000 10083876800 10577833984 1124144816 12.066 99 30 58 0 1180764112 50119040 6500256
12:35:21 149664 8192000000 10091459680 10525777920 1078588960 11.634 94 47 88 1 1117736576 30165792 8981824
12:38:54 33279 8192000000 10117981904 10569707520 1133237808 12.152 99 38 72 1 1213772960 73107456 7427696
12:40:30 113758 8192000000 10068411936 10512945152 1095941920 11.799 96 43 82 1 1211143232 106378240 8823072
12:43:29 51612 8192000000 10096638704 10552262656 1116922656 11.998 98 14 28 0 1182656720 62126848 3607216
12:46:02 17725 8192000000 10070912944 10553917440 1122872736 12.054 99 9 18 0 1161838736 36988848 1977152
12:47:25 32834 8192000000 10061966096 10509082624 1132673808 12.147 100 18 30 0 1160677920 25366688 2637424
12:49:04 17944 8192000000 10098791008 10551140352 1126826752 12.091 100 27 54 0 1201386800 68729296 5830752
12:50:25 29057 8192000000 10113690384 10583293952 1129314400 12.115 99 30 59 2 1221447648 85478848 6654400
12:51:59 55235 8192000000 10094078944 10546491392 1113397760 11.965 98 15 29 0 1200952528 84303664 3251104
12:53:59 10929 8192000000 10097012480 10526842880 1115481264 11.984 99 52 100 0 1177429568 51457728 10490576
12:55:14 23343 8192000000 10051953968 10489077760 1108582560 11.919 98 21 42 0 1130870304 17671152 4616592
12:56:41 17984 8192000000 10096080944 10540318720 1135669520 12.175 100 58 117 0 1182082512 33633072 12779920
12:57:59 47202 8192000000 10113640016 10546089984 1117625696 12.005 98 28 56 1 1180412272 57060576 5726000
12:59:51 131617 8192000000 10097132304 10585235456 1121333072 12.040 99 34 66 1 1189112096 60788192 6990832
13:03:03 69960 8192000000 10092240720 10555981824 1127789648 12.101 99 17 34 0 1190597056 59265088 3542320
13:05:13 66919 8192000000 10068851088 10549174272 1103308048 11.869 97 9 18 0 1177015632 71670784 2036800
13:07:22 51440 8192000000 10071604208 10504540160 1098595824 11.824 96 29 55 2 1150605088 46410112 5599152
13:09:14 36193 8192000000 10123162960 10559778816 1125295280 12.077 99 42 80 0 1208814880 75733392 7786208
13:11:07 72517 8192000000 10124696688 10576453632 1136525360 12.183 98 45 84 2 1233983104 88064432 9393312
13:13:23 40812 8192000000 10170184352 10663194624 1151806976 12.326 100 31 60 0 1254123968 95992368 6324624
13:15:04 41399 8192000000 10141133328 10577313792 1152467280 12.333 100 30 60 0 1242804512 84040368 6296864
13:17:36 37997 8192000000 10132200304 10586365952 1109854752 11.931 97 42 84 1 1164602768 46550272 8197744
13:19:16 41982 8192000000 10057418496 10511896576 1101898336 11.856 97 14 25 0 1131372960 26863232 2611392
13:21:00 139548 8192000000 10078134464 10548109312 1136587920 12.183 100 35 68 0 1191083408 47417328 7078160
13:24:30 52237 8192000000 10091177056 10579763200 1138180480 12.198 100 32 64 0 1204862832 60225216 6457136
13:27:12 63474 8192000000 10096682256 10574647296 1145391920 12.266 100 15 30 0 1208670432 59217232 4061280
13:29:16 51566 8192000000 10102145904 10548002816 1118857600 12.016 98 21 41 0 1172672336 49161216 4653520
13:31:12 46445 8192000000 10083124032 10534866944 1098780688 11.826 96 26 52 0 1152153696 47753776 5619232
13:33:00 24932 8192000000 10117707056 10569691136 1103744720 11.873 98 42 84 0 1200120544 87211456 9164368
13:34:28 37257 8192000000 10151210960 10584920064 1098752832 11.826 97 31 58 0 1206278112 101664352 5860928
13:36:13 27933 8192000000 10229494592 10684604416 1146062208 12.273 100 52 104 0 1307194464 149910976 11221280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-01-2023 13:39:06 to 16-01-2023 13:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 16-01-2023 13:39:23 to 16-01-2023 13:39:25.

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          230048
MON$OLDEST_ACTIVE               230049
MON$OLDEST_SNAPSHOT             230049
MON$NEXT_TRANSACTION            230049
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                       2096320
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             782
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 ---
--- end of diff output ---

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

16.01.23 13:39:27. 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-16 13:39:29 No severe PSQL-related problems occured
16.01.23 13:39:31. End of report.