Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.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 15833636 kB
MemAvailable 31069116 kB
Buffers 0 kB
Cached 15877916 kB
SwapCached 2580 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-11 13:37:05
DTS_END                         2021-08-11 13:37:05.8750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56240
TRN_ID                          241016
ATT_ID                          672
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: 9363.09 20000 1123571
customer order: creation 179.74 372 21569
customer order: refuse 15.77 331 1892
order to supplier: creation 48.18 1340 5781
order to supplier: removal 3.48 101806 418
invoice (draft): creation 47.27 3335 5672
invoice (draft): removal 51.69 875 6203
invoice accept: apply 43.86 16350 5263
invoice accept: cancel 46.93 810 5631
customer reserve: creation 4031.45 161 483774
customer reserve: removal 4588.40 60 550608
realization accept: apply 86.64 184 10397
realization accept: cancel 88.93 119 10672
payment from customer: creation 60.20 112 7224
payment from customer: removal 5.95 97 714
payment to supplier: creation 50.34 145 6041
payment to supplier: removal 3.78 78 453
service: total inventory turnovers 3.62 3698 434
service: total monetary turnovers 5.93 523 712
service: aggregate perf. data 0.91 16300 109
service: refresh index statistics 0.03 253301 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2021 13:39:03 to 11-08-2021 13:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7281
TEST_TIME 2 5623
TEST_TIME 3 5907
TEST_TIME 4 5638
TEST_TIME 5 5288
TEST_TIME 6 5461
TEST_TIME 7 5470
TEST_TIME 8 5302
TEST_TIME 9 5397
TEST_TIME 10 5973
TEST_TIME 11 6290
TEST_TIME 12 6745
TEST_TIME 13 7081
TEST_TIME 14 7518
TEST_TIME 15 7949
TEST_TIME 16 8357
TEST_TIME 17 8957
TEST_TIME 18 9147
TEST_TIME 19 9169
TEST_TIME 20 9709
TEST_TIME 21 9567
TEST_TIME 22 9913
TEST_TIME 23 9844
TEST_TIME 24 10152
TEST_TIME 25 10579
TEST_TIME 26 11738
TEST_TIME 27 11630
TEST_TIME 28 11481
TEST_TIME 29 11215
TEST_TIME 30 11370
TEST_TIME 31 11165
TEST_TIME 32 10998
TEST_TIME 33 10805
TEST_TIME 34 10615
TEST_TIME 35 10699
TEST_TIME 36 10719
TEST_TIME 37 10662
TEST_TIME 38 10498
TEST_TIME 39 10633
TEST_TIME 40 10504
TEST_TIME 41 10444
TEST_TIME 42 10502
TEST_TIME 43 10539
TEST_TIME 44 10540
TEST_TIME 45 10413
TEST_TIME 46 10301
TEST_TIME 47 10211
TEST_TIME 48 10160
TEST_TIME 49 10053
TEST_TIME 50 9951
TEST_TIME 51 10028
TEST_TIME 52 10008
TEST_TIME 53 9980
TEST_TIME 54 9946
TEST_TIME 55 9863
TEST_TIME 56 9802
TEST_TIME 57 9713
TEST_TIME 58 9712
TEST_TIME 59 9630
TEST_TIME 60 9740
TEST_TIME 61 9850
TEST_TIME 62 9832
TEST_TIME 63 9785
TEST_TIME 64 9825
TEST_TIME 65 9783
TEST_TIME 66 9968
TEST_TIME 67 9916
TEST_TIME 68 9919
TEST_TIME 69 9909
TEST_TIME 70 9937
TEST_TIME 71 9912
TEST_TIME 72 10025
TEST_TIME 73 9968
TEST_TIME 74 9908
TEST_TIME 75 9838
TEST_TIME 76 9798
TEST_TIME 77 9761
TEST_TIME 78 9720
TEST_TIME 79 9687
TEST_TIME 80 9703
TEST_TIME 81 9665
TEST_TIME 82 9618
TEST_TIME 83 9570
TEST_TIME 84 9540
TEST_TIME 85 9602
TEST_TIME 86 9598
TEST_TIME 87 9594
TEST_TIME 88 9627
TEST_TIME 89 9622
TEST_TIME 90 9590
TEST_TIME 91 9585
TEST_TIME 92 9736
TEST_TIME 93 9757
TEST_TIME 94 9764
TEST_TIME 95 9824
TEST_TIME 96 9799
TEST_TIME 97 9718
TEST_TIME 98 9666
TEST_TIME 99 9650
TEST_TIME 100 9611
TEST_TIME 101 9629
TEST_TIME 102 9592
TEST_TIME 103 9553
TEST_TIME 104 9519
TEST_TIME 105 9486
TEST_TIME 106 9567
TEST_TIME 107 9592
TEST_TIME 108 9576
TEST_TIME 109 9582
TEST_TIME 110 9612
TEST_TIME 111 9651
TEST_TIME 112 9683
TEST_TIME 113 9648
TEST_TIME 114 9581
TEST_TIME 115 9564
TEST_TIME 116 9517
TEST_TIME 117 9492
TEST_TIME 118 9487
TEST_TIME 119 9444
TEST_TIME 120 9369

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2021 13:39:07 to 11-08-2021 13:39:10.

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 1570689 1570689 0 0.00 0 23990 6 0 0
doc_list_biud 1570686 1570686 0 0.00 0 75242 27 0 0
sp_add_invoice_to_stock 5346 5263 83 1.55 38 175572 16351 0 83
sp_cancel_adding_invoice 5631 5631 0 0.00 6 63405 811 0 0
sp_cancel_client_order 1892 1892 0 0.00 5 22893 332 0 0
sp_cancel_customer_reserve 550608 550608 0 0.00 1 32709 61 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 7790 97 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 3168 78 0 0
sp_cancel_supplier_invoice 6213 6203 10 0.16 2 75316 876 0 10
sp_cancel_supplier_order 418 418 0 0.00 6 1077970 101807 0 0
sp_cancel_write_off 10672 10672 0 0.00 1 28055 120 0 0
sp_client_order 21569 21569 0 0.00 4 37738 372 0 0
sp_customer_reserve 483774 483774 0 0.00 0 64518 161 0 0
sp_fill_shopping_cart 33014 33014 0 0.00 1 14508 44 0 0
sp_fill_shopping_cart_clo_res 483771 483771 0 0.00 0 14451 14 0 0
sp_get_clo_for_invoice 5253 5253 0 0.00 0 28827 297 0 0
sp_kill_cost_storno 17470 17470 0 0.00 0 8116 56 0 0
sp_kill_qstorno_ret_qs2qd 1118509 1118509 0 0.00 0 75240 33 0 0
sp_kill_qty_storno 591073 591073 0 0.00 0 75241 71 0 0
sp_lock_selected_doc 21729 21729 0 0.00 0 7276 6 0 0
sp_make_cost_storno 28915 28915 0 0.00 0 19380 58 0 0
sp_multiply_rows_for_pdistr 28914 28914 0 0.00 0 10359 30 0 0
sp_multiply_rows_for_qdistr 21569 21569 0 0.00 0 16441 165 0 0
sp_pay_from_customer 7224 7224 0 0.00 4 16250 113 0 0
sp_pay_to_supplier 6041 6041 0 0.00 5 19387 145 0 0
sp_payment_common 13265 13265 0 0.00 1 10889 27 0 0
sp_qd_handle_on_cancel_clo 1892 1892 0 0.00 0 22884 292 0 0
sp_qd_handle_on_invoice_upd_sts 10884 10884 0 0.00 0 34904 343 0 0
sp_qd_handle_on_reserve_upd_sts 10396 10396 0 0.00 0 23977 59 0 0
sp_reserve_write_off 10397 10397 0 0.00 7 24014 185 0 0
sp_supplier_invoice 5672 5672 0 0.00 6 119728 3335 0 0
sp_supplier_order 5781 5781 0 0.00 5 37921 1341 0 0
srv_aggregate_perf_data 109 109 0 0.00 3036 236936 16300 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1195 183012 46721 0 0
srv_find_qd_qs_mism 1073669 1073669 0 0.00 0 23990 5 0 0
srv_make_invnt_saldo 434 434 0 0.00 39 127710 3699 0 0
srv_make_money_saldo 712 712 0 0.00 1 17555 523 0 0
srv_recalc_idx_stat 4 4 0 0.00 192068 337032 253301 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 5263 5263 0 0.00 0 11373 143 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 3027 69 0 0
t$perf-norm:sp_cancel_client_order 1892 1892 0 0.00 0 4013 14 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 6603 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 1858 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 54 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3655 22 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 42421 1525 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 3692 8 0 0
t$perf-norm:sp_client_order 21569 21569 0 0.00 0 24829 17 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 9665 23 0 0
t$perf-norm:sp_pay_from_customer 7224 7224 0 0.00 0 3042 7 0 0
t$perf-norm:sp_pay_to_supplier 6041 6041 0 0.00 0 9282 12 0 0
t$perf-norm:sp_reserve_write_off 10398 10398 0 0.00 0 6940 9 0 0
t$perf-norm:sp_supplier_invoice 5672 5672 0 0.00 0 5561 14 0 0
t$perf-norm:sp_supplier_order 5781 5781 0 0.00 0 17215 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 176 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 34 1 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 2718 18 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 1828 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10670 10504 166 1.55 0 7773 19 0 166
v_all_customers 39140 39140 0 0.00 0 109 1 0 0
v_all_suppliers 31526 31526 0 0.00 0 14 1 0 0
v_all_wares 43132 43132 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 158 5 0 0
v_cancel_client_order 3784 3784 0 0.00 0 5177 4 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 5178 9 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2363 7 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 1663 10 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 309 12 0 20
v_cancel_supplier_order 836 836 0 0.00 0 4375 14 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2543 16 0 0
v_max_id_clo_ord 5780 5780 0 0.00 0 664 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 9835 25 0 0
v_max_id_ord_sup 5668 5668 0 0.00 0 5930 6 0 0
v_max_non_paid_invoice 1726 1726 0 0.00 0 294 2 0 0
v_max_non_paid_realizn 3847 3847 0 0.00 0 10883 6 0 0
v_min_id_clo_ord 5780 5780 0 0.00 0 6530 8 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 5689 12 0 0
v_min_id_ord_sup 5668 5668 0 0.00 0 9249 5 0 0
v_min_non_paid_invoice 6041 6041 0 0.00 0 4955 19 0 0
v_min_non_paid_realizn 7224 7224 0 0.00 0 6356 18 0 0
v_our_firm 10746 10746 0 0.00 0 17 1 0 0
v_reserve_write_off 20792 20792 0 0.00 0 4065 7 0 0
x_lock_depdocs_on_canc_invoice 11423 11423 0 0.00 0 20946 109 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 14995 331 0 0
x_make_qstorno_1000_1200 5781 5781 0 0.00 2 34750 1261 0 0
x_make_qstorno_1200_2000 5672 5672 0 0.00 2 119708 3169 0 0
x_make_qstorno_2100_3300 483774 483774 0 0.00 0 64516 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2021 13:39:11 to 11-08-2021 13:39:15.

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:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:10 33343 8192000000 9718742816 10014806016 792704832 8.822 100 88 176 0 822281264 7884752 21691680
11:09:57 17968 8192000000 9763609472 10069475328 831113584 9.210 100 89 179 0 865888896 13362928 21412384
11:11:21 16979 8192000000 9812342000 10135322624 876433056 9.664 100 83 166 0 923370608 27683984 19253568
11:12:43 12089 8192000000 9877423824 10212839424 924252528 10.138 100 76 150 0 984132080 44172224 15707328
11:13:57 8767 8192000000 9923294288 10271907840 966148864 10.549 100 76 152 3 1033970976 51783920 16038192
11:15:08 5335 8192000000 9967089392 10322223104 1001256880 10.891 100 90 180 1 1078939408 59011760 18670768
11:16:16 2560 8192000000 10020894240 10381422592 1027963104 11.149 100 92 185 1 1134932944 89079872 17889968
11:17:20 1523 8192000000 10002091328 10366226432 1050933824 11.370 100 94 186 1 1119372096 48796352 19641920
11:18:23 2463 8192000000 10020232176 10397093888 1060633264 11.463 100 70 141 1 1132456656 57553056 14270336
11:19:25 1884 8192000000 9998499184 10375016448 1064266688 11.497 100 70 139 0 1105440944 26100192 15074064
11:20:28 9121 8192000000 9988477712 10392887296 1072912704 11.580 100 36 72 0 1118782608 37936816 7933088
11:21:38 8384 8192000000 9980605200 10384326656 1077607424 11.625 100 20 40 1 1106536640 24142624 4786592
11:22:47 10284 8192000000 9984943552 10399297536 1083615600 11.682 100 36 72 1 1115906896 23482976 8808320
11:23:58 44073 8192000000 10009248000 10403389440 1090077776 11.743 100 58 118 2 1120379104 17146112 13155216
11:25:42 3194 8192000000 10020455760 10418061312 1091229968 11.754 100 54 108 1 1140343584 37474592 11639024
11:26:48 7949 8192000000 10018187728 10424307712 1080988720 11.657 99 39 76 0 1109306256 20431616 7885920
11:27:58 8741 8192000000 9981005136 10379100160 1036973888 11.236 97 25 50 0 1064094768 21479568 5641312
11:29:10 44930 8192000000 9944859072 10337804288 981628080 10.700 93 32 65 0 1007726720 19879376 6219264
11:30:55 7176 8192000000 9943029520 10321166336 981108976 10.695 95 40 81 2 1015140864 24612592 9419296
11:32:03 6445 8192000000 9940376480 10323554304 1016442096 11.038 99 39 74 4 1046329248 21334784 8552368
11:33:10 8014 8192000000 9963904320 10362003456 1025596384 11.126 99 41 82 1 1060852176 26099200 9156592
11:34:19 29045 8192000000 9960606368 10369605632 1023324528 11.104 98 29 59 1 1063344368 33519232 6500608
11:35:48 5659 8192000000 9972037168 10360774656 1055869264 11.417 100 46 93 2 1078567424 13235008 9463152
11:36:56 5284 8192000000 9989654480 10377596928 1071957488 11.571 100 46 90 3 1098703312 16112704 10633120
11:38:08 5203 8192000000 10029452032 10423578624 1102096368 11.858 100 56 112 1 1139917600 26082960 11738272
11:39:16 40840 8192000000 10066479344 10474455040 1117775296 12.006 100 40 78 2 1176707408 51263904 7668208
11:41:07 28254 8192000000 10122796912 10538446848 1139683760 12.213 100 40 79 3 1245361760 97451200 8226800
11:42:44 15724 8192000000 10172339824 10579554304 1149224960 12.302 100 55 110 0 1267588864 106626512 11737392
11:44:05 12171 8192000000 10193670496 10617540608 1153473424 12.342 100 41 82 0 1308056240 144814832 9767984
11:45:25 10613 8192000000 10238931696 10660261888 1169942512 12.496 100 40 80 0 1353152464 173906560 9303392
11:46:52 4155 8192000000 10289120864 10696556544 1170799632 12.504 100 68 132 0 1385907008 200521744 14585632
11:48:01 9639 8192000000 10295682560 10708926464 1158763936 12.392 98 61 120 0 1390202336 217478480 13959920
11:49:15 6677 8192000000 10364522912 10772910080 1166444368 12.464 100 80 160 1 1456772080 271929088 18398624
11:50:24 2057 8192000000 10308784768 10721611776 1158149552 12.386 99 84 169 0 1404530768 226061632 20319584
11:51:31 1192 8192000000 10336632016 10755936256 1159023632 12.394 100 89 177 0 1433212304 252834784 21353888
11:52:39 1817 8192000000 10328078416 10726469632 1134802544 12.167 100 78 155 1 1422727856 269005168 18920144
11:53:45 6230 8192000000 10341865408 10778492928 1144879216 12.261 100 42 81 3 1447174384 292088608 10206560
11:54:55 10937 8192000000 10320309472 10741157888 1152470352 12.333 100 39 76 4 1428912832 267302736 9139744
11:56:09 11256 8192000000 10334520464 10753871872 1144166464 12.255 100 39 77 2 1425342608 270978096 10198048
11:57:26 12073 8192000000 10326977472 10762100736 1122555856 12.051 99 44 89 1 1424046208 290416112 11074240
11:58:45 24721 8192000000 10308062320 10754625536 1113370928 11.964 98 27 54 1 1405255472 284772960 7111584
12:00:15 16106 8192000000 10314081216 10766585856 1115209024 11.982 99 21 42 1 1422518640 301688848 5620768
12:01:42 25385 8192000000 10322662528 10751442944 1094231888 11.783 99 22 44 0 1411465088 311381856 5851344
12:03:09 16635 8192000000 10217276752 10718343168 1105301296 11.888 99 21 42 3 1357450096 246733792 5415008
12:04:53 65649 8192000000 10086513328 10542362624 1115413664 11.984 100 23 46 1 1198877056 78500656 4962736
12:07:03 86352 8192000000 10029273552 10445168640 1046480288 11.327 94 9 18 0 1079412864 31306320 1626256
12:09:45 43180 8192000000 10070094064 10503184384 1098731312 11.826 98 28 56 0 1139981648 35249088 6001248
12:11:34 58098 8192000000 10132359056 10598100992 1114346112 11.974 99 15 31 2 1218370768 100606848 3417808
12:13:43 84760 8192000000 10073367792 10528198656 1135260864 12.171 100 18 36 1 1182376672 43526512 3589296
12:16:13 88794 8192000000 10070551536 10533400576 1115777984 11.987 98 8 16 0 1188242192 70548784 1915424
12:18:42 183012 8192000000 10073914160 10567888896 1093716896 11.778 96 4 9 0 1142433200 47744592 971712
12:22:54 92950 8192000000 10101922288 10561626112 1119387600 12.021 98 24 48 0 1198362688 73707296 5267792
12:25:42 2026 8192000000 10083058480 10526117888 1136741632 12.185 100 84 165 3 1176659952 21288816 18629504
12:26:46 71415 8192000000 10114396976 10561757184 1146891040 12.280 100 34 68 0 1230797744 76940640 6966064
12:28:58 30445 8192000000 10102836160 10554834944 1144159872 12.255 100 27 54 2 1185207392 35809840 5237680
12:30:37 20765 8192000000 10093555536 10545074176 1142162336 12.236 100 40 80 1 1201206512 49952944 9091232
12:31:58 28953 8192000000 10068722144 10522554368 1118855952 12.016 99 13 26 1 1170715872 49131968 2727952
12:33:28 17693 8192000000 10114049424 10565304320 1129555952 12.117 99 17 34 0 1217730624 83711472 4463200
12:34:57 53150 8192000000 10172329264 10657005568 1135457408 12.173 99 11 22 0 1293401648 155194432 2749808
12:36:51 54333 8192000000 10139694960 10661928960 1120549488 12.032 99 14 27 1 1257640416 134010416 3080512
12:38:46 40990 8192000000 10063353040 10498408448 1119051808 12.018 98 12 24 0 1156392736 35212432 2128496
12:40:31 36811 8192000000 10113061264 10552741888 1106929472 11.903 96 16 32 2 1173255552 63355424 2970656
12:42:44 54559 8192000000 10062081920 10562367488 1081151968 11.658 95 17 34 0 1131384592 46713536 3519088
12:44:42 17729 8192000000 10059883968 10477350912 1090329072 11.746 97 49 98 1 1116050256 15779040 9942144
12:46:01 29352 8192000000 10110764272 10529406976 1101815664 11.855 98 42 84 0 1164299680 54414880 8069136
12:47:31 44746 8192000000 10092823136 10541772800 1124146832 12.066 99 35 70 0 1220492928 88861904 7484192
12:49:16 66155 8192000000 10050173504 10506813440 1084726896 11.692 97 9 18 0 1152409088 65627744 2054448
12:51:26 79500 8192000000 10127994432 10556456960 1117883504 12.007 99 56 112 2 1192197872 62894768 11419600
12:53:49 46684 8192000000 10072486992 10531454976 1124473696 12.069 100 30 60 0 1165828688 35260128 6094864
12:55:36 64672 8192000000 10092808160 10569027584 1118959760 12.017 99 17 34 0 1214311568 91650464 3701344
12:57:41 59606 8192000000 10037510112 10493931520 1092577136 11.767 97 13 26 0 1125510384 29366464 3566784
12:59:41 78781 8192000000 10090721840 10544254976 1117947120 12.008 99 8 16 0 1177815152 58269024 1599008
13:02:04 56509 8192000000 10084840336 10538606592 1127339792 12.096 99 20 40 1 1198638448 66754752 4543904
13:04:09 68084 8192000000 10105045712 10558296064 1141547936 12.230 100 13 26 0 1218404112 73766720 3089456
13:06:20 66877 8192000000 10146934976 10602983424 1140857024 12.224 99 20 41 1 1236177744 91092496 4228224
13:08:31 46877 8192000000 10145787520 10653896704 1133163376 12.151 98 23 46 1 1276718544 138538368 5016800
13:10:23 60530 8192000000 10097349456 10557566976 1141402960 12.229 99 15 30 1 1193659760 48930144 3326656
13:12:28 140533 8192000000 10081003904 10602323968 1089339808 11.736 95 14 28 0 1163572752 71272528 2960416
13:15:48 69379 8192000000 10066629776 10542567424 1134277776 12.162 100 14 27 1 1189332624 51945872 3108976
13:18:00 90396 8192000000 10069676144 10542882816 1116546096 11.994 98 10 20 0 1153502880 34866160 2090624
13:20:32 37800 8192000000 10106924448 10562797568 1129835040 12.120 99 32 64 1 1195895600 60066176 5994384
13:22:16 90267 8192000000 10150026784 10634731520 1119179408 12.019 98 24 48 0 1234570624 110285936 5105280
13:24:48 37928 8192000000 10071216816 10509021184 1085517312 11.700 96 18 36 1 1127056832 37783088 3756432
13:26:33 107565 8192000000 10066461648 10551238656 1065811920 11.512 95 7 14 0 1106293920 39210912 1271088
13:29:22 101473 8192000000 10038609872 10474979328 1081562400 11.662 96 15 30 1 1134618192 49715296 3340496
13:32:54 39520 8192000000 10037633632 10478391296 1089060592 11.734 97 16 32 0 1112024160 19504640 3458928
13:34:40 66764 8192000000 10091223648 10561552384 1118656384 12.014 99 25 50 4 1186441840 62532016 5253440

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-08-2021 13:39:17 to 11-08-2021 13:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 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 11-08-2021 13:39:33 to 11-08-2021 13:39:35.

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          241015
MON$OLDEST_ACTIVE               243732
MON$OLDEST_SNAPSHOT             243732
MON$NEXT_TRANSACTION            243732
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                       2091200
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2567 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2567 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Wed Aug 11 13:37:10 2021
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Wed Aug 11 13:37:20 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
--- end of diff output ---

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

11.08.21 13:39:36. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-11 13:39:38 No severe PSQL-related problems occured
11.08.21 13:39:40. End of report.