Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.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 14818936 kB
MemAvailable 30414116 kB
Buffers 20 kB
Cached 16838640 kB
SwapCached 5032 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-10 04:37:27
DTS_END                         2023-02-10 04:37:27.6690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41612
TRN_ID                          219281
ATT_ID                          568
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: 8885.85 22620 1066302
customer order: creation 169.88 488 20385
customer order: refuse 14.00 410 1680
order to supplier: creation 44.62 1351 5354
order to supplier: removal 3.32 179532 398
invoice (draft): creation 45.54 3250 5465
invoice (draft): removal 49.54 960 5945
invoice accept: apply 41.08 27080 4930
invoice accept: cancel 44.99 1448 5399
customer reserve: creation 3786.97 258 454436
customer reserve: removal 4394.72 108 527366
realization accept: apply 80.71 291 9685
realization accept: cancel 83.28 187 9994
payment from customer: creation 57.70 198 6924
payment from customer: removal 5.87 132 704
payment to supplier: creation 47.94 200 5753
payment to supplier: removal 3.38 122 405
service: total inventory turnovers 3.79 4376 455
service: total monetary turnovers 7.67 408 920
service: aggregate perf. data 0.83 19258 100
service: refresh index statistics 0.03 212340 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-02-2023 04:38:59 to 10-02-2023 04:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5507
TEST_TIME 2 6201
TEST_TIME 3 5988
TEST_TIME 4 6203
TEST_TIME 5 6202
TEST_TIME 6 6503
TEST_TIME 7 6689
TEST_TIME 8 6638
TEST_TIME 9 6529
TEST_TIME 10 6547
TEST_TIME 11 6596
TEST_TIME 12 6743
TEST_TIME 13 6840
TEST_TIME 14 6954
TEST_TIME 15 7211
TEST_TIME 16 7515
TEST_TIME 17 7709
TEST_TIME 18 7706
TEST_TIME 19 7705
TEST_TIME 20 7948
TEST_TIME 21 8070
TEST_TIME 22 8156
TEST_TIME 23 8247
TEST_TIME 24 8159
TEST_TIME 25 8116
TEST_TIME 26 8177
TEST_TIME 27 8028
TEST_TIME 28 8423
TEST_TIME 29 8752
TEST_TIME 30 9244
TEST_TIME 31 9599
TEST_TIME 32 9840
TEST_TIME 33 9971
TEST_TIME 34 10315
TEST_TIME 35 10514
TEST_TIME 36 11212
TEST_TIME 37 11135
TEST_TIME 38 11009
TEST_TIME 39 10903
TEST_TIME 40 10647
TEST_TIME 41 10493
TEST_TIME 42 10388
TEST_TIME 43 10303
TEST_TIME 44 10153
TEST_TIME 45 10103
TEST_TIME 46 10027
TEST_TIME 47 9948
TEST_TIME 48 9845
TEST_TIME 49 9773
TEST_TIME 50 9691
TEST_TIME 51 9621
TEST_TIME 52 9499
TEST_TIME 53 9408
TEST_TIME 54 9383
TEST_TIME 55 9294
TEST_TIME 56 9329
TEST_TIME 57 9301
TEST_TIME 58 9318
TEST_TIME 59 9326
TEST_TIME 60 9260
TEST_TIME 61 9264
TEST_TIME 62 9278
TEST_TIME 63 9357
TEST_TIME 64 9427
TEST_TIME 65 9379
TEST_TIME 66 9417
TEST_TIME 67 9455
TEST_TIME 68 9393
TEST_TIME 69 9484
TEST_TIME 70 9491
TEST_TIME 71 9431
TEST_TIME 72 9334
TEST_TIME 73 9275
TEST_TIME 74 9214
TEST_TIME 75 9153
TEST_TIME 76 9190
TEST_TIME 77 9146
TEST_TIME 78 9077
TEST_TIME 79 9032
TEST_TIME 80 8966
TEST_TIME 81 8937
TEST_TIME 82 8995
TEST_TIME 83 8958
TEST_TIME 84 8984
TEST_TIME 85 9013
TEST_TIME 86 8997
TEST_TIME 87 9052
TEST_TIME 88 9137
TEST_TIME 89 9185
TEST_TIME 90 9194
TEST_TIME 91 9195
TEST_TIME 92 9179
TEST_TIME 93 9152
TEST_TIME 94 9073
TEST_TIME 95 9021
TEST_TIME 96 9046
TEST_TIME 97 9028
TEST_TIME 98 8965
TEST_TIME 99 8947
TEST_TIME 100 8920
TEST_TIME 101 8929
TEST_TIME 102 8914
TEST_TIME 103 8919
TEST_TIME 104 8907
TEST_TIME 105 8877
TEST_TIME 106 8838
TEST_TIME 107 8812
TEST_TIME 108 8810
TEST_TIME 109 8954
TEST_TIME 110 9066
TEST_TIME 111 9101
TEST_TIME 112 9220
TEST_TIME 113 9151
TEST_TIME 114 9100
TEST_TIME 115 9053
TEST_TIME 116 9013
TEST_TIME 117 9017
TEST_TIME 118 8984
TEST_TIME 119 8942
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-02-2023 04:39:03 to 10-02-2023 04:39:06.

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 1485602 1485602 0 0.00 0 23539 14 0 0
doc_list_biud 1485582 1485582 0 0.00 0 58208 44 0 0
sp_add_invoice_to_stock 5076 4930 146 2.87 46 228842 27081 0 146
sp_cancel_adding_invoice 5399 5399 0 0.00 6 102067 1448 0 0
sp_cancel_client_order 1680 1680 0 0.00 7 12130 411 0 0
sp_cancel_customer_reserve 527366 527366 0 0.00 1 25842 109 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 4 4635 133 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 1417 123 0 0
sp_cancel_supplier_invoice 5954 5945 9 0.15 2 58216 961 0 9
sp_cancel_supplier_order 398 398 0 0.00 19 1579739 179532 0 0
sp_cancel_write_off 9994 9994 0 0.00 2 8671 187 0 0
sp_client_order 20385 20385 0 0.00 5 21845 488 0 0
sp_customer_reserve 454436 454436 0 0.00 1 154671 258 0 0
sp_fill_shopping_cart 31186 31186 0 0.00 1 25898 88 0 0
sp_fill_shopping_cart_clo_res 454403 454403 0 0.00 0 18405 18 0 0
sp_get_clo_for_invoice 4894 4894 0 0.00 0 26521 274 0 0
sp_kill_cost_storno 16502 16502 0 0.00 0 19780 80 0 0
sp_kill_qstorno_ret_qs2qd 1071069 1071069 0 0.00 0 58207 46 0 0
sp_kill_qty_storno 565355 565355 0 0.00 0 58208 106 0 0
sp_lock_selected_doc 20255 20255 0 0.00 0 8258 6 0 0
sp_make_cost_storno 27255 27255 0 0.00 0 16681 86 0 0
sp_multiply_rows_for_pdistr 27251 27251 0 0.00 0 6863 38 0 0
sp_multiply_rows_for_qdistr 20385 20385 0 0.00 0 19198 190 0 0
sp_pay_from_customer 6924 6924 0 0.00 5 13468 199 0 0
sp_pay_to_supplier 5753 5753 0 0.00 5 18436 201 0 0
sp_payment_common 12676 12676 0 0.00 2 7892 50 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 1 12120 335 0 0
sp_qd_handle_on_invoice_upd_sts 10290 10290 0 0.00 0 47974 554 0 0
sp_qd_handle_on_reserve_upd_sts 9681 9681 0 0.00 0 23547 66 0 0
sp_reserve_write_off 9685 9685 0 0.00 8 23566 291 0 0
sp_supplier_invoice 5465 5465 0 0.00 9 123764 3250 0 0
sp_supplier_order 5354 5354 0 0.00 7 58713 1351 0 0
srv_aggregate_perf_data 100 100 0 0.00 2617 184234 19258 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 673 135791 35421 0 0
srv_find_qd_qs_mism 1018601 1018601 0 0.00 0 23538 11 0 0
srv_make_invnt_saldo 455 455 0 0.00 73 59117 4377 0 0
srv_make_money_saldo 920 920 0 0.00 1 22627 409 0 0
srv_recalc_idx_stat 4 4 0 0.00 47343 452230 212341 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4933 4933 0 0.00 1 21032 1049 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 8555 624 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 1821 30 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 2761 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 1608 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 778 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 796 35 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 87620 7200 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 1149 18 0 0
t$perf-norm:sp_client_order 20385 20385 0 0.00 0 7543 41 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 5740 22 0 0
t$perf-norm:sp_pay_from_customer 6924 6924 0 0.00 0 7418 21 0 0
t$perf-norm:sp_pay_to_supplier 5753 5753 0 0.00 0 4070 20 0 0
t$perf-norm:sp_reserve_write_off 9685 9685 0 0.00 0 8521 28 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 1980 32 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 1702 32 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1344 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 380 11 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 948 17 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 1579 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 192 79 0 0
v_add_invoice_to_stock 10064 9772 292 2.90 0 13942 26 0 292
v_all_customers 37286 37286 0 0.00 0 177 4 0 0
v_all_suppliers 30066 30066 0 0.00 0 134 5 0 0
v_all_wares 40762 40762 0 0.00 0 168 4 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 328 16 0 0
v_cancel_client_order 3360 3360 0 0.00 0 1752 10 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 973 11 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 627 11 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 720 13 0 0
v_cancel_supplier_invoice 858 840 18 2.09 0 808 22 0 18
v_cancel_supplier_order 796 796 0 0.00 0 2061 13 0 0
v_cancel_write_off 1914 1914 0 0.00 0 3082 26 0 0
v_max_id_clo_ord 5352 5352 0 0.00 0 605 6 0 0
v_max_id_clo_res 998 998 0 0.00 0 6169 20 0 0
v_max_id_ord_sup 5451 5451 0 0.00 0 2293 7 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 388 9 0 0
v_max_non_paid_realizn 3563 3563 0 0.00 0 1509 8 0 0
v_min_id_clo_ord 5352 5352 0 0.00 0 2959 9 0 0
v_min_id_clo_res 998 998 0 0.00 0 4283 18 0 0
v_min_id_ord_sup 5451 5451 0 0.00 0 6390 14 0 0
v_min_non_paid_invoice 5752 5752 0 0.00 0 5189 14 0 0
v_min_non_paid_realizn 6924 6924 0 0.00 0 7844 22 0 0
v_our_firm 10198 10198 0 0.00 0 54 5 0 0
v_reserve_write_off 19362 19362 0 0.00 0 3423 11 0 0
x_lock_depdocs_on_canc_invoice 10924 10924 0 0.00 0 17576 145 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 9552 386 0 0
x_make_qstorno_1000_1200 5354 5354 0 0.00 2 58672 1172 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 5 119676 3016 0 0
x_make_qstorno_2100_3300 454436 454436 0 0.00 0 154670 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-02-2023 04:39:07 to 10-02-2023 04:39:11.

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:31 3 8192000000 8799328640 8810778624 4473472 0.054 2 1 2 0 4572864 63616 35776
02:08:32 18861 8192000000 9731959632 10032353280 812423568 9.022 100 86 171 0 840510224 7900976 20185680
02:09:56 14549 8192000000 9759850672 10065747968 834480064 9.244 100 89 178 0 867900752 12279120 21141568
02:11:13 14145 8192000000 9781183696 10094452736 851269792 9.413 100 86 170 0 889706640 18396880 20039968
02:12:31 14444 8192000000 9842518608 10172936192 897616416 9.875 100 77 152 0 951977248 37603472 16757360
02:13:55 4476 8192000000 9904441120 10246340608 949517264 10.386 100 87 172 0 1013981680 46320832 18143584
02:15:03 2541 8192000000 9958816672 10310881280 985901360 10.742 100 87 168 1 1083007584 80302704 16803520
02:16:09 1603 8192000000 9981707776 10340298752 1008380224 10.960 100 87 162 1 1097183760 72666848 16136688
02:17:13 1053 8192000000 9996748784 10360918016 1029773920 11.166 100 91 176 0 1116367536 68297648 18295968
02:18:16 1058 8192000000 10027229952 10397945856 1055285568 11.411 100 97 186 0 1148173536 74814144 18073824
02:19:20 1324 8192000000 10041496800 10421092352 1063115664 11.486 100 84 168 0 1161693696 81546656 17031376
02:20:24 1233 8192000000 10053337632 10434088960 1071461328 11.566 100 95 185 1 1178033744 88074368 18498048
02:21:27 987 8192000000 10072052304 10451410944 1083585440 11.682 100 96 174 0 1187675776 85900080 18190256
02:22:30 1173 8192000000 10049049856 10433814528 1084459120 11.690 100 97 185 3 1172968176 69021856 19487200
02:23:32 1187 8192000000 10071227120 10458488832 1091101856 11.753 100 95 180 2 1197155376 87398720 18654800
02:24:35 1280 8192000000 10065780048 10451857408 1096185744 11.801 100 98 187 2 1191555024 76547728 18821552
02:25:38 1200 8192000000 10067910096 10451464192 1101522896 11.852 100 99 190 0 1189496032 68257520 19715616
02:26:40 1049 8192000000 10081686896 10466955264 1104874144 11.884 100 88 166 1 1202142912 80038576 17230192
02:27:43 1213 8192000000 10052587296 10443689984 1100290848 11.840 100 74 143 2 1176346288 61476736 14578704
02:28:46 4673 8192000000 10051305776 10444939264 1091386544 11.756 100 69 135 2 1153565888 48467920 13711424
02:29:52 1204 8192000000 10046522720 10428579840 1077005296 11.619 99 96 186 1 1170925472 74874784 19045392
02:30:54 1410 8192000000 10023506112 10407092224 1064911120 11.503 99 95 184 3 1145853808 62343920 18598768
02:31:56 1242 8192000000 10048182800 10427240448 1065718128 11.511 100 93 175 0 1174001376 90565632 17717616
02:32:58 1195 8192000000 10007093264 10389487616 1046852352 11.330 100 92 173 3 1131153152 64526256 19774544
02:34:02 1473 8192000000 9991723152 10370830336 1043013664 11.294 100 86 154 6 1111090224 51944720 16131840
02:35:06 1167 8192000000 9986479120 10362077184 1035819424 11.224 100 99 185 2 1102663760 47870320 18974016
02:36:08 1202 8192000000 9981878544 10357702656 1023882240 11.109 99 93 174 0 1106160208 64979888 17298080
02:37:10 1164 8192000000 9979688624 10351669248 1040969312 11.274 100 93 170 1 1099476464 40756224 17750928
02:38:21 2634 8192000000 10017458480 10401062912 1064989728 11.504 100 78 154 1 1142272960 61611808 15671424
02:39:27 1173 8192000000 10053592368 10431655936 1075199152 11.602 100 96 179 5 1168337824 75405680 17732992
02:40:35 10733 8192000000 10067504560 10456567808 1082544384 11.672 100 63 124 2 1182894304 87931152 12418768
02:41:52 2303 8192000000 10080940656 10468630528 1107331600 11.907 100 84 150 3 1200601504 77423376 15846528
02:43:05 6983 8192000000 10119092848 10518896640 1104723328 11.882 99 67 129 0 1234969088 116973632 13272128
02:44:16 1113 8192000000 10162017712 10552991744 1128508848 12.107 100 92 175 1 1276281184 129228080 18544256
02:45:21 2535 8192000000 10183801136 10587881472 1142960848 12.243 100 77 144 1 1295137632 136817728 15359056
02:46:36 2221 8192000000 10209102976 10609901568 1155030608 12.357 100 78 154 0 1325476176 154435920 16009648
02:47:41 2012 8192000000 10245364528 10648850432 1169627728 12.493 100 84 157 3 1361451360 174322880 17500752
02:48:47 1464 8192000000 10275905680 10675990528 1167824320 12.476 100 93 174 1 1389748256 202855744 19068192
02:49:51 2444 8192000000 10304031456 10707210240 1176361824 12.556 100 83 165 1 1416832768 221922784 18548160
02:50:57 920 8192000000 10332010416 10732511232 1173995424 12.534 100 90 177 0 1442012496 247770816 20246256
02:52:02 893 8192000000 10371319376 10778931200 1177546448 12.567 100 97 192 2 1477962752 278727664 21688640
02:53:06 845 8192000000 10362459360 10794414080 1192481936 12.706 100 98 190 1 1479793280 265682864 21628480
02:54:09 3503 8192000000 10356997488 10789613568 1190341408 12.687 100 65 124 0 1463165472 258794400 14029664
02:55:17 830 8192000000 10403266272 10824491008 1198980656 12.767 100 98 192 1 1513761776 291977696 22803424
02:56:22 694 8192000000 10402415248 10808877056 1180298912 12.593 100 95 179 2 1506934768 307020432 19615424
02:57:26 1022 8192000000 10436042912 10858115072 1194632848 12.726 100 94 177 0 1539314080 324630224 20051008
02:58:29 667 8192000000 10446309904 10854998016 1200647216 12.782 100 96 192 0 1554387344 331303840 22436288
02:59:31 2798 8192000000 10475140672 10893852672 1197144944 12.750 100 87 172 0 1580742064 362879584 20717536
03:00:36 789 8192000000 10533337328 10953728000 1203971616 12.813 100 87 169 0 1635376880 411929168 19476096
03:01:40 815 8192000000 10559304400 10973708288 1192854128 12.710 100 97 184 1 1661202416 446713360 21634928
03:02:43 1907 8192000000 10599432480 11029098496 1194143760 12.722 100 78 140 0 1702369104 489245712 18979632
03:03:49 3014 8192000000 10631270080 11074433024 1195608976 12.736 100 71 142 0 1751439808 537774208 18056624
03:05:02 1976 8192000000 10662620096 11140935680 1189242928 12.676 100 85 166 0 1767874608 558209664 20422016
03:06:05 3358 8192000000 10641741488 11126026240 1204117536 12.815 100 64 117 3 1742245216 522131376 15996304
03:07:11 24709 8192000000 10555412672 11077574656 1188950736 12.674 100 49 98 0 1671417792 469419984 13047072
03:09:20 32934 8192000000 10437300464 10913255424 1170591360 12.502 99 29 58 0 1545499728 367489728 7418640
03:11:11 45384 8192000000 10343699584 10901430272 1141543792 12.230 99 20 39 0 1476704400 330220128 4940480
03:13:00 28819 8192000000 10110615248 10660675584 1138110400 12.198 100 27 51 1 1241959840 96639344 7210096
03:14:31 12449 8192000000 10093611664 10517450752 1129074336 12.113 99 53 101 1 1188834160 48742416 11017408
03:15:47 118748 8192000000 10091349888 10563522560 1118425568 12.012 99 22 44 0 1188994864 66080032 4489264
03:19:34 41191 8192000000 10077907904 10525954048 1131811920 12.138 100 47 91 2 1173058960 31807088 9439952
03:21:42 62872 8192000000 10076194384 10531950592 1141954032 12.234 100 37 72 1 1174751072 26077360 6719680
03:23:49 66991 8192000000 10048861520 10519142400 1113820400 11.969 98 9 18 0 1151983856 36321280 1842176
03:25:57 53899 8192000000 10056066448 10513666048 1120819040 12.035 98 21 41 0 1154376608 28959328 4598240
03:28:03 135789 8192000000 10101449712 10564161536 1126890160 12.092 99 29 58 0 1178359024 45005952 6462912
03:31:29 57838 8192000000 10079587824 10534346752 1120755328 12.034 99 21 37 1 1206058160 81388112 3914720
03:33:29 72451 8192000000 10078368000 10580783104 1112429216 11.955 98 20 40 0 1186033648 69350368 4254064
03:35:42 70833 8192000000 10037758816 10515554304 1106254400 11.897 99 12 24 0 1159350912 50453600 2642912
03:37:53 35788 8192000000 10139809264 10586603520 1131368464 12.134 99 34 66 0 1225655696 87893104 6394128
03:39:34 30377 8192000000 10161844064 10598326272 1140447424 12.220 99 49 98 0 1228350336 77267888 10635024
03:41:07 102016 8192000000 10079787664 10566168576 1082531328 11.672 94 25 50 1 1149557008 61930080 5095600
03:43:59 26027 8192000000 10108939456 10560786432 1118438032 12.012 98 33 66 0 1192591104 67410208 6742864
03:45:32 55792 8192000000 10135002064 10598293504 1142227472 12.236 100 31 61 1 1244746144 95988112 6530560
03:47:31 84032 8192000000 10075597664 10529132544 1134833536 12.167 100 25 50 0 1166306624 25714384 5758704
03:49:57 52734 8192000000 10095119696 10548613120 1124479936 12.069 99 69 128 2 1163802896 24982528 14340432
03:51:51 28683 8192000000 10071915904 10505080832 1135820592 12.176 100 51 100 3 1173732976 26364080 11548304
03:53:20 67889 8192000000 10096845360 10582761472 1141169024 12.227 100 23 46 0 1221740944 75369488 5202432
03:55:41 46397 8192000000 10142898288 10598912000 1140404112 12.219 99 27 50 1 1233651600 87386192 5861296
03:59:19 49697 8192000000 10125649120 10567753728 1090494496 11.747 96 32 63 1 1201094272 104299184 6300592
04:01:15 36526 8192000000 10143204368 10609926144 1126658544 12.090 100 33 66 0 1264333104 130277952 7396608
04:02:59 68848 8192000000 10155159952 10612027392 1114134912 11.972 98 29 54 0 1234964528 114832656 5996960
04:05:13 41303 8192000000 10097822896 10535411712 1101864704 11.855 98 37 72 0 1172829904 63744496 7220704
04:06:57 51362 8192000000 10088093072 10543632384 1111169552 11.943 98 31 62 0 1160556752 43583168 5804032
04:08:59 58522 8192000000 10059533120 10485469184 1119358816 12.021 99 56 98 5 1157005616 27717952 9928848
04:11:01 61840 8192000000 10175463488 10604834816 1143329360 12.247 100 60 120 0 1245145472 89743984 12072128
04:15:02 91614 8192000000 10070499568 10545283072 1114387728 11.974 98 14 28 0 1158081696 40912512 2781456
04:17:35 37213 8192000000 10088474272 10534899712 1101632752 11.853 97 18 36 0 1161240240 55897520 3709968
04:19:18 54906 8192000000 10097481488 10544271360 1108333872 11.917 98 32 59 0 1186558256 71636592 6587792
04:21:16 41544 8192000000 10143341552 10580582400 1127647040 12.099 99 29 56 1 1228638528 94472624 6518864
04:22:58 32213 8192000000 10239305040 10675785728 1163005584 12.431 100 44 86 0 1312455280 139828160 9621536
04:24:36 36966 8192000000 10302408720 10738012160 1167223584 12.471 100 37 74 0 1371904672 196466720 8214368
04:26:19 72721 8192000000 10176336480 10695655424 1163420928 12.435 100 16 32 0 1343640576 176486656 3732992
04:28:33 73180 8192000000 10095898544 10619629568 1126626384 12.090 98 10 20 0 1225259248 96057040 2575824
04:30:47 92543 8192000000 10081992432 10583609344 1117523776 12.004 98 18 34 1 1159529328 38598864 3406688
04:35:01 71967 8192000000 10055220640 10494443520 1069226880 11.545 94 19 37 0 1107469040 34421120 3821040
04:37:13 21098 8192000000 9050891440 9303674880 205894016 2.451 19 3 6 0 223504880 17018944 591920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 10-02-2023 04:39:13 to 10-02-2023 04:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-02-2023 04:39:31 to 10-02-2023 04:39:33.

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          219567
MON$OLDEST_ACTIVE               219568
MON$OLDEST_SNAPSHOT             219568
MON$NEXT_TRANSACTION            219568
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                       2086496
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Feb 10 04:37:35 2023
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

10.02.23 04:39:34. 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-10 04:39:36 No severe PSQL-related problems occured
10.02.23 04:39:38. End of report.