Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.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 16699500 kB
MemAvailable 31322496 kB
Buffers 0 kB
Cached 15007068 kB
SwapCached 1180 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.9
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-01-09 01:38:04
DTS_END                         2022-01-09 01:38:04.0880
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/49126
TRN_ID                          236882
ATT_ID                          704
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: 8991.71 27634 1079005
customer order: creation 161.58 380 19390
customer order: refuse 14.40 347 1728
order to supplier: creation 45.33 1261 5439
order to supplier: removal 3.45 87415 414
invoice (draft): creation 45.18 3492 5421
invoice (draft): removal 52.86 602 6343
invoice accept: apply 39.90 17457 4788
invoice accept: cancel 47.08 690 5650
customer reserve: creation 3715.29 170 445835
customer reserve: removal 4584.22 52 550106
realization accept: apply 80.30 178 9636
realization accept: cancel 76.49 100 9179
payment from customer: creation 56.33 101 6759
payment from customer: removal 5.88 69 706
payment to supplier: creation 47.94 134 5753
payment to supplier: removal 3.50 75 420
service: total inventory turnovers 4.51 1300 541
service: total monetary turnovers 6.62 22 794
service: aggregate perf. data 0.83 21980 99
service: refresh index statistics 0.03 416845 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 01:39:25 to 09-01-2022 01:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5727
TEST_TIME 2 4596
TEST_TIME 3 4896
TEST_TIME 4 4986
TEST_TIME 5 4623
TEST_TIME 6 4923
TEST_TIME 7 5032
TEST_TIME 8 5106
TEST_TIME 9 5296
TEST_TIME 10 5854
TEST_TIME 11 6228
TEST_TIME 12 6607
TEST_TIME 13 7098
TEST_TIME 14 7223
TEST_TIME 15 7330
TEST_TIME 16 8196
TEST_TIME 17 8722
TEST_TIME 18 8780
TEST_TIME 19 9298
TEST_TIME 20 9485
TEST_TIME 21 9780
TEST_TIME 22 10479
TEST_TIME 23 10657
TEST_TIME 24 10520
TEST_TIME 25 10302
TEST_TIME 26 10205
TEST_TIME 27 10258
TEST_TIME 28 10219
TEST_TIME 29 10274
TEST_TIME 30 10051
TEST_TIME 31 10166
TEST_TIME 32 10132
TEST_TIME 33 9961
TEST_TIME 34 9979
TEST_TIME 35 9837
TEST_TIME 36 9742
TEST_TIME 37 9573
TEST_TIME 38 9509
TEST_TIME 39 9652
TEST_TIME 40 9627
TEST_TIME 41 9493
TEST_TIME 42 9529
TEST_TIME 43 9389
TEST_TIME 44 9392
TEST_TIME 45 9342
TEST_TIME 46 9240
TEST_TIME 47 9205
TEST_TIME 48 9092
TEST_TIME 49 9080
TEST_TIME 50 9035
TEST_TIME 51 9116
TEST_TIME 52 9151
TEST_TIME 53 9114
TEST_TIME 54 9086
TEST_TIME 55 9148
TEST_TIME 56 9263
TEST_TIME 57 9253
TEST_TIME 58 9227
TEST_TIME 59 9215
TEST_TIME 60 9237
TEST_TIME 61 9258
TEST_TIME 62 9313
TEST_TIME 63 9436
TEST_TIME 64 9435
TEST_TIME 65 9306
TEST_TIME 66 9252
TEST_TIME 67 9225
TEST_TIME 68 9231
TEST_TIME 69 9255
TEST_TIME 70 9175
TEST_TIME 71 9213
TEST_TIME 72 9155
TEST_TIME 73 9143
TEST_TIME 74 9288
TEST_TIME 75 9231
TEST_TIME 76 9325
TEST_TIME 77 9314
TEST_TIME 78 9289
TEST_TIME 79 9261
TEST_TIME 80 9316
TEST_TIME 81 9329
TEST_TIME 82 9321
TEST_TIME 83 9268
TEST_TIME 84 9204
TEST_TIME 85 9211
TEST_TIME 86 9192
TEST_TIME 87 9136
TEST_TIME 88 9165
TEST_TIME 89 9139
TEST_TIME 90 9110
TEST_TIME 91 9054
TEST_TIME 92 9017
TEST_TIME 93 9099
TEST_TIME 94 9085
TEST_TIME 95 9065
TEST_TIME 96 9143
TEST_TIME 97 9239
TEST_TIME 98 9272
TEST_TIME 99 9233
TEST_TIME 100 9246
TEST_TIME 101 9283
TEST_TIME 102 9307
TEST_TIME 103 9275
TEST_TIME 104 9295
TEST_TIME 105 9361
TEST_TIME 106 9336
TEST_TIME 107 9335
TEST_TIME 108 9265
TEST_TIME 109 9242
TEST_TIME 110 9200
TEST_TIME 111 9211
TEST_TIME 112 9179
TEST_TIME 113 9144
TEST_TIME 114 9129
TEST_TIME 115 9096
TEST_TIME 116 9082
TEST_TIME 117 9085
TEST_TIME 118 9070
TEST_TIME 119 9069
TEST_TIME 120 8994

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2022 01:39:29 to 09-01-2022 01:39:32.

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 1490603 1490603 0 0.00 0 20913 6 0 0
doc_list_biud 1490603 1490603 0 0.00 0 51074 24 0 0
sp_add_invoice_to_stock 4900 4788 112 2.28 37 196123 17457 0 112
sp_cancel_adding_invoice 5650 5650 0 0.00 4 76765 691 0 0
sp_cancel_client_order 1728 1728 0 0.00 4 13992 347 0 0
sp_cancel_customer_reserve 550106 550106 0 0.00 1 51076 52 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 3260 70 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4593 75 0 0
sp_cancel_supplier_invoice 6349 6343 6 0.09 2 16574 602 0 6
sp_cancel_supplier_order 414 414 0 0.00 4 892348 87416 0 0
sp_cancel_write_off 9179 9179 0 0.00 1 10642 100 0 0
sp_client_order 19390 19390 0 0.00 3 38845 380 0 0
sp_customer_reserve 445835 445835 0 0.00 0 45820 171 0 0
sp_fill_shopping_cart 30240 30240 0 0.00 1 37130 42 0 0
sp_fill_shopping_cart_clo_res 445832 445832 0 0.00 0 15709 14 0 0
sp_get_clo_for_invoice 4785 4785 0 0.00 0 29343 365 0 0
sp_kill_cost_storno 15955 15955 0 0.00 0 20902 48 0 0
sp_kill_qstorno_ret_qs2qd 1116148 1116148 0 0.00 0 51072 28 0 0
sp_kill_qty_storno 587841 587841 0 0.00 0 51074 61 0 0
sp_lock_selected_doc 20096 20096 0 0.00 0 7126 4 0 0
sp_make_cost_storno 26933 26933 0 0.00 0 12614 51 0 0
sp_multiply_rows_for_pdistr 26933 26933 0 0.00 0 8905 29 0 0
sp_multiply_rows_for_qdistr 19390 19390 0 0.00 0 16222 169 0 0
sp_pay_from_customer 6759 6759 0 0.00 4 12864 101 0 0
sp_pay_to_supplier 5753 5753 0 0.00 5 11943 134 0 0
sp_payment_common 12512 12512 0 0.00 2 11007 32 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 13986 324 0 0
sp_qd_handle_on_invoice_upd_sts 10435 10435 0 0.00 0 31063 312 0 0
sp_qd_handle_on_reserve_upd_sts 9636 9636 0 0.00 0 14959 68 0 0
sp_reserve_write_off 9636 9636 0 0.00 6 19906 179 0 0
sp_supplier_invoice 5421 5421 0 0.00 6 93421 3492 0 0
sp_supplier_order 5439 5439 0 0.00 4 44927 1262 0 0
srv_aggregate_perf_data 99 99 0 0.00 2285 528267 21981 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2258 175814 51364 0 0
srv_find_qd_qs_mism 1032408 1032408 0 0.00 0 16320 5 0 0
srv_make_invnt_saldo 541 541 0 0.00 10 102624 1300 0 0
srv_make_money_saldo 794 794 0 0.00 1 2961 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 182419 543928 416846 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4788 4788 0 0.00 0 24402 167 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 2811 92 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 7011 23 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1400 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1355 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 769 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 17386 53 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 33095 1459 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 4593 8 0 0
t$perf-norm:sp_client_order 19390 19390 0 0.00 0 16895 18 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 4319 10 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 14163 9 0 0
t$perf-norm:sp_pay_to_supplier 5753 5753 0 0.00 0 14232 17 0 0
t$perf-norm:sp_reserve_write_off 9636 9636 0 0.00 0 6475 12 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 17565 19 0 0
t$perf-norm:sp_supplier_order 5439 5439 0 0.00 0 7218 13 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 216 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 272 7 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 786 16 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 2434 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9794 9570 224 2.28 0 8533 17 0 224
v_all_customers 35158 35158 0 0.00 0 16 0 0 0
v_all_suppliers 30060 30060 0 0.00 0 73 1 0 0
v_all_wares 38780 38780 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 1426 8 0 0
v_cancel_client_order 3456 3456 0 0.00 0 3807 5 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 1072 4 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 547 3 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4582 12 0 0
v_cancel_supplier_invoice 776 764 12 1.54 0 486 12 0 12
v_cancel_supplier_order 828 828 0 0.00 0 158 2 0 0
v_cancel_write_off 1966 1966 0 0.00 0 5525 19 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 593 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 3281 13 0 0
v_max_id_ord_sup 5411 5411 0 0.00 0 6521 6 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 1792 5 0 0
v_max_non_paid_realizn 3672 3672 0 0.00 0 4580 6 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 3809 7 0 0
v_min_id_clo_res 992 992 0 0.00 0 3424 6 0 0
v_min_id_ord_sup 5411 5411 0 0.00 0 7368 14 0 0
v_min_non_paid_invoice 5753 5753 0 0.00 0 5921 22 0 0
v_min_non_paid_realizn 6759 6759 0 0.00 0 11003 24 0 0
v_our_firm 9796 9796 0 0.00 0 31 1 0 0
v_reserve_write_off 19272 19272 0 0.00 0 5536 6 0 0
x_lock_depdocs_on_canc_invoice 11611 11611 0 0.00 0 21013 130 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 11485 374 0 0
x_make_qstorno_1000_1200 5439 5439 0 0.00 1 44915 1187 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 3 93405 3334 0 0
x_make_qstorno_2100_3300 445835 445835 0 0.00 0 41124 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 01:39:33 to 09-01-2022 01:39:37.

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:07 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:08 21532 8192000000 9907010656 10215460864 990818080 10.789 100 91 182 1 1020271920 5801936 23651904
23:10:33 19486 8192000000 9934814624 10255720448 1019308048 11.065 100 92 184 1 1051950384 8575568 24066768
23:11:56 14708 8192000000 9967568512 10298380288 1050334368 11.364 100 85 170 0 1081204672 9265216 21605088
23:13:17 12112 8192000000 10014328064 10357178368 1100234720 11.840 100 79 158 0 1129800864 9946240 19619904
23:14:31 9217 8192000000 10070645152 10429902848 1157642576 12.381 100 77 154 1 1186865968 11086112 18137280
23:15:43 4551 8192000000 10115676896 10485493760 1206479200 12.836 100 75 151 1 1235677632 11858544 17339888
23:16:50 4486 8192000000 10162138464 10535923712 1240017728 13.146 100 78 156 0 1270564080 13733248 16813104
23:17:57 1345 8192000000 10193889280 10575024128 1277101728 13.487 100 90 181 1 1312106592 14859200 20145664
23:19:00 1616 8192000000 10213287616 10596675584 1290732368 13.611 100 92 184 1 1325379248 14306608 20340272
23:20:03 15288 8192000000 10218608672 10605039616 1300552800 13.700 100 74 151 0 1326389520 9197376 16639344
23:21:19 4072 8192000000 10206083952 10614165504 1311653728 13.801 100 47 94 0 1330629072 9130464 9844880
23:22:27 5749 8192000000 10225216304 10634158080 1322036064 13.895 100 43 86 2 1341902224 9882496 9983664
23:23:33 63087 8192000000 10228576416 10653147136 1332799600 13.992 100 43 86 3 1353077744 10564640 9713504
23:25:38 6858 8192000000 10229592896 10645753856 1335564304 14.017 100 23 44 2 1346887216 6747360 4575552
23:26:46 10441 8192000000 10228876384 10652610560 1333370624 13.998 99 22 45 5 1348454288 10284608 4799056
23:27:59 47893 8192000000 10194728672 10613346304 1247916896 13.219 94 25 50 0 1258251776 4557888 5776992
23:29:48 9950 8192000000 10156123504 10554155008 1214939504 12.915 94 22 44 0 1225959616 5616256 5403856
23:30:58 7308 8192000000 10150486592 10541969408 1228526544 13.040 98 43 88 1 1246157440 7361024 10269872
23:32:08 31274 8192000000 10145945248 10544697344 1235088432 13.101 98 25 50 1 1246313168 5687376 5537360
23:33:42 8256 8192000000 10168985888 10566701056 1247271184 13.213 98 26 52 1 1258955104 4828528 6855392
23:34:51 6168 8192000000 10180905472 10593763328 1286243024 13.570 99 21 42 0 1301293472 10440752 4609696
23:36:00 7252 8192000000 10206677200 10614554624 1307370160 13.762 100 23 47 1 1318073104 5706928 4996016
23:37:09 9580 8192000000 10211171600 10617987072 1316566720 13.846 100 20 38 1 1326742592 5294928 4880944
23:38:20 40829 8192000000 10246049680 10657783808 1337380112 14.034 100 46 90 2 1354230384 6387600 10462672
23:40:11 27589 8192000000 10281459088 10694348800 1366002576 14.291 100 62 126 5 1388790720 8377648 14410496
23:41:47 18457 8192000000 10307561504 10726113280 1391564320 14.520 100 53 105 1 1420641696 16879904 12197472
23:43:17 15733 8192000000 10311356816 10730864640 1385225904 14.463 99 62 125 1 1419836816 19710336 14900576
23:44:35 11003 8192000000 10318707552 10741731328 1399272624 14.589 100 45 88 2 1430057104 20277696 10506784
23:45:51 9762 8192000000 10317854464 10744188928 1400096192 14.596 100 47 94 1 1431866496 20175664 11594640
23:47:04 2258 8192000000 10341481808 10757165056 1405677216 14.646 100 72 141 1 1445494624 22890064 16927344
23:48:11 5511 8192000000 10323815024 10743238656 1384275104 14.455 99 65 129 1 1429574928 30196128 15103696
23:49:21 6951 8192000000 10339430144 10760286208 1396033920 14.560 100 59 118 0 1443439248 33049552 14355776
23:50:38 3553 8192000000 10347742928 10761986048 1402804608 14.620 100 72 141 2 1457740928 37640096 17296224
23:51:45 7449 8192000000 10348617248 10774200320 1399715536 14.592 99 50 96 0 1448691888 35902752 13073600
23:52:56 3267 8192000000 10359436912 10772185088 1390984608 14.515 99 80 160 1 1451595904 39737712 20873584
23:54:07 4034 8192000000 10354647504 10766299136 1397387008 14.572 100 74 147 0 1459507616 43961648 18158960
23:55:14 15595 8192000000 10300176960 10726752256 1364779216 14.280 99 30 60 1 1403091920 30159904 8152800
23:56:33 11262 8192000000 10283842976 10708709376 1343322368 14.087 98 29 58 0 1380352848 28371616 8658864
23:57:52 24067 8192000000 10306360560 10737831936 1368627280 14.315 100 24 48 2 1403379088 28171072 6580736
23:59:17 32842 8192000000 10254828288 10691203072 1269759984 13.419 93 13 26 0 1297191520 23616288 3815248
00:00:52 34066 8192000000 10238079456 10669637632 1289715104 13.602 96 16 32 0 1311297264 17518336 4063824
00:02:28 68265 8192000000 10274979280 10700185600 1338276432 14.042 99 29 56 1 1360875344 15703248 6895664
00:04:59 50828 8192000000 10259481808 10713186304 1333015504 13.994 99 13 26 2 1361504240 24709072 3779664
00:06:55 77693 8192000000 10237357760 10674024448 1287003072 13.577 96 19 38 0 1304698320 13446320 4248928
00:09:13 60027 8192000000 10250982736 10688577536 1309700112 13.783 97 23 48 1 1329110160 14439968 4970080
00:11:14 143140 8192000000 10255452000 10708459520 1328208336 13.951 98 21 43 1 1347511872 14008880 5294656
00:14:38 68464 8192000000 10270559936 10713554944 1368918160 14.317 100 15 30 0 1380843856 8455952 3469744
00:16:52 59463 8192000000 10291043968 10750586880 1383627696 14.449 100 28 56 2 1406475504 15774080 7073728
00:18:52 36169 8192000000 10281226352 10735321088 1384105152 14.453 100 29 56 0 1399994848 8845664 7044032
00:20:29 42535 8192000000 10297291024 10761342976 1390043904 14.506 100 11 22 0 1411330144 18684944 2601296
00:22:33 107767 8192000000 10281400256 10750537728 1379490560 14.412 99 12 23 1 1392108976 9935968 2682448
00:25:22 63919 8192000000 10288985872 10752892928 1394925952 14.550 100 14 27 1 1408254672 10186288 3142432
00:27:26 71991 8192000000 10301085248 10774020096 1405076832 14.640 100 7 14 1 1420641360 13547408 2017120
00:29:41 69775 8192000000 10305812768 10772119552 1407926576 14.666 100 14 28 0 1423146144 12026896 3192672
00:31:55 50282 8192000000 10296226256 10753671168 1366953616 14.300 97 16 32 0 1379503008 9019392 3530000
00:33:46 37697 8192000000 10321880160 10771914752 1407428608 14.661 100 25 50 0 1422447104 9284080 5734416
00:35:27 91730 8192000000 10293812560 10741338112 1341348176 14.070 95 14 28 0 1352477568 8481072 2648320
00:38:00 17843 8192000000 10302394352 10746429440 1367505136 14.305 98 34 68 0 1392317728 17579216 7233376
00:39:21 43547 8192000000 10280025104 10726113280 1324592528 13.918 95 35 70 0 1343375920 11128512 7654880
00:41:05 87202 8192000000 10268562032 10725859328 1310783840 13.793 94 11 22 1 1331097408 17813392 2500176
00:43:36 49552 8192000000 10260794144 10705620992 1310150288 13.787 95 42 84 1 1326638032 6063232 10424512
00:45:26 74142 8192000000 10278304240 10740133888 1349626512 14.144 98 7 14 0 1365759168 14720464 1412192
00:47:43 41817 8192000000 10276455216 10722754560 1356036320 14.202 99 22 43 1 1376238112 15260800 4940992
00:49:33 76634 8192000000 10261481552 10701320192 1302082992 13.714 94 19 38 0 1315987888 9336992 4567904
00:51:50 61559 8192000000 10268801584 10725097472 1342684896 14.082 98 19 38 0 1360443616 13368640 4390080
00:54:06 175813 8192000000 10247116880 10676277248 1217558208 12.939 90 14 27 1 1231542736 10670032 3314496
00:58:07 34526 8192000000 10266432592 10706190336 1327921136 13.948 98 24 48 0 1346820880 13619376 5280368
00:59:42 39169 8192000000 10275867296 10709221376 1321604320 13.891 97 25 50 0 1339927216 13010960 5311936
01:01:26 90440 8192000000 10264147152 10710687744 1344605616 14.099 99 29 58 1 1360595776 9236288 6753872
01:04:22 102035 8192000000 10266584112 10725986304 1359059424 14.229 99 11 22 0 1379196624 17461040 2676160
01:07:05 36473 8192000000 10263566528 10715979776 1366635712 14.297 100 16 32 1 1384131472 13396400 4099360
01:08:42 49404 8192000000 10264704432 10712629248 1356554320 14.206 99 33 66 2 1373037968 9044880 7438768
01:11:01 82407 8192000000 10282172544 10748915712 1351324096 14.159 98 9 18 1 1366212640 12937280 1951264
01:13:24 81331 8192000000 10297569552 10747936768 1359222032 14.230 98 18 36 0 1378353376 15278000 3853344
01:15:50 62914 8192000000 10289398736 10744463360 1368297056 14.312 99 12 24 1 1380606416 9890560 2418800
01:17:53 29538 8192000000 10306340032 10751049728 1387036896 14.479 100 33 65 1 1407102768 12334240 7731632
01:19:27 112677 8192000000 10307886512 10757214208 1383959680 14.452 99 10 19 0 1404924320 19168096 1796544
01:22:23 51150 8192000000 10323031376 10774929408 1377369504 14.393 98 26 52 1 1407476144 24269952 5836688
01:24:29 44553 8192000000 10312022800 10758832128 1374654016 14.369 98 25 49 1 1391893936 11764336 5475584
01:26:15 84447 8192000000 10311669392 10759757824 1387536704 14.484 99 36 71 0 1403335360 7758560 8040096
01:28:40 9809 8192000000 10296506944 10744233984 1377856208 14.397 99 38 76 0 1398940272 12641328 8442736
01:29:51 79931 8192000000 10275090464 10732883968 1329427584 13.962 96 4 8 0 1339436464 9198560 810320
01:32:11 45012 8192000000 10255351936 10709274624 1316122336 13.842 95 33 66 1 1334308640 10569456 7616848
01:34:37 95242 8192000000 10267569680 10723438592 1365476432 14.286 99 10 20 0 1381683648 13356704 2850512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-01-2022 01:39:39 to 09-01-2022 01:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2022 01:39:54 to 09-01-2022 01:39:56.

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          236765
MON$OLDEST_ACTIVE               237316
MON$OLDEST_SNAPSHOT             237316
MON$NEXT_TRANSACTION            237316
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                       2009024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:677: 
:678: mscs-test-161.budzdorov.ru    Sun Jan  9 01:38:06 2022
:679:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:680: 
:681: 
:682: mscs-test-161.budzdorov.ru    Sun Jan  9 01:38:10 2022
:683:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:684: 
--- end of diff output ---

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

09.01.22 01:39:57. 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-01-09 01:40:00 No severe PSQL-related problems occured
09.01.22 01:40:01. End of report.