Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2021 10:38
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 15732592 kB
MemAvailable 30726148 kB
Buffers 0 kB
Cached 15997112 kB
SwapCached 3872 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-10-12 10:37:07
DTS_END                         2021-10-12 10:37:07.1530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38542
TRN_ID                          241719
ATT_ID                          628
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: 9378.47 21129 1125416
customer order: creation 174.88 399 20985
customer order: refuse 14.70 314 1764
order to supplier: creation 46.21 1435 5545
order to supplier: removal 3.47 111991 416
invoice (draft): creation 46.93 3401 5631
invoice (draft): removal 53.06 872 6367
invoice accept: apply 42.55 17891 5106
invoice accept: cancel 47.67 819 5720
customer reserve: creation 3907.32 177 468878
customer reserve: removal 4742.68 65 569121
realization accept: apply 85.01 183 10201
realization accept: cancel 83.39 115 10007
payment from customer: creation 60.91 113 7309
payment from customer: removal 5.74 95 689
payment to supplier: creation 49.86 123 5983
payment to supplier: removal 3.50 70 420
service: total inventory turnovers 4.02 3552 482
service: total monetary turnovers 5.68 692 681
service: aggregate perf. data 0.89 18415 107
service: refresh index statistics 0.03 261865 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2021 10:38:42 to 12-10-2021 10:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9260
TEST_TIME 2 8019
TEST_TIME 3 7321
TEST_TIME 4 7154
TEST_TIME 5 7331
TEST_TIME 6 7178
TEST_TIME 7 7139
TEST_TIME 8 7089
TEST_TIME 9 7360
TEST_TIME 10 7557
TEST_TIME 11 7456
TEST_TIME 12 7845
TEST_TIME 13 7589
TEST_TIME 14 7722
TEST_TIME 15 7719
TEST_TIME 16 7707
TEST_TIME 17 8193
TEST_TIME 18 8388
TEST_TIME 19 8386
TEST_TIME 20 8436
TEST_TIME 21 9146
TEST_TIME 22 9585
TEST_TIME 23 9542
TEST_TIME 24 9576
TEST_TIME 25 10004
TEST_TIME 26 10137
TEST_TIME 27 10963
TEST_TIME 28 11729
TEST_TIME 29 12015
TEST_TIME 30 11820
TEST_TIME 31 11772
TEST_TIME 32 11563
TEST_TIME 33 11680
TEST_TIME 34 11636
TEST_TIME 35 11615
TEST_TIME 36 11328
TEST_TIME 37 11119
TEST_TIME 38 11025
TEST_TIME 39 10935
TEST_TIME 40 10819
TEST_TIME 41 10765
TEST_TIME 42 10720
TEST_TIME 43 10632
TEST_TIME 44 10804
TEST_TIME 45 10733
TEST_TIME 46 10666
TEST_TIME 47 10552
TEST_TIME 48 10540
TEST_TIME 49 10454
TEST_TIME 50 10309
TEST_TIME 51 10334
TEST_TIME 52 10249
TEST_TIME 53 10203
TEST_TIME 54 10087
TEST_TIME 55 10133
TEST_TIME 56 10091
TEST_TIME 57 10035
TEST_TIME 58 10065
TEST_TIME 59 10070
TEST_TIME 60 10160
TEST_TIME 61 10110
TEST_TIME 62 10131
TEST_TIME 63 10185
TEST_TIME 64 10190
TEST_TIME 65 10202
TEST_TIME 66 10212
TEST_TIME 67 10180
TEST_TIME 68 10141
TEST_TIME 69 10018
TEST_TIME 70 10003
TEST_TIME 71 9973
TEST_TIME 72 9962
TEST_TIME 73 9887
TEST_TIME 74 9810
TEST_TIME 75 9815
TEST_TIME 76 9828
TEST_TIME 77 9767
TEST_TIME 78 9769
TEST_TIME 79 9819
TEST_TIME 80 9773
TEST_TIME 81 9808
TEST_TIME 82 9770
TEST_TIME 83 9856
TEST_TIME 84 9802
TEST_TIME 85 9828
TEST_TIME 86 9862
TEST_TIME 87 9886
TEST_TIME 88 9848
TEST_TIME 89 9743
TEST_TIME 90 9710
TEST_TIME 91 9683
TEST_TIME 92 9655
TEST_TIME 93 9618
TEST_TIME 94 9617
TEST_TIME 95 9626
TEST_TIME 96 9585
TEST_TIME 97 9575
TEST_TIME 98 9674
TEST_TIME 99 9698
TEST_TIME 100 9689
TEST_TIME 101 9686
TEST_TIME 102 9663
TEST_TIME 103 9675
TEST_TIME 104 9693
TEST_TIME 105 9726
TEST_TIME 106 9733
TEST_TIME 107 9698
TEST_TIME 108 9617
TEST_TIME 109 9618
TEST_TIME 110 9606
TEST_TIME 111 9573
TEST_TIME 112 9515
TEST_TIME 113 9511
TEST_TIME 114 9490
TEST_TIME 115 9449
TEST_TIME 116 9459
TEST_TIME 117 9444
TEST_TIME 118 9464
TEST_TIME 119 9459
TEST_TIME 120 9382

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2021 10:38:46 to 12-10-2021 10:38:49.

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 1560098 1560098 0 0.00 0 28140 7 0 0
doc_list_biud 1560098 1560098 0 0.00 0 27963 30 0 0
sp_add_invoice_to_stock 5267 5106 161 3.05 19 165377 17892 0 161
sp_cancel_adding_invoice 5720 5720 0 0.00 5 70803 819 0 0
sp_cancel_client_order 1764 1764 0 0.00 4 12247 315 0 0
sp_cancel_customer_reserve 569121 569121 0 0.00 1 22951 65 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 6736 96 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 5578 71 0 0
sp_cancel_supplier_invoice 6379 6367 12 0.18 2 17092 873 0 12
sp_cancel_supplier_order 416 416 0 0.00 12 1111102 111991 0 0
sp_cancel_write_off 10007 10007 0 0.00 1 13250 115 0 0
sp_client_order 20985 20985 0 0.00 4 24646 399 0 0
sp_customer_reserve 468878 468878 0 0.00 0 43895 177 0 0
sp_fill_shopping_cart 32146 32146 0 0.00 1 18772 43 0 0
sp_fill_shopping_cart_clo_res 468872 468872 0 0.00 0 18736 15 0 0
sp_get_clo_for_invoice 5100 5100 0 0.00 0 23812 352 0 0
sp_kill_cost_storno 16836 16836 0 0.00 0 6005 57 0 0
sp_kill_qstorno_ret_qs2qd 1155032 1155032 0 0.00 0 22934 35 0 0
sp_kill_qty_storno 608696 608696 0 0.00 0 27961 75 0 0
sp_lock_selected_doc 21124 21124 0 0.00 0 7713 7 0 0
sp_make_cost_storno 28593 28593 0 0.00 0 15995 59 0 0
sp_multiply_rows_for_pdistr 28593 28593 0 0.00 0 9352 30 0 0
sp_multiply_rows_for_qdistr 20985 20985 0 0.00 0 13694 197 0 0
sp_pay_from_customer 7309 7309 0 0.00 4 16572 113 0 0
sp_pay_to_supplier 5983 5983 0 0.00 5 13559 123 0 0
sp_payment_common 13292 13292 0 0.00 1 8318 23 0 0
sp_qd_handle_on_cancel_clo 1764 1764 0 0.00 0 12238 285 0 0
sp_qd_handle_on_invoice_upd_sts 10820 10820 0 0.00 0 27961 330 0 0
sp_qd_handle_on_reserve_upd_sts 10201 10201 0 0.00 0 11542 50 0 0
sp_reserve_write_off 10201 10201 0 0.00 7 20148 183 0 0
sp_supplier_invoice 5631 5631 0 0.00 8 85861 3402 0 0
sp_supplier_order 5545 5545 0 0.00 5 42354 1436 0 0
srv_aggregate_perf_data 107 107 0 0.00 2761 124188 18415 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 871 133216 43361 0 0
srv_find_qd_qs_mism 1077206 1077206 0 0.00 0 28138 6 0 0
srv_make_invnt_saldo 482 482 0 0.00 38 101351 3552 0 0
srv_make_money_saldo 681 681 0 0.00 1 32468 692 0 0
srv_recalc_idx_stat 4 4 0 0.00 125739 339548 261866 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5106 5106 0 0.00 0 17632 175 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 33015 201 0 0
t$perf-norm:sp_cancel_client_order 1764 1764 0 0.00 0 3704 15 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 412 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 3966 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 229 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 2030 19 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 18981 1655 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 6729 16 0 0
t$perf-norm:sp_client_order 20985 20985 0 0.00 0 16542 20 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 2286 7 0 0
t$perf-norm:sp_pay_from_customer 7309 7309 0 0.00 0 31590 11 0 0
t$perf-norm:sp_pay_to_supplier 5983 5983 0 0.00 0 7828 9 0 0
t$perf-norm:sp_reserve_write_off 10201 10201 0 0.00 0 14338 14 0 0
t$perf-norm:sp_supplier_invoice 5631 5631 0 0.00 0 13716 16 0 0
t$perf-norm:sp_supplier_order 5545 5545 0 0.00 0 5873 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 179 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 179 6 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 1881 14 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 643 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10522 10200 322 3.06 0 7024 19 0 322
v_all_customers 38618 38618 0 0.00 0 119 1 0 0
v_all_suppliers 31034 31034 0 0.00 0 120 1 0 0
v_all_wares 41970 41970 0 0.00 0 125 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 549 6 0 0
v_cancel_client_order 3528 3528 0 0.00 0 5782 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 506 4 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 3495 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 5487 10 0 0
v_cancel_supplier_invoice 890 866 24 2.69 0 5774 38 0 24
v_cancel_supplier_order 832 832 0 0.00 0 4327 15 0 0
v_cancel_write_off 2008 2008 0 0.00 0 5661 18 0 0
v_max_id_clo_ord 5541 5541 0 0.00 0 937 2 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 4950 16 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 6977 8 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 3329 5 0 0
v_max_non_paid_realizn 3834 3834 0 0.00 0 1804 3 0 0
v_min_id_clo_ord 5541 5541 0 0.00 0 10428 8 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 7912 35 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 2155 3 0 0
v_min_non_paid_invoice 5983 5983 0 0.00 0 4803 12 0 0
v_min_non_paid_realizn 7309 7309 0 0.00 0 6202 14 0 0
v_our_firm 10302 10302 0 0.00 0 122 1 0 0
v_reserve_write_off 20402 20402 0 0.00 0 5747 6 0 0
x_lock_depdocs_on_canc_invoice 11654 11654 0 0.00 0 19176 126 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 5874 357 0 0
x_make_qstorno_1000_1200 5545 5545 0 0.00 2 42340 1352 0 0
x_make_qstorno_1200_2000 5631 5631 0 0.00 3 84642 3245 0 0
x_make_qstorno_2100_3300 468878 468878 0 0.00 0 43892 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2021 10:38:50 to 12-10-2021 10:38:54.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 6724 8192000000 9713786256 10011648000 801650976 8.913 100 83 166 0 828303664 7312608 19340080
08:09:35 17867 8192000000 9762018928 10069225472 832659968 9.226 100 87 174 0 864632160 11544992 20427200
08:10:55 18167 8192000000 9794698496 10111090688 866817936 9.568 100 88 175 2 905206848 18286736 20102176
08:12:17 15147 8192000000 9861522960 10193707008 910080064 9.998 100 84 168 0 976256176 47744512 18431600
08:13:38 12137 8192000000 9910702656 10252152832 946414560 10.356 100 76 152 1 1023236400 60361168 16460672
08:14:54 4079 8192000000 9967972592 10320707584 989695200 10.779 100 86 172 0 1081687152 74522032 17469920
08:16:03 3566 8192000000 9997543744 10362310656 1025150480 11.122 100 78 155 1 1113095520 72135280 15809760
08:17:09 1841 8192000000 10019929552 10389561344 1049671184 11.358 100 96 192 1 1141415008 71583904 20159920
08:18:12 27076 8192000000 9993624352 10379485184 1060233600 11.459 100 55 109 1 1120437168 48273552 11930016
08:19:51 6996 8192000000 9988105280 10388475904 1071659184 11.568 100 40 80 1 1106572000 27122208 7790608
08:20:59 6246 8192000000 9996398240 10396766208 1083126144 11.677 100 37 72 0 1114159072 23367952 7664976
08:22:10 5881 8192000000 10011353744 10406047744 1092437632 11.766 100 36 70 1 1119671264 19234640 7998992
08:23:16 33908 8192000000 10007423744 10426335232 1091750112 11.759 100 40 79 2 1118263040 17544080 8968848
08:24:53 5043 8192000000 10031268496 10435432448 1098883120 11.827 100 46 92 1 1137468096 29234048 9350928
08:26:00 5613 8192000000 10025376144 10419163136 1097460224 11.814 100 37 74 2 1133144832 26461696 9222912
08:27:06 9691 8192000000 10012807408 10419101696 1094218800 11.783 100 37 75 0 1129887568 28259328 7409440
08:28:20 9473 8192000000 9984894896 10383548416 1053503696 11.394 98 23 46 0 1077103088 18847248 4752144
08:29:30 4705 8192000000 9947642432 10320601088 996035456 10.840 96 53 103 1 1038786032 32118160 10632416
08:30:38 4775 8192000000 9950771488 10322563072 1023085184 11.102 100 48 93 0 1062104544 28582128 10437232
08:31:44 61122 8192000000 9962697792 10362884096 1035018944 11.217 100 29 58 0 1062203648 20282576 6902128
08:33:45 7596 8192000000 9972865552 10357825536 1046834448 11.330 100 40 80 1 1081435552 26185456 8415648
08:34:53 4243 8192000000 9990686800 10378657792 1056873632 11.427 100 42 85 1 1097808560 31824640 9110288
08:35:58 12228 8192000000 9982094832 10391920640 1064599552 11.500 100 28 55 1 1089404240 19443200 5361488
08:37:11 32204 8192000000 10001332592 10398404608 1077352928 11.622 100 29 57 3 1098890352 14943920 6593504
08:38:50 12786 8192000000 10046879648 10440589312 1103708464 11.873 100 53 106 0 1156172816 41942080 10522272
08:40:06 14947 8192000000 10086473264 10488942592 1112884032 11.960 100 45 89 1 1189748816 67280640 9584144
08:41:28 8523 8192000000 10098451104 10505867264 1128517296 12.107 100 62 124 1 1212541264 70728480 13295488
08:42:41 7104 8192000000 10173429216 10574360576 1136594336 12.183 100 67 131 1 1267770000 117913408 13262256
08:43:52 13078 8192000000 10162948064 10571571200 1145774608 12.270 100 51 102 0 1268366480 111217504 11374368
08:45:08 10583 8192000000 10210493200 10614267904 1150081152 12.310 100 64 128 0 1306465920 142248992 14135776
08:46:23 12833 8192000000 10238215216 10656854016 1139030560 12.206 98 60 120 0 1324178848 172115296 13032992
08:47:51 5996 8192000000 10257927840 10682490880 1169126560 12.489 100 50 99 2 1377323600 196684080 11512960
08:49:08 4476 8192000000 10297277632 10720854016 1174630000 12.540 100 78 157 0 1400655440 208058464 17966976
08:50:19 3049 8192000000 10294765168 10694934528 1138122336 12.198 99 78 156 0 1380674048 224075280 18476432
08:51:28 3804 8192000000 10321953552 10747158528 1151981872 12.328 100 44 88 1 1424413744 260987936 11443936
08:52:49 5984 8192000000 10329976384 10728501248 1138401712 12.200 99 76 153 0 1420851568 264948320 17501536
08:53:59 2550 8192000000 10373934704 10775117824 1142858272 12.242 100 71 140 1 1473539280 313180960 17500048
08:55:08 4264 8192000000 10402961840 10799890432 1155893248 12.365 100 83 165 0 1495920208 321065360 18961600
08:56:14 11050 8192000000 10391909760 10811396096 1124288832 12.067 98 44 86 1 1477966368 342215680 11461856
08:57:33 868 8192000000 10431004128 10846359552 1134531440 12.164 99 86 174 0 1526949360 372378096 20039824
08:58:35 12861 8192000000 10397853888 10815012864 1149108688 12.301 100 36 70 0 1508031680 349369280 9553712
08:59:57 11273 8192000000 10384709104 10814857216 1147276144 12.284 100 32 62 2 1488059024 331385168 9397712
09:01:11 9014 8192000000 10409129520 10837745664 1140083552 12.216 100 32 64 1 1500667504 351893856 8690096
09:02:23 15549 8192000000 10400791088 10874830848 1132248672 12.143 100 24 48 0 1506280736 367325072 6706992
09:03:40 21573 8192000000 10364768992 10856677376 1119905808 12.026 100 21 42 2 1486017504 360428256 5683440
09:05:13 17104 8192000000 10125599552 10647552000 1122739712 12.053 100 18 36 2 1278930384 151740848 4449824
09:06:32 63116 8192000000 10031352416 10481311744 1078521584 11.633 97 7 14 1 1117649264 38227456 900224
09:08:52 49497 8192000000 10090625104 10508193792 1119998080 12.027 99 28 54 1 1170829360 45561312 5269968
09:10:44 91996 8192000000 10087227392 10572173312 1131317360 12.134 99 7 14 0 1165746560 33631280 797920
09:13:16 87964 8192000000 10107836624 10543841280 1125698784 12.081 99 23 46 0 1154606560 24081952 4825824
09:15:45 37432 8192000000 10128246032 10594504704 1129666928 12.118 99 24 48 0 1223308080 88760752 4880400
09:17:23 30670 8192000000 10155134928 10587607040 1136426272 12.182 99 48 96 0 1220926416 74603936 9896208
09:18:59 71102 8192000000 10177252976 10637197312 1136147904 12.179 99 45 89 1 1264535888 118323232 10064752
09:21:12 45819 8192000000 10127762432 10681872384 1138315840 12.200 99 13 24 2 1271391168 130078848 2996480
09:23:30 46341 8192000000 10130311216 10604498944 1155584240 12.362 100 29 60 1 1234266784 72653568 6028976
09:25:24 46093 8192000000 10098753904 10563166208 1152238656 12.331 100 17 34 0 1211140816 55413808 3488352
09:27:16 67364 8192000000 10115237520 10599448576 1148953840 12.300 99 14 28 0 1222022848 69963392 3105616
09:29:33 63013 8192000000 10071369056 10563510272 1151379536 12.322 100 16 32 0 1192916416 37885120 3651760
09:31:42 72524 8192000000 10099170160 10608975872 1160725488 12.410 100 26 52 0 1219358176 52863664 5769024
09:34:02 46207 8192000000 10115247712 10574721024 1133740352 12.157 98 20 40 1 1200032256 62174736 4117168
09:36:43 74344 8192000000 10081346768 10574905344 1095584192 11.796 94 13 26 0 1158368080 60475616 2308272
09:38:58 77041 8192000000 10073703680 10563809280 1076593184 11.615 94 12 24 0 1141677856 63116784 1967888
09:41:16 58999 8192000000 10094029184 10533539840 1095871200 11.798 96 25 50 1 1137115056 36165600 5078256
09:43:50 56553 8192000000 10057402176 10497953792 1057936224 11.437 94 20 38 0 1086729520 25115216 3678080
09:45:47 91528 8192000000 10067970736 10518810624 1122954864 12.055 100 29 58 0 1163532688 34540640 6037184
09:48:19 59907 8192000000 10074725200 10510348288 1103180672 11.868 97 26 52 0 1151837376 43293488 5363216
09:50:19 52649 8192000000 10083082560 10530025472 1116360768 11.993 99 10 20 0 1169637824 51321200 1955856
09:52:12 45585 8192000000 10103160544 10584326144 1133578656 12.155 100 12 24 0 1207862704 71735056 2548992
09:54:01 68531 8192000000 10095799312 10544758784 1080951264 11.657 95 11 22 0 1147788496 64407424 2429808
09:56:15 31837 8192000000 10079312624 10514288640 1102105888 11.858 98 22 44 1 1159105952 52256720 4743344
09:57:47 61635 8192000000 10072402032 10538217472 1091974784 11.761 97 23 45 0 1168458496 71712880 4770832
09:59:48 77831 8192000000 10047095680 10520244224 1098227600 11.821 98 10 20 0 1142414272 41901040 2285632
10:02:06 24937 8192000000 10142139392 10571788288 1134291712 12.162 100 46 92 1 1229458912 85341872 9825328
10:03:36 33546 8192000000 10088017152 10551795712 1113873600 11.969 98 18 36 0 1175019184 57516896 3628688
10:05:15 133215 8192000000 10106627552 10548547584 1119174160 12.019 98 45 90 1 1176518000 47538160 9805680
10:08:28 45522 8192000000 10075551696 10568712192 1147724448 12.288 100 9 18 0 1205408192 56069248 1614496
10:10:17 74949 8192000000 10082232496 10559635456 1148919520 12.299 99 7 14 0 1201480000 51320736 1239744
10:12:32 45753 8192000000 10117095168 10562236416 1158528432 12.389 100 27 52 1 1231007136 66420064 6058640
10:14:18 46897 8192000000 10234125472 10708672512 1176331008 12.556 100 24 48 1 1277866272 95944544 5590720
10:16:32 78430 8192000000 10123387120 10581319680 1143231520 12.246 98 17 34 1 1191287136 45027392 3028224
10:18:55 56845 8192000000 10093388976 10537054208 1067219424 11.526 93 26 52 1 1123390720 51089648 5081648
10:20:58 63238 8192000000 10121044336 10583224320 1122731776 12.053 99 29 57 1 1205681792 76940320 6009696
10:23:10 85500 8192000000 10088708576 10562072576 1104696448 11.882 97 20 40 0 1160746000 52361456 3688096
10:25:40 78377 8192000000 10088873424 10564415488 1125458704 12.079 99 20 40 0 1174128240 44642464 4027072
10:27:59 50749 8192000000 10058407568 10525876224 1085064192 11.696 96 11 22 0 1122306160 34921712 2320256
10:31:27 65349 8192000000 10058670672 10527031296 1068591472 11.539 95 6 12 0 1121221472 51427472 1202528
10:33:33 50696 8192000000 10111640240 10559471616 1107858736 11.912 98 29 58 0 1165054512 51005840 6189936
10:35:24 67319 8192000000 10145408656 10606784512 1139576256 12.212 100 21 42 0 1250023360 105624032 4823072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-10-2021 10:38:56 to 12-10-2021 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 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 12-10-2021 10:39:12 to 12-10-2021 10:39:14.

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          238446
MON$OLDEST_ACTIVE               241830
MON$OLDEST_SNAPSHOT             241830
MON$NEXT_TRANSACTION            241830
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                       2062496
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2628 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2628 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2628 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2628 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Oct 12 10:37:23 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

12.10.21 10:39:15. 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-10-12 10:39:18 No severe PSQL-related problems occured
12.10.21 10:39:19. End of report.