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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-27 13:37:06
DTS_END                         2021-08-27 13:37:06.1670
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/55058
TRN_ID                          235286
ATT_ID                          680
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: 9264.44 22876 1111733
customer order: creation 170.19 385 20423
customer order: refuse 14.67 320 1760
order to supplier: creation 45.75 1431 5490
order to supplier: removal 3.34 111900 401
invoice (draft): creation 45.38 3674 5445
invoice (draft): removal 53.43 874 6411
invoice accept: apply 40.90 17580 4908
invoice accept: cancel 47.86 811 5743
customer reserve: creation 3823.43 170 458811
customer reserve: removal 4727.08 61 567250
realization accept: apply 83.21 193 9985
realization accept: cancel 80.38 111 9645
payment from customer: creation 59.71 115 7165
payment from customer: removal 5.83 143 699
payment to supplier: creation 49.02 149 5882
payment to supplier: removal 3.25 131 390
service: total inventory turnovers 3.96 3508 475
service: total monetary turnovers 6.18 472 742
service: aggregate perf. data 0.87 15106 104
service: refresh index statistics 0.03 300390 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-08-2021 13:39:24 to 27-08-2021 13:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7642
TEST_TIME 2 5597
TEST_TIME 3 4502
TEST_TIME 4 4953
TEST_TIME 5 5662
TEST_TIME 6 6615
TEST_TIME 7 6529
TEST_TIME 8 6588
TEST_TIME 9 7014
TEST_TIME 10 7022
TEST_TIME 11 6978
TEST_TIME 12 7673
TEST_TIME 13 7543
TEST_TIME 14 7555
TEST_TIME 15 7750
TEST_TIME 16 7834
TEST_TIME 17 8175
TEST_TIME 18 8538
TEST_TIME 19 8747
TEST_TIME 20 8738
TEST_TIME 21 8837
TEST_TIME 22 8648
TEST_TIME 23 8961
TEST_TIME 24 9369
TEST_TIME 25 10106
TEST_TIME 26 10143
TEST_TIME 27 10279
TEST_TIME 28 11370
TEST_TIME 29 11344
TEST_TIME 30 11098
TEST_TIME 31 11147
TEST_TIME 32 11058
TEST_TIME 33 11025
TEST_TIME 34 11124
TEST_TIME 35 10957
TEST_TIME 36 10948
TEST_TIME 37 10938
TEST_TIME 38 10822
TEST_TIME 39 10672
TEST_TIME 40 10597
TEST_TIME 41 10412
TEST_TIME 42 10346
TEST_TIME 43 10231
TEST_TIME 44 10132
TEST_TIME 45 10132
TEST_TIME 46 10232
TEST_TIME 47 10312
TEST_TIME 48 10233
TEST_TIME 49 10292
TEST_TIME 50 10153
TEST_TIME 51 10057
TEST_TIME 52 9913
TEST_TIME 53 10026
TEST_TIME 54 9965
TEST_TIME 55 9946
TEST_TIME 56 9892
TEST_TIME 57 9983
TEST_TIME 58 9949
TEST_TIME 59 9934
TEST_TIME 60 9904
TEST_TIME 61 9861
TEST_TIME 62 10004
TEST_TIME 63 10047
TEST_TIME 64 10037
TEST_TIME 65 10142
TEST_TIME 66 10113
TEST_TIME 67 10058
TEST_TIME 68 9943
TEST_TIME 69 9955
TEST_TIME 70 9883
TEST_TIME 71 9808
TEST_TIME 72 9744
TEST_TIME 73 9659
TEST_TIME 74 9678
TEST_TIME 75 9642
TEST_TIME 76 9569
TEST_TIME 77 9554
TEST_TIME 78 9528
TEST_TIME 79 9513
TEST_TIME 80 9535
TEST_TIME 81 9495
TEST_TIME 82 9475
TEST_TIME 83 9528
TEST_TIME 84 9487
TEST_TIME 85 9486
TEST_TIME 86 9566
TEST_TIME 87 9560
TEST_TIME 88 9614
TEST_TIME 89 9646
TEST_TIME 90 9638
TEST_TIME 91 9703
TEST_TIME 92 9610
TEST_TIME 93 9537
TEST_TIME 94 9565
TEST_TIME 95 9519
TEST_TIME 96 9477
TEST_TIME 97 9522
TEST_TIME 98 9472
TEST_TIME 99 9474
TEST_TIME 100 9436
TEST_TIME 101 9444
TEST_TIME 102 9507
TEST_TIME 103 9520
TEST_TIME 104 9563
TEST_TIME 105 9576
TEST_TIME 106 9579
TEST_TIME 107 9632
TEST_TIME 108 9589
TEST_TIME 109 9683
TEST_TIME 110 9606
TEST_TIME 111 9542
TEST_TIME 112 9510
TEST_TIME 113 9500
TEST_TIME 114 9470
TEST_TIME 115 9450
TEST_TIME 116 9416
TEST_TIME 117 9397
TEST_TIME 118 9382
TEST_TIME 119 9340
TEST_TIME 120 9269

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-08-2021 13:39:27 to 27-08-2021 13:39:31.

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 1536163 1536163 0 0.00 0 24904 6 0 0
doc_list_biud 1536163 1536163 0 0.00 0 36588 29 0 0
sp_add_invoice_to_stock 5038 4908 130 2.58 47 202290 17581 0 130
sp_cancel_adding_invoice 5743 5743 0 0.00 4 49576 811 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 17327 321 0 0
sp_cancel_customer_reserve 567250 567250 0 0.00 1 26690 62 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 10351 144 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 6507 132 0 0
sp_cancel_supplier_invoice 6423 6411 12 0.18 2 19924 875 0 12
sp_cancel_supplier_order 401 401 0 0.00 6 975675 111901 0 0
sp_cancel_write_off 9645 9645 0 0.00 1 13360 111 0 0
sp_client_order 20423 20423 0 0.00 4 27607 386 0 0
sp_customer_reserve 458811 458811 0 0.00 0 97253 170 0 0
sp_fill_shopping_cart 31341 31341 0 0.00 1 10921 44 0 0
sp_fill_shopping_cart_clo_res 458799 458799 0 0.00 0 12815 14 0 0
sp_get_clo_for_invoice 4896 4896 0 0.00 0 21193 354 0 0
sp_kill_cost_storno 16477 16477 0 0.00 0 7525 62 0 0
sp_kill_qstorno_ret_qs2qd 1150957 1150957 0 0.00 0 26681 33 0 0
sp_kill_qty_storno 606091 606091 0 0.00 0 36587 72 0 0
sp_lock_selected_doc 20543 20543 0 0.00 0 8757 5 0 0
sp_make_cost_storno 27928 27928 0 0.00 0 24803 62 0 0
sp_multiply_rows_for_pdistr 27928 27928 0 0.00 0 11260 33 0 0
sp_multiply_rows_for_qdistr 20423 20423 0 0.00 0 24929 192 0 0
sp_pay_from_customer 7165 7165 0 0.00 4 18571 115 0 0
sp_pay_to_supplier 5882 5882 0 0.00 5 13499 150 0 0
sp_payment_common 13047 13047 0 0.00 1 10857 28 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 17314 287 0 0
sp_qd_handle_on_invoice_upd_sts 10639 10639 0 0.00 0 36586 379 0 0
sp_qd_handle_on_reserve_upd_sts 9985 9985 0 0.00 0 17328 61 0 0
sp_reserve_write_off 9985 9985 0 0.00 7 23112 194 0 0
sp_supplier_invoice 5445 5445 0 0.00 6 104098 3674 0 0
sp_supplier_order 5490 5490 0 0.00 6 41713 1432 0 0
srv_aggregate_perf_data 104 104 0 0.00 3333 113813 15107 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 926 136749 45932 0 0
srv_find_qd_qs_mism 1064067 1064067 0 0.00 0 14194 5 0 0
srv_make_invnt_saldo 475 475 0 0.00 73 101070 3508 0 0
srv_make_money_saldo 742 742 0 0.00 1 11347 472 0 0
srv_recalc_idx_stat 4 4 0 0.00 137450 438859 300390 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 4908 4908 0 0.00 0 23757 184 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 5942 122 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 3999 14 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 5234 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 827 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 1760 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1063 18 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 19888 1848 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 10277 19 0 0
t$perf-norm:sp_client_order 20423 20423 0 0.00 0 8744 19 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 2383 8 0 0
t$perf-norm:sp_pay_from_customer 7165 7165 0 0.00 0 6439 9 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 10394 19 0 0
t$perf-norm:sp_reserve_write_off 9985 9985 0 0.00 0 5994 14 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 10898 18 0 0
t$perf-norm:sp_supplier_order 5490 5490 0 0.00 0 5612 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 389 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 475 23 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 3058 16 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 1997 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 6 0 0
v_add_invoice_to_stock 10052 9792 260 2.58 0 6538 19 0 260
v_all_customers 37052 37052 0 0.00 0 66 1 0 0
v_all_suppliers 30516 30516 0 0.00 0 15 1 0 0
v_all_wares 40846 40846 0 0.00 0 272 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 2404 17 0 0
v_cancel_client_order 3520 3520 0 0.00 0 3257 9 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 3786 13 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 3524 6 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 6490 16 0 0
v_cancel_supplier_invoice 838 814 24 2.86 0 2784 22 0 24
v_cancel_supplier_order 802 802 0 0.00 0 2095 6 0 0
v_cancel_write_off 1872 1872 0 0.00 0 1227 11 0 0
v_max_id_clo_ord 5490 5490 0 0.00 0 299 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 1721 5 0 0
v_max_id_ord_sup 5428 5428 0 0.00 0 5526 8 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 2105 4 0 0
v_max_non_paid_realizn 3911 3911 0 0.00 0 3586 3 0 0
v_min_id_clo_ord 5490 5490 0 0.00 0 9013 18 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 1396 5 0 0
v_min_id_ord_sup 5428 5428 0 0.00 0 9803 12 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 10854 26 0 0
v_min_non_paid_realizn 7165 7165 0 0.00 0 9235 17 0 0
v_our_firm 10302 10302 0 0.00 0 16 1 0 0
v_reserve_write_off 19970 19970 0 0.00 0 7659 7 0 0
x_lock_depdocs_on_canc_invoice 11747 11747 0 0.00 0 16182 115 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 15411 388 0 0
x_make_qstorno_1000_1200 5490 5490 0 0.00 2 41704 1337 0 0
x_make_qstorno_1200_2000 5445 5445 0 0.00 2 104063 3521 0 0
x_make_qstorno_2100_3300 458811 458811 0 0.00 0 97249 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-08-2021 13:39:32 to 27-08-2021 13:39:36.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 32950 8192000000 9717477744 10015793152 796220160 8.858 100 85 170 0 824460992 7787088 20453744
11:09:47 17245 8192000000 9760439424 10067316736 836167872 9.261 100 86 172 1 868606208 12102640 20335696
11:11:10 18199 8192000000 9807964576 10127196160 872865680 9.629 100 83 166 0 914101072 21644336 19591056
11:12:32 11081 8192000000 9860960064 10193215488 914111040 10.038 100 82 164 0 969665904 37886576 17668288
11:13:48 9569 8192000000 9922004592 10262773760 958798576 10.477 100 82 163 0 1034364064 58759920 16805568
11:15:00 4694 8192000000 9957630496 10313576448 989703696 10.779 100 82 163 0 1072139408 65992880 16442832
11:16:06 3212 8192000000 9987222240 10345365504 1017246976 11.045 100 84 167 0 1103684448 69549712 16887760
11:17:11 2288 8192000000 10006460464 10371493888 1044412432 11.307 100 93 184 1 1115722288 52548272 18761584
11:18:16 1667 8192000000 10035055152 10403180544 1061605376 11.472 100 96 190 0 1149372992 68764640 19002976
11:19:18 22648 8192000000 10006085680 10383499264 1067791840 11.531 100 64 129 0 1115220848 33449968 13979040
11:20:41 7961 8192000000 9994278112 10380136448 1077124848 11.620 100 31 61 1 1110965680 27289856 6550976
11:21:52 6112 8192000000 10012835088 10411827200 1086358816 11.708 100 43 88 0 1112102816 16536000 9208000
11:22:58 40499 8192000000 10019120032 10401951744 1090649072 11.749 100 78 156 1 1119596512 11363264 17584176
11:24:39 3518 8192000000 10008570128 10414981120 1092400320 11.765 100 30 59 0 1136334528 37130768 6803440
11:25:43 8835 8192000000 10014458272 10414411776 1100018128 11.838 100 28 56 0 1127805664 21892992 5894544
11:26:53 5872 8192000000 9985547152 10392342528 1084213568 11.688 100 21 42 0 1103246880 14379344 4653968
11:28:00 49518 8192000000 9983413920 10378743808 1032104672 11.189 96 36 71 1 1054282848 13774992 8403184
11:29:49 4503 8192000000 9958795408 10346487808 1046621840 11.328 100 30 59 1 1084465040 31088672 6754528
11:30:55 5572 8192000000 9929106672 10314772480 1009236912 10.968 98 35 72 1 1032692544 15151344 8304288
11:32:03 14568 8192000000 9944907232 10342477824 1011399040 10.989 98 32 64 3 1038004096 19527200 7077856
11:33:19 62014 8192000000 9944237600 10350186496 1032689840 11.194 100 27 54 0 1065887280 27920640 5276800
11:35:21 11064 8192000000 9960280608 10361864192 1042732480 11.291 99 46 92 2 1069458912 16453488 10272944
11:36:33 6586 8192000000 9972301120 10373455872 1059236272 11.449 100 42 84 1 1078650896 8414128 11000496
11:37:42 33890 8192000000 10040235456 10433208320 1092813744 11.769 100 51 102 0 1143250336 39801408 10635184
11:39:22 116794 8192000000 10100511776 10516721664 1113181792 11.963 100 29 58 0 1197376688 77487280 6707616
11:42:25 18605 8192000000 10135573232 10562023424 1119491216 12.022 100 60 120 0 1251508768 118489584 13527968
11:43:55 4666 8192000000 10142584608 10551222272 1141181712 12.227 100 66 127 3 1255217072 99851472 14183888
11:45:04 3435 8192000000 10206534640 10615672832 1152797504 12.336 100 66 131 0 1314118704 147645360 13675840
11:46:10 4787 8192000000 10214784224 10643460096 1160173056 12.405 100 47 93 0 1325194368 154779264 10242048
11:47:20 4243 8192000000 10244572480 10655891456 1143493024 12.248 99 70 140 1 1337583568 177106976 16983568
11:48:28 7807 8192000000 10256261264 10676183040 1165871280 12.458 100 59 114 2 1356156032 177085920 13198832
11:49:43 3900 8192000000 10281209040 10685747200 1158673456 12.391 99 82 162 1 1370928816 194632704 17622656
11:50:55 5918 8192000000 10329254704 10744135680 1183747440 12.625 100 67 133 2 1424058448 224977696 15333312
11:52:06 3476 8192000000 10348234928 10769186816 1179137568 12.582 100 86 170 0 1449401152 251121552 19142032
11:53:15 1584 8192000000 10372272352 10779127808 1175774656 12.551 100 82 164 3 1473637648 279790768 18072224
11:54:22 3793 8192000000 10399048560 10831142912 1166868976 12.468 100 84 168 0 1497900736 311138144 19893616
11:55:30 2088 8192000000 10379047920 10785075200 1152994208 12.338 100 80 158 1 1472666560 300535440 19136912
11:56:41 923 8192000000 10395135536 10829651968 1169329184 12.491 100 97 191 0 1486712960 295852880 21530896
11:57:46 14939 8192000000 10393472528 10847440896 1143092048 12.245 98 43 87 0 1491366016 337237920 11036048
11:59:11 4755 8192000000 10451923872 10867179520 1141371328 12.228 99 58 114 2 1539380720 384130656 13878736
12:00:21 4189 8192000000 10495674512 10957504512 1151264352 12.321 100 37 73 0 1606424720 444828544 10331824
12:01:37 26489 8192000000 10432403504 10872991744 1124037888 12.065 99 34 67 1 1555129040 421222112 9869040
12:03:20 25482 8192000000 10301321840 10783559680 1125906128 12.083 100 23 46 0 1417546576 286169488 5470960
12:05:20 51640 8192000000 10078315680 10554462208 1109732816 11.930 99 9 18 0 1202366048 90976128 1657104
12:07:15 26991 8192000000 10084404336 10500857856 1126130208 12.085 100 56 112 5 1168611952 31077200 11404544
12:08:46 55468 8192000000 10086905888 10534264832 1096253584 11.802 97 20 41 0 1146360784 46457680 3649520
12:10:42 90266 8192000000 10053817024 10491052032 1118390800 12.012 99 22 44 2 1148390576 25493024 4506752
12:13:21 45503 8192000000 10098153024 10541948928 1131950992 12.140 100 20 40 0 1192866608 57081936 3833680
12:15:08 136749 8192000000 10083580768 10530197504 1102632800 11.863 97 25 48 3 1166801616 58828176 5340640
12:18:46 86252 8192000000 10096520944 10539261952 1114657136 11.976 98 28 57 1 1163935712 42726736 6551840
12:21:15 27667 8192000000 10160362768 10595553280 1151186784 12.321 100 31 62 0 1260079824 101654032 7239008
12:22:48 47678 8192000000 10178882544 10655776768 1132447584 12.144 98 29 59 2 1272838000 134061280 6329136
12:24:36 46408 8192000000 10120628688 10603884544 1148199328 12.293 100 15 29 0 1233181520 81244768 3737424
12:26:26 45472 8192000000 10077781840 10527973376 1138846880 12.205 100 21 39 2 1184163632 41574944 3741808
12:28:14 92614 8192000000 10149163360 10622795776 1143465584 12.248 100 25 50 0 1248204544 98927200 5811760
12:30:52 63127 8192000000 10068344464 10529181696 1130646096 12.127 99 9 18 0 1175290112 42945488 1698528
12:32:58 56869 8192000000 10118985648 10584006656 1131134640 12.132 98 16 32 0 1183395888 48968672 3292576
12:34:56 48444 8192000000 10078823632 10531561472 1101651440 11.853 97 23 44 0 1141447280 35432256 4363584
12:36:44 45423 8192000000 10078029024 10522931200 1091609776 11.758 97 37 74 0 1134112480 35654384 6848320
12:38:33 74800 8192000000 10130599488 10582781952 1123965616 12.064 98 24 48 0 1191499696 62747536 4786544
12:40:51 95583 8192000000 10085928752 10586140672 1123050128 12.056 98 13 26 0 1180698240 54857440 2790672
12:43:27 81870 8192000000 10074563744 10524991488 1103632912 11.872 97 31 62 1 1154130944 44149504 6348528
12:46:08 38666 8192000000 10055659856 10552127488 1109604640 11.929 100 16 32 1 1189777408 76724992 3447776
12:49:19 73767 8192000000 10059391008 10504970240 1094716496 11.787 98 16 32 0 1141557680 43413680 3427504
12:51:33 72146 8192000000 10047893456 10490580992 1057009232 11.428 94 17 34 0 1090133840 29241232 3883376
12:53:46 51605 8192000000 10100801920 10556039168 1118493792 12.013 99 18 35 1 1185896432 63657696 3744944
12:55:41 50795 8192000000 10088653808 10544713728 1119496496 12.022 100 10 20 0 1188143248 66887712 1759040
12:58:35 53087 8192000000 10088341648 10537963520 1113987392 11.970 99 24 48 0 1176140960 57217296 4936272
13:00:33 28774 8192000000 10076497312 10518261760 1116263056 11.992 99 15 30 0 1153219136 33548560 3407520
13:02:09 67248 8192000000 10139937216 10578456576 1107960960 11.913 98 15 30 0 1174792608 63907440 2924208
13:04:28 25954 8192000000 10126794240 10574716928 1136754448 12.185 100 38 76 1 1201347216 56563648 8029120
13:05:55 27598 8192000000 10104073456 10554138624 1149586544 12.306 100 32 64 2 1210242224 54370128 6285552
13:07:27 79185 8192000000 10086915296 10567016448 1135317680 12.171 98 22 44 0 1196295600 56040672 4937248
13:09:47 92832 8192000000 10090559840 10560385024 1126715408 12.090 97 32 64 0 1157443552 24126592 6601552
13:12:30 34628 8192000000 10093555792 10618552320 1145522448 12.267 100 5 10 0 1204231232 57877520 831264
13:14:06 55960 8192000000 10089442944 10607116288 1130215824 12.123 98 13 26 0 1180571552 47870368 2485360
13:16:04 38413 8192000000 10102225088 10582052864 1123539744 12.060 98 35 70 0 1167323536 36428672 7355120
13:18:36 92701 8192000000 10129578864 10647965696 1130241472 12.124 98 10 20 2 1194609696 62208288 2159936
13:21:37 94097 8192000000 10081228256 10596589568 1095999872 11.800 96 10 20 0 1156639376 58821664 1817840
13:24:12 79130 8192000000 10065136032 10519810048 1098619184 11.825 97 15 28 2 1135459520 34222864 2617472
13:26:31 74818 8192000000 10065238240 10584956928 1095736192 11.797 98 17 34 1 1179646352 80471968 3438192
13:29:12 36621 8192000000 10053222736 10510544896 1078096832 11.629 96 22 44 0 1128190176 45910496 4182848
13:30:49 58598 8192000000 10066101744 10532954112 1119793728 12.025 100 13 26 1 1171803392 49310064 2699600
13:32:49 56484 8192000000 10063431216 10517454848 1087421008 11.718 96 20 40 0 1147398240 55884352 4092880
13:34:46 47378 8192000000 10037867376 10517110784 1087448416 11.718 97 13 26 1 1141666352 51094208 3123728
13:36:42 52572 8192000000 9181652016 9475174400 334228816 3.920 30 5 9 0 348902352 13801888 871648

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-08-2021 13:39:38 to 27-08-2021 13:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 27-08-2021 13:39:54 to 27-08-2021 13:39:56.

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          236501
MON$OLDEST_ACTIVE               236793
MON$OLDEST_SNAPSHOT             236793
MON$NEXT_TRANSACTION            236793
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                       2075616
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

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

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

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