Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2021 16:39
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 16127148 kB
MemAvailable 31033928 kB
Buffers 0 kB
Cached 15567088 kB
SwapCached 2632 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-12 16:37:47
DTS_END                         2021-08-12 16:37:47.9000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40092
TRN_ID                          243315
ATT_ID                          647
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: 9597.04 18724 1151645
customer order: creation 180.67 362 21680
customer order: refuse 15.19 338 1823
order to supplier: creation 47.89 1217 5747
order to supplier: removal 3.46 85861 415
invoice (draft): creation 47.51 3257 5701
invoice (draft): removal 55.34 685 6641
invoice accept: apply 43.68 16550 5241
invoice accept: cancel 49.92 709 5990
customer reserve: creation 3980.48 166 477658
customer reserve: removal 4871.77 47 584612
realization accept: apply 86.68 163 10401
realization accept: cancel 79.81 97 9577
payment from customer: creation 61.36 95 7363
payment from customer: removal 5.83 56 700
payment to supplier: creation 51.80 118 6216
payment to supplier: removal 3.50 85 420
service: total inventory turnovers 4.65 1094 558
service: total monetary turnovers 6.63 43 796
service: aggregate perf. data 0.85 21202 102
service: refresh index statistics 0.03 242325 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-08-2021 16:40:12 to 12-08-2021 16:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1653
TEST_TIME 2 2892
TEST_TIME 3 3484
TEST_TIME 4 3729
TEST_TIME 5 4418
TEST_TIME 6 4560
TEST_TIME 7 4739
TEST_TIME 8 4886
TEST_TIME 9 5224
TEST_TIME 10 5572
TEST_TIME 11 5799
TEST_TIME 12 5894
TEST_TIME 13 6292
TEST_TIME 14 6577
TEST_TIME 15 7600
TEST_TIME 16 7539
TEST_TIME 17 7515
TEST_TIME 18 7929
TEST_TIME 19 8518
TEST_TIME 20 9162
TEST_TIME 21 9221
TEST_TIME 22 9757
TEST_TIME 23 10036
TEST_TIME 24 9879
TEST_TIME 25 9820
TEST_TIME 26 9510
TEST_TIME 27 9550
TEST_TIME 28 9599
TEST_TIME 29 10010
TEST_TIME 30 10056
TEST_TIME 31 9929
TEST_TIME 32 9904
TEST_TIME 33 9929
TEST_TIME 34 9858
TEST_TIME 35 9790
TEST_TIME 36 9715
TEST_TIME 37 9964
TEST_TIME 38 10007
TEST_TIME 39 9959
TEST_TIME 40 9951
TEST_TIME 41 9890
TEST_TIME 42 9919
TEST_TIME 43 9882
TEST_TIME 44 9830
TEST_TIME 45 9705
TEST_TIME 46 9630
TEST_TIME 47 9596
TEST_TIME 48 9529
TEST_TIME 49 9541
TEST_TIME 50 9521
TEST_TIME 51 9617
TEST_TIME 52 9649
TEST_TIME 53 9675
TEST_TIME 54 9604
TEST_TIME 55 9598
TEST_TIME 56 9512
TEST_TIME 57 9435
TEST_TIME 58 9564
TEST_TIME 59 9523
TEST_TIME 60 9507
TEST_TIME 61 9525
TEST_TIME 62 9591
TEST_TIME 63 9554
TEST_TIME 64 9602
TEST_TIME 65 9604
TEST_TIME 66 9637
TEST_TIME 67 9857
TEST_TIME 68 9832
TEST_TIME 69 9866
TEST_TIME 70 9946
TEST_TIME 71 9905
TEST_TIME 72 9860
TEST_TIME 73 9888
TEST_TIME 74 9797
TEST_TIME 75 9697
TEST_TIME 76 9658
TEST_TIME 77 9633
TEST_TIME 78 9601
TEST_TIME 79 9532
TEST_TIME 80 9565
TEST_TIME 81 9549
TEST_TIME 82 9468
TEST_TIME 83 9511
TEST_TIME 84 9474
TEST_TIME 85 9488
TEST_TIME 86 9487
TEST_TIME 87 9534
TEST_TIME 88 9526
TEST_TIME 89 9573
TEST_TIME 90 9581
TEST_TIME 91 9556
TEST_TIME 92 9607
TEST_TIME 93 9631
TEST_TIME 94 9660
TEST_TIME 95 9662
TEST_TIME 96 9677
TEST_TIME 97 9722
TEST_TIME 98 9689
TEST_TIME 99 9622
TEST_TIME 100 9579
TEST_TIME 101 9543
TEST_TIME 102 9506
TEST_TIME 103 9518
TEST_TIME 104 9464
TEST_TIME 105 9424
TEST_TIME 106 9461
TEST_TIME 107 9479
TEST_TIME 108 9476
TEST_TIME 109 9486
TEST_TIME 110 9491
TEST_TIME 111 9502
TEST_TIME 112 9525
TEST_TIME 113 9549
TEST_TIME 114 9562
TEST_TIME 115 9567
TEST_TIME 116 9580
TEST_TIME 117 9547
TEST_TIME 118 9508
TEST_TIME 119 9667
TEST_TIME 120 9598

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2021 16:40:16 to 12-08-2021 16:40:19.

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 1594535 1594535 0 0.00 0 19411 6 0 0
doc_list_biud 1594535 1594535 0 0.00 0 25382 23 0 0
sp_add_invoice_to_stock 5385 5241 144 2.67 19 169173 16550 0 144
sp_cancel_adding_invoice 5990 5990 0 0.00 4 57969 709 0 0
sp_cancel_client_order 1823 1823 0 0.00 4 11391 338 0 0
sp_cancel_customer_reserve 584612 584612 0 0.00 1 23777 47 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 2298 56 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4635 85 0 0
sp_cancel_supplier_invoice 6651 6641 10 0.15 1 23604 686 0 10
sp_cancel_supplier_order 415 415 0 0.00 7 969298 85862 0 0
sp_cancel_write_off 9577 9577 0 0.00 1 13359 97 0 0
sp_client_order 21680 21680 0 0.00 4 22141 363 0 0
sp_customer_reserve 477658 477658 0 0.00 0 37286 167 0 0
sp_fill_shopping_cart 33112 33112 0 0.00 1 24514 39 0 0
sp_fill_shopping_cart_clo_res 477653 477653 0 0.00 0 12689 11 0 0
sp_get_clo_for_invoice 5236 5236 0 0.00 0 18151 240 0 0
sp_kill_cost_storno 16687 16687 0 0.00 0 9651 48 0 0
sp_kill_qstorno_ret_qs2qd 1185857 1185857 0 0.00 0 23766 26 0 0
sp_kill_qty_storno 624695 624695 0 0.00 0 25381 58 0 0
sp_lock_selected_doc 21651 21651 0 0.00 0 6782 4 0 0
sp_make_cost_storno 29216 29216 0 0.00 0 16368 47 0 0
sp_multiply_rows_for_pdistr 29216 29216 0 0.00 0 8051 26 0 0
sp_multiply_rows_for_qdistr 21680 21680 0 0.00 0 15280 156 0 0
sp_pay_from_customer 7363 7363 0 0.00 4 14468 96 0 0
sp_pay_to_supplier 6216 6216 0 0.00 5 15068 119 0 0
sp_payment_common 13579 13579 0 0.00 1 14333 25 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 11383 319 0 0
sp_qd_handle_on_invoice_upd_sts 11226 11226 0 0.00 0 25378 329 0 0
sp_qd_handle_on_reserve_upd_sts 10401 10401 0 0.00 0 19140 63 0 0
sp_reserve_write_off 10401 10401 0 0.00 6 19265 164 0 0
sp_supplier_invoice 5701 5701 0 0.00 6 101808 3258 0 0
sp_supplier_order 5747 5747 0 0.00 5 22615 1218 0 0
srv_aggregate_perf_data 102 102 0 0.00 1933 504626 21203 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2293 136449 46313 0 0
srv_find_qd_qs_mism 1102273 1102273 0 0.00 0 19410 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 26 33327 1094 0 0
srv_make_money_saldo 796 796 0 0.00 1 14221 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 177132 350838 242325 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5241 5241 0 0.00 0 18607 134 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 3668 71 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 1181 11 0 0
t$perf-norm:sp_cancel_customer_reserve 744 744 0 0.00 0 1082 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 771 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 1491 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 488 7 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 32032 1650 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 5041 10 0 0
t$perf-norm:sp_client_order 21680 21680 0 0.00 0 21802 16 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 1085 5 0 0
t$perf-norm:sp_pay_from_customer 7363 7363 0 0.00 0 13897 13 0 0
t$perf-norm:sp_pay_to_supplier 6216 6216 0 0.00 0 6071 10 0 0
t$perf-norm:sp_reserve_write_off 10401 10401 0 0.00 0 5059 8 0 0
t$perf-norm:sp_supplier_invoice 5701 5701 0 0.00 0 17820 19 0 0
t$perf-norm:sp_supplier_order 5747 5747 0 0.00 0 21272 21 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 180 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 812 16 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 1058 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10754 10466 288 2.67 0 5781 20 0 288
v_all_customers 38974 38974 0 0.00 0 126 1 0 0
v_all_suppliers 31600 31600 0 0.00 0 78 1 0 0
v_all_wares 43360 43360 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1120 7 0 0
v_cancel_client_order 3646 3646 0 0.00 0 5702 5 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 688 3 0 0
v_cancel_customer_reserve 1488 1488 0 0.00 0 1073 3 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4065 11 0 0
v_cancel_supplier_invoice 926 906 20 2.15 0 1726 15 0 20
v_cancel_supplier_order 830 830 0 0.00 0 2203 8 0 0
v_cancel_write_off 2026 2026 0 0.00 0 6016 19 0 0
v_max_id_clo_ord 5747 5747 0 0.00 0 973 1 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 4813 12 0 0
v_max_id_ord_sup 5685 5685 0 0.00 0 5372 6 0 0
v_max_non_paid_invoice 1848 1848 0 0.00 0 874 3 0 0
v_max_non_paid_realizn 4104 4104 0 0.00 0 1489 3 0 0
v_min_id_clo_ord 5747 5747 0 0.00 0 5984 10 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 4291 11 0 0
v_min_id_ord_sup 5685 5685 0 0.00 0 7952 11 0 0
v_min_non_paid_invoice 6216 6216 0 0.00 0 14326 16 0 0
v_min_non_paid_realizn 7363 7363 0 0.00 0 12249 17 0 0
v_our_firm 10904 10904 0 0.00 0 23 1 0 0
v_reserve_write_off 20802 20802 0 0.00 0 6036 5 0 0
x_lock_depdocs_on_canc_invoice 12178 12178 0 0.00 0 12904 97 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 9676 283 0 0
x_make_qstorno_1000_1200 5747 5747 0 0.00 1 22587 1133 0 0
x_make_qstorno_1200_2000 5701 5701 0 0.00 3 101774 3114 0 0
x_make_qstorno_2100_3300 477658 477658 0 0.00 0 33423 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2021 16:40:20 to 12-08-2021 16:40:24.

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:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:52 31689 8192000000 10373585936 10693672960 1422842864 14.798 100 89 178 0 1456980880 5693472 28444544
14:10:29 27481 8192000000 10445329584 10774118400 1495106992 15.433 100 93 186 0 1532786848 8041584 29638272
14:12:06 23111 8192000000 10507019200 10853117952 1556446224 15.966 100 76 153 3 1589485168 9490928 23548016
14:13:31 10722 8192000000 10578556176 10939273216 1638826480 16.670 100 74 148 3 1671150896 9919872 22404544
14:14:48 7900 8192000000 10671077984 11042299904 1728210320 17.421 100 71 142 1 1759958208 11529440 20218448
14:15:58 6627 8192000000 10719737968 11101782016 1780349520 17.852 100 73 146 1 1813518272 12838576 20330176
14:17:07 3025 8192000000 10770648048 11157860352 1829064928 18.252 100 80 161 1 1863629968 12263920 22301120
14:18:13 2625 8192000000 10805349024 11193724928 1861093040 18.512 100 91 181 2 1901313168 14994352 25225776
14:19:17 24093 8192000000 10819902464 11209568256 1876198928 18.634 100 75 152 0 1907684896 10393248 21092720
14:20:43 2733 8192000000 10802054512 11221504000 1884216736 18.699 100 51 103 0 1908269216 8954976 15097504
14:21:48 4033 8192000000 10803178320 11227680768 1897220496 18.804 100 34 68 2 1913204704 6560224 9423984
14:22:54 61700 8192000000 10828061648 11247341568 1899261088 18.820 100 35 69 3 1914184288 5254544 9668656
14:24:57 13455 8192000000 10809906688 11247431680 1887592336 18.726 99 19 38 1 1899592000 5419664 6580000
14:26:11 10138 8192000000 10822458368 11258757120 1893060944 18.770 99 15 29 2 1902623136 5041792 4520400
14:27:22 14594 8192000000 10824778880 11252412416 1890857776 18.753 99 28 57 1 1903888208 5317552 7712880
14:28:40 12815 8192000000 10802206896 11231727616 1858972320 18.495 98 14 28 0 1868669376 5886496 3810560
14:29:53 6100 8192000000 10757895264 11176144896 1772665056 17.789 95 38 76 1 1789911728 6162608 11084064
14:31:04 7741 8192000000 10710924416 11117969408 1725020976 17.394 96 24 48 0 1742006944 9676416 7309552
14:32:15 71747 8192000000 10732312320 11155091456 1805425472 18.058 99 28 54 1 1819423328 5285264 8712592
14:34:28 6749 8192000000 10754635392 11173265408 1827986160 18.243 100 25 50 1 1841274544 6351952 6936432
14:35:38 13716 8192000000 10773174480 11193905152 1851266704 18.432 100 22 42 1 1863617152 6133088 6217360
14:36:53 62143 8192000000 10801909200 11221430272 1875307488 18.627 100 29 58 1 1889097616 5088944 8701184
14:39:06 4316 8192000000 10833575168 11254878208 1909165840 18.900 100 43 84 1 1928555264 7627760 11761664
14:40:15 7121 8192000000 10876412768 11290103808 1933391776 19.094 100 49 96 3 1958408720 11263584 13753360
14:41:42 19071 8192000000 10894624256 11320012800 1954278736 19.261 100 54 108 1 1988416128 18526560 15610832
14:43:05 11736 8192000000 10910485216 11335086080 1964056752 19.338 100 53 103 1 1997983056 19136144 14790160
14:44:32 8171 8192000000 10933674640 11364872192 1983195136 19.490 100 46 92 0 2018455056 22121072 13138848
14:45:45 3327 8192000000 10955486944 11379609600 1996586144 19.596 100 54 104 1 2038667216 26682096 15398976
14:46:57 4009 8192000000 10975578512 11398217728 2008840864 19.692 100 82 164 0 2063661200 31266688 23553648
14:48:05 8744 8192000000 10973686560 11397988352 2003788096 19.653 100 65 131 0 2057935984 34451056 19696832
14:49:17 4326 8192000000 10961143840 11390803968 1997445424 19.603 100 57 114 2 2049423568 33595872 18382272
14:50:28 10480 8192000000 10965215616 11398623232 1984637344 19.501 99 56 112 3 2042796960 40557680 17601936
14:51:46 3397 8192000000 10961193216 11384774656 1978756400 19.455 99 75 150 4 2040356400 38201248 23398752
14:52:51 12939 8192000000 10902710928 11352039424 1917000576 18.963 97 27 54 2 1958280560 32605456 8674528
14:54:17 5326 8192000000 10935244288 11368427520 1949295408 19.221 99 52 102 2 2005638624 40121760 16221456
14:55:27 2291 8192000000 10908032112 11332960256 1917967376 18.971 99 63 126 0 1981519856 43434368 20118112
14:56:32 11623 8192000000 10879413136 11317731328 1879707360 18.663 97 45 90 2 1935688832 41391952 14589520
14:57:49 10581 8192000000 10864123216 11305340928 1886645664 18.719 98 23 46 1 1919414528 25195376 7573488
14:59:03 15852 8192000000 10868806224 11319427072 1909293712 18.901 99 29 58 0 1946484592 27740384 9450496
15:00:24 20211 8192000000 10853968560 11291803648 1889838320 18.744 99 30 60 0 1927285088 28042000 9404768
15:01:49 18782 8192000000 10850270848 11292295168 1900077696 18.827 99 29 58 1 1931591792 22643200 8870896
15:03:41 22728 8192000000 10863435280 11308388352 1905026768 18.867 99 25 50 1 1939239920 26214704 7998448
15:05:33 42332 8192000000 10860423200 11328933888 1914026784 18.939 99 14 28 2 1950761632 32135600 4599248
15:07:26 53219 8192000000 10872802416 11323588608 1909233440 18.900 98 14 28 2 1923949072 10634752 4080880
15:09:31 136449 8192000000 10882171968 11342331904 1915910432 18.954 98 14 28 0 1936020864 15971760 4138672
15:12:48 34558 8192000000 10890315632 11334696960 1933376688 19.094 98 32 65 2 1952015952 9839440 8799824
15:14:24 73444 8192000000 10884496576 11351334912 1921538576 18.999 98 9 16 0 1938353904 14687216 2128112
15:16:38 40717 8192000000 10916430560 11361513472 1971660336 19.399 100 10 20 0 1981313072 7103744 2548992
15:18:26 65926 8192000000 10920994000 11388530688 1964587344 19.342 99 13 26 1 1977508160 9742400 3178416
15:20:40 109158 8192000000 10938750816 11407810560 1998431280 19.610 100 28 55 1 2027168832 19626080 9111472
15:23:38 27561 8192000000 10950048736 11409719296 2011271168 19.712 100 64 127 0 2043942752 14563616 18107968
15:25:08 25576 8192000000 10945859424 11408334848 1995610528 19.588 99 31 62 0 2019280224 14766688 8903008
15:26:35 48942 8192000000 10951473024 11401568256 1990082608 19.544 99 45 91 1 2020899536 16714736 14102192
15:28:29 43274 8192000000 10940396560 11409752064 1975450400 19.429 99 20 41 2 2004554112 23209600 5894112
15:30:14 97801 8192000000 10896886288 11362148352 1857037968 18.479 93 14 28 1 1874452064 13215936 4198160
15:32:52 62029 8192000000 10910468000 11387568128 1964987808 19.346 99 16 32 0 1988561520 18095856 5477856
15:34:59 76068 8192000000 10901804928 11393273856 1975263120 19.427 100 23 44 1 1997013232 15486336 6263776
15:37:24 69428 8192000000 10865076800 11344371712 1884413920 18.701 95 16 32 1 1901896048 13089968 4392160
15:39:43 39346 8192000000 10882401568 11341991936 1899866752 18.825 97 15 30 1 1911307328 7577328 3863248
15:41:22 54040 8192000000 10853070704 11318185984 1844106896 18.374 94 16 32 1 1864450176 15677296 4665984
15:43:28 28124 8192000000 10901003808 11368730624 1943015504 19.171 99 33 66 0 1980380176 27933632 9431040
15:44:56 107181 8192000000 10868399952 11320430592 1776051136 17.817 91 19 39 0 1794553760 13664752 4837872
15:47:44 92396 8192000000 10832177408 11270926336 1750720384 17.608 91 11 22 1 1767044592 13703984 2620224
15:50:16 128836 8192000000 10829186096 11287576576 1874617584 18.622 98 19 38 0 1890962784 10669328 5675872
15:53:26 19324 8192000000 10824191184 11292999680 1906827776 18.881 100 15 30 0 1919519072 8512816 4178480
15:54:46 80755 8192000000 10836207168 11313127424 1869310784 18.579 97 10 20 0 1883212208 10895600 3005824
15:57:06 39494 8192000000 10844945616 11324522496 1923405072 19.014 100 14 28 1 1942170256 14669360 4095824
15:59:07 54095 8192000000 10864314512 11344859136 1946996992 19.203 100 4 8 0 1957492000 9143072 1351936
16:01:24 44242 8192000000 10871483984 11332116480 1912010240 18.923 98 33 66 0 1930510624 8971040 9529344
16:03:10 100461 8192000000 10884152368 11364962304 1941097952 19.156 99 17 34 2 1958207456 12055232 5054272
16:05:50 30704 8192000000 10874663360 11340787712 1907944336 18.890 97 19 38 3 1927832640 14699552 5188752
16:07:25 41206 8192000000 10909960208 11368292352 1970039728 19.386 100 25 50 1 1988493840 12037568 6416544
16:09:07 53313 8192000000 10903654064 11376242688 1965938544 19.353 99 36 72 2 1987851408 11743216 10169648
16:11:00 82905 8192000000 10916786000 11398221824 1909656096 18.904 96 16 29 3 1931920896 17793600 4471200
16:13:24 52784 8192000000 10898816384 11365810176 1938271504 19.133 98 21 42 2 1955058032 11347904 5438624
16:15:33 85745 8192000000 10909446560 11383300096 1970887936 19.392 100 10 20 0 1984072416 10608112 2576368
16:18:07 11228 8192000000 10891178896 11366453248 1963654944 19.335 99 26 52 1 1985045312 13812992 7577376
16:19:19 104934 8192000000 10896967712 11392696320 1953480352 19.254 99 11 22 0 1978891040 22217984 3192704
16:22:04 62168 8192000000 10900546688 11371151360 1935294032 19.109 98 26 51 4 1962908288 20338208 7276048
16:24:10 56588 8192000000 10882965552 11362324480 1934565824 19.103 98 13 26 0 1954580304 16503920 3510560
16:26:15 74058 8192000000 10901915136 11380547584 1971063376 19.394 100 22 44 1 1988693456 11437488 6192592
16:28:33 33145 8192000000 10887357696 11366383616 1948312080 19.213 99 20 40 0 1968551440 14875376 5363984
16:30:23 95021 8192000000 10892086576 11364663296 1867217968 18.562 95 13 26 1 1882405232 11968368 3218896
16:33:07 93477 8192000000 10867797248 11342831616 1885864176 18.712 96 16 32 0 1904730816 14296832 4569808
16:35:51 26886 8192000000 10857473856 11329064960 1903840800 18.857 98 27 54 1 1947419920 35214320 8364800
16:37:24 44168 8192000000 10026115840 10422714368 1131874144 12.139 59 7 14 0 1160205872 26872112 1459616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-08-2021 16:40:26 to 12-08-2021 16:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2021 16:40:42 to 12-08-2021 16:40:44.

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          242338
MON$OLDEST_ACTIVE               243622
MON$OLDEST_SNAPSHOT             243622
MON$NEXT_TRANSACTION            243622
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                       2051488
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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Thu Aug 12 16:38:31 2021
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Thu Aug 12 16:38:35 2021
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Thu Aug 12 16:38:45 2021
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:161: 
--- end of diff output ---

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

12.08.21 16:40:45. 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-12 16:40:47 No severe PSQL-related problems occured
12.08.21 16:40:48. End of report.