Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.08.2021 13:38
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 16335424 kB
MemAvailable 31082024 kB
Buffers 0 kB
Cached 15361644 kB
SwapCached 2572 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-04 13:37:05
DTS_END                         2021-08-04 13:37:05.7270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58610
TRN_ID                          217457
ATT_ID                          612
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: 8493.27 28563 1019192
customer order: creation 152.26 456 18271
customer order: refuse 13.58 376 1629
order to supplier: creation 40.74 1526 4889
order to supplier: removal 3.13 138264 376
invoice (draft): creation 41.52 4186 4982
invoice (draft): removal 49.08 1056 5889
invoice accept: apply 37.42 21302 4490
invoice accept: cancel 44.23 977 5307
customer reserve: creation 3506.23 206 420748
customer reserve: removal 4338.28 78 520594
realization accept: apply 73.09 200 8771
realization accept: cancel 78.30 127 9396
payment from customer: creation 53.45 125 6414
payment from customer: removal 4.73 90 568
payment to supplier: creation 44.40 141 5328
payment to supplier: removal 2.99 81 359
service: total inventory turnovers 3.59 4253 431
service: total monetary turnovers 5.54 597 665
service: aggregate perf. data 0.68 23432 81
service: refresh index statistics 0.03 373782 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-08-2021 13:38:27 to 04-08-2021 13:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6561
TEST_TIME 2 6320
TEST_TIME 3 6505
TEST_TIME 4 5878
TEST_TIME 5 5227
TEST_TIME 6 5625
TEST_TIME 7 5874
TEST_TIME 8 6088
TEST_TIME 9 6089
TEST_TIME 10 6159
TEST_TIME 11 6538
TEST_TIME 12 6637
TEST_TIME 13 6944
TEST_TIME 14 6854
TEST_TIME 15 6897
TEST_TIME 16 7001
TEST_TIME 17 7102
TEST_TIME 18 7406
TEST_TIME 19 7533
TEST_TIME 20 7809
TEST_TIME 21 7915
TEST_TIME 22 8101
TEST_TIME 23 8399
TEST_TIME 24 8226
TEST_TIME 25 8739
TEST_TIME 26 8995
TEST_TIME 27 9276
TEST_TIME 28 9510
TEST_TIME 29 10720
TEST_TIME 30 10757
TEST_TIME 31 10585
TEST_TIME 32 10433
TEST_TIME 33 10253
TEST_TIME 34 10119
TEST_TIME 35 9965
TEST_TIME 36 9782
TEST_TIME 37 9712
TEST_TIME 38 9549
TEST_TIME 39 9401
TEST_TIME 40 9377
TEST_TIME 41 9319
TEST_TIME 42 9398
TEST_TIME 43 9495
TEST_TIME 44 9435
TEST_TIME 45 9323
TEST_TIME 46 9298
TEST_TIME 47 9201
TEST_TIME 48 9217
TEST_TIME 49 9125
TEST_TIME 50 9067
TEST_TIME 51 8926
TEST_TIME 52 8867
TEST_TIME 53 8751
TEST_TIME 54 8781
TEST_TIME 55 8699
TEST_TIME 56 8641
TEST_TIME 57 8721
TEST_TIME 58 8790
TEST_TIME 59 8799
TEST_TIME 60 8816
TEST_TIME 61 8774
TEST_TIME 62 8846
TEST_TIME 63 8952
TEST_TIME 64 8980
TEST_TIME 65 8986
TEST_TIME 66 8931
TEST_TIME 67 8978
TEST_TIME 68 8991
TEST_TIME 69 8986
TEST_TIME 70 9139
TEST_TIME 71 9106
TEST_TIME 72 8996
TEST_TIME 73 8935
TEST_TIME 74 8924
TEST_TIME 75 8876
TEST_TIME 76 8818
TEST_TIME 77 8771
TEST_TIME 78 8726
TEST_TIME 79 8736
TEST_TIME 80 8728
TEST_TIME 81 8781
TEST_TIME 82 8770
TEST_TIME 83 8729
TEST_TIME 84 8722
TEST_TIME 85 8710
TEST_TIME 86 8878
TEST_TIME 87 8871
TEST_TIME 88 8843
TEST_TIME 89 8818
TEST_TIME 90 8880
TEST_TIME 91 8978
TEST_TIME 92 8960
TEST_TIME 93 8875
TEST_TIME 94 8819
TEST_TIME 95 8794
TEST_TIME 96 8752
TEST_TIME 97 8743
TEST_TIME 98 8711
TEST_TIME 99 8736
TEST_TIME 100 8711
TEST_TIME 101 8738
TEST_TIME 102 8703
TEST_TIME 103 8775
TEST_TIME 104 8757
TEST_TIME 105 8757
TEST_TIME 106 8787
TEST_TIME 107 8806
TEST_TIME 108 8767
TEST_TIME 109 8825
TEST_TIME 110 8774
TEST_TIME 111 8711
TEST_TIME 112 8671
TEST_TIME 113 8680
TEST_TIME 114 8649
TEST_TIME 115 8623
TEST_TIME 116 8604
TEST_TIME 117 8584
TEST_TIME 118 8577
TEST_TIME 119 8564
TEST_TIME 120 8499

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-08-2021 13:38:30 to 04-08-2021 13:38:34.

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 1407427 1407427 0 0.00 0 23889 7 0 0
doc_list_biud 1407427 1407427 0 0.00 0 39600 36 0 0
sp_add_invoice_to_stock 4573 4490 83 1.81 30 207587 21302 0 83
sp_cancel_adding_invoice 5307 5307 0 0.00 5 96857 978 0 0
sp_cancel_client_order 1629 1629 0 0.00 4 8136 377 0 0
sp_cancel_customer_reserve 520594 520594 0 0.00 1 39605 79 0 0
sp_cancel_pay_from_customer 568 568 0 0.00 3 6672 90 0 0
sp_cancel_pay_to_supplier 359 359 0 0.00 3 2900 81 0 0
sp_cancel_supplier_invoice 5897 5889 8 0.13 2 35453 1056 0 8
sp_cancel_supplier_order 376 376 0 0.00 8 1162840 138265 0 0
sp_cancel_write_off 9396 9396 0 0.00 1 9665 128 0 0
sp_client_order 18271 18271 0 0.00 4 23613 457 0 0
sp_customer_reserve 420748 420748 0 0.00 0 85869 207 0 0
sp_fill_shopping_cart 28125 28125 0 0.00 1 17859 54 0 0
sp_fill_shopping_cart_clo_res 420735 420735 0 0.00 0 23220 17 0 0
sp_get_clo_for_invoice 4477 4477 0 0.00 0 18671 465 0 0
sp_kill_cost_storno 15630 15630 0 0.00 0 23880 73 0 0
sp_kill_qstorno_ret_qs2qd 1056849 1056849 0 0.00 0 36404 42 0 0
sp_kill_qty_storno 556439 556439 0 0.00 0 39599 91 0 0
sp_lock_selected_doc 18453 18453 0 0.00 0 6449 4 0 0
sp_make_cost_storno 24990 24990 0 0.00 0 21050 59 0 0
sp_multiply_rows_for_pdistr 24990 24990 0 0.00 0 9730 36 0 0
sp_multiply_rows_for_qdistr 18271 18271 0 0.00 0 23443 236 0 0
sp_pay_from_customer 6414 6414 0 0.00 4 13842 125 0 0
sp_pay_to_supplier 5328 5328 0 0.00 5 21055 141 0 0
sp_payment_common 11742 11742 0 0.00 1 13401 25 0 0
sp_qd_handle_on_cancel_clo 1629 1629 0 0.00 0 8103 338 0 0
sp_qd_handle_on_invoice_upd_sts 9784 9784 0 0.00 0 23946 422 0 0
sp_qd_handle_on_reserve_upd_sts 8771 8771 0 0.00 0 9952 61 0 0
sp_reserve_write_off 8771 8771 0 0.00 6 20154 201 0 0
sp_supplier_invoice 4982 4982 0 0.00 6 88159 4187 0 0
sp_supplier_order 4889 4889 0 0.00 6 46566 1526 0 0
srv_aggregate_perf_data 81 81 0 0.00 2751 207418 23432 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1286 144539 51606 0 0
srv_find_qd_qs_mism 975605 975605 0 0.00 0 16241 5 0 0
srv_make_invnt_saldo 431 431 0 0.00 58 105744 4254 0 0
srv_make_money_saldo 665 665 0 0.00 1 19004 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 165882 558449 373782 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4490 4490 0 0.00 0 16938 229 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 3832 111 0 0
t$perf-norm:sp_cancel_client_order 1629 1629 0 0.00 0 2947 15 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 1320 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 568 568 0 0.00 0 10523 33 0 0
t$perf-norm:sp_cancel_pay_to_supplier 359 359 0 0.00 0 685 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1067 13 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 29881 1858 0 0
t$perf-norm:sp_cancel_write_off 906 906 0 0.00 0 19198 27 0 0
t$perf-norm:sp_client_order 18271 18271 0 0.00 0 11260 25 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 1991 4 0 0
t$perf-norm:sp_pay_from_customer 6414 6414 0 0.00 0 14856 14 0 0
t$perf-norm:sp_pay_to_supplier 5328 5328 0 0.00 0 6203 11 0 0
t$perf-norm:sp_reserve_write_off 8771 8771 0 0.00 0 12903 18 0 0
t$perf-norm:sp_supplier_invoice 4982 4982 0 0.00 0 11973 25 0 0
t$perf-norm:sp_supplier_order 4889 4889 0 0.00 0 10932 23 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 7025 93 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 295 6 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 4538 29 0 0
t$perf-norm:srv_make_money_saldo 665 665 0 0.00 0 2128 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9118 8952 166 1.82 0 7116 19 0 166
v_all_customers 33484 33484 0 0.00 0 18 1 0 0
v_all_suppliers 27738 27738 0 0.00 0 19 1 0 0
v_all_wares 36542 36542 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 14750 36 0 0
v_cancel_client_order 3258 3258 0 0.00 0 6065 11 0 0
v_cancel_customer_prepayment 1136 1136 0 0.00 0 1430 5 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 10241 12 0 0
v_cancel_payment_to_supplier 718 718 0 0.00 0 1006 5 0 0
v_cancel_supplier_invoice 760 744 16 2.10 0 3419 19 0 16
v_cancel_supplier_order 752 752 0 0.00 0 3999 17 0 0
v_cancel_write_off 1812 1812 0 0.00 0 1788 14 0 0
v_max_id_clo_ord 4889 4889 0 0.00 0 639 1 0 0
v_max_id_clo_res 966 966 0 0.00 0 10359 23 0 0
v_max_id_ord_sup 4965 4965 0 0.00 0 4617 6 0 0
v_max_non_paid_invoice 1313 1313 0 0.00 0 1133 3 0 0
v_max_non_paid_realizn 3321 3321 0 0.00 0 3788 4 0 0
v_min_id_clo_ord 4889 4889 0 0.00 0 1821 6 0 0
v_min_id_clo_res 966 966 0 0.00 0 7519 24 0 0
v_min_id_ord_sup 4965 4965 0 0.00 0 7619 8 0 0
v_min_non_paid_invoice 5328 5328 0 0.00 0 4331 13 0 0
v_min_non_paid_realizn 6414 6414 0 0.00 0 6501 20 0 0
v_our_firm 9244 9244 0 0.00 0 14 1 0 0
v_reserve_write_off 17542 17542 0 0.00 0 7904 6 0 0
x_lock_depdocs_on_canc_invoice 10824 10824 0 0.00 0 17866 132 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 2882 385 0 0
x_make_qstorno_1000_1200 4889 4889 0 0.00 2 46557 1435 0 0
x_make_qstorno_1200_2000 4982 4982 0 0.00 4 88100 3960 0 0
x_make_qstorno_2100_3300 420748 420748 0 0.00 0 85866 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-08-2021 13:38:35 to 04-08-2021 13:38:39.

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
11:07:09 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:10 34068 8192000000 9741917120 10041733120 818069040 9.079 100 85 171 0 845945744 8236736 19639968
11:09:50 23511 8192000000 9782917360 10093780992 856505904 9.465 100 89 178 0 890864208 13679584 20678720
11:11:20 18972 8192000000 9814754736 10138550272 879010448 9.690 100 82 164 0 921630576 24030160 18589968
11:12:45 14370 8192000000 9886006272 10219409408 932124032 10.216 100 81 162 1 994979840 44468768 18387040
11:14:06 11986 8192000000 9923296896 10269290496 960964896 10.498 100 66 132 0 1036082784 61745392 13372496
11:15:24 6089 8192000000 9963671632 10316521472 999882048 10.877 100 85 170 0 1082240688 64821408 17537232
11:16:32 4127 8192000000 10000300656 10360459264 1019485456 11.067 100 89 177 2 1112715088 75563248 17666384
11:17:39 1306 8192000000 10018830896 10384146432 1039423504 11.259 100 92 185 0 1139501808 81077504 19000800
11:18:42 1483 8192000000 10040729072 10413318144 1064939536 11.504 100 90 180 1 1152383264 69171808 18271920
11:19:44 2703 8192000000 10014883168 10412007424 1068572032 11.538 100 52 104 0 1135156944 56196048 10388864
11:20:49 9902 8192000000 9998735728 10390429696 1076779056 11.617 100 35 70 3 1130691712 47149264 6763392
11:22:00 49632 8192000000 9986307008 10399272960 1084194000 11.687 100 47 93 1 1110678048 16375872 10108176
11:23:52 10060 8192000000 10001548672 10411507712 1091835024 11.760 100 24 48 1 1117048384 20418416 4794944
11:25:04 8247 8192000000 10014804864 10420498432 1101079504 11.848 100 28 54 1 1128142384 21205776 5857104
11:26:14 8173 8192000000 10027840944 10430476288 1100725504 11.845 100 51 100 3 1139632816 29203136 9704176
11:27:23 52089 8192000000 10021072944 10438213632 1101711520 11.854 100 36 72 1 1132828320 23149216 7967584
11:29:16 6161 8192000000 10001393664 10397827072 1064767408 11.502 100 34 68 0 1128258496 56483152 7007936
11:30:25 4869 8192000000 9969360464 10355605504 1030609792 11.174 99 48 96 2 1069887952 28852496 10425664
11:31:33 10987 8192000000 9918833952 10303266816 954375792 10.434 94 32 64 1 983555152 22061024 7118336
11:32:44 29783 8192000000 9919180384 10307821568 970714704 10.594 95 25 50 1 995278416 18921520 5642192
11:34:17 2749 8192000000 9964281936 10339282944 1036734256 11.233 100 46 91 1 1071843760 24971792 10137712
11:35:21 10085 8192000000 9955435552 10361827328 1045681488 11.319 100 36 72 2 1071677616 17555088 8441040
11:36:33 73831 8192000000 9991706336 10386022400 1067081840 11.524 100 47 91 3 1094798448 17187040 10529568
11:38:55 22105 8192000000 10065815216 10461462528 1097666208 11.815 100 59 117 0 1160243264 50858672 11718384
11:40:22 24035 8192000000 10069697088 10481614848 1112823248 11.959 100 43 83 0 1187918944 66230464 8865232
11:42:01 19023 8192000000 10102232192 10508967936 1123312288 12.058 100 58 116 1 1211339968 74935936 13091744
11:43:22 17628 8192000000 10124411552 10559488000 1130899888 12.130 100 33 66 0 1238856784 100361424 7595472
11:44:56 16744 8192000000 10150959344 10570964992 1146583712 12.277 100 50 100 1 1263681072 105883184 11214176
11:46:22 14245 8192000000 10206696960 10623266816 1154227488 12.349 100 65 128 0 1315874688 148323264 13323936
11:47:43 6081 8192000000 10251913904 10662531072 1159897728 12.402 100 67 134 1 1355844112 181754896 14191488
11:48:54 1285 8192000000 10269908576 10689576960 1171057184 12.507 100 76 152 2 1369846736 181722768 17066784
11:49:57 7398 8192000000 10296822160 10725113856 1175977920 12.553 100 66 132 0 1394249248 202940160 15331168
11:51:10 2446 8192000000 10302965216 10711543808 1168838848 12.486 99 72 144 1 1404602336 219515856 16247632
11:52:17 6080 8192000000 10346100096 10754334720 1166232624 12.462 100 85 169 2 1451318384 266034240 19051520
11:53:29 1821 8192000000 10385493840 10800144384 1172899840 12.524 100 65 130 0 1492180560 304132272 15148448
11:54:42 1894 8192000000 10385114688 10818633728 1156720464 12.373 98 83 164 0 1498664256 322570016 19373776
11:55:50 7029 8192000000 10417703072 10824458240 1162859632 12.430 100 80 160 2 1509053520 327067232 19126656
11:57:02 4661 8192000000 10413776752 10824482816 1166407200 12.463 100 69 139 1 1514304784 331166784 16730800
11:58:15 2522 8192000000 10455013280 10866073600 1152954592 12.337 100 71 141 1 1552929536 382745536 17229408
11:59:32 7896 8192000000 10427560096 10851004416 1152755808 12.335 100 61 122 2 1525738576 357333056 15649712
12:00:46 9565 8192000000 10414643024 10860027904 1143696880 12.250 100 36 72 0 1518563328 365026208 9840240
12:02:23 21530 8192000000 10438966464 10908225536 1154913232 12.356 100 39 77 1 1527177856 362236992 10027632
12:04:10 29553 8192000000 10396465776 10855538688 1118840496 12.016 99 29 58 1 1498784128 371795360 8148272
12:05:56 18575 8192000000 10206699840 10765619200 1113085728 11.962 100 22 44 0 1330146352 211156528 5904096
12:07:26 44054 8192000000 10097689888 10578653184 1111733088 11.949 99 24 48 0 1221732272 104992592 5006592
12:09:12 63582 8192000000 10042989056 10489462784 1090094128 11.744 97 37 75 1 1117863776 20646432 7123216
12:11:19 68274 8192000000 10042804976 10482872320 1090693408 11.749 98 18 36 0 1145074048 50535376 3845264
12:13:28 66963 8192000000 10096250288 10551681024 1106846592 11.903 98 16 32 1 1166918576 56685632 3386352
12:16:22 65150 8192000000 10210385952 10675560448 1149947456 12.309 100 26 52 3 1286449904 129831360 6671088
12:19:00 79363 8192000000 10165240768 10651521024 1126856224 12.092 99 18 36 1 1297631072 165839984 4934864
12:21:52 42403 8192000000 10104534880 10568359936 1132360720 12.144 100 34 69 1 1204595488 63968864 8265904
12:24:03 58719 8192000000 10085201712 10531643392 1113914192 11.969 98 48 96 0 1170173856 45876736 10382928
12:26:22 81442 8192000000 10036378784 10521714688 1080147392 11.649 96 10 20 0 1111280896 29129520 2003984
12:28:47 101416 8192000000 10047351824 10506764288 1098188368 11.820 98 16 32 0 1154215536 52652032 3375136
12:31:31 64858 8192000000 10103869968 10569613312 1137339360 12.190 100 26 52 2 1201165728 58552880 5273488
12:33:37 75653 8192000000 10153501824 10651672576 1126222848 12.086 99 18 36 0 1248311360 117145120 4943392
12:35:56 140108 8192000000 10096610512 10545135616 1117826704 12.006 98 14 29 0 1161262496 40479104 2956688
12:39:17 97301 8192000000 10065822832 10530484224 1096693856 11.806 95 15 31 1 1170694432 70875456 3125120
12:41:56 108844 8192000000 10100450448 10531565568 1091800048 11.760 95 24 48 0 1148359312 51762240 4797024
12:44:48 18413 8192000000 10112863360 10544123904 1140569600 12.221 100 40 81 0 1192693408 44394352 7729456
12:46:09 30329 8192000000 10148929312 10594267136 1141965824 12.234 100 53 106 0 1237994784 85198288 10830672
12:47:44 122462 8192000000 10045573520 10480099328 1079935152 11.647 95 10 20 0 1122738720 40433904 2369664
12:51:09 2823 8192000000 10063740256 10505089024 1117402320 12.002 100 39 78 0 1168969472 43467264 8099888
12:52:12 67506 8192000000 10033722864 10486120448 1072333872 11.574 97 12 24 2 1111201840 36081440 2786528
12:54:36 66527 8192000000 10071785888 10539503616 1093719536 11.778 99 15 30 1 1178122480 80742576 3660368
12:57:09 69674 8192000000 10077113296 10538676224 1107569744 11.909 100 17 34 2 1164545104 53217456 3757904
12:59:19 95586 8192000000 10077066896 10511183872 1071897392 11.570 95 15 30 0 1130277776 55118288 3262096
13:01:56 43216 8192000000 10138622800 10573393920 1101266672 11.850 98 25 50 0 1207063680 100114320 5682688
13:03:40 124624 8192000000 10061898736 10527158272 1095974496 11.799 98 16 32 1 1179922192 80596736 3350960
13:06:45 92594 8192000000 10111871520 10642382848 1126246240 12.086 100 28 56 0 1207468544 75582640 5639664
13:09:20 144535 8192000000 10056437184 10493493248 1111769408 11.949 98 24 48 0 1148542720 31632928 5140384
13:13:33 65067 8192000000 10070412784 10557009920 1130009968 12.121 100 9 18 0 1192441264 60546784 1884512
13:15:38 57893 8192000000 10088544000 10556370944 1139829456 12.214 100 21 42 0 1186725024 42550432 4345136
13:17:37 77884 8192000000 10080255440 10544766976 1114979472 11.980 98 16 32 0 1166270816 47645376 3645968
13:20:09 78294 8192000000 10080970288 10565472256 1116591664 11.995 98 15 30 0 1174568048 54543056 3433328
13:22:28 92051 8192000000 10100715280 10572414976 1107055824 11.905 97 18 35 1 1191340144 80657856 3626464
13:25:00 35631 8192000000 10083217376 10517839872 1115021136 11.980 98 37 74 3 1159613104 37325808 7266160
13:26:37 43876 8192000000 10075446752 10510954496 1111549920 11.947 98 24 48 0 1146081664 29486400 5045344
13:28:34 96790 8192000000 10133812848 10596933632 1136671360 12.184 100 58 116 0 1225541360 76826928 12043072
13:31:29 34662 8192000000 10058948304 10536222720 1120349632 12.030 100 8 16 0 1165652416 43158192 2144592
13:33:25 80943 8192000000 10054064960 10512154624 1092807424 11.769 97 21 42 1 1131366576 34234672 4324480
13:35:49 80813 8192000000 9472303888 9821220864 574680400 6.555 51 7 14 1 614102784 38292896 1129488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-08-2021 13:38:41 to 04-08-2021 13:38:55.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-08-2021 13:38:56 to 04-08-2021 13:38:58.

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          217456
MON$OLDEST_ACTIVE               218822
MON$OLDEST_SNAPSHOT             218822
MON$NEXT_TRANSACTION            218822
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                       2039072
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2556 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2556 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2556 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2556 Firebird 4.0/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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Wed Aug  4 13:37:12 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Wed Aug  4 13:37:13 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Wed Aug  4 13:37:13 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
--- end of diff output ---

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

04.08.21 13:38:59. 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-04 13:39:01 No severe PSQL-related problems occured
04.08.21 13:39:03. End of report.