Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.03.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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16617532 kB
MemAvailable 31546024 kB
Buffers 20 kB
Cached 14972412 kB
SwapCached 0 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-03-12 04:26:56
DTS_END                         2021-03-12 04:26:56.6970
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/32942
TRN_ID                          229162
ATT_ID                          576
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: 8852.95 22983 1062354
customer order: creation 161.68 409 19402
customer order: refuse 13.44 335 1613
order to supplier: creation 43.39 1337 5207
order to supplier: removal 3.41 120648 409
invoice (draft): creation 42.63 3955 5115
invoice (draft): removal 51.07 1014 6128
invoice accept: apply 38.85 16809 4662
invoice accept: cancel 46.48 933 5577
customer reserve: creation 3593.90 161 431268
customer reserve: removal 4583.61 71 550033
realization accept: apply 77.72 191 9326
realization accept: cancel 77.75 108 9330
payment from customer: creation 55.69 108 6683
payment from customer: removal 5.60 115 672
payment to supplier: creation 44.78 138 5374
payment to supplier: removal 3.11 127 373
service: total inventory turnovers 3.23 4601 388
service: total monetary turnovers 5.82 569 698
service: aggregate perf. data 0.77 20276 92
service: refresh index statistics 0.03 287745 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2021 04:28:18 to 12-03-2021 04:28:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1731
TEST_TIME 2 3386
TEST_TIME 3 3552
TEST_TIME 4 3493
TEST_TIME 5 3736
TEST_TIME 6 4165
TEST_TIME 7 5118
TEST_TIME 8 5563
TEST_TIME 9 5844
TEST_TIME 10 6163
TEST_TIME 11 6332
TEST_TIME 12 6928
TEST_TIME 13 7052
TEST_TIME 14 7389
TEST_TIME 15 7376
TEST_TIME 16 7789
TEST_TIME 17 8036
TEST_TIME 18 8421
TEST_TIME 19 8514
TEST_TIME 20 8748
TEST_TIME 21 8584
TEST_TIME 22 8940
TEST_TIME 23 8905
TEST_TIME 24 9055
TEST_TIME 25 9124
TEST_TIME 26 9005
TEST_TIME 27 9060
TEST_TIME 28 8923
TEST_TIME 29 9004
TEST_TIME 30 9603
TEST_TIME 31 10817
TEST_TIME 32 11356
TEST_TIME 33 11400
TEST_TIME 34 11564
TEST_TIME 35 11474
TEST_TIME 36 11545
TEST_TIME 37 11315
TEST_TIME 38 11150
TEST_TIME 39 10949
TEST_TIME 40 10806
TEST_TIME 41 10642
TEST_TIME 42 10577
TEST_TIME 43 10433
TEST_TIME 44 10330
TEST_TIME 45 10221
TEST_TIME 46 10117
TEST_TIME 47 9931
TEST_TIME 48 9818
TEST_TIME 49 10044
TEST_TIME 50 9896
TEST_TIME 51 9882
TEST_TIME 52 9810
TEST_TIME 53 9730
TEST_TIME 54 9746
TEST_TIME 55 9673
TEST_TIME 56 9653
TEST_TIME 57 9701
TEST_TIME 58 9619
TEST_TIME 59 9504
TEST_TIME 60 9479
TEST_TIME 61 9393
TEST_TIME 62 9431
TEST_TIME 63 9377
TEST_TIME 64 9371
TEST_TIME 65 9380
TEST_TIME 66 9462
TEST_TIME 67 9404
TEST_TIME 68 9431
TEST_TIME 69 9522
TEST_TIME 70 9436
TEST_TIME 71 9561
TEST_TIME 72 9509
TEST_TIME 73 9586
TEST_TIME 74 9695
TEST_TIME 75 9693
TEST_TIME 76 9691
TEST_TIME 77 9586
TEST_TIME 78 9518
TEST_TIME 79 9448
TEST_TIME 80 9392
TEST_TIME 81 9355
TEST_TIME 82 9301
TEST_TIME 83 9257
TEST_TIME 84 9248
TEST_TIME 85 9192
TEST_TIME 86 9164
TEST_TIME 87 9146
TEST_TIME 88 9164
TEST_TIME 89 9165
TEST_TIME 90 9171
TEST_TIME 91 9150
TEST_TIME 92 9181
TEST_TIME 93 9178
TEST_TIME 94 9274
TEST_TIME 95 9234
TEST_TIME 96 9164
TEST_TIME 97 9133
TEST_TIME 98 9078
TEST_TIME 99 9073
TEST_TIME 100 9045
TEST_TIME 101 9031
TEST_TIME 102 8998
TEST_TIME 103 8979
TEST_TIME 104 8957
TEST_TIME 105 8984
TEST_TIME 106 8952
TEST_TIME 107 9056
TEST_TIME 108 9055
TEST_TIME 109 9065
TEST_TIME 110 9008
TEST_TIME 111 9110
TEST_TIME 112 9104
TEST_TIME 113 9061
TEST_TIME 114 9003
TEST_TIME 115 8972
TEST_TIME 116 8928
TEST_TIME 117 8867
TEST_TIME 118 8876
TEST_TIME 119 8857
TEST_TIME 120 8853

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-03-2021 04:28:21 to 12-03-2021 04:28:24.

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 1462231 1462231 0 0.00 0 26907 6 0 0
doc_list_biud 1462231 1462231 0 0.00 0 84203 34 0 0
sp_add_invoice_to_stock 4822 4662 160 3.31 23 158551 16810 0 160
sp_cancel_adding_invoice 5577 5577 0 0.00 5 54623 934 0 0
sp_cancel_client_order 1613 1613 0 0.00 4 9646 336 0 0
sp_cancel_customer_reserve 550033 550033 0 0.00 1 84204 71 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 8031 115 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 10524 128 0 0
sp_cancel_supplier_invoice 6136 6128 8 0.13 2 30193 1014 0 8
sp_cancel_supplier_order 409 409 0 0.00 8 1090594 120649 0 0
sp_cancel_write_off 9330 9330 0 0.00 1 7125 109 0 0
sp_client_order 19402 19402 0 0.00 4 33499 409 0 0
sp_customer_reserve 431268 431268 0 0.00 0 39434 162 0 0
sp_fill_shopping_cart 29722 29722 0 0.00 1 17864 53 0 0
sp_fill_shopping_cart_clo_res 431268 431268 0 0.00 0 18660 17 0 0
sp_get_clo_for_invoice 4662 4662 0 0.00 0 27533 493 0 0
sp_kill_cost_storno 15952 15952 0 0.00 0 8025 61 0 0
sp_kill_qstorno_ret_qs2qd 1115933 1115933 0 0.00 0 84201 38 0 0
sp_kill_qty_storno 587078 587078 0 0.00 0 84203 83 0 0
sp_lock_selected_doc 19389 19389 0 0.00 0 10518 6 0 0
sp_make_cost_storno 26045 26045 0 0.00 0 22942 61 0 0
sp_multiply_rows_for_pdistr 26045 26045 0 0.00 0 26860 36 0 0
sp_multiply_rows_for_qdistr 19402 19402 0 0.00 0 19578 231 0 0
sp_pay_from_customer 6683 6683 0 0.00 4 17626 108 0 0
sp_pay_to_supplier 5374 5374 0 0.00 5 26911 138 0 0
sp_payment_common 12057 12057 0 0.00 1 14708 21 0 0
sp_qd_handle_on_cancel_clo 1613 1613 0 0.00 0 9641 301 0 0
sp_qd_handle_on_invoice_upd_sts 10239 10239 0 0.00 0 35999 416 0 0
sp_qd_handle_on_reserve_upd_sts 9326 9326 0 0.00 0 10566 60 0 0
sp_reserve_write_off 9326 9326 0 0.00 7 26176 191 0 0
sp_supplier_invoice 5115 5115 0 0.00 7 84067 3955 0 0
sp_supplier_order 5207 5207 0 0.00 5 37983 1338 0 0
srv_aggregate_perf_data 92 92 0 0.00 2605 313100 20277 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1213 146749 46351 0 0
srv_find_qd_qs_mism 1018104 1018104 0 0.00 0 18803 4 0 0
srv_make_invnt_saldo 388 388 0 0.00 41 132229 4602 0 0
srv_make_money_saldo 698 698 0 0.00 1 13706 570 0 0
srv_recalc_idx_stat 4 4 0 0.00 217996 439146 287746 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4663 4663 0 0.00 0 17346 190 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 0 6429 105 0 0
t$perf-norm:sp_cancel_client_order 1613 1613 0 0.00 0 3304 14 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 7050 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 3047 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 736 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 710 12 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 17899 1501 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 2505 8 0 0
t$perf-norm:sp_client_order 19402 19402 0 0.00 0 9621 20 0 0
t$perf-norm:sp_customer_reserve 923 923 0 0.00 0 10137 19 0 0
t$perf-norm:sp_pay_from_customer 6683 6683 0 0.00 0 11226 11 0 0
t$perf-norm:sp_pay_to_supplier 5374 5374 0 0.00 0 4266 8 0 0
t$perf-norm:sp_reserve_write_off 9326 9326 0 0.00 0 13439 16 0 0
t$perf-norm:sp_supplier_invoice 5115 5115 0 0.00 0 7130 22 0 0
t$perf-norm:sp_supplier_order 5207 5207 0 0.00 0 2599 14 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 401 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3149 56 0 0
t$perf-norm:srv_make_invnt_saldo 388 388 0 0.00 0 897 14 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 2296 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 9644 9324 320 3.31 0 4742 18 0 320
v_all_customers 35450 35450 0 0.00 0 97 1 0 0
v_all_suppliers 28558 28558 0 0.00 0 17 1 0 0
v_all_wares 38804 38804 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 1293 7 0 0
v_cancel_client_order 3226 3226 0 0.00 0 3408 6 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 2324 6 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 3705 10 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 262 3 0 0
v_cancel_supplier_invoice 740 724 16 2.16 0 3102 22 0 16
v_cancel_supplier_order 818 818 0 0.00 0 677 4 0 0
v_cancel_write_off 1944 1944 0 0.00 0 4927 18 0 0
v_max_id_clo_ord 5207 5207 0 0.00 0 1323 2 0 0
v_max_id_clo_res 923 923 0 0.00 0 2546 13 0 0
v_max_id_ord_sup 5113 5113 0 0.00 0 6405 7 0 0
v_max_non_paid_invoice 1415 1415 0 0.00 0 413 2 0 0
v_max_non_paid_realizn 3557 3557 0 0.00 0 2560 4 0 0
v_min_id_clo_ord 5207 5207 0 0.00 0 3815 8 0 0
v_min_id_clo_res 923 923 0 0.00 0 8572 28 0 0
v_min_id_ord_sup 5113 5113 0 0.00 0 5147 9 0 0
v_min_non_paid_invoice 5374 5374 0 0.00 0 7713 11 0 0
v_min_non_paid_realizn 6683 6683 0 0.00 0 11359 14 0 0
v_our_firm 9606 9606 0 0.00 0 13 1 0 0
v_reserve_write_off 18652 18652 0 0.00 0 4857 6 0 0
x_lock_depdocs_on_canc_invoice 11343 11343 0 0.00 0 12947 116 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3057 318 0 0
x_make_qstorno_1000_1200 5207 5207 0 0.00 2 37839 1243 0 0
x_make_qstorno_1200_2000 5115 5115 0 0.00 2 84057 3753 0 0
x_make_qstorno_2100_3300 431268 431268 0 0.00 0 34628 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2021 04:28:25 to 12-03-2021 04:28:29.

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:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 44496 8192000000 9733467216 10031345664 803837552 8.935 100 89 178 0 833209664 7934656 21437456
01:59:52 23274 8192000000 9771775664 10080821248 846097104 9.361 100 83 166 1 878856320 13248016 19511200
02:01:32 15245 8192000000 9810893952 10136911872 879508288 9.695 100 78 156 1 929817152 32381968 17926896
02:02:51 11793 8192000000 9899954304 10233987072 927726720 10.172 100 85 170 2 1007332928 61340656 18265552
02:04:08 4505 8192000000 9918924640 10270932992 972971888 10.616 100 75 150 1 1039385872 50808528 15605456
02:05:15 3236 8192000000 9976122016 10332508160 1011885520 10.994 100 80 160 0 1094466848 65934464 16646864
02:06:20 3490 8192000000 10013161536 10372349952 1027837408 11.148 100 92 182 0 1127974048 81971808 18164832
02:07:26 2133 8192000000 10016281760 10380439552 1048199312 11.343 100 91 180 1 1120148016 53827888 18120816
02:08:29 24968 8192000000 9990395440 10375589888 1063705056 11.492 100 60 122 0 1119986176 43852592 12428528
02:09:55 9343 8192000000 9979664768 10366390272 1071651296 11.568 100 34 67 2 1116050848 37356592 7042960
02:11:06 3941 8192000000 9986863856 10378534912 1077561904 11.624 100 48 91 2 1108360256 19514672 11283680
02:12:12 6722 8192000000 10007854336 10404212736 1086094336 11.706 100 32 63 0 1115928848 22864864 6969648
02:13:21 7273 8192000000 10006543104 10411728896 1093180720 11.773 100 46 93 2 1122490720 18253152 11056848
02:14:29 16796 8192000000 10022256128 10421059584 1093353616 11.775 100 36 72 0 1120841488 19444976 8042896
02:15:51 3807 8192000000 10006730656 10404306944 1095486848 11.795 100 40 79 2 1119945344 16141104 8317392
02:16:55 9980 8192000000 10000610320 10413981696 1089946992 11.742 100 32 62 1 1118548992 21621200 6980800
02:18:08 17851 8192000000 9963466784 10355089408 986042544 10.743 94 30 59 2 1009119616 16964704 6112368
02:19:29 11301 8192000000 9904945568 10292527104 953536064 10.426 92 24 48 1 987033504 28474512 5022928
02:20:46 5352 8192000000 9932549168 10310475776 996194688 10.842 97 27 54 3 1032196000 29651536 6349776
02:21:52 4222 8192000000 9954561360 10327179264 1021797856 11.089 99 51 98 1 1044880032 11759200 11322976
02:22:58 8842 8192000000 9937328112 10333822976 1034344112 11.210 100 44 87 4 1050474944 5424432 10706400
02:24:07 22324 8192000000 9945254848 10346213376 1022464672 11.096 98 13 26 0 1058211184 33122624 2623888
02:25:30 5822 8192000000 9975111984 10365153280 1060254192 11.459 100 35 70 1 1089458752 21465264 7739296
02:26:37 6971 8192000000 10001002400 10392240128 1070680624 11.559 100 38 77 3 1107913456 28976048 8256784
02:27:50 5741 8192000000 10014165120 10408353792 1092138816 11.763 100 45 92 1 1125208640 23108320 9961504
02:29:07 64937 8192000000 10056087184 10467098624 1096464752 11.804 99 32 64 1 1156200928 52313376 7422800
02:31:14 14170 8192000000 10094329968 10505854976 1121235872 12.039 100 42 84 0 1205968704 76161296 8571536
02:32:37 13326 8192000000 10109417936 10517561344 1126669440 12.090 100 40 80 4 1223885552 88370640 8845472
02:33:57 3625 8192000000 10125122272 10532102144 1137199744 12.189 100 64 128 0 1235825872 84241680 14384448
02:35:06 13530 8192000000 10173544144 10579623936 1142604016 12.240 100 60 120 1 1284942880 130335504 12003360
02:36:23 3113 8192000000 10196005392 10603810816 1160323936 12.406 100 76 151 3 1307439984 130739712 16376336
02:37:32 2913 8192000000 10216951184 10618490880 1145850080 12.271 100 73 146 1 1318063296 156309024 15904192
02:38:40 6627 8192000000 10233047248 10638098432 1161239072 12.415 100 75 148 4 1331804896 153521616 17044208
02:39:57 5192 8192000000 10221485328 10620788736 1145324128 12.266 100 67 130 0 1319551200 159255984 14971088
02:41:07 3249 8192000000 10244148928 10638180352 1133881760 12.158 99 80 157 1 1332667456 181313328 17472368
02:42:15 4944 8192000000 10276674288 10672181248 1130515376 12.126 100 73 145 0 1372052944 225382496 16155072
02:43:24 1634 8192000000 10227851456 10631315456 1132486224 12.145 100 72 144 3 1329552480 180306352 16759904
02:44:30 1211 8192000000 10232491488 10633457664 1125502800 12.079 99 90 184 0 1336990656 191680704 19807152
02:45:39 13031 8192000000 10255534272 10671505408 1122562928 12.051 99 38 76 1 1358620672 226785696 9272048
02:46:59 7315 8192000000 10283705376 10698612736 1142055232 12.235 100 36 72 1 1392162832 241037776 9069824
02:48:11 2553 8192000000 10327003632 10718842880 1126016512 12.084 98 75 150 0 1410815632 266649952 18149168
02:49:21 4719 8192000000 10375035152 10778959872 1152893440 12.337 100 66 129 0 1462176928 293497552 15785936
02:50:34 6096 8192000000 10431905200 10849685504 1170286384 12.500 100 52 101 2 1528528304 345222256 13019664
02:51:43 14340 8192000000 10468411536 10912952320 1154963648 12.356 99 38 76 0 1549434560 384584496 9886416
02:52:59 8971 8192000000 10501754288 10949722112 1160530464 12.408 100 35 70 0 1600706736 430559664 9616608
02:54:11 18538 8192000000 10554605696 10999689216 1164521584 12.446 100 32 64 0 1640876208 467846784 8507840
02:55:32 16430 8192000000 10597863536 11069992960 1136330064 12.181 100 34 68 0 1689359904 542845840 10184000
02:56:49 19907 8192000000 10402409696 10938556416 1106280416 11.897 98 31 62 1 1550077696 435390896 8406384
02:58:19 73776 8192000000 10073098128 10551820288 1106750368 11.902 98 12 24 0 1182978704 73934224 2294112
03:00:33 83049 8192000000 10075731760 10522148864 1096664512 11.806 96 9 18 0 1135887088 37305952 1916624
03:03:37 53648 8192000000 10061782528 10513743872 1130506400 12.126 99 27 53 1 1159118736 23032048 5580288
03:05:38 86955 8192000000 10083846320 10531766272 1146295744 12.275 100 20 40 1 1182076064 31854352 3925968
03:08:09 107904 8192000000 10104046576 10554757120 1144465552 12.258 99 14 29 0 1204331328 57489776 2376000
03:11:36 41778 8192000000 10170764832 10629828608 1157476896 12.380 100 30 60 1 1272926656 108303392 7146368
03:13:18 109427 8192000000 10180259664 10695372800 1158569584 12.390 100 22 44 0 1316805312 152899792 5335936
03:16:51 53893 8192000000 10107945312 10600333312 1158636416 12.390 100 23 46 1 1234856400 70851840 5368144
03:18:46 62884 8192000000 10159091024 10625380352 1150200960 12.311 99 17 35 1 1230080608 76095680 3783968
03:20:53 107784 8192000000 10152995344 10692792320 1141642128 12.231 98 17 34 1 1245883904 100012688 4229088
03:24:39 44310 8192000000 10082801120 10551136256 1153600608 12.343 100 24 48 0 1190623344 31751200 5271536
03:26:56 76750 8192000000 10096874000 10639933440 1159213968 12.396 100 6 12 0 1203625840 43356160 1055712
03:29:13 48292 8192000000 10075964192 10536419328 1130646656 12.127 98 19 38 1 1167328656 32805296 3876704
03:31:02 42366 8192000000 10089927328 10542034944 1099759920 11.835 96 17 34 1 1148633104 45301792 3571392
03:32:51 47487 8192000000 10064088464 10510221312 1100132144 11.839 98 23 46 1 1139074752 34467904 4474704
03:34:56 65888 8192000000 10049543696 10509225984 1105010496 11.885 98 18 36 0 1157504912 48994336 3500080
03:37:07 51122 8192000000 10093714928 10625650688 1132143920 12.142 100 22 44 1 1227128816 90219760 4765136
03:39:08 63203 8192000000 10121132416 10614267904 1107621168 11.910 98 14 28 0 1197067968 86190208 3256592
03:41:12 82541 8192000000 10029354784 10502451200 1061901552 11.475 95 12 24 0 1111296192 47177696 2216944
03:43:37 116522 8192000000 10027730304 10471985152 1084207712 11.688 98 15 30 0 1128852592 41334784 3310096
03:47:00 33543 8192000000 10032047808 10499391488 1090243152 11.745 98 3 6 0 1119797056 28991632 562272
03:48:35 108233 8192000000 10053857200 10509639680 1113453248 11.965 100 18 36 0 1147633072 30784592 3395232
03:52:05 49452 8192000000 10085775728 10538827776 1129768992 12.119 100 27 52 2 1186205680 50122208 6314480
03:54:05 63206 8192000000 10065592512 10516324352 1091790944 11.760 97 15 30 1 1125686352 30864992 3030416
03:56:16 49304 8192000000 10072281568 10530795520 1098474224 11.823 98 34 68 0 1159993424 54574960 6944240
03:58:05 121617 8192000000 10124370816 10582446080 1063324032 11.488 94 10 20 0 1120279952 55093008 1862912
04:02:11 79601 8192000000 10069440288 10540007424 1124363952 12.068 100 19 38 0 1182454048 54016960 4073136
04:04:32 74721 8192000000 10068290192 10558083072 1134867264 12.167 100 6 12 0 1182021344 45898832 1255248
04:06:47 85397 8192000000 10094207536 10557935616 1136869584 12.186 99 19 38 0 1193682608 53133952 3679072
04:09:13 25931 8192000000 10140849616 10589470720 1146005184 12.272 99 18 34 0 1227855568 77919024 3931360
04:10:40 75453 8192000000 10182915184 10652921856 1149166032 12.302 99 19 37 1 1260273104 106796736 4310336
04:13:00 51251 8192000000 10089443296 10559049728 1156159456 12.367 100 15 30 2 1228487680 69219872 3108352
04:14:52 34155 8192000000 10096474208 10553769984 1141757904 12.232 99 28 56 0 1188271600 39881472 6632224
04:16:29 34926 8192000000 10137424880 10564718592 1122110016 12.047 98 36 70 2 1203683120 73943088 7630016
04:18:13 146749 8192000000 10077664368 10552762368 1129270736 12.114 99 18 35 0 1162716816 29968560 3477520
04:21:47 78247 8192000000 10078147760 10550448128 1148985632 12.300 100 6 12 0 1186534160 36315424 1233104
04:24:36 64586 8192000000 10109907552 10588647424 1128318144 12.106 98 11 22 0 1173748656 43125296 2305216
04:26:41 21587 8192000000 8975325408 9202585600 133060880 1.598 12 1 2 0 142693776 9597488 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-03-2021 04:28:31 to 12-03-2021 04:28:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 12-03-2021 04:28:47 to 12-03-2021 04:28:48.

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          229448
MON$OLDEST_ACTIVE               229449
MON$OLDEST_SNAPSHOT             229449
MON$NEXT_TRANSACTION            229449
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                       2129216
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2385 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2385 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2385 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.2385 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 ---
:77: 
:78: mscs-test-161.budzdorov.ru    Fri Mar 12 04:27:04 2021
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
--- end of diff output ---

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

12.03.21 04:28:49. 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-03-12 04:28:52 No severe PSQL-related problems occured
12.03.21 04:28:53. End of report.