Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.2021 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16094324 kB
MemAvailable 30775068 kB
Buffers 20 kB
Cached 15537612 kB
SwapCached 2484 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-11-04 04:37:08
DTS_END                         2021-11-04 04:37:08.6830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55962
TRN_ID                          229612
ATT_ID                          592
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: 8823.13 24521 1058775
customer order: creation 165.98 425 19918
customer order: refuse 14.31 317 1717
order to supplier: creation 44.73 1340 5368
order to supplier: removal 3.21 112766 385
invoice (draft): creation 44.32 3330 5318
invoice (draft): removal 50.01 885 6001
invoice accept: apply 40.57 16602 4868
invoice accept: cancel 45.22 782 5426
customer reserve: creation 3679.54 165 441545
customer reserve: removal 4460.10 65 535212
realization accept: apply 80.71 172 9685
realization accept: cancel 71.64 130 8597
payment from customer: creation 57.00 133 6840
payment from customer: removal 5.65 87 678
payment to supplier: creation 46.78 127 5613
payment to supplier: removal 3.42 64 410
service: total inventory turnovers 3.74 3361 449
service: total monetary turnovers 5.43 412 651
service: aggregate perf. data 0.75 19747 90
service: refresh index statistics 0.03 329515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2021 04:38:00 to 04-11-2021 04:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5505
TEST_TIME 2 4894
TEST_TIME 3 4609
TEST_TIME 4 5050
TEST_TIME 5 5172
TEST_TIME 6 5150
TEST_TIME 7 5318
TEST_TIME 8 5453
TEST_TIME 9 5624
TEST_TIME 10 5976
TEST_TIME 11 6304
TEST_TIME 12 7045
TEST_TIME 13 7363
TEST_TIME 14 7659
TEST_TIME 15 7983
TEST_TIME 16 8171
TEST_TIME 17 8138
TEST_TIME 18 8566
TEST_TIME 19 8941
TEST_TIME 20 9000
TEST_TIME 21 9432
TEST_TIME 22 9944
TEST_TIME 23 10813
TEST_TIME 24 11022
TEST_TIME 25 10920
TEST_TIME 26 10570
TEST_TIME 27 10246
TEST_TIME 28 10271
TEST_TIME 29 10265
TEST_TIME 30 10263
TEST_TIME 31 10092
TEST_TIME 32 10004
TEST_TIME 33 9942
TEST_TIME 34 9964
TEST_TIME 35 9764
TEST_TIME 36 9575
TEST_TIME 37 9380
TEST_TIME 38 9369
TEST_TIME 39 9310
TEST_TIME 40 9171
TEST_TIME 41 9097
TEST_TIME 42 8944
TEST_TIME 43 8875
TEST_TIME 44 8788
TEST_TIME 45 8766
TEST_TIME 46 8687
TEST_TIME 47 8677
TEST_TIME 48 8692
TEST_TIME 49 8804
TEST_TIME 50 8748
TEST_TIME 51 8692
TEST_TIME 52 8691
TEST_TIME 53 8705
TEST_TIME 54 8818
TEST_TIME 55 8808
TEST_TIME 56 8876
TEST_TIME 57 9043
TEST_TIME 58 9032
TEST_TIME 59 9136
TEST_TIME 60 8995
TEST_TIME 61 8907
TEST_TIME 62 8889
TEST_TIME 63 8876
TEST_TIME 64 8797
TEST_TIME 65 8756
TEST_TIME 66 8772
TEST_TIME 67 8812
TEST_TIME 68 8743
TEST_TIME 69 8769
TEST_TIME 70 8831
TEST_TIME 71 8862
TEST_TIME 72 8802
TEST_TIME 73 8793
TEST_TIME 74 8865
TEST_TIME 75 8966
TEST_TIME 76 8982
TEST_TIME 77 8928
TEST_TIME 78 9117
TEST_TIME 79 9099
TEST_TIME 80 8996
TEST_TIME 81 8956
TEST_TIME 82 8946
TEST_TIME 83 8917
TEST_TIME 84 8877
TEST_TIME 85 8833
TEST_TIME 86 8830
TEST_TIME 87 8800
TEST_TIME 88 8777
TEST_TIME 89 8762
TEST_TIME 90 8808
TEST_TIME 91 8822
TEST_TIME 92 8822
TEST_TIME 93 8912
TEST_TIME 94 8914
TEST_TIME 95 8874
TEST_TIME 96 8937
TEST_TIME 97 8997
TEST_TIME 98 8968
TEST_TIME 99 8986
TEST_TIME 100 9004
TEST_TIME 101 8940
TEST_TIME 102 8889
TEST_TIME 103 8928
TEST_TIME 104 8916
TEST_TIME 105 8884
TEST_TIME 106 8850
TEST_TIME 107 8810
TEST_TIME 108 8803
TEST_TIME 109 8755
TEST_TIME 110 8765
TEST_TIME 111 8784
TEST_TIME 112 8761
TEST_TIME 113 8750
TEST_TIME 114 8744
TEST_TIME 115 8736
TEST_TIME 116 8744
TEST_TIME 117 8850
TEST_TIME 118 8930
TEST_TIME 119 8900
TEST_TIME 120 8829

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-11-2021 04:38:03 to 04-11-2021 04:38:06.

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 1467850 1467850 0 0.00 0 13694 6 0 0
doc_list_biud 1467849 1467849 0 0.00 0 32156 30 0 0
sp_add_invoice_to_stock 4987 4868 119 2.38 40 192525 16603 0 119
sp_cancel_adding_invoice 5426 5426 0 0.00 6 56078 782 0 0
sp_cancel_client_order 1717 1717 0 0.00 4 7822 317 0 0
sp_cancel_customer_reserve 535212 535212 0 0.00 1 32158 65 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 4651 88 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3333 65 0 0
sp_cancel_supplier_invoice 6010 6001 9 0.14 3 15915 886 0 9
sp_cancel_supplier_order 385 385 0 0.00 7 985077 112766 0 0
sp_cancel_write_off 8597 8597 0 0.00 1 14542 131 0 0
sp_client_order 19918 19918 0 0.00 4 29417 425 0 0
sp_customer_reserve 441545 441545 0 0.00 0 41218 165 0 0
sp_fill_shopping_cart 30604 30604 0 0.00 1 20969 47 0 0
sp_fill_shopping_cart_clo_res 441529 441529 0 0.00 0 20706 19 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 27483 432 0 0
sp_kill_cost_storno 15111 15111 0 0.00 0 11730 56 0 0
sp_kill_qstorno_ret_qs2qd 1085407 1085407 0 0.00 0 32153 35 0 0
sp_kill_qty_storno 571865 571865 0 0.00 0 32156 75 0 0
sp_lock_selected_doc 20195 20195 0 0.00 0 8443 5 0 0
sp_make_cost_storno 26980 26980 0 0.00 0 13688 59 0 0
sp_multiply_rows_for_pdistr 26979 26979 0 0.00 0 8053 29 0 0
sp_multiply_rows_for_qdistr 19918 19918 0 0.00 0 26371 240 0 0
sp_pay_from_customer 6840 6840 0 0.00 4 13670 134 0 0
sp_pay_to_supplier 5613 5613 0 0.00 4 15842 127 0 0
sp_payment_common 12452 12452 0 0.00 1 10820 25 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 0 7814 283 0 0
sp_qd_handle_on_invoice_upd_sts 10268 10268 0 0.00 0 24734 325 0 0
sp_qd_handle_on_reserve_upd_sts 9685 9685 0 0.00 0 9294 45 0 0
sp_reserve_write_off 9685 9685 0 0.00 7 15441 172 0 0
sp_supplier_invoice 5318 5318 0 0.00 5 74760 3331 0 0
sp_supplier_order 5368 5368 0 0.00 6 74968 1340 0 0
srv_aggregate_perf_data 91 90 1 1.09 2942 421986 19748 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 3317 148602 49563 0 0
srv_find_qd_qs_mism 1013091 1013091 0 0.00 0 10638 5 0 0
srv_make_invnt_saldo 449 449 0 0.00 46 111671 3361 0 0
srv_make_money_saldo 651 651 0 0.00 1 10249 413 0 0
srv_recalc_idx_stat 4 4 0 0.00 228220 421510 329515 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 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4868 4868 0 0.00 0 18258 157 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 4107 91 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 3307 12 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 2901 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 943 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 643 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1515 19 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 26185 1548 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 4714 13 0 0
t$perf-norm:sp_client_order 19918 19918 0 0.00 0 7416 16 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 261 2 0 0
t$perf-norm:sp_pay_from_customer 6840 6840 0 0.00 0 5565 11 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 9805 12 0 0
t$perf-norm:sp_reserve_write_off 9685 9685 0 0.00 0 26268 12 0 0
t$perf-norm:sp_supplier_invoice 5318 5318 0 0.00 0 6693 16 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 6348 18 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 541 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 68 2 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 4562 20 0 0
t$perf-norm:srv_make_money_saldo 651 651 0 0.00 0 365 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 138 37 0 0
v_add_invoice_to_stock 9922 9684 238 2.39 0 4371 17 0 238
v_all_customers 35678 35678 0 0.00 0 85 1 0 0
v_all_suppliers 29680 29680 0 0.00 0 16 1 0 0
v_all_wares 39836 39836 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1381 7 0 0
v_cancel_client_order 3434 3434 0 0.00 0 2880 6 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 4424 9 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1162 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3308 8 0 0
v_cancel_supplier_invoice 802 784 18 2.24 0 657 13 0 18
v_cancel_supplier_order 770 770 0 0.00 0 3433 10 0 0
v_cancel_write_off 2012 2012 0 0.00 0 1120 12 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 651 1 0 0
v_max_id_clo_res 998 998 0 0.00 0 4160 22 0 0
v_max_id_ord_sup 5318 5318 0 0.00 0 7053 5 0 0
v_max_non_paid_invoice 1458 1458 0 0.00 0 286 2 0 0
v_max_non_paid_realizn 3872 3872 0 0.00 0 4994 5 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 1963 4 0 0
v_min_id_clo_res 998 998 0 0.00 0 5535 20 0 0
v_min_id_ord_sup 5318 5318 0 0.00 0 7495 7 0 0
v_min_non_paid_invoice 5612 5612 0 0.00 0 5780 13 0 0
v_min_non_paid_realizn 6840 6840 0 0.00 0 10815 22 0 0
v_our_firm 10094 10094 0 0.00 0 12 1 0 0
v_reserve_write_off 19370 19370 0 0.00 0 4431 7 0 0
x_lock_depdocs_on_canc_invoice 11035 11035 0 0.00 0 15224 93 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 5101 286 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 2 74946 1271 0 0
x_make_qstorno_1200_2000 5318 5318 0 0.00 2 73205 3138 0 0
x_make_qstorno_2100_3300 441545 441545 0 0.00 0 36483 144 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-11-2021 04:38:07 to 04-11-2021 04:38:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 42815 8192000000 9734134416 10033557504 803725600 8.934 100 86 172 0 832198208 7605328 20867280
02:09:58 24693 8192000000 9772365600 10080366592 841962544 9.319 100 89 178 0 877146656 14095120 21088992
02:11:28 16552 8192000000 9811865200 10132652032 873693936 9.637 100 82 163 1 916020448 24286016 18040496
02:12:49 13280 8192000000 9895753776 10231721984 933868640 10.233 100 85 168 2 1006648208 54433456 18346112
02:14:10 8920 8192000000 9932752576 10287874048 974873376 10.634 100 68 136 0 1051123568 62019616 14230576
02:15:22 9736 8192000000 9971222816 10332286976 999125568 10.870 100 72 145 1 1093641152 80087600 14427984
02:16:36 3862 8192000000 10008456288 10373177344 1034571248 11.212 100 81 162 0 1127720720 76854608 16294864
02:17:41 5538 8192000000 10032769744 10404061184 1054685120 11.406 100 92 184 0 1150285088 76614576 18985392
02:18:49 5916 8192000000 10020178000 10399588352 1068964864 11.542 100 84 169 1 1139613552 53028704 17619984
02:19:57 6382 8192000000 10013970688 10404200448 1075505584 11.605 100 42 82 1 1125382704 41498176 8378944
02:21:04 5222 8192000000 10006936336 10392281088 1088231216 11.726 100 56 109 2 1122107152 21307968 12567968
02:22:10 6894 8192000000 10016978624 10424389632 1092832144 11.770 100 46 92 2 1146517920 44327296 9358480
02:23:23 4025 8192000000 10023078480 10411573248 1098132800 11.820 100 72 146 2 1130282704 15210016 16939888
02:24:27 6702 8192000000 10007001968 10415710208 1101606032 11.853 100 36 72 2 1129968800 20369232 7993536
02:25:39 2331 8192000000 10033863952 10429775872 1103959680 11.875 100 53 104 0 1136027552 21371264 10696608
02:26:42 46291 8192000000 10021857488 10407813120 1076994992 11.619 98 57 116 2 1112999296 23088960 12915344
02:28:28 7313 8192000000 9982380432 10385977344 1071073648 11.562 99 29 56 1 1098709040 21530624 6104768
02:29:37 5130 8192000000 9964816704 10342924288 1029484128 11.163 98 42 85 1 1057040512 18852240 8704144
02:30:43 6079 8192000000 9969710192 10362368000 1040540768 11.270 99 36 71 0 1063091776 14525696 8025312
02:31:51 34577 8192000000 9915932832 10309079040 930723168 10.202 90 28 54 1 958864528 22441824 5699536
02:33:26 9295 8192000000 9962589600 10352525312 1029505088 11.164 99 38 72 3 1065749168 28516016 7728064
02:34:38 7922 8192000000 9972430560 10363543552 1055229552 11.411 100 60 119 0 1084929424 15757424 13942448
02:35:49 15155 8192000000 9977832496 10378067968 1070302592 11.555 100 19 38 0 1093233968 18238816 4692560
02:37:05 51855 8192000000 10013449984 10406436864 1083032416 11.676 100 45 90 0 1114783264 22560560 9190288
02:39:04 15380 8192000000 10050107984 10452197376 1110318240 11.935 100 37 74 0 1157561120 39145296 8097584
02:40:22 11753 8192000000 10089259840 10493837312 1130973504 12.131 100 42 81 2 1197321584 57798288 8549792
02:41:41 48570 8192000000 10132260640 10546569216 1138234992 12.199 100 42 84 2 1238253184 91100720 8917472
02:43:35 17739 8192000000 10160183600 10573672448 1152812912 12.336 100 49 94 3 1275350112 111793728 10743472
02:44:55 5870 8192000000 10221292224 10630713344 1162127232 12.423 100 65 129 3 1327123328 150013808 14982288
02:46:14 11666 8192000000 10288300704 10696912896 1174434768 12.538 100 63 126 0 1383191504 194493136 14263600
02:47:29 9834 8192000000 10308542272 10742173696 1183181392 12.620 100 72 147 0 1422509040 222511600 16816048
02:48:42 9327 8192000000 10310103696 10730622976 1173230272 12.527 99 63 123 0 1411266800 223762592 14273936
02:49:59 3352 8192000000 10295881888 10736799744 1186901856 12.655 99 72 144 0 1395153088 191216640 17034592
02:51:06 3314 8192000000 10348573312 10763026432 1201916368 12.794 100 81 158 0 1445943472 225780336 18246768
02:52:14 4575 8192000000 10352092816 10771496960 1182119584 12.610 100 59 118 1 1453915696 257880208 13915904
02:53:21 5030 8192000000 10351825840 10771259392 1167474208 12.473 99 61 119 0 1440840832 258764448 14602176
02:54:49 3707 8192000000 10394391984 10808737792 1153296736 12.340 99 57 114 0 1484506608 317447264 13762608
02:56:02 6627 8192000000 10383121680 10799497216 1153693584 12.344 100 46 92 0 1475815136 311150784 10970768
02:57:11 16152 8192000000 10317734352 10750976000 1120480688 12.032 100 27 54 0 1436810640 308909760 7420192
02:58:31 47686 8192000000 10294294160 10749079552 1092542272 11.767 97 30 60 2 1413624320 312816192 8265856
03:00:30 31270 8192000000 10156694688 10595401728 1101695568 11.854 99 18 36 0 1266983632 161078976 4209088
03:02:02 12517 8192000000 10100605200 10524221440 1102132720 11.858 99 23 46 0 1188030272 80880032 5017520
03:03:16 148601 8192000000 10100157904 10584616960 1095653120 11.796 98 27 52 1 1217074848 115770560 5651168
03:06:45 45673 8192000000 10057870688 10539933696 1099331744 11.831 99 12 24 0 1145594208 43846224 2416240
03:08:32 52687 8192000000 10071876864 10513113088 1116104000 11.990 100 26 53 3 1175541744 53566640 5871104
03:10:24 91690 8192000000 10032060224 10502787072 1045374112 11.316 93 25 50 1 1108895920 58460576 5061232
03:12:58 85644 8192000000 10043264592 10468347904 1100477152 11.842 99 25 50 0 1138223264 32744656 5001456
03:15:25 55493 8192000000 10037460544 10495324160 1084793760 11.693 98 11 22 0 1129661568 42516848 2350960
03:17:23 69652 8192000000 10031464560 10480689152 1098686256 11.825 99 11 22 1 1131113520 30048416 2378848
03:19:33 84645 8192000000 10044413328 10504032256 1110959568 11.942 99 21 40 0 1132883040 17371888 4551584
03:22:07 72246 8192000000 10069076848 10527379456 1124086288 12.066 99 12 24 1 1170450976 44271744 2092944
03:24:39 103243 8192000000 10101172528 10582327296 1105987648 11.894 97 16 32 0 1185628448 76328656 3312144
03:27:22 60552 8192000000 10074429376 10506625024 1136566128 12.183 100 29 57 1 1161867872 19451248 5850496
03:29:30 42500 8192000000 10126416848 10568253440 1140591936 12.221 100 26 52 1 1222385728 76551536 5242256
03:31:20 33750 8192000000 10099594704 10538160128 1118096432 12.009 98 34 68 2 1159116576 33808064 7212080
03:32:55 41973 8192000000 10164720560 10625687552 1153663424 12.344 100 31 62 0 1242150416 81584256 6902736
03:34:37 45980 8192000000 10110751856 10576314368 1145730544 12.269 100 19 38 0 1218948224 69247184 3970496
03:36:29 144696 8192000000 10096512048 10531606528 1126909728 12.092 98 28 56 0 1179258320 46205504 6143088
03:39:55 29321 8192000000 10064853696 10553339904 1149883200 12.308 100 5 10 0 1184967904 34410032 674672
03:42:07 69516 8192000000 10103608768 10555310080 1135751584 12.176 99 13 26 0 1191611616 52925856 2934176
03:44:16 50087 8192000000 10078613984 10514857984 1137763776 12.194 99 30 60 0 1168289568 23670656 6855136
03:46:09 38887 8192000000 10092904976 10547752960 1114830400 11.978 98 23 46 0 1200123808 79768224 5525184
03:48:06 68252 8192000000 10056428368 10501083136 1085895312 11.704 97 18 34 0 1121778544 32404096 3479136
03:50:15 66342 8192000000 10131272400 10576568320 1093174800 11.773 97 13 26 0 1191473760 95738736 2560224
03:52:22 101106 8192000000 10078893712 10543378432 1110377744 11.936 99 24 48 0 1177728800 62555840 4795216
03:55:03 62343 8192000000 10041018304 10509381632 1062521408 11.481 94 7 14 0 1103656528 39932272 1202848
03:57:07 118841 8192000000 10082617344 10509000704 1074373296 11.594 96 40 79 3 1142505984 60008800 8123888
04:00:09 53547 8192000000 10063808480 10517778432 1118130784 12.009 100 28 56 0 1174003616 49564400 6308432
04:02:03 39943 8192000000 10051068256 10514042880 1086389152 11.708 97 12 24 0 1144641392 56099104 2153136
04:03:50 51400 8192000000 10075706032 10556289024 1111317776 11.945 100 11 22 0 1165934560 52365008 2251776
04:06:13 76913 8192000000 10110832304 10584477696 1129499312 12.117 100 15 30 0 1201212832 68323424 3390096
04:08:30 24357 8192000000 10058225248 10492092416 1105383328 11.889 98 30 59 2 1147866720 35680064 6803328
04:09:56 62153 8192000000 10077359952 10583093248 1112771984 11.959 99 14 28 0 1185577824 70075632 2730208
04:11:58 27728 8192000000 10085959392 10519560192 1102444368 11.861 98 27 54 4 1148923072 41385040 5093664
04:13:41 57552 8192000000 10072389760 10543357952 1106418064 11.898 98 26 52 0 1187791904 76088816 5285024
04:15:41 26931 8192000000 10083542080 10497499136 1125180128 12.076 99 52 104 0 1164991840 29303824 10507888
04:17:09 110609 8192000000 10130480464 10618212352 1143299216 12.247 100 40 80 1 1240035504 88685664 8050624
04:20:26 17008 8192000000 10086551472 10587521024 1141111936 12.226 100 17 33 1 1187912864 43283648 3517280
04:21:44 43355 8192000000 10043143248 10512965632 1083186256 11.678 95 5 10 0 1111495904 27534352 775296
04:23:29 69346 8192000000 10047610224 10516406272 1113392960 11.965 98 8 16 1 1135625392 20809840 1422592
04:25:51 88159 8192000000 10064370336 10527350784 1118793744 12.016 98 15 28 0 1160515152 38290672 3430736
04:28:24 41950 8192000000 10062277200 10505961472 1102126336 11.858 96 27 54 1 1144796160 36239664 6430160
04:30:08 45880 8192000000 10120704144 10569412608 1149195024 12.302 100 20 40 1 1210910880 57115936 4599920
04:32:00 90477 8192000000 10213040960 10666143744 1151899232 12.327 99 36 72 0 1265587920 105743952 7944736
04:34:35 44747 8192000000 10140425040 10735820800 1134464112 12.163 99 11 22 0 1260691056 123490320 2736624
04:36:21 50416 8192000000 9689943792 10105667584 775363888 8.646 67 12 24 0 814385424 36963792 2057744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 119 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-11-2021 04:38:29 to 04-11-2021 04:38:31.

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          228217
MON$OLDEST_ACTIVE               230006
MON$OLDEST_SNAPSHOT             230006
MON$NEXT_TRANSACTION            230006
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                       2025568
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2650 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2650 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2650 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2650 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Thu Nov  4 04:37:24 2021
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
:29: 
:30: mscs-test-161.budzdorov.ru    Thu Nov  4 04:37:27 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
--- end of diff output ---

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

04.11.21 04:38:32. 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-11-04 04:38:34 No severe PSQL-related problems occured
04.11.21 04:38:35. End of report.