Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.12.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 17073424 kB
MemAvailable 31564372 kB
Buffers 0 kB
Cached 14557500 kB
SwapCached 1924 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-12-01 07:36:52
DTS_END                         2020-12-01 07:36:52.9590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40080
TRN_ID                          222887
ATT_ID                          634
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: 8459.66 24730 1015159
customer order: creation 156.99 410 18839
customer order: refuse 13.64 427 1637
order to supplier: creation 41.72 1496 5006
order to supplier: removal 2.95 136007 354
invoice (draft): creation 42.93 4268 5151
invoice (draft): removal 48.49 1032 5819
invoice accept: apply 38.16 18458 4579
invoice accept: cancel 43.53 976 5224
customer reserve: creation 3553.59 176 426431
customer reserve: removal 4241.57 74 508988
realization accept: apply 75.83 211 9100
realization accept: cancel 83.62 122 10034
payment from customer: creation 53.28 131 6393
payment from customer: removal 5.19 91 623
payment to supplier: creation 45.27 136 5432
payment to supplier: removal 3.46 87 415
service: total inventory turnovers 3.13 4615 375
service: total monetary turnovers 5.49 716 659
service: aggregate perf. data 0.79 20633 95
service: refresh index statistics 0.04 304536 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-12-2020 07:38:29 to 01-12-2020 07:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2797
TEST_TIME 2 4003
TEST_TIME 3 4241
TEST_TIME 4 4321
TEST_TIME 5 4292
TEST_TIME 6 4563
TEST_TIME 7 4622
TEST_TIME 8 4621
TEST_TIME 9 4804
TEST_TIME 10 4649
TEST_TIME 11 4861
TEST_TIME 12 5042
TEST_TIME 13 5339
TEST_TIME 14 5553
TEST_TIME 15 5741
TEST_TIME 16 6046
TEST_TIME 17 6348
TEST_TIME 18 6393
TEST_TIME 19 6804
TEST_TIME 20 7498
TEST_TIME 21 7919
TEST_TIME 22 8160
TEST_TIME 23 8425
TEST_TIME 24 8396
TEST_TIME 25 8406
TEST_TIME 26 8538
TEST_TIME 27 8948
TEST_TIME 28 9258
TEST_TIME 29 9793
TEST_TIME 30 10320
TEST_TIME 31 10155
TEST_TIME 32 9953
TEST_TIME 33 9905
TEST_TIME 34 9779
TEST_TIME 35 9765
TEST_TIME 36 9776
TEST_TIME 37 9576
TEST_TIME 38 9383
TEST_TIME 39 9440
TEST_TIME 40 9311
TEST_TIME 41 9344
TEST_TIME 42 9443
TEST_TIME 43 9274
TEST_TIME 44 9238
TEST_TIME 45 9110
TEST_TIME 46 9069
TEST_TIME 47 9188
TEST_TIME 48 9119
TEST_TIME 49 8990
TEST_TIME 50 8978
TEST_TIME 51 8936
TEST_TIME 52 8862
TEST_TIME 53 8843
TEST_TIME 54 8765
TEST_TIME 55 8691
TEST_TIME 56 8607
TEST_TIME 57 8605
TEST_TIME 58 8510
TEST_TIME 59 8595
TEST_TIME 60 8632
TEST_TIME 61 8566
TEST_TIME 62 8550
TEST_TIME 63 8682
TEST_TIME 64 8644
TEST_TIME 65 8698
TEST_TIME 66 8706
TEST_TIME 67 8862
TEST_TIME 68 8850
TEST_TIME 69 8860
TEST_TIME 70 8918
TEST_TIME 71 8884
TEST_TIME 72 8872
TEST_TIME 73 8996
TEST_TIME 74 8935
TEST_TIME 75 8850
TEST_TIME 76 8759
TEST_TIME 77 8727
TEST_TIME 78 8718
TEST_TIME 79 8653
TEST_TIME 80 8646
TEST_TIME 81 8638
TEST_TIME 82 8580
TEST_TIME 83 8536
TEST_TIME 84 8518
TEST_TIME 85 8551
TEST_TIME 86 8545
TEST_TIME 87 8532
TEST_TIME 88 8591
TEST_TIME 89 8609
TEST_TIME 90 8579
TEST_TIME 91 8602
TEST_TIME 92 8666
TEST_TIME 93 8748
TEST_TIME 94 8737
TEST_TIME 95 8767
TEST_TIME 96 8842
TEST_TIME 97 8792
TEST_TIME 98 8715
TEST_TIME 99 8677
TEST_TIME 100 8630
TEST_TIME 101 8585
TEST_TIME 102 8534
TEST_TIME 103 8515
TEST_TIME 104 8482
TEST_TIME 105 8477
TEST_TIME 106 8444
TEST_TIME 107 8427
TEST_TIME 108 8473
TEST_TIME 109 8495
TEST_TIME 110 8541
TEST_TIME 111 8545
TEST_TIME 112 8527
TEST_TIME 113 8559
TEST_TIME 114 8631
TEST_TIME 115 8635
TEST_TIME 116 8602
TEST_TIME 117 8544
TEST_TIME 118 8517
TEST_TIME 119 8500
TEST_TIME 120 8462

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-12-2020 07:38:33 to 01-12-2020 07:38:36.

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 1407796 1407796 0 0.00 0 30830 7 0 0
doc_list_biud 1407796 1407796 0 0.00 0 61387 34 0 0
sp_add_invoice_to_stock 4633 4579 54 1.16 30 175615 18459 0 54
sp_cancel_adding_invoice 5224 5224 0 0.00 4 80519 976 0 0
sp_cancel_client_order 1637 1637 0 0.00 5 20158 428 0 0
sp_cancel_customer_reserve 508988 508988 0 0.00 1 46625 74 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 4712 92 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 2483 88 0 0
sp_cancel_supplier_invoice 5820 5819 1 0.01 2 61603 1032 0 1
sp_cancel_supplier_order 354 354 0 0.00 9 1195531 136007 0 0
sp_cancel_write_off 10034 10034 0 0.00 1 31245 122 0 0
sp_client_order 18839 18839 0 0.00 4 45532 410 0 0
sp_customer_reserve 426431 426431 0 0.00 0 45995 177 0 0
sp_fill_shopping_cart 28967 28967 0 0.00 1 22907 58 0 0
sp_fill_shopping_cart_clo_res 426431 426431 0 0.00 0 21793 19 0 0
sp_get_clo_for_invoice 4579 4579 0 0.00 0 37982 510 0 0
sp_kill_cost_storno 16296 16296 0 0.00 0 14897 66 0 0
sp_kill_qstorno_ret_qs2qd 1034183 1034183 0 0.00 0 61384 40 0 0
sp_kill_qty_storno 545735 545735 0 0.00 0 61387 87 0 0
sp_lock_selected_doc 19080 19080 0 0.00 0 8689 5 0 0
sp_make_cost_storno 25504 25504 0 0.00 0 12636 68 0 0
sp_multiply_rows_for_pdistr 25504 25504 0 0.00 0 9751 38 0 0
sp_multiply_rows_for_qdistr 18839 18839 0 0.00 0 45524 209 0 0
sp_pay_from_customer 6393 6393 0 0.00 4 13037 132 0 0
sp_pay_to_supplier 5432 5432 0 0.00 5 16142 136 0 0
sp_payment_common 11825 11825 0 0.00 1 12018 31 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 20151 392 0 0
sp_qd_handle_on_invoice_upd_sts 9803 9803 0 0.00 0 37885 436 0 0
sp_qd_handle_on_reserve_upd_sts 9100 9100 0 0.00 0 10855 57 0 0
sp_reserve_write_off 9100 9100 0 0.00 7 12616 211 0 0
sp_supplier_invoice 5151 5151 0 0.00 7 75229 4268 0 0
sp_supplier_order 5006 5006 0 0.00 6 58471 1496 0 0
srv_aggregate_perf_data 96 95 1 1.04 2413 385190 20634 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1120 128756 47964 0 0
srv_find_qd_qs_mism 970348 970348 0 0.00 0 30830 5 0 0
srv_make_invnt_saldo 375 375 0 0.00 69 164263 4615 0 0
srv_make_money_saldo 659 659 0 0.00 1 15828 717 0 0
srv_recalc_idx_stat 5 5 0 0.00 65981 772583 304536 0 0
t$perf-abend:sp_add_invoice_to_stock 54 54 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4581 4581 0 0.00 0 17229 191 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 5387 146 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 5295 14 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 1253 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 315 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1257 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 4514 39 0 0
t$perf-norm:sp_cancel_supplier_order 354 354 0 0.00 0 21077 1782 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 5928 18 0 0
t$perf-norm:sp_client_order 18839 18839 0 0.00 0 8769 22 0 0
t$perf-norm:sp_customer_reserve 884 884 0 0.00 0 1114 4 0 0
t$perf-norm:sp_pay_from_customer 6393 6393 0 0.00 0 5141 5 0 0
t$perf-norm:sp_pay_to_supplier 5432 5432 0 0.00 0 7902 10 0 0
t$perf-norm:sp_reserve_write_off 9100 9100 0 0.00 0 6629 12 0 0
t$perf-norm:sp_supplier_invoice 5151 5151 0 0.00 0 9052 25 0 0
t$perf-norm:sp_supplier_order 5006 5006 0 0.00 0 4451 20 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 473 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 375 375 0 0.00 0 2370 22 0 0
t$perf-norm:srv_make_money_saldo 659 659 0 0.00 0 1980 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 9266 9158 108 1.16 0 3382 14 0 108
v_all_customers 34210 34210 0 0.00 0 103 1 0 0
v_all_suppliers 27978 27978 0 0.00 0 62 1 0 0
v_all_wares 37678 37678 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 1125 7 0 0
v_cancel_client_order 3274 3274 0 0.00 0 2753 7 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 749 4 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 4348 10 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 795 7 0 0
v_cancel_supplier_invoice 806 804 2 0.24 0 1677 16 0 2
v_cancel_supplier_order 708 708 0 0.00 0 4320 15 0 0
v_cancel_write_off 1966 1966 0 0.00 0 1702 14 0 0
v_max_id_clo_ord 5004 5004 0 0.00 0 894 2 0 0
v_max_id_clo_res 884 884 0 0.00 0 1840 11 0 0
v_max_id_ord_sup 5124 5124 0 0.00 0 8411 7 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 1097 2 0 0
v_max_non_paid_realizn 3492 3492 0 0.00 0 2610 5 0 0
v_min_id_clo_ord 5004 5004 0 0.00 0 2755 6 0 0
v_min_id_clo_res 884 884 0 0.00 0 4135 12 0 0
v_min_id_ord_sup 5124 5124 0 0.00 0 7753 9 0 0
v_min_non_paid_invoice 5432 5432 0 0.00 0 5955 16 0 0
v_min_non_paid_realizn 6393 6393 0 0.00 0 12014 28 0 0
v_our_firm 9270 9270 0 0.00 0 21 1 0 0
v_reserve_write_off 18200 18200 0 0.00 0 4331 6 0 0
x_lock_depdocs_on_canc_invoice 10641 10641 0 0.00 0 16867 127 0 0
x_lock_depdocs_on_canc_sup_ord 354 354 0 0.00 0 14335 487 0 0
x_make_qstorno_1000_1200 5006 5006 0 0.00 2 58416 1399 0 0
x_make_qstorno_1200_2000 5151 5151 0 0.00 4 67984 4038 0 0
x_make_qstorno_2100_3300 426431 426431 0 0.00 0 45992 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-12-2020 07:38:37 to 01-12-2020 07:38:41.

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:06:56 5 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
05:07:57 33792 8192000000 9734946512 10034036736 809971440 8.997 100 86 173 0 838259856 7774992 20513424
05:09:33 21526 8192000000 9772052432 10079821824 842886080 9.329 100 90 180 0 876318528 12124400 21308048
05:11:01 13015 8192000000 9805845648 10122985472 875923424 9.659 100 82 164 1 913482608 18930336 18628848
05:12:19 16157 8192000000 9871073776 10203144192 914801952 10.045 100 83 164 2 977895632 44532544 18561136
05:13:42 11340 8192000000 9923305536 10265407488 949285408 10.384 100 82 163 2 1034682368 67792256 17604704
05:14:57 8225 8192000000 9960260688 10310836224 980634384 10.690 100 81 162 0 1063693280 65863776 17195120
05:16:08 3210 8192000000 9977361360 10335571968 1015544320 11.029 100 84 162 1 1088994800 56604080 16846400
05:17:13 2203 8192000000 10024889728 10391953408 1036221344 11.228 100 85 170 3 1141516672 87905088 17390240
05:18:17 1673 8192000000 10031974848 10400399360 1053243376 11.392 100 94 186 1 1149333264 77461520 18628368
05:19:20 35460 8192000000 10027087264 10404085760 1065425440 11.508 100 66 134 1 1151727632 71854032 14448160
05:20:56 8653 8192000000 10010678912 10404454400 1072225584 11.573 100 49 98 4 1123614160 41061168 10327408
05:22:06 8028 8192000000 9991494752 10402693120 1077937152 11.628 100 32 65 3 1111450368 26907056 6606160
05:23:14 49648 8192000000 10005965392 10412122112 1083594304 11.682 100 27 54 2 1108680240 19236528 5849408
05:25:04 9035 8192000000 10001879120 10400010240 1092350432 11.765 100 26 52 1 1107476432 9243168 5882832
05:26:15 6989 8192000000 10005487456 10414391296 1100377584 11.841 100 29 54 4 1119531712 12808112 6346016
05:27:23 4467 8192000000 9974679728 10362707968 1025889904 11.129 94 36 70 3 1041376560 7247952 8238704
05:28:30 15398 8192000000 10000926352 10389626880 1074181168 11.592 99 62 123 3 1106429648 18372208 13876272
05:29:46 3721 8192000000 9993287408 10379341824 1054679008 11.406 99 47 97 2 1094454320 30837456 8937856
05:30:52 3529 8192000000 9949562448 10340605952 1020125392 11.073 98 37 72 3 1040229440 12062624 8041424
05:31:58 8037 8192000000 9933432096 10324570112 1016221392 11.036 98 31 62 1 1033861248 10369632 7270224
05:33:10 52578 8192000000 9952843936 10360401920 1006392704 10.940 97 38 77 1 1029126432 14604272 8129456
05:35:10 6708 8192000000 9961909888 10362761216 1052379872 11.383 100 37 74 4 1092233216 31385248 8468096
05:36:19 8721 8192000000 9993115008 10392920064 1071244512 11.564 100 39 78 1 1096734576 15788528 9701536
05:37:31 11156 8192000000 10033203264 10428481536 1092386960 11.765 100 53 104 2 1136318896 32748240 11183696
05:38:45 53507 8192000000 10066363536 10469130240 1105260528 11.888 100 52 104 1 1177279584 61333248 10685808
05:40:42 6705 8192000000 10075612144 10483331072 1115998304 11.989 100 33 67 2 1185886128 63118672 6769152
05:41:53 2905 8192000000 10120112784 10523508736 1125687200 12.081 100 72 143 1 1228520208 86943104 15889904
05:42:59 10778 8192000000 10148446864 10558259200 1134404784 12.163 100 52 104 0 1251502480 105767344 11330352
05:44:15 5884 8192000000 10180582272 10585415680 1150890944 12.318 100 57 113 1 1286431664 123175872 12364848
05:45:31 17719 8192000000 10193085632 10624634880 1159651056 12.400 100 29 58 1 1308728656 142160832 6916768
05:46:53 4924 8192000000 10255995984 10664910848 1171349104 12.509 100 59 118 1 1359569616 174812704 13407808
05:48:01 7747 8192000000 10294367168 10723266560 1184019072 12.628 100 47 95 1 1395762288 201063760 10679456
05:49:12 2700 8192000000 10341370864 10744475648 1184978640 12.637 100 82 161 0 1441767744 238608992 18180112
05:50:17 3842 8192000000 10349706864 10764070912 1182845840 12.617 100 68 136 1 1457308608 259169008 15293760
05:51:28 1655 8192000000 10356449040 10781384704 1180230288 12.592 100 78 154 1 1461478704 262433840 18814576
05:52:37 2113 8192000000 10392830816 10791100416 1168381552 12.482 100 91 179 2 1486416960 297607200 20428208
05:53:49 3057 8192000000 10404763648 10827096064 1170534224 12.502 100 75 147 2 1505958496 318043984 17380288
05:54:58 9507 8192000000 10402259168 10831527936 1172948864 12.524 100 64 128 0 1501187024 312576192 15661968
05:56:13 5677 8192000000 10407762288 10827702272 1152237488 12.330 99 72 143 2 1502893520 333627136 17028896
05:57:27 13875 8192000000 10292424928 10756390912 1140191472 12.217 100 33 65 6 1398897232 250408368 8297392
05:58:51 3628 8192000000 10348419024 10778345472 1158631360 12.390 100 49 97 2 1455723744 285580576 11511808
06:00:00 14643 8192000000 10312663360 10738446336 1139243360 12.208 100 28 56 2 1403369824 257015552 7110912
06:01:21 17073 8192000000 10336607984 10799267840 1145143232 12.264 100 37 72 0 1453855696 299960688 8751776
06:02:55 18631 8192000000 10318515440 10805207040 1139002768 12.206 100 26 52 4 1465500880 319446064 7052048
06:04:17 22364 8192000000 10303024720 10746810368 1094428496 11.785 97 30 60 0 1402376192 299853312 8094384
06:05:43 17213 8192000000 10189262528 10661142528 1114492016 11.975 100 21 42 2 1324490608 204795840 5202752
06:07:02 109477 8192000000 10082339424 10500890624 1088000208 11.724 97 33 66 4 1169724864 74257168 7467488
06:09:55 32185 8192000000 10098083264 10546356224 1109061488 11.924 99 13 26 1 1198418720 86845248 2511984
06:11:48 81043 8192000000 10082409744 10553651200 1115099760 11.981 99 11 22 0 1202942944 85292816 2550368
06:14:09 72120 8192000000 10120939936 10554925056 1076637424 11.615 95 18 37 1 1200070000 119044640 4387936
06:16:42 128756 8192000000 10063271616 10585280512 1106630704 11.901 98 20 40 0 1195725072 84790640 4303728
06:19:56 1114 8192000000 10078345616 10490638336 1137857536 12.195 100 94 186 2 1182579744 23157824 21564384
06:20:58 79395 8192000000 10132642768 10581426176 1116000560 11.989 98 22 43 1 1209519344 88777360 4741424
06:23:20 46618 8192000000 10092216528 10537857024 1134810496 12.167 100 15 30 0 1193188592 54863920 3514176
06:25:07 106836 8192000000 10084562272 10562035712 1124767488 12.072 98 22 44 0 1192270064 62836224 4666352
06:28:30 61386 8192000000 10063866624 10523017216 1135651328 12.175 100 26 52 2 1196175408 54938976 5585104
06:30:33 89235 8192000000 10112566480 10570752000 1129286384 12.115 98 16 32 0 1195028576 62033344 3708848
06:33:02 90135 8192000000 10108589168 10576625664 1124729936 12.072 98 11 22 3 1196037568 68655200 2652432
06:35:55 67509 8192000000 10066348656 10577375232 1132178464 12.142 99 11 22 0 1186272624 52260944 1833216
06:38:10 87512 8192000000 10080308064 10533142528 1117378832 12.002 98 15 30 1 1180471408 60475376 2617200
06:40:45 85601 8192000000 10110148128 10556063744 1111841216 11.950 97 21 42 0 1161817872 45516304 4460352
06:43:11 93480 8192000000 10059732656 10536288256 1079599296 11.644 95 14 28 1 1133953504 51594720 2759488
06:45:53 44081 8192000000 10087857040 10557112320 1096595584 11.805 98 30 60 2 1151218800 48223504 6399712
06:47:44 29689 8192000000 10087850496 10512998400 1120458896 12.031 100 36 71 1 1169766688 42561600 6746192
06:49:18 89753 8192000000 10054580352 10518929408 1097030576 11.809 98 10 20 0 1155010272 56212448 1767248
06:52:02 73101 8192000000 10059962032 10504916992 1107818464 11.912 99 21 42 0 1155305776 42472912 5014400
06:54:20 40156 8192000000 10053560832 10500739072 1131814688 12.138 100 14 28 0 1162058256 27555968 2687600
06:56:00 54384 8192000000 10059871568 10530361344 1109419792 11.927 99 13 26 2 1178596208 66006624 3169792
06:58:00 82042 8192000000 10049942672 10491375616 1088642624 11.730 97 16 32 4 1130135584 37864928 3628032
07:00:42 44046 8192000000 10042187136 10508091392 1096184416 11.801 98 11 22 0 1141125024 42630480 2310128
07:02:55 57407 8192000000 10080397232 10518478848 1080549072 11.653 96 24 48 0 1153220800 67672336 4999392
07:05:00 61543 8192000000 10062364304 10495983616 1085739120 11.702 97 17 34 0 1125577696 36028160 3810416
07:07:05 76948 8192000000 10117882688 10603622400 1097949216 11.818 97 18 35 0 1236294944 134722688 3623040
07:09:23 39655 8192000000 10069780208 10511060992 1111514096 11.947 98 18 36 1 1157419264 42348176 3556992
07:11:10 64823 8192000000 10121243280 10585022464 1122938416 12.055 99 13 26 0 1194982208 69533936 2509856
07:13:15 72095 8192000000 10053238304 10547048448 1108453888 11.918 99 16 32 2 1152862896 41022080 3386928
07:15:32 49132 8192000000 10035385984 10480533504 1108538528 11.919 99 21 42 2 1130542992 17495728 4508736
07:17:22 70114 8192000000 10042348608 10489925632 1114343616 11.974 99 5 10 1 1134259216 19014352 901248
07:19:32 80848 8192000000 10075107520 10541977600 1118823024 12.016 99 10 20 0 1170985072 50342352 1819696
07:21:54 74713 8192000000 10133192720 10572488704 1149095872 12.301 100 27 54 3 1226575472 71592752 5886848
07:24:24 113282 8192000000 10102534000 10578014208 1100719888 11.844 96 10 20 0 1179562640 76688336 2154416
07:27:20 51470 8192000000 10100232368 10530459648 1124814448 12.072 98 27 54 1 1171651552 41270144 5566960
07:29:12 81593 8192000000 10081867520 10607591424 1140892064 12.224 100 14 28 0 1249459904 105757088 2810752
07:31:36 67655 8192000000 10105551088 10548314112 1134062240 12.160 99 40 80 5 1179921472 37989952 7869280
07:33:49 43054 8192000000 10071785424 10521829376 1121768336 12.044 98 17 34 3 1164517648 39179712 3569600
07:35:32 70391 8192000000 10076443504 10561982464 1112012160 11.951 98 6 12 2 1163563344 50402944 1148240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-12-2020 07:38:43 to 01-12-2020 07:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 54 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-12-2020 07:38:59 to 01-12-2020 07:39:01.

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          221605
MON$OLDEST_ACTIVE               222996
MON$OLDEST_SNAPSHOT             222996
MON$NEXT_TRANSACTION            222996
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                       2082592
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             774
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2285 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2285 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2285 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.2285 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 Dec  1 07:36:56 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Dec  1 07:36:58 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

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