Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2021 04:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16054032 kB
MemAvailable 30943452 kB
Buffers 20 kB
Cached 15541716 kB
SwapCached 2580 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.1
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-08-16 04:37:04
DTS_END                         2021-08-16 04:37:04.6960
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/45112
TRN_ID                          230366
ATT_ID                          630
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: 9126.83 27570 1095219
customer order: creation 159.53 423 19143
customer order: refuse 13.86 408 1663
order to supplier: creation 45.05 1383 5406
order to supplier: removal 3.29 144785 395
invoice (draft): creation 42.71 3682 5125
invoice (draft): removal 53.34 1123 6401
invoice accept: apply 39.78 20499 4774
invoice accept: cancel 48.12 935 5774
customer reserve: creation 3716.14 200 445937
customer reserve: removal 4717.44 78 566093
realization accept: apply 78.96 184 9475
realization accept: cancel 85.37 139 10244
payment from customer: creation 56.74 106 6809
payment from customer: removal 5.33 98 639
payment to supplier: creation 47.40 134 5688
payment to supplier: removal 3.19 68 383
service: total inventory turnovers 3.68 3924 441
service: total monetary turnovers 5.96 581 715
service: aggregate perf. data 0.91 15151 109
service: refresh index statistics 0.04 357500 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2021 04:37:55 to 16-08-2021 04:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6110
TEST_TIME 2 6256
TEST_TIME 3 5229
TEST_TIME 4 5753
TEST_TIME 5 5506
TEST_TIME 6 5452
TEST_TIME 7 5408
TEST_TIME 8 5310
TEST_TIME 9 5128
TEST_TIME 10 5368
TEST_TIME 11 5969
TEST_TIME 12 6171
TEST_TIME 13 6319
TEST_TIME 14 6447
TEST_TIME 15 6439
TEST_TIME 16 6519
TEST_TIME 17 6602
TEST_TIME 18 6613
TEST_TIME 19 6677
TEST_TIME 20 7153
TEST_TIME 21 7496
TEST_TIME 22 7508
TEST_TIME 23 7503
TEST_TIME 24 7662
TEST_TIME 25 7980
TEST_TIME 26 8446
TEST_TIME 27 9034
TEST_TIME 28 8952
TEST_TIME 29 9472
TEST_TIME 30 10691
TEST_TIME 31 10968
TEST_TIME 32 10877
TEST_TIME 33 10732
TEST_TIME 34 10595
TEST_TIME 35 10424
TEST_TIME 36 10333
TEST_TIME 37 10324
TEST_TIME 38 10230
TEST_TIME 39 9992
TEST_TIME 40 9935
TEST_TIME 41 9919
TEST_TIME 42 9794
TEST_TIME 43 9892
TEST_TIME 44 9876
TEST_TIME 45 9752
TEST_TIME 46 9748
TEST_TIME 47 9776
TEST_TIME 48 9601
TEST_TIME 49 9604
TEST_TIME 50 9569
TEST_TIME 51 9476
TEST_TIME 52 9603
TEST_TIME 53 9646
TEST_TIME 54 9605
TEST_TIME 55 9720
TEST_TIME 56 9670
TEST_TIME 57 9655
TEST_TIME 58 9629
TEST_TIME 59 9720
TEST_TIME 60 9663
TEST_TIME 61 9649
TEST_TIME 62 9708
TEST_TIME 63 9708
TEST_TIME 64 9612
TEST_TIME 65 9523
TEST_TIME 66 9460
TEST_TIME 67 9422
TEST_TIME 68 9359
TEST_TIME 69 9331
TEST_TIME 70 9297
TEST_TIME 71 9281
TEST_TIME 72 9277
TEST_TIME 73 9322
TEST_TIME 74 9284
TEST_TIME 75 9283
TEST_TIME 76 9287
TEST_TIME 77 9220
TEST_TIME 78 9248
TEST_TIME 79 9268
TEST_TIME 80 9421
TEST_TIME 81 9437
TEST_TIME 82 9569
TEST_TIME 83 9565
TEST_TIME 84 9524
TEST_TIME 85 9420
TEST_TIME 86 9363
TEST_TIME 87 9335
TEST_TIME 88 9268
TEST_TIME 89 9234
TEST_TIME 90 9221
TEST_TIME 91 9146
TEST_TIME 92 9265
TEST_TIME 93 9236
TEST_TIME 94 9209
TEST_TIME 95 9215
TEST_TIME 96 9214
TEST_TIME 97 9224
TEST_TIME 98 9250
TEST_TIME 99 9225
TEST_TIME 100 9267
TEST_TIME 101 9323
TEST_TIME 102 9321
TEST_TIME 103 9292
TEST_TIME 104 9272
TEST_TIME 105 9239
TEST_TIME 106 9221
TEST_TIME 107 9203
TEST_TIME 108 9144
TEST_TIME 109 9122
TEST_TIME 110 9159
TEST_TIME 111 9146
TEST_TIME 112 9147
TEST_TIME 113 9127
TEST_TIME 114 9130
TEST_TIME 115 9098
TEST_TIME 116 9107
TEST_TIME 117 9154
TEST_TIME 118 9150
TEST_TIME 119 9206
TEST_TIME 120 9131

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2021 04:37:59 to 16-08-2021 04:38:02.

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 1507282 1507282 0 0.00 0 17523 7 0 0
doc_list_biud 1507279 1507279 0 0.00 0 74550 37 0 0
sp_add_invoice_to_stock 4907 4774 133 2.71 13 220599 20500 0 133
sp_cancel_adding_invoice 5774 5774 0 0.00 5 84627 935 0 0
sp_cancel_client_order 1663 1663 0 0.00 5 20247 409 0 0
sp_cancel_customer_reserve 566093 566093 0 0.00 1 74553 78 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 5148 99 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 1112 68 0 0
sp_cancel_supplier_invoice 6408 6401 7 0.10 2 72092 1123 0 7
sp_cancel_supplier_order 395 395 0 0.00 6 1328159 144786 0 0
sp_cancel_write_off 10244 10244 0 0.00 1 19888 139 0 0
sp_client_order 19143 19143 0 0.00 4 25948 424 0 0
sp_customer_reserve 445937 445937 0 0.00 0 44332 200 0 0
sp_fill_shopping_cart 29668 29668 0 0.00 1 9985 44 0 0
sp_fill_shopping_cart_clo_res 445928 445928 0 0.00 0 24127 19 0 0
sp_get_clo_for_invoice 4764 4764 0 0.00 0 27999 370 0 0
sp_kill_cost_storno 17040 17040 0 0.00 0 7268 72 0 0
sp_kill_qstorno_ret_qs2qd 1149226 1149226 0 0.00 0 74545 42 0 0
sp_kill_qty_storno 604809 604809 0 0.00 0 74550 91 0 0
sp_lock_selected_doc 19759 19759 0 0.00 0 8753 5 0 0
sp_make_cost_storno 26736 26736 0 0.00 0 12179 56 0 0
sp_multiply_rows_for_pdistr 26736 26736 0 0.00 0 12381 33 0 0
sp_multiply_rows_for_qdistr 19143 19143 0 0.00 0 17232 228 0 0
sp_pay_from_customer 6809 6809 0 0.00 4 12881 106 0 0
sp_pay_to_supplier 5688 5688 0 0.00 5 12518 135 0 0
sp_payment_common 12497 12497 0 0.00 1 10257 22 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 20239 376 0 0
sp_qd_handle_on_invoice_upd_sts 10538 10538 0 0.00 0 21848 405 0 0
sp_qd_handle_on_reserve_upd_sts 9475 9475 0 0.00 0 19018 61 0 0
sp_reserve_write_off 9475 9475 0 0.00 6 19033 185 0 0
sp_supplier_invoice 5125 5125 0 0.00 8 87940 3682 0 0
sp_supplier_order 5406 5406 0 0.00 5 26152 1384 0 0
srv_aggregate_perf_data 109 109 0 0.00 2625 155371 15152 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 786 110399 41467 0 0
srv_find_qd_qs_mism 1049289 1049289 0 0.00 0 17496 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 67 120990 3925 0 0
srv_make_money_saldo 715 715 0 0.00 1 15745 582 0 0
srv_recalc_idx_stat 5 5 0 0.00 256772 493923 357500 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4774 4774 0 0.00 0 10273 191 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 5329 105 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 10070 22 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 7333 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 3238 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 4023 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 4584 29 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 20767 1808 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 6443 20 0 0
t$perf-norm:sp_client_order 19143 19143 0 0.00 0 15800 20 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 7610 18 0 0
t$perf-norm:sp_pay_from_customer 6809 6809 0 0.00 0 5618 9 0 0
t$perf-norm:sp_pay_to_supplier 5688 5688 0 0.00 0 8749 16 0 0
t$perf-norm:sp_reserve_write_off 9475 9475 0 0.00 0 15556 17 0 0
t$perf-norm:sp_supplier_invoice 5125 5125 0 0.00 0 5448 21 0 0
t$perf-norm:sp_supplier_order 5406 5406 0 0.00 0 4688 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1963 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 720 10 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 514 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 15 4 0 0
v_add_invoice_to_stock 9792 9526 266 2.71 0 5565 19 0 266
v_all_customers 35576 35576 0 0.00 0 111 1 0 0
v_all_suppliers 29602 29602 0 0.00 0 152 1 0 0
v_all_wares 38286 38286 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 992 7 0 0
v_cancel_client_order 3326 3326 0 0.00 0 2821 5 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 2163 7 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 2330 6 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 695 6 0 0
v_cancel_supplier_invoice 796 782 14 1.75 0 1522 15 0 14
v_cancel_supplier_order 790 790 0 0.00 0 523 6 0 0
v_cancel_write_off 1948 1948 0 0.00 0 3497 19 0 0
v_max_id_clo_ord 5403 5403 0 0.00 0 360 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 3351 11 0 0
v_max_id_ord_sup 5122 5122 0 0.00 0 6897 4 0 0
v_max_non_paid_invoice 1412 1412 0 0.00 0 874 3 0 0
v_max_non_paid_realizn 3354 3354 0 0.00 0 4446 4 0 0
v_min_id_clo_ord 5403 5403 0 0.00 0 2599 5 0 0
v_min_id_clo_res 958 958 0 0.00 0 1734 5 0 0
v_min_id_ord_sup 5122 5122 0 0.00 0 5538 6 0 0
v_min_non_paid_invoice 5688 5688 0 0.00 0 6866 13 0 0
v_min_non_paid_realizn 6809 6809 0 0.00 0 8693 17 0 0
v_our_firm 9620 9620 0 0.00 0 15 1 0 0
v_reserve_write_off 18950 18950 0 0.00 0 11091 5 0 0
x_lock_depdocs_on_canc_invoice 11784 11784 0 0.00 0 7984 112 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 15392 451 0 0
x_make_qstorno_1000_1200 5406 5406 0 0.00 2 26117 1295 0 0
x_make_qstorno_1200_2000 5125 5125 0 0.00 4 87924 3515 0 0
x_make_qstorno_2100_3300 445937 445937 0 0.00 0 43837 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2021 04:38:03 to 16-08-2021 04:38:07.

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
02:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:09 34468 8192000000 9728024944 10027544576 807448048 8.972 100 90 180 0 836569328 8090864 21030416
02:09:48 21727 8192000000 9763143280 10072416256 838734448 9.287 100 86 172 0 872017808 13200304 20083056
02:11:13 15979 8192000000 9804913392 10124345344 874526448 9.645 100 87 174 1 913427664 19643808 19257408
02:12:39 17792 8192000000 9874404832 10209505280 909973536 9.997 100 81 162 2 980400304 51861904 18564864
02:13:59 9929 8192000000 9930180272 10273628160 948783504 10.379 100 79 158 3 1038826784 72815952 17227328
02:15:11 6180 8192000000 9948702064 10303766528 983951168 10.723 100 75 150 0 1066928352 67208688 15768496
02:16:19 3446 8192000000 9987476288 10353967104 1025684208 11.127 100 86 172 0 1105847184 62501360 17661616
02:17:24 2826 8192000000 10025083232 10392383488 1045148640 11.314 100 88 176 1 1135322080 72524240 17649200
02:18:29 1783 8192000000 10052222640 10426327040 1060740960 11.464 100 83 165 1 1164363872 87114096 16508816
02:19:32 35754 8192000000 9991712128 10383974400 1065797776 11.512 100 51 102 1 1096819968 20430784 10591408
02:21:08 7134 8192000000 9997619264 10388103168 1070966448 11.561 100 32 64 4 1106844368 28375840 7502080
02:22:16 9518 8192000000 9989062592 10388299776 1079424832 11.642 100 24 49 0 1111146528 26567408 5154288
02:23:27 5623 8192000000 9996884448 10400219136 1085269968 11.698 100 23 43 3 1121399792 31888016 4241808
02:24:33 16121 8192000000 10010609376 10401280000 1089886064 11.742 100 80 160 5 1117872272 9471376 18514832
02:25:49 5930 8192000000 10010478768 10406977536 1092674768 11.768 100 42 84 0 1119856800 18256896 8925136
02:26:56 8890 8192000000 10013574512 10417532928 1088373248 11.727 100 35 70 3 1119085168 23783328 6928592
02:28:08 47924 8192000000 9995146464 10404159488 1065042336 11.505 98 32 64 2 1086163840 14167472 6954032
02:29:56 3997 8192000000 9945151040 10326814720 997044480 10.850 95 45 90 4 1040276736 33574512 9657744
02:31:01 6081 8192000000 9943983840 10331594752 1035179744 11.218 100 29 57 3 1060662640 19180880 6302016
02:32:09 7715 8192000000 9930291424 10315984896 1013412720 11.008 99 42 84 0 1040570560 17350320 9807520
02:33:18 33866 8192000000 9945097440 10345996288 1000852416 10.887 96 28 55 0 1034488640 27658432 5977792
02:34:53 6284 8192000000 9989296656 10370682880 1058044720 11.438 100 46 92 1 1085706416 17604928 10056768
02:36:00 10538 8192000000 9985376976 10392616960 1074323520 11.593 100 38 76 2 1108848864 26120256 8405088
02:37:14 15124 8192000000 10006625632 10402021376 1083415248 11.680 100 41 83 2 1115255616 22704832 9135536
02:38:34 56771 8192000000 10045168016 10448097280 1098631664 11.825 100 39 79 1 1166647568 59560080 8455824
02:40:37 59624 8192000000 10090985776 10522746880 1115558032 11.985 100 49 98 3 1197698240 71811216 10328992
02:42:52 8677 8192000000 10110066912 10518220800 1132326576 12.143 100 38 76 0 1211921120 71213920 8380624
02:44:06 18378 8192000000 10128359248 10544517120 1140999328 12.225 100 46 92 1 1237169328 85800720 10369280
02:45:26 14203 8192000000 10193658864 10613706752 1146746048 12.279 100 55 108 1 1292338208 133276560 12315600
02:47:04 6344 8192000000 10252644752 10663141376 1164048656 12.441 100 59 118 0 1348847840 171786448 13012736
02:48:15 9941 8192000000 10299023888 10707877888 1179522032 12.586 100 67 133 0 1387756112 194049408 14184672
02:49:32 8366 8192000000 10276507056 10696892416 1186477856 12.651 100 62 122 2 1381961696 182505184 12978656
02:50:47 4254 8192000000 10269969184 10688126976 1195837360 12.738 100 63 125 0 1379415088 169606496 13971232
02:51:57 2412 8192000000 10323372768 10732085248 1205892736 12.831 100 73 142 0 1422573856 200869184 15811936
02:53:06 1914 8192000000 10409526864 10819158016 1207576256 12.847 100 92 181 1 1503082992 274828464 20678272
02:54:15 2087 8192000000 10463807568 10868768768 1212785056 12.895 100 88 173 1 1564090064 331008768 20296240
02:55:21 779 8192000000 10527997616 10933575680 1208767104 12.858 100 97 194 1 1626711808 395004544 22940160
02:56:29 1469 8192000000 10566557680 10968055808 1184861040 12.636 100 95 190 2 1663713328 455672672 23179616
02:57:37 2390 8192000000 10580523200 11003330560 1182895392 12.617 100 79 157 0 1681027792 479082128 19050272
02:58:49 16557 8192000000 10541613600 11021164544 1185481264 12.641 100 43 86 0 1663644608 466853936 11309408
03:00:12 11891 8192000000 10610843312 11043454976 1193219504 12.713 100 57 114 2 1708592992 500971232 14402256
03:01:36 2057 8192000000 10651243520 11091308544 1176346864 12.556 99 93 182 1 1735908640 537002704 22559072
03:02:43 8971 8192000000 10612325920 11084877824 1179140096 12.582 100 42 82 2 1698258640 507645952 11472592
03:03:54 21165 8192000000 10512924448 11022180352 1160400288 12.407 99 44 85 0 1621289872 449250176 11639408
03:05:20 17715 8192000000 10477985904 10925277184 1157203872 12.377 100 28 56 2 1574188192 409365104 7619216
03:06:40 10947 8192000000 10406523376 10935058432 1127299392 12.096 99 19 38 0 1492147424 359770064 5077968
03:07:54 25230 8192000000 10120540992 10621792256 1129894192 12.120 100 16 33 0 1239416208 105886192 3635824
03:09:54 71074 8192000000 10069737680 10503520256 1098831920 11.827 97 13 26 0 1121762208 20217856 2712432
03:12:15 27252 8192000000 10111795840 10537340928 1140385280 12.219 100 29 56 3 1195772848 49705200 5682368
03:13:49 102604 8192000000 10081427872 10578882560 1112678656 11.958 97 27 54 0 1189406432 71249104 5478672
03:16:41 38014 8192000000 10121288304 10558713856 1139531136 12.211 100 43 86 0 1201388512 53565184 8292192
03:18:20 56903 8192000000 10134130688 10582216704 1130802768 12.129 98 28 56 1 1226132064 89185776 6143520
03:20:42 68730 8192000000 10099049472 10589200384 1134611744 12.165 99 15 31 0 1208600304 70935248 3053312
03:22:51 27014 8192000000 10109369232 10578079744 1131248976 12.133 99 9 18 0 1214119792 80689584 2181232
03:24:57 37191 8192000000 10094563312 10565517312 1143140224 12.245 100 12 24 1 1205294864 59524912 2629728
03:26:52 72646 8192000000 10119171408 10605744128 1146756080 12.279 99 11 22 1 1217918992 68954304 2208608
03:29:05 53853 8192000000 10096011280 10562482176 1104181728 11.877 96 15 30 0 1146954864 39505104 3268032
03:31:00 55346 8192000000 10101528928 10560163840 1094951232 11.790 95 23 46 1 1153274624 53742384 4581008
03:32:59 40834 8192000000 10090084112 10537340928 1126003504 12.084 98 44 86 1 1177451328 42156800 9291024
03:34:49 19890 8192000000 10113077952 10542919680 1136250784 12.180 100 42 84 1 1183680176 39736848 7692544
03:37:07 110399 8192000000 10117576928 10596401152 1064824640 11.503 93 9 18 0 1153742464 86944384 1973440
03:40:06 49746 8192000000 10141259328 10567245824 1141520048 12.230 100 47 94 0 1205371424 54375808 9475568
03:41:56 95944 8192000000 10100582320 10546356224 1132158352 12.142 99 35 70 1 1198405776 59069152 7178272
03:44:43 69903 8192000000 10085651808 10557112320 1140772816 12.223 100 15 30 0 1192599744 48522304 3304624
03:46:56 89749 8192000000 10082079488 10565902336 1093140656 11.773 97 14 28 0 1157348000 60742432 3464912
03:49:48 77341 8192000000 10045595824 10518368256 1075071008 11.600 96 10 20 1 1134199648 57069616 2059024
03:52:14 51759 8192000000 10086104784 10545790976 1124016768 12.065 100 23 46 2 1180886752 52186928 4683056
03:54:11 49916 8192000000 10176874256 10620891136 1108936592 11.922 98 36 71 1 1206460544 89888064 7635888
03:56:02 71925 8192000000 10204475552 10677792768 1133543040 12.155 99 23 46 0 1294190016 155483216 5163760
03:58:18 32766 8192000000 10070354784 10506543104 1120118064 12.028 99 19 39 1 1167517952 43641792 3758096
03:59:55 87634 8192000000 10071263744 10551816192 1115909120 11.988 98 6 12 0 1155662032 38556144 1196768
04:02:23 79113 8192000000 10106414112 10547068928 1143483120 12.248 100 30 60 1 1198729088 48690800 6555168
04:04:43 37381 8192000000 10093920224 10548695040 1130710736 12.128 98 11 22 0 1184461024 51495840 2254448
04:06:20 65005 8192000000 10136007360 10620391424 1127162896 12.095 99 13 26 1 1241171168 111024464 2983808
04:08:40 71629 8192000000 10081524192 10628263936 1113281504 11.963 98 16 32 1 1228067280 110755536 4030240
04:10:57 39097 8192000000 10055595888 10500497408 1112853824 11.959 98 21 39 0 1134342128 17742368 3745936
04:12:40 44462 8192000000 10055851376 10517676032 1114755056 11.977 98 17 34 0 1151766864 33578240 3433568
04:14:34 71394 8192000000 10073967392 10564022272 1128833440 12.110 99 17 34 1 1184499296 52111424 3554432
04:16:47 32146 8192000000 10156942224 10604998656 1127297792 12.096 98 37 73 1 1201715968 66897328 7520848
04:18:20 76437 8192000000 10094023856 10560245760 1112523776 11.956 97 13 26 0 1170760848 55662256 2574816
04:20:37 50350 8192000000 10083393376 10561728512 1120579520 12.032 98 33 63 0 1192158080 64573504 7005056
04:23:06 42108 8192000000 10106245472 10564431872 1137013744 12.187 99 33 66 0 1184428208 40663616 6750848
04:24:51 73781 8192000000 10117695344 10572353536 1143495392 12.248 100 11 22 0 1210831360 65150672 2185296
04:27:05 57684 8192000000 10064580192 10596298752 1099760944 11.835 97 6 12 0 1141860704 41052352 1047408
04:29:22 31695 8192000000 10076317008 10541297664 1138901408 12.205 100 28 57 0 1172037184 26810000 6325776
04:31:11 43681 8192000000 10118941328 10563977216 1119958016 12.027 98 25 50 0 1189207680 63939200 5310464
04:33:04 50309 8192000000 10147418448 10595659776 1120180880 12.029 99 24 49 1 1234528352 109220624 5126848
04:34:55 67552 8192000000 10142217728 10588299264 1108515856 11.918 98 22 44 0 1218184160 104092992 5575312
04:37:03 17222 8192000000 8980528032 9204359168 135347632 1.625 13 2 2 1 144277856 8557056 373168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-08-2021 04:38:09 to 16-08-2021 04:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2021 04:38:25 to 16-08-2021 04:38:27.

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          230649
MON$OLDEST_ACTIVE               230650
MON$OLDEST_SNAPSHOT             230650
MON$NEXT_TRANSACTION            230650
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                       2051296
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

16.08.21 04:38:27. 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-08-16 04:38:30 No severe PSQL-related problems occured
16.08.21 04:38:31. End of report.