Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.02.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 15329080 kB
MemAvailable 30397508 kB
Buffers 0 kB
Cached 16357784 kB
SwapCached 3356 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-02-12 01:38:49
DTS_END                         2023-02-12 01:38:40.7670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54340
TRN_ID                          240048
ATT_ID                          274
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: 9129.92 23025 1095591
customer order: creation 171.28 363 20554
customer order: refuse 14.18 353 1701
order to supplier: creation 45.88 1233 5505
order to supplier: removal 3.39 95225 407
invoice (draft): creation 45.97 3480 5516
invoice (draft): removal 52.43 745 6291
invoice accept: apply 41.33 17509 4960
invoice accept: cancel 47.08 768 5650
customer reserve: creation 3800.04 174 456005
customer reserve: removal 4613.08 55 553569
realization accept: apply 81.70 172 9804
realization accept: cancel 84.35 107 10122
payment from customer: creation 58.42 103 7010
payment from customer: removal 5.72 77 686
payment to supplier: creation 49.37 117 5924
payment to supplier: removal 3.36 50 403
service: total inventory turnovers 4.67 1006 560
service: total monetary turnovers 6.88 28 825
service: aggregate perf. data 0.79 21124 95
service: refresh index statistics 0.03 317806 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-02-2023 01:39:30 to 12-02-2023 01:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2801
TEST_TIME 2 1954
TEST_TIME 3 3102
TEST_TIME 4 3931
TEST_TIME 5 4940
TEST_TIME 6 4602
TEST_TIME 7 4746
TEST_TIME 8 5280
TEST_TIME 9 5076
TEST_TIME 10 5580
TEST_TIME 11 5963
TEST_TIME 12 5808
TEST_TIME 13 6127
TEST_TIME 14 6258
TEST_TIME 15 6809
TEST_TIME 16 6907
TEST_TIME 17 7730
TEST_TIME 18 7939
TEST_TIME 19 8307
TEST_TIME 20 8905
TEST_TIME 21 8840
TEST_TIME 22 8737
TEST_TIME 23 8744
TEST_TIME 24 8972
TEST_TIME 25 9118
TEST_TIME 26 9150
TEST_TIME 27 9107
TEST_TIME 28 9377
TEST_TIME 29 9652
TEST_TIME 30 10436
TEST_TIME 31 10474
TEST_TIME 32 10200
TEST_TIME 33 10271
TEST_TIME 34 10297
TEST_TIME 35 10273
TEST_TIME 36 10130
TEST_TIME 37 10165
TEST_TIME 38 9958
TEST_TIME 39 9889
TEST_TIME 40 9812
TEST_TIME 41 9712
TEST_TIME 42 9689
TEST_TIME 43 9704
TEST_TIME 44 9561
TEST_TIME 45 9588
TEST_TIME 46 9480
TEST_TIME 47 9372
TEST_TIME 48 9306
TEST_TIME 49 9243
TEST_TIME 50 9304
TEST_TIME 51 9328
TEST_TIME 52 9337
TEST_TIME 53 9243
TEST_TIME 54 9158
TEST_TIME 55 9056
TEST_TIME 56 8985
TEST_TIME 57 9067
TEST_TIME 58 9068
TEST_TIME 59 9040
TEST_TIME 60 8949
TEST_TIME 61 8962
TEST_TIME 62 8971
TEST_TIME 63 8924
TEST_TIME 64 8996
TEST_TIME 65 9051
TEST_TIME 66 9138
TEST_TIME 67 9078
TEST_TIME 68 9136
TEST_TIME 69 9169
TEST_TIME 70 9246
TEST_TIME 71 9238
TEST_TIME 72 9334
TEST_TIME 73 9303
TEST_TIME 74 9298
TEST_TIME 75 9369
TEST_TIME 76 9284
TEST_TIME 77 9206
TEST_TIME 78 9193
TEST_TIME 79 9164
TEST_TIME 80 9168
TEST_TIME 81 9171
TEST_TIME 82 9131
TEST_TIME 83 9092
TEST_TIME 84 9027
TEST_TIME 85 8996
TEST_TIME 86 8998
TEST_TIME 87 8995
TEST_TIME 88 9027
TEST_TIME 89 9009
TEST_TIME 90 9078
TEST_TIME 91 9108
TEST_TIME 92 9098
TEST_TIME 93 9132
TEST_TIME 94 9103
TEST_TIME 95 9149
TEST_TIME 96 9185
TEST_TIME 97 9202
TEST_TIME 98 9174
TEST_TIME 99 9205
TEST_TIME 100 9287
TEST_TIME 101 9231
TEST_TIME 102 9167
TEST_TIME 103 9180
TEST_TIME 104 9119
TEST_TIME 105 9090
TEST_TIME 106 9131
TEST_TIME 107 9099
TEST_TIME 108 9078
TEST_TIME 109 9088
TEST_TIME 110 9059
TEST_TIME 111 9045
TEST_TIME 112 9061
TEST_TIME 113 9056
TEST_TIME 114 9041
TEST_TIME 115 9069
TEST_TIME 116 9079
TEST_TIME 117 9121
TEST_TIME 118 9131
TEST_TIME 119 9099
TEST_TIME 120 9132

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-02-2023 01:39:34 to 12-02-2023 01:39:38.

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 1517284 1517284 0 0.00 0 14531 6 0 0
doc_list_biud 1517283 1517283 0 0.00 0 42111 26 0 0
sp_add_invoice_to_stock 5121 4960 161 3.14 17 163421 17509 0 161
sp_cancel_adding_invoice 5650 5650 0 0.00 4 56599 769 0 0
sp_cancel_client_order 1701 1701 0 0.00 4 13324 354 0 0
sp_cancel_customer_reserve 553569 553569 0 0.00 0 42116 55 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 2481 78 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 1089 51 0 0
sp_cancel_supplier_invoice 6302 6291 11 0.17 2 20175 746 0 11
sp_cancel_supplier_order 407 407 0 0.00 4 836292 95225 0 0
sp_cancel_write_off 10122 10122 0 0.00 1 11914 108 0 0
sp_client_order 20554 20554 0 0.00 3 54736 364 0 0
sp_customer_reserve 456005 456005 0 0.00 0 55448 174 0 0
sp_fill_shopping_cart 31565 31565 0 0.00 1 30912 44 0 0
sp_fill_shopping_cart_clo_res 456001 456001 0 0.00 0 22186 16 0 0
sp_get_clo_for_invoice 4955 4955 0 0.00 0 21790 394 0 0
sp_kill_cost_storno 16861 16861 0 0.00 0 7827 52 0 0
sp_kill_qstorno_ret_qs2qd 1123958 1123958 0 0.00 0 42098 30 0 0
sp_kill_qty_storno 592498 592498 0 0.00 0 42111 65 0 0
sp_lock_selected_doc 20471 20471 0 0.00 0 7893 7 0 0
sp_make_cost_storno 27693 27693 0 0.00 0 12368 50 0 0
sp_multiply_rows_for_pdistr 27693 27693 0 0.00 0 6858 26 0 0
sp_multiply_rows_for_qdistr 20554 20554 0 0.00 0 23786 155 0 0
sp_pay_from_customer 7010 7010 0 0.00 4 8811 103 0 0
sp_pay_to_supplier 5924 5924 0 0.00 5 9802 118 0 0
sp_payment_common 12934 12934 0 0.00 1 8346 25 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 13319 322 0 0
sp_qd_handle_on_invoice_upd_sts 10605 10605 0 0.00 0 29152 319 0 0
sp_qd_handle_on_reserve_upd_sts 9803 9803 0 0.00 0 13111 55 0 0
sp_reserve_write_off 9804 9804 0 0.00 6 15771 173 0 0
sp_supplier_invoice 5516 5516 0 0.00 7 77896 3481 0 0
sp_supplier_order 5505 5505 0 0.00 5 30419 1234 0 0
srv_aggregate_perf_data 95 95 0 0.00 2841 371018 21125 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1559 137041 46469 0 0
srv_find_qd_qs_mism 1047885 1047885 0 0.00 0 14529 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 50 27578 1007 0 0
srv_make_money_saldo 825 825 0 0.00 0 1418 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 152950 470565 317806 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 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 4960 4960 0 0.00 0 16838 160 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 11739 96 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 1835 11 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 3163 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 2173 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 3136 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1194 15 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 28473 1542 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 10588 18 0 0
t$perf-norm:sp_client_order 20554 20554 0 0.00 0 12849 17 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 2139 7 0 0
t$perf-norm:sp_pay_from_customer 7010 7010 0 0.00 0 9402 11 0 0
t$perf-norm:sp_pay_to_supplier 5924 5924 0 0.00 0 10660 10 0 0
t$perf-norm:sp_reserve_write_off 9805 9805 0 0.00 0 14467 12 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 10007 17 0 0
t$perf-norm:sp_supplier_order 5505 5505 0 0.00 0 4004 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 31 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 1961 25 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1526 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10228 9906 322 3.14 0 4559 21 0 322
v_all_customers 37444 37444 0 0.00 0 21 1 0 0
v_all_suppliers 30706 30706 0 0.00 0 24 1 0 0
v_all_wares 41102 41102 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 4236 14 0 0
v_cancel_client_order 3402 3402 0 0.00 0 3942 8 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2468 10 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 3741 13 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1010 6 0 0
v_cancel_supplier_invoice 868 846 22 2.53 0 1694 17 0 22
v_cancel_supplier_order 814 814 0 0.00 0 2685 8 0 0
v_cancel_write_off 1986 1986 0 0.00 0 6650 31 0 0
v_max_id_clo_ord 5503 5503 0 0.00 0 2646 3 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 8375 23 0 0
v_max_id_ord_sup 5511 5511 0 0.00 0 8011 6 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 3670 6 0 0
v_max_non_paid_realizn 3633 3633 0 0.00 0 2941 6 0 0
v_min_id_clo_ord 5503 5503 0 0.00 0 5655 10 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 5540 28 0 0
v_min_id_ord_sup 5511 5511 0 0.00 0 5902 6 0 0
v_min_non_paid_invoice 5924 5924 0 0.00 0 5408 15 0 0
v_min_non_paid_realizn 7010 7010 0 0.00 0 5295 19 0 0
v_our_firm 10412 10412 0 0.00 0 34 1 0 0
v_reserve_write_off 19606 19606 0 0.00 0 4097 9 0 0
x_lock_depdocs_on_canc_invoice 11518 11518 0 0.00 0 16213 117 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 23114 417 0 0
x_make_qstorno_1000_1200 5505 5505 0 0.00 1 30412 1149 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 3 77611 3319 0 0
x_make_qstorno_2100_3300 456005 456005 0 0.00 0 53725 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-02-2023 01:39:39 to 12-02-2023 01:39:43.

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:44 6 8192000000 8800896208 8812929024 6652880 0.081 3 1 2 0 6753808 58144 42784
23:09:45 29040 8192000000 9881947104 10183888896 966543040 10.553 100 91 182 0 996936144 5734544 24658560
23:11:23 14404 8192000000 9926838400 10240905216 1008285056 10.959 100 85 170 0 1037509168 7180720 22043392
23:12:44 10983 8192000000 9955247680 10281955328 1043599488 11.299 100 77 154 0 1071651712 8393904 19658320
23:14:02 8550 8192000000 10026056288 10369327104 1114359680 11.974 100 89 176 1 1145894416 10653888 20880848
23:15:17 4745 8192000000 10067777760 10425819136 1159609520 12.400 100 78 156 1 1190089088 12630640 17848928
23:16:23 4014 8192000000 10106756864 10473218048 1194592800 12.726 100 80 158 0 1225382496 13633744 17155952
23:17:29 2712 8192000000 10138619280 10508431360 1232557600 13.078 100 87 175 0 1264947248 13601104 18788544
23:18:34 1578 8192000000 10167968624 10541006848 1252508656 13.261 100 87 175 1 1285804144 14430640 18864848
23:19:38 1268 8192000000 10198079520 10577338368 1284991168 13.559 100 86 173 1 1318485120 13773120 19720832
23:20:41 1054 8192000000 10211454672 10597298176 1298305264 13.680 100 81 162 1 1329113056 13278048 17529744
23:21:46 23603 8192000000 10221037568 10622009344 1314626096 13.828 100 52 104 0 1334805072 8259456 11919520
23:23:12 6553 8192000000 10214224544 10622926848 1322961472 13.904 100 35 71 0 1336901680 5814496 8125712
23:24:19 7947 8192000000 10212749136 10632577024 1331407728 13.980 100 24 48 0 1343082352 6159968 5514656
23:25:29 33007 8192000000 10225415120 10647363584 1339204896 14.050 100 27 54 1 1350630640 5369520 6056224
23:27:18 6125 8192000000 10229484336 10652372992 1344772512 14.100 100 22 44 0 1356156432 5879344 5504576
23:28:26 4819 8192000000 10213046896 10634088448 1318744496 13.865 99 16 32 0 1328570256 5954800 3870960
23:29:31 33095 8192000000 10186910112 10597056512 1250934208 13.247 95 33 64 0 1262855360 4866480 7054672
23:31:22 6071 8192000000 10123613600 10521800704 1211766752 12.885 95 27 54 0 1224258720 5909296 6582672
23:32:29 5995 8192000000 10160293904 10565001216 1270279264 13.424 100 23 45 0 1280903648 4853504 5770880
23:33:37 7842 8192000000 10153070048 10554019840 1242582944 13.170 98 19 38 0 1252214096 5033136 4598016
23:34:45 41025 8192000000 10168790944 10581823488 1269646448 13.418 99 18 36 0 1283355088 9902544 3806096
23:36:26 6997 8192000000 10186315280 10601684992 1299185040 13.688 100 23 46 0 1310305936 5916384 5204512
23:37:34 8416 8192000000 10197950960 10616049664 1311474512 13.799 100 19 39 0 1321474512 5777248 4222752
23:38:44 41671 8192000000 10240063584 10650173440 1339135280 14.050 100 44 86 1 1355610208 6170480 10304448
23:40:29 71238 8192000000 10257975232 10686894080 1358953472 14.228 100 33 66 0 1380331488 14286560 7091456
23:42:43 15349 8192000000 10291915440 10714484736 1376488960 14.385 100 45 90 0 1406460000 20061984 9909056
23:44:07 33459 8192000000 10298533856 10727002112 1388128304 14.489 100 41 82 0 1414420304 16711328 9580672
23:45:46 9470 8192000000 10325762752 10751041536 1404345376 14.634 100 40 80 0 1439161952 25332608 9483968
23:47:02 16621 8192000000 10335146272 10756415488 1405175424 14.641 100 49 97 2 1441651680 24651808 11824448
23:48:30 6645 8192000000 10334856416 10762227712 1407636144 14.663 100 59 116 0 1452379888 30101568 14642176
23:49:41 4142 8192000000 10321167136 10747658240 1379442656 14.412 98 41 77 1 1413526688 24387056 9696976
23:50:50 5839 8192000000 10349600832 10771632128 1414897536 14.727 100 67 134 0 1458603712 27753424 15952752
23:51:59 1556 8192000000 10351310464 10767458304 1407977184 14.666 100 80 160 1 1457291568 29665456 19648928
23:53:06 4327 8192000000 10343552400 10761256960 1395329872 14.553 100 69 138 0 1449372512 37505440 16537200
23:54:17 2225 8192000000 10346556624 10765950976 1399222416 14.588 100 74 145 0 1452379872 34991952 18165504
23:55:23 3595 8192000000 10335135632 10747809792 1378524688 14.403 100 75 146 0 1437657264 41171584 17960992
23:56:30 2383 8192000000 10330636656 10751901696 1390873744 14.514 100 65 125 1 1440709312 34124384 15711184
23:57:35 12377 8192000000 10320996848 10746781696 1364624016 14.279 99 42 84 0 1419420544 43796656 10999872
23:58:54 16069 8192000000 10312010112 10742472704 1380388096 14.420 100 29 58 0 1423505168 35292368 7824704
00:00:16 14694 8192000000 10309043360 10740408320 1364319584 14.276 98 28 56 0 1405855264 33845952 7689728
00:01:54 5677 8192000000 10293466800 10711011328 1346328416 14.114 99 51 100 1 1397500064 38383184 12788464
00:03:12 16087 8192000000 10285680176 10716778496 1349265008 14.141 99 26 51 0 1392090992 35858224 6967760
00:04:50 30167 8192000000 10276932560 10699337728 1338722496 14.046 100 19 38 0 1381997776 37777456 5497824
00:06:24 53441 8192000000 10297508784 10729652224 1339670800 14.054 99 19 38 0 1390275168 45182128 5422240
00:08:22 35185 8192000000 10237961648 10663690240 1269246576 13.415 95 14 26 0 1300449424 28136320 3066528
00:10:13 89792 8192000000 10277837024 10707939328 1353351936 14.178 100 30 58 0 1376669920 16511248 6806736
00:12:47 29804 8192000000 10274972864 10716893184 1355246912 14.195 99 23 46 0 1377193568 16717504 5229152
00:14:32 69528 8192000000 10285361376 10736525312 1362847648 14.263 99 12 24 0 1383925152 18166976 2910528
00:16:42 20996 8192000000 10292251312 10728296448 1374814304 14.370 100 51 101 0 1407376560 20531952 12030304
00:18:04 111399 8192000000 10296424544 10735874048 1373919552 14.362 99 29 58 0 1390624080 9971776 6732752
00:20:59 93845 8192000000 10297557808 10736193536 1374265504 14.365 99 33 63 0 1389466000 7696768 7503728
00:23:37 76234 8192000000 10309684016 10759458816 1403310608 14.624 100 34 68 0 1422899312 11723328 7865376
00:26:43 38483 8192000000 10328035072 10775699456 1411349520 14.696 100 30 60 1 1433544352 15338928 6855904
00:28:24 40436 8192000000 10321956128 10777808896 1385537104 14.466 98 17 34 1 1407145312 17213472 4394736
00:30:08 45016 8192000000 10304882624 10764644352 1398113312 14.578 99 15 31 0 1410864544 9407280 3343952
00:31:58 61692 8192000000 10293727792 10756292608 1382560896 14.439 98 10 20 0 1393277968 8492976 2224096
00:34:06 89954 8192000000 10291951264 10748960768 1368638720 14.315 98 31 62 1 1388517152 12686688 7191744
00:36:46 18637 8192000000 10303760032 10765377536 1402883264 14.621 100 22 44 2 1418959232 10541504 5534464
00:38:37 68799 8192000000 10293717776 10761097216 1382118576 14.435 99 18 36 0 1392903472 6838880 3946016
00:40:49 73271 8192000000 10300804240 10755694592 1338641344 14.045 95 12 24 0 1353681328 12742272 2297712
00:43:04 80696 8192000000 10273854448 10720030720 1309075344 13.778 94 10 20 0 1328882656 17335216 2472096
00:45:25 54726 8192000000 10295801584 10742390784 1345871248 14.110 97 28 56 1 1363491344 11103744 6516352
00:47:23 107838 8192000000 10292298544 10761093120 1347092208 14.121 97 22 44 0 1371598992 19300960 5205824
00:50:22 76242 8192000000 10281790368 10729578496 1339080320 14.049 96 16 32 0 1352971216 10192896 3698000
00:52:43 29798 8192000000 10295257008 10745241600 1368782416 14.316 100 44 88 0 1396476592 17706320 9987856
00:54:14 115569 8192000000 10254283504 10704244736 1317230128 13.852 96 21 42 2 1330677104 8703920 4743056
00:57:12 34234 8192000000 10247881456 10704969728 1341193872 14.068 99 12 24 1 1354214512 10386256 2634384
00:58:47 54664 8192000000 10256245696 10713808896 1334687680 14.009 98 18 36 0 1350931584 11883712 4360192
01:00:46 66007 8192000000 10262626288 10713587712 1309588176 13.782 96 16 32 0 1322534704 9371056 3575472
01:03:38 83809 8192000000 10242665616 10708561920 1319305520 13.870 97 18 35 1 1337753392 14139040 4308832
01:06:04 48759 8192000000 10259388160 10706145280 1342036032 14.076 99 14 28 1 1353887856 8491472 3360352
01:08:44 43571 8192000000 10279270880 10737299456 1350706736 14.154 98 17 34 0 1367088080 12811808 3569536
01:10:28 82116 8192000000 10289720544 10754826240 1371977568 14.345 99 14 28 1 1392962240 18059712 2924960
01:13:05 44393 8192000000 10300607024 10760351744 1387570768 14.484 100 17 34 1 1409626624 18515232 3540624
01:14:50 14851 8192000000 10296908304 10742816768 1385698624 14.467 100 39 77 1 1401952928 7769024 8485280
01:16:07 35364 8192000000 10303001392 10744283136 1384263872 14.455 100 34 66 1 1401025472 9628112 7133488
01:17:43 137041 8192000000 10287282656 10751877120 1328739648 13.956 95 21 42 0 1348649280 15725056 4184576
01:21:22 66558 8192000000 10276388704 10735931392 1361593040 14.252 98 23 46 1 1377248528 10956192 4699296
01:23:46 52029 8192000000 10272505648 10745655296 1378512432 14.403 100 15 30 0 1397565584 15595520 3457632
01:25:40 69056 8192000000 10269149440 10724286464 1353674240 14.181 98 15 29 0 1367546576 10627616 3244720
01:29:06 58668 8192000000 10245207648 10706182144 1341946336 14.075 98 10 20 0 1352258592 8222208 2090048
01:31:07 54748 8192000000 10268111312 10721669120 1363629456 14.270 99 20 40 0 1375980400 7977728 4373216
01:33:08 28161 8192000000 10292487152 10747129856 1386614544 14.476 100 37 74 0 1405087168 10508128 7964496
01:34:41 70225 8192000000 10278526000 10736918528 1345377600 14.106 98 21 42 0 1366083312 16306144 4399568
01:37:23 41735 8192000000 10297249056 10742415360 1382679104 14.440 100 28 56 0 1405118272 16373392 6065776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-02-2023 01:39:45 to 12-02-2023 01:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 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 12-02-2023 01:40:01 to 12-02-2023 01:40:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246762
MON$OLDEST_ACTIVE               246763
MON$OLDEST_SNAPSHOT             246763
MON$NEXT_TRANSACTION            246763
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                       2025952
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
--- end of diff output ---

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

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