Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2021 07:27
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 16907788 kB
MemAvailable 31413068 kB
Buffers 0 kB
Cached 14729132 kB
SwapCached 60 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-06-11 07:27:00
DTS_END                         2021-06-11 07:27:00.2260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53880
TRN_ID                          225723
ATT_ID                          656
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: 8706.73 24127 1044808
customer order: creation 160.82 402 19299
customer order: refuse 14.13 326 1695
order to supplier: creation 42.05 1410 5046
order to supplier: removal 3.46 125353 415
invoice (draft): creation 42.05 3681 5046
invoice (draft): removal 50.13 1009 6016
invoice accept: apply 39.32 19993 4718
invoice accept: cancel 45.38 985 5445
customer reserve: creation 3641.35 195 436962
customer reserve: removal 4390.54 79 526865
realization accept: apply 78.32 187 9398
realization accept: cancel 78.01 129 9361
payment from customer: creation 55.80 100 6696
payment from customer: removal 5.65 74 678
payment to supplier: creation 45.88 123 5506
payment to supplier: removal 3.35 81 402
service: total inventory turnovers 3.72 3827 446
service: total monetary turnovers 5.86 566 703
service: aggregate perf. data 0.89 14095 107
service: refresh index statistics 0.03 309917 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-06-2021 07:27:42 to 11-06-2021 07:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7135
TEST_TIME 2 7326
TEST_TIME 3 6883
TEST_TIME 4 6635
TEST_TIME 5 5932
TEST_TIME 6 5991
TEST_TIME 7 6099
TEST_TIME 8 6087
TEST_TIME 9 6224
TEST_TIME 10 6260
TEST_TIME 11 6680
TEST_TIME 12 6878
TEST_TIME 13 7317
TEST_TIME 14 7297
TEST_TIME 15 7149
TEST_TIME 16 7507
TEST_TIME 17 7630
TEST_TIME 18 7586
TEST_TIME 19 7934
TEST_TIME 20 8389
TEST_TIME 21 8524
TEST_TIME 22 8450
TEST_TIME 23 8590
TEST_TIME 24 9291
TEST_TIME 25 9424
TEST_TIME 26 10062
TEST_TIME 27 11690
TEST_TIME 28 11468
TEST_TIME 29 11350
TEST_TIME 30 11121
TEST_TIME 31 10842
TEST_TIME 32 10653
TEST_TIME 33 10572
TEST_TIME 34 10412
TEST_TIME 35 10205
TEST_TIME 36 10022
TEST_TIME 37 9932
TEST_TIME 38 10015
TEST_TIME 39 9817
TEST_TIME 40 9740
TEST_TIME 41 9574
TEST_TIME 42 9452
TEST_TIME 43 9476
TEST_TIME 44 9604
TEST_TIME 45 9533
TEST_TIME 46 9412
TEST_TIME 47 9285
TEST_TIME 48 9236
TEST_TIME 49 9220
TEST_TIME 50 9132
TEST_TIME 51 9052
TEST_TIME 52 8976
TEST_TIME 53 8992
TEST_TIME 54 9052
TEST_TIME 55 8990
TEST_TIME 56 9071
TEST_TIME 57 9102
TEST_TIME 58 9103
TEST_TIME 59 9179
TEST_TIME 60 9331
TEST_TIME 61 9339
TEST_TIME 62 9330
TEST_TIME 63 9359
TEST_TIME 64 9352
TEST_TIME 65 9213
TEST_TIME 66 9163
TEST_TIME 67 9116
TEST_TIME 68 9132
TEST_TIME 69 9154
TEST_TIME 70 9090
TEST_TIME 71 9043
TEST_TIME 72 8996
TEST_TIME 73 9165
TEST_TIME 74 9200
TEST_TIME 75 9158
TEST_TIME 76 9110
TEST_TIME 77 9111
TEST_TIME 78 9077
TEST_TIME 79 9189
TEST_TIME 80 9136
TEST_TIME 81 9066
TEST_TIME 82 9008
TEST_TIME 83 8975
TEST_TIME 84 8913
TEST_TIME 85 8848
TEST_TIME 86 8812
TEST_TIME 87 8779
TEST_TIME 88 8804
TEST_TIME 89 8749
TEST_TIME 90 8794
TEST_TIME 91 8791
TEST_TIME 92 8828
TEST_TIME 93 8788
TEST_TIME 94 8849
TEST_TIME 95 8872
TEST_TIME 96 8883
TEST_TIME 97 8938
TEST_TIME 98 8923
TEST_TIME 99 8930
TEST_TIME 100 8932
TEST_TIME 101 8954
TEST_TIME 102 8933
TEST_TIME 103 8855
TEST_TIME 104 8814
TEST_TIME 105 8761
TEST_TIME 106 8714
TEST_TIME 107 8721
TEST_TIME 108 8704
TEST_TIME 109 8681
TEST_TIME 110 8688
TEST_TIME 111 8696
TEST_TIME 112 8687
TEST_TIME 113 8670
TEST_TIME 114 8655
TEST_TIME 115 8727
TEST_TIME 116 8717
TEST_TIME 117 8759
TEST_TIME 118 8748
TEST_TIME 119 8789
TEST_TIME 120 8715

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-06-2021 07:27:45 to 11-06-2021 07:27:48.

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 1448035 1448035 0 0.00 0 17829 7 0 0
doc_list_biud 1448035 1448035 0 0.00 0 34394 36 0 0
sp_add_invoice_to_stock 4861 4718 143 2.94 40 209920 19994 0 143
sp_cancel_adding_invoice 5445 5445 0 0.00 5 146479 986 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 8615 327 0 0
sp_cancel_customer_reserve 526865 526865 0 0.00 1 34412 79 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 6992 74 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 4686 81 0 0
sp_cancel_supplier_invoice 6028 6016 12 0.19 1 25522 1010 0 12
sp_cancel_supplier_order 415 415 0 0.00 7 1184133 125354 0 0
sp_cancel_write_off 9361 9361 0 0.00 1 14863 130 0 0
sp_client_order 19299 19299 0 0.00 4 17790 402 0 0
sp_customer_reserve 436962 436962 0 0.00 0 64427 196 0 0
sp_fill_shopping_cart 29367 29367 0 0.00 1 17108 48 0 0
sp_fill_shopping_cart_clo_res 436953 436953 0 0.00 0 19654 20 0 0
sp_get_clo_for_invoice 4698 4698 0 0.00 0 39071 466 0 0
sp_kill_cost_storno 15886 15886 0 0.00 0 7626 64 0 0
sp_kill_qstorno_ret_qs2qd 1069522 1069522 0 0.00 0 25499 43 0 0
sp_kill_qty_storno 563892 563892 0 0.00 0 34393 90 0 0
sp_lock_selected_doc 19609 19609 0 0.00 0 8048 4 0 0
sp_make_cost_storno 26297 26297 0 0.00 0 17819 59 0 0
sp_multiply_rows_for_pdistr 26297 26297 0 0.00 0 7427 29 0 0
sp_multiply_rows_for_qdistr 19299 19299 0 0.00 0 17781 226 0 0
sp_pay_from_customer 6696 6696 0 0.00 4 11238 100 0 0
sp_pay_to_supplier 5506 5506 0 0.00 5 15747 124 0 0
sp_payment_common 12202 12202 0 0.00 1 6882 18 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 8607 303 0 0
sp_qd_handle_on_invoice_upd_sts 10142 10142 0 0.00 0 15424 373 0 0
sp_qd_handle_on_reserve_upd_sts 9398 9398 0 0.00 0 14933 63 0 0
sp_reserve_write_off 9398 9398 0 0.00 7 15219 187 0 0
sp_supplier_invoice 5046 5046 0 0.00 6 119021 3682 0 0
sp_supplier_order 5046 5046 0 0.00 6 34904 1411 0 0
srv_aggregate_perf_data 107 107 0 0.00 2491 211437 14095 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1637 122736 45253 0 0
srv_find_qd_qs_mism 999336 999336 0 0.00 0 10364 6 0 0
srv_make_invnt_saldo 446 446 0 0.00 46 115871 3827 0 0
srv_make_money_saldo 703 703 0 0.00 1 16057 566 0 0
srv_recalc_idx_stat 4 4 0 0.00 140334 596562 309918 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4718 4718 0 0.00 0 12868 158 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 6468 80 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 1272 10 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 956 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 2118 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 3955 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1272 13 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 18845 1524 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 865 4 0 0
t$perf-norm:sp_client_order 19299 19299 0 0.00 0 8386 20 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 2038 6 0 0
t$perf-norm:sp_pay_from_customer 6696 6696 0 0.00 0 5081 7 0 0
t$perf-norm:sp_pay_to_supplier 5506 5506 0 0.00 0 12304 13 0 0
t$perf-norm:sp_reserve_write_off 9398 9398 0 0.00 0 5658 11 0 0
t$perf-norm:sp_supplier_invoice 5046 5046 0 0.00 0 9173 20 0 0
t$perf-norm:sp_supplier_order 5046 5046 0 0.00 0 6673 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 246 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 889 18 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 4064 16 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 5593 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 53 15 0 0
v_add_invoice_to_stock 9678 9392 286 2.95 0 6454 20 0 286
v_all_customers 35272 35272 0 0.00 0 135 1 0 0
v_all_suppliers 28160 28160 0 0.00 0 52 1 0 0
v_all_wares 38598 38598 0 0.00 0 295 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1320 10 0 0
v_cancel_client_order 3390 3390 0 0.00 0 2470 5 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 5210 11 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 2095 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 924 5 0 0
v_cancel_supplier_invoice 788 764 24 3.04 0 1335 14 0 24
v_cancel_supplier_order 830 830 0 0.00 0 6688 11 0 0
v_cancel_write_off 1896 1896 0 0.00 0 3845 14 0 0
v_max_id_clo_ord 5046 5046 0 0.00 0 191 1 0 0
v_max_id_clo_res 950 950 0 0.00 0 6175 22 0 0
v_max_id_ord_sup 5021 5021 0 0.00 0 6219 5 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 321 2 0 0
v_max_non_paid_realizn 3510 3510 0 0.00 0 717 2 0 0
v_min_id_clo_ord 5046 5046 0 0.00 0 10670 6 0 0
v_min_id_clo_res 950 950 0 0.00 0 6400 16 0 0
v_min_id_ord_sup 5021 5021 0 0.00 0 4551 6 0 0
v_min_non_paid_invoice 5506 5506 0 0.00 0 6788 13 0 0
v_min_non_paid_realizn 6696 6696 0 0.00 0 6878 13 0 0
v_our_firm 9698 9698 0 0.00 0 12 1 0 0
v_reserve_write_off 18796 18796 0 0.00 0 5854 7 0 0
x_lock_depdocs_on_canc_invoice 11079 11079 0 0.00 0 17067 124 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 9618 343 0 0
x_make_qstorno_1000_1200 5046 5046 0 0.00 2 34820 1334 0 0
x_make_qstorno_1200_2000 5046 5046 0 0.00 3 118899 3476 0 0
x_make_qstorno_2100_3300 436962 436962 0 0.00 0 64425 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2021 07:27:49 to 11-06-2021 07:27:53.

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
04:57:03 8 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
04:58:04 21227 8192000000 9727650672 10025680896 804439200 8.941 100 90 180 0 833534032 7599120 21495712
04:59:28 25176 8192000000 9763888144 10072555520 840434832 9.304 100 81 161 1 871136128 11955808 18745488
05:00:57 18966 8192000000 9808849504 10129182720 877640512 9.676 100 79 158 2 911227984 15984432 17603040
05:02:19 16387 8192000000 9871667584 10206023680 924263360 10.138 100 78 156 1 975901264 35136448 16501456
05:03:37 9938 8192000000 9919151440 10267295744 951892224 10.410 100 77 154 0 1027506288 59881440 15732624
05:04:51 4890 8192000000 9972716368 10330562560 985480912 10.738 100 76 152 0 1085719088 85447840 14790336
05:05:57 3517 8192000000 9987608560 10344939520 1004568640 10.923 100 85 170 0 1101728624 80034768 17125216
05:07:05 3224 8192000000 9984752192 10352046080 1031235952 11.180 100 81 160 0 1104732832 56954272 16542608
05:08:11 2350 8192000000 10041334832 10409848832 1056121072 11.419 100 95 189 0 1158499696 81837680 20540944
05:09:14 2518 8192000000 9996123056 10382684160 1057961472 11.437 100 59 119 1 1122537952 52834880 11741600
05:10:20 4442 8192000000 9971603216 10356973568 1064500992 11.500 100 43 85 3 1084631600 11567600 8563008
05:11:25 5011 8192000000 9990086720 10382319616 1070227712 11.554 100 40 80 2 1099482000 20864832 8389456
05:12:31 24694 8192000000 9992909152 10383298560 1070766432 11.559 100 42 83 2 1087804944 8274944 8763568
05:13:56 6853 8192000000 9993395888 10401402880 1080750144 11.655 100 34 68 0 1107660048 19170096 7739808
05:15:09 7498 8192000000 9996170752 10391920640 1084839040 11.694 100 37 72 1 1117027760 23863552 8325168
05:16:17 11530 8192000000 9992273280 10401607680 1086691920 11.711 100 30 58 2 1114069472 20283536 7094016
05:17:31 26072 8192000000 9978573632 10389962752 1044270720 11.306 96 19 38 2 1074482304 26359648 3851936
05:18:58 6236 8192000000 9967368608 10358497280 1014902640 11.023 97 51 103 1 1072512112 46226208 11383264
05:20:05 6898 8192000000 9938917408 10325475328 1008709008 10.963 99 36 73 1 1033133568 16336704 8087856
05:21:16 61564 8192000000 9926760432 10320736256 985967808 10.742 96 26 52 0 1015433888 23432416 6033664
05:23:18 4453 8192000000 9953143392 10345332736 1040653888 11.271 100 45 90 1 1086302208 35775152 9873168
05:24:27 7784 8192000000 9975479600 10376310784 1054501648 11.404 100 41 82 1 1084289312 20915536 8872128
05:25:35 4502 8192000000 9981730240 10377371648 1066479616 11.518 100 36 72 1 1088494416 13563392 8451408
05:26:40 50464 8192000000 10005739600 10402402304 1072283840 11.574 100 52 104 0 1101901648 19293408 10324400
05:28:38 19483 8192000000 10099441808 10497134592 1103395264 11.870 100 60 118 1 1201676800 86908288 11373248
05:30:02 29646 8192000000 10066772864 10478919680 1113788448 11.968 100 46 91 1 1185941216 62183264 9969504
05:31:38 9144 8192000000 10113444960 10529165312 1127462688 12.097 100 57 114 1 1223612288 83382672 12766928
05:32:51 11807 8192000000 10159523008 10565246976 1146161504 12.273 100 57 114 1 1265475296 106313920 12999872
05:34:05 7581 8192000000 10168193424 10581807104 1155253888 12.359 100 47 94 1 1284628352 118796400 10578064
05:35:16 17699 8192000000 10223044096 10637586432 1162354000 12.425 100 55 109 0 1325729856 150987008 12388848
05:36:42 10903 8192000000 10261199744 10675412992 1173395184 12.529 100 56 112 1 1358487536 172681600 12410752
05:37:59 6191 8192000000 10285439808 10701152256 1180345008 12.593 100 67 132 1 1390352688 195479152 14528528
05:39:08 3341 8192000000 10249899728 10662842368 1140529136 12.221 98 61 122 0 1342496128 188606896 13360096
05:40:23 5343 8192000000 10291991728 10736340992 1172629856 12.521 100 71 142 0 1396632000 208103008 15899136
05:41:34 1672 8192000000 10299007728 10715037696 1172939168 12.524 100 76 151 0 1395006496 203723888 18343440
05:42:43 1636 8192000000 10363637072 10774601728 1180142464 12.592 100 80 161 0 1466296000 267398144 18755392
05:43:52 3515 8192000000 10384447328 10795294720 1176812112 12.560 100 75 150 1 1483382128 287850768 18719248
05:45:00 3812 8192000000 10395666496 10814496768 1169627808 12.493 100 71 136 2 1487400464 300825008 16947648
05:46:07 6362 8192000000 10496745200 10928726016 1177264928 12.565 100 70 140 1 1599287280 404514352 17508000
05:47:16 7312 8192000000 10453303424 10895544320 1157047392 12.376 99 45 91 0 1544805920 376249072 11509456
05:48:28 5453 8192000000 10491804032 10899984384 1161828016 12.420 100 76 153 2 1585747200 406133056 17786128
05:49:39 13272 8192000000 10515493504 10942246912 1145729456 12.269 99 44 87 1 1606450512 448825072 11895984
05:50:54 11118 8192000000 10559389104 11017793536 1147915168 12.290 100 42 83 0 1650258384 490521648 11821568
05:52:25 8452 8192000000 10498861440 10974285824 1124782288 12.072 100 23 46 0 1598286064 466704800 6798976
05:53:46 25680 8192000000 10291477184 10796937216 1119600608 12.023 100 24 46 2 1483341376 357432352 6308416
05:55:17 78890 8192000000 10042213888 10482933760 1086296000 11.707 98 22 44 1 1147687504 56178704 5212800
05:57:37 117695 8192000000 10081170128 10527346688 1082319856 11.670 96 27 54 2 1105559456 17615680 5623920
06:00:35 79942 8192000000 10069284160 10528432128 1075997440 11.609 96 13 26 2 1147719200 68902080 2819680
06:02:59 77629 8192000000 10127576656 10580791296 1111845136 11.950 99 22 45 0 1210498416 93524768 5128512
06:05:32 74359 8192000000 10088041408 10536882176 1117733184 12.006 99 35 70 0 1175571072 49842112 7995776
06:08:25 81990 8192000000 10133227840 10582253568 1110926976 11.941 98 22 44 0 1202249968 86568720 4754272
06:10:57 66055 8192000000 10073575184 10576162816 1131946608 12.140 100 7 14 0 1183513968 49768832 1798528
06:13:56 34771 8192000000 10078765616 10522001408 1132785472 12.148 100 17 34 1 1186771696 50107696 3878528
06:15:40 59227 8192000000 10077434144 10607673344 1133139984 12.151 100 5 10 0 1212719216 78398736 1180496
06:17:49 35955 8192000000 10045240368 10499809280 1125078416 12.075 99 11 20 0 1149604512 22378400 2147696
06:19:38 59979 8192000000 10124414144 10589499392 1148801312 12.298 100 36 70 4 1197357200 40272944 8282944
06:21:40 51898 8192000000 10133912928 10588549120 1156763216 12.373 100 25 50 2 1221249280 59409456 5076608
06:23:32 44297 8192000000 10137070800 10596904960 1158216384 12.387 100 25 50 0 1245685488 81964176 5504928
06:25:29 89735 8192000000 10130913328 10615361536 1127355680 12.096 97 15 30 0 1232310128 101667760 3286688
06:28:09 65112 8192000000 10072642208 10532356096 1111215728 11.944 96 15 30 0 1144078496 30058992 2803776
06:30:15 122736 8192000000 10092319024 10613940224 1134878400 12.167 98 12 24 0 1204907648 67621184 2408064
06:33:22 61518 8192000000 10094169664 10552594432 1122645744 12.052 97 11 22 0 1169198096 44577296 1975056
06:35:24 89289 8192000000 10085058112 10592567296 1139782080 12.213 99 6 12 0 1171335936 30678960 874896
06:37:54 45256 8192000000 10131334912 10583699456 1156940400 12.375 100 28 55 0 1227185504 64335968 5909136
06:39:40 85412 8192000000 10095989152 10613211136 1143471808 12.248 99 15 30 0 1246006672 99198128 3336736
06:42:07 37200 8192000000 10096285248 10535268352 1146100352 12.273 100 45 86 2 1179638944 24778560 8760032
06:43:49 57935 8192000000 10075647824 10529386496 1067435360 11.528 93 31 62 1 1120505648 47104224 5966064
06:45:47 31051 8192000000 10130426320 10568650752 1094663936 11.787 97 45 90 0 1173223984 69528112 9031936
06:47:20 30705 8192000000 10065983552 10486104064 1105024208 11.885 99 41 82 0 1134385152 21236864 8124080
06:48:53 78911 8192000000 10051061104 10511601664 1124080624 12.066 100 18 36 0 1176426224 49087296 3258304
06:51:13 71703 8192000000 10048772800 10497650688 1105678752 11.891 98 9 18 2 1141747632 34022640 2046240
06:53:30 47272 8192000000 10114194768 10559561728 1124471184 12.069 99 25 50 1 1204325456 74099904 5754368
06:55:20 89204 8192000000 10092512384 10574512128 1087446288 11.718 96 15 30 1 1171149136 80282544 3420304
06:58:08 50491 8192000000 10076343136 10509950976 1081024768 11.657 96 29 57 0 1119795408 32917104 5853536
07:00:06 31990 8192000000 10089938416 10511966208 1091993440 11.762 97 37 74 3 1155202592 54989872 8219280
07:01:38 55634 8192000000 10069007168 10553643008 1066429120 11.518 95 22 42 3 1133101392 61855072 4817200
07:03:44 42325 8192000000 10073883008 10524139520 1087706560 11.721 97 36 72 1 1147785616 52280896 7798160
07:05:33 39599 8192000000 10085183472 10514456576 1115705424 11.986 99 24 48 0 1150766272 29206032 5854816
07:07:25 13358 8192000000 10099143072 10524258304 1123992592 12.065 99 48 96 0 1189701632 56428496 9280544
07:08:50 19858 8192000000 10075681920 10527666176 1127985152 12.102 100 39 78 1 1160540784 23945760 8609872
07:11:04 71004 8192000000 10041050304 10495115264 1105348032 11.888 97 14 28 0 1128807728 20685712 2773984
07:13:22 99131 8192000000 10052850464 10544394240 1095103888 11.791 97 11 22 0 1157841440 60512576 2224976
07:16:42 71658 8192000000 10109240992 10559778816 1115924832 11.988 98 26 51 3 1177558192 56251040 5382320
07:18:54 57670 8192000000 10098644352 10553180160 1121611760 12.042 98 17 33 0 1168254672 42949344 3693568
07:21:26 71450 8192000000 10151643936 10655514624 1138655984 12.203 99 26 52 1 1244870432 100815648 5398800
07:23:38 64252 8192000000 10101496656 10598350848 1121506016 12.041 98 20 40 1 1204361472 78724480 4130976
07:25:45 82001 8192000000 9330495856 9661870080 450422944 5.211 39 8 16 0 485517184 33554576 1539664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-06-2021 07:27:55 to 11-06-2021 07:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-06-2021 07:28:11 to 11-06-2021 07:28:13.

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          225723
MON$OLDEST_ACTIVE               225964
MON$OLDEST_SNAPSHOT             225964
MON$NEXT_TRANSACTION            225964
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                       2079968
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 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 ---
--- end of diff output ---

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

11.06.21 07:28:14. 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-06-11 07:28:16 No severe PSQL-related problems occured
11.06.21 07:28:17. End of report.