Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2021 16: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 16206868 kB
MemAvailable 31038576 kB
Buffers 0 kB
Cached 15492616 kB
SwapCached 2576 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-13 16:37:48
DTS_END                         2021-08-13 16:37:48.3160
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(57:17)
IP                              ::1/56036
TRN_ID                          238884
ATT_ID                          694
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: 9275.41 24171 1113049
customer order: creation 170.40 418 20448
customer order: refuse 14.48 379 1738
order to supplier: creation 44.29 1401 5315
order to supplier: removal 3.49 108627 419
invoice (draft): creation 45.84 3577 5501
invoice (draft): removal 52.97 855 6356
invoice accept: apply 42.21 18334 5065
invoice accept: cancel 47.89 820 5747
customer reserve: creation 3859.39 182 463127
customer reserve: removal 4699.57 63 563948
realization accept: apply 82.00 201 9840
realization accept: cancel 83.88 109 10066
payment from customer: creation 58.36 118 7003
payment from customer: removal 5.69 96 683
payment to supplier: creation 49.15 151 5898
payment to supplier: removal 3.28 80 394
service: total inventory turnovers 4.90 1162 588
service: total monetary turnovers 6.74 36 809
service: aggregate perf. data 0.84 19684 101
service: refresh index statistics 0.03 327131 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2021 16:39:21 to 13-08-2021 16:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2519
TEST_TIME 2 2145
TEST_TIME 3 3131
TEST_TIME 4 3639
TEST_TIME 5 4212
TEST_TIME 6 4313
TEST_TIME 7 4553
TEST_TIME 8 4731
TEST_TIME 9 5380
TEST_TIME 10 5545
TEST_TIME 11 6151
TEST_TIME 12 6080
TEST_TIME 13 6203
TEST_TIME 14 6335
TEST_TIME 15 6469
TEST_TIME 16 6502
TEST_TIME 17 6683
TEST_TIME 18 6884
TEST_TIME 19 7469
TEST_TIME 20 7513
TEST_TIME 21 7808
TEST_TIME 22 8154
TEST_TIME 23 8428
TEST_TIME 24 8386
TEST_TIME 25 8764
TEST_TIME 26 9097
TEST_TIME 27 9155
TEST_TIME 28 9242
TEST_TIME 29 10159
TEST_TIME 30 10386
TEST_TIME 31 10255
TEST_TIME 32 10285
TEST_TIME 33 10249
TEST_TIME 34 10231
TEST_TIME 35 10079
TEST_TIME 36 10121
TEST_TIME 37 10232
TEST_TIME 38 10001
TEST_TIME 39 9864
TEST_TIME 40 9850
TEST_TIME 41 9639
TEST_TIME 42 9568
TEST_TIME 43 9549
TEST_TIME 44 9420
TEST_TIME 45 9416
TEST_TIME 46 9719
TEST_TIME 47 9857
TEST_TIME 48 9769
TEST_TIME 49 9657
TEST_TIME 50 9689
TEST_TIME 51 9720
TEST_TIME 52 9759
TEST_TIME 53 9760
TEST_TIME 54 9685
TEST_TIME 55 9696
TEST_TIME 56 9823
TEST_TIME 57 9832
TEST_TIME 58 9771
TEST_TIME 59 9762
TEST_TIME 60 10022
TEST_TIME 61 10051
TEST_TIME 62 9992
TEST_TIME 63 9931
TEST_TIME 64 9821
TEST_TIME 65 9740
TEST_TIME 66 9695
TEST_TIME 67 9658
TEST_TIME 68 9622
TEST_TIME 69 9557
TEST_TIME 70 9507
TEST_TIME 71 9514
TEST_TIME 72 9445
TEST_TIME 73 9422
TEST_TIME 74 9441
TEST_TIME 75 9458
TEST_TIME 76 9411
TEST_TIME 77 9441
TEST_TIME 78 9441
TEST_TIME 79 9423
TEST_TIME 80 9502
TEST_TIME 81 9486
TEST_TIME 82 9443
TEST_TIME 83 9422
TEST_TIME 84 9489
TEST_TIME 85 9599
TEST_TIME 86 9601
TEST_TIME 87 9559
TEST_TIME 88 9559
TEST_TIME 89 9652
TEST_TIME 90 9559
TEST_TIME 91 9502
TEST_TIME 92 9467
TEST_TIME 93 9405
TEST_TIME 94 9405
TEST_TIME 95 9427
TEST_TIME 96 9414
TEST_TIME 97 9403
TEST_TIME 98 9416
TEST_TIME 99 9388
TEST_TIME 100 9353
TEST_TIME 101 9431
TEST_TIME 102 9397
TEST_TIME 103 9469
TEST_TIME 104 9563
TEST_TIME 105 9521
TEST_TIME 106 9542
TEST_TIME 107 9573
TEST_TIME 108 9569
TEST_TIME 109 9570
TEST_TIME 110 9502
TEST_TIME 111 9491
TEST_TIME 112 9450
TEST_TIME 113 9427
TEST_TIME 114 9410
TEST_TIME 115 9370
TEST_TIME 116 9377
TEST_TIME 117 9365
TEST_TIME 118 9323
TEST_TIME 119 9280
TEST_TIME 120 9279

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-08-2021 16:39:24 to 13-08-2021 16:39:28.

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 1541034 1541034 0 0.00 0 24950 7 0 0
doc_list_biud 1541034 1541034 0 0.00 0 27138 29 0 0
sp_add_invoice_to_stock 5150 5065 85 1.65 28 168272 18334 0 85
sp_cancel_adding_invoice 5747 5747 0 0.00 4 60590 820 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 9109 380 0 0
sp_cancel_customer_reserve 563948 563948 0 0.00 1 27140 63 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 7391 97 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 6167 80 0 0
sp_cancel_supplier_invoice 6363 6356 7 0.11 1 15758 855 0 7
sp_cancel_supplier_order 419 419 0 0.00 10 1043222 108628 0 0
sp_cancel_write_off 10066 10066 0 0.00 1 14981 110 0 0
sp_client_order 20448 20448 0 0.00 4 27604 418 0 0
sp_customer_reserve 463127 463127 0 0.00 0 78344 183 0 0
sp_fill_shopping_cart 31258 31258 0 0.00 1 11391 41 0 0
sp_fill_shopping_cart_clo_res 463125 463125 0 0.00 0 14494 14 0 0
sp_get_clo_for_invoice 5063 5063 0 0.00 0 28653 293 0 0
sp_kill_cost_storno 16890 16890 0 0.00 0 13545 61 0 0
sp_kill_qstorno_ret_qs2qd 1144737 1144737 0 0.00 0 27106 34 0 0
sp_kill_qty_storno 603177 603177 0 0.00 0 27138 74 0 0
sp_lock_selected_doc 20639 20639 0 0.00 0 8217 6 0 0
sp_make_cost_storno 27804 27804 0 0.00 0 24037 65 0 0
sp_multiply_rows_for_pdistr 27804 27804 0 0.00 0 10814 34 0 0
sp_multiply_rows_for_qdistr 20448 20448 0 0.00 0 14507 186 0 0
sp_pay_from_customer 7003 7003 0 0.00 4 26690 118 0 0
sp_pay_to_supplier 5898 5898 0 0.00 5 26583 152 0 0
sp_payment_common 12901 12901 0 0.00 1 15630 34 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 9097 355 0 0
sp_qd_handle_on_invoice_upd_sts 10810 10810 0 0.00 0 18632 348 0 0
sp_qd_handle_on_reserve_upd_sts 9840 9840 0 0.00 0 14458 58 0 0
sp_reserve_write_off 9840 9840 0 0.00 7 26632 202 0 0
sp_supplier_invoice 5501 5501 0 0.00 7 87321 3577 0 0
sp_supplier_order 5315 5315 0 0.00 5 33372 1401 0 0
srv_aggregate_perf_data 101 101 0 0.00 2791 304867 19684 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1354 119886 44091 0 0
srv_find_qd_qs_mism 1065119 1065119 0 0.00 0 14369 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 22 46258 1163 0 0
srv_make_money_saldo 809 809 0 0.00 1 7275 36 0 0
srv_recalc_idx_stat 3 3 0 0.00 205500 466946 327131 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5066 5066 0 0.00 0 13133 189 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 13451 94 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 2218 12 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 3627 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 387 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1498 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 2222 21 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 23081 1789 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 686 3 0 0
t$perf-norm:sp_client_order 20448 20448 0 0.00 0 10248 20 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 6425 20 0 0
t$perf-norm:sp_pay_from_customer 7003 7003 0 0.00 0 6370 12 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 6618 12 0 0
t$perf-norm:sp_reserve_write_off 9840 9840 0 0.00 0 10669 11 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 12612 22 0 0
t$perf-norm:sp_supplier_order 5315 5315 0 0.00 0 5231 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 555 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 271 9 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 8446 39 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 2487 17 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10296 10126 170 1.65 0 26681 22 0 170
v_all_customers 37120 37120 0 0.00 0 19 1 0 0
v_all_suppliers 30214 30214 0 0.00 0 21 1 0 0
v_all_wares 40896 40896 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1351 9 0 0
v_cancel_client_order 3476 3476 0 0.00 0 1436 5 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2432 7 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1258 4 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 328 4 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 261 12 0 14
v_cancel_supplier_order 838 838 0 0.00 0 2654 7 0 0
v_cancel_write_off 2030 2030 0 0.00 0 643 12 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 1289 2 0 0
v_max_id_clo_res 958 958 0 0.00 0 1373 14 0 0
v_max_id_ord_sup 5495 5495 0 0.00 0 4445 6 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 2383 4 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 858 3 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 2213 7 0 0
v_min_id_clo_res 958 958 0 0.00 0 3894 7 0 0
v_min_id_ord_sup 5495 5495 0 0.00 0 5718 13 0 0
v_min_non_paid_invoice 5898 5898 0 0.00 0 15627 26 0 0
v_min_non_paid_realizn 7003 7003 0 0.00 0 11016 20 0 0
v_our_firm 10222 10222 0 0.00 0 16 1 0 0
v_reserve_write_off 19678 19678 0 0.00 0 26542 8 0 0
x_lock_depdocs_on_canc_invoice 11695 11695 0 0.00 0 22933 132 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5443 320 0 0
x_make_qstorno_1000_1200 5315 5315 0 0.00 2 33298 1313 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 3 87312 3430 0 0
x_make_qstorno_2100_3300 463127 463127 0 0.00 0 78343 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2021 16:39:29 to 13-08-2021 16:39:33.

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
14:07:52 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:53 14947 8192000000 10374293200 10694258688 1435608176 14.911 100 83 166 0 1468447520 6184896 26654448
14:10:13 10619 8192000000 10432364960 10760888320 1485666800 15.351 100 87 174 0 1522136240 7998672 28470768
14:11:30 17971 8192000000 10475121792 10814423040 1529320624 15.731 100 88 176 1 1566867072 9613712 27932736
14:12:52 14944 8192000000 10551704976 10904907776 1602060416 16.357 100 75 150 0 1635248640 10658928 22529296
14:14:12 9483 8192000000 10626491104 10994421760 1685793184 17.066 100 79 158 2 1721463168 12430224 23239760
14:15:24 5624 8192000000 10697056688 11070046208 1754770160 17.641 100 85 170 1 1791382672 13544784 23067728
14:16:32 3030 8192000000 10736541344 11117772800 1800342336 18.017 100 88 172 1 1838435888 14054528 24039024
14:17:37 2530 8192000000 10773946320 11159207936 1833235936 18.286 100 82 164 1 1869698704 14246336 22216432
14:18:41 2336 8192000000 10826757232 11217653760 1883611920 18.694 100 92 185 2 1923286736 13355584 26319232
14:19:43 4728 8192000000 10820416352 11223150592 1885608640 18.710 100 64 128 0 1915229072 11375168 18245264
14:20:49 3595 8192000000 10802269424 11229163520 1897851888 18.809 100 37 75 2 1915470176 6399792 11218496
14:21:57 38872 8192000000 10801579344 11245248512 1901409360 18.838 100 25 50 1 1914691760 5628960 7653440
14:23:42 4588 8192000000 10829705744 11263913984 1913774256 18.937 100 37 72 1 1931419888 7235248 10410384
14:24:47 14706 8192000000 10822644512 11267874816 1917383488 18.966 100 12 23 0 1926762736 5750528 3628720
14:26:02 62213 8192000000 10870156240 11273633792 1907270384 18.885 99 66 133 2 1933294112 5899808 20123920
14:28:05 5092 8192000000 10780856288 11213598720 1850823232 18.429 98 30 59 2 1865827680 7010688 7993760
14:29:10 9963 8192000000 10757699632 11183063040 1797379568 17.992 97 22 43 4 1809459616 5877968 6202080
14:30:23 57211 8192000000 10727465664 11158507520 1695226912 17.145 92 18 34 1 1704368624 4839424 4302288
14:32:22 5805 8192000000 10715462528 11135971328 1790729088 17.938 99 29 60 1 1805532784 6499888 8303808
14:33:29 9750 8192000000 10749838256 11168071680 1797584096 17.994 98 20 40 1 1808522144 4921936 6016112
14:34:44 68642 8192000000 10812414560 11206148096 1867410016 18.563 100 69 143 1 1893333952 5448336 20475600
14:36:53 7697 8192000000 10791889152 11215872000 1871829856 18.599 100 39 77 1 1889209120 6402864 10976400
14:38:03 13341 8192000000 10833906128 11253510144 1910218544 18.908 100 44 86 0 1929187008 6184432 12784032
14:39:23 77536 8192000000 10866423296 11293822976 1937337072 19.126 100 49 98 4 1962445904 9990560 15118272
14:41:53 47047 8192000000 10886677232 11318710272 1956488432 19.278 100 38 75 0 1978467456 10606000 11373024
14:43:41 12721 8192000000 10914596368 11347238912 1976806112 19.439 100 48 96 4 2005221248 13998992 14416144
14:44:58 20682 8192000000 10925922368 11357462528 1971191312 19.395 99 56 112 0 2006106912 18148624 16766976
14:46:28 21175 8192000000 10924206224 11380084736 1990426496 19.547 100 32 64 2 2020561072 20314592 9819984
14:47:54 2144 8192000000 10972262032 11396739072 2007857872 19.685 100 71 142 1 2052418592 23493584 21067136
14:49:02 18359 8192000000 10962659488 11398692864 2006374192 19.673 100 56 112 0 2044825968 21848416 16603360
14:50:39 3938 8192000000 10966985792 11408867328 2015948016 19.748 100 56 109 1 2057318112 25568240 15801856
14:51:46 4067 8192000000 10992033888 11417890816 2019931616 19.780 100 67 134 0 2070167856 30042048 20194192
14:52:55 1354 8192000000 10964589680 11385581568 1987832240 19.527 99 89 181 3 2045012240 30210432 26969568
14:54:02 1745 8192000000 10979226544 11398885376 1999251440 19.617 100 79 158 0 2060746848 36938912 24556496
14:55:10 3989 8192000000 10974965104 11392561152 1990474272 19.548 100 90 181 1 2060645008 41873760 28296976
14:56:28 3010 8192000000 10974392064 11391844352 1989309232 19.538 100 90 179 1 2059912400 43125632 27477536
14:57:36 1712 8192000000 10965612480 11377246208 1981592704 19.477 100 92 184 0 2053654608 43181760 28880144
14:58:43 7869 8192000000 10938317664 11377942528 1968416944 19.373 100 40 80 1 2025285824 42900128 13968752
14:59:55 9979 8192000000 10927327616 11362947072 1946863936 19.201 99 47 94 2 2002812816 40296032 15652848
15:01:14 4522 8192000000 10933502240 11367669760 1963471712 19.334 100 52 104 1 2020452000 39413904 17566384
15:02:23 21351 8192000000 10919213744 11359334400 1940265088 19.149 99 36 72 2 1996101360 43019360 12816912
15:03:54 13590 8192000000 10899489984 11347120128 1943458176 19.174 100 25 50 1 1984148480 31512576 9177728
15:05:10 23055 8192000000 10888307232 11347451904 1926028304 19.035 99 22 44 1 1965416768 31889936 7498528
15:06:53 74740 8192000000 10866720416 11302371328 1850393840 18.425 95 17 34 2 1869107584 13415696 5298048
15:09:09 37694 8192000000 10866453696 11316854784 1907645392 18.888 98 13 26 1 1921941504 10740944 3555168
15:10:52 43414 8192000000 10885319232 11325988864 1928884192 19.058 99 24 46 4 1944585632 9695024 6006416
15:12:43 64493 8192000000 10902077952 11347386368 1946617824 19.200 99 28 56 1 1965431584 10186560 8627200
15:14:49 106178 8192000000 10898031440 11347005440 1959462368 19.302 100 23 45 2 1975569216 9613072 6493776
15:17:38 119880 8192000000 10921061456 11383681024 1957432992 19.286 99 23 46 2 1983003680 19078944 6491744
15:20:41 27787 8192000000 10934826032 11394850816 1988125056 19.529 100 38 74 4 2020994304 22315296 10553952
15:22:23 32182 8192000000 10951359840 11404607488 1987817680 19.527 100 29 58 3 2029095232 32000272 9277280
15:24:02 44699 8192000000 10907859344 11400040448 1968500096 19.374 99 17 34 2 2002155664 27710384 5945184
15:25:47 23537 8192000000 10915208896 11387990016 1988196976 19.530 100 13 26 0 2004159472 12151184 3811312
15:27:21 69941 8192000000 10914259600 11396820992 1962178688 19.323 99 16 32 1 1987047760 20418064 4451008
15:29:40 28914 8192000000 10914705584 11365773312 1971398272 19.397 100 37 73 1 1989840832 7907856 10534704
15:31:17 42512 8192000000 10937061680 11377717248 1967343760 19.364 99 35 68 2 1988768480 10643168 10781552
15:33:02 77430 8192000000 10904761584 11372322816 1918027968 18.971 97 14 28 2 1938744640 16887456 3829216
15:35:36 59915 8192000000 10895152048 11354509312 1880249136 18.667 95 13 27 1 1903749520 19667296 3833088
15:37:37 73502 8192000000 10879471936 11346018304 1896453312 18.798 97 9 18 0 1929397200 30282352 2661536
15:39:54 65628 8192000000 10878063136 11340013568 1916654960 18.960 98 27 52 2 1934742288 10266000 7821328
15:42:25 47518 8192000000 10845011296 11308204032 1895627584 18.791 98 28 56 2 1911030960 7502880 7900496
15:44:16 22732 8192000000 10834173824 11307569152 1915367248 18.950 100 22 43 1 1932539616 10678768 6493600
15:45:40 48604 8192000000 10849722560 11324268544 1923884000 19.018 100 9 18 0 1938207264 11642880 2680384
15:47:40 72091 8192000000 10854643904 11327725568 1913528784 18.935 99 21 43 0 1931421456 11693488 6199184
15:50:09 52197 8192000000 10848963456 11301834752 1907391408 18.886 99 22 44 0 1925746416 12210832 6144176
15:52:03 55645 8192000000 10859094944 11343978496 1932340624 19.086 100 12 23 1 1954614608 18589856 3684128
15:54:00 69088 8192000000 10867005280 11325218816 1914836272 18.945 99 13 26 0 1931157280 12850704 3470304
15:56:16 35306 8192000000 10832834688 11293937664 1872903968 18.608 97 31 61 4 1894058176 12219728 8934480
15:57:55 44240 8192000000 10832516704 11292884992 1831097584 18.268 95 23 46 1 1845367792 7683808 6586400
15:59:43 50405 8192000000 10870282624 11326889984 1897640672 18.807 98 21 42 1 1917461216 13762672 6057872
16:01:35 68464 8192000000 10879849552 11366178816 1932685936 19.088 99 21 41 2 1958253824 18914080 6653808
16:03:44 56661 8192000000 10907721344 11358715904 1939612464 19.144 99 19 38 0 1953804400 9273904 4918032
16:05:42 74356 8192000000 10893256768 11389911040 1971323296 19.396 100 25 50 1 1999572992 21069376 7180320
16:07:57 63893 8192000000 10884307376 11353899008 1935742768 19.113 98 24 48 1 1952362144 10294640 6324736
16:10:03 80223 8192000000 10891631264 11370639360 1932722480 19.089 98 9 16 2 1944724432 9851008 2150944
16:12:24 44301 8192000000 10910019264 11384893440 1971000608 19.393 100 19 38 1 1986203088 10093968 5108512
16:14:10 41025 8192000000 10927333440 11402706944 1994811824 19.582 100 21 42 0 2015403088 14197520 6393744
16:15:51 97043 8192000000 10960604032 11430150144 1990178864 19.545 99 17 34 1 2015050176 20010624 4860688
16:18:37 58729 8192000000 10954732928 11422322688 1991899264 19.559 99 19 38 0 2020579488 22783632 5896592
16:20:42 79304 8192000000 10923420224 11414441984 1930187904 19.068 96 6 12 0 1944281120 12365904 1727312
16:23:06 21675 8192000000 10972104432 11411337216 1992284368 19.562 99 56 109 0 2017655664 10453776 14917520
16:24:29 72235 8192000000 10899340912 11356659712 1897679472 18.808 96 24 47 0 1914818528 9828192 7310864
16:26:44 107412 8192000000 10864055648 11342184448 1938707408 19.136 100 28 56 0 1966144448 18892704 8544336
16:29:41 32726 8192000000 10863497248 11333816320 1894461584 18.782 97 14 28 0 1908389280 10469760 3457936
16:31:13 64186 8192000000 10867519200 11352956928 1934663280 19.104 99 8 16 0 1945333216 8569552 2100384
16:33:18 75402 8192000000 10883549952 11351601152 1932604976 19.088 98 18 37 0 1944865456 7463824 4796656
16:37:32 24472 8192000000 9264335680 9541836800 424956832 4.931 22 1 2 0 428341072 3322112 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-08-2021 16:39:35 to 13-08-2021 16:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2021 16:39:50 to 13-08-2021 16:39:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239166
MON$OLDEST_ACTIVE               239167
MON$OLDEST_SNAPSHOT             239167
MON$NEXT_TRANSACTION            239167
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-03 16:41:55.3470
MON$PAGES                       2044704
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:206: 
:207: mscs-test-161.budzdorov.ru    Fri Aug 13 16:37:59 2021
:208:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:209: 
:210: 
:211: mscs-test-161.budzdorov.ru    Fri Aug 13 16:37:59 2021
:212:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:213: 
:214: 
:215: mscs-test-161.budzdorov.ru    Fri Aug 13 16:37:59 2021
:216:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:217: 
--- end of diff output ---

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

13.08.21 16:39:53. There are 3 logs in /var/tmp/logs-oltp30 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-13 16:39:56 No severe PSQL-related problems occured
13.08.21 16:39:57. End of report.