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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15998136 kB
MemAvailable 31049476 kB
Buffers 20 kB
Cached 15651220 kB
SwapCached 2628 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-06 04:37:06
DTS_END                         2021-08-06 04:37:06.4790
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/56482
TRN_ID                          246117
ATT_ID                          624
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: 9251.06 18775 1110127
customer order: creation 181.43 357 21771
customer order: refuse 15.38 306 1845
order to supplier: creation 48.59 1361 5831
order to supplier: removal 3.45 100442 414
invoice (draft): creation 48.53 3404 5824
invoice (draft): removal 50.28 952 6034
invoice accept: apply 44.25 17155 5310
invoice accept: cancel 44.83 888 5379
customer reserve: creation 4088.24 169 490589
customer reserve: removal 4425.88 61 531105
realization accept: apply 89.88 184 10786
realization accept: cancel 75.23 120 9028
payment from customer: creation 62.58 123 7509
payment from customer: removal 5.99 77 719
payment to supplier: creation 51.54 126 6185
payment to supplier: removal 3.38 112 406
service: total inventory turnovers 4.22 3481 506
service: total monetary turnovers 6.43 453 772
service: aggregate perf. data 0.92 16794 110
service: refresh index statistics 0.03 228944 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-08-2021 04:39:06 to 06-08-2021 04:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5829
TEST_TIME 2 5802
TEST_TIME 3 5114
TEST_TIME 4 4767
TEST_TIME 5 4662
TEST_TIME 6 5227
TEST_TIME 7 5592
TEST_TIME 8 5948
TEST_TIME 9 6214
TEST_TIME 10 6691
TEST_TIME 11 6891
TEST_TIME 12 7011
TEST_TIME 13 7465
TEST_TIME 14 7566
TEST_TIME 15 7453
TEST_TIME 16 7705
TEST_TIME 17 7969
TEST_TIME 18 8054
TEST_TIME 19 8099
TEST_TIME 20 8262
TEST_TIME 21 8497
TEST_TIME 22 8909
TEST_TIME 23 8899
TEST_TIME 24 9335
TEST_TIME 25 9592
TEST_TIME 26 9968
TEST_TIME 27 11255
TEST_TIME 28 11064
TEST_TIME 29 11530
TEST_TIME 30 11530
TEST_TIME 31 11245
TEST_TIME 32 11067
TEST_TIME 33 10915
TEST_TIME 34 10762
TEST_TIME 35 10770
TEST_TIME 36 10811
TEST_TIME 37 10760
TEST_TIME 38 10678
TEST_TIME 39 10546
TEST_TIME 40 10799
TEST_TIME 41 10755
TEST_TIME 42 10750
TEST_TIME 43 10627
TEST_TIME 44 10437
TEST_TIME 45 10269
TEST_TIME 46 10250
TEST_TIME 47 10112
TEST_TIME 48 10069
TEST_TIME 49 10031
TEST_TIME 50 10048
TEST_TIME 51 10149
TEST_TIME 52 10086
TEST_TIME 53 9994
TEST_TIME 54 9990
TEST_TIME 55 9899
TEST_TIME 56 9902
TEST_TIME 57 9863
TEST_TIME 58 9748
TEST_TIME 59 9725
TEST_TIME 60 9789
TEST_TIME 61 9750
TEST_TIME 62 9812
TEST_TIME 63 9814
TEST_TIME 64 9770
TEST_TIME 65 9835
TEST_TIME 66 9907
TEST_TIME 67 9915
TEST_TIME 68 9895
TEST_TIME 69 9941
TEST_TIME 70 9963
TEST_TIME 71 9913
TEST_TIME 72 9966
TEST_TIME 73 9961
TEST_TIME 74 9892
TEST_TIME 75 9827
TEST_TIME 76 9770
TEST_TIME 77 9753
TEST_TIME 78 9722
TEST_TIME 79 9690
TEST_TIME 80 9643
TEST_TIME 81 9615
TEST_TIME 82 9568
TEST_TIME 83 9551
TEST_TIME 84 9526
TEST_TIME 85 9508
TEST_TIME 86 9524
TEST_TIME 87 9560
TEST_TIME 88 9550
TEST_TIME 89 9574
TEST_TIME 90 9572
TEST_TIME 91 9577
TEST_TIME 92 9586
TEST_TIME 93 9576
TEST_TIME 94 9681
TEST_TIME 95 9687
TEST_TIME 96 9623
TEST_TIME 97 9569
TEST_TIME 98 9533
TEST_TIME 99 9479
TEST_TIME 100 9449
TEST_TIME 101 9423
TEST_TIME 102 9392
TEST_TIME 103 9414
TEST_TIME 104 9437
TEST_TIME 105 9423
TEST_TIME 106 9383
TEST_TIME 107 9447
TEST_TIME 108 9436
TEST_TIME 109 9444
TEST_TIME 110 9441
TEST_TIME 111 9443
TEST_TIME 112 9551
TEST_TIME 113 9504
TEST_TIME 114 9483
TEST_TIME 115 9442
TEST_TIME 116 9428
TEST_TIME 117 9385
TEST_TIME 118 9342
TEST_TIME 119 9322
TEST_TIME 120 9253

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-08-2021 04:39:09 to 06-08-2021 04:39:13.

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 1563735 1563735 0 0.00 0 13367 7 0 0
doc_list_biud 1563735 1563735 0 0.00 0 40025 27 0 0
sp_add_invoice_to_stock 5447 5310 137 2.51 39 181226 17155 0 137
sp_cancel_adding_invoice 5379 5379 0 0.00 5 75259 889 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 7843 307 0 0
sp_cancel_customer_reserve 531105 531105 0 0.00 1 28461 62 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 6310 78 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 9924 112 0 0
sp_cancel_supplier_invoice 6048 6034 14 0.23 2 18952 953 0 14
sp_cancel_supplier_order 414 414 0 0.00 6 1239976 100442 0 0
sp_cancel_write_off 9028 9028 0 0.00 1 18076 120 0 0
sp_client_order 21771 21771 0 0.00 4 22222 357 0 0
sp_customer_reserve 490589 490589 0 0.00 0 35959 170 0 0
sp_fill_shopping_cart 33419 33419 0 0.00 1 30058 42 0 0
sp_fill_shopping_cart_clo_res 490587 490587 0 0.00 0 19388 14 0 0
sp_get_clo_for_invoice 5307 5307 0 0.00 0 33311 292 0 0
sp_kill_cost_storno 15532 15532 0 0.00 0 10130 60 0 0
sp_kill_qstorno_ret_qs2qd 1077686 1077686 0 0.00 0 28443 34 0 0
sp_kill_qty_storno 569898 569898 0 0.00 0 40025 73 0 0
sp_lock_selected_doc 22163 22163 0 0.00 0 9915 7 0 0
sp_make_cost_storno 29787 29787 0 0.00 0 13339 55 0 0
sp_multiply_rows_for_pdistr 29787 29787 0 0.00 0 8241 33 0 0
sp_multiply_rows_for_qdistr 21771 21771 0 0.00 0 13525 170 0 0
sp_pay_from_customer 7509 7509 0 0.00 4 13459 124 0 0
sp_pay_to_supplier 6185 6185 0 0.00 4 11907 126 0 0
sp_payment_common 13694 13694 0 0.00 1 10003 29 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 7799 279 0 0
sp_qd_handle_on_invoice_upd_sts 10686 10686 0 0.00 0 40023 344 0 0
sp_qd_handle_on_reserve_upd_sts 10786 10786 0 0.00 0 23860 61 0 0
sp_reserve_write_off 10786 10786 0 0.00 7 24407 185 0 0
sp_supplier_invoice 5824 5824 0 0.00 7 126847 3405 0 0
sp_supplier_order 5831 5831 0 0.00 6 43979 1362 0 0
srv_aggregate_perf_data 110 110 0 0.00 2704 215995 16795 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1401 153166 44753 0 0
srv_find_qd_qs_mism 1059411 1059411 0 0.00 0 12720 6 0 0
srv_make_invnt_saldo 506 506 0 0.00 67 146456 3481 0 0
srv_make_money_saldo 772 772 0 0.00 1 14126 453 0 0
srv_recalc_idx_stat 4 4 0 0.00 138099 457818 228945 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5311 5311 0 0.00 0 12934 163 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 0 3348 92 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 3082 13 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 4664 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 433 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 825 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 5710 27 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 19041 1510 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 9086 21 0 0
t$perf-norm:sp_client_order 21771 21771 0 0.00 0 15111 18 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3631 9 0 0
t$perf-norm:sp_pay_from_customer 7509 7509 0 0.00 0 7029 9 0 0
t$perf-norm:sp_pay_to_supplier 6185 6185 0 0.00 0 4969 10 0 0
t$perf-norm:sp_reserve_write_off 10786 10786 0 0.00 0 6337 12 0 0
t$perf-norm:sp_supplier_invoice 5824 5824 0 0.00 0 19516 30 0 0
t$perf-norm:sp_supplier_order 5831 5831 0 0.00 0 4742 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 1996 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 186 8 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 560 5 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 3521 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10888 10614 274 2.51 0 8597 17 0 274
v_all_customers 39740 39740 0 0.00 0 15 1 0 0
v_all_suppliers 32618 32618 0 0.00 0 30 1 0 0
v_all_wares 43537 43537 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 2224 10 0 0
v_cancel_client_order 3690 3690 0 0.00 0 1385 3 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 2849 5 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 1004 3 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1215 4 0 0
v_cancel_supplier_invoice 894 866 28 3.13 0 3506 18 0 28
v_cancel_supplier_order 828 828 0 0.00 0 4392 11 0 0
v_cancel_write_off 2142 2142 0 0.00 0 3512 13 0 0
v_max_id_clo_ord 5830 5830 0 0.00 0 293 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2889 17 0 0
v_max_id_ord_sup 5818 5818 0 0.00 0 4953 5 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 531 2 0 0
v_max_non_paid_realizn 4035 4035 0 0.00 0 1027 3 0 0
v_min_id_clo_ord 5830 5830 0 0.00 0 6345 7 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 6504 18 0 0
v_min_id_ord_sup 5818 5818 0 0.00 0 4949 10 0 0
v_min_non_paid_invoice 6185 6185 0 0.00 0 7249 21 0 0
v_min_non_paid_realizn 7508 7508 0 0.00 0 9811 22 0 0
v_our_firm 10742 10742 0 0.00 0 20 1 0 0
v_reserve_write_off 21570 21570 0 0.00 0 3476 5 0 0
x_lock_depdocs_on_canc_invoice 10980 10980 0 0.00 0 25852 103 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 15197 297 0 0
x_make_qstorno_1000_1200 5831 5831 0 0.00 2 43969 1291 0 0
x_make_qstorno_1200_2000 5824 5824 0 0.00 3 96786 3237 0 0
x_make_qstorno_2100_3300 490589 490589 0 0.00 0 35411 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-08-2021 04:39:14 to 06-08-2021 04:39:18.

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:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 24091 8192000000 9724476192 10020261888 800206448 8.898 100 90 180 0 829720032 7505184 22008400
02:09:39 23531 8192000000 9761395584 10064662528 832146944 9.221 100 90 180 0 866127440 12296528 21683968
02:11:10 22080 8192000000 9808512768 10123689984 868909200 9.589 100 92 182 2 917764240 27342624 21512416
02:12:35 13889 8192000000 9854387360 10190737408 917133456 10.068 100 77 154 3 967559920 33192800 17233664
02:13:51 8118 8192000000 9922691008 10269708288 959714016 10.486 100 80 158 2 1037152944 60780096 16658832
02:15:01 6744 8192000000 9977198208 10332819456 996924080 10.849 100 77 155 4 1083801744 71209216 15668448
02:16:10 2920 8192000000 10000487872 10359775232 1022358912 11.095 100 92 184 0 1109904912 69641552 17904448
02:17:16 2376 8192000000 10016373616 10380021760 1047437648 11.336 100 88 175 2 1123503264 57029760 19035856
02:18:20 2052 8192000000 10060838208 10430959616 1071190992 11.563 100 93 185 2 1157621168 67331312 19098864
02:19:23 12311 8192000000 10008368432 10410369024 1072686592 11.578 100 40 80 0 1118162848 37480160 7996096
02:20:37 4345 8192000000 10003741040 10405367808 1083866416 11.684 100 37 74 1 1115541952 24011024 7664512
02:21:43 7014 8192000000 9998758016 10390720512 1088632992 11.730 100 43 83 3 1114212992 16041984 9538016
02:22:52 7150 8192000000 10013754624 10422673408 1095384960 11.794 100 25 51 1 1122148832 21513504 5250368
02:24:00 51743 8192000000 10005915920 10408001536 1095643616 11.796 100 42 85 0 1120003120 14701696 9657808
02:25:54 8598 8192000000 10029187712 10440482816 1101634944 11.853 100 44 88 2 1140738416 29191184 9912288
02:27:05 6292 8192000000 10014955792 10414239744 1061442480 11.470 97 49 96 1 1097160544 24462544 11255520
02:28:16 5432 8192000000 9963470944 10353872896 1030935328 11.177 97 42 84 0 1056429936 16000704 9493904
02:29:26 53143 8192000000 9924473760 10319204352 950880576 10.400 90 29 56 1 976047712 19041360 6125776
02:31:26 12071 8192000000 9932430608 10318561280 972615568 10.612 95 34 68 1 1002435824 21756672 8063584
02:32:40 5923 8192000000 9960912768 10355310592 1048045472 11.342 100 40 80 2 1074591424 16645696 9900256
02:33:47 8758 8192000000 9991480048 10397392896 1068357584 11.536 100 47 94 0 1102907136 23239872 11309680
02:34:57 8545 8192000000 9999537488 10410782720 1068914944 11.542 100 30 60 2 1105075104 30274640 5885520
02:36:07 6165 8192000000 10010045296 10403811328 1080386176 11.651 100 45 90 1 1116605664 26240592 9978896
02:37:21 33043 8192000000 10023971824 10422804480 1105759296 11.892 100 48 93 2 1131203152 14934320 10509536
02:38:58 42170 8192000000 10100931056 10517528576 1122163936 12.047 100 49 98 0 1188311584 55567872 10579776
02:40:43 64746 8192000000 10111410272 10527154176 1138293984 12.199 100 44 87 2 1234311856 86616752 9401120
02:42:58 6738 8192000000 10160939168 10572910592 1142453280 12.239 100 45 90 4 1262482640 110304208 9725152
02:44:12 23491 8192000000 10159628304 10575368192 1151248288 12.321 100 47 94 1 1264216400 102889712 10078400
02:45:39 19432 8192000000 10196259216 10609508352 1162268896 12.425 100 62 124 1 1290574432 115678640 12626896
02:47:04 10223 8192000000 10232263856 10672504832 1181175552 12.601 100 56 112 1 1332769888 139191808 12402528
02:48:16 4202 8192000000 10253282352 10678071296 1187864592 12.663 100 66 132 0 1359448624 156644464 14939568
02:49:22 3528 8192000000 10244393344 10678460416 1176330032 12.556 100 65 127 2 1367430608 176445728 14654848
02:50:31 3286 8192000000 10289097024 10701045760 1180844592 12.598 100 77 153 1 1391104368 192714720 17545056
02:51:38 8243 8192000000 10307457280 10730000384 1176729920 12.560 100 46 92 1 1412020272 224188128 11102224
02:52:52 3422 8192000000 10385628144 10810019840 1179106512 12.582 99 75 150 2 1483763216 286512400 18144304
02:54:01 3339 8192000000 10338672112 10779406336 1167153136 12.470 100 69 135 0 1432799520 249390656 16255728
02:55:12 1400 8192000000 10406732432 10808086528 1179555616 12.586 100 84 167 0 1497387728 298667600 19164512
02:56:16 6323 8192000000 10404980944 10833149952 1163031584 12.432 99 45 89 0 1508371344 334098672 11241088
02:57:30 3828 8192000000 10466155328 10883223552 1162573824 12.427 100 67 134 0 1570588576 391431536 16583216
02:58:42 12255 8192000000 10461714448 10897350656 1131162896 12.132 98 44 88 1 1550080672 407449856 11467920
03:00:02 14674 8192000000 10444743168 10875789312 1128692560 12.109 99 33 67 1 1529249184 391103056 9453568
03:01:22 22767 8192000000 10429087472 10916134912 1119936128 12.026 100 28 56 1 1559780048 432023296 7820624
03:02:54 26365 8192000000 10333093952 10810515456 1105732752 11.892 100 23 46 1 1471755424 359678640 6344032
03:04:26 35413 8192000000 10151747296 10622656512 1104595152 11.881 99 17 34 0 1286664688 177932080 4137456
03:06:18 21622 8192000000 10101937456 10631704576 1099805760 11.836 99 9 18 0 1218662816 116607408 2249648
03:07:48 118144 8192000000 10051069568 10490777600 1095420992 11.794 97 24 48 1 1139847600 39984608 4442000
03:10:47 120349 8192000000 10098343776 10551193600 1114983024 11.980 98 20 41 0 1169452192 49568400 4900768
03:14:02 105590 8192000000 10047037648 10475032576 1098729616 11.826 98 16 32 0 1134546176 32583632 3232928
03:16:58 30477 8192000000 10134953424 10590158848 1132652608 12.146 100 37 75 0 1202243264 61795152 7795504
03:18:33 153164 8192000000 10085486368 10538979328 1131318336 12.134 99 18 36 0 1158949296 23756640 3874320
03:22:07 55614 8192000000 10109516080 10550706176 1144210432 12.255 100 48 95 0 1201048064 46623504 10214128
03:24:04 35169 8192000000 10105112400 10544902144 1153089552 12.338 100 28 56 0 1195616640 36584624 5942464
03:25:40 59449 8192000000 10163399456 10616156160 1128387280 12.106 98 21 42 1 1222039232 88876432 4775520
03:27:43 42742 8192000000 10085623088 10603024384 1146683632 12.278 100 12 24 1 1210823728 60703488 3436608
03:29:32 62631 8192000000 10101960128 10575826944 1153049888 12.338 100 13 26 0 1197087136 41650704 2386544
03:31:49 69575 8192000000 10080571120 10569912320 1138604928 12.202 99 24 49 0 1191629568 47535504 5489136
03:34:06 71912 8192000000 10037867984 10488934400 1129347408 12.115 99 16 31 1 1174751936 41974928 3429600
03:36:28 47466 8192000000 10095036720 10556612608 1107171200 11.906 97 6 12 0 1166723680 58488160 1064320
03:38:17 38669 8192000000 10097241472 10549473280 1145920192 12.271 100 21 42 0 1203033456 52922576 4190688
03:40:01 45434 8192000000 10080444960 10541010944 1143997152 12.253 100 13 26 0 1181675600 34941584 2736864
03:41:47 40681 8192000000 10071515712 10536849408 1119983632 12.027 98 30 59 1 1205357792 78964288 6409872
03:43:34 42567 8192000000 10056277920 10508185600 1060896624 11.465 94 19 38 0 1106325152 41648816 3779712
03:45:23 28990 8192000000 10082426608 10507919360 1086201008 11.707 97 45 90 1 1131302416 35551616 9549792
03:47:05 38659 8192000000 10037177408 10500120576 1074642160 11.596 96 9 18 1 1112318912 36014896 1661856
03:48:44 49755 8192000000 10041380592 10515992576 1056577296 11.424 96 38 76 0 1138181168 74116288 7487584
03:50:35 33919 8192000000 10066050160 10530394112 1082138800 11.668 98 39 78 2 1136888832 46636208 8113824
03:52:20 83075 8192000000 10051021856 10488213504 1095550192 11.795 99 21 43 0 1144264512 43425680 5288640
03:54:49 32690 8192000000 10033246640 10489044992 1108696976 11.920 100 34 64 6 1151488656 36231616 6560064
03:56:34 48456 8192000000 10026318624 10498785280 1083829856 11.684 98 7 15 0 1117254800 31854752 1570192
03:58:38 70880 8192000000 10038489664 10482114560 1084956752 11.695 98 9 18 0 1116485616 29978768 1550096
04:01:02 29525 8192000000 10054060496 10495975424 1098823424 11.826 98 47 95 0 1144181968 34495392 10863152
04:02:36 46453 8192000000 10042679824 10508226560 1103022880 11.866 99 30 60 1 1160989376 52016528 5949968
04:04:23 67064 8192000000 10054622240 10505572352 1108350272 11.917 99 28 56 1 1156453200 42414224 5688704
04:06:34 56945 8192000000 10064779136 10536906752 1129583664 12.117 100 19 38 1 1171512096 38172512 3755920
04:08:32 31343 8192000000 10093114816 10541731840 1100154624 11.839 96 25 50 1 1134312928 29224704 4933600
04:10:04 45410 8192000000 10136282480 10577711104 1147654720 12.287 100 24 48 0 1240439424 87619808 5164896
04:11:55 74313 8192000000 10066426960 10538696704 1095354528 11.794 96 12 24 0 1130531744 32560240 2616976
04:15:15 35313 8192000000 10070813840 10515095552 1130679040 12.128 100 16 32 0 1158766832 24863040 3224752
04:16:58 73784 8192000000 10050505008 10567004160 1127384128 12.097 99 8 16 0 1158950816 29868944 1697744
04:19:11 75351 8192000000 10109992688 10599780352 1088535136 11.729 95 23 46 0 1155626768 62347408 4744224
04:21:28 56158 8192000000 10091608896 10568253440 1107884512 11.912 97 19 38 0 1156086832 43992256 4210064
04:23:30 41510 8192000000 10112070736 10590457856 1125111360 12.075 98 33 66 1 1208231696 76435568 6684768
04:25:12 72653 8192000000 10091346128 10530902016 1098099040 11.820 96 36 72 0 1176716592 70795040 7822512
04:27:25 86811 8192000000 10112673760 10579484672 1075288688 11.603 94 27 54 1 1167856672 86975728 5592256
04:29:57 43724 8192000000 10089125024 10545672192 1109016176 11.923 99 11 22 2 1184158496 72723776 2418544
04:31:43 77926 8192000000 10046628784 10502696960 1099660064 11.834 99 24 48 0 1151996064 47753920 4582080
04:35:23 53240 8192000000 10018533008 10472939520 1074466880 11.595 97 7 14 0 1105820416 29814176 1539360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-08-2021 04:39:20 to 06-08-2021 04:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-08-2021 04:39:36 to 06-08-2021 04:39:38.

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          245702
MON$OLDEST_ACTIVE               246281
MON$OLDEST_SNAPSHOT             246281
MON$NEXT_TRANSACTION            246281
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                       2068064
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             824
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2558 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2558 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:11 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:13 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:20 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:20 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:20 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:20 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Fri Aug  6 04:37:20 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

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