Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.02.2021 13:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 16438348 kB
MemAvailable 31158396 kB
Buffers 0 kB
Cached 15231908 kB
SwapCached 4640 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.0
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-02-15 13:26:55
DTS_END                         2021-02-15 13:26:55.1370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37974
TRN_ID                          238190
ATT_ID                          673
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: 9023.85 23900 1082862
customer order: creation 171.13 365 20536
customer order: refuse 14.83 304 1780
order to supplier: creation 46.13 1314 5536
order to supplier: removal 3.40 102161 408
invoice (draft): creation 46.17 3802 5540
invoice (draft): removal 51.27 938 6152
invoice accept: apply 41.00 17211 4920
invoice accept: cancel 45.17 835 5420
customer reserve: creation 3837.22 166 460466
customer reserve: removal 4476.00 60 537120
realization accept: apply 83.59 184 10031
realization accept: cancel 79.54 110 9545
payment from customer: creation 59.28 114 7113
payment from customer: removal 5.78 80 694
payment to supplier: creation 48.38 145 5806
payment to supplier: removal 3.48 78 417
service: total inventory turnovers 4.06 3150 487
service: total monetary turnovers 6.52 477 782
service: aggregate perf. data 0.88 15739 105
service: refresh index statistics 0.03 330759 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-02-2021 13:28:24 to 15-02-2021 13:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2240
TEST_TIME 2 2759
TEST_TIME 3 3426
TEST_TIME 4 4480
TEST_TIME 5 5213
TEST_TIME 6 5145
TEST_TIME 7 5816
TEST_TIME 8 6075
TEST_TIME 9 6102
TEST_TIME 10 6061
TEST_TIME 11 5946
TEST_TIME 12 6011
TEST_TIME 13 6358
TEST_TIME 14 6580
TEST_TIME 15 6601
TEST_TIME 16 6943
TEST_TIME 17 7091
TEST_TIME 18 7911
TEST_TIME 19 7974
TEST_TIME 20 8465
TEST_TIME 21 8906
TEST_TIME 22 9311
TEST_TIME 23 10017
TEST_TIME 24 10133
TEST_TIME 25 10258
TEST_TIME 26 11125
TEST_TIME 27 11081
TEST_TIME 28 11057
TEST_TIME 29 10985
TEST_TIME 30 11081
TEST_TIME 31 11002
TEST_TIME 32 10913
TEST_TIME 33 10721
TEST_TIME 34 10483
TEST_TIME 35 10335
TEST_TIME 36 10326
TEST_TIME 37 10204
TEST_TIME 38 10199
TEST_TIME 39 10258
TEST_TIME 40 10193
TEST_TIME 41 10180
TEST_TIME 42 10062
TEST_TIME 43 9893
TEST_TIME 44 9846
TEST_TIME 45 9741
TEST_TIME 46 9734
TEST_TIME 47 9734
TEST_TIME 48 9738
TEST_TIME 49 9751
TEST_TIME 50 9797
TEST_TIME 51 9772
TEST_TIME 52 9778
TEST_TIME 53 9781
TEST_TIME 54 9806
TEST_TIME 55 9740
TEST_TIME 56 9996
TEST_TIME 57 10001
TEST_TIME 58 9975
TEST_TIME 59 9863
TEST_TIME 60 9821
TEST_TIME 61 9745
TEST_TIME 62 9735
TEST_TIME 63 9675
TEST_TIME 64 9609
TEST_TIME 65 9610
TEST_TIME 66 9568
TEST_TIME 67 9564
TEST_TIME 68 9583
TEST_TIME 69 9551
TEST_TIME 70 9616
TEST_TIME 71 9680
TEST_TIME 72 9629
TEST_TIME 73 9652
TEST_TIME 74 9685
TEST_TIME 75 9697
TEST_TIME 76 9705
TEST_TIME 77 9614
TEST_TIME 78 9549
TEST_TIME 79 9500
TEST_TIME 80 9496
TEST_TIME 81 9430
TEST_TIME 82 9471
TEST_TIME 83 9441
TEST_TIME 84 9416
TEST_TIME 85 9422
TEST_TIME 86 9366
TEST_TIME 87 9374
TEST_TIME 88 9366
TEST_TIME 89 9505
TEST_TIME 90 9502
TEST_TIME 91 9496
TEST_TIME 92 9488
TEST_TIME 93 9440
TEST_TIME 94 9391
TEST_TIME 95 9343
TEST_TIME 96 9325
TEST_TIME 97 9338
TEST_TIME 98 9309
TEST_TIME 99 9325
TEST_TIME 100 9310
TEST_TIME 101 9310
TEST_TIME 102 9320
TEST_TIME 103 9332
TEST_TIME 104 9358
TEST_TIME 105 9359
TEST_TIME 106 9360
TEST_TIME 107 9355
TEST_TIME 108 9362
TEST_TIME 109 9283
TEST_TIME 110 9212
TEST_TIME 111 9199
TEST_TIME 112 9152
TEST_TIME 113 9143
TEST_TIME 114 9122
TEST_TIME 115 9089
TEST_TIME 116 9053
TEST_TIME 117 9017
TEST_TIME 118 9027
TEST_TIME 119 9016
TEST_TIME 120 9027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-02-2021 13:28:27 to 15-02-2021 13:28:31.

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 1507920 1507920 0 0.00 0 12418 6 0 0
doc_list_biud 1507920 1507920 0 0.00 0 27485 28 0 0
sp_add_invoice_to_stock 5039 4920 119 2.36 35 192632 17212 0 119
sp_cancel_adding_invoice 5420 5420 0 0.00 7 80405 835 0 0
sp_cancel_client_order 1780 1780 0 0.00 4 13299 304 0 0
sp_cancel_customer_reserve 537120 537120 0 0.00 1 27487 60 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 3702 81 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3690 78 0 0
sp_cancel_supplier_invoice 6156 6152 4 0.06 2 25440 939 0 4
sp_cancel_supplier_order 408 408 0 0.00 6 1142447 102161 0 0
sp_cancel_write_off 9545 9545 0 0.00 1 6996 110 0 0
sp_client_order 20536 20536 0 0.00 4 18752 365 0 0
sp_customer_reserve 460466 460466 0 0.00 0 67595 167 0 0
sp_fill_shopping_cart 31600 31600 0 0.00 1 13360 44 0 0
sp_fill_shopping_cart_clo_res 460463 460463 0 0.00 0 19160 15 0 0
sp_get_clo_for_invoice 4916 4916 0 0.00 0 33641 375 0 0
sp_kill_cost_storno 16076 16076 0 0.00 0 8423 59 0 0
sp_kill_qstorno_ret_qs2qd 1090345 1090345 0 0.00 0 27483 33 0 0
sp_kill_qty_storno 575372 575372 0 0.00 0 27485 71 0 0
sp_lock_selected_doc 20739 20739 0 0.00 0 9924 7 0 0
sp_make_cost_storno 27866 27866 0 0.00 0 10720 59 0 0
sp_multiply_rows_for_pdistr 27866 27866 0 0.00 0 8533 34 0 0
sp_multiply_rows_for_qdistr 20536 20536 0 0.00 0 18743 192 0 0
sp_pay_from_customer 7113 7113 0 0.00 4 13625 114 0 0
sp_pay_to_supplier 5806 5806 0 0.00 4 23370 145 0 0
sp_payment_common 12919 12919 0 0.00 1 8220 25 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 13293 264 0 0
sp_qd_handle_on_invoice_upd_sts 10336 10336 0 0.00 0 16512 351 0 0
sp_qd_handle_on_reserve_upd_sts 10031 10031 0 0.00 0 8979 39 0 0
sp_reserve_write_off 10031 10031 0 0.00 7 14524 185 0 0
sp_supplier_invoice 5540 5540 0 0.00 5 112113 3803 0 0
sp_supplier_order 5536 5536 0 0.00 5 22441 1314 0 0
srv_aggregate_perf_data 105 105 0 0.00 2661 197358 15739 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 963 119900 46280 0 0
srv_find_qd_qs_mism 1034638 1034638 0 0.00 0 8010 5 0 0
srv_make_invnt_saldo 487 487 0 0.00 48 101066 3150 0 0
srv_make_money_saldo 782 782 0 0.00 1 15792 478 0 0
srv_recalc_idx_stat 4 4 0 0.00 182706 415696 330759 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 0 24238 160 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 4270 96 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 3219 10 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 2538 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 6682 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 4436 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 584 8 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 26919 1714 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 2869 12 0 0
t$perf-norm:sp_client_order 20536 20536 0 0.00 0 17085 18 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 11132 16 0 0
t$perf-norm:sp_pay_from_customer 7113 7113 0 0.00 0 11039 12 0 0
t$perf-norm:sp_pay_to_supplier 5806 5806 0 0.00 0 6288 7 0 0
t$perf-norm:sp_reserve_write_off 10031 10031 0 0.00 0 12024 9 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 18998 19 0 0
t$perf-norm:sp_supplier_order 5536 5536 0 0.00 0 10990 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 524 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 276 9 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 5031 24 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1078 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10070 9832 238 2.36 0 11798 18 0 238
v_all_customers 37448 37448 0 0.00 0 99 1 0 0
v_all_suppliers 30662 30662 0 0.00 0 99 1 0 0
v_all_wares 41072 41072 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2888 15 0 0
v_cancel_client_order 3560 3560 0 0.00 0 12002 8 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 544 4 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 6323 9 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1208 6 0 0
v_cancel_supplier_invoice 882 874 8 0.90 0 13031 31 0 8
v_cancel_supplier_order 816 816 0 0.00 0 1198 6 0 0
v_cancel_write_off 1962 1962 0 0.00 0 3672 15 0 0
v_max_id_clo_ord 5535 5535 0 0.00 0 2936 2 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 1507 9 0 0
v_max_id_ord_sup 5528 5528 0 0.00 0 7934 8 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 477 3 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 2196 4 0 0
v_min_id_clo_ord 5535 5535 0 0.00 0 2547 5 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 4513 13 0 0
v_min_id_ord_sup 5528 5528 0 0.00 0 8491 13 0 0
v_min_non_paid_invoice 5806 5806 0 0.00 0 8208 17 0 0
v_min_non_paid_realizn 7113 7113 0 0.00 0 6602 18 0 0
v_our_firm 10338 10338 0 0.00 0 184 1 0 0
v_reserve_write_off 20062 20062 0 0.00 0 12984 11 0 0
x_lock_depdocs_on_canc_invoice 11135 11135 0 0.00 0 5396 97 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 21601 384 0 0
x_make_qstorno_1000_1200 5536 5536 0 0.00 2 22425 1235 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 2 112100 3637 0 0
x_make_qstorno_2100_3300 460466 460466 0 0.00 0 67593 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-02-2021 13:28:32 to 15-02-2021 13:28:36.

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
10:56:58 11 8192000000 8800240688 8812027904 5880736 0.071 2 2 3 0 6032688 90592 61360
10:57:59 24802 8192000000 9722263888 10020925440 801153968 8.908 100 84 168 0 828942080 7571344 20216768
10:59:28 16572 8192000000 9757769696 10063728640 833142160 9.231 100 92 184 0 867274000 12016880 22114960
11:00:50 15655 8192000000 9790449856 10107596800 858260112 9.483 100 82 164 0 900308048 22712112 19335824
11:02:10 14786 8192000000 9856501312 10190118912 910934128 10.007 100 79 158 1 972779008 43829984 18014896
11:03:30 9073 8192000000 9913615024 10252615680 955268912 10.443 100 88 177 3 1023443008 49252144 18921952
11:04:42 7533 8192000000 9966848400 10321350656 991726544 10.798 100 70 140 0 1082927328 77171152 14029632
11:05:56 2496 8192000000 9995500384 10359586816 1025735888 11.127 100 81 161 0 1114040496 72492816 15811792
11:07:00 1891 8192000000 10025603760 10394386432 1046217616 11.324 100 88 176 1 1139041536 74695168 18128752
11:08:04 1573 8192000000 10038946624 10413371392 1074026528 11.591 100 88 168 2 1148628896 56584608 18017760
11:09:06 4102 8192000000 10044069936 10423361536 1077651328 11.625 100 69 137 1 1144254144 51508208 15094608
11:10:10 5777 8192000000 10020997344 10417041408 1091861808 11.760 100 62 122 1 1129618064 23470112 14286144
11:11:17 5048 8192000000 10012677648 10412482560 1097122752 11.810 100 36 72 5 1135095088 30310096 7662240
11:12:23 9745 8192000000 10027500784 10442432512 1101422160 11.851 100 31 62 0 1136226432 28308832 6495440
11:13:35 27906 8192000000 10012533264 10425200640 1102554784 11.862 100 31 60 2 1126336960 16175200 7606976
11:15:06 6249 8192000000 10031315888 10430595072 1108731648 11.920 100 45 90 2 1140448928 21546320 10170960
11:16:13 9651 8192000000 10021381088 10416967680 1100960288 11.847 100 38 73 0 1129201040 20211056 8029696
11:17:24 70640 8192000000 9986711792 10404990976 1065495232 11.509 98 33 67 1 1092365168 19584512 7285424
11:19:36 6505 8192000000 9948365184 10327719936 1002445584 10.902 98 47 94 1 1034137888 21421696 10270608
11:20:45 5942 8192000000 9936295728 10315915264 1010894112 10.984 99 54 106 2 1045214064 22786656 11533296
11:21:54 13205 8192000000 9964323664 10362523648 1042172768 11.286 100 35 69 0 1081923680 32776560 6974352
11:23:39 2963 8192000000 9967953536 10366418944 1058106832 11.438 100 34 70 0 1093445200 28028048 7310320
11:24:45 4586 8192000000 9974648784 10371174400 1064206096 11.497 100 50 101 0 1101858880 26103168 11549616
11:25:51 8277 8192000000 9989073952 10382204928 1072187056 11.573 100 35 70 2 1098655536 18033312 8435168
11:27:00 47972 8192000000 10010473120 10410213376 1094409664 11.785 100 35 68 3 1119404112 17435984 7558464
11:28:53 5331 8192000000 10044097392 10446102528 1107661200 11.910 100 40 79 1 1151057104 35079744 8316160
11:30:07 7208 8192000000 10094810032 10491514880 1121348352 12.040 100 61 121 0 1200306384 65270912 13687120
11:31:22 5706 8192000000 10121624784 10522968064 1133763104 12.157 100 52 99 1 1228590800 84009312 10818384
11:32:34 15055 8192000000 10178881760 10588667904 1148180352 12.292 100 56 112 1 1275677568 115588512 11908704
11:34:08 9534 8192000000 10184925376 10586218496 1147371984 12.285 99 65 129 1 1282436864 120415264 14649616
11:35:21 3451 8192000000 10215388608 10626723840 1173720704 12.532 100 63 125 0 1318754672 131486496 13547472
11:36:36 5910 8192000000 10249218464 10664755200 1171315632 12.509 100 54 108 0 1351680224 168200656 12163936
11:37:48 4023 8192000000 10279196976 10686152704 1164442880 12.445 99 67 132 4 1377699024 198697680 14558464
11:38:56 4216 8192000000 10335397968 10748121088 1176312208 12.556 100 63 126 1 1438776704 248114400 14350096
11:40:03 961 8192000000 10372948576 10785878016 1186689024 12.653 100 90 174 0 1474490112 267937392 19863696
11:41:10 6674 8192000000 10418965344 10823897088 1171415584 12.510 100 77 153 1 1510983056 321597280 17970192
11:42:24 6236 8192000000 10387239168 10793803776 1138985312 12.206 99 66 132 0 1478263712 323363264 15915136
11:43:40 1858 8192000000 10429605808 10838515712 1157752400 12.382 99 81 161 2 1527704160 350688208 19263552
11:44:45 5774 8192000000 10430748000 10865909760 1138809424 12.204 99 54 108 0 1527721456 375998464 12913568
11:45:59 6123 8192000000 10430522112 10875527168 1131249968 12.133 99 58 116 0 1523528944 378007520 14271456
11:47:09 10438 8192000000 10365251680 10819993600 1127632272 12.099 99 39 75 3 1460506208 323556304 9317632
11:48:29 11586 8192000000 10340567504 10776698880 1114198448 11.972 99 30 60 0 1441371904 319318848 7854608
11:49:45 25671 8192000000 10200185024 10680766464 1111109808 11.943 99 20 41 1 1338289616 222283664 4896144
11:51:20 33406 8192000000 10204566992 10641620992 1073724208 11.588 97 15 30 1 1269927904 191980432 4223264
11:52:54 55820 8192000000 10076161024 10519146496 1087800128 11.722 98 26 45 1 1155208272 62610592 4797552
11:54:53 31289 8192000000 10093526976 10565173248 1116381872 11.993 100 14 28 2 1182190464 62267968 3540624
11:56:27 87785 8192000000 10070763712 10533888000 1103414784 11.870 98 31 59 0 1178834256 68567008 6852464
11:58:57 89637 8192000000 10061914416 10523795456 1127607504 12.099 100 32 64 1 1163433072 29064224 6761344
12:01:28 57117 8192000000 10096992800 10545864704 1096368656 11.803 96 35 69 4 1172440496 68665200 7406640
12:03:40 55396 8192000000 10125805008 10590322688 1138029840 12.197 100 22 44 0 1229781664 87208720 4543104
12:05:37 40552 8192000000 10085050112 10534387712 1138832496 12.205 100 11 22 0 1196628352 55250768 2545088
12:07:18 83015 8192000000 10069853744 10521202688 1140862672 12.224 100 20 39 2 1179312704 34491024 3959008
12:09:41 63505 8192000000 10061998992 10552365056 1142023520 12.235 100 9 18 1 1168801104 25028896 1748688
12:11:47 48453 8192000000 10093401792 10567159808 1145843456 12.270 100 19 37 1 1223668608 73825920 3999232
12:14:45 47740 8192000000 10090654912 10564018176 1155520720 12.361 100 11 22 2 1206036896 48231104 2285072
12:16:36 68215 8192000000 10102669072 10525990912 1111342368 11.945 96 25 50 1 1148762672 32186240 5234064
12:18:50 56210 8192000000 10102223792 10555150336 1132869184 12.148 98 11 22 2 1196822992 61734784 2219024
12:20:56 65532 8192000000 10181332032 10636308480 1128204240 12.104 97 14 28 1 1240320240 109002992 3113008
12:23:14 46314 8192000000 10094348496 10517282816 1112403376 11.955 98 23 46 1 1162879600 45099648 5376576
12:25:03 119900 8192000000 10096469984 10586705920 1125289728 12.077 99 19 38 0 1194376144 64312016 4774400
12:28:08 36516 8192000000 10044558560 10524729344 1124060672 12.065 99 7 14 0 1181547408 56174000 1312736
12:29:47 27512 8192000000 10037657216 10483384320 1088486064 11.728 96 12 24 2 1111054240 20160976 2407200
12:31:59 52744 8192000000 10112002624 10570579968 1125129152 12.075 98 20 40 0 1186837136 57499616 4208368
12:33:52 78727 8192000000 10114735088 10589556736 1081679232 11.663 95 20 40 0 1190238496 103767520 4791744
12:36:13 45260 8192000000 10081265760 10530934784 1070424240 11.556 96 24 48 0 1155826768 80461168 4941360
12:38:02 14049 8192000000 10063450912 10483523584 1099007184 11.828 99 47 90 0 1139660160 31409056 9243920
12:39:20 10035 8192000000 10087329408 10514075648 1087966432 11.723 98 60 120 2 1161849216 62136944 11745840
12:40:30 43339 8192000000 10113058800 10560757760 1108101936 11.914 99 42 84 2 1165144752 48741712 8301104
12:42:14 37736 8192000000 10095226288 10558029824 1120172752 12.029 100 39 78 2 1194159152 65548736 8437664
12:43:58 92021 8192000000 10064841376 10530381824 1112919008 11.960 100 23 46 0 1174708784 57053376 4736400
12:46:30 48946 8192000000 10059928272 10534756352 1083663248 11.682 97 9 18 1 1149784368 63871072 2250048
12:48:40 65573 8192000000 10044877520 10481119232 1086474064 11.709 97 27 52 0 1117460496 25601776 5384656
12:50:46 43727 8192000000 10093671648 10527977472 1127233104 12.095 100 21 42 0 1177010848 45494848 4282896
12:52:30 36968 8192000000 10131625184 10564444160 1144248656 12.255 100 28 54 4 1225598752 74907664 6442432
12:54:11 98590 8192000000 10114847056 10671177728 1123933344 12.064 98 16 32 0 1224794128 97517504 3343280
12:56:54 61054 8192000000 10093304096 10545721344 1141566688 12.230 99 13 24 1 1173970432 30269088 2134656
12:59:15 79115 8192000000 10087287504 10556018688 1132778192 12.148 98 12 23 1 1164422576 29723536 1920848
13:01:36 77850 8192000000 10119704400 10609840128 1138962752 12.206 98 7 14 1 1198106704 57366496 1777456
13:04:56 74441 8192000000 10104164688 10571608064 1102452016 11.861 96 31 60 1 1130160304 21269008 6439280
13:07:11 89490 8192000000 10086879232 10537320448 1128034432 12.103 98 13 26 0 1178169952 47742640 2392880
13:09:42 88723 8192000000 10096766160 10561249280 1132448576 12.144 98 16 32 0 1189174016 53269840 3455600
13:12:11 92066 8192000000 10121789616 10555637760 1086223152 11.707 95 24 48 1 1136539168 45177456 5138560
13:14:43 112664 8192000000 10075328576 10541875200 1121282944 12.039 99 26 52 0 1177913552 50647120 5983488
13:17:37 74972 8192000000 10105962448 10583613440 1135852480 12.176 100 14 28 0 1213123632 73833472 3437680
13:19:52 83470 8192000000 10056819360 10517266432 1078890368 11.637 96 12 24 2 1113622832 32538976 2193488
13:22:28 47433 8192000000 10057018432 10521018368 1109980144 11.932 99 14 28 1 1162483248 49640048 2863056
13:24:15 56340 8192000000 10057077536 10492301312 1083249872 11.678 97 21 42 0 1120696432 33255248 4191312
13:26:12 58786 8192000000 8982244224 9255129088 134153136 1.611 13 3 4 1 168898000 34013104 731760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-02-2021 13:28:38 to 15-02-2021 13:28:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-02-2021 13:28:54 to 15-02-2021 13:28:56.

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          239333
MON$OLDEST_ACTIVE               239334
MON$OLDEST_SNAPSHOT             239334
MON$NEXT_TRANSACTION            239334
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                       2103424
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/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 ---
:496: 
:497: mscs-test-161.budzdorov.ru    Mon Feb 15 13:27:17 2021
:498:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:499: 
:500: 
:501: mscs-test-161.budzdorov.ru    Mon Feb 15 13:27:17 2021
:502:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:503: 
--- end of diff output ---

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

15.02.21 13:28:57. 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-02-15 13:28:59 No severe PSQL-related problems occured
15.02.21 13:29:01. End of report.