Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.2023 01: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 15269088 kB
MemAvailable 30423336 kB
Buffers 0 kB
Cached 16420848 kB
SwapCached 3032 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-10 01:38:42
DTS_END                         2023-03-10 01:38:42.9380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60472
TRN_ID                          248666
ATT_ID                          682
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: 9444.36 17466 1133323
customer order: creation 182.56 339 21907
customer order: refuse 15.03 345 1803
order to supplier: creation 48.83 1132 5860
order to supplier: removal 3.48 86514 418
invoice (draft): creation 47.49 3062 5699
invoice (draft): removal 52.02 672 6242
invoice accept: apply 45.05 16054 5406
invoice accept: cancel 47.76 701 5731
customer reserve: creation 4072.39 162 488687
customer reserve: removal 4622.10 51 554652
realization accept: apply 88.63 168 10635
realization accept: cancel 83.14 108 9977
payment from customer: creation 62.50 120 7500
payment from customer: removal 6.22 72 746
payment to supplier: creation 51.00 142 6120
payment to supplier: removal 3.53 94 423
service: total inventory turnovers 5.07 1210 608
service: total monetary turnovers 6.66 28 799
service: aggregate perf. data 0.88 18249 106
service: refresh index statistics 0.03 220098 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2023 01:40:00 to 10-03-2023 01:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3762
TEST_TIME 2 4095
TEST_TIME 3 3778
TEST_TIME 4 4016
TEST_TIME 5 4094
TEST_TIME 6 4195
TEST_TIME 7 4744
TEST_TIME 8 5032
TEST_TIME 9 5068
TEST_TIME 10 5207
TEST_TIME 11 5268
TEST_TIME 12 5443
TEST_TIME 13 5882
TEST_TIME 14 6551
TEST_TIME 15 6932
TEST_TIME 16 7214
TEST_TIME 17 7656
TEST_TIME 18 8040
TEST_TIME 19 8468
TEST_TIME 20 8950
TEST_TIME 21 9451
TEST_TIME 22 9481
TEST_TIME 23 10204
TEST_TIME 24 10152
TEST_TIME 25 10259
TEST_TIME 26 10282
TEST_TIME 27 11155
TEST_TIME 28 10948
TEST_TIME 29 10602
TEST_TIME 30 10588
TEST_TIME 31 10510
TEST_TIME 32 10377
TEST_TIME 33 10370
TEST_TIME 34 10268
TEST_TIME 35 10184
TEST_TIME 36 10064
TEST_TIME 37 10019
TEST_TIME 38 10126
TEST_TIME 39 10143
TEST_TIME 40 10064
TEST_TIME 41 9930
TEST_TIME 42 9837
TEST_TIME 43 9662
TEST_TIME 44 9787
TEST_TIME 45 9661
TEST_TIME 46 9742
TEST_TIME 47 9692
TEST_TIME 48 9666
TEST_TIME 49 9598
TEST_TIME 50 9727
TEST_TIME 51 9649
TEST_TIME 52 9749
TEST_TIME 53 9720
TEST_TIME 54 9924
TEST_TIME 55 9862
TEST_TIME 56 9867
TEST_TIME 57 9858
TEST_TIME 58 9904
TEST_TIME 59 9967
TEST_TIME 60 9924
TEST_TIME 61 9983
TEST_TIME 62 9843
TEST_TIME 63 9727
TEST_TIME 64 9771
TEST_TIME 65 9739
TEST_TIME 66 9671
TEST_TIME 67 9621
TEST_TIME 68 9545
TEST_TIME 69 9553
TEST_TIME 70 9622
TEST_TIME 71 9573
TEST_TIME 72 9554
TEST_TIME 73 9558
TEST_TIME 74 9671
TEST_TIME 75 9706
TEST_TIME 76 9705
TEST_TIME 77 9720
TEST_TIME 78 9683
TEST_TIME 79 9692
TEST_TIME 80 9750
TEST_TIME 81 9808
TEST_TIME 82 9784
TEST_TIME 83 9776
TEST_TIME 84 9699
TEST_TIME 85 9693
TEST_TIME 86 9636
TEST_TIME 87 9620
TEST_TIME 88 9581
TEST_TIME 89 9526
TEST_TIME 90 9532
TEST_TIME 91 9466
TEST_TIME 92 9449
TEST_TIME 93 9453
TEST_TIME 94 9451
TEST_TIME 95 9481
TEST_TIME 96 9504
TEST_TIME 97 9503
TEST_TIME 98 9594
TEST_TIME 99 9598
TEST_TIME 100 9679
TEST_TIME 101 9665
TEST_TIME 102 9638
TEST_TIME 103 9578
TEST_TIME 104 9669
TEST_TIME 105 9690
TEST_TIME 106 9756
TEST_TIME 107 9674
TEST_TIME 108 9614
TEST_TIME 109 9593
TEST_TIME 110 9583
TEST_TIME 111 9569
TEST_TIME 112 9515
TEST_TIME 113 9484
TEST_TIME 114 9470
TEST_TIME 115 9483
TEST_TIME 116 9445
TEST_TIME 117 9472
TEST_TIME 118 9474
TEST_TIME 119 9447
TEST_TIME 120 9451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-03-2023 01:40:04 to 10-03-2023 01:40:07.

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 1585793 1585793 0 0.00 0 17013 6 0 0
doc_list_biud 1585793 1585793 0 0.00 0 24115 23 0 0
sp_add_invoice_to_stock 5527 5406 121 2.18 41 183399 16054 0 121
sp_cancel_adding_invoice 5731 5731 0 0.00 4 46868 702 0 0
sp_cancel_client_order 1803 1803 0 0.00 4 11651 345 0 0
sp_cancel_customer_reserve 554652 554652 0 0.00 0 24124 52 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 2 10424 73 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 6241 94 0 0
sp_cancel_supplier_invoice 6257 6242 15 0.23 1 17968 672 0 15
sp_cancel_supplier_order 418 418 0 0.00 5 1121945 86515 0 0
sp_cancel_write_off 9977 9977 0 0.00 1 14123 109 0 0
sp_client_order 21907 21907 0 0.00 3 21438 339 0 0
sp_customer_reserve 488687 488687 0 0.00 0 132758 162 0 0
sp_fill_shopping_cart 33462 33462 0 0.00 1 25689 41 0 0
sp_fill_shopping_cart_clo_res 488675 488675 0 0.00 0 13592 14 0 0
sp_get_clo_for_invoice 5394 5394 0 0.00 0 27847 343 0 0
sp_kill_cost_storno 16877 16877 0 0.00 0 11952 48 0 0
sp_kill_qstorno_ret_qs2qd 1125941 1125941 0 0.00 0 24086 28 0 0
sp_kill_qty_storno 594852 594852 0 0.00 0 24112 61 0 0
sp_lock_selected_doc 22099 22099 0 0.00 0 7345 5 0 0
sp_make_cost_storno 29649 29649 0 0.00 0 16272 47 0 0
sp_multiply_rows_for_pdistr 29649 29649 0 0.00 0 15934 25 0 0
sp_multiply_rows_for_qdistr 21907 21907 0 0.00 0 13425 169 0 0
sp_pay_from_customer 7500 7500 0 0.00 4 24140 120 0 0
sp_pay_to_supplier 6120 6120 0 0.00 4 24567 143 0 0
sp_payment_common 13620 13620 0 0.00 1 16114 40 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 0 11644 319 0 0
sp_qd_handle_on_invoice_upd_sts 11125 11125 0 0.00 0 20123 283 0 0
sp_qd_handle_on_reserve_upd_sts 10635 10635 0 0.00 0 21286 61 0 0
sp_reserve_write_off 10635 10635 0 0.00 5 21303 168 0 0
sp_supplier_invoice 5699 5699 0 0.00 5 75248 3062 0 0
sp_supplier_order 5860 5860 0 0.00 4 30609 1132 0 0
srv_aggregate_perf_data 106 106 0 0.00 2892 280020 18250 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1597 155554 44260 0 0
srv_find_qd_qs_mism 1082931 1082931 0 0.00 0 15915 5 0 0
srv_make_invnt_saldo 608 608 0 0.00 46 119824 1211 0 0
srv_make_money_saldo 799 799 0 0.00 1 2179 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 76834 376036 220098 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5406 5406 0 0.00 0 12229 162 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 2422 57 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 2591 9 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 3257 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 2818 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 6197 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 457 10 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 24480 1450 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 902 3 0 0
t$perf-norm:sp_client_order 21907 21907 0 0.00 0 14119 16 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 8142 13 0 0
t$perf-norm:sp_pay_from_customer 7500 7500 0 0.00 0 8659 11 0 0
t$perf-norm:sp_pay_to_supplier 6120 6120 0 0.00 0 13561 14 0 0
t$perf-norm:sp_reserve_write_off 10635 10635 0 0.00 0 7007 11 0 0
t$perf-norm:sp_supplier_invoice 5699 5699 0 0.00 0 10912 22 0 0
t$perf-norm:sp_supplier_order 5860 5860 0 0.00 0 9789 21 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 24 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 44 1 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 984 15 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 2760 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 11030 10788 242 2.19 0 7004 19 0 242
v_all_customers 39822 39822 0 0.00 0 63 0 0 0
v_all_suppliers 31710 31710 0 0.00 0 19 1 0 0
v_all_wares 43812 43812 0 0.00 0 118 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 8224 22 0 0
v_cancel_client_order 3606 3606 0 0.00 0 5741 7 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 960 5 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 1968 6 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 2016 8 0 0
v_cancel_supplier_invoice 916 886 30 3.27 0 2976 23 0 30
v_cancel_supplier_order 836 836 0 0.00 0 496 3 0 0
v_cancel_write_off 2140 2140 0 0.00 0 8216 19 0 0
v_max_id_clo_ord 5860 5860 0 0.00 0 232 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 3433 14 0 0
v_max_id_ord_sup 5696 5696 0 0.00 0 4430 3 0 0
v_max_non_paid_invoice 1821 1821 0 0.00 0 1043 3 0 0
v_max_non_paid_realizn 4016 4016 0 0.00 0 5533 6 0 0
v_min_id_clo_ord 5860 5860 0 0.00 0 7148 5 0 0
v_min_id_clo_res 991 991 0 0.00 0 7788 19 0 0
v_min_id_ord_sup 5696 5696 0 0.00 0 4891 6 0 0
v_min_non_paid_invoice 6120 6120 0 0.00 0 15068 31 0 0
v_min_non_paid_realizn 7500 7500 0 0.00 0 12690 34 0 0
v_our_firm 10958 10958 0 0.00 0 27 1 0 0
v_reserve_write_off 21270 21270 0 0.00 0 8717 7 0 0
x_lock_depdocs_on_canc_invoice 11530 11530 0 0.00 0 22235 124 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 8005 327 0 0
x_make_qstorno_1000_1200 5860 5860 0 0.00 1 30595 1067 0 0
x_make_qstorno_1200_2000 5699 5699 0 0.00 2 75221 2896 0 0
x_make_qstorno_2100_3300 488687 488687 0 0.00 0 132756 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2023 01:40:09 to 10-03-2023 01:40:13.

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
23:08:46 2 8192000000 8799619824 8811085824 6016352 0.073 2 1 2 0 6115984 56848 42784
23:09:47 13663 8192000000 9900945664 10215608320 985780752 10.740 100 83 166 0 1013724960 6300080 21644128
23:11:06 3113 8192000000 9956131328 10280665088 1040157536 11.266 100 90 179 0 1071657712 8958768 22541408
23:12:28 12648 8192000000 9993486464 10327879680 1076882528 11.618 100 89 178 0 1109613248 10799856 21930864
23:13:51 12011 8192000000 10041394016 10387996672 1123507360 12.060 100 81 162 0 1154746400 12250784 18988256
23:15:05 6730 8192000000 10080584032 10436620288 1165866928 12.458 100 87 174 1 1199845888 13555616 20423344
23:16:17 3270 8192000000 10118861152 10486382592 1202705056 12.801 100 79 156 0 1233120496 13233616 17181824
23:17:22 2332 8192000000 10149438800 10522316800 1235166048 13.102 100 85 170 0 1267145440 12908832 19070560
23:18:27 3629 8192000000 10178274736 10560413696 1264670224 13.373 100 77 152 1 1297329984 16738032 15921728
23:19:32 770 8192000000 10219478352 10602844160 1299362368 13.689 100 90 180 0 1333751824 14954896 19434560
23:20:35 802 8192000000 10219107408 10609917952 1311260368 13.798 100 83 168 0 1340035840 10240400 18535072
23:21:36 19724 8192000000 10191239472 10608021504 1308714384 13.774 100 32 64 0 1322251840 5755760 7781696
23:22:56 6042 8192000000 10204316192 10618658816 1317504864 13.854 100 28 54 1 1329963232 5918048 6540320
23:24:03 8302 8192000000 10210655488 10626150400 1325257440 13.924 100 14 29 0 1333532960 5573360 2702160
23:25:17 11641 8192000000 10214427296 10636656640 1333187264 13.996 100 17 34 0 1342734400 5826128 3721008
23:26:34 22647 8192000000 10217715552 10640830464 1322121024 13.896 99 32 65 1 1335340736 5563520 7656192
23:27:57 7923 8192000000 10229338816 10648322048 1324549408 13.918 99 21 42 0 1334742480 5357680 4835392
23:29:06 9797 8192000000 10204819456 10619219968 1293900176 13.640 98 17 34 0 1302146256 4472688 3773392
23:30:16 8462 8192000000 10162682112 10565890048 1234127008 13.092 96 29 60 0 1246343280 5086352 7129920
23:31:57 6072 8192000000 10114093840 10514198528 1205743392 12.830 97 20 40 0 1215677792 5400592 4533808
23:33:06 7797 8192000000 10129558992 10529181696 1227104848 13.027 98 28 54 0 1239477376 6222480 6150048
23:34:15 7547 8192000000 10165447648 10570272768 1270941968 13.430 100 23 44 0 1282133728 5356592 5835168
23:35:28 47093 8192000000 10168055184 10580201472 1280982256 13.522 100 40 79 2 1295608688 5383168 9243264
23:37:17 6697 8192000000 10182241776 10593939456 1292541392 13.627 100 20 40 1 1302395264 4933808 4920064
23:38:26 7806 8192000000 10209037792 10622648320 1318932192 13.867 100 21 42 0 1328583056 5191072 4459792
23:39:42 11530 8192000000 10239746096 10645094400 1331115984 13.977 100 54 108 0 1354587616 11035360 12436272
23:40:57 66834 8192000000 10255371536 10673721344 1335365184 14.016 99 38 76 0 1355665120 11437776 8862160
23:43:10 48414 8192000000 10276171232 10699014144 1362030368 14.256 100 20 40 0 1387940848 21651264 4259216
23:45:01 13225 8192000000 10297045360 10714931200 1378117520 14.400 100 52 102 1 1412396448 22358480 11920448
23:46:17 6870 8192000000 10320309312 10738475008 1390577376 14.511 100 67 134 0 1432009520 25455936 15976208
23:47:30 10398 8192000000 10332661376 10751139840 1402419728 14.617 100 52 102 0 1440675440 25370352 12885360
23:48:52 3675 8192000000 10356367904 10776219648 1419660272 14.770 100 66 132 1 1464038896 27893920 16484704
23:50:01 2132 8192000000 10358174416 10773893120 1414811632 14.727 100 77 154 1 1465491616 31403888 19276096
23:51:08 4134 8192000000 10366942992 10791251968 1416609744 14.743 99 62 119 1 1470836512 39010768 15216000
23:52:17 3205 8192000000 10353694096 10759094272 1381538768 14.430 98 82 162 1 1445761280 44282592 19939920
23:53:26 5182 8192000000 10345409376 10766852096 1395674704 14.556 100 63 125 1 1452710640 41425728 15610208
23:54:46 5494 8192000000 10314787616 10722660352 1352338224 14.169 99 72 144 1 1412140800 41914832 17887744
23:55:59 1596 8192000000 10319933008 10728329216 1356531968 14.206 100 76 151 1 1421889344 46361120 18996256
23:57:05 10869 8192000000 10310892272 10737405952 1364668528 14.279 100 51 103 0 1426917920 48781408 13467984
23:58:26 12369 8192000000 10281601600 10714152960 1336465136 14.026 99 25 50 0 1380292128 36995728 6831264
23:59:44 32060 8192000000 10296616320 10728284160 1348085056 14.130 99 17 34 0 1384113152 31216656 4811440
00:01:21 28090 8192000000 10282819600 10730545152 1362585664 14.261 100 23 44 1 1403993680 35402528 6005488
00:02:56 30171 8192000000 10275315216 10712080384 1326060720 13.932 97 14 28 0 1349952224 20301728 3589776
00:04:28 79513 8192000000 10249629760 10697719808 1276160848 13.478 94 18 36 0 1304855872 24435824 4259200
00:06:48 85122 8192000000 10265599632 10700263424 1341921584 14.075 99 44 84 2 1357989728 7082624 8985520
00:09:14 56600 8192000000 10249186336 10685980672 1264354192 13.370 93 22 44 0 1275647840 6902784 4390864
00:11:16 155554 8192000000 10277512176 10719940608 1310234672 13.788 96 14 29 0 1323971104 9895184 3841248
00:14:58 30935 8192000000 10302541712 10730086400 1377002032 14.390 100 40 72 0 1399339984 14182080 8155872
00:16:30 41070 8192000000 10275753664 10738810880 1376746256 14.387 100 10 19 0 1393800192 14984784 2069152
00:18:33 58302 8192000000 10269600064 10724847616 1366027616 14.291 99 9 18 0 1374516224 6629312 1859296
00:20:34 79206 8192000000 10293362288 10743922688 1389777376 14.504 100 9 18 0 1401141232 8253296 3110560
00:22:59 71060 8192000000 10298418320 10758139904 1400851024 14.603 100 25 51 0 1417458240 11172912 5434304
00:25:10 53369 8192000000 10285865952 10756505600 1393187424 14.534 100 3 6 0 1403767392 9985936 594032
00:27:16 46980 8192000000 10306573456 10762928128 1404943760 14.639 100 15 30 0 1419070784 10874256 3252768
00:29:03 61069 8192000000 10303391840 10756374528 1356763408 14.208 96 29 58 0 1375251520 11916688 6571424
00:31:08 71955 8192000000 10297563456 10759905280 1355416912 14.196 97 10 20 0 1379193728 21548688 2228128
00:33:26 25238 8192000000 10301343392 10740879360 1363818624 14.272 98 34 64 0 1383804640 13018880 6967136
00:34:51 13238 8192000000 10266907120 10692169728 1326967072 13.940 98 46 93 0 1345877648 9160752 9749824
00:36:06 34763 8192000000 10282190432 10725060608 1349878304 14.146 99 31 60 1 1370954208 14334880 6741024
00:37:42 38893 8192000000 10286712480 10707427328 1320825536 13.884 96 32 64 3 1335891216 8277312 6788368
00:39:27 86835 8192000000 10278387440 10718724096 1359535568 14.233 99 30 60 2 1375938576 10078448 6324560
00:41:56 29760 8192000000 10267356336 10713325568 1350148736 14.149 99 33 66 0 1375750272 18409168 7192368
00:43:29 44427 8192000000 10257063712 10716147712 1342647184 14.081 98 10 20 0 1354937472 10163840 2126448
00:46:30 34716 8192000000 10288360864 10735779840 1380249840 14.419 100 19 39 0 1398371984 13186144 4936000
00:48:05 60883 8192000000 10272622768 10738851840 1331332096 13.979 97 12 24 0 1351320448 17605952 2382400
00:50:12 36886 8192000000 10276826720 10723086336 1360895808 14.245 99 18 36 0 1378713216 13460688 4356720
00:52:02 54087 8192000000 10268351056 10731610112 1352744256 14.172 99 21 42 0 1377499008 19499936 5254816
00:54:36 64500 8192000000 10264636352 10711531520 1324808624 13.920 97 21 42 0 1340092624 10671872 4612128
00:56:40 26821 8192000000 10266127056 10714599424 1338311440 14.042 98 31 62 0 1357028944 11348304 7369200
00:58:09 66067 8192000000 10281151952 10734989312 1342677616 14.082 98 13 26 0 1359230304 13986624 2566064
01:00:15 64984 8192000000 10283342784 10727362560 1330297088 13.970 97 14 28 0 1341328880 8206656 2825136
01:02:31 75087 8192000000 10293004272 10744193024 1381270032 14.428 100 28 56 0 1394148464 6758672 6119760
01:05:02 40086 8192000000 10281042560 10741772288 1390796784 14.513 100 32 64 0 1405721168 7721024 7203360
01:06:45 78935 8192000000 10287480336 10750541824 1391901856 14.523 100 9 18 1 1401936624 7884272 2150496
01:09:11 34875 8192000000 10295702576 10752016384 1392544784 14.529 99 10 20 0 1399813728 5682064 1586880
01:10:54 31369 8192000000 10297155008 10756698112 1401994000 14.613 100 16 30 0 1414066208 8874240 3197968
01:12:26 42402 8192000000 10290816240 10743144448 1370007536 14.327 98 13 26 1 1381514992 8845936 2661520
01:14:13 36562 8192000000 10286025504 10722729984 1299811984 13.694 93 26 53 0 1314953488 8920496 6221008
01:15:54 84039 8192000000 10297822368 10773716992 1380088576 14.417 100 11 23 0 1400559600 18046048 2424976
01:20:12 7794 8192000000 10279172912 10719191040 1352056528 14.166 98 35 70 2 1366260368 5849072 8354768
01:21:23 34732 8192000000 10289449792 10719780864 1337912384 14.039 97 39 78 0 1360954912 14390432 8652096
01:23:01 101396 8192000000 10282101216 10755084288 1313272832 13.816 95 6 12 0 1335826400 21243376 1310192
01:25:47 94564 8192000000 10270104656 10719944704 1338996224 14.048 97 28 56 0 1353829200 8302832 6530144
01:28:27 58470 8192000000 10261725376 10727895040 1359687328 14.235 99 10 20 0 1374208512 11638416 2882768
01:30:35 44111 8192000000 10270005296 10726264832 1343901632 14.093 97 16 32 1 1361497504 13646480 3949392
01:32:22 70768 8192000000 10282623328 10739552256 1363626704 14.270 99 19 39 0 1380536880 12539280 4370896
01:34:37 66112 8192000000 10260972320 10727297024 1360311584 14.240 99 11 20 0 1375011008 12269056 2430368
01:36:44 45401 8192000000 10286919632 10726146048 1359693424 14.235 99 27 54 0 1375545728 9535824 6316480
01:38:34 16771 8192000000 8939078352 9128103936 114711472 1.380 9 2 4 0 116314944 1234256 369216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-03-2023 01:40:15 to 10-03-2023 01:40:30.

Exceptions occured during test was in run

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

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

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          248964
MON$OLDEST_ACTIVE               248965
MON$OLDEST_SNAPSHOT             248965
MON$NEXT_TRANSACTION            248965
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                       2031840
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1257: 
:1258: mscs-test-161.budzdorov.ru    Fri Mar 10 01:38:54 2023
:1259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1260: 
:1261: 
:1262: mscs-test-161.budzdorov.ru    Fri Mar 10 01:38:54 2023
:1263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1264: 
:1265: 
:1266: mscs-test-161.budzdorov.ru    Fri Mar 10 01:38:54 2023
:1267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1268: 
--- end of diff output ---

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

10.03.23 01:40:34. 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-10 01:40:37 No severe PSQL-related problems occured
10.03.23 01:40:38. End of report.