Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.02.2023 01: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 15388652 kB
MemAvailable 30436812 kB
Buffers 0 kB
Cached 16349196 kB
SwapCached 3576 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-04 01:38:41
DTS_END                         2023-02-04 01:38:41.0460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45234
TRN_ID                          246132
ATT_ID                          601
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: 9232.38 23114 1107885
customer order: creation 176.95 367 21234
customer order: refuse 15.03 361 1804
order to supplier: creation 47.16 1116 5659
order to supplier: removal 3.61 89496 433
invoice (draft): creation 46.88 3424 5626
invoice (draft): removal 54.18 752 6501
invoice accept: apply 42.31 16535 5077
invoice accept: cancel 48.14 714 5777
customer reserve: creation 3831.71 167 459805
customer reserve: removal 4674.67 54 560960
realization accept: apply 85.08 190 10209
realization accept: cancel 75.42 103 9050
payment from customer: creation 60.01 110 7201
payment from customer: removal 6.13 67 735
payment to supplier: creation 49.58 126 5949
payment to supplier: removal 3.32 56 398
service: total inventory turnovers 4.68 1264 561
service: total monetary turnovers 6.68 24 801
service: aggregate perf. data 0.84 18576 101
service: refresh index statistics 0.03 328774 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-02-2023 01:40:18 to 04-02-2023 01:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3872
TEST_TIME 2 3442
TEST_TIME 3 3778
TEST_TIME 4 4935
TEST_TIME 5 4803
TEST_TIME 6 6787
TEST_TIME 7 7226
TEST_TIME 8 6930
TEST_TIME 9 6632
TEST_TIME 10 6421
TEST_TIME 11 6287
TEST_TIME 12 6314
TEST_TIME 13 6161
TEST_TIME 14 6486
TEST_TIME 15 6510
TEST_TIME 16 6334
TEST_TIME 17 6515
TEST_TIME 18 6580
TEST_TIME 19 6547
TEST_TIME 20 6695
TEST_TIME 21 6758
TEST_TIME 22 7186
TEST_TIME 23 7609
TEST_TIME 24 7709
TEST_TIME 25 7721
TEST_TIME 26 8109
TEST_TIME 27 8788
TEST_TIME 28 9226
TEST_TIME 29 9765
TEST_TIME 30 9923
TEST_TIME 31 9848
TEST_TIME 32 9725
TEST_TIME 33 9620
TEST_TIME 34 9616
TEST_TIME 35 9571
TEST_TIME 36 9707
TEST_TIME 37 9495
TEST_TIME 38 9483
TEST_TIME 39 9402
TEST_TIME 40 9324
TEST_TIME 41 9643
TEST_TIME 42 9481
TEST_TIME 43 9411
TEST_TIME 44 9449
TEST_TIME 45 9330
TEST_TIME 46 9330
TEST_TIME 47 9232
TEST_TIME 48 9239
TEST_TIME 49 9154
TEST_TIME 50 9064
TEST_TIME 51 9002
TEST_TIME 52 8962
TEST_TIME 53 8966
TEST_TIME 54 8979
TEST_TIME 55 8984
TEST_TIME 56 8977
TEST_TIME 57 9006
TEST_TIME 58 9072
TEST_TIME 59 9100
TEST_TIME 60 9196
TEST_TIME 61 9224
TEST_TIME 62 9273
TEST_TIME 63 9308
TEST_TIME 64 9259
TEST_TIME 65 9378
TEST_TIME 66 9348
TEST_TIME 67 9425
TEST_TIME 68 9337
TEST_TIME 69 9296
TEST_TIME 70 9256
TEST_TIME 71 9237
TEST_TIME 72 9219
TEST_TIME 73 9183
TEST_TIME 74 9187
TEST_TIME 75 9129
TEST_TIME 76 9162
TEST_TIME 77 9156
TEST_TIME 78 9136
TEST_TIME 79 9268
TEST_TIME 80 9248
TEST_TIME 81 9252
TEST_TIME 82 9252
TEST_TIME 83 9378
TEST_TIME 84 9373
TEST_TIME 85 9369
TEST_TIME 86 9378
TEST_TIME 87 9440
TEST_TIME 88 9410
TEST_TIME 89 9366
TEST_TIME 90 9295
TEST_TIME 91 9255
TEST_TIME 92 9213
TEST_TIME 93 9307
TEST_TIME 94 9269
TEST_TIME 95 9224
TEST_TIME 96 9223
TEST_TIME 97 9229
TEST_TIME 98 9223
TEST_TIME 99 9284
TEST_TIME 100 9327
TEST_TIME 101 9314
TEST_TIME 102 9384
TEST_TIME 103 9395
TEST_TIME 104 9415
TEST_TIME 105 9398
TEST_TIME 106 9454
TEST_TIME 107 9438
TEST_TIME 108 9479
TEST_TIME 109 9456
TEST_TIME 110 9482
TEST_TIME 111 9434
TEST_TIME 112 9382
TEST_TIME 113 9358
TEST_TIME 114 9360
TEST_TIME 115 9322
TEST_TIME 116 9291
TEST_TIME 117 9297
TEST_TIME 118 9267
TEST_TIME 119 9240
TEST_TIME 120 9235

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-02-2023 01:40:21 to 04-02-2023 01:40:25.

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 1533737 1533737 0 0.00 0 20345 6 0 0
doc_list_biud 1533734 1533734 0 0.00 0 27265 25 0 0
sp_add_invoice_to_stock 5202 5077 125 2.40 20 175790 16536 0 125
sp_cancel_adding_invoice 5777 5777 0 0.00 4 53617 715 0 0
sp_cancel_client_order 1804 1804 0 0.00 4 10916 362 0 0
sp_cancel_customer_reserve 560960 560960 0 0.00 0 27165 54 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 2 5427 68 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 1056 56 0 0
sp_cancel_supplier_invoice 6510 6501 9 0.13 1 20263 753 0 9
sp_cancel_supplier_order 433 433 0 0.00 8 997783 89496 0 0
sp_cancel_write_off 9050 9050 0 0.00 1 12119 104 0 0
sp_client_order 21234 21234 0 0.00 3 44927 368 0 0
sp_customer_reserve 459805 459805 0 0.00 0 37977 167 0 0
sp_fill_shopping_cart 32516 32516 0 0.00 1 18065 40 0 0
sp_fill_shopping_cart_clo_res 459801 459801 0 0.00 0 17601 14 0 0
sp_get_clo_for_invoice 5071 5071 0 0.00 0 23319 295 0 0
sp_kill_cost_storno 15960 15960 0 0.00 0 14675 56 0 0
sp_kill_qstorno_ret_qs2qd 1137904 1137904 0 0.00 0 27158 30 0 0
sp_kill_qty_storno 599804 599804 0 0.00 0 27258 64 0 0
sp_lock_selected_doc 21207 21207 0 0.00 0 8159 7 0 0
sp_make_cost_storno 28430 28430 0 0.00 0 20330 57 0 0
sp_multiply_rows_for_pdistr 28429 28429 0 0.00 0 16427 32 0 0
sp_multiply_rows_for_qdistr 21234 21234 0 0.00 0 44645 187 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 16435 111 0 0
sp_pay_to_supplier 5949 5949 0 0.00 4 12680 126 0 0
sp_payment_common 13150 13150 0 0.00 1 9165 33 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 10909 332 0 0
sp_qd_handle_on_invoice_upd_sts 10847 10847 0 0.00 0 27248 289 0 0
sp_qd_handle_on_reserve_upd_sts 10209 10209 0 0.00 0 17698 61 0 0
sp_reserve_write_off 10209 10209 0 0.00 6 20376 191 0 0
sp_supplier_invoice 5626 5626 0 0.00 6 80078 3424 0 0
sp_supplier_order 5659 5659 0 0.00 5 47993 1116 0 0
srv_aggregate_perf_data 102 101 1 0.98 2456 198617 18576 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 823 120295 44056 0 0
srv_find_qd_qs_mism 1059631 1059631 0 0.00 0 7894 5 0 0
srv_make_invnt_saldo 561 561 0 0.00 36 67916 1264 0 0
srv_make_money_saldo 801 801 0 0.00 1 1671 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 252814 506884 328775 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5077 5077 0 0.00 0 19762 180 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5731 74 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 2995 15 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 4107 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 1331 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 3250 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 2383 20 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 22896 1500 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 2276 7 0 0
t$perf-norm:sp_client_order 21234 21234 0 0.00 0 10372 20 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 2320 7 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 8424 12 0 0
t$perf-norm:sp_pay_to_supplier 5949 5949 0 0.00 0 6514 18 0 0
t$perf-norm:sp_reserve_write_off 10209 10209 0 0.00 0 12100 14 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 12787 27 0 0
t$perf-norm:sp_supplier_order 5659 5659 0 0.00 0 7339 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 390 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 574 10 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 1013 21 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 1678 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10388 10138 250 2.40 0 6043 16 0 250
v_all_customers 38198 38198 0 0.00 0 22 0 0 0
v_all_suppliers 31032 31032 0 0.00 0 55 1 0 0
v_all_wares 42468 42468 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 6204 19 0 0
v_cancel_client_order 3608 3608 0 0.00 0 1916 3 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 967 5 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 626 4 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1031 7 0 0
v_cancel_supplier_invoice 868 850 18 2.07 0 1789 17 0 18
v_cancel_supplier_order 866 866 0 0.00 0 2540 7 0 0
v_cancel_write_off 2020 2020 0 0.00 0 891 12 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 4822 3 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 2520 25 0 0
v_max_id_ord_sup 5622 5622 0 0.00 0 5152 6 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 139 2 0 0
v_max_non_paid_realizn 3987 3987 0 0.00 0 4232 7 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 17867 16 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 1612 5 0 0
v_min_id_ord_sup 5622 5622 0 0.00 0 5786 11 0 0
v_min_non_paid_invoice 5949 5949 0 0.00 0 9161 28 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 8528 21 0 0
v_our_firm 10698 10698 0 0.00 0 27 1 0 0
v_reserve_write_off 20418 20418 0 0.00 0 4224 6 0 0
x_lock_depdocs_on_canc_invoice 11853 11853 0 0.00 0 20757 101 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 8763 315 0 0
x_make_qstorno_1000_1200 5659 5659 0 0.00 2 47984 1031 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 2 80049 3277 0 0
x_make_qstorno_2100_3300 459804 459804 0 0.00 0 30058 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-02-2023 01:40:26 to 04-02-2023 01:40:30.

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
23:08:45 5 8192000000 8805311472 8818692096 10467712 0.127 3 2 3 0 10610176 67504 74960
23:09:46 24050 8192000000 9913905488 10226237440 996237584 10.842 100 85 171 0 1024006000 5826560 21941856
23:11:14 19126 8192000000 9971043424 10296107008 1050805840 11.368 100 88 174 1 1080951840 8295008 21850992
23:12:45 21085 8192000000 10019340832 10355433472 1096783840 11.807 100 91 182 0 1128660576 9567360 22309376
23:14:17 13790 8192000000 10054051952 10406064128 1141358448 12.228 100 76 152 2 1169308880 10214176 17736256
23:15:35 6370 8192000000 10107841984 10470678528 1193404528 12.715 100 82 164 1 1224869120 12396976 19067616
23:16:44 3663 8192000000 10132626256 10506788864 1227055776 13.027 100 73 146 0 1257422480 13754560 16612144
23:17:53 2153 8192000000 10169587904 10549440512 1258606432 13.317 100 86 168 0 1289493808 12011744 18875632
23:18:56 1259 8192000000 10204981760 10587963392 1293508368 13.636 100 86 169 1 1324463120 11620928 19333824
23:20:00 731 8192000000 10218407296 10606272512 1307596368 13.764 100 88 175 0 1339872528 12802720 19473440
23:21:04 1009 8192000000 10216760416 10611945472 1311005264 13.795 100 77 153 3 1339268784 11338720 16924800
23:22:06 5838 8192000000 10198068800 10617307136 1318144672 13.860 100 32 64 0 1333780784 8657104 6979008
23:23:12 6851 8192000000 10203405776 10624081920 1321771040 13.893 100 17 33 0 1331195952 6156576 3268336
23:24:22 5748 8192000000 10211794720 10635792384 1333472432 13.999 100 21 43 0 1344097296 5997872 4626992
23:25:30 31616 8192000000 10229731504 10653044736 1333674944 14.000 100 30 58 0 1350249952 9702688 6872320
23:27:02 4841 8192000000 10234512032 10655379456 1344717888 14.100 100 31 62 0 1358233824 6679424 6836512
23:28:07 14495 8192000000 10228209776 10649251840 1323595888 13.909 99 9 19 0 1330813824 5231200 1986736
23:29:26 17257 8192000000 10187684080 10598395904 1248467520 13.224 95 13 26 1 1256032064 4756464 2808080
23:31:18 4942 8192000000 10161130320 10565206016 1233580800 13.087 97 30 58 3 1244928496 4906656 6441040
23:32:24 9961 8192000000 10126650608 10516594688 1179606912 12.587 94 27 54 0 1190773360 4637504 6528944
23:33:36 7345 8192000000 10166204624 10561536000 1262921888 13.357 100 33 66 0 1276529744 5036336 8571520
23:34:44 66511 8192000000 10184697856 10594496512 1284415920 13.553 100 25 50 1 1299927024 9081776 6429328
23:36:52 9352 8192000000 10189875024 10600775680 1297250336 13.670 100 23 45 1 1307047488 5211280 4585872
23:38:02 9911 8192000000 10199355632 10613743616 1305248064 13.743 100 18 36 0 1318337488 9363680 3725744
23:39:16 45684 8192000000 10233188544 10644926464 1331336592 13.979 100 42 84 1 1348304208 7498720 9468896
23:41:18 47062 8192000000 10246526112 10670338048 1344111440 14.094 100 44 86 1 1363926368 9579744 10235184
23:43:10 22513 8192000000 10258574256 10681507840 1356322896 14.204 100 39 78 0 1377232480 11386272 9523312
23:44:59 1567 8192000000 10296040448 10708168704 1373987824 14.363 100 87 172 4 1410770064 17305936 19476304
23:46:06 18319 8192000000 10295183104 10720182272 1385524032 14.466 100 52 104 1 1408633680 11281488 11828160
23:47:39 44926 8192000000 10302710688 10740404224 1396112336 14.560 100 30 60 1 1418661312 15285888 7263088
23:49:34 14608 8192000000 10294480960 10720419840 1378912816 14.407 100 35 69 0 1405049648 17644960 8491872
23:50:55 13101 8192000000 10320333760 10739462144 1391072608 14.515 100 52 104 0 1429779952 25459520 13247824
23:52:21 5959 8192000000 10325994400 10745589760 1393196144 14.534 100 46 91 0 1433695456 29373872 11125440
23:53:35 4662 8192000000 10338799312 10756464640 1386003248 14.470 98 59 119 1 1434454752 32523344 15928160
23:54:46 12060 8192000000 10336675424 10747330560 1380900080 14.425 98 59 117 2 1434992944 39219648 14873216
23:56:04 12498 8192000000 10339157440 10765557760 1400930496 14.603 100 42 82 0 1448224640 36800432 10493712
23:57:25 2126 8192000000 10353826816 10764701696 1399924160 14.594 100 68 133 1 1458961344 41785408 17251776
23:58:33 820 8192000000 10321721872 10716508160 1351637184 14.162 99 87 172 1 1420024320 46569856 21817280
23:59:43 3753 8192000000 10334091664 10741047296 1370374160 14.330 100 69 135 2 1441210816 53202112 17634544
00:00:50 8548 8192000000 10301094288 10714304512 1340532592 14.062 99 40 79 0 1400986976 49555792 10898592
00:02:01 10297 8192000000 10288176384 10708746240 1338201024 14.041 100 25 50 0 1393187760 47491472 7495264
00:03:14 12596 8192000000 10301070320 10722689024 1346391936 14.115 100 32 64 0 1407954192 52621328 8940928
00:05:01 26210 8192000000 10283395984 10710265856 1320308512 13.880 99 30 60 0 1379652528 50833136 8510880
00:06:32 41291 8192000000 10272281168 10717065216 1337337008 14.033 99 19 37 0 1382102912 39472736 5293168
00:08:21 49031 8192000000 10241111952 10666369024 1319894048 13.876 98 25 48 0 1334720176 10053072 4773056
00:10:12 43265 8192000000 10265883616 10708230144 1362397696 14.259 100 23 44 1 1374312240 7055824 4858720
00:12:23 71738 8192000000 10285888960 10729381888 1356467680 14.206 98 27 54 0 1371424592 8920176 6036736
00:14:41 67607 8192000000 10275239872 10729361408 1363881312 14.272 99 13 26 0 1376325008 9748176 2695520
00:16:51 22390 8192000000 10283299984 10726539264 1375426576 14.376 99 20 40 0 1395022704 15268864 4327264
00:18:18 15299 8192000000 10308825104 10747637760 1395322992 14.553 100 27 54 1 1413915136 11891264 6700880
00:19:35 31788 8192000000 10292663984 10763083776 1391689808 14.521 100 28 54 0 1421332080 22879600 6762672
00:21:11 51942 8192000000 10289505024 10744365056 1378995856 14.408 99 11 22 0 1391101984 9404384 2701744
00:23:04 40969 8192000000 10292897696 10752430080 1395068944 14.551 100 24 46 2 1410573552 10096496 5408112
00:24:48 63483 8192000000 10299677168 10753404928 1386024624 14.470 99 21 42 1 1402388320 12088608 4275088
00:27:03 54208 8192000000 10291772896 10750713856 1398714352 14.584 100 18 36 0 1410588624 7092368 4781904
00:29:03 39050 8192000000 10293560720 10754301952 1403919712 14.630 100 21 42 0 1415016160 5959424 5137024
00:30:42 50012 8192000000 10293401424 10752036864 1392122928 14.525 99 7 14 0 1400784720 7077440 1584352
00:32:32 54125 8192000000 10285992720 10744041472 1359306000 14.231 97 8 16 0 1370879216 9818032 1755184
00:34:31 28388 8192000000 10281131808 10726342656 1349810208 14.146 97 30 60 0 1370252208 14306592 6135408
00:36:01 43197 8192000000 10289732992 10735620096 1354156976 14.185 98 24 48 0 1371897408 12146016 5594416
00:37:48 46744 8192000000 10283631632 10733588480 1347350432 14.124 97 23 46 0 1361522656 9119552 5052672
00:40:02 63680 8192000000 10277895248 10728996864 1343100560 14.085 97 6 12 0 1353554224 9393152 1060512
00:42:07 17161 8192000000 10282883200 10712059904 1338073104 14.040 97 36 73 0 1353416000 7285312 8057584
00:43:25 35173 8192000000 10226227040 10669961216 1236178784 13.111 91 26 52 0 1254682816 13091984 5412048
00:45:05 60941 8192000000 10221412048 10662846464 1278507008 13.499 96 23 46 0 1294342112 10933040 4902064
00:47:06 117226 8192000000 10260887120 10707558400 1330554176 13.972 99 31 62 2 1349881344 12491680 6835488
00:50:09 25368 8192000000 10257595472 10700800000 1350225616 14.150 100 25 50 0 1371250112 15378880 5645616
00:52:31 45723 8192000000 10246791392 10696499200 1337696864 14.037 99 12 24 1 1357609136 17166816 2745456
00:54:20 36415 8192000000 10264542064 10710622208 1362040448 14.256 100 17 34 0 1373588928 7689088 3859392
00:55:57 69960 8192000000 10273158832 10730102784 1362442912 14.259 99 14 28 0 1381492096 15604384 3444800
00:58:15 41568 8192000000 10283232480 10723790848 1359464096 14.233 99 26 52 0 1374164304 9115456 5584752
01:00:07 105156 8192000000 10275567776 10729177088 1332692672 13.991 97 22 44 0 1357933616 20018496 5222448
01:02:53 56123 8192000000 10272972016 10724495360 1357994192 14.219 99 14 26 0 1371867856 11295296 2578368
01:04:56 69728 8192000000 10289953920 10751258624 1379893344 14.416 100 8 16 0 1392645760 11217456 1534960
01:07:06 101738 8192000000 10304966144 10753433600 1381922128 14.434 99 46 92 0 1400349440 8358608 10068704
01:09:49 39616 8192000000 10312568112 10762694656 1402680896 14.619 100 23 46 0 1421606128 13821184 5104048
01:11:28 80203 8192000000 10297574784 10777427968 1388975680 14.497 99 9 18 0 1407609504 16079024 2554800
01:13:51 88718 8192000000 10297500992 10757017600 1379859248 14.415 98 14 28 0 1393888896 10990784 3038864
01:16:20 70605 8192000000 10299215408 10766192640 1362318720 14.258 97 19 38 1 1379199872 12711472 4169680
01:18:31 78721 8192000000 10296466496 10751942656 1358882352 14.227 97 11 22 0 1377065456 15830256 2352848
01:20:50 54884 8192000000 10287169088 10737311744 1337780544 14.037 96 16 32 0 1351811632 10849328 3181760
01:22:49 41685 8192000000 10306328464 10750910464 1389104400 14.498 100 33 66 0 1407747392 11645440 6997552
01:25:10 87237 8192000000 10278866480 10739195904 1319649040 13.874 95 15 30 0 1333756240 10876480 3230720
01:27:38 26213 8192000000 10262572928 10706747392 1305830640 13.748 95 24 48 0 1321737888 9847744 6059504
01:29:06 120295 8192000000 10244158912 10689155072 1305522544 13.745 96 18 36 0 1317804192 7849072 4432576
01:32:26 4503 8192000000 10263533840 10708787200 1353149856 14.176 100 44 83 3 1366874496 2953360 10771280
01:33:32 78441 8192000000 10258298960 10710843392 1330645520 13.973 97 11 22 0 1346089120 12773664 2669936
01:35:51 66007 8192000000 10230858656 10682023936 1271373616 13.434 94 15 30 0 1288976864 14122704 3480544
01:37:58 58692 8192000000 9083420528 9319014400 253017280 2.996 19 3 6 0 258348576 4934752 396544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-02-2023 01:40:32 to 04-02-2023 01:40:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 125 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-02-2023 01:40:48 to 04-02-2023 01:40:50.

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

04.02.23 01:40:52. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-04 01:40:54 No severe PSQL-related problems occured
04.02.23 01:40:55. End of report.