Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.09.2021 13: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 16030108 kB
MemAvailable 30898824 kB
Buffers 0 kB
Cached 15696968 kB
SwapCached 388 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-11 13:37:07
DTS_END                         2021-09-11 13:37:07.9210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(720:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37940
TRN_ID                          241154
ATT_ID                          623
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: 9262.84 26645 1111541
customer order: creation 173.27 414 20792
customer order: refuse 15.48 307 1857
order to supplier: creation 46.34 1288 5561
order to supplier: removal 3.36 114564 403
invoice (draft): creation 45.91 3347 5509
invoice (draft): removal 51.15 972 6138
invoice accept: apply 41.84 17587 5021
invoice accept: cancel 46.18 800 5541
customer reserve: creation 3978.38 168 477405
customer reserve: removal 4565.00 65 547800
realization accept: apply 84.93 186 10192
realization accept: cancel 80.17 119 9620
payment from customer: creation 60.98 109 7317
payment from customer: removal 5.86 109 703
payment to supplier: creation 49.26 109 5911
payment to supplier: removal 3.47 125 416
service: total inventory turnovers 3.79 3421 455
service: total monetary turnovers 6.52 456 782
service: aggregate perf. data 0.95 15834 114
service: refresh index statistics 0.03 372915 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-09-2021 13:38:35 to 11-09-2021 13:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2015
TEST_TIME 2 1872
TEST_TIME 3 2824
TEST_TIME 4 4285
TEST_TIME 5 4444
TEST_TIME 6 4626
TEST_TIME 7 4816
TEST_TIME 8 4939
TEST_TIME 9 4807
TEST_TIME 10 4964
TEST_TIME 11 5293
TEST_TIME 12 5785
TEST_TIME 13 6242
TEST_TIME 14 6464
TEST_TIME 15 7087
TEST_TIME 16 6924
TEST_TIME 17 7339
TEST_TIME 18 7822
TEST_TIME 19 8206
TEST_TIME 20 8734
TEST_TIME 21 9078
TEST_TIME 22 9260
TEST_TIME 23 9325
TEST_TIME 24 10095
TEST_TIME 25 11958
TEST_TIME 26 11829
TEST_TIME 27 11672
TEST_TIME 28 11626
TEST_TIME 29 11261
TEST_TIME 30 11153
TEST_TIME 31 11160
TEST_TIME 32 11153
TEST_TIME 33 10952
TEST_TIME 34 10948
TEST_TIME 35 10849
TEST_TIME 36 10799
TEST_TIME 37 10833
TEST_TIME 38 10751
TEST_TIME 39 10640
TEST_TIME 40 10692
TEST_TIME 41 10593
TEST_TIME 42 10456
TEST_TIME 43 10345
TEST_TIME 44 10320
TEST_TIME 45 10147
TEST_TIME 46 10047
TEST_TIME 47 10067
TEST_TIME 48 9998
TEST_TIME 49 9924
TEST_TIME 50 9868
TEST_TIME 51 10013
TEST_TIME 52 10156
TEST_TIME 53 10097
TEST_TIME 54 10094
TEST_TIME 55 10185
TEST_TIME 56 10265
TEST_TIME 57 10394
TEST_TIME 58 10306
TEST_TIME 59 10221
TEST_TIME 60 10130
TEST_TIME 61 10041
TEST_TIME 62 10041
TEST_TIME 63 9965
TEST_TIME 64 9872
TEST_TIME 65 9810
TEST_TIME 66 9788
TEST_TIME 67 9779
TEST_TIME 68 9772
TEST_TIME 69 9867
TEST_TIME 70 9934
TEST_TIME 71 9907
TEST_TIME 72 9981
TEST_TIME 73 9957
TEST_TIME 74 9929
TEST_TIME 75 9996
TEST_TIME 76 10017
TEST_TIME 77 9910
TEST_TIME 78 9828
TEST_TIME 79 9817
TEST_TIME 80 9765
TEST_TIME 81 9735
TEST_TIME 82 9690
TEST_TIME 83 9657
TEST_TIME 84 9662
TEST_TIME 85 9648
TEST_TIME 86 9625
TEST_TIME 87 9686
TEST_TIME 88 9684
TEST_TIME 89 9639
TEST_TIME 90 9720
TEST_TIME 91 9707
TEST_TIME 92 9744
TEST_TIME 93 9692
TEST_TIME 94 9602
TEST_TIME 95 9632
TEST_TIME 96 9609
TEST_TIME 97 9575
TEST_TIME 98 9548
TEST_TIME 99 9565
TEST_TIME 100 9533
TEST_TIME 101 9515
TEST_TIME 102 9520
TEST_TIME 103 9497
TEST_TIME 104 9507
TEST_TIME 105 9474
TEST_TIME 106 9536
TEST_TIME 107 9572
TEST_TIME 108 9581
TEST_TIME 109 9599
TEST_TIME 110 9541
TEST_TIME 111 9524
TEST_TIME 112 9520
TEST_TIME 113 9461
TEST_TIME 114 9427
TEST_TIME 115 9404
TEST_TIME 116 9363
TEST_TIME 117 9362
TEST_TIME 118 9340
TEST_TIME 119 9338
TEST_TIME 120 9266

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-09-2021 13:38:38 to 11-09-2021 13:38:42.

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 1550437 1550437 0 0.00 0 18375 6 0 0
doc_list_biud 1550434 1550434 0 0.00 0 88865 29 0 0
sp_add_invoice_to_stock 5139 5021 118 2.29 29 215269 17588 0 118
sp_cancel_adding_invoice 5541 5541 0 0.00 5 64304 801 0 0
sp_cancel_client_order 1857 1857 0 0.00 4 10072 308 0 0
sp_cancel_customer_reserve 547800 547800 0 0.00 1 50698 66 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 9452 109 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 13733 125 0 0
sp_cancel_supplier_invoice 6151 6138 13 0.21 2 88874 973 0 13
sp_cancel_supplier_order 403 403 0 0.00 14 1290337 114565 0 0
sp_cancel_write_off 9620 9620 0 0.00 1 13956 120 0 0
sp_client_order 20792 20792 0 0.00 4 40608 414 0 0
sp_customer_reserve 477405 477405 0 0.00 0 86056 169 0 0
sp_fill_shopping_cart 31856 31856 0 0.00 1 14452 42 0 0
sp_fill_shopping_cart_clo_res 477398 477398 0 0.00 0 19628 19 0 0
sp_get_clo_for_invoice 5013 5013 0 0.00 0 23491 413 0 0
sp_kill_cost_storno 16280 16280 0 0.00 0 13921 62 0 0
sp_kill_qstorno_ret_qs2qd 1111761 1111761 0 0.00 0 88863 36 0 0
sp_kill_qty_storno 586564 586564 0 0.00 0 88864 77 0 0
sp_lock_selected_doc 21102 21102 0 0.00 0 7002 6 0 0
sp_make_cost_storno 28433 28433 0 0.00 0 15035 50 0 0
sp_multiply_rows_for_pdistr 28433 28433 0 0.00 0 13695 29 0 0
sp_multiply_rows_for_qdistr 20792 20792 0 0.00 0 18738 234 0 0
sp_pay_from_customer 7317 7317 0 0.00 4 15220 109 0 0
sp_pay_to_supplier 5911 5911 0 0.00 5 9992 110 0 0
sp_payment_common 13228 13228 0 0.00 1 13774 18 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 0 10066 277 0 0
sp_qd_handle_on_invoice_upd_sts 10554 10554 0 0.00 0 44017 355 0 0
sp_qd_handle_on_reserve_upd_sts 10192 10192 0 0.00 0 42351 53 0 0
sp_reserve_write_off 10192 10192 0 0.00 7 42375 186 0 0
sp_supplier_invoice 5509 5509 0 0.00 7 92270 3347 0 0
sp_supplier_order 5561 5561 0 0.00 5 56034 1288 0 0
srv_aggregate_perf_data 114 114 0 0.00 2904 209382 15835 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1139 156985 49504 0 0
srv_find_qd_qs_mism 1061331 1061331 0 0.00 0 15697 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 66 118071 3421 0 0
srv_make_money_saldo 782 782 0 0.00 1 15165 457 0 0
srv_recalc_idx_stat 4 4 0 0.00 263306 486897 372915 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5021 5021 0 0.00 0 9616 130 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 0 6519 83 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 25055 41 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 2552 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 459 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1558 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 771 10 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 28036 1274 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1456 8 0 0
t$perf-norm:sp_client_order 20792 20792 0 0.00 0 23539 18 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 3184 12 0 0
t$perf-norm:sp_pay_from_customer 7317 7317 0 0.00 0 4877 7 0 0
t$perf-norm:sp_pay_to_supplier 5911 5911 0 0.00 0 8976 7 0 0
t$perf-norm:sp_reserve_write_off 10192 10192 0 0.00 0 4142 10 0 0
t$perf-norm:sp_supplier_invoice 5509 5509 0 0.00 0 19850 19 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 6437 11 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 541 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 848 9 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 545 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10262 10026 236 2.29 0 6554 18 0 236
v_all_customers 38284 38284 0 0.00 0 29 1 0 0
v_all_suppliers 30750 30750 0 0.00 0 43 1 0 0
v_all_wares 41584 41584 0 0.00 0 134 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 2719 11 0 0
v_cancel_client_order 3714 3714 0 0.00 0 3964 6 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 2711 11 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 5488 10 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3906 24 0 0
v_cancel_supplier_invoice 860 834 26 3.02 0 1891 18 0 26
v_cancel_supplier_order 806 806 0 0.00 0 1149 7 0 0
v_cancel_write_off 2062 2062 0 0.00 0 3211 16 0 0
v_max_id_clo_ord 5561 5561 0 0.00 0 252 1 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 2686 23 0 0
v_max_id_ord_sup 5502 5502 0 0.00 0 8314 7 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 319 2 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 1737 3 0 0
v_min_id_clo_ord 5561 5561 0 0.00 0 6179 5 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 13492 24 0 0
v_min_id_ord_sup 5502 5502 0 0.00 0 9776 7 0 0
v_min_non_paid_invoice 5911 5911 0 0.00 0 3446 9 0 0
v_min_non_paid_realizn 7317 7317 0 0.00 0 13770 16 0 0
v_our_firm 10328 10328 0 0.00 0 17 1 0 0
v_reserve_write_off 20384 20384 0 0.00 0 7288 8 0 0
x_lock_depdocs_on_canc_invoice 11262 11262 0 0.00 0 27494 115 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 9367 328 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 2 55942 1225 0 0
x_make_qstorno_1200_2000 5509 5509 0 0.00 3 92247 3174 0 0
x_make_qstorno_2100_3300 477405 477405 0 0.00 0 86055 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-09-2021 13:38:43 to 11-09-2021 13:38:47.

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
11:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 27997 8192000000 9725663120 10022334464 800591184 8.902 100 88 177 0 829225552 7778496 20855872
11:09:49 29317 8192000000 9777125696 10088030208 847479200 9.375 100 86 171 1 879951680 13352640 19119840
11:11:27 19962 8192000000 9821976272 10141220864 882200208 9.722 100 83 166 1 921337232 20833408 18303616
11:12:51 8548 8192000000 9885408368 10215542784 915470000 10.051 100 87 174 0 999541600 65335520 18736080
11:14:14 10487 8192000000 9914625120 10258567168 949551120 10.387 100 72 145 0 1032067824 67458544 15058160
11:15:32 3039 8192000000 9954197136 10308890624 991387616 10.795 100 82 162 2 1074423328 66513568 16522144
11:16:37 2028 8192000000 9991014144 10348634112 1027434736 11.144 100 88 176 0 1104114832 59515088 17165008
11:17:41 1638 8192000000 10033614640 10404458496 1055438704 11.413 100 87 172 1 1146297152 73091648 17766800
11:18:43 5392 8192000000 10010464480 10397564928 1064408944 11.499 100 58 114 1 1125792256 48865888 12517424
11:19:51 5583 8192000000 9989410544 10379464704 1070663840 11.558 100 38 76 2 1111630544 32773744 8192960
11:21:01 6890 8192000000 10001414160 10401689600 1077309792 11.622 100 44 89 0 1119689584 33129168 9250624
11:22:08 52513 8192000000 9988536864 10404036608 1082766832 11.674 100 33 64 0 1107454608 17691632 6996144
11:24:01 7130 8192000000 10006773152 10414817280 1090783984 11.750 100 38 77 0 1120031376 20791008 8456384
11:25:09 7552 8192000000 10005561088 10403491840 1092629552 11.768 100 38 76 0 1120865152 20226976 8008624
11:26:17 6083 8192000000 10003732208 10409639936 1086423376 11.709 99 41 82 3 1114491392 19283312 8784704
11:27:23 40771 8192000000 10011043888 10405830656 1086903936 11.713 99 68 134 1 1111169184 8697664 15567584
11:29:04 5700 8192000000 9960238576 10351067136 1007901008 10.955 96 45 88 1 1038009312 21401392 8706912
11:30:14 6481 8192000000 9907437008 10298138624 958826768 10.478 95 27 54 2 992970032 28044656 6098608
11:31:21 6713 8192000000 9962184464 10351751168 1011625488 10.991 98 47 94 1 1042459008 19647216 11186304
11:32:30 51479 8192000000 9938657152 10336526336 1022840656 11.099 99 42 86 0 1043346192 10776624 9728912
11:34:28 6192 8192000000 9961245056 10361991168 1048877712 11.350 100 49 95 0 1066293760 6393808 11022240
11:35:35 3650 8192000000 9967843872 10358497280 1064264688 11.497 100 40 73 3 1080786784 5976928 10545168
11:36:42 7216 8192000000 9978440704 10378276864 1065153648 11.506 100 25 50 0 1096824144 26740032 4930464
11:37:56 134614 8192000000 10068814576 10472005632 1091354032 11.756 100 47 96 0 1129695968 28833664 9508272
11:41:15 15322 8192000000 10114830224 10525384704 1122792192 12.053 100 59 117 0 1236073232 100552560 12728480
11:42:34 5919 8192000000 10151611120 10560126976 1128159392 12.104 100 55 106 2 1262413968 122208624 12045952
11:43:44 18361 8192000000 10190126112 10597879808 1139876736 12.214 100 59 118 0 1295588928 142713552 12998640
11:45:14 17596 8192000000 10244971984 10658590720 1163208992 12.433 100 49 98 2 1346393648 172053536 11131120
11:46:34 3659 8192000000 10297552048 10711965696 1173810800 12.532 100 56 109 1 1401241328 215199472 12231056
11:48:02 16854 8192000000 10366687632 10792165376 1179985952 12.590 100 59 118 1 1476583040 282379616 14217472
11:49:23 4731 8192000000 10392414752 10822135808 1193927296 12.720 100 78 155 1 1498733472 287150384 17655792
11:50:30 1136 8192000000 10417837360 10826174464 1168943072 12.487 99 88 176 1 1517158384 327718656 20496656
11:51:35 3438 8192000000 10434973488 10844770304 1163509536 12.436 99 77 154 2 1526275024 343855904 18909584
11:52:43 3180 8192000000 10487954848 10910339072 1165466016 12.454 100 64 128 1 1589656208 409223920 14966272
11:53:51 3674 8192000000 10481703040 10893860864 1138309744 12.200 99 73 144 0 1570131456 415432672 16389040
11:55:00 1142 8192000000 10534977072 10950578176 1160588224 12.409 100 70 140 0 1637013664 459586736 16838704
11:56:05 7724 8192000000 10491233984 10954817536 1155510512 12.361 100 38 76 0 1591684272 425618080 10555680
11:57:15 12334 8192000000 10469705664 10923151360 1131717696 12.138 99 33 66 0 1585195312 444738864 8738752
11:58:32 6507 8192000000 10448659792 10909474816 1155196224 12.358 100 42 84 0 1570628640 404186144 11246272
11:59:42 18103 8192000000 10465395120 10900656128 1138203104 12.199 99 33 66 0 1549730176 402807904 8719168
12:01:06 22112 8192000000 10418890768 10867679232 1107714240 11.911 99 21 42 0 1563763760 449542688 6506832
12:02:37 30106 8192000000 10052086160 10524102656 1103201616 11.868 98 13 26 0 1167929264 61495840 3231808
12:04:08 106155 8192000000 10059036560 10536882176 1071023392 11.562 96 20 40 1 1180286800 105025392 4238016
12:07:39 53887 8192000000 10116260656 10580008960 1086704656 11.711 96 21 42 0 1198826768 107712144 4409968
12:09:40 24963 8192000000 10094769136 10590232576 1129696640 12.119 100 29 56 1 1200101200 63597024 6807536
12:11:07 40774 8192000000 10087758064 10546614272 1108368592 11.917 98 17 35 0 1167124384 55552720 3203072
12:12:48 36094 8192000000 10147860272 10574061568 1112393456 11.955 99 46 94 1 1221304992 99115232 9796304
12:14:28 99209 8192000000 10094154384 10539806720 1099247408 11.830 98 15 30 0 1163646672 61459296 2939968
12:17:19 96594 8192000000 10044855024 10510286848 1130103440 12.122 100 17 34 2 1187252928 53503344 3646144
12:19:57 27209 8192000000 10096463120 10541617152 1142788880 12.242 100 22 42 1 1202702928 55115392 4798656
12:21:29 51924 8192000000 10045947200 10500005888 1138107904 12.198 100 11 22 1 1162289456 22325152 1856400
12:23:33 68021 8192000000 10096399440 10587402240 1143706528 12.250 100 13 27 0 1219697248 72871984 3118736
12:25:55 49996 8192000000 10121096256 10572234752 1121350928 12.040 97 24 45 2 1194072928 68030432 4691568
12:27:52 64705 8192000000 10148592048 10626256896 1135260432 12.171 99 20 40 0 1269844544 129760224 4823888
12:30:01 16289 8192000000 10105498016 10541559808 1131987872 12.140 99 27 54 0 1175166288 37411744 5766672
12:31:23 57504 8192000000 10096158320 10587820032 1098057936 11.819 96 22 44 0 1173647040 70409744 5179360
12:33:27 42620 8192000000 10145165216 10604445696 1150421952 12.313 100 22 44 0 1238155616 83085952 4647712
12:35:17 83215 8192000000 10083661040 10567131136 1148702896 12.297 100 22 44 0 1225658704 72405056 4550752
12:38:13 29037 8192000000 10074567648 10507722752 1119939568 12.026 98 32 64 0 1165155856 38933760 6282528
12:39:43 40934 8192000000 10077957904 10538209280 1134049728 12.160 100 14 28 0 1191183584 54399104 2734752
12:41:28 52829 8192000000 10095682240 10545311744 1123392208 12.059 98 10 20 0 1191545904 66154320 1999376
12:43:31 85739 8192000000 10122591680 10587430912 1079519248 11.643 95 14 28 0 1197469936 114758368 3192320
12:46:01 57440 8192000000 10087227344 10534027264 1096071152 11.800 97 18 36 3 1220704080 120266944 4365984
12:47:59 28757 8192000000 10102880448 10537803776 1118857728 12.016 99 46 92 0 1184629072 56274656 9496688
12:49:36 73052 8192000000 10062118112 10491904000 1103000960 11.866 98 34 67 1 1137999552 27602848 7395744
12:51:52 44581 8192000000 10105237296 10562486272 1108905264 11.922 99 28 56 0 1169872672 54874736 6092672
12:53:41 156984 8192000000 10056346208 10515349504 1105005680 11.885 98 16 33 0 1162864048 54181056 3677312
12:58:29 83057 8192000000 10050615168 10494849024 1054765936 11.406 94 11 22 1 1104504848 47407600 2331312
13:00:54 39232 8192000000 10063141264 10506674176 1120934192 12.036 100 18 36 2 1159196176 33977664 4284320
13:02:34 56251 8192000000 10093074976 10528440320 1076421296 11.613 95 22 44 0 1148033344 66785584 4826464
13:04:31 60000 8192000000 10066057232 10513203200 1098667936 11.825 98 20 40 0 1143510576 40763104 4079536
13:06:31 23278 8192000000 10087133264 10511781888 1097376128 11.813 98 52 104 1 1154931488 47219440 10335920
13:08:01 116741 8192000000 10073892016 10536071168 1102059024 11.857 98 13 26 0 1152649584 48327520 2263040
13:10:58 127145 8192000000 10083658976 10596253696 1122425328 12.050 99 16 32 0 1209047184 83404304 3217552
13:14:12 52385 8192000000 10066381520 10537967616 1129070624 12.113 100 10 19 1 1160931808 29446896 2414288
13:16:04 63611 8192000000 10092686816 10589474816 1133548896 12.155 99 17 34 1 1206019920 68666128 3804896
13:18:08 42902 8192000000 10070660976 10524606464 1125792144 12.082 98 31 59 2 1182150816 50248432 6110240
13:20:03 100986 8192000000 10072412752 10517876736 1089695904 11.740 95 13 26 0 1133959648 41807776 2455968
13:22:52 80781 8192000000 10074192944 10587578368 1076489680 11.614 95 22 44 1 1148553728 67624704 4439344
13:25:18 128565 8192000000 10077097728 10561626112 1081809328 11.665 96 11 22 1 1126481328 42001296 2670704
13:28:27 77505 8192000000 10059998144 10548613120 1133031184 12.150 100 13 26 1 1170141760 34247824 2862752
13:30:59 38717 8192000000 10036922928 10498174976 1087457680 11.718 96 10 20 0 1107227888 17699376 2070832
13:32:46 65164 8192000000 10066852256 10514612224 1104774560 11.883 98 10 20 0 1137438432 30679600 1984272
13:35:46 48328 8192000000 10099957168 10541232128 1091802048 11.760 96 23 46 1 1157535376 60551584 5181744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-09-2021 13:38:48 to 11-09-2021 13:39:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-09-2021 13:39:04 to 11-09-2021 13:39:06.

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          237524
MON$OLDEST_ACTIVE               241379
MON$OLDEST_SNAPSHOT             241379
MON$NEXT_TRANSACTION            241379
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                       2046944
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             812
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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Sep 11 13:37:17 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

11.09.21 13:39:07. 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-11 13:39:09 No severe PSQL-related problems occured
11.09.21 13:39:10. End of report.