Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.09.2021 04: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 15625680 kB
MemAvailable 30786572 kB
Buffers 20 kB
Cached 15976096 kB
SwapCached 412 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-09-14 04:37:08
DTS_END                         2021-09-14 04:37:08.6830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51228
TRN_ID                          236724
ATT_ID                          678
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: 9391.26 22971 1126951
customer order: creation 168.45 375 20214
customer order: refuse 14.98 329 1797
order to supplier: creation 45.03 1403 5404
order to supplier: removal 3.56 117167 427
invoice (draft): creation 46.01 3386 5521
invoice (draft): removal 53.39 1064 6407
invoice accept: apply 41.28 17691 4953
invoice accept: cancel 48.64 872 5837
customer reserve: creation 3872.83 171 464739
customer reserve: removal 4802.77 67 576332
realization accept: apply 82.79 194 9935
realization accept: cancel 81.85 125 9822
payment from customer: creation 59.58 113 7149
payment from customer: removal 5.65 70 678
payment to supplier: creation 49.89 133 5987
payment to supplier: removal 3.63 74 436
service: total inventory turnovers 3.63 3612 435
service: total monetary turnovers 6.27 396 752
service: aggregate perf. data 1.02 12667 122
service: refresh index statistics 0.03 299503 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-09-2021 04:38:19 to 14-09-2021 04:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7858
TEST_TIME 2 6414
TEST_TIME 3 5621
TEST_TIME 4 5596
TEST_TIME 5 5482
TEST_TIME 6 5520
TEST_TIME 7 5434
TEST_TIME 8 5545
TEST_TIME 9 5684
TEST_TIME 10 5551
TEST_TIME 11 6046
TEST_TIME 12 6126
TEST_TIME 13 6565
TEST_TIME 14 6552
TEST_TIME 15 7123
TEST_TIME 16 7170
TEST_TIME 17 7137
TEST_TIME 18 7638
TEST_TIME 19 7854
TEST_TIME 20 7941
TEST_TIME 21 8108
TEST_TIME 22 8135
TEST_TIME 23 8520
TEST_TIME 24 8596
TEST_TIME 25 9553
TEST_TIME 26 10641
TEST_TIME 27 10651
TEST_TIME 28 11169
TEST_TIME 29 11066
TEST_TIME 30 10824
TEST_TIME 31 10832
TEST_TIME 32 10808
TEST_TIME 33 10941
TEST_TIME 34 10906
TEST_TIME 35 10869
TEST_TIME 36 10905
TEST_TIME 37 10890
TEST_TIME 38 10922
TEST_TIME 39 10869
TEST_TIME 40 10758
TEST_TIME 41 10523
TEST_TIME 42 10416
TEST_TIME 43 10358
TEST_TIME 44 10253
TEST_TIME 45 10236
TEST_TIME 46 10273
TEST_TIME 47 10252
TEST_TIME 48 10216
TEST_TIME 49 10115
TEST_TIME 50 10011
TEST_TIME 51 10008
TEST_TIME 52 9904
TEST_TIME 53 9741
TEST_TIME 54 9820
TEST_TIME 55 9817
TEST_TIME 56 9858
TEST_TIME 57 9782
TEST_TIME 58 9814
TEST_TIME 59 9821
TEST_TIME 60 9751
TEST_TIME 61 9718
TEST_TIME 62 9880
TEST_TIME 63 9967
TEST_TIME 64 9929
TEST_TIME 65 9939
TEST_TIME 66 10057
TEST_TIME 67 10064
TEST_TIME 68 10075
TEST_TIME 69 10086
TEST_TIME 70 9991
TEST_TIME 71 9928
TEST_TIME 72 9868
TEST_TIME 73 9815
TEST_TIME 74 9782
TEST_TIME 75 9703
TEST_TIME 76 9687
TEST_TIME 77 9623
TEST_TIME 78 9598
TEST_TIME 79 9564
TEST_TIME 80 9665
TEST_TIME 81 9672
TEST_TIME 82 9681
TEST_TIME 83 9687
TEST_TIME 84 9685
TEST_TIME 85 9743
TEST_TIME 86 9720
TEST_TIME 87 9719
TEST_TIME 88 9784
TEST_TIME 89 9787
TEST_TIME 90 9752
TEST_TIME 91 9700
TEST_TIME 92 9655
TEST_TIME 93 9658
TEST_TIME 94 9623
TEST_TIME 95 9602
TEST_TIME 96 9539
TEST_TIME 97 9564
TEST_TIME 98 9543
TEST_TIME 99 9575
TEST_TIME 100 9544
TEST_TIME 101 9521
TEST_TIME 102 9654
TEST_TIME 103 9719
TEST_TIME 104 9693
TEST_TIME 105 9772
TEST_TIME 106 9751
TEST_TIME 107 9769
TEST_TIME 108 9707
TEST_TIME 109 9657
TEST_TIME 110 9605
TEST_TIME 111 9603
TEST_TIME 112 9557
TEST_TIME 113 9534
TEST_TIME 114 9520
TEST_TIME 115 9478
TEST_TIME 116 9479
TEST_TIME 117 9443
TEST_TIME 118 9423
TEST_TIME 119 9472
TEST_TIME 120 9402

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-09-2021 04:38:23 to 14-09-2021 04:38:26.

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 1556506 1556506 0 0.00 0 15431 7 0 0
doc_list_biud 1556506 1556506 0 0.00 0 29534 32 0 0
sp_add_invoice_to_stock 5053 4953 100 1.97 29 206829 17692 0 100
sp_cancel_adding_invoice 5837 5837 0 0.00 6 65230 873 0 0
sp_cancel_client_order 1797 1797 0 0.00 5 16594 330 0 0
sp_cancel_customer_reserve 576332 576332 0 0.00 1 22674 68 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 4307 70 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 6183 75 0 0
sp_cancel_supplier_invoice 6417 6407 10 0.15 3 21274 1064 0 10
sp_cancel_supplier_order 427 427 0 0.00 7 1136967 117168 0 0
sp_cancel_write_off 9822 9822 0 0.00 1 11038 126 0 0
sp_client_order 20214 20214 0 0.00 4 16956 375 0 0
sp_customer_reserve 464739 464739 0 0.00 0 141366 172 0 0
sp_fill_shopping_cart 31126 31126 0 0.00 1 25801 38 0 0
sp_fill_shopping_cart_clo_res 464718 464718 0 0.00 0 20110 15 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 21561 304 0 0
sp_kill_cost_storno 16773 16773 0 0.00 0 14138 63 0 0
sp_kill_qstorno_ret_qs2qd 1169320 1169320 0 0.00 0 22484 37 0 0
sp_kill_qty_storno 615484 615484 0 0.00 0 29534 79 0 0
sp_lock_selected_doc 20623 20623 0 0.00 0 9334 7 0 0
sp_make_cost_storno 27998 27998 0 0.00 0 11730 55 0 0
sp_multiply_rows_for_pdistr 27998 27998 0 0.00 0 13505 35 0 0
sp_multiply_rows_for_qdistr 20214 20214 0 0.00 0 12409 198 0 0
sp_pay_from_customer 7149 7149 0 0.00 4 10737 114 0 0
sp_pay_to_supplier 5987 5987 0 0.00 5 13520 134 0 0
sp_payment_common 13136 13136 0 0.00 1 8211 20 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 13891 294 0 0
sp_qd_handle_on_invoice_upd_sts 10764 10764 0 0.00 0 29533 349 0 0
sp_qd_handle_on_reserve_upd_sts 9935 9935 0 0.00 0 14656 61 0 0
sp_reserve_write_off 9935 9935 0 0.00 7 14718 195 0 0
sp_supplier_invoice 5521 5521 0 0.00 7 77379 3387 0 0
sp_supplier_order 5404 5404 0 0.00 6 22224 1404 0 0
srv_aggregate_perf_data 122 122 0 0.00 2444 108450 12668 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1586 197668 48510 0 0
srv_find_qd_qs_mism 1078879 1078879 0 0.00 0 15430 5 0 0
srv_make_invnt_saldo 435 435 0 0.00 45 124337 3612 0 0
srv_make_money_saldo 752 752 0 0.00 1 8953 397 0 0
srv_recalc_idx_stat 4 4 0 0.00 153373 488506 299504 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4953 4953 0 0.00 0 31257 191 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 3383 92 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 9035 19 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 2552 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 2622 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 519 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 1458 15 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 66720 2146 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 751 8 0 0
t$perf-norm:sp_client_order 20214 20214 0 0.00 0 21156 23 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 1215 6 0 0
t$perf-norm:sp_pay_from_customer 7149 7149 0 0.00 0 30257 15 0 0
t$perf-norm:sp_pay_to_supplier 5987 5987 0 0.00 0 15635 16 0 0
t$perf-norm:sp_reserve_write_off 9935 9935 0 0.00 0 14833 17 0 0
t$perf-norm:sp_supplier_invoice 5521 5521 0 0.00 0 7165 20 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 7364 21 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1395 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 324 8 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 2185 12 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 4416 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10054 9854 200 1.98 0 5674 21 0 200
v_all_customers 37254 37254 0 0.00 0 16 1 0 0
v_all_suppliers 30952 30952 0 0.00 0 30 1 0 0
v_all_wares 40428 40428 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 449 8 0 0
v_cancel_client_order 3594 3594 0 0.00 0 4584 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 4288 7 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1852 7 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 971 4 0 0
v_cancel_supplier_invoice 746 726 20 2.68 0 2087 16 0 20
v_cancel_supplier_order 854 854 0 0.00 0 3908 10 0 0
v_cancel_write_off 1972 1972 0 0.00 0 8041 26 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 224 1 0 0
v_max_id_clo_res 982 982 0 0.00 0 5840 16 0 0
v_max_id_ord_sup 5514 5514 0 0.00 0 1390 2 0 0
v_max_non_paid_invoice 1423 1423 0 0.00 0 383 2 0 0
v_max_non_paid_realizn 3640 3640 0 0.00 0 1708 3 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 1184 4 0 0
v_min_id_clo_res 982 982 0 0.00 0 3247 9 0 0
v_min_id_ord_sup 5514 5514 0 0.00 0 6985 6 0 0
v_min_non_paid_invoice 5987 5987 0 0.00 0 4859 11 0 0
v_min_non_paid_realizn 7149 7149 0 0.00 0 8206 16 0 0
v_our_firm 10192 10192 0 0.00 0 13 1 0 0
v_reserve_write_off 19870 19870 0 0.00 0 5715 8 0 0
x_lock_depdocs_on_canc_invoice 11881 11881 0 0.00 0 16242 124 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 12297 365 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 2 22205 1331 0 0
x_make_qstorno_1200_2000 5521 5521 0 0.00 4 76882 3247 0 0
x_make_qstorno_2100_3300 464739 464739 0 0.00 0 141364 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 04:38:27 to 14-09-2021 04:38:31.

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
02:07:12 996 8192000000 8956485344 9018814464 3901568 0.047 2 1 2 0 4000880 63536 35776
02:08:14 17113 8192000000 9699173200 9992187904 773316960 8.625 100 79 158 0 800682064 7412464 19952640
02:09:37 20705 8192000000 9751930960 10055516160 813907152 9.037 100 87 174 0 847963360 12746688 21309520
02:11:05 20309 8192000000 9806058416 10118987776 845156608 9.352 100 83 166 0 891514192 27208960 19148624
02:12:36 11642 8192000000 9870695584 10200952832 911624176 10.013 100 86 172 0 968682800 37886800 19171824
02:13:50 6806 8192000000 9935577248 10277982208 951548160 10.406 100 84 168 2 1038311792 69258512 17505120
02:15:00 4812 8192000000 9980226000 10331164672 990132128 10.783 100 80 160 1 1082695824 76212080 16351616
02:16:07 3489 8192000000 9979957328 10346221568 1015174464 11.025 100 86 171 1 1092501856 59463392 17864000
02:17:13 1883 8192000000 10016003488 10384412672 1040954176 11.274 100 90 181 2 1117324752 57722256 18648320
02:18:17 1696 8192000000 10046181728 10417512448 1055952688 11.418 100 87 173 2 1146462832 72541648 17968496
02:19:20 4188 8192000000 10038020752 10424651776 1060573552 11.462 100 48 94 1 1143684192 73858256 9252384
02:20:25 6014 8192000000 9980493520 10376568832 1072133552 11.572 100 55 108 3 1097641760 13131248 12376960
02:21:33 9731 8192000000 9987321040 10397552640 1073619312 11.587 100 26 52 1 1093999552 14535376 5844864
02:22:44 6366 8192000000 10018992752 10420129792 1083187120 11.678 100 31 60 2 1118032256 28549888 6295248
02:23:52 3702 8192000000 10011883664 10407219200 1090395424 11.746 100 41 83 0 1111283504 10570656 10317424
02:24:56 11684 8192000000 10008116976 10418352128 1090632448 11.749 100 25 51 1 1116322144 19802768 5886928
02:26:09 16119 8192000000 10019134496 10415734784 1091080960 11.753 100 59 119 1 1129820048 25422192 13316896
02:27:26 4716 8192000000 10022912608 10415800320 1067416000 11.527 99 53 107 0 1111203392 32734848 11052544
02:28:31 7564 8192000000 9952916912 10336632832 1009008336 10.966 97 41 79 3 1033259824 14943840 9307648
02:29:43 4137 8192000000 9948547376 10336010240 1018753120 11.060 98 45 90 2 1042199872 13462368 9984384
02:30:47 49873 8192000000 9918536448 10304008192 924284480 10.138 90 30 60 1 954779088 24388128 6106480
02:32:41 7261 8192000000 9967049328 10361061376 1039890704 11.264 100 43 86 0 1081963168 33605872 8466592
02:33:49 7698 8192000000 9973036336 10357293056 1049937088 11.360 99 27 54 0 1073185344 16762992 6485264
02:34:57 22217 8192000000 9971217600 10380165120 1064346032 11.498 100 33 65 2 1109846144 36758640 8741472
02:36:39 8356 8192000000 9976163072 10384404480 1074158208 11.592 100 35 70 1 1098402256 15754864 8489184
02:37:56 24564 8192000000 10034860720 10430263296 1095705568 11.797 100 42 84 0 1138006448 33764112 8536768
02:39:28 32297 8192000000 10050797200 10458402816 1113356992 11.964 100 44 88 1 1156899504 33523040 10019472
02:41:15 89181 8192000000 10128644704 10548224000 1134470032 12.163 100 36 72 1 1243908000 100270912 9167056
02:44:22 23355 8192000000 10163352896 10583617536 1153135120 12.339 100 39 77 3 1274337968 111557200 9645648
02:45:48 16044 8192000000 10210005264 10628423680 1158051968 12.385 100 63 121 5 1318015360 147221168 12742224
02:47:09 2250 8192000000 10221356880 10634407936 1162266720 12.424 100 53 106 1 1315836752 141914800 11655232
02:48:17 4847 8192000000 10243108688 10652467200 1161555424 12.418 100 63 122 0 1340827632 166182928 13089280
02:49:27 3683 8192000000 10280460320 10692190208 1176321712 12.556 100 73 144 0 1384685456 192645920 15717824
02:50:35 2383 8192000000 10328523680 10733469696 1185865312 12.645 100 81 162 0 1427913504 223776256 18271936
02:51:42 3408 8192000000 10396388976 10799337472 1178221648 12.574 100 84 166 2 1489019200 291623536 19174016
02:52:52 2913 8192000000 10374926560 10780786688 1203893424 12.812 100 85 170 0 1476980256 253335744 19751088
02:53:58 6482 8192000000 10437561360 10845790208 1192154720 12.703 100 80 160 0 1530506624 319098832 19253072
02:55:09 1584 8192000000 10506433616 10911711232 1197534304 12.753 100 86 172 1 1603381328 385022224 20824800
02:56:16 14843 8192000000 10456189712 10892918784 1168926208 12.487 98 43 85 1 1560120528 380479824 10714496
02:57:41 4682 8192000000 10513812464 10944286720 1180782960 12.597 100 43 86 1 1608367648 415926592 11658096
02:58:49 18685 8192000000 10509087968 10986242048 1172154544 12.517 100 42 82 2 1621415808 438331984 10929280
03:00:19 13936 8192000000 10500233168 10963492864 1151261360 12.321 100 35 68 0 1626257008 465127088 9868560
03:01:36 10645 8192000000 10505313728 10966175744 1124443440 12.069 97 39 78 2 1583391472 447853296 11094736
03:02:51 33086 8192000000 10364431936 10900791296 1168852528 12.486 100 22 44 0 1562594064 387516800 6224736
03:05:38 80994 8192000000 10054805136 10504601600 1055356576 11.412 93 17 34 1 1096910960 38393152 3161232
03:08:22 106552 8192000000 10103657280 10536910848 1108881552 11.922 97 23 46 1 1186771712 73488768 4401392
03:11:09 116620 8192000000 10060278256 10515087360 1065214512 11.506 94 6 12 0 1101680784 35365408 1100864
03:14:06 64047 8192000000 10079418720 10534055936 1101027584 11.847 98 16 32 0 1148792144 43631664 4132896
03:16:14 80496 8192000000 10066374976 10522562560 1127601280 12.099 100 24 47 2 1171001232 38320688 5079264
03:19:35 197668 8192000000 10099017328 10570035200 1079655824 11.644 95 25 50 1 1131082560 45513712 5913024
03:23:57 7793 8192000000 10085543072 10511802368 1140127936 12.217 100 62 121 2 1179301344 26848704 12324704
03:25:14 59611 8192000000 10078707168 10547195904 1138042768 12.197 99 18 36 0 1180381232 38301776 4036688
03:27:57 45577 8192000000 10125598304 10602176512 1145760416 12.270 100 18 34 1 1245261008 94838608 4661984
03:29:44 137534 8192000000 10130750384 10678005760 1136460272 12.182 99 18 36 0 1293443312 152293952 4689088
03:33:06 91807 8192000000 10094485968 10547712000 1120833344 12.035 98 8 16 0 1150080480 27843360 1403776
03:35:43 18753 8192000000 10049707152 10487230464 1111144096 11.943 98 27 54 0 1154250832 37352240 5754496
03:37:06 14141 8192000000 10088075152 10517950464 1133300640 12.152 100 39 75 1 1183200144 40836192 9063312
03:38:27 77814 8192000000 10108666800 10582241280 1124214736 12.067 99 8 16 0 1201450896 75781504 1454656
03:40:46 33654 8192000000 10100511920 10544427008 1129930688 12.121 99 27 54 3 1163568672 27702416 5935568
03:42:20 51173 8192000000 10081073200 10558189568 1090641216 11.749 96 35 70 1 1205156320 107128912 7386192
03:44:14 77232 8192000000 10091174912 10551582720 1119383120 12.021 98 10 20 0 1215226816 93520016 2323680
03:46:31 35557 8192000000 10073889136 10497118208 1113772368 11.968 99 40 79 0 1158679408 36784768 8122272
03:48:09 80765 8192000000 10072114256 10509447168 1116461872 11.994 98 25 50 0 1146707232 24937488 5307872
03:50:33 56537 8192000000 10105368608 10553434112 1143368928 12.247 100 22 44 0 1196657888 48632208 4656752
03:52:36 54431 8192000000 10095649984 10570264576 1117508048 12.003 98 8 16 0 1184702896 65520096 1674752
03:55:47 47350 8192000000 10100708592 10562994176 1116709984 11.996 98 20 40 1 1205321088 84103136 4507968
03:57:35 52258 8192000000 10072105424 10524471296 1109356464 11.926 98 17 34 0 1166299632 53005248 3937920
03:59:27 47841 8192000000 10067284448 10504847360 1107837024 11.912 99 25 50 0 1171988624 59073872 5077728
04:01:15 35216 8192000000 10088524704 10534162432 1106464944 11.899 98 33 66 0 1182171904 68824496 6882464
04:02:52 11632 8192000000 10045904896 10479489024 1115353744 11.983 99 44 88 0 1137929760 12633600 9942416
04:04:05 84111 8192000000 10117004736 10580025344 1111669184 11.948 99 33 66 0 1165677232 47689728 6318320
04:06:35 95651 8192000000 10066361120 10534154240 1067581488 11.529 94 28 57 0 1133660512 60314944 5764080
04:09:32 28867 8192000000 10057093728 10504867840 1101671728 11.853 98 22 44 2 1135364752 28361232 5331792
04:11:03 51149 8192000000 10095863312 10580844544 1130993952 12.131 100 15 29 1 1197327856 63540480 2793424
04:13:05 88239 8192000000 10082106496 10577043456 1086759936 11.712 96 12 24 1 1181029504 91899200 2370368
04:15:43 66638 8192000000 10098532800 10540544000 1120972992 12.036 98 20 40 0 1191767744 66289504 4505248
04:18:01 39040 8192000000 10181928224 10636615680 1140654400 12.222 99 19 38 1 1263422704 118459648 4308656
04:19:40 27237 8192000000 10101107056 10622529536 1092044496 11.762 97 10 20 0 1221327824 126838592 2444736
04:21:12 12786 8192000000 10085419184 10515505152 1131490656 12.135 100 53 106 0 1190908352 48898176 10519520
04:22:27 85019 8192000000 10058102496 10520772608 1125210768 12.076 99 8 16 1 1170730464 43841952 1677744
04:24:52 132040 8192000000 10061046896 10523475968 1126515632 12.089 99 24 48 2 1170052944 38528528 5008784
04:28:05 26350 8192000000 10099166240 10572218368 1137552560 12.193 100 34 67 0 1195282688 50795856 6934272
04:29:44 61208 8192000000 10064199088 10549829632 1127893648 12.102 99 9 18 0 1184736816 54897472 1945696
04:31:45 49412 8192000000 10066214896 10522959872 1119182736 12.019 98 19 38 2 1165998352 42079488 4736128
04:34:00 60458 8192000000 10064539040 10514702336 1046209840 11.324 92 11 22 0 1082968816 34130768 2628208
04:36:14 58869 8192000000 9473632336 9833979904 574731312 6.555 51 19 37 0 627102864 48293696 4077856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-09-2021 04:38:33 to 14-09-2021 04:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-09-2021 04:38:48 to 14-09-2021 04:38:50.

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          235377
MON$OLDEST_ACTIVE               236959
MON$OLDEST_SNAPSHOT             236959
MON$NEXT_TRANSACTION            236959
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                       2079968
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2588 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2588 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2588 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 ---
:105: 
:106: mscs-test-161.budzdorov.ru    Tue Sep 14 04:37:17 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Tue Sep 14 04:37:18 2021
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Tue Sep 14 04:37:19 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
--- end of diff output ---

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

14.09.21 04:38:51. 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-09-14 04:38:53 No severe PSQL-related problems occured
14.09.21 04:38:55. End of report.