Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.11.2020 10:29
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 17027536 kB
MemAvailable 31516532 kB
Buffers 0 kB
Cached 14676828 kB
SwapCached 3308 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-22 10:26:54
DTS_END                         2020-11-22 10:26:54.9090
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/36000
TRN_ID                          217803
ATT_ID                          656
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: 8803.46 26594 1056415
customer order: creation 152.47 443 18296
customer order: refuse 12.58 379 1510
order to supplier: creation 41.09 1205 4931
order to supplier: removal 3.37 108736 404
invoice (draft): creation 40.89 3192 4907
invoice (draft): removal 51.58 826 6189
invoice accept: apply 37.09 18925 4451
invoice accept: cancel 46.55 828 5586
customer reserve: creation 3513.68 181 421642
customer reserve: removal 4637.88 63 556545
realization accept: apply 74.58 204 8949
realization accept: cancel 77.98 112 9358
payment from customer: creation 52.41 117 6289
payment from customer: removal 5.02 81 602
payment to supplier: creation 43.52 160 5222
payment to supplier: removal 3.05 72 366
service: total inventory turnovers 3.42 3186 410
service: total monetary turnovers 5.46 479 655
service: aggregate perf. data 0.82 12258 98
service: refresh index statistics 0.04 380439 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2020 10:29:23 to 22-11-2020 10:29:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 681
TEST_TIME 2 1410
TEST_TIME 3 3990
TEST_TIME 4 4803
TEST_TIME 5 5051
TEST_TIME 6 5285
TEST_TIME 7 5334
TEST_TIME 8 5698
TEST_TIME 9 5655
TEST_TIME 10 5696
TEST_TIME 11 5743
TEST_TIME 12 6322
TEST_TIME 13 6287
TEST_TIME 14 6478
TEST_TIME 15 6720
TEST_TIME 16 7149
TEST_TIME 17 7398
TEST_TIME 18 7763
TEST_TIME 19 8491
TEST_TIME 20 8809
TEST_TIME 21 9221
TEST_TIME 22 9642
TEST_TIME 23 10349
TEST_TIME 24 11108
TEST_TIME 25 11169
TEST_TIME 26 10781
TEST_TIME 27 10497
TEST_TIME 28 10445
TEST_TIME 29 10168
TEST_TIME 30 10030
TEST_TIME 31 10009
TEST_TIME 32 9914
TEST_TIME 33 9965
TEST_TIME 34 9870
TEST_TIME 35 9762
TEST_TIME 36 9531
TEST_TIME 37 9592
TEST_TIME 38 9491
TEST_TIME 39 9471
TEST_TIME 40 9348
TEST_TIME 41 9334
TEST_TIME 42 9211
TEST_TIME 43 9179
TEST_TIME 44 9070
TEST_TIME 45 9038
TEST_TIME 46 8989
TEST_TIME 47 9051
TEST_TIME 48 9025
TEST_TIME 49 9000
TEST_TIME 50 8993
TEST_TIME 51 9006
TEST_TIME 52 9091
TEST_TIME 53 9079
TEST_TIME 54 9143
TEST_TIME 55 9170
TEST_TIME 56 9228
TEST_TIME 57 9304
TEST_TIME 58 9301
TEST_TIME 59 9389
TEST_TIME 60 9284
TEST_TIME 61 9197
TEST_TIME 62 9133
TEST_TIME 63 9097
TEST_TIME 64 9009
TEST_TIME 65 9004
TEST_TIME 66 9034
TEST_TIME 67 9020
TEST_TIME 68 9073
TEST_TIME 69 9050
TEST_TIME 70 9040
TEST_TIME 71 9083
TEST_TIME 72 9083
TEST_TIME 73 9279
TEST_TIME 74 9333
TEST_TIME 75 9279
TEST_TIME 76 9296
TEST_TIME 77 9341
TEST_TIME 78 9320
TEST_TIME 79 9415
TEST_TIME 80 9321
TEST_TIME 81 9222
TEST_TIME 82 9151
TEST_TIME 83 9091
TEST_TIME 84 9038
TEST_TIME 85 8982
TEST_TIME 86 8973
TEST_TIME 87 8925
TEST_TIME 88 8943
TEST_TIME 89 8939
TEST_TIME 90 8898
TEST_TIME 91 8884
TEST_TIME 92 8975
TEST_TIME 93 9053
TEST_TIME 94 9034
TEST_TIME 95 9056
TEST_TIME 96 9052
TEST_TIME 97 9108
TEST_TIME 98 9077
TEST_TIME 99 9003
TEST_TIME 100 8971
TEST_TIME 101 8924
TEST_TIME 102 8890
TEST_TIME 103 8893
TEST_TIME 104 8874
TEST_TIME 105 8872
TEST_TIME 106 8812
TEST_TIME 107 8919
TEST_TIME 108 8915
TEST_TIME 109 8965
TEST_TIME 110 8976
TEST_TIME 111 8942
TEST_TIME 112 8948
TEST_TIME 113 8958
TEST_TIME 114 8972
TEST_TIME 115 8974
TEST_TIME 116 8989
TEST_TIME 117 8920
TEST_TIME 118 8878
TEST_TIME 119 8855
TEST_TIME 120 8803

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-11-2020 10:29:27 to 22-11-2020 10:29:30.

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 1447273 1447273 0 0.00 0 42296 7 0 0
doc_list_biud 1447249 1447249 0 0.00 0 92968 30 0 0
sp_add_invoice_to_stock 4523 4451 72 1.59 41 179575 18925 0 72
sp_cancel_adding_invoice 5586 5586 0 0.00 6 65437 828 0 0
sp_cancel_client_order 1510 1510 0 0.00 4 27310 380 0 0
sp_cancel_customer_reserve 556545 556545 0 0.00 1 92971 63 0 0
sp_cancel_pay_from_customer 602 602 0 0.00 3 5910 82 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 3 3352 72 0 0
sp_cancel_supplier_invoice 6191 6189 2 0.03 2 71983 826 0 2
sp_cancel_supplier_order 404 404 0 0.00 6 1120872 108737 0 0
sp_cancel_write_off 9358 9358 0 0.00 1 15336 113 0 0
sp_client_order 18296 18296 0 0.00 4 34705 444 0 0
sp_customer_reserve 421642 421642 0 0.00 0 62876 181 0 0
sp_fill_shopping_cart 28127 28127 0 0.00 1 21903 45 0 0
sp_fill_shopping_cart_clo_res 421642 421642 0 0.00 0 19469 18 0 0
sp_get_clo_for_invoice 4451 4451 0 0.00 0 39982 450 0 0
sp_kill_cost_storno 15912 15912 0 0.00 0 10751 55 0 0
sp_kill_qstorno_ret_qs2qd 1129041 1129041 0 0.00 0 92965 34 0 0
sp_kill_qty_storno 592979 592979 0 0.00 0 92968 73 0 0
sp_lock_selected_doc 18459 18459 0 0.00 0 8062 6 0 0
sp_make_cost_storno 24911 24911 0 0.00 0 40200 70 0 0
sp_multiply_rows_for_pdistr 24889 24889 0 0.00 0 7555 28 0 0
sp_multiply_rows_for_qdistr 18296 18296 0 0.00 0 21421 248 0 0
sp_pay_from_customer 6289 6289 0 0.00 4 17878 117 0 0
sp_pay_to_supplier 5222 5222 0 0.00 4 22175 160 0 0
sp_payment_common 11500 11500 0 0.00 1 10259 21 0 0
sp_qd_handle_on_cancel_clo 1510 1510 0 0.00 0 27305 321 0 0
sp_qd_handle_on_invoice_upd_sts 10030 10030 0 0.00 0 37051 363 0 0
sp_qd_handle_on_reserve_upd_sts 8943 8943 0 0.00 0 27098 61 0 0
sp_reserve_write_off 8949 8949 0 0.00 7 38642 205 0 0
sp_supplier_invoice 4907 4907 0 0.00 7 71233 3193 0 0
sp_supplier_order 4931 4931 0 0.00 5 64959 1205 0 0
srv_aggregate_perf_data 98 98 0 0.00 2915 119936 12259 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2485 131074 52666 0 0
srv_find_qd_qs_mism 1013882 1013882 0 0.00 0 16927 6 0 0
srv_make_invnt_saldo 410 410 0 0.00 80 110942 3186 0 0
srv_make_money_saldo 655 655 0 0.00 1 11149 480 0 0
srv_recalc_idx_stat 5 5 0 0.00 143480 517460 380439 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4451 4451 0 0.00 0 16580 157 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 8085 92 0 0
t$perf-norm:sp_cancel_client_order 1510 1510 0 0.00 0 4343 18 0 0
t$perf-norm:sp_cancel_customer_reserve 594 594 0 0.00 0 714 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 602 602 0 0.00 0 750 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 129 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 452 9 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 30500 1404 0 0
t$perf-norm:sp_cancel_write_off 847 847 0 0.00 0 2508 14 0 0
t$perf-norm:sp_client_order 18296 18296 0 0.00 0 8105 15 0 0
t$perf-norm:sp_customer_reserve 856 856 0 0.00 0 3257 12 0 0
t$perf-norm:sp_pay_from_customer 6289 6289 0 0.00 0 2158 7 0 0
t$perf-norm:sp_pay_to_supplier 5222 5222 0 0.00 0 6101 8 0 0
t$perf-norm:sp_reserve_write_off 8949 8949 0 0.00 0 16633 10 0 0
t$perf-norm:sp_supplier_invoice 4907 4907 0 0.00 0 7776 16 0 0
t$perf-norm:sp_supplier_order 4931 4931 0 0.00 0 4872 11 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 130 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 1942 50 0 0
t$perf-norm:srv_make_invnt_saldo 410 410 0 0.00 0 680 6 0 0
t$perf-norm:srv_make_money_saldo 655 655 0 0.00 0 1226 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9032 8888 144 1.59 0 21932 24 0 144
v_all_customers 33364 33364 0 0.00 0 107 1 0 0
v_all_suppliers 27686 27686 0 0.00 0 57 1 0 0
v_all_wares 36592 36592 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 15331 27 0 0
v_cancel_client_order 3020 3020 0 0.00 0 21264 19 0 0
v_cancel_customer_prepayment 1204 1204 0 0.00 0 4640 12 0 0
v_cancel_customer_reserve 1188 1188 0 0.00 0 972 5 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 1144 6 0 0
v_cancel_supplier_invoice 728 724 4 0.54 0 965 12 0 4
v_cancel_supplier_order 808 808 0 0.00 0 7140 15 0 0
v_cancel_write_off 1694 1694 0 0.00 0 1515 14 0 0
v_max_id_clo_ord 4928 4928 0 0.00 0 1331 1 0 0
v_max_id_clo_res 856 856 0 0.00 0 1140 10 0 0
v_max_id_ord_sup 4901 4901 0 0.00 0 5904 8 0 0
v_max_non_paid_invoice 1199 1199 0 0.00 0 1742 4 0 0
v_max_non_paid_realizn 3379 3379 0 0.00 0 4358 5 0 0
v_min_id_clo_ord 4928 4928 0 0.00 0 1685 4 0 0
v_min_id_clo_res 856 856 0 0.00 0 3486 7 0 0
v_min_id_ord_sup 4900 4900 0 0.00 0 6589 8 0 0
v_min_non_paid_invoice 5214 5214 0 0.00 0 5422 12 0 0
v_min_non_paid_realizn 6285 6285 0 0.00 0 7692 12 0 0
v_our_firm 9040 9040 0 0.00 0 9 1 0 0
v_reserve_write_off 17886 17886 0 0.00 0 22010 9 0 0
x_lock_depdocs_on_canc_invoice 11413 11413 0 0.00 0 22844 105 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 24975 376 0 0
x_make_qstorno_1000_1200 4931 4931 0 0.00 2 64955 1139 0 0
x_make_qstorno_1200_2000 4907 4907 0 0.00 3 71224 3012 0 0
x_make_qstorno_2100_3300 421642 421642 0 0.00 0 62874 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2020 10:29:31 to 22-11-2020 10:29:35.

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
07:56:58 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:59 33577 8192000000 9726350032 10024390656 799572976 8.892 100 93 186 0 831218720 8355888 23289856
07:59:35 11244 8192000000 9763492416 10068279296 836033152 9.260 100 90 180 1 869330224 11924384 21372688
08:00:53 15574 8192000000 9795829616 10111848448 867349248 9.574 100 85 170 0 907561504 20623664 19588592
08:02:13 14536 8192000000 9847207248 10178363392 900724848 9.905 100 81 160 3 965504368 47582240 17197280
08:03:31 10373 8192000000 9903741184 10248744960 947389408 10.366 100 79 158 1 1012760560 47642384 17728768
08:04:45 4388 8192000000 9943638560 10296881152 983228256 10.716 100 73 144 2 1059367568 60967376 15171936
08:05:52 3381 8192000000 9998778480 10356961280 1013562624 11.010 100 90 180 1 1107740128 75972640 18204864
08:06:57 1803 8192000000 9989303920 10354991104 1033847792 11.205 100 87 172 0 1105331824 53972112 17511920
08:08:00 1239 8192000000 10033569280 10404945920 1052316064 11.383 100 90 181 1 1145616304 74150880 19149360
08:09:03 12479 8192000000 10023093104 10395918336 1062130640 11.477 100 70 140 1 1132147184 55360768 14655776
08:10:16 5301 8192000000 9990616464 10389524480 1068494160 11.538 100 35 70 0 1107136528 31188464 7453904
08:11:26 6384 8192000000 9990803728 10384670720 1079427520 11.642 100 39 78 4 1110611824 22814128 8370176
08:12:33 9084 8192000000 9987809568 10386345984 1082714064 11.673 100 35 66 2 1112707840 22035344 7958432
08:13:44 27338 8192000000 10015395104 10401824768 1085822144 11.703 100 70 139 3 1116219792 14105008 16292640
08:15:12 2898 8192000000 10029635008 10429669376 1091619904 11.758 100 58 116 1 1135523856 31608928 12295024
08:16:18 5678 8192000000 10010621600 10419822592 1092164480 11.763 100 34 69 5 1123984192 24701952 7117760
08:17:24 51117 8192000000 10003237040 10413973504 1084694128 11.692 100 35 67 3 1124295360 31614880 7986352
08:19:15 6450 8192000000 9964698784 10363863040 1043568464 11.299 98 50 100 1 1074289520 20199568 10521488
08:20:22 5607 8192000000 9965035648 10353315840 1009508640 10.971 97 48 96 0 1037825488 17643376 10673472
08:21:31 9379 8192000000 9944868864 10339405824 1016544432 11.039 99 36 72 0 1048768304 24448080 7775792
08:22:44 32791 8192000000 9923883712 10320977920 977966512 10.664 95 31 62 1 998866800 14354960 6545328
08:24:17 8781 8192000000 9957020656 10359173120 1042777408 11.291 100 45 91 2 1076483616 23714480 9991728
08:25:26 3457 8192000000 9976269392 10368352256 1060471712 11.461 100 45 89 1 1088046864 18009136 9566016
08:26:32 11028 8192000000 9991498048 10394697728 1082765296 11.674 100 30 58 3 1109088320 20334384 5988640
08:27:44 45413 8192000000 10018173856 10415509504 1091586896 11.758 100 54 105 1 1119288480 16427056 11274528
08:29:38 23051 8192000000 10071288176 10474962944 1114141328 11.972 100 51 101 3 1192483904 67388352 10954224
08:31:05 4925 8192000000 10110811088 10517516288 1125898608 12.083 100 47 92 0 1215175424 79412816 9864000
08:32:19 11468 8192000000 10128286560 10533580800 1139116608 12.207 100 56 106 2 1230494112 79051936 12325568
08:33:36 4075 8192000000 10183332400 10588782592 1149999040 12.309 100 59 116 0 1285429248 123659472 11770736
08:34:45 6569 8192000000 10227256768 10644525056 1158959600 12.394 100 62 124 2 1338778336 166136784 13681952
08:36:01 8177 8192000000 10250241344 10669260800 1163419136 12.435 100 57 116 1 1356108864 179545392 13144336
08:37:28 5515 8192000000 10319504192 10734391296 1181068288 12.600 100 72 142 3 1417565712 220118704 16378720
08:38:39 2483 8192000000 10309923008 10723586048 1167173152 12.470 99 64 128 1 1411662368 229470624 15018592
08:39:48 7913 8192000000 10382993120 10797064192 1187819424 12.663 100 65 128 2 1486937936 284043376 15075136
08:41:00 3825 8192000000 10415208576 10830614528 1180591232 12.596 100 83 166 2 1510997296 310219408 20186656
08:42:09 3969 8192000000 10404088272 10821124096 1154240576 12.349 98 76 151 3 1495290688 322862496 18187616
08:43:18 2552 8192000000 10472090832 10872389632 1172933728 12.524 100 89 178 0 1568462464 374589920 20938816
08:44:25 16379 8192000000 10453976704 10873868288 1126068576 12.084 98 42 84 1 1526542240 388381312 12092352
08:45:52 16219 8192000000 10385735280 10825326592 1135798912 12.176 99 34 68 0 1466125568 321481808 8844848
08:47:10 14446 8192000000 10350321792 10817998848 1132873936 12.148 99 24 48 0 1429091824 290044560 6173328
08:48:25 27209 8192000000 10296010096 10788581376 1113620704 11.967 99 20 40 1 1410141504 291164976 5355824
08:49:53 42860 8192000000 10086374496 10614865920 1068723376 11.540 96 11 22 1 1216198704 144682720 2792608
08:51:37 101670 8192000000 10057733744 10534752256 1089257968 11.736 98 15 29 2 1122159504 29425856 3475680
08:54:33 98457 8192000000 10103556864 10586783744 1104218768 11.878 99 14 28 1 1188465616 80914512 3332336
08:57:12 57081 8192000000 10086737472 10568617984 1102018096 11.857 99 26 52 1 1183926464 76225952 5682416
08:59:13 37771 8192000000 10060115504 10514026496 1116096032 11.990 99 25 50 3 1152504848 31088064 5320752
09:00:52 116767 8192000000 10099067648 10567069696 1103219184 11.868 98 21 41 0 1198564656 91044976 4300496
09:03:57 17275 8192000000 10054901360 10522931200 1101066032 11.848 98 24 48 0 1142236464 36513408 4657024
09:05:17 74566 8192000000 10048582016 10499952640 1105790560 11.893 99 12 24 2 1146406832 38301456 2314816
09:07:33 114552 8192000000 10074558768 10535325696 1116610464 11.995 100 25 50 0 1188198016 65954704 5632848
09:10:28 43654 8192000000 10093558832 10538409984 1113229808 11.963 99 12 23 1 1165894704 50249104 2415792
09:12:39 106619 8192000000 10071213920 10566488064 1107124192 11.905 98 6 12 0 1178793216 70483264 1185760
09:15:26 27833 8192000000 10056174704 10491564032 1105373744 11.889 98 36 72 3 1129650944 15891904 8385296
09:16:58 65676 8192000000 10103943840 10551099392 1127274832 12.096 99 21 42 1 1184706368 53359728 4071808
09:19:08 47811 8192000000 10104143936 10546827264 1125299264 12.077 99 26 49 1 1181817920 52004976 4513680
09:20:58 68615 8192000000 10107990384 10551996416 1143419952 12.248 100 16 32 0 1214762976 67853808 3489216
09:23:09 87775 8192000000 10106270096 10598572032 1132768960 12.147 98 13 25 1 1242950992 107433568 2748464
09:25:40 95186 8192000000 10112434736 10620428288 1142308320 12.237 99 10 20 0 1211145760 67043392 1794048
09:28:17 95478 8192000000 10091598288 10584317952 1146476400 12.276 99 9 18 0 1196563024 48065568 2021056
09:31:33 80287 8192000000 10120832432 10608394240 1160408880 12.407 100 9 18 0 1206555552 44498960 1647712
09:34:01 35036 8192000000 10106044368 10548846592 1127591488 12.099 97 31 62 2 1192905936 58757520 6556928
09:35:57 41487 8192000000 10136953984 10587471872 1133245536 12.152 98 28 56 1 1224976912 85643920 6087456
09:38:06 71385 8192000000 10140602496 10619052032 1060143152 11.458 93 17 34 0 1148752656 84743472 3866032
09:40:35 63098 8192000000 10060857888 10482872320 1088528480 11.729 97 27 52 0 1140737616 46485792 5723344
09:42:41 13573 8192000000 10111720704 10543853568 1123975264 12.065 99 62 123 0 1197490368 61594288 11920816
09:43:58 93101 8192000000 10138316528 10579996672 1102984064 11.866 97 9 18 1 1148680240 43760368 1935808
09:46:34 103208 8192000000 10040098016 10485391360 1117101552 12.000 99 12 24 0 1147061632 27929808 2030272
09:49:26 64307 8192000000 10055061744 10507288576 1109596256 11.929 99 7 14 0 1143953552 32927040 1430256
09:52:07 68124 8192000000 10071656272 10531532800 1113288128 11.964 99 16 31 1 1184498480 67772512 3437840
09:54:55 42574 8192000000 10109226768 10534072320 1114278928 11.973 99 31 62 2 1191432720 70167744 6986048
09:56:40 55360 8192000000 10155830080 10589188096 1097748064 11.816 97 35 70 3 1215084624 109538592 7797968
09:58:48 93888 8192000000 10073437936 10541527040 1088705456 11.730 98 13 26 0 1191619024 99582400 3331168
10:01:22 50987 8192000000 10086279248 10502512640 1103815472 11.874 98 37 74 1 1144083216 32790512 7477232
10:03:38 81560 8192000000 10088960672 10520571904 1124055104 12.065 99 32 63 0 1169758192 39801680 5901408
10:05:59 131074 8192000000 10083255840 10544488448 1114402096 11.974 98 14 28 0 1162882624 45437328 3043200
10:09:11 52364 8192000000 10063462928 10517696512 1130653328 12.128 100 6 12 0 1166553696 34964752 935616
10:11:04 35927 8192000000 10098494288 10540052480 1135301008 12.171 99 33 66 1 1175385264 33463952 6620304
10:12:40 118345 8192000000 10071931968 10529583104 1127212640 12.095 99 18 35 0 1208473088 77413616 3846832
10:15:41 85775 8192000000 10086402096 10575486976 1122321168 12.049 98 10 20 0 1180199600 55821168 2057264
10:18:15 38396 8192000000 10142744512 10611089408 1149604176 12.306 100 38 75 0 1212558096 55611728 7342192
10:20:04 17611 8192000000 10134364336 10564128768 1150838256 12.317 100 44 88 1 1216537232 57050784 8648192
10:21:25 92487 8192000000 10097569712 10584260608 1150780880 12.317 100 24 47 1 1225739632 70154512 4804240
10:25:08 73360 8192000000 10096054064 10570428416 1141840144 12.233 100 13 26 1 1194740208 50166400 2733664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-11-2020 10:29:37 to 22-11-2020 10:29:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2020 10:29:53 to 22-11-2020 10:29:55.

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          217802
MON$OLDEST_ACTIVE               219922
MON$OLDEST_SNAPSHOT             219922
MON$NEXT_TRANSACTION            219922
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                       2083104
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             765
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 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.2272 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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Sun Nov 22 10:27:23 2020
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sun Nov 22 10:27:23 2020
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
--- end of diff output ---

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

22.11.20 10:29:56. 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-22 10:29:58 No severe PSQL-related problems occured
22.11.20 10:29:59. End of report.