Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.09.2021 19:40
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16256620 kB
MemAvailable 30885528 kB
Buffers 0 kB
Cached 15458512 kB
SwapCached 372 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.8
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 2021-09-09 19:37:46
DTS_END                         2021-09-09 19:37:46.4430
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/33466
TRN_ID                          232441
ATT_ID                          676
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: 9061.42 21762 1087370
customer order: creation 164.19 346 19703
customer order: refuse 14.45 325 1734
order to supplier: creation 42.48 1302 5097
order to supplier: removal 3.45 94088 414
invoice (draft): creation 43.67 3623 5240
invoice (draft): removal 52.06 747 6247
invoice accept: apply 40.13 17270 4815
invoice accept: cancel 47.55 733 5706
customer reserve: creation 3737.33 168 448480
customer reserve: removal 4625.52 55 555062
realization accept: apply 79.78 161 9573
realization accept: cancel 86.65 97 10398
payment from customer: creation 57.50 113 6900
payment from customer: removal 5.78 87 693
payment to supplier: creation 46.28 125 5554
payment to supplier: removal 3.24 76 389
service: total inventory turnovers 4.49 1149 539
service: total monetary turnovers 6.17 22 740
service: aggregate perf. data 0.68 28125 82
service: refresh index statistics 0.03 286630 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-09-2021 19:40:21 to 09-09-2021 19:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2924
TEST_TIME 2 3466
TEST_TIME 3 3973
TEST_TIME 4 4180
TEST_TIME 5 3995
TEST_TIME 6 3929
TEST_TIME 7 3950
TEST_TIME 8 4552
TEST_TIME 9 4681
TEST_TIME 10 4941
TEST_TIME 11 6138
TEST_TIME 12 6338
TEST_TIME 13 6449
TEST_TIME 14 7157
TEST_TIME 15 7511
TEST_TIME 16 8081
TEST_TIME 17 8210
TEST_TIME 18 8284
TEST_TIME 19 8575
TEST_TIME 20 8660
TEST_TIME 21 8863
TEST_TIME 22 9417
TEST_TIME 23 9933
TEST_TIME 24 10680
TEST_TIME 25 10658
TEST_TIME 26 10971
TEST_TIME 27 10952
TEST_TIME 28 10912
TEST_TIME 29 10821
TEST_TIME 30 10493
TEST_TIME 31 10428
TEST_TIME 32 10401
TEST_TIME 33 10199
TEST_TIME 34 10076
TEST_TIME 35 9941
TEST_TIME 36 9826
TEST_TIME 37 9841
TEST_TIME 38 9837
TEST_TIME 39 9813
TEST_TIME 40 9652
TEST_TIME 41 9667
TEST_TIME 42 9481
TEST_TIME 43 9314
TEST_TIME 44 9218
TEST_TIME 45 9345
TEST_TIME 46 9302
TEST_TIME 47 9202
TEST_TIME 48 9158
TEST_TIME 49 9233
TEST_TIME 50 9168
TEST_TIME 51 9028
TEST_TIME 52 9116
TEST_TIME 53 9077
TEST_TIME 54 9023
TEST_TIME 55 9120
TEST_TIME 56 9118
TEST_TIME 57 9228
TEST_TIME 58 9281
TEST_TIME 59 9278
TEST_TIME 60 9271
TEST_TIME 61 9322
TEST_TIME 62 9357
TEST_TIME 63 9325
TEST_TIME 64 9372
TEST_TIME 65 9490
TEST_TIME 66 9501
TEST_TIME 67 9372
TEST_TIME 68 9289
TEST_TIME 69 9272
TEST_TIME 70 9258
TEST_TIME 71 9245
TEST_TIME 72 9221
TEST_TIME 73 9183
TEST_TIME 74 9124
TEST_TIME 75 9030
TEST_TIME 76 9023
TEST_TIME 77 9127
TEST_TIME 78 9081
TEST_TIME 79 9068
TEST_TIME 80 9051
TEST_TIME 81 9076
TEST_TIME 82 9065
TEST_TIME 83 9111
TEST_TIME 84 9103
TEST_TIME 85 9142
TEST_TIME 86 9172
TEST_TIME 87 9263
TEST_TIME 88 9207
TEST_TIME 89 9279
TEST_TIME 90 9290
TEST_TIME 91 9293
TEST_TIME 92 9216
TEST_TIME 93 9204
TEST_TIME 94 9183
TEST_TIME 95 9132
TEST_TIME 96 9107
TEST_TIME 97 9102
TEST_TIME 98 9069
TEST_TIME 99 9019
TEST_TIME 100 9045
TEST_TIME 101 9086
TEST_TIME 102 9129
TEST_TIME 103 9127
TEST_TIME 104 9106
TEST_TIME 105 9091
TEST_TIME 106 9188
TEST_TIME 107 9194
TEST_TIME 108 9206
TEST_TIME 109 9254
TEST_TIME 110 9252
TEST_TIME 111 9240
TEST_TIME 112 9202
TEST_TIME 113 9139
TEST_TIME 114 9195
TEST_TIME 115 9141
TEST_TIME 116 9165
TEST_TIME 117 9131
TEST_TIME 118 9136
TEST_TIME 119 9109
TEST_TIME 120 9062

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-09-2021 19:40:25 to 09-09-2021 19:40:28.

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 1500465 1500465 0 0.00 0 16977 6 0 0
doc_list_biud 1500464 1500464 0 0.00 0 43170 26 0 0
sp_add_invoice_to_stock 4956 4815 141 2.84 49 136716 17271 0 141
sp_cancel_adding_invoice 5706 5706 0 0.00 6 79208 734 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 9116 325 0 0
sp_cancel_customer_reserve 555062 555062 0 0.00 1 43172 56 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 2 5831 87 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 7472 76 0 0
sp_cancel_supplier_invoice 6256 6247 9 0.14 2 32438 747 0 9
sp_cancel_supplier_order 414 414 0 0.00 9 1100762 94088 0 0
sp_cancel_write_off 10398 10398 0 0.00 1 8646 98 0 0
sp_client_order 19703 19703 0 0.00 3 39673 346 0 0
sp_customer_reserve 448480 448480 0 0.00 0 50323 169 0 0
sp_fill_shopping_cart 30021 30021 0 0.00 1 11780 43 0 0
sp_fill_shopping_cart_clo_res 448471 448471 0 0.00 0 31918 14 0 0
sp_get_clo_for_invoice 4806 4806 0 0.00 0 43304 409 0 0
sp_kill_cost_storno 17186 17186 0 0.00 0 16966 49 0 0
sp_kill_qstorno_ret_qs2qd 1127183 1127183 0 0.00 0 43168 30 0 0
sp_kill_qty_storno 593940 593940 0 0.00 0 43170 65 0 0
sp_lock_selected_doc 19953 19953 0 0.00 0 7784 5 0 0
sp_make_cost_storno 26833 26833 0 0.00 0 16509 54 0 0
sp_multiply_rows_for_pdistr 26833 26833 0 0.00 0 6915 29 0 0
sp_multiply_rows_for_qdistr 19703 19703 0 0.00 0 37921 161 0 0
sp_pay_from_customer 6900 6900 0 0.00 4 11001 114 0 0
sp_pay_to_supplier 5554 5554 0 0.00 5 12792 126 0 0
sp_payment_common 12454 12454 0 0.00 1 10988 29 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 9109 296 0 0
sp_qd_handle_on_invoice_upd_sts 10512 10512 0 0.00 0 18679 319 0 0
sp_qd_handle_on_reserve_upd_sts 9573 9573 0 0.00 0 9139 44 0 0
sp_reserve_write_off 9573 9573 0 0.00 6 16517 162 0 0
sp_supplier_invoice 5240 5240 0 0.00 7 69010 3623 0 0
sp_supplier_order 5097 5097 0 0.00 5 29761 1303 0 0
srv_aggregate_perf_data 82 82 0 0.00 3096 615648 28126 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2046 142164 47976 0 0
srv_find_qd_qs_mism 1040445 1040445 0 0.00 0 12642 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 30 66958 1149 0 0
srv_make_money_saldo 740 740 0 0.00 0 580 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 131059 448954 286631 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 0 8966 163 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 7477 120 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 6131 16 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 2574 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 488 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 5857 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 348 348 0 0.00 0 1985 15 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 22408 1533 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 5986 13 0 0
t$perf-norm:sp_client_order 19703 19703 0 0.00 0 14913 17 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 931 6 0 0
t$perf-norm:sp_pay_from_customer 6900 6900 0 0.00 0 3593 8 0 0
t$perf-norm:sp_pay_to_supplier 5554 5554 0 0.00 0 7321 11 0 0
t$perf-norm:sp_reserve_write_off 9573 9573 0 0.00 0 7241 13 0 0
t$perf-norm:sp_supplier_invoice 5240 5240 0 0.00 0 4994 16 0 0
t$perf-norm:sp_supplier_order 5097 5097 0 0.00 0 4968 15 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 61 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 607 12 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 1675 18 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 5921 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 9892 9610 282 2.85 0 6096 21 0 282
v_all_customers 36046 36046 0 0.00 0 55 1 0 0
v_all_suppliers 28676 28676 0 0.00 0 63 1 0 0
v_all_wares 39406 39406 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 824 7 0 0
v_cancel_client_order 3468 3468 0 0.00 0 3875 6 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 2762 11 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 1259 5 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 888 5 0 0
v_cancel_supplier_invoice 714 696 18 2.52 0 1688 19 0 18
v_cancel_supplier_order 828 828 0 0.00 0 2640 8 0 0
v_cancel_write_off 1872 1872 0 0.00 0 3887 18 0 0
v_max_id_clo_ord 5097 5097 0 0.00 0 268 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 4677 17 0 0
v_max_id_ord_sup 5221 5221 0 0.00 0 6832 8 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 427 2 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 3792 5 0 0
v_min_id_clo_ord 5097 5097 0 0.00 0 5737 7 0 0
v_min_id_clo_res 974 974 0 0.00 0 5182 20 0 0
v_min_id_ord_sup 5221 5221 0 0.00 0 4120 6 0 0
v_min_non_paid_invoice 5554 5554 0 0.00 0 6410 18 0 0
v_min_non_paid_realizn 6900 6900 0 0.00 0 7503 17 0 0
v_our_firm 9830 9830 0 0.00 0 52 1 0 0
v_reserve_write_off 19146 19146 0 0.00 0 5042 7 0 0
x_lock_depdocs_on_canc_invoice 11605 11605 0 0.00 0 34638 113 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 5000 289 0 0
x_make_qstorno_1000_1200 5097 5097 0 0.00 2 29719 1228 0 0
x_make_qstorno_1200_2000 5240 5240 0 0.00 3 69003 3459 0 0
x_make_qstorno_2100_3300 448480 448480 0 0.00 0 50316 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-09-2021 19:40:29 to 09-09-2021 19:40:33.

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
17:07:50 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:51 14227 8192000000 10367050688 10688024576 1421306832 14.784 100 78 156 0 1451656176 5709872 24639472
17:10:10 16270 8192000000 10448997664 10783715328 1491618560 15.403 100 75 150 0 1523306608 7951520 23736528
17:11:30 19718 8192000000 10502832448 10844073984 1557360496 15.973 100 89 178 0 1595084592 9794896 27929200
17:12:54 11542 8192000000 10539847760 10898558976 1603341504 16.368 100 78 156 0 1637471568 11126192 23003872
17:14:13 9604 8192000000 10631871312 10994249728 1689424880 17.096 100 82 164 1 1725760032 12618912 23716240
17:15:25 6257 8192000000 10686722880 11065249792 1749734432 17.599 100 84 166 1 1785234128 11820160 23679536
17:16:34 4041 8192000000 10739804960 11119661056 1801996848 18.030 100 93 186 1 1841482192 13511552 25973792
17:17:41 1474 8192000000 10785129344 11168325632 1844741104 18.379 100 92 183 1 1884838064 13564768 26532192
17:18:45 2893 8192000000 10812215952 11197407232 1864277456 18.538 100 89 178 2 1902816944 13764512 24774976
17:19:49 1778 8192000000 10825334832 11212644352 1878351664 18.652 100 83 166 0 1914950560 11673104 24925792
17:20:52 9113 8192000000 10805577680 11220516864 1885624400 18.711 100 30 60 0 1900963904 7314160 8025344
17:22:04 10748 8192000000 10800139920 11229294592 1894198864 18.780 100 30 61 0 1908633072 5972256 8461952
17:23:17 24718 8192000000 10813072272 11245101056 1901263952 18.836 100 50 101 1 1922926128 6178528 15483648
17:24:44 7552 8192000000 10821679808 11258007552 1911289120 18.917 100 30 60 2 1926543008 6386928 8866960
17:25:56 5691 8192000000 10829585936 11262803968 1914779440 18.945 100 42 85 0 1935014128 7882128 12352560
17:27:07 54759 8192000000 10808331904 11229179904 1830491808 18.263 96 15 30 0 1844240144 9594544 4153792
17:29:10 8250 8192000000 10770145248 11193446400 1812566064 18.117 97 35 69 2 1828437920 6171008 9700848
17:30:19 9435 8192000000 10689017312 11100188672 1705603376 17.232 95 25 50 1 1722027008 9337936 7085696
17:31:30 5016 8192000000 10707956544 11132256256 1788788944 17.922 99 24 48 1 1801736704 5625920 7321840
17:32:36 34366 8192000000 10736921232 11166527488 1827709792 18.241 100 25 50 0 1841294496 5011504 8573200
17:34:12 7469 8192000000 10765602480 11183812608 1841831136 18.356 100 25 49 1 1854562592 5074192 7657264
17:35:20 12898 8192000000 10776045008 11207335936 1861994000 18.519 100 26 52 1 1879831168 9837040 8000128
17:36:34 10192 8192000000 10781620416 11220029440 1877075504 18.641 100 23 47 1 1889596320 5433360 7087456
17:37:46 62343 8192000000 10856653104 11270660096 1917454256 18.966 100 68 132 1 1945079152 8902128 18722768
17:39:52 32004 8192000000 10880601200 11309948928 1946361584 19.197 100 48 96 0 1972383280 12483856 13537840
17:41:32 28212 8192000000 10897921248 11336073216 1968274320 19.372 100 40 80 0 1997501168 17295280 11931568
17:43:07 14243 8192000000 10924757376 11357356032 1980182736 19.466 100 39 78 1 2010989328 18603504 12203088
17:44:27 8543 8192000000 10936838592 11368144896 1987847328 19.527 100 53 106 0 2029064464 24950720 16266416
17:45:54 4109 8192000000 10966275904 11390316544 2003725040 19.652 100 60 119 1 2049161824 28008672 17428112
17:47:02 5031 8192000000 10964444384 11394863104 2008380768 19.689 100 55 108 0 2054461328 30371728 15708832
17:48:12 2044 8192000000 10949539136 11372269568 1971224128 19.395 99 77 154 0 2030068048 36647600 22196320
17:49:17 2458 8192000000 10978961984 11402264576 2007604592 19.683 100 81 161 0 2066993072 35992720 23395760
17:50:24 3251 8192000000 10994443744 11413889024 2015373056 19.744 100 83 167 3 2083111568 42694208 25044304
17:51:31 3118 8192000000 10984739408 11403673600 1983326512 19.491 99 80 160 0 2051339424 44056944 23955968
17:52:37 4623 8192000000 10975734672 11408830464 1996188240 19.593 100 58 116 1 2064176208 50535744 17452224
17:53:48 15698 8192000000 10911633488 11358076928 1885939632 18.713 95 31 62 1 1946072096 50158768 9973696
17:55:06 7158 8192000000 10950700768 11380170752 1960653392 19.311 99 62 124 2 2022808816 42801952 19353472
17:56:17 9201 8192000000 10909277152 11352588288 1899581696 18.823 97 37 74 1 1958102128 46964448 11555984
17:57:37 19200 8192000000 10917771584 11362795520 1910177440 18.908 97 35 70 2 1971532864 49178176 12177248
17:58:59 31002 8192000000 10925063088 11379122176 1932459088 19.087 99 32 63 2 1989401312 45876208 11066016
18:00:58 21998 8192000000 10889517264 11344625664 1914065712 18.939 99 23 44 2 1961150640 39710768 7374160
18:02:30 49797 8192000000 10877000064 11330138112 1894177552 18.779 98 14 28 0 1931357776 32554992 4625232
18:04:22 30995 8192000000 10874764480 11326517248 1933080288 19.091 100 34 67 0 1959538528 16349648 10108592
18:05:54 59567 8192000000 10848962736 11323658240 1893631120 18.775 98 13 26 0 1914288704 17010208 3647376
18:08:58 54334 8192000000 10868866592 11313721344 1902606976 18.847 98 16 32 1 1916021296 9417152 3997168
18:11:13 9781 8192000000 10890255648 11344695296 1955178832 19.268 100 30 58 0 1976271824 13553152 7539840
18:12:24 44658 8192000000 10885646656 11343806464 1932248464 19.085 98 20 40 1 1948979936 11040480 5690992
18:14:11 66536 8192000000 10894239920 11358683136 1952105824 19.243 99 30 60 0 1975629424 14142176 9381424
18:16:18 50294 8192000000 10885563216 11354984448 1956190608 19.276 99 12 24 1 1970225616 10274224 3760784
18:18:10 55455 8192000000 10880659760 11349352448 1944751056 19.185 99 18 37 1 1957985520 8088096 5146368
18:20:45 41657 8192000000 10948382288 11387105280 1990887040 19.551 100 41 81 1 2018297936 15177568 12233328
18:22:34 38971 8192000000 10900557808 11367362560 1934104112 19.100 98 12 24 1 1949853888 12506896 3242880
18:24:23 32723 8192000000 10921352448 11382976512 1997549504 19.603 100 30 60 1 2024274320 18517680 8207136
18:26:02 76277 8192000000 10890082000 11376414720 1967618896 19.367 100 15 29 1 1993168608 20925504 4624208
18:28:20 66592 8192000000 10908128464 11388600320 1984331200 19.499 100 28 56 2 2007148256 14848496 7968560
18:30:47 69825 8192000000 10924979552 11397906432 1995044496 19.584 100 17 34 1 2013630096 13589776 4995824
18:33:08 22596 8192000000 10941299488 11397083136 2008739280 19.692 100 51 102 1 2035134416 11233024 15162112
18:34:31 120926 8192000000 10915842336 11387203584 1967428720 19.365 98 19 38 1 1990898096 18587728 4881648
18:37:46 67148 8192000000 10940229408 11420372992 1993806912 19.574 99 17 34 0 2010355264 12044064 4504288
18:40:00 43365 8192000000 10948505120 11402792960 1948198352 19.212 96 43 85 0 1970117920 10092688 11826880
18:41:44 142164 8192000000 10919741936 11408281600 1921923792 19.002 96 17 34 0 1942800064 16140368 4735904
18:46:11 46273 8192000000 10893376304 11368230912 1947188832 19.204 99 22 44 0 1969182160 16355920 5637408
18:48:02 42659 8192000000 10842491424 11327143936 1897865472 18.809 98 8 16 1 1912836128 12392672 2577984
18:49:49 81696 8192000000 10839623664 11297595392 1826740368 18.233 94 16 32 0 1838514064 6765424 5008272
18:53:32 51955 8192000000 10840969968 11318947840 1894000464 18.778 99 16 32 0 1917346096 18908992 4436640
18:56:34 48444 8192000000 10866164112 11330387968 1920224688 18.989 99 11 22 0 1932599296 9427760 2946848
18:58:23 28594 8192000000 10874184384 11330854912 1931927424 19.082 100 44 88 2 1955755264 10919520 12908320
18:59:52 97712 8192000000 10860159984 11314802688 1830238208 18.261 94 14 28 0 1844845424 10570896 4036320
19:02:30 61753 8192000000 10856871760 11323228160 1875437264 18.628 97 22 44 1 1898579088 17022144 6119680
19:04:32 78824 8192000000 10893084992 11344216064 1906597632 18.879 98 27 54 0 1931453120 17568608 7286880
19:06:54 57282 8192000000 10889735936 11352961024 1896960192 18.802 97 15 30 0 1915328592 14440720 3927680
19:08:53 92165 8192000000 10873880512 11344416768 1921936672 19.002 98 14 28 1 1937363664 10865696 4561296
19:11:48 70458 8192000000 10881550784 11363028992 1972718224 19.407 100 11 22 0 1983424432 8061168 2645040
19:13:58 69672 8192000000 10873847728 11353739264 1957352160 19.285 100 17 34 0 1969679168 7665584 4661424
19:16:09 39505 8192000000 10922000016 11363381248 1960632896 19.311 99 43 86 2 1981532976 8576336 12323744
19:17:51 73636 8192000000 10907539440 11381088256 1946898112 19.202 98 15 30 2 1965678096 13944064 4835920
19:20:12 67679 8192000000 10908953504 11385151488 1971433936 19.397 99 14 28 1 1984842112 9587040 3821136
19:22:28 23334 8192000000 10961253056 11423367168 2015644560 19.746 100 48 96 1 2048161680 19153088 13364032
19:24:26 134454 8192000000 10917393088 11407249408 1939957632 19.146 97 10 20 0 1964019888 21047168 3015088
19:27:41 78587 8192000000 10927039024 11388514304 1940032096 19.147 97 20 40 0 1954444592 8697824 5714672
19:30:04 114007 8192000000 10903568208 11383050240 1916399824 18.958 96 22 44 0 1936514992 14217136 5898032
19:32:59 38329 8192000000 10891175216 11370102784 1961632464 19.319 100 30 60 0 1982881328 12735232 8513632
19:35:11 75004 8192000000 10885883648 11370237952 1926324176 19.037 98 6 12 0 1941165872 13389472 1452224
19:37:26 36603 8192000000 10038766480 10431389696 1165002736 12.450 60 4 8 0 1173677552 7485072 1189744

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-09-2021 19:40:35 to 09-09-2021 19:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-09-2021 19:40:50 to 09-09-2021 19:40:52.

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          232775
MON$OLDEST_ACTIVE               232776
MON$OLDEST_SNAPSHOT             232776
MON$NEXT_TRANSACTION            232776
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                       2018624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33494 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33494 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

09.09.21 19:40:53. 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
2021-09-09 19:40:55 No severe PSQL-related problems occured
09.09.21 19:40:57. End of report.