Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.06.2022 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 16251300 kB
MemAvailable 31227648 kB
Buffers 0 kB
Cached 15437200 kB
SwapCached 2460 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.10
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-06-19 01:38:20
DTS_END                         2022-06-19 01:38:20.9420
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/34932
TRN_ID                          246216
ATT_ID                          644
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.95 24419 1101234
customer order: creation 168.92 368 20270
customer order: refuse 14.73 348 1767
order to supplier: creation 46.03 1224 5524
order to supplier: removal 3.59 87851 431
invoice (draft): creation 45.49 3463 5459
invoice (draft): removal 51.48 721 6178
invoice accept: apply 41.63 20152 4996
invoice accept: cancel 45.90 753 5508
customer reserve: creation 3856.63 199 462796
customer reserve: removal 4615.83 53 553899
realization accept: apply 81.83 234 9819
realization accept: cancel 75.19 122 9023
payment from customer: creation 59.27 180 7112
payment from customer: removal 5.62 90 674
payment to supplier: creation 49.16 166 5899
payment to supplier: removal 3.16 141 379
service: total inventory turnovers 4.83 1138 580
service: total monetary turnovers 6.85 30 822
service: aggregate perf. data 0.78 22961 94
service: refresh index statistics 0.03 348178 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-06-2022 01:39:27 to 19-06-2022 01:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2889
TEST_TIME 2 3467
TEST_TIME 3 3143
TEST_TIME 4 4036
TEST_TIME 5 3978
TEST_TIME 6 4370
TEST_TIME 7 4511
TEST_TIME 8 4693
TEST_TIME 9 4976
TEST_TIME 10 5232
TEST_TIME 11 5366
TEST_TIME 12 5430
TEST_TIME 13 5597
TEST_TIME 14 6082
TEST_TIME 15 6221
TEST_TIME 16 6573
TEST_TIME 17 7551
TEST_TIME 18 7551
TEST_TIME 19 7934
TEST_TIME 20 8421
TEST_TIME 21 9324
TEST_TIME 22 9156
TEST_TIME 23 9456
TEST_TIME 24 9770
TEST_TIME 25 10487
TEST_TIME 26 10311
TEST_TIME 27 10103
TEST_TIME 28 10105
TEST_TIME 29 10223
TEST_TIME 30 10240
TEST_TIME 31 9942
TEST_TIME 32 9996
TEST_TIME 33 9835
TEST_TIME 34 9969
TEST_TIME 35 9835
TEST_TIME 36 9704
TEST_TIME 37 9567
TEST_TIME 38 9447
TEST_TIME 39 9555
TEST_TIME 40 9475
TEST_TIME 41 9451
TEST_TIME 42 9347
TEST_TIME 43 9247
TEST_TIME 44 9207
TEST_TIME 45 9260
TEST_TIME 46 9170
TEST_TIME 47 9080
TEST_TIME 48 9196
TEST_TIME 49 9221
TEST_TIME 50 9219
TEST_TIME 51 9279
TEST_TIME 52 9265
TEST_TIME 53 9252
TEST_TIME 54 9205
TEST_TIME 55 9466
TEST_TIME 56 9395
TEST_TIME 57 9558
TEST_TIME 58 9693
TEST_TIME 59 9667
TEST_TIME 60 9748
TEST_TIME 61 9641
TEST_TIME 62 9562
TEST_TIME 63 9484
TEST_TIME 64 9478
TEST_TIME 65 9508
TEST_TIME 66 9407
TEST_TIME 67 9317
TEST_TIME 68 9307
TEST_TIME 69 9255
TEST_TIME 70 9313
TEST_TIME 71 9277
TEST_TIME 72 9280
TEST_TIME 73 9278
TEST_TIME 74 9278
TEST_TIME 75 9266
TEST_TIME 76 9304
TEST_TIME 77 9304
TEST_TIME 78 9399
TEST_TIME 79 9385
TEST_TIME 80 9407
TEST_TIME 81 9444
TEST_TIME 82 9457
TEST_TIME 83 9427
TEST_TIME 84 9376
TEST_TIME 85 9302
TEST_TIME 86 9263
TEST_TIME 87 9256
TEST_TIME 88 9230
TEST_TIME 89 9270
TEST_TIME 90 9235
TEST_TIME 91 9217
TEST_TIME 92 9221
TEST_TIME 93 9182
TEST_TIME 94 9261
TEST_TIME 95 9261
TEST_TIME 96 9276
TEST_TIME 97 9274
TEST_TIME 98 9349
TEST_TIME 99 9353
TEST_TIME 100 9343
TEST_TIME 101 9326
TEST_TIME 102 9369
TEST_TIME 103 9389
TEST_TIME 104 9387
TEST_TIME 105 9409
TEST_TIME 106 9482
TEST_TIME 107 9433
TEST_TIME 108 9365
TEST_TIME 109 9355
TEST_TIME 110 9309
TEST_TIME 111 9300
TEST_TIME 112 9281
TEST_TIME 113 9249
TEST_TIME 114 9205
TEST_TIME 115 9169
TEST_TIME 116 9159
TEST_TIME 117 9171
TEST_TIME 118 9200
TEST_TIME 119 9226
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-06-2022 01:39:30 to 19-06-2022 01: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 1529812 1529812 0 0.00 0 38901 7 0 0
doc_list_biud 1529811 1529811 0 0.00 0 20348 25 0 0
sp_add_invoice_to_stock 5111 4996 115 2.25 40 213205 20152 0 115
sp_cancel_adding_invoice 5508 5508 0 0.00 5 68433 754 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 9194 348 0 0
sp_cancel_customer_reserve 553899 553899 0 0.00 1 20350 53 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 6718 90 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 12070 141 0 0
sp_cancel_supplier_invoice 6191 6178 13 0.20 2 17792 722 0 13
sp_cancel_supplier_order 431 431 0 0.00 6 1081730 87851 0 0
sp_cancel_write_off 9023 9023 0 0.00 1 21955 122 0 0
sp_client_order 20270 20270 0 0.00 3 25592 368 0 0
sp_customer_reserve 462796 462796 0 0.00 0 144645 199 0 0
sp_fill_shopping_cart 31252 31252 0 0.00 1 11309 44 0 0
sp_fill_shopping_cart_clo_res 462795 462795 0 0.00 0 13356 15 0 0
sp_get_clo_for_invoice 4995 4995 0 0.00 0 24065 328 0 0
sp_kill_cost_storno 15584 15584 0 0.00 0 21656 64 0 0
sp_kill_qstorno_ret_qs2qd 1123430 1123430 0 0.00 0 20346 29 0 0
sp_kill_qty_storno 591620 591620 0 0.00 0 20347 63 0 0
sp_lock_selected_doc 20508 20508 0 0.00 0 5424 6 0 0
sp_make_cost_storno 27825 27825 0 0.00 0 38848 76 0 0
sp_multiply_rows_for_pdistr 27825 27825 0 0.00 0 21662 44 0 0
sp_multiply_rows_for_qdistr 20269 20269 0 0.00 0 13003 174 0 0
sp_pay_from_customer 7112 7112 0 0.00 4 38276 180 0 0
sp_pay_to_supplier 5899 5899 0 0.00 4 21348 167 0 0
sp_payment_common 13011 13011 0 0.00 2 38266 64 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 9187 312 0 0
sp_qd_handle_on_invoice_upd_sts 10503 10503 0 0.00 0 19277 331 0 0
sp_qd_handle_on_reserve_upd_sts 9819 9819 0 0.00 0 8314 58 0 0
sp_reserve_write_off 9819 9819 0 0.00 6 22146 234 0 0
sp_supplier_invoice 5459 5459 0 0.00 6 102068 3464 0 0
sp_supplier_order 5524 5524 0 0.00 5 37330 1225 0 0
srv_aggregate_perf_data 94 94 0 0.00 2659 364720 22961 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1684 135920 50053 0 0
srv_find_qd_qs_mism 1053685 1053685 0 0.00 0 19948 6 0 0
srv_make_invnt_saldo 580 580 0 0.00 24 61349 1138 0 0
srv_make_money_saldo 822 822 0 0.00 0 2793 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 258460 470405 348179 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 11194 193 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 0 3981 110 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 4785 19 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 6149 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1660 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 877 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1500 22 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 24876 1518 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 2167 8 0 0
t$perf-norm:sp_client_order 20270 20270 0 0.00 0 10956 19 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 6517 17 0 0
t$perf-norm:sp_pay_from_customer 7112 7112 0 0.00 0 7087 11 0 0
t$perf-norm:sp_pay_to_supplier 5899 5899 0 0.00 0 4902 14 0 0
t$perf-norm:sp_reserve_write_off 9819 9819 0 0.00 0 11743 14 0 0
t$perf-norm:sp_supplier_invoice 5459 5459 0 0.00 0 9253 22 0 0
t$perf-norm:sp_supplier_order 5524 5524 0 0.00 0 9067 20 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 243 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 2085 28 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 2421 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10218 9988 230 2.25 0 3551 20 0 230
v_all_customers 37012 37012 0 0.00 0 56 1 0 0
v_all_suppliers 30596 30596 0 0.00 0 17 1 0 0
v_all_wares 40538 40538 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 2457 8 0 0
v_cancel_client_order 3534 3534 0 0.00 0 5799 12 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 6712 14 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 2828 8 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 5115 16 0 0
v_cancel_supplier_invoice 814 788 26 3.19 0 418 13 0 26
v_cancel_supplier_order 862 862 0 0.00 0 3437 12 0 0
v_cancel_write_off 2134 2134 0 0.00 0 3031 15 0 0
v_max_id_clo_ord 5524 5524 0 0.00 0 2207 2 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 4101 11 0 0
v_max_id_ord_sup 5459 5459 0 0.00 0 1594 3 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 10314 9 0 0
v_max_non_paid_realizn 3820 3820 0 0.00 0 2121 5 0 0
v_min_id_clo_ord 5524 5524 0 0.00 0 11060 14 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 2737 11 0 0
v_min_id_ord_sup 5459 5459 0 0.00 0 5272 14 0 0
v_min_non_paid_invoice 5899 5899 0 0.00 0 19774 41 0 0
v_min_non_paid_realizn 7112 7112 0 0.00 0 19173 61 0 0
v_our_firm 10110 10110 0 0.00 0 14 1 0 0
v_reserve_write_off 19638 19638 0 0.00 0 6425 8 0 0
x_lock_depdocs_on_canc_invoice 11292 11292 0 0.00 0 13135 121 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 9881 345 0 0
x_make_qstorno_1000_1200 5524 5524 0 0.00 1 26064 1135 0 0
x_make_qstorno_1200_2000 5459 5459 0 0.00 3 102019 3308 0 0
x_make_qstorno_2100_3300 462796 462796 0 0.00 0 144643 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2022 01:39:35 to 19-06-2022 01: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
23:08:24 4 8192000000 8804537504 8817577984 10118656 0.123 3 2 3 0 10261104 67504 74944
23:09:26 27226 8192000000 9896272800 10203992064 979155552 10.676 100 90 180 0 1008869408 6129728 23584128
23:10:58 12237 8192000000 9943558592 10261921792 1020250608 11.074 100 91 182 0 1052258560 8430368 23577584
23:12:25 16314 8192000000 9976414560 10311507968 1058739584 11.444 100 80 160 1 1088467360 9969392 19758384
23:13:43 7641 8192000000 10044488480 10394488832 1131462960 12.135 100 74 148 0 1159831392 10546560 17821872
23:14:57 8219 8192000000 10092490832 10455212032 1186254016 12.648 100 76 153 0 1215905616 11828704 17822896
23:16:08 6725 8192000000 10139797184 10513854464 1231426976 13.067 100 77 154 0 1259887216 11196560 17263680
23:17:19 8627 8192000000 10176453520 10552049664 1256344864 13.296 100 85 170 0 1289925952 15258384 18322704
23:18:29 2272 8192000000 10204368992 10587717632 1291352384 13.617 100 91 181 2 1324864976 13407328 20105264
23:19:32 38497 8192000000 10185423152 10600431616 1302179680 13.715 100 29 59 0 1314203760 5620288 6403792
23:21:11 11135 8192000000 10207739728 10621472768 1311955216 13.804 100 20 40 0 1331126912 14427520 4744176
23:22:23 8609 8192000000 10205306208 10622910464 1321221104 13.888 100 24 48 2 1332772368 5832928 5718336
23:23:34 5776 8192000000 10223454800 10638659584 1328876608 13.957 100 34 66 2 1344161728 8031840 7253280
23:24:40 10676 8192000000 10249682320 10646724608 1333126656 13.995 100 65 129 3 1353708144 5186688 15394800
23:25:52 4804 8192000000 10229761520 10652225536 1340581968 14.063 100 15 30 0 1349520128 5453184 3484976
23:27:00 5338 8192000000 10221444304 10644205568 1333436048 13.998 100 15 28 0 1342999792 6567808 2995936
23:28:07 10002 8192000000 10170737232 10573848576 1196883760 12.747 91 20 42 0 1206183584 4901136 4398688
23:29:17 36861 8192000000 10157778960 10568306688 1244643424 13.189 97 46 90 1 1262686496 6453888 11589184
23:30:55 8782 8192000000 10150640640 10550202368 1232974432 13.081 98 30 59 3 1245641744 5570816 7096496
23:32:04 5517 8192000000 10147356928 10546757632 1217613984 12.940 96 36 71 4 1232012256 6404672 7993600
23:33:10 15626 8192000000 10173469760 10583187456 1281523376 13.527 100 25 50 0 1293050896 5355808 6171712
23:34:28 32927 8192000000 10182730304 10593910784 1291776384 13.620 100 28 55 2 1307775392 9422480 6576528
23:36:03 10011 8192000000 10188010832 10605944832 1300579856 13.701 100 24 48 0 1311745728 5329808 5836064
23:37:15 11562 8192000000 10205722384 10618130432 1308608320 13.773 100 25 52 0 1320869968 6290016 5971632
23:38:35 99268 8192000000 10259444272 10672410624 1340405632 14.061 100 37 73 1 1355248128 5808800 9033696
23:41:17 91808 8192000000 10276403920 10701365248 1363221696 14.266 100 37 71 0 1388602384 16462816 8917872
23:43:54 18103 8192000000 10293686096 10716196864 1378300448 14.401 100 37 72 3 1403903856 17056960 8546448
23:45:15 13412 8192000000 10305372176 10724921344 1374887904 14.371 99 53 106 1 1407177280 20177248 12112128
23:46:33 22114 8192000000 10307293584 10731446272 1384051088 14.453 100 48 96 1 1422740352 26742016 11947248
23:48:01 6950 8192000000 10327416784 10741354496 1373540624 14.359 98 62 124 1 1415961136 27682656 14737856
23:49:13 4487 8192000000 10315912112 10731446272 1370484016 14.331 98 64 128 1 1415165776 28998416 15683344
23:50:21 3161 8192000000 10321148304 10730610688 1382523568 14.439 100 70 140 2 1430898672 30423392 17951712
23:51:33 4756 8192000000 10336557760 10745982976 1389740624 14.504 100 64 128 0 1442970624 37652512 15577488
23:52:42 3845 8192000000 10336073584 10732163072 1371716736 14.342 100 91 183 1 1437926496 43297056 22912704
23:53:52 2559 8192000000 10325277616 10723606528 1352542048 14.170 99 88 176 0 1426253040 51268704 22442288
23:55:00 1682 8192000000 10312959936 10717061120 1342139664 14.077 100 84 167 2 1413140320 50209216 20791440
23:56:05 2522 8192000000 10309009312 10710720512 1352037200 14.166 100 78 156 2 1413298064 41840048 19420816
23:57:14 2806 8192000000 10342846976 10741551104 1370704816 14.333 100 89 178 0 1439348640 46537552 22106272
23:58:20 5394 8192000000 10315944736 10732736512 1357810544 14.218 99 63 126 1 1414121072 40254304 16056224
23:59:36 28362 8192000000 10304019792 10738487296 1375941664 14.380 100 33 66 1 1423071456 38060144 9069648
00:01:15 21397 8192000000 10302194976 10739245056 1371035776 14.336 100 19 38 0 1405648896 28932256 5680864
00:02:43 19034 8192000000 10303802448 10735235072 1360384688 14.241 99 36 72 0 1396503408 26243488 9875232
00:04:07 49415 8192000000 10289533248 10721751040 1348429152 14.133 98 36 70 0 1371514240 14487344 8597744
00:06:15 135919 8192000000 10290271808 10737586176 1354180848 14.185 98 18 36 0 1378703040 20258192 4264000
00:09:38 99477 8192000000 10318156832 10765389824 1398604848 14.583 100 42 82 1 1415042800 7578304 8859648
00:12:25 33601 8192000000 10289526736 10754113536 1376803136 14.388 98 5 10 0 1392184688 14399888 981664
00:14:00 33373 8192000000 10308533344 10760552448 1402680976 14.619 100 14 28 0 1418767248 13143280 2942992
00:15:39 55574 8192000000 10301073536 10746556416 1378225232 14.401 98 20 40 0 1396722768 14141552 4355984
00:17:36 60066 8192000000 10307957072 10758549504 1400718960 14.601 100 16 32 1 1411294096 6610720 3964416
00:19:36 70703 8192000000 10289607520 10749853696 1384382832 14.456 98 14 28 0 1398500912 10980256 3137824
00:21:48 39998 8192000000 10309029792 10766606336 1392720272 14.530 98 28 56 2 1413795632 14667408 6407952
00:23:28 76425 8192000000 10311098720 10769440768 1398039904 14.578 99 19 38 0 1415112672 12745584 4327184
00:26:15 50196 8192000000 10299573056 10761912320 1390433424 14.510 99 9 18 0 1407128432 14827232 1867776
00:28:06 39337 8192000000 10304527488 10760065024 1387678832 14.485 99 18 36 0 1403541088 11542976 4319280
00:29:54 39186 8192000000 10322662400 10772676608 1381403744 14.429 99 24 48 0 1402856576 15149856 6302976
00:31:37 81943 8192000000 10306789792 10761535488 1355932608 14.201 97 14 28 1 1382187728 22617024 3638096
00:34:00 22750 8192000000 10282597376 10721013760 1316437456 13.844 95 30 60 1 1339691072 16045312 7208304
00:35:31 28900 8192000000 10277982800 10726895616 1344758496 14.100 98 28 56 2 1374182000 22547184 6876320
00:37:05 54559 8192000000 10244436944 10684919808 1263176704 13.359 93 15 30 0 1279557280 12856048 3524528
00:39:42 71274 8192000000 10247448448 10682748928 1319793440 13.875 97 35 70 1 1335733104 8447408 7492256
00:42:06 18773 8192000000 10263048912 10702565376 1337321504 14.033 98 49 99 2 1357755376 9282720 11151152
00:43:26 60120 8192000000 10229234016 10683518976 1300483936 13.700 96 17 34 1 1313471792 9245376 3742480
00:45:26 72378 8192000000 10263320832 10721656832 1361441792 14.250 100 11 22 0 1377107936 12838608 2827536
00:47:39 68032 8192000000 10249399200 10707111936 1343441968 14.088 99 19 38 1 1362273824 14405344 4426512
00:49:47 67099 8192000000 10247610544 10703679488 1301422160 13.708 96 4 8 1 1310037584 7983280 632144
00:52:00 69470 8192000000 10262409456 10711683072 1336128944 14.022 98 12 24 0 1347676144 8960736 2586464
00:54:13 84936 8192000000 10281465424 10737020928 1356713696 14.208 99 18 36 3 1374701536 13914176 4073664
00:56:41 98978 8192000000 10289697568 10743205888 1365710400 14.289 99 13 26 0 1377744304 8948336 3085568
00:59:27 106163 8192000000 10279303616 10736345088 1328742800 13.956 96 4 8 0 1341502512 12001584 758128
01:02:19 122545 8192000000 10305911504 10746777600 1375495376 14.376 99 36 72 2 1393668464 9717184 8455904
01:05:24 1984 8192000000 10292195424 10743410688 1375631376 14.377 99 38 76 1 1393761024 9803744 8325904
01:06:35 53825 8192000000 10297786960 10772594688 1397808160 14.575 100 11 22 0 1423817216 22829200 3179856
01:08:49 84124 8192000000 10299192880 10771009536 1391916528 14.523 99 19 38 1 1409920640 13569936 4434176
01:11:15 67518 8192000000 10303512400 10764869632 1355991360 14.201 96 27 53 0 1372504496 10648768 5864368
01:13:24 82321 8192000000 10287216736 10752241664 1370787568 14.334 98 16 32 1 1386799088 12561280 3450240
01:15:54 82694 8192000000 10290637904 10762383360 1352216400 14.167 97 6 12 0 1368716656 15305632 1194624
01:18:17 29778 8192000000 10297201824 10748686336 1378242000 14.401 99 19 38 0 1396821648 14633168 3946480
01:19:48 27527 8192000000 10301265472 10741112832 1354888160 14.191 98 44 88 0 1379616592 14493008 10235424
01:21:18 27876 8192000000 10287429104 10732625920 1360892816 14.245 99 24 48 1 1380746672 14356528 5497328
01:22:47 47182 8192000000 10292557392 10729938944 1340635840 14.063 98 36 72 1 1360433168 12105792 7691536
01:24:39 60496 8192000000 10284572304 10722332672 1322581040 13.900 96 29 58 1 1338609392 9531856 6496496
01:26:53 85410 8192000000 10237084272 10682142720 1287057584 13.577 94 24 48 1 1299690848 7037792 5595472
01:29:18 70968 8192000000 10235599696 10689867776 1312114096 13.805 97 11 21 0 1321013120 7004928 1894096
01:31:52 69435 8192000000 10241848304 10686382080 1307880000 13.767 98 9 18 0 1321058544 11125264 2053280
01:34:14 84729 8192000000 10240928704 10694647808 1305747360 13.747 97 14 28 1 1320605168 11610144 3247664
01:36:56 64503 8192000000 10221330992 10670616576 1253266896 13.268 94 17 34 0 1272058240 14683360 4107984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-06-2022 01:39:41 to 19-06-2022 01:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-06-2022 01:39:56 to 19-06-2022 01: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          245641
MON$OLDEST_ACTIVE               246406
MON$OLDEST_SNAPSHOT             246406
MON$NEXT_TRANSACTION            246406
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                       2032672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Jun 19 01:38:26 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Sun Jun 19 01:38:26 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
--- end of diff output ---

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

19.06.22 01: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-06-19 01:40:02 No severe PSQL-related problems occured
19.06.22 01:40:03. End of report.