Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2022 13: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 15594348 kB
MemAvailable 30451776 kB
Buffers 0 kB
Cached 16105092 kB
SwapCached 1956 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-10-21 13:37:38
DTS_END                         2022-10-21 13:37:23.0080
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/46794
TRN_ID                          207906
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: 8656.42 24867 1038771
customer order: creation 158.82 509 19059
customer order: refuse 13.38 357 1605
order to supplier: creation 41.23 1326 4948
order to supplier: removal 3.40 149552 408
invoice (draft): creation 42.58 3128 5109
invoice (draft): removal 49.94 952 5993
invoice accept: apply 38.14 28617 4577
invoice accept: cancel 44.99 1033 5399
customer reserve: creation 3628.24 270 435389
customer reserve: removal 4367.96 90 524155
realization accept: apply 76.06 250 9127
realization accept: cancel 72.63 155 8716
payment from customer: creation 53.98 165 6477
payment from customer: removal 5.37 101 644
payment to supplier: creation 44.87 190 5384
payment to supplier: removal 3.18 150 381
service: total inventory turnovers 3.97 3533 476
service: total monetary turnovers 6.78 382 813
service: aggregate perf. data 0.88 12318 106
service: refresh index statistics 0.04 294269 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2022 13:38:10 to 21-10-2022 13:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7377
TEST_TIME 2 5544
TEST_TIME 3 4999
TEST_TIME 4 5084
TEST_TIME 5 5405
TEST_TIME 6 5063
TEST_TIME 7 5055
TEST_TIME 8 5122
TEST_TIME 9 5581
TEST_TIME 10 5763
TEST_TIME 11 5832
TEST_TIME 12 6070
TEST_TIME 13 6208
TEST_TIME 14 6264
TEST_TIME 15 6558
TEST_TIME 16 6786
TEST_TIME 17 7323
TEST_TIME 18 7456
TEST_TIME 19 7406
TEST_TIME 20 7815
TEST_TIME 21 7748
TEST_TIME 22 8097
TEST_TIME 23 8333
TEST_TIME 24 8656
TEST_TIME 25 9357
TEST_TIME 26 9675
TEST_TIME 27 10188
TEST_TIME 28 10550
TEST_TIME 29 10673
TEST_TIME 30 10561
TEST_TIME 31 10613
TEST_TIME 32 10400
TEST_TIME 33 10293
TEST_TIME 34 10145
TEST_TIME 35 9996
TEST_TIME 36 9989
TEST_TIME 37 9809
TEST_TIME 38 10025
TEST_TIME 39 9811
TEST_TIME 40 9613
TEST_TIME 41 9458
TEST_TIME 42 9503
TEST_TIME 43 9472
TEST_TIME 44 9378
TEST_TIME 45 9320
TEST_TIME 46 9308
TEST_TIME 47 9275
TEST_TIME 48 9231
TEST_TIME 49 9284
TEST_TIME 50 9276
TEST_TIME 51 9276
TEST_TIME 52 9283
TEST_TIME 53 9443
TEST_TIME 54 9384
TEST_TIME 55 9316
TEST_TIME 56 9289
TEST_TIME 57 9472
TEST_TIME 58 9347
TEST_TIME 59 9267
TEST_TIME 60 9170
TEST_TIME 61 9118
TEST_TIME 62 9083
TEST_TIME 63 9011
TEST_TIME 64 8983
TEST_TIME 65 8899
TEST_TIME 66 8818
TEST_TIME 67 8741
TEST_TIME 68 8717
TEST_TIME 69 8679
TEST_TIME 70 8807
TEST_TIME 71 8900
TEST_TIME 72 8965
TEST_TIME 73 9006
TEST_TIME 74 9040
TEST_TIME 75 9059
TEST_TIME 76 9045
TEST_TIME 77 9142
TEST_TIME 78 9193
TEST_TIME 79 9188
TEST_TIME 80 9233
TEST_TIME 81 9264
TEST_TIME 82 9180
TEST_TIME 83 9120
TEST_TIME 84 9060
TEST_TIME 85 9053
TEST_TIME 86 8996
TEST_TIME 87 8948
TEST_TIME 88 8944
TEST_TIME 89 8966
TEST_TIME 90 8904
TEST_TIME 91 8870
TEST_TIME 92 8892
TEST_TIME 93 8904
TEST_TIME 94 8885
TEST_TIME 95 8880
TEST_TIME 96 8898
TEST_TIME 97 8855
TEST_TIME 98 8865
TEST_TIME 99 8906
TEST_TIME 100 8878
TEST_TIME 101 8940
TEST_TIME 102 8950
TEST_TIME 103 8979
TEST_TIME 104 8980
TEST_TIME 105 8906
TEST_TIME 106 8880
TEST_TIME 107 8848
TEST_TIME 108 8796
TEST_TIME 109 8763
TEST_TIME 110 8728
TEST_TIME 111 8703
TEST_TIME 112 8694
TEST_TIME 113 8689
TEST_TIME 114 8689
TEST_TIME 115 8702
TEST_TIME 116 8683
TEST_TIME 117 8694
TEST_TIME 118 8680
TEST_TIME 119 8727
TEST_TIME 120 8683

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-10-2022 13:38:13 to 21-10-2022 13:38:17.

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 1439991 1439991 0 0.00 0 13603 13 0 0
doc_list_biud 1439979 1439979 0 0.00 0 23142 39 0 0
sp_add_invoice_to_stock 4657 4577 80 1.71 75 255641 28617 0 80
sp_cancel_adding_invoice 5399 5399 0 0.00 6 70865 1033 0 0
sp_cancel_client_order 1605 1605 0 0.00 7 6248 358 0 0
sp_cancel_customer_reserve 524155 524155 0 0.00 1 23145 90 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 2712 101 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 11120 150 0 0
sp_cancel_supplier_invoice 6003 5993 10 0.16 2 20654 953 0 10
sp_cancel_supplier_order 408 408 0 0.00 10 1368647 149553 0 0
sp_cancel_write_off 8716 8716 0 0.00 2 9225 156 0 0
sp_client_order 19059 19059 0 0.00 5 47219 509 0 0
sp_customer_reserve 435389 435389 0 0.00 0 91995 271 0 0
sp_fill_shopping_cart 29102 29102 0 0.00 1 23747 79 0 0
sp_fill_shopping_cart_clo_res 435357 435357 0 0.00 0 19980 19 0 0
sp_get_clo_for_invoice 4534 4534 0 0.00 0 19122 337 0 0
sp_kill_cost_storno 15140 15140 0 0.00 0 9205 65 0 0
sp_kill_qstorno_ret_qs2qd 1063427 1063427 0 0.00 0 23136 41 0 0
sp_kill_qty_storno 559935 559935 0 0.00 0 23141 92 0 0
sp_lock_selected_doc 18875 18875 0 0.00 0 9156 7 0 0
sp_make_cost_storno 25522 25522 0 0.00 0 9246 71 0 0
sp_multiply_rows_for_pdistr 25520 25520 0 0.00 0 5486 33 0 0
sp_multiply_rows_for_qdistr 19059 19059 0 0.00 0 32627 212 0 0
sp_pay_from_customer 6477 6477 0 0.00 5 17270 165 0 0
sp_pay_to_supplier 5384 5384 0 0.00 6 12908 190 0 0
sp_payment_common 11861 11861 0 0.00 2 8735 41 0 0
sp_qd_handle_on_cancel_clo 1605 1605 0 0.00 0 6240 295 0 0
sp_qd_handle_on_invoice_upd_sts 9931 9931 0 0.00 0 16795 462 0 0
sp_qd_handle_on_reserve_upd_sts 9127 9127 0 0.00 0 13553 59 0 0
sp_reserve_write_off 9127 9127 0 0.00 8 14179 251 0 0
sp_supplier_invoice 5109 5109 0 0.00 11 90708 3129 0 0
sp_supplier_order 4948 4948 0 0.00 6 62830 1326 0 0
srv_aggregate_perf_data 106 106 0 0.00 2392 134412 12319 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 696 133613 35887 0 0
srv_find_qd_qs_mism 993532 993532 0 0.00 0 13603 11 0 0
srv_make_invnt_saldo 476 476 0 0.00 15 75974 3534 0 0
srv_make_money_saldo 813 813 0 0.00 1 12996 383 0 0
srv_recalc_idx_stat 5 5 0 0.00 66924 603107 294269 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4581 4581 0 0.00 1 44220 832 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 1 8357 363 0 0
t$perf-norm:sp_cancel_client_order 1605 1605 0 0.00 0 2800 27 0 0
t$perf-norm:sp_cancel_customer_reserve 603 603 0 0.00 0 3313 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 2821 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 1896 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 340 340 0 0.00 0 3289 43 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 84091 6087 0 0
t$perf-norm:sp_cancel_write_off 874 874 0 0.00 0 2354 21 0 0
t$perf-norm:sp_client_order 19059 19059 0 0.00 0 34376 36 0 0
t$perf-norm:sp_customer_reserve 886 886 0 0.00 0 1676 14 0 0
t$perf-norm:sp_pay_from_customer 6477 6477 0 0.00 0 8179 19 0 0
t$perf-norm:sp_pay_to_supplier 5384 5384 0 0.00 0 7133 18 0 0
t$perf-norm:sp_reserve_write_off 9127 9127 0 0.00 0 5351 20 0 0
t$perf-norm:sp_supplier_invoice 5109 5109 0 0.00 0 2913 27 0 0
t$perf-norm:sp_supplier_order 4948 4948 0 0.00 0 4308 29 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 890 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 303 14 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 2435 21 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 593 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 175 44 0 0
v_add_invoice_to_stock 9220 9060 160 1.73 0 10865 26 0 160
v_all_customers 34760 34760 0 0.00 0 72 3 0 0
v_all_suppliers 28430 28430 0 0.00 0 68 4 0 0
v_all_wares 38112 38112 0 0.00 0 158 3 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 6241 30 0 0
v_cancel_client_order 3210 3210 0 0.00 0 1268 8 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 303 8 0 0
v_cancel_customer_reserve 1206 1206 0 0.00 1 293 9 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 11037 33 0 0
v_cancel_supplier_invoice 700 680 20 2.85 0 1703 21 0 20
v_cancel_supplier_order 816 816 0 0.00 0 614 10 0 0
v_cancel_write_off 1748 1748 0 0.00 0 7455 23 0 0
v_max_id_clo_ord 4945 4945 0 0.00 0 541 4 0 0
v_max_id_clo_res 886 886 0 0.00 0 1627 16 0 0
v_max_id_ord_sup 5099 5099 0 0.00 0 6346 10 0 0
v_max_non_paid_invoice 1213 1213 0 0.00 0 73 7 0 0
v_max_non_paid_realizn 3271 3271 0 0.00 0 645 6 0 0
v_min_id_clo_ord 4945 4945 0 0.00 0 4256 7 0 0
v_min_id_clo_res 886 886 0 0.00 0 5379 19 0 0
v_min_id_ord_sup 5099 5099 0 0.00 0 7763 12 0 0
v_min_non_paid_invoice 5384 5384 0 0.00 0 7431 17 0 0
v_min_non_paid_realizn 6476 6476 0 0.00 0 8709 16 0 0
v_our_firm 9763 9763 0 0.00 0 53 4 0 0
v_reserve_write_off 18254 18254 0 0.00 0 8851 10 0 0
x_lock_depdocs_on_canc_invoice 11052 11052 0 0.00 0 13336 124 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 6012 306 0 0
x_make_qstorno_1000_1200 4948 4948 0 0.00 2 62784 1185 0 0
x_make_qstorno_1200_2000 5109 5109 0 0.00 3 71658 2890 0 0
x_make_qstorno_2100_3300 435389 435389 0 0.00 0 91993 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2022 13:38:18 to 21-10-2022 13:38:22.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 23010 8192000000 9728768752 10029813760 804827344 8.945 100 88 176 0 834730496 8037776 21865376
11:09:57 18801 8192000000 9783778976 10097958912 857865504 9.479 100 87 175 0 891402960 13025024 20512432
11:11:21 9860 8192000000 9817444048 10139398144 882623616 9.726 100 88 176 0 924493744 21788592 20081536
11:12:37 7640 8192000000 9868952640 10206539776 922278272 10.119 100 78 156 1 978863072 39317392 17267408
11:13:51 5088 8192000000 9912037200 10259329024 962116176 10.510 100 81 160 0 1021604208 42511888 16976144
11:14:58 2124 8192000000 9947846032 10297970688 988456240 10.766 100 85 167 0 1066495296 61303744 16735312
11:16:03 1400 8192000000 10001002304 10360659968 1013302592 11.007 100 94 177 0 1119837808 89253584 17281632
11:17:07 3134 8192000000 9982679808 10349105152 1030829520 11.176 100 82 157 0 1109165696 62668608 15667568
11:18:13 1242 8192000000 10026580448 10400460800 1050357648 11.364 100 92 175 2 1153268144 83633728 19276768
11:19:16 2802 8192000000 10049195328 10426245120 1070309472 11.555 100 94 182 0 1163275920 74859600 18106848
11:20:24 1260 8192000000 10043820384 10420150272 1077013600 11.619 100 96 184 1 1160838224 64365424 19459200
11:21:27 1158 8192000000 10058041536 10437877760 1077808304 11.627 100 91 178 3 1177277872 81244640 18224928
11:22:30 1031 8192000000 10065741504 10452324352 1089549824 11.738 100 97 191 2 1184359504 75802224 19007456
11:23:33 1126 8192000000 10051806864 10436521984 1093669152 11.778 100 89 171 1 1170248400 57717824 18861424
11:24:35 1118 8192000000 10077761408 10466848768 1094071504 11.781 100 96 191 1 1200077808 86471248 19535056
11:25:37 940 8192000000 10046221984 10436915200 1094284976 11.783 100 87 159 0 1167322384 56878112 16159296
11:26:40 2694 8192000000 10053457680 10441498624 1100498240 11.842 100 80 151 3 1175528064 60977840 14051984
11:27:43 1148 8192000000 10076099024 10467102720 1102335296 11.860 100 92 177 2 1200152704 79252512 18564896
11:28:45 947 8192000000 10066498752 10457874432 1104118032 11.877 100 99 192 1 1186255184 62544448 19592704
11:29:47 1154 8192000000 10036121536 10419195904 1081877792 11.665 99 96 178 1 1154710992 53998368 18834832
11:30:50 4718 8192000000 10044392752 10431406080 1090482336 11.747 100 78 149 1 1159463456 52999600 15981520
11:31:55 4641 8192000000 10013605152 10397454336 1070899872 11.561 99 66 124 7 1130353136 47151216 12302048
11:33:01 2093 8192000000 10033788688 10421055488 1078413712 11.632 100 82 152 3 1158865744 65921568 14530464
11:34:05 3301 8192000000 10022243424 10405232640 1045400896 11.317 100 73 135 1 1140383616 81537232 13445488
11:35:09 1342 8192000000 9987604432 10358050816 1020251232 11.074 100 97 180 2 1110772704 72448896 18072576
11:36:12 2064 8192000000 9992964544 10379710464 1045317920 11.316 100 71 133 1 1116845472 56689520 14838032
11:37:15 1133 8192000000 9988603552 10362003456 1042737056 11.291 99 90 166 2 1108178800 48424320 17017424
11:38:26 1244 8192000000 10011575808 10385387520 1060374256 11.460 100 90 165 4 1128191120 50671088 17145776
11:39:37 10559 8192000000 10032098112 10423873536 1077580784 11.624 100 61 120 0 1149602176 59742176 12279216
11:40:54 4512 8192000000 10079025136 10468478976 1088978016 11.733 100 79 142 3 1194262768 90599424 14685328
11:42:02 6058 8192000000 10121710464 10508955648 1108731216 11.920 100 79 146 1 1239878240 116346624 14800400
11:43:11 6853 8192000000 10151712912 10551267328 1117452384 12.003 100 66 128 2 1271897760 140827056 13618320
11:44:26 4514 8192000000 10205585792 10608947200 1127204976 12.095 100 63 126 0 1325681616 183599184 14877456
11:45:39 4633 8192000000 10237164720 10645258240 1135558304 12.174 100 75 141 0 1358465520 207199312 15707904
11:46:49 1764 8192000000 10263487408 10670596096 1137589136 12.193 100 84 162 1 1369599392 214157888 17852368
11:47:55 1031 8192000000 10298397872 10691379200 1148520528 12.296 100 93 179 0 1408183184 239338160 20324496
11:49:01 3145 8192000000 10338969072 10746109952 1155990384 12.366 100 90 170 0 1452284640 276146192 20148064
11:50:08 755 8192000000 10387217200 10788155392 1178506480 12.576 100 95 185 0 1497547248 297151616 21889152
11:51:12 1547 8192000000 10441907648 10854502400 1183241056 12.620 100 94 185 0 1550572320 344210144 23121120
11:52:18 3738 8192000000 10451740736 10864644096 1184698480 12.634 100 95 185 6 1565498144 359238752 21560912
11:53:26 896 8192000000 10464760656 10895044608 1186501024 12.651 100 100 198 0 1575192992 365626880 23065088
11:54:30 1235 8192000000 10456411968 10920951808 1190162080 12.685 100 88 166 0 1564810720 354781424 19867216
11:55:40 2140 8192000000 10464764112 10885967872 1179808064 12.588 99 75 148 1 1566235264 368952368 17474832
11:56:49 2266 8192000000 10479935840 10933501952 1191768880 12.700 100 71 142 1 1584800480 375333680 17697920
11:57:55 13388 8192000000 10482613904 10924752896 1190906400 12.692 100 55 107 0 1586276944 381201408 14169136
11:59:13 2545 8192000000 10513106016 10971475968 1192587808 12.707 100 75 149 0 1619033904 407805120 18640976
12:00:19 686 8192000000 10516145440 10948874240 1184488928 12.632 100 87 175 0 1622252256 416275104 21488224
12:01:21 6800 8192000000 10463211328 10956996608 1186749152 12.653 100 48 96 1 1567728128 368782544 12196432
12:02:37 8412 8192000000 10357222528 10815860736 1160518928 12.408 100 36 68 0 1462295600 293119520 8657152
12:04:13 24564 8192000000 10220903104 10721083392 1147926976 12.290 99 24 46 0 1329712992 175872096 5913920
12:05:41 34578 8192000000 10157303424 10640678912 1134517120 12.164 100 23 46 0 1240531616 100722464 5292032
12:07:33 2668 8192000000 10100065024 10530029568 1127329168 12.096 98 61 106 1 1202142816 62887120 11926528
12:08:36 15526 8192000000 10105333568 10542067712 1144003408 12.253 99 46 90 1 1212784256 58675760 10105088
12:09:54 63744 8192000000 10116039472 10579005440 1120376384 12.031 98 34 68 0 1210712048 83794992 6540672
12:12:00 93894 8192000000 10137641936 10611716096 1133598544 12.155 99 18 33 0 1216030768 79370240 3061984
12:16:08 61902 8192000000 10085926592 10530033664 1137049488 12.188 99 40 77 1 1191915328 45705440 9160400
12:18:11 61495 8192000000 10075519760 10550104064 1137320368 12.190 99 48 95 0 1177328288 29059920 10948000
12:20:14 16347 8192000000 10070649392 10514341888 1116538928 11.994 98 37 74 0 1162228240 36963104 8726208
12:21:32 42456 8192000000 10096325408 10565713920 1116967072 11.998 98 20 40 0 1171259536 50180064 4112400
12:23:15 26148 8192000000 10085495184 10523680768 1143003312 12.244 100 33 62 0 1187989008 37926832 7058864
12:24:47 100743 8192000000 10121104080 10608189440 1109952976 11.932 97 23 44 0 1197746320 83215904 4577440
12:27:28 109411 8192000000 10138781120 10603208704 1077459952 11.623 94 20 40 0 1179389328 97414080 4515296
12:30:18 100830 8192000000 10058276464 10504892416 1106247584 11.897 98 27 54 0 1174413040 62224752 5940704
12:33:09 14857 8192000000 10151326880 10589261824 1134976256 12.168 99 37 74 0 1244035552 101509008 7550288
12:34:24 133612 8192000000 10070950064 10519187456 1115070128 11.980 98 22 44 0 1171219504 51356752 4792624
12:37:42 26114 8192000000 10068999408 10512703488 1129844816 12.120 100 42 78 0 1176639024 38735360 8058848
12:39:09 33083 8192000000 10045467584 10488938496 1122176400 12.048 99 14 22 0 1164802832 40928400 1698032
12:40:42 46903 8192000000 10055729232 10522128384 1109967120 11.932 98 11 22 0 1140455504 28081328 2407056
12:42:39 101854 8192000000 10157250928 10624024576 1138031680 12.197 100 14 28 0 1232971184 91529024 3410480
12:45:49 46608 8192000000 10209685872 10667823104 1141882272 12.233 99 26 50 1 1316476640 168809440 5784928
12:47:37 45859 8192000000 10175279200 10660110336 1139388656 12.210 99 22 44 0 1292174464 147644048 5141760
12:49:36 76067 8192000000 10142373168 10615447552 1117333280 12.002 97 16 32 0 1218389008 97875504 3180224
12:51:57 9663 8192000000 10139012416 10564538368 1145288016 12.265 100 49 91 1 1233142976 78871072 8983888
12:53:08 36287 8192000000 10142008848 10590519296 1119949232 12.027 99 35 66 0 1219792784 92975936 6867616
12:54:45 16737 8192000000 10121813808 10577543168 1129243984 12.114 100 30 60 0 1202968672 67917328 5807360
12:56:03 19768 8192000000 10113745872 10548719616 1134606720 12.165 100 46 91 1 1205899632 62096448 9196464
12:57:46 24658 8192000000 10125967808 10563624960 1143124192 12.245 100 38 76 0 1223802208 73361360 7316656
12:59:14 125643 8192000000 10085057984 10524119040 1133572640 12.155 99 38 75 0 1176958000 35999152 7386208
13:02:41 69757 8192000000 10125023264 10600898560 1138821600 12.204 99 22 44 0 1223183344 79673824 4687920
13:04:53 70262 8192000000 10059994176 10543304704 1090177968 11.744 95 20 40 0 1183443472 88675152 4590352
13:07:07 54438 8192000000 10098556016 10546774016 1095581888 11.796 97 37 67 0 1153801264 50421632 7797744
13:09:02 89323 8192000000 10064441792 10519703552 1103621968 11.872 98 15 28 0 1182813200 76207488 2983744
13:11:34 48468 8192000000 10070821936 10524905472 1105925616 11.894 98 22 44 1 1159271600 48353312 4992672
13:13:40 41712 8192000000 10104980208 10560794624 1124798640 12.072 99 23 44 0 1192533312 63338448 4396224
13:15:22 62019 8192000000 10103345312 10599948288 1108913200 11.922 98 23 45 1 1209344016 96018864 4411952
13:17:24 35485 8192000000 10114248832 10558365696 1092910896 11.770 97 42 81 0 1185448464 84332192 8205376
13:19:31 25700 8192000000 10120235424 10558418944 1094429280 11.785 97 41 82 0 1155992272 53887744 7675248
13:20:57 37644 8192000000 10105195216 10538995712 1108117200 11.915 98 45 87 1 1202010432 85175712 8717520
13:23:45 54464 8192000000 10043033168 10501087232 1118189808 12.010 99 18 36 0 1156890608 35459888 3240912
13:25:40 36233 8192000000 10042735632 10515456000 1131357872 12.134 100 8 16 0 1164872176 31737712 1776592
13:27:17 54832 8192000000 10044652080 10482659328 1073490832 11.585 95 8 16 0 1094392080 19420432 1480816
13:29:17 59006 8192000000 10050343472 10490810368 1091034688 11.752 97 33 60 0 1136205728 38829376 6341664
13:31:27 25803 8192000000 10073561632 10504335360 1116644032 11.995 100 24 48 0 1165636064 43882096 5109936
13:33:01 14417 8192000000 10067327040 10498445312 1113551472 11.966 99 40 79 0 1154468752 33531600 7385680
13:34:17 15265 8192000000 10092036400 10519445504 1117286016 12.001 99 45 90 0 1178858976 52720480 8852480
13:35:41 80398 8192000000 10096179248 10578108416 1116631760 11.995 99 39 78 0 1225227184 100924704 7670720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-10-2022 13:38:24 to 21-10-2022 13:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 13:38:41 to 21-10-2022 13:38:43.

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          214138
MON$OLDEST_ACTIVE               214139
MON$OLDEST_SNAPSHOT             214139
MON$NEXT_TRANSACTION            214139
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                       2013504
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             754
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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'

21.10.22 13:38:45. 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-10-21 13:38:47 No severe PSQL-related problems occured
21.10.22 13:38:48. End of report.