Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.08.2021 13: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 16140984 kB
MemAvailable 31088692 kB
Buffers 0 kB
Cached 15572584 kB
SwapCached 2628 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-06 13:37:06
DTS_END                         2021-08-06 13:37:06.0300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34142
TRN_ID                          240349
ATT_ID                          622
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: 9155.97 25666 1098716
customer order: creation 168.38 409 20206
customer order: refuse 15.28 315 1834
order to supplier: creation 45.99 1390 5519
order to supplier: removal 3.43 111407 412
invoice (draft): creation 45.09 3390 5411
invoice (draft): removal 52.06 1074 6247
invoice accept: apply 40.52 19165 4862
invoice accept: cancel 47.20 906 5664
customer reserve: creation 3786.23 186 454348
customer reserve: removal 4657.92 63 558950
realization accept: apply 83.04 220 9965
realization accept: cancel 82.77 118 9932
payment from customer: creation 59.21 129 7105
payment from customer: removal 5.64 93 677
payment to supplier: creation 49.17 158 5900
payment to supplier: removal 3.29 103 395
service: total inventory turnovers 3.53 3404 423
service: total monetary turnovers 6.16 472 739
service: aggregate perf. data 1.03 13219 123
service: refresh index statistics 0.03 357090 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-08-2021 13:39:12 to 06-08-2021 13:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2010
TEST_TIME 2 1542
TEST_TIME 3 1661
TEST_TIME 4 3064
TEST_TIME 5 4622
TEST_TIME 6 4711
TEST_TIME 7 4849
TEST_TIME 8 5116
TEST_TIME 9 5629
TEST_TIME 10 5527
TEST_TIME 11 5637
TEST_TIME 12 6215
TEST_TIME 13 6556
TEST_TIME 14 6944
TEST_TIME 15 7131
TEST_TIME 16 7625
TEST_TIME 17 7519
TEST_TIME 18 7629
TEST_TIME 19 7834
TEST_TIME 20 8519
TEST_TIME 21 8676
TEST_TIME 22 8564
TEST_TIME 23 9159
TEST_TIME 24 9792
TEST_TIME 25 11016
TEST_TIME 26 11079
TEST_TIME 27 11043
TEST_TIME 28 10845
TEST_TIME 29 10633
TEST_TIME 30 10575
TEST_TIME 31 10536
TEST_TIME 32 10481
TEST_TIME 33 10279
TEST_TIME 34 10222
TEST_TIME 35 10138
TEST_TIME 36 10089
TEST_TIME 37 10342
TEST_TIME 38 10197
TEST_TIME 39 10064
TEST_TIME 40 9941
TEST_TIME 41 9792
TEST_TIME 42 9875
TEST_TIME 43 9891
TEST_TIME 44 10178
TEST_TIME 45 10033
TEST_TIME 46 10133
TEST_TIME 47 10160
TEST_TIME 48 10159
TEST_TIME 49 10088
TEST_TIME 50 10140
TEST_TIME 51 10203
TEST_TIME 52 10199
TEST_TIME 53 10145
TEST_TIME 54 9999
TEST_TIME 55 9865
TEST_TIME 56 9833
TEST_TIME 57 9851
TEST_TIME 58 9806
TEST_TIME 59 9734
TEST_TIME 60 9661
TEST_TIME 61 9525
TEST_TIME 62 9500
TEST_TIME 63 9600
TEST_TIME 64 9558
TEST_TIME 65 9533
TEST_TIME 66 9663
TEST_TIME 67 9620
TEST_TIME 68 9654
TEST_TIME 69 9665
TEST_TIME 70 9654
TEST_TIME 71 9827
TEST_TIME 72 9815
TEST_TIME 73 9779
TEST_TIME 74 9902
TEST_TIME 75 9910
TEST_TIME 76 9783
TEST_TIME 77 9723
TEST_TIME 78 9679
TEST_TIME 79 9640
TEST_TIME 80 9580
TEST_TIME 81 9528
TEST_TIME 82 9510
TEST_TIME 83 9500
TEST_TIME 84 9457
TEST_TIME 85 9451
TEST_TIME 86 9476
TEST_TIME 87 9464
TEST_TIME 88 9486
TEST_TIME 89 9483
TEST_TIME 90 9527
TEST_TIME 91 9512
TEST_TIME 92 9573
TEST_TIME 93 9612
TEST_TIME 94 9605
TEST_TIME 95 9632
TEST_TIME 96 9618
TEST_TIME 97 9591
TEST_TIME 98 9542
TEST_TIME 99 9497
TEST_TIME 100 9441
TEST_TIME 101 9385
TEST_TIME 102 9350
TEST_TIME 103 9290
TEST_TIME 104 9314
TEST_TIME 105 9283
TEST_TIME 106 9290
TEST_TIME 107 9291
TEST_TIME 108 9267
TEST_TIME 109 9286
TEST_TIME 110 9317
TEST_TIME 111 9284
TEST_TIME 112 9306
TEST_TIME 113 9293
TEST_TIME 114 9282
TEST_TIME 115 9296
TEST_TIME 116 9354
TEST_TIME 117 9325
TEST_TIME 118 9256
TEST_TIME 119 9233
TEST_TIME 120 9157

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-08-2021 13:39:16 to 06-08-2021 13:39: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 1519970 1519970 0 0.00 0 35126 7 0 0
doc_list_biud 1519969 1519969 0 0.00 0 49508 30 0 0
sp_add_invoice_to_stock 4994 4862 132 2.64 28 201871 19165 0 132
sp_cancel_adding_invoice 5664 5664 0 0.00 5 90639 906 0 0
sp_cancel_client_order 1834 1834 0 0.00 5 20015 316 0 0
sp_cancel_customer_reserve 558950 558950 0 0.00 1 49512 64 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 7165 94 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 14101 103 0 0
sp_cancel_supplier_invoice 6253 6247 6 0.09 3 28040 1074 0 6
sp_cancel_supplier_order 412 412 0 0.00 5 1273894 111408 0 0
sp_cancel_write_off 9932 9932 0 0.00 1 19821 119 0 0
sp_client_order 20206 20206 0 0.00 4 49780 410 0 0
sp_customer_reserve 454348 454348 0 0.00 0 67599 187 0 0
sp_fill_shopping_cart 31132 31132 0 0.00 1 24913 47 0 0
sp_fill_shopping_cart_clo_res 454346 454346 0 0.00 0 40543 17 0 0
sp_get_clo_for_invoice 4860 4860 0 0.00 0 40089 393 0 0
sp_kill_cost_storno 16668 16668 0 0.00 0 19528 59 0 0
sp_kill_qstorno_ret_qs2qd 1134491 1134491 0 0.00 0 49484 36 0 0
sp_kill_qty_storno 597864 597864 0 0.00 0 49507 76 0 0
sp_lock_selected_doc 20631 20631 0 0.00 0 9800 7 0 0
sp_make_cost_storno 27830 27830 0 0.00 0 22798 63 0 0
sp_multiply_rows_for_pdistr 27830 27830 0 0.00 0 22640 37 0 0
sp_multiply_rows_for_qdistr 20206 20206 0 0.00 0 49677 219 0 0
sp_pay_from_customer 7105 7105 0 0.00 4 22566 130 0 0
sp_pay_to_supplier 5900 5900 0 0.00 5 22823 159 0 0
sp_payment_common 13005 13005 0 0.00 1 22531 47 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 20007 280 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 34396 357 0 0
sp_qd_handle_on_reserve_upd_sts 9965 9965 0 0.00 0 16820 60 0 0
sp_reserve_write_off 9965 9965 0 0.00 7 35507 221 0 0
sp_supplier_invoice 5411 5411 0 0.00 6 96489 3391 0 0
sp_supplier_order 5519 5519 0 0.00 6 49657 1391 0 0
srv_aggregate_perf_data 123 123 0 0.00 2737 156830 13220 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1341 182980 46720 0 0
srv_find_qd_qs_mism 1051881 1051881 0 0.00 0 15463 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 75 95147 3405 0 0
srv_make_money_saldo 739 739 0 0.00 1 15400 472 0 0
srv_recalc_idx_stat 4 4 0 0.00 319313 388364 357091 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4863 4863 0 0.00 0 9023 140 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 13031 130 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 2352 13 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 686 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 4688 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 2943 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 910 17 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 29892 1479 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1076 4 0 0
t$perf-norm:sp_client_order 20206 20206 0 0.00 0 10966 16 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 1965 7 0 0
t$perf-norm:sp_pay_from_customer 7105 7105 0 0.00 0 7142 8 0 0
t$perf-norm:sp_pay_to_supplier 5900 5900 0 0.00 0 7793 8 0 0
t$perf-norm:sp_reserve_write_off 9965 9965 0 0.00 0 7952 8 0 0
t$perf-norm:sp_supplier_invoice 5412 5412 0 0.00 0 5530 18 0 0
t$perf-norm:sp_supplier_order 5519 5519 0 0.00 0 4815 16 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 1083 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 125 4 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 3032 20 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 1121 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 37 11 0 0
v_add_invoice_to_stock 9982 9718 264 2.64 0 11813 23 0 264
v_all_customers 36508 36508 0 0.00 0 17 1 0 0
v_all_suppliers 30548 30548 0 0.00 0 126 1 0 0
v_all_wares 40410 40410 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 3916 14 0 0
v_cancel_client_order 3668 3668 0 0.00 0 11845 10 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1878 6 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 2524 8 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2193 8 0 0
v_cancel_supplier_invoice 826 814 12 1.45 0 1538 19 0 12
v_cancel_supplier_order 824 824 0 0.00 0 3160 7 0 0
v_cancel_write_off 1930 1930 0 0.00 0 11915 24 0 0
v_max_id_clo_ord 5519 5519 0 0.00 0 4647 3 0 0
v_max_id_clo_res 979 979 0 0.00 0 2557 11 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 5506 7 0 0
v_max_non_paid_invoice 1553 1553 0 0.00 0 703 2 0 0
v_max_non_paid_realizn 3835 3835 0 0.00 0 2788 4 0 0
v_min_id_clo_ord 5519 5519 0 0.00 0 19299 33 0 0
v_min_id_clo_res 979 979 0 0.00 0 2078 7 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 7999 11 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 22513 45 0 0
v_min_non_paid_realizn 7105 7105 0 0.00 0 22323 34 0 0
v_our_firm 10442 10442 0 0.00 0 17 1 0 0
v_reserve_write_off 19930 19930 0 0.00 0 7653 7 0 0
x_lock_depdocs_on_canc_invoice 11504 11504 0 0.00 0 16257 107 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 21961 399 0 0
x_make_qstorno_1000_1200 5519 5519 0 0.00 2 36220 1282 0 0
x_make_qstorno_1200_2000 5411 5411 0 0.00 3 94480 3232 0 0
x_make_qstorno_2100_3300 454348 454348 0 0.00 0 67595 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-08-2021 13:39:20 to 06-08-2021 13:39:25.

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
11:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 31351 8192000000 9719531296 10015924224 795186464 8.848 100 83 166 2 823920464 8126352 20607648
11:09:52 20906 8192000000 9766883888 10075955200 836788592 9.268 100 81 162 0 869270992 13398784 19083616
11:11:17 16301 8192000000 9818485520 10140000256 872070800 9.621 100 85 169 0 925025200 33120432 19833968
11:12:43 9973 8192000000 9870001552 10206494720 929084096 10.186 100 78 156 1 989496384 43931184 16481104
11:13:55 6993 8192000000 9937289792 10282512384 970133216 10.588 100 88 175 2 1048738928 60379056 18226656
11:15:04 5004 8192000000 9970685808 10328252416 1007581696 10.952 100 88 172 1 1086140432 60993168 17565568
11:16:11 1674 8192000000 10016969808 10383159296 1030803616 11.176 100 86 171 1 1133481488 85668864 17009008
11:17:15 1771 8192000000 10043121520 10411741184 1064162400 11.496 100 92 184 1 1152446560 69060096 19224064
11:18:17 15457 8192000000 9984653504 10380767232 1065650000 11.511 100 29 57 0 1107960800 36680960 5629840
11:19:33 5214 8192000000 9989610560 10373066752 1075635056 11.606 100 38 77 2 1100132320 15482304 9014960
11:20:42 4583 8192000000 10002937344 10407837696 1088488080 11.728 100 38 75 0 1128560128 32366816 7705232
11:21:48 5944 8192000000 9995114656 10393681920 1086988864 11.714 100 27 51 1 1111797152 18739152 6069136
11:22:55 57951 8192000000 10014093536 10434772992 1093768368 11.778 100 36 72 0 1125082688 23771200 7543120
11:24:54 8797 8192000000 10032318112 10444361728 1101713712 11.854 100 25 50 0 1134649904 27787536 5148656
11:26:05 7712 8192000000 10013642560 10412613632 1094393856 11.784 99 34 66 0 1119286112 16643328 8248928
11:27:14 3492 8192000000 9985595360 10372923392 1063025120 11.485 98 55 110 3 1091168272 15500560 12642592
11:28:19 32361 8192000000 9957815648 10365423616 1002142464 10.899 94 28 56 2 1030491408 22677520 5671424
11:29:53 5554 8192000000 9960018032 10338013184 1010646560 10.982 99 48 95 2 1058540912 37655824 10238528
11:31:02 9032 8192000000 9928497024 10317942784 1013126128 11.006 100 47 94 0 1039060944 15303648 10631168
11:32:13 26017 8192000000 9929002256 10330079232 1009744032 10.973 98 37 74 0 1042018224 23981856 8292336
11:33:46 7315 8192000000 9959361712 10360918016 1046856816 11.331 100 35 68 2 1062611088 7821984 7932288
11:34:53 5438 8192000000 9988502144 10377461760 1054390352 11.403 100 35 69 0 1091314960 28833328 8091280
11:36:00 7125 8192000000 9964784912 10367582208 1068151456 11.534 100 44 90 2 1086227808 8708160 9368192
11:37:08 182980 8192000000 10075295264 10480574464 1098390928 11.822 100 38 74 1 1132698112 26448320 7858864
11:41:28 14375 8192000000 10126244560 10531934208 1123655888 12.062 100 45 88 0 1226984848 93913264 9415696
11:42:52 12286 8192000000 10127920112 10543661056 1137319936 12.190 100 38 76 1 1248127616 102214304 8593376
11:44:09 19279 8192000000 10182927312 10593226752 1146429600 12.276 100 55 110 1 1284841648 126063376 12348672
11:45:33 15985 8192000000 10207432544 10624372736 1158543264 12.390 100 53 107 2 1313605552 143039184 12023104
11:46:54 10514 8192000000 10226687488 10641256448 1170436560 12.501 100 64 127 2 1326047392 142444704 13166128
11:48:13 6724 8192000000 10294445568 10707554304 1167630592 12.475 100 64 128 1 1393784448 211950432 14203424
11:49:29 1362 8192000000 10344332016 10752946176 1186666032 12.652 100 82 164 1 1442518384 236983136 18869216
11:50:35 4172 8192000000 10372304928 10782523392 1184550224 12.633 99 83 165 2 1463146896 260125024 18471648
11:51:46 1341 8192000000 10353529264 10756567040 1161250160 12.415 99 87 176 1 1451898848 271313296 19335392
11:52:54 2819 8192000000 10356206160 10765017088 1157006480 12.375 100 85 166 0 1452531552 276458400 19066672
11:53:59 7434 8192000000 10342681312 10762182656 1125776288 12.082 97 41 82 3 1427864592 291375456 10712848
11:55:16 2413 8192000000 10434673168 10839502848 1145886848 12.271 100 73 146 0 1528259024 364802560 17569616
11:56:27 8769 8192000000 10410560992 10829606912 1130304736 12.124 99 54 107 0 1503475600 359236864 13934000
11:57:48 5603 8192000000 10397525968 10847211520 1140615168 12.221 100 44 88 0 1509760848 357416832 11728848
11:58:58 18885 8192000000 10427031568 10885013504 1126110064 12.085 99 38 74 1 1503787952 367951568 9726320
12:00:19 7277 8192000000 10376075600 10832912384 1124755968 12.072 100 43 83 1 1494232224 358260304 11215952
12:01:29 24019 8192000000 10275106640 10766479360 1095263776 11.793 98 25 50 3 1391802016 289421872 7116368
12:03:04 38788 8192000000 10055885376 10549972992 1107562848 11.909 99 11 22 1 1155541808 45994672 1984288
12:05:18 32219 8192000000 10091296080 10516537344 1113820640 11.969 99 42 84 4 1189991808 66382752 9788416
12:06:56 8599 8192000000 10110588864 10536681472 1108267296 11.916 99 43 87 2 1198829808 81441936 9120576
12:09:01 130756 8192000000 10116016720 10532589568 1118980768 12.017 98 39 77 4 1178164080 50657296 8526016
12:12:13 41444 8192000000 10138047568 10586673152 1144091808 12.254 100 25 50 0 1262419376 112466304 5861264
12:14:13 24676 8192000000 10105440608 10590146560 1126829424 12.091 99 22 45 1 1200854704 68862576 5162704
12:15:41 47605 8192000000 10098950064 10566926336 1148866032 12.299 100 14 28 0 1203147888 51000768 3281088
12:17:30 41166 8192000000 10157720192 10602041344 1154120720 12.348 100 15 30 0 1255911408 97976176 3814512
12:19:12 42462 8192000000 10164787872 10668171264 1155474256 12.361 100 10 20 1 1294714128 136590208 2649664
12:21:01 92285 8192000000 10125635712 10591641600 1130665264 12.128 98 21 42 0 1211992944 77022736 4304944
12:23:40 43862 8192000000 10100848752 10582667264 1144192352 12.255 99 13 24 2 1212796160 65922240 2681568
12:25:26 44109 8192000000 10121484528 10573508608 1147292576 12.284 99 26 52 0 1207968640 55166544 5509520
12:27:15 49870 8192000000 10129322032 10608590848 1155677024 12.363 100 28 56 0 1256089504 94468736 5943744
12:29:05 25731 8192000000 10092172912 10548391936 1134762336 12.166 98 16 32 0 1178510560 40534896 3213328
12:30:32 93891 8192000000 10113007328 10549059584 1092135248 11.763 94 31 61 0 1140505968 42384656 5986064
12:33:06 47977 8192000000 10092757984 10555494400 1132162512 12.142 99 27 54 0 1196465872 59470016 4833344
12:35:21 94063 8192000000 10046456304 10496704512 1103041088 11.866 96 10 20 0 1134233360 29151920 2040352
12:37:55 39362 8192000000 10065388944 10517032960 1107546352 11.909 97 12 23 0 1154435216 44694816 2194048
12:40:38 55588 8192000000 10081573168 10526142464 1093503200 11.776 96 13 26 1 1153160400 57239120 2418080
12:42:49 50830 8192000000 10077109632 10576224256 1099361184 11.832 97 21 42 0 1186471824 82315456 4795184
12:44:46 40253 8192000000 10125823616 10599538688 1116827056 11.997 98 31 61 0 1206237680 83400272 6010352
12:46:26 29659 8192000000 10121432528 10568241152 1134288816 12.162 100 47 94 1 1207210672 62950624 9971232
12:47:56 50914 8192000000 10121821536 10631872512 1111424608 11.946 97 20 40 1 1221141792 105612688 4104496
12:50:07 20806 8192000000 10078712784 10502389760 1103338144 11.869 97 44 88 0 1140888528 28494592 9055792
12:51:33 72856 8192000000 10090632896 10631008256 1062211552 11.478 95 11 22 0 1134603216 70572176 1819488
12:53:46 74888 8192000000 10043876128 10469179392 1107219312 11.906 99 29 58 1 1164431776 50896912 6315552
12:56:01 69963 8192000000 10063073472 10507698176 1105718512 11.892 98 14 28 0 1150375616 41793328 2863776
12:58:14 70507 8192000000 10093984432 10545070080 1115725216 11.987 99 25 54 1 1176166640 55331712 5109712
13:00:41 45005 8192000000 10073626496 10518417408 1121052752 12.037 99 18 35 1 1171656368 47099344 3504272
13:02:27 85014 8192000000 10063004240 10530222080 1076544048 11.615 95 18 37 1 1126688176 46267024 3877104
13:04:52 50055 8192000000 10117095424 10579791872 1123977984 12.065 99 23 46 0 1206326640 77605552 4743104
13:07:03 79516 8192000000 10090861680 10568192000 1130479920 12.126 99 24 48 0 1183454064 48311568 4662576
13:09:23 64111 8192000000 10102255664 10612977664 1136744640 12.185 99 14 28 2 1226279968 86736688 2798640
13:11:52 61334 8192000000 10089130352 10578124800 1124794944 12.072 98 7 14 0 1154102256 28350048 957264
13:13:59 103287 8192000000 10058907552 10521849856 1129573296 12.117 99 24 48 1 1173422416 37854976 5994144
13:16:46 66418 8192000000 10087613568 10552340480 1113877760 11.969 97 10 20 0 1163112080 47021808 2212512
13:19:21 66265 8192000000 10104216976 10557857792 1151205328 12.321 100 11 21 0 1191293152 37537296 2550528
13:21:30 79076 8192000000 10100597056 10578554880 1141875408 12.233 99 15 28 1 1214950848 69904880 3170560
13:23:49 22768 8192000000 10093989840 10543112192 1149362464 12.304 100 16 32 1 1190490432 37902496 3225472
13:25:24 71188 8192000000 10106203984 10582048768 1131883200 12.139 98 27 54 1 1210444832 73078592 5483040
13:27:37 43746 8192000000 10086495248 10542645248 1137551744 12.192 99 12 23 0 1173440416 33611872 2276800
13:29:21 82267 8192000000 10080495360 10530566144 1085950608 11.704 95 20 40 1 1128002080 38110896 3940576
13:31:46 52289 8192000000 10068737664 10527346688 1082737504 11.674 96 18 36 0 1147722720 61334368 3650848
13:33:38 51420 8192000000 10044367376 10470973440 1069893120 11.551 96 26 52 0 1118862336 43221216 5748000
13:35:33 98125 8192000000 9699427232 10086326272 782843568 8.722 71 20 40 0 829321184 42759344 3718272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-08-2021 13:39:26 to 06-08-2021 13:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-08-2021 13:39:42 to 06-08-2021 13:39:44.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240588
MON$OLDEST_ACTIVE               240589
MON$OLDEST_SNAPSHOT             240589
MON$NEXT_TRANSACTION            240589
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2059168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             813
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Aug  6 13:37:20 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

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