Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2023 19:40
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 15392984 kB
MemAvailable 30437704 kB
Buffers 0 kB
Cached 16350292 kB
SwapCached 4476 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.11
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 2023-01-11 19:38:39
DTS_END                         2023-01-11 19:38:39.1710
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/57110
TRN_ID                          247990
ATT_ID                          673
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: 9317.18 17089 1118062
customer order: creation 180.88 333 21705
customer order: refuse 15.20 311 1824
order to supplier: creation 48.02 1188 5762
order to supplier: removal 3.48 84420 418
invoice (draft): creation 47.01 3122 5641
invoice (draft): removal 51.38 713 6165
invoice accept: apply 44.18 17387 5301
invoice accept: cancel 46.50 800 5580
customer reserve: creation 4028.73 176 483448
customer reserve: removal 4549.69 50 545963
realization accept: apply 88.14 156 10577
realization accept: cancel 79.30 102 9516
payment from customer: creation 62.58 108 7509
payment from customer: removal 5.66 69 679
payment to supplier: creation 50.29 120 6035
payment to supplier: removal 3.66 60 439
service: total inventory turnovers 5.03 1006 604
service: total monetary turnovers 6.46 27 775
service: aggregate perf. data 0.98 15970 117
service: refresh index statistics 0.03 215652 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2023 19:41:04 to 11-01-2023 19:41:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5352
TEST_TIME 2 3683
TEST_TIME 3 4613
TEST_TIME 4 5343
TEST_TIME 5 5080
TEST_TIME 6 4801
TEST_TIME 7 4809
TEST_TIME 8 4914
TEST_TIME 9 5339
TEST_TIME 10 5172
TEST_TIME 11 5473
TEST_TIME 12 5557
TEST_TIME 13 5423
TEST_TIME 14 5845
TEST_TIME 15 5912
TEST_TIME 16 6472
TEST_TIME 17 6945
TEST_TIME 18 7043
TEST_TIME 19 7394
TEST_TIME 20 7211
TEST_TIME 21 7348
TEST_TIME 22 7823
TEST_TIME 23 8123
TEST_TIME 24 8947
TEST_TIME 25 8876
TEST_TIME 26 9251
TEST_TIME 27 9409
TEST_TIME 28 9886
TEST_TIME 29 9843
TEST_TIME 30 9799
TEST_TIME 31 9705
TEST_TIME 32 9845
TEST_TIME 33 9984
TEST_TIME 34 10016
TEST_TIME 35 10006
TEST_TIME 36 10021
TEST_TIME 37 9943
TEST_TIME 38 9804
TEST_TIME 39 9768
TEST_TIME 40 9827
TEST_TIME 41 9783
TEST_TIME 42 9738
TEST_TIME 43 9635
TEST_TIME 44 9706
TEST_TIME 45 9822
TEST_TIME 46 9786
TEST_TIME 47 9805
TEST_TIME 48 9696
TEST_TIME 49 9693
TEST_TIME 50 9601
TEST_TIME 51 9545
TEST_TIME 52 9459
TEST_TIME 53 9384
TEST_TIME 54 9480
TEST_TIME 55 9575
TEST_TIME 56 9575
TEST_TIME 57 9593
TEST_TIME 58 9608
TEST_TIME 59 9609
TEST_TIME 60 9651
TEST_TIME 61 9676
TEST_TIME 62 9726
TEST_TIME 63 9744
TEST_TIME 64 9759
TEST_TIME 65 9706
TEST_TIME 66 9777
TEST_TIME 67 9848
TEST_TIME 68 9998
TEST_TIME 69 9972
TEST_TIME 70 9879
TEST_TIME 71 9800
TEST_TIME 72 9795
TEST_TIME 73 9844
TEST_TIME 74 9768
TEST_TIME 75 9744
TEST_TIME 76 9686
TEST_TIME 77 9620
TEST_TIME 78 9581
TEST_TIME 79 9591
TEST_TIME 80 9607
TEST_TIME 81 9592
TEST_TIME 82 9574
TEST_TIME 83 9588
TEST_TIME 84 9557
TEST_TIME 85 9576
TEST_TIME 86 9577
TEST_TIME 87 9651
TEST_TIME 88 9625
TEST_TIME 89 9639
TEST_TIME 90 9636
TEST_TIME 91 9698
TEST_TIME 92 9731
TEST_TIME 93 9714
TEST_TIME 94 9658
TEST_TIME 95 9593
TEST_TIME 96 9588
TEST_TIME 97 9553
TEST_TIME 98 9509
TEST_TIME 99 9470
TEST_TIME 100 9459
TEST_TIME 101 9423
TEST_TIME 102 9414
TEST_TIME 103 9435
TEST_TIME 104 9411
TEST_TIME 105 9474
TEST_TIME 106 9447
TEST_TIME 107 9420
TEST_TIME 108 9404
TEST_TIME 109 9494
TEST_TIME 110 9525
TEST_TIME 111 9519
TEST_TIME 112 9518
TEST_TIME 113 9510
TEST_TIME 114 9545
TEST_TIME 115 9554
TEST_TIME 116 9486
TEST_TIME 117 9447
TEST_TIME 118 9414
TEST_TIME 119 9363
TEST_TIME 120 9320

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2023 19:41:08 to 11-01-2023 19:41:11.

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 1566516 1566516 0 0.00 0 21138 6 0 0
doc_list_biud 1566516 1566516 0 0.00 0 37571 23 0 0
sp_add_invoice_to_stock 5431 5301 130 2.39 33 186938 17388 0 130
sp_cancel_adding_invoice 5580 5580 0 0.00 4 66338 800 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 8858 312 0 0
sp_cancel_customer_reserve 545963 545963 0 0.00 1 37572 51 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 4419 70 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 2 1743 61 0 0
sp_cancel_supplier_invoice 6177 6165 12 0.19 2 17533 714 0 12
sp_cancel_supplier_order 418 418 0 0.00 7 1084809 84421 0 0
sp_cancel_write_off 9516 9516 0 0.00 1 15157 102 0 0
sp_client_order 21705 21705 0 0.00 3 17391 334 0 0
sp_customer_reserve 483448 483448 0 0.00 0 83377 176 0 0
sp_fill_shopping_cart 33096 33096 0 0.00 1 14112 32 0 0
sp_fill_shopping_cart_clo_res 483441 483441 0 0.00 0 25373 15 0 0
sp_get_clo_for_invoice 5294 5294 0 0.00 0 44221 273 0 0
sp_kill_cost_storno 16214 16214 0 0.00 0 12429 49 0 0
sp_kill_qstorno_ret_qs2qd 1108025 1108025 0 0.00 0 27977 28 0 0
sp_kill_qty_storno 585337 585337 0 0.00 0 37571 61 0 0
sp_lock_selected_doc 21868 21868 0 0.00 0 10825 5 0 0
sp_make_cost_storno 29415 29415 0 0.00 0 13882 49 0 0
sp_multiply_rows_for_pdistr 29415 29415 0 0.00 0 9817 24 0 0
sp_multiply_rows_for_qdistr 21705 21705 0 0.00 0 16211 140 0 0
sp_pay_from_customer 7509 7509 0 0.00 4 13536 108 0 0
sp_pay_to_supplier 6035 6035 0 0.00 4 8228 121 0 0
sp_payment_common 13544 13544 0 0.00 1 13530 31 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 7324 278 0 0
sp_qd_handle_on_invoice_upd_sts 10874 10874 0 0.00 0 20266 313 0 0
sp_qd_handle_on_reserve_upd_sts 10577 10577 0 0.00 0 13323 59 0 0
sp_reserve_write_off 10577 10577 0 0.00 6 13334 157 0 0
sp_supplier_invoice 5641 5641 0 0.00 6 89645 3123 0 0
sp_supplier_order 5762 5762 0 0.00 5 18967 1189 0 0
srv_aggregate_perf_data 117 117 0 0.00 2180 180627 15971 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 723 105045 42367 0 0
srv_find_qd_qs_mism 1068604 1068604 0 0.00 0 21089 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 41 78905 1006 0 0
srv_make_money_saldo 775 775 0 0.00 1 3619 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 100956 309104 215652 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5301 5301 0 0.00 0 11929 124 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 4001 69 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 2557 13 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 1723 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 2867 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 2538 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 1012 15 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 15009 1268 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 2571 10 0 0
t$perf-norm:sp_client_order 21705 21705 0 0.00 0 11587 13 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 4388 8 0 0
t$perf-norm:sp_pay_from_customer 7509 7509 0 0.00 0 4160 8 0 0
t$perf-norm:sp_pay_to_supplier 6035 6035 0 0.00 0 7880 10 0 0
t$perf-norm:sp_reserve_write_off 10577 10577 0 0.00 0 4248 8 0 0
t$perf-norm:sp_supplier_invoice 5641 5641 0 0.00 0 13102 19 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 5597 11 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 334 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 274 5 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 945 16 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 1035 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10842 10582 260 2.39 0 8395 17 0 260
v_all_customers 39708 39708 0 0.00 0 98 0 0 0
v_all_suppliers 31238 31238 0 0.00 0 22 1 0 0
v_all_wares 43410 43410 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 236 5 0 0
v_cancel_client_order 3648 3648 0 0.00 0 2254 4 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 3059 7 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 2497 5 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 862 5 0 0
v_cancel_supplier_invoice 896 872 24 2.67 0 2235 22 0 24
v_cancel_supplier_order 836 836 0 0.00 0 1199 6 0 0
v_cancel_write_off 2050 2050 0 0.00 0 1927 17 0 0
v_max_id_clo_ord 5761 5761 0 0.00 0 2172 2 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 2419 16 0 0
v_max_id_ord_sup 5630 5630 0 0.00 0 3953 5 0 0
v_max_non_paid_invoice 1807 1807 0 0.00 0 479 2 0 0
v_max_non_paid_realizn 3955 3955 0 0.00 0 1242 3 0 0
v_min_id_clo_ord 5761 5761 0 0.00 0 4772 10 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 3217 5 0 0
v_min_id_ord_sup 5630 5630 0 0.00 0 6498 6 0 0
v_min_non_paid_invoice 6035 6035 0 0.00 0 6119 22 0 0
v_min_non_paid_realizn 7509 7509 0 0.00 0 13525 27 0 0
v_our_firm 10810 10810 0 0.00 0 13 1 0 0
v_reserve_write_off 21154 21154 0 0.00 0 3950 4 0 0
x_lock_depdocs_on_canc_invoice 11309 11309 0 0.00 0 14535 102 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 4615 308 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 1 18933 1120 0 0
x_make_qstorno_1200_2000 5641 5641 0 0.00 2 89608 3008 0 0
x_make_qstorno_2100_3300 483448 483448 0 0.00 0 83375 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2023 19:41:12 to 11-01-2023 19:41:16.

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
17:08:43 11 8192000000 8811242256 8826630144 15641792 0.190 4 4 5 0 15868528 87456 139280
17:09:44 24841 8192000000 9902063632 10210332672 982191984 10.706 100 86 172 0 1010816448 6068336 22556128
17:11:12 13362 8192000000 9945431408 10267197440 1028764736 11.157 100 90 180 0 1060669632 8273760 23631136
17:12:41 17197 8192000000 9984836464 10317078528 1065366304 11.508 100 86 172 0 1097151936 10413008 21372624
17:14:02 8839 8192000000 10023928512 10367635456 1106578384 11.900 100 84 168 1 1138741568 12006752 20156432
17:15:15 5162 8192000000 10072309600 10433433600 1164715872 12.447 100 79 158 0 1195064256 11802080 18546304
17:16:28 3809 8192000000 10128288912 10496905216 1220757456 12.969 100 79 156 0 1251319504 12353088 18208960
17:17:33 3083 8192000000 10156112736 10533687296 1244012080 13.183 100 71 140 2 1272494512 12949632 15532800
17:18:39 1965 8192000000 10191409040 10575171584 1283199984 13.542 100 83 166 0 1313030416 11889456 17940976
17:19:44 1161 8192000000 10214094992 10597785600 1295966464 13.659 100 86 172 1 1327306528 12055328 19284736
17:20:48 12908 8192000000 10207770816 10605002752 1310163440 13.788 100 58 116 1 1330001504 5969472 13868592
17:22:02 5009 8192000000 10199659296 10608680960 1316725856 13.847 100 30 61 2 1330603424 6812592 7064976
17:23:07 9717 8192000000 10198864496 10617511936 1319181472 13.869 100 13 27 1 1328138576 5887984 3069120
17:24:19 11334 8192000000 10211802784 10631434240 1327966224 13.949 100 15 31 1 1336600976 5335200 3299552
17:25:32 10931 8192000000 10223119296 10641981440 1340596256 14.063 100 26 52 0 1352911680 6260912 6054512
17:26:44 4687 8192000000 10231239504 10637504512 1329039536 13.958 99 57 117 0 1349037168 7008528 12989104
17:27:49 3768 8192000000 10240533392 10657689600 1344265360 14.096 100 28 56 0 1357714240 6820032 6628848
17:28:54 6513 8192000000 10226808944 10645348352 1323456896 13.908 99 26 52 0 1336269328 6388160 6424272
17:30:02 14296 8192000000 10172456304 10578513920 1207724912 12.848 92 24 47 2 1218251024 4449824 6076288
17:31:23 12179 8192000000 10133015408 10540658688 1188730208 12.672 94 21 42 1 1199348288 6153136 4464944
17:32:35 6704 8192000000 10134191920 10535219200 1234800464 13.098 99 15 28 0 1243191040 5250336 3140240
17:33:45 8517 8192000000 10142412736 10538115072 1223128512 12.991 98 23 47 0 1233101632 4700800 5272320
17:34:56 72703 8192000000 10179735312 10585915392 1287836160 13.585 100 35 70 1 1301777504 5306192 8635152
17:37:10 7753 8192000000 10183199712 10594246656 1291064352 13.614 100 21 42 0 1301839616 5314032 5461232
17:38:18 11786 8192000000 10208545632 10626854912 1322856272 13.903 100 18 36 0 1331400096 4914288 3629536
17:39:38 4862 8192000000 10247429200 10656206848 1340247200 14.060 100 62 128 1 1363478192 7905552 15325440
17:40:44 61323 8192000000 10250284368 10674245632 1349049904 14.139 100 40 77 0 1367295568 8818896 9426768
17:42:54 15249 8192000000 10261734016 10683965440 1355018192 14.193 99 43 87 0 1373163760 8476208 9669360
17:44:14 12533 8192000000 10285258432 10703028224 1376026016 14.381 100 47 94 1 1399314720 11961872 11326832
17:45:32 7468 8192000000 10305774304 10725220352 1391179392 14.516 100 40 80 2 1415775952 15041504 9555056
17:46:43 6302 8192000000 10329103408 10749276160 1404229584 14.633 100 52 104 1 1437774000 20720736 12823680
17:47:56 11353 8192000000 10324274064 10754007040 1406960960 14.657 100 39 78 0 1437022256 20472128 9589168
17:49:13 8944 8192000000 10332546784 10764959744 1412681648 14.708 100 46 92 0 1448681760 25022256 10977856
17:50:33 6056 8192000000 10330807360 10749571072 1400143632 14.596 100 55 103 0 1435918032 23375200 12399200
17:51:44 2616 8192000000 10343164672 10763628544 1407507632 14.662 100 60 119 0 1451838496 30140848 14190016
17:52:52 7443 8192000000 10330518064 10760552448 1401621440 14.609 100 44 87 0 1444848032 31567392 11659200
17:54:10 2428 8192000000 10347493680 10774220800 1408711264 14.672 100 64 127 0 1456500432 32254416 15534752
17:55:19 836 8192000000 10356567664 10775093248 1412821040 14.709 100 79 157 0 1465313776 32801376 19691360
17:56:25 4734 8192000000 10334693712 10757861376 1393671168 14.539 100 52 102 0 1439909264 33156560 13081536
17:57:39 14926 8192000000 10313589520 10749800448 1378144336 14.400 99 34 68 1 1426830528 39236464 9449728
17:59:12 722 8192000000 10337402032 10739408896 1373388784 14.357 100 86 167 2 1437890480 43639184 20862512
18:00:15 5638 8192000000 10348910048 10760937472 1384463904 14.456 100 63 126 0 1447618064 46262512 16891648
18:01:22 10697 8192000000 10329230192 10754330624 1362092272 14.256 99 35 70 0 1424346848 52025504 10229072
18:02:37 14461 8192000000 10281688288 10708713472 1317089376 13.850 96 24 49 0 1359736912 36678032 5969504
18:03:54 9288 8192000000 10290217152 10720088064 1362643520 14.261 100 44 89 0 1404401888 31236080 10522288
18:05:05 21325 8192000000 10257024768 10691899392 1302024880 13.714 97 12 22 0 1335923872 30701680 3197312
18:06:27 26152 8192000000 10250318976 10686210048 1324019840 13.913 99 17 34 0 1340936416 12835280 4081296
18:07:55 33286 8192000000 10252100640 10679988224 1304627664 13.737 97 21 44 0 1321006016 11953792 4424560
18:09:30 39595 8192000000 10275610304 10706862080 1332418928 13.989 98 37 73 0 1356082864 15725376 7938560
18:11:11 94746 8192000000 10275760256 10710298624 1317550816 13.855 96 17 34 0 1332608464 11187232 3870416
18:15:00 41336 8192000000 10291576160 10738077696 1384115200 14.453 100 36 73 1 1405336400 12792880 8428320
18:16:46 57534 8192000000 10292921600 10743087104 1381285056 14.428 99 29 57 0 1396565136 9453360 5826720
18:19:00 69941 8192000000 10304087456 10766594048 1397219824 14.570 100 10 20 0 1416742640 17060848 2461968
18:21:11 13904 8192000000 10327774592 10761834496 1402408960 14.616 100 58 113 0 1426333792 10064032 13860800
18:22:29 15643 8192000000 10316430592 10759593984 1389452016 14.501 99 46 91 0 1414615328 14247472 10915840
18:23:51 40105 8192000000 10288269200 10744737792 1381349392 14.429 99 17 35 0 1395973872 11006736 3617744
18:25:32 42407 8192000000 10289161504 10754203648 1392187632 14.525 100 5 10 0 1404004192 10767232 1049328
18:27:14 42650 8192000000 10288626512 10757644288 1400893056 14.603 100 7 14 0 1410858784 7919920 2045808
18:28:57 72036 8192000000 10291727008 10761678848 1389691648 14.503 99 17 34 0 1401434240 8372208 3370384
18:31:09 78945 8192000000 10302257264 10775969792 1383231648 14.445 98 18 35 0 1404579760 17356704 3991408
18:33:33 44007 8192000000 10296440416 10758250496 1389238688 14.499 99 12 24 0 1407106416 14838448 3029280
18:35:24 26544 8192000000 10287463744 10737770496 1370737520 14.334 98 22 44 0 1385106000 9119696 5248784
18:36:54 36644 8192000000 10307293824 10757214208 1395423184 14.554 100 27 54 0 1410558608 9442432 5692992
18:38:35 55937 8192000000 10282631040 10735161344 1328803360 13.956 96 25 50 0 1348599696 14782128 5014208
18:40:48 77278 8192000000 10269757616 10715271168 1284218736 13.552 93 14 28 1 1296495360 9322224 2954400
18:43:15 26434 8192000000 10282992848 10726514688 1342763744 14.082 98 26 52 0 1366009616 17425648 5820224
18:44:45 44148 8192000000 10280379232 10719973376 1322598336 13.900 96 25 51 0 1349333968 20795440 5940192
18:46:37 91368 8192000000 10257321952 10709221376 1285193776 13.560 95 18 36 0 1310224336 21666768 3363792
18:49:11 68138 8192000000 10269615760 10713022464 1336418304 14.025 98 32 63 0 1352525664 9017840 7089520
18:51:56 20113 8192000000 10274894512 10706169856 1351680560 14.163 99 54 106 5 1369701872 5457968 12563344
18:53:20 55696 8192000000 10220816688 10672660480 1261842448 13.347 93 16 32 0 1275191584 9919536 3429600
18:55:16 99354 8192000000 10246398880 10702688256 1311982464 13.804 97 20 41 0 1337188000 20691568 4513968
18:59:01 50384 8192000000 10239903424 10699096064 1328439616 13.953 98 23 45 1 1344084720 10519200 5125904
19:00:53 96476 8192000000 10238560848 10692210688 1276082080 13.477 94 12 24 0 1287607504 9259744 2265680
19:03:30 25757 8192000000 10265359488 10706137088 1342769136 14.082 99 33 63 1 1358984752 9627360 6588256
19:04:57 105044 8192000000 10275413760 10727784448 1338434832 14.043 98 11 22 0 1355532192 14771136 2326224
19:07:43 49462 8192000000 10278191952 10722889728 1338058224 14.040 97 33 66 0 1355492992 10578448 6856320
19:09:35 100786 8192000000 10291229552 10749059072 1353321920 14.177 97 17 34 0 1369664640 12798112 3544608
19:12:20 12214 8192000000 10321799040 10750693376 1392974160 14.532 100 44 86 0 1408796144 5845280 9976704
19:13:35 64205 8192000000 10294061920 10739134464 1385677760 14.467 99 52 102 0 1406355056 9464784 11212512
19:16:11 77579 8192000000 10284532784 10749005824 1391852560 14.522 100 14 28 1 1405889488 10645808 3391120
19:18:29 54911 8192000000 10283127728 10738528256 1345816912 14.110 96 15 30 0 1357823792 7882544 4124336
19:20:42 59705 8192000000 10269340832 10729529344 1353623552 14.180 98 7 14 0 1367761712 12771504 1366656
19:22:42 74176 8192000000 10269938448 10739630080 1346865008 14.119 97 9 18 0 1360434688 11011936 2557744
19:25:02 36729 8192000000 10295384208 10739011584 1346144640 14.113 97 34 66 0 1363028768 9394256 7489872
19:26:42 78535 8192000000 10279805600 10740658176 1345706064 14.109 97 15 30 1 1362381872 13108928 3566880
19:29:01 73512 8192000000 10272740000 10726883328 1321960224 13.894 96 15 30 0 1340579632 15591328 3028080
19:31:20 63556 8192000000 10290840400 10741182464 1338170320 14.041 97 32 64 0 1360911840 15868624 6872896
19:33:24 85154 8192000000 10266610416 10723250176 1348707088 14.136 98 12 24 0 1362146128 10942720 2496320
19:36:09 102459 8192000000 10250232096 10710978560 1326582176 13.936 97 11 22 0 1340247216 11042848 2622192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-01-2023 19:41:18 to 11-01-2023 19:41:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2023 19:41:34 to 11-01-2023 19:41:36.

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          245809
MON$OLDEST_ACTIVE               248104
MON$OLDEST_SNAPSHOT             248104
MON$NEXT_TRANSACTION            248104
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                       2025696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Wed Jan 11 19:39:00 2023
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Jan 11 19:39:01 2023
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Jan 11 19:39:02 2023
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jan 11 19:39:02 2023
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Jan 11 19:39:02 2023
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Jan 11 19:39:03 2023
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

11.01.23 19:41:38. 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
2023-01-11 19:41:40 No severe PSQL-related problems occured
11.01.23 19:41:41. End of report.