Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.09.2021 10:37
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 15903024 kB
MemAvailable 30833356 kB
Buffers 0 kB
Cached 15775040 kB
SwapCached 412 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-09-14 10:37:34
DTS_END                         2021-09-14 10:37:06.6700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53466
TRN_ID                          240588
ATT_ID                          259
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: 9369.14 23260 1124297
customer order: creation 176.04 398 21125
customer order: refuse 15.95 358 1914
order to supplier: creation 46.33 1355 5560
order to supplier: removal 3.46 110879 415
invoice (draft): creation 47.36 3190 5683
invoice (draft): removal 52.47 954 6296
invoice accept: apply 42.60 18363 5112
invoice accept: cancel 47.72 795 5726
customer reserve: creation 3920.30 182 470436
customer reserve: removal 4707.46 64 564895
realization accept: apply 85.47 184 10256
realization accept: cancel 91.83 115 11020
payment from customer: creation 60.93 112 7312
payment from customer: removal 5.94 73 713
payment to supplier: creation 50.67 130 6080
payment to supplier: removal 3.52 84 422
service: total inventory turnovers 3.92 3632 470
service: total monetary turnovers 6.24 449 749
service: aggregate perf. data 0.91 16174 109
service: refresh index statistics 0.03 307700 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-09-2021 10:38:01 to 14-09-2021 10:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9496
TEST_TIME 2 7404
TEST_TIME 3 7079
TEST_TIME 4 6763
TEST_TIME 5 6897
TEST_TIME 6 6167
TEST_TIME 7 6043
TEST_TIME 8 6063
TEST_TIME 9 5970
TEST_TIME 10 6751
TEST_TIME 11 6727
TEST_TIME 12 6891
TEST_TIME 13 6961
TEST_TIME 14 6884
TEST_TIME 15 7218
TEST_TIME 16 7442
TEST_TIME 17 8086
TEST_TIME 18 7980
TEST_TIME 19 8159
TEST_TIME 20 8398
TEST_TIME 21 8514
TEST_TIME 22 8943
TEST_TIME 23 9396
TEST_TIME 24 10697
TEST_TIME 25 10768
TEST_TIME 26 11227
TEST_TIME 27 11229
TEST_TIME 28 11009
TEST_TIME 29 10831
TEST_TIME 30 10904
TEST_TIME 31 10897
TEST_TIME 32 10834
TEST_TIME 33 10869
TEST_TIME 34 10687
TEST_TIME 35 10503
TEST_TIME 36 10354
TEST_TIME 37 10409
TEST_TIME 38 10187
TEST_TIME 39 10190
TEST_TIME 40 10045
TEST_TIME 41 10148
TEST_TIME 42 10177
TEST_TIME 43 10064
TEST_TIME 44 9958
TEST_TIME 45 10015
TEST_TIME 46 9855
TEST_TIME 47 9800
TEST_TIME 48 9714
TEST_TIME 49 9725
TEST_TIME 50 9602
TEST_TIME 51 9648
TEST_TIME 52 9602
TEST_TIME 53 9541
TEST_TIME 54 9581
TEST_TIME 55 9650
TEST_TIME 56 9675
TEST_TIME 57 9815
TEST_TIME 58 9936
TEST_TIME 59 9973
TEST_TIME 60 9953
TEST_TIME 61 10011
TEST_TIME 62 9908
TEST_TIME 63 9872
TEST_TIME 64 9743
TEST_TIME 65 9808
TEST_TIME 66 9720
TEST_TIME 67 9662
TEST_TIME 68 9659
TEST_TIME 69 9670
TEST_TIME 70 9691
TEST_TIME 71 9685
TEST_TIME 72 9667
TEST_TIME 73 9622
TEST_TIME 74 9658
TEST_TIME 75 9633
TEST_TIME 76 9694
TEST_TIME 77 9681
TEST_TIME 78 9698
TEST_TIME 79 9814
TEST_TIME 80 9699
TEST_TIME 81 9639
TEST_TIME 82 9578
TEST_TIME 83 9560
TEST_TIME 84 9571
TEST_TIME 85 9513
TEST_TIME 86 9476
TEST_TIME 87 9408
TEST_TIME 88 9389
TEST_TIME 89 9396
TEST_TIME 90 9357
TEST_TIME 91 9400
TEST_TIME 92 9451
TEST_TIME 93 9432
TEST_TIME 94 9461
TEST_TIME 95 9443
TEST_TIME 96 9523
TEST_TIME 97 9489
TEST_TIME 98 9546
TEST_TIME 99 9542
TEST_TIME 100 9523
TEST_TIME 101 9585
TEST_TIME 102 9528
TEST_TIME 103 9446
TEST_TIME 104 9452
TEST_TIME 105 9400
TEST_TIME 106 9365
TEST_TIME 107 9319
TEST_TIME 108 9309
TEST_TIME 109 9311
TEST_TIME 110 9305
TEST_TIME 111 9290
TEST_TIME 112 9289
TEST_TIME 113 9257
TEST_TIME 114 9264
TEST_TIME 115 9324
TEST_TIME 116 9342
TEST_TIME 117 9367
TEST_TIME 118 9413
TEST_TIME 119 9444
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-09-2021 10:38:05 to 14-09-2021 10:38:08.

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 1559231 1559231 0 0.00 0 21015 7 0 0
doc_list_biud 1559230 1559230 0 0.00 0 54451 30 0 0
sp_add_invoice_to_stock 5229 5112 117 2.23 42 224773 18364 0 117
sp_cancel_adding_invoice 5726 5726 0 0.00 5 69511 796 0 0
sp_cancel_client_order 1914 1914 0 0.00 4 17081 359 0 0
sp_cancel_customer_reserve 564895 564895 0 0.00 1 25669 64 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 4120 73 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 9985 85 0 0
sp_cancel_supplier_invoice 6305 6296 9 0.14 2 54482 954 0 9
sp_cancel_supplier_order 415 415 0 0.00 5 1079531 110880 0 0
sp_cancel_write_off 11020 11020 0 0.00 1 12270 116 0 0
sp_client_order 21125 21125 0 0.00 4 22506 399 0 0
sp_customer_reserve 470436 470436 0 0.00 0 98630 182 0 0
sp_fill_shopping_cart 32366 32366 0 0.00 1 17480 43 0 0
sp_fill_shopping_cart_clo_res 470428 470428 0 0.00 0 14366 17 0 0
sp_get_clo_for_invoice 5104 5104 0 0.00 0 48661 390 0 0
sp_kill_cost_storno 17881 17881 0 0.00 0 5999 54 0 0
sp_kill_qstorno_ret_qs2qd 1147521 1147521 0 0.00 0 54449 35 0 0
sp_kill_qty_storno 605626 605626 0 0.00 0 54450 75 0 0
sp_lock_selected_doc 21517 21517 0 0.00 0 10351 7 0 0
sp_make_cost_storno 28752 28752 0 0.00 0 18806 57 0 0
sp_multiply_rows_for_pdistr 28752 28752 0 0.00 0 8888 33 0 0
sp_multiply_rows_for_qdistr 21125 21125 0 0.00 0 16635 217 0 0
sp_pay_from_customer 7312 7312 0 0.00 4 18313 112 0 0
sp_pay_to_supplier 6080 6080 0 0.00 5 15858 131 0 0
sp_payment_common 13391 13391 0 0.00 1 15931 21 0 0
sp_qd_handle_on_cancel_clo 1914 1914 0 0.00 0 17075 315 0 0
sp_qd_handle_on_invoice_upd_sts 10830 10830 0 0.00 0 43657 327 0 0
sp_qd_handle_on_reserve_upd_sts 10256 10256 0 0.00 0 16476 59 0 0
sp_reserve_write_off 10256 10256 0 0.00 7 21877 185 0 0
sp_supplier_invoice 5683 5683 0 0.00 6 85945 3190 0 0
sp_supplier_order 5560 5560 0 0.00 6 34677 1356 0 0
srv_aggregate_perf_data 109 109 0 0.00 2740 381264 16174 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2982 103258 43199 0 0
srv_find_qd_qs_mism 1075166 1075166 0 0.00 0 21015 6 0 0
srv_make_invnt_saldo 470 470 0 0.00 57 116977 3633 0 0
srv_make_money_saldo 749 749 0 0.00 1 20562 449 0 0
srv_recalc_idx_stat 4 4 0 0.00 125317 562627 307701 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5112 5112 0 0.00 0 20428 152 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 17275 125 0 0
t$perf-norm:sp_cancel_client_order 1914 1914 0 0.00 0 1894 9 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1151 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1910 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 703 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 457 457 0 0.00 0 920 11 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 18264 1627 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 4063 12 0 0
t$perf-norm:sp_client_order 21125 21125 0 0.00 0 15175 16 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 1982 5 0 0
t$perf-norm:sp_pay_from_customer 7312 7312 0 0.00 0 15576 14 0 0
t$perf-norm:sp_pay_to_supplier 6080 6080 0 0.00 0 18038 12 0 0
t$perf-norm:sp_reserve_write_off 10256 10256 0 0.00 0 18695 13 0 0
t$perf-norm:sp_supplier_invoice 5683 5683 0 0.00 0 15868 20 0 0
t$perf-norm:sp_supplier_order 5560 5560 0 0.00 0 5911 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 3784 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 206 6 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1139 9 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 398 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10438 10204 234 2.24 0 7282 20 0 234
v_all_customers 39028 39028 0 0.00 0 169 1 0 0
v_all_suppliers 31336 31336 0 0.00 0 15 1 0 0
v_all_wares 42250 42250 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 2256 17 0 0
v_cancel_client_order 3828 3828 0 0.00 0 7130 12 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 2727 6 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 292 3 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2266 12 0 0
v_cancel_supplier_invoice 932 914 18 1.93 0 1320 14 0 18
v_cancel_supplier_order 830 830 0 0.00 0 2263 10 0 0
v_cancel_write_off 2132 2132 0 0.00 0 4197 18 0 0
v_max_id_clo_ord 5560 5560 0 0.00 0 395 1 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 1522 11 0 0
v_max_id_ord_sup 5681 5681 0 0.00 0 4515 6 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 232 2 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 7445 8 0 0
v_min_id_clo_ord 5560 5560 0 0.00 0 4801 7 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 9379 14 0 0
v_min_id_ord_sup 5681 5681 0 0.00 0 8659 6 0 0
v_min_non_paid_invoice 6079 6079 0 0.00 0 8755 14 0 0
v_min_non_paid_realizn 7312 7312 0 0.00 0 7016 12 0 0
v_our_firm 10452 10452 0 0.00 0 16 1 0 0
v_reserve_write_off 20512 20512 0 0.00 0 6225 8 0 0
x_lock_depdocs_on_canc_invoice 11565 11565 0 0.00 0 12336 93 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 6713 288 0 0
x_make_qstorno_1000_1200 5560 5560 0 0.00 2 34653 1281 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 3 85937 3024 0 0
x_make_qstorno_2100_3300 470436 470436 0 0.00 0 98626 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 10:38:09 to 14-09-2021 10:38:13.

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
08:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 29636 8192000000 9713335840 10011029504 790114496 8.796 100 82 164 1 818395536 7374256 20906784
08:09:49 20941 8192000000 9762800896 10069794816 835661872 9.256 100 87 174 0 868981488 12310592 21009024
08:11:19 20773 8192000000 9810933152 10129981440 871289664 9.613 100 87 173 0 915338192 23984544 20063984
08:12:44 11805 8192000000 9868650496 10205192192 920542032 10.101 100 77 154 0 986093376 48815856 16735488
08:13:58 7075 8192000000 9925598464 10271604736 960043776 10.489 100 76 150 2 1029237872 53753056 15441040
08:15:07 4843 8192000000 9968976528 10324361216 990586880 10.787 100 76 152 1 1081473424 75370960 15515584
08:16:14 4770 8192000000 10003791072 10364612608 1015281456 11.026 100 72 144 1 1111904176 82153648 14469072
08:17:20 2502 8192000000 10009136912 10374770688 1048744816 11.349 100 91 183 1 1119828000 52609200 18473984
08:18:25 1941 8192000000 10034505408 10403389440 1064033584 11.495 100 92 182 1 1149129232 65655488 19440160
08:19:28 4365 8192000000 10003476608 10394439680 1069297760 11.545 100 35 70 2 1122175424 45972784 6904880
08:20:34 6632 8192000000 9996557472 10382958592 1073707600 11.587 100 52 103 2 1110098320 25248144 11142576
08:21:43 20112 8192000000 9993644768 10413518848 1081647632 11.663 100 37 73 2 1123004976 32542432 8814912
08:23:04 16295 8192000000 10000524000 10409521152 1084390640 11.689 100 24 48 3 1115020656 25661872 4968144
08:24:23 8218 8192000000 10032827984 10436300800 1094233824 11.783 100 39 78 0 1130692864 27981392 8477648
08:25:35 9504 8192000000 10010052512 10419277824 1100235072 11.840 100 36 71 1 1128734992 20340080 8159840
08:26:45 65133 8192000000 10004838896 10413678592 1081712880 11.664 99 26 51 1 1117863120 31227984 4922256
08:28:55 4307 8192000000 9974241648 10361126912 1058629200 11.443 99 36 70 4 1085880768 19265376 7986192
08:30:01 6233 8192000000 9936003840 10318295040 1001083168 10.889 96 32 62 2 1031493648 23679632 6730848
08:31:09 77001 8192000000 9932036384 10329616384 997955376 10.859 96 30 60 0 1027830976 23697504 6178096
08:33:27 8502 8192000000 9976643296 10369171456 1037414096 11.240 99 41 82 1 1060031504 13940048 8677360
08:34:37 6508 8192000000 9979833968 10373423104 1066720816 11.521 100 34 68 0 1095128224 19943968 8463440
08:35:46 63222 8192000000 9972725952 10390401024 1073662912 11.587 100 32 64 1 1096078208 14962704 7452592
08:37:51 13078 8192000000 10043768032 10443948032 1105887120 11.893 100 56 110 2 1157528480 40882544 10758816
08:39:09 5183 8192000000 10081302704 10476810240 1124411888 12.069 100 58 117 0 1185558640 48332416 12814336
08:40:18 23538 8192000000 10094460304 10505957376 1131283968 12.133 100 37 74 1 1204468032 65343504 7840560
08:41:48 16983 8192000000 10124004224 10558160896 1151775136 12.326 100 38 76 1 1240250128 79681520 8793472
08:43:09 21095 8192000000 10169391456 10582204416 1163124256 12.433 100 43 86 1 1277312256 104139680 10048320
08:44:46 7028 8192000000 10239224368 10647314432 1173953584 12.534 100 57 114 1 1331531088 144828832 12748672
08:45:57 10671 8192000000 10285384064 10694705152 1168528704 12.483 99 68 134 1 1374988352 191255024 15204624
08:47:15 2981 8192000000 10288730384 10723590144 1187101408 12.656 100 61 122 2 1391819600 191277744 13440448
08:48:28 13366 8192000000 10329618240 10754777088 1197783712 12.756 100 63 126 0 1424973088 212628080 14561296
08:49:50 8311 8192000000 10368835056 10782007296 1209762432 12.867 100 76 151 0 1468629808 241666112 17201264
08:51:02 21535 8192000000 10407822816 10829008896 1200287296 12.779 100 63 126 0 1499661728 284921456 14452976
08:52:39 6685 8192000000 10398546000 10823172096 1192027440 12.702 99 69 137 2 1498875744 290483760 16364544
08:53:50 5289 8192000000 10412567136 10845405184 1182956480 12.618 99 72 144 0 1498698624 297879232 17862912
08:54:59 9622 8192000000 10379690112 10816090112 1167195840 12.471 99 32 64 1 1481303616 306112480 7995296
08:56:18 8525 8192000000 10422202800 10854699008 1166723760 12.466 100 42 84 0 1520635200 343647504 10263936
08:57:31 8493 8192000000 10437457296 10880376832 1165114704 12.451 100 45 87 0 1538499344 362361728 11022912
08:58:41 21528 8192000000 10430763904 10863575040 1123368544 12.059 98 35 70 0 1505691376 373181680 9141152
09:00:05 13300 8192000000 10369629904 10824237056 1092420272 11.766 96 32 64 0 1458907296 357914368 8572656
09:01:20 36855 8192000000 10167537344 10691829760 1113169392 11.962 99 23 45 2 1425357344 305923040 6264912
09:03:27 42152 8192000000 10070258336 10564104192 1115185920 11.981 100 8 16 1 1175935312 59291856 1457536
09:05:45 23199 8192000000 10052206160 10479296512 1129988176 12.121 100 31 60 2 1155600448 18579872 7032400
09:07:08 68866 8192000000 10081523536 10553421824 1107062384 11.905 99 7 14 0 1151530928 43420352 1048192
09:09:29 93890 8192000000 10044579808 10485084160 1036462528 11.231 92 8 15 1 1064729120 26888624 1377968
09:12:04 103258 8192000000 10084954352 10497335296 1108747936 11.921 98 46 92 3 1164944592 44831488 11365168
09:14:50 77914 8192000000 10111167520 10531549184 1121354384 12.040 99 29 58 1 1171747808 43787792 6605632
09:17:15 10241 8192000000 10124009168 10543448064 1134009376 12.159 100 42 86 0 1205878608 62883136 8986096
09:18:29 40196 8192000000 10102463648 10583887872 1138314640 12.200 100 16 32 1 1212970224 71337920 3317664
09:20:13 44915 8192000000 10085522048 10532941824 1106264608 11.897 97 19 38 0 1165549536 54966928 4318000
09:22:46 76599 8192000000 10082057328 10534924288 1128029872 12.103 99 15 29 0 1176456832 45535152 2891808
09:25:13 12173 8192000000 10065648656 10514493440 1126232096 12.086 99 25 51 2 1159313104 27897168 5183840
09:26:26 78636 8192000000 10097344576 10592079872 1136333344 12.181 100 13 25 0 1186491536 47645744 2512448
09:28:45 48092 8192000000 10113168544 10577985536 1152186464 12.330 100 16 32 0 1224624976 68910672 3527840
09:30:35 62200 8192000000 10179362288 10644910080 1158193920 12.386 100 17 34 0 1286150784 124347760 3609104
09:32:39 48380 8192000000 10160043984 10642419712 1113251648 11.963 96 23 46 1 1234181536 115577840 5352048
09:34:27 61827 8192000000 10094972080 10570752000 1107027520 11.904 97 19 38 1 1199985568 88707264 4250784
09:36:29 36117 8192000000 10078796592 10541199360 1118868944 12.016 99 21 42 1 1160079408 37501648 3708816
09:38:09 39358 8192000000 10109503264 10559332352 1108484672 11.918 97 33 66 0 1196723008 82244032 5994304
09:39:52 75278 8192000000 10123007840 10599202816 1141857456 12.233 100 32 63 2 1214668000 66461776 6348768
09:42:16 47036 8192000000 10040606800 10494812160 1098435696 11.823 98 23 46 1 1138438416 35610320 4392400
09:44:25 84971 8192000000 10087911088 10583797760 1122855872 12.054 99 17 34 0 1212783904 86332864 3595168
09:46:50 48839 8192000000 10079410416 10553991168 1093402752 11.775 96 26 52 1 1166578576 67147040 6028784
09:48:40 54581 8192000000 10062606160 10498060288 1090711248 11.749 97 22 44 1 1141859248 46778256 4369744
09:50:36 31353 8192000000 10094362080 10519261184 1087019120 11.714 98 45 90 1 1169777168 73804624 8953424
09:52:08 13143 8192000000 10093515552 10516246528 1124335376 12.068 100 62 124 1 1184688272 47687696 12665200
09:53:21 57206 8192000000 10085172416 10527744000 1091695104 11.759 97 22 44 0 1173313360 77189968 4428288
09:55:19 81711 8192000000 10126817632 10603474944 1118805776 12.016 99 16 32 1 1186089440 63609488 3674176
09:58:06 52953 8192000000 10032943088 10469797888 1088763872 11.731 97 17 34 0 1127746032 35500688 3481472
10:00:00 19226 8192000000 10066691536 10506309632 1100207664 11.840 99 40 81 0 1161716352 53166848 8341840
10:01:20 53200 8192000000 10041956256 10544226304 1117192224 12.000 100 12 22 2 1147056128 27768864 2095040
10:03:20 51115 8192000000 10043022240 10493571072 1094914416 11.789 98 11 23 0 1121420000 23985904 2519680
10:05:12 90469 8192000000 10068450880 10571620352 1098195600 11.821 99 11 21 0 1149670784 49343760 2131424
10:08:29 57195 8192000000 10108482544 10564243456 1100650464 11.844 97 21 42 1 1180152224 75376272 4125488
10:10:30 64935 8192000000 10110281056 10576752640 1127627360 12.099 99 24 49 1 1201885312 69659584 4598368
10:12:36 77492 8192000000 10083548368 10540089344 1102777984 11.864 97 15 30 1 1188487184 82679440 3029760
10:14:54 56952 8192000000 10127007408 10577252352 1141548064 12.230 100 29 58 0 1221766400 74047296 6171040
10:16:51 18848 8192000000 10074254784 10507968512 1084026352 11.686 96 45 90 0 1135688752 43101808 8560592
10:18:10 61205 8192000000 10080903584 10538053632 1111414000 11.946 98 23 46 1 1173421104 57502992 4504112
10:20:12 59485 8192000000 10076731184 10516090880 1123336368 12.058 99 48 96 0 1174893504 41850576 9706560
10:22:11 61474 8192000000 10080575968 10546364416 1134862480 12.167 100 11 22 0 1191572896 54184608 2525808
10:24:15 70935 8192000000 10080448880 10559881216 1107476720 11.909 98 18 36 0 1183029728 71877872 3675136
10:26:40 78498 8192000000 10071111776 10551230464 1091356176 11.756 97 23 46 0 1143200480 46900096 4944208
10:29:04 47122 8192000000 10114669328 10579054592 1112776224 11.959 98 22 44 0 1190120784 72869168 4475392
10:30:54 39170 8192000000 10153436192 10589986816 1088119120 11.725 95 28 56 1 1191801968 97103472 6579376
10:32:41 66602 8192000000 10107216288 10594992128 1134405168 12.163 100 16 32 0 1226450512 88306528 3738816
10:35:15 64712 8192000000 10109576048 10596970496 1137502816 12.192 100 17 34 1 1182209792 41455200 3251776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-09-2021 10:38:15 to 14-09-2021 10:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-09-2021 10:38:31 to 14-09-2021 10:38:33.

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          252374
MON$OLDEST_ACTIVE               252375
MON$OLDEST_SNAPSHOT             252375
MON$NEXT_TRANSACTION            252375
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                       2055072
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             807
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

14.09.21 10:38:34. 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-09-14 10:38:36 No severe PSQL-related problems occured
14.09.21 10:38:37. End of report.