Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.07.2022 16: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 16021572 kB
MemAvailable 31057828 kB
Buffers 0 kB
Cached 15673360 kB
SwapCached 2484 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-07-18 16:38:25
DTS_END                         2022-07-18 16:38:25.0770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33046
TRN_ID                          236665
ATT_ID                          703
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: 9176.99 18685 1101239
customer order: creation 171.49 341 20579
customer order: refuse 14.75 366 1770
order to supplier: creation 46.14 1226 5537
order to supplier: removal 3.43 98522 412
invoice (draft): creation 45.66 3226 5479
invoice (draft): removal 52.03 758 6243
invoice accept: apply 40.84 17893 4901
invoice accept: cancel 46.84 726 5621
customer reserve: creation 3815.24 175 457829
customer reserve: removal 4643.03 57 557164
realization accept: apply 83.50 160 10020
realization accept: cancel 86.26 102 10351
payment from customer: creation 58.89 107 7067
payment from customer: removal 5.64 80 677
payment to supplier: creation 48.28 118 5793
payment to supplier: removal 3.08 87 369
service: total inventory turnovers 4.43 1470 531
service: total monetary turnovers 6.72 23 806
service: aggregate perf. data 0.72 22488 86
service: refresh index statistics 0.03 225782 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-07-2022 16:39:27 to 18-07-2022 16:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3958
TEST_TIME 2 2871
TEST_TIME 3 3118
TEST_TIME 4 3396
TEST_TIME 5 4016
TEST_TIME 6 4479
TEST_TIME 7 4944
TEST_TIME 8 5240
TEST_TIME 9 5522
TEST_TIME 10 5627
TEST_TIME 11 5816
TEST_TIME 12 5839
TEST_TIME 13 6007
TEST_TIME 14 6436
TEST_TIME 15 6737
TEST_TIME 16 7149
TEST_TIME 17 7223
TEST_TIME 18 7647
TEST_TIME 19 8092
TEST_TIME 20 8479
TEST_TIME 21 8729
TEST_TIME 22 8819
TEST_TIME 23 8847
TEST_TIME 24 8837
TEST_TIME 25 8798
TEST_TIME 26 8916
TEST_TIME 27 8989
TEST_TIME 28 10093
TEST_TIME 29 10792
TEST_TIME 30 10877
TEST_TIME 31 10806
TEST_TIME 32 10626
TEST_TIME 33 10373
TEST_TIME 34 10442
TEST_TIME 35 10441
TEST_TIME 36 10205
TEST_TIME 37 10054
TEST_TIME 38 10098
TEST_TIME 39 10047
TEST_TIME 40 9942
TEST_TIME 41 9917
TEST_TIME 42 9815
TEST_TIME 43 9848
TEST_TIME 44 9743
TEST_TIME 45 9669
TEST_TIME 46 9609
TEST_TIME 47 9506
TEST_TIME 48 9431
TEST_TIME 49 9333
TEST_TIME 50 9285
TEST_TIME 51 9373
TEST_TIME 52 9382
TEST_TIME 53 9304
TEST_TIME 54 9288
TEST_TIME 55 9287
TEST_TIME 56 9206
TEST_TIME 57 9294
TEST_TIME 58 9261
TEST_TIME 59 9242
TEST_TIME 60 9251
TEST_TIME 61 9380
TEST_TIME 62 9331
TEST_TIME 63 9402
TEST_TIME 64 9319
TEST_TIME 65 9367
TEST_TIME 66 9395
TEST_TIME 67 9445
TEST_TIME 68 9473
TEST_TIME 69 9431
TEST_TIME 70 9617
TEST_TIME 71 9601
TEST_TIME 72 9561
TEST_TIME 73 9485
TEST_TIME 74 9496
TEST_TIME 75 9480
TEST_TIME 76 9419
TEST_TIME 77 9410
TEST_TIME 78 9403
TEST_TIME 79 9369
TEST_TIME 80 9330
TEST_TIME 81 9332
TEST_TIME 82 9251
TEST_TIME 83 9213
TEST_TIME 84 9155
TEST_TIME 85 9186
TEST_TIME 86 9151
TEST_TIME 87 9103
TEST_TIME 88 9091
TEST_TIME 89 9157
TEST_TIME 90 9157
TEST_TIME 91 9168
TEST_TIME 92 9179
TEST_TIME 93 9225
TEST_TIME 94 9210
TEST_TIME 95 9298
TEST_TIME 96 9290
TEST_TIME 97 9270
TEST_TIME 98 9330
TEST_TIME 99 9404
TEST_TIME 100 9352
TEST_TIME 101 9432
TEST_TIME 102 9434
TEST_TIME 103 9440
TEST_TIME 104 9356
TEST_TIME 105 9336
TEST_TIME 106 9329
TEST_TIME 107 9309
TEST_TIME 108 9298
TEST_TIME 109 9250
TEST_TIME 110 9237
TEST_TIME 111 9208
TEST_TIME 112 9136
TEST_TIME 113 9133
TEST_TIME 114 9132
TEST_TIME 115 9164
TEST_TIME 116 9184
TEST_TIME 117 9176
TEST_TIME 118 9164
TEST_TIME 119 9245
TEST_TIME 120 9180

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-07-2022 16:39:31 to 18-07-2022 16:39:34.

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 1525276 1525276 0 0.00 0 44192 6 0 0
doc_list_biud 1525272 1525272 0 0.00 0 40507 27 0 0
sp_add_invoice_to_stock 5083 4901 182 3.58 28 153529 17893 0 182
sp_cancel_adding_invoice 5621 5621 0 0.00 4 50493 726 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 17898 366 0 0
sp_cancel_customer_reserve 557164 557164 0 0.00 0 40508 58 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 4165 81 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 2 8890 87 0 0
sp_cancel_supplier_invoice 6255 6243 12 0.19 1 15518 758 0 12
sp_cancel_supplier_order 412 412 0 0.00 5 1124694 98522 0 0
sp_cancel_write_off 10351 10351 0 0.00 1 9630 103 0 0
sp_client_order 20579 20579 0 0.00 3 25795 342 0 0
sp_customer_reserve 457829 457829 0 0.00 0 75101 175 0 0
sp_fill_shopping_cart 31593 31593 0 0.00 1 21256 40 0 0
sp_fill_shopping_cart_clo_res 457828 457828 0 0.00 0 21725 14 0 0
sp_get_clo_for_invoice 4894 4894 0 0.00 0 36375 332 0 0
sp_kill_cost_storno 17018 17018 0 0.00 0 8886 52 0 0
sp_kill_qstorno_ret_qs2qd 1131334 1131334 0 0.00 0 40504 31 0 0
sp_kill_qty_storno 596474 596474 0 0.00 0 40507 67 0 0
sp_lock_selected_doc 20615 20615 0 0.00 0 7836 4 0 0
sp_make_cost_storno 27774 27774 0 0.00 0 17274 56 0 0
sp_multiply_rows_for_pdistr 27770 27770 0 0.00 0 10066 25 0 0
sp_multiply_rows_for_qdistr 20579 20579 0 0.00 0 24812 139 0 0
sp_pay_from_customer 7067 7067 0 0.00 4 21052 107 0 0
sp_pay_to_supplier 5793 5793 0 0.00 5 15036 119 0 0
sp_payment_common 12857 12857 0 0.00 1 13945 22 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 17891 335 0 0
sp_qd_handle_on_invoice_upd_sts 10515 10515 0 0.00 0 36273 307 0 0
sp_qd_handle_on_reserve_upd_sts 10019 10019 0 0.00 0 9956 55 0 0
sp_reserve_write_off 10020 10020 0 0.00 6 13622 161 0 0
sp_supplier_invoice 5479 5479 0 0.00 5 78098 3227 0 0
sp_supplier_order 5537 5537 0 0.00 4 37569 1227 0 0
srv_aggregate_perf_data 86 86 0 0.00 2506 151207 22488 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1340 181979 49748 0 0
srv_find_qd_qs_mism 1053923 1053923 0 0.00 0 44136 5 0 0
srv_make_invnt_saldo 531 531 0 0.00 35 152612 1471 0 0
srv_make_money_saldo 806 806 0 0.00 1 441 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 142736 360505 225782 0 0
t$perf-abend:sp_add_invoice_to_stock 182 182 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 4901 4901 0 0.00 0 16578 186 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 9433 162 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1479 12 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 836 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 913 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 3676 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 616 13 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27532 1587 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 10347 27 0 0
t$perf-norm:sp_client_order 20579 20579 0 0.00 0 17990 18 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 6596 11 0 0
t$perf-norm:sp_pay_from_customer 7067 7067 0 0.00 0 7767 17 0 0
t$perf-norm:sp_pay_to_supplier 5793 5793 0 0.00 0 3728 10 0 0
t$perf-norm:sp_reserve_write_off 10020 10020 0 0.00 0 9597 18 0 0
t$perf-norm:sp_supplier_invoice 5479 5479 0 0.00 0 12718 19 0 0
t$perf-norm:sp_supplier_order 5537 5537 0 0.00 0 8961 18 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 195 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 3328 29 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 1740 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10144 9780 364 3.58 0 2384 15 0 364
v_all_customers 37442 37442 0 0.00 0 42 1 0 0
v_all_suppliers 30362 30362 0 0.00 0 18 1 0 0
v_all_wares 41158 41158 0 0.00 0 126 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 3334 17 0 0
v_cancel_client_order 3540 3540 0 0.00 0 5361 9 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 3115 9 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 1274 4 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 2588 10 0 0
v_cancel_supplier_invoice 844 820 24 2.84 0 5023 26 0 24
v_cancel_supplier_order 824 824 0 0.00 0 2372 7 0 0
v_cancel_write_off 1972 1972 0 0.00 0 1376 13 0 0
v_max_id_clo_ord 5537 5537 0 0.00 0 1727 1 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 5337 15 0 0
v_max_id_ord_sup 5477 5477 0 0.00 0 3974 4 0 0
v_max_non_paid_invoice 1625 1625 0 0.00 0 4180 6 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 2802 4 0 0
v_min_id_clo_ord 5537 5537 0 0.00 0 10246 9 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 8643 21 0 0
v_min_id_ord_sup 5477 5477 0 0.00 0 4309 8 0 0
v_min_non_paid_invoice 5792 5792 0 0.00 0 4878 13 0 0
v_min_non_paid_realizn 7065 7065 0 0.00 0 13940 16 0 0
v_our_firm 10270 10270 0 0.00 0 68 1 0 0
v_reserve_write_off 20036 20036 0 0.00 0 5751 6 0 0
x_lock_depdocs_on_canc_invoice 11454 11454 0 0.00 0 12382 119 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 7195 323 0 0
x_make_qstorno_1000_1200 5537 5537 0 0.00 1 37356 1140 0 0
x_make_qstorno_1200_2000 5479 5479 0 0.00 3 78090 3084 0 0
x_make_qstorno_2100_3300 457829 457829 0 0.00 0 75099 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-07-2022 16:39:35 to 18-07-2022 16:39:39.

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
14:08:28 4 8192000000 8806145616 8820133888 10792480 0.131 4 2 3 0 10936272 68832 74960
14:09:30 31854 8192000000 9899697360 10208317440 978005456 10.665 100 84 168 0 1006667360 5884608 22777296
14:11:04 26007 8192000000 9946199504 10266464256 1030528960 11.174 100 92 184 0 1062427168 8262720 23635488
14:12:33 19282 8192000000 9988988592 10318880768 1062288320 11.478 100 85 170 0 1092951344 9671344 20991680
14:14:04 8704 8192000000 10037013856 10386681856 1126305952 12.087 100 74 147 0 1154084512 10770288 17008272
14:15:23 6144 8192000000 10087053312 10448064512 1174846608 12.542 100 76 152 2 1204012992 11617920 17548464
14:16:32 3478 8192000000 10127678592 10495811584 1216818592 12.932 100 81 160 0 1247367744 12616176 17932976
14:17:38 2403 8192000000 10162931536 10534977536 1240599440 13.152 100 89 172 0 1278835264 19659328 18576496
14:18:41 1703 8192000000 10175480016 10557095936 1265804672 13.383 100 83 166 2 1301927824 17168128 18955024
14:19:45 1633 8192000000 10206582304 10588831744 1291468704 13.618 100 91 182 2 1324336768 12302656 20565408
14:20:49 8612 8192000000 10212275408 10604171264 1303427712 13.726 100 70 138 0 1329466016 10621024 15417280
14:21:59 7159 8192000000 10200170784 10608021504 1307137520 13.760 100 24 48 0 1319890816 7749264 5004032
14:23:09 12007 8192000000 10201777696 10616193024 1315375984 13.835 100 10 20 0 1323147056 5610784 2160288
14:24:22 51456 8192000000 10234049408 10641326080 1327305968 13.943 100 42 82 1 1342172848 4547552 10319328
14:26:15 3240 8192000000 10228699120 10649063424 1337398128 14.034 100 35 68 0 1351215488 5835344 7982016
14:27:23 8045 8192000000 10228230304 10651336704 1340772560 14.064 100 16 30 1 1349321664 5387472 3161632
14:28:32 7357 8192000000 10211931664 10633424896 1321916784 13.894 99 16 31 0 1330155024 5067712 3170528
14:29:42 35964 8192000000 10211116672 10628407296 1313458736 13.817 99 24 48 0 1324946384 6103664 5383984
14:31:19 10672 8192000000 10155853760 10551193600 1200552368 12.781 94 27 54 0 1216577264 9577280 6447616
14:32:33 5648 8192000000 10134384464 10527080448 1191169504 12.694 95 29 59 1 1202385104 4795376 6420224
14:33:41 60815 8192000000 10166957760 10565566464 1235258016 13.103 97 29 59 1 1247676112 5818288 6599808
14:35:43 4031 8192000000 10171552384 10585505792 1286501984 13.572 100 22 45 1 1297436672 6088784 4845904
14:36:47 6573 8192000000 10188919616 10604748800 1301222992 13.706 100 20 40 0 1311492032 5466192 4802848
14:37:54 10841 8192000000 10203488048 10617581568 1310627328 13.792 100 17 33 0 1319465520 5145440 3692752
14:39:11 44551 8192000000 10238844464 10655985664 1338611040 14.045 100 33 63 0 1351561728 6389312 6561376
14:41:11 26307 8192000000 10254485104 10675003392 1353814992 14.182 100 35 68 1 1372187792 11002848 7369952
14:42:43 9787 8192000000 10277530240 10695905280 1366355056 14.294 100 48 95 0 1391525680 14563824 10606800
14:44:03 13791 8192000000 10287612720 10710192128 1376185888 14.382 100 47 94 0 1403866624 17050784 10629952
14:45:20 17329 8192000000 10312159136 10733731840 1390108624 14.507 100 50 100 0 1425358112 23398256 11851232
14:46:44 19385 8192000000 10312966720 10742431744 1394247584 14.544 100 44 87 0 1425451808 20978288 10225936
14:48:20 3397 8192000000 10341155392 10762489856 1412871056 14.709 100 62 122 0 1451548256 24756672 13920528
14:49:29 12081 8192000000 10357844176 10778202112 1420944304 14.781 100 68 134 0 1466816096 29442624 16429168
14:50:46 5171 8192000000 10366390928 10789224448 1427814320 14.842 100 58 116 1 1473731472 31958704 13958448
14:51:55 4709 8192000000 10373510384 10794176512 1426339280 14.829 100 70 138 0 1478323248 34827056 17156912
14:53:05 2318 8192000000 10361529472 10774396928 1398751008 14.584 99 85 168 3 1458992672 39285296 20956368
14:54:16 2775 8192000000 10365741040 10787586048 1413668416 14.717 100 73 142 0 1473284800 41589504 18026880
14:55:25 1338 8192000000 10373814976 10784575488 1407552368 14.662 100 89 174 0 1471873664 43355440 20965856
14:56:31 7233 8192000000 10364832080 10781327360 1404914256 14.639 100 76 150 2 1466156688 42067792 19174640
14:57:42 15941 8192000000 10328422688 10755256320 1372297536 14.348 98 40 80 0 1432005536 48721968 10986032
14:59:04 10372 8192000000 10332199776 10762379264 1389601776 14.502 100 38 74 2 1443777984 43696272 10479936
15:00:19 13007 8192000000 10337542656 10772873216 1394869152 14.549 100 34 66 0 1446125536 42166832 9089552
15:01:41 17482 8192000000 10295188528 10725109760 1329538896 13.963 97 26 52 1 1378556656 41906304 7111456
15:03:03 3576 8192000000 10323497776 10754396160 1373076112 14.355 100 39 77 0 1431733088 47965248 10691728
15:04:12 26173 8192000000 10316982624 10751172608 1356565792 14.207 99 28 56 0 1418284144 53836048 7882304
15:05:39 19741 8192000000 10329167168 10765115392 1362744832 14.262 100 21 42 2 1430071728 60722480 6604416
15:07:05 53698 8192000000 10250914208 10706452480 1320366560 13.880 98 18 35 0 1360235728 35041248 4827920
15:09:00 17616 8192000000 10291109680 10725617664 1371722064 14.342 100 42 80 4 1389221104 8976752 8522288
15:10:22 80932 8192000000 10270698688 10710970368 1353016256 14.175 99 38 75 0 1371095440 8205824 9873360
15:13:02 29460 8192000000 10272076864 10720325632 1365739312 14.289 100 21 42 0 1380764560 10420032 4605216
15:15:28 102980 8192000000 10272152592 10725339136 1357534288 14.215 99 22 44 0 1373953744 10989424 5430032
15:18:20 50105 8192000000 10281584464 10736242688 1378632192 14.404 100 13 26 0 1395480768 14234912 2613664
15:20:10 93142 8192000000 10289579408 10746523648 1380424560 14.420 99 35 71 0 1399627152 11317040 7885552
15:22:51 65174 8192000000 10302643200 10761478144 1395284512 14.553 100 14 27 0 1409147360 11013104 2849744
15:24:58 152634 8192000000 10308880688 10767093760 1389811488 14.504 99 29 58 2 1407124384 9875888 7437008
15:28:37 59230 8192000000 10308012608 10779353088 1410872736 14.692 100 42 84 0 1438198672 17310256 10015680
15:30:39 62155 8192000000 10299074032 10764677120 1383429168 14.447 98 12 24 1 1396589632 10357904 2802560
15:32:57 76758 8192000000 10301063440 10765291520 1384194816 14.454 99 9 16 0 1398871568 12849488 1827264
15:35:27 89087 8192000000 10300537024 10762952704 1386444048 14.474 99 10 20 1 1399391232 10839856 2107328
15:38:06 59563 8192000000 10308223824 10769350656 1387449824 14.483 98 18 36 0 1408786128 17510336 3825968
15:40:06 71384 8192000000 10285128352 10735316992 1290525136 13.609 92 15 29 0 1303648176 10235280 2887760
15:42:24 15768 8192000000 10297728560 10730561536 1350492432 14.152 98 29 58 1 1364202240 7502208 6207600
15:43:45 51093 8192000000 10284198192 10730348544 1331357632 13.979 96 23 46 0 1347821888 11143008 5321248
15:45:37 84913 8192000000 10275584096 10717511680 1309298720 13.780 96 11 22 0 1326964672 14898480 2767472
15:48:02 181978 8192000000 10259302048 10710601728 1241779920 13.163 91 14 28 0 1268860688 23974656 3106112
15:52:47 93845 8192000000 10262939872 10722414592 1344118640 14.095 99 30 59 1 1368524992 16995968 7410384
15:56:08 78575 8192000000 10238482544 10686689280 1332061232 13.986 99 7 14 0 1343858032 10090480 1706320
15:58:26 62951 8192000000 10231961696 10685927424 1323115488 13.905 99 17 34 0 1339248032 12055088 4077456
16:01:36 99476 8192000000 10246087040 10704621568 1347835232 14.128 100 21 42 1 1366521424 13792768 4893424
16:04:21 38022 8192000000 10280503952 10727616512 1371159856 14.337 100 37 75 0 1394475664 15082352 8233456
16:06:10 81226 8192000000 10256607984 10714021888 1341468384 14.071 98 7 14 0 1354691776 11281712 1941680
16:08:34 55747 8192000000 10269726752 10721210368 1346461792 14.116 98 23 46 0 1359683312 8380480 4841040
16:10:35 42337 8192000000 10289673696 10737848320 1385813232 14.468 100 19 38 0 1404234224 14530880 3890112
16:12:23 53659 8192000000 10296284864 10739703808 1355454240 14.197 97 41 82 0 1378242128 13576240 9211648
16:14:17 68442 8192000000 10304438016 10742575104 1366536816 14.296 98 34 69 0 1384570080 11148288 6884976
16:16:27 28115 8192000000 10310405248 10761322496 1363645872 14.270 98 37 74 0 1389031280 17096016 8289392
16:18:04 46202 8192000000 10298140832 10732535808 1357202384 14.212 98 39 73 1 1376644288 11974304 7467600
16:19:51 60342 8192000000 10299658256 10756186112 1371706864 14.342 99 17 34 0 1392561088 17432560 3421664
16:21:52 101993 8192000000 10294630352 10748006400 1388400240 14.492 100 26 51 2 1403396320 9558880 5437200
16:24:35 63012 8192000000 10288112240 10752389120 1389686080 14.503 100 16 30 0 1404948944 11680032 3582832
16:26:41 62685 8192000000 10277664352 10742095872 1373940624 14.362 99 14 27 0 1386999104 9550992 3507488
16:28:52 95151 8192000000 10284643648 10740551680 1358802544 14.227 98 18 35 1 1369703680 6932800 3968336
16:31:27 65689 8192000000 10293162192 10757894144 1386729184 14.477 100 15 30 0 1406373776 16462944 3181648
16:33:33 90865 8192000000 10273563600 10736533504 1321651024 13.892 95 12 24 0 1338661920 14307104 2703792
16:36:11 28376 8192000000 10264557440 10713120768 1344190064 14.095 99 29 59 0 1363771040 13558160 6022816
16:37:54 43687 8192000000 9094653296 9337778176 256779072 3.039 19 1 2 0 259451200 2629776 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-07-2022 16:39:41 to 18-07-2022 16:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 182 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 18-07-2022 16:39:56 to 18-07-2022 16:39:58.

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          237618
MON$OLDEST_ACTIVE               237619
MON$OLDEST_SNAPSHOT             237619
MON$NEXT_TRANSACTION            237619
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                       2034144
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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'

18.07.22 16:39:59. 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-07-18 16:40:01 No severe PSQL-related problems occured
18.07.22 16:40:03. End of report.