Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2022 10: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 15650236 kB
MemAvailable 30623588 kB
Buffers 0 kB
Cached 16098512 kB
SwapCached 2068 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-09-27 10:37:20
DTS_END                         2022-09-27 10:37:20.2300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44416
TRN_ID                          215138
ATT_ID                          654
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: 8525.28 24805 1023033
customer order: creation 165.31 444 19837
customer order: refuse 13.41 432 1609
order to supplier: creation 42.72 1271 5126
order to supplier: removal 3.28 146787 394
invoice (draft): creation 44.28 3184 5313
invoice (draft): removal 47.52 948 5702
invoice accept: apply 40.55 26392 4866
invoice accept: cancel 42.68 1125 5122
customer reserve: creation 3716.83 256 446020
customer reserve: removal 4133.84 92 496061
realization accept: apply 79.83 299 9579
realization accept: cancel 72.97 172 8756
payment from customer: creation 56.03 177 6724
payment from customer: removal 5.53 131 664
payment to supplier: creation 45.96 188 5515
payment to supplier: removal 2.89 126 347
service: total inventory turnovers 3.90 3748 468
service: total monetary turnovers 6.83 383 820
service: aggregate perf. data 0.88 16412 106
service: refresh index statistics 0.03 293542 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2022 10:39:11 to 27-09-2022 10:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8765
TEST_TIME 2 7417
TEST_TIME 3 6263
TEST_TIME 4 6168
TEST_TIME 5 6087
TEST_TIME 6 6223
TEST_TIME 7 6164
TEST_TIME 8 6096
TEST_TIME 9 6250
TEST_TIME 10 6206
TEST_TIME 11 6353
TEST_TIME 12 6456
TEST_TIME 13 6783
TEST_TIME 14 6741
TEST_TIME 15 6791
TEST_TIME 16 6997
TEST_TIME 17 7134
TEST_TIME 18 7719
TEST_TIME 19 7976
TEST_TIME 20 7824
TEST_TIME 21 7937
TEST_TIME 22 8618
TEST_TIME 23 8590
TEST_TIME 24 8877
TEST_TIME 25 8823
TEST_TIME 26 8662
TEST_TIME 27 9195
TEST_TIME 28 9879
TEST_TIME 29 10196
TEST_TIME 30 10401
TEST_TIME 31 10479
TEST_TIME 32 10520
TEST_TIME 33 10493
TEST_TIME 34 10735
TEST_TIME 35 10705
TEST_TIME 36 10420
TEST_TIME 37 10236
TEST_TIME 38 10090
TEST_TIME 39 9973
TEST_TIME 40 9791
TEST_TIME 41 9739
TEST_TIME 42 9698
TEST_TIME 43 9686
TEST_TIME 44 9739
TEST_TIME 45 9600
TEST_TIME 46 9651
TEST_TIME 47 9679
TEST_TIME 48 9566
TEST_TIME 49 9457
TEST_TIME 50 9472
TEST_TIME 51 9317
TEST_TIME 52 9380
TEST_TIME 53 9301
TEST_TIME 54 9295
TEST_TIME 55 9197
TEST_TIME 56 9069
TEST_TIME 57 9047
TEST_TIME 58 9025
TEST_TIME 59 8933
TEST_TIME 60 8906
TEST_TIME 61 8874
TEST_TIME 62 8830
TEST_TIME 63 8847
TEST_TIME 64 8799
TEST_TIME 65 8844
TEST_TIME 66 8933
TEST_TIME 67 8895
TEST_TIME 68 9070
TEST_TIME 69 9049
TEST_TIME 70 9075
TEST_TIME 71 9036
TEST_TIME 72 9025
TEST_TIME 73 8996
TEST_TIME 74 8917
TEST_TIME 75 8915
TEST_TIME 76 8854
TEST_TIME 77 8850
TEST_TIME 78 8794
TEST_TIME 79 8729
TEST_TIME 80 8648
TEST_TIME 81 8706
TEST_TIME 82 8733
TEST_TIME 83 8724
TEST_TIME 84 8706
TEST_TIME 85 8736
TEST_TIME 86 8775
TEST_TIME 87 8772
TEST_TIME 88 8780
TEST_TIME 89 8780
TEST_TIME 90 8852
TEST_TIME 91 8891
TEST_TIME 92 8910
TEST_TIME 93 8872
TEST_TIME 94 8797
TEST_TIME 95 8814
TEST_TIME 96 8753
TEST_TIME 97 8757
TEST_TIME 98 8804
TEST_TIME 99 8784
TEST_TIME 100 8749
TEST_TIME 101 8786
TEST_TIME 102 8756
TEST_TIME 103 8762
TEST_TIME 104 8808
TEST_TIME 105 8771
TEST_TIME 106 8841
TEST_TIME 107 8848
TEST_TIME 108 8826
TEST_TIME 109 8812
TEST_TIME 110 8881
TEST_TIME 111 8821
TEST_TIME 112 8805
TEST_TIME 113 8786
TEST_TIME 114 8768
TEST_TIME 115 8735
TEST_TIME 116 8698
TEST_TIME 117 8660
TEST_TIME 118 8644
TEST_TIME 119 8616
TEST_TIME 120 8557

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2022 10:39:15 to 27-09-2022 10:39:18.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1434572 1434572 0 0.00 0 26310 13 0 0
doc_list_biud 1434559 1434559 0 0.00 0 31546 38 0 0
sp_add_invoice_to_stock 4991 4866 125 2.50 72 281248 26392 0 125
sp_cancel_adding_invoice 5122 5122 0 0.00 8 100509 1126 0 0
sp_cancel_client_order 1609 1609 0 0.00 5 15058 432 0 0
sp_cancel_customer_reserve 496061 496061 0 0.00 1 31556 92 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 6252 131 0 0
sp_cancel_pay_to_supplier 347 347 0 0.00 4 5220 126 0 0
sp_cancel_supplier_invoice 5710 5702 8 0.14 2 22564 949 0 8
sp_cancel_supplier_order 394 394 0 0.00 15 1187388 146787 0 0
sp_cancel_write_off 8756 8756 0 0.00 2 21680 172 0 0
sp_client_order 19837 19837 0 0.00 5 27203 445 0 0
sp_customer_reserve 446020 446020 0 0.00 0 46691 256 0 0
sp_fill_shopping_cart 30266 30266 0 0.00 1 49515 80 0 0
sp_fill_shopping_cart_clo_res 445976 445976 0 0.00 0 21460 20 0 0
sp_get_clo_for_invoice 4808 4808 0 0.00 0 20773 335 0 0
sp_kill_cost_storno 14889 14889 0 0.00 0 8306 70 0 0
sp_kill_qstorno_ret_qs2qd 1006974 1006974 0 0.00 0 31533 41 0 0
sp_kill_qty_storno 532031 532031 0 0.00 0 31544 95 0 0
sp_lock_selected_doc 19721 19721 0 0.00 0 8043 7 0 0
sp_make_cost_storno 26626 26626 0 0.00 0 17119 82 0 0
sp_multiply_rows_for_pdistr 26626 26626 0 0.00 0 8630 38 0 0
sp_multiply_rows_for_qdistr 19836 19836 0 0.00 0 9917 176 0 0
sp_pay_from_customer 6724 6724 0 0.00 5 15813 177 0 0
sp_pay_to_supplier 5515 5515 0 0.00 6 23746 188 0 0
sp_payment_common 12238 12238 0 0.00 2 8079 38 0 0
sp_qd_handle_on_cancel_clo 1609 1609 0 0.00 0 15025 335 0 0
sp_qd_handle_on_invoice_upd_sts 9930 9930 0 0.00 0 15154 482 0 0
sp_qd_handle_on_reserve_upd_sts 9579 9579 0 0.00 0 13579 66 0 0
sp_reserve_write_off 9579 9579 0 0.00 8 23748 299 0 0
sp_supplier_invoice 5313 5313 0 0.00 9 107624 3184 0 0
sp_supplier_order 5126 5126 0 0.00 7 30975 1272 0 0
srv_aggregate_perf_data 106 106 0 0.00 2454 152260 16413 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 756 115013 36431 0 0
srv_find_qd_qs_mism 976690 976690 0 0.00 0 26309 10 0 0
srv_make_invnt_saldo 468 468 0 0.00 74 64494 3749 0 0
srv_make_money_saldo 820 820 0 0.00 1 25327 383 0 0
srv_recalc_idx_stat 4 4 0 0.00 94824 457330 293543 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 4868 4868 0 0.00 1 13073 813 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 8024 443 0 0
t$perf-norm:sp_cancel_client_order 1609 1609 0 0.00 0 3343 26 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 1065 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 2497 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 347 347 0 0.00 0 1593 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 2827 44 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 96510 6421 0 0
t$perf-norm:sp_cancel_write_off 918 918 0 0.00 0 2672 15 0 0
t$perf-norm:sp_client_order 19837 19837 0 0.00 0 12504 36 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 2204 15 0 0
t$perf-norm:sp_pay_from_customer 6724 6724 0 0.00 0 10844 21 0 0
t$perf-norm:sp_pay_to_supplier 5515 5515 0 0.00 0 7602 17 0 0
t$perf-norm:sp_reserve_write_off 9579 9579 0 0.00 0 10665 26 0 0
t$perf-norm:sp_supplier_invoice 5313 5313 0 0.00 0 7497 31 0 0
t$perf-norm:sp_supplier_order 5126 5126 0 0.00 0 10179 33 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 571 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 7319 117 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 2795 15 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 224 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 627 168 0 0
v_add_invoice_to_stock 9852 9602 250 2.53 0 8417 31 0 250
v_all_customers 36001 36001 0 0.00 0 121 3 0 0
v_all_suppliers 28975 28975 0 0.00 0 125 4 0 0
v_all_wares 39668 39668 0 0.00 0 75 3 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 828 13 0 0
v_cancel_client_order 3218 3218 0 0.00 0 11491 26 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 4162 16 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1296 11 0 0
v_cancel_payment_to_supplier 694 694 0 0.00 0 5148 20 0 0
v_cancel_supplier_invoice 774 758 16 2.06 0 3629 34 0 16
v_cancel_supplier_order 788 788 0 0.00 0 2684 15 0 0
v_cancel_write_off 1836 1836 0 0.00 0 11978 35 0 0
v_max_id_clo_ord 5121 5121 0 0.00 0 434 4 0 0
v_max_id_clo_res 966 966 0 0.00 0 3406 11 0 0
v_max_id_ord_sup 5308 5308 0 0.00 0 6834 7 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 206 7 0 0
v_max_non_paid_realizn 3574 3574 0 0.00 0 2241 7 0 0
v_min_id_clo_ord 5121 5121 0 0.00 0 3010 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 4514 17 0 0
v_min_id_ord_sup 5308 5308 0 0.00 0 6291 12 0 0
v_min_non_paid_invoice 5514 5514 0 0.00 0 3599 10 0 0
v_min_non_paid_realizn 6723 6723 0 0.00 0 6009 14 0 0
v_our_firm 9962 9962 0 0.00 0 124 4 0 0
v_reserve_write_off 19153 19153 0 0.00 0 13896 17 0 0
x_lock_depdocs_on_canc_invoice 10445 10445 0 0.00 0 18415 119 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 3274 345 0 0
x_make_qstorno_1000_1200 5126 5126 0 0.00 3 30770 1129 0 0
x_make_qstorno_1200_2000 5313 5313 0 0.00 4 103529 2947 0 0
x_make_qstorno_2100_3300 446018 446018 0 0.00 0 46689 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2022 10:39:19 to 27-09-2022 10:39:23.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:24 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:25 28282 8192000000 9731545920 10030166016 798419776 8.880 100 88 176 0 827074720 7710944 20944000
08:09:57 28508 8192000000 9770228592 10078703616 840988224 9.310 100 84 168 0 873231760 12949344 19294192
08:11:31 11676 8192000000 9800448656 10120937472 874024704 9.640 100 85 170 2 915001728 21684912 19292112
08:12:52 6123 8192000000 9884449056 10215333888 914453088 10.041 100 88 176 0 994400432 60563408 19383936
08:14:01 1915 8192000000 9923224880 10263605248 948408144 10.375 100 93 181 1 1038517696 71699312 18410240
08:15:05 1782 8192000000 9951888256 10302771200 987221648 10.754 100 91 175 1 1068748144 63552848 17973648
08:16:09 1508 8192000000 9988602832 10349654016 1008927216 10.965 100 91 181 0 1100796304 73773104 18095984
08:17:12 958 8192000000 10033287984 10403274752 1033072208 11.198 100 93 182 1 1155749024 105236784 17440032
08:18:15 966 8192000000 10015484656 10386956288 1052302624 11.383 100 97 187 2 1135336736 64469120 18564992
08:19:17 1082 8192000000 10023245104 10398818304 1066615696 11.520 100 96 187 0 1145827216 60586816 18624704
08:20:20 1788 8192000000 10048099824 10429022208 1072394784 11.575 100 76 146 0 1175385776 88901600 14089392
08:21:24 1121 8192000000 10054467296 10436358144 1079357008 11.641 100 98 191 0 1177044416 78818160 18869248
08:22:27 1147 8192000000 10059309408 10446389248 1084911504 11.694 100 91 170 0 1178028336 75932432 17184400
08:23:29 7734 8192000000 10034403392 10417254400 1092984400 11.771 100 88 162 3 1154073376 43171552 17917424
08:24:37 4549 8192000000 10051651088 10437042176 1095890928 11.799 100 83 149 3 1172216448 62407632 13917888
08:25:44 6421 8192000000 10038542304 10429845504 1097871920 11.817 100 75 139 1 1179964688 68853792 13238976
08:26:51 1707 8192000000 10054334944 10449747968 1097574752 11.815 100 82 160 2 1178243776 65105424 15563600
08:27:53 4141 8192000000 10061417648 10458820608 1099633200 11.834 100 64 114 1 1174641104 63128464 11879440
08:28:58 1464 8192000000 10065895680 10453041152 1088738704 11.731 100 91 176 4 1188397056 81401152 18257200
08:30:02 1122 8192000000 10055969344 10442395648 1097112544 11.810 100 97 186 1 1180080240 64077920 18889776
08:31:04 1060 8192000000 10048793600 10430640128 1077918192 11.628 100 87 162 0 1171947152 77387984 16640976
08:32:06 1069 8192000000 10023788800 10407763968 1077477824 11.623 100 94 171 1 1146836640 52476976 16881840
08:33:08 1060 8192000000 10019858240 10397216768 1052039472 11.380 100 93 169 1 1143069008 73404208 17625328
08:34:10 981 8192000000 9975039840 10348982272 1030571728 11.174 100 79 144 0 1101129424 55066064 15491632
08:35:13 1856 8192000000 9982513216 10363518976 1036098448 11.227 100 78 143 1 1106580496 55852608 14629440
08:36:16 1068 8192000000 9975855040 10349838336 1033404000 11.201 99 84 160 2 1093875968 43657408 16814560
08:37:17 1031 8192000000 10018700240 10396626944 1050686336 11.367 100 82 157 5 1137987008 71747136 15553536
08:38:26 7861 8192000000 10027826896 10411417600 1073523120 11.586 100 73 132 3 1142590080 55951344 13115616
08:39:38 4298 8192000000 10030234064 10420752384 1085127184 11.696 100 77 150 0 1159435232 57513328 16794720
08:40:45 4029 8192000000 10071894112 10457096192 1092344656 11.765 100 83 150 4 1188705744 80613440 15747648
08:41:56 1228 8192000000 10098597776 10488188928 1103025904 11.866 100 75 142 2 1216836272 99065632 14744736
08:43:00 7983 8192000000 10123000624 10521714688 1117522480 12.004 100 73 144 0 1238998576 106985392 14490704
08:44:11 2743 8192000000 10150330928 10539405312 1128754320 12.110 100 82 151 2 1258454608 114645360 15054928
08:45:17 5184 8192000000 10185616832 10583789568 1141448688 12.229 100 79 149 3 1305580528 148195488 15936352
08:46:26 2962 8192000000 10223943248 10635632640 1155743568 12.363 100 74 146 1 1343347024 171276560 16326896
08:47:36 1533 8192000000 10283597296 10682875904 1161852800 12.421 100 92 178 0 1389792560 208029232 19910528
08:48:41 2880 8192000000 10274147920 10680197120 1150750144 12.317 99 77 150 2 1386318304 218510928 17057232
08:49:47 1304 8192000000 10317764880 10722938880 1173181808 12.527 100 90 177 0 1426904512 233272736 20449968
08:50:50 855 8192000000 10339552128 10743054336 1170983024 12.506 100 94 180 0 1451500480 259937808 20579648
08:51:55 746 8192000000 10383417744 10792067072 1178568672 12.577 100 95 186 2 1497596176 295316672 23710832
08:52:59 1818 8192000000 10389733488 10814365696 1174663184 12.540 100 86 164 0 1500523440 306892864 18967392
08:54:04 3967 8192000000 10380911136 10835689472 1190767024 12.691 100 85 165 2 1491183840 281645344 18771472
08:55:10 851 8192000000 10410326224 10873470976 1196610128 12.745 100 97 188 0 1519607488 301136880 21860480
08:56:15 2677 8192000000 10387007616 10831409152 1197015872 12.749 100 63 122 0 1506501424 294758848 14726704
08:57:22 1317 8192000000 10442693680 10863026176 1198186816 12.759 100 72 137 2 1545623392 330943392 16493184
08:58:29 1206 8192000000 10455564640 10897608704 1187041072 12.656 100 87 174 0 1562922944 354406672 21475200
08:59:33 4121 8192000000 10419087984 10885382144 1174963152 12.543 100 63 124 1 1529309376 338618672 15727552
09:00:40 3849 8192000000 10408522416 10835156992 1175401296 12.547 100 50 98 0 1513788768 325680256 12707216
09:02:22 11327 8192000000 10407620608 10836815872 1152403232 12.332 99 44 88 1 1498638960 335116704 11119024
09:03:42 19807 8192000000 10393313312 10875170816 1129178784 12.114 99 32 60 0 1502304592 364288080 8837728
09:05:05 24594 8192000000 10261668544 10719920128 1139614720 12.212 100 44 86 0 1375176080 224413440 11147920
09:06:35 17361 8192000000 10178716656 10609348608 1141558160 12.230 100 37 71 1 1275574576 126197552 7818864
09:07:56 63869 8192000000 10139162608 10609172480 1108057632 11.914 96 18 36 0 1232366992 120012128 4297232
09:10:02 63383 8192000000 10141147888 10650230784 1114582032 11.976 97 15 30 0 1254623248 136455632 3585584
09:12:25 113114 8192000000 10071075232 10501836800 1113162144 11.962 98 52 97 1 1159756624 36012192 10582288
09:15:59 73027 8192000000 10107752272 10553946112 1110212368 11.934 98 16 32 0 1200152816 86259008 3681440
09:18:15 40919 8192000000 10155043776 10617556992 1129225312 12.114 100 19 37 0 1257845056 123762432 4857312
09:20:16 25680 8192000000 10156773328 10606534656 1124858224 12.073 100 14 28 0 1267525360 138851664 3815472
09:21:45 73536 8192000000 10100075424 10537086976 1094586368 11.786 97 23 43 0 1162131280 62239168 5305744
09:24:00 84012 8192000000 10077312320 10562637824 1113999760 11.970 99 17 34 0 1170894656 52954976 3939920
09:27:15 56717 8192000000 10104658944 10615894016 1136179680 12.180 100 19 38 0 1219125680 79265088 3680912
09:29:13 36118 8192000000 10098069264 10550255616 1112350544 11.955 98 43 85 0 1186619664 65683296 8585824
09:30:56 67934 8192000000 10075408800 10558046208 1107895040 11.912 99 17 34 0 1171747744 60384448 3468256
09:33:15 115013 8192000000 10070184448 10569273344 1106959712 11.904 98 22 42 0 1194121056 82598336 4563008
09:36:21 43215 8192000000 10075864240 10543398912 1131792720 12.138 100 25 50 1 1172064912 35212128 5060064
09:38:09 43656 8192000000 10083231136 10536951808 1144592576 12.259 100 25 49 0 1189471248 39911744 4966928
09:40:07 36231 8192000000 10105342896 10571202560 1139867376 12.214 99 25 50 0 1200275648 54986576 5421696
09:41:43 19717 8192000000 10145658848 10587926528 1139908624 12.215 99 41 82 0 1235906576 87757952 8240000
09:43:08 24476 8192000000 10118738656 10568331264 1138044016 12.197 99 29 59 0 1229173056 84766096 6362944
09:44:35 78066 8192000000 10129306592 10668044288 1134687904 12.166 98 24 48 0 1231733600 92170480 4875216
09:46:58 38424 8192000000 10089579040 10561052672 1137713488 12.194 99 30 53 0 1194465344 51366320 5385536
09:48:40 14220 8192000000 10099057584 10535841792 1141701904 12.232 100 62 123 1 1188340720 34870896 11767920
09:49:55 19837 8192000000 10133171568 10564190208 1124377632 12.068 98 53 106 0 1199771824 65037008 10357184
09:51:17 82606 8192000000 10045168288 10507538432 1103197664 11.868 97 24 48 0 1164065040 56121616 4745760
09:53:41 34938 8192000000 10103609104 10564952064 1120942608 12.036 99 47 91 0 1175810864 45004784 9863472
09:55:17 37851 8192000000 10062830384 10549321728 1132076208 12.141 100 6 12 0 1183034624 49132208 1826208
09:56:55 59839 8192000000 10107508032 10559176704 1128337952 12.106 100 17 34 1 1217688128 85694032 3656144
09:59:01 42576 8192000000 10063646384 10535817216 1102160720 11.858 98 20 40 0 1172691056 65583760 4946576
10:00:45 42936 8192000000 10095087424 10553667584 1127924352 12.102 100 25 51 0 1203816528 69850016 6042160
10:02:29 51566 8192000000 10089608000 10550263808 1102390000 11.860 99 26 52 1 1151437360 43679600 5367760
10:04:22 43627 8192000000 10063996496 10519990272 1104710016 11.882 99 27 54 0 1171601056 61360368 5530672
10:06:10 42484 8192000000 10135138992 10582052864 1126225392 12.086 100 44 88 0 1220842128 85767392 8849344
10:07:53 43760 8192000000 10082403504 10536976384 1109880000 11.931 99 41 82 0 1196856064 78927712 8048352
10:09:43 75776 8192000000 10083680240 10539520000 1103778048 11.873 98 30 60 0 1158471856 48755184 5938624
10:12:59 52041 8192000000 10099515840 10594000896 1125721024 12.081 100 6 12 0 1198086016 71199744 1165248
10:15:09 93666 8192000000 10057403632 10510766080 1081384624 11.661 96 15 30 0 1117435856 32872912 3178320
10:17:50 51641 8192000000 10084138624 10532352000 1110253712 11.935 99 26 52 0 1156239712 40430576 5555424
10:19:47 45889 8192000000 10126643344 10569441280 1123604640 12.061 100 26 52 0 1207031920 78081008 5346272
10:21:43 63819 8192000000 10119951152 10546921472 1118491856 12.013 98 27 50 1 1188114464 64535984 5086624
10:23:47 57487 8192000000 10068224496 10531917824 1119926976 12.026 98 10 20 0 1162784288 40968784 1888528
10:25:47 18700 8192000000 10112281296 10533650432 1138588272 12.202 100 43 83 1 1191604080 44461712 8554096
10:27:08 35034 8192000000 10124959808 10628640768 1138896256 12.205 100 40 80 0 1260108064 113087184 8124624
10:30:24 86108 8192000000 10045614016 10529243136 1106240976 11.897 98 13 26 0 1153812064 45023472 2547616
10:32:51 92898 8192000000 10074808752 10545741824 1124925584 12.073 99 13 26 0 1158199200 29774192 3499424
10:35:52 54141 8192000000 10109781984 10572189696 1081685472 11.664 95 19 38 0 1152505776 66778720 4041584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-09-2022 10:39:25 to 27-09-2022 10:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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 27-09-2022 10:39:42 to 27-09-2022 10:39:44.

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          210588
MON$OLDEST_ACTIVE               215252
MON$OLDEST_SNAPSHOT             215252
MON$NEXT_TRANSACTION            215252
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                       2032032
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Sep 27 10:37:27 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

27.09.22 10:39: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-09-27 10:39:48 No severe PSQL-related problems occured
27.09.22 10:39:49. End of report.