Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2020 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 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 16819792 kB
MemAvailable 31324716 kB
Buffers 0 kB
Cached 14736648 kB
SwapCached 4820 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 2020-12-28 07:26:53
DTS_END                         2020-12-28 07:26:53.7080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42104
TRN_ID                          232759
ATT_ID                          645
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: 8816.59 21849 1057991
customer order: creation 167.03 411 20043
customer order: refuse 14.26 319 1711
order to supplier: creation 44.64 1406 5357
order to supplier: removal 3.28 114984 393
invoice (draft): creation 44.65 3756 5358
invoice (draft): removal 49.49 999 5939
invoice accept: apply 40.78 17265 4894
invoice accept: cancel 44.85 895 5382
customer reserve: creation 3749.23 169 449908
customer reserve: removal 4368.76 67 524251
realization accept: apply 79.98 190 9598
realization accept: cancel 84.90 115 10188
payment from customer: creation 57.43 137 6891
payment from customer: removal 5.47 80 656
payment to supplier: creation 47.93 156 5751
payment to supplier: removal 3.21 83 385
service: total inventory turnovers 3.68 3259 441
service: total monetary turnovers 6.01 535 721
service: aggregate perf. data 1.00 12707 120
service: refresh index statistics 0.03 279450 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-12-2020 07:28:04 to 28-12-2020 07:28:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3181
TEST_TIME 2 4011
TEST_TIME 3 4249
TEST_TIME 4 4558
TEST_TIME 5 4272
TEST_TIME 6 4197
TEST_TIME 7 4703
TEST_TIME 8 4885
TEST_TIME 9 4975
TEST_TIME 10 5720
TEST_TIME 11 6342
TEST_TIME 12 6354
TEST_TIME 13 6682
TEST_TIME 14 6947
TEST_TIME 15 7292
TEST_TIME 16 7309
TEST_TIME 17 7443
TEST_TIME 18 8036
TEST_TIME 19 8226
TEST_TIME 20 8263
TEST_TIME 21 8276
TEST_TIME 22 8640
TEST_TIME 23 9024
TEST_TIME 24 8850
TEST_TIME 25 9025
TEST_TIME 26 10034
TEST_TIME 27 11171
TEST_TIME 28 10914
TEST_TIME 29 10848
TEST_TIME 30 10519
TEST_TIME 31 10448
TEST_TIME 32 10255
TEST_TIME 33 10060
TEST_TIME 34 10015
TEST_TIME 35 10020
TEST_TIME 36 9904
TEST_TIME 37 9790
TEST_TIME 38 9604
TEST_TIME 39 9416
TEST_TIME 40 9487
TEST_TIME 41 9449
TEST_TIME 42 9515
TEST_TIME 43 9705
TEST_TIME 44 9629
TEST_TIME 45 9558
TEST_TIME 46 9707
TEST_TIME 47 9653
TEST_TIME 48 9589
TEST_TIME 49 9565
TEST_TIME 50 9602
TEST_TIME 51 9724
TEST_TIME 52 9709
TEST_TIME 53 9777
TEST_TIME 54 9686
TEST_TIME 55 9534
TEST_TIME 56 9440
TEST_TIME 57 9366
TEST_TIME 58 9299
TEST_TIME 59 9265
TEST_TIME 60 9191
TEST_TIME 61 9127
TEST_TIME 62 9098
TEST_TIME 63 9073
TEST_TIME 64 9145
TEST_TIME 65 9186
TEST_TIME 66 9139
TEST_TIME 67 9187
TEST_TIME 68 9179
TEST_TIME 69 9260
TEST_TIME 70 9190
TEST_TIME 71 9267
TEST_TIME 72 9293
TEST_TIME 73 9298
TEST_TIME 74 9323
TEST_TIME 75 9303
TEST_TIME 76 9218
TEST_TIME 77 9157
TEST_TIME 78 9170
TEST_TIME 79 9135
TEST_TIME 80 9079
TEST_TIME 81 9018
TEST_TIME 82 9031
TEST_TIME 83 8988
TEST_TIME 84 8983
TEST_TIME 85 8956
TEST_TIME 86 8913
TEST_TIME 87 8893
TEST_TIME 88 8937
TEST_TIME 89 9057
TEST_TIME 90 9122
TEST_TIME 91 9114
TEST_TIME 92 9142
TEST_TIME 93 9187
TEST_TIME 94 9221
TEST_TIME 95 9222
TEST_TIME 96 9217
TEST_TIME 97 9142
TEST_TIME 98 9060
TEST_TIME 99 9058
TEST_TIME 100 9010
TEST_TIME 101 8972
TEST_TIME 102 8933
TEST_TIME 103 8926
TEST_TIME 104 8938
TEST_TIME 105 8945
TEST_TIME 106 8935
TEST_TIME 107 8915
TEST_TIME 108 8933
TEST_TIME 109 8927
TEST_TIME 110 8949
TEST_TIME 111 8966
TEST_TIME 112 9001
TEST_TIME 113 8937
TEST_TIME 114 8897
TEST_TIME 115 8851
TEST_TIME 116 8818
TEST_TIME 117 8820
TEST_TIME 118 8854
TEST_TIME 119 8827
TEST_TIME 120 8822

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2020 07:28:08 to 28-12-2020 07:28:12.

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 1474087 1474087 0 0.00 0 23956 7 0 0
doc_list_biud 1474087 1474087 0 0.00 0 33216 31 0 0
sp_add_invoice_to_stock 4995 4894 101 2.02 30 173211 17265 0 101
sp_cancel_adding_invoice 5382 5382 0 0.00 4 76715 896 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 20548 320 0 0
sp_cancel_customer_reserve 524251 524251 0 0.00 1 33218 68 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 3035 80 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 5984 83 0 0
sp_cancel_supplier_invoice 5949 5939 10 0.16 1 26590 1000 0 10
sp_cancel_supplier_order 393 393 0 0.00 7 1185460 114985 0 0
sp_cancel_write_off 10188 10188 0 0.00 1 11527 115 0 0
sp_client_order 20043 20043 0 0.00 4 36014 412 0 0
sp_customer_reserve 449908 449908 0 0.00 0 102082 170 0 0
sp_fill_shopping_cart 30729 30729 0 0.00 1 17453 50 0 0
sp_fill_shopping_cart_clo_res 449895 449895 0 0.00 0 21869 17 0 0
sp_get_clo_for_invoice 4881 4881 0 0.00 0 45819 416 0 0
sp_kill_cost_storno 16611 16611 0 0.00 0 7984 50 0 0
sp_kill_qstorno_ret_qs2qd 1065022 1065022 0 0.00 0 33212 37 0 0
sp_kill_qty_storno 562343 562343 0 0.00 0 33216 80 0 0
sp_lock_selected_doc 20047 20047 0 0.00 0 11598 5 0 0
sp_make_cost_storno 27121 27121 0 0.00 0 15691 72 0 0
sp_multiply_rows_for_pdistr 27121 27121 0 0.00 0 8921 32 0 0
sp_multiply_rows_for_qdistr 20043 20043 0 0.00 0 21087 228 0 0
sp_pay_from_customer 6891 6891 0 0.00 4 18188 137 0 0
sp_pay_to_supplier 5751 5751 0 0.00 5 20728 157 0 0
sp_payment_common 12642 12642 0 0.00 1 14019 31 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 20542 282 0 0
sp_qd_handle_on_invoice_upd_sts 10263 10263 0 0.00 0 29395 384 0 0
sp_qd_handle_on_reserve_upd_sts 9598 9598 0 0.00 0 12498 51 0 0
sp_reserve_write_off 9598 9598 0 0.00 7 17641 190 0 0
sp_supplier_invoice 5358 5358 0 0.00 7 105757 3757 0 0
sp_supplier_order 5357 5357 0 0.00 6 48851 1407 0 0
srv_aggregate_perf_data 120 120 0 0.00 2850 141932 12708 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1535 155241 49423 0 0
srv_find_qd_qs_mism 1011389 1011389 0 0.00 0 23956 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 63 128950 3260 0 0
srv_make_money_saldo 721 721 0 0.00 1 12620 535 0 0
srv_recalc_idx_stat 4 4 0 0.00 106111 390907 279450 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 0 24276 144 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 2793 75 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 6211 16 0 0
t$perf-norm:sp_cancel_customer_reserve 610 610 0 0.00 0 741 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 1988 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 617 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1045 15 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 12940 1313 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2285 7 0 0
t$perf-norm:sp_client_order 20043 20043 0 0.00 0 5337 14 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 3137 10 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 2976 6 0 0
t$perf-norm:sp_pay_to_supplier 5751 5751 0 0.00 0 12419 12 0 0
t$perf-norm:sp_reserve_write_off 9598 9598 0 0.00 0 23341 12 0 0
t$perf-norm:sp_supplier_invoice 5358 5358 0 0.00 0 2254 13 0 0
t$perf-norm:sp_supplier_order 5357 5357 0 0.00 0 2452 13 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 23685 202 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2248 37 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 4093 17 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 1981 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 5 0 0
v_add_invoice_to_stock 9964 9762 202 2.02 0 5665 17 0 202
v_all_customers 36134 36134 0 0.00 0 85 1 0 0
v_all_suppliers 29568 29568 0 0.00 0 18 1 0 0
v_all_wares 40086 40086 0 0.00 0 172 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 1245 7 0 0
v_cancel_client_order 3422 3422 0 0.00 0 2422 7 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 1217 6 0 0
v_cancel_customer_reserve 1220 1220 0 0.00 0 554 4 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 695 5 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 3102 21 0 20
v_cancel_supplier_order 786 786 0 0.00 0 2671 11 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5257 15 0 0
v_max_id_clo_ord 5351 5351 0 0.00 0 288 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 6642 37 0 0
v_max_id_ord_sup 5335 5335 0 0.00 0 8331 11 0 0
v_max_non_paid_invoice 1653 1653 0 0.00 0 1272 3 0 0
v_max_non_paid_realizn 3694 3694 0 0.00 0 2252 4 0 0
v_min_id_clo_ord 5351 5351 0 0.00 0 4962 11 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1059 5 0 0
v_min_id_ord_sup 5335 5335 0 0.00 0 4035 6 0 0
v_min_non_paid_invoice 5751 5751 0 0.00 0 7651 18 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 14014 24 0 0
v_our_firm 10346 10346 0 0.00 0 20 1 0 0
v_reserve_write_off 19196 19196 0 0.00 0 4260 6 0 0
x_lock_depdocs_on_canc_invoice 10918 10918 0 0.00 0 10937 113 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 6649 336 0 0
x_make_qstorno_1000_1200 5357 5357 0 0.00 2 48782 1326 0 0
x_make_qstorno_1200_2000 5358 5358 0 0.00 4 105746 3557 0 0
x_make_qstorno_2100_3300 449908 449908 0 0.00 0 102080 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2020 07:28:13 to 28-12-2020 07:28:17.

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:56:57 3 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:58 33254 8192000000 9728089344 10022719488 804664912 8.944 100 91 183 0 834210304 7467648 22077744
04:59:34 24937 8192000000 9762714608 10069696512 833651600 9.236 100 82 164 0 866172784 13116416 19404768
05:01:08 18681 8192000000 9808777024 10126995456 871193472 9.612 100 86 172 0 918876592 27740096 19943024
05:02:51 8796 8192000000 9895835232 10232406016 941438752 10.307 100 72 143 1 1000630320 44033296 15158272
05:04:08 6473 8192000000 9940417120 10290683904 987431936 10.757 100 81 161 1 1056630608 52693040 16505632
05:05:21 3478 8192000000 9996562592 10354745344 1014475536 11.019 100 90 179 2 1118775536 86163776 18136224
05:06:26 2058 8192000000 10017333344 10382934016 1032399024 11.192 100 87 173 4 1130254448 79401744 18453680
05:07:30 1989 8192000000 10024897072 10393968640 1056416992 11.422 100 95 190 2 1143839056 68276848 19145216
05:08:33 19536 8192000000 10003646496 10382364672 1067122752 11.525 100 71 144 1 1123040608 40297040 15620816
05:09:53 5948 8192000000 9982273728 10379341824 1073705744 11.587 100 49 99 3 1096567488 12586384 10275360
05:11:04 6521 8192000000 10004149968 10400665600 1082873680 11.675 100 27 54 2 1120371104 32119376 5378048
05:12:11 5562 8192000000 9999637248 10391425024 1093231136 11.773 100 29 56 1 1118510288 19682272 5596880
05:13:20 75055 8192000000 10001957968 10424287232 1092579104 11.767 100 33 65 3 1122557312 23700736 6277472
05:15:35 9253 8192000000 10004877184 10418049024 1097929232 11.818 100 17 34 0 1122033216 20165936 3938048
05:16:46 10035 8192000000 10006112176 10412380160 1049005440 11.351 96 22 44 3 1074091840 21006496 4079904
05:17:57 5601 8192000000 9985234560 10375946240 1054483712 11.404 98 44 88 1 1088138768 23875840 9779216
05:19:13 17374 8192000000 9973635088 10374803456 1030185936 11.170 97 23 46 0 1056094560 21357600 4551024
05:20:33 4689 8192000000 9930777936 10304421888 996609248 10.846 98 41 82 0 1020189344 14137840 9442256
05:21:39 6271 8192000000 9928794608 10300223488 987063056 10.753 97 42 83 1 1012883744 17432128 8388560
05:22:46 9515 8192000000 9954454256 10346098688 1033564128 11.203 100 43 86 1 1060467088 16661760 10241200
05:23:58 46129 8192000000 9979368608 10379759616 1046242688 11.325 100 32 64 0 1082261888 29710512 6308688
05:25:46 5876 8192000000 9973697344 10364497920 1055176224 11.410 100 47 94 2 1084189360 18459712 10553424
05:26:55 49420 8192000000 10012191328 10404458496 1087317680 11.717 100 42 83 6 1116109696 19741232 9050784
05:28:48 61581 8192000000 10065951136 10476511232 1108800960 11.921 100 49 94 2 1182484944 64028352 9655632
05:30:55 56554 8192000000 10120852656 10531848192 1127224384 12.095 100 43 86 4 1236210032 100569616 8416032
05:33:03 3988 8192000000 10155357152 10555793408 1145079664 12.263 100 69 134 3 1255619808 95822704 14717440
05:34:09 25281 8192000000 10205766384 10627747840 1152156400 12.330 100 56 110 4 1315973600 150532704 13284496
05:35:43 6604 8192000000 10268367744 10682306560 1165692112 12.457 100 61 120 0 1372845424 193666144 13487168
05:37:00 5081 8192000000 10255813440 10668421120 1169427088 12.491 100 73 145 3 1363986944 177377312 17182544
05:38:09 5154 8192000000 10270286256 10684325888 1172557600 12.521 100 64 124 0 1372981456 186888512 13535344
05:39:24 7617 8192000000 10286699584 10708832256 1171612240 12.512 100 51 103 0 1401211632 217414032 12185360
05:40:39 2822 8192000000 10309019376 10724446208 1161943888 12.421 100 85 168 1 1408961456 227451680 19565888
05:41:46 9336 8192000000 10319429696 10733899776 1154151904 12.348 99 57 112 3 1416430672 248946720 13332048
05:43:03 4201 8192000000 10332431904 10743345152 1170016064 12.497 100 54 108 0 1434562896 251798400 12748432
05:44:14 4511 8192000000 10423731488 10845896704 1180598128 12.596 100 58 116 1 1519986400 325709936 13678336
05:45:34 5990 8192000000 10345469728 10764886016 1156626192 12.372 100 40 80 2 1448724256 282714320 9383744
05:46:49 14647 8192000000 10387213184 10812801024 1151723040 12.326 99 41 79 1 1479725456 318063200 9939216
05:48:08 1533 8192000000 10431694368 10835623936 1143904304 12.252 100 74 148 1 1522986064 361086096 17995664
05:49:13 15601 8192000000 10421073008 10862370816 1122838336 12.054 98 34 68 0 1506488992 374260768 9389888
05:50:30 17905 8192000000 10423923584 10858418176 1117964496 12.008 99 31 62 0 1506080816 379558832 8557488
05:52:02 14065 8192000000 10428450496 10887888896 1108242240 11.916 99 22 44 1 1549135520 434044816 6848464
05:54:00 16641 8192000000 10044080320 10475905024 1103833440 11.874 99 22 43 1 1131875728 23046592 4995696
05:55:23 33172 8192000000 10074587024 10527903744 1103758816 11.873 99 45 91 1 1178606384 65779792 9067776
05:57:05 155240 8192000000 10051601152 10493829120 1104126864 11.877 98 21 42 1 1160574128 52398400 4048864
06:00:41 11511 8192000000 10061381632 10485833728 1112419008 11.955 99 39 70 4 1158470736 38758736 7292992
06:01:54 34237 8192000000 10100992800 10521493504 1130395424 12.125 100 30 59 0 1181297024 44361968 6539632
06:03:34 152433 8192000000 10175270128 10615926784 1115951152 11.989 98 27 54 2 1220133568 98785568 5396848
06:07:07 37270 8192000000 10136044288 10599079936 1131051568 12.131 99 22 44 1 1250361488 114050464 5259456
06:08:45 93041 8192000000 10080362704 10593140736 1112132464 11.953 98 11 22 2 1191060752 76496928 2431360
06:11:19 91397 8192000000 10092684800 10605998080 1137787296 12.195 100 22 44 1 1247845200 104299984 5757920
06:13:52 42072 8192000000 10153612288 10588758016 1147469008 12.286 100 29 58 1 1226762448 73577360 5716080
06:15:35 90818 8192000000 10113824336 10573987840 1127648688 12.099 98 13 26 0 1210598016 79798112 3151216
06:18:09 50969 8192000000 10104554064 10554802176 1129021360 12.112 98 29 58 1 1175625136 40901152 5702624
06:20:09 103035 8192000000 10058446032 10517426176 1137339232 12.190 99 12 24 1 1189329680 49430496 2559952
06:23:05 42298 8192000000 10054307616 10500542464 1118014496 12.008 98 5 10 0 1145702176 26833520 854160
06:24:54 64413 8192000000 10053049872 10519773184 1116132816 11.990 98 17 35 2 1159716016 39761776 3821424
06:27:00 91553 8192000000 10070908896 10512396288 1066080608 11.515 93 19 38 0 1107665296 38029264 3555424
06:29:40 87603 8192000000 10096233168 10605600768 1139645760 12.212 100 18 36 0 1219250816 75251072 4353984
06:32:08 32008 8192000000 10098889424 10524741632 1127098656 12.094 99 29 58 1 1169017040 36383376 5535008
06:33:48 51259 8192000000 10093210928 10527129600 1088037440 11.724 95 21 42 1 1147294368 54840112 4416816
06:36:24 21983 8192000000 10105339696 10524508160 1091895376 11.761 96 48 92 2 1155585616 53576528 10113712
06:37:49 64406 8192000000 10106546240 10547036160 1086591312 11.710 96 31 62 0 1152925168 59962256 6371600
06:40:22 68705 8192000000 10035030592 10484101120 1064462608 11.499 94 22 43 1 1108792464 39896032 4433824
06:42:31 91227 8192000000 10093126416 10535436288 1115432176 11.984 99 16 32 1 1183419840 64452944 3534720
06:45:07 38370 8192000000 10042357152 10520829952 1102425872 11.861 98 11 22 0 1134383456 29247376 2710208
06:46:46 49404 8192000000 10067694848 10512101376 1102806128 11.864 98 6 12 1 1136246064 32180080 1259856
06:48:36 66372 8192000000 10050010112 10512863232 1101849824 11.855 98 12 23 2 1148379952 43480736 3049392
06:51:01 28985 8192000000 10068028160 10497232896 1107360832 11.907 98 32 64 3 1142194912 27867408 6966672
06:52:30 32376 8192000000 10122870160 10558369792 1126792592 12.091 99 29 58 3 1210456464 77268288 6395584
06:54:07 87420 8192000000 10195521952 10686345216 1114658768 11.977 97 18 36 0 1276527296 157586720 4281808
06:56:54 22951 8192000000 10077352784 10511663104 1109133968 11.924 97 35 70 0 1143982720 27500000 7348752
06:58:26 82757 8192000000 10103539808 10569383936 1127392064 12.097 99 20 40 0 1200930576 69763472 3775040
07:00:49 44135 8192000000 10105410864 10538487808 1130436880 12.125 99 23 47 0 1182277152 47174320 4665952
07:02:38 140949 8192000000 10099075040 10543001600 1147721568 12.288 100 18 36 0 1191588400 39977872 3888960
07:06:07 40705 8192000000 10070867232 10556444672 1143860384 12.252 100 3 6 0 1173881568 29606240 414944
07:07:48 47709 8192000000 10111063968 10556088320 1144565024 12.258 99 16 31 3 1191420432 43216464 3638944
07:09:44 32012 8192000000 10114452880 10560110592 1151723808 12.326 100 19 38 2 1198669936 43322416 3623712
07:11:27 55531 8192000000 10100140080 10570571776 1128241024 12.105 98 22 44 1 1185783408 52692672 4849712
07:13:23 37546 8192000000 10119698320 10547679232 1130274976 12.124 98 27 54 1 1176497648 40907632 5315040
07:15:06 50376 8192000000 10085741712 10523762688 1119142720 12.019 98 25 48 3 1171324656 47476496 4705440
07:16:59 59730 8192000000 10106397696 10564440064 1129790288 12.119 99 32 64 0 1170998720 34871600 6336832
07:19:27 83000 8192000000 10067593968 10552836096 1122702688 12.053 99 10 20 0 1184007472 59075168 2229616
07:22:10 85584 8192000000 10114063728 10576449536 1113888112 11.969 98 10 20 0 1168850304 52843232 2118960
07:24:42 139035 8192000000 9208541568 9517383680 310793936 3.655 28 2 4 0 340236928 29118320 324672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-12-2020 07:28:19 to 28-12-2020 07:28:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2020 07:28:34 to 28-12-2020 07:28:36.

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          233194
MON$OLDEST_ACTIVE               233195
MON$OLDEST_SNAPSHOT             233195
MON$NEXT_TRANSACTION            233195
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                       2083040
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             799
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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'

28.12.20 07:28:37. 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
2020-12-28 07:28:40 No severe PSQL-related problems occured
28.12.20 07:28:41. End of report.