Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.01.2021 04: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 16751016 kB
MemAvailable 31210360 kB
Buffers 20 kB
Cached 14843652 kB
SwapCached 4868 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.0
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-01-27 04:26:56
DTS_END                         2021-01-27 04:26:56.5950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51370
TRN_ID                          226248
ATT_ID                          630
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: 8757.58 19405 1050910
customer order: creation 162.06 422 19447
customer order: refuse 13.89 314 1667
order to supplier: creation 42.93 1423 5152
order to supplier: removal 3.27 115861 392
invoice (draft): creation 42.58 3668 5109
invoice (draft): removal 49.54 903 5945
invoice accept: apply 40.17 17446 4820
invoice accept: cancel 44.72 934 5366
customer reserve: creation 3678.67 171 441440
customer reserve: removal 4403.77 70 528453
realization accept: apply 79.68 194 9561
realization accept: cancel 74.36 119 8923
payment from customer: creation 55.89 110 6707
payment from customer: removal 5.63 70 676
payment to supplier: creation 46.43 131 5572
payment to supplier: removal 3.54 63 425
service: total inventory turnovers 3.80 3263 456
service: total monetary turnovers 5.71 620 685
service: aggregate perf. data 0.91 13758 109
service: refresh index statistics 0.04 228555 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-01-2021 04:27:47 to 27-01-2021 04:27:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3341
TEST_TIME 2 5210
TEST_TIME 3 6036
TEST_TIME 4 5901
TEST_TIME 5 5521
TEST_TIME 6 5607
TEST_TIME 7 5571
TEST_TIME 8 5556
TEST_TIME 9 5696
TEST_TIME 10 6168
TEST_TIME 11 6414
TEST_TIME 12 6434
TEST_TIME 13 6503
TEST_TIME 14 7058
TEST_TIME 15 7269
TEST_TIME 16 7753
TEST_TIME 17 7963
TEST_TIME 18 7967
TEST_TIME 19 8270
TEST_TIME 20 8381
TEST_TIME 21 8418
TEST_TIME 22 8609
TEST_TIME 23 8833
TEST_TIME 24 9533
TEST_TIME 25 9985
TEST_TIME 26 10383
TEST_TIME 27 10693
TEST_TIME 28 10927
TEST_TIME 29 10921
TEST_TIME 30 10657
TEST_TIME 31 10380
TEST_TIME 32 10210
TEST_TIME 33 10007
TEST_TIME 34 9878
TEST_TIME 35 9717
TEST_TIME 36 9760
TEST_TIME 37 9641
TEST_TIME 38 9530
TEST_TIME 39 9400
TEST_TIME 40 9308
TEST_TIME 41 9237
TEST_TIME 42 9362
TEST_TIME 43 9323
TEST_TIME 44 9438
TEST_TIME 45 9309
TEST_TIME 46 9280
TEST_TIME 47 9379
TEST_TIME 48 9360
TEST_TIME 49 9316
TEST_TIME 50 9428
TEST_TIME 51 9287
TEST_TIME 52 9407
TEST_TIME 53 9475
TEST_TIME 54 9346
TEST_TIME 55 9455
TEST_TIME 56 9467
TEST_TIME 57 9310
TEST_TIME 58 9203
TEST_TIME 59 9151
TEST_TIME 60 9053
TEST_TIME 61 9044
TEST_TIME 62 9002
TEST_TIME 63 8976
TEST_TIME 64 8928
TEST_TIME 65 8908
TEST_TIME 66 8902
TEST_TIME 67 8904
TEST_TIME 68 9006
TEST_TIME 69 9097
TEST_TIME 70 9219
TEST_TIME 71 9241
TEST_TIME 72 9175
TEST_TIME 73 9273
TEST_TIME 74 9320
TEST_TIME 75 9287
TEST_TIME 76 9307
TEST_TIME 77 9195
TEST_TIME 78 9127
TEST_TIME 79 9063
TEST_TIME 80 9043
TEST_TIME 81 9061
TEST_TIME 82 9033
TEST_TIME 83 8992
TEST_TIME 84 9006
TEST_TIME 85 8990
TEST_TIME 86 9009
TEST_TIME 87 9018
TEST_TIME 88 9009
TEST_TIME 89 9016
TEST_TIME 90 9037
TEST_TIME 91 9071
TEST_TIME 92 9098
TEST_TIME 93 9108
TEST_TIME 94 9027
TEST_TIME 95 8971
TEST_TIME 96 8944
TEST_TIME 97 8900
TEST_TIME 98 8852
TEST_TIME 99 8818
TEST_TIME 100 8834
TEST_TIME 101 8861
TEST_TIME 102 8864
TEST_TIME 103 8827
TEST_TIME 104 8805
TEST_TIME 105 8859
TEST_TIME 106 8884
TEST_TIME 107 8904
TEST_TIME 108 8880
TEST_TIME 109 8941
TEST_TIME 110 8933
TEST_TIME 111 8905
TEST_TIME 112 8828
TEST_TIME 113 8802
TEST_TIME 114 8752
TEST_TIME 115 8737
TEST_TIME 116 8698
TEST_TIME 117 8723
TEST_TIME 118 8727
TEST_TIME 119 8736
TEST_TIME 120 8763

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-01-2021 04:27:50 to 27-01-2021 04:27:54.

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 1459049 1459049 0 0.00 0 19694 6 0 0
doc_list_biud 1459047 1459047 0 0.00 0 32144 32 0 0
sp_add_invoice_to_stock 4928 4820 108 2.19 33 164633 17447 0 108
sp_cancel_adding_invoice 5366 5366 0 0.00 5 72735 935 0 0
sp_cancel_client_order 1667 1667 0 0.00 4 9809 314 0 0
sp_cancel_customer_reserve 528453 528453 0 0.00 1 26308 71 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 2974 71 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 1989 64 0 0
sp_cancel_supplier_invoice 5956 5945 11 0.18 2 32156 904 0 11
sp_cancel_supplier_order 392 392 0 0.00 11 950368 115861 0 0
sp_cancel_write_off 8923 8923 0 0.00 1 7631 120 0 0
sp_client_order 19447 19447 0 0.00 4 22236 423 0 0
sp_customer_reserve 441440 441440 0 0.00 0 41281 172 0 0
sp_fill_shopping_cart 29698 29698 0 0.00 1 35814 52 0 0
sp_fill_shopping_cart_clo_res 441430 441430 0 0.00 0 26493 19 0 0
sp_get_clo_for_invoice 4809 4809 0 0.00 0 28894 460 0 0
sp_kill_cost_storno 15390 15390 0 0.00 0 5552 53 0 0
sp_kill_qstorno_ret_qs2qd 1072166 1072166 0 0.00 0 32141 38 0 0
sp_kill_qty_storno 565116 565116 0 0.00 0 32144 81 0 0
sp_lock_selected_doc 19890 19890 0 0.00 0 10101 8 0 0
sp_make_cost_storno 26649 26649 0 0.00 0 17250 59 0 0
sp_multiply_rows_for_pdistr 26649 26649 0 0.00 0 10255 32 0 0
sp_multiply_rows_for_qdistr 19447 19447 0 0.00 0 22030 253 0 0
sp_pay_from_customer 6707 6707 0 0.00 4 15529 111 0 0
sp_pay_to_supplier 5572 5572 0 0.00 5 16936 132 0 0
sp_payment_common 12279 12279 0 0.00 1 14762 22 0 0
sp_qd_handle_on_cancel_clo 1667 1667 0 0.00 0 9802 281 0 0
sp_qd_handle_on_invoice_upd_sts 10175 10175 0 0.00 0 30062 366 0 0
sp_qd_handle_on_reserve_upd_sts 9561 9561 0 0.00 0 18431 54 0 0
sp_reserve_write_off 9561 9561 0 0.00 6 18476 194 0 0
sp_supplier_invoice 5109 5109 0 0.00 7 98758 3669 0 0
sp_supplier_order 5152 5152 0 0.00 5 27374 1424 0 0
srv_aggregate_perf_data 109 109 0 0.00 2774 352132 13759 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1992 144546 45135 0 0
srv_find_qd_qs_mism 1005399 1005399 0 0.00 0 19693 5 0 0
srv_make_invnt_saldo 456 456 0 0.00 38 122723 3264 0 0
srv_make_money_saldo 685 685 0 0.00 1 21603 620 0 0
srv_recalc_idx_stat 5 5 0 0.00 127926 279650 228555 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 0 15873 154 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 3135 99 0 0
t$perf-norm:sp_cancel_client_order 1667 1667 0 0.00 0 2377 9 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1282 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 8727 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 8417 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 8178 50 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 13138 1220 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 1448 6 0 0
t$perf-norm:sp_client_order 19447 19447 0 0.00 0 7414 14 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 2717 9 0 0
t$perf-norm:sp_pay_from_customer 6707 6707 0 0.00 0 3114 7 0 0
t$perf-norm:sp_pay_to_supplier 5572 5572 0 0.00 0 7781 9 0 0
t$perf-norm:sp_reserve_write_off 9561 9561 0 0.00 0 4913 8 0 0
t$perf-norm:sp_supplier_invoice 5109 5109 0 0.00 0 4577 13 0 0
t$perf-norm:sp_supplier_order 5152 5152 0 0.00 0 3308 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 536 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 165 4 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 653 5 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 500 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9834 9618 216 2.19 0 3422 18 0 216
v_all_customers 35386 35386 0 0.00 0 28 1 0 0
v_all_suppliers 28564 28564 0 0.00 0 27 1 0 0
v_all_wares 38892 38892 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 519 7 0 0
v_cancel_client_order 3334 3334 0 0.00 0 4535 7 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 2245 8 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 1369 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 450 4 0 0
v_cancel_supplier_invoice 800 778 22 2.75 0 3909 23 0 22
v_cancel_supplier_order 784 784 0 0.00 0 2314 8 0 0
v_cancel_write_off 1892 1892 0 0.00 0 4154 18 0 0
v_max_id_clo_ord 5150 5150 0 0.00 0 561 1 0 0
v_max_id_clo_res 952 952 0 0.00 0 2992 29 0 0
v_max_id_ord_sup 5102 5102 0 0.00 0 5039 5 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 154 2 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 1759 3 0 0
v_min_id_clo_ord 5150 5150 0 0.00 0 3319 9 0 0
v_min_id_clo_res 952 952 0 0.00 0 7689 11 0 0
v_min_id_ord_sup 5102 5102 0 0.00 0 9175 8 0 0
v_min_non_paid_invoice 5572 5572 0 0.00 0 9507 14 0 0
v_min_non_paid_realizn 6707 6707 0 0.00 0 10275 13 0 0
v_our_firm 9568 9568 0 0.00 0 13 1 0 0
v_reserve_write_off 19122 19122 0 0.00 0 4063 7 0 0
x_lock_depdocs_on_canc_invoice 10922 10922 0 0.00 0 21156 121 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 5173 320 0 0
x_make_qstorno_1000_1200 5152 5152 0 0.00 2 27365 1343 0 0
x_make_qstorno_1200_2000 5109 5109 0 0.00 3 98692 3458 0 0
x_make_qstorno_2100_3300 441440 441440 0 0.00 0 38865 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-01-2021 04:27:55 to 27-01-2021 04:27:59.

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
01:57:00 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
01:58:01 33532 8192000000 9726657040 10021101568 804369520 8.941 100 91 182 0 833923024 8033360 21520144
01:59:42 23861 8192000000 9761651952 10065534976 831714832 9.216 100 88 176 0 866039776 13389024 20935920
02:01:09 15986 8192000000 9808441248 10127134720 868085776 9.581 100 85 170 0 917814896 30652160 19076960
02:02:27 11281 8192000000 9866908064 10196058112 906989184 9.968 100 82 164 0 974743360 49661856 18092320
02:03:41 9588 8192000000 9894593920 10240352256 942780960 10.320 100 75 150 0 1014308864 55294880 16233024
02:04:53 5655 8192000000 9944842416 10297335808 991498352 10.796 100 88 173 1 1061977888 52808992 17670544
02:06:00 2777 8192000000 9988430016 10347859968 1019745392 11.070 100 82 165 1 1099297232 62806128 16745712
02:07:04 1559 8192000000 10025848288 10390646784 1041049504 11.275 100 92 182 1 1131984576 72552384 18382688
02:08:07 5000 8192000000 9998273840 10371678208 1055932016 11.418 100 74 146 2 1124708432 54176064 14600352
02:09:12 3761 8192000000 10004334864 10385756160 1062226208 11.478 100 55 110 2 1114984928 41166704 11592016
02:10:17 54791 8192000000 9968419408 10381918208 1066339536 11.517 100 32 61 1 1091348512 17437040 7571936
02:12:12 4335 8192000000 9996266976 10387169280 1075278864 11.602 100 43 85 2 1105699504 20985872 9434768
02:13:20 11007 8192000000 9997092928 10400563200 1087533808 11.719 100 28 56 1 1111749520 17473456 6742256
02:14:32 9025 8192000000 9999674288 10393124864 1091382320 11.756 100 31 59 1 1113435952 15853584 6200048
02:15:42 32572 8192000000 9982407840 10392072192 1057538800 11.433 97 27 52 0 1084952800 21633104 5780896
02:17:18 8398 8192000000 9993897648 10396549120 1072716048 11.578 99 38 74 1 1099560656 19244432 7600176
02:18:26 9913 8192000000 9958493568 10348773376 1025262992 11.123 96 25 50 0 1041263568 10145888 5854688
02:19:40 6688 8192000000 9912477760 10289885184 966379792 10.551 94 26 53 1 988595968 16308640 5907536
02:20:48 18517 8192000000 9889415664 10277191680 943669552 10.329 93 19 38 1 979967968 32734304 3564112
02:22:11 9901 8192000000 9909798272 10278506496 990706256 10.788 99 37 72 0 1023785840 25491760 7587824
02:23:27 6342 8192000000 9948351280 10333179904 1034589824 11.213 100 58 114 4 1060676720 12814432 13272464
02:24:33 53552 8192000000 9984753648 10385989632 1046920144 11.331 100 45 88 1 1076661248 20099824 9641280
02:26:27 7550 8192000000 9976750784 10364035072 1058808048 11.445 100 36 70 1 1081806080 14944160 8053872
02:27:45 14748 8192000000 10008986208 10401189888 1088298048 11.726 100 45 89 2 1121168832 23145024 9725760
02:29:04 6145 8192000000 10059481376 10456117248 1104480736 11.880 100 50 96 2 1169102064 54334848 10286480
02:30:19 36778 8192000000 10078743840 10492465152 1117923392 12.007 100 55 110 2 1198643952 69262896 11457664
02:32:08 5609 8192000000 10118431328 10518962176 1129682400 12.118 100 55 108 2 1229007088 87863264 11461424
02:33:25 1992 8192000000 10146736832 10554359808 1136915344 12.187 100 52 104 1 1255896384 107493520 11487520
02:34:31 7002 8192000000 10150806960 10557825024 1146130880 12.273 100 42 84 2 1261708096 106208016 9369200
02:35:49 13808 8192000000 10197627296 10604609536 1154800032 12.355 100 54 107 1 1301412448 134414256 12198160
02:37:07 3182 8192000000 10249908560 10649882624 1168845904 12.486 100 79 157 2 1355181936 169247360 17088672
02:38:13 5695 8192000000 10271688192 10685227008 1169946240 12.496 100 50 100 0 1369747248 188421440 11379568
02:39:24 4961 8192000000 10269850480 10687836160 1160475888 12.408 100 53 104 2 1374137680 201998384 11663408
02:40:35 3914 8192000000 10286199408 10712023040 1163204112 12.433 99 76 150 1 1389623072 209982992 16435968
02:41:42 3962 8192000000 10315306672 10733035520 1164249680 12.443 100 66 132 0 1408532240 228770464 15512096
02:42:50 2195 8192000000 10316531040 10720264192 1159809168 12.401 100 73 146 1 1416319968 240608400 15902400
02:43:56 2475 8192000000 10309421984 10705313792 1150532672 12.314 99 84 166 2 1395757200 225636160 19588368
02:45:02 2084 8192000000 10344868848 10742763520 1132972400 12.149 99 84 167 0 1442687792 289702288 20013104
02:46:08 7300 8192000000 10309333712 10743148544 1107226384 11.906 97 47 94 1 1391612464 272865536 11520544
02:47:22 2264 8192000000 10374246144 10781937664 1137059568 12.188 100 69 138 2 1475438672 320446800 17932304
02:48:32 15242 8192000000 10393495232 10837966848 1142374544 12.238 100 46 90 1 1510788624 356478384 11935696
02:49:49 11287 8192000000 10389225520 10847211520 1142711376 12.241 99 43 86 1 1512123664 357941616 11470672
02:51:08 11515 8192000000 10314229504 10756988928 1137465280 12.192 100 29 57 0 1429462992 284297504 7700208
02:52:20 26172 8192000000 10215708720 10673037312 1126835264 12.092 100 17 34 0 1328585536 197379024 4371248
02:53:49 66251 8192000000 10115783344 10626695168 1117075584 11.999 100 14 28 3 1256506672 136167440 3263648
02:55:58 133757 8192000000 10064223760 10512293888 1121383072 12.040 100 24 48 0 1153221520 26663952 5174496
02:59:14 55056 8192000000 10066732576 10503340032 1099509296 11.833 98 26 51 1 1152897024 48292160 5095568
03:01:09 69203 8192000000 10105366976 10548350976 1111584960 11.947 98 23 45 0 1192626944 75520528 5521456
03:03:23 65515 8192000000 10123226976 10551005184 1118576960 12.014 98 19 39 0 1209339488 86511168 4251360
03:05:36 59726 8192000000 10176141568 10610933760 1118790432 12.016 98 18 36 1 1227383632 104372272 4220928
03:07:41 107605 8192000000 10127820624 10628599808 1139774976 12.213 100 16 32 0 1264906992 121241424 3890592
03:10:36 63402 8192000000 10082009888 10543648768 1095101824 11.791 97 11 22 0 1140873376 43579808 2191744
03:12:56 53118 8192000000 10101878464 10602323968 1124998768 12.074 99 14 28 1 1213367760 85254352 3114640
03:14:58 27011 8192000000 10101507376 10529275904 1140536912 12.221 100 54 107 1 1184077248 31834304 11706032
03:16:31 92555 8192000000 10140354960 10613874688 1145322128 12.266 100 9 18 0 1197872432 50546320 2003984
03:19:04 90975 8192000000 10119634928 10559418368 1140948336 12.224 99 11 22 0 1187155616 43792096 2415184
03:21:35 144546 8192000000 10083233360 10560053248 1140456576 12.220 99 13 26 0 1171615856 27940432 3218848
03:25:27 29423 8192000000 10065134608 10524250112 1135956800 12.177 99 6 12 0 1163077888 25886752 1234336
03:26:56 47416 8192000000 10104711248 10559983616 1142657632 12.241 99 12 24 0 1200568192 55713920 2196640
03:28:44 34495 8192000000 10066446160 10526380032 1103077552 11.867 96 10 19 2 1141120848 36539264 1504032
03:30:30 19124 8192000000 10104992592 10538868736 1148492656 12.295 100 40 79 0 1198263232 41396112 8374464
03:31:54 38638 8192000000 10156368176 10593300480 1112859744 11.959 95 31 62 1 1202755616 82864976 7030896
03:33:33 63519 8192000000 10177805664 10726395904 1130077840 12.122 98 16 32 1 1271105568 137277072 3750656
03:35:46 62030 8192000000 10085595264 10552930304 1093556208 11.776 96 10 20 2 1177330960 81761776 2012976
03:37:48 35610 8192000000 10105225024 10532298752 1128202336 12.104 100 37 74 3 1183546112 47887952 7455824
03:39:48 48475 8192000000 10084267968 10587602944 1102047856 11.857 98 27 54 0 1187289376 79693248 5548272
03:41:42 28755 8192000000 10127276896 10559238144 1122920112 12.055 100 28 56 1 1194185536 65776848 5488576
03:43:18 82014 8192000000 10056189056 10520367104 1108088432 11.914 99 24 48 1 1158152976 44990080 5074464
03:45:54 86026 8192000000 10067570960 10568630272 1086196208 11.706 98 10 20 0 1156356304 67994688 2165408
03:48:21 95996 8192000000 10062859584 10503700480 1089699376 11.740 98 17 34 0 1111242320 18030256 3512688
03:51:03 26854 8192000000 10072034304 10509127680 1096598784 11.805 98 31 61 1 1146597728 43282592 6716352
03:52:34 49788 8192000000 10078102544 10515320832 1094231136 11.783 98 20 40 0 1129498112 31367408 3899568
03:54:25 44883 8192000000 10108010224 10576449536 1096623680 11.806 97 41 81 3 1155573904 50344208 8606016
03:56:12 73556 8192000000 10099121968 10554253312 1114640560 11.976 99 21 42 0 1203561744 84587856 4333328
03:58:36 38705 8192000000 10065331152 10500308992 1097780272 11.817 98 22 44 0 1137568896 35267296 4521328
04:00:19 100557 8192000000 10055244848 10495889408 1103825072 11.874 99 35 70 0 1158120352 47310304 6984976
04:03:28 31043 8192000000 10057812496 10502500352 1128505696 12.107 100 6 12 1 1146856896 16805344 1545856
04:05:04 91773 8192000000 10105375792 10585853952 1126846928 12.092 99 21 42 0 1206531088 75158272 4525888
04:07:39 46453 8192000000 10065776144 10540879872 1138285488 12.199 100 12 23 0 1187733376 47044000 2403888
04:09:25 46658 8192000000 10093867072 10539802624 1122303008 12.049 98 37 74 2 1156574976 26539280 7732688
04:11:12 68878 8192000000 10114456368 10565857280 1126339824 12.087 99 25 50 0 1211280384 79590160 5350400
04:13:43 61170 8192000000 10098832544 10545565696 1127858896 12.101 99 13 26 0 1165468272 35262000 2347376
04:15:47 69834 8192000000 10077212224 10538373120 1130490976 12.126 99 19 38 0 1167212736 32940272 3781488
04:18:00 99631 8192000000 10075885248 10538663936 1127119312 12.094 99 26 52 0 1163880480 31271136 5490032
04:20:44 78322 8192000000 10098454928 10577403904 1119763184 12.025 98 9 18 0 1183064640 61421456 1880000
04:23:02 48908 8192000000 10080779744 10551160832 1121403904 12.040 99 10 18 0 1168188752 44655008 2129840
04:24:52 55702 8192000000 10079272656 10537320448 1086479040 11.709 96 22 44 0 1129553808 38731104 4343664
04:26:52 12227 8192000000 8979337440 9192763392 134781456 1.618 13 1 2 0 142698880 7882016 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-01-2021 04:28:01 to 27-01-2021 04:28:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-01-2021 04:28:17 to 27-01-2021 04:28:19.

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          226672
MON$OLDEST_ACTIVE               226673
MON$OLDEST_SNAPSHOT             226673
MON$NEXT_TRANSACTION            226673
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                       2075488
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

27.01.21 04:28:20. 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-01-27 04:28:22 No severe PSQL-related problems occured
27.01.21 04:28:24. End of report.