Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2022 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 15523956 kB
MemAvailable 30599440 kB
Buffers 0 kB
Cached 16220096 kB
SwapCached 2080 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 2022-10-01 19:38:32
DTS_END                         2022-10-01 19:38:32.7870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40376
TRN_ID                          245139
ATT_ID                          685
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: 9285.99 18619 1114319
customer order: creation 177.58 347 21309
customer order: refuse 15.33 360 1839
order to supplier: creation 46.37 1183 5564
order to supplier: removal 3.56 103977 427
invoice (draft): creation 47.15 3132 5658
invoice (draft): removal 53.21 760 6385
invoice accept: apply 42.56 17044 5107
invoice accept: cancel 47.93 702 5752
customer reserve: creation 3858.70 172 463044
customer reserve: removal 4695.79 62 563495
realization accept: apply 85.90 182 10308
realization accept: cancel 80.56 117 9667
payment from customer: creation 59.96 141 7195
payment from customer: removal 6.00 127 720
payment to supplier: creation 50.43 158 6052
payment to supplier: removal 3.58 82 430
service: total inventory turnovers 4.53 1047 543
service: total monetary turnovers 6.12 28 734
service: aggregate perf. data 0.72 25896 86
service: refresh index statistics 0.03 216857 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2022 19:40:33 to 01-10-2022 19:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2299
TEST_TIME 2 1929
TEST_TIME 3 2037
TEST_TIME 4 3596
TEST_TIME 5 4636
TEST_TIME 6 5166
TEST_TIME 7 5311
TEST_TIME 8 5201
TEST_TIME 9 5136
TEST_TIME 10 5768
TEST_TIME 11 5775
TEST_TIME 12 6259
TEST_TIME 13 6201
TEST_TIME 14 6791
TEST_TIME 15 6634
TEST_TIME 16 6653
TEST_TIME 17 6752
TEST_TIME 18 7143
TEST_TIME 19 7165
TEST_TIME 20 7412
TEST_TIME 21 7614
TEST_TIME 22 8077
TEST_TIME 23 8276
TEST_TIME 24 8271
TEST_TIME 25 8794
TEST_TIME 26 8735
TEST_TIME 27 9020
TEST_TIME 28 10276
TEST_TIME 29 10591
TEST_TIME 30 10426
TEST_TIME 31 10571
TEST_TIME 32 10546
TEST_TIME 33 10308
TEST_TIME 34 10057
TEST_TIME 35 9989
TEST_TIME 36 9953
TEST_TIME 37 9957
TEST_TIME 38 10021
TEST_TIME 39 9939
TEST_TIME 40 9895
TEST_TIME 41 9825
TEST_TIME 42 9837
TEST_TIME 43 10092
TEST_TIME 44 9948
TEST_TIME 45 9840
TEST_TIME 46 9913
TEST_TIME 47 9803
TEST_TIME 48 9680
TEST_TIME 49 9679
TEST_TIME 50 9577
TEST_TIME 51 9588
TEST_TIME 52 9517
TEST_TIME 53 9467
TEST_TIME 54 9437
TEST_TIME 55 9388
TEST_TIME 56 9439
TEST_TIME 57 9400
TEST_TIME 58 9444
TEST_TIME 59 9435
TEST_TIME 60 9506
TEST_TIME 61 9567
TEST_TIME 62 9573
TEST_TIME 63 9562
TEST_TIME 64 9665
TEST_TIME 65 9737
TEST_TIME 66 9745
TEST_TIME 67 9715
TEST_TIME 68 9727
TEST_TIME 69 9745
TEST_TIME 70 9701
TEST_TIME 71 9635
TEST_TIME 72 9627
TEST_TIME 73 9547
TEST_TIME 74 9592
TEST_TIME 75 9533
TEST_TIME 76 9504
TEST_TIME 77 9463
TEST_TIME 78 9431
TEST_TIME 79 9413
TEST_TIME 80 9421
TEST_TIME 81 9416
TEST_TIME 82 9436
TEST_TIME 83 9413
TEST_TIME 84 9453
TEST_TIME 85 9518
TEST_TIME 86 9565
TEST_TIME 87 9554
TEST_TIME 88 9553
TEST_TIME 89 9593
TEST_TIME 90 9633
TEST_TIME 91 9648
TEST_TIME 92 9682
TEST_TIME 93 9593
TEST_TIME 94 9518
TEST_TIME 95 9541
TEST_TIME 96 9476
TEST_TIME 97 9456
TEST_TIME 98 9422
TEST_TIME 99 9408
TEST_TIME 100 9419
TEST_TIME 101 9389
TEST_TIME 102 9489
TEST_TIME 103 9489
TEST_TIME 104 9468
TEST_TIME 105 9503
TEST_TIME 106 9530
TEST_TIME 107 9553
TEST_TIME 108 9543
TEST_TIME 109 9540
TEST_TIME 110 9549
TEST_TIME 111 9586
TEST_TIME 112 9556
TEST_TIME 113 9497
TEST_TIME 114 9444
TEST_TIME 115 9436
TEST_TIME 116 9421
TEST_TIME 117 9381
TEST_TIME 118 9349
TEST_TIME 119 9356
TEST_TIME 120 9288

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-10-2022 19:40:36 to 01-10-2022 19:40:40.

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 1545291 1545291 0 0.00 0 28085 6 0 0
doc_list_biud 1545290 1545290 0 0.00 0 22553 28 0 0
sp_add_invoice_to_stock 5231 5107 124 2.37 17 183222 17045 0 124
sp_cancel_adding_invoice 5752 5752 0 0.00 3 87972 702 0 0
sp_cancel_client_order 1839 1839 0 0.00 3 12532 360 0 0
sp_cancel_customer_reserve 563495 563495 0 0.00 0 21103 62 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 3 23695 127 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 3451 82 0 0
sp_cancel_supplier_invoice 6395 6385 10 0.15 2 20108 761 0 10
sp_cancel_supplier_order 427 427 0 0.00 6 1016176 103977 0 0
sp_cancel_write_off 9667 9667 0 0.00 1 23695 117 0 0
sp_client_order 21309 21309 0 0.00 3 17929 348 0 0
sp_customer_reserve 463044 463044 0 0.00 0 94739 172 0 0
sp_fill_shopping_cart 32519 32519 0 0.00 1 12591 36 0 0
sp_fill_shopping_cart_clo_res 463039 463039 0 0.00 0 14957 15 0 0
sp_get_clo_for_invoice 5101 5101 0 0.00 0 27618 338 0 0
sp_kill_cost_storno 16569 16569 0 0.00 0 23682 61 0 0
sp_kill_qstorno_ret_qs2qd 1143469 1143469 0 0.00 0 21100 33 0 0
sp_kill_qty_storno 602972 602972 0 0.00 0 22552 71 0 0
sp_lock_selected_doc 21367 21367 0 0.00 0 11677 7 0 0
sp_make_cost_storno 28654 28654 0 0.00 0 28067 62 0 0
sp_multiply_rows_for_pdistr 28654 28654 0 0.00 0 27373 34 0 0
sp_multiply_rows_for_qdistr 21309 21309 0 0.00 0 17860 165 0 0
sp_pay_from_customer 7195 7195 0 0.00 3 28976 141 0 0
sp_pay_to_supplier 6052 6052 0 0.00 4 28976 159 0 0
sp_payment_common 13247 13247 0 0.00 1 23664 33 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 12526 335 0 0
sp_qd_handle_on_invoice_upd_sts 10851 10851 0 0.00 0 22548 291 0 0
sp_qd_handle_on_reserve_upd_sts 10308 10308 0 0.00 0 11075 56 0 0
sp_reserve_write_off 10308 10308 0 0.00 6 26920 183 0 0
sp_supplier_invoice 5658 5658 0 0.00 6 77861 3133 0 0
sp_supplier_order 5564 5564 0 0.00 5 22639 1183 0 0
srv_aggregate_perf_data 86 86 0 0.00 2606 430206 25896 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2062 152340 43618 0 0
srv_find_qd_qs_mism 1066939 1066939 0 0.00 0 12532 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 35 37162 1048 0 0
srv_make_money_saldo 734 734 0 0.00 1 4396 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 160677 279125 216858 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 0 10532 184 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 5201 89 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 3236 13 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 5712 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 3069 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3365 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2150 21 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 21244 1713 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 4198 17 0 0
t$perf-norm:sp_client_order 21309 21309 0 0.00 0 5721 16 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 2946 7 0 0
t$perf-norm:sp_pay_from_customer 7195 7195 0 0.00 0 24456 16 0 0
t$perf-norm:sp_pay_to_supplier 6052 6052 0 0.00 0 20836 15 0 0
t$perf-norm:sp_reserve_write_off 10308 10308 0 0.00 0 17245 13 0 0
t$perf-norm:sp_supplier_invoice 5658 5658 0 0.00 0 3722 20 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 4895 18 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 2012 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 90 3 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 1211 28 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 1677 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10446 10198 248 2.37 0 5708 18 0 248
v_all_customers 38862 38862 0 0.00 0 104 1 0 0
v_all_suppliers 31236 31236 0 0.00 0 104 1 0 0
v_all_wares 42618 42618 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 4216 20 0 0
v_cancel_client_order 3678 3678 0 0.00 0 3322 7 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 5922 16 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 3799 11 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 1560 7 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 3830 24 0 20
v_cancel_supplier_order 854 854 0 0.00 0 4201 9 0 0
v_cancel_write_off 2038 2038 0 0.00 0 4020 21 0 0
v_max_id_clo_ord 5560 5560 0 0.00 0 1665 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 5222 13 0 0
v_max_id_ord_sup 5649 5649 0 0.00 0 3561 5 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 1085 3 0 0
v_max_non_paid_realizn 3789 3789 0 0.00 0 2762 6 0 0
v_min_id_clo_ord 5560 5560 0 0.00 0 2389 6 0 0
v_min_id_clo_res 985 985 0 0.00 0 1240 5 0 0
v_min_id_ord_sup 5649 5649 0 0.00 0 5397 8 0 0
v_min_non_paid_invoice 6052 6052 0 0.00 0 23612 24 0 0
v_min_non_paid_realizn 7195 7195 0 0.00 0 23658 28 0 0
v_our_firm 10568 10568 0 0.00 0 31 1 0 0
v_reserve_write_off 20615 20615 0 0.00 0 4460 7 0 0
x_lock_depdocs_on_canc_invoice 11734 11734 0 0.00 0 10124 121 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 5941 328 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 1 22337 1112 0 0
x_make_qstorno_1200_2000 5658 5658 0 0.00 2 77857 2998 0 0
x_make_qstorno_2100_3300 463044 463044 0 0.00 0 94738 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2022 19:40:41 to 01-10-2022 19:40:45.

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:36 3 8192000000 8800663440 8812400640 6716512 0.081 2 2 2 0 6820816 61520 42784
17:09:37 31938 8192000000 9913069248 10225831936 989439472 10.776 100 84 168 0 1017489728 6269664 21780592
17:11:15 17337 8192000000 9962148192 10286907392 1036810288 11.234 100 86 172 0 1067637792 9198512 21628992
17:12:42 15248 8192000000 9986724496 10322837504 1070089616 11.553 100 85 172 0 1102089264 11043536 20956112
17:14:12 6997 8192000000 10048640480 10397474816 1133392000 12.153 100 81 161 1 1163969072 11923552 18653520
17:15:26 4129 8192000000 10101371792 10461261824 1188455760 12.669 100 87 173 0 1219895360 12117680 19321920
17:16:37 2507 8192000000 10146891696 10515681280 1230229376 13.056 100 87 173 0 1267048768 17628496 19190896
17:17:43 2291 8192000000 10164006496 10540138496 1254517536 13.280 100 73 142 1 1282826544 12475024 15833984
17:18:48 1322 8192000000 10203566784 10586017792 1285570320 13.564 100 81 162 0 1316215344 13077824 17567200
17:19:51 2137 8192000000 10219376560 10605006848 1303266384 13.725 100 88 177 1 1336882656 13900736 19715536
17:20:55 20692 8192000000 10210262320 10610737152 1305405488 13.744 100 47 92 0 1323729024 6724656 11598880
17:22:17 3732 8192000000 10207132864 10615140352 1312800016 13.811 100 30 60 0 1326836176 7776288 6259872
17:23:21 11486 8192000000 10207252672 10621120512 1317324064 13.852 100 22 42 1 1328693808 6681408 4688336
17:24:36 76875 8192000000 10220467088 10637590528 1325028656 13.922 100 29 57 0 1336224000 5324560 5870784
17:26:55 4805 8192000000 10214895056 10638462976 1328346656 13.952 100 18 37 1 1337873552 5477712 4049184
17:28:01 6800 8192000000 10223339776 10641223680 1315635728 13.837 99 31 64 0 1333804736 10195920 7973088
17:29:08 28611 8192000000 10166772320 10581790720 1226898272 13.025 94 25 50 0 1237776496 5412400 5465824
17:31:04 4441 8192000000 10163486720 10561867776 1264217264 13.369 100 31 61 0 1277057200 5897248 6942688
17:32:09 5624 8192000000 10137023744 10536296448 1237233568 13.121 99 25 50 0 1248999408 6247472 5518368
17:33:18 7515 8192000000 10152819232 10549481472 1240941328 13.155 98 23 47 0 1251389712 5247552 5200832
17:34:26 35189 8192000000 10188185104 10586034176 1284083888 13.550 100 54 106 1 1302517200 6415840 12017472
17:36:02 6696 8192000000 10184798416 10595659776 1295642224 13.656 100 31 62 0 1308880880 6017552 7221104
17:37:11 11755 8192000000 10202798144 10613260288 1306216176 13.752 100 26 52 0 1318130320 6032192 5881952
17:38:28 15805 8192000000 10224661232 10637262848 1328460352 13.953 100 23 46 1 1339091632 5380256 5251024
17:40:09 97540 8192000000 10261361936 10681290752 1355626176 14.198 100 51 102 0 1378363168 10962208 11774784
17:43:13 6604 8192000000 10284809136 10702000128 1370354480 14.330 100 42 82 0 1392985840 12744256 9887104
17:44:23 6978 8192000000 10300801792 10717532160 1382229920 14.436 100 62 121 1 1409772912 13465664 14077328
17:45:40 4735 8192000000 10303034064 10730840064 1395426176 14.554 100 42 84 1 1418014560 13211056 9377328
17:46:49 14560 8192000000 10309122240 10730618880 1391618288 14.520 100 42 84 1 1421583264 20084448 9880528
17:48:13 13238 8192000000 10295983248 10721226752 1360624256 14.243 97 59 116 0 1398001776 23256080 14121440
17:49:34 2399 8192000000 10325525296 10742484992 1399911808 14.594 100 66 129 1 1435754736 21328896 14514032
17:50:43 6263 8192000000 10317181744 10740695040 1396272368 14.562 100 52 104 1 1431543936 22662336 12609232
17:51:58 4378 8192000000 10335091936 10756472832 1404603856 14.636 100 60 117 0 1446055904 27031888 14420160
17:53:15 3952 8192000000 10319463200 10731003904 1384946368 14.461 98 67 128 1 1423887344 22594704 16346272
17:54:25 3123 8192000000 10334003824 10742128640 1389822624 14.504 100 77 154 0 1438394864 29720096 18852144
17:55:33 2338 8192000000 10340226112 10738200576 1372381216 14.348 99 83 162 2 1429927696 36678976 20867504
17:56:40 3524 8192000000 10346345360 10764046336 1397472800 14.572 100 49 93 1 1454321600 44456592 12392208
17:57:47 2060 8192000000 10358107888 10761510912 1390021696 14.506 99 79 157 2 1459713152 49605168 20086288
17:58:58 2503 8192000000 10366804896 10774724608 1394891984 14.549 99 70 138 0 1464648160 51329840 18426336
18:00:06 11054 8192000000 10374175520 10791927808 1405628528 14.645 100 53 104 1 1476349008 55871936 14848544
18:01:29 5460 8192000000 10384221280 10805895168 1416415904 14.741 100 73 142 2 1486500960 50843056 19242000
18:02:38 4040 8192000000 10355256848 10783207424 1400289920 14.598 100 36 70 0 1462284592 51659504 10335168
18:03:51 21137 8192000000 10337313184 10770731008 1394160144 14.543 99 27 54 0 1445485152 43835728 7489280
18:05:13 27150 8192000000 10322302032 10766028800 1375783024 14.379 100 21 42 1 1439856576 57500912 6572640
18:07:09 41695 8192000000 10253016080 10699509760 1308274800 13.770 97 10 20 0 1330956032 19757728 2923504
18:09:47 64731 8192000000 10276218464 10722562048 1347985216 14.129 98 10 20 0 1362536560 12584256 1967088
18:11:51 51673 8192000000 10292391776 10729304064 1363870688 14.272 99 51 99 2 1380864944 6161920 10832336
18:14:18 78905 8192000000 10292327504 10733441024 1344516912 14.098 97 21 42 0 1364284176 15051216 4716048
18:16:38 58560 8192000000 10281022480 10731503616 1353576560 14.180 98 11 22 0 1368529760 12123312 2829888
18:18:37 31161 8192000000 10307570048 10760704000 1395285952 14.553 100 29 58 0 1415379440 13134816 6958672
18:20:09 19864 8192000000 10332205680 10770124800 1404791520 14.638 100 53 107 0 1434997488 17568976 12636992
18:21:39 56972 8192000000 10311462032 10770280448 1380495056 14.421 99 13 26 0 1400629504 17437936 2696512
18:23:39 44713 8192000000 10289380016 10749022208 1367903008 14.308 98 9 18 0 1380276752 10227328 2146416
18:25:27 104936 8192000000 10295019488 10758578176 1378493952 14.403 99 16 32 1 1396624304 14010416 4119936
18:28:29 63336 8192000000 10300545824 10750971904 1376644816 14.387 99 28 56 1 1394324944 11308400 6371728
18:30:42 103809 8192000000 10294468256 10766921728 1365418624 14.286 98 11 22 0 1385040848 17191760 2430464
18:34:35 20428 8192000000 10290477216 10751361024 1364986752 14.282 98 19 36 0 1378610240 9433216 4190272
18:35:57 27163 8192000000 10280592288 10740178944 1358556256 14.224 98 21 42 0 1371874656 9109408 4208992
18:37:24 10818 8192000000 10294641776 10729336832 1365507760 14.287 99 63 124 0 1391395520 11551744 14336016
18:38:37 55961 8192000000 10282832384 10738884608 1349190064 14.140 98 21 40 1 1366402448 13302208 3910176
18:40:36 97784 8192000000 10263806672 10718707712 1299343968 13.689 95 11 22 0 1314936832 12910336 2682528
18:43:14 39149 8192000000 10271501568 10718715904 1346061408 14.112 99 11 22 0 1360032816 11673280 2298128
18:44:54 36220 8192000000 10259996080 10699419648 1337163648 14.032 99 15 30 0 1347969024 7754544 3050832
18:46:31 35917 8192000000 10273698576 10710990848 1311950672 13.804 96 31 62 0 1328550432 9877216 6722544
18:48:08 60409 8192000000 10256731296 10701451264 1337885264 14.038 98 28 56 0 1354677600 10288352 6503984
18:51:15 38653 8192000000 10270121392 10727682048 1364778880 14.280 100 11 22 0 1382053728 14561040 2713808
18:52:54 48763 8192000000 10257498432 10723282944 1314532352 13.827 96 13 26 0 1334308752 17192080 2584320
18:54:43 32725 8192000000 10264951472 10720813056 1358604432 14.225 100 4 8 0 1371304640 12061568 638640
18:56:16 57774 8192000000 10261497952 10722508800 1359293616 14.231 100 16 32 0 1374298032 11370352 3634064
18:58:28 61535 8192000000 10260417184 10719858688 1319213152 13.870 96 13 26 0 1336193328 14021920 2958256
19:00:30 50288 8192000000 10267424464 10718916608 1349733424 14.145 99 27 52 1 1368305424 12195792 6376208
19:02:26 54828 8192000000 10275970944 10727837696 1341215008 14.068 98 13 26 0 1360366800 15801952 3349840
19:04:31 44419 8192000000 10284174912 10735276032 1347150080 14.122 98 13 26 0 1365230288 15480240 2599968
19:06:28 35256 8192000000 10305412384 10746118144 1379340720 14.411 100 30 59 0 1398501968 11953296 7207952
19:08:06 69109 8192000000 10297673920 10757042176 1374451696 14.367 99 25 50 0 1392085232 11639056 5994480
19:10:15 152339 8192000000 10284981984 10734522368 1338170128 14.041 96 12 24 0 1351538240 10984912 2383200
19:13:49 47977 8192000000 10275776144 10745573376 1369241792 14.320 99 8 16 1 1382050096 11062992 1745312
19:17:28 64635 8192000000 10284849248 10753150976 1367686528 14.306 99 18 36 0 1379651328 7748160 4216640
19:19:37 118078 8192000000 10301792992 10772889600 1378733680 14.405 99 11 22 0 1393157872 11763808 2660384
19:23:40 66751 8192000000 10298537968 10748178432 1338123664 14.040 96 20 40 2 1356100672 13526400 4450608
19:25:49 124263 8192000000 10267993328 10722254848 1226758832 13.024 89 16 32 0 1241851728 11654288 3438608
19:28:55 41159 8192000000 10288443472 10732040192 1329803888 13.965 97 31 62 0 1350724288 14134928 6785472
19:30:38 99885 8192000000 10237870768 10696704000 1300300544 13.698 96 13 26 0 1313745584 10583264 2861776
19:33:31 32234 8192000000 10233153120 10679099392 1293172384 13.633 96 18 34 1 1303397600 6518784 3706432
19:35:04 77637 8192000000 10256675056 10713063424 1334155376 14.005 98 10 20 0 1344008544 7086128 2767040
19:37:30 66528 8192000000 9648676896 10007105536 777455120 8.667 57 11 21 0 784435056 4663600 2316336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-10-2022 19:40:47 to 01-10-2022 19:41:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-10-2022 19:41:03 to 01-10-2022 19:41:05.

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          243149
MON$OLDEST_ACTIVE               245415
MON$OLDEST_SNAPSHOT             245415
MON$NEXT_TRANSACTION            245415
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                       2034336
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Oct  1 19:38:53 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

01.10.22 19:41:06. 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-10-01 19:41:08 No severe PSQL-related problems occured
01.10.22 19:41:10. End of report.