Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.07.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 16119848 kB
MemAvailable 31093920 kB
Buffers 0 kB
Cached 15573976 kB
SwapCached 2520 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-11 19:38:22
DTS_END                         2022-07-11 19:38:22.2080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40012
TRN_ID                          242303
ATT_ID                          670
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: 9243.78 25077 1109254
customer order: creation 170.50 371 20460
customer order: refuse 15.43 337 1852
order to supplier: creation 46.45 1389 5574
order to supplier: removal 3.54 81829 425
invoice (draft): creation 46.68 3439 5602
invoice (draft): removal 50.80 729 6096
invoice accept: apply 43.37 18581 5204
invoice accept: cancel 45.70 690 5484
customer reserve: creation 4030.01 183 483601
customer reserve: removal 4490.81 50 538897
realization accept: apply 84.23 171 10107
realization accept: cancel 85.98 86 10317
payment from customer: creation 60.96 116 7315
payment from customer: removal 5.92 60 710
payment to supplier: creation 48.18 132 5782
payment to supplier: removal 3.17 63 380
service: total inventory turnovers 4.63 1319 555
service: total monetary turnovers 6.57 46 788
service: aggregate perf. data 0.84 17639 101
service: refresh index statistics 0.03 374315 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-07-2022 19:39:25 to 11-07-2022 19:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8806
TEST_TIME 2 6821
TEST_TIME 3 6775
TEST_TIME 4 6554
TEST_TIME 5 7061
TEST_TIME 6 7132
TEST_TIME 7 7622
TEST_TIME 8 8118
TEST_TIME 9 7861
TEST_TIME 10 7874
TEST_TIME 11 8185
TEST_TIME 12 8495
TEST_TIME 13 8436
TEST_TIME 14 8992
TEST_TIME 15 8946
TEST_TIME 16 8983
TEST_TIME 17 9544
TEST_TIME 18 9560
TEST_TIME 19 9433
TEST_TIME 20 9900
TEST_TIME 21 9786
TEST_TIME 22 10156
TEST_TIME 23 10017
TEST_TIME 24 9775
TEST_TIME 25 10018
TEST_TIME 26 10385
TEST_TIME 27 10447
TEST_TIME 28 10638
TEST_TIME 29 10958
TEST_TIME 30 10956
TEST_TIME 31 10814
TEST_TIME 32 10981
TEST_TIME 33 10987
TEST_TIME 34 10842
TEST_TIME 35 10960
TEST_TIME 36 10658
TEST_TIME 37 10508
TEST_TIME 38 10427
TEST_TIME 39 10353
TEST_TIME 40 10320
TEST_TIME 41 10162
TEST_TIME 42 10095
TEST_TIME 43 9921
TEST_TIME 44 9867
TEST_TIME 45 9880
TEST_TIME 46 9881
TEST_TIME 47 9745
TEST_TIME 48 9776
TEST_TIME 49 9716
TEST_TIME 50 9708
TEST_TIME 51 9639
TEST_TIME 52 9562
TEST_TIME 53 9532
TEST_TIME 54 9523
TEST_TIME 55 9495
TEST_TIME 56 9514
TEST_TIME 57 9471
TEST_TIME 58 9387
TEST_TIME 59 9389
TEST_TIME 60 9386
TEST_TIME 61 9360
TEST_TIME 62 9479
TEST_TIME 63 9461
TEST_TIME 64 9553
TEST_TIME 65 9484
TEST_TIME 66 9597
TEST_TIME 67 9591
TEST_TIME 68 9567
TEST_TIME 69 9541
TEST_TIME 70 9496
TEST_TIME 71 9652
TEST_TIME 72 9648
TEST_TIME 73 9651
TEST_TIME 74 9623
TEST_TIME 75 9501
TEST_TIME 76 9465
TEST_TIME 77 9471
TEST_TIME 78 9440
TEST_TIME 79 9417
TEST_TIME 80 9413
TEST_TIME 81 9400
TEST_TIME 82 9362
TEST_TIME 83 9290
TEST_TIME 84 9260
TEST_TIME 85 9253
TEST_TIME 86 9220
TEST_TIME 87 9218
TEST_TIME 88 9257
TEST_TIME 89 9217
TEST_TIME 90 9248
TEST_TIME 91 9320
TEST_TIME 92 9293
TEST_TIME 93 9286
TEST_TIME 94 9406
TEST_TIME 95 9377
TEST_TIME 96 9436
TEST_TIME 97 9470
TEST_TIME 98 9419
TEST_TIME 99 9475
TEST_TIME 100 9475
TEST_TIME 101 9533
TEST_TIME 102 9483
TEST_TIME 103 9401
TEST_TIME 104 9389
TEST_TIME 105 9374
TEST_TIME 106 9347
TEST_TIME 107 9324
TEST_TIME 108 9321
TEST_TIME 109 9302
TEST_TIME 110 9307
TEST_TIME 111 9247
TEST_TIME 112 9245
TEST_TIME 113 9210
TEST_TIME 114 9253
TEST_TIME 115 9223
TEST_TIME 116 9248
TEST_TIME 117 9307
TEST_TIME 118 9275
TEST_TIME 119 9288
TEST_TIME 120 9247

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-07-2022 19:39:28 to 11-07-2022 19:39:32.

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 1553259 1553259 0 0.00 0 19831 6 0 0
doc_list_biud 1553259 1553259 0 0.00 0 49894 23 0 0
sp_add_invoice_to_stock 5366 5204 162 3.01 71 223179 18581 0 162
sp_cancel_adding_invoice 5484 5484 0 0.00 5 44319 691 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 9979 338 0 0
sp_cancel_customer_reserve 538897 538897 0 0.00 0 49895 50 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 2 2285 61 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 3416 64 0 0
sp_cancel_supplier_invoice 6108 6096 12 0.19 1 14950 729 0 12
sp_cancel_supplier_order 425 425 0 0.00 6 770298 81830 0 0
sp_cancel_write_off 10317 10317 0 0.00 0 7200 86 0 0
sp_client_order 20460 20460 0 0.00 3 51842 371 0 0
sp_customer_reserve 483601 483601 0 0.00 0 35384 183 0 0
sp_fill_shopping_cart 31623 31623 0 0.00 1 14683 44 0 0
sp_fill_shopping_cart_clo_res 483594 483594 0 0.00 0 13964 13 0 0
sp_get_clo_for_invoice 5197 5197 0 0.00 0 32378 325 0 0
sp_kill_cost_storno 16891 16891 0 0.00 0 5261 47 0 0
sp_kill_qstorno_ret_qs2qd 1094632 1094632 0 0.00 0 49796 27 0 0
sp_kill_qty_storno 578375 578375 0 0.00 0 49894 60 0 0
sp_lock_selected_doc 21144 21144 0 0.00 0 7383 5 0 0
sp_make_cost_storno 28401 28401 0 0.00 0 19831 55 0 0
sp_multiply_rows_for_pdistr 28401 28401 0 0.00 0 6722 29 0 0
sp_multiply_rows_for_qdistr 20460 20460 0 0.00 0 51833 160 0 0
sp_pay_from_customer 7315 7315 0 0.00 4 23888 117 0 0
sp_pay_to_supplier 5782 5782 0 0.00 5 19836 133 0 0
sp_payment_common 13097 13097 0 0.00 1 12173 27 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 9973 310 0 0
sp_qd_handle_on_invoice_upd_sts 10681 10681 0 0.00 0 21795 310 0 0
sp_qd_handle_on_reserve_upd_sts 10107 10107 0 0.00 0 7801 51 0 0
sp_reserve_write_off 10107 10107 0 0.00 6 14238 171 0 0
sp_supplier_invoice 5602 5602 0 0.00 7 76900 3439 0 0
sp_supplier_order 5574 5574 0 0.00 5 30493 1390 0 0
srv_aggregate_perf_data 101 101 0 0.00 2621 125859 17640 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1220 107002 39939 0 0
srv_find_qd_qs_mism 1058731 1058731 0 0.00 0 13362 6 0 0
srv_make_invnt_saldo 555 555 0 0.00 40 62224 1319 0 0
srv_make_money_saldo 788 788 0 0.00 1 13482 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 114283 552413 374316 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5204 5204 0 0.00 0 10501 157 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 8376 107 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 2844 12 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 651 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 8345 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 622 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1067 14 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 15655 1198 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 2147 7 0 0
t$perf-norm:sp_client_order 20460 20460 0 0.00 0 7182 15 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 1555 5 0 0
t$perf-norm:sp_pay_from_customer 7315 7315 0 0.00 0 8359 12 0 0
t$perf-norm:sp_pay_to_supplier 5782 5782 0 0.00 0 3582 8 0 0
t$perf-norm:sp_reserve_write_off 10107 10107 0 0.00 0 6937 9 0 0
t$perf-norm:sp_supplier_invoice 5602 5602 0 0.00 0 4514 18 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 16126 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1204 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 112 2 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 452 16 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 3053 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 131 35 0 0
v_add_invoice_to_stock 10716 10392 324 3.02 0 5290 18 0 324
v_all_customers 37638 37638 0 0.00 0 16 1 0 0
v_all_suppliers 30246 30246 0 0.00 0 26 1 0 0
v_all_wares 40920 40920 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 2677 9 0 0
v_cancel_client_order 3704 3704 0 0.00 0 1996 5 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2279 7 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 1796 5 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 692 5 0 0
v_cancel_supplier_invoice 804 780 24 2.98 0 572 15 0 24
v_cancel_supplier_order 850 850 0 0.00 0 3044 14 0 0
v_cancel_write_off 1930 1930 0 0.00 0 747 13 0 0
v_max_id_clo_ord 5574 5574 0 0.00 0 240 1 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 2391 12 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 6266 7 0 0
v_max_non_paid_invoice 1822 1822 0 0.00 0 3380 4 0 0
v_max_non_paid_realizn 3930 3930 0 0.00 0 1935 4 0 0
v_min_id_clo_ord 5574 5574 0 0.00 0 2518 6 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 3564 19 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 4557 6 0 0
v_min_non_paid_invoice 5782 5782 0 0.00 0 8888 22 0 0
v_min_non_paid_realizn 7315 7315 0 0.00 0 7129 18 0 0
v_our_firm 10052 10052 0 0.00 0 30 1 0 0
v_reserve_write_off 20214 20214 0 0.00 0 3260 5 0 0
x_lock_depdocs_on_canc_invoice 11190 11190 0 0.00 0 15132 108 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 7259 369 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 1 30480 1307 0 0
x_make_qstorno_1200_2000 5602 5602 0 0.00 3 69029 3274 0 0
x_make_qstorno_2100_3300 483601 483601 0 0.00 0 35383 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-07-2022 19:39:33 to 11-07-2022 19:39:37.

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:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:26 25002 8192000000 9900052944 10207252480 984778336 10.731 100 88 176 0 1014663856 6230512 23655008
17:10:55 16972 8192000000 9954254848 10275872768 1029455056 11.163 100 87 172 1 1059772800 8379296 21938448
17:12:15 16871 8192000000 9990130272 10323865600 1071859552 11.570 100 91 182 1 1105125360 10445040 22820768
17:13:36 9147 8192000000 10031181456 10378915840 1115126528 11.981 100 74 148 0 1144042512 11369120 17546864
17:14:48 5487 8192000000 10073493872 10431893504 1159327680 12.397 100 77 152 0 1189797392 13063632 17406080
17:15:56 3999 8192000000 10119045136 10487656448 1205388784 12.826 100 77 152 0 1234274112 11840944 17044384
17:17:01 2282 8192000000 10138314128 10510397440 1233351904 13.085 100 77 154 0 1264020096 14017760 16650432
17:18:05 1457 8192000000 10175922432 10554970112 1260925472 13.338 100 81 162 0 1292200464 12790496 18484496
17:19:09 2065 8192000000 10197026448 10583334912 1285933360 13.567 100 79 156 0 1315467168 11394320 18139488
17:20:12 1401 8192000000 10203247184 10596773888 1295119664 13.651 100 65 131 1 1319734048 10311296 14303088
17:21:15 4455 8192000000 10192043696 10600022016 1302660528 13.719 100 31 62 1 1316913616 7436672 6816416
17:22:24 9805 8192000000 10197181888 10611335168 1309521696 13.782 100 11 22 0 1317303632 5644096 2137840
17:23:34 22051 8192000000 10207492016 10622869504 1318639584 13.864 100 23 46 0 1330434480 5516832 6278064
17:24:57 15549 8192000000 10220976752 10639278080 1323242016 13.906 100 23 46 0 1338750400 10618720 4889664
17:26:15 5977 8192000000 10226368496 10649305088 1334014160 14.003 100 23 45 1 1345097920 6040592 5043168
17:27:26 6166 8192000000 10234744096 10648915968 1326946176 13.940 99 19 38 0 1340549024 9155456 4447392
17:28:35 56413 8192000000 10199504464 10610700288 1257861344 13.310 95 28 54 1 1269646096 4983536 6801216
17:30:33 3162 8192000000 10140355712 10538323968 1223319936 12.992 97 34 66 0 1237646928 5929936 8397056
17:31:38 4161 8192000000 10130803936 10523844608 1206307632 12.835 97 27 50 1 1218051040 6139120 5604288
17:32:43 71532 8192000000 10156245120 10568970240 1260627248 13.336 99 29 57 0 1272688016 5735952 6324816
17:34:59 6772 8192000000 10192704624 10603892736 1297906912 13.676 100 18 35 0 1307245072 5223744 4114416
17:36:08 14432 8192000000 10203836352 10616393728 1305970160 13.749 100 15 31 0 1319188960 9636064 3582736
17:37:26 72130 8192000000 10227470560 10642423808 1323705568 13.910 100 23 44 0 1333778800 4756144 5317088
17:39:42 7732 8192000000 10258737200 10675310592 1357941568 14.219 100 48 97 0 1376793104 7863344 10988192
17:40:53 8875 8192000000 10286872928 10701209600 1372080208 14.346 100 61 120 0 1397007488 11101152 13826128
17:42:07 10664 8192000000 10299987056 10717421568 1385879968 14.469 100 58 114 0 1414872096 15657248 13334880
17:43:23 5574 8192000000 10305500576 10727116800 1393862000 14.540 100 49 97 1 1419025568 13844224 11319344
17:44:35 7127 8192000000 10316481952 10741919744 1398407776 14.581 100 50 97 0 1425721792 16397216 10916800
17:45:46 6354 8192000000 10317384016 10745810944 1400853680 14.603 100 47 94 1 1429195792 16718544 11623568
17:46:56 12322 8192000000 10316882496 10744508416 1400698880 14.601 100 51 101 1 1427185728 15409520 11077328
17:48:13 2951 8192000000 10334595600 10757902336 1407581856 14.662 100 64 126 0 1440424320 18760688 14081776
17:49:21 3397 8192000000 10310495728 10731114496 1387596464 14.484 99 56 112 1 1420535632 19830896 13108272
17:50:33 9268 8192000000 10311967344 10735046656 1382974048 14.443 100 57 114 2 1420728192 23965520 13788624
17:51:53 14514 8192000000 10308990416 10729934848 1378601808 14.404 100 58 117 1 1417941712 25315328 14024576
17:53:11 4162 8192000000 10308274480 10721046528 1372353648 14.348 100 69 136 0 1414291536 25569552 16368336
17:54:23 2321 8192000000 10328548256 10740776960 1383254160 14.446 100 61 118 3 1430960224 32840864 14865200
17:55:30 1216 8192000000 10314900112 10726723584 1368073344 14.310 100 68 130 1 1420106096 35649024 16383728
17:56:49 8729 8192000000 10298118352 10714558464 1363400448 14.268 100 55 108 2 1406182656 29380144 13402064
17:58:05 3211 8192000000 10293534912 10704809984 1343210912 14.086 100 60 120 1 1394801072 36282240 15307920
17:59:14 7549 8192000000 10299681328 10719084544 1364276624 14.276 100 55 105 0 1407625904 29669952 13679328
18:00:25 8166 8192000000 10308026032 10734383104 1377093776 14.391 100 33 64 2 1413994000 28715888 8184336
18:01:38 10381 8192000000 10302062784 10731642880 1371928720 14.344 100 30 60 0 1411561184 30998224 8634240
18:03:10 12181 8192000000 10313372176 10735366144 1357821360 14.218 99 43 86 0 1407126144 38291568 11013216
18:04:27 32462 8192000000 10298084544 10737446912 1376929280 14.389 100 20 39 0 1411930112 29733776 5267056
18:06:06 24209 8192000000 10297083040 10748162048 1372168800 14.346 100 14 28 0 1403199104 27375024 3655280
18:07:31 97725 8192000000 10292853392 10721202176 1325521152 13.927 97 15 30 0 1342076768 13329680 3225936
18:10:12 36154 8192000000 10279788288 10725277696 1349011984 14.139 98 9 18 0 1366518048 15547104 1958960
18:11:51 107002 8192000000 10299824384 10755911680 1363732832 14.271 98 9 18 0 1385719456 19737968 2248656
18:15:21 6797 8192000000 10309964352 10743599104 1387320912 14.482 100 62 122 4 1406032880 5632656 13079312
18:16:30 34192 8192000000 10297001136 10755653632 1398957440 14.586 100 17 34 0 1414509488 11248880 4303168
18:18:06 29259 8192000000 10295654320 10758000640 1397158944 14.570 100 18 34 1 1408559552 7388304 4012304
18:19:35 61661 8192000000 10304992640 10757574656 1387619840 14.485 99 31 62 0 1407793168 13184528 6988800
18:22:25 83064 8192000000 10318010464 10777116672 1407703872 14.664 100 15 30 0 1424986768 13650304 3632592
18:24:50 60439 8192000000 10303429728 10750320640 1353139568 14.176 96 35 68 0 1376178384 15064064 7974752
18:26:57 20661 8192000000 10313106752 10764816384 1404458464 14.635 100 39 76 0 1421406672 8157056 8791152
18:28:19 35584 8192000000 10311900384 10780614656 1405809776 14.647 100 14 28 0 1426104608 16908512 3386320
18:30:03 26356 8192000000 10310970896 10766110720 1406221376 14.650 99 40 77 0 1426032160 10595424 9215360
18:31:33 72081 8192000000 10320114176 10791956480 1416953792 14.746 100 16 32 0 1440692864 19593312 4145760
18:33:45 49499 8192000000 10272795280 10722881536 1346748448 14.118 96 13 24 1 1366139664 15917392 3473824
18:35:52 92807 8192000000 10292480944 10762141696 1376264368 14.383 99 20 38 0 1394219120 13568080 4386672
18:38:42 59075 8192000000 10290442064 10740305920 1342869680 14.083 96 19 38 0 1361555056 14561504 4123872
18:40:41 58055 8192000000 10279887776 10734018560 1328905088 13.957 96 30 59 0 1346369024 11101584 6362352
18:43:45 49705 8192000000 10272761088 10721443840 1315657840 13.837 95 15 30 0 1334808128 15829472 3320816
18:45:35 43830 8192000000 10281641184 10718507008 1322396736 13.898 96 42 82 0 1340070176 9566032 8107408
18:47:22 34156 8192000000 10265800704 10692173824 1309076224 13.778 96 42 83 2 1328474880 10557680 8840976
18:48:57 30672 8192000000 10251736048 10693324800 1328320176 13.952 99 39 78 0 1351095664 14222896 8552592
18:50:28 79274 8192000000 10270663920 10711556096 1322889184 13.903 98 20 38 2 1341641536 14498592 4253760
18:52:51 72812 8192000000 10257645600 10697465856 1331527776 13.981 98 23 46 0 1349279120 12935552 4815792
18:55:08 77090 8192000000 10254707872 10703568896 1317552192 13.855 97 17 34 0 1334141984 12739696 3850096
18:58:19 54250 8192000000 10258482704 10703790080 1346966464 14.120 99 15 30 0 1361577168 11865264 2745440
19:00:15 51887 8192000000 10251198976 10704707584 1335081536 14.013 98 16 32 0 1350330896 11591952 3657408
19:02:41 69978 8192000000 10236984864 10695774208 1312998704 13.813 97 17 33 0 1327842000 10936640 3906656
19:04:52 42067 8192000000 10262480080 10725380096 1365524736 14.287 100 15 29 0 1379486016 10568144 3393136
19:07:30 69564 8192000000 10285331408 10735624192 1348400256 14.133 98 21 43 0 1361360192 8173504 4786432
19:10:47 70604 8192000000 10311066080 10766958592 1396523520 14.564 100 16 32 0 1413132672 13177648 3431504
19:13:39 67191 8192000000 10308736000 10772275200 1400422448 14.599 100 12 24 0 1414677728 11801584 2453696
19:15:51 47243 8192000000 10323567216 10767663104 1372573488 14.350 97 36 72 0 1391269952 10384448 8312016
19:17:45 53068 8192000000 10327765616 10779639808 1406623824 14.654 100 31 60 1 1426229168 13032816 6572528
19:19:43 63391 8192000000 10307979200 10753748992 1352540064 14.170 96 24 48 0 1366950144 8867968 5542112
19:21:54 65330 8192000000 10280862640 10738921472 1337176816 14.032 96 25 50 1 1350193200 7346048 5670336
19:24:05 15990 8192000000 10294520912 10755280896 1387850400 14.487 100 25 50 0 1407667408 14476608 5340400
19:25:26 47498 8192000000 10294397152 10758893568 1397724592 14.575 100 23 46 0 1413104336 10104480 5275264
19:27:18 48445 8192000000 10282425120 10739568640 1367719888 14.307 98 16 32 0 1379905072 8576928 3608256
19:29:09 56475 8192000000 10261801824 10709635072 1287316016 13.580 93 22 42 1 1298410656 6606576 4488064
19:31:06 59786 8192000000 10274601440 10734325760 1368514320 14.314 100 9 18 0 1382894336 12426464 1953552
19:33:06 64566 8192000000 10271538016 10720980992 1300201808 13.697 94 20 40 0 1316891008 12210368 4478832
19:35:19 74117 8192000000 10269591712 10716618752 1313806144 13.821 96 14 28 0 1329910064 12582960 3520960
19:38:08 21034 8192000000 9104937584 9336446976 264198080 3.124 20 4 8 0 275336320 10388448 749792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-07-2022 19:39:39 to 11-07-2022 19:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-07-2022 19:39:54 to 11-07-2022 19:39:56.

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          242591
MON$OLDEST_ACTIVE               242592
MON$OLDEST_SNAPSHOT             242592
MON$NEXT_TRANSACTION            242592
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                       2030112
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:907: 
:908: mscs-test-161.budzdorov.ru    Mon Jul 11 19:38:33 2022
:909:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:910: 
:911: 
:912: mscs-test-161.budzdorov.ru    Mon Jul 11 19:38:33 2022
:913:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:914: 
:915: 
:916: mscs-test-161.budzdorov.ru    Mon Jul 11 19:38:38 2022
:917:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:918: 
--- end of diff output ---

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

11.07.22 19:39:57. 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-11 19:40:00 No severe PSQL-related problems occured
11.07.22 19:40:01. End of report.