Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2021 04:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16848064 kB
MemAvailable 31248116 kB
Buffers 20 kB
Cached 14654840 kB
SwapCached 4864 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 2021-01-04 04:26:55
DTS_END                         2021-01-04 04:26:55.6750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36828
TRN_ID                          224142
ATT_ID                          659
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: 8631.49 31725 1035779
customer order: creation 157.80 469 18936
customer order: refuse 13.42 372 1610
order to supplier: creation 40.33 1407 4840
order to supplier: removal 3.18 129869 381
invoice (draft): creation 41.31 3283 4957
invoice (draft): removal 48.77 906 5852
invoice accept: apply 38.28 19485 4594
invoice accept: cancel 44.27 875 5312
customer reserve: creation 3588.59 187 430631
customer reserve: removal 4385.83 76 526299
realization accept: apply 76.17 182 9140
realization accept: cancel 78.33 124 9400
payment from customer: creation 52.44 136 6293
payment from customer: removal 5.38 101 645
payment to supplier: creation 44.43 132 5332
payment to supplier: removal 3.18 83 381
service: total inventory turnovers 3.55 3606 426
service: total monetary turnovers 5.38 568 645
service: aggregate perf. data 0.85 14387 102
service: refresh index statistics 0.03 458244 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2021 04:27:35 to 04-01-2021 04:27:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1318
TEST_TIME 2 2421
TEST_TIME 3 2985
TEST_TIME 4 3464
TEST_TIME 5 3705
TEST_TIME 6 3579
TEST_TIME 7 3840
TEST_TIME 8 4060
TEST_TIME 9 4623
TEST_TIME 10 4673
TEST_TIME 11 4742
TEST_TIME 12 5423
TEST_TIME 13 5677
TEST_TIME 14 5945
TEST_TIME 15 6021
TEST_TIME 16 6276
TEST_TIME 17 6615
TEST_TIME 18 7129
TEST_TIME 19 7379
TEST_TIME 20 7673
TEST_TIME 21 7762
TEST_TIME 22 8371
TEST_TIME 23 8570
TEST_TIME 24 8785
TEST_TIME 25 9424
TEST_TIME 26 10160
TEST_TIME 27 10270
TEST_TIME 28 10021
TEST_TIME 29 9913
TEST_TIME 30 9705
TEST_TIME 31 9465
TEST_TIME 32 9490
TEST_TIME 33 9331
TEST_TIME 34 9322
TEST_TIME 35 9230
TEST_TIME 36 9134
TEST_TIME 37 9038
TEST_TIME 38 8870
TEST_TIME 39 8806
TEST_TIME 40 8798
TEST_TIME 41 9002
TEST_TIME 42 8932
TEST_TIME 43 9078
TEST_TIME 44 9025
TEST_TIME 45 8928
TEST_TIME 46 9133
TEST_TIME 47 9172
TEST_TIME 48 9080
TEST_TIME 49 9104
TEST_TIME 50 9162
TEST_TIME 51 9183
TEST_TIME 52 9135
TEST_TIME 53 8984
TEST_TIME 54 8902
TEST_TIME 55 8826
TEST_TIME 56 8775
TEST_TIME 57 8705
TEST_TIME 58 8700
TEST_TIME 59 8718
TEST_TIME 60 8638
TEST_TIME 61 8655
TEST_TIME 62 8759
TEST_TIME 63 8714
TEST_TIME 64 8811
TEST_TIME 65 8754
TEST_TIME 66 9077
TEST_TIME 67 9046
TEST_TIME 68 8980
TEST_TIME 69 8918
TEST_TIME 70 8946
TEST_TIME 71 8924
TEST_TIME 72 9000
TEST_TIME 73 8974
TEST_TIME 74 8867
TEST_TIME 75 8818
TEST_TIME 76 8751
TEST_TIME 77 8680
TEST_TIME 78 8662
TEST_TIME 79 8626
TEST_TIME 80 8609
TEST_TIME 81 8691
TEST_TIME 82 8664
TEST_TIME 83 8645
TEST_TIME 84 8653
TEST_TIME 85 8709
TEST_TIME 86 8766
TEST_TIME 87 8737
TEST_TIME 88 8703
TEST_TIME 89 8754
TEST_TIME 90 8744
TEST_TIME 91 8790
TEST_TIME 92 8724
TEST_TIME 93 8655
TEST_TIME 94 8636
TEST_TIME 95 8633
TEST_TIME 96 8650
TEST_TIME 97 8607
TEST_TIME 98 8622
TEST_TIME 99 8638
TEST_TIME 100 8646
TEST_TIME 101 8617
TEST_TIME 102 8653
TEST_TIME 103 8709
TEST_TIME 104 8690
TEST_TIME 105 8688
TEST_TIME 106 8760
TEST_TIME 107 8758
TEST_TIME 108 8717
TEST_TIME 109 8656
TEST_TIME 110 8645
TEST_TIME 111 8604
TEST_TIME 112 8574
TEST_TIME 113 8545
TEST_TIME 114 8529
TEST_TIME 115 8509
TEST_TIME 116 8527
TEST_TIME 117 8506
TEST_TIME 118 8515
TEST_TIME 119 8594
TEST_TIME 120 8634

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2021 04:27:39 to 04-01-2021 04:27:42.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1433450 1433450 0 0.00 0 16306 6 0 0
doc_list_biud 1433447 1433447 0 0.00 0 76200 34 0 0
sp_add_invoice_to_stock 4758 4594 164 3.44 23 189854 19485 0 164
sp_cancel_adding_invoice 5312 5312 0 0.00 3 145544 875 0 0
sp_cancel_client_order 1610 1610 0 0.00 5 14290 373 0 0
sp_cancel_customer_reserve 526299 526299 0 0.00 1 76231 77 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 14147 101 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 6044 83 0 0
sp_cancel_supplier_invoice 5863 5852 11 0.18 2 36033 906 0 11
sp_cancel_supplier_order 381 381 0 0.00 10 1216928 129869 0 0
sp_cancel_write_off 9400 9400 0 0.00 1 12611 124 0 0
sp_client_order 18936 18936 0 0.00 4 33252 469 0 0
sp_customer_reserve 430631 430631 0 0.00 0 85228 187 0 0
sp_fill_shopping_cart 28712 28712 0 0.00 1 12004 41 0 0
sp_fill_shopping_cart_clo_res 430624 430624 0 0.00 0 36031 22 0 0
sp_get_clo_for_invoice 4586 4586 0 0.00 0 53580 507 0 0
sp_kill_cost_storno 15738 15738 0 0.00 0 9066 59 0 0
sp_kill_qstorno_ret_qs2qd 1068231 1068231 0 0.00 0 76119 41 0 0
sp_kill_qty_storno 562579 562579 0 0.00 0 76200 87 0 0
sp_lock_selected_doc 18970 18970 0 0.00 0 8217 6 0 0
sp_make_cost_storno 25350 25350 0 0.00 0 10156 54 0 0
sp_multiply_rows_for_pdistr 25350 25350 0 0.00 0 8630 36 0 0
sp_multiply_rows_for_qdistr 18936 18936 0 0.00 0 33241 294 0 0
sp_pay_from_customer 6293 6293 0 0.00 4 14250 136 0 0
sp_pay_to_supplier 5332 5332 0 0.00 4 14258 133 0 0
sp_payment_common 11625 11625 0 0.00 1 8103 26 0 0
sp_qd_handle_on_cancel_clo 1610 1610 0 0.00 0 9368 333 0 0
sp_qd_handle_on_invoice_upd_sts 9897 9897 0 0.00 0 25189 376 0 0
sp_qd_handle_on_reserve_upd_sts 9140 9140 0 0.00 0 7949 48 0 0
sp_reserve_write_off 9140 9140 0 0.00 7 17270 183 0 0
sp_supplier_invoice 4957 4957 0 0.00 6 74625 3284 0 0
sp_supplier_order 4840 4840 0 0.00 6 58143 1407 0 0
srv_aggregate_perf_data 102 102 0 0.00 2655 131563 14387 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2726 174794 47336 0 0
srv_find_qd_qs_mism 991700 991700 0 0.00 0 16306 5 0 0
srv_make_invnt_saldo 426 426 0 0.00 55 131141 3607 0 0
srv_make_money_saldo 645 645 0 0.00 0 20210 568 0 0
srv_recalc_idx_stat 3 3 0 0.00 300546 620675 458245 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4594 4594 0 0.00 0 12772 162 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 0 12568 167 0 0
t$perf-norm:sp_cancel_client_order 1610 1610 0 0.00 0 2557 11 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 540 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 148 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 751 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 1730 17 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 15099 1441 0 0
t$perf-norm:sp_cancel_write_off 870 870 0 0.00 0 607 6 0 0
t$perf-norm:sp_client_order 18936 18936 0 0.00 0 9059 17 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 3234 14 0 0
t$perf-norm:sp_pay_from_customer 6293 6293 0 0.00 0 9904 12 0 0
t$perf-norm:sp_pay_to_supplier 5332 5332 0 0.00 0 7064 11 0 0
t$perf-norm:sp_reserve_write_off 9140 9140 0 0.00 0 4948 10 0 0
t$perf-norm:sp_supplier_invoice 4957 4957 0 0.00 0 8749 16 0 0
t$perf-norm:sp_supplier_order 4840 4840 0 0.00 0 7835 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 416 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 240 8 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 708 8 0 0
t$perf-norm:srv_make_money_saldo 645 645 0 0.00 0 2030 7 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 93 33 0 0
v_add_invoice_to_stock 9496 9168 328 3.45 0 14147 20 0 328
v_all_customers 33878 33878 0 0.00 0 17 1 0 0
v_all_suppliers 27226 27226 0 0.00 0 19 1 0 0
v_all_wares 37870 37870 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 1535 11 0 0
v_cancel_client_order 3220 3220 0 0.00 0 14124 12 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 14124 21 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 1110 5 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 996 5 0 0
v_cancel_supplier_invoice 746 724 22 2.94 0 8150 25 0 22
v_cancel_supplier_order 762 762 0 0.00 0 2509 10 0 0
v_cancel_write_off 1740 1740 0 0.00 0 5691 17 0 0
v_max_id_clo_ord 4828 4828 0 0.00 0 1233 1 0 0
v_max_id_clo_res 914 914 0 0.00 0 6434 34 0 0
v_max_id_ord_sup 4949 4949 0 0.00 0 3296 3 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 2293 4 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 6884 6 0 0
v_min_id_clo_ord 4828 4828 0 0.00 0 6819 9 0 0
v_min_id_clo_res 914 914 0 0.00 0 8112 29 0 0
v_min_id_ord_sup 4949 4949 0 0.00 0 4742 7 0 0
v_min_non_paid_invoice 5332 5332 0 0.00 0 7601 17 0 0
v_min_non_paid_realizn 6293 6293 0 0.00 0 8098 18 0 0
v_our_firm 9584 9584 0 0.00 0 14 1 0 0
v_reserve_write_off 18280 18280 0 0.00 0 14210 7 0 0
x_lock_depdocs_on_canc_invoice 10802 10802 0 0.00 0 20315 119 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 24777 552 0 0
x_make_qstorno_1000_1200 4840 4840 0 0.00 2 57274 1330 0 0
x_make_qstorno_1200_2000 4957 4957 0 0.00 3 74618 3127 0 0
x_make_qstorno_2100_3300 430631 430631 0 0.00 0 85227 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2021 04:27:43 to 04-01-2021 04:27:47.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:56:59 7 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:58:01 24544 8192000000 9717253424 10014150656 800157392 8.898 100 90 179 0 829132336 7193120 21781824
01:59:32 14509 8192000000 9756504576 10062290944 836210544 9.262 100 88 176 0 869090112 11233264 21646304
02:00:56 11536 8192000000 9794375040 10104680448 861667392 9.517 100 88 176 0 899578960 17701456 20210112
02:02:10 15755 8192000000 9833529568 10162331648 895569024 9.854 100 70 140 2 945116432 34035760 15511648
02:03:28 10669 8192000000 9906858912 10248720384 951489712 10.406 100 79 158 1 1020152048 52042352 16619984
02:04:44 4459 8192000000 9956983584 10303098880 986845056 10.751 100 86 169 3 1070024656 65006720 18172880
02:05:53 3643 8192000000 9998324560 10356080640 1011152688 10.987 100 85 169 1 1104338592 76551952 16633952
02:06:58 4061 8192000000 10005693232 10368827392 1030204352 11.170 100 84 169 1 1113326496 66082416 17039728
02:08:03 1368 8192000000 10018786048 10386276352 1051523488 11.375 100 91 182 1 1126763952 56503552 18736912
02:09:05 2102 8192000000 10010580576 10386141184 1061282736 11.469 100 73 148 0 1120533376 43331104 15919536
02:10:10 5371 8192000000 9988486928 10376302592 1074406208 11.594 100 53 104 4 1104653024 19319776 10927040
02:11:17 72028 8192000000 9996413792 10389422080 1089303184 11.736 100 42 84 2 1122014256 23512592 9198480
02:13:29 5639 8192000000 10023441312 10418249728 1097287488 11.812 100 39 79 0 1126057408 20686016 8083904
02:14:37 7986 8192000000 10016889280 10417569792 1098659632 11.825 100 30 60 0 1131888864 26036512 7192720
02:15:49 33689 8192000000 10010195088 10424524800 1102447664 11.861 100 27 54 2 1134029664 25809104 5772896
02:17:26 3272 8192000000 10019615648 10411565056 1091494256 11.757 100 50 98 2 1127821280 25108800 11218224
02:18:31 8797 8192000000 9986597520 10370301952 1031589440 11.184 97 36 70 2 1064726496 25361248 7775808
02:19:42 62659 8192000000 9915679680 10298544128 940822368 10.301 92 39 78 1 975612256 26732544 8057344
02:22:00 5724 8192000000 9944961152 10325073920 1015405440 11.028 99 50 99 3 1045868576 19350880 11112256
02:23:09 11775 8192000000 9947922256 10342014976 1032540912 11.193 100 37 73 3 1060279744 18532592 9206240
02:24:22 74657 8192000000 9988497264 10393956352 1056975488 11.428 100 30 60 3 1093290144 29558592 6756064
02:26:39 3404 8192000000 10000299456 10393669632 1084685840 11.692 100 50 96 3 1106524960 10011808 11827312
02:27:50 37840 8192000000 10038678416 10434945024 1094411856 11.785 100 47 92 1 1129510032 25462176 9636000
02:29:37 17524 8192000000 10109027696 10503962624 1119010144 12.018 100 57 114 1 1199416768 67795728 12610896
02:31:12 34792 8192000000 10130902704 10552573952 1129299616 12.115 100 37 74 1 1247974224 109983152 8691456
02:32:50 34721 8192000000 10171234112 10594504704 1136739136 12.185 100 48 95 1 1278322912 130347552 11236224
02:34:31 12451 8192000000 10211705824 10613813248 1148754784 12.298 100 63 126 0 1309263408 146640032 13868592
02:35:46 5320 8192000000 10238024432 10651435008 1161444656 12.417 100 57 112 1 1333652416 160422160 11785600
02:36:59 10291 8192000000 10235606992 10655760384 1160638624 12.409 99 54 107 0 1347287808 174084560 12564624
02:38:15 5390 8192000000 10328947376 10736943104 1178889440 12.580 100 71 142 0 1430842720 236229440 15723840
02:39:26 8646 8192000000 10303970816 10717634560 1174340912 12.537 100 60 120 0 1408014112 220240976 13432224
02:40:39 2925 8192000000 10334045168 10744332288 1172502080 12.520 100 68 136 1 1428270080 240244256 15523744
02:41:51 6968 8192000000 10397480592 10810859520 1185805888 12.644 100 74 147 1 1499049184 296169136 17074160
02:43:00 2755 8192000000 10409410608 10807369728 1171513680 12.511 100 88 176 0 1503659552 310827216 21318656
02:44:08 2726 8192000000 10419025152 10822758400 1151426864 12.323 99 84 166 0 1508874304 337562560 19884880
02:45:14 4552 8192000000 10402312720 10837078016 1179679792 12.587 100 53 103 0 1515259248 323252912 12326544
02:46:27 12916 8192000000 10397665472 10841374720 1159241808 12.396 100 49 98 0 1523745072 352437728 12065536
02:47:44 4008 8192000000 10405544816 10824568832 1140842576 12.223 99 52 103 1 1502197968 348236256 13119136
02:48:51 10915 8192000000 10373135648 10836525056 1148014992 12.291 100 29 58 4 1466954032 311282928 7656112
02:50:13 25823 8192000000 10325430128 10765086720 1118044032 12.009 100 23 45 0 1415115728 291071456 6000240
02:51:50 17395 8192000000 10206895168 10649726976 1112612544 11.957 100 28 56 2 1305821392 186582048 6626800
02:53:09 6701 8192000000 10043953936 10496405504 1099670688 11.835 100 28 57 3 1143831808 38166400 5994720
02:54:20 36839 8192000000 10073790528 10485587968 1102101696 11.858 98 58 115 2 1161128960 46919808 12107456
02:56:01 109602 8192000000 10090583328 10523525120 1105012416 11.885 99 20 40 1 1183425328 74302192 4110720
02:59:46 65702 8192000000 10040457872 10494324736 1092121392 11.763 98 18 36 0 1125645552 30016176 3507984
03:01:52 80929 8192000000 10087786816 10523623424 1102910608 11.865 98 17 34 1 1161609760 55049984 3649168
03:04:27 49248 8192000000 10128756672 10578214912 1125405088 12.078 100 14 28 1 1227175792 98612288 3158416
03:06:29 39678 8192000000 10147566368 10585649152 1120188304 12.029 99 31 63 2 1238615856 112008240 6419312
03:08:13 71181 8192000000 10145128256 10592083968 1138578560 12.202 100 26 51 1 1225874512 81813904 5482048
03:10:25 41060 8192000000 10136907200 10594160640 1136719216 12.185 99 33 64 0 1205066272 61283680 7063376
03:12:16 52664 8192000000 10119339184 10609815552 1131288368 12.134 99 12 24 0 1222232736 88560144 2384224
03:14:11 13259 8192000000 10088547376 10513018880 1141904528 12.233 100 54 107 3 1179064816 26029664 11130624
03:15:25 52844 8192000000 10082226192 10529153024 1137111440 12.188 100 21 42 1 1184888624 43508720 4268464
03:17:25 95167 8192000000 10079523824 10548400128 1125199440 12.076 98 9 18 1 1175769072 48654816 1914816
03:21:07 60252 8192000000 10094015520 10554470400 1133175360 12.151 99 12 24 1 1190059952 54461056 2423536
03:23:12 74774 8192000000 10137454992 10595258368 1126621632 12.090 98 11 22 0 1188174896 59104288 2448976
03:25:28 59424 8192000000 10142729040 10598793216 1123182848 12.057 98 18 36 2 1222190736 94828496 4179392
03:27:34 37099 8192000000 10151335376 10628857856 1136894528 12.186 99 15 30 0 1237405184 97203328 3307328
03:29:38 37221 8192000000 10208044304 10644688896 1153024608 12.338 99 28 56 0 1269230816 110215360 5990848
03:31:51 80705 8192000000 10101001152 10561765376 1115429616 11.984 97 12 24 0 1193513984 75248944 2835424
03:34:12 50954 8192000000 10108134000 10537590784 1139965504 12.215 99 26 52 0 1169213312 23617008 5630800
03:36:03 50387 8192000000 10123729152 10550325248 1135648560 12.175 98 30 60 0 1197436144 55871264 5916320
03:37:56 41642 8192000000 10105219104 10585808896 1151151408 12.320 100 32 64 2 1236522480 77895376 7475696
03:39:56 111469 8192000000 10100954736 10541133824 1125047008 12.075 98 40 78 3 1173674096 40389424 8237664
03:42:52 56910 8192000000 10057496144 10504544256 1100623952 11.844 97 8 16 0 1150078400 47795056 1659392
03:44:53 75248 8192000000 10097695424 10555002880 1119353984 12.021 99 17 34 0 1180448960 56937712 4157264
03:47:09 54470 8192000000 10056060176 10544844800 1084560400 11.691 96 17 34 0 1165004592 76555968 3888224
03:49:05 33428 8192000000 10068133888 10500280320 1100202112 11.840 98 37 74 3 1151587200 43763920 7621168
03:50:38 113251 8192000000 10083677696 10595782656 1104116080 11.877 98 12 24 0 1195078528 88410464 2551984
03:53:32 59356 8192000000 10058087376 10490933248 1098634528 11.825 98 16 32 0 1136154816 34251968 3268320
03:55:31 49424 8192000000 10114155888 10589118464 1111836624 11.950 99 41 83 2 1205741456 85870496 8034336
03:57:25 174794 8192000000 10083638976 10525691904 1120975280 12.036 99 21 40 2 1176530384 50363568 5191536
04:01:20 63487 8192000000 10092389152 10593546240 1113029392 11.961 98 8 16 1 1149610496 34932816 1648288
04:03:33 34006 8192000000 10086439232 10521153536 1125699568 12.081 100 21 42 4 1160529056 30633744 4195744
04:05:08 66612 8192000000 10094076640 10562609152 1122893312 12.054 99 11 22 0 1193888224 68475648 2519264
04:07:15 19757 8192000000 10099737136 10520231936 1144605344 12.259 100 44 86 4 1182880032 29048240 9226448
04:08:35 165441 8192000000 10062070544 10500321280 1072252688 11.574 94 17 34 0 1133204768 57446608 3505472
04:12:22 44139 8192000000 10092949024 10573918208 1140998144 12.225 100 22 45 1 1199681824 54566112 4117568
04:14:08 21017 8192000000 10087459040 10508832768 1134046848 12.159 99 41 82 1 1160523712 18388544 8088320
04:15:32 88833 8192000000 10067623744 10535669760 1141210144 12.227 100 23 45 0 1192912160 46852944 4849072
04:18:01 39243 8192000000 10052481856 10513899520 1106273808 11.897 97 8 16 1 1126239344 18583888 1381648
04:19:41 55650 8192000000 10070552688 10525130752 1103989440 11.875 97 18 36 1 1127562192 19562304 4010448
04:21:46 71008 8192000000 10122873824 10600169472 1127021952 12.093 99 23 46 0 1210466752 78319280 5125520
04:24:00 57404 8192000000 10187532976 10658484224 1145446576 12.267 100 28 56 2 1265070368 113441232 6182560
04:25:58 61789 8192000000 9046856416 9281650688 198239136 2.362 18 1 2 0 214593168 16318544 35488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2021 04:28:05 to 04-01-2021 04:28:07.

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          224658
MON$OLDEST_ACTIVE               224659
MON$OLDEST_SNAPSHOT             224659
MON$NEXT_TRANSACTION            224659
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                       2069216
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             778
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:145: 
:146: mscs-test-161.budzdorov.ru    Mon Jan  4 04:27:02 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
--- end of diff output ---

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

04.01.21 04:28:08. 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
2021-01-04 04:28:10 No severe PSQL-related problems occured
04.01.21 04:28:11. End of report.