Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2021 22: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 15810744 kB
MemAvailable 30683688 kB
Buffers 0 kB
Cached 15857000 kB
SwapCached 4800 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-10-06 22:37:53
DTS_END                         2021-10-06 22:37:53.5470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32802
TRN_ID                          251467
ATT_ID                          671
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: 9396.80 21513 1127616
customer order: creation 181.74 341 21809
customer order: refuse 15.68 361 1881
order to supplier: creation 49.28 1361 5913
order to supplier: removal 3.51 98132 421
invoice (draft): creation 48.37 3328 5804
invoice (draft): removal 52.26 809 6271
invoice accept: apply 43.44 17750 5213
invoice accept: cancel 46.79 807 5615
customer reserve: creation 3993.29 176 479195
customer reserve: removal 4657.74 57 558929
realization accept: apply 87.89 198 10547
realization accept: cancel 78.04 115 9365
payment from customer: creation 63.47 145 7616
payment from customer: removal 5.98 93 717
payment to supplier: creation 52.18 163 6261
payment to supplier: removal 3.81 101 457
service: total inventory turnovers 5.35 965 642
service: total monetary turnovers 7.10 32 852
service: aggregate perf. data 0.88 24016 105
service: refresh index statistics 0.03 281310 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-10-2021 22:38:54 to 06-10-2021 22:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2421
TEST_TIME 2 1728
TEST_TIME 3 3232
TEST_TIME 4 3481
TEST_TIME 5 4321
TEST_TIME 6 4658
TEST_TIME 7 4832
TEST_TIME 8 4776
TEST_TIME 9 4890
TEST_TIME 10 5080
TEST_TIME 11 5241
TEST_TIME 12 5572
TEST_TIME 13 5855
TEST_TIME 14 5805
TEST_TIME 15 5856
TEST_TIME 16 6375
TEST_TIME 17 6555
TEST_TIME 18 6656
TEST_TIME 19 7194
TEST_TIME 20 7388
TEST_TIME 21 7823
TEST_TIME 22 7914
TEST_TIME 23 8240
TEST_TIME 24 8354
TEST_TIME 25 8416
TEST_TIME 26 8603
TEST_TIME 27 9183
TEST_TIME 28 9633
TEST_TIME 29 10282
TEST_TIME 30 10225
TEST_TIME 31 10368
TEST_TIME 32 10234
TEST_TIME 33 10255
TEST_TIME 34 10435
TEST_TIME 35 10215
TEST_TIME 36 10472
TEST_TIME 37 10319
TEST_TIME 38 10207
TEST_TIME 39 10344
TEST_TIME 40 10200
TEST_TIME 41 10168
TEST_TIME 42 10026
TEST_TIME 43 9870
TEST_TIME 44 9827
TEST_TIME 45 9862
TEST_TIME 46 9860
TEST_TIME 47 9752
TEST_TIME 48 9739
TEST_TIME 49 9660
TEST_TIME 50 9631
TEST_TIME 51 9810
TEST_TIME 52 9794
TEST_TIME 53 9729
TEST_TIME 54 9662
TEST_TIME 55 9747
TEST_TIME 56 9589
TEST_TIME 57 9557
TEST_TIME 58 9555
TEST_TIME 59 9467
TEST_TIME 60 9382
TEST_TIME 61 9381
TEST_TIME 62 9413
TEST_TIME 63 9365
TEST_TIME 64 9408
TEST_TIME 65 9459
TEST_TIME 66 9479
TEST_TIME 67 9479
TEST_TIME 68 9564
TEST_TIME 69 9554
TEST_TIME 70 9631
TEST_TIME 71 9619
TEST_TIME 72 9670
TEST_TIME 73 9702
TEST_TIME 74 9665
TEST_TIME 75 9753
TEST_TIME 76 9746
TEST_TIME 77 9672
TEST_TIME 78 9613
TEST_TIME 79 9598
TEST_TIME 80 9580
TEST_TIME 81 9580
TEST_TIME 82 9527
TEST_TIME 83 9507
TEST_TIME 84 9452
TEST_TIME 85 9402
TEST_TIME 86 9416
TEST_TIME 87 9388
TEST_TIME 88 9371
TEST_TIME 89 9338
TEST_TIME 90 9340
TEST_TIME 91 9420
TEST_TIME 92 9431
TEST_TIME 93 9422
TEST_TIME 94 9427
TEST_TIME 95 9426
TEST_TIME 96 9459
TEST_TIME 97 9441
TEST_TIME 98 9525
TEST_TIME 99 9552
TEST_TIME 100 9565
TEST_TIME 101 9653
TEST_TIME 102 9651
TEST_TIME 103 9662
TEST_TIME 104 9609
TEST_TIME 105 9603
TEST_TIME 106 9592
TEST_TIME 107 9553
TEST_TIME 108 9527
TEST_TIME 109 9475
TEST_TIME 110 9436
TEST_TIME 111 9504
TEST_TIME 112 9443
TEST_TIME 113 9415
TEST_TIME 114 9394
TEST_TIME 115 9364
TEST_TIME 116 9373
TEST_TIME 117 9351
TEST_TIME 118 9365
TEST_TIME 119 9421
TEST_TIME 120 9401

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2021 22:38:58 to 06-10-2021 22:39:01.

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 1572734 1572734 0 0.00 0 23844 7 0 0
doc_list_biud 1572718 1572718 0 0.00 0 59033 26 0 0
sp_add_invoice_to_stock 5332 5213 119 2.23 54 194806 17750 0 119
sp_cancel_adding_invoice 5615 5615 0 0.00 5 92630 808 0 0
sp_cancel_client_order 1881 1881 0 0.00 4 10521 361 0 0
sp_cancel_customer_reserve 558929 558929 0 0.00 1 59034 58 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 2 8047 93 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 3 8438 101 0 0
sp_cancel_supplier_invoice 6284 6271 13 0.20 2 15203 809 0 13
sp_cancel_supplier_order 421 421 0 0.00 11 1155807 98133 0 0
sp_cancel_write_off 9365 9365 0 0.00 1 11489 116 0 0
sp_client_order 21809 21809 0 0.00 3 29040 342 0 0
sp_customer_reserve 479195 479195 0 0.00 0 73026 177 0 0
sp_fill_shopping_cart 33520 33520 0 0.00 1 14898 40 0 0
sp_fill_shopping_cart_clo_res 479191 479191 0 0.00 0 16405 13 0 0
sp_get_clo_for_invoice 5208 5208 0 0.00 0 27531 271 0 0
sp_kill_cost_storno 16154 16154 0 0.00 0 6470 59 0 0
sp_kill_qstorno_ret_qs2qd 1133915 1133915 0 0.00 0 59030 31 0 0
sp_kill_qty_storno 598232 598232 0 0.00 0 59031 68 0 0
sp_lock_selected_doc 21863 21863 0 0.00 0 8040 8 0 0
sp_make_cost_storno 29632 29632 0 0.00 0 23472 80 0 0
sp_multiply_rows_for_pdistr 29616 29616 0 0.00 0 5978 28 0 0
sp_multiply_rows_for_qdistr 21809 21809 0 0.00 0 18348 143 0 0
sp_pay_from_customer 7616 7616 0 0.00 4 23848 145 0 0
sp_pay_to_supplier 6261 6261 0 0.00 5 23114 164 0 0
sp_payment_common 13866 13866 0 0.00 1 11850 32 0 0
sp_qd_handle_on_cancel_clo 1881 1881 0 0.00 0 10503 323 0 0
sp_qd_handle_on_invoice_upd_sts 10823 10823 0 0.00 0 14988 322 0 0
sp_qd_handle_on_reserve_upd_sts 10542 10542 0 0.00 0 9775 52 0 0
sp_reserve_write_off 10547 10547 0 0.00 6 23667 199 0 0
sp_supplier_invoice 5804 5804 0 0.00 5 89449 3329 0 0
sp_supplier_order 5913 5913 0 0.00 5 27415 1361 0 0
srv_aggregate_perf_data 106 105 1 0.94 2586 336864 24017 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2063 113502 43648 0 0
srv_find_qd_qs_mism 1077960 1077960 0 0.00 0 11640 5 0 0
srv_make_invnt_saldo 642 642 0 0.00 17 38172 966 0 0
srv_make_money_saldo 852 852 0 0.00 1 2667 32 0 0
srv_recalc_idx_stat 3 3 0 0.00 234861 312457 281310 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5213 5213 0 0.00 0 14037 166 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 3888 103 0 0
t$perf-norm:sp_cancel_client_order 1881 1881 0 0.00 0 3322 17 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 1115 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 21154 37 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 2115 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1929 19 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 26830 1876 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 2633 9 0 0
t$perf-norm:sp_client_order 21809 21809 0 0.00 0 8371 17 0 0
t$perf-norm:sp_customer_reserve 1091 1091 0 0.00 0 5162 13 0 0
t$perf-norm:sp_pay_from_customer 7616 7616 0 0.00 0 4550 12 0 0
t$perf-norm:sp_pay_to_supplier 6261 6261 0 0.00 0 8382 11 0 0
t$perf-norm:sp_reserve_write_off 10547 10547 0 0.00 0 7803 11 0 0
t$perf-norm:sp_supplier_invoice 5804 5804 0 0.00 0 5262 19 0 0
t$perf-norm:sp_supplier_order 5913 5913 0 0.00 0 7110 21 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 134 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 491 16 0 0
t$perf-norm:srv_make_invnt_saldo 642 642 0 0.00 0 3470 36 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 3029 18 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10654 10416 238 2.23 0 10234 21 0 238
v_all_customers 39688 39688 0 0.00 0 79 1 0 0
v_all_suppliers 32638 32638 0 0.00 0 29 1 0 0
v_all_wares 43614 43614 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 8075 17 0 0
v_cancel_client_order 3762 3762 0 0.00 0 3084 5 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 2673 6 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 6245 9 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 1152 4 0 0
v_cancel_supplier_invoice 876 850 26 2.96 0 6615 36 0 26
v_cancel_supplier_order 842 842 0 0.00 0 645 5 0 0
v_cancel_write_off 2118 2118 0 0.00 0 6098 22 0 0
v_max_id_clo_ord 5911 5911 0 0.00 0 1021 2 0 0
v_max_id_clo_res 1091 1091 0 0.00 0 3254 17 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 3133 3 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 983 3 0 0
v_max_non_paid_realizn 4088 4088 0 0.00 0 5270 5 0 0
v_min_id_clo_ord 5911 5911 0 0.00 0 14692 19 0 0
v_min_id_clo_res 1091 1091 0 0.00 0 2515 7 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 7734 10 0 0
v_min_non_paid_invoice 6254 6254 0 0.00 0 4417 15 0 0
v_min_non_paid_realizn 7612 7612 0 0.00 0 6411 21 0 0
v_our_firm 10974 10974 0 0.00 0 19 1 0 0
v_reserve_write_off 21084 21084 0 0.00 0 10232 5 0 0
x_lock_depdocs_on_canc_invoice 11461 11461 0 0.00 0 8094 117 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 6975 352 0 0
x_make_qstorno_1000_1200 5913 5913 0 0.00 1 20487 1264 0 0
x_make_qstorno_1200_2000 5804 5804 0 0.00 2 82998 3199 0 0
x_make_qstorno_2100_3300 479195 479195 0 0.00 0 73022 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2021 22:39:02 to 06-10-2021 22:39:06.

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
20:07:57 20 8192000000 8825524592 8843079680 28157744 0.342 5 5 6 0 28517344 98112 261488
20:08:57 34620 8192000000 10417002768 10741923840 1467774720 15.194 100 89 178 0 1503673888 7098480 28800688
20:10:37 20856 8192000000 10474883808 10812719104 1526632480 15.708 100 88 176 1 1564307312 9386336 28288496
20:12:01 13294 8192000000 10521160048 10868117504 1569962256 16.082 100 81 164 0 1605945952 10696256 25287440
20:13:18 14080 8192000000 10568060368 10924630016 1622738384 16.533 100 82 162 1 1658360080 10974608 24647088
20:14:35 9784 8192000000 10638416464 11009900544 1695043568 17.144 100 72 142 1 1727852496 12109040 20699888
20:15:47 4210 8192000000 10698918192 11077148672 1762869776 17.708 100 82 165 0 1799717536 13770000 23077760
20:16:52 2977 8192000000 10751935824 11137597440 1814264032 18.131 100 90 178 1 1853546768 14354512 24928224
20:17:57 1914 8192000000 10805404384 11189084160 1858707904 18.493 100 94 187 1 1898284624 13432064 26144656
20:19:00 37138 8192000000 10829219184 11236585472 1892703424 18.768 100 69 137 3 1921024256 9693488 18627344
20:20:38 5043 8192000000 10820322992 11237314560 1898282928 18.812 100 41 82 0 1917664768 7110224 12271616
20:21:44 7197 8192000000 10813118768 11250188288 1907794304 18.889 100 13 26 0 1916911888 5480816 3636768
20:22:53 8648 8192000000 10814513024 11256868864 1913118000 18.932 100 9 18 0 1921270528 5887360 2265168
20:24:06 27043 8192000000 10869687424 11278823424 1925776832 19.033 100 64 128 0 1951467408 5425680 20264896
20:25:33 5072 8192000000 10831655328 11276509184 1924940688 19.026 100 28 49 2 1939089024 5618416 8529920
20:26:38 6608 8192000000 10831888288 11266084864 1884755328 18.703 98 21 42 1 1896060304 5156224 6148752
20:27:49 5767 8192000000 10793260448 11221082112 1854450320 18.458 98 24 47 1 1867168080 5758736 6959024
20:28:56 23215 8192000000 10702034912 11124408320 1663855136 16.881 89 13 25 2 1671932464 4854128 3223200
20:30:21 4988 8192000000 10737874592 11153559552 1803969328 18.046 98 36 71 1 1821142768 6698288 10475152
20:31:29 11165 8192000000 10704671968 11131015168 1759720896 17.682 97 21 41 0 1771219984 5385072 6114016
20:32:46 59637 8192000000 10750138912 11166523392 1808072528 18.080 99 33 67 1 1824308016 5568352 10667136
20:34:52 6764 8192000000 10775440976 11194601472 1852230128 18.440 100 34 68 1 1869206752 7458976 9517648
20:36:03 6767 8192000000 10783557152 11208105984 1864958480 18.543 100 22 44 0 1875764144 4423632 6382032
20:37:10 15302 8192000000 10808179056 11243466752 1900577056 18.831 100 25 50 1 1912304384 4700400 7026928
20:38:31 3467 8192000000 10852054976 11270148096 1921294096 18.997 100 48 94 0 1941361664 6962144 13105424
20:39:41 78140 8192000000 10865592400 11296903168 1934323712 19.101 100 51 101 1 1962392160 13873456 14194992
20:42:19 42123 8192000000 10892280832 11318095872 1952411440 19.246 100 34 69 2 1973549312 12162592 8975280
20:44:04 20888 8192000000 10919293296 11349164032 1970316480 19.388 100 51 102 1 2003423072 18427200 14679392
20:45:36 14859 8192000000 10916779280 11351707648 1965839040 19.352 99 48 96 1 1997678032 17813616 14025376
20:46:55 9296 8192000000 10932267360 11362508800 1981479808 19.476 100 55 111 0 2017843248 19381184 16982256
20:48:07 8972 8192000000 10944930400 11373793280 1991001568 19.552 100 58 116 1 2030538112 21816160 17720384
20:49:20 2083 8192000000 10952502320 11374551040 1992789184 19.566 100 81 162 2 2044853824 27739488 24325152
20:50:27 3918 8192000000 10944243056 11362217984 1972712528 19.407 100 74 143 3 2021671216 27487744 21470944
20:51:48 5471 8192000000 10967179376 11383099392 1991447888 19.555 100 83 166 3 2050574160 34704512 24421760
20:53:02 9372 8192000000 10932747456 11352055808 1948081840 19.211 99 67 133 1 2004129552 35246880 20800832
20:54:17 6134 8192000000 10913373776 11324948480 1919297088 18.981 98 76 150 0 1978455104 35066880 24091136
20:55:29 3118 8192000000 10930990400 11345645568 1950047344 19.227 100 77 152 1 2013347808 38304224 24996240
20:56:38 5464 8192000000 10960784880 11379027968 1971698688 19.399 100 77 154 3 2039893424 43709120 24485616
20:57:49 2062 8192000000 10969148560 11385655296 1974634624 19.422 99 77 154 0 2046294448 47364368 24295456
20:58:54 2855 8192000000 10946877568 11379748864 1983092704 19.489 100 47 94 0 2033656224 35305520 15258000
21:00:07 24592 8192000000 10965370096 11411406848 1997563328 19.604 100 36 72 3 2052397760 41861856 12972576
21:01:43 9297 8192000000 10958509920 11399299072 1973170816 19.411 99 36 72 0 2026290448 40959936 12159696
21:03:00 15444 8192000000 10965767952 11415728128 1975408960 19.428 99 31 62 2 2034834176 48877344 10547872
21:04:42 31953 8192000000 10898361232 11366903808 1920715568 18.993 99 16 32 1 1971080224 44381744 5982912
21:06:40 37508 8192000000 10860397248 11322167296 1917392752 18.966 99 17 34 1 1939598352 17626320 4579280
21:08:19 54578 8192000000 10903577680 11378388992 1941597488 19.160 99 8 16 1 1968087872 24370624 2119760
21:10:14 64687 8192000000 10919697376 11398397952 1957883824 19.289 99 6 12 0 1981824960 21932720 2008416
21:12:19 31744 8192000000 10936586672 11383771136 1989746816 19.542 100 48 91 0 2014878080 12152992 12978272
21:14:07 97456 8192000000 10933524048 11390218240 1989473872 19.540 100 16 32 0 2008278528 14529232 4275424
21:17:36 53040 8192000000 10911879344 11372511232 1940916048 19.154 97 24 48 0 1957811424 9765472 7129904
21:19:40 96173 8192000000 10950686112 11397492736 1997294832 19.601 100 37 72 1 2016860032 8821440 10743760
21:22:19 65378 8192000000 10931824752 11396608000 1935102144 19.108 96 29 58 0 1960406624 17588128 7716352
21:24:36 17963 8192000000 10912578944 11370315776 1970876288 19.392 98 33 66 0 1990335824 9896672 9562864
21:25:56 34045 8192000000 10929141280 11401633792 1954901952 19.265 97 17 34 0 1968675856 9081472 4692432
21:27:37 53592 8192000000 10950525408 11421102080 1964002896 19.338 97 49 97 0 1998764640 20745024 14016720
21:29:33 57096 8192000000 10932831456 11422220288 1987817744 19.527 99 7 14 0 2004449168 14467280 2164144
21:31:48 64560 8192000000 10902515040 11393667072 1973426160 19.413 99 17 34 1 1989832576 11260144 5146272
21:34:03 24579 8192000000 10890363568 11361792000 1952714304 19.248 98 25 46 1 1967843040 8980160 6148576
21:35:29 78921 8192000000 10891049056 11370094592 1903440704 18.854 96 12 24 0 1918875504 12035152 3399648
21:37:56 57984 8192000000 10863405712 11345100800 1885058496 18.706 96 8 16 0 1899747280 12461984 2226800
21:39:54 43308 8192000000 10871856720 11332894720 1831956624 18.275 93 16 32 1 1845545328 9465472 4123232
21:41:40 23659 8192000000 10875824000 11336929280 1929012352 19.059 99 29 57 0 1949245456 11225168 9007936
21:43:03 26561 8192000000 10891018400 11334737920 1900657040 18.832 97 35 67 2 1918910576 8709424 9544112
21:44:31 51352 8192000000 10883847344 11348779008 1881729504 18.679 96 27 54 1 1903430112 14551664 7148944
21:46:23 30551 8192000000 10927500896 11363364864 1959227328 19.300 100 38 76 0 1980618352 10893008 10498016
21:47:55 24767 8192000000 10905923264 11368128512 1935891968 19.114 98 29 58 0 1954809072 10223952 8693152
21:49:21 113502 8192000000 10884639856 11364282368 1869032032 18.576 95 14 28 0 1888324256 15460688 3831536
21:52:16 80878 8192000000 10881174864 11360137216 1900344336 18.829 97 18 36 1 1917954512 12583440 5026736
21:54:37 84784 8192000000 10884877488 11353485312 1939227952 19.141 99 38 76 0 1958579168 7760784 11590432
21:57:02 88902 8192000000 10871512736 11346046976 1916351152 18.958 98 24 47 2 1938248416 14723184 7174080
21:59:32 61824 8192000000 10871281856 11352059904 1933146720 19.092 99 20 40 0 1948353680 9028480 6178480
22:01:35 30818 8192000000 10888382608 11363311616 1953915296 19.258 99 26 50 0 1971378512 10606144 6857072
22:03:06 55934 8192000000 10863563520 11339780096 1921977248 19.003 98 15 30 0 1938715376 12374128 4364000
22:05:03 82012 8192000000 10888948576 11364605952 1939208544 19.140 99 11 22 0 1950786592 8675360 2902688
22:07:31 37632 8192000000 10892262144 11366612992 1948230944 19.212 99 10 20 1 1962006720 11343440 2432336
22:09:15 34653 8192000000 10892995360 11359846400 1937908256 19.130 98 19 38 1 1950592256 7480384 5203616
22:10:50 62941 8192000000 10886152240 11354902528 1920544784 18.991 97 22 44 0 1936897360 10355520 5997056
22:12:57 59166 8192000000 10896912176 11372400640 1933779232 19.097 97 22 44 0 1956684416 16383712 6521472
22:15:18 82936 8192000000 10903785440 11374223360 1910273040 18.909 96 23 43 3 1932650912 16037680 6340192
22:17:42 67089 8192000000 10877081648 11365601280 1907407520 18.886 97 16 33 0 1928681744 16836096 4438128
22:20:03 49165 8192000000 10854868544 11321823232 1849379440 18.417 95 21 41 1 1867166832 11805120 5982272
22:22:03 93480 8192000000 10827251584 11313741824 1840323584 18.343 96 13 26 0 1859846704 15968064 3555056
22:24:51 36343 8192000000 10850672048 11330064384 1905962848 18.874 99 15 30 0 1918009856 7327408 4719600
22:26:39 50253 8192000000 10835776608 11304964096 1861370688 18.514 97 11 21 1 1879612704 15531552 2710464
22:28:51 48890 8192000000 10848146176 11290222592 1886610384 18.718 98 37 80 0 1904897632 7920112 10367136
22:30:50 69506 8192000000 10819557120 11301564416 1880910112 18.672 98 7 14 0 1893239904 10439328 1890464
22:33:00 57130 8192000000 10859258336 11322195968 1924923856 19.026 100 11 22 0 1936091440 7566752 3600832
22:35:08 57394 8192000000 10894883216 11374145536 1955069536 19.267 100 18 36 0 1972852496 12635840 5147120
22:37:13 42793 8192000000 9970182144 10350391296 1017010832 11.043 52 15 30 0 1025029552 4722496 3296224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-10-2021 22:39:08 to 06-10-2021 22:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 119 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2021 22:39:24 to 06-10-2021 22:39:26.

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          249297
MON$OLDEST_ACTIVE               251713
MON$OLDEST_SNAPSHOT             251713
MON$NEXT_TRANSACTION            251713
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                       2044384
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:332: 
:333: mscs-test-161.budzdorov.ru    Wed Oct  6 22:38:01 2021
:334:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:335: 
--- end of diff output ---

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

06.10.21 22:39:27. 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-10-06 22:39:30 No severe PSQL-related problems occured
06.10.21 22:39:31. End of report.