Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.07.2022 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16143268 kB
MemAvailable 31114820 kB
Buffers 20 kB
Cached 15482876 kB
SwapCached 2492 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.2
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-07-01 04:37:17
DTS_END                         2022-07-01 04:37:17.7830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38356
TRN_ID                          215797
ATT_ID                          606
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: 8725.53 21916 1047063
customer order: creation 165.19 477 19823
customer order: refuse 14.31 384 1717
order to supplier: creation 43.45 1388 5214
order to supplier: removal 3.23 155262 387
invoice (draft): creation 45.08 2819 5409
invoice (draft): removal 47.53 946 5703
invoice accept: apply 40.69 27058 4883
invoice accept: cancel 42.62 1204 5114
customer reserve: creation 3851.96 253 462235
customer reserve: removal 4185.78 95 502294
realization accept: apply 81.58 252 9790
realization accept: cancel 78.26 168 9391
payment from customer: creation 57.48 174 6897
payment from customer: removal 5.40 117 648
payment to supplier: creation 47.28 191 5674
payment to supplier: removal 3.16 143 379
service: total inventory turnovers 4.23 3147 508
service: total monetary turnovers 7.38 359 886
service: aggregate perf. data 0.88 14659 106
service: refresh index statistics 0.04 229227 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-07-2022 04:38:13 to 01-07-2022 04:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4833
TEST_TIME 2 5591
TEST_TIME 3 5645
TEST_TIME 4 5100
TEST_TIME 5 5176
TEST_TIME 6 5427
TEST_TIME 7 5426
TEST_TIME 8 6195
TEST_TIME 9 6167
TEST_TIME 10 6379
TEST_TIME 11 6483
TEST_TIME 12 6524
TEST_TIME 13 6601
TEST_TIME 14 6783
TEST_TIME 15 6888
TEST_TIME 16 7036
TEST_TIME 17 7141
TEST_TIME 18 7456
TEST_TIME 19 7691
TEST_TIME 20 7979
TEST_TIME 21 8280
TEST_TIME 22 8649
TEST_TIME 23 8616
TEST_TIME 24 8747
TEST_TIME 25 9110
TEST_TIME 26 9193
TEST_TIME 27 9512
TEST_TIME 28 9923
TEST_TIME 29 10380
TEST_TIME 30 10875
TEST_TIME 31 10745
TEST_TIME 32 10488
TEST_TIME 33 10381
TEST_TIME 34 10471
TEST_TIME 35 10311
TEST_TIME 36 10080
TEST_TIME 37 9821
TEST_TIME 38 9788
TEST_TIME 39 9677
TEST_TIME 40 9549
TEST_TIME 41 9514
TEST_TIME 42 9447
TEST_TIME 43 9428
TEST_TIME 44 9314
TEST_TIME 45 9314
TEST_TIME 46 9327
TEST_TIME 47 9257
TEST_TIME 48 9276
TEST_TIME 49 9254
TEST_TIME 50 9477
TEST_TIME 51 9469
TEST_TIME 52 9475
TEST_TIME 53 9460
TEST_TIME 54 9493
TEST_TIME 55 9511
TEST_TIME 56 9359
TEST_TIME 57 9237
TEST_TIME 58 9208
TEST_TIME 59 9170
TEST_TIME 60 9072
TEST_TIME 61 9060
TEST_TIME 62 9050
TEST_TIME 63 8986
TEST_TIME 64 9006
TEST_TIME 65 9051
TEST_TIME 66 9009
TEST_TIME 67 8959
TEST_TIME 68 8983
TEST_TIME 69 8965
TEST_TIME 70 9029
TEST_TIME 71 9070
TEST_TIME 72 9046
TEST_TIME 73 9171
TEST_TIME 74 9346
TEST_TIME 75 9278
TEST_TIME 76 9200
TEST_TIME 77 9123
TEST_TIME 78 9084
TEST_TIME 79 9077
TEST_TIME 80 9030
TEST_TIME 81 9028
TEST_TIME 82 8961
TEST_TIME 83 8994
TEST_TIME 84 9028
TEST_TIME 85 9001
TEST_TIME 86 8969
TEST_TIME 87 8927
TEST_TIME 88 8917
TEST_TIME 89 8889
TEST_TIME 90 8863
TEST_TIME 91 8843
TEST_TIME 92 8837
TEST_TIME 93 8803
TEST_TIME 94 8856
TEST_TIME 95 8890
TEST_TIME 96 8953
TEST_TIME 97 8977
TEST_TIME 98 8978
TEST_TIME 99 8985
TEST_TIME 100 9001
TEST_TIME 101 9026
TEST_TIME 102 9087
TEST_TIME 103 9033
TEST_TIME 104 8970
TEST_TIME 105 8940
TEST_TIME 106 8934
TEST_TIME 107 8896
TEST_TIME 108 8862
TEST_TIME 109 8810
TEST_TIME 110 8818
TEST_TIME 111 8773
TEST_TIME 112 8781
TEST_TIME 113 8759
TEST_TIME 114 8740
TEST_TIME 115 8744
TEST_TIME 116 8750
TEST_TIME 117 8797
TEST_TIME 118 8779
TEST_TIME 119 8784
TEST_TIME 120 8746

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-07-2022 04:38:16 to 01-07-2022 04:38:19.

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 1470729 1470729 0 0.00 0 21749 13 0 0
doc_list_biud 1470715 1470715 0 0.00 0 81237 38 0 0
sp_add_invoice_to_stock 4973 4883 90 1.80 62 230828 27058 0 90
sp_cancel_adding_invoice 5114 5114 0 0.00 5 87683 1205 0 0
sp_cancel_client_order 1717 1717 0 0.00 5 14839 385 0 0
sp_cancel_customer_reserve 502294 502294 0 0.00 1 25306 96 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 3234 118 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 4 6912 143 0 0
sp_cancel_supplier_invoice 5709 5703 6 0.10 3 81246 947 0 6
sp_cancel_supplier_order 387 387 0 0.00 10 1537113 155262 0 0
sp_cancel_write_off 9391 9391 0 0.00 2 13072 169 0 0
sp_client_order 19823 19823 0 0.00 5 41860 477 0 0
sp_customer_reserve 462235 462235 0 0.00 0 170456 254 0 0
sp_fill_shopping_cart 30432 30432 0 0.00 1 14221 81 0 0
sp_fill_shopping_cart_clo_res 462213 462213 0 0.00 0 21291 20 0 0
sp_get_clo_for_invoice 4854 4854 0 0.00 0 25180 410 0 0
sp_kill_cost_storno 15532 15532 0 0.00 0 18164 68 0 0
sp_kill_qstorno_ret_qs2qd 1020069 1020069 0 0.00 0 81235 43 0 0
sp_kill_qty_storno 539247 539247 0 0.00 0 81237 97 0 0
sp_lock_selected_doc 20190 20190 0 0.00 0 10526 8 0 0
sp_make_cost_storno 27215 27215 0 0.00 0 18663 74 0 0
sp_multiply_rows_for_pdistr 27213 27213 0 0.00 0 14759 36 0 0
sp_multiply_rows_for_qdistr 19823 19823 0 0.00 0 41820 205 0 0
sp_pay_from_customer 6897 6897 0 0.00 5 9409 174 0 0
sp_pay_to_supplier 5674 5674 0 0.00 6 29306 192 0 0
sp_payment_common 12569 12569 0 0.00 2 29218 46 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 0 14747 305 0 0
sp_qd_handle_on_invoice_upd_sts 9967 9967 0 0.00 0 31836 485 0 0
sp_qd_handle_on_reserve_upd_sts 9788 9788 0 0.00 0 12306 55 0 0
sp_reserve_write_off 9790 9790 0 0.00 8 19313 253 0 0
sp_supplier_invoice 5409 5409 0 0.00 9 71076 2819 0 0
sp_supplier_order 5214 5214 0 0.00 8 34016 1389 0 0
srv_aggregate_perf_data 106 106 0 0.00 2472 108259 14659 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 773 131762 38170 0 0
srv_find_qd_qs_mism 998196 998196 0 0.00 0 21747 10 0 0
srv_make_invnt_saldo 508 508 0 0.00 41 59199 3148 0 0
srv_make_money_saldo 886 886 0 0.00 1 13956 360 0 0
srv_recalc_idx_stat 5 5 0 0.00 48621 441629 229227 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4888 4888 0 0.00 1 26123 880 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 14670 489 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 3421 27 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1972 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 3822 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 1872 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1089 30 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 70766 6221 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 3187 21 0 0
t$perf-norm:sp_client_order 19823 19823 0 0.00 0 17258 35 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 227 10 0 0
t$perf-norm:sp_pay_from_customer 6897 6897 0 0.00 0 17398 21 0 0
t$perf-norm:sp_pay_to_supplier 5674 5674 0 0.00 0 10964 20 0 0
t$perf-norm:sp_reserve_write_off 9790 9790 0 0.00 0 7924 20 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 3281 29 0 0
t$perf-norm:sp_supplier_order 5214 5214 0 0.00 0 4076 31 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1258 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1045 22 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 746 12 0 0
t$perf-norm:srv_make_money_saldo 886 886 0 0.00 0 531 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 390 116 0 0
v_add_invoice_to_stock 9878 9698 180 1.82 0 6741 26 0 180
v_all_customers 36249 36249 0 0.00 0 56 3 0 0
v_all_suppliers 29662 29662 0 0.00 0 121 4 0 0
v_all_wares 39636 39636 0 0.00 0 119 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 642 13 0 0
v_cancel_client_order 3434 3434 0 0.00 0 6795 14 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 2817 13 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 1 1878 13 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2736 13 0 0
v_cancel_supplier_invoice 770 758 12 1.55 0 1931 23 0 12
v_cancel_supplier_order 774 774 0 0.00 0 4004 19 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2538 23 0 0
v_max_id_clo_ord 5210 5210 0 0.00 0 5418 6 0 0
v_max_id_clo_res 958 958 0 0.00 0 2466 14 0 0
v_max_id_ord_sup 5401 5401 0 0.00 0 6230 9 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 580 8 0 0
v_max_non_paid_realizn 3560 3560 0 0.00 0 3664 9 0 0
v_min_id_clo_ord 5210 5210 0 0.00 0 2081 7 0 0
v_min_id_clo_res 958 958 0 0.00 0 1563 9 0 0
v_min_id_ord_sup 5401 5401 0 0.00 0 8769 13 0 0
v_min_non_paid_invoice 5674 5674 0 0.00 0 29207 20 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 7351 18 0 0
v_our_firm 10054 10054 0 0.00 0 64 4 0 0
v_reserve_write_off 19576 19576 0 0.00 0 9385 12 0 0
x_lock_depdocs_on_canc_invoice 10438 10438 0 0.00 0 20844 106 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 6051 355 0 0
x_make_qstorno_1000_1200 5214 5214 0 0.00 2 33994 1237 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 4 71062 2585 0 0
x_make_qstorno_2100_3300 462234 462234 0 0.00 0 170441 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-07-2022 04:38:20 to 01-07-2022 04:38:24.

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:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 10602 8192000000 9716704720 10010071040 796629536 8.862 100 91 182 0 826755504 7444560 22681408
02:09:42 12490 8192000000 9747341872 10051686400 824417968 9.143 100 90 179 0 856686656 10578352 21690336
02:11:09 13067 8192000000 9791295440 10111520768 862512256 9.525 100 79 158 0 896623104 15734176 18376672
02:12:24 6366 8192000000 9847968448 10182696960 912881120 10.026 100 85 164 0 964858992 33292912 18684960
02:13:35 7187 8192000000 9910132384 10254725120 957604576 10.466 100 78 154 0 1025606736 51987712 16014448
02:14:46 3416 8192000000 9960716848 10312814592 991456736 10.796 100 92 179 1 1070326400 60684576 18185088
02:15:52 2105 8192000000 10005926352 10366963712 1021159344 11.083 100 92 182 1 1116014064 76429824 18424896
02:16:56 1005 8192000000 10013040272 10379657216 1030563152 11.174 100 94 184 0 1131671824 83039520 18069152
02:17:59 773 8192000000 10014988160 10388226048 1050228912 11.363 100 96 181 1 1140367792 70554016 19584864
02:19:01 9674 8192000000 10031788992 10404945920 1060434496 11.461 100 82 158 0 1151061360 73951776 16675088
02:20:13 1248 8192000000 10040153616 10415538176 1066047520 11.514 100 93 182 0 1157338352 72963408 18327424
02:21:16 1219 8192000000 10033715776 10410229760 1070977952 11.561 100 94 178 0 1155608208 66760400 17869856
02:22:19 1187 8192000000 10046467968 10428030976 1081097888 11.658 100 93 177 0 1167573584 68629216 17846480
02:23:23 1027 8192000000 10060163808 10445926400 1082048000 11.667 100 98 188 1 1178118448 76562672 19507776
02:24:26 1160 8192000000 10071878944 10457223168 1086916976 11.713 100 99 185 0 1193657840 88568848 18172016
02:25:28 1729 8192000000 10089727936 10478026752 1092416832 11.766 100 88 169 0 1213855360 104995488 16443040
02:26:31 835 8192000000 10058403552 10444763136 1098723504 11.826 100 94 179 1 1181066672 63314800 19028368
02:27:34 1727 8192000000 10077061568 10463657984 1096455952 11.804 100 88 170 1 1194033904 80841840 16736112
02:28:37 919 8192000000 10067282512 10465202176 1101177200 11.849 100 88 169 2 1188324016 69381312 17765504
02:29:39 1348 8192000000 10017214944 10407190528 1086866880 11.713 100 80 151 1 1140684208 37803424 16013904
02:30:42 6599 8192000000 10065033104 10459119616 1088071328 11.724 100 62 116 1 1184164896 84657824 11435744
02:31:49 3058 8192000000 10012572160 10397818880 1050051120 11.361 98 84 163 2 1122120832 54668704 17401008
02:32:53 924 8192000000 10021385936 10401701888 1072257888 11.574 100 89 170 1 1144263632 53981232 18024512
02:33:56 1004 8192000000 10006501920 10381557760 1043005504 11.294 99 95 175 2 1126901968 65174496 18721968
02:34:58 1026 8192000000 10029674880 10407669760 1063744592 11.492 100 98 181 1 1152953488 71667360 17541536
02:36:00 1067 8192000000 10002883600 10373632000 1025697552 11.127 100 96 185 0 1124065520 80393360 17974608
02:37:02 1396 8192000000 10003656176 10380677120 1049998752 11.361 100 87 153 9 1124590448 59320784 15270912
02:38:06 34105 8192000000 10024824992 10405171200 1067399632 11.527 100 72 129 2 1130219984 50111072 12709280
02:39:45 3097 8192000000 10057478960 10446057472 1085608384 11.701 100 75 146 2 1178345920 78472720 14264816
02:40:54 6626 8192000000 10069825856 10459348992 1102028080 11.857 100 70 124 1 1179077792 64473344 12576368
02:42:07 22812 8192000000 10139292752 10540802048 1106062560 11.895 100 65 128 0 1246239232 126819104 13357568
02:43:45 920 8192000000 10136670768 10529095680 1123769808 12.063 100 80 148 2 1252954032 113106816 16077408
02:44:57 2228 8192000000 10200085152 10594893824 1137357504 12.191 100 88 171 3 1317692512 162415600 17919408
02:46:04 9951 8192000000 10174824368 10576687104 1131277344 12.133 100 73 143 1 1293602896 146920352 15405200
02:47:19 765 8192000000 10222094784 10620469248 1150903600 12.318 100 93 175 1 1334207616 164883344 18420672
02:48:24 1429 8192000000 10242537984 10640744448 1161344128 12.416 100 90 162 3 1349268080 169474928 18449024
02:49:31 3497 8192000000 10289201536 10696417280 1157653872 12.381 100 88 176 2 1402046848 224373184 20019792
02:50:38 1097 8192000000 10320323328 10728955904 1169846800 12.495 100 98 191 1 1430569760 239473600 21249360
02:51:42 1238 8192000000 10368462128 10773368832 1179936272 12.590 100 94 186 2 1476466224 275471328 21058624
02:52:47 1125 8192000000 10391184384 10812338176 1180163008 12.592 100 97 187 3 1498572848 297028656 21381184
02:53:52 834 8192000000 10416045584 10839113728 1187282512 12.658 100 96 186 1 1524087152 314287856 22516784
02:54:56 2361 8192000000 10405360768 10854137856 1185404224 12.641 100 75 142 2 1518028160 315522000 17101936
02:56:01 1186 8192000000 10429599296 10860265472 1196625152 12.745 100 93 179 1 1535546704 317121120 21800432
02:57:05 3631 8192000000 10438954992 10893611008 1190523552 12.688 99 67 134 0 1535261840 327783776 16954512
02:58:11 2377 8192000000 10412968352 10858397696 1190357312 12.687 100 74 146 0 1510204304 302437344 17409648
02:59:15 5388 8192000000 10391170880 10840309760 1192518096 12.707 100 63 123 1 1497951232 290660800 14772336
03:00:22 8447 8192000000 10400093488 10826383360 1175617200 12.549 99 53 106 0 1496572752 307726816 13228736
03:01:32 5142 8192000000 10404269344 10861604864 1186814112 12.654 100 54 108 0 1510680128 310124080 13741936
03:02:47 4184 8192000000 10406824832 10847391744 1179054592 12.581 100 55 108 1 1509221888 317521776 12645520
03:03:59 16777 8192000000 10358495184 10835738624 1171818128 12.514 100 38 76 0 1496423728 314969600 9636000
03:05:31 21763 8192000000 10281928560 10780585984 1151271104 12.321 100 20 40 0 1399619296 242848112 5500080
03:07:14 64189 8192000000 10073752512 10558251008 1097584672 11.815 97 8 16 0 1178439008 79337360 1516976
03:09:19 977 8192000000 10060785776 10470756352 1116129280 11.990 100 92 181 6 1160516288 24811264 19575744
03:10:21 2926 8192000000 10129819088 10531106816 1129172992 12.114 100 89 166 1 1229606528 84189344 16244192
03:11:26 73177 8192000000 10082859568 10538246144 1132386640 12.144 99 20 40 0 1199518160 62787008 4344512
03:13:40 95657 8192000000 10073006416 10555629568 1107956928 11.913 97 27 49 1 1160068176 46845184 5266064
03:16:18 131762 8192000000 10104502864 10553688064 1068351824 11.536 94 20 36 0 1118265520 46249200 3664496
03:19:31 62715 8192000000 10069494992 10509545472 1095758560 11.797 98 32 60 0 1149686192 47347136 6580496
03:21:44 25776 8192000000 10128542016 10568409088 1132676320 12.147 100 30 60 0 1206018448 66342096 7000032
03:23:10 72075 8192000000 10149524128 10590318592 1115398640 11.983 98 28 56 0 1202745328 80626032 6720656
03:25:28 89440 8192000000 10191400560 10661912576 1122129632 12.047 98 15 30 0 1261971760 136633744 3208384
03:28:11 99069 8192000000 10126679200 10590756864 1086726320 11.712 96 15 30 0 1170919184 81009504 3183360
03:31:02 98528 8192000000 10057348144 10539573248 1092738304 11.769 97 27 52 1 1190417088 92153456 5525328
03:33:41 90758 8192000000 10081307920 10527662080 1131252000 12.133 100 41 80 1 1183783856 44004576 8527280
03:36:13 74679 8192000000 10078917728 10580996096 1139090544 12.207 100 8 16 0 1191802576 51041632 1670400
03:38:28 60289 8192000000 10087280240 10555826176 1112540688 11.956 97 18 37 0 1186630864 70528688 3561488
03:40:34 100468 8192000000 10103242544 10560200704 1134473536 12.164 99 18 36 0 1238066640 99819952 3773152
03:43:16 24590 8192000000 10086858112 10528940032 1145176288 12.264 100 20 39 0 1182334064 33420576 3737200
03:44:44 18311 8192000000 10151262416 10592169984 1151769392 12.326 100 41 81 0 1237353744 77421264 8163088
03:46:04 43965 8192000000 10174197328 10626101248 1137823792 12.195 98 27 54 0 1248508352 104887776 5796784
03:47:49 33799 8192000000 10170366784 10612903936 1142849600 12.242 99 29 58 0 1239843136 90376608 6616928
03:49:23 39010 8192000000 10222985968 10672386048 1129712832 12.119 98 23 46 0 1265654672 130634288 5307552
03:51:09 46668 8192000000 10089312912 10569469952 1144063152 12.254 100 15 30 0 1233563936 86316608 3184176
03:52:56 9790 8192000000 10126324400 10549764096 1148629616 12.297 100 49 94 0 1207187392 49515536 9042240
03:54:08 131065 8192000000 10149012112 10605580288 1138218368 12.199 99 48 93 1 1247477328 99593584 9665376
03:57:24 82570 8192000000 10102152832 10593550336 1105968432 11.894 97 17 35 0 1184555024 74699472 3887120
03:59:47 58881 8192000000 10145450432 10605035520 1118111696 12.009 99 14 26 1 1215009536 93378480 3519360
04:01:46 86001 8192000000 10064927392 10527281152 1108195136 11.915 98 20 36 1 1155182608 43271936 3715536
04:04:15 6739 8192000000 10061301072 10512429056 1103821648 11.874 98 58 108 3 1160747632 46498592 10427392
04:05:22 65754 8192000000 10059736400 10541154304 1105171600 11.887 99 10 21 0 1153775568 46555840 2048128
04:08:01 21118 8192000000 10054489760 10498224128 1092190976 11.763 97 33 61 4 1132397120 33620272 6585872
04:09:31 27559 8192000000 10123715792 10563457024 1120380256 12.031 99 26 51 0 1192827840 67280624 5166960
04:11:02 39284 8192000000 10167959584 10622861312 1104162896 11.877 97 43 85 1 1231550208 117710896 9676416
04:12:59 78415 8192000000 10074538288 10530779136 1100488560 11.842 98 19 33 0 1181145824 77279088 3378176
04:15:30 15580 8192000000 10105318800 10532241408 1090465680 11.747 98 53 105 0 1176005344 75376544 10163120
04:16:49 5961 8192000000 10112119744 10527494144 1115580304 11.985 100 65 130 0 1191976576 63653152 12743120
04:17:56 4581 8192000000 10119594304 10549755904 1116452032 11.993 100 67 132 0 1217627712 88108448 13067232
04:19:01 57407 8192000000 10089964496 10555047936 1114418480 11.974 99 41 82 0 1203245472 80565168 8261824
04:21:17 65108 8192000000 10094763520 10526953472 1129190304 12.114 100 49 88 1 1189495264 51573328 8731632
04:23:23 55896 8192000000 10062043024 10530992128 1119869776 12.026 99 10 19 0 1167446880 45956912 1620192
04:25:25 30537 8192000000 10056325024 10513358848 1133524384 12.155 100 10 21 0 1157068432 21599248 1944800
04:26:56 79135 8192000000 10061218384 10547060736 1105237712 11.887 97 14 28 0 1161544656 53733248 2573696
04:29:34 28131 8192000000 10089781440 10555908096 1124930640 12.074 99 40 79 0 1191508976 58515840 8062496
04:31:18 43110 8192000000 10128521728 10570244096 1116561728 11.994 98 32 64 0 1204750400 81510256 6678416
04:33:02 54614 8192000000 10126466816 10593677312 1094332096 11.784 96 24 46 0 1189289648 89942944 5014608
04:34:56 76425 8192000000 10123706464 10586742784 1128777248 12.110 99 23 46 0 1222479088 88501472 5200368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-07-2022 04:38:26 to 01-07-2022 04:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-07-2022 04:38:43 to 01-07-2022 04:38:45.

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          212656
MON$OLDEST_ACTIVE               215914
MON$OLDEST_SNAPSHOT             215914
MON$NEXT_TRANSACTION            215914
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                       2032480
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2794 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2794 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2794 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2794 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:20 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:23 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:28 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:29 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:30 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:30 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Jul  1 04:37:31 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
--- end of diff output ---

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

01.07.22 04:38:46. 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-07-01 04:38:49 No severe PSQL-related problems occured
01.07.22 04:38:50. End of report.