Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.2023 04: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 15065944 kB
MemAvailable 30412076 kB
Buffers 20 kB
Cached 16587252 kB
SwapCached 3176 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-02-18 04:37:27
DTS_END                         2023-02-18 04:37:27.9880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52310
TRN_ID                          208168
ATT_ID                          622
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: 8372.08 27211 1004649
customer order: creation 161.97 510 19436
customer order: refuse 13.39 476 1607
order to supplier: creation 42.13 1440 5055
order to supplier: removal 3.24 205430 389
invoice (draft): creation 41.58 3545 4990
invoice (draft): removal 48.63 1114 5836
invoice accept: apply 38.13 26796 4576
invoice accept: cancel 43.41 1260 5209
customer reserve: creation 3492.78 255 419134
customer reserve: removal 4216.98 124 506038
realization accept: apply 76.86 319 9223
realization accept: cancel 73.95 191 8874
payment from customer: creation 54.15 199 6498
payment from customer: removal 5.21 149 625
payment to supplier: creation 45.06 219 5407
payment to supplier: removal 3.03 112 364
service: total inventory turnovers 3.51 4831 421
service: total monetary turnovers 6.98 458 838
service: aggregate perf. data 1.03 15150 124
service: refresh index statistics 0.04 281648 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-02-2023 04:38:49 to 18-02-2023 04:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7061
TEST_TIME 2 6784
TEST_TIME 3 6192
TEST_TIME 4 6160
TEST_TIME 5 6069
TEST_TIME 6 5847
TEST_TIME 7 5734
TEST_TIME 8 5533
TEST_TIME 9 5612
TEST_TIME 10 5959
TEST_TIME 11 6200
TEST_TIME 12 6454
TEST_TIME 13 6591
TEST_TIME 14 6775
TEST_TIME 15 6928
TEST_TIME 16 6879
TEST_TIME 17 6862
TEST_TIME 18 7154
TEST_TIME 19 7280
TEST_TIME 20 7453
TEST_TIME 21 7421
TEST_TIME 22 7377
TEST_TIME 23 7383
TEST_TIME 24 7522
TEST_TIME 25 7727
TEST_TIME 26 7879
TEST_TIME 27 8344
TEST_TIME 28 8426
TEST_TIME 29 8298
TEST_TIME 30 8866
TEST_TIME 31 8910
TEST_TIME 32 9395
TEST_TIME 33 9557
TEST_TIME 34 10023
TEST_TIME 35 10537
TEST_TIME 36 10308
TEST_TIME 37 10081
TEST_TIME 38 10068
TEST_TIME 39 9943
TEST_TIME 40 9806
TEST_TIME 41 9788
TEST_TIME 42 9741
TEST_TIME 43 9829
TEST_TIME 44 9695
TEST_TIME 45 9565
TEST_TIME 46 9512
TEST_TIME 47 9511
TEST_TIME 48 9574
TEST_TIME 49 9464
TEST_TIME 50 9332
TEST_TIME 51 9191
TEST_TIME 52 9201
TEST_TIME 53 9079
TEST_TIME 54 9113
TEST_TIME 55 9024
TEST_TIME 56 9017
TEST_TIME 57 8986
TEST_TIME 58 8882
TEST_TIME 59 8775
TEST_TIME 60 8716
TEST_TIME 61 8662
TEST_TIME 62 8612
TEST_TIME 63 8580
TEST_TIME 64 8580
TEST_TIME 65 8540
TEST_TIME 66 8582
TEST_TIME 67 8643
TEST_TIME 68 8639
TEST_TIME 69 8665
TEST_TIME 70 8729
TEST_TIME 71 8838
TEST_TIME 72 8771
TEST_TIME 73 8751
TEST_TIME 74 8788
TEST_TIME 75 8829
TEST_TIME 76 8923
TEST_TIME 77 8914
TEST_TIME 78 8929
TEST_TIME 79 8852
TEST_TIME 80 8788
TEST_TIME 81 8733
TEST_TIME 82 8667
TEST_TIME 83 8653
TEST_TIME 84 8664
TEST_TIME 85 8666
TEST_TIME 86 8619
TEST_TIME 87 8574
TEST_TIME 88 8527
TEST_TIME 89 8471
TEST_TIME 90 8493
TEST_TIME 91 8492
TEST_TIME 92 8503
TEST_TIME 93 8521
TEST_TIME 94 8542
TEST_TIME 95 8563
TEST_TIME 96 8605
TEST_TIME 97 8631
TEST_TIME 98 8802
TEST_TIME 99 8833
TEST_TIME 100 8802
TEST_TIME 101 8844
TEST_TIME 102 8847
TEST_TIME 103 8767
TEST_TIME 104 8727
TEST_TIME 105 8759
TEST_TIME 106 8752
TEST_TIME 107 8719
TEST_TIME 108 8666
TEST_TIME 109 8656
TEST_TIME 110 8649
TEST_TIME 111 8613
TEST_TIME 112 8578
TEST_TIME 113 8548
TEST_TIME 114 8506
TEST_TIME 115 8517
TEST_TIME 116 8514
TEST_TIME 117 8495
TEST_TIME 118 8476
TEST_TIME 119 8443
TEST_TIME 120 8402

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-02-2023 04:38:53 to 18-02-2023 04:38:56.

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 1391931 1391931 0 0.00 0 28633 15 0 0
doc_list_biud 1391920 1391920 0 0.00 0 30501 51 0 0
sp_add_invoice_to_stock 4657 4576 81 1.73 72 227821 26797 0 81
sp_cancel_adding_invoice 5209 5209 0 0.00 8 83885 1260 0 0
sp_cancel_client_order 1607 1607 0 0.00 6 12974 477 0 0
sp_cancel_customer_reserve 506038 506038 0 0.00 1 30523 125 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 4 7658 150 0 0
sp_cancel_pay_to_supplier 364 364 0 0.00 4 2843 113 0 0
sp_cancel_supplier_invoice 5844 5836 8 0.13 3 27614 1115 0 8
sp_cancel_supplier_order 389 389 0 0.00 11 1606959 205430 0 0
sp_cancel_write_off 8874 8874 0 0.00 2 8702 191 0 0
sp_client_order 19436 19436 0 0.00 6 30964 510 0 0
sp_customer_reserve 419134 419134 0 0.00 1 126525 256 0 0
sp_fill_shopping_cart 29476 29476 0 0.00 2 19550 93 0 0
sp_fill_shopping_cart_clo_res 419092 419092 0 0.00 0 13345 20 0 0
sp_get_clo_for_invoice 4527 4527 0 0.00 0 35683 375 0 0
sp_kill_cost_storno 15072 15072 0 0.00 0 16399 83 0 0
sp_kill_qstorno_ret_qs2qd 1027175 1027175 0 0.00 0 30144 54 0 0
sp_kill_qty_storno 541701 541701 0 0.00 0 30499 122 0 0
sp_lock_selected_doc 18999 18999 0 0.00 0 11000 9 0 0
sp_make_cost_storno 25654 25654 0 0.00 0 22514 101 0 0
sp_multiply_rows_for_pdistr 25653 25653 0 0.00 0 6914 43 0 0
sp_multiply_rows_for_qdistr 19435 19435 0 0.00 0 9558 183 0 0
sp_pay_from_customer 6498 6498 0 0.00 5 14798 200 0 0
sp_pay_to_supplier 5407 5407 0 0.00 6 14171 220 0 0
sp_payment_common 11905 11905 0 0.00 2 7397 55 0 0
sp_qd_handle_on_cancel_clo 1607 1607 0 0.00 1 12963 386 0 0
sp_qd_handle_on_invoice_upd_sts 9734 9734 0 0.00 0 21427 585 0 0
sp_qd_handle_on_reserve_upd_sts 9223 9223 0 0.00 0 13543 70 0 0
sp_reserve_write_off 9223 9223 0 0.00 8 23890 319 0 0
sp_supplier_invoice 4990 4990 0 0.00 9 76383 3546 0 0
sp_supplier_order 5055 5055 0 0.00 8 44083 1440 0 0
srv_aggregate_perf_data 124 124 0 0.00 2165 121338 15150 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 807 122819 32938 0 0
srv_find_qd_qs_mism 960377 960377 0 0.00 0 28629 11 0 0
srv_make_invnt_saldo 421 421 0 0.00 62 84888 4831 0 0
srv_make_money_saldo 838 838 0 0.00 1 14798 458 0 0
srv_recalc_idx_stat 5 5 0 0.00 81823 498365 281649 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4578 4578 0 0.00 1 13220 1010 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 1 6239 441 0 0
t$perf-norm:sp_cancel_client_order 1607 1607 0 0.00 0 5080 30 0 0
t$perf-norm:sp_cancel_customer_reserve 602 602 0 0.00 0 2572 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 1214 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 364 364 0 0.00 0 4817 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 4682 40 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 111107 8152 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 5212 31 0 0
t$perf-norm:sp_client_order 19436 19436 0 0.00 0 7253 39 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 7019 25 0 0
t$perf-norm:sp_pay_from_customer 6498 6498 0 0.00 0 6782 21 0 0
t$perf-norm:sp_pay_to_supplier 5407 5407 0 0.00 0 7280 20 0 0
t$perf-norm:sp_reserve_write_off 9223 9223 0 0.00 0 8607 27 0 0
t$perf-norm:sp_supplier_invoice 4990 4990 0 0.00 0 3687 31 0 0
t$perf-norm:sp_supplier_order 5055 5055 0 0.00 0 5432 35 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 2413 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 482 13 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 496 15 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 4061 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 192 77 0 0
v_add_invoice_to_stock 9208 9046 162 1.75 0 8946 25 0 162
v_all_customers 35396 35396 0 0.00 0 128 4 0 0
v_all_suppliers 28482 28482 0 0.00 0 104 5 0 0
v_all_wares 38870 38870 0 0.00 0 106 4 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 8695 38 0 0
v_cancel_client_order 3214 3214 0 0.00 0 9004 14 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 3632 16 0 0
v_cancel_customer_reserve 1204 1204 0 0.00 0 8279 17 0 0
v_cancel_payment_to_supplier 728 728 0 0.00 0 1030 13 0 0
v_cancel_supplier_invoice 748 732 16 2.13 0 462 22 0 16
v_cancel_supplier_order 778 778 0 0.00 0 2463 17 0 0
v_cancel_write_off 1878 1878 0 0.00 0 4071 27 0 0
v_max_id_clo_ord 5055 5055 0 0.00 0 3736 6 0 0
v_max_id_clo_res 929 929 0 0.00 0 3590 19 0 0
v_max_id_ord_sup 4985 4985 0 0.00 0 6706 9 0 0
v_max_non_paid_invoice 1206 1206 0 0.00 0 227 8 0 0
v_max_non_paid_realizn 3331 3331 0 0.00 0 3404 10 0 0
v_min_id_clo_ord 5055 5055 0 0.00 0 10381 18 0 0
v_min_id_clo_res 928 928 0 0.00 0 6932 24 0 0
v_min_id_ord_sup 4985 4985 0 0.00 0 5416 10 0 0
v_min_non_paid_invoice 5407 5407 0 0.00 0 6402 19 0 0
v_min_non_paid_realizn 6498 6498 0 0.00 0 6902 26 0 0
v_our_firm 9808 9808 0 0.00 0 108 5 0 0
v_reserve_write_off 18446 18446 0 0.00 0 8911 14 0 0
x_lock_depdocs_on_canc_invoice 10679 10679 0 0.00 0 20052 142 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 3999 404 0 0
x_make_qstorno_1000_1200 5055 5055 0 0.00 3 44069 1250 0 0
x_make_qstorno_1200_2000 4990 4990 0 0.00 4 76328 3292 0 0
x_make_qstorno_2100_3300 419134 419134 0 0.00 0 126493 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-02-2023 04:38:57 to 18-02-2023 04:39:01.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:32 14 8192000000 8802022624 8814579712 5757808 0.070 3 1 2 0 5858976 65392 35776
02:08:32 33881 8192000000 9742329008 10042740736 814867088 9.047 100 91 183 0 845156976 8243888 22046000
02:10:09 19800 8192000000 9772076464 10081755136 846212864 9.362 100 91 182 0 885794352 17917312 21664176
02:11:35 11379 8192000000 9802166704 10124652544 875653776 9.656 100 82 162 0 915613888 21291040 18669072
02:12:56 12589 8192000000 9879578672 10210013184 921797248 10.114 100 87 172 1 986623360 45948000 18878112
02:14:14 3290 8192000000 9933494048 10273075200 958769248 10.477 100 88 172 0 1050145104 73338544 18037312
02:15:20 1952 8192000000 9952964640 10304442368 985207200 10.735 100 99 183 1 1071061968 67265056 18589712
02:16:24 1775 8192000000 9987245024 10348003328 1016705424 11.040 100 85 167 1 1105710208 72447248 16557536
02:17:27 992 8192000000 10006850768 10378416128 1039907008 11.264 100 96 182 0 1129157408 70918624 18331776
02:18:29 1040 8192000000 10025336624 10399641600 1060063088 11.457 100 98 189 0 1139484784 60329600 19092096
02:19:31 757 8192000000 10034818752 10409242624 1064659280 11.501 100 96 186 1 1151067744 69651200 16757264
02:20:32 1069 8192000000 10035918528 10414960640 1065514704 11.509 100 97 187 0 1158423424 74437344 18471376
02:21:35 871 8192000000 10039966304 10422968320 1073243840 11.583 100 90 173 1 1161862832 70743040 17875952
02:22:37 848 8192000000 10040882016 10427113472 1076919248 11.618 100 90 174 1 1162343936 67564880 17859808
02:23:44 1069 8192000000 10039146400 10430341120 1083546928 11.681 100 86 157 2 1169567584 68912832 17107824
02:24:47 1170 8192000000 10048278704 10439872512 1087016464 11.714 100 97 184 4 1175444864 68168240 20260160
02:25:49 1120 8192000000 10066255792 10455003136 1087864208 11.722 100 98 183 0 1189238112 83083744 18290160
02:26:51 948 8192000000 10053059648 10439008256 1088870160 11.732 100 95 186 2 1179481680 70469616 20141904
02:27:53 1339 8192000000 10027980272 10413187072 1080121616 11.649 100 78 145 0 1148697568 53333472 15242480
02:28:56 972 8192000000 10036778672 10422767616 1081198832 11.659 99 86 163 1 1154257056 56091536 16966688
02:29:58 942 8192000000 10035996272 10419195904 1065685936 11.511 100 95 177 1 1153980800 70470608 17824256
02:31:00 1072 8192000000 10024202448 10410176512 1077222512 11.621 100 93 175 1 1147765024 53267856 17274656
02:32:03 1305 8192000000 10043231296 10427879424 1065746000 11.511 100 97 190 0 1166665296 80541328 20377968
02:33:05 1765 8192000000 10013920480 10403033088 1059286384 11.450 100 72 133 1 1129781360 56104128 14390848
02:34:08 5175 8192000000 9970001504 10342514688 1014778800 11.022 97 71 132 1 1086874064 59101920 12993344
02:35:14 936 8192000000 9993880240 10367197184 1032464240 11.192 100 91 178 0 1119950192 67443728 20042224
02:36:16 1200 8192000000 9986468544 10357628928 1031816464 11.186 100 87 161 1 1108607232 60016672 16774096
02:37:18 1336 8192000000 9992670944 10368065536 1038806608 11.253 100 74 146 1 1115076320 62516896 13752816
02:38:24 1492 8192000000 10017676624 10393653248 1050468048 11.365 100 85 157 1 1136005648 70169136 15368464
02:39:29 2950 8192000000 10008112560 10390253568 1066303648 11.517 100 75 133 3 1132433632 53048256 13081728
02:40:38 4185 8192000000 10056164032 10438459392 1082484256 11.671 100 76 143 3 1173711376 76046976 15180144
02:41:45 4250 8192000000 10089188000 10476990464 1098809488 11.826 100 74 137 1 1204926544 91542336 14574720
02:42:55 1708 8192000000 10128028304 10513436672 1115746928 11.987 100 90 171 1 1245225216 111466640 18011648
02:44:02 13074 8192000000 10181657872 10583322624 1123713648 12.062 100 74 139 3 1303011600 163235056 16062896
02:45:17 7294 8192000000 10205961776 10607759360 1131831920 12.139 100 62 122 0 1318087008 172431440 13823648
02:46:30 2819 8192000000 10225356464 10638999552 1140280672 12.218 100 86 164 1 1338556576 179879328 18396576
02:47:38 2428 8192000000 10276050224 10679275520 1153112848 12.339 100 91 173 0 1388056144 215215920 19727376
02:48:44 1659 8192000000 10303822176 10706767872 1165855840 12.458 100 83 156 0 1414947088 230593856 18497392
02:49:48 3582 8192000000 10348315648 10765791232 1174541840 12.539 100 86 172 0 1462327632 268036848 19748944
02:50:58 827 8192000000 10357047760 10769235968 1178155488 12.573 100 95 188 0 1468199264 268926688 21117088
02:52:06 1270 8192000000 10395569936 10803097600 1191159552 12.694 100 97 182 3 1502199568 290387792 20652224
02:53:11 899 8192000000 10408173728 10817556480 1192026784 12.702 100 98 194 1 1516746896 303102960 21617152
02:54:14 1224 8192000000 10432249280 10864455680 1179968736 12.590 98 95 180 0 1539661552 337962656 21730160
02:55:19 1675 8192000000 10466622448 10909753344 1209793904 12.867 100 85 167 0 1577344352 346522864 21027584
02:56:25 1129 8192000000 10494869408 10922840064 1209594048 12.865 100 94 183 3 1596839376 365894048 21351280
02:57:28 1916 8192000000 10513908560 10930987008 1205338208 12.826 100 91 172 2 1619168544 393031520 20798816
02:58:34 1895 8192000000 10565366112 10982469632 1198450592 12.762 100 100 182 2 1665650496 445047920 22151984
02:59:42 854 8192000000 10570256752 11009654784 1197616528 12.754 100 95 186 0 1670109040 449888272 22604240
03:00:46 1285 8192000000 10634100048 11089530880 1216433184 12.929 100 95 190 0 1737860464 497497408 23929872
03:01:52 797 8192000000 10673458576 11110944768 1206808208 12.840 99 87 174 0 1781448880 553011680 21628992
03:02:56 1483 8192000000 10661540208 11083825152 1193008208 12.711 100 78 155 1 1764323056 551786736 19528112
03:04:01 2905 8192000000 10610783488 11101130752 1190090848 12.684 100 74 136 0 1718854224 511567408 17195968
03:05:19 19626 8192000000 10578812560 11062554624 1152293888 12.331 98 42 81 0 1667248592 503881040 11073664
03:06:39 15939 8192000000 10562230864 11071152128 1178368048 12.575 100 39 76 0 1695644560 507090640 10185872
03:07:57 32558 8192000000 10513909888 10975010816 1163390272 12.435 99 35 70 1 1612253568 439781728 9081568
03:10:24 19654 8192000000 10359665584 10858106880 1145961088 12.272 100 27 53 0 1467681872 315006048 6714736
03:11:46 48089 8192000000 10168952304 10640138240 1095432528 11.794 95 23 46 0 1273866000 172770176 5663296
03:13:44 27937 8192000000 10147435760 10573484032 1128633696 12.108 100 50 91 1 1239048224 101112928 9301600
03:15:21 57123 8192000000 10167376032 10612563968 1148264192 12.293 100 32 56 2 1266085232 112358496 5462544
03:17:22 47658 8192000000 10145738912 10611535872 1107058608 11.905 98 24 47 0 1233321760 120858256 5404896
03:19:11 30412 8192000000 10102686640 10568556544 1115689056 11.986 99 14 26 0 1198322576 79708752 2924768
03:20:45 32505 8192000000 10074534624 10506227712 1107693200 11.911 98 28 56 0 1158436416 44391120 6352096
03:22:21 43792 8192000000 10127802448 10560741376 1125328816 12.077 100 34 68 0 1232503216 99607488 7566912
03:24:07 72201 8192000000 10075923424 10551095296 1085537216 11.700 97 13 23 0 1176756336 88677568 2541552
03:27:19 74038 8192000000 10057979072 10482765824 1114731968 11.977 99 52 97 0 1139752256 14762928 10257360
03:29:37 41914 8192000000 10105843392 10553626624 1132140128 12.142 100 36 71 0 1233728464 93593696 7994640
03:31:32 80657 8192000000 10082265824 10535116800 1128208096 12.104 99 11 22 0 1172986064 41811856 2966112
03:34:23 65054 8192000000 10044753328 10524401664 1111476800 11.946 97 14 26 0 1143109568 28613760 3019008
03:36:36 64622 8192000000 10079017136 10538057728 1141964832 12.234 100 22 44 0 1182654128 36335312 4353984
03:39:25 76669 8192000000 10114959568 10602401792 1136271376 12.180 99 12 24 0 1186850704 48241392 2337936
03:41:43 32848 8192000000 10140224320 10584674304 1160218032 12.405 100 23 46 0 1238502224 73405664 4878528
03:43:28 32760 8192000000 10151722912 10608795648 1127658464 12.099 97 29 58 0 1245888272 111743808 6486000
03:45:02 23108 8192000000 10120582720 10567340032 1131113456 12.132 98 36 70 0 1216112816 77267760 7731600
03:46:29 30740 8192000000 10167106192 10637258752 1161012512 12.413 100 33 66 0 1277942400 110023456 6906432
03:48:13 65123 8192000000 10097363616 10538131456 1143785568 12.251 99 25 50 0 1179620256 30519504 5315184
03:50:18 6335 8192000000 10125887136 10546573312 1136837296 12.186 99 67 131 0 1218142256 68022208 13282752
03:51:25 63340 8192000000 10104190240 10574802944 1109765456 11.930 97 39 72 1 1209087536 92344864 6977216
03:53:30 121445 8192000000 10068434352 10552156160 1086945776 11.714 95 18 33 1 1145143568 54090176 4107616
03:56:44 122819 8192000000 10091187376 10536468480 1117204960 12.001 98 41 81 0 1142628848 17155104 8268784
04:00:14 27628 8192000000 10130645056 10585116672 1134766480 12.166 99 31 62 0 1232092848 91011792 6314576
04:01:46 52163 8192000000 10053814272 10553556992 1098316080 11.822 97 7 14 0 1146531904 46918000 1297824
04:03:38 49516 8192000000 10097379536 10545004544 1128433584 12.107 99 23 44 0 1194024096 60598016 4992496
04:05:33 58025 8192000000 10180438464 10632089600 1149415968 12.304 100 19 37 0 1262605760 108997216 4192576
04:07:32 19205 8192000000 10184446672 10656825344 1127174384 12.095 98 33 65 0 1269157200 133733536 8249280
04:08:58 15467 8192000000 10225000864 10664124416 1156587584 12.371 100 43 84 0 1313395552 147180944 9627024
04:10:20 19038 8192000000 10240764592 10678550528 1158091104 12.385 100 47 91 2 1337350128 168634128 10624896
04:11:43 33717 8192000000 10188084464 10659004416 1124491344 12.069 98 26 50 0 1309232384 179027072 5713968
04:13:17 40612 8192000000 10206729328 10677653504 1132956816 12.149 100 31 62 0 1324463936 184694992 6812128
04:15:08 49955 8192000000 10077604864 10523709440 1059150512 11.448 93 23 46 0 1152564992 88705888 4708592
04:17:00 30137 8192000000 10119115264 10559598592 1104254256 11.878 98 43 84 0 1178935456 66315280 8365920
04:18:54 30190 8192000000 10159344896 10592632832 1124257232 12.067 99 42 82 0 1231095056 98626528 8211296
04:20:38 99055 8192000000 10099171088 10576359424 1128726240 12.109 100 27 54 0 1221473904 86741056 6006608
04:23:18 83637 8192000000 10057228720 10542432256 1121001760 12.036 99 11 22 0 1175236224 52012224 2222240
04:25:42 67206 8192000000 10088617264 10533322752 1123342864 12.059 99 24 48 0 1176454080 48075376 5035840
04:28:01 93041 8192000000 10061407152 10519126016 1127261760 12.096 100 24 49 0 1166264112 33624240 5378112
04:30:47 33285 8192000000 10071006848 10522030080 1127403504 12.097 99 17 32 0 1178408688 47553392 3451792
04:32:24 61164 8192000000 10071165632 10565058560 1125816768 12.082 99 23 46 0 1185358160 54222352 5319040
04:34:28 58045 8192000000 10066428656 10522435584 1140247136 12.218 100 10 20 0 1172113040 29673792 2192112
04:36:27 4612 8192000000 10065230048 10496634880 1121330544 12.040 99 50 95 1 1155858816 25249104 9279168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-02-2023 04:39:03 to 18-02-2023 04:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-02-2023 04:39:21 to 18-02-2023 04:39:23.

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          206632
MON$OLDEST_ACTIVE               210122
MON$OLDEST_SNAPSHOT             210122
MON$NEXT_TRANSACTION            210122
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                       2060832
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             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2902 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2902 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2902 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2902 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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Sat Feb 18 04:37:33 2023
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:168: 
--- end of diff output ---

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

18.02.23 04:39:24. 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-02-18 04:39:27 No severe PSQL-related problems occured
18.02.23 04:39:28. End of report.