Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2020 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 16950928 kB
MemAvailable 31512788 kB
Buffers 0 kB
Cached 14748228 kB
SwapCached 2868 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-03 13:26:52
DTS_END                         2020-11-03 13:26:52.9820
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/48836
TRN_ID                          226869
ATT_ID                          642
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: 8854.15 21948 1062498
customer order: creation 165.46 416 19855
customer order: refuse 14.03 367 1684
order to supplier: creation 43.32 1358 5198
order to supplier: removal 3.32 120641 398
invoice (draft): creation 44.57 3656 5348
invoice (draft): removal 49.92 973 5990
invoice accept: apply 40.04 19914 4805
invoice accept: cancel 44.57 992 5348
customer reserve: creation 3779.82 190 453578
customer reserve: removal 4388.23 71 526587
realization accept: apply 80.45 183 9654
realization accept: cancel 76.90 133 9228
payment from customer: creation 57.45 111 6894
payment from customer: removal 5.51 65 661
payment to supplier: creation 46.72 114 5606
payment to supplier: removal 3.33 106 400
service: total inventory turnovers 3.77 3811 452
service: total monetary turnovers 5.95 571 714
service: aggregate perf. data 0.78 19420 93
service: refresh index statistics 0.04 265860 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2020 13:29:11 to 03-11-2020 13:29:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2022
TEST_TIME 2 3031
TEST_TIME 3 4068
TEST_TIME 4 4064
TEST_TIME 5 4429
TEST_TIME 6 4593
TEST_TIME 7 4532
TEST_TIME 8 4851
TEST_TIME 9 4754
TEST_TIME 10 5145
TEST_TIME 11 5547
TEST_TIME 12 5579
TEST_TIME 13 5931
TEST_TIME 14 6259
TEST_TIME 15 6535
TEST_TIME 16 6687
TEST_TIME 17 6938
TEST_TIME 18 6885
TEST_TIME 19 7343
TEST_TIME 20 7310
TEST_TIME 21 7802
TEST_TIME 22 8052
TEST_TIME 23 8312
TEST_TIME 24 8233
TEST_TIME 25 8388
TEST_TIME 26 8691
TEST_TIME 27 9029
TEST_TIME 28 9233
TEST_TIME 29 9956
TEST_TIME 30 10338
TEST_TIME 31 10181
TEST_TIME 32 10236
TEST_TIME 33 10077
TEST_TIME 34 9908
TEST_TIME 35 9749
TEST_TIME 36 9612
TEST_TIME 37 9554
TEST_TIME 38 9614
TEST_TIME 39 9641
TEST_TIME 40 9577
TEST_TIME 41 9541
TEST_TIME 42 9532
TEST_TIME 43 9504
TEST_TIME 44 9390
TEST_TIME 45 9261
TEST_TIME 46 9240
TEST_TIME 47 9240
TEST_TIME 48 9130
TEST_TIME 49 9465
TEST_TIME 50 9438
TEST_TIME 51 9430
TEST_TIME 52 9430
TEST_TIME 53 9456
TEST_TIME 54 9551
TEST_TIME 55 9485
TEST_TIME 56 9426
TEST_TIME 57 9473
TEST_TIME 58 9480
TEST_TIME 59 9517
TEST_TIME 60 9426
TEST_TIME 61 9283
TEST_TIME 62 9266
TEST_TIME 63 9202
TEST_TIME 64 9088
TEST_TIME 65 9108
TEST_TIME 66 9108
TEST_TIME 67 9170
TEST_TIME 68 9149
TEST_TIME 69 9098
TEST_TIME 70 9092
TEST_TIME 71 9110
TEST_TIME 72 9233
TEST_TIME 73 9187
TEST_TIME 74 9157
TEST_TIME 75 9157
TEST_TIME 76 9308
TEST_TIME 77 9281
TEST_TIME 78 9249
TEST_TIME 79 9332
TEST_TIME 80 9309
TEST_TIME 81 9198
TEST_TIME 82 9116
TEST_TIME 83 9065
TEST_TIME 84 9023
TEST_TIME 85 8955
TEST_TIME 86 8933
TEST_TIME 87 8912
TEST_TIME 88 8930
TEST_TIME 89 8929
TEST_TIME 90 8925
TEST_TIME 91 8904
TEST_TIME 92 8887
TEST_TIME 93 8892
TEST_TIME 94 8938
TEST_TIME 95 8980
TEST_TIME 96 9013
TEST_TIME 97 9052
TEST_TIME 98 9079
TEST_TIME 99 9095
TEST_TIME 100 9035
TEST_TIME 101 8964
TEST_TIME 102 8933
TEST_TIME 103 8903
TEST_TIME 104 8900
TEST_TIME 105 8873
TEST_TIME 106 8848
TEST_TIME 107 8838
TEST_TIME 108 8866
TEST_TIME 109 8855
TEST_TIME 110 8867
TEST_TIME 111 8873
TEST_TIME 112 8897
TEST_TIME 113 8938
TEST_TIME 114 8971
TEST_TIME 115 9016
TEST_TIME 116 8982
TEST_TIME 117 8946
TEST_TIME 118 8913
TEST_TIME 119 8866
TEST_TIME 120 8855

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2020 13:29:15 to 03-11-2020 13:29:18.

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 1480128 1480128 0 0.00 0 16958 7 0 0
doc_list_biud 1480128 1480128 0 0.00 0 47319 32 0 0
sp_add_invoice_to_stock 4912 4805 107 2.17 18 196670 19915 0 107
sp_cancel_adding_invoice 5348 5348 0 0.00 5 68144 993 0 0
sp_cancel_client_order 1684 1684 0 0.00 5 7734 368 0 0
sp_cancel_customer_reserve 526587 526587 0 0.00 1 47321 72 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 964 66 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 6608 107 0 0
sp_cancel_supplier_invoice 5997 5990 7 0.11 2 20112 974 0 7
sp_cancel_supplier_order 398 398 0 0.00 10 1028525 120642 0 0
sp_cancel_write_off 9228 9228 0 0.00 1 9499 133 0 0
sp_client_order 19855 19855 0 0.00 4 23880 417 0 0
sp_customer_reserve 453578 453578 0 0.00 0 113538 191 0 0
sp_fill_shopping_cart 30386 30386 0 0.00 1 19299 47 0 0
sp_fill_shopping_cart_clo_res 453576 453576 0 0.00 0 26689 20 0 0
sp_get_clo_for_invoice 4803 4803 0 0.00 0 47115 404 0 0
sp_kill_cost_storno 15637 15637 0 0.00 0 6924 64 0 0
sp_kill_qstorno_ret_qs2qd 1068790 1068790 0 0.00 0 46929 39 0 0
sp_kill_qty_storno 563692 563692 0 0.00 0 47318 84 0 0
sp_lock_selected_doc 20019 20019 0 0.00 0 8941 6 0 0
sp_make_cost_storno 26957 26957 0 0.00 0 16950 55 0 0
sp_multiply_rows_for_pdistr 26957 26957 0 0.00 0 7646 33 0 0
sp_multiply_rows_for_qdistr 19855 19855 0 0.00 0 14682 231 0 0
sp_pay_from_customer 6894 6894 0 0.00 4 10429 111 0 0
sp_pay_to_supplier 5606 5606 0 0.00 5 8061 115 0 0
sp_payment_common 12500 12500 0 0.00 1 10362 21 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 7727 334 0 0
sp_qd_handle_on_invoice_upd_sts 10151 10151 0 0.00 0 23680 438 0 0
sp_qd_handle_on_reserve_upd_sts 9654 9654 0 0.00 0 12123 56 0 0
sp_reserve_write_off 9654 9654 0 0.00 7 16971 184 0 0
sp_supplier_invoice 5348 5348 0 0.00 7 90740 3656 0 0
sp_supplier_order 5198 5198 0 0.00 6 73839 1359 0 0
srv_aggregate_perf_data 93 93 0 0.00 2495 344397 19421 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 783 132376 43309 0 0
srv_find_qd_qs_mism 1015137 1015137 0 0.00 0 14577 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 56 115390 3812 0 0
srv_make_money_saldo 714 714 0 0.00 1 15244 572 0 0
srv_recalc_idx_stat 5 5 0 0.00 71415 377702 265860 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4805 4805 0 0.00 0 9682 176 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 5381 112 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 9986 17 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2483 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 4745 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1002 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 1748 20 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 16792 1604 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 2772 7 0 0
t$perf-norm:sp_client_order 19855 19855 0 0.00 0 10973 21 0 0
t$perf-norm:sp_customer_reserve 848 848 0 0.00 0 723 5 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 18700 13 0 0
t$perf-norm:sp_pay_to_supplier 5606 5606 0 0.00 0 5836 10 0 0
t$perf-norm:sp_reserve_write_off 9654 9654 0 0.00 0 18942 15 0 0
t$perf-norm:sp_supplier_invoice 5348 5348 0 0.00 0 6023 20 0 0
t$perf-norm:sp_supplier_order 5198 5198 0 0.00 0 17694 21 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 391 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 262 5 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 1818 14 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 768 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 13 6 0 0
v_add_invoice_to_stock 9820 9606 214 2.17 0 6301 17 0 214
v_all_customers 36282 36282 0 0.00 0 29 1 0 0
v_all_suppliers 29300 29300 0 0.00 0 18 1 0 0
v_all_wares 39710 39710 0 0.00 0 222 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 154 6 0 0
v_cancel_client_order 3368 3368 0 0.00 0 2474 7 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 903 6 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 2606 7 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 704 5 0 0
v_cancel_supplier_invoice 760 746 14 1.84 0 591 13 0 14
v_cancel_supplier_order 796 796 0 0.00 0 331 4 0 0
v_cancel_write_off 1940 1940 0 0.00 0 2911 16 0 0
v_max_id_clo_ord 5197 5197 0 0.00 0 1404 1 0 0
v_max_id_clo_res 848 848 0 0.00 0 2941 13 0 0
v_max_id_ord_sup 5334 5334 0 0.00 0 3105 5 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 138 2 0 0
v_max_non_paid_realizn 3705 3705 0 0.00 0 2624 4 0 0
v_min_id_clo_ord 5197 5197 0 0.00 0 12155 11 0 0
v_min_id_clo_res 848 848 0 0.00 0 5099 17 0 0
v_min_id_ord_sup 5334 5334 0 0.00 0 7700 12 0 0
v_min_non_paid_invoice 5606 5606 0 0.00 0 6357 12 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 7766 15 0 0
v_our_firm 9806 9806 0 0.00 0 17 1 0 0
v_reserve_write_off 19308 19308 0 0.00 0 2636 6 0 0
x_lock_depdocs_on_canc_invoice 10965 10965 0 0.00 0 12118 119 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 16898 441 0 0
x_make_qstorno_1000_1200 5198 5198 0 0.00 2 73830 1269 0 0
x_make_qstorno_1200_2000 5348 5348 0 0.00 3 90731 3478 0 0
x_make_qstorno_2100_3300 453578 453578 0 0.00 0 113537 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2020 13:29:19 to 03-11-2020 13:29:23.

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:56 9 8192000000 8799691648 8811499520 5501696 0.067 2 1 2 0 5632400 68400 62304
10:57:57 29178 8192000000 9719240096 10018361344 792425120 8.819 100 80 160 0 819630064 7386304 19818640
10:59:33 19728 8192000000 9760487280 10066337792 831361456 9.213 100 87 174 0 863730112 11030976 21337680
11:00:56 25225 8192000000 9794641568 10110296064 858339600 9.484 100 80 160 1 897499008 20399376 18760032
11:02:24 16446 8192000000 9847357424 10180149248 906746832 9.965 100 75 150 0 963683328 39900352 17036144
11:03:43 14388 8192000000 9919654976 10267877376 957281936 10.462 100 77 152 1 1037915136 64954816 15678384
11:05:00 6438 8192000000 9952880176 10308837376 999037504 10.869 100 86 171 1 1072121440 55631040 17452896
11:06:09 11835 8192000000 10000330240 10368974848 1023514752 11.106 100 83 165 0 1121436544 81689936 16231856
11:07:23 2452 8192000000 10041332080 10409050112 1042566400 11.289 100 90 179 1 1150770816 90041264 18163152
11:08:27 2266 8192000000 10022965856 10391547904 1055616240 11.415 100 83 165 0 1133137376 61255936 16265200
11:09:33 11116 8192000000 10014177952 10396950528 1060518064 11.461 100 52 102 0 1127425136 56586592 10320480
11:10:47 7710 8192000000 9980889600 10376384512 1067464048 11.528 100 28 56 2 1106357136 32388928 6504160
11:11:57 6696 8192000000 9982528720 10380525568 1078163760 11.630 100 28 55 2 1112677184 28770336 5743088
11:13:04 17184 8192000000 9987361648 10389385216 1085965808 11.704 100 41 82 4 1111997088 17542368 8488912
11:14:28 11229 8192000000 10014865168 10399084544 1089171664 11.735 100 74 150 3 1114129760 8636720 16321376
11:15:40 3873 8192000000 10018417744 10415611904 1084830256 11.693 99 35 69 1 1129223600 36796288 7597056
11:16:44 8067 8192000000 9989675456 10393718784 1073571104 11.586 99 44 88 2 1092883632 9244464 10068064
11:17:53 7131 8192000000 9987097792 10385289216 1063119568 11.486 99 25 50 1 1086268528 17953664 5195296
11:19:03 46969 8192000000 9951302000 10347823104 1010141792 10.977 95 27 54 1 1034237600 18055280 6040528
11:20:51 5879 8192000000 9938427440 10327093248 1019856128 11.071 98 38 74 2 1056954560 28242416 8856016
11:21:57 6721 8192000000 9927963600 10303352832 1012106144 10.996 99 34 66 3 1029570816 9390912 8073760
11:23:09 8748 8192000000 9958294688 10344562688 1010706448 10.982 98 49 96 0 1040591712 19315904 10569360
11:24:18 50676 8192000000 9951513296 10348408832 1039661104 11.261 100 34 66 1 1070969424 24678016 6630304
11:26:15 7661 8192000000 9963561312 10362527744 1061084960 11.467 100 44 87 0 1088237312 16287744 10864608
11:27:25 10335 8192000000 10003752992 10393391104 1080083424 11.648 100 49 99 1 1111825840 21703648 10038768
11:28:52 15321 8192000000 10070961584 10455404544 1094924864 11.789 100 56 111 1 1176910352 70266128 11719360
11:30:11 19417 8192000000 10074595472 10473758720 1107098736 11.905 100 44 90 0 1175829696 58834480 9896480
11:31:36 30018 8192000000 10104856704 10527272960 1125073040 12.075 100 44 88 1 1230345824 95164832 10107952
11:33:14 23231 8192000000 10128713872 10553573376 1126312096 12.087 100 46 93 2 1245119680 107677776 11129808
11:34:50 38121 8192000000 10151701216 10570932224 1141932608 12.234 100 39 78 3 1271922304 119902416 10087280
11:36:34 5245 8192000000 10221530560 10621820928 1150073312 12.310 100 66 132 0 1328243424 163993856 14176256
11:37:46 6101 8192000000 10241424576 10642665472 1158082144 12.385 100 76 152 1 1341444496 166675264 16687088
11:39:04 6270 8192000000 10265594784 10673856512 1160687904 12.410 100 63 123 2 1361763664 188219344 12856416
11:40:15 3324 8192000000 10268164912 10673168384 1166369200 12.463 100 75 148 1 1370857104 187734416 16753488
11:41:23 8333 8192000000 10287536288 10707369984 1168265328 12.481 100 70 140 2 1385584688 201859632 15459728
11:42:37 779 8192000000 10302766912 10701639680 1169862144 12.496 100 88 174 3 1402763776 214416528 18485104
11:43:42 2375 8192000000 10324445312 10738036736 1168095376 12.479 100 78 154 2 1434456304 248117760 18243168
11:44:53 4806 8192000000 10348725328 10757570560 1166704384 12.466 100 76 150 1 1453056352 269316352 17035616
11:46:08 2486 8192000000 10357510208 10759233536 1166572416 12.465 100 90 178 2 1454804128 267512592 20719120
11:47:15 2197 8192000000 10386200336 10814971904 1176061280 12.553 100 83 166 1 1489219792 292747952 20410560
11:48:24 3119 8192000000 10332192624 10734616576 1126750416 12.091 99 81 162 0 1428452736 282312512 19389808
11:49:36 10405 8192000000 10348061200 10774638592 1122917408 12.055 99 36 72 1 1435794336 303781872 9095056
11:51:02 3104 8192000000 10347949472 10746171392 1131092640 12.132 100 81 157 3 1445687136 296664528 17929968
11:52:09 20102 8192000000 10348610192 10807062528 1118523856 12.013 100 33 66 1 1464531616 336940240 9067520
11:53:31 6400 8192000000 10286315776 10731532288 1110964768 11.942 100 39 78 1 1381004912 260793600 9246544
11:55:08 12442 8192000000 10204376864 10660712448 1101875056 11.855 99 18 36 0 1330877904 224325344 4677504
11:56:22 40247 8192000000 10013830816 10455355392 1049977984 11.360 96 12 22 1 1089694480 37262384 2454112
11:58:03 67278 8192000000 10050904416 10484518912 1122966272 12.055 100 22 44 0 1150994608 23739456 4288880
12:00:12 132376 8192000000 10123485072 10572488704 1142222512 12.236 100 39 78 0 1217811984 67386416 8203056
12:04:28 64163 8192000000 10069782320 10535608320 1104995120 11.885 97 17 34 1 1156957264 47902096 4060048
12:06:38 7461 8192000000 10102310560 10527154176 1120278368 12.030 99 58 114 5 1193108832 61136880 11693584
12:07:58 87339 8192000000 10065811712 10514649088 1101755536 11.854 97 20 40 2 1171306624 65129696 4421392
12:10:26 64889 8192000000 10098926112 10542837760 1114642624 11.976 98 14 28 1 1175246576 57400624 3203328
12:12:32 62850 8192000000 10157167312 10632458240 1140435520 12.220 100 15 30 0 1277004528 132985152 3583856
12:15:40 40852 8192000000 10076627984 10508881920 1137281536 12.190 100 23 45 1 1203994576 61668208 5044832
12:17:21 13944 8192000000 10072949152 10506747904 1141590384 12.230 100 61 122 1 1181159184 26556592 13012208
12:18:39 16497 8192000000 10109959776 10557366272 1140382624 12.219 99 42 83 0 1191153536 42555440 8215472
12:19:57 90988 8192000000 10046326032 10495799296 1090174304 11.744 95 13 26 1 1156980496 64108688 2697504
12:22:30 37566 8192000000 10126588384 10557325312 1111242720 11.944 97 38 73 1 1168989008 50261664 7484624
12:24:08 72911 8192000000 10105385808 10551906304 1135727552 12.175 100 18 36 1 1185803760 46279456 3796752
12:26:22 111903 8192000000 10080099248 10512613376 1119585664 12.023 98 29 58 3 1191083456 65499632 5998160
12:29:17 36442 8192000000 10105542848 10585563136 1122230544 12.048 98 9 17 1 1208633088 84491312 1911232
12:31:36 47609 8192000000 10114680304 10598633472 1114117472 11.971 98 6 13 0 1206987456 91153120 1716864
12:33:24 99445 8192000000 10073446688 10529370112 1073006752 11.581 95 19 38 0 1157836592 80719648 4110192
12:36:04 69130 8192000000 10099857232 10560180224 1083932576 11.685 96 28 57 1 1155238016 65305504 5999936
12:39:36 34957 8192000000 10078373392 10488647680 1089910640 11.742 97 41 80 2 1145841200 46948880 8981680
12:41:14 44959 8192000000 10145536272 10598301696 1085956928 11.704 98 33 65 2 1218670624 126024032 6689664
12:42:59 40899 8192000000 10045932736 10476003328 1075177664 11.601 97 24 48 0 1120050960 40094944 4778352
12:44:41 43431 8192000000 10057572512 10511261696 1089157264 11.735 98 30 60 2 1148167232 52798896 6211072
12:46:35 106223 8192000000 10045401456 10494705664 1106860848 11.903 100 15 30 1 1147166496 37074048 3231600
12:49:37 46167 8192000000 10042111392 10494656512 1100776224 11.845 99 9 18 0 1152072528 49412768 1883536
12:51:23 44561 8192000000 10051372608 10509623296 1098738416 11.826 99 10 20 1 1141998016 41423360 1836240
12:53:08 58342 8192000000 10062526096 10506940416 1102162880 11.858 99 12 24 3 1171344736 66227408 2954448
12:55:24 69635 8192000000 10058533200 10498711552 1121721888 12.043 100 20 39 1 1178573152 52577344 4273920
12:58:03 37871 8192000000 10086047280 10516815872 1125144368 12.076 99 35 70 0 1179611328 47413552 7053408
12:59:41 72140 8192000000 10134264288 10613104640 1124660464 12.071 99 33 65 1 1209052752 77534704 6857584
13:01:56 58328 8192000000 10081381216 10514554880 1110511296 11.937 98 14 28 0 1175557792 62402560 2643936
13:03:54 41096 8192000000 10110491072 10543878144 1129399376 12.116 99 22 44 1 1173841744 40179920 4262448
13:06:12 108589 8192000000 10072046752 10522771456 1135005984 12.169 100 17 34 0 1185710464 46860560 3843920
13:09:19 48949 8192000000 10060048896 10527776768 1128001904 12.103 99 4 8 0 1162334768 33522032 810832
13:11:08 32025 8192000000 10072975360 10516525056 1134787392 12.166 100 12 25 1 1164248832 27147664 2313776
13:12:45 80301 8192000000 10140097920 10580717568 1151328512 12.322 100 18 36 1 1211874128 56719376 3826240
13:15:20 76867 8192000000 10091128864 10543611904 1103604800 11.872 96 18 36 0 1168234208 60743360 3886048
13:17:42 41656 8192000000 10150780368 10590093312 1155875456 12.365 100 28 56 0 1256984256 94960176 6148624
13:19:33 94525 8192000000 10120796032 10601078784 1122317584 12.049 98 9 18 1 1211352560 87200032 1834944
13:23:33 97758 8192000000 10105092544 10559434752 1132034448 12.141 99 20 40 0 1203421424 67273856 4113120
13:26:15 51278 8192000000 9527406112 9903403008 633192944 7.174 56 3 6 0 668749936 35033824 523168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2020 13:29:25 to 03-11-2020 13:29:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2020 13:29:41 to 03-11-2020 13:29:43.

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          226868
MON$OLDEST_ACTIVE               227575
MON$OLDEST_SNAPSHOT             227575
MON$NEXT_TRANSACTION            227575
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                       2091840
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             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2243 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.2243 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 ---
:60: 
:61: mscs-test-161.budzdorov.ru    Tue Nov  3 13:27:14 2020
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
--- end of diff output ---

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

03.11.20 13:29:44. 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-03 13:29:46 No severe PSQL-related problems occured
03.11.20 13:29:47. End of report.