Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2023 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 15041760 kB
MemAvailable 30398552 kB
Buffers 0 kB
Cached 16640220 kB
SwapCached 3180 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 2023-03-16 19:38:42
DTS_END                         2023-03-16 19:38:42.8760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51228
TRN_ID                          251229
ATT_ID                          680
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: 9767.28 16397 1172074
customer order: creation 186.24 310 22349
customer order: refuse 15.28 338 1833
order to supplier: creation 49.30 1201 5916
order to supplier: removal 3.49 100207 419
invoice (draft): creation 48.40 3371 5808
invoice (draft): removal 54.57 779 6548
invoice accept: apply 45.37 16304 5444
invoice accept: cancel 49.93 804 5991
customer reserve: creation 4093.95 165 491274
customer reserve: removal 4895.89 56 587507
realization accept: apply 89.86 166 10783
realization accept: cancel 95.49 100 11459
payment from customer: creation 63.56 96 7627
payment from customer: removal 6.41 66 769
payment to supplier: creation 53.83 117 6460
payment to supplier: removal 3.45 72 414
service: total inventory turnovers 4.78 1119 573
service: total monetary turnovers 6.64 28 797
service: aggregate perf. data 0.83 23090 99
service: refresh index statistics 0.03 179545 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-03-2023 19:39:30 to 16-03-2023 19:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3032
TEST_TIME 2 4061
TEST_TIME 3 3993
TEST_TIME 4 5365
TEST_TIME 5 5145
TEST_TIME 6 5510
TEST_TIME 7 5272
TEST_TIME 8 5261
TEST_TIME 9 5436
TEST_TIME 10 5592
TEST_TIME 11 5429
TEST_TIME 12 5552
TEST_TIME 13 5634
TEST_TIME 14 5688
TEST_TIME 15 6052
TEST_TIME 16 6421
TEST_TIME 17 6516
TEST_TIME 18 6674
TEST_TIME 19 7180
TEST_TIME 20 7628
TEST_TIME 21 7493
TEST_TIME 22 8072
TEST_TIME 23 8348
TEST_TIME 24 8875
TEST_TIME 25 8898
TEST_TIME 26 9248
TEST_TIME 27 9938
TEST_TIME 28 10910
TEST_TIME 29 10727
TEST_TIME 30 10663
TEST_TIME 31 10642
TEST_TIME 32 10626
TEST_TIME 33 10748
TEST_TIME 34 10596
TEST_TIME 35 10450
TEST_TIME 36 10467
TEST_TIME 37 10411
TEST_TIME 38 10425
TEST_TIME 39 10454
TEST_TIME 40 10356
TEST_TIME 41 10189
TEST_TIME 42 10022
TEST_TIME 43 10013
TEST_TIME 44 9888
TEST_TIME 45 9847
TEST_TIME 46 9817
TEST_TIME 47 9775
TEST_TIME 48 10026
TEST_TIME 49 9994
TEST_TIME 50 9911
TEST_TIME 51 9929
TEST_TIME 52 9910
TEST_TIME 53 9798
TEST_TIME 54 9787
TEST_TIME 55 9746
TEST_TIME 56 9632
TEST_TIME 57 9708
TEST_TIME 58 9660
TEST_TIME 59 9760
TEST_TIME 60 9694
TEST_TIME 61 9713
TEST_TIME 62 9854
TEST_TIME 63 9906
TEST_TIME 64 9886
TEST_TIME 65 10035
TEST_TIME 66 9951
TEST_TIME 67 10081
TEST_TIME 68 10011
TEST_TIME 69 10085
TEST_TIME 70 10152
TEST_TIME 71 10230
TEST_TIME 72 10167
TEST_TIME 73 10152
TEST_TIME 74 10028
TEST_TIME 75 10100
TEST_TIME 76 10004
TEST_TIME 77 10029
TEST_TIME 78 9971
TEST_TIME 79 9923
TEST_TIME 80 9854
TEST_TIME 81 9844
TEST_TIME 82 9819
TEST_TIME 83 9799
TEST_TIME 84 9822
TEST_TIME 85 9784
TEST_TIME 86 9799
TEST_TIME 87 9797
TEST_TIME 88 9798
TEST_TIME 89 9805
TEST_TIME 90 9859
TEST_TIME 91 9872
TEST_TIME 92 9839
TEST_TIME 93 9842
TEST_TIME 94 9826
TEST_TIME 95 9921
TEST_TIME 96 9960
TEST_TIME 97 9968
TEST_TIME 98 9991
TEST_TIME 99 10010
TEST_TIME 100 9933
TEST_TIME 101 9882
TEST_TIME 102 9875
TEST_TIME 103 9842
TEST_TIME 104 9826
TEST_TIME 105 9798
TEST_TIME 106 9784
TEST_TIME 107 9745
TEST_TIME 108 9741
TEST_TIME 109 9719
TEST_TIME 110 9733
TEST_TIME 111 9752
TEST_TIME 112 9737
TEST_TIME 113 9744
TEST_TIME 114 9769
TEST_TIME 115 9746
TEST_TIME 116 9796
TEST_TIME 117 9770
TEST_TIME 118 9807
TEST_TIME 119 9779
TEST_TIME 120 9771

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-03-2023 19:39:34 to 16-03-2023 19:39:37.

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 1627660 1627660 0 0.00 0 16692 6 0 0
doc_list_biud 1627660 1627660 0 0.00 0 27356 26 0 0
sp_add_invoice_to_stock 5557 5444 113 2.03 19 167412 16304 0 113
sp_cancel_adding_invoice 5991 5991 0 0.00 5 70573 804 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 13837 338 0 0
sp_cancel_customer_reserve 587507 587507 0 0.00 0 27362 57 0 0
sp_cancel_pay_from_customer 769 769 0 0.00 3 3433 66 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 5947 72 0 0
sp_cancel_supplier_invoice 6564 6548 16 0.24 2 25267 779 0 16
sp_cancel_supplier_order 419 419 0 0.00 9 1239574 100208 0 0
sp_cancel_write_off 11459 11459 0 0.00 1 11563 101 0 0
sp_client_order 22349 22349 0 0.00 3 27283 311 0 0
sp_customer_reserve 491274 491274 0 0.00 0 98502 166 0 0
sp_fill_shopping_cart 34063 34063 0 0.00 1 16803 35 0 0
sp_fill_shopping_cart_clo_res 491266 491266 0 0.00 0 12925 11 0 0
sp_get_clo_for_invoice 5436 5436 0 0.00 0 26994 247 0 0
sp_kill_cost_storno 18633 18633 0 0.00 0 13786 52 0 0
sp_kill_qstorno_ret_qs2qd 1193440 1193440 0 0.00 0 27348 31 0 0
sp_kill_qty_storno 629975 629975 0 0.00 0 27356 66 0 0
sp_lock_selected_doc 22322 22322 0 0.00 0 9429 6 0 0
sp_make_cost_storno 30305 30305 0 0.00 0 16625 50 0 0
sp_multiply_rows_for_pdistr 30305 30305 0 0.00 0 12852 25 0 0
sp_multiply_rows_for_qdistr 22349 22349 0 0.00 0 27274 142 0 0
sp_pay_from_customer 7627 7627 0 0.00 4 14057 96 0 0
sp_pay_to_supplier 6460 6460 0 0.00 4 16680 117 0 0
sp_payment_common 14087 14087 0 0.00 1 12269 25 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 13831 301 0 0
sp_qd_handle_on_invoice_upd_sts 11426 11426 0 0.00 0 23501 302 0 0
sp_qd_handle_on_reserve_upd_sts 10783 10783 0 0.00 0 18791 60 0 0
sp_reserve_write_off 10783 10783 0 0.00 6 18936 167 0 0
sp_supplier_invoice 5808 5808 0 0.00 5 84827 3372 0 0
sp_supplier_order 5916 5916 0 0.00 5 35878 1201 0 0
srv_aggregate_perf_data 99 99 0 0.00 2577 425072 23091 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1338 116090 42859 0 0
srv_find_qd_qs_mism 1121190 1121190 0 0.00 0 14846 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 44 61959 1119 0 0
srv_make_money_saldo 797 797 0 0.00 1 3181 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 109308 276407 179546 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5445 5445 0 0.00 0 10569 156 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 2937 77 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 3542 12 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 7064 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 769 769 0 0.00 0 3248 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 398 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 754 8 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 27918 1562 0 0
t$perf-norm:sp_cancel_write_off 1099 1099 0 0.00 0 771 5 0 0
t$perf-norm:sp_client_order 22350 22350 0 0.00 0 20074 16 0 0
t$perf-norm:sp_customer_reserve 1112 1112 0 0.00 0 509 4 0 0
t$perf-norm:sp_pay_from_customer 7628 7628 0 0.00 0 15326 12 0 0
t$perf-norm:sp_pay_to_supplier 6460 6460 0 0.00 0 4570 9 0 0
t$perf-norm:sp_reserve_write_off 10783 10783 0 0.00 0 6204 9 0 0
t$perf-norm:sp_supplier_invoice 5808 5808 0 0.00 0 5668 16 0 0
t$perf-norm:sp_supplier_order 5916 5916 0 0.00 0 6932 14 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 156 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 26 1 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 1895 26 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 2021 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 11096 10870 226 2.03 0 5052 18 0 226
v_all_customers 40704 40704 0 0.00 0 37 1 0 0
v_all_suppliers 32688 32688 0 0.00 0 92 1 0 0
v_all_wares 44698 44698 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 750 7 0 0
v_cancel_client_order 3666 3666 0 0.00 0 3851 6 0 0
v_cancel_customer_prepayment 1538 1538 0 0.00 0 2553 5 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 1827 5 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 5915 12 0 0
v_cancel_supplier_invoice 826 794 32 3.87 0 203 10 0 32
v_cancel_supplier_order 838 838 0 0.00 0 1291 7 0 0
v_cancel_write_off 2198 2198 0 0.00 0 3519 14 0 0
v_max_id_clo_ord 5913 5913 0 0.00 0 8839 3 0 0
v_max_id_clo_res 1112 1112 0 0.00 0 6445 16 0 0
v_max_id_ord_sup 5801 5801 0 0.00 0 7336 5 0 0
v_max_non_paid_invoice 1830 1830 0 0.00 0 3397 4 0 0
v_max_non_paid_realizn 4078 4078 0 0.00 0 9419 7 0 0
v_min_id_clo_ord 5913 5913 0 0.00 0 2225 4 0 0
v_min_id_clo_res 1112 1112 0 0.00 0 3923 12 0 0
v_min_id_ord_sup 5801 5801 0 0.00 0 7099 5 0 0
v_min_non_paid_invoice 6460 6460 0 0.00 0 6031 17 0 0
v_min_non_paid_realizn 7627 7627 0 0.00 0 12265 19 0 0
v_our_firm 11092 11092 0 0.00 0 19 1 0 0
v_reserve_write_off 21566 21566 0 0.00 0 3809 5 0 0
x_lock_depdocs_on_canc_invoice 12142 12142 0 0.00 0 12680 106 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 20250 339 0 0
x_make_qstorno_1000_1200 5916 5916 0 0.00 2 35869 1130 0 0
x_make_qstorno_1200_2000 5808 5808 0 0.00 2 84728 3242 0 0
x_make_qstorno_2100_3300 491274 491274 0 0.00 0 98498 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2023 19:39:38 to 16-03-2023 19:39:42.

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:46 3 8192000000 8801262096 8813252608 7287968 0.088 2 2 3 0 7429056 66144 74944
17:09:48 34499 8192000000 9901863232 10212794368 974574048 10.631 100 91 183 0 1004633424 5499392 24559984
17:11:27 17861 8192000000 9946448784 10267074560 1029484224 11.163 100 92 184 0 1061812080 8044992 24282864
17:13:07 12099 8192000000 9982131824 10314911744 1068220224 11.535 100 83 166 0 1098492384 9304272 20967888
17:14:24 8474 8192000000 10043288336 10392104960 1129429696 12.116 100 76 152 0 1158359664 10381104 18548864
17:15:39 4435 8192000000 10104942864 10468827136 1194658048 12.727 100 80 160 0 1224971120 12025872 18287200
17:16:45 3077 8192000000 10133458448 10503344128 1223649936 12.995 100 86 172 0 1258159680 15533216 18976528
17:17:50 1498 8192000000 10159799136 10536529920 1252291248 13.259 100 92 183 0 1285444800 13220432 19933120
17:18:53 1507 8192000000 10196171264 10580025344 1284911712 13.558 100 81 160 0 1314124976 11443456 17769808
17:19:55 36491 8192000000 10198086896 10601631744 1301134928 13.706 100 47 94 0 1320838048 9103904 10599216
17:21:34 3232 8192000000 10199428752 10609078272 1313145856 13.815 100 29 58 0 1326571216 7409760 6015600
17:22:39 9189 8192000000 10208283232 10623725568 1319651952 13.874 100 22 44 0 1330117120 5423200 5041968
17:23:54 11207 8192000000 10200484304 10621677568 1321978128 13.895 100 17 34 0 1331029104 5486608 3564368
17:25:08 42937 8192000000 10243998176 10644074496 1330251872 13.969 100 60 120 1 1350873984 6078608 14543504
17:26:51 3449 8192000000 10228516304 10649980928 1336508272 14.026 100 26 53 0 1347240272 4951552 5780448
17:27:58 10046 8192000000 10231160400 10646818816 1332930560 13.994 100 19 39 0 1343187600 6050608 4206432
17:29:08 21555 8192000000 10187339312 10606194688 1293270016 13.634 99 41 82 0 1311016784 5213296 12533472
17:30:30 14855 8192000000 10145179632 10544312320 1160214112 12.405 90 21 42 0 1170734544 5957664 4562768
17:31:46 5341 8192000000 10141071616 10533879808 1220826368 12.969 98 29 58 0 1236870176 9864800 6179008
17:32:54 13439 8192000000 10159551984 10556903424 1259325168 13.324 100 20 40 0 1269478160 5255680 4897312
17:34:09 49315 8192000000 10155946016 10569187328 1248939088 13.228 98 37 71 0 1262477632 5268288 8270256
17:36:02 9962 8192000000 10186539520 10590982144 1286698480 13.574 100 25 50 0 1298169600 5974880 5496240
17:37:18 9000 8192000000 10187521424 10600366080 1297071232 13.669 100 10 20 0 1304407072 5330672 2005168
17:38:29 69353 8192000000 10231051664 10638491648 1323038784 13.904 100 34 68 0 1336168016 5157104 7972128
17:40:42 7658 8192000000 10245814256 10660204544 1345063408 14.103 100 48 94 0 1365899552 10299312 10536832
17:41:54 38064 8192000000 10274443072 10695180288 1362461088 14.259 100 38 76 0 1387342112 16251264 8629760
17:43:36 17831 8192000000 10277294864 10696736768 1363817360 14.272 100 67 133 1 1396574784 17029088 15728336
17:45:02 14002 8192000000 10279202992 10705313792 1362014976 14.255 99 33 66 0 1388511840 17669344 8827520
17:46:21 9834 8192000000 10303615248 10727993344 1382048384 14.435 100 48 96 0 1419548832 25649968 11850480
17:47:47 13565 8192000000 10336727504 10758963200 1405993024 14.648 100 59 115 0 1448790624 28703296 14094304
17:49:04 6797 8192000000 10348395712 10769874944 1402802384 14.620 99 55 110 0 1449855024 33529600 13523040
17:50:29 2568 8192000000 10362557952 10779942912 1415997712 14.737 100 66 132 2 1470086576 37309968 16778896
17:51:33 2820 8192000000 10356765952 10769133568 1391010992 14.515 99 67 134 0 1450250928 43135472 16104464
17:52:41 9540 8192000000 10364666720 10782318592 1409326224 14.678 100 69 137 0 1472674432 46237856 17110352
17:54:04 1901 8192000000 10366489216 10783223808 1401644704 14.610 99 71 140 1 1467467504 48741600 17081200
17:55:10 2316 8192000000 10344883296 10754039808 1385196416 14.463 100 75 150 0 1452733408 48673136 18863856
17:56:24 4029 8192000000 10328062016 10729918464 1352564640 14.171 99 75 148 0 1424832976 52954688 19313648
17:57:40 1338 8192000000 10332452512 10737180672 1355676000 14.199 100 88 176 0 1440205232 61606672 22922560
17:58:46 2397 8192000000 10307921152 10716471296 1344028768 14.094 99 65 129 2 1409333152 49148464 16155920
17:59:59 3295 8192000000 10347527488 10754027520 1368501872 14.314 100 73 145 1 1449297008 62188592 18606544
18:01:05 17913 8192000000 10335561216 10755805184 1362756144 14.262 99 43 86 0 1431110928 56579184 11775600
18:02:33 25801 8192000000 10314904192 10746056704 1362180720 14.257 99 33 66 0 1418497072 46826576 9489776
18:04:15 18677 8192000000 10299054512 10725883904 1343982576 14.093 99 26 52 0 1396868144 45177504 7708064
18:05:48 55987 8192000000 10290126288 10740506624 1327762832 13.947 98 12 24 1 1375240016 43608400 3868784
18:07:44 59467 8192000000 10275147712 10706604032 1357933376 14.219 100 29 55 0 1375728288 11805008 5989904
18:09:54 37946 8192000000 10294323104 10740989952 1368289200 14.312 100 9 18 0 1382698288 11931040 2478048
18:13:12 30929 8192000000 10304872048 10734215168 1375166448 14.373 99 28 55 0 1388360096 7430336 5763312
18:14:45 78205 8192000000 10298762080 10743918592 1362392016 14.259 98 13 26 0 1373688384 8728960 2567408
18:17:04 28488 8192000000 10313640112 10756440064 1394122944 14.543 100 8 16 0 1407885024 11987936 1774144
18:18:42 73160 8192000000 10306558336 10750324736 1381688288 14.432 99 17 34 1 1397861344 12105872 4067184
18:21:08 105215 8192000000 10333683392 10787864576 1410064064 14.685 100 24 48 0 1424249008 8604384 5580560
18:23:55 38729 8192000000 10339235744 10789019648 1398242816 14.579 98 34 68 0 1422445952 16358560 7844576
18:25:52 41604 8192000000 10310315792 10776084480 1380782144 14.424 97 19 38 0 1401955152 16557712 4615296
18:27:47 51099 8192000000 10321193408 10789445632 1416501280 14.742 100 11 22 0 1431985824 13019200 2465344
18:29:38 32408 8192000000 10304567088 10782060544 1388044720 14.488 98 8 16 0 1407159872 17084592 2030560
18:31:15 70707 8192000000 10298348032 10756124672 1388463808 14.492 99 15 30 0 1402521168 10947984 3109376
18:33:26 84525 8192000000 10297056992 10763030528 1380529232 14.421 98 15 30 0 1394444208 10376672 3538304
18:36:00 51635 8192000000 10284445952 10743554048 1333376000 13.998 96 11 22 0 1343072928 7307840 2389088
18:37:56 42466 8192000000 10286072144 10736439296 1317765360 13.856 95 14 28 0 1327348976 6773568 2810048
18:39:42 112247 8192000000 10290231568 10759041024 1342325520 14.078 97 10 20 0 1359402208 15043408 2033280
18:42:35 44995 8192000000 10262495936 10715168768 1325459568 13.926 96 20 40 0 1342929328 12245472 5224288
18:44:21 40209 8192000000 10287335680 10733158400 1355600512 14.198 99 29 58 0 1379350384 17228000 6521872
18:46:45 51731 8192000000 10260021680 10713538560 1283324256 13.543 94 33 66 0 1308843248 17461200 8057792
18:48:42 39969 8192000000 10273142688 10716700672 1340334128 14.060 99 28 58 0 1363627552 17199808 6093616
18:50:22 44797 8192000000 10271399776 10708201472 1298504896 13.682 95 29 56 1 1315233632 10274800 6453936
18:52:31 116090 8192000000 10252671808 10716045312 1305883904 13.749 96 18 37 0 1324366032 14052368 4429760
18:55:40 25657 8192000000 10256533840 10708725760 1351042256 14.157 100 15 30 0 1363098496 8623904 3432336
18:57:05 61364 8192000000 10277376736 10733932544 1360326512 14.240 99 12 24 0 1375632544 12335168 2970864
18:59:07 49284 8192000000 10279441264 10740838400 1362486544 14.260 99 16 32 0 1384440288 17597104 4356640
19:01:05 47153 8192000000 10275382288 10734624768 1370006016 14.327 99 20 40 0 1388225152 13020192 5198944
19:03:02 39431 8192000000 10285684656 10745671680 1380765984 14.423 100 21 40 0 1394880480 9311536 4802960
19:04:51 73465 8192000000 10296937264 10754285568 1374133312 14.364 99 25 50 0 1388511008 9215408 5162288
19:07:06 66112 8192000000 10299519392 10767609856 1378462176 14.403 99 15 30 0 1401256496 19181664 3612656
19:09:16 56344 8192000000 10302239152 10745327616 1373429024 14.358 98 29 58 0 1388111744 8027184 6655536
19:11:16 23729 8192000000 10319514560 10769575936 1403223648 14.624 100 25 50 0 1418233792 9836560 5173584
19:12:48 63672 8192000000 10319547584 10793795584 1412381648 14.705 100 21 42 0 1435180224 18192736 4605840
19:14:59 109759 8192000000 10308136784 10776891392 1353889968 14.182 96 11 21 0 1365817952 9582816 2345168
19:17:50 92162 8192000000 10301968800 10745999360 1372141952 14.346 98 26 49 1 1394178272 15939552 6096768
19:20:27 68417 8192000000 10301212912 10768486400 1392212880 14.526 99 17 34 1 1407965600 11532048 4220672
19:22:36 37471 8192000000 10278035600 10734862336 1360057536 14.238 98 20 40 0 1372323056 7653648 4611872
19:24:22 33164 8192000000 10276757344 10736963584 1376274720 14.383 100 21 40 0 1389784048 8950096 4559232
19:26:23 71800 8192000000 10266127520 10737270784 1355950912 14.201 99 12 24 0 1372536640 13812464 2773264
19:28:45 72311 8192000000 10269704512 10747670528 1355581536 14.198 99 6 12 0 1368977440 12094336 1301568
19:30:59 61308 8192000000 10282901776 10739359744 1337046864 14.031 97 20 40 0 1353478080 11757056 4674160
19:33:01 43475 8192000000 10268991296 10717577216 1359497216 14.233 100 32 64 1 1376835296 10183904 7154176
19:34:44 47697 8192000000 10278611696 10723803136 1335728832 14.019 98 25 50 1 1352089104 10973776 5386496
19:36:35 21486 8192000000 10260574768 10703560704 1317856352 13.857 97 38 76 0 1335142256 9168768 8117136
19:37:58 18217 8192000000 10270897424 10713083904 1347164992 14.122 100 45 89 1 1371094096 14417200 9511904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-03-2023 19:39:44 to 16-03-2023 19:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2023 19:40:01 to 16-03-2023 19:40:03.

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          247877
MON$OLDEST_ACTIVE               251359
MON$OLDEST_SNAPSHOT             251359
MON$NEXT_TRANSACTION            251359
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                       2054048
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:347: 
:348: mscs-test-161.budzdorov.ru    Thu Mar 16 19:38:44 2023
:349:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:350: 
:351: 
:352: mscs-test-161.budzdorov.ru    Thu Mar 16 19:38:45 2023
:353:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:354: 
--- end of diff output ---

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

16.03.23 19:40:04. 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
2023-03-16 19:40:06 No severe PSQL-related problems occured
16.03.23 19:40:08. End of report.