Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2021 04:28
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 16525852 kB
MemAvailable 31299016 kB
Buffers 20 kB
Cached 15026400 kB
SwapCached 4864 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-06 04:26:54
DTS_END                         2021-01-06 04:26:54.8360
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/39404
TRN_ID                          232063
ATT_ID                          591
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: 8971.53 20476 1076584
customer order: creation 166.31 385 19957
customer order: refuse 13.65 312 1638
order to supplier: creation 46.22 1377 5546
order to supplier: removal 3.46 101103 415
invoice (draft): creation 45.47 3397 5456
invoice (draft): removal 50.69 870 6083
invoice accept: apply 39.98 17242 4798
invoice accept: cancel 46.42 885 5570
customer reserve: creation 3706.06 168 444727
customer reserve: removal 4572.46 60 548695
realization accept: apply 80.20 164 9624
realization accept: cancel 76.11 115 9133
payment from customer: creation 57.62 99 6914
payment from customer: removal 5.64 98 677
payment to supplier: creation 47.46 113 5695
payment to supplier: removal 3.33 78 399
service: total inventory turnovers 3.78 3235 454
service: total monetary turnovers 5.84 547 701
service: aggregate perf. data 0.81 14567 97
service: refresh index statistics 0.04 264702 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2021 04:28:37 to 06-01-2021 04:28:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3861
TEST_TIME 2 3314
TEST_TIME 3 4531
TEST_TIME 4 5311
TEST_TIME 5 5932
TEST_TIME 6 5890
TEST_TIME 7 5770
TEST_TIME 8 5796
TEST_TIME 9 6045
TEST_TIME 10 6626
TEST_TIME 11 6620
TEST_TIME 12 7052
TEST_TIME 13 7197
TEST_TIME 14 7429
TEST_TIME 15 7672
TEST_TIME 16 7937
TEST_TIME 17 8081
TEST_TIME 18 9220
TEST_TIME 19 9036
TEST_TIME 20 9613
TEST_TIME 21 9490
TEST_TIME 22 9548
TEST_TIME 23 10206
TEST_TIME 24 10111
TEST_TIME 25 10119
TEST_TIME 26 10425
TEST_TIME 27 10673
TEST_TIME 28 11074
TEST_TIME 29 11250
TEST_TIME 30 11358
TEST_TIME 31 11224
TEST_TIME 32 11324
TEST_TIME 33 11103
TEST_TIME 34 10853
TEST_TIME 35 10591
TEST_TIME 36 10439
TEST_TIME 37 10454
TEST_TIME 38 10353
TEST_TIME 39 10308
TEST_TIME 40 10166
TEST_TIME 41 10140
TEST_TIME 42 10056
TEST_TIME 43 10014
TEST_TIME 44 10092
TEST_TIME 45 9971
TEST_TIME 46 10106
TEST_TIME 47 10043
TEST_TIME 48 9961
TEST_TIME 49 9984
TEST_TIME 50 10132
TEST_TIME 51 10077
TEST_TIME 52 10046
TEST_TIME 53 10019
TEST_TIME 54 10122
TEST_TIME 55 10177
TEST_TIME 56 10147
TEST_TIME 57 10219
TEST_TIME 58 10095
TEST_TIME 59 9952
TEST_TIME 60 9894
TEST_TIME 61 9812
TEST_TIME 62 9705
TEST_TIME 63 9656
TEST_TIME 64 9627
TEST_TIME 65 9546
TEST_TIME 66 9496
TEST_TIME 67 9444
TEST_TIME 68 9394
TEST_TIME 69 9413
TEST_TIME 70 9580
TEST_TIME 71 9605
TEST_TIME 72 9578
TEST_TIME 73 9583
TEST_TIME 74 9555
TEST_TIME 75 9615
TEST_TIME 76 9618
TEST_TIME 77 9728
TEST_TIME 78 9692
TEST_TIME 79 9697
TEST_TIME 80 9675
TEST_TIME 81 9593
TEST_TIME 82 9479
TEST_TIME 83 9440
TEST_TIME 84 9396
TEST_TIME 85 9330
TEST_TIME 86 9265
TEST_TIME 87 9190
TEST_TIME 88 9243
TEST_TIME 89 9221
TEST_TIME 90 9182
TEST_TIME 91 9190
TEST_TIME 92 9153
TEST_TIME 93 9154
TEST_TIME 94 9249
TEST_TIME 95 9266
TEST_TIME 96 9302
TEST_TIME 97 9292
TEST_TIME 98 9307
TEST_TIME 99 9291
TEST_TIME 100 9359
TEST_TIME 101 9274
TEST_TIME 102 9209
TEST_TIME 103 9158
TEST_TIME 104 9106
TEST_TIME 105 9085
TEST_TIME 106 9032
TEST_TIME 107 9071
TEST_TIME 108 9069
TEST_TIME 109 9047
TEST_TIME 110 9047
TEST_TIME 111 9024
TEST_TIME 112 9074
TEST_TIME 113 9038
TEST_TIME 114 9093
TEST_TIME 115 9122
TEST_TIME 116 9162
TEST_TIME 117 9117
TEST_TIME 118 9054
TEST_TIME 119 9015
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2021 04:28:41 to 06-01-2021 04:28:44.

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 1489798 1489798 0 0.00 0 22691 6 0 0
doc_list_biud 1489797 1489797 0 0.00 0 35323 28 0 0
sp_add_invoice_to_stock 4933 4798 135 2.73 23 138589 17243 0 135
sp_cancel_adding_invoice 5570 5570 0 0.00 5 58999 886 0 0
sp_cancel_client_order 1638 1638 0 0.00 4 12423 313 0 0
sp_cancel_customer_reserve 548695 548695 0 0.00 1 25619 61 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 5469 99 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 4635 79 0 0
sp_cancel_supplier_invoice 6094 6083 11 0.18 3 35327 871 0 11
sp_cancel_supplier_order 415 415 0 0.00 7 1234009 101104 0 0
sp_cancel_write_off 9133 9133 0 0.00 1 6002 116 0 0
sp_client_order 19957 19957 0 0.00 4 35059 385 0 0
sp_customer_reserve 444727 444727 0 0.00 0 53099 168 0 0
sp_fill_shopping_cart 30954 30954 0 0.00 1 24365 41 0 0
sp_fill_shopping_cart_clo_res 444724 444724 0 0.00 0 23576 16 0 0
sp_get_clo_for_invoice 4794 4794 0 0.00 0 49993 396 0 0
sp_kill_cost_storno 15779 15779 0 0.00 0 11808 51 0 0
sp_kill_qstorno_ret_qs2qd 1113021 1113021 0 0.00 0 35321 33 0 0
sp_kill_qty_storno 585952 585952 0 0.00 0 35322 71 0 0
sp_lock_selected_doc 19977 19977 0 0.00 0 7393 5 0 0
sp_make_cost_storno 27027 27027 0 0.00 0 15777 50 0 0
sp_multiply_rows_for_pdistr 27027 27027 0 0.00 0 8149 27 0 0
sp_multiply_rows_for_qdistr 19956 19956 0 0.00 0 35048 230 0 0
sp_pay_from_customer 6914 6914 0 0.00 4 16660 100 0 0
sp_pay_to_supplier 5695 5695 0 0.00 5 16954 114 0 0
sp_payment_common 12609 12609 0 0.00 1 8151 24 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 12415 285 0 0
sp_qd_handle_on_invoice_upd_sts 10364 10364 0 0.00 0 18853 357 0 0
sp_qd_handle_on_reserve_upd_sts 9624 9624 0 0.00 0 10582 45 0 0
sp_reserve_write_off 9624 9624 0 0.00 7 15787 164 0 0
sp_supplier_invoice 5456 5456 0 0.00 6 96311 3398 0 0
sp_supplier_order 5546 5546 0 0.00 5 114628 1377 0 0
srv_aggregate_perf_data 97 97 0 0.00 3015 76639 14567 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1284 134867 45663 0 0
srv_find_qd_qs_mism 1031035 1031035 0 0.00 0 22690 5 0 0
srv_make_invnt_saldo 454 454 0 0.00 62 85639 3235 0 0
srv_make_money_saldo 701 701 0 0.00 1 17605 548 0 0
srv_recalc_idx_stat 5 5 0 0.00 95283 422989 264702 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4798 4798 0 0.00 0 8484 163 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 1903 64 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 2090 10 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 442 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 1058 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 156 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 598 14 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 27645 1546 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 687 4 0 0
t$perf-norm:sp_client_order 19957 19957 0 0.00 0 11578 18 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1111 6 0 0
t$perf-norm:sp_pay_from_customer 6914 6914 0 0.00 0 6994 9 0 0
t$perf-norm:sp_pay_to_supplier 5695 5695 0 0.00 0 4505 8 0 0
t$perf-norm:sp_reserve_write_off 9624 9624 0 0.00 0 17788 11 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 8166 20 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 8468 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 455 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 190 6 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 1039 17 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 641 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9858 9588 270 2.73 0 7796 19 0 270
v_all_customers 36622 36622 0 0.00 0 53 1 0 0
v_all_suppliers 30380 30380 0 0.00 0 66 1 0 0
v_all_wares 39912 39912 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1908 9 0 0
v_cancel_client_order 3276 3276 0 0.00 0 3971 7 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 4128 18 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 1992 6 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 3157 7 0 0
v_cancel_supplier_invoice 768 746 22 2.86 0 3941 23 0 22
v_cancel_supplier_order 830 830 0 0.00 0 3764 15 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2077 15 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 3131 2 0 0
v_max_id_clo_res 984 984 0 0.00 0 12035 27 0 0
v_max_id_ord_sup 5453 5453 0 0.00 0 5289 3 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 2610 3 0 0
v_max_non_paid_realizn 3615 3615 0 0.00 0 2743 4 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 4837 7 0 0
v_min_id_clo_res 984 984 0 0.00 0 2363 8 0 0
v_min_id_ord_sup 5453 5453 0 0.00 0 5455 8 0 0
v_min_non_paid_invoice 5695 5695 0 0.00 0 8009 15 0 0
v_min_non_paid_realizn 6914 6914 0 0.00 0 7860 18 0 0
v_our_firm 9888 9888 0 0.00 0 14 1 0 0
v_reserve_write_off 19246 19246 0 0.00 0 3806 7 0 0
x_lock_depdocs_on_canc_invoice 11280 11280 0 0.00 0 18698 112 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 11319 323 0 0
x_make_qstorno_1000_1200 5546 5546 0 0.00 2 114501 1295 0 0
x_make_qstorno_1200_2000 5456 5456 0 0.00 3 96049 3250 0 0
x_make_qstorno_2100_3300 444727 444727 0 0.00 0 53098 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2021 04:28:45 to 06-01-2021 04:28:49.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:56:58 8 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:57:59 22933 8192000000 9704463808 9996959744 784845600 8.742 100 90 180 0 814294560 7294000 22154960
01:59:28 13437 8192000000 9748818912 10052296704 825962848 9.159 100 88 176 1 859842640 12606672 21273120
02:00:47 14469 8192000000 9780982064 10092408832 848757280 9.388 100 85 170 0 895160512 26239312 20163920
02:02:13 11957 8192000000 9846880576 10176794624 895952080 9.858 100 78 158 1 952551040 39546464 17052496
02:03:29 8100 8192000000 9910651424 10247499776 945635376 10.348 100 82 164 1 1022527680 59441856 17450448
02:04:42 4380 8192000000 9939787264 10289197056 983324064 10.717 100 81 160 2 1050550064 50353520 16872480
02:05:47 2427 8192000000 10013139488 10371186688 1010722176 10.982 100 96 188 1 1125333424 96436352 18174896
02:06:51 2075 8192000000 10014260256 10378694656 1035121040 11.218 100 93 188 0 1132686752 77474544 20091168
02:07:54 1527 8192000000 10013161808 10382610432 1059050832 11.447 100 92 181 1 1127517488 50329248 18137408
02:08:56 9033 8192000000 9982063808 10375852032 1059708032 11.454 100 30 59 2 1117606432 51569888 6328512
02:10:07 5606 8192000000 9976361440 10368512000 1068929040 11.542 100 47 93 1 1089156640 9439936 10787664
02:11:16 12601 8192000000 10006682352 10415226880 1077164432 11.620 100 26 52 0 1109633344 27271312 5197600
02:12:30 26742 8192000000 10004511456 10418839552 1085377552 11.699 100 34 68 3 1122587280 29846464 7363264
02:13:58 6122 8192000000 10001133776 10392104960 1085466704 11.700 100 37 74 3 1112748640 19483904 7798032
02:15:06 6365 8192000000 10015372736 10407985152 1095412224 11.794 100 38 74 2 1122373616 18632368 8329024
02:16:13 11947 8192000000 10000163248 10403602432 1090050704 11.743 100 42 86 3 1114984336 15941840 8991792
02:17:27 20553 8192000000 9990287440 10393608192 1060616704 11.462 97 21 41 4 1099705984 35240000 3849280
02:18:48 7655 8192000000 9949586864 10337198080 987740272 10.760 93 16 32 1 1014626912 23281168 3605472
02:19:56 6182 8192000000 9934482496 10327064576 1007127968 10.948 98 34 69 0 1031392496 16969376 7295152
02:21:04 3453 8192000000 9927182672 10296967168 1008951680 10.965 99 42 86 1 1028557520 10308752 9297088
02:22:09 17150 8192000000 9913631152 10291068928 937929552 10.273 92 19 37 0 955296624 13623248 3743824
02:23:27 2969 8192000000 9964560784 10341871616 1038370544 11.249 100 68 134 3 1074552672 21032384 15149744
02:24:32 6518 8192000000 9973639536 10363883520 1056472384 11.423 100 25 49 3 1099738512 37545296 5720832
02:25:40 64113 8192000000 9973075904 10381357056 1066953680 11.523 100 30 59 2 1099776848 26651232 6171936
02:27:53 13256 8192000000 10047895552 10444488704 1098954944 11.828 100 42 83 0 1156633072 49618528 8059600
02:29:10 15197 8192000000 10083079312 10481577984 1115239232 11.982 100 51 102 1 1197872832 72542656 10090944
02:30:30 5559 8192000000 10142864848 10542059520 1126135712 12.085 100 61 120 2 1250970048 112453040 12381296
02:31:40 13144 8192000000 10133708416 10546843648 1139398752 12.210 100 58 116 0 1236030848 84184064 12448032
02:33:00 5517 8192000000 10149953520 10557767680 1149661696 12.306 100 46 91 2 1260284272 100494160 10128416
02:34:14 22919 8192000000 10220764544 10637766656 1155886592 12.365 100 48 93 5 1328351744 162312608 10152544
02:35:39 1573 8192000000 10269403760 10664833024 1164893024 12.449 100 81 159 1 1362011472 178790464 18327984
02:36:46 3075 8192000000 10245212016 10651095040 1177436032 12.566 100 75 149 0 1351324736 156523504 17365200
02:37:54 7450 8192000000 10298247280 10711597056 1174630976 12.540 100 65 129 1 1392997472 204081488 14285008
02:39:10 3687 8192000000 10277808160 10677997568 1161271360 12.415 100 81 160 2 1372353792 193419488 17662944
02:40:16 2936 8192000000 10335222736 10734944256 1164596464 12.446 100 80 158 2 1427592112 244817248 18178400
02:41:23 1284 8192000000 10346522336 10744320000 1165870848 12.458 100 92 186 1 1445544832 259009168 20664816
02:42:28 13472 8192000000 10312565168 10730938368 1142889360 12.243 100 38 73 1 1415217296 262981904 9346032
02:43:48 1594 8192000000 10359093040 10757111808 1129972208 12.121 100 89 176 3 1449207248 299600512 19634528
02:44:53 17159 8192000000 10233388160 10650857472 1100836368 11.846 98 31 62 1 1312286032 203410928 8038736
02:46:15 27388 8192000000 10227148256 10676666368 1101490528 11.852 98 35 70 1 1367332192 257722256 8119408
02:47:44 14042 8192000000 10251608720 10660990976 1079542000 11.643 98 27 54 0 1340469312 254109024 6818288
02:49:05 22930 8192000000 10198069968 10658177024 1085280272 11.698 98 27 52 1 1334922576 243031648 6610656
02:50:39 9820 8192000000 10200752896 10619199488 1108119712 11.915 100 28 56 2 1302528656 187178080 7230864
02:51:57 15050 8192000000 10246212720 10705698816 1111667120 11.948 100 28 56 0 1356214384 237186208 7361056
02:53:54 17668 8192000000 10180270864 10663133184 1100470192 11.842 99 20 40 2 1268915888 163694160 4751536
02:55:26 54691 8192000000 10062234272 10590781440 1104689216 11.882 100 11 22 0 1221618656 114681360 2248080
02:57:41 27689 8192000000 10086735504 10534899712 1100521136 11.843 100 32 64 4 1183134928 75903488 6710304
02:59:13 62013 8192000000 10036048224 10469351424 1096546176 11.805 99 17 34 1 1120077712 19813520 3718016
03:01:16 84436 8192000000 10068338192 10503557120 1099344528 11.831 98 23 46 0 1150984256 46695824 4943904
03:03:42 39989 8192000000 10064894208 10506678272 1116865936 11.997 99 35 67 2 1143353920 19321376 7166608
03:05:51 71890 8192000000 10120497488 10561273856 1130621664 12.127 99 21 42 0 1193603552 58380512 4601376
03:08:13 49883 8192000000 10132458064 10591576064 1146332464 12.275 100 25 50 0 1236093488 83780464 5980560
03:10:05 59013 8192000000 10142368944 10662023168 1145326624 12.266 99 20 40 1 1261176288 111082496 4767168
03:12:49 70457 8192000000 10087752496 10530721792 1147968432 12.290 100 12 24 1 1209055808 58450176 2637200
03:15:00 84471 8192000000 10106950080 10597814272 1155122064 12.358 100 22 44 3 1232683536 73173152 4388320
03:17:25 65678 8192000000 10079166608 10552709120 1136277936 12.181 98 34 68 0 1186826384 43650432 6898016
03:19:31 40992 8192000000 10144280272 10603307008 1136660704 12.184 98 25 50 1 1210203232 68182256 5360272
03:21:12 102405 8192000000 10088420656 10548375552 1074076112 11.591 93 9 18 0 1133660320 57624128 1960080
03:23:55 134863 8192000000 10050625776 10501332992 1114165216 11.972 98 20 40 2 1149993488 31610192 4218080
03:27:21 52602 8192000000 10066322640 10527367168 1115699808 11.986 98 13 27 1 1155328384 37282528 2346048
03:29:13 53844 8192000000 10064801632 10547073024 1102283312 11.859 97 6 12 0 1151455664 48019120 1153232
03:31:08 82472 8192000000 10048171456 10502119424 1081904144 11.666 96 11 22 0 1127314528 42938528 2471856
03:33:36 53977 8192000000 10113956512 10554093568 1085842688 11.703 95 19 38 1 1166534976 76563152 4129136
03:35:31 82365 8192000000 10075395712 10536960000 1067792256 11.531 94 16 32 0 1175101776 103824256 3485264
03:37:53 45059 8192000000 10061573104 10505662464 1030327792 11.172 92 27 54 1 1090897872 55456064 5114016
03:39:39 42214 8192000000 10078069760 10517344256 1081892736 11.666 98 42 84 2 1139090864 48263216 8934912
03:41:27 71018 8192000000 10062917824 10528358400 1075299200 11.603 98 10 20 1 1198180816 120462352 2419264
03:43:40 56266 8192000000 10038376688 10482069504 1095998080 11.800 99 17 34 0 1158935936 58799808 4138048
03:45:38 77980 8192000000 10109645504 10601656320 1094324016 11.784 98 19 38 0 1156060304 58004304 3731984
03:50:40 64512 8192000000 10057784016 10519883776 1119902528 12.026 100 12 24 0 1148281456 25980144 2398784
03:52:45 63214 8192000000 10099341328 10560401408 1103913344 11.875 98 5 10 0 1175947552 71054416 979792
03:54:48 34326 8192000000 10060011600 10495340544 1115706368 11.986 100 18 35 0 1175218608 55925616 3586624
03:56:25 47555 8192000000 10073974864 10529431552 1121234656 12.039 100 21 42 0 1182474048 56964512 4274880
03:58:13 105706 8192000000 10133934656 10568843264 1102923600 11.865 98 24 48 1 1184224368 76166464 5134304
04:00:59 64656 8192000000 10075291792 10529296384 1118231744 12.010 98 19 38 1 1161627248 39574432 3821072
04:03:05 60552 8192000000 10097955936 10543116288 1130263808 12.124 99 10 20 0 1162402208 30574960 1563440
04:05:06 25556 8192000000 10120124720 10550190080 1149694560 12.307 100 45 90 2 1197260528 37716400 9849568
04:06:35 86599 8192000000 10082015248 10587422720 1144205936 12.255 100 17 33 1 1191090896 43529136 3355824
04:09:24 38010 8192000000 10082265312 10549563392 1140621712 12.221 99 7 14 0 1175162784 32885344 1655728
04:13:19 88807 8192000000 10090005840 10576932864 1148863088 12.299 99 17 34 0 1228209824 75384880 3961856
04:15:52 39500 8192000000 10106484064 10551513088 1121905952 12.045 98 19 38 0 1197630960 72144896 3580112
04:17:31 58486 8192000000 10126517184 10617593856 1150616160 12.315 100 21 42 1 1208509072 53394704 4498208
04:19:31 32915 8192000000 10140952208 10593906688 1137478448 12.192 99 31 61 1 1225383232 81508496 6396288
04:21:23 110605 8192000000 10070018800 10515406848 1097388336 11.813 96 15 30 0 1135619152 34896080 3334736
04:24:15 86583 8192000000 10038867744 10493964288 1097882240 11.818 97 15 29 1 1118539424 17665520 2991664
04:26:45 22460 8192000000 9325996560 9642033152 462269984 5.341 42 2 4 0 477728544 15142672 315888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2021 04:29:07 to 06-01-2021 04:29:09.

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          232390
MON$OLDEST_ACTIVE               232391
MON$OLDEST_SNAPSHOT             232391
MON$NEXT_TRANSACTION            232391
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                       2113088
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             789
MON$NEXT_STATEMENT              53


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

06.01.21 04:29:10. 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-06 04:29:12 No severe PSQL-related problems occured
06.01.21 04:29:14. End of report.