Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.03.2021 10: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 16869332 kB
MemAvailable 31583604 kB
Buffers 0 kB
Cached 14771132 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-09 10:26:58
DTS_END                         2021-03-09 10:26:58.1210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46796
TRN_ID                          238434
ATT_ID                          638
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: 8933.42 21175 1072011
customer order: creation 173.30 410 20796
customer order: refuse 14.71 325 1765
order to supplier: creation 46.37 1483 5564
order to supplier: removal 3.49 109903 419
invoice (draft): creation 46.72 3315 5606
invoice (draft): removal 51.57 921 6188
invoice accept: apply 41.89 17450 5027
invoice accept: cancel 45.79 879 5495
customer reserve: creation 3757.15 176 450858
customer reserve: removal 4464.45 68 535734
realization accept: apply 86.57 177 10388
realization accept: cancel 70.78 131 8493
payment from customer: creation 60.06 108 7207
payment from customer: removal 5.81 57 697
payment to supplier: creation 50.88 128 6106
payment to supplier: removal 3.29 76 395
service: total inventory turnovers 4.16 3290 499
service: total monetary turnovers 5.60 678 672
service: aggregate perf. data 0.82 19717 98
service: refresh index statistics 0.03 264209 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-03-2021 10:28:19 to 09-03-2021 10:28:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2860
TEST_TIME 2 4970
TEST_TIME 3 4866
TEST_TIME 4 5772
TEST_TIME 5 5778
TEST_TIME 6 5300
TEST_TIME 7 5592
TEST_TIME 8 5668
TEST_TIME 9 6001
TEST_TIME 10 6181
TEST_TIME 11 6128
TEST_TIME 12 6462
TEST_TIME 13 6698
TEST_TIME 14 6863
TEST_TIME 15 7252
TEST_TIME 16 7349
TEST_TIME 17 7589
TEST_TIME 18 7575
TEST_TIME 19 7712
TEST_TIME 20 8265
TEST_TIME 21 8645
TEST_TIME 22 8527
TEST_TIME 23 8900
TEST_TIME 24 9312
TEST_TIME 25 9536
TEST_TIME 26 9760
TEST_TIME 27 9642
TEST_TIME 28 10519
TEST_TIME 29 10843
TEST_TIME 30 10724
TEST_TIME 31 10583
TEST_TIME 32 10715
TEST_TIME 33 10671
TEST_TIME 34 10627
TEST_TIME 35 10626
TEST_TIME 36 10422
TEST_TIME 37 10167
TEST_TIME 38 9987
TEST_TIME 39 9918
TEST_TIME 40 9850
TEST_TIME 41 9768
TEST_TIME 42 9761
TEST_TIME 43 9726
TEST_TIME 44 9626
TEST_TIME 45 9636
TEST_TIME 46 9613
TEST_TIME 47 9450
TEST_TIME 48 9341
TEST_TIME 49 9413
TEST_TIME 50 9438
TEST_TIME 51 9530
TEST_TIME 52 9457
TEST_TIME 53 9545
TEST_TIME 54 9469
TEST_TIME 55 9490
TEST_TIME 56 9507
TEST_TIME 57 9605
TEST_TIME 58 9593
TEST_TIME 59 9723
TEST_TIME 60 9712
TEST_TIME 61 9625
TEST_TIME 62 9503
TEST_TIME 63 9419
TEST_TIME 64 9346
TEST_TIME 65 9261
TEST_TIME 66 9227
TEST_TIME 67 9171
TEST_TIME 68 9221
TEST_TIME 69 9200
TEST_TIME 70 9165
TEST_TIME 71 9245
TEST_TIME 72 9284
TEST_TIME 73 9270
TEST_TIME 74 9258
TEST_TIME 75 9335
TEST_TIME 76 9295
TEST_TIME 77 9248
TEST_TIME 78 9388
TEST_TIME 79 9343
TEST_TIME 80 9260
TEST_TIME 81 9280
TEST_TIME 82 9263
TEST_TIME 83 9224
TEST_TIME 84 9194
TEST_TIME 85 9154
TEST_TIME 86 9131
TEST_TIME 87 9150
TEST_TIME 88 9098
TEST_TIME 89 9075
TEST_TIME 90 9065
TEST_TIME 91 9098
TEST_TIME 92 9272
TEST_TIME 93 9253
TEST_TIME 94 9281
TEST_TIME 95 9288
TEST_TIME 96 9292
TEST_TIME 97 9236
TEST_TIME 98 9149
TEST_TIME 99 9103
TEST_TIME 100 9051
TEST_TIME 101 9041
TEST_TIME 102 8991
TEST_TIME 103 9006
TEST_TIME 104 8993
TEST_TIME 105 8994
TEST_TIME 106 9033
TEST_TIME 107 9008
TEST_TIME 108 9039
TEST_TIME 109 9046
TEST_TIME 110 9009
TEST_TIME 111 9033
TEST_TIME 112 9062
TEST_TIME 113 9094
TEST_TIME 114 9043
TEST_TIME 115 8972
TEST_TIME 116 8987
TEST_TIME 117 8946
TEST_TIME 118 8924
TEST_TIME 119 8890
TEST_TIME 120 8935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-03-2021 10:28:22 to 09-03-2021 10:28:26.

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 1490638 1490638 0 0.00 0 21021 6 0 0
doc_list_biud 1490637 1490637 0 0.00 0 81944 31 0 0
sp_add_invoice_to_stock 5150 5027 123 2.38 30 156820 17451 0 123
sp_cancel_adding_invoice 5495 5495 0 0.00 4 73949 880 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 14944 326 0 0
sp_cancel_customer_reserve 535734 535734 0 0.00 1 66554 69 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 992 58 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2108 76 0 0
sp_cancel_supplier_invoice 6197 6188 9 0.14 2 81950 922 0 9
sp_cancel_supplier_order 419 419 0 0.00 5 986740 109903 0 0
sp_cancel_write_off 8493 8493 0 0.00 1 11013 131 0 0
sp_client_order 20796 20796 0 0.00 4 69572 410 0 0
sp_customer_reserve 450858 450858 0 0.00 0 56852 176 0 0
sp_fill_shopping_cart 31961 31961 0 0.00 1 15232 45 0 0
sp_fill_shopping_cart_clo_res 450856 450856 0 0.00 0 28032 19 0 0
sp_get_clo_for_invoice 5024 5024 0 0.00 0 45649 411 0 0
sp_kill_cost_storno 15080 15080 0 0.00 0 10387 60 0 0
sp_kill_qstorno_ret_qs2qd 1086568 1086568 0 0.00 0 81943 37 0 0
sp_kill_qty_storno 573506 573506 0 0.00 0 81944 80 0 0
sp_lock_selected_doc 21169 21169 0 0.00 0 6946 5 0 0
sp_make_cost_storno 28725 28725 0 0.00 0 14760 52 0 0
sp_multiply_rows_for_pdistr 28725 28725 0 0.00 0 8420 32 0 0
sp_multiply_rows_for_qdistr 20796 20796 0 0.00 0 69565 242 0 0
sp_pay_from_customer 7207 7207 0 0.00 4 9176 109 0 0
sp_pay_to_supplier 6106 6106 0 0.00 4 13219 128 0 0
sp_payment_common 13313 13313 0 0.00 1 10379 18 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 14935 301 0 0
sp_qd_handle_on_invoice_upd_sts 10519 10519 0 0.00 0 18298 376 0 0
sp_qd_handle_on_reserve_upd_sts 10388 10388 0 0.00 0 13805 55 0 0
sp_reserve_write_off 10388 10388 0 0.00 7 19634 177 0 0
sp_supplier_invoice 5606 5606 0 0.00 6 91353 3315 0 0
sp_supplier_order 5564 5564 0 0.00 5 85336 1484 0 0
srv_aggregate_perf_data 98 98 0 0.00 2889 314235 19718 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 841 145526 44602 0 0
srv_find_qd_qs_mism 1024872 1024872 0 0.00 0 21020 5 0 0
srv_make_invnt_saldo 499 499 0 0.00 39 91637 3291 0 0
srv_make_money_saldo 672 672 0 0.00 1 12385 678 0 0
srv_recalc_idx_stat 4 4 0 0.00 123715 409508 264210 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 0 12364 171 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 10789 142 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 2372 13 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 2062 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 4295 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 2586 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 7574 42 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 20407 1538 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 877 3 0 0
t$perf-norm:sp_client_order 20796 20796 0 0.00 0 9879 18 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 3704 11 0 0
t$perf-norm:sp_pay_from_customer 7207 7207 0 0.00 0 8445 10 0 0
t$perf-norm:sp_pay_to_supplier 6106 6106 0 0.00 0 5004 9 0 0
t$perf-norm:sp_reserve_write_off 10388 10388 0 0.00 0 7475 13 0 0
t$perf-norm:sp_supplier_invoice 5606 5606 0 0.00 0 7067 19 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 7461 14 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 604 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 496 8 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 602 6 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 3321 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10292 10046 246 2.39 0 4913 15 0 246
v_all_customers 37364 37364 0 0.00 0 22 1 0 0
v_all_suppliers 30872 30872 0 0.00 0 26 1 0 0
v_all_wares 41592 41592 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 154 5 0 0
v_cancel_client_order 3530 3530 0 0.00 0 5668 6 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 761 4 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 4927 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 1111 8 0 0
v_cancel_supplier_invoice 874 856 18 2.05 0 2598 16 0 18
v_cancel_supplier_order 838 838 0 0.00 0 649 4 0 0
v_cancel_write_off 1892 1892 0 0.00 0 6208 18 0 0
v_max_id_clo_ord 5564 5564 0 0.00 0 6005 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 3537 24 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 8078 5 0 0
v_max_non_paid_invoice 1835 1835 0 0.00 0 585 2 0 0
v_max_non_paid_realizn 3994 3994 0 0.00 0 644 3 0 0
v_min_id_clo_ord 5564 5564 0 0.00 0 5440 8 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 8041 32 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 10010 9 0 0
v_min_non_paid_invoice 6106 6106 0 0.00 0 5643 12 0 0
v_min_non_paid_realizn 7207 7207 0 0.00 0 5764 12 0 0
v_our_firm 10654 10654 0 0.00 0 15 1 0 0
v_reserve_write_off 20776 20776 0 0.00 0 3466 5 0 0
x_lock_depdocs_on_canc_invoice 11255 11255 0 0.00 0 15449 103 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 4340 297 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 2 82304 1398 0 0
x_make_qstorno_1200_2000 5606 5606 0 0.00 3 91347 3146 0 0
x_make_qstorno_2100_3300 450858 450858 0 0.00 0 56851 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2021 10:28:27 to 09-03-2021 10:28:31.

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
07:57:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:02 27510 8192000000 9718325584 10012753920 792353888 8.819 100 86 172 0 821470112 7833872 21282352
07:59:31 24052 8192000000 9761902928 10065039360 835689216 9.256 100 90 180 1 869804480 12691600 21423664
08:01:02 17407 8192000000 9803290144 10118619136 864528272 9.545 100 76 152 0 908875840 27144496 17203072
08:02:26 14318 8192000000 9886075936 10223128576 930570000 10.200 100 76 152 3 992413520 46116768 15726752
08:03:43 7392 8192000000 9944247312 10290487296 969785584 10.585 100 80 158 0 1053172624 67378688 16008352
08:04:53 3609 8192000000 9959261472 10316636160 997776960 10.857 100 76 153 0 1078350352 64922608 15650784
08:05:58 3115 8192000000 9995754240 10360786944 1026240432 11.132 100 77 154 1 1110658784 68495104 15923248
08:07:02 1632 8192000000 10044134608 10409119744 1051921344 11.379 100 90 181 0 1154014416 83173824 18919248
08:08:06 6058 8192000000 9991578384 10380480512 1063569952 11.491 100 49 98 1 1116420752 42442112 10408688
08:09:13 28708 8192000000 9991076832 10375491584 1070559424 11.557 100 47 94 1 1098452128 17589584 10303120
08:10:42 6360 8192000000 10000981184 10391433216 1077509792 11.624 100 44 86 1 1110966800 23753728 9703280
08:11:49 6239 8192000000 9997476576 10399326208 1083888256 11.685 100 40 80 2 1107848256 14262432 9697568
08:12:57 5591 8192000000 10002574016 10398617600 1090908896 11.751 100 46 88 3 1113669568 13478208 9282464
08:14:06 58934 8192000000 10006175072 10399305728 1094139920 11.782 100 29 56 2 1106392672 6138544 6114208
08:16:05 4966 8192000000 10010672304 10406830080 1095196688 11.792 100 40 80 1 1131220912 27759920 8264304
08:17:11 8578 8192000000 9982096080 10383286272 1043350800 11.297 97 36 71 1 1077746528 27118720 7277008
08:18:21 9412 8192000000 9943509600 10341449728 1013562240 11.010 97 36 71 2 1042719584 21836576 7320768
08:19:33 50893 8192000000 9919066864 10312261632 938795536 10.281 90 23 45 1 963946752 20728544 4422672
08:21:26 7544 8192000000 9944563328 10323841024 1006365936 10.940 98 41 82 0 1037814128 21707184 9741008
08:22:35 6569 8192000000 9963490496 10349985792 1026725488 11.137 99 45 92 2 1055271344 18957136 9588720
08:23:48 11973 8192000000 9981047184 10384252928 1056679104 11.425 100 27 54 2 1079642896 16967712 5996080
08:25:00 71797 8192000000 9958157040 10368466944 1058793776 11.445 100 18 36 0 1085231968 22468112 3970080
08:27:13 22873 8192000000 10011494480 10407202816 1089909744 11.742 100 65 131 2 1126214192 22803632 13500816
08:28:43 7113 8192000000 10060533904 10459512832 1112511872 11.956 100 52 104 0 1168196976 45269552 10415552
08:30:00 13720 8192000000 10096978912 10504065024 1123453888 12.060 100 44 86 0 1212660960 79644864 9562208
08:31:16 34598 8192000000 10079537568 10496262144 1133020112 12.150 100 33 65 2 1214118976 73893152 7205712
08:32:58 10212 8192000000 10136660896 10552504320 1141738688 12.232 100 51 101 0 1233411488 81281728 10391072
08:34:20 3398 8192000000 10159205760 10567057408 1150891680 12.318 100 60 120 0 1260143008 96419952 12831376
08:35:31 4459 8192000000 10168527280 10578513920 1149551024 12.305 99 57 111 3 1271578352 109866736 12160592
08:36:40 11249 8192000000 10210568704 10625060864 1160101952 12.404 100 63 126 3 1310462128 136697792 13662384
08:38:02 2434 8192000000 10249336912 10655760384 1167455536 12.473 100 80 157 0 1350898144 165483088 17959520
08:39:08 3941 8192000000 10273946528 10676211712 1165444784 12.454 100 89 174 1 1373197632 188375648 19377200
08:40:18 3368 8192000000 10294635072 10708381696 1167627312 12.475 100 63 126 0 1396542816 214588976 14326528
08:41:25 1923 8192000000 10280906464 10679185408 1145052560 12.263 99 77 153 3 1376143168 214061872 17028736
08:42:31 3544 8192000000 10289135744 10705821696 1149517936 12.305 100 71 140 2 1395440880 229173488 16749456
08:43:38 838 8192000000 10338375216 10743365632 1156791296 12.373 100 92 184 0 1434595360 256619904 21184160
08:44:44 3883 8192000000 10351003952 10758402048 1160495312 12.408 100 69 138 0 1449995056 273347440 16152304
08:45:51 4052 8192000000 10403684336 10815127552 1164545520 12.446 100 61 122 1 1510409056 331026368 14837168
08:47:08 5052 8192000000 10355485360 10771365888 1154203040 12.349 100 51 101 0 1455220528 289417200 11600288
08:48:21 10438 8192000000 10351992512 10776100864 1141937856 12.234 99 38 76 0 1451276912 299648784 9690272
08:49:34 9453 8192000000 10364057712 10783277056 1149549888 12.305 100 45 90 0 1465872208 305031136 11291184
08:50:45 8076 8192000000 10307219728 10755743744 1117887552 12.007 99 42 85 1 1409397392 280563904 10945936
08:52:35 31561 8192000000 10231225616 10678153216 1110023312 11.933 99 34 66 1 1345635632 227191600 8420720
08:54:11 23841 8192000000 10233302256 10748518400 1105317856 11.888 100 13 26 1 1325606560 216394368 3894336
08:55:36 84573 8192000000 10064101296 10518355968 1112653664 11.958 99 9 18 0 1187685968 72951200 2081104
08:58:12 86073 8192000000 10065945664 10527121408 1125486016 12.079 99 16 30 1 1199904928 71023680 3395232
09:00:43 110716 8192000000 10044722688 10508320768 1083911136 11.685 96 7 14 0 1121723712 36765344 1047232
09:03:34 75346 8192000000 10078276800 10500743168 1128871232 12.111 100 40 80 0 1161882384 25108400 7902752
09:05:51 24339 8192000000 10093165376 10534449152 1132918544 12.149 99 38 73 1 1191232320 50866176 7447600
09:07:20 70048 8192000000 10089722048 10561179648 1132747664 12.147 99 25 50 0 1211691648 73171696 5772288
09:10:25 60812 8192000000 10100442640 10559483904 1110246976 11.935 97 15 29 0 1158614288 44849168 3518144
09:12:26 56395 8192000000 10071608000 10528718848 1124822896 12.073 99 14 28 1 1166539504 38505904 3210704
09:14:26 56023 8192000000 10101423712 10567692288 1105394480 11.889 97 8 16 0 1178410944 71217392 1799072
09:16:24 59471 8192000000 10069424768 10536017920 1127438784 12.097 99 15 30 0 1192194976 61606576 3149616
09:18:36 49060 8192000000 10102885856 10593648640 1128227680 12.105 99 25 50 0 1178558624 45652368 4678576
09:20:46 36463 8192000000 10125661120 10574938112 1144876944 12.261 100 37 74 0 1199178144 46723120 7578080
09:22:23 53705 8192000000 10126843232 10595848192 1121789008 12.044 98 30 59 1 1186084272 58419968 5875296
09:24:26 40000 8192000000 10129036624 10569637888 1113393456 11.965 97 23 46 1 1199526768 81016256 5117056
09:26:16 78826 8192000000 10067462512 10496163840 1079268880 11.641 95 15 30 0 1123513248 41664240 2580128
09:28:36 77822 8192000000 10054723552 10500816896 1119230416 12.020 98 38 76 2 1152553440 25965376 7357648
09:31:01 68055 8192000000 10089764944 10550812672 1106032464 11.895 97 9 17 0 1157401616 49499632 1869520
09:33:09 41470 8192000000 10075038768 10546221056 1103146736 11.867 97 10 21 1 1158725072 53573472 2004864
09:35:08 36082 8192000000 10048904112 10485530624 1088135456 11.725 97 15 30 0 1115241104 23521792 3583856
09:36:47 70203 8192000000 10092585024 10580078592 1112534448 11.956 98 28 56 1 1207664080 88932032 6197600
09:39:01 17753 8192000000 10083622608 10515103744 1116740944 11.996 99 46 89 2 1162984144 36566576 9676624
09:40:19 83814 8192000000 10051238080 10551595008 1107564256 11.909 98 14 28 1 1150054224 39484048 3005920
09:42:46 56690 8192000000 10129044288 10565640192 1099933248 11.837 98 23 46 1 1201956096 97341184 4681664
09:44:43 26484 8192000000 10118045840 10554933248 1133600192 12.155 100 42 84 0 1191057808 48814784 8642832
09:46:17 109620 8192000000 10089826704 10595663872 1116636096 11.995 99 22 44 0 1203921392 82068624 5216672
09:49:15 55092 8192000000 10046818400 10507956224 1091827696 11.760 97 10 20 1 1133592576 39879456 1885424
09:51:19 64957 8192000000 10067696176 10525462528 1092106224 11.763 97 19 37 1 1127847168 31238752 4502192
09:53:33 56192 8192000000 10092467232 10529771520 1097804304 11.817 97 22 44 2 1151802368 49260976 4737088
09:55:45 67390 8192000000 10165413232 10617327616 1108232752 11.916 97 22 44 0 1222280704 109037168 5010784
09:57:55 74573 8192000000 10098072080 10621689856 1137940896 12.196 100 9 18 0 1271897248 131835728 2120624
10:00:10 44441 8192000000 10066339520 10549035008 1121040544 12.037 99 16 32 1 1162963344 38969264 2953536
10:01:57 95778 8192000000 10076794608 10517454848 1127747344 12.100 99 21 42 0 1168860624 36688400 4424880
10:04:41 64646 8192000000 10072589184 10530869248 1126393232 12.087 99 16 32 0 1173756944 43650896 3712816
10:06:46 44197 8192000000 10062106032 10533408768 1096824032 11.807 97 8 16 0 1158503472 59731232 1948208
10:08:30 76349 8192000000 10069071216 10543140864 1077467840 11.623 95 19 38 0 1156936928 75045456 4423632
10:10:51 57791 8192000000 10103774384 10548391936 1112995696 11.961 98 25 49 1 1183839888 65730768 5113424
10:12:49 29417 8192000000 10051075136 10486198272 1102619248 11.862 98 23 46 1 1122885280 15489136 4776896
10:14:28 50843 8192000000 10089548224 10533621760 1133492752 12.154 100 18 36 0 1167668080 30445344 3729984
10:16:22 56742 8192000000 10121679376 10583715840 1143252576 12.246 100 31 61 2 1214163568 64363520 6547472
10:18:19 28585 8192000000 10100770208 10546348032 1125558480 12.079 100 28 56 2 1194780560 63744544 5477536
10:19:51 145526 8192000000 10102618928 10561036288 1135437904 12.173 100 19 38 0 1189522080 50187728 3896448
10:23:17 49470 8192000000 10075745136 10543386624 1114063200 11.971 98 6 12 0 1169278704 54065136 1150368
10:25:23 87174 8192000000 10076937632 10548838400 1082774800 11.674 96 15 30 3 1130914336 44794688 3344848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-03-2021 10:28:33 to 09-03-2021 10:28:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-03-2021 10:28:48 to 09-03-2021 10:28:50.

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          237643
MON$OLDEST_ACTIVE               238725
MON$OLDEST_SNAPSHOT             238725
MON$NEXT_TRANSACTION            238725
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                       2105600
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             805
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 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.2382 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 ---
:276: 
:277: mscs-test-161.budzdorov.ru    Tue Mar  9 10:27:04 2021
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:279: 
:280: 
:281: mscs-test-161.budzdorov.ru    Tue Mar  9 10:27:06 2021
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:283: 
:284: 
:285: mscs-test-161.budzdorov.ru    Tue Mar  9 10:27:06 2021
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:287: 
:288: 
:289: mscs-test-161.budzdorov.ru    Tue Mar  9 10:27:06 2021
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:291: 
:292: 
:293: mscs-test-161.budzdorov.ru    Tue Mar  9 10:27:06 2021
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:295: 
:296: 
:297: mscs-test-161.budzdorov.ru    Tue Mar  9 10:27:06 2021
:298:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:299: 
--- end of diff output ---

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

09.03.21 10:28:51. 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-09 10:28:54 No severe PSQL-related problems occured
09.03.21 10:28:55. End of report.