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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16950632 kB
MemAvailable 31371344 kB
Buffers 20 kB
Cached 14629440 kB
SwapCached 2916 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-10 04:26:54
DTS_END                         2020-11-10 04:26:54.3070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40042
TRN_ID                          233985
ATT_ID                          673
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: 9019.68 20212 1082362
customer order: creation 171.23 413 20547
customer order: refuse 13.90 274 1668
order to supplier: creation 43.88 1372 5266
order to supplier: removal 3.37 105257 404
invoice (draft): creation 45.32 3098 5438
invoice (draft): removal 49.34 855 5921
invoice accept: apply 41.66 17049 4999
invoice accept: cancel 44.90 811 5388
customer reserve: creation 3865.78 166 463894
customer reserve: removal 4459.38 64 535126
realization accept: apply 82.85 173 9942
realization accept: cancel 71.20 140 8544
payment from customer: creation 58.06 113 6967
payment from customer: removal 5.59 60 671
payment to supplier: creation 48.84 117 5861
payment to supplier: removal 3.52 80 422
service: total inventory turnovers 4.02 2993 482
service: total monetary turnovers 5.83 572 699
service: aggregate perf. data 0.99 13520 119
service: refresh index statistics 0.03 257107 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-11-2020 04:27:47 to 10-11-2020 04:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 823
TEST_TIME 2 3152
TEST_TIME 3 3343
TEST_TIME 4 3917
TEST_TIME 5 4437
TEST_TIME 6 4401
TEST_TIME 7 4563
TEST_TIME 8 4554
TEST_TIME 9 4893
TEST_TIME 10 5063
TEST_TIME 11 5564
TEST_TIME 12 5977
TEST_TIME 13 6276
TEST_TIME 14 6313
TEST_TIME 15 6681
TEST_TIME 16 6985
TEST_TIME 17 7593
TEST_TIME 18 8001
TEST_TIME 19 8478
TEST_TIME 20 8419
TEST_TIME 21 8499
TEST_TIME 22 8579
TEST_TIME 23 9247
TEST_TIME 24 9989
TEST_TIME 25 10696
TEST_TIME 26 10577
TEST_TIME 27 10534
TEST_TIME 28 10403
TEST_TIME 29 10437
TEST_TIME 30 10104
TEST_TIME 31 9874
TEST_TIME 32 9747
TEST_TIME 33 9684
TEST_TIME 34 9641
TEST_TIME 35 9515
TEST_TIME 36 9752
TEST_TIME 37 9642
TEST_TIME 38 9513
TEST_TIME 39 9445
TEST_TIME 40 9316
TEST_TIME 41 9238
TEST_TIME 42 9378
TEST_TIME 43 9326
TEST_TIME 44 9283
TEST_TIME 45 9307
TEST_TIME 46 9409
TEST_TIME 47 9711
TEST_TIME 48 9630
TEST_TIME 49 9619
TEST_TIME 50 9562
TEST_TIME 51 9551
TEST_TIME 52 9554
TEST_TIME 53 9653
TEST_TIME 54 9619
TEST_TIME 55 9503
TEST_TIME 56 9489
TEST_TIME 57 9397
TEST_TIME 58 9333
TEST_TIME 59 9324
TEST_TIME 60 9285
TEST_TIME 61 9276
TEST_TIME 62 9300
TEST_TIME 63 9316
TEST_TIME 64 9267
TEST_TIME 65 9354
TEST_TIME 66 9449
TEST_TIME 67 9533
TEST_TIME 68 9522
TEST_TIME 69 9539
TEST_TIME 70 9543
TEST_TIME 71 9573
TEST_TIME 72 9609
TEST_TIME 73 9519
TEST_TIME 74 9424
TEST_TIME 75 9391
TEST_TIME 76 9326
TEST_TIME 77 9315
TEST_TIME 78 9275
TEST_TIME 79 9280
TEST_TIME 80 9251
TEST_TIME 81 9280
TEST_TIME 82 9279
TEST_TIME 83 9267
TEST_TIME 84 9267
TEST_TIME 85 9253
TEST_TIME 86 9284
TEST_TIME 87 9301
TEST_TIME 88 9285
TEST_TIME 89 9395
TEST_TIME 90 9345
TEST_TIME 91 9267
TEST_TIME 92 9201
TEST_TIME 93 9154
TEST_TIME 94 9119
TEST_TIME 95 9077
TEST_TIME 96 9080
TEST_TIME 97 9078
TEST_TIME 98 9056
TEST_TIME 99 9083
TEST_TIME 100 9038
TEST_TIME 101 9095
TEST_TIME 102 9124
TEST_TIME 103 9190
TEST_TIME 104 9175
TEST_TIME 105 9140
TEST_TIME 106 9185
TEST_TIME 107 9178
TEST_TIME 108 9140
TEST_TIME 109 9078
TEST_TIME 110 9033
TEST_TIME 111 9008
TEST_TIME 112 8946
TEST_TIME 113 8965
TEST_TIME 114 9016
TEST_TIME 115 8985
TEST_TIME 116 9005
TEST_TIME 117 9037
TEST_TIME 118 9036
TEST_TIME 119 8995
TEST_TIME 120 9022

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-11-2020 04:27:51 to 10-11-2020 04:27:55.

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 1510778 1510778 0 0.00 0 15510 6 0 0
doc_list_biud 1510777 1510777 0 0.00 0 28576 28 0 0
sp_add_invoice_to_stock 5101 4999 102 1.99 26 160706 17049 0 102
sp_cancel_adding_invoice 5388 5388 0 0.00 5 90114 812 0 0
sp_cancel_client_order 1668 1668 0 0.00 4 6639 275 0 0
sp_cancel_customer_reserve 535126 535126 0 0.00 1 23126 64 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 2776 61 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 4573 81 0 0
sp_cancel_supplier_invoice 5930 5921 9 0.15 2 14172 856 0 9
sp_cancel_supplier_order 404 404 0 0.00 11 1168416 105257 0 0
sp_cancel_write_off 8544 8544 0 0.00 1 10573 141 0 0
sp_client_order 20547 20547 0 0.00 4 27756 413 0 0
sp_customer_reserve 463894 463894 0 0.00 0 54871 167 0 0
sp_fill_shopping_cart 31242 31242 0 0.00 1 18847 44 0 0
sp_fill_shopping_cart_clo_res 463893 463893 0 0.00 0 16875 18 0 0
sp_get_clo_for_invoice 4995 4995 0 0.00 0 47700 457 0 0
sp_kill_cost_storno 15025 15025 0 0.00 0 8712 58 0 0
sp_kill_qstorno_ret_qs2qd 1085121 1085121 0 0.00 0 28574 35 0 0
sp_kill_qty_storno 571986 571986 0 0.00 0 28576 74 0 0
sp_lock_selected_doc 20635 20635 0 0.00 0 9757 6 0 0
sp_make_cost_storno 27764 27764 0 0.00 0 15497 55 0 0
sp_multiply_rows_for_pdistr 27763 27763 0 0.00 0 9273 29 0 0
sp_multiply_rows_for_qdistr 20547 20547 0 0.00 0 27735 225 0 0
sp_pay_from_customer 6967 6967 0 0.00 4 11826 113 0 0
sp_pay_to_supplier 5861 5861 0 0.00 5 8756 117 0 0
sp_payment_common 12828 12828 0 0.00 1 8707 19 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 4692 241 0 0
sp_qd_handle_on_invoice_upd_sts 10382 10382 0 0.00 0 19364 316 0 0
sp_qd_handle_on_reserve_upd_sts 9941 9941 0 0.00 0 21928 50 0 0
sp_reserve_write_off 9942 9942 0 0.00 6 21940 173 0 0
sp_supplier_invoice 5438 5438 0 0.00 6 98012 3099 0 0
sp_supplier_order 5266 5266 0 0.00 6 31825 1373 0 0
srv_aggregate_perf_data 120 119 1 0.83 2410 135973 13521 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2759 139149 45773 0 0
srv_find_qd_qs_mism 1034424 1034424 0 0.00 0 8206 6 0 0
srv_make_invnt_saldo 482 482 0 0.00 54 124798 2993 0 0
srv_make_money_saldo 699 699 0 0.00 1 59462 572 0 0
srv_recalc_idx_stat 4 4 0 0.00 96966 437760 257107 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4999 4999 0 0.00 0 15508 166 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 5357 98 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 3480 15 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 620 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 7420 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 439 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 3735 22 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 13399 1379 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 835 5 0 0
t$perf-norm:sp_client_order 20547 20547 0 0.00 0 14114 17 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 1259 6 0 0
t$perf-norm:sp_pay_from_customer 6967 6967 0 0.00 0 4738 9 0 0
t$perf-norm:sp_pay_to_supplier 5861 5861 0 0.00 0 15082 14 0 0
t$perf-norm:sp_reserve_write_off 9942 9942 0 0.00 0 15065 13 0 0
t$perf-norm:sp_supplier_invoice 5438 5438 0 0.00 0 3802 13 0 0
t$perf-norm:sp_supplier_order 5266 5266 0 0.00 0 3606 14 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 512 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 644 15 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 1387 10 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 1676 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10192 9988 204 2.00 0 5067 18 0 204
v_all_customers 37520 37520 0 0.00 0 30 1 0 0
v_all_suppliers 29684 29684 0 0.00 0 31 1 0 0
v_all_wares 41086 41086 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 226 5 0 0
v_cancel_client_order 3336 3336 0 0.00 0 2771 6 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2758 8 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 926 4 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 905 6 0 0
v_cancel_supplier_invoice 896 878 18 2.00 0 2670 16 0 18
v_cancel_supplier_order 808 808 0 0.00 0 5557 15 0 0
v_cancel_write_off 2018 2018 0 0.00 0 3609 16 0 0
v_max_id_clo_ord 5262 5262 0 0.00 0 4410 2 0 0
v_max_id_clo_res 952 952 0 0.00 0 2145 19 0 0
v_max_id_ord_sup 5437 5437 0 0.00 0 10905 9 0 0
v_max_non_paid_invoice 1718 1718 0 0.00 0 2668 3 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 3153 3 0 0
v_min_id_clo_ord 5262 5262 0 0.00 0 5538 6 0 0
v_min_id_clo_res 952 952 0 0.00 0 11324 43 0 0
v_min_id_ord_sup 5437 5437 0 0.00 0 8259 14 0 0
v_min_non_paid_invoice 5861 5861 0 0.00 0 8700 14 0 0
v_min_non_paid_realizn 6967 6967 0 0.00 0 6730 13 0 0
v_our_firm 10098 10098 0 0.00 0 13 1 0 0
v_reserve_write_off 19882 19882 0 0.00 0 5006 6 0 0
x_lock_depdocs_on_canc_invoice 10870 10870 0 0.00 0 25583 94 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 11276 320 0 0
x_make_qstorno_1000_1200 5266 5266 0 0.00 3 26820 1302 0 0
x_make_qstorno_1200_2000 5438 5438 0 0.00 3 86196 2920 0 0
x_make_qstorno_2100_3300 463894 463894 0 0.00 0 54870 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2020 04:27:56 to 10-11-2020 04:28:00.

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 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:59 29378 8192000000 9719368704 10017120256 801712144 8.914 100 92 184 0 831782400 7766224 22304032
01:59:32 16019 8192000000 9757742800 10064056320 832371648 9.223 100 84 168 2 865886544 12821024 20693872
02:00:54 15741 8192000000 9794682880 10112573440 860796768 9.508 100 73 146 0 897271328 19411680 17062880
02:02:16 13292 8192000000 9865815136 10196176896 916235984 10.059 100 80 160 1 970248512 36519456 17493072
02:03:31 10799 8192000000 9918600480 10257735680 964583424 10.534 100 91 181 3 1031207456 48055936 18568096
02:04:48 5462 8192000000 9960803328 10313662464 985683568 10.740 100 82 164 2 1076207664 74075888 16448208
02:05:56 3184 8192000000 9986261920 10348298240 1020457280 11.076 100 81 161 1 1110119632 72878400 16783952
02:07:00 1899 8192000000 10007085120 10373849088 1043538560 11.299 100 90 178 2 1123883584 61844480 18500544
02:08:03 1174 8192000000 10042701296 10415759360 1061543328 11.471 100 92 182 1 1155255584 74433792 19278464
02:09:04 6050 8192000000 9987737808 10375553024 1071138880 11.563 100 54 110 1 1102589792 19769632 11681280
02:10:13 64964 8192000000 9992511376 10409365504 1074590544 11.596 100 35 70 1 1104810976 22457360 7763072
02:12:18 6958 8192000000 10028957120 10437627904 1086835520 11.713 100 41 83 1 1147918656 53154240 7928896
02:13:27 6486 8192000000 10023373232 10419376128 1092121968 11.763 100 48 96 2 1123061552 20754976 10184608
02:14:40 4075 8192000000 10021042032 10418225152 1095069776 11.791 100 40 79 2 1128867984 25172240 8625968
02:15:47 23007 8192000000 10011165040 10425569280 1098521792 11.824 100 20 40 1 1126508928 23483664 4503472
02:17:11 5065 8192000000 10012425968 10405752832 1077514288 11.624 99 33 65 0 1108838048 23047888 8275872
02:18:17 5849 8192000000 9994624880 10395099136 1081821152 11.665 100 45 88 5 1108162672 16146240 10195280
02:19:25 61030 8192000000 9933501136 10318962688 969341696 10.580 93 18 35 1 990320240 17393264 3585280
02:21:27 10626 8192000000 9939072496 10322976768 1009737376 10.973 98 34 68 2 1032167600 15151824 7278400
02:22:39 7709 8192000000 9956504704 10341961728 1030141168 11.170 100 50 99 2 1053738480 11226320 12370992
02:23:50 7509 8192000000 9977308432 10364375040 1055922144 11.417 100 29 58 3 1085896704 24117344 5857216
02:24:59 52846 8192000000 9983646000 10391003136 1064873408 11.503 100 25 50 2 1094489856 24556848 5059600
02:26:52 58402 8192000000 10015523120 10411999232 1094771136 11.788 100 21 42 0 1117064400 18039632 4253632
02:28:54 28162 8192000000 10072156208 10469650432 1110263648 11.935 99 49 97 5 1164685440 43104944 11316848
02:30:26 13656 8192000000 10134078288 10535272448 1144672784 12.259 100 55 109 1 1233413408 76654048 12086576
02:31:43 29250 8192000000 10169652304 10583711744 1152073520 12.329 100 36 72 1 1281215872 120777984 8364368
02:33:16 10705 8192000000 10208344752 10612658176 1155786912 12.364 100 48 96 0 1309138880 142171632 11180336
02:34:36 19885 8192000000 10245747520 10654621696 1172008896 12.516 100 56 111 2 1362780896 177703344 13068656
02:36:02 3100 8192000000 10290054448 10693144576 1181323312 12.603 100 60 119 1 1389914000 194873104 13717584
02:37:07 15346 8192000000 10343640560 10754224128 1184230512 12.630 100 64 128 0 1444907120 245897008 14779600
02:38:28 13839 8192000000 10346004736 10772975616 1187737552 12.662 100 67 133 0 1451668288 248198048 15732688
02:39:49 4924 8192000000 10344542096 10767237120 1189048032 12.675 100 64 126 0 1438319824 235583744 13688048
02:40:59 2757 8192000000 10364350576 10778177536 1183064320 12.619 100 71 141 1 1465482208 264230256 18187632
02:42:06 3635 8192000000 10374210032 10783113216 1186469904 12.650 100 76 152 0 1475315888 271854224 16991760
02:43:16 4555 8192000000 10408317008 10831990784 1187181600 12.657 100 68 134 1 1501804048 298569136 16053312
02:44:23 28099 8192000000 10330677536 10769530880 1161080208 12.413 99 31 61 1 1426292160 257282752 7929200
02:46:16 18926 8192000000 10335018416 10789122048 1157725200 12.382 100 25 50 1 1437988192 274044816 6218176
02:48:09 9576 8192000000 10318370416 10757656576 1134760880 12.166 99 36 72 0 1412321936 268815024 8746032
02:49:34 18767 8192000000 10264045680 10696384512 1102554288 11.862 98 23 44 1 1389827216 280251664 7021264
02:51:11 56901 8192000000 10063041440 10503528448 1085327200 11.698 97 15 30 1 1166796448 78603792 2865456
02:53:11 25498 8192000000 10063903040 10488557568 1064043056 11.495 96 49 96 3 1116309744 41565808 10700880
02:54:42 24776 8192000000 10056239152 10517860352 1082226992 11.669 98 28 56 2 1134121680 46184208 5710480
02:56:13 112161 8192000000 10034383232 10470998016 1095824880 11.798 99 22 43 3 1120565248 20681824 4058544
02:59:06 40458 8192000000 10113018512 10542686208 1091747808 11.759 97 43 84 2 1180154400 80145088 8261504
03:00:52 25007 8192000000 10105950912 10567274496 1119812800 12.025 100 13 26 0 1216365024 94034032 2518192
03:02:18 68269 8192000000 10040260096 10479812608 1100186400 11.839 98 24 47 2 1145997248 40573376 5237472
03:04:27 67399 8192000000 10031972256 10483789824 1090072720 11.743 97 9 18 1 1131727376 40075296 1579360
03:07:02 38409 8192000000 10085405120 10522480640 1123709504 12.062 100 19 38 1 1159161664 31433952 4018208
03:08:45 43775 8192000000 10079568224 10511093760 1123253312 12.058 99 19 38 1 1152454176 25227584 3973280
03:10:29 34597 8192000000 10145107952 10609868800 1153842016 12.346 100 34 68 1 1262667472 101418784 7406672
03:12:08 39468 8192000000 10184059888 10629287936 1137067648 12.188 98 31 63 2 1277014560 132821584 7125328
03:14:00 44747 8192000000 10080634976 10510934016 1121555248 12.042 98 21 42 0 1155419424 29747040 4117136
03:15:46 44410 8192000000 10135855200 10567487488 1155153888 12.358 100 37 72 0 1210416560 47714816 7547856
03:17:42 105705 8192000000 10151266320 10619133952 1112173968 11.953 96 16 33 2 1218208656 102352736 3681952
03:20:28 92911 8192000000 10086139472 10548137984 1134731248 12.166 99 17 34 0 1175433280 37392592 3309440
03:23:03 32044 8192000000 10092699424 10568237056 1131370144 12.134 99 6 12 0 1195045728 62199968 1475616
03:24:35 40441 8192000000 10090664496 10567528448 1126960512 12.093 99 5 10 0 1192479360 64512160 1006688
03:26:16 35434 8192000000 10110634992 10543419392 1149094784 12.301 100 36 72 1 1190064896 33612160 7357952
03:27:52 40070 8192000000 10062205104 10506567680 1103648976 11.872 96 22 44 0 1138985664 31250528 4086160
03:29:33 31397 8192000000 10105469968 10540285952 1148568320 12.296 100 25 51 0 1183585664 29632128 5385216
03:31:15 90008 8192000000 10098417696 10596827136 1113382800 11.964 97 16 32 0 1216911968 100053024 3476144
03:34:25 40009 8192000000 10067737168 10523004928 1094457456 11.785 97 25 50 1 1157337728 58092512 4787760
03:36:10 18984 8192000000 10084988144 10495389696 1100048352 11.838 98 54 107 2 1143439440 32560464 10830624
03:37:31 38587 8192000000 10096197536 10530406400 1093641744 11.777 97 36 72 2 1148519360 47412224 7465392
03:39:18 87456 8192000000 10055255376 10489495552 1088507344 11.728 98 19 38 1 1141916624 49479680 3929600
03:41:48 54393 8192000000 10065980800 10520403968 1116224832 11.991 100 4 8 0 1166135968 49045056 866080
03:44:28 57932 8192000000 10039524720 10483851264 1071789792 11.569 96 23 46 1 1120708304 43455168 5463344
03:46:31 45549 8192000000 10066003056 10510049280 1101650832 11.853 98 20 40 1 1147175152 40911120 4613200
03:48:37 36905 8192000000 10070266064 10503417856 1100962224 11.847 98 16 32 3 1121223200 17290448 2970528
03:50:14 32674 8192000000 10060545808 10485260288 1105433840 11.889 98 31 61 3 1154105232 42299024 6372368
03:51:47 70774 8192000000 10103857200 10581848064 1107175952 11.906 98 19 38 1 1172442496 61756496 3510048
03:53:59 139149 8192000000 10083771280 10560077824 1106335408 11.898 98 8 16 0 1167733088 59516400 1881280
03:57:18 49057 8192000000 10072186272 10547355648 1130660960 12.128 100 8 16 1 1172997552 40973568 1363024
03:59:17 80735 8192000000 10066716368 10545344512 1143714224 12.250 100 7 14 1 1195241872 50144832 1382816
04:02:17 89507 8192000000 10076845056 10541690880 1077537344 11.624 94 15 30 0 1112686816 31612080 3537392
04:04:47 48419 8192000000 10123795888 10566275072 1120901728 12.036 98 24 48 1 1176767936 50878768 4987440
04:06:42 39945 8192000000 10155556416 10611531776 1137906848 12.196 99 44 88 0 1214877616 67284336 9686432
04:08:23 78391 8192000000 10082599264 10569977856 1105451104 11.889 97 11 22 1 1188358064 80664416 2242544
04:10:45 26727 8192000000 10076165184 10501963776 1146501456 12.277 100 44 88 2 1169695424 14317792 8876176
04:12:14 61767 8192000000 10102221712 10559664128 1139542368 12.211 99 30 60 0 1216574896 71115824 5916704
04:14:24 132478 8192000000 10117688752 10570833920 1145168544 12.264 99 21 42 0 1222351008 72173472 5008992
04:18:02 77543 8192000000 10170901792 10644865024 1161631520 12.419 100 14 28 0 1249975808 84801248 3543040
04:20:35 54826 8192000000 10085224384 10529845248 1113262080 11.963 96 16 32 1 1171858336 55123360 3472896
04:22:31 51135 8192000000 10093373856 10541527040 1094170704 11.782 96 16 32 2 1138567680 41108624 3288352
04:24:28 66233 8192000000 10072326944 10496319488 1073942144 11.590 95 13 26 0 1110482592 33746576 2793872
04:26:34 32783 8192000000 9200617648 9505259520 326115824 3.828 29 4 8 0 359811744 33087760 608160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-11-2020 04:28:02 to 10-11-2020 04:28:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 102 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-11-2020 04:28:18 to 10-11-2020 04:28:20.

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          234251
MON$OLDEST_ACTIVE               234252
MON$OLDEST_SNAPSHOT             234252
MON$NEXT_TRANSACTION            234252
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                       2074912
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             797
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2249 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.2249 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Tue Nov 10 04:27:14 2020
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

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