Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16328296 kB
MemAvailable 31197480 kB
Buffers 0 kB
Cached 15356864 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-22 19:38:20
DTS_END                         2022-06-22 19:38:20.1800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35918
TRN_ID                          249335
ATT_ID                          661
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: 9534.16 18743 1144099
customer order: creation 185.05 361 22206
customer order: refuse 15.29 333 1835
order to supplier: creation 49.40 1241 5928
order to supplier: removal 3.48 97997 417
invoice (draft): creation 49.06 2991 5887
invoice (draft): removal 52.55 761 6306
invoice accept: apply 45.07 17488 5408
invoice accept: cancel 47.42 798 5690
customer reserve: creation 4141.05 173 496926
customer reserve: removal 4631.58 58 555789
realization accept: apply 91.11 158 10933
realization accept: cancel 82.08 111 9849
payment from customer: creation 65.44 100 7853
payment from customer: removal 6.22 67 746
payment to supplier: creation 52.43 113 6291
payment to supplier: removal 3.83 85 460
service: total inventory turnovers 5.14 971 617
service: total monetary turnovers 6.98 32 838
service: aggregate perf. data 0.97 16257 116
service: refresh index statistics 0.03 234756 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 19:39:19 to 22-06-2022 19:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4524
TEST_TIME 2 4866
TEST_TIME 3 5807
TEST_TIME 4 5220
TEST_TIME 5 5078
TEST_TIME 6 4942
TEST_TIME 7 5713
TEST_TIME 8 5904
TEST_TIME 9 5852
TEST_TIME 10 6265
TEST_TIME 11 6474
TEST_TIME 12 6648
TEST_TIME 13 6849
TEST_TIME 14 6988
TEST_TIME 15 7466
TEST_TIME 16 7693
TEST_TIME 17 7734
TEST_TIME 18 8170
TEST_TIME 19 8416
TEST_TIME 20 9128
TEST_TIME 21 9613
TEST_TIME 22 9694
TEST_TIME 23 9797
TEST_TIME 24 9974
TEST_TIME 25 9891
TEST_TIME 26 10260
TEST_TIME 27 10986
TEST_TIME 28 10999
TEST_TIME 29 10848
TEST_TIME 30 10857
TEST_TIME 31 10533
TEST_TIME 32 10654
TEST_TIME 33 10532
TEST_TIME 34 10342
TEST_TIME 35 10328
TEST_TIME 36 10246
TEST_TIME 37 10323
TEST_TIME 38 10258
TEST_TIME 39 10258
TEST_TIME 40 10144
TEST_TIME 41 10138
TEST_TIME 42 9936
TEST_TIME 43 9954
TEST_TIME 44 9894
TEST_TIME 45 9828
TEST_TIME 46 9814
TEST_TIME 47 9736
TEST_TIME 48 9781
TEST_TIME 49 9777
TEST_TIME 50 9734
TEST_TIME 51 9866
TEST_TIME 52 9829
TEST_TIME 53 9825
TEST_TIME 54 9861
TEST_TIME 55 9889
TEST_TIME 56 9939
TEST_TIME 57 10001
TEST_TIME 58 10038
TEST_TIME 59 10053
TEST_TIME 60 9940
TEST_TIME 61 9916
TEST_TIME 62 9833
TEST_TIME 63 9811
TEST_TIME 64 9763
TEST_TIME 65 9736
TEST_TIME 66 9684
TEST_TIME 67 9639
TEST_TIME 68 9610
TEST_TIME 69 9622
TEST_TIME 70 9600
TEST_TIME 71 9554
TEST_TIME 72 9646
TEST_TIME 73 9743
TEST_TIME 74 9710
TEST_TIME 75 9756
TEST_TIME 76 9795
TEST_TIME 77 9720
TEST_TIME 78 9776
TEST_TIME 79 9747
TEST_TIME 80 9852
TEST_TIME 81 9775
TEST_TIME 82 9712
TEST_TIME 83 9659
TEST_TIME 84 9616
TEST_TIME 85 9587
TEST_TIME 86 9558
TEST_TIME 87 9523
TEST_TIME 88 9531
TEST_TIME 89 9559
TEST_TIME 90 9525
TEST_TIME 91 9517
TEST_TIME 92 9531
TEST_TIME 93 9636
TEST_TIME 94 9671
TEST_TIME 95 9647
TEST_TIME 96 9664
TEST_TIME 97 9688
TEST_TIME 98 9708
TEST_TIME 99 9674
TEST_TIME 100 9681
TEST_TIME 101 9689
TEST_TIME 102 9709
TEST_TIME 103 9699
TEST_TIME 104 9622
TEST_TIME 105 9584
TEST_TIME 106 9592
TEST_TIME 107 9561
TEST_TIME 108 9512
TEST_TIME 109 9505
TEST_TIME 110 9464
TEST_TIME 111 9426
TEST_TIME 112 9506
TEST_TIME 113 9505
TEST_TIME 114 9472
TEST_TIME 115 9474
TEST_TIME 116 9492
TEST_TIME 117 9536
TEST_TIME 118 9588
TEST_TIME 119 9584
TEST_TIME 120 9536

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2022 19:39:23 to 22-06-2022 19:39:26.

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 1602642 1602642 0 0.00 0 25926 6 0 0
doc_list_biud 1602642 1602642 0 0.00 0 27114 26 0 0
sp_add_invoice_to_stock 5534 5408 126 2.27 26 147115 17488 0 126
sp_cancel_adding_invoice 5690 5690 0 0.00 5 61069 799 0 0
sp_cancel_client_order 1835 1835 0 0.00 4 10927 334 0 0
sp_cancel_customer_reserve 555789 555789 0 0.00 1 27116 58 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 2 4002 68 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 5461 85 0 0
sp_cancel_supplier_invoice 6321 6306 15 0.23 1 11618 762 0 15
sp_cancel_supplier_order 417 417 0 0.00 10 1039168 97998 0 0
sp_cancel_write_off 9849 9849 0 0.00 1 22612 111 0 0
sp_client_order 22206 22206 0 0.00 3 24419 362 0 0
sp_customer_reserve 496926 496926 0 0.00 0 36760 174 0 0
sp_fill_shopping_cart 34015 34015 0 0.00 1 17992 39 0 0
sp_fill_shopping_cart_clo_res 496921 496921 0 0.00 0 12921 14 0 0
sp_get_clo_for_invoice 5403 5403 0 0.00 0 16348 304 0 0
sp_kill_cost_storno 16745 16745 0 0.00 0 4407 52 0 0
sp_kill_qstorno_ret_qs2qd 1128150 1128150 0 0.00 0 27111 32 0 0
sp_kill_qty_storno 596221 596221 0 0.00 0 27114 68 0 0
sp_lock_selected_doc 22487 22487 0 0.00 0 8493 6 0 0
sp_make_cost_storno 30479 30479 0 0.00 0 11796 43 0 0
sp_multiply_rows_for_pdistr 30479 30479 0 0.00 0 9866 24 0 0
sp_multiply_rows_for_qdistr 22206 22206 0 0.00 0 18522 175 0 0
sp_pay_from_customer 7853 7853 0 0.00 4 12606 100 0 0
sp_pay_to_supplier 6291 6291 0 0.00 5 12944 113 0 0
sp_payment_common 14144 14144 0 0.00 1 12912 22 0 0
sp_qd_handle_on_cancel_clo 1835 1835 0 0.00 0 10919 306 0 0
sp_qd_handle_on_invoice_upd_sts 11092 11092 0 0.00 0 21059 313 0 0
sp_qd_handle_on_reserve_upd_sts 10933 10933 0 0.00 0 24666 54 0 0
sp_reserve_write_off 10933 10933 0 0.00 6 29835 158 0 0
sp_supplier_invoice 5887 5887 0 0.00 5 77367 2991 0 0
sp_supplier_order 5928 5928 0 0.00 5 87955 1242 0 0
srv_aggregate_perf_data 116 116 0 0.00 2861 217885 16258 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3181 108908 45558 0 0
srv_find_qd_qs_mism 1091760 1091760 0 0.00 0 25926 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 35 45001 972 0 0
srv_make_money_saldo 838 838 0 0.00 0 3203 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 104872 389934 234756 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5408 5408 0 0.00 0 23948 157 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 2206 57 0 0
t$perf-norm:sp_cancel_client_order 1835 1835 0 0.00 0 6457 20 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 1801 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 2094 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 792 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 2560 20 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 24756 1490 0 0
t$perf-norm:sp_cancel_write_off 1089 1089 0 0.00 0 14607 29 0 0
t$perf-norm:sp_client_order 22206 22206 0 0.00 0 14643 18 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 4463 10 0 0
t$perf-norm:sp_pay_from_customer 7853 7853 0 0.00 0 6323 9 0 0
t$perf-norm:sp_pay_to_supplier 6291 6291 0 0.00 0 9142 10 0 0
t$perf-norm:sp_reserve_write_off 10933 10933 0 0.00 0 10486 15 0 0
t$perf-norm:sp_supplier_invoice 5887 5887 0 0.00 0 8975 20 0 0
t$perf-norm:sp_supplier_order 5928 5928 0 0.00 0 5217 19 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1906 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 106 3 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 6372 39 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 1439 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 52 16 0 0
v_add_invoice_to_stock 11054 10802 252 2.27 0 6951 18 0 252
v_all_customers 40392 40392 0 0.00 0 29 1 0 0
v_all_suppliers 32632 32632 0 0.00 0 67 1 0 0
v_all_wares 44412 44412 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1576 10 0 0
v_cancel_client_order 3670 3670 0 0.00 0 2471 7 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 1464 5 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 1640 5 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 1893 6 0 0
v_cancel_supplier_invoice 892 862 30 3.36 0 1092 16 0 30
v_cancel_supplier_order 834 834 0 0.00 0 3164 11 0 0
v_cancel_write_off 2178 2178 0 0.00 0 3164 15 0 0
v_max_id_clo_ord 5926 5926 0 0.00 0 403 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 2400 15 0 0
v_max_id_ord_sup 5883 5883 0 0.00 0 3159 4 0 0
v_max_non_paid_invoice 1784 1784 0 0.00 0 1397 3 0 0
v_max_non_paid_realizn 4225 4225 0 0.00 0 3092 3 0 0
v_min_id_clo_ord 5926 5926 0 0.00 0 17329 17 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 1817 4 0 0
v_min_id_ord_sup 5883 5883 0 0.00 0 7654 6 0 0
v_min_non_paid_invoice 6291 6291 0 0.00 0 7897 15 0 0
v_min_non_paid_realizn 7853 7853 0 0.00 0 7566 15 0 0
v_our_firm 11276 11276 0 0.00 0 36 1 0 0
v_reserve_write_off 21862 21862 0 0.00 0 3557 6 0 0
x_lock_depdocs_on_canc_invoice 11565 11565 0 0.00 0 27107 116 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 10267 363 0 0
x_make_qstorno_1000_1200 5928 5928 0 0.00 1 87937 1149 0 0
x_make_qstorno_1200_2000 5887 5887 0 0.00 2 77348 2865 0 0
x_make_qstorno_2100_3300 496926 496926 0 0.00 0 36719 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 19:39:27 to 22-06-2022 19:39:31.

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:08:24 14 8192000000 8800549456 8812527616 5315664 0.064 2 1 2 0 5415264 56816 42784
17:09:25 14006 8192000000 9889269968 10197471232 973672192 10.623 100 84 166 1 1001891648 6064000 22155456
17:10:44 18904 8192000000 9951531056 10273628160 1033385312 11.201 100 89 177 2 1064287536 7974736 22927488
17:12:09 17645 8192000000 10000306496 10333024256 1075800352 11.607 100 89 179 0 1108175648 10082048 22293248
17:13:35 15843 8192000000 10031824608 10378129408 1119868432 12.026 100 82 162 2 1150145840 10153888 20123520
17:14:53 8894 8192000000 10078463120 10438242304 1165699824 12.457 100 70 140 0 1193120944 11445696 15975424
17:16:08 6460 8192000000 10127804480 10497695744 1218747392 12.950 100 86 172 0 1250762656 12755904 19259360
17:17:16 4554 8192000000 10164938272 10537029632 1250064912 13.239 100 86 173 0 1282300176 13592688 18642576
17:18:22 2727 8192000000 10201001216 10579111936 1282819456 13.539 100 90 181 1 1316084304 13214832 20050016
17:19:26 29922 8192000000 10209657216 10603462656 1303031120 13.723 100 57 115 4 1326044256 9818064 13195072
17:20:56 7302 8192000000 10208867952 10611003392 1312470144 13.808 100 27 52 1 1323959504 5741344 5748016
17:22:04 10012 8192000000 10208907792 10626441216 1323920784 13.912 100 19 37 2 1334429680 5519040 4989856
17:23:17 38610 8192000000 10224946400 10643128320 1330059728 13.968 100 40 80 1 1346687200 7630576 8996896
17:24:56 4144 8192000000 10237583968 10653659136 1336811712 14.029 100 40 81 0 1353970112 7257120 9901280
17:26:03 4433 8192000000 10230882832 10651688960 1337255232 14.033 100 37 73 1 1352131040 6276752 8599056
17:27:11 61896 8192000000 10232365424 10648473600 1311461952 13.799 98 42 84 1 1327153072 5701280 9989840
17:29:16 9313 8192000000 10188723648 10606891008 1274247872 13.460 97 21 42 1 1284857968 5237472 5372624
17:30:26 5909 8192000000 10159144080 10558197760 1245751472 13.199 98 22 45 3 1256284816 5649776 4883568
17:31:33 50749 8192000000 10138411296 10512285696 1189082448 12.675 96 48 95 4 1206317488 5318864 11916176
17:33:24 5136 8192000000 10156316160 10556456960 1261510880 13.344 100 32 64 1 1275799504 6972352 7316272
17:34:30 7168 8192000000 10169340816 10579652608 1280970288 13.522 100 21 42 0 1291975808 5212656 5792864
17:35:38 9937 8192000000 10186205856 10600542208 1294445824 13.645 100 16 28 3 1307987776 9677152 3864800
17:36:52 49857 8192000000 10195607136 10612584448 1306799248 13.757 100 37 73 0 1320545936 4555568 9191120
17:38:47 18917 8192000000 10252277312 10659815424 1341086016 14.067 100 52 102 3 1360779568 8518128 11175424
17:40:14 3175 8192000000 10282008128 10690396160 1357650112 14.216 100 63 126 0 1388662080 17130208 13881760
17:41:22 7010 8192000000 10289355168 10706841600 1375161952 14.373 100 56 112 0 1406631648 18960784 12508912
17:42:33 59413 8192000000 10299849712 10721677312 1371462208 14.340 99 37 74 0 1400680848 20693472 8525168
17:44:59 14471 8192000000 10317414976 10741768192 1394324496 14.544 100 45 90 1 1430962512 25489520 11148496
17:46:21 3751 8192000000 10343455328 10764419072 1411104832 14.694 100 56 111 1 1450692416 26039728 13547856
17:47:29 5065 8192000000 10349702576 10764599296 1408688096 14.672 100 71 142 2 1456916720 31138320 17090304
17:48:38 6184 8192000000 10356933600 10778714112 1417360448 14.749 100 64 128 0 1470025520 35953312 16711760
17:49:52 20943 8192000000 10369311920 10800893952 1431894848 14.878 100 45 90 1 1477792480 33778336 12119296
17:51:18 5247 8192000000 10356075056 10777522176 1407112016 14.658 99 67 134 2 1454489296 31313104 16064176
17:52:29 5835 8192000000 10369480992 10789257216 1425354448 14.820 100 63 125 2 1475971200 34142656 16474096
17:53:40 4348 8192000000 10349649440 10759188480 1385308000 14.464 99 78 156 2 1446208944 41098896 19802048
17:54:50 7385 8192000000 10337861024 10749517824 1389555616 14.502 100 63 126 0 1445775536 39940400 16279520
17:56:05 3508 8192000000 10366205808 10776387584 1399071632 14.587 100 80 159 1 1466433904 47286176 20076096
17:57:13 5005 8192000000 10342830352 10752729088 1378986080 14.407 99 62 121 0 1440867872 45530240 16351552
17:58:29 7522 8192000000 10341691584 10762829824 1383893840 14.451 100 58 113 1 1444784448 45631216 15259392
17:59:39 7348 8192000000 10323893680 10752917504 1383314128 14.446 100 35 70 2 1424677680 32287072 9076480
18:00:53 15803 8192000000 10299577072 10738032640 1361900672 14.254 99 24 48 1 1399431472 30897424 6633376
18:02:12 4110 8192000000 10314766624 10748227584 1382856544 14.442 100 37 74 1 1423352672 30952624 9543504
18:03:18 19978 8192000000 10291333504 10726715392 1346156736 14.113 99 25 50 0 1386858928 33781920 6920272
18:04:39 68131 8192000000 10274199568 10733264896 1311723312 13.802 96 16 32 2 1361767680 45485456 4558912
18:06:53 30240 8192000000 10290792656 10717487104 1353495856 14.179 99 64 126 2 1381892192 13273808 15122528
18:08:39 100875 8192000000 10285104640 10720280576 1347281904 14.123 98 31 61 2 1366157584 11969360 6906320
18:11:25 27794 8192000000 10282311408 10727886848 1365340384 14.285 99 21 40 0 1381678192 11129696 5208112
18:12:58 5489 8192000000 10312468752 10741530624 1383731984 14.450 100 69 137 0 1411054976 12442992 14880000
18:14:06 37011 8192000000 10301210480 10748186624 1381582736 14.431 99 33 63 1 1399023504 9906000 7534768
18:15:49 52632 8192000000 10301442848 10767298560 1395840464 14.558 100 8 16 0 1413817200 16218992 1757744
18:17:43 52318 8192000000 10297279248 10758840320 1398052912 14.578 100 13 26 1 1409897584 9011584 2833088
18:19:42 41419 8192000000 10283551216 10742915072 1391518352 14.519 100 12 24 0 1405383728 10986096 2879280
18:21:45 22504 8192000000 10297997968 10758791168 1396426304 14.563 100 17 34 0 1410152256 9918080 3807872
18:23:08 64310 8192000000 10299185824 10772922368 1402052464 14.613 100 11 22 0 1417249344 12658992 2537888
18:25:21 9602 8192000000 10304439968 10756755456 1392655072 14.530 99 56 111 2 1414253120 7505984 14092064
18:26:30 46728 8192000000 10289546384 10753548288 1393478512 14.537 100 11 22 0 1404910928 8787424 2644992
18:28:19 95389 8192000000 10291847856 10750308352 1342825952 14.083 95 21 42 0 1357808064 9947536 5034576
18:31:08 26257 8192000000 10300176688 10747719680 1380480672 14.421 99 43 86 2 1406898432 15961600 10456160
18:32:36 56390 8192000000 10290315168 10742120448 1357108592 14.211 98 33 66 0 1381038400 16710976 7218832
18:34:34 97934 8192000000 10277669504 10728710144 1287141216 13.578 93 26 52 4 1307230928 13348576 6741136
18:37:22 86206 8192000000 10234448272 10682519552 1278081072 13.495 94 15 30 1 1293116368 11503280 3532016
18:40:13 43921 8192000000 10245964176 10682818560 1313997728 13.822 98 47 92 1 1332223568 8576592 9649248
18:42:07 50770 8192000000 10259440096 10722205696 1350217120 14.149 99 12 24 0 1368075200 15134448 2723632
18:43:58 83115 8192000000 10260968224 10722729984 1338192560 14.041 98 12 24 0 1351531664 10387152 2951952
18:47:12 50006 8192000000 10289130960 10746540032 1358535776 14.224 98 18 36 1 1380134384 17219888 4378720
18:49:54 78179 8192000000 10261791488 10725584896 1325525632 13.927 97 15 30 0 1349880816 20799216 3555968
18:52:13 49177 8192000000 10278634048 10731724800 1371522064 14.341 100 35 70 0 1395075600 15697232 7856304
18:54:05 85520 8192000000 10280540304 10718806016 1342222912 14.077 97 28 55 0 1360059296 11781120 6055264
18:56:35 45912 8192000000 10279224512 10722160640 1353236432 14.177 99 29 58 0 1370657056 11061232 6359392
18:58:21 87238 8192000000 10267770112 10728738816 1344669072 14.099 98 15 30 0 1360129392 12451424 3008896
19:01:18 64785 8192000000 10282622480 10730287104 1364758640 14.280 99 33 66 1 1382176704 9746608 7671456
19:03:23 50560 8192000000 10264462336 10726330368 1361296144 14.249 99 9 18 0 1375912128 12579744 2036240
19:05:14 64595 8192000000 10281145888 10748698624 1385775024 14.468 100 11 23 0 1396616352 8022224 2819104
19:07:22 57789 8192000000 10281422896 10735599616 1358073024 14.220 98 5 10 0 1371348576 12225056 1050496
19:09:23 57070 8192000000 10307869472 10762944512 1386620144 14.476 99 25 50 1 1407461808 15265392 5576272
19:11:20 82742 8192000000 10285842160 10743369728 1351298368 14.159 97 13 26 0 1366077456 12151360 2627728
19:13:58 75941 8192000000 10283559200 10740736000 1348181984 14.131 97 29 58 0 1373334224 18637744 6514496
19:16:25 80702 8192000000 10248371840 10691751936 1230197760 13.056 89 13 26 1 1243641696 10755520 2688416
19:18:49 50278 8192000000 10263308368 10712371200 1344585408 14.099 99 14 28 0 1359132784 11677984 2869392
19:20:41 86356 8192000000 10260227328 10709372928 1322353600 13.898 97 21 42 0 1337542672 10228576 4960496
19:23:11 81225 8192000000 10278251040 10732396544 1336463744 14.026 97 28 56 2 1359738736 16550624 6724368
19:25:33 62593 8192000000 10256067152 10715422720 1314101136 13.823 96 11 21 0 1330577968 14194048 2282784
19:27:38 55934 8192000000 10270901168 10731560960 1360586848 14.243 100 13 26 1 1378454736 14752784 3115104
19:29:53 108908 8192000000 10274709344 10728529920 1290547312 13.609 94 6 12 0 1306588224 14852336 1188576
19:32:42 69194 8192000000 10285420928 10736541696 1345255344 14.105 98 29 57 2 1369220976 16993472 6972160
19:34:52 101618 8192000000 10261799728 10710585344 1292406864 13.626 94 19 38 0 1312297072 15380880 4509328
19:37:55 45282 8192000000 9123711440 9361993728 279690608 3.301 21 4 8 0 285851296 5239888 920800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-06-2022 19:39:33 to 22-06-2022 19:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2022 19:39:49 to 22-06-2022 19:39:51.

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          250232
MON$OLDEST_ACTIVE               250233
MON$OLDEST_SNAPSHOT             250233
MON$NEXT_TRANSACTION            250233
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                       2017600
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:275: 
:276: mscs-test-161.budzdorov.ru    Wed Jun 22 19:38:43 2022
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
:279: 
:280: mscs-test-161.budzdorov.ru    Wed Jun 22 19:38:45 2022
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:282: 
:283: 
:284: mscs-test-161.budzdorov.ru    Wed Jun 22 19:38:45 2022
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:286: 
:287: 
:288: mscs-test-161.budzdorov.ru    Wed Jun 22 19:38:45 2022
:289:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:290: 
:291: 
:292: mscs-test-161.budzdorov.ru    Wed Jun 22 19:38:45 2022
:293:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:294: 
--- end of diff output ---

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

22.06.22 19:39:52. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-22 19:39:54 No severe PSQL-related problems occured
22.06.22 19:39:56. End of report.