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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15545504 kB
MemAvailable 30557812 kB
Buffers 0 kB
Cached 16163288 kB
SwapCached 2112 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-03 22:38:40
DTS_END                         2022-10-03 22:38:27.8610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43176
TRN_ID                          247039
ATT_ID                          274
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: 9329.53 20171 1119543
customer order: creation 178.82 358 21458
customer order: refuse 15.63 352 1876
order to supplier: creation 47.94 1175 5753
order to supplier: removal 3.57 88334 428
invoice (draft): creation 47.20 3203 5664
invoice (draft): removal 53.06 738 6367
invoice accept: apply 43.29 17887 5195
invoice accept: cancel 47.68 745 5722
customer reserve: creation 3974.81 180 476977
customer reserve: removal 4609.18 53 553101
realization accept: apply 88.29 172 10595
realization accept: cancel 86.59 109 10391
payment from customer: creation 60.62 94 7274
payment from customer: removal 5.87 86 704
payment to supplier: creation 50.67 122 6080
payment to supplier: removal 3.52 79 422
service: total inventory turnovers 5.16 873 619
service: total monetary turnovers 6.72 25 806
service: aggregate perf. data 0.89 18412 107
service: refresh index statistics 0.03 270417 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4128
TEST_TIME 2 2390
TEST_TIME 3 4064
TEST_TIME 4 4164
TEST_TIME 5 4296
TEST_TIME 6 4267
TEST_TIME 7 4452
TEST_TIME 8 4515
TEST_TIME 9 4696
TEST_TIME 10 4954
TEST_TIME 11 5023
TEST_TIME 12 4988
TEST_TIME 13 5153
TEST_TIME 14 5288
TEST_TIME 15 5845
TEST_TIME 16 6303
TEST_TIME 17 6307
TEST_TIME 18 6525
TEST_TIME 19 7307
TEST_TIME 20 7563
TEST_TIME 21 7723
TEST_TIME 22 7976
TEST_TIME 23 8714
TEST_TIME 24 9078
TEST_TIME 25 9521
TEST_TIME 26 9756
TEST_TIME 27 10185
TEST_TIME 28 10143
TEST_TIME 29 9890
TEST_TIME 30 9875
TEST_TIME 31 9733
TEST_TIME 32 9570
TEST_TIME 33 9928
TEST_TIME 34 9740
TEST_TIME 35 9749
TEST_TIME 36 9699
TEST_TIME 37 9588
TEST_TIME 38 9675
TEST_TIME 39 9613
TEST_TIME 40 9725
TEST_TIME 41 9727
TEST_TIME 42 9613
TEST_TIME 43 9519
TEST_TIME 44 9448
TEST_TIME 45 9424
TEST_TIME 46 9304
TEST_TIME 47 9332
TEST_TIME 48 9350
TEST_TIME 49 9244
TEST_TIME 50 9172
TEST_TIME 51 9358
TEST_TIME 52 9298
TEST_TIME 53 9289
TEST_TIME 54 9343
TEST_TIME 55 9499
TEST_TIME 56 9491
TEST_TIME 57 9533
TEST_TIME 58 9572
TEST_TIME 59 9534
TEST_TIME 60 9602
TEST_TIME 61 9638
TEST_TIME 62 9656
TEST_TIME 63 9700
TEST_TIME 64 9639
TEST_TIME 65 9585
TEST_TIME 66 9549
TEST_TIME 67 9484
TEST_TIME 68 9435
TEST_TIME 69 9497
TEST_TIME 70 9441
TEST_TIME 71 9387
TEST_TIME 72 9328
TEST_TIME 73 9265
TEST_TIME 74 9348
TEST_TIME 75 9341
TEST_TIME 76 9345
TEST_TIME 77 9305
TEST_TIME 78 9392
TEST_TIME 79 9415
TEST_TIME 80 9410
TEST_TIME 81 9426
TEST_TIME 82 9413
TEST_TIME 83 9461
TEST_TIME 84 9470
TEST_TIME 85 9465
TEST_TIME 86 9515
TEST_TIME 87 9543
TEST_TIME 88 9454
TEST_TIME 89 9400
TEST_TIME 90 9412
TEST_TIME 91 9362
TEST_TIME 92 9345
TEST_TIME 93 9315
TEST_TIME 94 9321
TEST_TIME 95 9343
TEST_TIME 96 9343
TEST_TIME 97 9354
TEST_TIME 98 9360
TEST_TIME 99 9447
TEST_TIME 100 9458
TEST_TIME 101 9405
TEST_TIME 102 9478
TEST_TIME 103 9433
TEST_TIME 104 9530
TEST_TIME 105 9510
TEST_TIME 106 9587
TEST_TIME 107 9606
TEST_TIME 108 9553
TEST_TIME 109 9474
TEST_TIME 110 9505
TEST_TIME 111 9483
TEST_TIME 112 9477
TEST_TIME 113 9473
TEST_TIME 114 9435
TEST_TIME 115 9414
TEST_TIME 116 9414
TEST_TIME 117 9392
TEST_TIME 118 9382
TEST_TIME 119 9346
TEST_TIME 120 9330

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2022 22:39:53 to 03-10-2022 22:39:56.

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 1561977 1561977 0 0.00 0 18135 6 0 0
doc_list_biud 1561976 1561976 0 0.00 0 41256 25 0 0
sp_add_invoice_to_stock 5344 5195 149 2.78 32 177850 17887 0 149
sp_cancel_adding_invoice 5722 5722 0 0.00 4 83250 745 0 0
sp_cancel_client_order 1876 1876 0 0.00 4 17768 352 0 0
sp_cancel_customer_reserve 553101 553101 0 0.00 0 18343 54 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 4347 87 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 3527 79 0 0
sp_cancel_supplier_invoice 6379 6367 12 0.18 1 13432 739 0 12
sp_cancel_supplier_order 428 428 0 0.00 5 1040979 88334 0 0
sp_cancel_write_off 10391 10391 0 0.00 1 19222 109 0 0
sp_client_order 21458 21458 0 0.00 3 28695 359 0 0
sp_customer_reserve 476977 476977 0 0.00 0 70852 180 0 0
sp_fill_shopping_cart 32875 32875 0 0.00 1 11773 33 0 0
sp_fill_shopping_cart_clo_res 476976 476976 0 0.00 0 20240 14 0 0
sp_get_clo_for_invoice 5194 5194 0 0.00 0 23992 254 0 0
sp_kill_cost_storno 17239 17239 0 0.00 0 15901 52 0 0
sp_kill_qstorno_ret_qs2qd 1123388 1123388 0 0.00 0 19164 29 0 0
sp_kill_qty_storno 593673 593673 0 0.00 0 41256 64 0 0
sp_lock_selected_doc 21761 21761 0 0.00 0 5669 5 0 0
sp_make_cost_storno 29143 29143 0 0.00 0 16092 47 0 0
sp_multiply_rows_for_pdistr 29142 29142 0 0.00 0 16178 27 0 0
sp_multiply_rows_for_qdistr 21458 21458 0 0.00 0 17679 160 0 0
sp_pay_from_customer 7274 7274 0 0.00 4 14414 94 0 0
sp_pay_to_supplier 6080 6080 0 0.00 4 14279 122 0 0
sp_payment_common 13354 13354 0 0.00 1 11453 28 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 17761 320 0 0
sp_qd_handle_on_invoice_upd_sts 10916 10916 0 0.00 0 41254 311 0 0
sp_qd_handle_on_reserve_upd_sts 10594 10594 0 0.00 0 15294 55 0 0
sp_reserve_write_off 10595 10595 0 0.00 6 17738 173 0 0
sp_supplier_invoice 5664 5664 0 0.00 6 110379 3204 0 0
sp_supplier_order 5753 5753 0 0.00 5 30302 1176 0 0
srv_aggregate_perf_data 108 107 1 0.92 2684 346914 18413 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 995 126616 44531 0 0
srv_find_qd_qs_mism 1070585 1070585 0 0.00 0 18134 6 0 0
srv_make_invnt_saldo 619 619 0 0.00 45 36293 874 0 0
srv_make_money_saldo 806 806 0 0.00 1 2065 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 146562 357817 270418 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5195 5195 0 0.00 0 15553 162 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 2306 76 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 1969 11 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 576 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 755 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 2815 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1959 17 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 20609 1190 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 529 5 0 0
t$perf-norm:sp_client_order 21458 21458 0 0.00 0 8515 16 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 850 4 0 0
t$perf-norm:sp_pay_from_customer 7274 7274 0 0.00 0 6334 9 0 0
t$perf-norm:sp_pay_to_supplier 6080 6080 0 0.00 0 4845 10 0 0
t$perf-norm:sp_reserve_write_off 10595 10595 0 0.00 0 7417 10 0 0
t$perf-norm:sp_supplier_invoice 5664 5664 0 0.00 0 6571 16 0 0
t$perf-norm:sp_supplier_order 5753 5753 0 0.00 0 8000 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 297 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 619 619 0 0.00 0 2473 17 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 2768 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 10684 10386 298 2.78 0 4536 16 0 298
v_all_customers 38694 38694 0 0.00 0 15 0 0 0
v_all_suppliers 31306 31306 0 0.00 0 22 1 0 0
v_all_wares 42916 42916 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 166 5 0 0
v_cancel_client_order 3752 3752 0 0.00 0 10977 6 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 1376 6 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 2257 5 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2321 5 0 0
v_cancel_supplier_invoice 860 836 24 2.79 0 2861 18 0 24
v_cancel_supplier_order 856 856 0 0.00 0 601 3 0 0
v_cancel_write_off 1916 1916 0 0.00 0 2289 14 0 0
v_max_id_clo_ord 5753 5753 0 0.00 0 213 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 1971 14 0 0
v_max_id_ord_sup 5664 5664 0 0.00 0 6040 4 0 0
v_max_non_paid_invoice 1844 1844 0 0.00 0 4624 5 0 0
v_max_non_paid_realizn 3992 3992 0 0.00 0 4622 7 0 0
v_min_id_clo_ord 5753 5753 0 0.00 0 3828 9 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 7975 23 0 0
v_min_id_ord_sup 5664 5664 0 0.00 0 10121 12 0 0
v_min_non_paid_invoice 6080 6080 0 0.00 0 7951 19 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 6650 20 0 0
v_our_firm 10786 10786 0 0.00 0 25 1 0 0
v_reserve_write_off 21188 21188 0 0.00 0 14126 6 0 0
x_lock_depdocs_on_canc_invoice 11671 11671 0 0.00 0 12916 106 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 13655 324 0 0
x_make_qstorno_1000_1200 5753 5753 0 0.00 1 30280 1108 0 0
x_make_qstorno_1200_2000 5664 5664 0 0.00 3 109623 3086 0 0
x_make_qstorno_2100_3300 476977 476977 0 0.00 0 70851 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2022 22:39:57 to 03-10-2022 22:40:01.

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:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 31920 8192000000 9913749728 10225655808 992469152 10.805 100 89 178 0 1021537616 5922560 23145904
20:11:10 21889 8192000000 9953543760 10279829504 1039107744 11.256 100 87 174 0 1070181360 8363024 22710592
20:12:38 9484 8192000000 9984212416 10319630336 1069975840 11.552 100 83 166 0 1100828672 10022512 20830320
20:13:52 10275 8192000000 10018817984 10365923328 1112909392 11.960 100 81 162 2 1143293968 11026512 19358064
20:15:10 5276 8192000000 10080075344 10440581120 1168540192 12.483 100 77 153 0 1198663216 12166848 17956176
20:16:19 2971 8192000000 10124714160 10496573440 1217274912 12.936 100 71 142 0 1244924928 11756000 15894016
20:17:24 1715 8192000000 10166000592 10542956544 1255228560 13.286 100 88 175 0 1287473952 13414208 18831184
20:18:29 1510 8192000000 10194773472 10578558976 1280883120 13.521 100 85 170 1 1313221968 13502560 18836288
20:19:31 1147 8192000000 10206605504 10596012032 1295474944 13.654 100 74 146 1 1323830976 11675824 16680208
20:20:34 34338 8192000000 10199037184 10604539904 1304133552 13.733 100 38 74 0 1319474640 6909728 8431360
20:22:09 9989 8192000000 10206458624 10620248064 1315602720 13.837 100 20 39 0 1325175360 5307344 4265296
20:23:21 9826 8192000000 10203147216 10626420736 1325733152 13.929 100 20 40 0 1336604032 6625872 4245008
20:24:31 13073 8192000000 10217771984 10634514432 1333940272 14.003 100 27 52 2 1345265792 5642192 5683328
20:26:18 3943 8192000000 10230519008 10646302720 1338447472 14.043 100 35 67 0 1352117808 6282064 7388272
20:27:23 10361 8192000000 10223643120 10645565440 1334896608 14.011 100 22 44 1 1345686304 5427728 5361968
20:28:36 11738 8192000000 10218095024 10635890688 1313582080 13.819 99 13 25 1 1321480368 5599824 2298464
20:29:48 17449 8192000000 10198266080 10589528064 1284707248 13.556 100 59 118 0 1304728144 6247440 13773456
20:31:07 4932 8192000000 10151654272 10554667008 1239072768 13.138 97 18 34 1 1248714512 5144128 4497616
20:32:16 5295 8192000000 10124297632 10524102656 1229933072 13.053 99 24 50 0 1241423904 6161456 5329376
20:33:24 6156 8192000000 10154459776 10555588608 1253468656 13.270 99 24 44 0 1263304480 4903808 4932016
20:34:30 30148 8192000000 10163680656 10575773696 1274491520 13.463 100 21 40 1 1284396256 5191440 4713296
20:36:04 3843 8192000000 10195807824 10598887424 1292491680 13.627 100 44 88 0 1309149136 7130256 9527200
20:37:10 8466 8192000000 10201356720 10609635328 1302249232 13.716 100 17 34 1 1312132192 6026624 3856336
20:38:19 13201 8192000000 10223201456 10633334784 1324493744 13.917 100 15 30 1 1332229424 4821920 2913760
20:39:37 109522 8192000000 10254138656 10671247360 1346077072 14.112 100 44 88 0 1367493456 12280624 9135760
20:42:38 27318 8192000000 10288291184 10708680704 1369264032 14.320 100 40 80 0 1391992864 13485200 9243632
20:44:21 8728 8192000000 10299255872 10725146624 1389333424 14.500 100 42 84 1 1417240832 17733424 10173984
20:45:34 12321 8192000000 10319715888 10744397824 1400579824 14.600 100 51 102 1 1434867536 22333152 11954560
20:46:51 27136 8192000000 10335897280 10758684672 1405078848 14.640 100 58 114 0 1443333504 24204912 14049744
20:48:29 18719 8192000000 10338030432 10765094912 1403267984 14.624 99 50 99 0 1444363376 28881184 12214208
20:49:53 1932 8192000000 10352677072 10773020672 1415940464 14.737 100 65 129 0 1460657072 28750880 15965728
20:50:59 5219 8192000000 10359354448 10780594176 1419151136 14.765 100 58 116 3 1471813824 37553040 15109648
20:52:11 2043 8192000000 10368124992 10788188160 1417514896 14.751 100 67 130 1 1475855184 41966560 16373728
20:53:19 993 8192000000 10367197200 10778038272 1402670624 14.619 100 88 169 1 1473395808 49202960 21522224
20:54:25 8635 8192000000 10341275616 10750963712 1383179568 14.445 100 69 133 1 1447607984 47546080 16882336
20:55:36 5508 8192000000 10358570544 10767036416 1390324592 14.509 100 69 138 0 1459826080 52586624 16914864
20:56:44 4172 8192000000 10334296960 10743144448 1353439008 14.178 99 79 156 0 1435320704 61844704 20036992
20:57:52 17817 8192000000 10320822832 10744061952 1354811072 14.191 99 33 65 0 1415611152 51461328 9338752
20:59:13 18790 8192000000 10305292240 10730393600 1352903520 14.174 100 29 58 0 1410915104 49139216 8872368
21:00:36 18232 8192000000 10309668544 10744721408 1352966848 14.174 100 29 58 0 1418710576 57122560 8621168
21:02:05 24646 8192000000 10278145296 10716278784 1338900208 14.047 99 18 36 0 1381050464 37225856 4924400
21:03:31 20525 8192000000 10288759184 10727182336 1358076912 14.220 100 23 44 1 1392000384 27872800 6050672
21:04:52 46564 8192000000 10255634880 10706632704 1321318304 13.889 98 18 35 0 1345728224 19980736 4429184
21:07:20 62415 8192000000 10251168096 10693435392 1324690032 13.919 98 24 46 1 1339587968 9437328 5460608
21:09:22 44560 8192000000 10258017728 10685964288 1327454464 13.944 98 34 65 2 1341658608 6548800 7655344
21:11:10 117070 8192000000 10269534688 10716090368 1363269504 14.267 100 14 26 1 1377235168 10949232 3016432
21:14:10 9828 8192000000 10280557104 10721366016 1368301392 14.312 99 42 84 1 1385275264 7743040 9230832
21:15:25 48558 8192000000 10304631616 10751361024 1390656448 14.512 100 42 83 0 1415821536 16050432 9114656
21:17:23 72081 8192000000 10296295648 10752057344 1377930400 14.398 99 23 47 0 1404489584 21173616 5385568
21:19:36 23657 8192000000 10289298032 10740281344 1387200896 14.481 100 16 33 1 1401023824 9614752 4208176
21:21:00 64246 8192000000 10280453040 10741460992 1376483088 14.385 99 26 52 2 1393143872 10165600 6495184
21:23:08 64884 8192000000 10298219376 10760196096 1397794880 14.575 100 16 30 0 1410151360 8484064 3872416
21:25:30 41991 8192000000 10315214336 10774921216 1405935936 14.648 100 18 36 0 1426317712 16150304 4231472
21:27:13 61773 8192000000 10315890448 10777018368 1411064288 14.693 100 22 44 0 1432042384 15355856 5622240
21:29:47 35109 8192000000 10303204288 10764746752 1400119568 14.596 99 9 18 0 1414302432 12333392 1849472
21:31:24 54504 8192000000 10301112288 10768486400 1389585008 14.502 99 35 70 0 1411705232 13987264 8132960
21:33:20 86176 8192000000 10325780992 10771042304 1368066848 14.310 97 20 40 0 1381599232 9265808 4266576
21:35:47 63600 8192000000 10285652960 10739515392 1316594000 13.846 94 12 24 0 1329449680 10511312 2344368
21:37:56 28937 8192000000 10264702624 10707845120 1335319952 14.015 98 36 72 0 1355679152 12738976 7620224
21:39:33 117093 8192000000 10283977024 10745790464 1298563792 13.682 94 13 26 0 1311779984 10593584 2622608
21:42:30 62172 8192000000 10264097728 10717978624 1340266816 14.060 98 25 48 1 1362233728 16032800 5934112
21:44:32 90308 8192000000 10243318432 10688307200 1291961392 13.622 95 27 54 0 1312782752 14933824 5887536
21:47:04 46340 8192000000 10225068720 10676539392 1281582528 13.527 95 13 26 0 1293846128 9736480 2527120
21:48:53 50685 8192000000 10237362112 10684612608 1300158288 13.697 96 15 30 0 1312473712 8800608 3514816
21:50:45 75212 8192000000 10247577216 10697871360 1340644304 14.063 99 16 32 0 1358575200 13802592 4128304
21:53:04 29780 8192000000 10280142288 10716000256 1347737648 14.127 99 28 56 0 1363078704 8384944 6956112
21:54:38 41973 8192000000 10270162720 10711105536 1338425056 14.043 98 15 30 0 1352954592 11190816 3338720
21:56:20 46237 8192000000 10270309120 10715525120 1355276848 14.195 99 15 30 0 1369916208 11267584 3371776
21:58:06 59880 8192000000 10277492144 10717466624 1324774064 13.920 97 32 65 0 1340519744 9035328 6710352
22:00:12 37286 8192000000 10290423152 10726371328 1348220464 14.131 98 43 86 0 1368915776 11142208 9553104
22:01:54 42884 8192000000 10288223616 10735005696 1374152592 14.364 100 16 33 0 1389063632 11304976 3606064
22:03:37 32753 8192000000 10269974832 10701070336 1334794288 14.010 98 28 56 1 1351782240 10901824 6086128
22:05:10 126616 8192000000 10272158464 10718982144 1368502784 14.314 100 14 28 0 1385818832 14137648 3178400
22:08:24 45875 8192000000 10276973072 10738446336 1380727040 14.423 100 20 40 0 1395198672 10315184 4156448
22:12:09 35571 8192000000 10301583488 10750726144 1372952896 14.353 98 17 34 0 1386588784 9977344 3658544
22:14:06 54585 8192000000 10304333184 10756984832 1365687792 14.288 98 24 48 0 1390359552 19275312 5396448
22:16:01 41996 8192000000 10324857584 10770968576 1397703936 14.575 100 36 72 0 1421034224 14804096 8526192
22:17:44 56225 8192000000 10284676208 10740420608 1357064320 14.211 98 12 24 0 1383147232 23157488 2925424
22:19:41 53148 8192000000 10289038576 10740297728 1338272112 14.042 96 30 59 1 1356957136 12092496 6592528
22:21:45 49081 8192000000 10293663344 10739257344 1363488272 14.269 99 18 36 0 1385007488 17188688 4330528
22:23:42 40260 8192000000 10311286352 10749923328 1368208688 14.311 99 37 72 1 1392801376 16600704 7991984
22:25:31 78460 8192000000 10287247520 10732306432 1330186656 13.969 96 8 16 0 1347978496 15634960 2156880
22:27:58 62010 8192000000 10268962064 10716639232 1296337680 13.662 94 18 36 0 1319499152 18842784 4318688
22:31:29 37604 8192000000 10216755232 10669973504 1264061632 13.367 93 13 27 0 1277854064 11153440 2638992
22:33:09 76923 8192000000 10238599200 10689835008 1280463520 13.517 95 17 34 0 1299422368 15137696 3821152
22:35:34 44111 8192000000 10240024448 10692182016 1305801872 13.748 98 17 34 0 1325352912 15953808 3597232
22:37:18 43484 8192000000 10249300976 10689966080 1326848976 13.939 99 24 48 0 1340904112 8787856 5267280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-10-2022 22:40:03 to 03-10-2022 22:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 149 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 03-10-2022 22:40:19 to 03-10-2022 22:40:21.

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          252088
MON$OLDEST_ACTIVE               252089
MON$OLDEST_SNAPSHOT             252089
MON$NEXT_TRANSACTION            252089
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                       2027680
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 ---
--- end of diff output ---

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

03.10.22 22:40:22. 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-03 22:40:25 No severe PSQL-related problems occured
03.10.22 22:40:26. End of report.