Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.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 15263520 kB
MemAvailable 30407568 kB
Buffers 0 kB
Cached 16441624 kB
SwapCached 3028 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-07 19:38:43
DTS_END                         2023-03-07 19:38:43.2440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54118
TRN_ID                          244906
ATT_ID                          683
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: 9465.23 21865 1135828
customer order: creation 179.82 354 21578
customer order: refuse 15.39 321 1847
order to supplier: creation 47.90 1203 5748
order to supplier: removal 3.38 93635 405
invoice (draft): creation 47.97 3167 5756
invoice (draft): removal 51.56 757 6187
invoice accept: apply 43.69 16572 5243
invoice accept: cancel 47.32 787 5678
customer reserve: creation 4050.67 164 486080
customer reserve: removal 4667.66 53 560119
realization accept: apply 87.59 163 10511
realization accept: cancel 88.98 108 10677
payment from customer: creation 60.24 107 7229
payment from customer: removal 5.53 56 664
payment to supplier: creation 51.31 123 6157
payment to supplier: removal 4.03 92 484
service: total inventory turnovers 4.88 831 585
service: total monetary turnovers 6.52 23 782
service: aggregate perf. data 0.78 21112 94
service: refresh index statistics 0.03 297679 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-03-2023 19:40:09 to 07-03-2023 19:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3480
TEST_TIME 2 3493
TEST_TIME 3 4655
TEST_TIME 4 5818
TEST_TIME 5 5978
TEST_TIME 6 5418
TEST_TIME 7 5647
TEST_TIME 8 5782
TEST_TIME 9 6440
TEST_TIME 10 6406
TEST_TIME 11 7058
TEST_TIME 12 6856
TEST_TIME 13 6933
TEST_TIME 14 6998
TEST_TIME 15 7448
TEST_TIME 16 7617
TEST_TIME 17 7715
TEST_TIME 18 7894
TEST_TIME 19 8374
TEST_TIME 20 8798
TEST_TIME 21 8819
TEST_TIME 22 8989
TEST_TIME 23 9150
TEST_TIME 24 9113
TEST_TIME 25 9075
TEST_TIME 26 9299
TEST_TIME 27 10223
TEST_TIME 28 10865
TEST_TIME 29 10938
TEST_TIME 30 10706
TEST_TIME 31 10651
TEST_TIME 32 10481
TEST_TIME 33 10491
TEST_TIME 34 10652
TEST_TIME 35 10456
TEST_TIME 36 10340
TEST_TIME 37 10236
TEST_TIME 38 10270
TEST_TIME 39 10227
TEST_TIME 40 10177
TEST_TIME 41 10092
TEST_TIME 42 9990
TEST_TIME 43 9876
TEST_TIME 44 9808
TEST_TIME 45 9692
TEST_TIME 46 9650
TEST_TIME 47 9973
TEST_TIME 48 10017
TEST_TIME 49 9945
TEST_TIME 50 9902
TEST_TIME 51 10008
TEST_TIME 52 10015
TEST_TIME 53 9942
TEST_TIME 54 9978
TEST_TIME 55 9992
TEST_TIME 56 10032
TEST_TIME 57 9947
TEST_TIME 58 10076
TEST_TIME 59 10144
TEST_TIME 60 10099
TEST_TIME 61 10038
TEST_TIME 62 9971
TEST_TIME 63 10003
TEST_TIME 64 9917
TEST_TIME 65 9854
TEST_TIME 66 9796
TEST_TIME 67 9801
TEST_TIME 68 9739
TEST_TIME 69 9723
TEST_TIME 70 9661
TEST_TIME 71 9614
TEST_TIME 72 9610
TEST_TIME 73 9520
TEST_TIME 74 9580
TEST_TIME 75 9567
TEST_TIME 76 9528
TEST_TIME 77 9570
TEST_TIME 78 9630
TEST_TIME 79 9617
TEST_TIME 80 9616
TEST_TIME 81 9678
TEST_TIME 82 9758
TEST_TIME 83 9742
TEST_TIME 84 9711
TEST_TIME 85 9829
TEST_TIME 86 9833
TEST_TIME 87 9781
TEST_TIME 88 9708
TEST_TIME 89 9723
TEST_TIME 90 9682
TEST_TIME 91 9640
TEST_TIME 92 9586
TEST_TIME 93 9548
TEST_TIME 94 9537
TEST_TIME 95 9617
TEST_TIME 96 9581
TEST_TIME 97 9573
TEST_TIME 98 9584
TEST_TIME 99 9647
TEST_TIME 100 9605
TEST_TIME 101 9685
TEST_TIME 102 9714
TEST_TIME 103 9669
TEST_TIME 104 9720
TEST_TIME 105 9681
TEST_TIME 106 9739
TEST_TIME 107 9742
TEST_TIME 108 9717
TEST_TIME 109 9662
TEST_TIME 110 9635
TEST_TIME 111 9599
TEST_TIME 112 9605
TEST_TIME 113 9557
TEST_TIME 114 9536
TEST_TIME 115 9513
TEST_TIME 116 9473
TEST_TIME 117 9442
TEST_TIME 118 9499
TEST_TIME 119 9502
TEST_TIME 120 9479

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-03-2023 19:40:13 to 07-03-2023 19:40:16.

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 1584560 1584560 0 0.00 0 17305 6 0 0
doc_list_biud 1584559 1584559 0 0.00 0 54719 24 0 0
sp_add_invoice_to_stock 5381 5243 138 2.56 36 194559 16572 0 138
sp_cancel_adding_invoice 5678 5678 0 0.00 4 60487 787 0 0
sp_cancel_client_order 1847 1847 0 0.00 4 13162 321 0 0
sp_cancel_customer_reserve 560119 560119 0 0.00 0 54721 53 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 3002 57 0 0
sp_cancel_pay_to_supplier 484 484 0 0.00 2 7440 92 0 0
sp_cancel_supplier_invoice 6197 6187 10 0.16 1 14497 758 0 10
sp_cancel_supplier_order 405 405 0 0.00 7 975372 93636 0 0
sp_cancel_write_off 10677 10677 0 0.00 1 23352 108 0 0
sp_client_order 21578 21578 0 0.00 3 41669 354 0 0
sp_customer_reserve 486080 486080 0 0.00 0 54118 164 0 0
sp_fill_shopping_cart 33078 33078 0 0.00 1 12688 34 0 0
sp_fill_shopping_cart_clo_res 486071 486071 0 0.00 0 13492 12 0 0
sp_get_clo_for_invoice 5229 5229 0 0.00 0 24651 278 0 0
sp_kill_cost_storno 17503 17503 0 0.00 0 6594 48 0 0
sp_kill_qstorno_ret_qs2qd 1137507 1137507 0 0.00 0 54714 29 0 0
sp_kill_qty_storno 600653 600653 0 0.00 0 54719 63 0 0
sp_lock_selected_doc 21800 21800 0 0.00 0 7922 5 0 0
sp_make_cost_storno 29126 29126 0 0.00 0 14242 46 0 0
sp_multiply_rows_for_pdistr 29126 29126 0 0.00 0 7525 24 0 0
sp_multiply_rows_for_qdistr 21577 21577 0 0.00 0 41653 173 0 0
sp_pay_from_customer 7229 7229 0 0.00 3 21121 107 0 0
sp_pay_to_supplier 6157 6157 0 0.00 4 21099 123 0 0
sp_payment_common 13386 13386 0 0.00 2 9566 25 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 0 13153 293 0 0
sp_qd_handle_on_invoice_upd_sts 10907 10907 0 0.00 0 30504 307 0 0
sp_qd_handle_on_reserve_upd_sts 10511 10511 0 0.00 0 10653 47 0 0
sp_reserve_write_off 10511 10511 0 0.00 6 17348 164 0 0
sp_supplier_invoice 5756 5756 0 0.00 6 103523 3168 0 0
sp_supplier_order 5748 5748 0 0.00 5 41250 1203 0 0
srv_aggregate_perf_data 94 94 0 0.00 2378 346504 21113 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 923 148791 45664 0 0
srv_find_qd_qs_mism 1085344 1085344 0 0.00 0 16690 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 44 21096 832 0 0
srv_make_money_saldo 782 782 0 0.00 1 1296 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 139253 421837 297679 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5244 5244 0 0.00 0 11718 160 0 0
t$perf-norm:sp_cancel_adding_invoice 466 466 0 0.00 0 3447 98 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 1024 9 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 8418 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1137 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 484 484 0 0.00 0 7325 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 5421 19 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 20018 1454 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 4178 8 0 0
t$perf-norm:sp_client_order 21578 21578 0 0.00 0 9150 15 0 0
t$perf-norm:sp_customer_reserve 1128 1128 0 0.00 0 4037 8 0 0
t$perf-norm:sp_pay_from_customer 7229 7229 0 0.00 0 5530 9 0 0
t$perf-norm:sp_pay_to_supplier 6157 6157 0 0.00 0 4353 10 0 0
t$perf-norm:sp_reserve_write_off 10511 10511 0 0.00 0 8501 10 0 0
t$perf-norm:sp_supplier_invoice 5756 5756 0 0.00 0 8876 22 0 0
t$perf-norm:sp_supplier_order 5748 5748 0 0.00 0 2291 13 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 32 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 659 13 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 601 15 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 5142 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10734 10458 276 2.57 0 9943 17 0 276
v_all_customers 38752 38752 0 0.00 0 20 1 0 0
v_all_suppliers 31926 31926 0 0.00 0 24 1 0 0
v_all_wares 43154 43154 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 932 932 0 0.00 0 21695 29 0 0
v_cancel_client_order 3694 3694 0 0.00 0 4027 6 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2655 6 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1440 6 0 0
v_cancel_payment_to_supplier 968 968 0 0.00 0 1750 8 0 0
v_cancel_supplier_invoice 858 838 20 2.33 0 1142 16 0 20
v_cancel_supplier_order 810 810 0 0.00 0 236 3 0 0
v_cancel_write_off 2126 2126 0 0.00 0 21099 25 0 0
v_max_id_clo_ord 5748 5748 0 0.00 0 231 1 0 0
v_max_id_clo_res 1128 1128 0 0.00 0 2668 15 0 0
v_max_id_ord_sup 5753 5753 0 0.00 0 4899 4 0 0
v_max_non_paid_invoice 1695 1695 0 0.00 0 1968 4 0 0
v_max_non_paid_realizn 3844 3844 0 0.00 0 1614 4 0 0
v_min_id_clo_ord 5748 5748 0 0.00 0 4232 5 0 0
v_min_id_clo_res 1128 1128 0 0.00 0 3020 13 0 0
v_min_id_ord_sup 5753 5753 0 0.00 0 11232 13 0 0
v_min_non_paid_invoice 6157 6157 0 0.00 0 5008 18 0 0
v_min_non_paid_realizn 7229 7229 0 0.00 0 9563 17 0 0
v_our_firm 11172 11172 0 0.00 0 24 1 0 0
v_reserve_write_off 21022 21022 0 0.00 0 13373 10 0 0
x_lock_depdocs_on_canc_invoice 11446 11446 0 0.00 0 14964 116 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 6635 335 0 0
x_make_qstorno_1000_1200 5748 5748 0 0.00 1 41244 1138 0 0
x_make_qstorno_1200_2000 5756 5756 0 0.00 2 100619 3046 0 0
x_make_qstorno_2100_3300 486078 486078 0 0.00 0 54116 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-03-2023 19:40:17 to 07-03-2023 19:40:21.

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:47 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:48 30728 8192000000 9906484048 10218577920 985881312 10.741 100 87 174 0 1015543216 6545184 23116720
17:11:24 14879 8192000000 9939963328 10260922368 1022419904 11.095 100 89 178 0 1054482288 8904048 23158336
17:12:52 13612 8192000000 9976583920 10312564736 1063798528 11.493 100 82 164 0 1093498528 9775024 19924976
17:14:08 6825 8192000000 10022451024 10366754816 1105872400 11.893 100 74 144 2 1134000816 11196800 16931616
17:15:23 5884 8192000000 10067822256 10425434112 1152922528 12.337 100 77 154 0 1182776512 12434528 17419456
17:16:34 4760 8192000000 10113024592 10479067136 1199955744 12.776 100 81 161 1 1230975408 12921616 18098048
17:17:41 1926 8192000000 10147147696 10519523328 1236189856 13.111 100 83 164 0 1267637008 12893264 18553888
17:18:44 2108 8192000000 10187154560 10570559488 1273883920 13.457 100 80 159 1 1305013280 13595520 17533840
17:19:48 1680 8192000000 10208445024 10594263040 1293329680 13.635 100 85 166 3 1325062624 13233520 18499424
17:20:52 1253 8192000000 10211721936 10603429888 1301907040 13.713 100 68 135 0 1329417888 12715616 14795232
17:21:56 4714 8192000000 10203609376 10613321728 1315025328 13.832 100 24 48 0 1327569424 7448176 5095920
17:23:02 13721 8192000000 10211749904 10629681152 1326707376 13.937 100 18 36 0 1337207440 6658288 3841776
17:24:18 45197 8192000000 10239049840 10643701760 1330522608 13.972 100 55 111 0 1349102960 5297952 13282400
17:26:03 6318 8192000000 10223965088 10645975040 1336395520 14.025 100 32 64 0 1349428816 5757264 7276032
17:27:13 5343 8192000000 10226240784 10644307968 1334230704 14.005 100 26 50 1 1350387232 10671344 5485184
17:28:20 16523 8192000000 10218841552 10635493376 1307809776 13.766 98 20 40 0 1317996288 5330832 4855680
17:29:40 11725 8192000000 10204703456 10616606720 1281586272 13.527 97 22 44 1 1292055888 5886896 4582720
17:30:52 5916 8192000000 10164278720 10568859648 1255036256 13.284 98 21 40 2 1265138704 5010768 5091680
17:32:01 66345 8192000000 10136745152 10537762816 1169823760 12.495 92 25 49 0 1180710416 4870384 6016272
17:34:08 5243 8192000000 10143666544 10541559808 1244109808 13.184 99 34 68 0 1257861568 5989360 7762400
17:35:14 8520 8192000000 10169928816 10578292736 1279132864 13.505 100 20 38 0 1289248496 5863424 4252208
17:36:28 13859 8192000000 10208646672 10606608384 1301680464 13.711 100 32 60 1 1314975488 4822432 8472592
17:37:42 27974 8192000000 10212411440 10622459904 1314588544 13.828 100 25 46 1 1325055888 5634672 4832672
17:39:15 13461 8192000000 10233396880 10647388160 1335195472 14.014 100 35 69 0 1349275792 6207824 7872496
17:40:33 31379 8192000000 10264124624 10680168448 1358011296 14.219 100 51 100 0 1378948544 9538928 11398320
17:42:06 31171 8192000000 10276020800 10700615680 1370659568 14.333 100 45 84 1 1392218080 11346576 10211936
17:43:47 14509 8192000000 10284467072 10716684288 1384941072 14.461 100 38 75 0 1405425184 11437136 9046976
17:45:09 11064 8192000000 10308214736 10734583808 1394945200 14.550 100 52 101 1 1422059456 14977424 12136832
17:46:27 2652 8192000000 10338253984 10753859584 1406305184 14.651 100 78 156 0 1447546240 23675904 17565152
17:47:34 19411 8192000000 10335105184 10764914688 1409841392 14.683 100 50 100 0 1446492704 24385280 12266032
17:48:59 5751 8192000000 10346812784 10775674880 1414691104 14.726 100 58 113 0 1453719040 25969088 13058848
17:50:08 8095 8192000000 10343168880 10772660224 1410141712 14.685 100 51 102 0 1455013472 32669008 12202752
17:51:23 15454 8192000000 10361110384 10793492480 1423123040 14.800 100 46 92 1 1465408880 31245360 11040480
17:52:43 3941 8192000000 10363488208 10787471360 1416912704 14.745 100 75 147 0 1473861024 39482784 17465536
17:53:54 918 8192000000 10383845824 10806755328 1417050432 14.747 99 78 156 0 1475045424 39240464 18754528
17:54:57 2560 8192000000 10359465952 10786111488 1411023632 14.693 100 57 111 1 1467280592 41702592 14554368
17:56:04 3158 8192000000 10360359168 10777882624 1398886112 14.585 100 66 130 0 1466345936 50883920 16575904
17:57:19 3032 8192000000 10350281392 10761502720 1379087264 14.408 100 76 150 0 1453804368 55634000 19083104
17:58:26 18261 8192000000 10305628048 10739003392 1351913328 14.165 98 33 66 0 1409263728 48257040 9093360
17:59:55 4763 8192000000 10329208048 10737901568 1369755152 14.325 100 74 147 0 1430987248 42651088 18581008
18:01:04 36126 8192000000 10334334432 10763866112 1375817488 14.379 99 27 54 1 1426630544 43266624 7546432
18:02:45 12268 8192000000 10331285936 10766225408 1380166816 14.418 99 28 56 0 1431275808 42836192 8272800
18:04:06 34887 8192000000 10310412688 10743828480 1349282032 14.141 99 27 54 0 1408499424 51239568 7977824
18:06:06 83304 8192000000 10267114192 10716807168 1343914192 14.093 98 16 32 0 1379776096 31786400 4075504
18:08:30 93018 8192000000 10246920416 10699288576 1296506384 13.663 95 33 64 0 1318416096 14926208 6983504
18:11:06 49003 8192000000 10277632816 10716749824 1349952704 14.147 99 26 50 2 1367885136 12683920 5248512
18:12:55 79610 8192000000 10284686128 10722537472 1350457392 14.152 98 28 55 0 1367082256 10493680 6131184
18:15:23 27131 8192000000 10287960800 10729607168 1371948384 14.344 99 14 28 0 1383773232 8388576 3436272
18:16:54 37014 8192000000 10292911968 10735431680 1388129936 14.489 100 42 84 0 1407985568 10232864 9622768
18:18:33 64765 8192000000 10307194336 10756067328 1398579312 14.582 100 27 53 0 1414278288 8744576 6954400
18:20:42 56266 8192000000 10323469520 10775965696 1398778832 14.584 99 21 40 0 1421887008 17787280 5320896
18:23:19 87704 8192000000 10331107728 10792800256 1412949280 14.710 100 16 32 0 1445144800 27904160 4291360
18:25:48 45868 8192000000 10306544288 10768769024 1387300720 14.482 99 20 40 0 1405438496 13807056 4330720
18:27:34 50615 8192000000 10326002416 10772594688 1401423504 14.608 100 20 40 1 1414289728 8832464 4033760
18:29:26 50204 8192000000 10318578816 10788081664 1411196256 14.695 100 21 42 0 1434404320 18078320 5129744
18:31:32 27295 8192000000 10309298544 10755276800 1382724256 14.441 99 29 58 0 1400824560 11495120 6605184
18:33:05 59608 8192000000 10298418576 10750496768 1369873584 14.326 98 16 32 0 1386434224 13055904 3504736
18:35:04 48419 8192000000 10298044784 10746896384 1361753408 14.253 98 16 32 0 1385800544 20477008 3570128
18:37:04 97778 8192000000 10293025456 10741121024 1350952864 14.156 98 10 20 0 1368978000 15610848 2414288
18:39:45 93898 8192000000 10297636288 10743951360 1371722368 14.342 99 23 46 0 1389600512 12298544 5579600
18:42:27 27753 8192000000 10285437856 10751594496 1374250128 14.365 99 10 18 0 1391409200 15818736 1340336
18:43:59 69219 8192000000 10260166032 10714284032 1345426144 14.106 98 12 25 0 1357292080 9191472 2674464
18:46:09 46580 8192000000 10252965168 10706997248 1314008944 13.822 96 14 26 1 1327121024 9849520 3262560
18:48:07 80637 8192000000 10250172144 10690863104 1293547600 13.637 95 20 40 0 1308042976 10184944 4310432
18:50:29 54362 8192000000 10257758160 10717884416 1353084448 14.175 99 10 20 0 1367015952 11690992 2240512
18:52:29 73413 8192000000 10266225808 10724716544 1332158208 13.987 97 15 31 0 1354342176 18666384 3517584
18:54:46 39368 8192000000 10268945792 10713333760 1318625776 13.864 96 32 63 0 1340409952 14884368 6899808
18:56:26 40948 8192000000 10269242128 10716504064 1322938864 13.903 97 15 30 0 1341859648 15699504 3221280
18:58:21 57425 8192000000 10277628592 10729984000 1332121488 13.986 98 23 46 0 1351031184 13725488 5184208
19:00:29 65545 8192000000 10250832016 10691743744 1280298720 13.516 94 13 26 0 1293543920 10539872 2705328
19:02:40 17709 8192000000 10275718304 10709192704 1343130976 14.086 99 32 64 0 1366713600 16712096 6870528
19:04:05 46086 8192000000 10265822064 10714185728 1340988608 14.066 99 16 32 0 1356686224 12251152 3446464
19:05:54 148791 8192000000 10271800928 10729091072 1344083504 14.094 98 21 42 1 1360242112 11488640 4669968
19:09:26 38509 8192000000 10251171104 10707759104 1355410256 14.196 99 11 22 0 1362441888 4941008 2090624
19:11:05 90958 8192000000 10287552496 10748944384 1335111680 14.013 96 9 18 0 1347086848 10025408 1949760
19:13:59 84687 8192000000 10288919072 10738917376 1346979584 14.120 97 23 46 0 1361635936 9502256 5154096
19:16:37 70679 8192000000 10289448000 10758021120 1377529328 14.394 99 22 44 1 1394567280 11748880 5289072
19:19:16 44429 8192000000 10292602688 10760847360 1372835648 14.352 99 18 36 1 1399889856 22784480 4269728
19:21:04 71813 8192000000 10301146560 10764165120 1381737680 14.432 99 21 43 0 1405897024 19399856 4759488
19:23:17 25546 8192000000 10296049280 10739920896 1358471296 14.224 98 28 56 0 1374746528 9982864 6292368
19:24:49 25014 8192000000 10283994368 10708889600 1334655840 14.009 97 44 88 1 1351522560 7016272 9850448
19:26:16 66235 8192000000 10278587376 10738946048 1374952448 14.371 100 7 14 0 1393164848 16581760 1630640
19:28:28 62589 8192000000 10285400448 10735333376 1345325776 14.105 97 26 51 1 1359367088 8486944 5554368
19:30:31 37489 8192000000 10260904576 10729414656 1352060912 14.166 98 7 14 0 1362098512 8463024 1574576
19:32:42 75867 8192000000 10255992544 10702794752 1314979712 13.831 95 14 28 0 1325134864 7082336 3072816
19:35:11 65468 8192000000 10265666336 10730987520 1360884912 14.245 99 17 33 0 1376434384 11499408 4050064
19:37:24 70858 8192000000 10259337920 10707578880 1308959504 13.777 95 14 28 1 1328592064 16428704 3203856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-03-2023 19:40:23 to 07-03-2023 19:40:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          242774
MON$OLDEST_ACTIVE               245169
MON$OLDEST_SNAPSHOT             245169
MON$NEXT_TRANSACTION            245169
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                       2027872
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 ---
:1131: 
:1132: mscs-test-161.budzdorov.ru    Tue Mar  7 19:38:50 2023
:1133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1134: 
:1135: 
:1136: mscs-test-161.budzdorov.ru    Tue Mar  7 19:38:51 2023
:1137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1138: 
:1139: 
:1140: mscs-test-161.budzdorov.ru    Tue Mar  7 19:38:51 2023
:1141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1142: 
--- end of diff output ---

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

07.03.23 19:40:42. 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-07 19:40:45 No severe PSQL-related problems occured
07.03.23 19:40:46. End of report.