Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2022 19:40
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 15661252 kB
MemAvailable 30533784 kB
Buffers 0 kB
Cached 16088636 kB
SwapCached 2184 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-14 19:38:29
DTS_END                         2022-10-14 19:38:29.5190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43024
TRN_ID                          235976
ATT_ID                          686
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: 8759.46 21795 1051135
customer order: creation 168.63 372 20235
customer order: refuse 13.86 348 1663
order to supplier: creation 44.95 1225 5394
order to supplier: removal 3.31 98205 397
invoice (draft): creation 44.88 3399 5385
invoice (draft): removal 49.87 758 5984
invoice accept: apply 39.81 18410 4777
invoice accept: cancel 45.67 864 5480
customer reserve: creation 3600.60 187 432072
customer reserve: removal 4466.46 57 535975
realization accept: apply 81.08 171 9729
realization accept: cancel 75.60 119 9072
payment from customer: creation 57.66 107 6919
payment from customer: removal 5.42 79 650
payment to supplier: creation 46.36 140 5563
payment to supplier: removal 3.46 84 415
service: total inventory turnovers 4.83 1017 579
service: total monetary turnovers 6.18 26 742
service: aggregate perf. data 0.83 19581 100
service: refresh index statistics 0.03 290754 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2022 19:40:27 to 14-10-2022 19:40:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4892
TEST_TIME 2 3460
TEST_TIME 3 5562
TEST_TIME 4 4676
TEST_TIME 5 4914
TEST_TIME 6 5110
TEST_TIME 7 5186
TEST_TIME 8 5184
TEST_TIME 9 5194
TEST_TIME 10 5649
TEST_TIME 11 5512
TEST_TIME 12 5601
TEST_TIME 13 5726
TEST_TIME 14 6114
TEST_TIME 15 6112
TEST_TIME 16 6379
TEST_TIME 17 7053
TEST_TIME 18 7856
TEST_TIME 19 8294
TEST_TIME 20 8507
TEST_TIME 21 8304
TEST_TIME 22 8721
TEST_TIME 23 9388
TEST_TIME 24 9518
TEST_TIME 25 9556
TEST_TIME 26 9632
TEST_TIME 27 9614
TEST_TIME 28 9802
TEST_TIME 29 10059
TEST_TIME 30 10258
TEST_TIME 31 10188
TEST_TIME 32 9927
TEST_TIME 33 10042
TEST_TIME 34 9907
TEST_TIME 35 9745
TEST_TIME 36 9554
TEST_TIME 37 9406
TEST_TIME 38 9353
TEST_TIME 39 9189
TEST_TIME 40 9047
TEST_TIME 41 9007
TEST_TIME 42 8912
TEST_TIME 43 8794
TEST_TIME 44 8960
TEST_TIME 45 8857
TEST_TIME 46 8799
TEST_TIME 47 8968
TEST_TIME 48 9136
TEST_TIME 49 9059
TEST_TIME 50 9083
TEST_TIME 51 9031
TEST_TIME 52 9039
TEST_TIME 53 9145
TEST_TIME 54 9192
TEST_TIME 55 9244
TEST_TIME 56 9196
TEST_TIME 57 9218
TEST_TIME 58 9247
TEST_TIME 59 9475
TEST_TIME 60 9380
TEST_TIME 61 9254
TEST_TIME 62 9191
TEST_TIME 63 9106
TEST_TIME 64 9113
TEST_TIME 65 9084
TEST_TIME 66 9038
TEST_TIME 67 8964
TEST_TIME 68 8986
TEST_TIME 69 8948
TEST_TIME 70 8857
TEST_TIME 71 8818
TEST_TIME 72 8858
TEST_TIME 73 8791
TEST_TIME 74 8831
TEST_TIME 75 8784
TEST_TIME 76 8843
TEST_TIME 77 8887
TEST_TIME 78 8855
TEST_TIME 79 8970
TEST_TIME 80 8985
TEST_TIME 81 8961
TEST_TIME 82 9054
TEST_TIME 83 9046
TEST_TIME 84 9132
TEST_TIME 85 9125
TEST_TIME 86 9070
TEST_TIME 87 9079
TEST_TIME 88 8993
TEST_TIME 89 9043
TEST_TIME 90 8996
TEST_TIME 91 9000
TEST_TIME 92 8986
TEST_TIME 93 8911
TEST_TIME 94 8976
TEST_TIME 95 8929
TEST_TIME 96 8921
TEST_TIME 97 8956
TEST_TIME 98 9007
TEST_TIME 99 8996
TEST_TIME 100 9005
TEST_TIME 101 9058
TEST_TIME 102 9014
TEST_TIME 103 9099
TEST_TIME 104 9073
TEST_TIME 105 9096
TEST_TIME 106 9127
TEST_TIME 107 9105
TEST_TIME 108 9093
TEST_TIME 109 9100
TEST_TIME 110 9047
TEST_TIME 111 9019
TEST_TIME 112 8988
TEST_TIME 113 8978
TEST_TIME 114 8930
TEST_TIME 115 8908
TEST_TIME 116 8901
TEST_TIME 117 8865
TEST_TIME 118 8839
TEST_TIME 119 8789
TEST_TIME 120 8761

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2022 19:40:30 to 14-10-2022 19:40:34.

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 1454334 1454334 0 0.00 0 15037 6 0 0
doc_list_biud 1454334 1454334 0 0.00 0 36514 27 0 0
sp_add_invoice_to_stock 4901 4777 124 2.53 17 195097 18411 0 124
sp_cancel_adding_invoice 5480 5480 0 0.00 4 65018 864 0 0
sp_cancel_client_order 1663 1663 0 0.00 3 11741 348 0 0
sp_cancel_customer_reserve 535975 535975 0 0.00 0 36517 58 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 2 4877 79 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 2 6805 85 0 0
sp_cancel_supplier_invoice 5995 5984 11 0.18 2 19423 759 0 11
sp_cancel_supplier_order 397 397 0 0.00 5 925791 98205 0 0
sp_cancel_write_off 9072 9072 0 0.00 1 11099 119 0 0
sp_client_order 20235 20235 0 0.00 3 23079 372 0 0
sp_customer_reserve 432072 432072 0 0.00 0 37797 187 0 0
sp_fill_shopping_cart 31011 31011 0 0.00 1 18511 42 0 0
sp_fill_shopping_cart_clo_res 432072 432072 0 0.00 0 18572 16 0 0
sp_get_clo_for_invoice 4776 4776 0 0.00 0 27566 328 0 0
sp_kill_cost_storno 15617 15617 0 0.00 0 15025 57 0 0
sp_kill_qstorno_ret_qs2qd 1087403 1087403 0 0.00 0 36510 31 0 0
sp_kill_qty_storno 573076 573076 0 0.00 0 36514 68 0 0
sp_lock_selected_doc 20000 20000 0 0.00 0 9855 5 0 0
sp_make_cost_storno 26987 26987 0 0.00 0 13944 52 0 0
sp_multiply_rows_for_pdistr 26987 26987 0 0.00 0 10631 28 0 0
sp_multiply_rows_for_qdistr 20235 20235 0 0.00 0 14873 173 0 0
sp_pay_from_customer 6919 6919 0 0.00 4 13416 108 0 0
sp_pay_to_supplier 5563 5563 0 0.00 4 13416 140 0 0
sp_payment_common 12482 12482 0 0.00 1 9414 27 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 11734 321 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 19044 335 0 0
sp_qd_handle_on_reserve_upd_sts 9729 9729 0 0.00 0 9084 63 0 0
sp_reserve_write_off 9729 9729 0 0.00 6 14051 171 0 0
sp_supplier_invoice 5385 5385 0 0.00 6 64082 3400 0 0
sp_supplier_order 5394 5394 0 0.00 5 28075 1226 0 0
srv_aggregate_perf_data 100 100 0 0.00 2830 236269 19581 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1566 132718 41819 0 0
srv_find_qd_qs_mism 1006933 1006933 0 0.00 0 15016 6 0 0
srv_make_invnt_saldo 579 579 0 0.00 36 45986 1018 0 0
srv_make_money_saldo 742 742 0 0.00 1 621 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 94672 416854 290755 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4778 4778 0 0.00 0 22251 191 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 1 4962 96 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 1858 13 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 1733 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 1395 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 170 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 4063 33 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 20028 1533 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 1737 8 0 0
t$perf-norm:sp_client_order 20235 20235 0 0.00 0 11587 18 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 5697 14 0 0
t$perf-norm:sp_pay_from_customer 6919 6919 0 0.00 0 4073 10 0 0
t$perf-norm:sp_pay_to_supplier 5563 5563 0 0.00 0 5299 11 0 0
t$perf-norm:sp_reserve_write_off 9729 9729 0 0.00 0 8473 11 0 0
t$perf-norm:sp_supplier_invoice 5385 5385 0 0.00 0 6368 22 0 0
t$perf-norm:sp_supplier_order 5394 5394 0 0.00 0 6510 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 978 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 34 1 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 640 16 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 1775 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9800 9552 248 2.53 0 5157 17 0 248
v_all_customers 36752 36752 0 0.00 0 20 1 0 0
v_all_suppliers 29354 29354 0 0.00 0 31 1 0 0
v_all_wares 40470 40470 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 5513 20 0 0
v_cancel_client_order 3326 3326 0 0.00 0 3236 7 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 4832 13 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 947 5 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3338 9 0 0
v_cancel_supplier_invoice 732 710 22 3.00 0 4392 21 0 22
v_cancel_supplier_order 794 794 0 0.00 0 1836 6 0 0
v_cancel_write_off 1904 1904 0 0.00 0 3379 19 0 0
v_max_id_clo_ord 5394 5394 0 0.00 0 5307 2 0 0
v_max_id_clo_res 979 979 0 0.00 0 2211 9 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 6000 8 0 0
v_max_non_paid_invoice 1662 1662 0 0.00 0 2629 6 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 7290 7 0 0
v_min_id_clo_ord 5394 5394 0 0.00 0 3055 7 0 0
v_min_id_clo_res 979 979 0 0.00 0 1947 6 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 12578 8 0 0
v_min_non_paid_invoice 5563 5563 0 0.00 0 9322 20 0 0
v_min_non_paid_realizn 6919 6919 0 0.00 0 7679 19 0 0
v_our_firm 10162 10162 0 0.00 0 21 1 0 0
v_reserve_write_off 19458 19458 0 0.00 0 4698 6 0 0
x_lock_depdocs_on_canc_invoice 11109 11109 0 0.00 0 13583 121 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3400 325 0 0
x_make_qstorno_1000_1200 5394 5394 0 0.00 1 28065 1147 0 0
x_make_qstorno_1200_2000 5385 5385 0 0.00 3 63195 3245 0 0
x_make_qstorno_2100_3300 432072 432072 0 0.00 0 37795 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2022 19:40:35 to 14-10-2022 19:40:39.

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:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:34 18544 8192000000 9887120416 10194837504 976498384 10.650 100 81 162 0 1003539968 5442576 21599008
17:11:00 14772 8192000000 9936269648 10255740928 1014349712 11.017 100 90 180 0 1045193024 7489264 23354048
17:12:20 15000 8192000000 9967794608 10296107008 1051462768 11.375 100 84 168 0 1081616960 8642800 21511392
17:13:40 12237 8192000000 10015039840 10357489664 1099845584 11.836 100 77 152 1 1127287296 9517200 17924512
17:14:54 8407 8192000000 10072813440 10431586304 1163236096 12.434 100 80 158 0 1192883328 11481520 18165712
17:16:05 3990 8192000000 10111224160 10479857664 1200921408 12.785 100 80 159 0 1231013440 12559168 17532864
17:17:11 3245 8192000000 10148469808 10523967488 1238255696 13.130 100 81 161 1 1269400000 13220928 17923376
17:18:17 2397 8192000000 10188247200 10568949760 1271008832 13.431 100 92 184 0 1306973344 16355792 19608720
17:19:21 1281 8192000000 10213643248 10598318080 1295800592 13.657 100 95 186 1 1329854064 13817312 20236160
17:20:23 22202 8192000000 10215625088 10616897536 1314429168 13.826 100 65 131 0 1342491584 13721552 14340864
17:21:47 4948 8192000000 10215589344 10623262720 1321336624 13.889 100 47 92 0 1339563008 6323728 11902656
17:22:52 4463 8192000000 10216570848 10633240576 1325525840 13.927 100 33 65 0 1339994576 7816928 6651808
17:23:58 3363 8192000000 10216597568 10636341248 1329854384 13.966 100 38 76 2 1345772480 7540928 8377168
17:25:03 11756 8192000000 10221526896 10639327232 1333786128 14.001 100 14 28 0 1342330880 5512144 3032608
17:26:15 60550 8192000000 10237149632 10652938240 1339373168 14.052 100 35 70 0 1353507264 5677360 8456736
17:28:17 3405 8192000000 10224341760 10639716352 1313377648 13.817 98 41 82 2 1330442880 7990416 9074816
17:29:22 7973 8192000000 10200433440 10615160832 1298140688 13.678 99 24 48 0 1309821136 5857488 5822960
17:30:34 11062 8192000000 10163260720 10565087232 1201725600 12.792 93 25 50 0 1213088672 5108080 6254992
17:31:49 10024 8192000000 10145711744 10551988224 1229155360 13.046 97 21 41 0 1239976272 6622960 4197952
17:33:03 6060 8192000000 10146410272 10545795072 1250885680 13.246 100 28 55 0 1263269664 6505408 5878576
17:34:09 6270 8192000000 10164545008 10565623808 1266700176 13.391 100 22 44 0 1278411200 6367728 5343296
17:35:18 49904 8192000000 10173620480 10584530944 1274837632 13.466 99 27 54 0 1286617728 4710816 7069280
17:37:09 7898 8192000000 10183644000 10597449728 1296889232 13.667 100 22 44 0 1307607568 5637712 5080624
17:38:18 7149 8192000000 10205060032 10623766528 1317790128 13.857 100 19 36 1 1327144128 5094880 4259120
17:39:29 4601 8192000000 10249737184 10655277056 1338905184 14.048 100 50 99 0 1358815536 9064752 10845600
17:40:36 49431 8192000000 10251068224 10676285440 1340893504 14.065 99 39 78 1 1362500960 12423280 9184176
17:42:28 19540 8192000000 10266528464 10687877120 1362734960 14.262 100 33 64 0 1380887792 10666096 7486736
17:43:55 16601 8192000000 10288042192 10709413888 1375872032 14.380 100 35 69 1 1401429280 17116288 8440960
17:45:17 3982 8192000000 10302114752 10716745728 1384374624 14.456 100 57 111 1 1415600544 18394912 12831008
17:46:34 25984 8192000000 10319633600 10744905728 1392881024 14.532 100 37 73 0 1427397024 25163792 9352208
17:48:08 4245 8192000000 10330531584 10749747200 1396578656 14.565 100 58 112 1 1438593264 28398080 13616528
17:49:15 9767 8192000000 10318979488 10743078912 1393111520 14.534 100 45 88 0 1428952752 24581600 11259632
17:50:35 4607 8192000000 10345777840 10760077312 1401643776 14.610 100 63 121 1 1448198960 32356336 14198848
17:51:42 1561 8192000000 10357038304 10770644992 1409461200 14.679 100 83 162 1 1466520304 37635888 19423216
17:52:50 3344 8192000000 10336015904 10752290816 1394318224 14.544 100 63 124 0 1446837968 37181216 15338528
17:54:01 4757 8192000000 10354357264 10765975552 1393921296 14.541 100 73 146 1 1461177008 49195696 18060016
17:55:11 6158 8192000000 10320175120 10730172416 1346741888 14.118 99 75 149 1 1417772976 51940768 19090320
17:56:22 3163 8192000000 10304753104 10714120192 1351140688 14.158 100 64 126 0 1410167008 42768128 16258192
17:57:28 9385 8192000000 10299111744 10730987520 1352528160 14.170 99 31 62 1 1399388736 38893264 7967312
17:58:44 8889 8192000000 10278971168 10696175616 1319467776 13.872 97 41 82 1 1362970640 32812848 10690016
18:00:16 4129 8192000000 10310630432 10723213312 1355659424 14.198 100 53 103 0 1411515952 42750240 13106288
18:01:22 25000 8192000000 10278761392 10711724032 1325661360 13.928 98 27 50 0 1366651696 34158432 6831904
18:02:52 13553 8192000000 10291766528 10715099136 1346867184 14.119 99 29 58 1 1380457216 25669472 7920560
18:04:26 40336 8192000000 10291111600 10720542720 1332085712 13.986 97 23 46 1 1366553568 28333424 6134432
18:06:10 42258 8192000000 10280144624 10718760960 1319005904 13.868 97 16 32 0 1363875056 40088624 4780528
18:07:54 43902 8192000000 10289255360 10721775616 1364400432 14.277 100 33 63 0 1384915776 13316016 7199328
18:09:39 32384 8192000000 10291763712 10727833600 1370159888 14.328 99 44 84 1 1391510320 12297280 9053152
18:11:13 12761 8192000000 10293276400 10743865344 1387470736 14.483 100 30 60 0 1403476080 9361408 6643936
18:12:26 42090 8192000000 10270802976 10719055872 1362825472 14.263 99 6 11 0 1370754080 7224576 704032
18:14:09 14455 8192000000 10291267088 10736517120 1384821600 14.460 100 9 18 0 1394385072 7561712 2001760
18:15:24 52461 8192000000 10295445488 10748227584 1394060352 14.542 100 20 40 0 1405502160 6938080 4503728
18:17:31 58854 8192000000 10309432016 10753908736 1385323568 14.464 99 33 66 1 1407182832 14181888 7677376
18:20:21 27990 8192000000 10306367744 10758860800 1396503168 14.564 100 29 53 1 1417929184 14959776 6466240
18:21:57 19067 8192000000 10319926032 10775625728 1404928480 14.639 100 33 66 0 1438639008 24830896 8879632
18:23:23 80694 8192000000 10337927392 10797543424 1424729232 14.815 100 30 59 2 1451090704 18936240 7425232
18:27:30 31836 8192000000 10305282080 10758795264 1385182256 14.463 99 40 78 1 1408816240 14245440 9388544
18:29:13 93053 8192000000 10321875984 10765426688 1380289488 14.419 98 16 32 1 1402936032 18567632 4078912
18:31:57 132716 8192000000 10300706784 10752749568 1358450080 14.223 97 11 22 0 1373895184 12796896 2648208
18:35:10 21381 8192000000 10305676576 10751717376 1390529136 14.511 100 48 97 2 1411023808 9547040 10947632
18:36:33 108087 8192000000 10289497936 10756890624 1316653872 13.846 94 11 22 0 1342383456 23046288 2683296
18:39:21 83552 8192000000 10295336048 10740826112 1364214480 14.275 98 41 82 0 1381172864 7782720 9175664
18:41:51 10964 8192000000 10310016864 10754867200 1400326384 14.598 100 38 74 0 1416059696 7596944 8136368
18:43:02 34300 8192000000 10291274464 10753576960 1369690496 14.324 98 16 32 0 1385635376 12495824 3449056
18:44:40 59737 8192000000 10263905712 10721017856 1346370352 14.115 97 10 20 0 1357335440 8220128 2744960
18:46:40 40540 8192000000 10245192736 10699268096 1337520800 14.035 98 5 10 0 1345111280 6295088 1295392
18:48:21 91821 8192000000 10261941936 10714685440 1319920912 13.876 96 26 50 0 1336023680 10519728 5583040
18:50:58 83641 8192000000 10229869712 10687959040 1292880736 13.630 96 20 38 0 1310026896 12365488 4780672
18:53:25 39832 8192000000 10243625152 10685247488 1303355264 13.726 97 18 36 0 1326516944 19419280 3742400
18:55:05 37102 8192000000 10235805776 10684039168 1335255984 14.015 100 21 42 0 1353157456 12679984 5221488
18:56:47 52534 8192000000 10276963008 10730053632 1363821280 14.272 100 29 58 0 1386534256 16050112 6662864
18:58:57 21741 8192000000 10278899264 10713739264 1338809376 14.047 98 43 86 0 1360575856 12136816 9629664
19:00:19 84723 8192000000 10278479792 10730790912 1321173248 13.887 96 15 30 0 1337582624 13280784 3128592
19:02:44 88006 8192000000 10283448064 10732388352 1364899424 14.281 99 10 20 1 1379776656 12642384 2234848
19:05:14 48974 8192000000 10306732896 10761797632 1390726992 14.512 100 34 68 0 1421715072 23227088 7760992
19:07:07 42641 8192000000 10289500304 10734379008 1365689760 14.288 98 37 74 0 1387472432 13537888 8244784
19:08:52 62779 8192000000 10291673312 10753544192 1380969856 14.425 99 26 52 0 1399637200 12631184 6036160
19:10:55 33928 8192000000 10293906464 10764992512 1397657008 14.574 100 8 16 0 1412914656 13512848 1744800
19:12:30 73637 8192000000 10285082864 10740162560 1368071232 14.310 98 13 26 0 1385386368 14360560 2954576
19:14:43 60252 8192000000 10289500288 10734194688 1355866096 14.200 97 19 38 0 1373715376 12908784 4940496
19:16:46 55821 8192000000 10300421728 10754768896 1369557808 14.323 98 21 42 1 1388974624 15102304 4314512
19:18:43 83745 8192000000 10311946704 10769731584 1374453632 14.367 98 13 26 0 1389507584 12012640 3041312
19:21:11 41295 8192000000 10304039232 10769182720 1387248448 14.481 99 17 34 0 1403510608 12471856 3790304
19:22:56 84543 8192000000 10302707488 10751315968 1347372960 14.124 97 27 54 0 1364606976 11484272 5749744
19:25:21 27522 8192000000 10304248992 10751311872 1378891840 14.407 99 26 52 0 1393724640 9120864 5711936
19:26:53 34476 8192000000 10314720208 10756345856 1356542640 14.206 97 41 82 0 1379363632 13813248 9007744
19:28:33 98856 8192000000 10294811904 10740895744 1362379248 14.259 98 28 56 0 1378721152 10295968 6045936
19:31:15 41311 8192000000 10266742368 10732060672 1366983744 14.300 99 4 8 0 1378723744 11287200 452800
19:33:02 86025 8192000000 10267809344 10722779136 1345789936 14.110 98 12 25 0 1356225040 7733408 2701696
19:36:11 44915 8192000000 10256945392 10716917760 1363772288 14.271 100 13 26 0 1374876800 8317392 2787120
19:38:00 37710 8192000000 9220748608 9500020736 388458160 4.527 29 6 12 0 395588368 5685504 1444704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2022 19:40:41 to 14-10-2022 19:40:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2022 19:40:57 to 14-10-2022 19:40:59.

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          236254
MON$OLDEST_ACTIVE               236255
MON$OLDEST_SNAPSHOT             236255
MON$NEXT_TRANSACTION            236255
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                       2013952
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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    Fri Oct 14 19:38:46 2022
:349:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:350: 
:351: 
:352: mscs-test-161.budzdorov.ru    Fri Oct 14 19:38:49 2022
: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'

14.10.22 19:41:01. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-14 19:41:03 No severe PSQL-related problems occured
14.10.22 19:41:04. End of report.