Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.01.2021 07:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16504152 kB
MemAvailable 31178424 kB
Buffers 0 kB
Cached 15054852 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-23 07:36:17
DTS_END                         2021-01-23 07:36:17.8920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47580
TRN_ID                          236847
ATT_ID                          639
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: 9021.99 22829 1082639
customer order: creation 170.96 370 20515
customer order: refuse 14.98 313 1797
order to supplier: creation 45.67 1250 5480
order to supplier: removal 3.60 101074 432
invoice (draft): creation 46.50 3401 5580
invoice (draft): removal 51.23 870 6148
invoice accept: apply 41.68 17959 5002
invoice accept: cancel 46.06 825 5527
customer reserve: creation 3858.37 175 463004
customer reserve: removal 4454.85 65 534582
realization accept: apply 83.66 161 10039
realization accept: cancel 74.72 128 8967
payment from customer: creation 59.16 101 7099
payment from customer: removal 5.93 76 712
payment to supplier: creation 49.96 108 5995
payment to supplier: removal 3.73 63 447
service: total inventory turnovers 4.11 2939 493
service: total monetary turnovers 5.92 519 710
service: aggregate perf. data 0.88 16298 105
service: refresh index statistics 0.04 309881 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-01-2021 07:37:31 to 23-01-2021 07:37:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5455
TEST_TIME 2 7167
TEST_TIME 3 6008
TEST_TIME 4 5667
TEST_TIME 5 5699
TEST_TIME 6 5671
TEST_TIME 7 5595
TEST_TIME 8 6097
TEST_TIME 9 5961
TEST_TIME 10 6287
TEST_TIME 11 6219
TEST_TIME 12 6441
TEST_TIME 13 6821
TEST_TIME 14 7101
TEST_TIME 15 7239
TEST_TIME 16 7341
TEST_TIME 17 7853
TEST_TIME 18 8062
TEST_TIME 19 8362
TEST_TIME 20 8500
TEST_TIME 21 8881
TEST_TIME 22 8859
TEST_TIME 23 9221
TEST_TIME 24 9448
TEST_TIME 25 9739
TEST_TIME 26 10919
TEST_TIME 27 11499
TEST_TIME 28 11319
TEST_TIME 29 11197
TEST_TIME 30 11200
TEST_TIME 31 11320
TEST_TIME 32 11214
TEST_TIME 33 11186
TEST_TIME 34 10994
TEST_TIME 35 10747
TEST_TIME 36 10492
TEST_TIME 37 10417
TEST_TIME 38 10322
TEST_TIME 39 10224
TEST_TIME 40 10302
TEST_TIME 41 10286
TEST_TIME 42 10176
TEST_TIME 43 10066
TEST_TIME 44 10080
TEST_TIME 45 9990
TEST_TIME 46 9915
TEST_TIME 47 9940
TEST_TIME 48 9923
TEST_TIME 49 9889
TEST_TIME 50 9908
TEST_TIME 51 9872
TEST_TIME 52 9945
TEST_TIME 53 9996
TEST_TIME 54 10029
TEST_TIME 55 9975
TEST_TIME 56 10015
TEST_TIME 57 9990
TEST_TIME 58 10082
TEST_TIME 59 10060
TEST_TIME 60 10138
TEST_TIME 61 9992
TEST_TIME 62 9910
TEST_TIME 63 9807
TEST_TIME 64 9743
TEST_TIME 65 9647
TEST_TIME 66 9582
TEST_TIME 67 9577
TEST_TIME 68 9648
TEST_TIME 69 9612
TEST_TIME 70 9599
TEST_TIME 71 9548
TEST_TIME 72 9592
TEST_TIME 73 9557
TEST_TIME 74 9611
TEST_TIME 75 9609
TEST_TIME 76 9560
TEST_TIME 77 9557
TEST_TIME 78 9551
TEST_TIME 79 9521
TEST_TIME 80 9433
TEST_TIME 81 9388
TEST_TIME 82 9346
TEST_TIME 83 9315
TEST_TIME 84 9333
TEST_TIME 85 9263
TEST_TIME 86 9322
TEST_TIME 87 9297
TEST_TIME 88 9410
TEST_TIME 89 9390
TEST_TIME 90 9374
TEST_TIME 91 9378
TEST_TIME 92 9410
TEST_TIME 93 9425
TEST_TIME 94 9456
TEST_TIME 95 9462
TEST_TIME 96 9408
TEST_TIME 97 9313
TEST_TIME 98 9264
TEST_TIME 99 9213
TEST_TIME 100 9151
TEST_TIME 101 9155
TEST_TIME 102 9164
TEST_TIME 103 9150
TEST_TIME 104 9165
TEST_TIME 105 9124
TEST_TIME 106 9155
TEST_TIME 107 9159
TEST_TIME 108 9181
TEST_TIME 109 9159
TEST_TIME 110 9177
TEST_TIME 111 9174
TEST_TIME 112 9187
TEST_TIME 113 9232
TEST_TIME 114 9158
TEST_TIME 115 9113
TEST_TIME 116 9090
TEST_TIME 117 9098
TEST_TIME 118 9083
TEST_TIME 119 9042
TEST_TIME 120 9029

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-01-2021 07:37:35 to 23-01-2021 07:37:39.

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 1509328 1509328 0 0.00 0 18574 7 0 0
doc_list_biud 1509327 1509327 0 0.00 0 73099 29 0 0
sp_add_invoice_to_stock 5094 5002 92 1.80 33 291560 17960 0 92
sp_cancel_adding_invoice 5527 5527 0 0.00 4 53371 826 0 0
sp_cancel_client_order 1797 1797 0 0.00 4 10536 313 0 0
sp_cancel_customer_reserve 534582 534582 0 0.00 1 73101 65 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 5815 77 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 1940 63 0 0
sp_cancel_supplier_invoice 6151 6148 3 0.04 2 15543 870 0 3
sp_cancel_supplier_order 432 432 0 0.00 12 1515158 101075 0 0
sp_cancel_write_off 8967 8967 0 0.00 1 23207 128 0 0
sp_client_order 20515 20515 0 0.00 4 72501 371 0 0
sp_customer_reserve 463004 463004 0 0.00 0 38121 176 0 0
sp_fill_shopping_cart 31563 31563 0 0.00 1 13717 39 0 0
sp_fill_shopping_cart_clo_res 462993 462993 0 0.00 0 25982 17 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 42052 378 0 0
sp_kill_cost_storno 15653 15653 0 0.00 0 7107 53 0 0
sp_kill_qstorno_ret_qs2qd 1084711 1084711 0 0.00 0 73095 35 0 0
sp_kill_qty_storno 572482 572482 0 0.00 0 73098 76 0 0
sp_lock_selected_doc 21014 21014 0 0.00 0 8435 7 0 0
sp_make_cost_storno 28123 28123 0 0.00 0 13476 48 0 0
sp_multiply_rows_for_pdistr 28123 28123 0 0.00 0 8589 27 0 0
sp_multiply_rows_for_qdistr 20515 20515 0 0.00 0 72492 208 0 0
sp_pay_from_customer 7099 7099 0 0.00 4 14734 102 0 0
sp_pay_to_supplier 5995 5995 0 0.00 5 14071 109 0 0
sp_payment_common 13093 13093 0 0.00 1 5675 18 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 10529 275 0 0
sp_qd_handle_on_invoice_upd_sts 10517 10517 0 0.00 0 34960 357 0 0
sp_qd_handle_on_reserve_upd_sts 10039 10039 0 0.00 0 17187 49 0 0
sp_reserve_write_off 10039 10039 0 0.00 6 17217 161 0 0
sp_supplier_invoice 5580 5580 0 0.00 6 143040 3401 0 0
sp_supplier_order 5480 5480 0 0.00 6 18093 1251 0 0
srv_aggregate_perf_data 105 105 0 0.00 2675 140785 16299 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1045 168062 47029 0 0
srv_find_qd_qs_mism 1033784 1033784 0 0.00 0 18573 6 0 0
srv_make_invnt_saldo 493 493 0 0.00 50 125059 2939 0 0
srv_make_money_saldo 710 710 0 0.00 1 17607 519 0 0
srv_recalc_idx_stat 5 5 0 0.00 197671 514730 309882 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5002 5002 0 0.00 0 21129 167 0 0
t$perf-norm:sp_cancel_adding_invoice 456 456 0 0.00 0 7195 125 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 9050 16 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 1072 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 7312 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 473 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 3652 24 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 18381 1330 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 1074 5 0 0
t$perf-norm:sp_client_order 20516 20516 0 0.00 0 20801 18 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 1474 6 0 0
t$perf-norm:sp_pay_from_customer 7099 7099 0 0.00 0 5123 7 0 0
t$perf-norm:sp_pay_to_supplier 5995 5995 0 0.00 0 5973 10 0 0
t$perf-norm:sp_reserve_write_off 10039 10039 0 0.00 0 5143 10 0 0
t$perf-norm:sp_supplier_invoice 5580 5580 0 0.00 0 12454 17 0 0
t$perf-norm:sp_supplier_order 5480 5480 0 0.00 0 3927 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 470 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 159 4 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 5067 16 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 2275 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 21 6 0 0
v_add_invoice_to_stock 10162 9978 184 1.81 0 5724 13 0 184
v_all_customers 36884 36884 0 0.00 0 18 1 0 0
v_all_suppliers 30660 30660 0 0.00 0 66 1 0 0
v_all_wares 41026 41026 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 912 912 0 0.00 0 346 6 0 0
v_cancel_client_order 3594 3594 0 0.00 0 3535 4 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1723 8 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 453 3 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 668 4 0 0
v_cancel_supplier_invoice 900 894 6 0.66 0 5150 16 0 6
v_cancel_supplier_order 864 864 0 0.00 0 202 3 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2926 14 0 0
v_max_id_clo_ord 5475 5475 0 0.00 0 380 1 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 1367 9 0 0
v_max_id_ord_sup 5574 5574 0 0.00 0 4457 6 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 643 2 0 0
v_max_non_paid_realizn 3974 3974 0 0.00 0 4031 3 0 0
v_min_id_clo_ord 5475 5475 0 0.00 0 3640 6 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 1729 5 0 0
v_min_id_ord_sup 5574 5574 0 0.00 0 6090 8 0 0
v_min_non_paid_invoice 5995 5995 0 0.00 0 4002 10 0 0
v_min_non_paid_realizn 7098 7098 0 0.00 0 4211 10 0 0
v_our_firm 10390 10390 0 0.00 0 52 1 0 0
v_reserve_write_off 20078 20078 0 0.00 0 1776 5 0 0
x_lock_depdocs_on_canc_invoice 11228 11228 0 0.00 0 24445 120 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 7471 358 0 0
x_make_qstorno_1000_1200 5480 5480 0 0.00 2 17991 1177 0 0
x_make_qstorno_1200_2000 5580 5580 0 0.00 3 143017 3258 0 0
x_make_qstorno_2100_3300 463004 463004 0 0.00 0 38119 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-01-2021 07:37:40 to 23-01-2021 07:37:44.

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
05:06:21 8 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
05:07:22 26329 8192000000 9717522592 10010345472 791245312 8.808 100 90 180 0 821551472 8393104 21913056
05:09:00 20598 8192000000 9759941184 10063527936 834140048 9.241 100 87 174 1 868344800 13573280 20631472
05:10:26 22725 8192000000 9815897808 10132205568 864314576 9.543 100 83 166 1 915470144 31529232 19626336
05:11:58 13214 8192000000 9869479968 10202890240 905876512 9.957 100 72 143 1 971550464 49622752 16051200
05:13:18 9788 8192000000 9919638576 10267033600 956329392 10.453 100 74 148 0 1032385216 60889872 15165952
05:14:30 6262 8192000000 9972388304 10331533312 995563568 10.835 100 80 158 2 1093101808 81276832 16261408
05:15:38 3846 8192000000 9992918192 10358337536 1022638928 11.097 100 81 162 1 1111959808 71893328 17427552
05:16:43 1954 8192000000 10021582512 10386857984 1052814496 11.388 100 90 181 1 1131004064 59576464 18613104
05:17:46 1314 8192000000 10045757920 10420514816 1064767760 11.502 100 82 164 1 1152589536 71002192 16819584
05:18:50 32396 8192000000 9998575168 10375065600 1076406096 11.613 100 54 107 2 1095752464 7589840 11756528
05:20:23 4973 8192000000 9991523776 10389864448 1081417632 11.661 100 32 64 0 1111982000 23736512 6827856
05:21:31 8047 8192000000 10014318416 10415300608 1087750160 11.721 100 40 81 2 1116059168 19791344 8517664
05:22:40 5753 8192000000 10011394560 10408292352 1094812928 11.788 100 34 67 1 1117283392 15515408 6955056
05:23:49 62129 8192000000 10002645856 10400894976 1090362768 11.746 99 34 68 1 1118417552 20072416 7982368
05:25:52 9434 8192000000 10029643264 10431918080 1104946064 11.885 100 36 72 3 1136522672 24177952 7398656
05:27:03 8618 8192000000 10004731536 10406731776 1083024800 11.676 99 23 46 5 1102481136 15145440 4310896
05:28:15 7215 8192000000 9955231472 10346500096 995273584 10.833 94 25 49 3 1017538400 17196368 5068448
05:29:22 33275 8192000000 9919593536 10305220608 969663776 10.583 92 26 53 1 986928128 11385504 5878848
05:30:59 7083 8192000000 9929796880 10315079680 1001314928 10.891 98 33 64 2 1035860384 27358784 7186672
05:32:08 8466 8192000000 9958186912 10358784000 1017773360 11.051 98 45 89 4 1048349040 19841520 10734160
05:33:18 5134 8192000000 9972104560 10356465664 1048615440 11.347 100 25 47 2 1087226416 33665568 4945408
05:34:25 56633 8192000000 9976236944 10382659584 1060525984 11.462 100 23 46 2 1082350704 16820480 5004240
05:36:23 8106 8192000000 9988292608 10384650240 1070405584 11.556 100 51 99 0 1097292960 16511408 10375968
05:37:38 28030 8192000000 10056289520 10451492864 1086183056 11.706 100 53 106 1 1161601696 64830288 10588352
05:39:15 47523 8192000000 10097571088 10506424320 1113491296 11.965 100 42 84 0 1205904480 83948400 8464784
05:41:06 33478 8192000000 10147562688 10557353984 1127385424 12.097 100 40 82 2 1246819152 110424800 9008928
05:42:45 11026 8192000000 10159733632 10565771264 1141164240 12.226 100 50 100 1 1261991040 109859008 10967792
05:44:00 11064 8192000000 10201380352 10608717824 1148907040 12.299 100 54 106 3 1302684848 141736912 12040896
05:45:18 11735 8192000000 10237631664 10646863872 1163685536 12.438 100 70 137 2 1338768768 159779344 15303888
05:46:37 4335 8192000000 10289747312 10703151104 1179930960 12.590 100 63 126 1 1396003360 201901280 14171120
05:47:45 2973 8192000000 10298676064 10706096128 1176280384 12.555 100 70 137 0 1396050304 204910400 14859520
05:48:52 8136 8192000000 10321238000 10754031616 1184106256 12.628 100 74 148 1 1436961392 235957456 16897680
05:50:06 6579 8192000000 10327850176 10737614848 1168918192 12.487 99 65 130 0 1429778960 246303728 14557040
05:51:17 2605 8192000000 10352757456 10774466560 1176126768 12.554 100 82 163 1 1453272688 258492992 18652928
05:52:24 3924 8192000000 10384181680 10798284800 1160405408 12.407 99 68 136 0 1486082720 309550320 16126992
05:53:35 1040 8192000000 10392932240 10791346176 1166412336 12.463 100 97 192 2 1491757408 303196752 22148320
05:54:40 10686 8192000000 10384462528 10823938048 1165269952 12.453 100 38 76 0 1479921232 304853552 9797728
05:55:55 7206 8192000000 10383147376 10798645248 1137680288 12.194 98 43 86 2 1461732176 313547696 10504192
05:57:03 5508 8192000000 10409876944 10828619776 1137594320 12.193 99 50 98 0 1499696768 350044000 12058448
05:58:11 12303 8192000000 10388536192 10813165568 1132551968 12.145 99 30 60 1 1481426576 340851328 8023280
05:59:32 11903 8192000000 10399788048 10889003008 1135125088 12.170 100 34 66 1 1496982064 353089888 8767088
06:00:45 11560 8192000000 10366857600 10867027968 1124129104 12.066 99 22 44 1 1478622432 348164816 6328512
06:01:58 29595 8192000000 10359807840 10827927552 1099471456 11.833 98 26 52 1 1457604864 350815920 7317488
06:03:35 61263 8192000000 10069202288 10525417472 1108697392 11.920 98 7 14 1 1154856912 45128624 1030896
06:05:36 26998 8192000000 10119626896 10540744704 1128339584 12.106 99 39 76 1 1195250656 58742720 8168352
06:07:08 90547 8192000000 10054635712 10510929920 1088416768 11.728 97 9 18 1 1141177616 51249728 1511120
06:09:48 168062 8192000000 10072530016 10511065088 1110899952 11.941 98 26 52 1 1158270304 41214496 6155856
06:14:11 32487 8192000000 10059880736 10502529024 1109363536 11.926 100 29 58 2 1174530224 58899184 6267504
06:15:49 20383 8192000000 10082726784 10546524160 1110452384 11.937 99 31 61 1 1206790912 89836992 6501536
06:17:31 47905 8192000000 10074180320 10537861120 1131505264 12.136 100 15 30 1 1177273136 42200112 3567760
06:19:29 48059 8192000000 10091282064 10568691712 1122807328 12.054 99 16 29 1 1182025792 55909024 3309440
06:21:28 63508 8192000000 10100904560 10569916416 1129045648 12.112 99 19 38 0 1207221264 73386928 4788688
06:23:40 50143 8192000000 10081343008 10567028736 1114181536 11.972 98 5 10 0 1176168032 61185232 801264
06:25:31 50342 8192000000 10067962944 10517688320 1136425088 12.182 99 20 41 0 1171900800 31355888 4119824
06:27:28 67454 8192000000 10109942080 10607128576 1137988272 12.197 99 17 31 4 1194697616 53449264 3260080
06:29:40 35241 8192000000 10107703392 10549456896 1137130848 12.189 99 28 57 1 1190130784 46723968 6275968
06:31:19 51442 8192000000 10085202256 10543771648 1114584576 11.976 98 38 76 0 1185920800 62699040 8637184
06:33:16 53025 8192000000 10109649536 10590605312 1139853904 12.214 100 23 46 0 1242867392 98079328 4934160
06:35:45 108036 8192000000 10055839792 10496233472 1071309616 11.565 95 25 50 0 1105663520 28820432 5533472
06:39:00 36889 8192000000 10064322400 10527105024 1100170528 11.839 98 15 30 2 1151784944 48329952 3284464
06:40:54 54495 8192000000 10077654944 10543296512 1111565616 11.947 99 12 24 0 1170590528 56485584 2539328
06:42:48 59574 8192000000 10105673712 10579324928 1117909600 12.007 99 8 16 0 1177464048 57681168 1873280
06:45:08 71206 8192000000 10067313104 10514878464 1096832240 11.808 98 19 38 0 1143513600 42355280 4326080
06:47:19 41403 8192000000 10088354000 10523381760 1114660288 11.977 100 22 43 1 1179261792 59974576 4626928
06:49:19 78764 8192000000 10070601424 10551595008 1111704080 11.949 99 22 44 0 1178156704 61739280 4713344
06:51:38 30445 8192000000 10072821248 10520973312 1123966016 12.064 100 36 71 0 1174836576 43833600 7036960
06:53:09 52840 8192000000 10100117424 10542448640 1120292464 12.030 99 40 80 0 1173005536 45242896 7470176
06:55:05 74336 8192000000 10106755920 10576293888 1140898928 12.224 100 42 83 0 1212932720 64027776 8006016
06:58:08 53759 8192000000 10110045824 10608717824 1138133616 12.198 99 7 14 0 1224507568 84715920 1658032
07:00:04 109636 8192000000 10097305360 10528518144 1064925184 11.504 93 15 30 1 1123926144 56068048 2932912
07:02:57 58569 8192000000 10118390496 10567512064 1123924160 12.064 99 22 44 0 1182799936 54180208 4695568
07:05:03 79920 8192000000 10085419168 10575077376 1117451920 12.003 98 9 18 0 1172629104 53156608 2020576
07:07:36 89407 8192000000 10069876880 10581794816 1095220800 11.792 96 8 16 0 1140256352 43575056 1460496
07:10:07 37752 8192000000 10079477248 10557702144 1137047328 12.188 100 8 16 0 1178181120 39693936 1439856
07:11:49 148180 8192000000 10077566704 10511765504 1100685888 11.844 97 18 36 1 1138270208 34163152 3421168
07:15:19 46743 8192000000 10085383728 10544582656 1127797040 12.101 100 5 10 0 1185162096 56252480 1112576
07:17:15 51252 8192000000 10063204096 10575175680 1117399008 12.002 99 11 22 0 1193498848 73450272 2649568
07:19:12 63709 8192000000 10071732784 10574823424 1121396272 12.040 99 19 38 2 1210256496 84222480 4637744
07:21:19 25195 8192000000 10071835792 10508394496 1100682064 11.844 98 27 54 2 1139722928 33223520 5817344
07:22:44 42066 8192000000 10083443888 10511048704 1114963776 11.979 99 46 92 1 1152613456 27983040 9666640
07:24:29 99390 8192000000 10106779472 10547752960 1082448048 11.671 95 15 28 1 1123036128 37903984 2684096
07:27:22 74815 8192000000 10104097680 10555727872 1115476640 11.984 98 31 62 1 1182973968 61052112 6445216
07:29:38 104992 8192000000 10080295472 10530136064 1120271088 12.030 99 11 21 0 1164172320 41661312 2239920
07:33:47 86159 8192000000 10090044304 10562854912 1097935344 11.818 98 8 16 1 1137976912 38769760 1271808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-01-2021 07:37:45 to 23-01-2021 07:38:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-01-2021 07:38:01 to 23-01-2021 07:38:03.

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          236127
MON$OLDEST_ACTIVE               237027
MON$OLDEST_SNAPSHOT             237027
MON$NEXT_TRANSACTION            237027
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                       2100480
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             807
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2337 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2337 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.2337 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Jan 23 07:36:23 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Jan 23 07:36:25 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Jan 23 07:36:25 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

23.01.21 07:38:04. 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-23 07:38:07 No severe PSQL-related problems occured
23.01.21 07:38:08. End of report.