Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2023 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 15428360 kB
MemAvailable 30375156 kB
Buffers 0 kB
Cached 16263920 kB
SwapCached 5756 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-01-02 16:38:39
DTS_END                         2023-01-02 16:38:39.2750
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/57534
TRN_ID                          247325
ATT_ID                          710
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: 9158.23 21123 1098988
customer order: creation 177.33 341 21280
customer order: refuse 16.08 332 1930
order to supplier: creation 46.23 1208 5547
order to supplier: removal 3.38 92966 405
invoice (draft): creation 48.22 3156 5786
invoice (draft): removal 49.79 692 5975
invoice accept: apply 43.93 17302 5271
invoice accept: cancel 44.80 779 5376
customer reserve: creation 3988.08 174 478569
customer reserve: removal 4440.98 56 532917
realization accept: apply 86.98 158 10438
realization accept: cancel 74.45 113 8934
payment from customer: creation 62.23 102 7468
payment from customer: removal 6.33 98 759
payment to supplier: creation 52.08 109 6250
payment to supplier: removal 3.77 79 452
service: total inventory turnovers 5.50 921 660
service: total monetary turnovers 7.22 23 866
service: aggregate perf. data 0.84 22612 101
service: refresh index statistics 0.03 281240 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2023 16:39:35 to 02-01-2023 16:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3677
TEST_TIME 2 4326
TEST_TIME 3 4741
TEST_TIME 4 5513
TEST_TIME 5 5089
TEST_TIME 6 4884
TEST_TIME 7 5249
TEST_TIME 8 5346
TEST_TIME 9 5754
TEST_TIME 10 5968
TEST_TIME 11 6201
TEST_TIME 12 6821
TEST_TIME 13 6766
TEST_TIME 14 7071
TEST_TIME 15 7246
TEST_TIME 16 7540
TEST_TIME 17 7720
TEST_TIME 18 7886
TEST_TIME 19 8425
TEST_TIME 20 8944
TEST_TIME 21 9103
TEST_TIME 22 9414
TEST_TIME 23 9340
TEST_TIME 24 9794
TEST_TIME 25 9827
TEST_TIME 26 10226
TEST_TIME 27 10295
TEST_TIME 28 11039
TEST_TIME 29 10667
TEST_TIME 30 10576
TEST_TIME 31 10407
TEST_TIME 32 10397
TEST_TIME 33 10429
TEST_TIME 34 10248
TEST_TIME 35 10093
TEST_TIME 36 10013
TEST_TIME 37 9934
TEST_TIME 38 9768
TEST_TIME 39 9890
TEST_TIME 40 9688
TEST_TIME 41 9612
TEST_TIME 42 9428
TEST_TIME 43 9469
TEST_TIME 44 9314
TEST_TIME 45 9210
TEST_TIME 46 9246
TEST_TIME 47 9300
TEST_TIME 48 9340
TEST_TIME 49 9362
TEST_TIME 50 9466
TEST_TIME 51 9381
TEST_TIME 52 9407
TEST_TIME 53 9397
TEST_TIME 54 9436
TEST_TIME 55 9434
TEST_TIME 56 9427
TEST_TIME 57 9400
TEST_TIME 58 9437
TEST_TIME 59 9486
TEST_TIME 60 9490
TEST_TIME 61 9363
TEST_TIME 62 9361
TEST_TIME 63 9284
TEST_TIME 64 9256
TEST_TIME 65 9232
TEST_TIME 66 9150
TEST_TIME 67 9142
TEST_TIME 68 9162
TEST_TIME 69 9216
TEST_TIME 70 9228
TEST_TIME 71 9312
TEST_TIME 72 9295
TEST_TIME 73 9294
TEST_TIME 74 9310
TEST_TIME 75 9313
TEST_TIME 76 9354
TEST_TIME 77 9411
TEST_TIME 78 9390
TEST_TIME 79 9358
TEST_TIME 80 9437
TEST_TIME 81 9454
TEST_TIME 82 9401
TEST_TIME 83 9373
TEST_TIME 84 9355
TEST_TIME 85 9320
TEST_TIME 86 9276
TEST_TIME 87 9215
TEST_TIME 88 9162
TEST_TIME 89 9215
TEST_TIME 90 9178
TEST_TIME 91 9162
TEST_TIME 92 9171
TEST_TIME 93 9280
TEST_TIME 94 9263
TEST_TIME 95 9278
TEST_TIME 96 9334
TEST_TIME 97 9292
TEST_TIME 98 9319
TEST_TIME 99 9330
TEST_TIME 100 9392
TEST_TIME 101 9350
TEST_TIME 102 9312
TEST_TIME 103 9403
TEST_TIME 104 9357
TEST_TIME 105 9293
TEST_TIME 106 9271
TEST_TIME 107 9267
TEST_TIME 108 9216
TEST_TIME 109 9178
TEST_TIME 110 9151
TEST_TIME 111 9131
TEST_TIME 112 9120
TEST_TIME 113 9119
TEST_TIME 114 9126
TEST_TIME 115 9135
TEST_TIME 116 9179
TEST_TIME 117 9190
TEST_TIME 118 9161
TEST_TIME 119 9189
TEST_TIME 120 9160

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2023 16:39:39 to 02-01-2023 16:39:42.

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 1542091 1542091 0 0.00 0 22536 6 0 0
doc_list_biud 1542090 1542090 0 0.00 0 26123 25 0 0
sp_add_invoice_to_stock 5357 5271 86 1.60 22 170465 17302 0 86
sp_cancel_adding_invoice 5376 5376 0 0.00 4 63692 780 0 0
sp_cancel_client_order 1930 1930 0 0.00 4 9879 333 0 0
sp_cancel_customer_reserve 532917 532917 0 0.00 0 22846 57 0 0
sp_cancel_pay_from_customer 759 759 0 0.00 2 7350 99 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 2 2853 79 0 0
sp_cancel_supplier_invoice 5979 5975 4 0.06 1 24199 692 0 4
sp_cancel_supplier_order 405 405 0 0.00 5 1094001 92966 0 0
sp_cancel_write_off 8934 8934 0 0.00 1 14146 114 0 0
sp_client_order 21280 21280 0 0.00 3 19184 342 0 0
sp_customer_reserve 478569 478569 0 0.00 0 37687 175 0 0
sp_fill_shopping_cart 32609 32609 0 0.00 0 9721 40 0 0
sp_fill_shopping_cart_clo_res 478566 478566 0 0.00 0 13380 12 0 0
sp_get_clo_for_invoice 5268 5268 0 0.00 0 30743 320 0 0
sp_kill_cost_storno 15521 15521 0 0.00 0 8715 53 0 0
sp_kill_qstorno_ret_qs2qd 1081148 1081148 0 0.00 0 24189 30 0 0
sp_kill_qty_storno 571243 571243 0 0.00 0 26122 66 0 0
sp_lock_selected_doc 21831 21831 0 0.00 0 7431 7 0 0
sp_make_cost_storno 29424 29424 0 0.00 0 11979 47 0 0
sp_multiply_rows_for_pdistr 29424 29424 0 0.00 0 5988 24 0 0
sp_multiply_rows_for_qdistr 21280 21280 0 0.00 0 9511 161 0 0
sp_pay_from_customer 7468 7468 0 0.00 4 11620 102 0 0
sp_pay_to_supplier 6250 6250 0 0.00 5 10414 110 0 0
sp_payment_common 13718 13718 0 0.00 1 7898 24 0 0
sp_qd_handle_on_cancel_clo 1930 1930 0 0.00 0 9854 295 0 0
sp_qd_handle_on_invoice_upd_sts 10644 10644 0 0.00 0 26121 308 0 0
sp_qd_handle_on_reserve_upd_sts 10438 10438 0 0.00 0 17355 46 0 0
sp_reserve_write_off 10438 10438 0 0.00 6 17663 159 0 0
sp_supplier_invoice 5786 5786 0 0.00 7 67864 3157 0 0
sp_supplier_order 5547 5547 0 0.00 4 24835 1209 0 0
srv_aggregate_perf_data 101 101 0 0.00 2271 410286 22612 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1679 124469 42682 0 0
srv_find_qd_qs_mism 1049205 1049205 0 0.00 0 22535 6 0 0
srv_make_invnt_saldo 660 660 0 0.00 26 89507 922 0 0
srv_make_money_saldo 866 866 0 0.00 1 693 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 191000 364740 281241 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5271 5271 0 0.00 0 7980 153 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 12855 163 0 0
t$perf-norm:sp_cancel_client_order 1930 1930 0 0.00 0 4859 15 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 9016 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 759 759 0 0.00 0 1568 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 1285 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1754 22 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 24067 1521 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 1972 7 0 0
t$perf-norm:sp_client_order 21280 21280 0 0.00 0 9569 17 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 3025 8 0 0
t$perf-norm:sp_pay_from_customer 7468 7468 0 0.00 0 5965 12 0 0
t$perf-norm:sp_pay_to_supplier 6250 6250 0 0.00 0 7364 14 0 0
t$perf-norm:sp_reserve_write_off 10438 10438 0 0.00 0 6813 14 0 0
t$perf-norm:sp_supplier_invoice 5786 5786 0 0.00 0 6062 20 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 6732 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 122 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 136 3 0 0
t$perf-norm:srv_make_invnt_saldo 660 660 0 0.00 0 1478 19 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 3631 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10700 10528 172 1.60 0 7841 18 0 172
v_all_customers 38730 38730 0 0.00 0 22 1 0 0
v_all_suppliers 31734 31734 0 0.00 0 27 1 0 0
v_all_wares 42560 42560 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 3777 13 0 0
v_cancel_client_order 3860 3860 0 0.00 0 2645 6 0 0
v_cancel_customer_prepayment 1518 1518 0 0.00 0 7274 16 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 7493 10 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 2384 9 0 0
v_cancel_supplier_invoice 830 822 8 0.96 0 186 12 0 8
v_cancel_supplier_order 810 810 0 0.00 0 1650 6 0 0
v_cancel_write_off 2084 2084 0 0.00 0 2672 14 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 436 1 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 2489 14 0 0
v_max_id_ord_sup 5783 5783 0 0.00 0 5170 7 0 0
v_max_non_paid_invoice 1712 1712 0 0.00 0 1094 4 0 0
v_max_non_paid_realizn 4026 4026 0 0.00 0 1360 4 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 3633 5 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 1295 5 0 0
v_min_id_ord_sup 5783 5783 0 0.00 0 6684 5 0 0
v_min_non_paid_invoice 6250 6250 0 0.00 0 7893 14 0 0
v_min_non_paid_realizn 7468 7468 0 0.00 0 6106 18 0 0
v_our_firm 10714 10714 0 0.00 0 22 1 0 0
v_reserve_write_off 20876 20876 0 0.00 0 7027 7 0 0
x_lock_depdocs_on_canc_invoice 10940 10940 0 0.00 0 16443 120 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 12451 406 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 1 24827 1137 0 0
x_make_qstorno_1200_2000 5786 5786 0 0.00 2 62222 3000 0 0
x_make_qstorno_2100_3300 478569 478569 0 0.00 0 37669 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2023 16:39:43 to 02-01-2023 16:39:47.

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:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:44 32332 8192000000 9907789440 10218381312 983389008 10.717 100 87 174 0 1013422080 6319488 23713584
14:11:21 8687 8192000000 9943567952 10262118400 1024017232 11.111 100 89 178 1 1055609504 8663680 22928592
14:12:37 9542 8192000000 9966262512 10294525952 1049334272 11.354 100 89 178 0 1081763056 9702512 22726272
14:13:55 12908 8192000000 10010139664 10354225152 1097608912 11.815 100 79 158 0 1126687776 10450128 18628736
14:15:12 5523 8192000000 10052904608 10408054784 1143484112 12.248 100 78 156 0 1173399952 11816320 18099520
14:16:20 4485 8192000000 10109371504 10477150208 1198729664 12.765 100 77 154 0 1227515024 11443616 17341744
14:17:27 2109 8192000000 10162788144 10534547456 1246849920 13.209 100 90 177 0 1279746656 13597600 19299136
14:18:31 1070 8192000000 10193212768 10570514432 1272222256 13.442 100 85 171 1 1304306992 13768112 18316624
14:19:33 1359 8192000000 10205591264 10590625792 1295571184 13.655 100 82 162 0 1326299120 12729696 17998240
14:20:36 7522 8192000000 10202112800 10597826560 1302305712 13.716 100 68 132 1 1327249008 10055392 14887904
14:21:44 7242 8192000000 10212685072 10619289600 1318333120 13.862 100 31 63 0 1332497952 7451440 6713392
14:22:55 15706 8192000000 10206415680 10628046848 1326987408 13.940 100 17 35 0 1336572496 5935584 3649504
14:24:12 24595 8192000000 10216430624 10642092032 1332808016 13.993 100 34 66 1 1346636320 4899968 8928336
14:25:38 3976 8192000000 10230582016 10650607616 1337781840 14.037 100 28 56 2 1349932512 5840224 6310448
14:26:43 12678 8192000000 10238430960 10660999168 1339607136 14.054 100 16 32 0 1352566432 9504528 3454768
14:27:58 42098 8192000000 10218389312 10632359936 1290464432 13.608 97 48 96 0 1307895456 5970848 11460176
14:29:46 5422 8192000000 10157560976 10556166144 1249106480 13.230 97 27 55 0 1260640592 5465504 6068608
14:30:52 9146 8192000000 10115004608 10513829888 1197963680 12.757 97 16 32 1 1206572272 5158272 3450320
14:32:04 11281 8192000000 10133781984 10526388224 1235016336 13.100 99 27 54 2 1246267936 5108880 6142720
14:33:18 13637 8192000000 10144218432 10552680448 1254008784 13.275 100 20 40 1 1268054784 9463344 4582656
14:34:33 6785 8192000000 10165177056 10568830976 1261233184 13.341 99 39 78 2 1276322256 6116256 8972816
14:35:41 10698 8192000000 10189350800 10595237888 1286995040 13.577 100 20 40 0 1297189520 5187936 5006544
14:36:55 10609 8192000000 10194720768 10611802112 1300957376 13.704 100 11 22 0 1312600864 9323536 2319952
14:38:09 36971 8192000000 10214917840 10631131136 1314106672 13.823 100 27 51 1 1325145664 4651792 6387200
14:39:54 26070 8192000000 10252850928 10670538752 1343680560 14.091 100 38 76 1 1362278176 10525664 8071952
14:41:24 21586 8192000000 10267208096 10687959040 1359691792 14.235 100 50 100 0 1383937664 13129696 11116176
14:42:50 30938 8192000000 10287856784 10710786048 1373450448 14.358 100 34 66 0 1395009200 13691344 7867408
14:44:24 10705 8192000000 10317061920 10736345088 1393725712 14.539 100 53 104 1 1427107104 21103408 12277984
14:45:40 3010 8192000000 10322728256 10744717312 1389086080 14.498 99 51 100 0 1424594336 22631424 12876832
14:46:46 6631 8192000000 10332626624 10753957888 1406106240 14.649 100 59 118 1 1445766304 25562928 14097136
14:47:56 3699 8192000000 10346183168 10767945728 1412213904 14.704 100 55 110 1 1454547808 28359088 13974816
14:49:05 2896 8192000000 10364342880 10781458432 1422071392 14.791 100 75 146 1 1471119872 31945824 17102656
14:50:12 7695 8192000000 10359211216 10790449152 1424012480 14.808 100 52 102 0 1476007488 38791968 13203040
14:51:27 2728 8192000000 10362573952 10776334336 1400188144 14.597 99 72 139 1 1455891376 38435904 17267328
14:52:34 2699 8192000000 10370369872 10785783808 1415677136 14.734 100 78 151 0 1474379456 40140864 18561456
14:53:42 2572 8192000000 10351252768 10759081984 1392972224 14.532 100 77 152 0 1452853664 40594256 19287184
14:54:48 1675 8192000000 10317734192 10727157760 1350952128 14.156 99 73 146 1 1415005712 45272768 18780816
14:56:00 6974 8192000000 10335020432 10747527168 1370727168 14.334 100 62 123 0 1434429312 48317424 15384720
14:57:10 8171 8192000000 10316890960 10746986496 1367718560 14.307 100 45 90 1 1422359936 42804112 11837264
14:58:38 10393 8192000000 10302919728 10733273088 1353961520 14.183 99 31 62 0 1398926272 35984528 8980224
14:59:53 3608 8192000000 10326060064 10747445248 1376671728 14.387 100 54 107 0 1427399200 37895568 12831904
15:01:06 29335 8192000000 10301915776 10730233856 1350600736 14.153 99 24 48 0 1401378288 44350384 6427168
15:02:40 15308 8192000000 10289551392 10718740480 1349668016 14.144 99 25 50 0 1384830864 28821584 6341264
15:03:58 34488 8192000000 10262909264 10693492736 1295342112 13.653 97 24 46 1 1338689584 36853808 6493664
15:05:33 49088 8192000000 10251759728 10689245184 1311146912 13.796 99 13 26 0 1348441808 33489984 3804912
15:07:23 53212 8192000000 10252225904 10681585664 1333999120 14.003 100 32 63 1 1354730544 13610448 7120976
15:09:42 16436 8192000000 10265047520 10696232960 1355722160 14.199 100 33 61 1 1370041152 7388768 6930224
15:11:10 61466 8192000000 10270169888 10726412288 1369995504 14.327 100 14 27 0 1383568320 10548224 3024592
15:13:39 72487 8192000000 10282644656 10742046720 1369171520 14.320 99 10 20 1 1382805088 11418592 2214976
15:16:31 48867 8192000000 10286879952 10755239936 1387796064 14.486 100 10 20 0 1402879760 12568848 2514848
15:18:21 124469 8192000000 10290212416 10753077248 1378164976 14.400 99 24 48 1 1390446480 7008416 5273088
15:21:35 35032 8192000000 10289849328 10758647808 1391881152 14.523 100 13 26 0 1408042688 12951184 3210352
15:23:11 49914 8192000000 10292076912 10758164480 1385291920 14.464 100 12 24 0 1406293952 18250656 2751376
15:25:15 73246 8192000000 10280022640 10752937984 1351361872 14.160 97 9 18 0 1368465968 14973024 2131072
15:27:30 77718 8192000000 10293150928 10760556544 1392899344 14.532 100 15 31 1 1409800176 13356656 3544176
15:29:49 25467 8192000000 10303118656 10759307264 1391187392 14.516 99 38 76 0 1409955872 10425984 8342496
15:31:15 30155 8192000000 10302934112 10758623232 1372849056 14.353 98 23 45 0 1389394384 11926016 4619312
15:32:51 35201 8192000000 10310812176 10758041600 1376755920 14.388 98 23 44 0 1389878512 8210224 4912368
15:34:29 14071 8192000000 10308601888 10752454656 1390868624 14.514 100 59 117 2 1412232240 7154144 14209472
15:35:53 38622 8192000000 10302651840 10749689856 1382799456 14.442 100 35 70 0 1401544336 11426976 7317904
15:37:32 67214 8192000000 10256842160 10711666688 1264375328 13.370 92 18 36 0 1278301312 9804320 4121664
15:39:40 41529 8192000000 10276090400 10724450304 1371491056 14.340 100 32 64 1 1390798896 12392448 6915392
15:41:24 20410 8192000000 10258546256 10695651328 1341652576 14.072 99 37 74 0 1358143696 7997952 8493168
15:42:50 31585 8192000000 10262663216 10709012480 1354810912 14.191 99 35 68 1 1376127552 13838240 7478400
15:44:27 42715 8192000000 10256178816 10709770240 1340657776 14.063 98 11 22 0 1358989552 15472992 2858784
15:47:37 72120 8192000000 10259187392 10700349440 1274037920 13.459 93 17 31 0 1288914480 11369248 3507312
15:49:50 106636 8192000000 10269417504 10728534016 1318746272 13.865 97 14 28 0 1332335376 10183424 3405680
15:52:40 47030 8192000000 10268496256 10706878464 1309437024 13.781 96 17 34 0 1323831312 10530672 3863616
15:54:33 72303 8192000000 10253113584 10700578816 1278566064 13.500 94 11 23 0 1298546752 17633088 2347600
15:56:49 19419 8192000000 10279359936 10713292800 1340373440 14.061 99 29 59 1 1352652704 6392528 5886736
15:58:09 73479 8192000000 10266833296 10720387072 1336011760 14.021 98 9 18 0 1352418656 14421504 1985392
16:00:50 112548 8192000000 10274616448 10727137280 1365648080 14.288 100 19 38 0 1383385424 13552448 4184896
16:03:43 54047 8192000000 10273013648 10727763968 1368785744 14.316 99 13 25 1 1378639040 7141232 2712064
16:05:50 55642 8192000000 10289353712 10747449344 1387457616 14.483 100 14 27 0 1402482528 11788560 3236352
16:07:48 60811 8192000000 10285711968 10744758272 1374023312 14.363 99 7 14 0 1382886512 7600752 1262448
16:09:50 32735 8192000000 10307296496 10760142848 1396339744 14.562 99 23 46 0 1412309744 10525456 5444544
16:11:23 80204 8192000000 10318506208 10783870976 1409005280 14.675 100 16 30 0 1426855680 13715008 4135392
16:13:44 75289 8192000000 10285674544 10735521792 1323447216 13.908 94 12 24 0 1334171248 8276608 2447424
16:16:02 75185 8192000000 10291615616 10746814464 1338844976 14.047 96 24 48 0 1354400720 10524080 5031664
16:18:18 111674 8192000000 10298278704 10740248576 1315488704 13.836 94 16 32 0 1328639904 10082832 3068368
16:21:14 118695 8192000000 10284156016 10741403648 1341157648 14.068 97 9 18 0 1364002288 20667584 2177056
16:24:55 40222 8192000000 10246574192 10702413824 1338360912 14.043 97 12 24 0 1348370816 7684800 2325104
16:26:36 29967 8192000000 10252069856 10714673152 1353982560 14.183 99 4 8 0 1363500048 8741600 775888
16:28:06 69551 8192000000 10264001200 10722766848 1367603520 14.306 99 16 32 0 1379844032 7773280 4467232
16:30:15 59744 8192000000 10247242576 10702196736 1295390816 13.653 95 10 20 0 1305783584 7958800 2433968
16:32:15 49502 8192000000 10255828400 10701766656 1310706512 13.792 96 21 42 0 1323542192 8175952 4659728
16:34:46 65582 8192000000 10241384064 10685337600 1295006688 13.650 95 11 22 0 1307125792 9504480 2614624
16:36:52 40656 8192000000 10275044336 10713636864 1335583952 14.018 98 28 56 0 1352442528 10412640 6445936
16:38:36 16906 8192000000 8950509536 9132244992 121642144 1.463 10 3 6 0 123710544 1591792 476608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-01-2023 16:39:49 to 02-01-2023 16:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2023 16:40:06 to 02-01-2023 16:40:08.

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          247174
MON$OLDEST_ACTIVE               247714
MON$OLDEST_SNAPSHOT             247714
MON$NEXT_TRANSACTION            247714
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                       2017280
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1564: 
:1565: mscs-test-161.budzdorov.ru    Mon Jan  2 16:38:54 2023
:1566:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1567: 
--- end of diff output ---

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

02.01.23 16:40:09. 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-01-02 16:40:12 No severe PSQL-related problems occured
02.01.23 16:40:13. End of report.