Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.01.2023 04:39
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 15013824 kB
MemAvailable 30380212 kB
Buffers 20 kB
Cached 16655800 kB
SwapCached 4148 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-01-15 04:37:25
DTS_END                         2023-01-15 04:37:25.0590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38944
TRN_ID                          216381
ATT_ID                          575
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: 9039.57 24123 1084748
customer order: creation 163.27 477 19592
customer order: refuse 13.73 366 1648
order to supplier: creation 43.17 1285 5180
order to supplier: removal 3.53 154937 424
invoice (draft): creation 44.69 3100 5363
invoice (draft): removal 53.33 931 6399
invoice accept: apply 38.62 25876 4634
invoice accept: cancel 47.63 1022 5715
customer reserve: creation 3706.29 239 444755
customer reserve: removal 4640.30 92 556836
realization accept: apply 79.21 257 9505
realization accept: cancel 80.53 148 9663
payment from customer: creation 56.71 182 6805
payment from customer: removal 5.63 106 675
payment to supplier: creation 47.43 191 5691
payment to supplier: removal 3.53 127 424
service: total inventory turnovers 3.77 4066 452
service: total monetary turnovers 7.22 288 866
service: aggregate perf. data 0.98 14674 117
service: refresh index statistics 0.03 274097 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5367
TEST_TIME 2 6823
TEST_TIME 3 6093
TEST_TIME 4 6184
TEST_TIME 5 6383
TEST_TIME 6 6093
TEST_TIME 7 5974
TEST_TIME 8 6198
TEST_TIME 9 6426
TEST_TIME 10 6674
TEST_TIME 11 6663
TEST_TIME 12 6792
TEST_TIME 13 6667
TEST_TIME 14 6823
TEST_TIME 15 7032
TEST_TIME 16 7466
TEST_TIME 17 7827
TEST_TIME 18 7951
TEST_TIME 19 7929
TEST_TIME 20 7851
TEST_TIME 21 7846
TEST_TIME 22 8018
TEST_TIME 23 8146
TEST_TIME 24 8298
TEST_TIME 25 8634
TEST_TIME 26 9119
TEST_TIME 27 9636
TEST_TIME 28 9962
TEST_TIME 29 10316
TEST_TIME 30 10405
TEST_TIME 31 11075
TEST_TIME 32 11305
TEST_TIME 33 11600
TEST_TIME 34 11497
TEST_TIME 35 11313
TEST_TIME 36 11258
TEST_TIME 37 11130
TEST_TIME 38 11100
TEST_TIME 39 11143
TEST_TIME 40 11124
TEST_TIME 41 10982
TEST_TIME 42 10739
TEST_TIME 43 10540
TEST_TIME 44 10494
TEST_TIME 45 10378
TEST_TIME 46 10291
TEST_TIME 47 10223
TEST_TIME 48 10088
TEST_TIME 49 10094
TEST_TIME 50 10041
TEST_TIME 51 10167
TEST_TIME 52 10021
TEST_TIME 53 9946
TEST_TIME 54 9825
TEST_TIME 55 9785
TEST_TIME 56 9777
TEST_TIME 57 9686
TEST_TIME 58 9619
TEST_TIME 59 9519
TEST_TIME 60 9427
TEST_TIME 61 9448
TEST_TIME 62 9579
TEST_TIME 63 9597
TEST_TIME 64 9536
TEST_TIME 65 9527
TEST_TIME 66 9611
TEST_TIME 67 9563
TEST_TIME 68 9617
TEST_TIME 69 9611
TEST_TIME 70 9700
TEST_TIME 71 9701
TEST_TIME 72 9663
TEST_TIME 73 9696
TEST_TIME 74 9724
TEST_TIME 75 9703
TEST_TIME 76 9603
TEST_TIME 77 9542
TEST_TIME 78 9522
TEST_TIME 79 9556
TEST_TIME 80 9469
TEST_TIME 81 9468
TEST_TIME 82 9436
TEST_TIME 83 9362
TEST_TIME 84 9277
TEST_TIME 85 9353
TEST_TIME 86 9339
TEST_TIME 87 9303
TEST_TIME 88 9237
TEST_TIME 89 9206
TEST_TIME 90 9255
TEST_TIME 91 9236
TEST_TIME 92 9220
TEST_TIME 93 9253
TEST_TIME 94 9260
TEST_TIME 95 9280
TEST_TIME 96 9276
TEST_TIME 97 9292
TEST_TIME 98 9297
TEST_TIME 99 9279
TEST_TIME 100 9285
TEST_TIME 101 9283
TEST_TIME 102 9219
TEST_TIME 103 9180
TEST_TIME 104 9170
TEST_TIME 105 9143
TEST_TIME 106 9089
TEST_TIME 107 9053
TEST_TIME 108 9084
TEST_TIME 109 9067
TEST_TIME 110 9053
TEST_TIME 111 9039
TEST_TIME 112 9042
TEST_TIME 113 9039
TEST_TIME 114 9034
TEST_TIME 115 9113
TEST_TIME 116 9142
TEST_TIME 117 9126
TEST_TIME 118 9135
TEST_TIME 119 9134
TEST_TIME 120 9059

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-01-2023 04:40:03 to 15-01-2023 04:40:07.

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 1493899 1493899 0 0.00 0 27619 13 0 0
doc_list_biud 1493891 1493891 0 0.00 0 39084 40 0 0
sp_add_invoice_to_stock 4713 4634 79 1.67 49 229887 25877 0 79
sp_cancel_adding_invoice 5715 5715 0 0.00 7 55947 1023 0 0
sp_cancel_client_order 1648 1648 0 0.00 6 10071 366 0 0
sp_cancel_customer_reserve 556836 556836 0 0.00 1 39096 92 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 4 5384 107 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 4 4784 128 0 0
sp_cancel_supplier_invoice 6403 6399 4 0.06 2 21763 932 0 4
sp_cancel_supplier_order 424 424 0 0.00 12 1497947 154937 0 0
sp_cancel_write_off 9663 9663 0 0.00 2 17293 148 0 0
sp_client_order 19592 19592 0 0.00 5 37446 477 0 0
sp_customer_reserve 444755 444755 0 0.00 1 67142 240 0 0
sp_fill_shopping_cart 30125 30125 0 0.00 1 12846 74 0 0
sp_fill_shopping_cart_clo_res 444745 444745 0 0.00 0 14951 18 0 0
sp_get_clo_for_invoice 4600 4600 0 0.00 0 27950 302 0 0
sp_kill_cost_storno 16477 16477 0 0.00 0 11051 65 0 0
sp_kill_qstorno_ret_qs2qd 1130158 1130158 0 0.00 0 39038 40 0 0
sp_kill_qty_storno 594784 594784 0 0.00 0 39081 92 0 0
sp_lock_selected_doc 19676 19676 0 0.00 0 6513 6 0 0
sp_make_cost_storno 26599 26599 0 0.00 0 27604 81 0 0
sp_multiply_rows_for_pdistr 26596 26596 0 0.00 0 9825 37 0 0
sp_multiply_rows_for_qdistr 19591 19591 0 0.00 0 14798 187 0 0
sp_pay_from_customer 6805 6805 0 0.00 5 24570 183 0 0
sp_pay_to_supplier 5691 5691 0 0.00 6 18132 192 0 0
sp_payment_common 12496 12496 0 0.00 2 8700 47 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 10025 299 0 0
sp_qd_handle_on_invoice_upd_sts 10310 10310 0 0.00 0 36920 494 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 15803 59 0 0
sp_reserve_write_off 9505 9505 0 0.00 9 27671 257 0 0
sp_supplier_invoice 5363 5363 0 0.00 11 94088 3101 0 0
sp_supplier_order 5180 5180 0 0.00 8 50645 1285 0 0
srv_aggregate_perf_data 117 117 0 0.00 2349 226080 14675 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 740 114995 40356 0 0
srv_find_qd_qs_mism 1037547 1037547 0 0.00 0 10363 10 0 0
srv_make_invnt_saldo 452 452 0 0.00 74 114350 4066 0 0
srv_make_money_saldo 866 866 0 0.00 1 18962 288 0 0
srv_recalc_idx_stat 4 4 0 0.00 76957 393740 274098 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4635 4635 0 0.00 1 9869 853 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 5553 372 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 3292 27 0 0
t$perf-norm:sp_cancel_customer_reserve 596 596 0 0.00 0 2238 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1323 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 526 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3249 37 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 85305 6780 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2952 22 0 0
t$perf-norm:sp_client_order 19592 19592 0 0.00 0 9943 36 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 1218 13 0 0
t$perf-norm:sp_pay_from_customer 6805 6805 0 0.00 0 8620 20 0 0
t$perf-norm:sp_pay_to_supplier 5691 5691 0 0.00 0 6799 22 0 0
t$perf-norm:sp_reserve_write_off 9505 9505 0 0.00 0 9344 23 0 0
t$perf-norm:sp_supplier_invoice 5363 5363 0 0.00 0 7637 35 0 0
t$perf-norm:sp_supplier_order 5181 5181 0 0.00 0 2101 26 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 699 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 210 6 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 1856 17 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 5952 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 190 56 0 0
v_add_invoice_to_stock 9346 9188 158 1.69 0 12441 23 0 158
v_all_customers 35732 35732 0 0.00 0 76 3 0 0
v_all_suppliers 29682 29682 0 0.00 0 229 4 0 0
v_all_wares 39180 39180 0 0.00 0 155 3 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1101 16 0 0
v_cancel_client_order 3296 3296 0 0.00 0 2362 10 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 380 10 0 0
v_cancel_customer_reserve 1192 1192 0 0.00 1 2906 12 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 770 11 0 0
v_cancel_supplier_invoice 816 808 8 0.98 0 1277 22 0 8
v_cancel_supplier_order 848 848 0 0.00 0 883 11 0 0
v_cancel_write_off 2016 2016 0 0.00 0 4950 23 0 0
v_max_id_clo_ord 5178 5178 0 0.00 0 107 4 0 0
v_max_id_clo_res 944 944 0 0.00 0 1604 8 0 0
v_max_id_ord_sup 5356 5356 0 0.00 0 1844 7 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 2228 8 0 0
v_max_non_paid_realizn 3574 3574 0 0.00 0 679 7 0 0
v_min_id_clo_ord 5178 5178 0 0.00 0 2531 8 0 0
v_min_id_clo_res 944 944 0 0.00 0 6192 16 0 0
v_min_id_ord_sup 5356 5356 0 0.00 0 3778 10 0 0
v_min_non_paid_invoice 5691 5691 0 0.00 0 6953 18 0 0
v_min_non_paid_realizn 6805 6805 0 0.00 0 7424 20 0 0
v_our_firm 9910 9910 0 0.00 0 70 4 0 0
v_reserve_write_off 19008 19008 0 0.00 0 3596 10 0 0
x_lock_depdocs_on_canc_invoice 11710 11710 0 0.00 0 13228 119 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 9556 334 0 0
x_make_qstorno_1000_1200 5180 5180 0 0.00 3 50588 1140 0 0
x_make_qstorno_1200_2000 5363 5363 0 0.00 4 92661 2890 0 0
x_make_qstorno_2100_3300 444752 444752 0 0.00 0 61154 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2023 04:40:08 to 15-01-2023 04:40:12.

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:28 920 8192000000 8934399632 8991637504 36709856 0.446 8 6 12 0 38663952 308656 1645440
02:08:31 18190 8192000000 9713495104 10010943488 791965536 8.815 100 89 178 0 820696912 7401072 21330304
02:09:52 18121 8192000000 9766200800 10073882624 840330080 9.303 100 84 168 0 871259776 10810496 20119200
02:11:15 12826 8192000000 9798525952 10117029888 872994688 9.630 100 89 178 1 910763488 17519856 20248944
02:12:34 4219 8192000000 9870573504 10198155264 914298112 10.040 100 90 178 0 979712928 45665504 19749312
02:13:46 5674 8192000000 9920101136 10261274624 952840032 10.419 100 88 176 0 1040847280 68996416 19010832
02:14:55 2283 8192000000 9941584432 10292486144 989961376 10.781 100 83 165 0 1062339184 55372224 17005584
02:15:59 1441 8192000000 9999706448 10359787520 1012663088 11.001 100 91 173 0 1111103600 81166656 17273856
02:17:03 830 8192000000 9999524816 10366447616 1038958288 11.255 100 93 179 0 1117075488 60618096 17499104
02:18:05 853 8192000000 10043137376 10419548160 1051967968 11.380 100 93 173 2 1165723136 95784720 17970448
02:19:08 990 8192000000 10038205200 10413551616 1064549440 11.500 100 95 174 0 1148341744 66118416 17673888
02:20:09 3046 8192000000 10037062160 10414059520 1071462912 11.566 100 82 145 1 1151997696 64638304 15896480
02:21:14 1393 8192000000 10055098320 10441035776 1080435440 11.652 100 93 167 0 1175638512 77130304 18072768
02:22:16 916 8192000000 10057334512 10441179136 1085144432 11.696 100 89 173 2 1176525280 74341744 17039104
02:23:18 1137 8192000000 10053306432 10446663680 1091003424 11.752 100 70 133 0 1172397264 68485136 12908704
02:24:20 1032 8192000000 10052588608 10439888896 1092186400 11.763 100 85 160 1 1171354384 64490032 14677952
02:25:22 4572 8192000000 10043880416 10436829184 1094007056 11.781 100 64 123 1 1165053648 58985952 12060640
02:26:27 1062 8192000000 10078846064 10470760448 1096549520 11.805 100 95 182 3 1200159248 84512352 19097376
02:27:36 1117 8192000000 10051909040 10437332992 1096006944 11.800 100 91 174 2 1179619936 64596496 19016496
02:28:38 3265 8192000000 10048122480 10440790016 1096631200 11.806 100 74 145 3 1170295424 59523568 14140656
02:29:41 1477 8192000000 10049874432 10447421440 1098351232 11.822 100 70 132 1 1168583664 57183760 13048672
02:30:43 1218 8192000000 10026619440 10412572672 1080512816 11.652 100 94 175 1 1150897984 51442384 18942784
02:31:46 1251 8192000000 9991497072 10364981248 1038863264 11.254 100 98 184 0 1117235072 59700224 18671584
02:32:48 1270 8192000000 9987922576 10358173696 1033946608 11.206 98 95 178 2 1110696224 59380992 17368624
02:33:50 1743 8192000000 9990110784 10369417216 1045848896 11.321 100 76 145 0 1113263120 52735968 14678256
02:34:53 1304 8192000000 10006325040 10381979648 1048984688 11.351 100 91 174 0 1128314256 61419920 17909648
02:35:55 1040 8192000000 10012001280 10388529152 1047397328 11.336 100 83 157 2 1131362080 68364608 15600144
02:36:57 1072 8192000000 9959436144 10329788416 1029215120 11.161 98 92 173 2 1083127728 35867728 18044880
02:38:03 16435 8192000000 10004325392 10383921152 1066643328 11.520 100 71 140 2 1132901360 52478256 13779776
02:39:27 1026 8192000000 10071677760 10453155840 1076988016 11.619 100 95 181 2 1191178752 96224976 17965760
02:40:30 12689 8192000000 10086880432 10480226304 1086863376 11.713 99 65 127 0 1203193920 103555888 12774656
02:41:47 5381 8192000000 10104298528 10496294912 1103286016 11.869 100 58 111 0 1217438304 102812992 11339296
02:42:56 11606 8192000000 10150000640 10542473216 1121771584 12.044 100 70 135 0 1265647008 129619760 14255664
02:44:17 5958 8192000000 10222067392 10617991168 1131467008 12.135 100 71 142 1 1324098752 177372544 15259200
02:45:34 1280 8192000000 10260527824 10653876224 1149618592 12.306 100 94 177 1 1375279584 204890336 20770656
02:46:47 872 8192000000 10288552176 10680860672 1174818288 12.542 100 92 180 0 1395674400 200683216 20172896
02:47:53 1952 8192000000 10329595072 10726813696 1188734480 12.672 100 100 197 1 1441541984 230048128 22759376
02:48:58 2416 8192000000 10375940736 10780278784 1199076384 12.768 100 90 172 1 1490887792 272060960 19750448
02:50:02 1793 8192000000 10429242016 10833039360 1208609696 12.856 100 91 179 0 1538055744 308480544 20965504
02:51:06 735 8192000000 10478744864 10892832768 1211722464 12.885 100 98 181 0 1591741952 358596480 21423008
02:52:10 1359 8192000000 10502351584 10936053760 1222129888 12.981 100 78 154 0 1612851360 372293408 18428064
02:53:14 805 8192000000 10519014208 10959187968 1223878448 12.998 100 98 189 1 1629791456 383165840 22747168
02:54:16 1197 8192000000 10539713984 10953666560 1217088128 12.935 100 89 176 1 1647780656 410292464 20400064
02:55:21 4282 8192000000 10502343424 10945830912 1209423280 12.864 100 64 122 2 1616242528 391719392 15099856
02:56:27 1384 8192000000 10544994832 10966937600 1220135632 12.963 100 67 128 1 1652344240 417159920 15048688
02:57:35 754 8192000000 10607586416 11033206784 1222258256 12.983 100 94 185 2 1714777584 470023584 22495744
02:58:40 2081 8192000000 10634976096 11053666304 1208575760 12.856 100 86 163 0 1738406544 510578016 19252768
02:59:45 11060 8192000000 10625114448 11109335040 1198274944 12.760 100 51 100 0 1743233024 531737184 13220896
03:01:18 5693 8192000000 10586776128 11023388672 1164859536 12.449 99 53 103 1 1693015968 514977840 13178592
03:02:30 22347 8192000000 10546844704 11074654208 1176600144 12.558 100 58 115 0 1638960848 448145552 14215152
03:04:03 14130 8192000000 10425819520 10948386816 1151266704 12.321 99 42 80 1 1534854784 373110768 10477312
03:05:19 7909 8192000000 10448497856 10932011008 1153714352 12.344 99 51 98 1 1546164752 380251616 12198784
03:06:29 52013 8192000000 10323014624 10819510272 1160910208 12.412 100 25 50 0 1471468928 303922640 6636080
03:08:22 44346 8192000000 10200942352 10749308928 1130601184 12.127 99 19 38 0 1348216976 212774368 4841424
03:10:07 69941 8192000000 10048221888 10510118912 1057221472 11.430 93 12 19 0 1088230240 29136720 1872048
03:12:18 59634 8192000000 10076265152 10525192192 1125087440 12.075 100 21 43 0 1163062608 33538352 4436816
03:14:27 96235 8192000000 10076959056 10535600128 1096365728 11.803 97 15 30 0 1126350944 27251360 2733856
03:17:03 47956 8192000000 10051801840 10519683072 1098382176 11.822 97 9 17 0 1144617104 44283520 1951408
03:18:53 114992 8192000000 10087131840 10512244736 1119231808 12.020 99 38 73 0 1162866128 36306528 7327792
03:22:05 82402 8192000000 10124781312 10568945664 1102596336 11.862 96 27 52 0 1179389792 71488080 5305376
03:24:32 56371 8192000000 10187998816 10627915776 1131372784 12.134 99 41 82 0 1247502400 106955008 9174608
03:26:37 34241 8192000000 10154840112 10660261888 1118398352 12.012 99 19 37 0 1254053856 131525104 4130400
03:28:20 93602 8192000000 10048161696 10507722752 1100752624 11.845 97 7 14 0 1153750256 51530640 1466992
03:30:56 60620 8192000000 10088911856 10547105792 1127365456 12.097 99 22 44 0 1183317312 50898016 5053840
03:33:06 70208 8192000000 10061017024 10516692992 1133863808 12.158 100 15 31 0 1182862144 45469424 3528912
03:35:21 50937 8192000000 10123709344 10581168128 1147671520 12.288 100 28 56 0 1220023520 66288496 6063504
03:37:13 94830 8192000000 10136113696 10647547904 1130584144 12.127 98 15 30 0 1247454336 113326016 3544176
03:40:13 57622 8192000000 10090246960 10540331008 1145499136 12.267 99 22 40 0 1192415520 42374400 4541984
03:42:12 53605 8192000000 10120289872 10596519936 1105636144 11.891 96 23 46 0 1179990352 69224832 5129376
03:44:13 37575 8192000000 10120097776 10585489408 1133731520 12.157 99 13 26 0 1209393232 73040288 2621424
03:45:51 66854 8192000000 10124079168 10613497856 1126054464 12.084 98 30 60 0 1214915504 82959792 5901248
03:48:02 97952 8192000000 10126988000 10604785664 1106156272 11.896 96 12 21 0 1146761760 38816512 1788976
03:50:44 26162 8192000000 10125156400 10562609152 1138477136 12.201 99 37 70 0 1203129520 56837728 7814656
03:52:15 55031 8192000000 10160311728 10633719808 1137002352 12.187 99 33 64 1 1249555536 105678208 6874976
03:54:14 72698 8192000000 10119307824 10565844992 1141862992 12.233 99 23 44 1 1214576208 67912416 4800800
03:56:34 81044 8192000000 10106788672 10576793600 1119441024 12.022 97 17 34 0 1187998688 65021872 3535792
03:58:57 60527 8192000000 10122038112 10594881536 1128267792 12.105 98 13 27 0 1190252080 58994704 2989584
04:01:02 113525 8192000000 10111581520 10589622272 1144601120 12.259 100 13 24 0 1217892368 70473712 2817536
04:03:57 46669 8192000000 10081353472 10533351424 1093176928 11.773 97 31 60 0 1152884160 53537408 6169824
04:05:43 53284 8192000000 10119464848 10593591296 1107031024 11.904 98 13 26 0 1196345664 86614336 2700304
04:07:41 78835 8192000000 10093322512 10564218880 1135428592 12.173 100 15 26 1 1195563440 57169776 2965072
04:10:06 87235 8192000000 10101012528 10546536448 1117307632 12.002 98 15 30 0 1192402160 71770384 3324144
04:12:36 22092 8192000000 10095826656 10536554496 1113773296 11.968 99 53 104 0 1170947856 46245360 10929200
04:13:59 23725 8192000000 10145511504 10583740416 1131042320 12.131 100 47 92 0 1223986000 83856816 9086864
04:15:24 13536 8192000000 10087680560 10526269440 1115610032 11.985 99 48 96 0 1172373696 47515232 9248432
04:16:38 8741 8192000000 10105717696 10540187648 1120439888 12.031 99 53 106 0 1200099568 68953840 10705840
04:17:47 13129 8192000000 10096314928 10522386432 1111811440 11.950 98 65 122 0 1182378480 58272560 12294480
04:19:02 29192 8192000000 10104244080 10547974144 1135505296 12.173 100 68 134 0 1205992112 56963648 13523168
04:21:21 62144 8192000000 10109314144 10593796096 1136357024 12.181 100 48 96 0 1210081232 63659136 10065072
04:24:51 77348 8192000000 10073631696 10562019328 1074620992 11.596 94 20 40 0 1118551712 39259696 4671024
04:27:13 38796 8192000000 10093317168 10537824256 1120057840 12.028 99 29 58 2 1171096400 44564992 6473568
04:28:52 30771 8192000000 10139622496 10586513408 1097112032 11.810 96 31 60 1 1187984480 84434800 6437648
04:30:23 66578 8192000000 10167846912 10636001280 1123002656 12.055 99 30 60 0 1250288016 120818480 6466880
04:32:34 107598 8192000000 10057810880 10515812352 1086167616 11.706 96 12 22 0 1123439216 34786480 2485120
04:35:22 45003 8192000000 10097354784 10550833152 1121084416 12.037 100 31 60 0 1202099264 74681008 6333840
04:37:10 53208 8192000000 9295905760 9602494464 403799808 4.697 37 5 10 0 452946640 48239152 907680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-01-2023 04:40:14 to 15-01-2023 04:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-01-2023 04:40:31 to 15-01-2023 04:40: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          216672
MON$OLDEST_ACTIVE               216673
MON$OLDEST_SNAPSHOT             216673
MON$NEXT_TRANSACTION            216673
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                       2060640
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

15.01.23 04:40: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-01-15 04:40:37 No severe PSQL-related problems occured
15.01.23 04:40:38. End of report.