Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.03.2021 04:30
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16827284 kB
MemAvailable 31541036 kB
Buffers 20 kB
Cached 14804828 kB
SwapCached 0 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-03-19 04:26:59
DTS_END                         2021-03-19 04:26:59.7330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57702
TRN_ID                          224411
ATT_ID                          573
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: 8768.57 26820 1052228
customer order: creation 161.28 412 19353
customer order: refuse 14.20 353 1704
order to supplier: creation 42.31 1497 5077
order to supplier: removal 3.22 140984 386
invoice (draft): creation 43.46 4035 5215
invoice (draft): removal 48.58 1148 5830
invoice accept: apply 39.81 18367 4777
invoice accept: cancel 43.33 997 5200
customer reserve: creation 3767.27 175 452072
customer reserve: removal 4329.58 82 519550
realization accept: apply 78.71 195 9445
realization accept: cancel 73.67 145 8840
payment from customer: creation 56.86 102 6823
payment from customer: removal 5.29 107 635
payment to supplier: creation 47.33 137 5680
payment to supplier: removal 3.31 107 397
service: total inventory turnovers 3.50 3880 420
service: total monetary turnovers 6.08 641 730
service: aggregate perf. data 0.75 22827 90
service: refresh index statistics 0.03 340212 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-03-2021 04:30:20 to 19-03-2021 04:30:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1650
TEST_TIME 2 1455
TEST_TIME 3 2975
TEST_TIME 4 2889
TEST_TIME 5 3388
TEST_TIME 6 3537
TEST_TIME 7 3605
TEST_TIME 8 4046
TEST_TIME 9 4127
TEST_TIME 10 4185
TEST_TIME 11 4413
TEST_TIME 12 4740
TEST_TIME 13 4773
TEST_TIME 14 5283
TEST_TIME 15 5511
TEST_TIME 16 5903
TEST_TIME 17 6247
TEST_TIME 18 6340
TEST_TIME 19 6551
TEST_TIME 20 6747
TEST_TIME 21 6718
TEST_TIME 22 6822
TEST_TIME 23 7261
TEST_TIME 24 7259
TEST_TIME 25 7205
TEST_TIME 26 7331
TEST_TIME 27 7491
TEST_TIME 28 7750
TEST_TIME 29 8186
TEST_TIME 30 8718
TEST_TIME 31 9732
TEST_TIME 32 9917
TEST_TIME 33 9706
TEST_TIME 34 9594
TEST_TIME 35 9750
TEST_TIME 36 9842
TEST_TIME 37 9706
TEST_TIME 38 9574
TEST_TIME 39 9461
TEST_TIME 40 9570
TEST_TIME 41 9512
TEST_TIME 42 9439
TEST_TIME 43 9515
TEST_TIME 44 9445
TEST_TIME 45 9346
TEST_TIME 46 9436
TEST_TIME 47 9413
TEST_TIME 48 9417
TEST_TIME 49 9477
TEST_TIME 50 9456
TEST_TIME 51 9280
TEST_TIME 52 9135
TEST_TIME 53 8996
TEST_TIME 54 9113
TEST_TIME 55 9039
TEST_TIME 56 8984
TEST_TIME 57 9070
TEST_TIME 58 9043
TEST_TIME 59 8989
TEST_TIME 60 8923
TEST_TIME 61 8916
TEST_TIME 62 8882
TEST_TIME 63 8855
TEST_TIME 64 8824
TEST_TIME 65 8807
TEST_TIME 66 8797
TEST_TIME 67 8839
TEST_TIME 68 8778
TEST_TIME 69 8796
TEST_TIME 70 8798
TEST_TIME 71 8824
TEST_TIME 72 8813
TEST_TIME 73 8892
TEST_TIME 74 8955
TEST_TIME 75 8929
TEST_TIME 76 8950
TEST_TIME 77 8920
TEST_TIME 78 9013
TEST_TIME 79 8962
TEST_TIME 80 8897
TEST_TIME 81 8805
TEST_TIME 82 8773
TEST_TIME 83 8730
TEST_TIME 84 8694
TEST_TIME 85 8652
TEST_TIME 86 8637
TEST_TIME 87 8734
TEST_TIME 88 8703
TEST_TIME 89 8692
TEST_TIME 90 8677
TEST_TIME 91 8676
TEST_TIME 92 8688
TEST_TIME 93 8705
TEST_TIME 94 8791
TEST_TIME 95 8862
TEST_TIME 96 8854
TEST_TIME 97 8872
TEST_TIME 98 8900
TEST_TIME 99 8887
TEST_TIME 100 8921
TEST_TIME 101 8858
TEST_TIME 102 8812
TEST_TIME 103 8774
TEST_TIME 104 8762
TEST_TIME 105 8742
TEST_TIME 106 8715
TEST_TIME 107 8661
TEST_TIME 108 8628
TEST_TIME 109 8642
TEST_TIME 110 8611
TEST_TIME 111 8625
TEST_TIME 112 8705
TEST_TIME 113 8731
TEST_TIME 114 8718
TEST_TIME 115 8774
TEST_TIME 116 8746
TEST_TIME 117 8772
TEST_TIME 118 8789
TEST_TIME 119 8775
TEST_TIME 120 8769
TEST_TIME 121 8696

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-03-2021 04:30:24 to 19-03-2021 04:30:27.

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 1467871 1467871 0 0.00 0 39115 7 0 0
doc_list_biud 1467842 1467842 0 0.00 0 41258 37 0 0
sp_add_invoice_to_stock 4924 4777 147 2.98 32 199645 18367 0 147
sp_cancel_adding_invoice 5200 5200 0 0.00 4 92172 997 0 0
sp_cancel_client_order 1704 1704 0 0.00 5 8044 354 0 0
sp_cancel_customer_reserve 519550 519550 0 0.00 1 41260 82 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 2143 108 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 10565 107 0 0
sp_cancel_supplier_invoice 5843 5830 13 0.22 3 16078 1148 0 13
sp_cancel_supplier_order 386 386 0 0.00 9 1183153 140984 0 0
sp_cancel_write_off 8840 8840 0 0.00 1 40806 145 0 0
sp_client_order 19353 19353 0 0.00 4 23407 412 0 0
sp_customer_reserve 452072 452072 0 0.00 0 101634 175 0 0
sp_fill_shopping_cart 29641 29641 0 0.00 1 18014 50 0 0
sp_fill_shopping_cart_clo_res 452072 452072 0 0.00 0 21993 15 0 0
sp_get_clo_for_invoice 4777 4777 0 0.00 0 37244 387 0 0
sp_kill_cost_storno 15072 15072 0 0.00 0 8987 73 0 0
sp_kill_qstorno_ret_qs2qd 1054156 1054156 0 0.00 0 41256 45 0 0
sp_kill_qty_storno 555714 555714 0 0.00 0 41257 96 0 0
sp_lock_selected_doc 19721 19721 0 0.00 0 7608 4 0 0
sp_make_cost_storno 26725 26725 0 0.00 0 38558 61 0 0
sp_multiply_rows_for_pdistr 26696 26696 0 0.00 0 9386 32 0 0
sp_multiply_rows_for_qdistr 19353 19353 0 0.00 0 18916 195 0 0
sp_pay_from_customer 6823 6823 0 0.00 4 8021 103 0 0
sp_pay_to_supplier 5680 5680 0 0.00 5 26608 137 0 0
sp_payment_common 12492 12492 0 0.00 1 8864 19 0 0
sp_qd_handle_on_cancel_clo 1704 1704 0 0.00 0 8025 331 0 0
sp_qd_handle_on_invoice_upd_sts 9969 9969 0 0.00 0 33517 458 0 0
sp_qd_handle_on_reserve_upd_sts 9435 9435 0 0.00 0 14136 60 0 0
sp_reserve_write_off 9445 9445 0 0.00 7 34943 195 0 0
sp_supplier_invoice 5215 5215 0 0.00 7 97896 4036 0 0
sp_supplier_order 5077 5077 0 0.00 5 24954 1497 0 0
srv_aggregate_perf_data 90 90 0 0.00 2483 630395 22828 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2106 111124 47045 0 0
srv_find_qd_qs_mism 1005018 1005018 0 0.00 0 19731 5 0 0
srv_make_invnt_saldo 420 420 0 0.00 71 105955 3881 0 0
srv_make_money_saldo 730 730 0 0.00 1 16018 642 0 0
srv_recalc_idx_stat 4 4 0 0.00 287602 379076 340213 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4777 4777 0 0.00 0 42021 173 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 4487 97 0 0
t$perf-norm:sp_cancel_client_order 1704 1704 0 0.00 0 6906 12 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 2429 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 1409 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 6266 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 6853 27 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 35973 1868 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 16408 32 0 0
t$perf-norm:sp_client_order 19353 19353 0 0.00 0 7462 18 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 3079 9 0 0
t$perf-norm:sp_pay_from_customer 6823 6823 0 0.00 0 8876 12 0 0
t$perf-norm:sp_pay_to_supplier 5680 5680 0 0.00 0 6775 11 0 0
t$perf-norm:sp_reserve_write_off 9445 9445 0 0.00 0 11416 12 0 0
t$perf-norm:sp_supplier_invoice 5215 5215 0 0.00 0 6503 23 0 0
t$perf-norm:sp_supplier_order 5077 5077 0 0.00 0 2915 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 345 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 84 3 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 1822 12 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 6697 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9832 9538 294 2.99 0 6437 14 0 294
v_all_customers 35544 35544 0 0.00 0 26 1 0 0
v_all_suppliers 28998 28998 0 0.00 0 20 1 0 0
v_all_wares 38704 38704 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 2955 15 0 0
v_cancel_client_order 3408 3408 0 0.00 0 2275 5 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 1264 9 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1384 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 5650 11 0 0
v_cancel_supplier_invoice 878 852 26 2.96 0 1085 14 0 26
v_cancel_supplier_order 772 772 0 0.00 0 1397 7 0 0
v_cancel_write_off 1834 1834 0 0.00 0 2395 15 0 0
v_max_id_clo_ord 5076 5076 0 0.00 0 927 2 0 0
v_max_id_clo_res 949 949 0 0.00 0 3792 30 0 0
v_max_id_ord_sup 5213 5213 0 0.00 0 6875 5 0 0
v_max_non_paid_invoice 1462 1462 0 0.00 0 3239 4 0 0
v_max_non_paid_realizn 3628 3628 0 0.00 0 746 2 0 0
v_min_id_clo_ord 5076 5076 0 0.00 0 4263 6 0 0
v_min_id_clo_res 949 949 0 0.00 0 10174 41 0 0
v_min_id_ord_sup 5213 5213 0 0.00 0 6828 12 0 0
v_min_non_paid_invoice 5672 5672 0 0.00 0 8860 13 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 6313 11 0 0
v_our_firm 9544 9544 0 0.00 0 97 1 0 0
v_reserve_write_off 18870 18870 0 0.00 0 6152 6 0 0
x_lock_depdocs_on_canc_invoice 10604 10604 0 0.00 0 14665 124 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 6690 360 0 0
x_make_qstorno_1000_1200 5077 5077 0 0.00 2 24944 1415 0 0
x_make_qstorno_1200_2000 5215 5215 0 0.00 4 97881 3835 0 0
x_make_qstorno_2100_3300 452072 452072 0 0.00 0 101631 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-03-2021 04:30:28 to 19-03-2021 04:30:32.

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
01:57:02 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:03 30713 8192000000 9735737200 10032218112 809916368 8.997 100 94 188 0 841300208 7988464 23395376
01:59:43 14907 8192000000 9767396336 10073030656 839011872 9.290 100 88 176 2 873210208 13019216 21179120
02:01:14 22831 8192000000 9817815680 10133319680 871798896 9.618 100 89 178 0 923248992 31179120 20270976
02:02:39 11189 8192000000 9860410032 10195181568 908817088 9.986 100 75 150 1 971298128 45296320 17184720
02:03:52 13178 8192000000 9914977648 10259234816 957482336 10.464 100 71 140 0 1024750912 52869696 14398880
02:05:09 5690 8192000000 9951955456 10307481600 1001225968 10.890 100 70 140 1 1074957360 59658336 14073056
02:06:17 9140 8192000000 9968663216 10334642176 1017505536 11.048 100 69 136 3 1090642288 60431424 12705328
02:07:29 2752 8192000000 10010036960 10375278592 1045372496 11.316 100 88 173 1 1125956640 63387952 17196192
02:08:36 2354 8192000000 10031492816 10400759808 1049756304 11.358 100 90 182 0 1145401680 76698848 18946528
02:09:40 1656 8192000000 10039218656 10413457408 1059217408 11.449 100 75 150 0 1148898656 74330960 15350288
02:10:43 13456 8192000000 10030444160 10421415936 1062071392 11.476 100 47 94 0 1115799456 44653936 9074128
02:11:57 5826 8192000000 9997057696 10389458944 1068397200 11.537 100 30 59 2 1111205888 36548576 6260112
02:13:05 64079 8192000000 9975695360 10375118848 1075616384 11.606 100 34 65 0 1101792080 18938832 7236864
02:15:09 10373 8192000000 9992896272 10398601216 1083308112 11.679 100 33 66 1 1115959632 25529488 7122032
02:16:22 7744 8192000000 10002155952 10407878656 1087045088 11.715 100 23 44 0 1112509408 21000304 4464016
02:17:30 54080 8192000000 9990925904 10402566144 1076616288 11.615 99 47 92 2 1102448144 15526560 10305296
02:19:25 5566 8192000000 9979929040 10368471040 1052618784 11.386 98 38 75 1 1087289680 26868064 7802832
02:20:33 10600 8192000000 9949146416 10348761088 1001342096 10.892 95 31 63 0 1028446032 20300800 6803136
02:21:46 8457 8192000000 9945131552 10332393472 1010034096 10.976 98 38 78 1 1042478960 24155744 8289120
02:22:57 23222 8192000000 9936654544 10311974912 1014674800 11.021 99 39 79 4 1033023088 9376048 8972240
02:24:21 3773 8192000000 9914257008 10302484480 1015481872 11.028 100 45 89 1 1032755216 6615568 10657776
02:25:26 9516 8192000000 9973124928 10370273280 1051770048 11.378 100 35 68 1 1078213856 18308240 8135568
02:26:39 12792 8192000000 9991928400 10396999680 1067645344 11.530 100 32 64 2 1096927904 22802608 6479952
02:27:56 59761 8192000000 10017568848 10410778624 1082859584 11.675 100 44 86 1 1119226816 27669664 8697568
02:30:10 35832 8192000000 10058975872 10464505856 1102253744 11.859 100 53 106 0 1169668544 56913952 10500848
02:31:57 22618 8192000000 10065030528 10470539264 1113998592 11.970 100 32 62 2 1178943872 58008000 6937280
02:33:26 11867 8192000000 10126523728 10527076352 1134768080 12.166 100 58 116 1 1234660000 87567472 12324448
02:34:47 3476 8192000000 10153605152 10567360512 1142455616 12.239 100 60 118 0 1260626720 105395344 12775760
02:35:59 6543 8192000000 10180401920 10588438528 1149839504 12.308 100 57 115 0 1293847328 131476720 12531104
02:37:25 14245 8192000000 10246427888 10655834112 1169411536 12.491 100 60 118 2 1348730576 165582848 13736192
02:38:46 9706 8192000000 10249826720 10669740032 1169685616 12.494 100 67 133 0 1350107040 166014224 14407200
02:40:00 9360 8192000000 10318139776 10734764032 1177218048 12.564 100 63 126 0 1419524240 227848224 14457968
02:41:16 2360 8192000000 10294246928 10711511040 1182963856 12.618 100 61 122 0 1400074880 202689264 14421760
02:42:23 3770 8192000000 10312100144 10724352000 1185775392 12.644 100 82 165 2 1408568336 204768752 18024192
02:43:32 2996 8192000000 10310346080 10720108544 1162558832 12.427 98 82 157 0 1392979216 212651104 17769280
02:44:42 2524 8192000000 10329871024 10733584384 1154501984 12.352 98 83 163 0 1429418672 257452656 17464032
02:45:50 2105 8192000000 10337594096 10742226944 1163757632 12.438 100 84 166 2 1437176576 253473024 19945920
02:46:58 12134 8192000000 10374013136 10796027904 1135071200 12.169 98 48 96 0 1462874816 315797504 12006112
02:48:18 6871 8192000000 10417514432 10828423168 1167661728 12.475 100 62 125 0 1519113536 336547920 14903888
02:49:32 8666 8192000000 10438807040 10881478656 1170701168 12.503 100 53 106 0 1539313184 355044272 13567744
02:50:49 12164 8192000000 10511216080 10949115904 1178547376 12.577 100 41 82 0 1611682112 422343152 10791584
02:52:10 11845 8192000000 10547289808 10989764608 1162353056 12.425 99 45 88 1 1652367792 477860368 12154368
02:53:35 8885 8192000000 10556111360 11008364544 1155114016 12.357 99 54 108 0 1655115920 484698096 15303808
02:54:54 9532 8192000000 10533742944 10991513600 1154014080 12.347 100 36 72 0 1632927072 468828480 10084512
02:56:44 22266 8192000000 10299084144 10821783552 1121896704 12.045 100 23 46 2 1458454448 330656640 5901104
02:58:08 8755 8192000000 10109441904 10553856000 1146446928 12.276 100 46 88 1 1194094784 38688352 8959504
02:59:18 57956 8192000000 10137256832 10582716416 1139308464 12.209 100 33 66 1 1208309888 62225984 6775440
03:01:22 85270 8192000000 10088747168 10632355840 1110856560 11.941 98 15 30 0 1202249664 88401200 2991904
03:03:56 69134 8192000000 10115720064 10563702784 1120733296 12.034 99 22 44 0 1182078576 56791664 4553616
03:06:18 61660 8192000000 10027302144 10480025600 1093391360 11.775 97 15 31 1 1179034496 81957264 3685872
03:08:40 111124 8192000000 10052476416 10517311488 1095988816 11.800 97 11 22 1 1136259648 37972736 2298096
03:11:33 53987 8192000000 10116412864 10553389056 1129872032 12.120 100 18 36 0 1185428944 52102256 3454656
03:13:28 91548 8192000000 10064442144 10496286720 1093519024 11.776 96 10 20 1 1124163168 28534112 2110032
03:16:00 87971 8192000000 10052465536 10509783040 1082691376 11.673 96 13 25 0 1112597696 27432896 2473424
03:18:30 91846 8192000000 10106411984 10542829568 1125271840 12.077 100 28 57 0 1210094736 78614400 6208496
03:21:09 42663 8192000000 10105154960 10557693952 1123525152 12.060 99 36 72 3 1174233392 42229136 8479104
03:22:52 47953 8192000000 10074361360 10566926336 1141027936 12.225 100 11 22 1 1229918912 86678352 2212624
03:24:40 31059 8192000000 10083445888 10539098112 1145242896 12.265 100 11 22 0 1185985024 38360624 2381504
03:26:12 45134 8192000000 10074267008 10511781888 1099824288 11.836 96 13 26 0 1147614368 44911648 2878432
03:27:59 77653 8192000000 10053921056 10503028736 1123673888 12.062 99 15 29 2 1160484576 33712096 3098592
03:30:24 21726 8192000000 10036681712 10482061312 1104212016 11.878 97 20 40 0 1123025168 15093680 3719472
03:31:51 24054 8192000000 10080888992 10503282688 1110682064 11.939 97 41 83 1 1149998880 29685504 9631312
03:33:16 59507 8192000000 10054076160 10519044096 1093700144 11.778 96 12 23 0 1138532512 42026368 2806000
03:35:23 72192 8192000000 10075841952 10537750528 1115831536 11.988 99 27 54 0 1189313456 67965248 5516672
03:37:37 73451 8192000000 10098970272 10533003264 1119359312 12.021 98 29 58 1 1190275056 65010048 5905696
03:39:51 95142 8192000000 10061572848 10521362432 1030082512 11.169 91 18 36 1 1074748912 41314720 3351680
03:42:35 49839 8192000000 10104288752 10560860160 1106068960 11.895 99 42 84 1 1180197728 65223632 8905136
03:44:38 83446 8192000000 10061019392 10559459328 1119673856 12.024 100 23 46 0 1188398912 63842432 4882624
03:48:08 73796 8192000000 10099944880 10550923264 1110260336 11.935 99 18 36 0 1186502864 71822976 4419552
03:50:37 86839 8192000000 10113470672 10621579264 1113126976 11.962 99 17 34 0 1241258144 124032352 4098816
03:53:25 67201 8192000000 10050437504 10487025664 1081198688 11.659 96 12 24 0 1121154176 37488592 2466896
03:55:32 71148 8192000000 10106477088 10537656320 1108729776 11.920 98 24 48 2 1192573232 78547344 5296112
03:57:47 40351 8192000000 10094373680 10527391744 1118647040 12.014 98 27 54 0 1160780544 36430864 5702640
03:59:32 80909 8192000000 10125001712 10637148160 1130522096 12.126 99 15 30 2 1228879216 95188384 3168736
04:02:00 47542 8192000000 10103161824 10549456896 1126671232 12.090 99 20 40 2 1151927264 21422752 3833280
04:03:52 34883 8192000000 10123942784 10553020416 1137486656 12.192 100 28 56 0 1205656384 62614048 5555680
04:05:31 24294 8192000000 10136793296 10561773568 1124849904 12.073 99 59 118 2 1207348544 70822640 11676000
04:06:56 86696 8192000000 10089110048 10566254592 1132877936 12.148 100 14 28 0 1199449712 63447344 3124432
04:09:22 43190 8192000000 10073302672 10539708416 1138288896 12.199 100 8 14 1 1192270736 52169536 1812304
04:11:11 48000 8192000000 10068018928 10558058496 1123036336 12.056 99 5 10 0 1161397008 37463264 897408
04:13:04 84808 8192000000 10111882800 10578964480 1144510976 12.258 100 18 36 1 1211294560 62850976 3932608
04:15:34 76993 8192000000 10168104176 10619977728 1133509280 12.154 98 25 50 1 1223948208 85392896 5046032
04:18:14 104368 8192000000 10095903200 10607091712 1115199424 11.982 97 22 44 0 1213342176 93417248 4725504
04:20:59 89668 8192000000 10086999264 10520027136 1132535712 12.145 99 24 48 1 1172714608 34933872 5245024
04:23:29 90552 8192000000 10067352624 10545430528 1102836896 11.865 97 18 36 0 1163210192 56872960 3500336
04:26:05 89815 8192000000 9695653264 10121248768 780699968 8.700 70 17 31 2 832312432 48249296 3363168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-03-2021 04:30:34 to 19-03-2021 04:30:49.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-03-2021 04:30:50 to 19-03-2021 04:30:52.

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          225584
MON$OLDEST_ACTIVE               225585
MON$OLDEST_SNAPSHOT             225585
MON$NEXT_TRANSACTION            225585
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                       2102144
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2389 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.2389 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Fri Mar 19 04:28:01 2021
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Fri Mar 19 04:28:01 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
--- end of diff output ---

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

19.03.21 04:30:53. 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-03-19 04:30:55 No severe PSQL-related problems occured
19.03.21 04:30:57. End of report.