Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2022 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 15444240 kB
MemAvailable 30547396 kB
Buffers 0 kB
Cached 16300952 kB
SwapCached 2204 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 01:38:31
DTS_END                         2022-10-14 01:38:31.1380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59902
TRN_ID                          251255
ATT_ID                          681
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: 9132.22 20736 1095866
customer order: creation 183.52 338 22022
customer order: refuse 16.08 348 1929
order to supplier: creation 49.57 1257 5948
order to supplier: removal 3.52 84025 422
invoice (draft): creation 48.53 3554 5823
invoice (draft): removal 49.91 747 5989
invoice accept: apply 44.01 16681 5281
invoice accept: cancel 44.52 768 5342
customer reserve: creation 3997.88 167 479746
customer reserve: removal 4392.59 52 527111
realization accept: apply 88.08 186 10570
realization accept: cancel 77.75 101 9330
payment from customer: creation 62.79 114 7535
payment from customer: removal 5.89 102 707
payment to supplier: creation 51.40 132 6168
payment to supplier: removal 3.52 74 422
service: total inventory turnovers 5.00 1080 600
service: total monetary turnovers 6.88 42 825
service: aggregate perf. data 0.77 23990 92
service: refresh index statistics 0.03 280964 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2022 01:40:11 to 14-10-2022 01:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5039
TEST_TIME 2 4690
TEST_TIME 3 4056
TEST_TIME 4 4894
TEST_TIME 5 4782
TEST_TIME 6 4832
TEST_TIME 7 4827
TEST_TIME 8 4987
TEST_TIME 9 5452
TEST_TIME 10 5998
TEST_TIME 11 6380
TEST_TIME 12 6287
TEST_TIME 13 6388
TEST_TIME 14 6748
TEST_TIME 15 6836
TEST_TIME 16 7022
TEST_TIME 17 7142
TEST_TIME 18 7428
TEST_TIME 19 7826
TEST_TIME 20 8168
TEST_TIME 21 8417
TEST_TIME 22 8841
TEST_TIME 23 8845
TEST_TIME 24 8979
TEST_TIME 25 9027
TEST_TIME 26 9215
TEST_TIME 27 9665
TEST_TIME 28 9678
TEST_TIME 29 9769
TEST_TIME 30 9947
TEST_TIME 31 10303
TEST_TIME 32 10268
TEST_TIME 33 10167
TEST_TIME 34 10231
TEST_TIME 35 10207
TEST_TIME 36 10099
TEST_TIME 37 9994
TEST_TIME 38 9838
TEST_TIME 39 9945
TEST_TIME 40 9882
TEST_TIME 41 9849
TEST_TIME 42 9715
TEST_TIME 43 9683
TEST_TIME 44 9761
TEST_TIME 45 9809
TEST_TIME 46 9863
TEST_TIME 47 9738
TEST_TIME 48 9683
TEST_TIME 49 9610
TEST_TIME 50 9465
TEST_TIME 51 9508
TEST_TIME 52 9568
TEST_TIME 53 9557
TEST_TIME 54 9567
TEST_TIME 55 9579
TEST_TIME 56 9640
TEST_TIME 57 9625
TEST_TIME 58 9678
TEST_TIME 59 9744
TEST_TIME 60 9734
TEST_TIME 61 9862
TEST_TIME 62 9815
TEST_TIME 63 9857
TEST_TIME 64 9862
TEST_TIME 65 9844
TEST_TIME 66 9755
TEST_TIME 67 9654
TEST_TIME 68 9673
TEST_TIME 69 9583
TEST_TIME 70 9494
TEST_TIME 71 9468
TEST_TIME 72 9388
TEST_TIME 73 9397
TEST_TIME 74 9319
TEST_TIME 75 9357
TEST_TIME 76 9321
TEST_TIME 77 9279
TEST_TIME 78 9313
TEST_TIME 79 9275
TEST_TIME 80 9302
TEST_TIME 81 9335
TEST_TIME 82 9399
TEST_TIME 83 9401
TEST_TIME 84 9385
TEST_TIME 85 9365
TEST_TIME 86 9405
TEST_TIME 87 9446
TEST_TIME 88 9517
TEST_TIME 89 9483
TEST_TIME 90 9400
TEST_TIME 91 9379
TEST_TIME 92 9336
TEST_TIME 93 9337
TEST_TIME 94 9280
TEST_TIME 95 9242
TEST_TIME 96 9241
TEST_TIME 97 9242
TEST_TIME 98 9234
TEST_TIME 99 9213
TEST_TIME 100 9196
TEST_TIME 101 9308
TEST_TIME 102 9286
TEST_TIME 103 9331
TEST_TIME 104 9317
TEST_TIME 105 9347
TEST_TIME 106 9415
TEST_TIME 107 9392
TEST_TIME 108 9424
TEST_TIME 109 9440
TEST_TIME 110 9403
TEST_TIME 111 9335
TEST_TIME 112 9296
TEST_TIME 113 9274
TEST_TIME 114 9275
TEST_TIME 115 9249
TEST_TIME 116 9232
TEST_TIME 117 9206
TEST_TIME 118 9198
TEST_TIME 119 9180
TEST_TIME 120 9133

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2022 01:40:15 to 14-10-2022 01:40:18.

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 1540546 1540546 0 0.00 0 17605 6 0 0
doc_list_biud 1540534 1540534 0 0.00 0 43794 23 0 0
sp_add_invoice_to_stock 5382 5281 101 1.87 22 199093 16681 0 101
sp_cancel_adding_invoice 5342 5342 0 0.00 5 51469 768 0 0
sp_cancel_client_order 1929 1929 0 0.00 4 9746 348 0 0
sp_cancel_customer_reserve 527111 527111 0 0.00 0 24261 53 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 2 6562 103 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 2 4946 74 0 0
sp_cancel_supplier_invoice 5997 5989 8 0.13 1 19490 748 0 8
sp_cancel_supplier_order 422 422 0 0.00 8 987551 84026 0 0
sp_cancel_write_off 9330 9330 0 0.00 1 13119 101 0 0
sp_client_order 22022 22022 0 0.00 3 24978 338 0 0
sp_customer_reserve 479746 479746 0 0.00 0 50886 168 0 0
sp_fill_shopping_cart 33784 33784 0 0.00 1 15596 41 0 0
sp_fill_shopping_cart_clo_res 479746 479746 0 0.00 0 14336 14 0 0
sp_get_clo_for_invoice 5281 5281 0 0.00 0 27362 280 0 0
sp_kill_cost_storno 15801 15801 0 0.00 0 10272 51 0 0
sp_kill_qstorno_ret_qs2qd 1069963 1069963 0 0.00 0 24254 29 0 0
sp_kill_qty_storno 565963 565963 0 0.00 0 43793 63 0 0
sp_lock_selected_doc 21911 21911 0 0.00 0 8769 6 0 0
sp_make_cost_storno 29554 29554 0 0.00 0 17596 53 0 0
sp_multiply_rows_for_pdistr 29554 29554 0 0.00 0 8951 27 0 0
sp_multiply_rows_for_qdistr 22022 22022 0 0.00 0 10939 154 0 0
sp_pay_from_customer 7535 7535 0 0.00 4 11280 114 0 0
sp_pay_to_supplier 6168 6168 0 0.00 5 9903 132 0 0
sp_payment_common 13702 13702 0 0.00 1 11275 30 0 0
sp_qd_handle_on_cancel_clo 1929 1929 0 0.00 0 9740 322 0 0
sp_qd_handle_on_invoice_upd_sts 10618 10618 0 0.00 0 43792 313 0 0
sp_qd_handle_on_reserve_upd_sts 10564 10564 0 0.00 0 16931 61 0 0
sp_reserve_write_off 10570 10570 0 0.00 6 17463 186 0 0
sp_supplier_invoice 5823 5823 0 0.00 6 102302 3555 0 0
sp_supplier_order 5948 5948 0 0.00 5 73581 1257 0 0
srv_aggregate_perf_data 92 92 0 0.00 2338 504070 23990 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2235 127210 41489 0 0
srv_find_qd_qs_mism 1045837 1045837 0 0.00 0 13600 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 35 22862 1081 0 0
srv_make_money_saldo 825 825 0 0.00 1 12081 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 253769 338924 280964 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5283 5283 0 0.00 0 20566 176 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 2706 78 0 0
t$perf-norm:sp_cancel_client_order 1929 1929 0 0.00 0 6797 30 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 3134 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 9853 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 136 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 3775 41 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 20645 1592 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 3636 11 0 0
t$perf-norm:sp_client_order 22022 22022 0 0.00 0 28021 20 0 0
t$perf-norm:sp_customer_reserve 1100 1100 0 0.00 0 1651 7 0 0
t$perf-norm:sp_pay_from_customer 7535 7535 0 0.00 0 10803 14 0 0
t$perf-norm:sp_pay_to_supplier 6168 6168 0 0.00 0 4954 13 0 0
t$perf-norm:sp_reserve_write_off 10570 10570 0 0.00 0 18778 14 0 0
t$perf-norm:sp_supplier_invoice 5823 5823 0 0.00 0 4524 13 0 0
t$perf-norm:sp_supplier_order 5948 5948 0 0.00 0 17372 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 427 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2278 43 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 844 18 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 3435 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10754 10552 202 1.87 0 6732 20 0 202
v_all_customers 39630 39630 0 0.00 0 81 1 0 0
v_all_suppliers 32066 32066 0 0.00 0 19 1 0 0
v_all_wares 44044 44044 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 2056 10 0 0
v_cancel_client_order 3858 3858 0 0.00 0 3996 6 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 769 5 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 5269 10 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 4921 12 0 0
v_cancel_supplier_invoice 850 834 16 1.88 0 3563 21 0 16
v_cancel_supplier_order 844 844 0 0.00 0 2440 9 0 0
v_cancel_write_off 2046 2046 0 0.00 0 3568 18 0 0
v_max_id_clo_ord 5947 5947 0 0.00 0 1728 2 0 0
v_max_id_clo_res 1100 1100 0 0.00 0 4139 16 0 0
v_max_id_ord_sup 5815 5815 0 0.00 0 3783 5 0 0
v_max_non_paid_invoice 1896 1896 0 0.00 0 5409 9 0 0
v_max_non_paid_realizn 4185 4185 0 0.00 0 3024 5 0 0
v_min_id_clo_ord 5947 5947 0 0.00 0 4698 7 0 0
v_min_id_clo_res 1100 1100 0 0.00 0 6931 21 0 0
v_min_id_ord_sup 5815 5815 0 0.00 0 6141 15 0 0
v_min_non_paid_invoice 6167 6167 0 0.00 0 9540 21 0 0
v_min_non_paid_realizn 7535 7535 0 0.00 0 11270 23 0 0
v_our_firm 11114 11114 0 0.00 0 17 1 0 0
v_reserve_write_off 21128 21128 0 0.00 0 8470 8 0 0
x_lock_depdocs_on_canc_invoice 10914 10914 0 0.00 0 28041 111 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9838 369 0 0
x_make_qstorno_1000_1200 5948 5948 0 0.00 1 73538 1178 0 0
x_make_qstorno_1200_2000 5823 5823 0 0.00 2 92240 3401 0 0
x_make_qstorno_2100_3300 479746 479746 0 0.00 0 50884 152 0 0

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

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:34 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:35 19162 8192000000 9895806464 10207633408 987472608 10.757 100 94 188 0 1018406848 6204464 24729776
23:10:58 34154 8192000000 9959347808 10280607744 1035030048 11.217 100 89 178 0 1066802928 8660096 23112784
23:12:37 11849 8192000000 9989513808 10319409152 1066964496 11.523 100 87 173 0 1098477248 9982976 21529776
23:13:54 10608 8192000000 10023223744 10371825664 1113706576 11.967 100 72 142 0 1141553040 10966240 16880224
23:15:08 12111 8192000000 10078696144 10438029312 1165196816 12.452 100 81 162 0 1197357760 13704912 18456032
23:16:23 3690 8192000000 10121703168 10490773504 1208607696 12.856 100 77 153 0 1240036816 13850848 17578272
23:17:30 1681 8192000000 10155734864 10532659200 1244441616 13.187 100 76 152 0 1274294928 12433424 17419888
23:18:33 948 8192000000 10203417680 10584113152 1287732208 13.584 100 89 174 0 1320073824 12563440 19778176
23:19:35 1187 8192000000 10225323184 10610982912 1307060688 13.759 100 87 174 1 1340276496 14558336 18657472
23:20:38 20095 8192000000 10205880800 10612228096 1314375024 13.826 100 45 88 2 1332049696 7492064 10182608
23:22:00 6006 8192000000 10208575376 10620981248 1320519552 13.881 100 27 55 0 1334110848 7295136 6296160
23:23:08 9964 8192000000 10210665696 10628657152 1328478160 13.953 100 24 52 0 1344306432 10179376 5648896
23:24:18 69731 8192000000 10234080432 10648932352 1331129472 13.977 100 26 50 0 1341572528 4861344 5581712
23:26:30 4875 8192000000 10228118672 10651086848 1337752656 14.037 100 30 56 1 1350371488 6553232 6065600
23:27:35 14016 8192000000 10215936656 10634543104 1305825552 13.748 98 19 38 0 1315404832 5450144 4129136
23:28:50 13076 8192000000 10180954288 10588647424 1254033200 13.275 96 19 39 1 1262607744 4883872 3690672
23:30:04 18064 8192000000 10138088432 10545737728 1182645296 12.615 92 17 34 1 1191838256 5321040 3871920
23:31:24 3749 8192000000 10133936208 10530549760 1217196224 12.936 97 19 39 0 1226817696 4938736 4682736
23:32:28 4907 8192000000 10143076592 10541846528 1248260240 13.222 100 27 55 0 1260216944 5091536 6865168
23:33:33 6319 8192000000 10162231296 10570129408 1271252608 13.433 100 23 46 0 1281500608 5187488 5060512
23:34:40 15134 8192000000 10177485792 10588246016 1285332464 13.562 100 22 44 0 1296276912 5769248 5175200
23:35:57 6983 8192000000 10191970592 10604011520 1301975152 13.713 100 21 42 0 1312726144 6065520 4685472
23:37:05 6796 8192000000 10194362288 10613194752 1309962896 13.786 100 24 44 1 1321799216 5861568 5974752
23:38:13 28539 8192000000 10236888768 10649583616 1333219456 13.996 100 16 32 0 1341605328 4179440 4206432
23:39:44 30015 8192000000 10245064880 10662559744 1347444944 14.124 100 39 76 0 1362243968 6283088 8515936
23:41:25 12506 8192000000 10271635440 10689953792 1366160432 14.293 100 48 94 0 1385784784 8989616 10634736
23:42:41 31458 8192000000 10275036640 10706845696 1377287936 14.392 100 30 60 1 1393748416 9222640 7237840
23:44:16 9930 8192000000 10295817808 10724114432 1391125664 14.516 100 37 70 1 1409788016 9935280 8727072
23:45:32 17982 8192000000 10310234352 10739367936 1397832464 14.576 100 43 86 0 1422650752 14327632 10490656
23:46:53 9232 8192000000 10310657376 10733281280 1372333216 14.348 98 56 110 2 1404837392 20082128 12422048
23:48:07 3226 8192000000 10323516656 10737487872 1392012544 14.524 100 71 140 0 1432729280 23535200 17181536
23:49:13 9919 8192000000 10328309712 10750455808 1399094400 14.587 100 60 117 1 1440501904 27263248 14144256
23:50:28 4459 8192000000 10322701216 10740871168 1389772384 14.504 100 66 125 2 1430196624 25021168 15403072
23:51:37 19289 8192000000 10335337056 10762321920 1402811536 14.620 100 46 90 0 1444441104 29917296 11712272
23:53:02 3179 8192000000 10321473008 10747170816 1387071776 14.480 99 49 98 0 1423245248 24146624 12026848
23:54:11 7171 8192000000 10332769408 10742345728 1382253568 14.437 100 81 158 0 1432569184 30111056 20204560
23:55:25 3713 8192000000 10313402464 10722889728 1360993984 14.246 99 71 142 1 1410935136 32919536 17021616
23:56:33 2741 8192000000 10305753056 10711584768 1354326112 14.186 100 82 164 1 1412566848 38016896 20223840
23:57:39 12983 8192000000 10303204896 10711977984 1356952016 14.210 100 73 145 2 1405926512 30683408 18291088
23:58:58 2232 8192000000 10303547968 10714148864 1356275360 14.204 100 63 124 0 1407746768 35909296 15562112
00:00:08 12295 8192000000 10306355376 10727116800 1355602624 14.198 99 36 72 0 1403048608 37745712 9700272
00:01:30 30261 8192000000 10294932080 10717298688 1367614992 14.306 100 40 81 1 1403152656 25327792 10209872
00:03:15 10356 8192000000 10296556640 10714845184 1365246288 14.284 100 39 78 0 1401849296 26687008 9916000
00:04:28 18670 8192000000 10311513168 10736984064 1378170688 14.400 100 37 75 0 1413553584 25349344 10033552
00:05:58 29645 8192000000 10302419488 10739871744 1369667760 14.324 100 17 34 0 1404257104 29996016 4593328
00:07:31 46225 8192000000 10283373216 10716340224 1353803712 14.182 100 13 26 0 1385229296 27873152 3552432
00:09:21 48698 8192000000 10276966000 10717487104 1367446032 14.304 100 12 25 0 1389858000 19923632 2488336
00:11:16 29819 8192000000 10298949904 10725466112 1374686784 14.369 100 47 93 1 1397863296 12691664 10484848
00:12:48 44575 8192000000 10281035104 10727133184 1333044048 13.995 96 11 22 0 1343536560 8243376 2249136
00:14:33 19508 8192000000 10324834944 10748981248 1394893088 14.550 100 58 111 2 1415654000 7125728 13635184
00:16:00 115596 8192000000 10303313280 10747990016 1379970192 14.416 99 26 48 2 1400285312 14205680 6109440
00:18:58 48531 8192000000 10295524576 10750427136 1397092192 14.569 100 31 62 0 1416081520 11659920 7329408
00:20:49 14385 8192000000 10317833168 10762764288 1406506560 14.653 100 40 77 2 1426620912 10502688 9611664
00:22:05 29147 8192000000 10312409728 10763976704 1388712112 14.494 99 21 40 2 1411476752 17817104 4947536
00:23:36 8394 8192000000 10309044336 10760409088 1396463824 14.563 100 53 103 3 1417097856 8356272 12277760
00:24:45 71544 8192000000 10298283584 10758717440 1372268496 14.347 98 5 10 0 1385017632 11549760 1199376
00:26:58 59868 8192000000 10285385200 10737094656 1368087568 14.310 98 7 14 0 1383297360 13557648 1652144
00:29:01 45975 8192000000 10298815776 10750885888 1369202176 14.320 98 30 59 0 1396904224 20628496 7073552
00:30:48 68110 8192000000 10309276976 10771918848 1376424560 14.385 98 9 18 0 1395638832 16856816 2357456
00:32:56 37254 8192000000 10256553808 10701598720 1300403936 13.699 94 17 34 0 1325153568 20731392 4018240
00:34:34 64506 8192000000 10279137520 10731438080 1336244496 14.024 97 17 34 0 1353061888 12817680 3999712
00:36:42 60272 8192000000 10283965872 10731098112 1349817072 14.146 99 30 60 0 1373066176 16521824 6727280
00:38:43 35230 8192000000 10276481360 10719191040 1332734080 13.992 97 27 53 0 1351633216 13070032 5829104
00:40:18 74497 8192000000 10258121056 10688344064 1236421456 13.113 91 13 24 0 1249850208 10911968 2516784
00:42:35 68441 8192000000 10275772960 10735656960 1361652416 14.252 100 33 65 0 1386460752 17264016 7544320
00:45:12 49277 8192000000 10268381920 10714775552 1345628608 14.108 99 24 48 0 1360973024 9641936 5702480
00:47:03 56854 8192000000 10245762576 10698924032 1321794128 13.893 98 10 20 0 1330444384 6435504 2214752
00:50:30 8902 8192000000 10271179616 10712698880 1360075616 14.238 100 42 85 0 1376152528 6073808 10003104
00:51:42 74205 8192000000 10257751216 10708070400 1327389744 13.944 97 17 31 1 1340781456 9816720 3574992
00:53:59 52304 8192000000 10257165056 10722992128 1352665136 14.171 99 10 20 1 1363117600 8252112 2200352
00:55:52 12444 8192000000 10281200496 10727366656 1373574816 14.359 100 46 90 1 1391849520 8114736 10159968
00:57:10 31071 8192000000 10282957216 10727288832 1364059776 14.274 99 35 70 0 1382317232 10957440 7300016
00:58:42 45151 8192000000 10278662832 10743955456 1366629792 14.297 99 18 36 1 1386048352 15506112 3912448
01:00:27 66028 8192000000 10276440640 10737942528 1349121136 14.140 98 18 36 0 1370216832 17421808 3673888
01:02:36 50767 8192000000 10297066176 10746699776 1372608832 14.350 99 39 78 0 1393190448 11932032 8649584
01:04:29 68871 8192000000 10299406528 10758258688 1388013232 14.488 100 25 50 0 1408965824 15546608 5405984
01:06:46 83660 8192000000 10301673776 10754584576 1380381248 14.420 99 13 26 0 1396059200 12937168 2740784
01:09:54 45109 8192000000 10301477072 10759426048 1404031072 14.631 100 12 24 1 1412988320 6308432 2648816
01:11:40 59303 8192000000 10296595824 10765189120 1404851952 14.638 100 9 18 0 1416329216 9791408 1685856
01:13:41 49998 8192000000 10269777856 10730680320 1346127952 14.113 96 17 34 0 1360673520 10424352 4121216
01:15:31 41549 8192000000 10286635040 10749984768 1370178976 14.329 98 16 32 0 1381872272 8076432 3616864
01:17:13 57355 8192000000 10283728816 10740019200 1331889696 13.984 95 28 56 0 1349605488 11420336 6295456
01:19:22 85829 8192000000 10285343104 10740170752 1345779024 14.109 97 14 28 0 1363057056 14095024 3183008
01:21:52 47780 8192000000 10287086816 10739228672 1356829904 14.209 98 24 47 0 1375838608 13913632 5095072
01:23:40 67253 8192000000 10256824640 10710544384 1297338752 13.671 95 26 52 0 1318057600 14598304 6120544
01:25:47 77413 8192000000 10267092672 10717876224 1313376384 13.817 98 8 17 0 1327368496 12442928 1549184
01:28:11 127210 8192000000 10276967920 10723459072 1329058176 13.959 97 16 32 0 1345728016 13393536 3276304
01:31:18 88519 8192000000 10255561376 10720579584 1350185584 14.149 99 7 14 0 1365141008 13295312 1660112
01:34:23 75310 8192000000 10242901776 10693267456 1311188208 13.797 96 11 22 0 1324882448 10687840 3006400
01:36:55 57131 8192000000 10251144352 10710151168 1347906032 14.129 99 8 14 1 1356720064 7388240 1425792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-10-2022 01:40:25 to 14-10-2022 01:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2022 01:40:42 to 14-10-2022 01:40:44.

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          249408
MON$OLDEST_ACTIVE               251377
MON$OLDEST_SNAPSHOT             251377
MON$NEXT_TRANSACTION            251377
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                       2036832
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 ---
:317: 
:318: mscs-test-161.budzdorov.ru    Fri Oct 14 01:38:33 2022
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:320: 
:321: 
:322: mscs-test-161.budzdorov.ru    Fri Oct 14 01:38:34 2022
:323:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:324: 
--- end of diff output ---

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

14.10.22 01:40:45. 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 01:40:48 No severe PSQL-related problems occured
14.10.22 01:40:49. End of report.