Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.01.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 17016544 kB
MemAvailable 31299112 kB
Buffers 0 kB
Cached 14700756 kB
SwapCached 4868 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-01-28 13:26:55
DTS_END                         2021-01-28 13:26:55.9090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44198
TRN_ID                          217694
ATT_ID                          600
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: 8292.90 22167 995148
customer order: creation 154.05 428 18486
customer order: refuse 13.19 367 1583
order to supplier: creation 40.77 1371 4892
order to supplier: removal 3.02 124685 362
invoice (draft): creation 40.68 3724 4882
invoice (draft): removal 47.20 908 5664
invoice accept: apply 37.98 19850 4557
invoice accept: cancel 42.20 891 5064
customer reserve: creation 3493.57 195 419228
customer reserve: removal 4156.24 72 498749
realization accept: apply 75.42 170 9050
realization accept: cancel 73.44 121 8813
payment from customer: creation 52.51 118 6301
payment from customer: removal 5.62 88 674
payment to supplier: creation 44.06 127 5287
payment to supplier: removal 3.26 103 391
service: total inventory turnovers 3.46 3392 415
service: total monetary turnovers 5.45 513 654
service: aggregate perf. data 0.77 15897 92
service: refresh index statistics 0.03 270324 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-01-2021 13:28:36 to 28-01-2021 13:28:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1004
TEST_TIME 2 1164
TEST_TIME 3 1911
TEST_TIME 4 3056
TEST_TIME 5 3463
TEST_TIME 6 4136
TEST_TIME 7 4173
TEST_TIME 8 4512
TEST_TIME 9 4576
TEST_TIME 10 4764
TEST_TIME 11 4767
TEST_TIME 12 5092
TEST_TIME 13 5001
TEST_TIME 14 5088
TEST_TIME 15 5551
TEST_TIME 16 5748
TEST_TIME 17 5784
TEST_TIME 18 5909
TEST_TIME 19 6386
TEST_TIME 20 7068
TEST_TIME 21 7371
TEST_TIME 22 7806
TEST_TIME 23 7884
TEST_TIME 24 8006
TEST_TIME 25 8322
TEST_TIME 26 8948
TEST_TIME 27 9162
TEST_TIME 28 9582
TEST_TIME 29 9821
TEST_TIME 30 9603
TEST_TIME 31 9515
TEST_TIME 32 9324
TEST_TIME 33 9246
TEST_TIME 34 9229
TEST_TIME 35 9100
TEST_TIME 36 9065
TEST_TIME 37 9025
TEST_TIME 38 8929
TEST_TIME 39 8784
TEST_TIME 40 8629
TEST_TIME 41 8645
TEST_TIME 42 8650
TEST_TIME 43 8527
TEST_TIME 44 8542
TEST_TIME 45 8644
TEST_TIME 46 8593
TEST_TIME 47 8491
TEST_TIME 48 8545
TEST_TIME 49 8613
TEST_TIME 50 8647
TEST_TIME 51 8768
TEST_TIME 52 8787
TEST_TIME 53 8770
TEST_TIME 54 8774
TEST_TIME 55 8751
TEST_TIME 56 8869
TEST_TIME 57 8779
TEST_TIME 58 8642
TEST_TIME 59 8646
TEST_TIME 60 8563
TEST_TIME 61 8467
TEST_TIME 62 8462
TEST_TIME 63 8478
TEST_TIME 64 8479
TEST_TIME 65 8492
TEST_TIME 66 8484
TEST_TIME 67 8482
TEST_TIME 68 8513
TEST_TIME 69 8507
TEST_TIME 70 8500
TEST_TIME 71 8538
TEST_TIME 72 8630
TEST_TIME 73 8623
TEST_TIME 74 8628
TEST_TIME 75 8635
TEST_TIME 76 8627
TEST_TIME 77 8717
TEST_TIME 78 8648
TEST_TIME 79 8554
TEST_TIME 80 8473
TEST_TIME 81 8415
TEST_TIME 82 8413
TEST_TIME 83 8371
TEST_TIME 84 8418
TEST_TIME 85 8369
TEST_TIME 86 8354
TEST_TIME 87 8363
TEST_TIME 88 8331
TEST_TIME 89 8322
TEST_TIME 90 8407
TEST_TIME 91 8408
TEST_TIME 92 8424
TEST_TIME 93 8424
TEST_TIME 94 8387
TEST_TIME 95 8428
TEST_TIME 96 8470
TEST_TIME 97 8400
TEST_TIME 98 8341
TEST_TIME 99 8333
TEST_TIME 100 8287
TEST_TIME 101 8303
TEST_TIME 102 8258
TEST_TIME 103 8266
TEST_TIME 104 8312
TEST_TIME 105 8315
TEST_TIME 106 8335
TEST_TIME 107 8360
TEST_TIME 108 8339
TEST_TIME 109 8395
TEST_TIME 110 8389
TEST_TIME 111 8367
TEST_TIME 112 8427
TEST_TIME 113 8515
TEST_TIME 114 8483
TEST_TIME 115 8454
TEST_TIME 116 8405
TEST_TIME 117 8364
TEST_TIME 118 8337
TEST_TIME 119 8295
TEST_TIME 120 8294

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-01-2021 13:28:39 to 28-01-2021 13:28:43.

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 1382325 1382325 0 0.00 0 23827 7 0 0
doc_list_biud 1382325 1382325 0 0.00 0 39401 33 0 0
sp_add_invoice_to_stock 4695 4557 138 2.93 41 274910 19851 0 138
sp_cancel_adding_invoice 5064 5064 0 0.00 4 85697 892 0 0
sp_cancel_client_order 1583 1583 0 0.00 4 9357 367 0 0
sp_cancel_customer_reserve 498749 498749 0 0.00 1 26543 72 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 6089 89 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 4012 103 0 0
sp_cancel_supplier_invoice 5676 5664 12 0.21 2 19654 909 0 12
sp_cancel_supplier_order 362 362 0 0.00 9 1134662 124686 0 0
sp_cancel_write_off 8813 8813 0 0.00 1 7791 121 0 0
sp_client_order 18486 18486 0 0.00 4 65249 429 0 0
sp_customer_reserve 419228 419228 0 0.00 0 145615 196 0 0
sp_fill_shopping_cart 28249 28249 0 0.00 1 29439 48 0 0
sp_fill_shopping_cart_clo_res 419226 419226 0 0.00 0 20143 19 0 0
sp_get_clo_for_invoice 4551 4551 0 0.00 0 20894 460 0 0
sp_kill_cost_storno 14942 14942 0 0.00 0 6070 66 0 0
sp_kill_qstorno_ret_qs2qd 1012337 1012337 0 0.00 0 39400 39 0 0
sp_kill_qty_storno 533836 533836 0 0.00 0 39401 83 0 0
sp_lock_selected_doc 18879 18879 0 0.00 0 10317 5 0 0
sp_make_cost_storno 25189 25189 0 0.00 0 23822 52 0 0
sp_multiply_rows_for_pdistr 25189 25189 0 0.00 0 9407 27 0 0
sp_multiply_rows_for_qdistr 18486 18486 0 0.00 0 65241 250 0 0
sp_pay_from_customer 6301 6301 0 0.00 4 11124 119 0 0
sp_pay_to_supplier 5287 5287 0 0.00 4 11531 128 0 0
sp_payment_common 11588 11588 0 0.00 1 8877 24 0 0
sp_qd_handle_on_cancel_clo 1583 1583 0 0.00 0 9351 339 0 0
sp_qd_handle_on_invoice_upd_sts 9615 9615 0 0.00 0 25708 395 0 0
sp_qd_handle_on_reserve_upd_sts 9050 9050 0 0.00 0 14570 45 0 0
sp_reserve_write_off 9050 9050 0 0.00 7 23837 171 0 0
sp_supplier_invoice 4882 4882 0 0.00 8 105240 3724 0 0
sp_supplier_order 4892 4892 0 0.00 5 61800 1371 0 0
srv_aggregate_perf_data 92 92 0 0.00 2735 151825 15898 0 0
srv_fill_mon_cache_memory 56 56 0 0.00 884 147873 54648 0 0
srv_find_qd_qs_mism 951759 951759 0 0.00 0 17234 6 0 0
srv_make_invnt_saldo 415 415 0 0.00 61 148007 3392 0 0
srv_make_money_saldo 654 654 0 0.00 1 10784 513 0 0
srv_recalc_idx_stat 4 4 0 0.00 167908 375926 270324 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4557 4557 0 0.00 0 26875 174 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 1870 85 0 0
t$perf-norm:sp_cancel_client_order 1583 1583 0 0.00 0 8849 14 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 4816 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1082 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 3177 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 716 11 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 18636 1546 0 0
t$perf-norm:sp_cancel_write_off 895 895 0 0.00 0 4561 10 0 0
t$perf-norm:sp_client_order 18486 18486 0 0.00 0 21171 23 0 0
t$perf-norm:sp_customer_reserve 888 888 0 0.00 0 3257 9 0 0
t$perf-norm:sp_pay_from_customer 6301 6301 0 0.00 0 3581 8 0 0
t$perf-norm:sp_pay_to_supplier 5287 5287 0 0.00 0 14113 13 0 0
t$perf-norm:sp_reserve_write_off 9050 9050 0 0.00 0 12157 13 0 0
t$perf-norm:sp_supplier_invoice 4882 4882 0 0.00 0 10452 21 0 0
t$perf-norm:sp_supplier_order 4892 4892 0 0.00 0 4654 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 2070 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 56 56 0 0.00 0 2400 44 0 0
t$perf-norm:srv_make_invnt_saldo 415 415 0 0.00 0 3894 17 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 415 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9378 9102 276 2.94 0 11327 23 0 276
v_all_customers 33214 33214 0 0.00 0 13 1 0 0
v_all_suppliers 26992 26992 0 0.00 0 29 1 0 0
v_all_wares 36972 36972 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 1529 8 0 0
v_cancel_client_order 3166 3166 0 0.00 0 4197 8 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 3489 8 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 0 1927 5 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3995 19 0 0
v_cancel_supplier_invoice 814 790 24 2.94 0 3244 22 0 24
v_cancel_supplier_order 724 724 0 0.00 0 284 3 0 0
v_cancel_write_off 1790 1790 0 0.00 0 2852 15 0 0
v_max_id_clo_ord 4891 4891 0 0.00 0 276 1 0 0
v_max_id_clo_res 888 888 0 0.00 0 6236 27 0 0
v_max_id_ord_sup 4870 4870 0 0.00 0 5276 4 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 448 2 0 0
v_max_non_paid_realizn 3474 3474 0 0.00 0 2829 3 0 0
v_min_id_clo_ord 4891 4891 0 0.00 0 4755 5 0 0
v_min_id_clo_res 888 888 0 0.00 0 7988 45 0 0
v_min_id_ord_sup 4870 4870 0 0.00 0 6553 9 0 0
v_min_non_paid_invoice 5287 5287 0 0.00 0 8874 18 0 0
v_min_non_paid_realizn 6301 6301 0 0.00 0 8248 17 0 0
v_our_firm 9412 9412 0 0.00 0 17 1 0 0
v_reserve_write_off 18100 18100 0 0.00 0 6181 9 0 0
x_lock_depdocs_on_canc_invoice 10333 10333 0 0.00 0 23843 122 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 12524 404 0 0
x_make_qstorno_1000_1200 4892 4892 0 0.00 2 61789 1296 0 0
x_make_qstorno_1200_2000 4882 4882 0 0.00 4 101882 3525 0 0
x_make_qstorno_2100_3300 419228 419228 0 0.00 0 145610 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-01-2021 13:28:44 to 28-01-2021 13:28:48.

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:59 3 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
10:58:00 18348 8192000000 9703552448 9993863168 785893216 8.753 100 87 174 0 814553328 7437440 21222672
10:59:25 14106 8192000000 9744809520 10045710336 827399280 9.173 100 88 176 0 860512576 12456544 20656752
11:00:55 18175 8192000000 9793025168 10106372096 855278272 9.453 100 83 166 0 894696448 20255072 19163104
11:02:22 17152 8192000000 9843305152 10169102336 895428656 9.853 100 83 166 2 951205040 37592128 18184256
11:03:44 11701 8192000000 9907798416 10244665344 940109664 10.294 100 90 180 4 1017568192 58262544 19195984
11:04:59 5790 8192000000 9941939616 10292527104 976774656 10.653 100 67 133 1 1057731824 67350640 13606528
11:06:08 4374 8192000000 9981654208 10338533376 1011347920 10.988 100 91 182 2 1096290528 66367808 18574800
11:07:15 2898 8192000000 9996250400 10361581568 1028955552 11.158 100 82 162 3 1106983472 61953200 16074720
11:08:20 2157 8192000000 10030765952 10394939392 1055319808 11.412 100 97 192 2 1136740784 61276256 20144720
11:09:25 1494 8192000000 10029044416 10396372992 1062884944 11.484 100 92 183 0 1141931856 59840512 19206400
11:10:28 3220 8192000000 10012511584 10394513408 1066502192 11.519 100 53 108 2 1124469152 47200112 10766848
11:11:32 7472 8192000000 10006296208 10400415744 1074311552 11.593 100 35 71 2 1113514720 32064624 7138544
11:12:41 54000 8192000000 10024252528 10433359872 1089651440 11.739 100 49 98 2 1128712384 28804944 10256000
11:14:39 10604 8192000000 10011504800 10410467328 1096519840 11.805 100 37 72 2 1127394256 22851360 8023056
11:15:57 7768 8192000000 10005991856 10411851776 1103132848 11.867 100 39 75 1 1141919568 29696528 9090192
11:17:08 11500 8192000000 10016810384 10419408896 1088453632 11.728 99 30 58 1 1114143776 19054816 6635328
11:18:23 11506 8192000000 10023648832 10405044224 1083810192 11.684 99 79 157 1 1114332416 12112992 18409232
11:19:34 4730 8192000000 9993652192 10389135360 1062789936 11.483 99 54 102 4 1093745296 20596544 10358816
11:20:41 4340 8192000000 9969493632 10342793216 1032695424 11.194 100 61 122 1 1064863504 19046352 13121728
11:21:50 52333 8192000000 9939497632 10318733312 1001026112 10.888 97 29 58 1 1031021376 23171808 6823456
11:23:46 5587 8192000000 9939338576 10327273472 1006691840 10.943 98 42 85 1 1047772704 31654656 9426208
11:24:54 6809 8192000000 9975267664 10362724352 1042714640 11.291 99 42 85 0 1064811904 12440704 9656560
11:26:03 5646 8192000000 9981274640 10369490944 1067483248 11.528 100 27 52 3 1086746512 13484816 5778448
11:27:09 101166 8192000000 10007068832 10405679104 1081313552 11.660 100 28 53 1 1098657792 11605024 5739216
11:30:05 22316 8192000000 10067916368 10473619456 1108315952 11.916 100 51 100 1 1191507392 71885088 11306352
11:31:31 22568 8192000000 10113440320 10509574144 1125019488 12.074 100 66 131 0 1223547408 83864304 14663616
11:32:58 17776 8192000000 10141888608 10548670464 1134136464 12.160 100 58 115 1 1250937920 104652416 12149040
11:34:20 5296 8192000000 10178251680 10579615744 1146544176 12.277 100 60 120 1 1280109120 120595408 12969536
11:35:32 17235 8192000000 10185643440 10590949376 1156021520 12.366 100 58 116 0 1295041424 126516448 12503456
11:37:06 6970 8192000000 10241719984 10651344896 1182246912 12.611 100 63 126 0 1348162544 151780368 14135264
11:38:18 9613 8192000000 10267280128 10679005184 1179612848 12.587 100 56 112 0 1378260800 185580880 13067072
11:39:33 11465 8192000000 10332821232 10740121600 1191941888 12.701 100 77 152 0 1428814560 220309424 16563248
11:40:55 3558 8192000000 10374661024 10778365952 1189185712 12.676 100 93 184 2 1475647376 266247232 20214432
11:42:01 8559 8192000000 10338331600 10776449024 1188339456 12.668 100 60 119 1 1453503776 251561376 13602944
11:43:15 5314 8192000000 10389103152 10799882240 1195251840 12.732 100 82 164 2 1495510320 281258464 19000016
11:44:29 2473 8192000000 10416088480 10816815104 1177083312 12.563 99 84 168 1 1510512880 313934272 19495296
11:45:41 4046 8192000000 10439467456 10885107712 1179104928 12.582 99 82 164 1 1540906176 341900896 19900352
11:46:51 882 8192000000 10390251104 10804142080 1176865824 12.561 100 93 186 0 1495282736 296570304 21846608
11:47:54 10726 8192000000 10318099632 10795491328 1174402336 12.538 100 37 74 1 1435452448 250771168 10278944
11:49:09 16891 8192000000 10342144864 10776453120 1165392016 12.454 100 31 62 0 1433656688 259884880 8379792
11:50:38 21724 8192000000 10314414240 10750091264 1123958736 12.064 98 20 40 0 1407835600 278002256 5874608
11:52:11 30430 8192000000 10204336592 10656657408 1137460928 12.192 99 23 45 1 1336152768 193227248 5464592
11:53:43 37582 8192000000 10170294480 10639912960 1099911840 11.837 98 25 50 4 1255399184 149529648 5957696
11:55:22 53474 8192000000 10066890784 10520813568 1094250944 11.783 98 27 54 1 1153335504 52854144 6230416
11:57:43 79653 8192000000 10080462688 10514575360 1113220272 11.963 98 44 89 2 1153459840 31660112 8579456
12:00:48 147853 8192000000 10038818864 10471714816 996253968 10.842 88 18 36 0 1054968144 55233360 3480816
12:04:17 47031 8192000000 10058754576 10501582848 1081151536 11.658 97 10 20 0 1126590448 43150352 2288560
12:06:13 59905 8192000000 10095364896 10571628544 1123914416 12.064 100 16 33 0 1217758128 90315040 3528672
12:08:20 68677 8192000000 10098048112 10568425472 1117435040 12.003 99 18 36 1 1194953104 73622592 3895472
12:10:29 58837 8192000000 10086251184 10603626496 1127230512 12.095 100 12 24 0 1200328560 70499344 2598704
12:12:29 50615 8192000000 10087132928 10519994368 1090724288 11.750 96 25 49 2 1152390224 56013648 5652288
12:14:20 77256 8192000000 10091073824 10574196736 1136232208 12.180 100 26 52 1 1239144192 97011616 5900368
12:16:52 104447 8192000000 10069285040 10559729664 1102926464 11.865 97 15 30 0 1186316256 80273008 3116784
12:19:45 19517 8192000000 10072639136 10496450560 1128531744 12.108 100 46 91 0 1167493040 29760288 9201008
12:21:21 91971 8192000000 10073640848 10548547584 1113930240 11.970 98 29 58 0 1174613152 54886560 5796352
12:23:56 98208 8192000000 10070054912 10563334144 1136454656 12.182 100 10 20 0 1193499984 54963088 2082240
12:27:21 74135 8192000000 10092003888 10573725696 1150787456 12.317 100 13 26 1 1213741392 60234544 2719392
12:30:15 57212 8192000000 10098704416 10540544000 1155661184 12.363 100 14 28 0 1199107600 41026656 2419760
12:32:14 74894 8192000000 10099243248 10572697600 1104588480 11.881 95 31 60 1 1175635216 65073616 5973120
12:34:48 87968 8192000000 10098046800 10575114240 1130804512 12.129 99 11 22 0 1194834576 61687280 2342784
12:37:16 35820 8192000000 10101184960 10541555712 1107127312 11.905 97 36 72 2 1167808256 53400640 7280304
12:38:52 102148 8192000000 10057027264 10501652480 1058013264 11.437 93 18 34 2 1098363648 37001008 3349376
12:41:37 70409 8192000000 10120324432 10574573568 1103604208 11.872 97 21 42 2 1153382832 45422896 4355728
12:44:08 136071 8192000000 10063103072 10517577728 1139234304 12.208 100 12 24 0 1178188128 36267680 2686144
12:47:34 86963 8192000000 10099503776 10593722368 1121268896 12.039 98 6 12 1 1207104336 84441152 1394288
12:50:01 79727 8192000000 10047913728 10479652864 1101562848 11.852 97 18 35 0 1144307840 38955680 3789312
12:52:25 68858 8192000000 10105117728 10559578112 1120318464 12.030 99 28 57 1 1204813024 78315600 6178960
12:54:46 51159 8192000000 10136760080 10587611136 1117877936 12.007 98 35 70 2 1218714384 93678864 7157584
12:56:55 97928 8192000000 10082920800 10511605760 1098310528 11.822 97 23 46 0 1149324336 46018352 4995456
12:59:33 42154 8192000000 10042650768 10448953344 1056000560 11.418 95 39 77 1 1106600016 43810320 6789136
13:01:15 92479 8192000000 10047697344 10495160320 1045155312 11.314 95 31 61 0 1102316768 51170272 5991184
13:05:00 52532 8192000000 10024607328 10481401856 1088041344 11.724 100 10 20 0 1146677568 56678688 1957536
13:06:53 76638 8192000000 10058114800 10547130368 1104322112 11.879 100 9 18 1 1170338592 64195440 1821040
13:09:15 63981 8192000000 10111481088 10552954880 1112624656 11.957 99 19 38 1 1188180976 71379696 4176624
13:11:24 74611 8192000000 10100288176 10547986432 1114397552 11.974 99 25 50 0 1167931584 48540432 4993600
13:14:42 77557 8192000000 10070479520 10538479616 1109217472 11.925 98 28 56 0 1174242592 59369056 5656064
13:17:27 59347 8192000000 10170292720 10633154560 1139748128 12.213 99 17 34 0 1257990016 114306800 3935088
13:20:26 114926 8192000000 10080491232 10577342464 1129914976 12.121 99 18 36 0 1183729712 50270864 3543872
13:24:58 68648 8192000000 10068600592 10512793600 1102141024 11.858 97 6 12 0 1134784752 31386688 1257040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-01-2021 13:29:05 to 28-01-2021 13:29:07.

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          216597
MON$OLDEST_ACTIVE               217893
MON$OLDEST_SNAPSHOT             217893
MON$NEXT_TRANSACTION            217893
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                       2060256
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2345 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2345 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2345 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.2345 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Jan 28 13:27:06 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

28.01.21 13:29:08. 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-01-28 13:29:10 No severe PSQL-related problems occured
28.01.21 13:29:11. End of report.