Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.06.2022 07:49
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16163516 kB
MemAvailable 31230972 kB
Buffers 0 kB
Cached 15581864 kB
SwapCached 2504 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.2
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 2022-06-28 07:47:36
DTS_END                         2022-06-28 07:47:36.7030
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/50450
TRN_ID                          217304
ATT_ID                          614
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: 8762.26 28924 1051471
customer order: creation 162.18 498 19462
customer order: refuse 14.18 419 1702
order to supplier: creation 43.93 1573 5271
order to supplier: removal 3.36 163520 403
invoice (draft): creation 42.75 3733 5130
invoice (draft): removal 49.69 938 5963
invoice accept: apply 40.05 26386 4806
invoice accept: cancel 44.88 1166 5385
customer reserve: creation 3681.42 253 441770
customer reserve: removal 4396.93 98 527631
realization accept: apply 78.88 298 9466
realization accept: cancel 79.86 180 9583
payment from customer: creation 57.07 176 6848
payment from customer: removal 5.54 125 665
payment to supplier: creation 46.50 199 5580
payment to supplier: removal 3.33 107 399
service: total inventory turnovers 3.78 4847 453
service: total monetary turnovers 7.02 445 842
service: aggregate perf. data 0.90 15781 108
service: refresh index statistics 0.03 357734 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-06-2022 07:50:00 to 28-06-2022 07:50:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8366
TEST_TIME 2 8423
TEST_TIME 3 8030
TEST_TIME 4 7436
TEST_TIME 5 6930
TEST_TIME 6 6740
TEST_TIME 7 6753
TEST_TIME 8 7309
TEST_TIME 9 7517
TEST_TIME 10 7377
TEST_TIME 11 7304
TEST_TIME 12 7628
TEST_TIME 13 7407
TEST_TIME 14 7376
TEST_TIME 15 7482
TEST_TIME 16 7517
TEST_TIME 17 7641
TEST_TIME 18 7705
TEST_TIME 19 7697
TEST_TIME 20 7793
TEST_TIME 21 7820
TEST_TIME 22 7746
TEST_TIME 23 8044
TEST_TIME 24 8469
TEST_TIME 25 8707
TEST_TIME 26 8843
TEST_TIME 27 8883
TEST_TIME 28 9225
TEST_TIME 29 9584
TEST_TIME 30 9900
TEST_TIME 31 10325
TEST_TIME 32 10533
TEST_TIME 33 10903
TEST_TIME 34 10774
TEST_TIME 35 10576
TEST_TIME 36 10545
TEST_TIME 37 10443
TEST_TIME 38 10286
TEST_TIME 39 10311
TEST_TIME 40 10215
TEST_TIME 41 10175
TEST_TIME 42 10060
TEST_TIME 43 10022
TEST_TIME 44 9910
TEST_TIME 45 9820
TEST_TIME 46 9657
TEST_TIME 47 9646
TEST_TIME 48 9553
TEST_TIME 49 9427
TEST_TIME 50 9369
TEST_TIME 51 9312
TEST_TIME 52 9197
TEST_TIME 53 9200
TEST_TIME 54 9238
TEST_TIME 55 9161
TEST_TIME 56 9026
TEST_TIME 57 9032
TEST_TIME 58 8971
TEST_TIME 59 9173
TEST_TIME 60 9178
TEST_TIME 61 9178
TEST_TIME 62 9159
TEST_TIME 63 9224
TEST_TIME 64 9201
TEST_TIME 65 9179
TEST_TIME 66 9286
TEST_TIME 67 9358
TEST_TIME 68 9412
TEST_TIME 69 9376
TEST_TIME 70 9372
TEST_TIME 71 9291
TEST_TIME 72 9341
TEST_TIME 73 9248
TEST_TIME 74 9181
TEST_TIME 75 9204
TEST_TIME 76 9183
TEST_TIME 77 9129
TEST_TIME 78 9108
TEST_TIME 79 9064
TEST_TIME 80 9003
TEST_TIME 81 8906
TEST_TIME 82 8897
TEST_TIME 83 8917
TEST_TIME 84 8889
TEST_TIME 85 8902
TEST_TIME 86 8861
TEST_TIME 87 8876
TEST_TIME 88 8839
TEST_TIME 89 8849
TEST_TIME 90 8842
TEST_TIME 91 8880
TEST_TIME 92 8928
TEST_TIME 93 8919
TEST_TIME 94 8985
TEST_TIME 95 9016
TEST_TIME 96 9046
TEST_TIME 97 9079
TEST_TIME 98 8994
TEST_TIME 99 8976
TEST_TIME 100 8975
TEST_TIME 101 8929
TEST_TIME 102 8875
TEST_TIME 103 8839
TEST_TIME 104 8873
TEST_TIME 105 8840
TEST_TIME 106 8827
TEST_TIME 107 8821
TEST_TIME 108 8796
TEST_TIME 109 8784
TEST_TIME 110 8796
TEST_TIME 111 8769
TEST_TIME 112 8754
TEST_TIME 113 8798
TEST_TIME 114 8923
TEST_TIME 115 8929
TEST_TIME 116 8940
TEST_TIME 117 8953
TEST_TIME 118 8885
TEST_TIME 119 8837
TEST_TIME 120 8796

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-06-2022 07:50:04 to 28-06-2022 07:50:07.

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 1458144 1458144 0 0.00 0 46848 13 0 0
doc_list_biud 1458127 1458127 0 0.00 0 38669 42 0 0
sp_add_invoice_to_stock 4998 4806 192 3.84 60 238427 26387 0 192
sp_cancel_adding_invoice 5385 5385 0 0.00 6 98931 1166 0 0
sp_cancel_client_order 1702 1702 0 0.00 6 13671 419 0 0
sp_cancel_customer_reserve 527631 527631 0 0.00 1 22187 99 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 5736 125 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 2660 107 0 0
sp_cancel_supplier_invoice 5983 5963 20 0.33 2 39801 938 0 20
sp_cancel_supplier_order 403 403 0 0.00 14 1271937 163521 0 0
sp_cancel_write_off 9583 9583 0 0.00 2 22193 181 0 0
sp_client_order 19462 19462 0 0.00 6 48503 498 0 0
sp_customer_reserve 441770 441770 0 0.00 0 74594 253 0 0
sp_fill_shopping_cart 29857 29857 0 0.00 1 16173 89 0 0
sp_fill_shopping_cart_clo_res 441738 441738 0 0.00 0 14830 21 0 0
sp_get_clo_for_invoice 4761 4761 0 0.00 0 29941 338 0 0
sp_kill_cost_storno 16032 16032 0 0.00 0 23859 77 0 0
sp_kill_qstorno_ret_qs2qd 1071211 1071211 0 0.00 0 38666 43 0 0
sp_kill_qty_storno 564890 564890 0 0.00 0 38668 100 0 0
sp_lock_selected_doc 19703 19703 0 0.00 0 5677 7 0 0
sp_make_cost_storno 26653 26653 0 0.00 0 23416 80 0 0
sp_multiply_rows_for_pdistr 26651 26651 0 0.00 0 10959 38 0 0
sp_multiply_rows_for_qdistr 19461 19461 0 0.00 0 48492 183 0 0
sp_pay_from_customer 6848 6848 0 0.00 6 18174 177 0 0
sp_pay_to_supplier 5580 5580 0 0.00 6 17044 200 0 0
sp_payment_common 12428 12428 0 0.00 2 9829 47 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 13662 343 0 0
sp_qd_handle_on_invoice_upd_sts 10142 10142 0 0.00 0 26433 510 0 0
sp_qd_handle_on_reserve_upd_sts 9466 9466 0 0.00 0 26107 89 0 0
sp_reserve_write_off 9466 9466 0 0.00 8 26223 299 0 0
sp_supplier_invoice 5130 5130 0 0.00 7 108249 3733 0 0
sp_supplier_order 5271 5271 0 0.00 8 48080 1573 0 0
srv_aggregate_perf_data 108 108 0 0.00 2244 188866 15782 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 797 145478 39072 0 0
srv_find_qd_qs_mism 1004777 1004777 0 0.00 0 46843 10 0 0
srv_make_invnt_saldo 453 453 0 0.00 55 194414 4848 0 0
srv_make_money_saldo 842 842 0 0.00 1 18008 445 0 0
srv_recalc_idx_stat 4 4 0 0.00 169330 521951 357734 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 1 14961 943 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 2 6614 414 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 4362 28 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 2223 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 1679 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 441 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 2102 37 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 111509 6234 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 9381 32 0 0
t$perf-norm:sp_client_order 19462 19462 0 0.00 0 6702 37 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 7866 36 0 0
t$perf-norm:sp_pay_from_customer 6848 6848 0 0.00 0 5571 20 0 0
t$perf-norm:sp_pay_to_supplier 5580 5580 0 0.00 0 4026 18 0 0
t$perf-norm:sp_reserve_write_off 9466 9466 0 0.00 0 5464 26 0 0
t$perf-norm:sp_supplier_invoice 5130 5130 0 0.00 0 5910 36 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 9100 33 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1029 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 529 15 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 905 13 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 7843 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 546 145 0 0
v_add_invoice_to_stock 9888 9504 384 3.88 0 8947 30 0 384
v_all_customers 36066 36066 0 0.00 0 127 4 0 0
v_all_suppliers 29148 29148 0 0.00 0 77 4 0 0
v_all_wares 38919 38919 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 10933 55 0 0
v_cancel_client_order 3404 3404 0 0.00 0 3343 13 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 5640 15 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 1 1085 12 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2612 15 0 0
v_cancel_supplier_invoice 812 772 40 4.92 0 705 27 0 40
v_cancel_supplier_order 806 806 0 0.00 0 987 12 0 0
v_cancel_write_off 1816 1816 0 0.00 0 4909 27 0 0
v_max_id_clo_ord 5271 5271 0 0.00 0 2990 6 0 0
v_max_id_clo_res 967 967 0 0.00 0 1790 14 0 0
v_max_id_ord_sup 5125 5125 0 0.00 0 6051 10 0 0
v_max_non_paid_invoice 1401 1401 0 0.00 0 425 8 0 0
v_max_non_paid_realizn 3441 3441 0 0.00 0 2867 9 0 0
v_min_id_clo_ord 5271 5271 0 0.00 0 6128 16 0 0
v_min_id_clo_res 967 967 0 0.00 0 8609 19 0 0
v_min_id_ord_sup 5125 5125 0 0.00 0 3510 10 0 0
v_min_non_paid_invoice 5579 5579 0 0.00 0 9820 17 0 0
v_min_non_paid_realizn 6848 6848 0 0.00 0 9740 23 0 0
v_our_firm 9658 9658 0 0.00 0 84 4 0 0
v_reserve_write_off 18930 18930 0 0.00 0 8389 14 0 0
x_lock_depdocs_on_canc_invoice 10962 10962 0 0.00 0 20521 123 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 5558 368 0 0
x_make_qstorno_1000_1200 5271 5271 0 0.00 2 48072 1395 0 0
x_make_qstorno_1200_2000 5130 5130 0 0.00 3 108199 3479 0 0
x_make_qstorno_2100_3300 441770 441770 0 0.00 0 74591 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-06-2022 07:50:08 to 28-06-2022 07:50:12.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:40 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:41 25412 8192000000 9704230128 10003218432 783581056 8.730 100 87 174 0 813010400 7121664 22307680
05:20:14 23844 8192000000 9759572464 10066145280 832903056 9.228 100 92 184 1 868011280 12523568 22584656
05:21:48 13610 8192000000 9809273984 10134810624 877996144 9.680 100 81 160 0 920581408 24801600 17783664
05:23:03 12525 8192000000 9873510144 10207838208 922312208 10.119 100 82 164 0 981297984 41523424 17462352
05:24:23 2726 8192000000 9947736464 10290790400 972182176 10.608 100 89 178 1 1056887216 65873424 18831616
05:25:29 1788 8192000000 9973499600 10326384640 996425072 10.844 100 91 179 0 1087081840 73344144 17312624
05:26:33 1176 8192000000 10000569744 10361241600 1014254384 11.017 100 92 180 1 1119262944 85781936 19226624
05:27:36 892 8192000000 10003025840 10373844992 1038900368 11.254 100 95 181 2 1126518864 69298464 18320032
05:28:38 1023 8192000000 10025874928 10397634560 1057051216 11.428 100 94 181 3 1145211648 69144096 19016336
05:29:40 7235 8192000000 10014265360 10391531520 1063025424 11.485 100 76 150 2 1141336128 63645392 14665312
05:30:48 1143 8192000000 10054545616 10438823936 1071312352 11.565 100 94 182 1 1173457888 83685808 18459728
05:31:51 1038 8192000000 10047129616 10427936768 1079700880 11.645 100 96 182 1 1171039664 72639664 18699120
05:32:54 1713 8192000000 10019726848 10408259584 1078912576 11.637 100 84 144 4 1140073296 46040432 15120288
05:33:56 1021 8192000000 10046293728 10433650688 1084856800 11.694 100 89 176 2 1154699872 51468352 18374720
05:34:59 3142 8192000000 10080905568 10465370112 1088879536 11.732 100 91 172 1 1195482416 89410304 17192576
05:36:03 1164 8192000000 10068486112 10459291648 1098120672 11.820 100 93 179 1 1192638832 75312032 19206128
05:37:05 924 8192000000 10072058784 10465398784 1094521200 11.786 100 93 178 1 1198215104 85564752 18129152
05:38:09 1367 8192000000 10067539152 10462298112 1101407536 11.851 100 86 162 2 1193367312 74578816 17380960
05:39:11 1680 8192000000 10016406592 10413080576 1086875744 11.713 100 76 138 4 1146194528 44364320 14954464
05:40:14 1134 8192000000 10046807232 10436448256 1087051968 11.715 100 94 178 5 1174034928 68066992 18915968
05:41:15 1001 8192000000 10037415840 10418053120 1070491200 11.557 100 90 167 2 1161686144 74308160 16886784
05:42:18 1180 8192000000 10024260880 10402930688 1052710832 11.387 99 93 176 2 1150745168 79783056 18251280
05:43:20 1264 8192000000 10001974832 10379382784 1044380944 11.307 99 97 190 3 1127003168 63937152 18685072
05:44:22 1134 8192000000 9978472816 10349981696 1030187104 11.170 100 99 185 2 1104432144 55572224 18672816
05:45:24 10324 8192000000 9962239344 10347630592 1024607536 11.116 99 43 82 0 1075895760 43277840 8010384
05:46:35 1959 8192000000 9977380992 10350116864 1036926672 11.235 100 87 163 1 1093252960 40766496 15559792
05:47:38 1410 8192000000 10002386784 10379853824 1046347392 11.326 100 87 165 2 1137720768 74262352 17111024
05:48:45 808 8192000000 10046698288 10426519552 1057928672 11.437 99 97 185 1 1169103360 90313552 20861136
05:50:02 9005 8192000000 10043804864 10435457024 1079602704 11.644 100 65 123 0 1164399264 72687504 12109056
05:51:14 883 8192000000 10077386192 10465890304 1091742144 11.759 100 79 151 2 1189667056 81619600 16305312
05:52:19 1724 8192000000 10106151328 10492293120 1100377552 11.841 100 86 162 1 1221221744 104370480 16473712
05:53:25 3676 8192000000 10127489200 10517573632 1109663504 11.929 100 79 146 2 1242834288 118989184 14181600
05:54:49 2112 8192000000 10162744688 10555912192 1119012496 12.018 100 91 170 1 1275637472 137315968 19309008
05:55:55 1482 8192000000 10179598304 10577481728 1136464416 12.182 100 88 171 1 1293797232 137715936 19616880
05:57:01 2098 8192000000 10156776112 10551177216 1124257664 12.067 99 85 154 4 1264132896 121734528 18140704
05:58:09 3169 8192000000 10205842320 10611200000 1150993408 12.319 100 80 154 5 1318520896 150721248 16806240
05:59:18 1598 8192000000 10247870848 10650988544 1169337600 12.491 100 96 177 3 1356213808 168851680 18024528
06:00:22 3520 8192000000 10266360288 10663460864 1176049120 12.553 100 81 158 2 1376228160 182786112 17392928
06:01:31 1112 8192000000 10315463936 10741497856 1200017792 12.776 100 95 179 2 1423504528 202073344 21413392
06:02:35 1623 8192000000 10330018176 10741673984 1200989568 12.786 100 93 182 2 1437317120 215683760 20643792
06:03:41 1363 8192000000 10360433696 10775814144 1196122720 12.740 100 91 180 0 1466265008 249368192 20774096
06:04:45 1269 8192000000 10373823568 10788368384 1192970192 12.711 100 95 184 1 1480900800 266184000 21746608
06:05:50 990 8192000000 10437682816 10835013632 1194110272 12.722 100 95 185 1 1536107808 320698544 21298992
06:06:55 802 8192000000 10470754544 10875154432 1192746704 12.709 100 97 186 0 1579695008 364537536 22410768
06:07:58 900 8192000000 10515559952 10918531072 1192054912 12.702 100 97 192 0 1618898080 404329120 22514048
06:09:02 906 8192000000 10529190016 10937942016 1197648576 12.754 100 96 185 0 1634335088 413484832 23201680
06:10:06 778 8192000000 10518439488 10982920192 1202350288 12.798 100 96 184 1 1622275136 396492000 23432848
06:11:09 789 8192000000 10528646912 10952392704 1199657792 12.773 100 90 175 4 1636895056 414952208 22285056
06:12:13 9648 8192000000 10487214528 10969042944 1187680560 12.662 100 42 83 0 1588090816 389032672 11377584
06:13:27 6473 8192000000 10505408944 10942525440 1177493120 12.567 100 49 93 1 1607383856 417598304 12292432
06:14:42 9250 8192000000 10433000384 10942369792 1164025200 12.441 100 42 84 0 1550227952 375358464 10844288
06:16:22 27398 8192000000 10377883872 10859036672 1152781760 12.336 100 29 56 1 1482926608 322718800 7426048
06:17:55 19878 8192000000 10246892768 10725011456 1150594464 12.315 100 17 34 1 1389393248 234273664 4525120
06:19:16 26767 8192000000 10164098096 10656239616 1112122720 11.953 97 20 40 0 1273275232 156045104 5107408
06:20:44 77504 8192000000 10073443264 10503925760 1110591504 11.938 98 23 42 0 1202454672 87026224 4836944
06:23:02 20257 8192000000 10109376080 10542669824 1127753280 12.100 100 43 86 0 1195501104 59678624 8069200
06:24:23 145458 8192000000 10062020832 10562883584 1125984000 12.083 99 12 25 0 1199298208 70643904 2670304
06:28:04 107413 8192000000 10089015088 10547273728 1130995584 12.131 99 23 45 1 1176241248 40540032 4705632
06:30:57 88393 8192000000 10063417328 10510745600 1088333184 11.727 95 16 32 0 1139443392 47476016 3634192
06:34:29 93027 8192000000 10083335584 10520412160 1118523056 12.013 98 43 84 0 1157427024 29924640 8979328
06:37:15 16808 8192000000 10099307344 10541658112 1126226304 12.086 99 37 72 2 1207264016 72375312 8662400
06:38:34 21528 8192000000 10107620192 10547974144 1143250432 12.246 100 23 45 0 1223441984 75289232 4902320
06:40:02 20104 8192000000 10183439328 10638344192 1150418896 12.313 100 44 88 0 1276310272 116573968 9317408
06:41:31 83848 8192000000 10155871536 10637766656 1152011456 12.328 100 16 32 0 1272396432 116606688 3778288
06:43:58 106892 8192000000 10169587664 10674393088 1133732192 12.157 99 24 48 0 1274576576 135436352 5408032
06:47:04 75616 8192000000 10118424656 10596929536 1130806352 12.129 99 21 42 0 1221321904 85739920 4775632
06:49:32 59760 8192000000 10158678912 10599743488 1145924080 12.271 100 33 66 0 1244163936 91201040 7038816
06:51:38 24683 8192000000 10151631728 10586406912 1149008224 12.300 99 47 94 0 1229323904 70332800 9982880
06:53:10 89899 8192000000 10149924096 10631938048 1121626224 12.042 97 25 48 0 1240204768 113284720 5293824
06:56:55 35913 8192000000 10130525760 10581815296 1140980544 12.225 99 35 70 0 1205449872 57693536 6775792
06:58:34 99805 8192000000 10075843744 10539155456 1133641328 12.156 99 37 72 0 1248812864 107396944 7774592
07:01:15 24694 8192000000 10121632416 10557366272 1140511072 12.220 99 44 86 0 1201351984 52299232 8541680
07:02:57 64271 8192000000 10057947312 10555035648 1106854592 11.903 97 11 22 1 1157025936 47967792 2203552
07:05:04 53683 8192000000 10060004720 10515439616 1124315008 12.068 98 16 32 0 1153681808 25806416 3560384
07:06:58 68929 8192000000 10095200560 10547552256 1149849984 12.308 100 26 52 0 1190167824 34226000 6091840
07:09:38 97654 8192000000 10091688784 10586365952 1129434912 12.116 98 17 33 0 1186091184 53547264 3109008
07:12:32 62426 8192000000 10056819584 10497212416 1085621888 11.701 95 15 26 0 1116913136 28160192 3131056
07:14:35 46152 8192000000 10132049152 10567991296 1116049584 11.990 97 29 58 0 1200133840 77695616 6388640
07:16:31 35428 8192000000 10137611600 10578817024 1118697344 12.015 98 36 72 1 1226085840 99192144 8196352
07:18:07 33858 8192000000 10163427248 10626420736 1125504768 12.079 98 26 52 0 1254062928 123133424 5424736
07:19:42 42540 8192000000 10153500368 10595168256 1118752480 12.015 98 22 44 0 1218301072 94161392 5387200
07:21:30 40798 8192000000 10113583840 10544992256 1114628800 11.976 98 35 68 1 1215996720 94368704 6999216
07:23:28 71907 8192000000 10111690384 10599591936 1136725888 12.185 100 32 64 1 1208355520 65217888 6411744
07:25:42 65223 8192000000 10061350256 10524282880 1115536800 11.985 98 56 107 0 1174313840 46602240 12174800
07:27:48 33392 8192000000 10049122752 10510647296 1095137888 11.791 98 10 20 0 1142897792 45994336 1765568
07:29:40 46669 8192000000 10051412640 10528808960 1104698144 11.882 99 7 14 0 1150402016 44234320 1469552
07:31:27 72067 8192000000 10064475872 10551496704 1044418528 11.307 93 19 35 0 1087281488 39368704 3494256
07:33:43 49274 8192000000 10079919968 10531205120 1118160192 12.010 99 21 40 0 1171110400 48639472 4310736
07:35:33 36421 8192000000 10122258304 10557194240 1105296544 11.888 98 42 82 0 1186215792 71932192 8987056
07:37:15 82050 8192000000 10157788432 10606354432 1115261232 11.982 98 30 60 0 1221187376 99687296 6238848
07:40:11 93660 8192000000 10065492752 10557108224 1122289680 12.049 99 29 54 0 1293541344 165142416 6109248
07:42:47 51261 8192000000 10092409216 10544414720 1139903296 12.215 100 48 93 0 1179205424 30347872 8954256
07:44:40 136896 8192000000 10098972240 10544115712 1125578000 12.080 99 37 74 0 1189153168 56189504 7385664

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-06-2022 07:50:14 to 28-06-2022 07:50:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 192 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-06-2022 07:50:30 to 28-06-2022 07:50:32.

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          215576
MON$OLDEST_ACTIVE               217426
MON$OLDEST_SNAPSHOT             217426
MON$NEXT_TRANSACTION            217426
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                       2042272
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jun 28 07:47:58 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

28.06.22 07:50:33. 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
2022-06-28 07:50:36 No severe PSQL-related problems occured
28.06.22 07:50:37. End of report.