Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.2021 16:44
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 16052512 kB
MemAvailable 30837300 kB
Buffers 0 kB
Cached 15668924 kB
SwapCached 2476 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-04 16:43:08
DTS_END                         2021-11-04 16:43:08.6750
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/58100
TRN_ID                          232415
ATT_ID                          648
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: 9338.23 24507 1120587
customer order: creation 159.63 353 19155
customer order: refuse 14.06 373 1687
order to supplier: creation 43.18 1249 5182
order to supplier: removal 3.65 98257 438
invoice (draft): creation 41.99 3140 5039
invoice (draft): removal 54.88 782 6586
invoice accept: apply 39.83 18736 4780
invoice accept: cancel 49.64 841 5957
customer reserve: creation 3747.21 180 449665
customer reserve: removal 4896.07 57 587528
realization accept: apply 79.60 221 9552
realization accept: cancel 85.93 107 10312
payment from customer: creation 56.58 130 6789
payment from customer: removal 5.58 78 670
payment to supplier: creation 46.41 137 5569
payment to supplier: removal 3.27 114 392
service: total inventory turnovers 4.05 1195 486
service: total monetary turnovers 5.92 29 710
service: aggregate perf. data 0.72 23430 86
service: refresh index statistics 0.03 340733 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2021 16:44:36 to 04-11-2021 16:44:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5936
TEST_TIME 2 4468
TEST_TIME 3 3695
TEST_TIME 4 3877
TEST_TIME 5 3503
TEST_TIME 6 3742
TEST_TIME 7 3817
TEST_TIME 8 4533
TEST_TIME 9 4732
TEST_TIME 10 4897
TEST_TIME 11 5592
TEST_TIME 12 5565
TEST_TIME 13 5825
TEST_TIME 14 6080
TEST_TIME 15 6439
TEST_TIME 16 7013
TEST_TIME 17 7317
TEST_TIME 18 7695
TEST_TIME 19 8072
TEST_TIME 20 8385
TEST_TIME 21 8674
TEST_TIME 22 8798
TEST_TIME 23 8926
TEST_TIME 24 9059
TEST_TIME 25 9484
TEST_TIME 26 9730
TEST_TIME 27 9968
TEST_TIME 28 10341
TEST_TIME 29 10372
TEST_TIME 30 10531
TEST_TIME 31 10601
TEST_TIME 32 10512
TEST_TIME 33 10370
TEST_TIME 34 10233
TEST_TIME 35 10116
TEST_TIME 36 10036
TEST_TIME 37 10037
TEST_TIME 38 9976
TEST_TIME 39 10070
TEST_TIME 40 10013
TEST_TIME 41 9861
TEST_TIME 42 9756
TEST_TIME 43 9670
TEST_TIME 44 9632
TEST_TIME 45 9646
TEST_TIME 46 9606
TEST_TIME 47 9659
TEST_TIME 48 9490
TEST_TIME 49 9449
TEST_TIME 50 9340
TEST_TIME 51 9355
TEST_TIME 52 9370
TEST_TIME 53 9319
TEST_TIME 54 9332
TEST_TIME 55 9501
TEST_TIME 56 9507
TEST_TIME 57 9525
TEST_TIME 58 9527
TEST_TIME 59 9587
TEST_TIME 60 9804
TEST_TIME 61 9796
TEST_TIME 62 9797
TEST_TIME 63 9860
TEST_TIME 64 9960
TEST_TIME 65 9952
TEST_TIME 66 10000
TEST_TIME 67 10042
TEST_TIME 68 9991
TEST_TIME 69 9911
TEST_TIME 70 9832
TEST_TIME 71 9879
TEST_TIME 72 9825
TEST_TIME 73 9771
TEST_TIME 74 9777
TEST_TIME 75 9773
TEST_TIME 76 9687
TEST_TIME 77 9730
TEST_TIME 78 9679
TEST_TIME 79 9600
TEST_TIME 80 9580
TEST_TIME 81 9521
TEST_TIME 82 9502
TEST_TIME 83 9496
TEST_TIME 84 9510
TEST_TIME 85 9503
TEST_TIME 86 9500
TEST_TIME 87 9490
TEST_TIME 88 9465
TEST_TIME 89 9490
TEST_TIME 90 9526
TEST_TIME 91 9555
TEST_TIME 92 9587
TEST_TIME 93 9644
TEST_TIME 94 9644
TEST_TIME 95 9604
TEST_TIME 96 9540
TEST_TIME 97 9567
TEST_TIME 98 9519
TEST_TIME 99 9531
TEST_TIME 100 9483
TEST_TIME 101 9446
TEST_TIME 102 9394
TEST_TIME 103 9397
TEST_TIME 104 9369
TEST_TIME 105 9390
TEST_TIME 106 9397
TEST_TIME 107 9370
TEST_TIME 108 9362
TEST_TIME 109 9381
TEST_TIME 110 9443
TEST_TIME 111 9421
TEST_TIME 112 9415
TEST_TIME 113 9431
TEST_TIME 114 9485
TEST_TIME 115 9475
TEST_TIME 116 9499
TEST_TIME 117 9438
TEST_TIME 118 9416
TEST_TIME 119 9411
TEST_TIME 120 9341

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-11-2021 16:44:39 to 04-11-2021 16:44:43.

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 1537511 1537511 0 0.00 0 29059 6 0 0
doc_list_biud 1537510 1537510 0 0.00 0 87967 28 0 0
sp_add_invoice_to_stock 4898 4780 118 2.40 44 169355 18736 0 118
sp_cancel_adding_invoice 5957 5957 0 0.00 5 62249 842 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 19460 373 0 0
sp_cancel_customer_reserve 587528 587528 0 0.00 1 36031 58 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 6247 78 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 9691 114 0 0
sp_cancel_supplier_invoice 6597 6586 11 0.16 2 87986 782 0 11
sp_cancel_supplier_order 438 438 0 0.00 7 1098708 98257 0 0
sp_cancel_write_off 10312 10312 0 0.00 1 33535 107 0 0
sp_client_order 19155 19155 0 0.00 3 62865 353 0 0
sp_customer_reserve 449665 449665 0 0.00 0 80906 181 0 0
sp_fill_shopping_cart 29374 29374 0 0.00 1 20082 36 0 0
sp_fill_shopping_cart_clo_res 449655 449655 0 0.00 0 17568 16 0 0
sp_get_clo_for_invoice 4770 4770 0 0.00 0 44709 389 0 0
sp_kill_cost_storno 17331 17331 0 0.00 0 17480 54 0 0
sp_kill_qstorno_ret_qs2qd 1192392 1192392 0 0.00 0 87955 31 0 0
sp_kill_qty_storno 626829 626829 0 0.00 0 87962 67 0 0
sp_lock_selected_doc 19880 19880 0 0.00 0 8781 9 0 0
sp_make_cost_storno 26680 26680 0 0.00 0 28545 74 0 0
sp_multiply_rows_for_pdistr 26679 26679 0 0.00 0 20291 33 0 0
sp_multiply_rows_for_qdistr 19155 19155 0 0.00 0 62856 171 0 0
sp_pay_from_customer 6789 6789 0 0.00 4 19008 130 0 0
sp_pay_to_supplier 5569 5569 0 0.00 5 25078 138 0 0
sp_payment_common 12358 12358 0 0.00 1 18995 39 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 19424 340 0 0
sp_qd_handle_on_invoice_upd_sts 10727 10727 0 0.00 0 27768 329 0 0
sp_qd_handle_on_reserve_upd_sts 9551 9551 0 0.00 0 11702 64 0 0
sp_reserve_write_off 9552 9552 0 0.00 5 23542 222 0 0
sp_supplier_invoice 5039 5039 0 0.00 6 100553 3141 0 0
sp_supplier_order 5182 5182 0 0.00 5 93235 1250 0 0
srv_aggregate_perf_data 86 86 0 0.00 2476 457365 23430 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 2491 145638 54900 0 0
srv_find_qd_qs_mism 1075462 1075462 0 0.00 0 13720 5 0 0
srv_make_invnt_saldo 486 486 0 0.00 37 37829 1195 0 0
srv_make_money_saldo 710 710 0 0.00 1 3135 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 171703 443190 340733 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4780 4780 0 0.00 0 10882 177 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 6293 120 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 2962 15 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 2133 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 2878 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 4948 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 3476 29 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 18825 1811 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 6160 15 0 0
t$perf-norm:sp_client_order 19155 19155 0 0.00 0 8882 21 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 2560 10 0 0
t$perf-norm:sp_pay_from_customer 6789 6789 0 0.00 0 8659 10 0 0
t$perf-norm:sp_pay_to_supplier 5569 5569 0 0.00 0 9670 16 0 0
t$perf-norm:sp_reserve_write_off 9552 9552 0 0.00 0 7024 14 0 0
t$perf-norm:sp_supplier_invoice 5039 5039 0 0.00 0 9245 24 0 0
t$perf-norm:sp_supplier_order 5182 5182 0 0.00 0 8254 19 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 185 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 144 3 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 716 19 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 662 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 6 5 0 0
v_add_invoice_to_stock 9776 9540 236 2.41 0 11587 21 0 236
v_all_customers 35218 35218 0 0.00 0 12 1 0 0
v_all_suppliers 28634 28634 0 0.00 0 16 1 0 0
v_all_wares 38310 38310 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3753 14 0 0
v_cancel_client_order 3374 3374 0 0.00 0 2001 5 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1353 6 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 4752 10 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 2144 15 0 0
v_cancel_supplier_invoice 802 780 22 2.74 0 1586 19 0 22
v_cancel_supplier_order 876 876 0 0.00 0 638 4 0 0
v_cancel_write_off 1874 1874 0 0.00 0 2975 14 0 0
v_max_id_clo_ord 5181 5181 0 0.00 0 5693 3 0 0
v_max_id_clo_res 949 949 0 0.00 0 2604 12 0 0
v_max_id_ord_sup 5038 5038 0 0.00 0 2630 3 0 0
v_max_non_paid_invoice 1471 1471 0 0.00 0 1671 3 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 6535 8 0 0
v_min_id_clo_ord 5181 5181 0 0.00 0 8189 11 0 0
v_min_id_clo_res 949 949 0 0.00 0 1154 4 0 0
v_min_id_ord_sup 5038 5038 0 0.00 0 10438 10 0 0
v_min_non_paid_invoice 5569 5569 0 0.00 0 14596 30 0 0
v_min_non_paid_realizn 6789 6789 0 0.00 0 15784 28 0 0
v_our_firm 9388 9388 0 0.00 0 10 1 0 0
v_reserve_write_off 19102 19102 0 0.00 0 4407 6 0 0
x_lock_depdocs_on_canc_invoice 12153 12153 0 0.00 0 24808 125 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 25724 396 0 0
x_make_qstorno_1000_1200 5182 5182 0 0.00 2 92258 1165 0 0
x_make_qstorno_1200_2000 5039 5039 0 0.00 2 94394 3020 0 0
x_make_qstorno_2100_3300 449665 449665 0 0.00 0 80904 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-11-2021 16:44:44 to 04-11-2021 16:44:48.

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:13:12 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:14:13 33019 8192000000 9896867312 10206846976 971464512 10.601 100 90 180 1 1001783008 5792944 24525552
14:15:52 12637 8192000000 9947983680 10270945280 1031740672 11.185 100 88 176 0 1062720208 7849520 23130016
14:17:08 14323 8192000000 9979369376 10311180288 1064410848 11.499 100 85 170 0 1095360768 9256256 21693664
14:18:26 12737 8192000000 10029781424 10377846784 1118099984 12.009 100 79 158 0 1146850512 9956720 18793808
14:19:43 10335 8192000000 10079816688 10442305536 1171029184 12.506 100 76 152 0 1200616208 11835232 17751792
14:20:58 6681 8192000000 10127307824 10497171456 1216487664 12.929 100 78 156 0 1246905680 13419184 16998832
14:22:07 2844 8192000000 10164863264 10542010368 1249720400 13.236 100 84 168 1 1283471200 14624752 19126048
14:23:12 1797 8192000000 10196562960 10576355328 1278225200 13.497 100 89 179 1 1311780240 14158160 19396880
14:24:15 1735 8192000000 10218846400 10603130880 1299771328 13.693 100 86 172 0 1332384032 13172144 19440560
14:25:22 3990 8192000000 10219454000 10609922048 1306155872 13.751 100 64 128 2 1328137296 6375184 15606240
14:26:26 6207 8192000000 10206013536 10616442880 1312901312 13.812 100 34 68 0 1326350608 5587872 7861424
14:27:35 11181 8192000000 10202901584 10621046784 1319533136 13.872 100 12 24 0 1327397392 5423984 2440272
14:28:47 9136 8192000000 10209101248 10632974336 1327439888 13.944 100 10 20 1 1335314176 5610912 2263376
14:29:57 51588 8192000000 10253537744 10654744576 1333810224 14.002 100 66 132 3 1355808016 6411728 15586064
14:31:49 8342 8192000000 10223572048 10644393984 1320513152 13.881 99 26 52 3 1333015264 6621248 5880864
14:33:01 9637 8192000000 10211561344 10626260992 1285540160 13.564 97 17 34 1 1298724000 9668048 3515792
14:34:11 50887 8192000000 10170815952 10577354752 1241191232 13.157 96 47 95 2 1258011136 5839472 10980432
14:36:07 5306 8192000000 10145839536 10547884032 1243595328 13.179 99 34 68 0 1257792208 6677632 7519248
14:37:12 6283 8192000000 10131993808 10530054144 1204870704 12.822 96 23 46 0 1215379600 5255296 5253600
14:38:22 13267 8192000000 10166621280 10570817536 1269801632 13.420 100 26 49 2 1281789776 5940912 6047232
14:39:35 21074 8192000000 10171582416 10582831104 1283370784 13.544 100 22 44 2 1294112368 6015552 4726032
14:40:57 7187 8192000000 10184178016 10599923712 1296697136 13.665 100 14 26 2 1305632272 5920688 3014448
14:42:05 15262 8192000000 10193350160 10613346304 1306946128 13.758 100 20 40 0 1317284944 5874224 4464592
14:43:24 4710 8192000000 10237277920 10647056384 1338499072 14.044 100 53 106 2 1356367248 5393760 12474416
14:44:37 33590 8192000000 10246616576 10666778624 1347750176 14.127 100 28 55 4 1364690736 10206576 6733984
14:46:12 79512 8192000000 10268221056 10695757824 1366167264 14.293 100 42 83 0 1389480704 13716848 9596592
14:48:34 68368 8192000000 10287808640 10712326144 1373002880 14.354 100 21 40 2 1392865712 15018416 4844416
14:50:48 22424 8192000000 10310571072 10732756992 1388332528 14.491 100 55 110 0 1422533936 20976320 13225088
14:52:16 5012 8192000000 10323100864 10745995264 1401309648 14.607 100 54 108 0 1432979680 19136336 12533696
14:53:26 15246 8192000000 10340371584 10761248768 1397376144 14.572 99 66 132 1 1438753360 25690000 15687216
14:54:47 6513 8192000000 10329245440 10759434240 1403633920 14.627 100 57 113 2 1439718736 21928816 14156000
14:56:02 2882 8192000000 10358628208 10775859200 1415929488 14.737 100 81 163 2 1463235456 27753520 19552448
14:57:10 2836 8192000000 10360015760 10776862720 1413166384 14.712 100 80 156 0 1464497152 31469936 19860832
14:58:21 7322 8192000000 10361028704 10777616384 1401770288 14.611 99 74 147 2 1456539936 36161616 18608032
14:59:38 4930 8192000000 10364474144 10787119104 1413696288 14.717 100 65 130 1 1465897824 34869920 17331616
15:00:46 2489 8192000000 10364279280 10777817088 1400413056 14.599 100 85 170 0 1462960464 40481712 22065696
15:01:52 6953 8192000000 10324692032 10741448704 1375830944 14.379 99 70 139 2 1430866272 36922448 18112880
15:03:02 10123 8192000000 10325192256 10756452352 1387821360 14.486 100 44 85 4 1430836496 31550624 11464512
15:04:23 12442 8192000000 10332810048 10756980736 1378125024 14.400 99 46 90 1 1424861680 33884448 12852208
15:05:38 12044 8192000000 10309215776 10732343296 1365615328 14.288 99 46 92 1 1406380944 29163056 11602560
15:06:54 13428 8192000000 10321891648 10758152192 1380530208 14.421 100 28 56 0 1424813776 36280096 8003472
15:08:10 18948 8192000000 10301600912 10737745920 1356203712 14.203 100 34 68 3 1408884688 42997872 9683104
15:09:32 24084 8192000000 10292649152 10721603584 1351413440 14.160 100 22 44 2 1394863136 37219520 6230176
15:11:02 28583 8192000000 10286220944 10728783872 1328996352 13.958 98 24 48 0 1381739024 46197616 6545056
15:12:32 72355 8192000000 10260672848 10694754304 1322976656 13.904 98 28 54 1 1345270928 15735728 6558544
15:14:46 25219 8192000000 10255144224 10696060928 1340196816 14.059 99 24 49 0 1358500624 13024400 5279408
15:16:13 79424 8192000000 10271074640 10712965120 1351329136 14.159 99 33 66 1 1367491408 8596624 7565648
15:18:58 99688 8192000000 10269890080 10716966912 1343921936 14.093 98 11 22 0 1358396160 11848240 2625984
15:21:39 69443 8192000000 10290762560 10745102336 1381878288 14.433 100 13 26 0 1400644000 16138352 2627360
15:23:49 25125 8192000000 10297627488 10730803200 1378841808 14.406 100 44 87 5 1396595808 7358880 10395120
15:25:19 92314 8192000000 10293597216 10735992832 1385443520 14.465 100 29 58 0 1403140832 10277904 7419408
15:27:56 64574 8192000000 10310838928 10758443008 1398457504 14.581 100 27 54 1 1415480144 10736752 6285888
15:30:02 97505 8192000000 10310124160 10776719360 1409149696 14.676 100 28 56 2 1430826320 15360800 6315824
15:32:40 43131 8192000000 10324208784 10777931776 1392759536 14.530 98 18 36 0 1411831008 14696864 4374608
15:34:24 83993 8192000000 10316570512 10790645760 1401168256 14.605 99 21 42 0 1430763568 23899632 5695680
15:36:50 76702 8192000000 10324615216 10805178368 1410346448 14.687 100 11 22 0 1438052848 25001376 2705024
15:39:48 63049 8192000000 10314708320 10777956352 1398287616 14.580 99 19 39 1 1420694768 17706912 4700240
15:41:57 114272 8192000000 10349034768 10831302656 1428702544 14.850 100 14 28 0 1452308480 20344000 3261936
15:44:52 112555 8192000000 10312018752 10772398080 1329381648 13.962 93 23 46 1 1351480496 17273584 4825264
15:47:48 101849 8192000000 10315021856 10774024192 1361011136 14.246 97 14 28 0 1375642192 11679408 2951648
15:51:04 67393 8192000000 10314005120 10751180800 1332638192 13.991 95 40 80 1 1355674912 14431888 8604832
15:53:15 138788 8192000000 10285907984 10748325888 1339101408 14.049 96 29 57 1 1355488736 9680160 6707168
15:58:14 74558 8192000000 10283935968 10744340480 1373463424 14.358 99 13 26 0 1389105344 12554544 3087376
16:00:28 56010 8192000000 10267695552 10727055360 1350328112 14.150 98 10 19 1 1360346768 7734400 2284256
16:02:27 100470 8192000000 10267733920 10730012672 1358876000 14.227 99 12 24 1 1369092176 7578336 2637840
16:06:14 79359 8192000000 10259539920 10710597632 1271669360 13.437 92 11 22 0 1283725264 9791600 2264304
16:08:37 44713 8192000000 10267798320 10714689536 1337050400 14.031 98 21 42 1 1354956992 12953120 4953472
16:10:26 39872 8192000000 10273049136 10713116672 1338824048 14.047 98 24 46 0 1353110048 9454256 4831744
16:12:09 44459 8192000000 10274945936 10720100352 1340421136 14.061 98 34 68 1 1361428448 13243088 7764224
16:14:01 46027 8192000000 10283182016 10733879296 1357196960 14.212 99 18 36 0 1370140832 9451776 3492096
16:15:51 126322 8192000000 10271093232 10722295808 1322837040 13.902 96 15 30 1 1341933088 15943600 3152448
16:18:57 145638 8192000000 10276921040 10733268992 1358761888 14.226 99 14 28 1 1375084384 13226016 3096480
16:22:33 48263 8192000000 10258754496 10724847616 1356731664 14.208 99 8 16 0 1368741568 10303280 1706624
16:24:28 63744 8192000000 10273675328 10732048384 1369197216 14.320 99 8 16 0 1377840432 6768352 1874864
16:26:33 76682 8192000000 10275153120 10741235712 1373685616 14.360 99 15 31 0 1385364432 8080896 3597920
16:29:03 24233 8192000000 10292908160 10746691584 1391736032 14.521 100 25 48 2 1405288912 7731152 5821728
16:30:28 41825 8192000000 10312961200 10755350528 1391392256 14.518 100 34 68 1 1408905120 9594864 7918000
16:32:11 62673 8192000000 10284490368 10751225856 1361057360 14.247 98 16 33 1 1380830480 15585680 4187440
16:34:25 76478 8192000000 10301392432 10762555392 1371115136 14.337 98 26 52 1 1387420128 10629056 5675936
16:36:45 127417 8192000000 10271446288 10723409920 1244195552 13.185 89 12 24 0 1256340912 9936208 2209152
16:39:55 95534 8192000000 10283395520 10740125696 1353057552 14.175 98 17 34 1 1375286800 17962544 4266704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-11-2021 16:44:50 to 04-11-2021 16:45:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          232401
MON$OLDEST_ACTIVE               232891
MON$OLDEST_SNAPSHOT             232891
MON$NEXT_TRANSACTION            232891
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                       2031200
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Nov  4 16:43:11 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Nov  4 16:43:13 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Nov  4 16:43:13 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

04.11.21 16:45:08. 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-04 16:45:10 No severe PSQL-related problems occured
04.11.21 16:45:12. End of report.