Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.05.2022 22: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 16445672 kB
MemAvailable 31500212 kB
Buffers 0 kB
Cached 15239908 kB
SwapCached 3260 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-05-02 22:38:11
DTS_END                         2022-05-02 22:38:11.0370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49176
TRN_ID                          236632
ATT_ID                          717
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: 9356.72 25279 1122806
customer order: creation 161.54 408 19385
customer order: refuse 13.08 379 1569
order to supplier: creation 43.84 1434 5261
order to supplier: removal 3.33 118360 400
invoice (draft): creation 42.67 3888 5120
invoice (draft): removal 55.94 898 6713
invoice accept: apply 39.50 18883 4740
invoice accept: cancel 50.53 869 6064
customer reserve: creation 3757.73 180 450928
customer reserve: removal 4897.30 62 587676
realization accept: apply 78.98 220 9478
realization accept: cancel 87.28 119 10473
payment from customer: creation 56.88 134 6825
payment from customer: removal 5.54 85 665
payment to supplier: creation 46.73 167 5608
payment to supplier: removal 3.58 117 430
service: total inventory turnovers 4.54 1278 545
service: total monetary turnovers 6.87 25 824
service: aggregate perf. data 0.82 23434 98
service: refresh index statistics 0.03 334632 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-05-2022 22:39:03 to 02-05-2022 22:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5054
TEST_TIME 2 5551
TEST_TIME 3 4224
TEST_TIME 4 3822
TEST_TIME 5 4890
TEST_TIME 6 5411
TEST_TIME 7 5574
TEST_TIME 8 5920
TEST_TIME 9 5809
TEST_TIME 10 6221
TEST_TIME 11 6331
TEST_TIME 12 6213
TEST_TIME 13 6407
TEST_TIME 14 6563
TEST_TIME 15 6689
TEST_TIME 16 6878
TEST_TIME 17 7458
TEST_TIME 18 7605
TEST_TIME 19 7808
TEST_TIME 20 8168
TEST_TIME 21 8452
TEST_TIME 22 8424
TEST_TIME 23 8716
TEST_TIME 24 9051
TEST_TIME 25 8887
TEST_TIME 26 9128
TEST_TIME 27 9223
TEST_TIME 28 9484
TEST_TIME 29 9892
TEST_TIME 30 10659
TEST_TIME 31 11277
TEST_TIME 32 11129
TEST_TIME 33 10838
TEST_TIME 34 10726
TEST_TIME 35 10713
TEST_TIME 36 10676
TEST_TIME 37 10568
TEST_TIME 38 10389
TEST_TIME 39 10358
TEST_TIME 40 10290
TEST_TIME 41 10159
TEST_TIME 42 10084
TEST_TIME 43 10054
TEST_TIME 44 9910
TEST_TIME 45 9778
TEST_TIME 46 9831
TEST_TIME 47 9749
TEST_TIME 48 9737
TEST_TIME 49 9569
TEST_TIME 50 9541
TEST_TIME 51 9474
TEST_TIME 52 9475
TEST_TIME 53 9417
TEST_TIME 54 9480
TEST_TIME 55 9338
TEST_TIME 56 9427
TEST_TIME 57 9491
TEST_TIME 58 9434
TEST_TIME 59 9623
TEST_TIME 60 9600
TEST_TIME 61 9526
TEST_TIME 62 9578
TEST_TIME 63 9618
TEST_TIME 64 9658
TEST_TIME 65 9618
TEST_TIME 66 9760
TEST_TIME 67 9702
TEST_TIME 68 9731
TEST_TIME 69 9780
TEST_TIME 70 9806
TEST_TIME 71 9681
TEST_TIME 72 9618
TEST_TIME 73 9582
TEST_TIME 74 9556
TEST_TIME 75 9527
TEST_TIME 76 9539
TEST_TIME 77 9535
TEST_TIME 78 9472
TEST_TIME 79 9442
TEST_TIME 80 9359
TEST_TIME 81 9373
TEST_TIME 82 9413
TEST_TIME 83 9370
TEST_TIME 84 9331
TEST_TIME 85 9335
TEST_TIME 86 9314
TEST_TIME 87 9289
TEST_TIME 88 9325
TEST_TIME 89 9283
TEST_TIME 90 9380
TEST_TIME 91 9434
TEST_TIME 92 9470
TEST_TIME 93 9492
TEST_TIME 94 9517
TEST_TIME 95 9569
TEST_TIME 96 9571
TEST_TIME 97 9619
TEST_TIME 98 9607
TEST_TIME 99 9598
TEST_TIME 100 9560
TEST_TIME 101 9512
TEST_TIME 102 9553
TEST_TIME 103 9508
TEST_TIME 104 9496
TEST_TIME 105 9478
TEST_TIME 106 9459
TEST_TIME 107 9456
TEST_TIME 108 9415
TEST_TIME 109 9382
TEST_TIME 110 9329
TEST_TIME 111 9390
TEST_TIME 112 9348
TEST_TIME 113 9352
TEST_TIME 114 9320
TEST_TIME 115 9363
TEST_TIME 116 9334
TEST_TIME 117 9353
TEST_TIME 118 9399
TEST_TIME 119 9433
TEST_TIME 120 9362

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-05-2022 22:39:07 to 02-05-2022 22:39:10.

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 1539075 1539075 0 0.00 0 23971 7 0 0
doc_list_biud 1539073 1539073 0 0.00 0 93975 30 0 0
sp_add_invoice_to_stock 4851 4740 111 2.28 42 200309 18883 0 111
sp_cancel_adding_invoice 6064 6064 0 0.00 5 110932 870 0 0
sp_cancel_client_order 1569 1569 0 0.00 4 23465 379 0 0
sp_cancel_customer_reserve 587676 587676 0 0.00 1 33000 62 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 9195 86 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 10842 118 0 0
sp_cancel_supplier_invoice 6721 6713 8 0.11 1 93990 898 0 8
sp_cancel_supplier_order 400 400 0 0.00 10 1222961 118361 0 0
sp_cancel_write_off 10473 10473 0 0.00 1 13784 120 0 0
sp_client_order 19385 19385 0 0.00 3 56402 409 0 0
sp_customer_reserve 450928 450928 0 0.00 0 80804 181 0 0
sp_fill_shopping_cart 29756 29756 0 0.00 1 16379 42 0 0
sp_fill_shopping_cart_clo_res 450917 450917 0 0.00 0 18228 15 0 0
sp_get_clo_for_invoice 4729 4729 0 0.00 0 33283 341 0 0
sp_kill_cost_storno 17632 17632 0 0.00 0 10397 63 0 0
sp_kill_qstorno_ret_qs2qd 1192938 1192938 0 0.00 0 93965 34 0 0
sp_kill_qty_storno 627102 627102 0 0.00 0 93972 74 0 0
sp_lock_selected_doc 19695 19695 0 0.00 0 7872 4 0 0
sp_make_cost_storno 26640 26640 0 0.00 0 20661 66 0 0
sp_multiply_rows_for_pdistr 26640 26640 0 0.00 0 23897 37 0 0
sp_multiply_rows_for_qdistr 19385 19385 0 0.00 0 56396 174 0 0
sp_pay_from_customer 6825 6825 0 0.00 4 19360 135 0 0
sp_pay_to_supplier 5608 5608 0 0.00 5 22480 168 0 0
sp_payment_common 12433 12433 0 0.00 1 22361 45 0 0
sp_qd_handle_on_cancel_clo 1569 1569 0 0.00 0 23460 336 0 0
sp_qd_handle_on_invoice_upd_sts 10793 10793 0 0.00 0 24266 378 0 0
sp_qd_handle_on_reserve_upd_sts 9478 9478 0 0.00 0 24118 74 0 0
sp_reserve_write_off 9478 9478 0 0.00 6 24169 221 0 0
sp_supplier_invoice 5120 5120 0 0.00 7 94215 3889 0 0
sp_supplier_order 5261 5261 0 0.00 5 46639 1435 0 0
srv_aggregate_perf_data 98 98 0 0.00 2676 343958 23435 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1975 154388 49261 0 0
srv_find_qd_qs_mism 1076331 1076331 0 0.00 0 18938 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 29 87701 1278 0 0
srv_make_money_saldo 824 824 0 0.00 1 474 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 254594 475877 334632 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4740 4740 0 0.00 0 14540 224 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 3826 133 0 0
t$perf-norm:sp_cancel_client_order 1569 1569 0 0.00 0 4289 14 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 5541 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 1752 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3563 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 3277 21 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 24630 1732 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 1778 8 0 0
t$perf-norm:sp_client_order 19385 19385 0 0.00 0 8510 18 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 2767 7 0 0
t$perf-norm:sp_pay_from_customer 6825 6825 0 0.00 0 17275 14 0 0
t$perf-norm:sp_pay_to_supplier 5608 5608 0 0.00 0 17063 16 0 0
t$perf-norm:sp_reserve_write_off 9478 9478 0 0.00 0 14477 14 0 0
t$perf-norm:sp_supplier_invoice 5120 5120 0 0.00 0 12363 26 0 0
t$perf-norm:sp_supplier_order 5261 5261 0 0.00 0 8007 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 405 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 361 12 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 1931 25 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 894 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 9680 9458 222 2.29 0 13480 25 0 222
v_all_customers 35976 35976 0 0.00 0 64 1 0 0
v_all_suppliers 28890 28890 0 0.00 0 15 1 0 0
v_all_wares 38770 38770 0 0.00 0 132 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1759 9 0 0
v_cancel_client_order 3138 3138 0 0.00 0 17811 16 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 2042 5 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2490 5 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 643 7 0 0
v_cancel_supplier_invoice 738 722 16 2.16 0 3316 23 0 16
v_cancel_supplier_order 800 800 0 0.00 0 3337 11 0 0
v_cancel_write_off 1986 1986 0 0.00 0 1426 12 0 0
v_max_id_clo_ord 5261 5261 0 0.00 0 637 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 6428 19 0 0
v_max_id_ord_sup 5110 5110 0 0.00 0 2951 3 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 2049 5 0 0
v_max_non_paid_realizn 3456 3456 0 0.00 0 1097 4 0 0
v_min_id_clo_ord 5261 5261 0 0.00 0 9378 8 0 0
v_min_id_clo_res 985 985 0 0.00 0 4533 14 0 0
v_min_id_ord_sup 5110 5110 0 0.00 0 2971 6 0 0
v_min_non_paid_invoice 5608 5608 0 0.00 0 22343 35 0 0
v_min_non_paid_realizn 6825 6825 0 0.00 0 19260 39 0 0
v_our_firm 9530 9530 0 0.00 0 20 1 0 0
v_reserve_write_off 18956 18956 0 0.00 0 11241 8 0 0
x_lock_depdocs_on_canc_invoice 12416 12416 0 0.00 0 17081 133 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 20280 512 0 0
x_make_qstorno_1000_1200 5261 5261 0 0.00 2 46546 1343 0 0
x_make_qstorno_1200_2000 5120 5120 0 0.00 3 92010 3747 0 0
x_make_qstorno_2100_3300 450928 450928 0 0.00 0 80783 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-05-2022 22:39:11 to 02-05-2022 22:39:15.

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
20:08:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:15 19329 8192000000 9900326704 10210447360 979602032 10.680 100 87 174 0 1007853264 5759920 22491312
20:10:42 15063 8192000000 9937857056 10258665472 1019651408 11.069 100 88 176 0 1050772176 8508160 22612608
20:12:02 14498 8192000000 9977753760 10309537792 1062374240 11.479 100 86 172 0 1093709520 9953776 21381504
20:13:22 10883 8192000000 10031573488 10375524352 1117634032 12.005 100 85 171 1 1148784032 11049840 20100160
20:14:40 8616 8192000000 10086746496 10446360576 1172627632 12.521 100 79 158 1 1203146016 12745968 17772416
20:15:51 4901 8192000000 10125807520 10491428864 1214983856 12.915 100 86 172 0 1248695808 14818352 18893600
20:16:58 8582 8192000000 10159526896 10538651648 1248977264 13.229 100 79 158 0 1278907568 13184592 16745712
20:18:08 1729 8192000000 10212493184 10592354304 1286649024 13.574 100 94 186 1 1325680480 18824784 20206672
20:19:11 1439 8192000000 10220332448 10606665728 1299455792 13.690 100 90 180 0 1333298448 13793104 20049552
20:20:13 46842 8192000000 10215637728 10616279040 1312998432 13.813 100 58 110 5 1332428096 5879328 13550336
20:22:01 7654 8192000000 10219106160 10627182592 1319897584 13.876 100 28 56 1 1332522720 6485920 6139216
20:23:13 7954 8192000000 10219960544 10640732160 1329850160 13.966 100 22 45 0 1340550704 5535872 5164672
20:24:23 8294 8192000000 10226299856 10645471232 1336048624 14.022 100 14 28 0 1345105632 5580864 3476144
20:25:35 7974 8192000000 10241887920 10656063488 1337264864 14.033 100 77 155 2 1363361120 7337872 18758384
20:26:45 5224 8192000000 10224250240 10642157568 1328473184 13.953 100 23 47 0 1341448048 7388944 5585920
20:27:51 9740 8192000000 10178263920 10592194560 1250106672 13.239 95 17 34 2 1259536496 5716848 3712976
20:29:02 6227 8192000000 10148959104 10556805120 1214627712 12.912 95 22 44 2 1224972960 5351248 4994000
20:30:10 37111 8192000000 10175632864 10555736064 1217635808 12.940 96 49 98 2 1234599568 4203840 12759920
20:31:48 4878 8192000000 10144768672 10539986944 1246883024 13.210 100 35 70 0 1261168720 6815520 7470176
20:32:58 4812 8192000000 10156638224 10558464000 1254007632 13.275 99 30 60 1 1267741216 6128544 7605040
20:34:06 8922 8192000000 10176014688 10589282304 1285568560 13.564 100 25 50 1 1296957520 5468096 5920864
20:35:18 12154 8192000000 10182814880 10595123200 1295889808 13.658 100 16 32 1 1305252816 5701136 3661872
20:36:30 5054 8192000000 10200179648 10612617216 1303481744 13.727 100 30 60 0 1320745808 10407360 6856704
20:37:38 7659 8192000000 10205676096 10620461056 1312951504 13.813 100 32 64 3 1326284928 5589216 7744208
20:38:55 35745 8192000000 10259130912 10671890432 1351155168 14.158 100 56 111 2 1376178368 12106880 12916320
20:40:32 119610 8192000000 10274483424 10702434304 1374358928 14.366 100 37 74 0 1396575088 13747808 8468352
20:43:37 8361 8192000000 10301448592 10721148928 1381101488 14.426 100 53 100 2 1411267872 17932480 12233904
20:44:49 5086 8192000000 10305027184 10731237376 1392075696 14.524 100 39 78 0 1418132032 16927920 9128416
20:45:59 13788 8192000000 10310149808 10739077120 1394568656 14.547 100 60 120 1 1430246960 21945808 13732496
20:47:22 8596 8192000000 10309887376 10728599552 1385761504 14.468 100 59 118 0 1422031648 22544352 13725792
20:48:35 4679 8192000000 10341825104 10756964352 1400808352 14.602 100 84 168 1 1451159360 30256192 20094816
20:49:42 4147 8192000000 10352485680 10771251200 1412922976 14.710 100 67 135 1 1462244576 33014928 16306672
20:50:50 6924 8192000000 10353032912 10764726272 1402766272 14.620 100 64 128 1 1457330976 38326624 16238080
20:52:05 2633 8192000000 10335262880 10740875264 1376770672 14.388 100 83 165 1 1439191408 42284912 20135824
20:53:13 4695 8192000000 10338751536 10746667008 1381233280 14.428 100 67 134 0 1443167376 45494848 16439248
20:54:30 3175 8192000000 10350661104 10756730880 1384011296 14.452 100 83 166 1 1453759424 48688464 21059664
20:55:39 7572 8192000000 10348186048 10759614464 1392113760 14.525 100 73 146 2 1462081728 51139616 18828352
20:56:52 1975 8192000000 10350576496 10751172608 1382721328 14.441 100 86 171 1 1454709216 50013728 21974160
20:57:59 12060 8192000000 10313993680 10738147328 1353456784 14.179 98 46 91 1 1421363280 54901664 13004832
20:59:16 16449 8192000000 10331769696 10745122816 1379104384 14.409 100 64 126 3 1433052672 37897632 16050656
21:00:36 9215 8192000000 10360491840 10782416896 1401310896 14.607 100 51 102 0 1466313728 51178032 13824800
21:01:53 8533 8192000000 10364445808 10800566272 1404236864 14.633 100 50 100 3 1480680256 63319728 13123664
21:03:03 25409 8192000000 10358370288 10798501888 1402272816 14.615 100 29 58 2 1465111120 54377568 8460736
21:05:13 6520 8192000000 10361656656 10786033664 1372938896 14.353 100 45 90 0 1445281024 60381648 11960480
21:06:23 25660 8192000000 10342540960 10781589504 1367915584 14.308 100 23 46 0 1437140080 62325936 6898560
21:08:41 39377 8192000000 10308906816 10752077824 1351213296 14.158 99 21 42 0 1398829984 42360240 5256448
21:10:43 55991 8192000000 10303901584 10734350336 1361321920 14.249 99 43 86 1 1377881840 6309184 10250736
21:12:44 10317 8192000000 10311311440 10752741376 1382290848 14.437 100 32 62 2 1408254928 18956000 7008080
21:13:55 154388 8192000000 10272122576 10717634560 1328259920 13.951 96 19 36 1 1353640144 21555072 3825152
21:17:40 71634 8192000000 10275880752 10735374336 1376629984 14.386 100 24 48 1 1398214432 16114720 5469728
21:20:20 98725 8192000000 10294382080 10758336512 1393199024 14.534 100 17 34 0 1408008816 10722576 4087216
21:23:02 70599 8192000000 10291242368 10754981888 1381613856 14.431 99 15 30 0 1400379136 14829680 3935600
21:25:22 53307 8192000000 10297970688 10760429568 1396519888 14.564 100 16 32 0 1411317440 11337056 3460496
21:27:26 32845 8192000000 10322755888 10775576576 1407483088 14.662 100 33 66 1 1430018128 14444784 8090256
21:29:02 58616 8192000000 10303534400 10759020544 1393732016 14.539 99 28 56 2 1414626032 13875408 7018608
21:31:06 32284 8192000000 10326598528 10785140736 1404296736 14.633 99 34 68 1 1428350656 16027648 8026272
21:33:02 73240 8192000000 10295663840 10771570688 1377784032 14.397 98 13 26 0 1399440624 18318480 3338112
21:35:18 72707 8192000000 10298409312 10754686976 1338963920 14.048 96 14 28 0 1354498048 12560864 2973264
21:37:39 117086 8192000000 10291878976 10736975872 1299669648 13.692 93 13 26 0 1311833120 9607008 2556464
21:41:35 43098 8192000000 10278501936 10740727808 1376846224 14.388 100 22 44 1 1392455712 11006768 4602720
21:43:19 64089 8192000000 10306255072 10748723200 1335395824 14.016 96 25 50 0 1360854384 20076016 5382544
21:45:28 29531 8192000000 10273447584 10716991488 1326856240 13.939 96 25 49 2 1340836368 8457840 5522288
21:47:02 112649 8192000000 10267087072 10730016768 1299559664 13.691 96 7 14 0 1318968416 18077504 1331248
21:49:56 58079 8192000000 10254986496 10700451840 1332491632 13.990 98 48 96 1 1350543520 6006640 12045248
21:51:55 85758 8192000000 10247489488 10704801792 1333658640 14.000 99 17 34 0 1349811712 12165728 3987344
21:54:22 85745 8192000000 10229088912 10675216384 1257360608 13.306 93 14 28 0 1271856576 11410016 3085952
21:56:52 52187 8192000000 10251986928 10706276352 1338291296 14.042 99 14 28 1 1354006240 12185152 3529792
21:58:45 80614 8192000000 10243191376 10694672384 1336741520 14.028 99 22 44 0 1363538800 21526960 5270320
22:01:11 51832 8192000000 10238259824 10692726784 1314024272 13.823 97 17 33 1 1328499264 9964608 4510384
22:03:03 72655 8192000000 10253832720 10710622208 1341924512 14.075 99 10 20 0 1352955968 8556160 2475296
22:05:19 53340 8192000000 10270238432 10723672064 1352309536 14.168 98 19 38 0 1372662656 15140208 5212912
22:07:22 44771 8192000000 10289170752 10732322816 1338961872 14.048 97 35 68 3 1367404064 20080272 8361920
22:09:31 72966 8192000000 10288353280 10740912128 1359638384 14.234 98 10 20 0 1378048832 16504048 1906400
22:11:50 89667 8192000000 10301793088 10764840960 1382098112 14.435 99 14 28 0 1397908944 13007856 2802976
22:14:20 101169 8192000000 10301540896 10760957952 1346226096 14.114 96 19 38 0 1361088864 11144192 3718576
22:17:04 50555 8192000000 10330469888 10784681984 1390873328 14.514 99 16 32 1 1415106672 20463296 3770048
22:19:07 97033 8192000000 10279896992 10733887488 1366595616 14.297 98 27 54 1 1386692048 13243072 6853360
22:21:51 40735 8192000000 10295853872 10755637248 1382337472 14.437 99 21 42 0 1394545808 7490752 4717584
22:23:34 42395 8192000000 10293450624 10764759040 1403654576 14.628 100 15 30 0 1416940816 9758176 3528064
22:25:17 65550 8192000000 10289699920 10752802816 1387514592 14.484 99 14 28 1 1398984096 7367584 4101920
22:27:24 87336 8192000000 10289043088 10750083072 1371433712 14.340 98 21 40 1 1388646496 12368000 4844784
22:30:04 65268 8192000000 10278127200 10748477440 1372924784 14.353 99 10 20 1 1390468528 15290320 2253424
22:32:10 43686 8192000000 10296177936 10756743168 1381503936 14.430 99 26 52 0 1396728144 9387216 5836992
22:33:54 43413 8192000000 10279716960 10724270080 1347310160 14.123 98 25 50 1 1363058528 10478016 5270352
22:35:39 98842 8192000000 10258576416 10720919552 1264079648 13.367 92 17 35 0 1278998080 11062624 3855808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-05-2022 22:39:17 to 02-05-2022 22:39:32.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-05-2022 22:39:33 to 02-05-2022 22:39:35.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235812
MON$OLDEST_ACTIVE               236774
MON$OLDEST_SNAPSHOT             236774
MON$NEXT_TRANSACTION            236774
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-03 16:41:55.3470
MON$PAGES                       2048480
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33583 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33583 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33583 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33583 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Mon May  2 22:38:13 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Mon May  2 22:38:14 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
--- end of diff output ---

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

02.05.22 22:39:36. There are 3 logs in /var/tmp/logs-oltp30 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-05-02 22:39:38 No severe PSQL-related problems occured
02.05.22 22:39:39. End of report.