Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2020 07:38
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 16924816 kB
MemAvailable 31426860 kB
Buffers 0 kB
Cached 14768556 kB
SwapCached 3824 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-17 07:37:12
DTS_END                         2020-11-17 07:37:12.6170
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/38688
TRN_ID                          239741
ATT_ID                          629
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: 8715.31 23731 1045837
customer order: creation 175.96 435 21115
customer order: refuse 15.94 355 1913
order to supplier: creation 47.55 1430 5706
order to supplier: removal 3.22 120555 386
invoice (draft): creation 47.84 3586 5741
invoice (draft): removal 46.93 1047 5632
invoice accept: apply 43.11 17582 5173
invoice accept: cancel 42.19 1021 5063
customer reserve: creation 3890.89 175 466907
customer reserve: removal 4111.33 76 493359
realization accept: apply 85.91 184 10309
realization accept: cancel 72.72 141 8726
payment from customer: creation 61.38 107 7366
payment from customer: removal 6.03 76 724
payment to supplier: creation 49.28 129 5914
payment to supplier: removal 3.18 102 382
service: total inventory turnovers 4.25 3000 510
service: total monetary turnovers 6.64 554 797
service: aggregate perf. data 0.91 15620 109
service: refresh index statistics 0.04 308444 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2020 07:38:45 to 17-11-2020 07:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3360
TEST_TIME 2 4229
TEST_TIME 3 4091
TEST_TIME 4 4559
TEST_TIME 5 4444
TEST_TIME 6 4534
TEST_TIME 7 4673
TEST_TIME 8 4780
TEST_TIME 9 5120
TEST_TIME 10 5244
TEST_TIME 11 5941
TEST_TIME 12 6204
TEST_TIME 13 6357
TEST_TIME 14 6915
TEST_TIME 15 6941
TEST_TIME 16 7178
TEST_TIME 17 7796
TEST_TIME 18 7625
TEST_TIME 19 7810
TEST_TIME 20 8200
TEST_TIME 21 8424
TEST_TIME 22 8583
TEST_TIME 23 8477
TEST_TIME 24 9369
TEST_TIME 25 10046
TEST_TIME 26 10979
TEST_TIME 27 10962
TEST_TIME 28 10943
TEST_TIME 29 10885
TEST_TIME 30 10608
TEST_TIME 31 10333
TEST_TIME 32 10305
TEST_TIME 33 10069
TEST_TIME 34 10008
TEST_TIME 35 9818
TEST_TIME 36 9847
TEST_TIME 37 10007
TEST_TIME 38 9913
TEST_TIME 39 9903
TEST_TIME 40 9815
TEST_TIME 41 9768
TEST_TIME 42 9676
TEST_TIME 43 9631
TEST_TIME 44 9678
TEST_TIME 45 10019
TEST_TIME 46 9961
TEST_TIME 47 9888
TEST_TIME 48 9863
TEST_TIME 49 9903
TEST_TIME 50 9919
TEST_TIME 51 9870
TEST_TIME 52 9926
TEST_TIME 53 9971
TEST_TIME 54 9878
TEST_TIME 55 9704
TEST_TIME 56 9614
TEST_TIME 57 9498
TEST_TIME 58 9402
TEST_TIME 59 9383
TEST_TIME 60 9293
TEST_TIME 61 9421
TEST_TIME 62 9433
TEST_TIME 63 9388
TEST_TIME 64 9335
TEST_TIME 65 9313
TEST_TIME 66 9408
TEST_TIME 67 9376
TEST_TIME 68 9380
TEST_TIME 69 9386
TEST_TIME 70 9413
TEST_TIME 71 9469
TEST_TIME 72 9523
TEST_TIME 73 9504
TEST_TIME 74 9507
TEST_TIME 75 9437
TEST_TIME 76 9361
TEST_TIME 77 9281
TEST_TIME 78 9255
TEST_TIME 79 9204
TEST_TIME 80 9159
TEST_TIME 81 9210
TEST_TIME 82 9164
TEST_TIME 83 9139
TEST_TIME 84 9157
TEST_TIME 85 9198
TEST_TIME 86 9211
TEST_TIME 87 9195
TEST_TIME 88 9175
TEST_TIME 89 9242
TEST_TIME 90 9248
TEST_TIME 91 9293
TEST_TIME 92 9212
TEST_TIME 93 9125
TEST_TIME 94 9066
TEST_TIME 95 9019
TEST_TIME 96 8998
TEST_TIME 97 9002
TEST_TIME 98 8975
TEST_TIME 99 8966
TEST_TIME 100 8965
TEST_TIME 101 8951
TEST_TIME 102 8984
TEST_TIME 103 8968
TEST_TIME 104 8918
TEST_TIME 105 8933
TEST_TIME 106 9097
TEST_TIME 107 9073
TEST_TIME 108 9069
TEST_TIME 109 9072
TEST_TIME 110 9070
TEST_TIME 111 9038
TEST_TIME 112 8966
TEST_TIME 113 8923
TEST_TIME 114 8871
TEST_TIME 115 8872
TEST_TIME 116 8857
TEST_TIME 117 8828
TEST_TIME 118 8791
TEST_TIME 119 8780
TEST_TIME 120 8717

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-11-2020 07:38:48 to 17-11-2020 07:38:52.

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 1477639 1477639 0 0.00 0 19540 7 0 0
doc_list_biud 1477632 1477632 0 0.00 0 94613 32 0 0
sp_add_invoice_to_stock 5270 5173 97 1.84 36 168147 17582 0 97
sp_cancel_adding_invoice 5063 5063 0 0.00 4 129204 1022 0 0
sp_cancel_client_order 1913 1913 0 0.00 4 20039 356 0 0
sp_cancel_customer_reserve 493359 493359 0 0.00 1 94614 76 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 4637 77 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 9563 102 0 0
sp_cancel_supplier_invoice 5638 5632 6 0.10 1 22374 1048 0 6
sp_cancel_supplier_order 386 386 0 0.00 9 1188316 120556 0 0
sp_cancel_write_off 8726 8726 0 0.00 1 38687 141 0 0
sp_client_order 21115 21115 0 0.00 4 74361 436 0 0
sp_customer_reserve 466907 466907 0 0.00 0 98593 175 0 0
sp_fill_shopping_cart 32543 32543 0 0.00 1 19618 43 0 0
sp_fill_shopping_cart_clo_res 466904 466904 0 0.00 0 20891 17 0 0
sp_get_clo_for_invoice 5168 5168 0 0.00 0 46098 384 0 0
sp_kill_cost_storno 14895 14895 0 0.00 0 6937 58 0 0
sp_kill_qstorno_ret_qs2qd 1001462 1001462 0 0.00 0 94611 42 0 0
sp_kill_qty_storno 530554 530554 0 0.00 0 94613 89 0 0
sp_lock_selected_doc 21512 21512 0 0.00 0 10055 7 0 0
sp_make_cost_storno 28757 28757 0 0.00 0 9250 53 0 0
sp_multiply_rows_for_pdistr 28750 28750 0 0.00 0 6920 32 0 0
sp_multiply_rows_for_qdistr 21115 21115 0 0.00 0 74339 250 0 0
sp_pay_from_customer 7366 7366 0 0.00 4 10738 108 0 0
sp_pay_to_supplier 5914 5914 0 0.00 5 12725 129 0 0
sp_payment_common 13275 13275 0 0.00 1 7963 19 0 0
sp_qd_handle_on_cancel_clo 1913 1913 0 0.00 0 20032 308 0 0
sp_qd_handle_on_invoice_upd_sts 10229 10229 0 0.00 0 61781 397 0 0
sp_qd_handle_on_reserve_upd_sts 10309 10309 0 0.00 0 21781 57 0 0
sp_reserve_write_off 10309 10309 0 0.00 7 21822 185 0 0
sp_supplier_invoice 5741 5741 0 0.00 7 98762 3587 0 0
sp_supplier_order 5706 5706 0 0.00 5 46420 1431 0 0
srv_aggregate_perf_data 109 109 0 0.00 2744 198153 15620 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1214 150056 47751 0 0
srv_find_qd_qs_mism 996912 996912 0 0.00 0 19530 6 0 0
srv_make_invnt_saldo 510 510 0 0.00 67 130436 3000 0 0
srv_make_money_saldo 797 797 0 0.00 1 15157 554 0 0
srv_recalc_idx_stat 5 5 0 0.00 224788 371811 308444 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5173 5173 0 0.00 0 14925 167 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 1 2657 59 0 0
t$perf-norm:sp_cancel_client_order 1913 1913 0 0.00 0 1940 13 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 12935 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 2337 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 602 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 827 17 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 24734 1447 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 3892 12 0 0
t$perf-norm:sp_client_order 21116 21116 0 0.00 0 11154 18 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 712 5 0 0
t$perf-norm:sp_pay_from_customer 7366 7366 0 0.00 0 7968 13 0 0
t$perf-norm:sp_pay_to_supplier 5914 5914 0 0.00 0 4320 7 0 0
t$perf-norm:sp_reserve_write_off 10309 10309 0 0.00 0 11007 10 0 0
t$perf-norm:sp_supplier_invoice 5741 5741 0 0.00 0 10821 20 0 0
t$perf-norm:sp_supplier_order 5706 5706 0 0.00 0 8620 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 86 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 2258 10 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1630 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10520 10326 194 1.84 0 7885 16 0 194
v_all_customers 37896 37896 0 0.00 0 15 1 0 0
v_all_suppliers 31182 31182 0 0.00 0 16 1 0 0
v_all_wares 42230 42230 0 0.00 0 49 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 315 6 0 0
v_cancel_client_order 3826 3826 0 0.00 0 6998 8 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 885 7 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 7782 11 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 4552 13 0 0
v_cancel_supplier_invoice 900 888 12 1.33 0 1941 14 0 12
v_cancel_supplier_order 772 772 0 0.00 0 266 3 0 0
v_cancel_write_off 2088 2088 0 0.00 0 1827 15 0 0
v_max_id_clo_ord 5706 5706 0 0.00 0 5478 2 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 1975 20 0 0
v_max_id_ord_sup 5722 5722 0 0.00 0 5942 7 0 0
v_max_non_paid_invoice 1750 1750 0 0.00 0 5245 6 0 0
v_max_non_paid_realizn 4299 4299 0 0.00 0 1061 2 0 0
v_min_id_clo_ord 5706 5706 0 0.00 0 4513 8 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 2433 5 0 0
v_min_id_ord_sup 5722 5722 0 0.00 0 6366 11 0 0
v_min_non_paid_invoice 5913 5913 0 0.00 0 5065 11 0 0
v_min_non_paid_realizn 7362 7362 0 0.00 0 6214 11 0 0
v_our_firm 10460 10460 0 0.00 0 15 1 0 0
v_reserve_write_off 20618 20618 0 0.00 0 7588 5 0 0
x_lock_depdocs_on_canc_invoice 10251 10251 0 0.00 0 34942 127 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 8169 278 0 0
x_make_qstorno_1000_1200 5706 5706 0 0.00 2 46411 1350 0 0
x_make_qstorno_1200_2000 5741 5741 0 0.00 3 97321 3417 0 0
x_make_qstorno_2100_3300 466907 466907 0 0.00 0 98590 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2020 07:38:53 to 17-11-2020 07:38:57.

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
05:07:16 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
05:08:17 22709 8192000000 9726011904 10022150144 800737056 8.904 100 79 158 0 827200480 7293424 19170000
05:09:43 30591 8192000000 9770035824 10077818880 844100272 9.341 100 88 176 2 876862256 11447424 21314560
05:11:19 19309 8192000000 9803264080 10124496896 873724320 9.637 100 78 156 1 910474336 18974176 17775840
05:12:44 19576 8192000000 9865503840 10202001408 916104224 10.058 100 76 152 2 980423248 47446432 16872592
05:14:06 7373 8192000000 9905967392 10252410880 958648960 10.476 100 79 157 1 1028037120 52487392 16900768
05:15:17 4958 8192000000 9955304352 10308411392 996534064 10.845 100 76 152 1 1064269312 51998032 15737216
05:16:24 8738 8192000000 9989329872 10350948352 1019277552 11.065 100 68 136 1 1101055456 68018160 13759744
05:17:35 2498 8192000000 10022309984 10394677248 1045825936 11.321 100 83 166 2 1152899264 90008768 17064560
05:18:39 1470 8192000000 10050215488 10420994048 1065365984 11.508 100 90 180 0 1165648000 82156608 18125408
05:19:41 4113 8192000000 10005606448 10395250688 1073135808 11.582 100 51 103 0 1122237280 38680896 10420576
05:20:47 8845 8192000000 9992467184 10386264064 1081883248 11.665 100 47 93 1 1111680000 19829008 9967744
05:21:57 70432 8192000000 10009744160 10417942528 1083979584 11.685 100 39 76 1 1120310128 28203440 8127104
05:24:12 8056 8192000000 9994178608 10400280576 1086122160 11.706 100 31 62 2 1123863920 30603088 7138672
05:25:20 7405 8192000000 10003995344 10405384192 1090826400 11.751 100 26 51 2 1114913024 18767392 5319232
05:26:28 11589 8192000000 9984864128 10385338368 1057990352 11.437 97 15 29 0 1072641248 11432640 3218256
05:27:40 38557 8192000000 10006276704 10409951232 1073984016 11.590 99 28 56 1 1107271376 27072208 6215152
05:29:21 11790 8192000000 9944520544 10337415168 993577392 10.816 95 32 64 1 1027338512 27081328 6679792
05:30:37 7370 8192000000 9953861472 10338009088 1009032464 10.966 98 38 77 0 1033071696 14799520 9239712
05:31:45 79750 8192000000 9926819760 10325217280 975749632 10.643 94 27 54 2 997420048 16169440 5500976
05:34:08 3943 8192000000 9968035296 10354618368 1042173952 11.286 100 54 101 3 1074189024 20563312 11451760
05:35:12 7967 8192000000 9982703680 10373644288 1055411024 11.413 100 44 88 2 1086167968 20581824 10175120
05:36:20 62217 8192000000 9978321584 10379452416 1062972544 11.485 100 30 60 1 1091674688 21223680 7478464
05:38:28 41594 8192000000 10035727888 10437214208 1092582688 11.767 100 33 66 2 1130847696 31804272 6460736
05:40:16 13066 8192000000 10085637360 10491277312 1114995952 11.980 100 41 82 2 1191750464 68456592 8297920
05:41:36 13543 8192000000 10124638800 10526666752 1130108992 12.122 100 51 102 2 1230425392 88880864 11435536
05:42:53 15042 8192000000 10195694336 10612170752 1140100304 12.216 100 48 96 0 1303116112 152575888 10439920
05:44:16 21924 8192000000 10225993360 10635829248 1153955392 12.347 100 42 84 0 1329651024 165873840 9821792
05:45:41 6543 8192000000 10270099568 10686734336 1170595168 12.502 100 51 101 1 1382402176 200326912 11480096
05:46:52 7224 8192000000 10308509184 10723639296 1181723792 12.606 100 60 120 0 1419388432 223641392 14023248
05:48:10 7346 8192000000 10383686272 10790539264 1174521120 12.539 100 81 162 1 1477059520 284468064 18070336
05:49:30 3593 8192000000 10338309648 10743590912 1167051552 12.469 99 75 150 1 1436120896 252091520 16977824
05:50:41 6843 8192000000 10373676432 10792841216 1172257520 12.518 100 73 145 1 1473528864 284635856 16635488
05:51:52 3738 8192000000 10362977136 10773553152 1175690352 12.550 100 79 154 4 1460516400 267739232 17086816
05:53:03 1283 8192000000 10415191840 10818723840 1172901632 12.524 100 83 167 0 1511347824 319676352 18769840
05:54:10 2487 8192000000 10406525424 10824585216 1148478976 12.295 100 90 180 1 1499719408 328926016 22314416
05:55:18 1214 8192000000 10377340864 10783068160 1147153856 12.283 100 84 168 1 1479323280 313186016 18983408
05:56:22 2654 8192000000 10442857472 10864439296 1147335312 12.284 100 77 154 1 1537707744 372600368 17772064
05:57:27 16542 8192000000 10453839584 10884026368 1139275744 12.209 99 51 102 2 1554251200 402513664 12461792
05:58:48 13769 8192000000 10437937328 10894159872 1150589504 12.315 100 34 68 1 1537874176 377913088 9371584
06:00:24 6329 8192000000 10467482368 10893955072 1141737520 12.232 100 52 105 0 1571010656 415981120 13292016
06:01:44 32385 8192000000 10274117840 10782715904 1121953456 12.045 100 22 44 3 1441373120 313169216 6250448
06:03:19 12828 8192000000 10132113088 10565201920 1094591520 11.786 98 25 50 2 1219117296 118776880 5748896
06:04:36 77472 8192000000 10046460800 10503094272 1097925760 11.818 98 19 36 1 1152178192 50066576 4185856
06:06:55 69195 8192000000 10096767168 10531176448 1123523952 12.060 99 35 70 0 1176031408 45445696 7061760
06:10:00 99279 8192000000 10107352560 10558451712 1104086032 11.876 97 30 61 1 1173161280 62677520 6397728
06:12:41 35580 8192000000 10164918592 10599620608 1129573552 12.117 99 45 90 1 1258497248 118125728 10797968
06:14:17 40905 8192000000 10091087728 10558664704 1131129136 12.132 99 14 28 0 1191518608 57434016 2955456
06:16:16 40742 8192000000 10078233632 10530058240 1137316624 12.190 100 13 26 2 1179604544 39856272 2431648
06:18:02 37401 8192000000 10066355664 10510860288 1134763088 12.166 99 16 32 0 1175869440 37735248 3371104
06:19:40 40728 8192000000 10132354656 10579378176 1147753872 12.288 99 34 66 2 1235426768 80001744 7671152
06:21:23 76131 8192000000 10117084400 10624258048 1136656848 12.184 98 12 24 2 1236148960 96827104 2665008
06:23:41 39099 8192000000 10086942800 10524835840 1127957968 12.102 98 39 78 0 1169829520 32434080 9437472
06:25:26 65777 8192000000 10077775584 10548797440 1117778000 12.006 97 28 56 1 1167544144 44435840 5330304
06:27:33 62543 8192000000 10136995920 10594947072 1148504704 12.295 100 27 54 1 1207413856 53544176 5364976
06:29:37 35612 8192000000 10093243440 10562228224 1109128528 11.924 97 23 46 0 1199858192 85900384 4829280
06:31:13 86417 8192000000 10046643072 10507468800 1118451648 12.012 99 11 22 0 1154794736 33578480 2764608
06:33:59 48070 8192000000 10069521328 10514681856 1103157744 11.868 98 15 30 0 1148390368 41767488 3465136
06:35:48 87496 8192000000 10114587168 10615320576 1122443616 12.050 99 13 26 1 1223558224 98010432 3104176
06:38:59 65133 8192000000 10036583680 10473136128 1084470672 11.690 96 10 18 2 1141962512 55335136 2156704
06:41:05 28462 8192000000 10079626704 10512859136 1097303280 11.812 98 49 97 2 1155912800 48514544 10094976
06:42:39 52377 8192000000 10083222480 10560864256 1096015056 11.800 99 10 20 0 1172316304 74397088 1904160
06:44:33 57326 8192000000 10069608608 10511589376 1106450608 11.899 98 28 58 1 1164172032 52087408 5634016
06:46:35 73008 8192000000 10128093872 10602180608 1112889632 11.960 99 18 36 1 1178339552 61757424 3692496
06:48:49 72839 8192000000 10080873392 10523746304 1059960480 11.456 95 14 26 1 1120398336 57513872 2923984
06:51:30 104972 8192000000 10025581360 10470195200 1097345632 11.812 98 28 56 2 1149069152 45469824 6253696
06:54:17 55618 8192000000 10039413968 10477826048 1086178464 11.706 97 8 16 0 1138767712 50955520 1633728
06:56:14 67182 8192000000 10086370080 10550087680 1107774256 11.911 99 9 18 0 1148453504 38505568 2173680
06:58:21 119431 8192000000 10082476096 10546360320 1060175968 11.458 94 17 33 0 1097238864 32877536 4185360
07:01:21 81972 8192000000 10090882544 10579804160 1133999392 12.159 100 22 44 0 1211472688 71815744 5657552
07:03:45 40842 8192000000 10076579136 10547654656 1120852624 12.035 99 17 34 0 1173931008 49465376 3613008
07:05:29 80985 8192000000 10080697344 10577022976 1080042560 11.648 95 26 52 1 1165638336 80114016 5481760
07:07:52 47085 8192000000 10122345632 10574176256 1094623696 11.787 97 18 36 0 1158592288 60220240 3748352
07:09:40 150056 8192000000 10073765552 10531065856 1133411200 12.154 100 16 30 3 1172622064 35234624 3976240
07:14:24 110679 8192000000 10080813936 10517422080 1069868464 11.551 93 16 32 1 1110897744 37841408 3187872
07:17:19 70145 8192000000 10095508640 10541510656 1101713696 11.854 98 16 32 0 1140460768 35504416 3242656
07:19:29 46692 8192000000 10094049056 10524594176 1114123888 11.971 98 19 38 1 1155346432 37223600 3998944
07:21:19 68069 8192000000 10197646160 10632798208 1123601840 12.061 98 23 46 0 1245830592 117149408 5079344
07:23:44 44371 8192000000 10072081920 10521354240 1100429632 11.842 97 18 36 0 1147126656 42952032 3744992
07:25:30 51570 8192000000 10083764304 10495651840 1076908736 11.618 95 49 96 2 1117988224 31363760 9715728
07:27:30 64692 8192000000 10067327344 10497306624 1074451920 11.595 96 17 33 0 1126753920 49237408 3064592
07:29:35 79859 8192000000 10058220784 10507706368 1117776336 12.006 100 19 38 1 1161610384 39623600 4210448
07:31:59 29233 8192000000 10041182208 10522492928 1105873744 11.893 99 5 10 0 1147981856 40883520 1224592
07:33:28 59232 8192000000 10066589008 10503806976 1103901392 11.875 98 18 36 1 1129596800 21958576 3736832
07:35:28 51829 8192000000 10127145200 10563862528 1084599168 11.691 96 29 58 1 1173045904 81749792 6696944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-11-2020 07:38:59 to 17-11-2020 07:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2020 07:39:14 to 17-11-2020 07:39:16.

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          234777
MON$OLDEST_ACTIVE               240161
MON$OLDEST_SNAPSHOT             240161
MON$NEXT_TRANSACTION            240161
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                       2081824
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             813
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2267 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2267 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2267 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.2267 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Nov 17 07:37:16 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Nov 17 07:37:19 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Nov 17 07:37:20 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Tue Nov 17 07:37:21 2020
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

17.11.20 07:39:17. 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-17 07:39:20 No severe PSQL-related problems occured
17.11.20 07:39:21. End of report.