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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15559168 kB
MemAvailable 30587116 kB
Buffers 0 kB
Cached 16150988 kB
SwapCached 2032 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-09-26 19:38:23
DTS_END                         2022-09-26 19:38:23.2130
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/48308
TRN_ID                          235237
ATT_ID                          690
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: 9442.31 24231 1133077
customer order: creation 166.92 427 20030
customer order: refuse 13.91 347 1669
order to supplier: creation 44.68 1288 5362
order to supplier: removal 3.67 90905 440
invoice (draft): creation 44.69 3308 5363
invoice (draft): removal 56.65 645 6798
invoice accept: apply 40.35 17090 4842
invoice accept: cancel 51.58 791 6189
customer reserve: creation 3708.83 169 445059
customer reserve: removal 5003.73 53 600448
realization accept: apply 82.01 183 9841
realization accept: cancel 99.40 90 11928
payment from customer: creation 57.38 122 6885
payment from customer: removal 5.78 115 693
payment to supplier: creation 48.18 157 5782
payment to supplier: removal 3.14 83 377
service: total inventory turnovers 4.52 1083 542
service: total monetary turnovers 6.18 22 742
service: aggregate perf. data 0.69 26794 83
service: refresh index statistics 0.03 340940 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-09-2022 19:39:36 to 26-09-2022 19:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5602
TEST_TIME 2 5373
TEST_TIME 3 5286
TEST_TIME 4 4680
TEST_TIME 5 5485
TEST_TIME 6 5384
TEST_TIME 7 5712
TEST_TIME 8 5674
TEST_TIME 9 6014
TEST_TIME 10 5913
TEST_TIME 11 6111
TEST_TIME 12 6473
TEST_TIME 13 6423
TEST_TIME 14 6555
TEST_TIME 15 6810
TEST_TIME 16 7137
TEST_TIME 17 7194
TEST_TIME 18 7535
TEST_TIME 19 7695
TEST_TIME 20 7991
TEST_TIME 21 8746
TEST_TIME 22 9109
TEST_TIME 23 9476
TEST_TIME 24 9441
TEST_TIME 25 9897
TEST_TIME 26 9916
TEST_TIME 27 10453
TEST_TIME 28 10319
TEST_TIME 29 10200
TEST_TIME 30 10206
TEST_TIME 31 10197
TEST_TIME 32 10205
TEST_TIME 33 9937
TEST_TIME 34 9903
TEST_TIME 35 9781
TEST_TIME 36 9766
TEST_TIME 37 9709
TEST_TIME 38 9591
TEST_TIME 39 9591
TEST_TIME 40 9589
TEST_TIME 41 9589
TEST_TIME 42 9693
TEST_TIME 43 9668
TEST_TIME 44 9513
TEST_TIME 45 9679
TEST_TIME 46 9643
TEST_TIME 47 9691
TEST_TIME 48 9694
TEST_TIME 49 9630
TEST_TIME 50 9615
TEST_TIME 51 9722
TEST_TIME 52 9699
TEST_TIME 53 9753
TEST_TIME 54 9857
TEST_TIME 55 9937
TEST_TIME 56 10029
TEST_TIME 57 9973
TEST_TIME 58 10009
TEST_TIME 59 9859
TEST_TIME 60 9771
TEST_TIME 61 9772
TEST_TIME 62 9697
TEST_TIME 63 9670
TEST_TIME 64 9703
TEST_TIME 65 9683
TEST_TIME 66 9655
TEST_TIME 67 9573
TEST_TIME 68 9604
TEST_TIME 69 9597
TEST_TIME 70 9617
TEST_TIME 71 9580
TEST_TIME 72 9720
TEST_TIME 73 9713
TEST_TIME 74 9752
TEST_TIME 75 9691
TEST_TIME 76 9681
TEST_TIME 77 9694
TEST_TIME 78 9799
TEST_TIME 79 9808
TEST_TIME 80 9811
TEST_TIME 81 9720
TEST_TIME 82 9666
TEST_TIME 83 9602
TEST_TIME 84 9583
TEST_TIME 85 9585
TEST_TIME 86 9541
TEST_TIME 87 9514
TEST_TIME 88 9479
TEST_TIME 89 9439
TEST_TIME 90 9384
TEST_TIME 91 9377
TEST_TIME 92 9451
TEST_TIME 93 9424
TEST_TIME 94 9393
TEST_TIME 95 9458
TEST_TIME 96 9536
TEST_TIME 97 9530
TEST_TIME 98 9549
TEST_TIME 99 9518
TEST_TIME 100 9638
TEST_TIME 101 9640
TEST_TIME 102 9644
TEST_TIME 103 9632
TEST_TIME 104 9698
TEST_TIME 105 9687
TEST_TIME 106 9612
TEST_TIME 107 9571
TEST_TIME 108 9548
TEST_TIME 109 9528
TEST_TIME 110 9501
TEST_TIME 111 9469
TEST_TIME 112 9442
TEST_TIME 113 9407
TEST_TIME 114 9372
TEST_TIME 115 9364
TEST_TIME 116 9491
TEST_TIME 117 9483
TEST_TIME 118 9464
TEST_TIME 119 9505
TEST_TIME 120 9444

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2022 19:39:40 to 26-09-2022 19:39: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 1545794 1545794 0 0.00 0 22641 6 0 0
doc_list_biud 1545788 1545788 0 0.00 0 52150 26 0 0
sp_add_invoice_to_stock 4987 4842 145 2.90 20 189547 17091 0 145
sp_cancel_adding_invoice 6189 6189 0 0.00 5 78873 792 0 0
sp_cancel_client_order 1669 1669 0 0.00 4 10179 347 0 0
sp_cancel_customer_reserve 600448 600448 0 0.00 0 52152 53 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 2 20961 115 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 5587 83 0 0
sp_cancel_supplier_invoice 6811 6798 13 0.19 1 22906 646 0 13
sp_cancel_supplier_order 440 440 0 0.00 7 933565 90905 0 0
sp_cancel_write_off 11928 11928 0 0.00 1 16945 91 0 0
sp_client_order 20030 20030 0 0.00 3 63511 427 0 0
sp_customer_reserve 445059 445059 0 0.00 0 87002 170 0 0
sp_fill_shopping_cart 30747 30747 0 0.00 1 8912 36 0 0
sp_fill_shopping_cart_clo_res 445050 445050 0 0.00 0 12924 13 0 0
sp_get_clo_for_invoice 4833 4833 0 0.00 0 29578 300 0 0
sp_kill_cost_storno 19187 19187 0 0.00 0 20955 50 0 0
sp_kill_qstorno_ret_qs2qd 1220062 1220062 0 0.00 0 52147 28 0 0
sp_kill_qty_storno 642145 642145 0 0.00 0 52150 62 0 0
sp_lock_selected_doc 20353 20353 0 0.00 0 8630 5 0 0
sp_make_cost_storno 27341 27341 0 0.00 0 22625 60 0 0
sp_multiply_rows_for_pdistr 27339 27339 0 0.00 0 20639 32 0 0
sp_multiply_rows_for_qdistr 20030 20030 0 0.00 0 63495 213 0 0
sp_pay_from_customer 6885 6885 0 0.00 4 20665 123 0 0
sp_pay_to_supplier 5782 5782 0 0.00 5 20689 158 0 0
sp_payment_common 12666 12666 0 0.00 1 20632 39 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 10173 320 0 0
sp_qd_handle_on_invoice_upd_sts 11021 11021 0 0.00 0 27539 319 0 0
sp_qd_handle_on_reserve_upd_sts 9841 9841 0 0.00 0 15854 54 0 0
sp_reserve_write_off 9841 9841 0 0.00 6 20710 184 0 0
sp_supplier_invoice 5363 5363 0 0.00 6 121038 3309 0 0
sp_supplier_order 5362 5362 0 0.00 5 36972 1289 0 0
srv_aggregate_perf_data 83 83 0 0.00 2529 573176 26794 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1925 133117 46579 0 0
srv_find_qd_qs_mism 1087105 1087105 0 0.00 0 12263 4 0 0
srv_make_invnt_saldo 542 542 0 0.00 40 50508 1084 0 0
srv_make_money_saldo 742 742 0 0.00 1 336 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 183580 467732 340940 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4842 4842 0 0.00 0 11327 172 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 5758 123 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2155 12 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 2053 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 6365 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 5027 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 2881 21 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 26983 1668 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1705 7 0 0
t$perf-norm:sp_client_order 20030 20030 0 0.00 0 7147 16 0 0
t$perf-norm:sp_customer_reserve 923 923 0 0.00 0 8368 16 0 0
t$perf-norm:sp_pay_from_customer 6885 6885 0 0.00 0 9186 6 0 0
t$perf-norm:sp_pay_to_supplier 5782 5782 0 0.00 0 7126 13 0 0
t$perf-norm:sp_reserve_write_off 9841 9841 0 0.00 0 13940 11 0 0
t$perf-norm:sp_supplier_invoice 5363 5363 0 0.00 0 12189 17 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 12209 18 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 381 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 304 9 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 821 24 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 3910 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9954 9664 290 2.91 0 11731 20 0 290
v_all_customers 36468 36468 0 0.00 0 17 1 0 0
v_all_suppliers 29958 29958 0 0.00 0 24 1 0 0
v_all_wares 40060 40060 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 5944 16 0 0
v_cancel_client_order 3338 3338 0 0.00 0 3669 5 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 5919 11 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 408 3 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 393 3 0 0
v_cancel_supplier_invoice 848 822 26 3.06 0 613 14 0 26
v_cancel_supplier_order 880 880 0 0.00 0 1841 7 0 0
v_cancel_write_off 1968 1968 0 0.00 0 1716 13 0 0
v_max_id_clo_ord 5361 5361 0 0.00 0 2721 3 0 0
v_max_id_clo_res 923 923 0 0.00 0 2031 15 0 0
v_max_id_ord_sup 5356 5356 0 0.00 0 2244 3 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 11410 15 0 0
v_max_non_paid_realizn 3689 3689 0 0.00 0 1357 3 0 0
v_min_id_clo_ord 5361 5361 0 0.00 0 3941 8 0 0
v_min_id_clo_res 923 923 0 0.00 0 3423 11 0 0
v_min_id_ord_sup 5356 5356 0 0.00 0 5631 14 0 0
v_min_non_paid_invoice 5781 5781 0 0.00 0 20610 37 0 0
v_min_non_paid_realizn 6885 6885 0 0.00 0 20458 25 0 0
v_our_firm 9984 9984 0 0.00 0 16 1 0 0
v_reserve_write_off 19682 19682 0 0.00 0 5138 7 0 0
x_lock_depdocs_on_canc_invoice 12576 12576 0 0.00 0 23843 117 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 13742 335 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 1 36954 1214 0 0
x_make_qstorno_1200_2000 5363 5363 0 0.00 3 120973 3178 0 0
x_make_qstorno_2100_3300 445059 445059 0 0.00 0 86998 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2022 19:39:45 to 26-09-2022 19:39:49.

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:27 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:27 30565 8192000000 9902344496 10210787328 979131984 10.676 100 93 186 0 1010353328 6525760 24695584
17:11:04 18618 8192000000 9941667552 10263064576 1022736752 11.098 100 90 180 0 1054613792 8865264 23011776
17:12:30 15730 8192000000 9985826384 10316914688 1065700464 11.511 100 85 170 0 1097082560 10275072 21107024
17:13:52 7593 8192000000 10030506672 10377633792 1113060960 11.961 100 72 144 1 1141220064 11271664 16887440
17:15:02 8271 8192000000 10088841712 10449010688 1174575216 12.540 100 83 166 0 1206179568 12842784 18761568
17:16:14 5713 8192000000 10120104928 10489352192 1209045200 12.860 100 71 143 1 1237824784 13207760 15571824
17:17:22 2197 8192000000 10148707600 10520854528 1238812080 13.135 100 85 171 0 1270738432 13246752 18679600
17:18:25 1497 8192000000 10176763280 10557075456 1264661488 13.373 100 84 165 1 1296716640 14049376 18005776
17:19:28 1373 8192000000 10195829184 10578649088 1285461536 13.563 100 87 171 2 1317846240 13068384 19316320
17:20:31 8336 8192000000 10182082112 10589089792 1298146736 13.678 100 42 81 1 1314443440 7878400 8418304
17:21:44 1207 8192000000 10215209632 10609205248 1306285456 13.752 100 75 148 0 1334536112 11643808 16606848
17:22:46 9277 8192000000 10193058992 10607919104 1307687760 13.765 100 29 59 0 1321194592 7525728 5981104
17:23:57 2645 8192000000 10217990864 10628247552 1318506016 13.863 100 51 102 0 1341461792 10851920 12103856
17:25:02 2304 8192000000 10222121728 10632404992 1325971616 13.931 100 41 81 0 1341499712 6169152 9358944
17:26:06 10891 8192000000 10213388016 10634412032 1329638464 13.964 100 16 33 0 1338691808 5209232 3844112
17:27:18 26153 8192000000 10244338464 10647887872 1331569392 13.981 100 71 142 2 1353686432 5437344 16679696
17:28:44 4334 8192000000 10202157728 10614685696 1310605920 13.792 100 29 56 1 1322906416 5895152 6405344
17:29:50 6323 8192000000 10192657520 10600742912 1299022400 13.686 100 26 52 0 1311489632 6343024 6124208
17:30:59 4360 8192000000 10182697056 10594467840 1293775680 13.639 100 21 40 0 1303955328 5115536 5064112
17:32:04 4462 8192000000 10141059808 10540204032 1248811616 13.227 100 25 47 1 1259946608 5459088 5675904
17:33:09 9747 8192000000 10141063376 10540752896 1239475520 13.141 98 13 26 1 1247953792 5143360 3334912
17:34:22 56399 8192000000 10151641920 10555961344 1244006944 13.183 98 24 46 0 1255307824 5753424 5547456
17:36:31 9141 8192000000 10175449888 10587303936 1284046656 13.550 100 24 46 0 1295238048 5261968 5929424
17:37:42 9272 8192000000 10189202576 10605793280 1300055312 13.696 100 15 30 1 1308740544 5493232 3192000
17:38:58 5846 8192000000 10233223776 10639495168 1327385376 13.944 100 51 100 0 1346037168 7418544 11233248
17:40:09 20517 8192000000 10250455824 10664206336 1346146336 14.113 100 42 85 0 1365813072 10225792 9440944
17:41:33 25694 8192000000 10261110272 10681671680 1354410592 14.187 100 35 69 0 1372155632 10407664 7337376
17:43:15 25718 8192000000 10278920928 10704678912 1368652656 14.315 100 33 67 0 1395992912 19319344 8020912
17:44:43 22907 8192000000 10294181952 10723672064 1382772432 14.441 100 39 78 1 1414522224 22752288 8997504
17:46:18 22285 8192000000 10315684592 10743345152 1389646272 14.503 100 41 80 0 1422046240 22897232 9502736
17:47:54 6775 8192000000 10315597008 10743771136 1380185824 14.418 99 40 78 1 1419284160 29382528 9715808
17:49:03 5657 8192000000 10323389808 10742218752 1386200352 14.472 100 56 111 1 1435106208 35635904 13269952
17:50:14 5985 8192000000 10329913760 10752917504 1371514224 14.341 98 53 106 0 1422450768 37590464 13346080
17:51:32 7195 8192000000 10340228768 10761678848 1398986400 14.586 100 56 112 0 1449214976 36554176 13674400
17:52:46 1923 8192000000 10367650752 10784768000 1416580224 14.742 100 76 146 0 1472469696 38352704 17536768
17:53:52 5893 8192000000 10359249728 10785050624 1402840800 14.620 99 59 117 1 1458081200 40216112 15024288
17:55:07 4866 8192000000 10357328192 10769526784 1385207040 14.463 98 75 150 1 1447161792 43212160 18742592
17:56:20 2523 8192000000 10376801568 10790756352 1409225056 14.677 100 84 164 0 1477761360 48792384 19743920
17:57:24 3322 8192000000 10354076368 10774491136 1397769424 14.575 100 60 120 1 1460341296 46724448 15847424
17:58:35 3927 8192000000 10328597152 10744078336 1375434784 14.376 100 62 122 0 1430889680 39466240 15988656
17:59:44 13737 8192000000 10323094912 10759057408 1389259248 14.499 100 32 64 3 1430614544 33064800 8290496
18:01:10 29567 8192000000 10284604240 10732367872 1350971136 14.156 98 18 37 0 1382993552 26880384 5142032
18:03:19 35120 8192000000 10273796592 10711506944 1333984928 14.003 98 23 46 1 1360364784 20370832 6009024
18:04:59 43122 8192000000 10258939600 10684964864 1316925568 13.849 98 18 36 0 1339661792 18030768 4705456
18:06:47 43457 8192000000 10244967568 10678747136 1307844512 13.767 98 17 33 0 1326630288 14316928 4468848
18:08:35 133117 8192000000 10272731360 10714226688 1311387200 13.799 97 17 33 0 1332946048 17583744 3975104
18:12:31 118146 8192000000 10269853808 10698809344 1306054080 13.750 96 43 86 0 1334308160 18996400 9257680
18:15:31 49233 8192000000 10278563344 10718388224 1331200528 13.978 97 31 61 1 1355008400 16247904 7559968
18:17:24 38877 8192000000 10298301792 10741202944 1364924880 14.282 99 20 36 0 1392790256 23374704 4490672
18:19:18 68250 8192000000 10290991120 10749849600 1381638000 14.431 100 19 38 1 1414010912 27279104 5093808
18:21:27 105983 8192000000 10289955792 10755846144 1383679472 14.449 100 10 20 0 1402766736 16817280 2269984
18:24:15 36341 8192000000 10297578816 10747170816 1389906176 14.505 100 20 39 1 1401036352 7082816 4047360
18:25:57 24437 8192000000 10295842192 10754686976 1392176512 14.525 100 24 48 0 1407516224 9579776 5759936
18:27:22 57355 8192000000 10291411104 10744107008 1374794144 14.370 99 31 62 0 1395031248 13154880 7082224
18:29:20 49077 8192000000 10306530288 10764546048 1400744352 14.602 100 12 24 0 1416614496 12898544 2971600
18:31:10 42256 8192000000 10331684416 10792169472 1409784032 14.682 100 23 45 0 1436529728 21935920 4809776
18:32:52 96579 8192000000 10311336064 10781511680 1349122064 14.140 96 17 34 0 1374723520 21458208 4143248
18:35:31 121170 8192000000 10309482816 10770329600 1385392032 14.465 99 15 28 0 1408376848 19768016 3216800
18:38:50 63397 8192000000 10293573824 10743881728 1390929840 14.514 99 31 60 1 1403909664 6424368 6555456
18:41:00 61379 8192000000 10293623712 10756513792 1389392864 14.500 99 18 36 0 1407689936 14381600 3915472
18:43:16 38532 8192000000 10306720336 10759467008 1404092688 14.631 100 19 39 2 1422181376 14328144 3760544
18:45:06 49787 8192000000 10292476608 10742628352 1347497824 14.125 96 17 30 1 1360898928 9495600 3905504
18:46:57 93087 8192000000 10287406608 10750914560 1356811344 14.209 97 17 34 0 1375599440 14783616 4004480
18:49:42 30933 8192000000 10289549696 10747162624 1361248448 14.249 98 33 65 0 1387149280 18080160 7820672
18:51:23 77199 8192000000 10266152832 10719813632 1299232672 13.688 94 13 26 0 1314043024 12238608 2571744
18:53:50 26107 8192000000 10278158256 10723713024 1343650320 14.090 98 36 72 0 1363395152 12023664 7721168
18:55:19 31192 8192000000 10284496000 10719682560 1331997616 13.985 97 48 97 0 1355426208 12668704 10759888
18:56:55 48011 8192000000 10281920592 10723758080 1341761264 14.073 98 21 42 0 1359927216 13111776 5054176
18:58:46 104014 8192000000 10252953776 10691645440 1300337008 13.698 96 28 56 0 1316179728 9449824 6392896
19:01:39 15935 8192000000 10231571088 10666754048 1305911664 13.749 97 32 64 0 1323055824 9639296 7504864
19:02:56 52119 8192000000 10229774656 10685947904 1308039968 13.768 97 18 34 1 1319408784 7149184 4219632
19:04:48 35532 8192000000 10238989216 10688802816 1333451776 13.998 99 11 22 2 1341257008 5820928 1984304
19:06:24 58853 8192000000 10244685408 10691198976 1315512656 13.836 97 14 26 1 1325233408 6554960 3165792
19:09:03 68898 8192000000 10256528688 10722885632 1330453952 13.971 97 15 30 0 1348266720 14161552 3651216
19:11:17 73306 8192000000 10281003024 10727772160 1352941136 14.174 98 22 44 1 1369289184 11630016 4718032
19:13:34 52027 8192000000 10289672576 10762055680 1380542864 14.421 99 25 50 0 1412217104 25454400 6219840
19:16:07 26741 8192000000 10299590832 10748231680 1367177952 14.302 98 33 64 1 1388571120 14305936 7087232
19:17:34 46663 8192000000 10294323472 10767114240 1380767920 14.423 99 18 36 0 1405193488 20177376 4248192
19:19:25 69981 8192000000 10299639696 10757861376 1381746928 14.432 99 38 74 1 1400834112 10446672 8640512
19:21:44 56976 8192000000 10313763472 10768293888 1381249696 14.428 99 41 82 0 1405829792 15103472 9476624
19:23:42 130045 8192000000 10299581216 10753482752 1365972368 14.291 98 16 32 0 1379322672 9805200 3545104
19:26:53 26494 8192000000 10292939344 10760032256 1399301120 14.589 100 7 14 0 1408257216 7218528 1737568
19:28:21 59526 8192000000 10284901744 10752823296 1360328464 14.240 97 7 14 0 1370294352 8242784 1723104
19:30:21 72907 8192000000 10283992928 10748923904 1358884752 14.227 97 15 30 0 1373653312 11811952 2956608
19:32:54 92994 8192000000 10308674848 10785984512 1367790368 14.307 98 12 23 0 1392092816 20989408 3313040
19:35:28 48806 8192000000 10301119968 10757881856 1386029072 14.470 99 15 30 0 1399156096 9950656 3176368
19:37:18 46039 8192000000 10277150992 10735321088 1351126304 14.158 98 24 48 1 1366025424 9740224 5158896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2022 19:39:51 to 26-09-2022 19:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2022 19:40:07 to 26-09-2022 19:40:09.

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          234566
MON$OLDEST_ACTIVE               235360
MON$OLDEST_SNAPSHOT             235360
MON$NEXT_TRANSACTION            235360
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                       2029408
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Mon Sep 26 19:38:36 2022
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Mon Sep 26 19:38:41 2022
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Mon Sep 26 19:38:41 2022
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:86: 
--- end of diff output ---

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

26.09.22 19:40:10. 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-09-26 19:40:12 No severe PSQL-related problems occured
26.09.22 19:40:14. End of report.