Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.2021 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 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 16004496 kB
MemAvailable 30829252 kB
Buffers 0 kB
Cached 15716528 kB
SwapCached 2440 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-11-05 16:37:55
DTS_END                         2021-11-05 16:37:55.9410
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/45642
TRN_ID                          242271
ATT_ID                          686
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: 9151.47 20619 1098176
customer order: creation 174.41 338 20929
customer order: refuse 15.24 349 1829
order to supplier: creation 46.58 1292 5590
order to supplier: removal 3.45 91099 414
invoice (draft): creation 46.91 3303 5629
invoice (draft): removal 51.82 808 6218
invoice accept: apply 42.36 17381 5083
invoice accept: cancel 46.12 786 5534
customer reserve: creation 3907.74 172 468929
customer reserve: removal 4522.63 53 542716
realization accept: apply 84.09 166 10091
realization accept: cancel 81.35 106 9762
payment from customer: creation 59.79 101 7175
payment from customer: removal 5.77 77 692
payment to supplier: creation 47.99 123 5759
payment to supplier: removal 3.64 83 437
service: total inventory turnovers 4.42 1036 530
service: total monetary turnovers 6.36 33 763
service: aggregate perf. data 0.77 19685 92
service: refresh index statistics 0.03 275398 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-11-2021 16:39:46 to 05-11-2021 16:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1503
TEST_TIME 2 2762
TEST_TIME 3 3743
TEST_TIME 4 3662
TEST_TIME 5 3671
TEST_TIME 6 4195
TEST_TIME 7 4739
TEST_TIME 8 5201
TEST_TIME 9 5312
TEST_TIME 10 5170
TEST_TIME 11 5395
TEST_TIME 12 5298
TEST_TIME 13 5455
TEST_TIME 14 5651
TEST_TIME 15 6113
TEST_TIME 16 6237
TEST_TIME 17 6961
TEST_TIME 18 7558
TEST_TIME 19 7813
TEST_TIME 20 8042
TEST_TIME 21 8219
TEST_TIME 22 8282
TEST_TIME 23 8562
TEST_TIME 24 9068
TEST_TIME 25 10182
TEST_TIME 26 10081
TEST_TIME 27 10393
TEST_TIME 28 10574
TEST_TIME 29 10411
TEST_TIME 30 10105
TEST_TIME 31 9885
TEST_TIME 32 10073
TEST_TIME 33 10124
TEST_TIME 34 9993
TEST_TIME 35 9821
TEST_TIME 36 9793
TEST_TIME 37 9791
TEST_TIME 38 9696
TEST_TIME 39 9737
TEST_TIME 40 9867
TEST_TIME 41 9753
TEST_TIME 42 9622
TEST_TIME 43 9531
TEST_TIME 44 9509
TEST_TIME 45 9546
TEST_TIME 46 9418
TEST_TIME 47 9250
TEST_TIME 48 9364
TEST_TIME 49 9468
TEST_TIME 50 9511
TEST_TIME 51 9454
TEST_TIME 52 9583
TEST_TIME 53 9541
TEST_TIME 54 9525
TEST_TIME 55 9439
TEST_TIME 56 9550
TEST_TIME 57 9514
TEST_TIME 58 9573
TEST_TIME 59 9574
TEST_TIME 60 9681
TEST_TIME 61 9727
TEST_TIME 62 9624
TEST_TIME 63 9518
TEST_TIME 64 9423
TEST_TIME 65 9422
TEST_TIME 66 9432
TEST_TIME 67 9342
TEST_TIME 68 9302
TEST_TIME 69 9318
TEST_TIME 70 9284
TEST_TIME 71 9196
TEST_TIME 72 9130
TEST_TIME 73 9106
TEST_TIME 74 9111
TEST_TIME 75 9050
TEST_TIME 76 9094
TEST_TIME 77 9118
TEST_TIME 78 9123
TEST_TIME 79 9090
TEST_TIME 80 9075
TEST_TIME 81 9206
TEST_TIME 82 9168
TEST_TIME 83 9260
TEST_TIME 84 9254
TEST_TIME 85 9242
TEST_TIME 86 9326
TEST_TIME 87 9300
TEST_TIME 88 9340
TEST_TIME 89 9243
TEST_TIME 90 9213
TEST_TIME 91 9225
TEST_TIME 92 9226
TEST_TIME 93 9194
TEST_TIME 94 9150
TEST_TIME 95 9149
TEST_TIME 96 9158
TEST_TIME 97 9170
TEST_TIME 98 9215
TEST_TIME 99 9261
TEST_TIME 100 9254
TEST_TIME 101 9259
TEST_TIME 102 9256
TEST_TIME 103 9232
TEST_TIME 104 9246
TEST_TIME 105 9254
TEST_TIME 106 9277
TEST_TIME 107 9320
TEST_TIME 108 9391
TEST_TIME 109 9412
TEST_TIME 110 9386
TEST_TIME 111 9316
TEST_TIME 112 9307
TEST_TIME 113 9264
TEST_TIME 114 9278
TEST_TIME 115 9265
TEST_TIME 116 9212
TEST_TIME 117 9206
TEST_TIME 118 9197
TEST_TIME 119 9205
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-11-2021 16:39:50 to 05-11-2021 16:39:54.

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 1534191 1534191 0 0.00 0 18150 6 0 0
doc_list_biud 1534185 1534185 0 0.00 0 28057 25 0 0
sp_add_invoice_to_stock 5165 5083 82 1.58 43 147998 17381 0 82
sp_cancel_adding_invoice 5534 5534 0 0.00 2 89244 787 0 0
sp_cancel_client_order 1829 1829 0 0.00 4 11048 350 0 0
sp_cancel_customer_reserve 542716 542716 0 0.00 1 28059 54 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 5057 78 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 5354 83 0 0
sp_cancel_supplier_invoice 6226 6218 8 0.12 1 18344 808 0 8
sp_cancel_supplier_order 414 414 0 0.00 11 1060312 91099 0 0
sp_cancel_write_off 9762 9762 0 0.00 1 10932 107 0 0
sp_client_order 20929 20929 0 0.00 3 22320 338 0 0
sp_customer_reserve 468929 468929 0 0.00 0 112894 172 0 0
sp_fill_shopping_cart 32136 32136 0 0.00 1 24320 40 0 0
sp_fill_shopping_cart_clo_res 468922 468922 0 0.00 0 16087 14 0 0
sp_get_clo_for_invoice 5076 5076 0 0.00 0 25250 289 0 0
sp_kill_cost_storno 16425 16425 0 0.00 0 7073 52 0 0
sp_kill_qstorno_ret_qs2qd 1101826 1101826 0 0.00 0 28028 30 0 0
sp_kill_qty_storno 581639 581639 0 0.00 0 28057 65 0 0
sp_lock_selected_doc 21019 21019 0 0.00 0 7712 5 0 0
sp_make_cost_storno 28101 28101 0 0.00 0 18123 51 0 0
sp_multiply_rows_for_pdistr 28101 28101 0 0.00 0 7693 28 0 0
sp_multiply_rows_for_qdistr 20929 20929 0 0.00 0 22273 162 0 0
sp_pay_from_customer 7175 7175 0 0.00 4 11641 102 0 0
sp_pay_to_supplier 5759 5759 0 0.00 4 12631 123 0 0
sp_payment_common 12933 12933 0 0.00 1 10023 28 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 0 11039 315 0 0
sp_qd_handle_on_invoice_upd_sts 10610 10610 0 0.00 0 22524 335 0 0
sp_qd_handle_on_reserve_upd_sts 10090 10090 0 0.00 0 7306 50 0 0
sp_reserve_write_off 10091 10091 0 0.00 6 13589 166 0 0
sp_supplier_invoice 5629 5629 0 0.00 7 82757 3304 0 0
sp_supplier_order 5590 5590 0 0.00 5 48380 1293 0 0
srv_aggregate_perf_data 92 92 0 0.00 2479 141352 19685 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2032 139823 45568 0 0
srv_find_qd_qs_mism 1050889 1050889 0 0.00 0 10668 5 0 0
srv_make_invnt_saldo 530 530 0 0.00 34 33904 1036 0 0
srv_make_money_saldo 763 763 0 0.00 1 4390 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 86062 439471 275398 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5083 5083 0 0.00 0 6820 145 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 1262 70 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 4322 16 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 4075 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 3143 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2411 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 1712 18 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 28086 1632 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 562 4 0 0
t$perf-norm:sp_client_order 20929 20929 0 0.00 0 7048 17 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 8084 15 0 0
t$perf-norm:sp_pay_from_customer 7175 7175 0 0.00 0 6168 10 0 0
t$perf-norm:sp_pay_to_supplier 5759 5759 0 0.00 0 8988 14 0 0
t$perf-norm:sp_reserve_write_off 10091 10091 0 0.00 0 5608 10 0 0
t$perf-norm:sp_supplier_invoice 5629 5629 0 0.00 0 6298 17 0 0
t$perf-norm:sp_supplier_order 5590 5590 0 0.00 0 8089 20 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 168 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 37 2 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 2497 25 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 801 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 86 24 0 0
v_add_invoice_to_stock 10316 10152 164 1.58 0 8311 18 0 164
v_all_customers 38168 38168 0 0.00 0 47 1 0 0
v_all_suppliers 30540 30540 0 0.00 0 115 1 0 0
v_all_wares 41854 41854 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 689 7 0 0
v_cancel_client_order 3658 3658 0 0.00 0 7224 8 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1913 8 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 690 5 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 656 3 0 0
v_cancel_supplier_invoice 898 882 16 1.78 0 1011 15 0 16
v_cancel_supplier_order 828 828 0 0.00 0 3152 13 0 0
v_cancel_write_off 1912 1912 0 0.00 0 2236 16 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 4934 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 3315 21 0 0
v_max_id_ord_sup 5620 5620 0 0.00 0 3311 4 0 0
v_max_non_paid_invoice 1697 1697 0 0.00 0 1077 4 0 0
v_max_non_paid_realizn 3831 3831 0 0.00 0 4502 7 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 2441 5 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 7962 15 0 0
v_min_id_ord_sup 5620 5620 0 0.00 0 6840 11 0 0
v_min_non_paid_invoice 5759 5759 0 0.00 0 7189 20 0 0
v_min_non_paid_realizn 7174 7174 0 0.00 0 10015 19 0 0
v_our_firm 10372 10372 0 0.00 0 14 1 0 0
v_reserve_write_off 20180 20180 0 0.00 0 5742 6 0 0
x_lock_depdocs_on_canc_invoice 11311 11311 0 0.00 0 18910 109 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 4778 316 0 0
x_make_qstorno_1000_1200 5590 5590 0 0.00 2 48343 1211 0 0
x_make_qstorno_1200_2000 5629 5629 0 0.00 3 82686 3162 0 0
x_make_qstorno_2100_3300 468929 468929 0 0.00 0 112893 156 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 05-11-2021 16:39:54 to 05-11-2021 16:39:59.

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:07:59 36 8192000000 8818717536 8836845568 19377840 0.235 6 3 4 0 19567264 82320 107104
14:09:01 28059 8192000000 9891592336 10205630464 971485360 10.601 100 82 163 1 998586416 5512432 21588624
14:10:35 17944 8192000000 9950201504 10274881536 1035521520 11.222 100 87 174 0 1066617664 8481232 22614912
14:11:56 15550 8192000000 10004686880 10338377728 1082511136 11.671 100 91 182 1 1119646512 14719216 22416160
14:13:19 11720 8192000000 10042279312 10390888448 1127497904 12.098 100 84 168 0 1158944160 11474400 19971856
14:14:39 8414 8192000000 10092283936 10451488768 1173052560 12.525 100 81 162 0 1203636000 11830000 18753440
14:15:50 8465 8192000000 10138733904 10514108416 1229506304 13.049 100 83 166 0 1259941280 11994640 18440336
14:17:01 3415 8192000000 10179120848 10561896448 1267705232 13.401 100 82 164 2 1299106496 13200432 18200832
14:18:06 2997 8192000000 10204320704 10594230272 1294296080 13.643 100 75 151 0 1323513856 12312864 16904912
14:19:10 21377 8192000000 10219741104 10614636544 1313577024 13.819 100 69 138 1 1337956720 8448688 15931008
14:20:32 6452 8192000000 10207592112 10621472768 1318827728 13.866 100 40 79 1 1336488064 8370144 9290192
14:21:40 45049 8192000000 10234220512 10633285632 1325307136 13.925 100 55 111 0 1343871808 5402272 13162400
14:23:25 6656 8192000000 10223580768 10642190336 1338529680 14.044 100 26 51 2 1354955600 11052208 5373712
14:24:32 12263 8192000000 10226715008 10646601728 1338341424 14.042 100 13 25 1 1346935552 5605488 2988640
14:25:45 62758 8192000000 10233229824 10657173504 1344961488 14.102 100 50 101 0 1363655104 6290944 12402672
14:27:49 7431 8192000000 10225063040 10642804736 1331932816 13.985 100 19 38 1 1341563200 5615680 4014704
14:28:58 9921 8192000000 10192204688 10601095168 1259872832 13.329 96 22 44 0 1271140400 5570656 5696912
14:30:09 16568 8192000000 10086404992 10462343168 1082969296 11.676 86 26 52 1 1094240000 4945552 6325152
14:31:26 21793 8192000000 10132601904 10526547968 1169437520 12.492 94 21 42 0 1183945344 10055456 4452368
14:32:51 5277 8192000000 10151199776 10554101760 1250562160 13.243 99 20 38 2 1261408496 6194000 4652336
14:33:58 6631 8192000000 10181158624 10589249536 1287381184 13.580 100 25 50 0 1303953392 10732272 5839936
14:35:06 71858 8192000000 10190372960 10604912640 1294719648 13.647 99 31 62 1 1307702688 5545168 7437872
14:37:21 9369 8192000000 10212944704 10631270400 1323973552 13.913 100 18 36 0 1333394256 4835504 4585200
14:38:35 23580 8192000000 10251564656 10659631104 1338822592 14.047 100 45 88 2 1356550096 7144608 10582896
14:40:00 19896 8192000000 10257411936 10677604352 1357574288 14.216 100 48 96 1 1381823456 12780144 11469024
14:41:27 18973 8192000000 10284468480 10698231808 1366345408 14.294 100 55 110 0 1391950400 13545552 12059440
14:42:53 26882 8192000000 10277220368 10708430848 1374680096 14.369 100 39 79 0 1403295312 19082256 9532960
14:44:25 14733 8192000000 10302465008 10725785600 1387098256 14.480 100 39 78 1 1416045472 19353952 9593264
14:45:50 10552 8192000000 10318450736 10741391360 1398370288 14.580 100 52 103 3 1435884672 25614160 11900224
14:47:05 21069 8192000000 10334312880 10762317824 1409879552 14.683 100 42 84 1 1446275888 25828480 10567856
14:48:34 15459 8192000000 10347190432 10779525120 1418008000 14.755 100 50 100 2 1463799760 33672192 12119568
14:50:05 2030 8192000000 10368106624 10785849344 1419967920 14.772 100 83 165 0 1474619952 33696112 20955920
14:51:13 8042 8192000000 10353010416 10772611072 1413036912 14.711 100 66 131 3 1464605344 35095088 16473344
14:52:26 2310 8192000000 10342516496 10750672896 1384297488 14.455 100 92 183 1 1449186720 41514992 23374240
14:53:33 4086 8192000000 10347993616 10752438272 1373633840 14.360 99 83 166 2 1440412960 46360080 20419040
14:54:43 2239 8192000000 10357221104 10772025344 1397544576 14.573 100 67 132 3 1461539440 47119280 16875584
14:55:53 7673 8192000000 10296847984 10708107264 1347622896 14.126 98 53 105 1 1394963120 33874016 13466208
14:57:07 2580 8192000000 10327518688 10740477952 1361161472 14.248 98 78 156 2 1414187472 33717536 19308464
14:58:13 5017 8192000000 10304953552 10727182336 1365800160 14.289 100 37 74 1 1411959152 36450496 9708496
14:59:20 23050 8192000000 10271920656 10692820992 1295007184 13.650 96 33 67 3 1341700000 37609024 9083792
15:00:57 28961 8192000000 10291852144 10720010240 1328201472 13.951 98 31 62 1 1383871664 46855280 8814912
15:02:31 12374 8192000000 10285265984 10722807808 1365067952 14.283 100 25 50 0 1399119792 27772608 6279232
15:03:59 37436 8192000000 10290842176 10730328064 1376517520 14.385 100 22 43 0 1400218352 18309568 5391264
15:05:37 38905 8192000000 10273628304 10705223680 1342571008 14.081 98 31 63 2 1358561136 7901600 8088528
15:07:22 59209 8192000000 10299862208 10726363136 1356318832 14.204 99 44 88 1 1374897728 7851840 10727056
15:09:25 108669 8192000000 10285093680 10727874560 1351586736 14.162 98 22 44 0 1368764720 12670240 4507744
15:12:15 34547 8192000000 10300357840 10742349824 1380036416 14.417 99 27 54 1 1397619088 11594880 5987792
15:13:51 38994 8192000000 10304395216 10755727360 1395031600 14.551 100 28 54 1 1416618176 14574800 7011776
15:15:31 14861 8192000000 10316484560 10760290304 1400961216 14.604 100 42 85 1 1424838656 14633424 9244016
15:16:48 45259 8192000000 10303808752 10759610368 1383113008 14.444 99 20 40 0 1408164160 19776864 5274288
15:18:39 76048 8192000000 10317485856 10770731008 1411532240 14.698 100 23 46 0 1427386032 10423520 5430272
15:20:56 62983 8192000000 10302867376 10759434240 1381201968 14.427 98 16 32 0 1400462624 15220064 4040592
15:23:02 53281 8192000000 10325166320 10778570752 1412401680 14.705 100 13 27 1 1436536896 21136960 2998256
15:25:04 51694 8192000000 10310590352 10776399872 1405853104 14.647 99 19 38 0 1428649008 18632464 4163440
15:27:04 26715 8192000000 10309281600 10764574720 1375205504 14.374 98 36 73 1 1394428512 10823472 8399536
15:28:38 70538 8192000000 10302454448 10770677760 1372057472 14.345 98 17 34 2 1394982432 18504176 4420784
15:30:49 51871 8192000000 10283178928 10737070080 1352096672 14.166 97 14 28 0 1364624192 9263600 3263920
15:32:42 84937 8192000000 10292189824 10744950784 1358408224 14.223 98 21 42 1 1372890544 10041664 4440656
15:35:07 42700 8192000000 10297514672 10747019264 1358111936 14.220 98 30 58 0 1378407024 13569472 6725616
15:36:52 139823 8192000000 10254673760 10700754944 1244130752 13.184 91 6 12 0 1266238736 20919664 1188320
15:40:24 79567 8192000000 10250374480 10692874240 1281131632 13.523 94 26 52 1 1298100784 11361120 5608032
15:42:46 20944 8192000000 10260882752 10700861440 1349587712 14.144 100 23 43 0 1361611392 7307872 4715808
15:44:08 49705 8192000000 10274518400 10726010880 1353683840 14.181 99 6 12 0 1363382912 8574400 1124672
15:46:00 49085 8192000000 10246811088 10703200256 1316907360 13.849 96 12 24 0 1330997520 11056832 3033328
15:47:54 73496 8192000000 10243518544 10699739136 1350001216 14.147 100 12 24 1 1360128528 7326992 2800320
15:50:09 61275 8192000000 10236805680 10696855552 1329880080 13.966 98 13 26 0 1342755632 9935072 2940480
15:52:13 72272 8192000000 10232781584 10693558272 1267445440 13.398 94 10 20 0 1282938288 13469088 2023760
15:54:35 48696 8192000000 10253152704 10706935808 1328396704 13.953 98 15 29 2 1345313360 14219488 2697168
15:56:29 85835 8192000000 10281150256 10729324544 1357847040 14.218 99 26 52 0 1380173296 16075008 6251248
15:58:57 21357 8192000000 10276657760 10719260672 1352740768 14.172 99 18 36 1 1366906704 9890528 4275408
16:00:29 68425 8192000000 10290098352 10740043776 1364482960 14.278 99 16 32 1 1378290784 10516960 3290864
16:02:38 55525 8192000000 10284450160 10747318272 1376296288 14.383 100 15 30 1 1396360816 16397328 3667200
16:04:34 35484 8192000000 10301896448 10748379136 1382383696 14.438 100 41 82 0 1407521520 15603168 9534656
16:06:11 118450 8192000000 10303478816 10764496896 1386683904 14.476 100 20 40 1 1411586096 20559728 4342464
16:09:10 77692 8192000000 10288758304 10757615616 1371515632 14.341 98 7 14 0 1381424720 8765968 1143120
16:11:31 26978 8192000000 10312113744 10756939776 1388669600 14.494 99 30 59 0 1402785712 7427504 6688608
16:13:03 69229 8192000000 10295160864 10755760128 1380282672 14.419 99 10 20 0 1398191600 15215088 2693840
16:15:16 73281 8192000000 10298987328 10770350080 1371786736 14.343 98 11 22 0 1394930128 20427952 2715440
16:17:36 44073 8192000000 10315397008 10770628608 1385252656 14.463 99 13 26 0 1401634528 13755888 2625984
16:19:29 47262 8192000000 10302314720 10743099392 1352721792 14.172 97 27 54 2 1367595280 9146080 5727408
16:21:18 69502 8192000000 10286969264 10728128512 1283133296 13.542 92 31 61 3 1304817872 14992912 6691664
16:23:30 52176 8192000000 10284331584 10735128576 1356376288 14.205 99 20 39 0 1376488560 15965200 4147072
16:25:36 57543 8192000000 10277942064 10721583104 1312905392 13.812 96 24 48 0 1330927744 12427280 5595072
16:27:37 69422 8192000000 10286448592 10719707136 1360770160 14.244 100 23 46 1 1377810400 11868640 5171600
16:29:56 53135 8192000000 10277241712 10721775616 1353093776 14.175 99 34 68 0 1371150480 10042544 8014160
16:32:25 84337 8192000000 10249830736 10697625600 1286471408 13.572 94 9 18 0 1299069680 10290336 2307936
16:34:52 63558 8192000000 10244902832 10704367616 1329952352 13.967 98 13 26 1 1345515776 12232736 3330688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 16:40:00 to 05-11-2021 16:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2021 16:40:16 to 05-11-2021 16:40:18.

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          241470
MON$OLDEST_ACTIVE               243024
MON$OLDEST_SNAPSHOT             243024
MON$NEXT_TRANSACTION            243024
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                       2038368
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:66: 
:67: mscs-test-161.budzdorov.ru    Fri Nov  5 16:37:56 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Fri Nov  5 16:37:59 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Fri Nov  5 16:38:03 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Fri Nov  5 16:38:03 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Fri Nov  5 16:38:04 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
--- end of diff output ---

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

05.11.21 16:40:19. 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-11-05 16:40:22 No severe PSQL-related problems occured
05.11.21 16:40:23. End of report.