Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2021 13: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 16769444 kB
MemAvailable 31366100 kB
Buffers 0 kB
Cached 14851128 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-01 13:26:51
DTS_END                         2021-01-01 13:26:51.6750
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/51596
TRN_ID                          223022
ATT_ID                          568
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: 8740.17 22570 1048821
customer order: creation 162.93 382 19552
customer order: refuse 14.05 354 1686
order to supplier: creation 44.56 1410 5347
order to supplier: removal 3.14 125479 377
invoice (draft): creation 44.78 3883 5373
invoice (draft): removal 49.33 1121 5920
invoice accept: apply 39.51 17719 4741
invoice accept: cancel 44.13 989 5295
customer reserve: creation 3667.44 172 440093
customer reserve: removal 4396.18 69 527542
realization accept: apply 79.18 174 9502
realization accept: cancel 71.93 123 8631
payment from customer: creation 56.95 110 6834
payment from customer: removal 5.67 95 680
payment to supplier: creation 46.88 129 5626
payment to supplier: removal 3.31 89 397
service: total inventory turnovers 3.78 3756 454
service: total monetary turnovers 5.56 611 667
service: aggregate perf. data 0.83 19405 100
service: refresh index statistics 0.03 275321 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2021 13:28:53 to 01-01-2021 13:28:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 281
TEST_TIME 2 1750
TEST_TIME 3 2448
TEST_TIME 4 3222
TEST_TIME 5 3580
TEST_TIME 6 4016
TEST_TIME 7 4477
TEST_TIME 8 4644
TEST_TIME 9 5293
TEST_TIME 10 5225
TEST_TIME 11 5217
TEST_TIME 12 5183
TEST_TIME 13 5194
TEST_TIME 14 5408
TEST_TIME 15 5736
TEST_TIME 16 5645
TEST_TIME 17 5989
TEST_TIME 18 6057
TEST_TIME 19 6049
TEST_TIME 20 6643
TEST_TIME 21 6955
TEST_TIME 22 6878
TEST_TIME 23 7092
TEST_TIME 24 7455
TEST_TIME 25 7581
TEST_TIME 26 8090
TEST_TIME 27 8732
TEST_TIME 28 9695
TEST_TIME 29 9692
TEST_TIME 30 9799
TEST_TIME 31 9681
TEST_TIME 32 9760
TEST_TIME 33 9666
TEST_TIME 34 9636
TEST_TIME 35 9816
TEST_TIME 36 9779
TEST_TIME 37 9711
TEST_TIME 38 9461
TEST_TIME 39 9303
TEST_TIME 40 9305
TEST_TIME 41 9195
TEST_TIME 42 9183
TEST_TIME 43 9113
TEST_TIME 44 9208
TEST_TIME 45 9101
TEST_TIME 46 9059
TEST_TIME 47 8998
TEST_TIME 48 8955
TEST_TIME 49 9031
TEST_TIME 50 9099
TEST_TIME 51 9084
TEST_TIME 52 9107
TEST_TIME 53 9029
TEST_TIME 54 9214
TEST_TIME 55 9189
TEST_TIME 56 9233
TEST_TIME 57 9199
TEST_TIME 58 9208
TEST_TIME 59 9261
TEST_TIME 60 9347
TEST_TIME 61 9353
TEST_TIME 62 9292
TEST_TIME 63 9200
TEST_TIME 64 9152
TEST_TIME 65 9068
TEST_TIME 66 9048
TEST_TIME 67 9023
TEST_TIME 68 9020
TEST_TIME 69 8997
TEST_TIME 70 8937
TEST_TIME 71 8951
TEST_TIME 72 8957
TEST_TIME 73 8945
TEST_TIME 74 8915
TEST_TIME 75 8927
TEST_TIME 76 8938
TEST_TIME 77 9107
TEST_TIME 78 9129
TEST_TIME 79 9116
TEST_TIME 80 9026
TEST_TIME 81 8922
TEST_TIME 82 8998
TEST_TIME 83 8940
TEST_TIME 84 8887
TEST_TIME 85 8855
TEST_TIME 86 8824
TEST_TIME 87 8792
TEST_TIME 88 8809
TEST_TIME 89 8783
TEST_TIME 90 8815
TEST_TIME 91 8830
TEST_TIME 92 9070
TEST_TIME 93 9048
TEST_TIME 94 9110
TEST_TIME 95 9158
TEST_TIME 96 9138
TEST_TIME 97 9158
TEST_TIME 98 9107
TEST_TIME 99 9049
TEST_TIME 100 8999
TEST_TIME 101 8940
TEST_TIME 102 8884
TEST_TIME 103 8867
TEST_TIME 104 8885
TEST_TIME 105 8848
TEST_TIME 106 8818
TEST_TIME 107 8807
TEST_TIME 108 8825
TEST_TIME 109 8834
TEST_TIME 110 8857
TEST_TIME 111 8830
TEST_TIME 112 8814
TEST_TIME 113 8832
TEST_TIME 114 8880
TEST_TIME 115 8900
TEST_TIME 116 8939
TEST_TIME 117 8894
TEST_TIME 118 8823
TEST_TIME 119 8789
TEST_TIME 120 8740

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2021 13:28:57 to 01-01-2021 13:29:00.

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 1457194 1457194 0 0.00 0 27173 7 0 0
doc_list_biud 1457194 1457194 0 0.00 0 83631 33 0 0
sp_add_invoice_to_stock 4819 4741 78 1.61 56 192978 17720 0 78
sp_cancel_adding_invoice 5295 5295 0 0.00 5 64457 989 0 0
sp_cancel_client_order 1686 1686 0 0.00 5 24640 355 0 0
sp_cancel_customer_reserve 527542 527542 0 0.00 1 41055 70 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 8949 96 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 4152 89 0 0
sp_cancel_supplier_invoice 5928 5920 8 0.13 2 83634 1122 0 8
sp_cancel_supplier_order 377 377 0 0.00 16 1360404 125480 0 0
sp_cancel_write_off 8631 8631 0 0.00 1 9970 124 0 0
sp_client_order 19552 19552 0 0.00 4 26646 383 0 0
sp_customer_reserve 440093 440093 0 0.00 0 91323 173 0 0
sp_fill_shopping_cart 30257 30257 0 0.00 1 25638 46 0 0
sp_fill_shopping_cart_clo_res 440093 440093 0 0.00 0 24637 17 0 0
sp_get_clo_for_invoice 4741 4741 0 0.00 0 44811 419 0 0
sp_kill_cost_storno 15003 15003 0 0.00 0 8980 65 0 0
sp_kill_qstorno_ret_qs2qd 1070012 1070012 0 0.00 0 83630 39 0 0
sp_kill_qty_storno 563694 563694 0 0.00 0 83631 83 0 0
sp_lock_selected_doc 19780 19780 0 0.00 0 9598 6 0 0
sp_make_cost_storno 26703 26703 0 0.00 0 17778 55 0 0
sp_multiply_rows_for_pdistr 26703 26703 0 0.00 0 8939 34 0 0
sp_multiply_rows_for_qdistr 19552 19552 0 0.00 0 22442 201 0 0
sp_pay_from_customer 6834 6834 0 0.00 3 12965 110 0 0
sp_pay_to_supplier 5626 5626 0 0.00 5 12829 129 0 0
sp_payment_common 12460 12460 0 0.00 1 7851 19 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 24630 335 0 0
sp_qd_handle_on_invoice_upd_sts 10036 10036 0 0.00 0 49386 399 0 0
sp_qd_handle_on_reserve_upd_sts 9502 9502 0 0.00 0 8795 49 0 0
sp_reserve_write_off 9502 9502 0 0.00 7 13549 174 0 0
sp_supplier_invoice 5373 5373 0 0.00 7 147125 3883 0 0
sp_supplier_order 5347 5347 0 0.00 5 57571 1411 0 0
srv_aggregate_perf_data 100 100 0 0.00 2594 375910 19406 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2653 143701 46094 0 0
srv_find_qd_qs_mism 1003683 1003683 0 0.00 0 27172 5 0 0
srv_make_invnt_saldo 454 454 0 0.00 55 110810 3757 0 0
srv_make_money_saldo 667 667 0 0.00 1 15357 612 0 0
srv_recalc_idx_stat 4 4 0 0.00 158943 406445 275322 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4741 4741 0 0.00 0 17079 161 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 11561 127 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 3164 12 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 5133 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 755 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 454 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 2031 22 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 19746 1518 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 5064 13 0 0
t$perf-norm:sp_client_order 19552 19552 0 0.00 0 10322 18 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 2186 8 0 0
t$perf-norm:sp_pay_from_customer 6834 6834 0 0.00 0 4544 9 0 0
t$perf-norm:sp_pay_to_supplier 5626 5626 0 0.00 0 4113 8 0 0
t$perf-norm:sp_reserve_write_off 9502 9502 0 0.00 0 4647 9 0 0
t$perf-norm:sp_supplier_invoice 5373 5373 0 0.00 0 6185 20 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 4434 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 476 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 709 8 0 0
t$perf-norm:srv_make_money_saldo 667 667 0 0.00 0 802 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9638 9482 156 1.61 0 4949 18 0 156
v_all_customers 35420 35420 0 0.00 0 27 1 0 0
v_all_suppliers 29646 29646 0 0.00 0 111 1 0 0
v_all_wares 39104 39104 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 4824 14 0 0
v_cancel_client_order 3372 3372 0 0.00 0 1617 4 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2574 9 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 2807 6 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 4076 15 0 0
v_cancel_supplier_invoice 778 762 16 2.05 0 2773 24 0 16
v_cancel_supplier_order 754 754 0 0.00 0 929 6 0 0
v_cancel_write_off 1872 1872 0 0.00 0 688 11 0 0
v_max_id_clo_ord 5347 5347 0 0.00 0 1404 2 0 0
v_max_id_clo_res 965 965 0 0.00 0 3448 19 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 6603 8 0 0
v_max_non_paid_invoice 1508 1508 0 0.00 0 2846 3 0 0
v_max_non_paid_realizn 3698 3698 0 0.00 0 3340 5 0 0
v_min_id_clo_ord 5347 5347 0 0.00 0 5032 6 0 0
v_min_id_clo_res 965 965 0 0.00 0 1396 5 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 10787 8 0 0
v_min_non_paid_invoice 5626 5626 0 0.00 0 5614 12 0 0
v_min_non_paid_realizn 6834 6834 0 0.00 0 4983 10 0 0
v_our_firm 9956 9956 0 0.00 0 14 1 0 0
v_reserve_write_off 19004 19004 0 0.00 0 5102 7 0 0
x_lock_depdocs_on_canc_invoice 10834 10834 0 0.00 0 16007 114 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 9646 338 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 2 57453 1328 0 0
x_make_qstorno_1200_2000 5373 5373 0 0.00 4 147104 3703 0 0
x_make_qstorno_2100_3300 440093 440093 0 0.00 0 91302 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2021 13:29:01 to 01-01-2021 13:29:05.

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
10:56:55 10 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:57 14511 8192000000 9705943088 10001068032 786249312 8.757 100 87 174 0 815675456 7339936 22086208
10:59:14 22134 8192000000 9751526608 10055614464 833249488 9.232 100 88 176 0 865773696 10727408 21796800
11:00:43 19842 8192000000 9787331296 10097065984 852844320 9.429 100 89 178 0 891943424 18063600 21035504
11:02:09 17297 8192000000 9842375440 10169577472 894408976 9.843 100 83 167 1 948620192 35194192 19017024
11:03:32 8983 8192000000 9894555280 10236624896 942434432 10.317 100 78 156 2 1007669968 48492432 16743104
11:04:47 8315 8192000000 9958095616 10307862528 980516368 10.689 100 85 170 0 1070199456 72295152 17387936
11:05:58 3780 8192000000 9985873600 10339213312 1009680752 10.972 100 86 170 3 1099559728 73372736 16506240
11:07:04 2356 8192000000 9995457936 10356928512 1035379600 11.220 100 86 174 0 1107482544 54293520 17809424
11:08:08 1776 8192000000 10040098672 10411331584 1062152160 11.477 100 92 183 1 1150796288 69776080 18868048
11:09:12 11051 8192000000 10018473120 10398076928 1068693008 11.540 100 74 147 4 1145455696 62190160 14572528
11:10:24 7835 8192000000 10005374080 10408116224 1073304512 11.584 100 41 80 1 1132171952 50757952 8109488
11:11:33 8319 8192000000 9990104096 10402926592 1076167200 11.611 100 29 57 0 1119258448 36958544 6132704
11:12:41 36743 8192000000 9993330496 10384015360 1081731344 11.664 100 61 123 2 1102671920 7507456 13433120
11:14:19 7069 8192000000 10006810576 10402091008 1088366976 11.727 100 42 82 3 1121966768 24858288 8741504
11:15:30 4749 8192000000 10002414832 10401255424 1093091600 11.772 100 34 68 2 1123999280 22381664 8526016
11:16:39 9593 8192000000 10014419584 10414645248 1099794384 11.836 100 28 55 0 1149934160 44156176 5983600
11:17:55 3905 8192000000 10017188688 10420166656 1092079232 11.762 100 38 75 2 1129550288 29970880 7500176
11:19:00 6899 8192000000 10002732784 10401341440 1070655232 11.558 100 45 90 2 1107146416 26411536 10079648
11:20:09 5597 8192000000 9977979776 10367086592 1052580560 11.385 99 49 95 0 1093192592 29984528 10627504
11:21:17 64657 8192000000 9918578752 10307313664 950204928 10.393 93 35 70 4 993460800 35942272 7313600
11:23:27 10150 8192000000 9945592736 10336059392 1023864848 11.109 99 29 58 2 1054338992 23771760 6702384
11:24:39 7814 8192000000 9959140400 10363977728 1046716080 11.329 100 40 81 2 1075518928 19934640 8868208
11:25:48 6528 8192000000 9977673152 10373992448 1061214064 11.468 100 45 89 6 1088878304 17710368 9953872
11:26:58 94132 8192000000 10006571536 10414399488 1088435312 11.728 100 23 46 1 1118162976 24814560 4913104
11:29:37 10697 8192000000 10057718368 10462281728 1109689296 11.929 100 40 80 1 1164521776 46406080 8426400
11:30:56 27314 8192000000 10074085152 10486579200 1126708608 12.090 100 30 58 2 1187264944 52985632 7570704
11:32:29 7754 8192000000 10126510464 10531774464 1141703264 12.232 100 49 96 2 1235896544 83918320 10274960
11:33:41 14960 8192000000 10168157840 10587209728 1138772512 12.204 99 47 94 1 1265354832 116308336 10273984
11:35:01 21640 8192000000 10188591744 10623107072 1158755152 12.392 100 50 98 2 1303289664 133931248 10603264
11:36:25 5788 8192000000 10202453760 10621054976 1168531120 12.483 100 62 124 0 1306338352 124913808 12893424
11:37:35 6127 8192000000 10204855472 10622939136 1171869920 12.514 100 43 86 0 1310365296 129293072 9202304
11:38:44 14558 8192000000 10247314608 10664087552 1179473168 12.585 100 47 94 1 1353355600 163449216 10433216
11:40:05 8325 8192000000 10329867472 10738569216 1188433408 12.669 100 78 154 0 1427482112 221800944 17247760
11:41:17 5724 8192000000 10326389248 10753204224 1192704160 12.709 100 55 111 0 1437601888 231835408 13062320
11:42:28 3376 8192000000 10378697824 10791616512 1202226144 12.797 100 74 148 1 1474609984 255163920 17219920
11:43:37 2652 8192000000 10372754528 10784579584 1201742160 12.793 100 80 161 0 1473855792 253159984 18953648
11:44:46 3824 8192000000 10384839408 10795225088 1185581264 12.642 100 74 147 1 1490858560 288643424 16633872
11:45:59 3484 8192000000 10437705792 10876833792 1189005632 12.674 100 86 172 2 1546709664 337652944 20051088
11:47:08 3452 8192000000 10428657584 10842247168 1193192800 12.713 100 66 131 0 1530314944 322115872 15006272
11:48:16 7618 8192000000 10418284448 10841800704 1154955664 12.356 99 49 97 1 1506867712 340012384 11899664
11:49:40 5799 8192000000 10503034656 10922807296 1154968656 12.356 100 58 116 0 1601618688 431863552 14786480
11:50:54 18434 8192000000 10442859568 10899111936 1147089088 12.282 99 42 84 2 1571372464 413621472 10661904
11:52:17 31216 8192000000 10414673904 10862637056 1129353504 12.115 99 30 60 1 1525966048 388472832 8139712
11:54:26 27285 8192000000 10098222144 10600177664 1108825440 11.921 99 25 50 1 1339776992 224582864 6368688
11:55:55 31333 8192000000 10062355184 10540830720 1116231808 11.991 99 31 63 2 1168829584 46456736 6141040
11:57:31 55687 8192000000 10048610992 10505281536 1093839760 11.779 97 19 38 1 1162979264 65746400 3393104
11:59:28 41047 8192000000 10095346544 10542985216 1111514096 11.947 99 15 30 1 1188704944 74279904 2910944
12:01:16 48422 8192000000 10052599456 10498998272 1092941328 11.771 97 14 27 5 1122960432 26724656 3294448
12:03:04 105653 8192000000 10075027584 10514079744 1064988032 11.504 95 28 56 1 1099890160 28212400 6689728
12:06:16 17237 8192000000 10083832656 10527752192 1111711232 11.949 100 30 61 0 1180516096 62367840 6437024
12:07:37 14258 8192000000 10125458608 10546876416 1123993184 12.065 99 44 84 3 1200111600 66251056 9867360
12:08:56 35193 8192000000 10105726048 10578976768 1131053392 12.131 100 17 34 0 1210255680 74821600 4380688
12:10:39 57550 8192000000 10104810000 10541244416 1116035920 11.990 98 15 30 1 1168560624 49068384 3456320
12:12:36 27156 8192000000 10128315536 10562580480 1138225632 12.199 100 19 38 0 1224156608 81413968 4517008
12:14:08 65992 8192000000 10152432544 10625060864 1131237232 12.133 99 13 26 1 1247491424 113168336 3085856
12:16:39 43366 8192000000 10092840096 10557210624 1143006944 12.244 100 16 32 3 1195824464 49777568 3039952
12:18:25 56715 8192000000 10116116736 10561110016 1141216720 12.227 99 23 46 1 1218827776 72937328 4673728
12:20:27 65137 8192000000 10083736048 10549538816 1128765696 12.110 99 13 24 1 1160182944 28586240 2831008
12:22:32 41780 8192000000 10065952064 10528845824 1112865056 11.960 97 12 24 1 1171013872 55487616 2661200
12:24:18 44392 8192000000 10121613760 10565115904 1139668528 12.212 99 37 74 1 1188708400 40650960 8388912
12:26:39 72980 8192000000 10080472000 10547138560 1114164848 11.972 97 17 33 2 1162825568 45356976 3303744
12:29:00 87889 8192000000 10069124016 10528763904 1144758672 12.260 100 16 32 0 1187101376 38885568 3457136
12:31:30 34070 8192000000 10084813488 10550812672 1142994160 12.244 99 9 18 1 1191946928 47417504 1535264
12:33:05 105961 8192000000 10059677984 10538995712 1094137904 11.782 96 11 22 0 1138058352 42009520 1910928
12:35:54 45682 8192000000 10058725008 10496409600 1075341600 11.603 95 18 36 1 1112745760 33355232 4048928
12:37:40 39748 8192000000 10053066896 10476015616 1054711888 11.406 94 23 46 1 1127272336 67429824 5130624
12:39:21 27699 8192000000 10072326672 10514120704 1090905104 11.751 99 37 74 2 1160308192 62127808 7275280
12:40:49 45013 8192000000 10106771152 10554441728 1087722016 11.721 98 40 79 3 1190583872 94622432 8239424
12:42:35 59975 8192000000 10072693696 10612793344 1097587552 11.815 99 18 36 0 1225301488 123734448 3979488
12:44:35 63677 8192000000 10081202448 10516041728 1079174832 11.640 96 25 50 2 1123655088 38731808 5748448
12:46:42 104685 8192000000 10060335872 10560552960 1120153728 12.028 100 27 54 1 1210129616 84355696 5620192
12:50:03 39710 8192000000 10027645888 10503372800 1091695008 11.759 98 3 6 0 1120382896 28261184 426704
12:51:43 83891 8192000000 10111419776 10566139904 1089530688 11.738 97 16 32 1 1165109984 72161216 3418080
12:54:10 45402 8192000000 10136718752 10568556544 1113625824 11.967 99 31 62 0 1219714272 99384944 6703504
12:55:56 96407 8192000000 10212118448 10761330688 1137716352 12.194 100 21 42 3 1356003920 212809984 5477584
12:58:41 100217 8192000000 10107715408 10554392576 1088877120 11.732 96 18 35 2 1137933536 45630432 3425984
13:01:21 113813 8192000000 10081782864 10519445504 1105595248 11.891 97 29 56 3 1137883408 26715344 5572816
13:04:19 49227 8192000000 10087204592 10582626304 1140383888 12.219 100 6 12 0 1198012480 56488672 1139920
13:06:18 31362 8192000000 10069796048 10558373888 1145379760 12.266 100 7 13 0 1178717056 32478160 859136
13:08:15 85035 8192000000 10088062992 10572926976 1133835584 12.158 99 12 24 0 1183705216 47329744 2539888
13:11:26 46311 8192000000 10076644672 10530119680 1139098576 12.207 100 23 43 3 1168957296 24978880 4879840
13:13:32 64653 8192000000 10072212464 10513805312 1122551216 12.051 98 19 38 0 1168016496 41822736 3642544
13:15:44 84943 8192000000 10067314096 10558996480 1128910480 12.111 99 13 26 1 1182390448 50354912 3125056
13:18:16 93313 8192000000 10111950160 10570547200 1123138096 12.057 97 22 44 3 1172018656 43989600 4890960
13:20:49 63421 8192000000 10080689584 10560159744 1127932944 12.102 98 9 18 1 1194676368 65122800 1620624
13:22:53 143701 8192000000 10093988544 10547458048 1131244752 12.133 98 12 24 0 1167289584 33828128 2216704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2021 13:29:07 to 01-01-2021 13:29:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 78 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2021 13:29:23 to 01-01-2021 13:29:25.

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          223021
MON$OLDEST_ACTIVE               224826
MON$OLDEST_SNAPSHOT             224826
MON$NEXT_TRANSACTION            224826
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                       2095680
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             775
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Jan  1 13:27:10 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

01.01.21 13:29:26. 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-01 13:29:28 No severe PSQL-related problems occured
01.01.21 13:29:30. End of report.