Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

PLTEST failure on OSX JDK11 #3685

Closed
babsingh opened this issue Nov 14, 2018 · 12 comments
Closed

PLTEST failure on OSX JDK11 #3685

babsingh opened this issue Nov 14, 2018 · 12 comments

Comments

@babsingh
Copy link
Contributor

babsingh commented Nov 14, 2018

Link to OSX JDK11 Test Sanity CI Job where the failure happened. Seen in #3603.

Log snippet:

17:14:05 Testing: PLTEST
17:14:05 Test start time: 2018/11/14 17:14:05 Eastern Standard Time
17:14:05 Running command: /Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/test/openj9//pltest 
17:14:05 Time spent starting: 36 milliseconds
17:16:22 /bin/sh: line 1: 64363 Quit: 3                 { mkdir -p "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15422313122267/cmdLineTester_pltest_0"; cd "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15422313122267/cmdLineTester_pltest_0"; export LD_LIBRARY_PATH=""/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/test/openj9/:/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/jdk/bin/../lib/compressedrefs:/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/jdk/bin/../lib/j9vm":"; "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/jdk/bin/java" -Xcompressedrefs -DRESOURCES_DIR="/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../../jvmtest/TestConfig/resources" -DJVM_TEST_ROOT="/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../../jvmtest" -DEXE='/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/test/openj9//pltest ' -jar "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../../jvmtest/functional/cmdline_options_tester/cmdlinetester.jar" -config "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../../jvmtest/functional/cmdLineTests/pltest/pltest.xml" -verbose -explainExcludes -xids all,osx_x86-64_cr, -plats all,osx_x86-64_cr, -nonZeroExitWhenError; if [ $? -eq 0 ]; then
17:16:22     echo ""; echo "cmdLineTester_pltest_0""_PASSED"; echo ""; cd /Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../..; rm -f -r "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15422313122267/cmdLineTester_pltest_0";
17:16:22 else
17:16:22     echo ""; echo "cmdLineTester_pltest_0""_FAILED"; echo "";
17:16:22 fi; } 2>&1
17:16:22      64364                       | tee -a "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15422313122267/TestTargetResult"
17:16:22 make[4]: *** [cmdLineTester_pltest_0] Error 131
17:16:22 make[3]: *** [sanity-pltest] Error 1
17:16:22 make[2]: *** [sanity-cmdLineTests] Error 1
17:16:22 make[1]: *** [sanity-functional] Error 1
17:16:22 make: *** [sanity-..] Error 1

In the above log snippet, the following test script command seems invalid:

64364                       | tee -a "/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/openj9/test/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15422313122267/TestTargetResult"

The above command is generated by test script mkgen.pl.

After running the pltest executable, the following failures are seen:

FAILURES DETECTED. Number of failed tests: 23

1: j9sysinfo_testMemoryInfo
	si.c line 1037: Invalid memory usage statistics retrieved.

		LastErrorNumber: 0
		LastErrorMessage:

2: j9sysinfo_testProcessorInfo
	si.c line 1249: Invalid processor usage statistics retrieved.

		LastErrorNumber: 0
		LastErrorMessage:

3: j9sysinfo_test_get_cwd3
	si.c line 1884: invalid directory rc: 4

		LastErrorNumber: 0
		LastErrorMessage:

4: j9sysinfo_test_get_l1dcache_line_size
	si.c line 2174: j9sysinfo_get_cache_info returned -100

		LastErrorNumber: -108
		LastErrorMessage: No such file or directory

5: j9sysinfo_test_get_levels_and_types
	si.c line 1994: j9sysinfo_get_cache_info returned -355

		LastErrorNumber: -108
		LastErrorMessage: No such file or directory

6: j9sock_test1_basic
	j9sockTest.c line  196: error creating address: Unknown error: -1

		LastErrorNumber: -252
		LastErrorMessage: Unknown error: -1

7: j9sock_test1_basic
	j9sockTest.c line  392: error starting server: Unknown error: -1

		LastErrorNumber: -252
		LastErrorMessage: Unknown error: -1

8: j9sock_test1_basic
	j9sockTest.c line  244: error creating address: Unknown error: -1

		LastErrorNumber: -252
		LastErrorMessage: Unknown error: -1

9: j9sock_test1_basic
	j9sockTest.c line  396: error creating/connecting client: Unknown error: -1

		LastErrorNumber: -252
		LastErrorMessage: Unknown error: -1

10: j9sock_test1_basic
	j9sockTest.c line  401: error accepting connection: Invalid argument

		LastErrorNumber: -212
		LastErrorMessage: Invalid argument

11: j9sock_test3_fdset_basic_select
	j9sockTest.c line  196: error creating address: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

12: j9sock_test3_fdset_basic_select
	j9sockTest.c line  609: error starting server: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

13: j9sock_test3_fdset_basic_select
	j9sockTest.c line  244: error creating address: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

14: j9sock_test3_fdset_basic_select
	j9sockTest.c line  613: error creating/connecting client: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

15: j9sock_test3_fdset_basic_select
	j9sockTest.c line  618: error accepting connection: Invalid argument

		LastErrorNumber: -212
		LastErrorMessage: Invalid argument

16: j9sock_test5_basic_options
	j9sockTest.c line  930: error creating address: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

17: j9sock_test6_nonblocking_connect
	j9sockTest.c line  196: error creating address: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

18: j9sock_test6_nonblocking_connect
	j9sockTest.c line 1008: error starting server: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

19: j9sock_test7_nonblocking_read
	j9sockTest.c line  196: error creating address: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

20: j9sock_test7_nonblocking_read
	j9sockTest.c line 1073: error starting server: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

21: j9sock_test7_nonblocking_read
	j9sockTest.c line  244: error creating address: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

22: j9sock_test7_nonblocking_read
	j9sockTest.c line 1077: error creating/connecting client: Bad file descriptor

		LastErrorNumber: -200
		LastErrorMessage: Bad file descriptor

23: j9sock_test7_nonblocking_read
	j9sockTest.c line 1081: error accepting connection: Invalid argument

		LastErrorNumber: -212
		LastErrorMessage: Invalid argument

fyi - @llxia @smlambert @gacholio

@smlambert
Copy link
Contributor

smlambert commented Nov 14, 2018

The command that looks 'off' is:
/Users/jenkins/workspace/PullRequest-Sanity-JDK11-osx_x86-64_cmprssptrs-OpenJ9/build/macosx-x86_64-normal-server-release/images/test/openj9//pltest <- // is something not getting defined?

Edit, that location is what matches the xLinux builds.

Where are the native test libs to be found in an OSX build?

@smlambert
Copy link
Contributor

Ignore my comments above, I will try running locally. Has someone tried on their laptop and encountered the same issue?

@babsingh
Copy link
Contributor Author

Not tried locally yet. Before tests would hang at Test: abort stage. So, no one could reach Test: PLTEST stage. Since #3603 is merged now, the above failure can be reproduced locally.

@smlambert
Copy link
Contributor

Notice export LD_LIBRARY_PATH is also used in commandline for this test setup

@babsingh
Copy link
Contributor Author

@babsingh
Copy link
Contributor Author

@llxia @sophia-guo any updates on this issue? this issue prevents OSX Java 8 and Java 11 test sanity jobs to run completely.

@llxia
Copy link
Contributor

llxia commented Nov 20, 2018

I will have a PR for this soon.

babsingh added a commit to babsingh/openj9 that referenced this issue Nov 21, 2018
Running PLTEST on OSX prevents test jobs to run completely. Thus,
disabling PLTEST on OSX.

Issue: eclipse-openj9#3685

Signed-off-by: Babneet Singh <[email protected]>
@llxia
Copy link
Contributor

llxia commented Nov 22, 2018

Tested pltest with DYLD_LIBRARY_PATH on MacOSX. Similar error

00:19:48.242 openjdk version "11.0.1-internal" 2018-10-16
00:19:48.242 OpenJDK Runtime Environment (build 11.0.1-internal+0-adhoc.jenkins.Build-JDK11-osxx86-64cmprssptrs)
00:19:48.242 Eclipse OpenJ9 VM (build master-d8b22d802, JRE 11 Mac OS X amd64-64-Bit Compressed References 20181120_9 (JIT enabled, AOT enabled)
00:19:48.242 OpenJ9   - d8b22d802
00:19:48.242 OMR      - 1b8bfbae
00:19:48.242 JCL      - 2fbed1f42df based on jdk-11.0.1+13)
01:02:13.545 ===============================================
01:02:13.545 Running test cmdLineTester_pltest_0 ...
01:02:13.545 ===============================================
01:02:13.545 cmdLineTester_pltest_0 Start Time: Thu Nov 22 16:35:39 2018 Epoch Time (ms): 1542922539108
01:02:13.545 variation: NoOptions
01:02:13.545 JVM_OPTIONS: -Xcompressedrefs 
01:02:13.545 { mkdir -p "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15429199923919/cmdLineTester_pltest_0"; \
01:02:13.545 	cd "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15429199923919/cmdLineTester_pltest_0"; \
01:02:13.545 	export DYLD_LIBRARY_PATH=""/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../../native-test-libs/:/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../lib/compressedrefs:/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../lib/j9vm":"; \
01:02:13.545 	"/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/java" -Xcompressedrefs  -DRESOURCES_DIR="/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest/TestConfig/resources" -DJVM_TEST_ROOT="/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest" \
01:02:13.545 	-DEXE='/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../../native-test-libs//pltest ' -jar "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest/functional/cmdline_options_tester/cmdlinetester.jar" -config "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest/functional/cmdLineTests/pltest/pltest.xml" -verbose -explainExcludes \
01:02:13.545 	-xids all,osx_x86-64_cr, -plats all,osx_x86-64_cr, -nonZeroExitWhenError; \
01:02:13.545 	if [ $? -eq 0 ] ; then echo ""; echo "cmdLineTester_pltest_0""_PASSED"; echo ""; cd /Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../..;  else echo ""; echo "cmdLineTester_pltest_0""_FAILED"; echo ""; fi; } 2>&1 | tee -a "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15429199923919/TestTargetResult";
01:02:15.719 *** Starting test suite: J9 Command-Line Option Test for pltest ***
01:02:15.719 Testing: PLTEST
01:02:15.719 Test start time: 2018/11/22 16:35:41 Eastern Standard Time
01:02:15.719 Running command: /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../../native-test-libs//pltest 
01:02:15.719 Time spent starting: 30 milliseconds
01:04:40.105 /bin/sh: line 1: 86573 Quit: 3                 { mkdir -p "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15429199923919/cmdLineTester_pltest_0"; cd "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15429199923919/cmdLineTester_pltest_0"; export DYLD_LIBRARY_PATH=""/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../../native-test-libs/:/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../lib/compressedrefs:/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../lib/j9vm":"; "/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/java" -Xcompressedrefs -DRESOURCES_DIR="/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest/TestConfig/resources" -DJVM_TEST_ROOT="/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest" -DEXE='/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/../../native-test-libs//pltest ' -jar "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest/functional/cmdline_options_tester/cmdlinetester.jar" -config "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../../jvmtest/functional/cmdLineTests/pltest/pltest.xml" -verbose -explainExcludes -xids all,osx_x86-64_cr, -plats all,osx_x86-64_cr, -nonZeroExitWhenError; if [ $? -eq 0 ]; then
01:04:40.105     echo ""; echo "cmdLineTester_pltest_0""_PASSED"; echo ""; cd /Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../..;
01:04:40.105 else
01:04:40.105     echo ""; echo "cmdLineTester_pltest_0""_FAILED"; echo "";
01:04:40.105 fi; } 2>&1
01:04:40.105      86574                       | tee -a "/Users/jenkins/workspace/Grinder/openjdk-tests/TestConfig/scripts/testKitGen/../../../TestConfig/test_output_15429199923919/TestTargetResult"
01:04:40.105 make[3]: *** [cmdLineTester_pltest_0] Error 131

@pshipton
Copy link
Member

@babsingh
Copy link
Contributor Author

Updated the PR description with testcase failures in the PLTEST suite. 23 testcases are failing.

@llxia
Copy link
Contributor

llxia commented Nov 29, 2018

Note: cmdLineTester_pltest is temporarily excluded in #3765

@babsingh
Copy link
Contributor Author

babsingh commented Jan 16, 2019

Update:

  • 3 tests no longer fail:
j9sysinfo_testMemoryInfo - si.c line 1037: Invalid memory usage statistics retrieved.
j9sysinfo_testProcessorInfo - si.c line 1249: Invalid processor usage statistics retrieved.
j9sysinfo_test_get_l1dcache_line_size - si.c line 2174: j9sysinfo_get_cache_info returned -100.
  • 20 tests still fail:
si.c line 1894: j9sysinfo_test_get_cwd3 invalid directory rc: 4
si.c line 2004: j9sysinfo_test_get_levels_and_types j9sysinfo_get_cache_info returned -355
j9sockTest.c line  196: j9sock_test1_basic error creating address: Unknown error: -1
j9sockTest.c line  392: j9sock_test1_basic error starting server: Unknown error: -1
j9sockTest.c line  244: j9sock_test1_basic error creating address: Unknown error: -1
j9sockTest.c line  396: j9sock_test1_basic error creating/connecting client: Unknown error: -1
j9sockTest.c line  401: j9sock_test1_basic error accepting connection: Invalid argument
j9sockTest.c line  196: j9sock_test3_fdset_basic_select error creating address: Bad file descriptor
j9sockTest.c line  609: j9sock_test3_fdset_basic_select error starting server: Bad file descriptor
j9sockTest.c line  244: j9sock_test3_fdset_basic_select error creating address: Bad file descriptor
j9sockTest.c line  613: j9sock_test3_fdset_basic_select error creating/connecting client: Bad file descriptor
j9sockTest.c line  618: j9sock_test3_fdset_basic_select error accepting connection: Invalid argument
j9sockTest.c line  930: j9sock_test5_basic_options error creating address: Bad file descriptor
j9sockTest.c line  196: j9sock_test6_nonblocking_connect error creating address: Bad file descriptor
j9sockTest.c line 1008: j9sock_test6_nonblocking_connect error starting server: Bad file descriptor
j9sockTest.c line  196: j9sock_test7_nonblocking_read error creating address: Bad file descriptor
j9sockTest.c line 1073: j9sock_test7_nonblocking_read error starting server: Bad file descriptor
j9sockTest.c line  244: j9sock_test7_nonblocking_read error creating address: Bad file descriptor
j9sockTest.c line 1077: j9sock_test7_nonblocking_read error creating/connecting client: Bad file descriptor
j9sockTest.c line 1081: j9sock_test7_nonblocking_read error accepting connection: Invalid argument

For the j9sysinfo failures, the j9sysinfo tests were not updated for the OSX specific j9sysinfo API.

For the j9sockTest failures, the j9sock API is not implemented for OSX. So, the j9sockTest suite can be temporarily disabled until the j9sock API is implemented for OSX.

  • Sometimes, the pltest executable terminates abruptly. It terminates abruptly when SIGQUIT is raised in j9process_testNewProcessGroupByTriggeringSignal. This behavior is only seen when the output of pltest is piped to a log file: pltest 2>&1 | tee pltest_jdk11.log. If the output is not piped to a log file, then the pltest executable runs properly.
Starting test j9process_testNewProcessGroupByTriggeringSignal
 Starting process /Users/[email protected]/Desktop/openj9_osx_tests/jdk11/jdk/bin/../../native-test-libs//pltest -child_j9process_processRegisterHandlerAndSpin
 Waiting for child to register handler
Quit: 3
  • Same behavior is seen with JDK8 and JDK11 on OSX.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

5 participants