Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.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 16631000 kB
MemAvailable 31423356 kB
Buffers 20 kB
Cached 14870976 kB
SwapCached 1988 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-12-09 04:26:53
DTS_END                         2020-12-09 04:26:53.9800
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/51316
TRN_ID                          235058
ATT_ID                          669
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: 9261.61 25468 1111393
customer order: creation 164.95 448 19794
customer order: refuse 14.78 310 1773
order to supplier: creation 45.18 1365 5421
order to supplier: removal 3.71 103690 445
invoice (draft): creation 44.73 3381 5367
invoice (draft): removal 53.46 807 6415
invoice accept: apply 41.63 17534 4995
invoice accept: cancel 48.58 820 5830
customer reserve: creation 3754.76 175 450571
customer reserve: removal 4797.92 64 575750
realization accept: apply 79.82 164 9578
realization accept: cancel 85.37 109 10244
payment from customer: creation 57.48 108 6898
payment from customer: removal 5.99 78 719
payment to supplier: creation 49.23 126 5908
payment to supplier: removal 3.23 67 387
service: total inventory turnovers 3.42 3420 410
service: total monetary turnovers 6.48 573 777
service: aggregate perf. data 0.89 15241 107
service: refresh index statistics 0.03 360876 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2020 04:27:35 to 09-12-2020 04:27:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4275
TEST_TIME 2 5484
TEST_TIME 3 5871
TEST_TIME 4 5609
TEST_TIME 5 5933
TEST_TIME 6 5958
TEST_TIME 7 5788
TEST_TIME 8 6133
TEST_TIME 9 6371
TEST_TIME 10 6364
TEST_TIME 11 6886
TEST_TIME 12 7064
TEST_TIME 13 7225
TEST_TIME 14 8016
TEST_TIME 15 7941
TEST_TIME 16 8012
TEST_TIME 17 8440
TEST_TIME 18 8651
TEST_TIME 19 8706
TEST_TIME 20 8910
TEST_TIME 21 8963
TEST_TIME 22 9116
TEST_TIME 23 9202
TEST_TIME 24 9978
TEST_TIME 25 9940
TEST_TIME 26 10440
TEST_TIME 27 11449
TEST_TIME 28 11492
TEST_TIME 29 11219
TEST_TIME 30 10967
TEST_TIME 31 10630
TEST_TIME 32 10539
TEST_TIME 33 10542
TEST_TIME 34 10473
TEST_TIME 35 10306
TEST_TIME 36 10206
TEST_TIME 37 10252
TEST_TIME 38 10076
TEST_TIME 39 10239
TEST_TIME 40 10117
TEST_TIME 41 10079
TEST_TIME 42 9945
TEST_TIME 43 10136
TEST_TIME 44 10018
TEST_TIME 45 10025
TEST_TIME 46 9992
TEST_TIME 47 10064
TEST_TIME 48 10122
TEST_TIME 49 10146
TEST_TIME 50 10378
TEST_TIME 51 10297
TEST_TIME 52 10262
TEST_TIME 53 10297
TEST_TIME 54 10249
TEST_TIME 55 10106
TEST_TIME 56 10017
TEST_TIME 57 9909
TEST_TIME 58 9793
TEST_TIME 59 9695
TEST_TIME 60 9634
TEST_TIME 61 9568
TEST_TIME 62 9559
TEST_TIME 63 9518
TEST_TIME 64 9479
TEST_TIME 65 9461
TEST_TIME 66 9451
TEST_TIME 67 9474
TEST_TIME 68 9503
TEST_TIME 69 9487
TEST_TIME 70 9511
TEST_TIME 71 9646
TEST_TIME 72 9670
TEST_TIME 73 9579
TEST_TIME 74 9497
TEST_TIME 75 9459
TEST_TIME 76 9391
TEST_TIME 77 9334
TEST_TIME 78 9415
TEST_TIME 79 9379
TEST_TIME 80 9355
TEST_TIME 81 9334
TEST_TIME 82 9361
TEST_TIME 83 9407
TEST_TIME 84 9400
TEST_TIME 85 9434
TEST_TIME 86 9559
TEST_TIME 87 9500
TEST_TIME 88 9423
TEST_TIME 89 9381
TEST_TIME 90 9304
TEST_TIME 91 9254
TEST_TIME 92 9292
TEST_TIME 93 9248
TEST_TIME 94 9212
TEST_TIME 95 9206
TEST_TIME 96 9232
TEST_TIME 97 9248
TEST_TIME 98 9237
TEST_TIME 99 9243
TEST_TIME 100 9327
TEST_TIME 101 9373
TEST_TIME 102 9388
TEST_TIME 103 9403
TEST_TIME 104 9413
TEST_TIME 105 9376
TEST_TIME 106 9317
TEST_TIME 107 9284
TEST_TIME 108 9220
TEST_TIME 109 9211
TEST_TIME 110 9155
TEST_TIME 111 9148
TEST_TIME 112 9111
TEST_TIME 113 9109
TEST_TIME 114 9082
TEST_TIME 115 9052
TEST_TIME 116 9099
TEST_TIME 117 9167
TEST_TIME 118 9196
TEST_TIME 119 9243
TEST_TIME 120 9264

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-12-2020 04:27:38 to 09-12-2020 04:27:42.

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 1529320 1529320 0 0.00 0 20093 6 0 0
doc_list_biud 1529316 1529316 0 0.00 0 50037 30 0 0
sp_add_invoice_to_stock 5143 4995 148 2.87 40 207101 17535 0 148
sp_cancel_adding_invoice 5830 5830 0 0.00 5 72445 820 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 8027 311 0 0
sp_cancel_customer_reserve 575750 575750 0 0.00 1 50038 65 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 2575 78 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 3473 68 0 0
sp_cancel_supplier_invoice 6428 6415 13 0.20 2 19321 808 0 13
sp_cancel_supplier_order 445 445 0 0.00 5 1035050 103690 0 0
sp_cancel_write_off 10244 10244 0 0.00 1 13289 109 0 0
sp_client_order 19794 19794 0 0.00 4 27928 449 0 0
sp_customer_reserve 450571 450571 0 0.00 0 103918 176 0 0
sp_fill_shopping_cart 30557 30557 0 0.00 1 10938 42 0 0
sp_fill_shopping_cart_clo_res 450565 450565 0 0.00 0 28835 21 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 25425 424 0 0
sp_kill_cost_storno 17180 17180 0 0.00 0 5598 51 0 0
sp_kill_qstorno_ret_qs2qd 1168604 1168604 0 0.00 0 50035 35 0 0
sp_kill_qty_storno 615021 615021 0 0.00 0 50036 74 0 0
sp_lock_selected_doc 20350 20350 0 0.00 0 6861 7 0 0
sp_make_cost_storno 27373 27373 0 0.00 0 10605 50 0 0
sp_multiply_rows_for_pdistr 27372 27372 0 0.00 0 6965 31 0 0
sp_multiply_rows_for_qdistr 19794 19794 0 0.00 0 27917 276 0 0
sp_pay_from_customer 6898 6898 0 0.00 4 8292 108 0 0
sp_pay_to_supplier 5908 5908 0 0.00 4 11795 126 0 0
sp_payment_common 12806 12806 0 0.00 1 5691 19 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 8021 282 0 0
sp_qd_handle_on_invoice_upd_sts 10816 10816 0 0.00 0 30505 359 0 0
sp_qd_handle_on_reserve_upd_sts 9578 9578 0 0.00 0 17039 53 0 0
sp_reserve_write_off 9578 9578 0 0.00 7 17110 165 0 0
sp_supplier_invoice 5367 5367 0 0.00 6 89921 3381 0 0
sp_supplier_order 5421 5421 0 0.00 5 17197 1365 0 0
srv_aggregate_perf_data 107 107 0 0.00 2663 143429 15242 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1767 220954 46020 0 0
srv_find_qd_qs_mism 1065227 1065227 0 0.00 0 20085 5 0 0
srv_make_invnt_saldo 410 410 0 0.00 73 95314 3421 0 0
srv_make_money_saldo 777 777 0 0.00 1 21585 573 0 0
srv_recalc_idx_stat 4 4 0 0.00 268835 495306 360876 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 20346 174 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 2885 104 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 6318 18 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1271 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 438 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 943 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 981 17 0 0
t$perf-norm:sp_cancel_supplier_order 445 445 0 0.00 0 24829 1427 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 4542 10 0 0
t$perf-norm:sp_client_order 19794 19794 0 0.00 0 7632 15 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 2522 8 0 0
t$perf-norm:sp_pay_from_customer 6898 6898 0 0.00 0 4397 8 0 0
t$perf-norm:sp_pay_to_supplier 5908 5908 0 0.00 0 4548 9 0 0
t$perf-norm:sp_reserve_write_off 9578 9578 0 0.00 0 3798 10 0 0
t$perf-norm:sp_supplier_invoice 5367 5367 0 0.00 0 6268 20 0 0
t$perf-norm:sp_supplier_order 5421 5421 0 0.00 0 5913 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 163 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 151 6 0 0
t$perf-norm:srv_make_invnt_saldo 410 410 0 0.00 0 1914 18 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 5081 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10266 9970 296 2.88 0 4970 16 0 296
v_all_customers 36226 36226 0 0.00 0 29 1 0 0
v_all_suppliers 29914 29914 0 0.00 0 210 1 0 0
v_all_wares 39588 39588 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1481 7 0 0
v_cancel_client_order 3546 3546 0 0.00 0 2276 5 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 2555 9 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 1137 5 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 490 4 0 0
v_cancel_supplier_invoice 788 762 26 3.29 0 604 14 0 26
v_cancel_supplier_order 890 890 0 0.00 0 1473 6 0 0
v_cancel_write_off 1920 1920 0 0.00 0 8164 20 0 0
v_max_id_clo_ord 5413 5413 0 0.00 0 966 2 0 0
v_max_id_clo_res 932 932 0 0.00 0 2699 15 0 0
v_max_id_ord_sup 5350 5350 0 0.00 0 5506 9 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 2793 5 0 0
v_max_non_paid_realizn 3635 3635 0 0.00 0 2794 4 0 0
v_min_id_clo_ord 5413 5413 0 0.00 0 1057 5 0 0
v_min_id_clo_res 932 932 0 0.00 0 7583 18 0 0
v_min_id_ord_sup 5350 5350 0 0.00 0 7133 9 0 0
v_min_non_paid_invoice 5908 5908 0 0.00 0 2873 11 0 0
v_min_non_paid_realizn 6898 6898 0 0.00 0 3072 11 0 0
v_our_firm 9888 9888 0 0.00 0 20 1 0 0
v_reserve_write_off 19156 19156 0 0.00 0 2571 5 0 0
x_lock_depdocs_on_canc_invoice 11864 11864 0 0.00 0 10708 99 0 0
x_lock_depdocs_on_canc_sup_ord 445 445 0 0.00 0 8130 329 0 0
x_make_qstorno_1000_1200 5421 5421 0 0.00 2 17192 1283 0 0
x_make_qstorno_1200_2000 5367 5367 0 0.00 3 85774 3229 0 0
x_make_qstorno_2100_3300 450571 450571 0 0.00 0 103914 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2020 04:27:43 to 09-12-2020 04:27:47.

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:57 9 8192000000 8796641392 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:59 22323 8192000000 9709083200 10003759104 785940864 8.754 100 83 166 0 814065232 7619712 20504656
01:59:26 15736 8192000000 9762309872 10067259392 838486720 9.285 100 84 167 1 869216480 11379280 19350480
02:00:48 13934 8192000000 9796944720 10112225280 865015120 9.550 100 84 166 2 900985888 17079888 18890880
02:02:06 11234 8192000000 9861908208 10189950976 907450576 9.972 100 80 160 0 958983264 33647344 17885344
02:03:19 9219 8192000000 9916970960 10260307968 954232720 10.433 100 84 167 0 1032449136 60735872 17480544
02:04:30 7003 8192000000 9948228208 10300203008 986896048 10.751 100 91 180 3 1076507520 70833616 18777856
02:05:38 3515 8192000000 9994644496 10353201152 1019855024 11.071 100 91 182 0 1111680368 73765664 18059680
02:06:43 2855 8192000000 10043537648 10409971712 1040634256 11.271 100 85 170 1 1150732448 93574064 16524128
02:07:47 2111 8192000000 10023924608 10392260608 1060449504 11.461 100 93 185 0 1130788912 50989040 19350368
02:08:50 17494 8192000000 10011946016 10399125504 1064942560 11.504 100 62 125 3 1143546224 65875072 12728592
02:10:08 5841 8192000000 9992276944 10383794176 1076304224 11.612 100 37 74 1 1108241440 24385408 7551808
02:11:17 9593 8192000000 10018684304 10424315904 1086038704 11.705 100 38 75 1 1118781104 24623040 8119360
02:12:30 4936 8192000000 10013223328 10435313664 1087133984 11.715 100 29 58 1 1144759536 51308112 6317440
02:13:36 7294 8192000000 10003474752 10403536896 1088146400 11.725 100 22 44 1 1114821504 21411024 5264080
02:14:45 3897 8192000000 10032077520 10429386752 1095554784 11.795 100 51 102 2 1145418512 38554864 11308864
02:15:52 62974 8192000000 9990275600 10402193408 1052787888 11.387 96 36 72 0 1088091152 27550240 7753024
02:18:01 3678 8192000000 9999694000 10394312704 1034062352 11.208 95 42 84 1 1073021424 29900960 9058112
02:19:06 6524 8192000000 9978406352 10373398528 1043996720 11.303 99 35 67 1 1077807712 26673056 7137936
02:20:15 10052 8192000000 9927705808 10318073856 968117040 10.568 94 24 48 2 991944448 18601680 5225728
02:21:26 22447 8192000000 9914494256 10309660672 974991536 10.635 96 26 52 0 1005240512 24217504 6031472
02:22:52 5025 8192000000 9948835952 10326040576 1015986064 11.033 99 54 106 2 1049058912 22113856 10958992
02:23:59 5248 8192000000 9968982784 10349821952 1043145440 11.295 100 39 77 2 1073745648 22298160 8302048
02:25:05 58288 8192000000 9979181808 10371428352 1053833728 11.397 100 46 92 4 1074121296 9966336 10321232
02:27:14 10785 8192000000 10001022672 10397605888 1076212704 11.611 100 59 119 1 1117664496 27910640 13541152
02:28:38 2693 8192000000 10034494000 10424967168 1090676272 11.749 100 61 123 1 1143389664 38645040 14068352
02:29:45 5360 8192000000 10067646096 10465964032 1106171408 11.896 100 58 113 2 1181457216 63816640 11469168
02:30:59 38683 8192000000 10119992352 10538369024 1116427040 11.993 100 41 82 1 1254173376 129014624 8731712
02:32:45 6040 8192000000 10120923968 10552180736 1128076208 12.103 100 38 74 2 1233243616 97294608 7872800
02:33:56 20274 8192000000 10159920032 10571718656 1133282464 12.152 100 53 105 1 1257017376 111515280 12219632
02:35:19 4594 8192000000 10163130160 10562871296 1139116208 12.207 100 65 128 0 1271384496 117991616 14276672
02:36:30 3138 8192000000 10223482928 10625196032 1147882912 12.290 100 69 134 0 1325102816 163243872 13976032
02:37:52 3012 8192000000 10235793120 10667270144 1154546608 12.352 100 60 117 1 1336478208 168721808 13209792
02:38:59 3253 8192000000 10293296864 10695139328 1167751904 12.476 100 90 180 1 1382589824 195196288 19641632
02:40:08 1766 8192000000 10300249440 10704592896 1148998864 12.300 99 85 171 0 1399686416 231383296 19304256
02:41:15 3097 8192000000 10253635264 10661867520 1150676912 12.316 99 63 126 0 1359480336 194977792 13825632
02:42:25 4749 8192000000 10321494848 10731167744 1157218640 12.377 100 78 155 0 1422689008 247593936 17876432
02:43:34 3131 8192000000 10324511568 10748182528 1146515168 12.277 100 84 168 2 1417463296 251538704 19409424
02:44:43 2141 8192000000 10311399904 10713817088 1150235040 12.312 100 81 157 1 1408739424 239168496 19335888
02:45:50 2637 8192000000 10336057248 10738380800 1160182928 12.405 100 84 166 1 1432200432 252369344 19648160
02:46:57 12729 8192000000 10366484864 10787962880 1172078608 12.516 100 35 70 0 1454088480 273078128 8931744
02:48:19 21850 8192000000 10407960432 10842714112 1150348256 12.313 99 34 68 1 1499919872 340759280 8812336
02:49:44 16446 8192000000 10418579728 10849402880 1153453552 12.342 100 35 70 1 1506249904 343418016 9378336
02:51:04 22437 8192000000 10316452512 10762338304 1128973968 12.112 100 25 50 0 1415625216 279831152 6820096
02:52:28 25883 8192000000 10289573456 10826756096 1128450672 12.107 100 18 36 1 1389022272 255460448 5111152
02:54:41 41060 8192000000 10036071776 10462474240 1081321008 11.660 96 32 64 4 1104923936 15942800 7660128
02:56:23 220954 8192000000 10045300032 10500153344 1067854000 11.532 95 33 64 1 1118821968 45084848 5883120
03:01:04 32630 8192000000 10087106864 10519330816 1117438288 12.003 99 41 82 3 1177638656 51542992 8657376
03:02:40 121119 8192000000 10130297488 10576179200 1122521440 12.051 99 34 68 1 1223702864 93384384 7797040
03:05:42 95708 8192000000 10077653664 10534572032 1119780448 12.025 99 15 30 1 1210262448 87735088 2746912
03:08:21 44670 8192000000 10116954352 10573737984 1146810720 12.280 100 9 17 0 1212315008 63800144 1704144
03:10:16 41159 8192000000 10116350592 10549743616 1128768720 12.110 98 29 58 1 1185944240 50499984 6675536
03:11:58 46895 8192000000 10161956416 10634764288 1151077008 12.320 100 20 40 2 1258212480 102554064 4581408
03:13:45 38885 8192000000 10185213584 10624606208 1123353648 12.059 97 37 74 2 1232007408 100316752 8337008
03:15:27 70432 8192000000 10150258352 10626453504 1140223664 12.218 99 17 34 0 1240941488 96790496 3927328
03:17:38 63076 8192000000 10111282320 10546819072 1124411680 12.069 98 25 50 0 1221110400 90851440 5847280
03:19:48 48149 8192000000 10099250768 10550185984 1123681632 12.062 98 44 88 1 1191771008 59349056 8740320
03:22:32 46504 8192000000 10055165872 10523611136 1119249008 12.020 98 9 18 0 1156365616 35302912 1813696
03:24:23 32979 8192000000 10054102976 10517508096 1116961008 11.998 98 2 4 0 1145678464 28431552 285904
03:25:57 60260 8192000000 10063687376 10532937728 1119269088 12.020 98 14 28 0 1165862000 43724960 2867952
03:28:06 47860 8192000000 10064576208 10509987840 1123446240 12.060 99 24 48 1 1166827616 38009040 5372336
03:30:02 13125 8192000000 10069411920 10509725696 1122001360 12.046 100 50 100 2 1162288784 29617600 10669824
03:31:20 56113 8192000000 10094108624 10548879360 1106060480 11.895 99 23 46 0 1162154208 51215728 4878000
03:33:16 58188 8192000000 10070385056 10534936576 1122299552 12.049 100 21 42 1 1190032368 63372720 4360096
03:35:20 64954 8192000000 10110861840 10587570176 1094121728 11.782 97 20 40 0 1170789072 72503264 4164080
03:37:48 99790 8192000000 10065869472 10537971712 1064164352 11.496 94 15 30 1 1108455744 41528544 2762848
03:40:30 72256 8192000000 10041640640 10489831424 1073808496 11.588 96 12 24 0 1116124048 40443824 1871728
03:42:43 45266 8192000000 10054170816 10483724288 1062136560 11.477 95 19 38 2 1100265504 33696304 4432640
03:44:38 78524 8192000000 10108829456 10589831168 1079739888 11.645 96 18 36 0 1143653776 59973328 3940560
03:47:05 38629 8192000000 10120852208 10550804480 1103474560 11.871 98 27 54 2 1187970032 78732048 5763424
03:49:15 62922 8192000000 10100443584 10547081216 1124786736 12.072 100 19 38 1 1215575872 86186928 4602208
03:51:42 42337 8192000000 10132503824 10651410432 1109374880 11.926 98 10 20 1 1225498096 113969664 2153552
03:53:25 19054 8192000000 10056877088 10475888640 1104120704 11.877 98 49 99 0 1127157504 12976160 10060640
03:54:44 117769 8192000000 10124009568 10581557248 1119683488 12.024 99 24 48 2 1205327536 80780944 4863104
03:59:11 54435 8192000000 10071797456 10501091328 1106270784 11.897 98 18 36 2 1148123344 37840032 4012528
04:01:08 67631 8192000000 10105899840 10579869696 1125451456 12.078 99 18 34 2 1220923632 91986832 3485344
04:03:27 54408 8192000000 10137795760 10586558464 1120909008 12.036 98 24 48 1 1205975760 79416368 5650384
04:05:22 71754 8192000000 10162041952 10653413376 1129195488 12.114 98 23 45 2 1246495008 112328384 4971136
04:07:35 47262 8192000000 10079149504 10536534016 1119044176 12.018 98 24 48 0 1146583632 22960880 4578576
04:09:43 110299 8192000000 10090295440 10586390528 1125109680 12.075 98 11 22 0 1151182048 24310656 1761712
04:12:35 55486 8192000000 10068552048 10528051200 1132535328 12.145 99 12 22 0 1167169248 32679920 1954000
04:15:02 84491 8192000000 10065619120 10559328256 1121573392 12.042 98 8 15 0 1159652304 36532304 1546608
04:17:29 42254 8192000000 10102580320 10572652544 1148985920 12.300 100 13 25 0 1200156992 48652512 2518560
04:19:12 29020 8192000000 10140907456 10587217920 1139569536 12.211 99 42 83 6 1225520080 77197536 8753008
04:20:41 56565 8192000000 10159476192 10602115072 1136816128 12.186 99 27 54 1 1240876384 97796512 6263744
04:22:40 85286 8192000000 10072993648 10604187648 1061198064 11.468 94 22 44 0 1149866848 83883168 4785616
04:25:15 104259 8192000000 9467084640 9834188800 553771360 6.331 50 4 8 0 591413776 37106208 536208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2020 04:27:49 to 09-12-2020 04:28:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2020 04:28:04 to 09-12-2020 04:28:06.

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          234280
MON$OLDEST_ACTIVE               235312
MON$OLDEST_SNAPSHOT             235312
MON$NEXT_TRANSACTION            235312
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                       2111808
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             795
MON$NEXT_STATEMENT              53


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

09.12.20 04:28:07. 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-12-09 04:28:10 No severe PSQL-related problems occured
09.12.20 04:28:11. End of report.