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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15941824 kB
MemAvailable 31039992 kB
Buffers 0 kB
Cached 15798248 kB
SwapCached 2512 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.11
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-07-30 22:38:23
DTS_END                         2022-07-30 22:38:23.6630
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/52428
TRN_ID                          240507
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: 9418.14 24619 1130177
customer order: creation 169.88 375 20386
customer order: refuse 15.07 382 1808
order to supplier: creation 45.61 1316 5473
order to supplier: removal 3.54 110544 425
invoice (draft): creation 45.73 3461 5488
invoice (draft): removal 55.26 819 6631
invoice accept: apply 40.73 18559 4887
invoice accept: cancel 49.98 812 5998
customer reserve: creation 3777.52 183 453302
customer reserve: removal 4917.48 63 590097
realization accept: apply 81.21 203 9745
realization accept: cancel 86.75 121 10410
payment from customer: creation 58.18 123 6982
payment from customer: removal 5.28 92 634
payment to supplier: creation 49.55 134 5946
payment to supplier: removal 3.31 84 397
service: total inventory turnovers 5.08 1026 609
service: total monetary turnovers 7.16 43 859
service: aggregate perf. data 0.80 22730 96
service: refresh index statistics 0.03 331318 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-07-2022 22:40:22 to 30-07-2022 22:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4747
TEST_TIME 2 5024
TEST_TIME 3 4308
TEST_TIME 4 5106
TEST_TIME 5 5023
TEST_TIME 6 4950
TEST_TIME 7 5120
TEST_TIME 8 6060
TEST_TIME 9 6050
TEST_TIME 10 6068
TEST_TIME 11 6236
TEST_TIME 12 6421
TEST_TIME 13 6551
TEST_TIME 14 6640
TEST_TIME 15 6846
TEST_TIME 16 6874
TEST_TIME 17 6924
TEST_TIME 18 7146
TEST_TIME 19 7317
TEST_TIME 20 7399
TEST_TIME 21 8100
TEST_TIME 22 8304
TEST_TIME 23 8249
TEST_TIME 24 8163
TEST_TIME 25 8072
TEST_TIME 26 9157
TEST_TIME 27 9878
TEST_TIME 28 10748
TEST_TIME 29 10649
TEST_TIME 30 10750
TEST_TIME 31 10653
TEST_TIME 32 10456
TEST_TIME 33 10251
TEST_TIME 34 10485
TEST_TIME 35 10557
TEST_TIME 36 10435
TEST_TIME 37 10358
TEST_TIME 38 10299
TEST_TIME 39 10224
TEST_TIME 40 10064
TEST_TIME 41 10087
TEST_TIME 42 10108
TEST_TIME 43 10100
TEST_TIME 44 10021
TEST_TIME 45 9905
TEST_TIME 46 9892
TEST_TIME 47 9809
TEST_TIME 48 9683
TEST_TIME 49 9612
TEST_TIME 50 9643
TEST_TIME 51 9737
TEST_TIME 52 9691
TEST_TIME 53 9668
TEST_TIME 54 9661
TEST_TIME 55 9631
TEST_TIME 56 9739
TEST_TIME 57 9788
TEST_TIME 58 9772
TEST_TIME 59 9986
TEST_TIME 60 9940
TEST_TIME 61 9979
TEST_TIME 62 10103
TEST_TIME 63 10138
TEST_TIME 64 10159
TEST_TIME 65 10016
TEST_TIME 66 9948
TEST_TIME 67 9939
TEST_TIME 68 9849
TEST_TIME 69 9893
TEST_TIME 70 9859
TEST_TIME 71 9852
TEST_TIME 72 9836
TEST_TIME 73 9816
TEST_TIME 74 9921
TEST_TIME 75 9894
TEST_TIME 76 9885
TEST_TIME 77 9912
TEST_TIME 78 10036
TEST_TIME 79 10088
TEST_TIME 80 10045
TEST_TIME 81 9974
TEST_TIME 82 9887
TEST_TIME 83 9887
TEST_TIME 84 9805
TEST_TIME 85 9768
TEST_TIME 86 9765
TEST_TIME 87 9712
TEST_TIME 88 9681
TEST_TIME 89 9702
TEST_TIME 90 9665
TEST_TIME 91 9616
TEST_TIME 92 9567
TEST_TIME 93 9580
TEST_TIME 94 9570
TEST_TIME 95 9611
TEST_TIME 96 9579
TEST_TIME 97 9632
TEST_TIME 98 9632
TEST_TIME 99 9650
TEST_TIME 100 9627
TEST_TIME 101 9665
TEST_TIME 102 9651
TEST_TIME 103 9674
TEST_TIME 104 9663
TEST_TIME 105 9682
TEST_TIME 106 9754
TEST_TIME 107 9767
TEST_TIME 108 9759
TEST_TIME 109 9699
TEST_TIME 110 9670
TEST_TIME 111 9637
TEST_TIME 112 9660
TEST_TIME 113 9640
TEST_TIME 114 9585
TEST_TIME 115 9565
TEST_TIME 116 9527
TEST_TIME 117 9483
TEST_TIME 118 9477
TEST_TIME 119 9444
TEST_TIME 120 9422

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-07-2022 22:40:26 to 30-07-2022 22:40:29.

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 1550612 1550612 0 0.00 0 37387 6 0 0
doc_list_biud 1550610 1550610 0 0.00 0 29112 30 0 0
sp_add_invoice_to_stock 5011 4887 124 2.47 36 202968 18559 0 124
sp_cancel_adding_invoice 5998 5998 0 0.00 4 51808 813 0 0
sp_cancel_client_order 1808 1808 0 0.00 3 10780 383 0 0
sp_cancel_customer_reserve 590097 590097 0 0.00 0 17645 63 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 5618 93 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 4806 85 0 0
sp_cancel_supplier_invoice 6638 6631 7 0.10 2 16363 819 0 7
sp_cancel_supplier_order 425 425 0 0.00 6 1108885 110544 0 0
sp_cancel_write_off 10410 10410 0 0.00 1 29235 122 0 0
sp_client_order 20386 20386 0 0.00 3 24965 376 0 0
sp_customer_reserve 453302 453302 0 0.00 0 111996 184 0 0
sp_fill_shopping_cart 31335 31335 0 0.00 1 15559 40 0 0
sp_fill_shopping_cart_clo_res 453295 453295 0 0.00 0 16197 14 0 0
sp_get_clo_for_invoice 4880 4880 0 0.00 0 17660 294 0 0
sp_kill_cost_storno 17439 17439 0 0.00 0 15545 59 0 0
sp_kill_qstorno_ret_qs2qd 1197660 1197660 0 0.00 0 29110 34 0 0
sp_kill_qty_storno 629994 629994 0 0.00 0 29111 73 0 0
sp_lock_selected_doc 20430 20430 0 0.00 0 8949 7 0 0
sp_make_cost_storno 27553 27553 0 0.00 0 37377 58 0 0
sp_multiply_rows_for_pdistr 27553 27553 0 0.00 0 8359 28 0 0
sp_multiply_rows_for_qdistr 20386 20386 0 0.00 0 21113 156 0 0
sp_pay_from_customer 6982 6982 0 0.00 4 12689 124 0 0
sp_pay_to_supplier 5946 5946 0 0.00 4 37587 134 0 0
sp_payment_common 12928 12928 0 0.00 1 12118 33 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 10770 337 0 0
sp_qd_handle_on_invoice_upd_sts 10878 10878 0 0.00 0 15733 329 0 0
sp_qd_handle_on_reserve_upd_sts 9745 9745 0 0.00 0 14481 71 0 0
sp_reserve_write_off 9745 9745 0 0.00 6 15004 203 0 0
sp_supplier_invoice 5488 5488 0 0.00 5 89842 3462 0 0
sp_supplier_order 5473 5473 0 0.00 5 28442 1316 0 0
srv_aggregate_perf_data 96 96 0 0.00 2425 326110 22731 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 853 137978 40738 0 0
srv_find_qd_qs_mism 1083329 1083329 0 0.00 0 30338 5 0 0
srv_make_invnt_saldo 609 609 0 0.00 39 72313 1027 0 0
srv_make_money_saldo 859 859 0 0.00 0 9558 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 142962 419878 331319 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 0 11833 165 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 5604 131 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 5129 15 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 2268 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 1867 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 285 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 731 16 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 24490 1571 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 2332 9 0 0
t$perf-norm:sp_client_order 20386 20386 0 0.00 0 15519 16 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 4077 10 0 0
t$perf-norm:sp_pay_from_customer 6982 6982 0 0.00 0 3724 8 0 0
t$perf-norm:sp_pay_to_supplier 5946 5946 0 0.00 0 4797 12 0 0
t$perf-norm:sp_reserve_write_off 9745 9745 0 0.00 0 14291 9 0 0
t$perf-norm:sp_supplier_invoice 5488 5488 0 0.00 0 9732 17 0 0
t$perf-norm:sp_supplier_order 5473 5473 0 0.00 0 3242 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 1014 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 745 20 0 0
t$perf-norm:srv_make_invnt_saldo 609 609 0 0.00 0 1196 25 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 2197 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 837 211 0 0
v_add_invoice_to_stock 10008 9760 248 2.47 0 4391 19 0 248
v_all_customers 37194 37194 0 0.00 0 26 1 0 0
v_all_suppliers 30764 30764 0 0.00 0 23 1 0 0
v_all_wares 40772 40772 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 1890 13 0 0
v_cancel_client_order 3616 3616 0 0.00 0 4903 9 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 3872 10 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 1426 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 620 3 0 0
v_cancel_supplier_invoice 840 826 14 1.66 0 4772 27 0 14
v_cancel_supplier_order 850 850 0 0.00 0 853 6 0 0
v_cancel_write_off 2042 2042 0 0.00 0 6249 26 0 0
v_max_id_clo_ord 5469 5469 0 0.00 0 1218 1 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 2884 15 0 0
v_max_id_ord_sup 5480 5480 0 0.00 0 5697 7 0 0
v_max_non_paid_invoice 1513 1513 0 0.00 0 416 2 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 3688 5 0 0
v_min_id_clo_ord 5469 5469 0 0.00 0 4961 6 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 6512 19 0 0
v_min_id_ord_sup 5480 5480 0 0.00 0 13615 9 0 0
v_min_non_paid_invoice 5946 5946 0 0.00 0 12115 22 0 0
v_min_non_paid_realizn 6982 6982 0 0.00 0 9476 27 0 0
v_our_firm 10160 10160 0 0.00 0 26 1 0 0
v_reserve_write_off 19488 19488 0 0.00 0 5346 8 0 0
x_lock_depdocs_on_canc_invoice 12216 12216 0 0.00 0 13898 127 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 5662 353 0 0
x_make_qstorno_1000_1200 5473 5473 0 0.00 1 28432 1237 0 0
x_make_qstorno_1200_2000 5488 5488 0 0.00 3 89798 3317 0 0
x_make_qstorno_2100_3300 453302 453302 0 0.00 0 111981 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-07-2022 22:40:30 to 30-07-2022 22:40:34.

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
20:08:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:28 16171 8192000000 9899812960 10208382976 983135568 10.715 100 90 180 0 1013080864 6432224 23513072
20:10:52 19150 8192000000 9939201744 10259263488 1014153920 11.016 100 84 168 0 1044481664 8227424 22100320
20:12:16 14208 8192000000 9965495984 10295611392 1050472832 11.365 100 84 168 0 1080901968 9346896 21082240
20:13:35 11453 8192000000 10016700208 10357530624 1097771056 11.816 100 76 152 1 1126243168 10415760 18056352
20:14:57 6950 8192000000 10072502672 10430951424 1163700928 12.438 100 78 155 0 1193236912 11805680 17730304
20:16:07 3443 8192000000 10112031920 10479112192 1198426480 12.762 100 83 165 0 1231165728 13421216 19318032
20:17:12 1643 8192000000 10155723792 10527625216 1239718096 13.144 100 97 194 0 1275511024 13529248 22263680
20:18:16 2112 8192000000 10187225264 10566467584 1270230000 13.424 100 88 177 0 1303352112 13376032 19746080
20:19:20 1582 8192000000 10195373088 10585571328 1286244512 13.570 100 74 149 1 1315774272 13008064 16521696
20:20:23 36040 8192000000 10184811360 10592841728 1295219712 13.652 100 29 56 1 1307825552 6119968 6485872
20:21:59 4721 8192000000 10195177424 10608447488 1309256272 13.779 100 46 87 3 1325000368 5300992 10443104
20:23:05 8251 8192000000 10186385232 10605838336 1311832640 13.803 100 25 48 0 1322274192 4955584 5485968
20:24:16 15342 8192000000 10205661744 10623397888 1316463008 13.845 100 21 41 0 1330890032 9958224 4468800
20:25:32 42860 8192000000 10223211920 10635829248 1322974144 13.904 100 52 98 1 1340299984 4727456 12598384
20:27:40 4638 8192000000 10220486704 10638073856 1329089808 13.959 100 24 48 0 1340349840 5257664 6002368
20:28:46 7057 8192000000 10182903744 10595528704 1263560352 13.363 96 19 38 0 1273406432 5439088 4406992
20:29:54 7390 8192000000 10145873920 10552397824 1235652752 13.106 97 22 40 1 1246023184 5713920 4656512
20:31:02 33701 8192000000 10116188352 10515501056 1162309472 12.425 92 17 34 0 1171216112 4784656 4121984
20:32:36 3436 8192000000 10147881936 10546987008 1255903136 13.292 100 33 67 2 1268937888 5406768 7627984
20:33:43 6835 8192000000 10163172240 10566189056 1272727104 13.447 100 29 58 3 1284960112 5504944 6728064
20:34:50 4220 8192000000 10191904528 10598875136 1296313056 13.662 100 33 66 0 1309470096 5247536 7909504
20:35:57 39029 8192000000 10186622672 10605395968 1301179408 13.706 100 21 42 0 1311571520 5641072 4751040
20:37:40 6778 8192000000 10198050320 10611064832 1307003712 13.759 100 28 56 0 1319039264 5891760 6143792
20:38:56 34584 8192000000 10244629792 10661339136 1344503136 14.098 100 41 82 0 1360570352 6017792 10049424
20:40:50 17383 8192000000 10273901600 10687913984 1368192416 14.311 100 45 88 1 1389972336 11050384 10729536
20:42:09 19932 8192000000 10275138704 10701201408 1370654304 14.333 100 47 93 0 1394962704 13943536 10364864
20:43:35 12050 8192000000 10295542944 10718863360 1385512512 14.466 100 37 71 0 1407810608 13880656 8417440
20:44:54 12930 8192000000 10314306976 10734374912 1396354448 14.563 100 53 104 1 1426919440 18472096 12092896
20:46:11 19462 8192000000 10314806544 10742575104 1399301616 14.589 100 46 91 0 1432139408 21078928 11758864
20:47:33 7024 8192000000 10324567968 10752487424 1411286480 14.695 100 51 98 0 1442437920 19279504 11871936
20:48:47 2321 8192000000 10347624336 10763223040 1404145168 14.632 99 72 144 1 1446028064 23652768 18230128
20:49:53 2411 8192000000 10330196464 10752258048 1397073296 14.569 99 60 120 0 1438440192 26107056 15259840
20:51:04 2546 8192000000 10343090720 10759008256 1402044320 14.613 100 74 149 0 1448462784 28635760 17782704
20:52:13 852 8192000000 10346821840 10756141056 1402140960 14.614 100 81 161 1 1452097520 30100720 19855840
20:53:18 4604 8192000000 10349722528 10760413184 1398800896 14.584 100 72 142 0 1453669520 37261856 17606768
20:54:30 4620 8192000000 10356333984 10768949248 1402161904 14.614 100 68 136 1 1461089200 41891696 17035600
20:55:40 2994 8192000000 10370941024 10784419840 1412325296 14.705 100 76 150 1 1477193008 45428768 19438944
20:56:50 2531 8192000000 10377218160 10784038912 1397187648 14.570 99 87 174 0 1471939808 52752976 21999184
20:57:59 1951 8192000000 10353153008 10768359424 1392922608 14.532 100 69 137 0 1465106480 54364064 17819808
20:59:11 2975 8192000000 10359025024 10768596992 1394556880 14.547 99 67 133 0 1468849008 56770896 17521232
21:00:24 14605 8192000000 10371555088 10797277184 1405097200 14.640 99 44 85 0 1466249952 49303712 11849040
21:01:47 10479 8192000000 10363630816 10788085760 1409728320 14.682 100 43 86 1 1471453776 50128416 11597040
21:03:06 9027 8192000000 10370369696 10795888640 1398288976 14.580 100 45 85 0 1476918688 66113824 12515888
21:04:27 6157 8192000000 10353066000 10795233280 1378731008 14.405 100 36 72 0 1461004336 71144976 11128352
21:05:36 36878 8192000000 10296471328 10749464576 1338443776 14.043 98 17 34 0 1387906544 44768896 4693872
21:07:15 58685 8192000000 10275895648 10720440320 1347346528 14.124 99 32 66 1 1364180400 9055040 7778832
21:09:42 112490 8192000000 10308958832 10743832576 1370219168 14.329 99 31 57 1 1387517920 10906608 6392144
21:12:41 67450 8192000000 10291189840 10763202560 1372391216 14.348 99 14 26 0 1392892816 17700800 2800800
21:14:54 137379 8192000000 10287731936 10745303040 1377739136 14.396 99 27 54 0 1391672512 8203664 5729712
21:18:17 21134 8192000000 10304441744 10748518400 1382766864 14.441 99 36 72 0 1402182880 11046912 8369104
21:19:43 47868 8192000000 10305802928 10758995968 1379536960 14.412 99 32 64 0 1406632544 19947040 7148544
21:22:09 34807 8192000000 10298221408 10750394368 1387431792 14.483 99 13 26 0 1397989056 7367664 3189600
21:23:44 53851 8192000000 10287856640 10753159168 1381552496 14.430 99 16 32 0 1396374352 10605120 4216736
21:25:42 26560 8192000000 10289137616 10739171328 1369153648 14.319 98 53 101 3 1388449024 4994240 14301136
21:27:19 48372 8192000000 10316360448 10773348352 1407265216 14.660 100 26 52 0 1428265360 14967536 6032608
21:29:08 63755 8192000000 10300754128 10783989760 1401179280 14.605 100 12 24 0 1420717632 16527696 3010656
21:31:12 32140 8192000000 10281854944 10741198848 1364612240 14.279 98 16 32 0 1376609312 8267728 3729344
21:32:45 50723 8192000000 10307127008 10768162816 1390213184 14.508 99 16 32 0 1404207136 10245776 3748176
21:34:36 28452 8192000000 10309074576 10766184448 1397185472 14.570 100 17 34 0 1411704256 10420368 4098416
21:36:05 41980 8192000000 10309883040 10752393216 1376487408 14.385 99 14 28 0 1391779216 11893664 3398144
21:37:48 64386 8192000000 10283067136 10738507776 1356997584 14.210 98 8 16 0 1366877264 8846640 1033040
21:39:57 90490 8192000000 10280172320 10752466944 1339449760 14.052 97 11 22 0 1359550368 17996608 2104000
21:42:30 106934 8192000000 10275099856 10712604672 1338115168 14.040 97 31 62 0 1353881792 8124656 7641968
21:45:20 44040 8192000000 10265957312 10723643392 1349361888 14.142 99 8 16 1 1358058784 7057776 1639120
21:47:04 50503 8192000000 10252628848 10707668992 1313744720 13.820 97 15 31 0 1330290656 13057248 3488688
21:48:55 15040 8192000000 10261238144 10696876032 1343340016 14.088 100 48 96 0 1366443168 11922016 11181136
21:50:14 46879 8192000000 10253056080 10689208320 1295932720 13.658 96 28 56 0 1314216256 11905536 6378000
21:52:17 40124 8192000000 10239129856 10695036928 1330684576 13.973 100 13 26 0 1347979840 14329792 2965472
21:54:10 54957 8192000000 10276698000 10717163520 1324342080 13.916 98 11 22 0 1342140336 15027104 2771152
21:56:17 84029 8192000000 10273879264 10737790976 1302834144 13.721 96 11 22 1 1325179328 19533968 2811216
21:59:18 11386 8192000000 10285867280 10714853376 1361047680 14.247 100 66 133 1 1386454432 10712208 14694544
22:00:30 111028 8192000000 10268519680 10718990336 1329928736 13.967 97 14 28 0 1340607952 7785904 2893312
22:03:21 21447 8192000000 10281532224 10730393600 1371810176 14.343 100 33 64 1 1388930016 10175504 6944336
22:04:51 49213 8192000000 10270114320 10730242048 1362846336 14.263 99 9 18 0 1375688880 10764528 2078016
22:06:43 43777 8192000000 10276370480 10727882752 1363164576 14.266 99 20 40 0 1383035856 15002048 4869232
22:08:27 52449 8192000000 10279861696 10742063104 1384542272 14.457 100 14 25 0 1394785904 6613712 3629920
22:10:20 5599 8192000000 10278259472 10725007360 1372646576 14.351 100 53 98 2 1388894176 5680752 10566848
22:11:30 80813 8192000000 10281299184 10741239808 1358279472 14.222 98 13 26 0 1368514752 7540640 2694640
22:13:52 34013 8192000000 10295675776 10748616704 1388301008 14.491 100 16 32 0 1400011520 7687360 4023152
22:15:35 48686 8192000000 10293309296 10749005824 1376194144 14.383 99 23 46 0 1393141968 11399008 5548816
22:17:24 30244 8192000000 10279626672 10733580288 1331286528 13.979 96 35 70 0 1350634224 11770000 7577696
22:18:55 8353 8192000000 10313143424 10754260992 1391119824 14.516 100 39 78 0 1410933648 11308848 8504976
22:20:05 45104 8192000000 10311935984 10757369856 1393474480 14.537 100 23 46 0 1407935488 9319888 5141120
22:21:50 68650 8192000000 10312761216 10761961472 1380500816 14.421 99 18 36 0 1403184032 18543568 4139648
22:24:02 58082 8192000000 10293944992 10751270912 1348839152 14.137 97 16 32 0 1366158592 13872400 3447040
22:26:00 137978 8192000000 10291835552 10751094784 1360170160 14.239 98 6 12 0 1372401856 11339344 892352
22:29:28 18743 8192000000 10286566816 10747035648 1374210848 14.365 99 15 30 0 1390420240 12704976 3504416
22:30:47 82059 8192000000 10248558688 10714394624 1293696864 13.638 95 5 10 0 1304216688 9616048 903776
22:33:09 84044 8192000000 10244428592 10693967872 1314956848 13.831 96 16 32 0 1333154976 14045392 4152736
22:35:33 67337 8192000000 10244868336 10705694720 1315221552 13.833 97 12 24 0 1331580288 13502048 2856688
22:37:41 59685 8192000000 9309491344 9609797632 456343520 5.276 34 6 12 0 468022160 10210896 1467744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-07-2022 22:40:36 to 30-07-2022 22:40:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-07-2022 22:40:52 to 30-07-2022 22:40:54.

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          241313
MON$OLDEST_ACTIVE               241314
MON$OLDEST_SNAPSHOT             241314
MON$NEXT_TRANSACTION            241314
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                       2039712
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:536: 
:537: mscs-test-161.budzdorov.ru    Sat Jul 30 22:38:42 2022
:538:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:539: 
:540: 
:541: mscs-test-161.budzdorov.ru    Sat Jul 30 22:38:42 2022
:542:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:543: 
:544: 
:545: mscs-test-161.budzdorov.ru    Sat Jul 30 22:38:43 2022
:546:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:547: 
:548: 
:549: mscs-test-161.budzdorov.ru    Sat Jul 30 22:38:43 2022
:550:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:551: 
--- end of diff output ---

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

30.07.22 22:40:55. 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-07-30 22:40:58 No severe PSQL-related problems occured
30.07.22 22:40:59. End of report.