Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 10:29
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16983908 kB
MemAvailable 31544976 kB
Buffers 0 kB
Cached 14721788 kB
SwapCached 3064 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-28 10:26:51
DTS_END                         2020-10-28 10:26:51.7950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/39502
TRN_ID                          218572
ATT_ID                          631
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: 8618.17 21106 1034180
customer order: creation 153.86 422 18463
customer order: refuse 13.47 375 1616
order to supplier: creation 41.56 1281 4987
order to supplier: removal 3.42 130407 410
invoice (draft): creation 41.18 3624 4942
invoice (draft): removal 50.66 1031 6079
invoice accept: apply 37.38 19942 4485
invoice accept: cancel 45.86 884 5503
customer reserve: creation 3480.95 194 417714
customer reserve: removal 4486.98 79 538438
realization accept: apply 73.72 180 8847
realization accept: cancel 73.71 121 8845
payment from customer: creation 53.33 119 6399
payment from customer: removal 5.06 94 607
payment to supplier: creation 44.56 149 5347
payment to supplier: removal 3.22 122 386
service: total inventory turnovers 3.17 4267 380
service: total monetary turnovers 5.33 529 640
service: aggregate perf. data 0.73 14526 88
service: refresh index statistics 0.03 243779 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 10:29:41 to 28-10-2020 10:29:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3967
TEST_TIME 2 3962
TEST_TIME 3 3948
TEST_TIME 4 3958
TEST_TIME 5 4025
TEST_TIME 6 4523
TEST_TIME 7 4800
TEST_TIME 8 5169
TEST_TIME 9 5101
TEST_TIME 10 5254
TEST_TIME 11 5091
TEST_TIME 12 5218
TEST_TIME 13 5512
TEST_TIME 14 5467
TEST_TIME 15 5613
TEST_TIME 16 5735
TEST_TIME 17 6323
TEST_TIME 18 6522
TEST_TIME 19 6760
TEST_TIME 20 7342
TEST_TIME 21 7222
TEST_TIME 22 7227
TEST_TIME 23 7491
TEST_TIME 24 7676
TEST_TIME 25 8005
TEST_TIME 26 8238
TEST_TIME 27 9115
TEST_TIME 28 9801
TEST_TIME 29 10479
TEST_TIME 30 10346
TEST_TIME 31 10288
TEST_TIME 32 10095
TEST_TIME 33 9977
TEST_TIME 34 9931
TEST_TIME 35 9762
TEST_TIME 36 9761
TEST_TIME 37 9613
TEST_TIME 38 9540
TEST_TIME 39 9544
TEST_TIME 40 9312
TEST_TIME 41 9267
TEST_TIME 42 9121
TEST_TIME 43 9120
TEST_TIME 44 9076
TEST_TIME 45 9102
TEST_TIME 46 9012
TEST_TIME 47 8907
TEST_TIME 48 8777
TEST_TIME 49 8798
TEST_TIME 50 8876
TEST_TIME 51 8809
TEST_TIME 52 8770
TEST_TIME 53 8944
TEST_TIME 54 8896
TEST_TIME 55 8896
TEST_TIME 56 8933
TEST_TIME 57 8915
TEST_TIME 58 8845
TEST_TIME 59 8984
TEST_TIME 60 8998
TEST_TIME 61 8990
TEST_TIME 62 9027
TEST_TIME 63 8902
TEST_TIME 64 8871
TEST_TIME 65 8800
TEST_TIME 66 8698
TEST_TIME 67 8607
TEST_TIME 68 8639
TEST_TIME 69 8602
TEST_TIME 70 8666
TEST_TIME 71 8625
TEST_TIME 72 8718
TEST_TIME 73 8666
TEST_TIME 74 8643
TEST_TIME 75 8847
TEST_TIME 76 8787
TEST_TIME 77 8908
TEST_TIME 78 8881
TEST_TIME 79 8969
TEST_TIME 80 8971
TEST_TIME 81 8934
TEST_TIME 82 8842
TEST_TIME 83 8800
TEST_TIME 84 8755
TEST_TIME 85 8692
TEST_TIME 86 8652
TEST_TIME 87 8604
TEST_TIME 88 8608
TEST_TIME 89 8680
TEST_TIME 90 8652
TEST_TIME 91 8635
TEST_TIME 92 8637
TEST_TIME 93 8659
TEST_TIME 94 8663
TEST_TIME 95 8694
TEST_TIME 96 8736
TEST_TIME 97 8752
TEST_TIME 98 8736
TEST_TIME 99 8817
TEST_TIME 100 8783
TEST_TIME 101 8779
TEST_TIME 102 8709
TEST_TIME 103 8729
TEST_TIME 104 8664
TEST_TIME 105 8666
TEST_TIME 106 8634
TEST_TIME 107 8650
TEST_TIME 108 8619
TEST_TIME 109 8605
TEST_TIME 110 8603
TEST_TIME 111 8627
TEST_TIME 112 8590
TEST_TIME 113 8568
TEST_TIME 114 8666
TEST_TIME 115 8693
TEST_TIME 116 8766
TEST_TIME 117 8767
TEST_TIME 118 8710
TEST_TIME 119 8648
TEST_TIME 120 8621

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-10-2020 10:29:44 to 28-10-2020 10:29:48.

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 1420791 1420791 0 0.00 0 16741 7 0 0
doc_list_biud 1420790 1420790 0 0.00 0 77383 37 0 0
sp_add_invoice_to_stock 4644 4485 159 3.42 27 185879 19943 0 159
sp_cancel_adding_invoice 5503 5503 0 0.00 5 72542 885 0 0
sp_cancel_client_order 1616 1616 0 0.00 4 11783 376 0 0
sp_cancel_customer_reserve 538438 538438 0 0.00 1 77384 79 0 0
sp_cancel_pay_from_customer 607 607 0 0.00 3 7670 95 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 3 8165 123 0 0
sp_cancel_supplier_invoice 6092 6079 13 0.21 3 39292 1031 0 13
sp_cancel_supplier_order 410 410 0 0.00 7 1319279 130408 0 0
sp_cancel_write_off 8845 8845 0 0.00 1 9328 121 0 0
sp_client_order 18463 18463 0 0.00 4 23589 423 0 0
sp_customer_reserve 417714 417714 0 0.00 0 38525 194 0 0
sp_fill_shopping_cart 28389 28389 0 0.00 1 16468 47 0 0
sp_fill_shopping_cart_clo_res 417707 417707 0 0.00 0 32363 22 0 0
sp_get_clo_for_invoice 4476 4476 0 0.00 0 69031 468 0 0
sp_kill_cost_storno 15341 15341 0 0.00 0 14883 59 0 0
sp_kill_qstorno_ret_qs2qd 1092210 1092210 0 0.00 0 77376 43 0 0
sp_kill_qty_storno 574212 574212 0 0.00 0 77382 91 0 0
sp_lock_selected_doc 18571 18571 0 0.00 0 13291 8 0 0
sp_make_cost_storno 25067 25067 0 0.00 0 15141 56 0 0
sp_multiply_rows_for_pdistr 25067 25067 0 0.00 0 8344 32 0 0
sp_multiply_rows_for_qdistr 18463 18463 0 0.00 0 23559 261 0 0
sp_pay_from_customer 6399 6399 0 0.00 4 15941 119 0 0
sp_pay_to_supplier 5347 5347 0 0.00 5 16745 150 0 0
sp_payment_common 11746 11746 0 0.00 1 15933 30 0 0
sp_qd_handle_on_cancel_clo 1616 1616 0 0.00 0 11773 350 0 0
sp_qd_handle_on_invoice_upd_sts 9977 9977 0 0.00 0 27183 407 0 0
sp_qd_handle_on_reserve_upd_sts 8847 8847 0 0.00 0 7780 50 0 0
sp_reserve_write_off 8847 8847 0 0.00 6 14713 181 0 0
sp_supplier_invoice 4942 4942 0 0.00 7 100107 3625 0 0
sp_supplier_order 4987 4987 0 0.00 5 72784 1281 0 0
srv_aggregate_perf_data 89 88 1 1.12 2493 161797 14526 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1631 167084 49557 0 0
srv_find_qd_qs_mism 991133 991133 0 0.00 0 15815 6 0 0
srv_make_invnt_saldo 380 380 0 0.00 58 151446 4268 0 0
srv_make_money_saldo 640 640 0 0.00 1 18559 529 0 0
srv_recalc_idx_stat 4 4 0 0.00 107025 378108 243779 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4485 4485 0 0.00 0 15851 191 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 0 5625 77 0 0
t$perf-norm:sp_cancel_client_order 1616 1616 0 0.00 0 3340 14 0 0
t$perf-norm:sp_cancel_customer_reserve 592 592 0 0.00 0 2913 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 607 607 0 0.00 0 3374 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 239 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 470 12 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 56341 1646 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 8120 14 0 0
t$perf-norm:sp_client_order 18463 18463 0 0.00 0 22485 20 0 0
t$perf-norm:sp_customer_reserve 912 912 0 0.00 0 1074 5 0 0
t$perf-norm:sp_pay_from_customer 6399 6399 0 0.00 0 27077 14 0 0
t$perf-norm:sp_pay_to_supplier 5347 5347 0 0.00 0 2204 7 0 0
t$perf-norm:sp_reserve_write_off 8847 8847 0 0.00 0 27205 18 0 0
t$perf-norm:sp_supplier_invoice 4942 4942 0 0.00 0 8034 17 0 0
t$perf-norm:sp_supplier_order 4987 4987 0 0.00 0 5706 19 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 471 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 531 16 0 0
t$perf-norm:srv_make_invnt_saldo 380 380 0 0.00 0 1822 12 0 0
t$perf-norm:srv_make_money_saldo 640 640 0 0.00 0 1762 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9264 8946 318 3.43 0 8559 16 0 318
v_all_customers 33838 33838 0 0.00 0 17 1 0 0
v_all_suppliers 27844 27844 0 0.00 0 13 1 0 0
v_all_wares 36924 36924 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 419 6 0 0
v_cancel_client_order 3232 3232 0 0.00 0 662 3 0 0
v_cancel_customer_prepayment 1214 1214 0 0.00 0 7622 10 0 0
v_cancel_customer_reserve 1184 1184 0 0.00 0 968 4 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 4729 15 0 0
v_cancel_supplier_invoice 752 726 26 3.45 0 336 10 0 26
v_cancel_supplier_order 820 820 0 0.00 0 2618 9 0 0
v_cancel_write_off 1826 1826 0 0.00 0 4366 13 0 0
v_max_id_clo_ord 4986 4986 0 0.00 0 366 1 0 0
v_max_id_clo_res 912 912 0 0.00 0 6956 18 0 0
v_max_id_ord_sup 4941 4941 0 0.00 0 1218 3 0 0
v_max_non_paid_invoice 1352 1352 0 0.00 0 5554 7 0 0
v_max_non_paid_realizn 3345 3345 0 0.00 0 547 3 0 0
v_min_id_clo_ord 4986 4986 0 0.00 0 2430 5 0 0
v_min_id_clo_res 912 912 0 0.00 0 2319 12 0 0
v_min_id_ord_sup 4941 4941 0 0.00 0 6013 9 0 0
v_min_non_paid_invoice 5347 5347 0 0.00 0 12612 27 0 0
v_min_non_paid_realizn 6399 6399 0 0.00 0 9855 16 0 0
v_our_firm 9194 9194 0 0.00 0 90 1 0 0
v_reserve_write_off 17694 17694 0 0.00 0 3594 4 0 0
x_lock_depdocs_on_canc_invoice 11219 11219 0 0.00 0 20200 113 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 3461 354 0 0
x_make_qstorno_1000_1200 4987 4987 0 0.00 1 72081 1206 0 0
x_make_qstorno_1200_2000 4942 4942 0 0.00 3 100042 3433 0 0
x_make_qstorno_2100_3300 417714 417714 0 0.00 0 35477 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 10:29:49 to 28-10-2020 10:29:53.

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:56:55 2 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
07:57:57 16325 8192000000 9716166944 10011295744 795480704 8.850 100 87 174 0 824695600 7860368 21354528
07:59:19 17132 8192000000 9762604992 10069520384 835993840 9.260 100 90 180 0 872976352 15571376 21411136
08:00:43 16409 8192000000 9787367600 10104778752 861544672 9.516 100 83 166 0 899060960 18171040 19345248
08:02:02 15461 8192000000 9859424208 10187206656 909771504 9.995 100 87 174 0 973487776 44626672 19089600
08:03:22 15107 8192000000 9896132224 10236203008 944224576 10.334 100 80 160 1 1013340784 51608256 17507952
08:04:43 5916 8192000000 9951085088 10301730816 979768928 10.682 100 80 159 0 1061299248 65032224 16498096
08:05:53 4669 8192000000 9977554096 10336264192 1014638752 11.020 100 78 157 0 1090354848 59912208 15803888
08:06:58 3972 8192000000 10014492576 10376941568 1033429888 11.201 100 91 182 0 1133785584 81105120 19250576
08:08:04 1584 8192000000 10015958176 10382987264 1051207312 11.372 100 85 171 1 1127415936 58621520 17587104
08:09:07 30112 8192000000 9993141584 10370244608 1066534320 11.519 100 53 106 1 1120424432 42524016 11366096
08:10:37 5686 8192000000 10001480256 10388803584 1074289232 11.593 100 46 93 0 1115482176 31419488 9773456
08:11:44 10327 8192000000 9996564000 10406617088 1081099760 11.658 100 26 52 1 1110827936 23852896 5875280
08:12:59 69186 8192000000 9993819184 10406563840 1089009936 11.733 100 33 65 1 1125442656 29404096 7028624
08:15:11 6912 8192000000 10007039104 10407514112 1097530544 11.814 100 34 66 0 1121434736 16508544 7395648
08:16:22 8441 8192000000 10023903024 10429878272 1095937104 11.799 100 29 58 0 1122643328 21042576 5663648
08:17:32 11134 8192000000 9999990176 10409144320 1062912992 11.484 97 28 57 0 1099141136 29694176 6533968
08:18:44 23049 8192000000 9988034032 10393870336 1029944608 11.168 95 20 40 4 1064060272 30335808 3779856
08:20:08 5217 8192000000 9982727296 10379550720 1054840208 11.407 100 31 63 1 1084730288 23315488 6574592
08:21:16 7693 8192000000 9941006048 10335330304 996675872 10.846 97 22 42 3 1025832576 24553440 4603264
08:22:29 62729 8192000000 9911178944 10306822144 964254640 10.531 94 22 44 1 981386144 12215440 4916064
08:24:34 9084 8192000000 9953235840 10343415808 1039371424 11.259 100 32 63 4 1063025024 17234144 6419456
08:25:45 7816 8192000000 9969470560 10364837888 1049445616 11.355 100 32 64 1 1076296000 19840864 7009520
08:26:59 17150 8192000000 10014714560 10404061184 1081548832 11.662 100 50 101 0 1118393872 26273728 10571312
08:28:25 82452 8192000000 10048870240 10456629248 1097333840 11.812 100 37 72 1 1158121728 52586544 8201344
08:30:55 14351 8192000000 10073527040 10477318144 1115519088 11.985 100 43 86 1 1192333120 67916544 8897488
08:32:15 16575 8192000000 10116441712 10524430336 1127034928 12.093 100 57 113 2 1236074192 96359296 12679968
08:33:42 2257 8192000000 10168523456 10564104192 1146507392 12.277 100 78 156 1 1277297168 113994576 16795200
08:34:46 8975 8192000000 10181227856 10579509248 1154313856 12.350 100 68 134 3 1283883264 115112784 14456624
08:36:12 7307 8192000000 10227709616 10631192576 1168377312 12.482 100 69 137 1 1334227232 150696240 15153680
08:37:25 7849 8192000000 10276521872 10683310080 1177594704 12.568 100 66 130 1 1379257648 187218464 14444480
08:38:42 4728 8192000000 10359131600 10765283328 1192331872 12.705 100 63 126 2 1455425888 248421408 14672608
08:39:51 5102 8192000000 10377293728 10797182976 1205410736 12.827 100 60 117 0 1486486832 267453696 13622400
08:41:00 3521 8192000000 10448485888 10853482496 1192048496 12.702 99 89 176 1 1545831248 333308848 20473904
08:42:06 5379 8192000000 10486348896 10894659584 1168296944 12.481 99 77 151 0 1582903920 396731760 17875216
08:43:19 7080 8192000000 10434344592 10875142144 1161013984 12.413 99 61 122 0 1545391280 369136672 15240624
08:44:35 1630 8192000000 10464822768 10872344576 1176430656 12.557 100 76 152 0 1564197840 370179152 17588032
08:45:53 2884 8192000000 10494138592 10899619840 1175560880 12.549 100 79 157 1 1591157808 396855168 18741760
08:46:57 11978 8192000000 10434768576 10849271808 1169298192 12.490 100 54 108 3 1530086288 348249008 12539088
08:48:19 10554 8192000000 10499017728 10938679296 1176014656 12.553 100 43 86 0 1589921808 402656240 11250912
08:49:33 8982 8192000000 10498175376 10926026752 1167866608 12.477 100 46 91 1 1596408960 417396416 11145936
08:50:49 9884 8192000000 10520957328 10945388544 1165716784 12.457 100 42 84 1 1611035344 434229520 11089040
08:52:02 21391 8192000000 10483369744 10947166208 1156665344 12.372 100 34 68 3 1582753248 417007104 9080800
08:53:24 39908 8192000000 10277102560 10788782080 1084740592 11.693 97 19 38 1 1438674384 348095632 5838160
08:55:13 22761 8192000000 10078513040 10514857984 1101344896 11.850 99 19 38 0 1172996000 67565296 4085808
08:56:36 75049 8192000000 10031121792 10471489536 1089267744 11.736 98 11 22 0 1155537760 63995792 2274224
08:58:52 85329 8192000000 10072082352 10518421504 1097044160 11.810 97 20 40 1 1156181632 55298800 3838672
09:01:20 59996 8192000000 10094236080 10534457344 1096155600 11.801 98 14 28 0 1176874816 77675232 3043984
09:03:42 55073 8192000000 10088319568 10539503616 1104948208 11.885 98 17 34 1 1160526736 51816912 3761616
09:05:42 127397 8192000000 10016539104 10467921920 1079762256 11.645 97 19 39 1 1135260096 50992496 4505344
09:08:54 19532 8192000000 10063397984 10489479168 1113423072 11.965 99 13 26 1 1163316656 47418624 2474960
09:10:15 43957 8192000000 10065299120 10495307776 1119944016 12.026 100 36 73 0 1156234288 28800432 7489840
09:12:26 59850 8192000000 10068266176 10510774272 1119096272 12.018 99 17 34 1 1157546128 34447120 4002736
09:14:29 97563 8192000000 10119657296 10615644160 1136374544 12.181 100 18 36 0 1232236928 91778528 4083856
09:17:07 72785 8192000000 10103729888 10534133760 1131706128 12.137 100 18 34 1 1178879152 43695248 3477776
09:19:21 59189 8192000000 10074880848 10528686080 1142285344 12.237 100 18 36 0 1212424048 66422928 3715776
09:21:28 21467 8192000000 10084400528 10507444224 1142096432 12.235 100 32 61 3 1179057872 31237376 5724064
09:22:50 48763 8192000000 10078700192 10529492992 1125438880 12.078 98 16 32 0 1172003280 43430960 3133440
09:24:47 25613 8192000000 10189064464 10630062080 1155199712 12.358 100 54 108 1 1263359184 96977264 11182208
09:26:23 45043 8192000000 10105483024 10526576640 1128150944 12.104 98 30 60 1 1177035536 43381072 5503520
09:28:13 100204 8192000000 10083762768 10516008960 1143373408 12.247 100 16 32 0 1189238080 42967552 2897120
09:31:03 71722 8192000000 10076416880 10521903104 1117151184 12.000 97 16 32 0 1157857456 37452704 3253568
09:33:15 64258 8192000000 10143356640 10590826496 1131848464 12.139 99 10 20 1 1221432144 87568240 2015440
09:35:53 65865 8192000000 10095748256 10523131904 1092917600 11.770 95 23 46 1 1157755584 60026240 4811744
09:38:04 96905 8192000000 10126709872 10586611712 1080863504 11.656 95 13 26 1 1158485920 74564672 3057744
09:40:50 85132 8192000000 10116992432 10611994624 1134737312 12.166 99 23 46 0 1240827312 100904752 5185248
09:43:44 100387 8192000000 10122752448 10597474304 1081749568 11.664 96 13 26 0 1159102928 74920448 2432912
09:46:26 167084 8192000000 10032218080 10483347456 1067761360 11.531 95 21 42 1 1102995456 31124624 4109472
09:50:16 41314 8192000000 10026008160 10475208704 1078737264 11.635 97 9 18 0 1106006640 24837808 2431568
09:52:01 70618 8192000000 10066512864 10534748160 1087606640 11.720 98 12 24 1 1140535744 50560000 2369104
09:54:30 93213 8192000000 10039158672 10557259776 1070919424 11.561 96 10 20 0 1148354032 75026192 2408416
09:57:04 76389 8192000000 10114487344 10535886848 1109318496 11.926 98 19 38 2 1170633088 57312336 4002256
09:59:25 77807 8192000000 10085572800 10511069184 1073418448 11.585 95 12 24 0 1154541904 78684400 2439056
10:01:47 43930 8192000000 10089217168 10559623168 1102643600 11.863 98 42 84 2 1162290080 51463152 8183328
10:03:40 17295 8192000000 10073710560 10511278080 1108090352 11.914 98 39 77 1 1169874064 53817456 7966256
10:05:03 40922 8192000000 10083931392 10597470208 1122064352 12.046 99 22 44 0 1220170592 93635968 4470272
10:06:44 19919 8192000000 10099042784 10514903040 1125100976 12.075 99 41 80 0 1177864800 44864416 7899408
10:08:06 137533 8192000000 10087330656 10573541376 1139366768 12.210 100 21 41 2 1219510368 75925424 4218176
10:13:39 43035 8192000000 10070936608 10499858432 1116579264 11.995 98 22 44 2 1150509808 29315104 4615440
10:15:25 46580 8192000000 10125808288 10581311488 1129907488 12.120 99 19 38 2 1222408720 88111088 4390144
10:17:13 80730 8192000000 10148627920 10592411648 1131671648 12.137 99 23 46 0 1216827936 79948272 5208016
10:19:48 71595 8192000000 10158109712 10658648064 1143074480 12.244 99 21 42 0 1261993504 114066576 4852448
10:22:03 88900 8192000000 10121142384 10626203648 1125143376 12.076 98 6 12 1 1171043344 44680208 1219760
10:25:08 112273 8192000000 9472409392 9842372608 583649472 6.650 51 11 22 1 642686208 56723328 2313408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2020 10:29:54 to 28-10-2020 10:30:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 159 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2020 10:30:10 to 28-10-2020 10:30:12.

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          216870
MON$OLDEST_ACTIVE               219226
MON$OLDEST_SNAPSHOT             219226
MON$NEXT_TRANSACTION            219226
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                       2091648
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             748
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2240 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.2240 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Wed Oct 28 10:27:51 2020
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
--- end of diff output ---

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

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