Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 10:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15923364 kB
MemAvailable 30959556 kB
Buffers 0 kB
Cached 15739888 kB
SwapCached 2604 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2021-08-20 10:37:05
DTS_END                         2021-08-20 10:37:05.3030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(20:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55376
TRN_ID                          243510
ATT_ID                          633
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: 9299.49 20032 1115939
customer order: creation 178.56 379 21427
customer order: refuse 15.23 327 1828
order to supplier: creation 46.74 1332 5609
order to supplier: removal 3.48 103879 417
invoice (draft): creation 47.35 3206 5682
invoice (draft): removal 52.71 981 6325
invoice accept: apply 42.82 16486 5138
invoice accept: cancel 47.30 831 5676
customer reserve: creation 3918.52 162 470222
customer reserve: removal 4645.21 59 557425
realization accept: apply 87.22 190 10466
realization accept: cancel 81.88 108 9825
payment from customer: creation 60.48 132 7258
payment from customer: removal 6.06 70 727
payment to supplier: creation 51.16 134 6139
payment to supplier: removal 3.54 63 425
service: total inventory turnovers 3.87 3388 464
service: total monetary turnovers 6.37 444 764
service: aggregate perf. data 0.98 17595 118
service: refresh index statistics 0.03 250866 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2021 10:38:17 to 20-08-2021 10:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5990
TEST_TIME 2 4031
TEST_TIME 3 3630
TEST_TIME 4 4214
TEST_TIME 5 4553
TEST_TIME 6 4704
TEST_TIME 7 4744
TEST_TIME 8 4738
TEST_TIME 9 5031
TEST_TIME 10 5599
TEST_TIME 11 5942
TEST_TIME 12 5850
TEST_TIME 13 6300
TEST_TIME 14 6534
TEST_TIME 15 7301
TEST_TIME 16 7670
TEST_TIME 17 7456
TEST_TIME 18 7709
TEST_TIME 19 8453
TEST_TIME 20 8615
TEST_TIME 21 8750
TEST_TIME 22 9255
TEST_TIME 23 9459
TEST_TIME 24 9835
TEST_TIME 25 10670
TEST_TIME 26 10499
TEST_TIME 27 11187
TEST_TIME 28 11154
TEST_TIME 29 11023
TEST_TIME 30 10807
TEST_TIME 31 10801
TEST_TIME 32 10806
TEST_TIME 33 10844
TEST_TIME 34 10801
TEST_TIME 35 10746
TEST_TIME 36 10662
TEST_TIME 37 10665
TEST_TIME 38 10736
TEST_TIME 39 10686
TEST_TIME 40 10694
TEST_TIME 41 10656
TEST_TIME 42 10586
TEST_TIME 43 10374
TEST_TIME 44 10293
TEST_TIME 45 10318
TEST_TIME 46 10156
TEST_TIME 47 10249
TEST_TIME 48 10180
TEST_TIME 49 10135
TEST_TIME 50 10203
TEST_TIME 51 10091
TEST_TIME 52 10036
TEST_TIME 53 10034
TEST_TIME 54 10034
TEST_TIME 55 9882
TEST_TIME 56 9889
TEST_TIME 57 9953
TEST_TIME 58 9890
TEST_TIME 59 9889
TEST_TIME 60 9928
TEST_TIME 61 9866
TEST_TIME 62 9878
TEST_TIME 63 9936
TEST_TIME 64 9879
TEST_TIME 65 9963
TEST_TIME 66 10095
TEST_TIME 67 10022
TEST_TIME 68 10041
TEST_TIME 69 10049
TEST_TIME 70 10052
TEST_TIME 71 10169
TEST_TIME 72 10047
TEST_TIME 73 10009
TEST_TIME 74 9914
TEST_TIME 75 9865
TEST_TIME 76 9823
TEST_TIME 77 9844
TEST_TIME 78 9798
TEST_TIME 79 9738
TEST_TIME 80 9654
TEST_TIME 81 9634
TEST_TIME 82 9748
TEST_TIME 83 9691
TEST_TIME 84 9702
TEST_TIME 85 9668
TEST_TIME 86 9649
TEST_TIME 87 9784
TEST_TIME 88 9759
TEST_TIME 89 9697
TEST_TIME 90 9776
TEST_TIME 91 9748
TEST_TIME 92 9812
TEST_TIME 93 9715
TEST_TIME 94 9620
TEST_TIME 95 9672
TEST_TIME 96 9639
TEST_TIME 97 9559
TEST_TIME 98 9531
TEST_TIME 99 9519
TEST_TIME 100 9509
TEST_TIME 101 9538
TEST_TIME 102 9515
TEST_TIME 103 9523
TEST_TIME 104 9506
TEST_TIME 105 9443
TEST_TIME 106 9489
TEST_TIME 107 9494
TEST_TIME 108 9545
TEST_TIME 109 9523
TEST_TIME 110 9560
TEST_TIME 111 9540
TEST_TIME 112 9469
TEST_TIME 113 9435
TEST_TIME 114 9405
TEST_TIME 115 9350
TEST_TIME 116 9310
TEST_TIME 117 9267
TEST_TIME 118 9321
TEST_TIME 119 9354
TEST_TIME 120 9306

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2021 10:38:21 to 20-08-2021 10:38:24.

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 1549989 1549989 0 0.00 0 24005 6 0 0
doc_list_biud 1549987 1549987 0 0.00 0 47409 28 0 0
sp_add_invoice_to_stock 5279 5138 141 2.67 20 155524 16487 0 141
sp_cancel_adding_invoice 5676 5676 0 0.00 5 61636 832 0 0
sp_cancel_client_order 1828 1828 0 0.00 5 12741 328 0 0
sp_cancel_customer_reserve 557425 557425 0 0.00 1 30194 60 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 4135 70 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 1801 64 0 0
sp_cancel_supplier_invoice 6337 6325 12 0.18 2 37390 981 0 12
sp_cancel_supplier_order 417 417 0 0.00 7 1113966 103879 0 0
sp_cancel_write_off 9825 9825 0 0.00 1 10019 108 0 0
sp_client_order 21427 21427 0 0.00 4 24473 380 0 0
sp_customer_reserve 470222 470222 0 0.00 0 46624 162 0 0
sp_fill_shopping_cart 32715 32715 0 0.00 1 12844 40 0 0
sp_fill_shopping_cart_clo_res 470207 470207 0 0.00 0 19894 15 0 0
sp_get_clo_for_invoice 5122 5122 0 0.00 0 34018 376 0 0
sp_kill_cost_storno 16653 16653 0 0.00 0 21558 57 0 0
sp_kill_qstorno_ret_qs2qd 1131417 1131417 0 0.00 0 37365 33 0 0
sp_kill_qty_storno 597083 597083 0 0.00 0 47408 73 0 0
sp_lock_selected_doc 21577 21577 0 0.00 0 9778 6 0 0
sp_make_cost_storno 28984 28984 0 0.00 0 24002 65 0 0
sp_multiply_rows_for_pdistr 28983 28983 0 0.00 0 12242 32 0 0
sp_multiply_rows_for_qdistr 21427 21427 0 0.00 0 24072 204 0 0
sp_pay_from_customer 7258 7258 0 0.00 4 25537 133 0 0
sp_pay_to_supplier 6139 6139 0 0.00 5 20437 134 0 0
sp_payment_common 13396 13396 0 0.00 1 15149 33 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 12734 299 0 0
sp_qd_handle_on_invoice_upd_sts 10797 10797 0 0.00 0 47408 379 0 0
sp_qd_handle_on_reserve_upd_sts 10466 10466 0 0.00 0 19656 66 0 0
sp_reserve_write_off 10466 10466 0 0.00 7 25613 190 0 0
sp_supplier_invoice 5682 5682 0 0.00 6 102243 3206 0 0
sp_supplier_order 5609 5609 0 0.00 6 28193 1332 0 0
srv_aggregate_perf_data 118 118 0 0.00 3011 219813 17596 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2001 170703 53251 0 0
srv_find_qd_qs_mism 1066265 1066265 0 0.00 0 15288 5 0 0
srv_make_invnt_saldo 464 464 0 0.00 50 93931 3389 0 0
srv_make_money_saldo 764 764 0 0.00 1 11098 445 0 0
srv_recalc_idx_stat 4 4 0 0.00 156702 319981 250866 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 5138 5138 0 0.00 0 8484 149 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 12832 168 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 8168 17 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1808 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 711 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 717 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1533 16 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 21945 1641 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 5036 13 0 0
t$perf-norm:sp_client_order 21427 21427 0 0.00 0 17349 17 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 8927 16 0 0
t$perf-norm:sp_pay_from_customer 7258 7258 0 0.00 0 10712 13 0 0
t$perf-norm:sp_pay_to_supplier 6139 6139 0 0.00 0 4483 9 0 0
t$perf-norm:sp_reserve_write_off 10466 10466 0 0.00 0 10919 12 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 7682 15 0 0
t$perf-norm:sp_supplier_order 5609 5609 0 0.00 0 4653 14 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 201 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 1889 37 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 1648 11 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 531 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10520 10238 282 2.68 0 6601 17 0 282
v_all_customers 38720 38720 0 0.00 0 83 1 0 0
v_all_suppliers 31076 31076 0 0.00 0 245 1 0 0
v_all_wares 42848 42848 0 0.00 0 226 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2591 8 0 0
v_cancel_client_order 3656 3656 0 0.00 0 3975 6 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 4113 8 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1112 4 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 1790 5 0 0
v_cancel_supplier_invoice 888 864 24 2.70 0 3422 19 0 24
v_cancel_supplier_order 834 834 0 0.00 0 2591 7 0 0
v_cancel_write_off 2088 2088 0 0.00 0 4845 13 0 0
v_max_id_clo_ord 5607 5607 0 0.00 0 5036 2 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 2368 13 0 0
v_max_id_ord_sup 5682 5682 0 0.00 0 6836 9 0 0
v_max_non_paid_invoice 1890 1890 0 0.00 0 14398 13 0 0
v_max_non_paid_realizn 4022 4022 0 0.00 0 3129 5 0 0
v_min_id_clo_ord 5607 5607 0 0.00 0 5679 6 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 4478 15 0 0
v_min_id_ord_sup 5682 5682 0 0.00 0 6211 9 0 0
v_min_non_paid_invoice 6139 6139 0 0.00 0 6523 16 0 0
v_min_non_paid_realizn 7257 7257 0 0.00 0 12583 23 0 0
v_our_firm 10598 10598 0 0.00 0 12 1 0 0
v_reserve_write_off 20930 20930 0 0.00 0 3961 6 0 0
x_lock_depdocs_on_canc_invoice 11569 11569 0 0.00 0 11966 106 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 7092 280 0 0
x_make_qstorno_1000_1200 5609 5609 0 0.00 2 28186 1256 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 3 90440 3050 0 0
x_make_qstorno_2100_3300 470222 470222 0 0.00 0 46621 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2021 10:38:25 to 20-08-2021 10:38:29.

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
08:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:09 34545 8192000000 9732859808 10031214592 801216480 8.909 100 90 180 1 831346512 8025712 22104320
08:09:50 12465 8192000000 9761465280 10069118976 837342560 9.273 100 89 178 1 872094928 13534080 21218288
08:11:14 19595 8192000000 9810852096 10130575360 868313024 9.583 100 82 164 0 910250064 23132096 18804944
08:12:38 12296 8192000000 9874928128 10209570816 924094496 10.136 100 86 172 2 981518128 39341856 18081776
08:13:57 7610 8192000000 9919211648 10268254208 967327344 10.561 100 71 141 0 1033277792 51328464 14621984
08:15:07 5095 8192000000 9961381888 10325966848 1000123104 10.880 100 73 146 0 1082893888 68106496 14664288
08:16:14 3241 8192000000 9988018848 10357432320 1023446864 11.105 100 70 139 2 1099121872 61597264 14077744
08:17:20 2174 8192000000 10066723232 10434273280 1050416192 11.365 100 86 172 1 1171658752 104054336 17188224
08:18:24 24672 8192000000 10011023536 10391764992 1066007248 11.514 100 67 133 0 1129747408 49182896 14557264
08:19:50 9182 8192000000 9984808400 10376142848 1068534256 11.538 100 33 66 0 1103441760 27457072 7450432
08:21:00 8676 8192000000 9984807184 10392186880 1076213792 11.611 100 26 51 0 1093856720 11436832 6206096
08:22:12 5418 8192000000 10005999744 10413297664 1091092976 11.753 100 44 87 1 1126946320 26223952 9629392
08:23:20 40060 8192000000 10009891024 10411429888 1093081216 11.772 100 41 81 1 1117926144 15116512 9728416
08:25:02 6512 8192000000 10018649680 10423689216 1099811136 11.836 100 28 54 3 1146163184 40362112 5989936
08:26:09 10384 8192000000 10029974640 10437173248 1092730432 11.769 99 44 89 0 1125745584 23289072 9726080
08:27:20 7477 8192000000 9999230400 10396172288 1067034176 11.524 99 50 100 3 1090669312 12821216 10813920
08:28:30 27463 8192000000 9956927312 10349563904 961490928 10.504 89 24 47 2 994716784 28471056 4754800
08:30:00 8719 8192000000 9964588176 10347827200 1021605200 11.088 97 46 91 2 1040349680 8815136 9929344
08:31:11 8872 8192000000 9926269472 10316087296 987406800 10.756 97 32 62 1 1016528048 22318240 6803008
08:32:20 64116 8192000000 9968130768 10369441792 1015478544 11.028 97 26 52 2 1049394352 28172832 5742976
08:34:25 7209 8192000000 9979127088 10380304384 1064860528 11.503 100 29 58 1 1086860800 16146192 5854080
08:35:35 14158 8192000000 9975771232 10378006528 1065621024 11.510 100 19 37 1 1086803504 16724768 4457712
08:36:55 7819 8192000000 9988511296 10390941696 1072454704 11.576 100 38 77 4 1091950112 10658512 8836896
08:38:12 125594 8192000000 10047785264 10458333184 1104340336 11.879 100 36 72 1 1153367488 42063968 6963184
08:41:28 12766 8192000000 10101426528 10515640320 1124805056 12.072 100 41 82 2 1224238720 90990384 8443280
08:42:42 26913 8192000000 10132020208 10545704960 1133853296 12.158 100 36 69 2 1231911552 90908944 7149312
08:44:21 8043 8192000000 10193535024 10604290048 1151881856 12.327 100 57 114 0 1297699264 133203840 12613568
08:45:33 9726 8192000000 10247209744 10654117888 1152699536 12.335 100 61 122 1 1350684672 183791488 14193648
08:46:46 3011 8192000000 10284615824 10688159744 1165104416 12.451 100 71 141 1 1379719216 198017808 16596992
08:47:55 7932 8192000000 10275200272 10683572224 1167748768 12.476 100 62 123 2 1375559232 193736720 14073744
08:49:07 11422 8192000000 10317331264 10733510656 1176009600 12.553 100 71 140 1 1419001680 227415312 15576768
08:50:28 2321 8192000000 10339302800 10746486784 1176459024 12.557 100 86 172 1 1440746240 244648768 19638448
08:51:37 7114 8192000000 10388301888 10805596160 1166528640 12.464 99 71 141 1 1488434816 305720000 16186176
08:52:51 2858 8192000000 10382807696 10786258944 1173393408 12.529 100 90 176 0 1480312896 286289424 20630064
08:53:58 8712 8192000000 10375743120 10813538304 1146281312 12.275 98 35 69 0 1468330304 313388336 8660656
08:55:11 1995 8192000000 10442481568 10847563776 1148922976 12.299 100 89 174 0 1547967680 378446448 20598256
08:56:18 3774 8192000000 10446005248 10853752832 1150122832 12.311 100 79 158 0 1542215696 373352352 18740512
08:57:30 13745 8192000000 10350145536 10776875008 1118936064 12.017 99 33 66 1 1448629680 320941968 8751648
08:59:04 15646 8192000000 10362062368 10810839040 1109112080 11.924 98 35 71 0 1468256256 349601152 9543024
09:00:30 11778 8192000000 10331359728 10764865536 1097998224 11.819 97 30 60 1 1432356016 326591936 7765856
09:01:57 19301 8192000000 10270200032 10781810688 1107383312 11.908 98 18 36 0 1405033040 292351840 5297888
09:03:30 62689 8192000000 10106467504 10615906304 1104453728 11.880 99 31 62 0 1254542256 143148736 6939792
09:05:50 63491 8192000000 10080939168 10556485632 1113055392 11.961 99 25 51 1 1178042624 59691104 5296128
09:08:55 100951 8192000000 10055508768 10504790016 1110033136 11.933 97 10 20 1 1175071728 62522736 2515856
09:11:36 60664 8192000000 10054840176 10515263488 1117535328 12.004 98 9 18 0 1175159008 55623552 2000128
09:13:37 9718 8192000000 10095405888 10519240704 1135534592 12.174 100 39 77 1 1186966736 42819120 8613024
09:14:51 68793 8192000000 10084055456 10574180352 1116317520 11.992 98 25 50 1 1167877936 45961456 5598960
09:17:05 114516 8192000000 10123704656 10591678464 1131740496 12.138 98 11 22 1 1172232480 38346496 2145488
09:20:01 170703 8192000000 10120204272 10572107776 1112147728 11.953 96 35 70 3 1169047824 49335088 7565008
09:25:23 41195 8192000000 10115502528 10556796928 1137098096 12.188 98 29 59 0 1202769280 59217664 6453520
09:27:07 41617 8192000000 10120645120 10591682560 1161959440 12.422 100 31 62 3 1244683488 75559392 7164656
09:28:53 54737 8192000000 10133761712 10592849920 1135808688 12.176 98 14 29 0 1217145520 78519440 2817392
09:30:49 25887 8192000000 10117720224 10657959936 1120616448 12.033 97 8 17 0 1219269744 96244256 2409040
09:32:18 62313 8192000000 10106652400 10583056384 1126919312 12.092 98 40 81 0 1198102656 62427104 8756240
09:34:20 61408 8192000000 10060224448 10563346432 1066150848 11.515 93 6 12 0 1123570944 56283712 1136384
09:36:22 80138 8192000000 10074222880 10531753984 1072417296 11.575 94 23 46 0 1103121744 25520016 5184432
09:38:50 30082 8192000000 10108711536 10564050944 1123210576 12.057 99 31 62 0 1174095984 44873280 6012128
09:40:23 48438 8192000000 10085825072 10540552192 1114688560 11.977 98 20 40 1 1170956656 51841600 4426496
09:42:12 83063 8192000000 10084579392 10581327872 1089556752 11.738 96 18 36 0 1204996240 111456752 3982736
09:44:42 15144 8192000000 10073115904 10501160960 1117327360 12.002 99 38 76 0 1148915536 23811568 7776608
09:45:57 54058 8192000000 10122990320 10604371968 1111733632 11.949 98 30 60 0 1219557664 101724400 6099632
09:48:35 98779 8192000000 10031451808 10463027200 1095569440 11.796 98 17 34 0 1147064592 47435040 4060112
09:51:14 73480 8192000000 10079742768 10541424640 1126075888 12.084 100 22 44 1 1196158608 65496160 4586560
09:53:42 61214 8192000000 10064210896 10553413632 1125065024 12.075 100 17 34 3 1180958608 52394800 3498784
09:55:45 84438 8192000000 10080494352 10534998016 1123828128 12.063 99 16 32 1 1168393536 41349200 3216208
09:58:10 82024 8192000000 10124791184 10591141888 1107480816 11.909 97 19 38 2 1202178400 90423376 4274208
10:00:32 25613 8192000000 10061210784 10512674816 1120572336 12.032 99 20 40 1 1158596784 33726272 4298176
10:02:05 38094 8192000000 10133721248 10583040000 1143840864 12.252 100 32 64 2 1210772480 60171568 6760048
10:03:52 140735 8192000000 10117710256 10567720960 1133739696 12.157 99 10 20 0 1205312736 69512736 2060304
10:07:15 82705 8192000000 10137514720 10579341312 1098671296 11.825 95 29 58 0 1154381744 49905872 5804576
10:09:41 140260 8192000000 10115978960 10582560768 1127726384 12.100 99 18 36 0 1215184048 83503296 3954368
10:13:01 34446 8192000000 10060244432 10526752768 1135661840 12.175 99 10 20 0 1171428064 33173584 2592640
10:14:44 60146 8192000000 10091060832 10567102464 1154768464 12.354 100 9 18 0 1215724976 58927840 2028672
10:16:46 103291 8192000000 10076964000 10559991808 1098275760 11.821 95 15 29 1 1141124528 40265536 2583232
10:19:30 43283 8192000000 10071043168 10521522176 1113164112 11.962 97 16 30 0 1141644752 25428880 3051760
10:21:17 60768 8192000000 10081609632 10524770304 1105521936 11.890 97 23 46 1 1150743552 39907744 5313872
10:23:27 79611 8192000000 10096117936 10570989568 1099773872 11.835 96 20 40 0 1170322560 66435392 4113296
10:25:57 59124 8192000000 10072341888 10508423168 1091417680 11.756 96 9 18 0 1124596128 31511696 1666752
10:28:26 111216 8192000000 10044748000 10484412416 1093627328 11.777 97 36 71 5 1121431152 20349536 7454288
10:31:19 72078 8192000000 10075015296 10525904896 1115441072 11.984 98 17 35 1 1164189072 44872608 3875392
10:33:38 78175 8192000000 10157088144 10629758976 1120068992 12.028 98 12 23 2 1216971120 94034784 2867344
10:35:56 69992 8192000000 9585823520 9993449472 649154640 7.342 58 8 16 0 708655680 57905568 1595472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-08-2021 10:38:31 to 20-08-2021 10:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 20-08-2021 10:38:47 to 20-08-2021 10:38:49.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242798
MON$OLDEST_ACTIVE               243704
MON$OLDEST_SNAPSHOT             243704
MON$NEXT_TRANSACTION            243704
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-30 07:30:08.6030 GMT
MON$PAGES                       2070944
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Aug 20 10:37:14 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

20.08.21 10:38:50. There are 3 logs in /var/tmp/logs-oltp40 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
2021-08-20 10:38:52 No severe PSQL-related problems occured
20.08.21 10:38:53. End of report.