Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.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 16572060 kB
MemAvailable 31569220 kB
Buffers 0 kB
Cached 15135108 kB
SwapCached 2264 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.10
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-04-06 01:38:12
DTS_END                         2022-04-06 01:38:12.4530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33772
TRN_ID                          252553
ATT_ID                          660
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: 9405.60 20294 1128672
customer order: creation 184.96 334 22195
customer order: refuse 15.78 391 1893
order to supplier: creation 49.50 1185 5940
order to supplier: removal 3.38 91880 406
invoice (draft): creation 48.08 3167 5769
invoice (draft): removal 51.38 731 6165
invoice accept: apply 44.97 17510 5396
invoice accept: cancel 46.89 808 5627
customer reserve: creation 4073.71 177 488845
customer reserve: removal 4572.70 53 548724
realization accept: apply 90.02 206 10802
realization accept: cancel 84.94 104 10193
payment from customer: creation 63.88 121 7666
payment from customer: removal 6.01 57 721
payment to supplier: creation 52.53 126 6303
payment to supplier: removal 4.10 53 492
service: total inventory turnovers 5.13 1105 615
service: total monetary turnovers 6.61 27 793
service: aggregate perf. data 1.03 15865 123
service: refresh index statistics 0.03 271976 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-04-2022 01:39:37 to 06-04-2022 01:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3642
TEST_TIME 2 4539
TEST_TIME 3 4907
TEST_TIME 4 4365
TEST_TIME 5 4709
TEST_TIME 6 5053
TEST_TIME 7 5341
TEST_TIME 8 5761
TEST_TIME 9 5756
TEST_TIME 10 5841
TEST_TIME 11 6224
TEST_TIME 12 6536
TEST_TIME 13 6516
TEST_TIME 14 6745
TEST_TIME 15 7608
TEST_TIME 16 7802
TEST_TIME 17 7819
TEST_TIME 18 8017
TEST_TIME 19 8140
TEST_TIME 20 8534
TEST_TIME 21 8888
TEST_TIME 22 9024
TEST_TIME 23 9118
TEST_TIME 24 9354
TEST_TIME 25 9358
TEST_TIME 26 9467
TEST_TIME 27 9605
TEST_TIME 28 9701
TEST_TIME 29 10252
TEST_TIME 30 10615
TEST_TIME 31 10511
TEST_TIME 32 10363
TEST_TIME 33 10271
TEST_TIME 34 10369
TEST_TIME 35 10265
TEST_TIME 36 10295
TEST_TIME 37 10326
TEST_TIME 38 10264
TEST_TIME 39 10329
TEST_TIME 40 10176
TEST_TIME 41 10012
TEST_TIME 42 9894
TEST_TIME 43 9889
TEST_TIME 44 9875
TEST_TIME 45 9792
TEST_TIME 46 9786
TEST_TIME 47 9701
TEST_TIME 48 9763
TEST_TIME 49 9754
TEST_TIME 50 9796
TEST_TIME 51 9698
TEST_TIME 52 9734
TEST_TIME 53 9669
TEST_TIME 54 9653
TEST_TIME 55 9665
TEST_TIME 56 9729
TEST_TIME 57 9743
TEST_TIME 58 9745
TEST_TIME 59 9775
TEST_TIME 60 9879
TEST_TIME 61 9795
TEST_TIME 62 9947
TEST_TIME 63 10026
TEST_TIME 64 9959
TEST_TIME 65 9990
TEST_TIME 66 9971
TEST_TIME 67 9985
TEST_TIME 68 10015
TEST_TIME 69 9946
TEST_TIME 70 9830
TEST_TIME 71 9811
TEST_TIME 72 9732
TEST_TIME 73 9796
TEST_TIME 74 9754
TEST_TIME 75 9717
TEST_TIME 76 9721
TEST_TIME 77 9686
TEST_TIME 78 9621
TEST_TIME 79 9546
TEST_TIME 80 9537
TEST_TIME 81 9552
TEST_TIME 82 9508
TEST_TIME 83 9504
TEST_TIME 84 9545
TEST_TIME 85 9559
TEST_TIME 86 9595
TEST_TIME 87 9590
TEST_TIME 88 9573
TEST_TIME 89 9682
TEST_TIME 90 9667
TEST_TIME 91 9667
TEST_TIME 92 9785
TEST_TIME 93 9821
TEST_TIME 94 9775
TEST_TIME 95 9705
TEST_TIME 96 9690
TEST_TIME 97 9626
TEST_TIME 98 9599
TEST_TIME 99 9627
TEST_TIME 100 9612
TEST_TIME 101 9559
TEST_TIME 102 9517
TEST_TIME 103 9523
TEST_TIME 104 9445
TEST_TIME 105 9478
TEST_TIME 106 9484
TEST_TIME 107 9493
TEST_TIME 108 9500
TEST_TIME 109 9514
TEST_TIME 110 9492
TEST_TIME 111 9487
TEST_TIME 112 9493
TEST_TIME 113 9534
TEST_TIME 114 9519
TEST_TIME 115 9512
TEST_TIME 116 9526
TEST_TIME 117 9530
TEST_TIME 118 9546
TEST_TIME 119 9486
TEST_TIME 120 9407

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-04-2022 01:39:41 to 06-04-2022 01:39:45.

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 1583033 1583033 0 0.00 0 32712 6 0 0
doc_list_biud 1583032 1583032 0 0.00 0 38189 24 0 0
sp_add_invoice_to_stock 5538 5396 142 2.56 19 172972 17510 0 142
sp_cancel_adding_invoice 5627 5627 0 0.00 4 72548 808 0 0
sp_cancel_client_order 1893 1893 0 0.00 4 17991 391 0 0
sp_cancel_customer_reserve 548724 548724 0 0.00 1 38191 54 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 3 1844 57 0 0
sp_cancel_pay_to_supplier 492 492 0 0.00 3 4216 54 0 0
sp_cancel_supplier_invoice 6177 6165 12 0.19 2 17602 731 0 12
sp_cancel_supplier_order 406 406 0 0.00 5 895129 91880 0 0
sp_cancel_write_off 10193 10193 0 0.00 1 14265 105 0 0
sp_client_order 22195 22195 0 0.00 3 20476 335 0 0
sp_customer_reserve 488845 488845 0 0.00 0 43697 178 0 0
sp_fill_shopping_cart 33898 33898 0 0.00 1 13417 38 0 0
sp_fill_shopping_cart_clo_res 488841 488841 0 0.00 0 21968 12 0 0
sp_get_clo_for_invoice 5392 5392 0 0.00 0 20969 219 0 0
sp_kill_cost_storno 17033 17033 0 0.00 0 11171 52 0 0
sp_kill_qstorno_ret_qs2qd 1114212 1114212 0 0.00 0 38186 29 0 0
sp_kill_qty_storno 589202 589202 0 0.00 0 38189 64 0 0
sp_lock_selected_doc 22353 22353 0 0.00 0 9910 7 0 0
sp_make_cost_storno 30163 30163 0 0.00 0 32551 58 0 0
sp_multiply_rows_for_pdistr 30163 30163 0 0.00 0 14003 33 0 0
sp_multiply_rows_for_qdistr 22195 22195 0 0.00 0 11365 160 0 0
sp_pay_from_customer 7666 7666 0 0.00 4 20162 121 0 0
sp_pay_to_supplier 6303 6303 0 0.00 5 18642 126 0 0
sp_payment_common 13969 13969 0 0.00 1 12716 30 0 0
sp_qd_handle_on_cancel_clo 1893 1893 0 0.00 0 17985 347 0 0
sp_qd_handle_on_invoice_upd_sts 11019 11019 0 0.00 0 25614 302 0 0
sp_qd_handle_on_reserve_upd_sts 10802 10802 0 0.00 0 21718 60 0 0
sp_reserve_write_off 10802 10802 0 0.00 6 28908 207 0 0
sp_supplier_invoice 5769 5769 0 0.00 6 73964 3168 0 0
sp_supplier_order 5940 5940 0 0.00 5 33992 1185 0 0
srv_aggregate_perf_data 123 123 0 0.00 2810 282620 15866 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 3466 136339 45164 0 0
srv_find_qd_qs_mism 1078531 1078531 0 0.00 0 11499 6 0 0
srv_make_invnt_saldo 615 615 0 0.00 41 39373 1105 0 0
srv_make_money_saldo 793 793 0 0.00 0 2464 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 208939 355795 271977 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5396 5396 0 0.00 0 9111 147 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 1 4673 92 0 0
t$perf-norm:sp_cancel_client_order 1893 1893 0 0.00 0 3972 18 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 3594 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 2103 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 492 492 0 0.00 0 41 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 4840 23 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 25057 1676 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2675 8 0 0
t$perf-norm:sp_client_order 22195 22195 0 0.00 0 9117 16 0 0
t$perf-norm:sp_customer_reserve 1118 1118 0 0.00 0 1932 5 0 0
t$perf-norm:sp_pay_from_customer 7666 7666 0 0.00 0 3251 7 0 0
t$perf-norm:sp_pay_to_supplier 6303 6303 0 0.00 0 2813 8 0 0
t$perf-norm:sp_reserve_write_off 10802 10802 0 0.00 0 6745 11 0 0
t$perf-norm:sp_supplier_invoice 5769 5769 0 0.00 0 4762 17 0 0
t$perf-norm:sp_supplier_order 5940 5940 0 0.00 0 4876 16 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 755 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 64 2 0 0
t$perf-norm:srv_make_invnt_saldo 615 615 0 0.00 0 1185 17 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 1823 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 11068 10784 284 2.56 0 15103 23 0 284
v_all_customers 40454 40454 0 0.00 0 15 0 0 0
v_all_suppliers 32140 32140 0 0.00 0 26 1 0 0
v_all_wares 44390 44390 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 3780 11 0 0
v_cancel_client_order 3786 3786 0 0.00 0 16913 16 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 1753 7 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 3748 9 0 0
v_cancel_payment_to_supplier 984 984 0 0.00 0 894 4 0 0
v_cancel_supplier_invoice 892 868 24 2.69 0 14634 32 0 24
v_cancel_supplier_order 812 812 0 0.00 0 2704 10 0 0
v_cancel_write_off 2014 2014 0 0.00 0 8993 15 0 0
v_max_id_clo_ord 5940 5940 0 0.00 0 2684 3 0 0
v_max_id_clo_res 1118 1118 0 0.00 0 8584 22 0 0
v_max_id_ord_sup 5763 5763 0 0.00 0 2503 3 0 0
v_max_non_paid_invoice 1936 1936 0 0.00 0 2238 5 0 0
v_max_non_paid_realizn 4095 4095 0 0.00 0 3033 4 0 0
v_min_id_clo_ord 5940 5940 0 0.00 0 6808 10 0 0
v_min_id_clo_res 1118 1118 0 0.00 0 2633 6 0 0
v_min_id_ord_sup 5763 5763 0 0.00 0 6605 13 0 0
v_min_non_paid_invoice 6303 6303 0 0.00 0 11184 20 0 0
v_min_non_paid_realizn 7666 7666 0 0.00 0 12707 22 0 0
v_our_firm 11078 11078 0 0.00 0 21 1 0 0
v_reserve_write_off 21604 21604 0 0.00 0 15113 10 0 0
x_lock_depdocs_on_canc_invoice 11358 11358 0 0.00 0 17494 116 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 25105 369 0 0
x_make_qstorno_1000_1200 5940 5940 0 0.00 1 33985 1100 0 0
x_make_qstorno_1200_2000 5769 5769 0 0.00 2 67351 3034 0 0
x_make_qstorno_2100_3300 488845 488845 0 0.00 0 43695 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2022 01:39:46 to 06-04-2022 01:39:50.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:16 15938 8192000000 9916965408 10226872320 1002613200 10.904 100 92 184 0 1031867552 5879088 23375264
23:10:34 17826 8192000000 9944370512 10263769088 1027387216 11.143 100 89 178 0 1058268048 8002000 22878832
23:11:58 21974 8192000000 9977198496 10311073792 1059847760 11.455 100 77 154 2 1088165744 8945616 19372368
23:13:22 14850 8192000000 10033697792 10381643776 1121037056 12.037 100 80 160 1 1150182176 10075056 19070064
23:14:42 10196 8192000000 10076913648 10432835584 1160947104 12.412 100 84 168 0 1193536496 12958384 19631008
23:15:58 7590 8192000000 10113711376 10479812608 1200509536 12.781 100 81 162 2 1233837056 14773232 18554288
23:17:08 2283 8192000000 10148985248 10522259456 1237996432 13.128 100 86 171 0 1270108816 13160464 18951920
23:18:11 2450 8192000000 10181706320 10559426560 1265091664 13.377 100 90 179 3 1298980048 14040256 19848128
23:19:15 1430 8192000000 10209246400 10593759232 1297276720 13.670 100 92 185 0 1330397440 11820864 21299856
23:20:17 14104 8192000000 10203978192 10609098752 1306270336 13.752 100 42 85 0 1323707040 8704928 8731776
23:21:32 8532 8192000000 10196437584 10613149696 1317068240 13.850 100 21 42 0 1327359472 5643296 4647936
23:22:43 14304 8192000000 10206094160 10627280896 1323232208 13.906 100 17 34 1 1333020624 6011632 3776784
23:24:00 15008 8192000000 10218557232 10637721600 1329166464 13.960 100 22 43 2 1343975232 9338784 5469984
23:25:18 31855 8192000000 10221377856 10641625088 1329555568 13.963 100 33 62 3 1342641792 6169840 6916384
23:26:50 2878 8192000000 10227276016 10638262272 1328501792 13.954 100 50 99 1 1347831328 7333152 11996384
23:27:53 7678 8192000000 10201157536 10621251584 1298709328 13.684 98 11 22 0 1306889392 5344784 2835280
23:29:02 12456 8192000000 10180418304 10584285184 1266670768 13.391 98 18 35 0 1276540336 5564064 4305504
23:30:15 28727 8192000000 10159481760 10563371008 1200508800 12.781 93 24 48 0 1211268448 5513904 5245744
23:31:46 4744 8192000000 10133524528 10528116736 1203421008 12.808 96 27 54 1 1215981120 6028112 6532000
23:32:52 10395 8192000000 10159380368 10568237056 1261038864 13.340 99 23 46 0 1272076656 5396352 5641440
23:34:03 51387 8192000000 10183892864 10586451968 1282487616 13.536 100 26 53 1 1293590720 4820880 6282224
23:35:55 8390 8192000000 10192331120 10599329792 1297401280 13.672 100 33 66 1 1310648704 5904384 7343040
23:37:06 7440 8192000000 10205704800 10620936192 1308080560 13.769 100 25 48 2 1323876832 9984512 5811760
23:38:19 19457 8192000000 10225621760 10638979072 1329986544 13.967 100 32 65 0 1343329280 5320016 8022720
23:39:48 42864 8192000000 10247696944 10671931392 1352236720 14.168 100 36 70 3 1368416512 7926848 8252944
23:42:08 20995 8192000000 10284965600 10703646720 1370452496 14.331 100 52 104 1 1392483952 10129216 11902240
23:43:54 22336 8192000000 10293455840 10717384704 1382822064 14.442 100 54 108 0 1409149824 13933504 12394256
23:45:22 14519 8192000000 10295301136 10719404032 1372415696 14.349 99 38 76 1 1395657424 14014048 9227680
23:46:57 12139 8192000000 10319112832 10747478016 1397435728 14.572 100 47 94 0 1431638496 22885216 11317552
23:48:15 5048 8192000000 10330621136 10749038592 1400919328 14.603 100 63 126 1 1439219056 23800848 14498880
23:49:26 4680 8192000000 10336482688 10752045056 1399102048 14.587 100 70 139 1 1443489888 27607008 16780832
23:50:35 10572 8192000000 10324183808 10737815552 1387484992 14.483 100 71 141 1 1433481184 28133472 17862720
23:51:51 5265 8192000000 10323201280 10727821312 1376449584 14.385 100 74 146 1 1433856176 39396160 18010432
23:53:02 8056 8192000000 10329424096 10746904576 1381665232 14.431 100 62 125 2 1435346720 37752704 15928784
23:54:17 3524 8192000000 10327374800 10738184192 1381916064 14.434 100 75 147 1 1432137824 32515008 17706752
23:55:29 3463 8192000000 10335402016 10748780544 1379732384 14.414 99 67 134 0 1429372896 33327296 16313216
23:56:40 7549 8192000000 10329292896 10751524864 1379274208 14.410 99 51 101 1 1427061760 34898784 12888768
23:57:54 6424 8192000000 10333530624 10753257472 1374958816 14.371 99 54 109 2 1428117168 39333888 13824464
23:59:04 16956 8192000000 10314072672 10753077248 1364836352 14.281 99 30 60 4 1412735040 39795344 8103344
00:00:23 18405 8192000000 10313511232 10742329344 1364672192 14.279 98 29 58 3 1410801472 38264208 7865072
00:01:49 3484 8192000000 10307790544 10730221568 1374139968 14.364 100 41 79 4 1416613152 32258880 10214304
00:03:00 24287 8192000000 10293508352 10721509376 1357986000 14.219 99 32 63 0 1392554704 26531120 8037584
00:04:26 15561 8192000000 10309408160 10744705024 1359801984 14.236 99 33 64 2 1408625040 40032400 8790656
00:06:08 21556 8192000000 10293354336 10731495424 1348190592 14.131 99 23 44 0 1390485792 36206064 6089136
00:07:44 44517 8192000000 10274824912 10722562048 1321854224 13.893 97 18 36 0 1357358160 31126368 4377568
00:09:39 64569 8192000000 10295655936 10719543296 1358337792 14.222 99 28 55 0 1374654544 10597392 5719360
00:11:47 118269 8192000000 10286720192 10728984576 1363291152 14.267 99 20 40 0 1379173024 11572864 4309008
00:14:46 63320 8192000000 10296084592 10743767040 1373896688 14.362 99 11 22 0 1388599328 12236112 2466528
00:17:01 49079 8192000000 10302206192 10745094144 1378529760 14.403 99 23 47 0 1396782816 12938288 5314768
00:18:50 86272 8192000000 10312687904 10756964352 1385895792 14.469 99 24 48 1 1401867968 10547152 5425024
00:21:17 38222 8192000000 10329513072 10768359424 1392914016 14.532 99 51 103 3 1412673504 8416080 11343408
00:22:57 37134 8192000000 10286229488 10742525952 1381493072 14.430 100 12 24 0 1393168960 8821328 2854560
00:24:36 32964 8192000000 10316846112 10761093120 1399583952 14.591 100 39 78 0 1421235648 12427856 9223840
00:26:39 47150 8192000000 10301461280 10759507968 1360543536 14.242 97 18 36 1 1376017120 11129008 4344576
00:28:27 64092 8192000000 10292827840 10761039872 1388094752 14.489 100 9 18 0 1404572672 14080560 2397360
00:30:31 34570 8192000000 10291331872 10757066752 1379928160 14.416 99 9 18 0 1391388288 9199520 2260608
00:32:06 54675 8192000000 10283870464 10740830208 1353797728 14.182 97 26 52 1 1369271440 9622032 5851680
00:34:09 61477 8192000000 10283192048 10753163264 1338698608 14.046 97 8 16 0 1354648720 14548272 1401840
00:36:17 44638 8192000000 10287153184 10743144448 1343371504 14.088 97 27 54 0 1365783632 15914352 6497776
00:38:02 105972 8192000000 10300776400 10748428288 1335950048 14.021 96 21 41 1 1363704592 23329968 4424576
00:40:57 68527 8192000000 10300578368 10758553600 1376896976 14.389 99 14 29 1 1394338032 14723728 2717328
00:43:09 36819 8192000000 10303506752 10758131712 1377130080 14.391 99 25 50 0 1392985888 10581216 5274592
00:44:49 50686 8192000000 10295872144 10737684480 1326543392 13.936 96 45 90 1 1350210416 12231088 11435936
00:46:44 40650 8192000000 10256495904 10700083200 1304679344 13.738 96 24 48 1 1321870720 11881904 5309472
00:48:24 60283 8192000000 10257416032 10700120064 1315345328 13.835 97 27 54 0 1327534112 6224992 5963792
00:50:27 70709 8192000000 10260593968 10720739328 1323153248 13.905 97 23 46 0 1346489344 17947840 5388256
00:53:05 70023 8192000000 10249137280 10702295040 1318020448 13.859 97 10 20 1 1328989200 8633680 2335072
00:55:16 53346 8192000000 10262969200 10715340800 1350070944 14.148 99 28 55 3 1364685632 7842208 6772480
00:57:10 79218 8192000000 10271349920 10735251456 1359722896 14.235 99 10 20 0 1374578128 12481408 2373824
00:59:33 42929 8192000000 10265304336 10716274688 1344853104 14.101 98 12 25 0 1354607328 7067616 2686608
01:01:22 79834 8192000000 10293759024 10757484544 1384150976 14.454 100 22 44 0 1405203712 15560832 5491904
01:03:42 41885 8192000000 10283324656 10736848896 1357178704 14.212 98 20 40 1 1371805104 10102528 4523872
01:05:26 57341 8192000000 10299706224 10749235200 1359276704 14.231 98 35 70 1 1384486640 17767344 7442592
01:07:49 85484 8192000000 10304820960 10756878336 1366219344 14.293 98 10 20 0 1378893424 10766016 1908064
01:10:15 136336 8192000000 10322413456 10763882496 1362755056 14.262 97 18 36 0 1386467840 20094736 3618048
01:13:36 102177 8192000000 10288294688 10751860736 1342455056 14.080 96 22 44 0 1356493872 9248720 4790096
01:16:19 47759 8192000000 10302583984 10773450752 1403676464 14.628 100 21 42 1 1421554496 12789984 5088048
01:18:20 31258 8192000000 10295968256 10755272704 1394173344 14.543 100 18 35 0 1406219408 7869760 4176304
01:19:52 73739 8192000000 10285196752 10756198400 1384126672 14.453 99 17 34 1 1398080880 9627280 4326928
01:22:22 82361 8192000000 10290508464 10770010112 1392373856 14.527 99 11 22 0 1404116848 8912432 2830560
01:24:44 82743 8192000000 10284536944 10752204800 1332451600 13.989 95 21 42 1 1351028224 13347456 5229168
01:27:08 38357 8192000000 10299260848 10742669312 1359844192 14.236 98 29 58 1 1376112000 9637520 6630288
01:28:47 15757 8192000000 10277271072 10726809600 1354667136 14.189 98 38 75 1 1372414640 8349024 9398480
01:30:03 57859 8192000000 10265868816 10717769728 1322672592 13.901 96 16 32 0 1335174752 8924384 3577776
01:32:06 67130 8192000000 10284439520 10733568000 1364480064 14.278 100 12 23 1 1376406992 9483792 2443136
01:34:19 16302 8192000000 10302420016 10741104640 1376069872 14.381 100 48 96 0 1399940016 13559456 10310688
01:35:35 31830 8192000000 10276642928 10723934208 1339274000 14.051 98 25 48 0 1360421328 16138688 5008640
01:37:08 80205 8192000000 8953521568 9136185344 128711600 1.546 10 5 7 1 130700544 931616 1057328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-04-2022 01:39:52 to 06-04-2022 01:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-04-2022 01:40:07 to 06-04-2022 01:40:09.

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          252843
MON$OLDEST_ACTIVE               252844
MON$OLDEST_SNAPSHOT             252844
MON$NEXT_TRANSACTION            252844
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                       2043232
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33575 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33575 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33575 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33575 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Wed Apr  6 01:38:32 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
--- end of diff output ---

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

06.04.22 01:40:10. 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-04-06 01:40:13 No severe PSQL-related problems occured
06.04.22 01:40:14. End of report.