builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0159 starttime: 1476708419.63 results: success (0) buildid: 20161017032035 builduid: 76b8755dd850486f85562a7ca5937c17 revision: 99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.632840) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.633319) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.633596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.662992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.663376) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZbwbHJhfo/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-17 05:46:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.01M=0.001s 2016-10-17 05:46:59 (9.01 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150768 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.835818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.837214) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZbwbHJhfo/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.019098 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.885811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 05:46:59.886187) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZbwbHJhfo/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-17 05:46:59,958 truncating revision to first 12 chars 2016-10-17 05:46:59,958 Setting DEBUG logging. 2016-10-17 05:46:59,958 attempt 1/10 2016-10-17 05:46:59,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/99eb47ffccb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 05:47:00,638 unpacking tar archive at: autoland-99eb47ffccb9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.930847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 05:47:00.841746) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 05:47:00.845089) ========= script_repo_revision: 99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 05:47:00.845494) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 05:47:00.845762) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 05:47:00.863694) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-17 05:47:00.864039) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZbwbHJhfo/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:47:01 INFO - MultiFileLogger online at 20161017 05:47:01 in /builds/slave/test 05:47:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 05:47:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:47:01 INFO - {'append_to_log': False, 05:47:01 INFO - 'base_work_dir': '/builds/slave/test', 05:47:01 INFO - 'blob_upload_branch': 'Autoland', 05:47:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:47:01 INFO - 'branch': 'Autoland', 05:47:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:47:01 INFO - 'config_files': ('talos/mac_config.py',), 05:47:01 INFO - 'default_actions': ('clobber', 05:47:01 INFO - 'read-buildbot-config', 05:47:01 INFO - 'download-and-extract', 05:47:01 INFO - 'populate-webroot', 05:47:01 INFO - 'create-virtualenv', 05:47:01 INFO - 'install', 05:47:01 INFO - 'run-tests'), 05:47:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:47:01 INFO - 'download_minidump_stackwalk': True, 05:47:01 INFO - 'download_symbols': 'ondemand', 05:47:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:47:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:47:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:47:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:47:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:47:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:47:01 INFO - 'installer_path': 'installer.exe', 05:47:01 INFO - 'log_level': 'info', 05:47:01 INFO - 'log_name': 'talos', 05:47:01 INFO - 'log_to_console': True, 05:47:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:47:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:47:01 INFO - 'opt_config_files': (), 05:47:01 INFO - 'pip_index': False, 05:47:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:01 INFO - 'cmd': ('bash', 05:47:01 INFO - '-c', 05:47:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:01 INFO - 'enabled': True, 05:47:01 INFO - 'halt_on_failure': False, 05:47:01 INFO - 'name': 'check_screen_resolution'},), 05:47:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:01 INFO - 'cmd': ('bash', 05:47:01 INFO - '-c', 05:47:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:01 INFO - 'enabled': True, 05:47:01 INFO - 'halt_on_failure': False, 05:47:01 INFO - 'name': 'check_screen_resolution'},), 05:47:01 INFO - 'run_cmd_checks_enabled': True, 05:47:01 INFO - 'sps_profile': False, 05:47:01 INFO - 'sps_profile_interval': 0, 05:47:01 INFO - 'suite': 'dromaeojs-e10s', 05:47:01 INFO - 'system_bits': '32', 05:47:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:47:01 INFO - 'title': 't-yosemite-r7-0159', 05:47:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:47:01 INFO - 'use_talos_json': True, 05:47:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:47:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:47:01 INFO - 'work_dir': 'build'} 05:47:01 INFO - [mozharness: 2016-10-17 12:47:01.067472Z] Running clobber step. 05:47:01 INFO - Running pre-action listener: _resource_record_pre_action 05:47:01 INFO - Running main action method: clobber 05:47:01 INFO - rmtree: /builds/slave/test/build 05:47:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:47:02 INFO - Running post-action listener: _resource_record_post_action 05:47:02 INFO - [mozharness: 2016-10-17 12:47:02.618206Z] Finished clobber step (success) 05:47:02 INFO - [mozharness: 2016-10-17 12:47:02.618400Z] Running read-buildbot-config step. 05:47:02 INFO - Running pre-action listener: _resource_record_pre_action 05:47:02 INFO - Running main action method: read_buildbot_config 05:47:02 INFO - Using buildbot properties: 05:47:02 INFO - { 05:47:02 INFO - "project": "", 05:47:02 INFO - "product": "firefox", 05:47:02 INFO - "script_repo_revision": "production", 05:47:02 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 05:47:02 INFO - "repository": "", 05:47:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 05:47:02 INFO - "buildid": "20161017032035", 05:47:02 INFO - "pgo_build": "False", 05:47:02 INFO - "basedir": "/builds/slave/test", 05:47:02 INFO - "buildnumber": 219, 05:47:02 INFO - "slavename": "t-yosemite-r7-0159", 05:47:02 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:47:02 INFO - "platform": "yosemite_r7", 05:47:02 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 05:47:02 INFO - "branch": "autoland", 05:47:02 INFO - "repo_path": "integration/autoland", 05:47:02 INFO - "revision": "99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c", 05:47:02 INFO - "stage_platform": "macosx64", 05:47:02 INFO - "builduid": "76b8755dd850486f85562a7ca5937c17", 05:47:02 INFO - "slavebuilddir": "test" 05:47:02 INFO - } 05:47:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:47:02 INFO - Running post-action listener: _resource_record_post_action 05:47:02 INFO - [mozharness: 2016-10-17 12:47:02.621604Z] Finished read-buildbot-config step (success) 05:47:02 INFO - [mozharness: 2016-10-17 12:47:02.621721Z] Running download-and-extract step. 05:47:02 INFO - Running pre-action listener: _resource_record_pre_action 05:47:02 INFO - Running main action method: download_and_extract 05:47:02 INFO - mkdir: /builds/slave/test/build/tests 05:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:02 INFO - trying https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:47:02 INFO - Downloading https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:47:04 INFO - Downloaded 1199 bytes. 05:47:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:47:04 INFO - Using the following test package requirements: 05:47:04 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:47:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:47:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:47:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'jsshell-mac.zip'], 05:47:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:47:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:47:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:47:04 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:47:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:47:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:04 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:47:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:47:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:47:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:04 INFO - Fetch https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:47:04 INFO - Http code: 200 05:47:04 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 05:47:04 INFO - x-amz-cf-id: _F7TXKxRvsV4NTuuooAAlL461W5--xO8f8_1ZLqHnq51DTfMTytBbQ== 05:47:04 INFO - x-amz-version-id: kp0U2kkaPcs_qrWDH_OKC8CbKUOm7KGw 05:47:05 INFO - Expected file size: 16483070 05:47:05 INFO - Obtained file size: 16483070 05:47:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:47:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:11 INFO - Fetch https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:47:12 INFO - Http code: 200 05:47:12 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 05:47:12 INFO - x-amz-cf-id: GZ3q2b8g_F2mdNPKwJtJp7zDddIHE0pUPDCNcdE4vNC4l6__W9pKuw== 05:47:12 INFO - x-amz-version-id: kp0U2kkaPcs_qrWDH_OKC8CbKUOm7KGw 05:47:12 INFO - Expected file size: 16483070 05:47:12 INFO - Obtained file size: 16483070 05:47:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:47:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:47:19 INFO - Fetch https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:47:20 INFO - Http code: 200 05:47:20 INFO - via: 1.1 3d3d633d266d05d90a4eea7a6a59b514.cloudfront.net (CloudFront) 05:47:20 INFO - x-amz-cf-id: DZAt3sukujTMacZl6nETrUa39svOJDv68htx0h6vkNZ7Tosw8TuXsw== 05:47:20 INFO - x-amz-version-id: UkxlkW6buwJZZN5lFhiXC_8oiSTKHUPh 05:47:20 INFO - Expected file size: 14409028 05:47:20 INFO - Obtained file size: 14409028 05:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:21 INFO - trying https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:47:21 INFO - Downloading https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:47:23 INFO - Downloaded 111705096 bytes. 05:47:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:47:23 INFO - mkdir: /builds/slave/test/properties 05:47:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:47:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:47:23 INFO - Contents: 05:47:23 INFO - build_url:https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:47:24 INFO - Running post-action listener: _resource_record_post_action 05:47:24 INFO - Running post-action listener: set_extra_try_arguments 05:47:24 INFO - [mozharness: 2016-10-17 12:47:24.735535Z] Finished download-and-extract step (success) 05:47:24 INFO - [mozharness: 2016-10-17 12:47:24.735680Z] Running populate-webroot step. 05:47:24 INFO - Running pre-action listener: _resource_record_pre_action 05:47:24 INFO - Running main action method: populate_webroot 05:47:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:47:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:47:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:47:24 INFO - '--tppagecycles', 05:47:24 INFO - '7'], 05:47:24 INFO - 'tests': ['tsvgm']}}, 05:47:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:47:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:47:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:47:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:47:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['damp', 'tps']}, 05:47:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'tests': ['damp', 'tps']}, 05:47:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['dromaeo_dom']}, 05:47:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:47:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['basic_compositor_video']}, 05:47:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:47:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['a11yr', 05:47:24 INFO - 'ts_paint', 05:47:24 INFO - 'tpaint', 05:47:24 INFO - 'sessionrestore', 05:47:24 INFO - 'sessionrestore_no_auto_restore', 05:47:24 INFO - 'tabpaint']}, 05:47:24 INFO - 'other-e10s': {'tests': ['a11yr', 05:47:24 INFO - 'ts_paint', 05:47:24 INFO - 'tpaint', 05:47:24 INFO - 'sessionrestore', 05:47:24 INFO - 'sessionrestore_no_auto_restore', 05:47:24 INFO - 'tabpaint']}, 05:47:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:47:24 INFO - 'ts_paint', 05:47:24 INFO - 'tpaint', 05:47:24 INFO - 'sessionrestore', 05:47:24 INFO - 'sessionrestore_no_auto_restore', 05:47:24 INFO - 'tabpaint']}, 05:47:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:47:24 INFO - 'ts_paint', 05:47:24 INFO - 'tpaint', 05:47:24 INFO - 'sessionrestore', 05:47:24 INFO - 'sessionrestore_no_auto_restore', 05:47:24 INFO - 'tabpaint']}, 05:47:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['a11yr', 05:47:24 INFO - 'ts_paint', 05:47:24 INFO - 'tpaint', 05:47:24 INFO - 'sessionrestore', 05:47:24 INFO - 'sessionrestore_no_auto_restore', 05:47:24 INFO - 'tabpaint']}, 05:47:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['a11yr', 05:47:24 INFO - 'ts_paint', 05:47:24 INFO - 'tpaint', 05:47:24 INFO - 'sessionrestore', 05:47:24 INFO - 'sessionrestore_no_auto_restore', 05:47:24 INFO - 'tabpaint']}, 05:47:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['tsvgx', 05:47:24 INFO - 'tsvgr_opacity', 05:47:24 INFO - 'tart', 05:47:24 INFO - 'tscrollx', 05:47:24 INFO - 'cart']}, 05:47:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:47:24 INFO - 'tsvgr_opacity', 05:47:24 INFO - 'tart', 05:47:24 INFO - 'tscrollx', 05:47:24 INFO - 'cart']}, 05:47:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'talos_options': ['--disable-e10s'], 05:47:24 INFO - 'tests': ['tp5o']}, 05:47:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'tests': ['tp5o']}, 05:47:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'talos_options': ['--disable-e10s', 05:47:24 INFO - '--xperf_path', 05:47:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:24 INFO - 'tests': ['tp5n']}, 05:47:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:24 INFO - 'talos_options': ['--xperf_path', 05:47:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:24 INFO - 'tests': ['tp5n']}}, 05:47:24 INFO - 'talos.zip': {'path': '', 05:47:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:47:24 INFO - Running post-action listener: _resource_record_post_action 05:47:24 INFO - [mozharness: 2016-10-17 12:47:24.754468Z] Finished populate-webroot step (success) 05:47:24 INFO - [mozharness: 2016-10-17 12:47:24.754604Z] Running create-virtualenv step. 05:47:24 INFO - Running pre-action listener: _resource_record_pre_action 05:47:24 INFO - Running main action method: create_virtualenv 05:47:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:47:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:47:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:47:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:47:24 INFO - Using real prefix '/tools/python27' 05:47:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:47:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:47:28 INFO - Installing pip.................done. 05:47:28 INFO - Return code: 0 05:47:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:47:28 INFO - Reading from file tmpfile_stdout 05:47:28 INFO - Output received: 05:47:28 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:47:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:28 INFO - 'HOME': '/Users/cltbld', 05:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:28 INFO - 'LOGNAME': 'cltbld', 05:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:28 INFO - 'MOZ_NO_REMOTE': '1', 05:47:28 INFO - 'NO_EM_RESTART': '1', 05:47:28 INFO - 'PAGER': '/bin/cat', 05:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:28 INFO - 'PWD': '/builds/slave/test', 05:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:28 INFO - 'SHELL': '/bin/bash', 05:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:28 INFO - 'USER': 'cltbld', 05:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:28 INFO - 'XPC_FLAGS': '0x0', 05:47:28 INFO - 'XPC_SERVICE_NAME': '0', 05:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:47:28 INFO - Cleaning up... 05:47:28 INFO - Return code: 0 05:47:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:28 INFO - 'HOME': '/Users/cltbld', 05:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:28 INFO - 'LOGNAME': 'cltbld', 05:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:28 INFO - 'MOZ_NO_REMOTE': '1', 05:47:28 INFO - 'NO_EM_RESTART': '1', 05:47:28 INFO - 'PAGER': '/bin/cat', 05:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:28 INFO - 'PWD': '/builds/slave/test', 05:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:28 INFO - 'SHELL': '/bin/bash', 05:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:28 INFO - 'USER': 'cltbld', 05:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:28 INFO - 'XPC_FLAGS': '0x0', 05:47:28 INFO - 'XPC_SERVICE_NAME': '0', 05:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:29 INFO - Downloading/unpacking psutil>=3.1.1 05:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:47:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:32 INFO - Installing collected packages: psutil 05:47:32 INFO - Running setup.py install for psutil 05:47:32 INFO - building 'psutil._psutil_osx' extension 05:47:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:47:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:47:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:47:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:47:33 INFO - building 'psutil._psutil_posix' extension 05:47:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:47:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:47:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:47:33 INFO - ^ 05:47:33 INFO - 1 warning generated. 05:47:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:47:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:33 INFO - Successfully installed psutil 05:47:33 INFO - Cleaning up... 05:47:33 INFO - Return code: 0 05:47:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:33 INFO - 'HOME': '/Users/cltbld', 05:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:33 INFO - 'LOGNAME': 'cltbld', 05:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:33 INFO - 'MOZ_NO_REMOTE': '1', 05:47:33 INFO - 'NO_EM_RESTART': '1', 05:47:33 INFO - 'PAGER': '/bin/cat', 05:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:33 INFO - 'PWD': '/builds/slave/test', 05:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:33 INFO - 'SHELL': '/bin/bash', 05:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:33 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:33 INFO - 'USER': 'cltbld', 05:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:33 INFO - 'XPC_FLAGS': '0x0', 05:47:33 INFO - 'XPC_SERVICE_NAME': '0', 05:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:47:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:47:36 INFO - Installing collected packages: mozsystemmonitor 05:47:36 INFO - Running setup.py install for mozsystemmonitor 05:47:36 INFO - Successfully installed mozsystemmonitor 05:47:36 INFO - Cleaning up... 05:47:36 INFO - Return code: 0 05:47:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:36 INFO - 'HOME': '/Users/cltbld', 05:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:36 INFO - 'LOGNAME': 'cltbld', 05:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:36 INFO - 'MOZ_NO_REMOTE': '1', 05:47:36 INFO - 'NO_EM_RESTART': '1', 05:47:36 INFO - 'PAGER': '/bin/cat', 05:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:36 INFO - 'PWD': '/builds/slave/test', 05:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:36 INFO - 'SHELL': '/bin/bash', 05:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:36 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:36 INFO - 'USER': 'cltbld', 05:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:36 INFO - 'XPC_FLAGS': '0x0', 05:47:36 INFO - 'XPC_SERVICE_NAME': '0', 05:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:47:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:47:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:47:39 INFO - warning: no files found matching '*.txt' 05:47:39 INFO - no previously-included directories found matching 'build' 05:47:39 INFO - no previously-included directories found matching 'dist' 05:47:39 INFO - no previously-included directories found matching '.git*' 05:47:39 INFO - Installing collected packages: jsonschema, functools32 05:47:39 INFO - Running setup.py install for functools32 05:47:40 INFO - warning: no files found matching '*.txt' 05:47:40 INFO - no previously-included directories found matching 'build' 05:47:40 INFO - no previously-included directories found matching 'dist' 05:47:40 INFO - no previously-included directories found matching '.git*' 05:47:40 INFO - Successfully installed jsonschema functools32 05:47:40 INFO - Cleaning up... 05:47:40 INFO - Return code: 0 05:47:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:47:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:47:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:40 INFO - 'HOME': '/Users/cltbld', 05:47:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:40 INFO - 'LOGNAME': 'cltbld', 05:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:40 INFO - 'MOZ_NO_REMOTE': '1', 05:47:40 INFO - 'NO_EM_RESTART': '1', 05:47:40 INFO - 'PAGER': '/bin/cat', 05:47:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:40 INFO - 'PWD': '/builds/slave/test', 05:47:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:40 INFO - 'SHELL': '/bin/bash', 05:47:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:40 INFO - 'USER': 'cltbld', 05:47:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:40 INFO - 'XPC_FLAGS': '0x0', 05:47:40 INFO - 'XPC_SERVICE_NAME': '0', 05:47:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:47:40 INFO - Cleaning up... 05:47:40 INFO - Return code: 0 05:47:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:47:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:47:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:40 INFO - 'HOME': '/Users/cltbld', 05:47:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:40 INFO - 'LOGNAME': 'cltbld', 05:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:40 INFO - 'MOZ_NO_REMOTE': '1', 05:47:40 INFO - 'NO_EM_RESTART': '1', 05:47:40 INFO - 'PAGER': '/bin/cat', 05:47:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:40 INFO - 'PWD': '/builds/slave/test', 05:47:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:40 INFO - 'SHELL': '/bin/bash', 05:47:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:40 INFO - 'USER': 'cltbld', 05:47:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:40 INFO - 'XPC_FLAGS': '0x0', 05:47:40 INFO - 'XPC_SERVICE_NAME': '0', 05:47:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:47:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:47:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:44 INFO - Downloading docopt-0.6.1.tar.gz 05:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:47:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:47:44 INFO - Running setup.py install for blobuploader 05:47:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:47:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:47:44 INFO - Running setup.py install for requests 05:47:45 INFO - Running setup.py install for docopt 05:47:45 INFO - Successfully installed blobuploader requests docopt 05:47:45 INFO - Cleaning up... 05:47:45 INFO - Return code: 0 05:47:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:47:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:45 INFO - 'HOME': '/Users/cltbld', 05:47:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:45 INFO - 'LOGNAME': 'cltbld', 05:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:45 INFO - 'MOZ_NO_REMOTE': '1', 05:47:45 INFO - 'NO_EM_RESTART': '1', 05:47:45 INFO - 'PAGER': '/bin/cat', 05:47:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:45 INFO - 'PWD': '/builds/slave/test', 05:47:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:45 INFO - 'SHELL': '/bin/bash', 05:47:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:45 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:45 INFO - 'USER': 'cltbld', 05:47:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:45 INFO - 'XPC_FLAGS': '0x0', 05:47:45 INFO - 'XPC_SERVICE_NAME': '0', 05:47:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-rqcSSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ZjA5ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-UQ71rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Lvwxm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2nad1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2zR94y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gBXDTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-K1OkUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-t7suk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-5g2h9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-wwCf9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-MWjcsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-61bohG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Y_nJkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-tZGDxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-YIFRna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-KsERZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:47:47 INFO - Running setup.py install for manifestparser 05:47:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:47:47 INFO - Running setup.py install for mozcrash 05:47:47 INFO - Running setup.py install for mozdebug 05:47:47 INFO - Running setup.py install for mozdevice 05:47:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Running setup.py install for mozfile 05:47:48 INFO - Running setup.py install for mozhttpd 05:47:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Running setup.py install for mozinfo 05:47:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Running setup.py install for mozInstall 05:47:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Running setup.py install for mozleak 05:47:48 INFO - Running setup.py install for mozlog 05:47:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:47:48 INFO - Running setup.py install for moznetwork 05:47:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:47:49 INFO - Running setup.py install for mozprocess 05:47:49 INFO - Running setup.py install for mozprofile 05:47:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:47:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:47:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:47:49 INFO - Running setup.py install for mozrunner 05:47:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:47:49 INFO - Running setup.py install for mozscreenshot 05:47:49 INFO - Running setup.py install for moztest 05:47:49 INFO - Running setup.py install for mozversion 05:47:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:47:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:47:50 INFO - Cleaning up... 05:47:50 INFO - Return code: 0 05:47:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:50 INFO - 'HOME': '/Users/cltbld', 05:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:50 INFO - 'LOGNAME': 'cltbld', 05:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:50 INFO - 'MOZ_NO_REMOTE': '1', 05:47:50 INFO - 'NO_EM_RESTART': '1', 05:47:50 INFO - 'PAGER': '/bin/cat', 05:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:50 INFO - 'PWD': '/builds/slave/test', 05:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:50 INFO - 'SHELL': '/bin/bash', 05:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:50 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:50 INFO - 'USER': 'cltbld', 05:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:50 INFO - 'XPC_FLAGS': '0x0', 05:47:50 INFO - 'XPC_SERVICE_NAME': '0', 05:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-FyFsrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ohey8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Z292En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-cjvRlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-0ukIKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-N9Pdz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-CdrC3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-dTV04x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-8dWgwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-7O5QxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-9Dp1pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-02zVRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-1JSafC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-miWsJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Kst6iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-xJeqcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-6XkXA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:54 INFO - Downloading blessings-1.6.tar.gz 05:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:47:54 INFO - Installing collected packages: blessings 05:47:54 INFO - Running setup.py install for blessings 05:47:54 INFO - Successfully installed blessings 05:47:54 INFO - Cleaning up... 05:47:55 INFO - Return code: 0 05:47:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:47:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:55 INFO - Reading from file tmpfile_stdout 05:47:55 INFO - Current package versions: 05:47:55 INFO - blessings == 1.6 05:47:55 INFO - blobuploader == 1.2.4 05:47:55 INFO - docopt == 0.6.1 05:47:55 INFO - functools32 == 3.2.3-2 05:47:55 INFO - jsonschema == 2.5.1 05:47:55 INFO - manifestparser == 1.1 05:47:55 INFO - mozInstall == 1.12 05:47:55 INFO - mozcrash == 0.17 05:47:55 INFO - mozdebug == 0.1 05:47:55 INFO - mozdevice == 0.48 05:47:55 INFO - mozfile == 1.2 05:47:55 INFO - mozhttpd == 0.7 05:47:55 INFO - mozinfo == 0.9 05:47:55 INFO - mozleak == 0.1 05:47:55 INFO - mozlog == 3.4 05:47:55 INFO - moznetwork == 0.27 05:47:55 INFO - mozprocess == 0.23 05:47:55 INFO - mozprofile == 0.28 05:47:55 INFO - mozrunner == 6.12 05:47:55 INFO - mozscreenshot == 0.1 05:47:55 INFO - mozsystemmonitor == 0.3 05:47:55 INFO - moztest == 0.7 05:47:55 INFO - mozversion == 1.4 05:47:55 INFO - psutil == 3.1.1 05:47:55 INFO - requests == 1.2.3 05:47:55 INFO - wsgiref == 0.1.2 05:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:56 INFO - 'HOME': '/Users/cltbld', 05:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:56 INFO - 'LOGNAME': 'cltbld', 05:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:56 INFO - 'MOZ_NO_REMOTE': '1', 05:47:56 INFO - 'NO_EM_RESTART': '1', 05:47:56 INFO - 'PAGER': '/bin/cat', 05:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:56 INFO - 'PWD': '/builds/slave/test', 05:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:56 INFO - 'SHELL': '/bin/bash', 05:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:56 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:56 INFO - 'USER': 'cltbld', 05:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:56 INFO - 'XPC_FLAGS': '0x0', 05:47:56 INFO - 'XPC_SERVICE_NAME': '0', 05:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:47:56 INFO - Cleaning up... 05:47:56 INFO - Return code: 0 05:47:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feee490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6d8d67b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:47:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:47:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:57 INFO - 'HOME': '/Users/cltbld', 05:47:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:57 INFO - 'LOGNAME': 'cltbld', 05:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:57 INFO - 'MOZ_NO_REMOTE': '1', 05:47:57 INFO - 'NO_EM_RESTART': '1', 05:47:57 INFO - 'PAGER': '/bin/cat', 05:47:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:57 INFO - 'PWD': '/builds/slave/test', 05:47:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:57 INFO - 'SHELL': '/bin/bash', 05:47:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:47:57 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:47:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:57 INFO - 'USER': 'cltbld', 05:47:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:57 INFO - 'XPC_FLAGS': '0x0', 05:47:57 INFO - 'XPC_SERVICE_NAME': '0', 05:47:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:47:57 INFO - Cleaning up... 05:47:57 INFO - Return code: 0 05:47:57 INFO - Running post-action listener: _resource_record_post_action 05:47:57 INFO - Running post-action listener: _start_resource_monitoring 05:47:57 INFO - Starting resource monitoring. 05:47:57 INFO - [mozharness: 2016-10-17 12:47:57.537474Z] Finished create-virtualenv step (success) 05:47:57 INFO - [mozharness: 2016-10-17 12:47:57.538494Z] Running install step. 05:47:57 INFO - Running pre-action listener: _resource_record_pre_action 05:47:57 INFO - Running main action method: install 05:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:57 INFO - Reading from file tmpfile_stdout 05:47:57 INFO - Detecting whether we're running mozinstall >=1.0... 05:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:47:57 INFO - Reading from file tmpfile_stdout 05:47:57 INFO - Output received: 05:47:57 INFO - Usage: mozinstall [options] installer 05:47:57 INFO - Options: 05:47:57 INFO - -h, --help show this help message and exit 05:47:57 INFO - -d DEST, --destination=DEST 05:47:57 INFO - Directory to install application into. [default: 05:47:57 INFO - "/builds/slave/test"] 05:47:57 INFO - --app=APP Application being installed. [default: firefox] 05:47:57 INFO - mkdir: /builds/slave/test/build/application 05:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:48:32 INFO - Reading from file tmpfile_stdout 05:48:32 INFO - Output received: 05:48:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:48:32 INFO - Running post-action listener: _resource_record_post_action 05:48:32 INFO - [mozharness: 2016-10-17 12:48:32.188214Z] Finished install step (success) 05:48:32 INFO - [mozharness: 2016-10-17 12:48:32.188332Z] Running run-tests step. 05:48:32 INFO - Running pre-action listener: _resource_record_pre_action 05:48:32 INFO - Running main action method: run_tests 05:48:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:32 INFO - 2016-10-17 05:48:32.203 screenresolution[1936:17343] starting screenresolution argv=screenresolution get 05:48:32 INFO - 2016-10-17 05:48:32.205 screenresolution[1936:17343] Display 0: 1600x1200x32@60 05:48:32 INFO - 2016-10-17 05:48:32.211 screenresolution[1937:17345] starting screenresolution argv=screenresolution list 05:48:32 INFO - Available Modes on Display 0 05:48:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:32 INFO - Intel Iris: 05:48:32 INFO - Chipset Model: Intel Iris 05:48:32 INFO - Type: GPU 05:48:32 INFO - Bus: Built-In 05:48:32 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:32 INFO - Vendor: Intel (0x8086) 05:48:32 INFO - Device ID: 0x0a2e 05:48:32 INFO - Revision ID: 0x0009 05:48:32 INFO - Displays: 05:48:32 INFO - Display: 05:48:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:32 INFO - Display Serial Number: 1600x1200 60 05:48:32 INFO - Main Display: Yes 05:48:32 INFO - Mirror: Off 05:48:32 INFO - Online: Yes 05:48:32 INFO - Rotation: Supported 05:48:32 INFO - Return code: 0 05:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:48:32 INFO - Python 2.7.3 05:48:32 INFO - Return code: 0 05:48:32 INFO - grabbing minidump binary from tooltool 05:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd02d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcfab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:48:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:48:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:48:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:48:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:48:32 INFO - Return code: 0 05:48:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:48:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:48:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:48:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:48:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oZbwbHJhfo/Render', 05:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:32 INFO - 'HOME': '/Users/cltbld', 05:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:32 INFO - 'LOGNAME': 'cltbld', 05:48:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:48:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:32 INFO - 'MOZ_NO_REMOTE': '1', 05:48:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:48:32 INFO - 'NO_EM_RESTART': '1', 05:48:32 INFO - 'PAGER': '/bin/cat', 05:48:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:32 INFO - 'PWD': '/builds/slave/test', 05:48:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:32 INFO - 'SHELL': '/bin/bash', 05:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners', 05:48:32 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:32 INFO - 'USER': 'cltbld', 05:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:32 INFO - 'XPC_FLAGS': '0x0', 05:48:32 INFO - 'XPC_SERVICE_NAME': '0', 05:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:48:32 INFO - mozversion application_buildid: 20161017032035 05:48:32 INFO - mozversion application_changeset: 99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c 05:48:32 INFO - mozversion application_display_name: Nightly 05:48:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:48:32 INFO - mozversion application_name: Firefox 05:48:32 INFO - mozversion application_remotingname: firefox 05:48:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:48:32 INFO - mozversion application_vendor: Mozilla 05:48:32 INFO - mozversion application_version: 52.0a1 05:48:32 INFO - mozversion platform_buildid: 20161017032035 05:48:32 INFO - mozversion platform_changeset: 99eb47ffccb916c6c2ef4cef5fcb4c71dae0616c 05:48:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:48:32 INFO - mozversion platform_version: 52.0a1 05:48:32 INFO - using testdate: 1476708512 05:48:32 INFO - actual date: 1476708512 05:48:32 INFO - starting webserver on 'localhost:54406' 05:48:32 INFO - SUITE-START | Running 2 tests 05:48:32 INFO - TEST-START | dromaeo_css 05:48:32 INFO - operating with platform_type : mac_ 05:48:32 INFO - Initialising browser for dromaeo_css test... 05:48:32 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpzzLNlm/profile http://localhost:54406/getInfo.html) 05:48:35 INFO - PROCESS | 1947 | 2016-10-17 05:48:35.404 plugin-container[1949:17495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 05:48:35 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:35 INFO - PROCESS | 1947 | 2016-10-17 05:48:35.408 plugin-container[1949:17495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 05:48:35 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:35 INFO - PROCESS | 1947 | 2016-10-17 05:48:35.408 plugin-container[1949:17495] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:35 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 05:48:35 INFO - PROCESS | 1947 | colorDepth:24 05:48:35 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 05:48:35 INFO - PROCESS | 1947 | __metrics 05:48:35 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 05:48:36 INFO - TEST-INFO | 1947: exit 0 05:48:36 INFO - Browser initialized. 05:48:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:48:36 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpzzLNlm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:38 INFO - PROCESS | 1950 | 2016-10-17 05:48:38.672 plugin-container[1951:17619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:48:38 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:38 INFO - PROCESS | 1950 | 2016-10-17 05:48:38.675 plugin-container[1951:17619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 05:48:38 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:38 INFO - PROCESS | 1950 | 2016-10-17 05:48:38.675 plugin-container[1951:17619] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:48 INFO - PROCESS | 1950 | RSS: Main: 237330432 05:48:48 INFO - PROCESS | 1950 | 05:49:38 INFO - PROCESS | 1950 | 1476708578027 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:51:51 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:54406/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54406/tests/dromaeo/cssquery-ext.html) 05:51:53 INFO - PROCESS | 1950 | RSS: Main: 250277888 05:51:53 INFO - PROCESS | 1950 | 05:54:55 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:54406/tests/dromaeo/cssquery-ext.html (next: http://localhost:54406/tests/dromaeo/cssquery-jquery.html) 05:54:56 INFO - PROCESS | 1950 | RSS: Main: 262184960 05:54:56 INFO - PROCESS | 1950 | 05:57:58 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:54406/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54406/tests/dromaeo/cssquery-mootools.html) 05:57:58 INFO - PROCESS | 1950 | RSS: Main: 267943936 05:57:58 INFO - PROCESS | 1950 | 06:01:00 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:54406/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54406/tests/dromaeo/cssquery-prototype.html) 06:01:01 INFO - PROCESS | 1950 | RSS: Main: 267743232 06:01:01 INFO - PROCESS | 1950 | 06:04:04 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:54406/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54406/tests/dromaeo/cssquery-yui.html) 06:04:04 INFO - PROCESS | 1950 | RSS: Main: 274337792 06:04:04 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:54406/tests/dromaeo/cssquery-yui.html (next: http://localhost:54406/tests/dromaeo/cssquery-dojo.html) 06:07:07 INFO - PROCESS | 1950 | RSS: Main: 277139456 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | __start_tp_report 06:07:07 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 06:07:07 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 06:07:07 INFO - PROCESS | 1950 | |i|pagename|runs| 06:07:07 INFO - PROCESS | 1950 | |0;dojo.html;3070.846457677116;3281.5077738339246;3320.800751954883;3345.866165353386;3379.949300760489;5491.780328786854;5551.777928882845;5579.86050348741;5589.441055894414;5645.887082258352;4375.64994800416;4694.765261736917;5557.083081291582;5642.633228840123;5644.294463192101;4890.535399137094;5102.795888164469;5122.462141475129;5249.947500525003;5281;5100.9489905100845;5284.894302113975;5300.125479295912;5325.627206095575;5366;5080.288759573663;5121.718305493197;5192.376914770214;5203.453637368059;5267.947320526803;4211.810468528924;4434.623057040156;5075.213341932006;5161.896762064775;5254.448282930313;5124.231365295198;5162.22566615007;5182.144946083891;5197.220416937452;5271.525562699337;4999.0501804657;5266.7366631668265;5525.668459892379;5733.340665823442;5740.712964351766;5492.203630473599;6007.219061522018;6110.255008202027;6135.509159267248;6213.347598502138;6178.165947597062;6246.219222597176;6268.059791031336;6283.7800676976085;6285.120083188357;5728.484436400744;5747.195392645033;5764.250647415809;5764.769409223584;5805.158252053428;6144.126041557279;6152;6158.876822463526;6186.319504854462;6187.319394866561;6076.54425918049;6100.157317757059;6100.702285529923;6104.084387341934;6116.396640261069;6017.06735456005;6138.877222455526;6199.690015499207;6214.689265536796;6231.626102433868;4990.2764099205415;6000.669963151981;6036.667983260875;6121.173641558422;6165.229346331708;5084.694918304914;6126.571140020156;6166.321704612489;6171.197744293303;6178.196834411498;4871.074495845778;4882.975585122052;4887.437944636412;4891.461939186686;4916.311716359713;3743.887683369503;4327.437433133672;4331.177076355482;4383.430154079949;4437.977810110993;5630.183623374588;5642;5657.4908258256955;5666.320041594952;5676.035074037423;6184.319724830265;6221.751129954841;6259.436650701367;6290.24517057956;6301;4668.5798278155125;5526.171074338881;5606.074997625427;5641.8589535261945;5653.23681303027;5405.108157154025;5416.377116631637;5434.239206511092;5441.020616289027;5462.235287059815;6299.307076221612;6303;6305.4009869062365;6326.905096423558;6331.620102793847;6116.633002019893;6141.332587233645;6180.196574445294;6280.717367718372;6319.368063193644;6251.337830101872;6271.78048768291;6275.905861412174;6298.685065746603;6312.684365781601;6150.077488376779;6178.876422471435;6189.876202476015;6202.193714817045;6228.4705800007505;6100.115483254992;6127.647695153045;6290.477332693707;6294.5279104068295;6298.212723409574;6007.8197654070445;6024.186734790747;6052.394760523913;6121.331035925228;6134.957077260823;5936;6018.518518518596;6056.333803281547;6118.694065296806;6150.261968563801;5700.030994730822;5831.504322132666;5888.263967004124;5917.852053698692;5988.461038506555;6040.697965101639;6117.877642447037;6120.877582448237;6126.927787637056;6142.539309551712;5953.077273022688;5954.523638108854;5978.880422391441;5988.820335389945;5996.100584912124 06:07:07 INFO - PROCESS | 1950 | |1;ext.html;475.60049558370974;496.1639637211299;508.4102441168241;510.93979991517597;532.2468706779906;40732.79633601835;42379.364309535384;42420.727378178664;42421;42639.36040959384;43452.78273608635;43626.34560481595;43731.90670233246;43773.34339984895;43892.12215755691;9491.6203351866;9660.855087173699;9664.178544823699;9695.951520242388;9732.805343893118;9472.621095156186;9567.473788941605;9573.425594464354;9584.137427631511;9595.992420795821;9552.71341859742;9614.086661767122;9623.759406014835;9630.903690963105;9646.228301735879;8010.27907488323;9532.189763870045;9545.188658963996;9582.90417095827;9587.280953928448;8611.784705382353;9489.383190092622;9501.952490237505;9522.714318570454;9529.666461673878;41319.7934010328;41336.793316033225;41495.585044149775;41503.7924810377;41550.58449415497;3325.833708314599;3437.535932649085;3600.981995090034;3605.0446631642635;3658.5426821647293;24495.02019919201;29724;31103.533446998314;31347.686523134933;32208.516872246935;2454.5459090068393;3137.62348518177;3218.7746857719967;3259.7555183361105;3307.371599396107;479216;480489;485290;485625.14374856505;486343.56828215986;60437.18688439354;60601;60650;60707.696461518295;60726.4818379544;63844;64060.039099413545;64261.67869160624;64306;64471.35528644654;16541.84207105491;16755.497335079966;16773.58066048334;16780.328786848633;16790.58023549421;12287.508499660093;12304.692382690504;12333.260004399765;12340.568080117153;12341.136120471481;15024.474143404928;16781;17013.48959531216;17081.914590426968;17100.0594967278;2645.5105805426056;2755.5728862026417;6109.816705498753;6823.351781580742;6900.758473453506;841.869510225916;900.2797761790672;966.0822218892081;1072.5763323487179;1111.927724697892;23936;26886.5967010495;30466.08601741907;30676.38647227043;31313.904013359424;935.2410389351305;955.8422860227985;992.6228033347281;1017.1659239423601;1110.5113749949996;15420.537383878502;15879.206039697969;15944.963577367434;15987.600309992344;16323;692944;700616;701234;704476;709529;58183.41816581865;58550;58574.7071264641;58674.11988820171;58736.11895821566;1349.8323949783367;1397.5457976157586;1403.410567561606;1411.5976946570179;1412.3573773932835;26128;26882.462350752485;29790.80836766592;30396.544051839683;30438.086857394315;50876.745616271684;54242.728786357395;56985.715071426035;57018;60193.39806601878;641393;648733;650064;652253;654651;25827.837747301794;42630.65531395803;42736;42809.5719042818;42905.57094429016;44852.77573612111;45130.77434612806;45531.54468455406;45709.31436028529;46403.53596464127;37457.43813842849;37479.87560373193;37575;38778.80610597042;39044.41433378445;37903.43144852774;38082;39245.6075439242;39627;40037.39943900902;36322.4551631731;37066.62933370632;37542.624573755005;37723;38529.422058669705;33820.830895846346;34166.658333416344;34498;34631;34656;23212.883935580216;24909.377265567782;24993.000279988595;25149.24552263435;25632.615510767726 06:07:07 INFO - PROCESS | 1950 | |2;jquery.html;348.8186143205533;377.5299751808997;381.8568036986129;383.5550761117103;386.84911889892186;19377.903110484447;19925.40223793285;20155.899220503914;20550.177992880286;20714.171433142696;9800.705978820646;11737.589184378548;11916.821247681291;12108.33404162773;12135.514579416813;4824.7105173689515;5221.738913054351;5432.782688692448;5609.57928155388;5613.158026296068;3944.3097457944746;4167.249895018896;5449.809256676017;5591.30108736408;7523.623818809065;7533.35966442853;8107.391945604103;8144.51132932023;8179.304759095484;8214.712485063024;8001.759947201594;8096.595170241523;8103.473274237214;8114.9594252028655;8140.674373025072;7497;7704.922950770477;7776.377889768805;7779.144294127642;7810.0237470316215;28623.57064644032;28787.28031799201;28960.855195723885;28978.42043159146;29064;2350.717913850349;2456.9754302457104;2552.1577879299784;2599.272203782944;3034.2111051126644;16404;16606.25271862757;16694.248758805883;17366.56583585408;17658.823411765847;1814.872958892878;1933.2943475631434;1960.6176795524877;1983.9206431742714;2075.6575165097743;86104.56947715284;108422;108750;140309;142571.14857702891;9328.39365441244;9926.503674816231;9941.602335906628;9953.402795832273;21723.239686610894;12307.4461649226;12335.93832030852;12355.93822030902;12361.134720569653;12432.813507797391;8076.3942704297415;8181.427300088937;8183.631736571869;8239.258466738022;8329.12544182852;8544.855744963688;8597.52713600758;8619.310455163572;8780.912190878138;8789.780255493535;3921.098147426108;3940.192260586573;3947.4078888167005;3968.761874287552;4014.0767623446186;1501.5045035138378;1716.48505448365;1854.840724547158;1913.8086191381028;1916.6837471817273;855.3713020929612;887.5118684723376;910.8360495110811;938.1650331205233;948.9335746497679;15747.448839290799;19697.113629886688;20439.693404598944;21298.574028519342;21350.57298854014;883.3330835219368;892.5091199840062;914.8902131744101;942.9669961551313;954.2080073538946;9107.408018478778;11009.724756881142;11095.278806877526;11386.658400248005;11406.885931140583;463759;466584;530754.3462282742;597117;603665.9816700888;78584.60707696425;87636;115311.42344288225;121526;139739.12217461425;928.9860652090224;954.737447202025;977.1547611316303;1026.0867827633413;1032.5250384822916;19676.50808729793;19980.90009549943;20224.797752022292;20250.392488225378;20282.188712451338;37693.81153094217;51209.463716088576;53557;58154.7092264536;58333.708331458074;68974;79476.41047178909;81999.5900020496;83511;84382.57808710917;7760.223977602194;8029.558374289237;8078.7172448964;8232.300254478436;8343.749687509384;8378;9259.305552083486;10543.94728026355;12742.299173545172;13133.408996594984;10731.517081731185;11169.888301116885;11619.070474361863;11670.416479176178;11781.882181178078;11206.43967801623;11427.314361138358;11441.656750297505;11748.17762756633;12061.51753929833;11494.655160345203;11685.591004314809;11846.289222646668;11886.34625095628;11998.820017699567;5847.415258474119;8106.756797296091;8386.580670966548;8470.745877623682;8535.061143274359;7414.777556673308;10401.53193106297;11509.942450287695;11706.941465292619;11827.994620457013 06:07:07 INFO - PROCESS | 1950 | |3;mootools.html;1552.4799192270584;1568.647054412757;1833.834954854063;1868.9532761680948;1952.0239880059978;6641.667916604175;7961.7213397531095;8226.218509241628;9120.087991200877;9120.680776172827;8511.404201705884;8788.25299849512;8795.076516965706;8902.418298693387;8937.374383793138;2335.708036495438;2347.424880904181;2362.7123217846315;2367.2188177901253;2370.8814559272055;2301.1255722825304;2321.570509455756;2346.3195673254736;2350.236173243694;2350.7061617297836;2306.850054746436;2307.826912981525;2326.4067662745974;2328.8253380996407;2329;2290.4960908600083;2311.838171328008;2311.90752369905;2322.419395151212;2324.604817181075;2316.733575638806;2319.884005799703;2322.93031209064;2330.697009388786;2333.661619065226;8809.03100658927;8943.731688049304;9012.0086790453;9012.909870901338;9046.004939456654;5216.3218781558535;5384.91922621161;5410.91883621746;5415.891682166334;5422.566194704454;3100.410921924827;3454.6890779829937;3563.610192025112;3639.727020473475;3655.7623754455876;910.7131253655143;1049.3769324463549;1081.475484390078;1084.6637542361893;1103.8178700514407;9732.805343893153;10226.590936362536;10367.948160259226;11533.019693325994;11668.416579171093;4088.97775556111;4597.586217240464;4622.329762184464;4825.927611085836;4836.854894353175;9897.80204395908;9957.601695932226;9962.651307204213;9973.451460169616;9992.700218993441;5301.363836339664;5366.436524164985;5367.604422850009;5378.2470454136455;5386.649867758583;4392.31919052541;4534.478534968521;4755.120302744003;4882.926756098661;4921.975390123026;3909.198614284122;3916.6083391661177;3979.3633018716864;4002.039510517455;4059.837606495707;1284.8265484159706;1287.9678008049873;1289.265118882247;1298.3897568142959;1308.9214647121203;799.4044436894435;843.3674743942044;843.3969711656196;903.4714691905177;962.4706411473801;5775.653460792282;5816.505597024216;5825.417458254141;5996.550258730613;6094.329623741385;423.8919075635755;428.87991362418575;433.26561478294616;440.3856620015125;443.4479073553447;3911.4523966644692;3982.482277303932;3986.740861843971;4005.3591425372383;4022.4770779798437;10251.641192558225;10691.984261495145;10697.144228461704;10866.348019118877;10868.402237876835;10820.13438924884;10829.783404331869;10832.24174307791;10833.83749243762;10845.457727113771;8079.636416361278;8246.299064579458;8265.710700125346;8287.875681864776;8304.169583041648;5744.798932037359;5951.851203719942;6093.268807743099;6103.542234332354;6170.876582468415;9200.95399522998;9987.850182247126;10118.443485608068;10132.392056476636;10149.949250253701;6992.895106573304;9039.322050846082;9407.294452915921;10119.241056920908;10344.689659310232;6018.458338749533;6077.997130473423;6975.337342952412;7558.433117516099;7696.884546731915;7147.892076728115;8247;8425.199606037417;8565.57172141403;8572.828543429221;7519.736809211651;7594.278543538355;7635.770926872203;7663.425243106678;7741.148473668005;7352.154502232312;7362.6686799093095;7404.185539590749;7528.284812942762;7576.962115189609;5989.580729348903;6967.477439191979;7112.395446386908;7138.286171382821;7207.279272072751;4247.745135291892;5061.240813877947;5117.769700363567;5170.353705786777;5184.429712731672;4941;4997.075541024922;5017.397912250553;5114.2840002400435;5200.531952124327 06:07:07 INFO - PROCESS | 1950 | |4;prototype.html;298.2543640897757;307.2579719976257;316.19055218640284;320.10051754569645;322.701501111472;4982.402111746586;5001.774920128594;5479.917801232985;5558.610897237195;5567.944320556788;5340.652857564265;5385.623006389555;5444.428335024825;5522.950639378515;5528.530074943625;4166.354215096657;4168.77021278722;4179.164167166564;4180.745776267123;4181.414601955728;3634.67287944085;4082.020220193204;4138.255114079465;4162.375643653446;4197.580241975793;4078.7552746835286;4129.339305711087;4161.230172418097;4165.875403544184;4174;3723.6648701616987;4079.306517891936;4151.3150330195485;4183.832646694129;4201.4538110045805;2761.7710118997056;3489;3639.6724294813594;3645.2527231917393;3655.214128962286;4238.980647480555;4780.330753694486;4965.510346895953;5141.041398123424;5466.699331536764;3720.330340538702;4257.87226383209;4548.772561371951;4712.198926182557;4764.857054288343;5711.514521265697;5750.30996280449;5787.8424315137145;5805.606654402955;5829.300483941912;3591.586967498745;3620.2759448110482;3756.69946404287;3861.2856621524825;3975.980120099381;4930.8520744377365;5645.576581756384;5864.5894787364905;5944.195714742568;5963.821085367403;3505.4566542186003;3883.310759819431;3885.6000999125763;3921.176552923861;3925.2738243425056;3336.1484376170974;3511.754177207571;3821.961780382216;3986.1828325193264;3992.7604343739467;4134.669226461876;4665.510121437268;4670.252759558454;4678.228092364721;4681.344611754357;4650.465196502378;5664.971675141598;5743.62666426681;5747.827565173052;5830.854228644318;1114.788190243851;1272.3320256865036;1345.5088892554147;1353.499205294028;1354.4852955876704;700.5411455496658;749.5727435361791;816.1348970091722;824.6083110522454;830.9127541608165;3838.731288809812;3930.7051971102283;3971.920561588752;4570.611498022706;4597.908041839211;5026;5027.99440111979;5045.823396181116;5054.09026375256;5056.699201863144;4137.40007698882;4479.596836284685;4579.992401671627;4586.403767510269;4590.839320623762;2257.7064981552294;2278.259565641173;2590.8575028373243;2595.065776320523;2663;4596.448426188878;4739.502352253033;5046.417043898799;5055.205402082208;5075.670081444694;4692.718436893797;4843.813265749914;4988.60546937472;5010.394594810678;5153.845384638467;5103.87240318995;5284.947150528523;5484.911378097438;6053.3946605339115;6226.221722284715;5065.3161823153405;5395.001924643953;6007.369226231182;6020.51835853122;6033.8535678221;4583.510359133362;4987.251912213197;5278.736063196901;5746.913796292975;5921.526277897672;5824.301083869963;5947.197128387612;6176.320604733565;6199.1011303359855;6258.405451482102;3293.3084052349277;3464.9307013860084;3956.6043395660204;3989;3989.8005099744314;4976.2286845539;5708.57185711065;5942.266557383652;5946.583739138218;5981.431763982415;4425.645948324191;5542.972285138549;5659.462351076641;5730.541556675373;5749.712514374348;5148.382194136728;5792.536597072309;5840.678762668094;5877.764889404376;5927.911081333869;5437.238786569963;5575.721213939206;5601.215829783915;5626.774929002794;5645.689487078086;4592.645169675021;4804.5195480451675;5442.972785136049;5711.714414279352;5787.148112603926;4627.93058104122;4650;5580.5535557156145;5605.411431799603;5622.606417550732 06:07:07 INFO - PROCESS | 1950 | |5;yui.html;723.9348458638722;734.060402684564;777.8172129549558;778.928972662589;793.5635400529709;15311.157886316249;16282.185890705476;16286.185690715447;16324.938878972873;16345;14687.192204428753;14716.779248311284;14836.925815370934;14863.628409289773;14910.627234319147;639.1467391032977;751.6692655231708;854.521677497929;888.7200531832484;922.8569571716395;901.4591245252861;903.8824952756165;909.3498148823589;918.857577075551;919.0809190809191;985.3053597213983;986.2701600815417;987.9604815807359;991.1971303244359;992.092235604421;976.7753416714124;976.9609215631366;979.9118079372855;981.2444417798256;981.5484876956644;713.4292565947221;733.9119305683353;736.4108713029555;736.8084298082483;741.910970683516;14171.008029437944;14443.133411995208;14449.494267700686;14449.711005779931;14454.71090578193;13991.300434978311;14765.557033289018;14856.405743770236;14882.702345953021;14940.925295373563;11848.052155827512;11950.820737688942;11984;11987.64037078889;12255.448504817216;3742.6631603155574;3773.5471743390694;3823.5220597425323;3900.8829735107706;3958.4458175855116;90171;115562.84437155604;115832;117257.24114138295;117860.41069794682;11188.832167517494;11426.649985907019;11465.541378344773;11641.825372619418;11747.88252117485;23892.522149556913;24315.270541883772;24568.508629827662;24768.25695229146;24880.880360383828;8010.479318844256;8017.839643207103;8111.351091912633;8250.308686719209;8300.460470069425;4416.24923762961;4515.909681806411;4527.139843429738;4533.5013148553635;4591.977040114845;3496.8426420811124;3624.257027309395;3627.619099994515;3627.927441451209;3639.1266096136737;618.5268269773627;636.328673249726;645.0936434309803;647.6308504152588;658.1871388834785;542.5090293284584;636.8918082536353;649.7498463091744;666.776629829003;689.3210187964851;9490.620375184915;9659.71020869375;9751.609935602639;9776.315657903879;9792.951035244922;647.8727014993971;667.3493343989571;673.8618583190434;681.8602186551745;682.5938566552893;3450.4306789379975;3732.8133593320767;4334.848280310174;5383.81156659515;5520.91718624221;159122;159918;160349;161527;162852;134594;134719;136451;142653;143113.13773724466;94313.99999999863;120747;123846.14230786545;127406;129315;9704.660336888175;10054.396736195851;10062.345947513388;10077.193824493876;10098.293119481566;47992.760036199594;59854;60097.295621696256;62247;62249.68875155595;62470;77194.84207736776;89625;90043;93036.60445093193;9731.756706082404;10971.561137554409;12639.620811375673;13138.343082846011;13377.264250466318;11442.94278528602;13981.650458738613;14399.640008999859;14916.105033698013;15080.396784128512;11128.666140015812;11319.943400283275;13863.584092477242;14227.430902763774;14399.78400324017;11604;14020.439182432588;14027.579172624837;14171;14190;12073.335966521925;13056.086073974731;13535.390907409386;13729.931350343584;14038;9617.326787124834;10767.784644307227;11470.71323216893;11647.184697071123;11998.520059197534;7912.9208707912185;8583.699570515002;9887.258455615714;10797.460126993461;11789.469473873525 06:07:07 INFO - PROCESS | 1950 | __end_tp_report 06:07:07 INFO - PROCESS | 1950 | __start_cc_report 06:07:07 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,368 06:07:07 INFO - PROCESS | 1950 | __end_cc_report 06:07:07 INFO - PROCESS | 1950 | __startTimestamp1476709627458__endTimestamp 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | ------- Summary: start ------- 06:07:07 INFO - PROCESS | 1950 | Number of tests: 6 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5651.52 Median:5936.00 stddev:688.50 (11.6%) stddev-sans-first:660.17 06:07:07 INFO - PROCESS | 1950 | Values: 3070.8 3281.5 3320.8 3345.9 3379.9 5491.8 5551.8 5579.9 5589.4 5645.9 4375.6 4694.8 5557.1 5642.6 5644.3 4890.5 5102.8 5122.5 5249.9 5281.0 5100.9 5284.9 5300.1 5325.6 5366.0 5080.3 5121.7 5192.4 5203.5 5267.9 4211.8 4434.6 5075.2 5161.9 5254.4 5124.2 5162.2 5182.1 5197.2 5271.5 4999.1 5266.7 5525.7 5733.3 5740.7 5492.2 6007.2 6110.3 6135.5 6213.3 6178.2 6246.2 6268.1 6283.8 6285.1 5728.5 5747.2 5764.3 5764.8 5805.2 6144.1 6152.0 6158.9 6186.3 6187.3 6076.5 6100.2 6100.7 6104.1 6116.4 6017.1 6138.9 6199.7 6214.7 6231.6 4990.3 6000.7 6036.7 6121.2 6165.2 5084.7 6126.6 6166.3 6171.2 6178.2 4871.1 4883.0 4887.4 4891.5 4916.3 3743.9 4327.4 4331.2 4383.4 4438.0 5630.2 5642.0 5657.5 5666.3 5676.0 6184.3 6221.8 6259.4 6290.2 6301.0 4668.6 5526.2 5606.1 5641.9 5653.2 5405.1 5416.4 5434.2 5441.0 5462.2 6299.3 6303.0 6305.4 6326.9 6331.6 6116.6 6141.3 6180.2 6280.7 6319.4 6251.3 6271.8 6275.9 6298.7 6312.7 6150.1 6178.9 6189.9 6202.2 6228.5 6100.1 6127.6 6290.5 6294.5 6298.2 6007.8 6024.2 6052.4 6121.3 6135.0 5936.0 6018.5 6056.3 6118.7 6150.3 5700.0 5831.5 5888.3 5917.9 5988.5 6040.7 6117.9 6120.9 6126.9 6142.5 5953.1 5954.5 5978.9 5988.8 5996.1 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:74200.66 Median:42525.83 stddev:165733.64 (389.7%) stddev-sans-first:166106.12 06:07:07 INFO - PROCESS | 1950 | Values: 475.6 496.2 508.4 510.9 532.2 40732.8 42379.4 42420.7 42421.0 42639.4 43452.8 43626.3 43731.9 43773.3 43892.1 9491.6 9660.9 9664.2 9696.0 9732.8 9472.6 9567.5 9573.4 9584.1 9596.0 9552.7 9614.1 9623.8 9630.9 9646.2 8010.3 9532.2 9545.2 9582.9 9587.3 8611.8 9489.4 9502.0 9522.7 9529.7 41319.8 41336.8 41495.6 41503.8 41550.6 3325.8 3437.5 3601.0 3605.0 3658.5 24495.0 29724.0 31103.5 31347.7 32208.5 2454.5 3137.6 3218.8 3259.8 3307.4 479216.0 480489.0 485290.0 485625.1 486343.6 60437.2 60601.0 60650.0 60707.7 60726.5 63844.0 64060.0 64261.7 64306.0 64471.4 16541.8 16755.5 16773.6 16780.3 16790.6 12287.5 12304.7 12333.3 12340.6 12341.1 15024.5 16781.0 17013.5 17081.9 17100.1 2645.5 2755.6 6109.8 6823.4 6900.8 841.9 900.3 966.1 1072.6 1111.9 23936.0 26886.6 30466.1 30676.4 31313.9 935.2 955.8 992.6 1017.2 1110.5 15420.5 15879.2 15945.0 15987.6 16323.0 692944.0 700616.0 701234.0 704476.0 709529.0 58183.4 58550.0 58574.7 58674.1 58736.1 1349.8 1397.5 1403.4 1411.6 1412.4 26128.0 26882.5 29790.8 30396.5 30438.1 50876.7 54242.7 56985.7 57018.0 60193.4 641393.0 648733.0 650064.0 652253.0 654651.0 25827.8 42630.7 42736.0 42809.6 42905.6 44852.8 45130.8 45531.5 45709.3 46403.5 37457.4 37479.9 37575.0 38778.8 39044.4 37903.4 38082.0 39245.6 39627.0 40037.4 36322.5 37066.6 37542.6 37723.0 38529.4 33820.8 34166.7 34498.0 34631.0 34656.0 23212.9 24909.4 24993.0 25149.2 25632.6 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:32756.11 Median:2153.97 stddev:89716.74 (4165.2%) stddev-sans-first:89935.42 06:07:07 INFO - PROCESS | 1950 | Values: 348.8 377.5 381.9 383.6 386.8 19377.9 19925.4 20155.9 20550.2 20714.2 9800.7 11737.6 11916.8 12108.3 12135.5 4824.7 5221.7 5432.8 5609.6 5613.2 3944.3 4167.2 5449.8 5591.3 7523.6 7533.4 8107.4 8144.5 8179.3 8214.7 8001.8 8096.6 8103.5 8115.0 8140.7 7497.0 7704.9 7776.4 7779.1 7810.0 28623.6 28787.3 28960.9 28978.4 29064.0 2350.7 2457.0 2552.2 2599.3 3034.2 16404.0 16606.3 16694.2 17366.6 17658.8 1814.9 1933.3 1960.6 1983.9 2075.7 86104.6 108422.0 108750.0 140309.0 142571.1 9328.4 9926.5 9941.6 9953.4 21723.2 12307.4 12335.9 12355.9 12361.1 12432.8 8076.4 8181.4 8183.6 8239.3 8329.1 8544.9 8597.5 8619.3 8780.9 8789.8 3921.1 3940.2 3947.4 3968.8 4014.1 1501.5 1716.5 1854.8 1913.8 1916.7 855.4 887.5 910.8 938.2 948.9 15747.4 19697.1 20439.7 21298.6 21350.6 883.3 892.5 914.9 943.0 954.2 9107.4 11009.7 11095.3 11386.7 11406.9 463759.0 466584.0 530754.3 597117.0 603666.0 78584.6 87636.0 115311.4 121526.0 139739.1 929.0 954.7 977.2 1026.1 1032.5 19676.5 19980.9 20224.8 20250.4 20282.2 37693.8 51209.5 53557.0 58154.7 58333.7 68974.0 79476.4 81999.6 83511.0 84382.6 7760.2 8029.6 8078.7 8232.3 8343.7 8378.0 9259.3 10543.9 12742.3 13133.4 10731.5 11169.9 11619.1 11670.4 11781.9 11206.4 11427.3 11441.7 11748.2 12061.5 11494.7 11685.6 11846.3 11886.3 11998.8 5847.4 8106.8 8386.6 8470.7 8535.1 7414.8 10401.5 11509.9 11706.9 11828.0 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5636.63 Median:5007.24 stddev:3153.70 (63.0%) stddev-sans-first:3147.61 06:07:07 INFO - PROCESS | 1950 | Values: 1552.5 1568.6 1833.8 1869.0 1952.0 6641.7 7961.7 8226.2 9120.1 9120.7 8511.4 8788.3 8795.1 8902.4 8937.4 2335.7 2347.4 2362.7 2367.2 2370.9 2301.1 2321.6 2346.3 2350.2 2350.7 2306.9 2307.8 2326.4 2328.8 2329.0 2290.5 2311.8 2311.9 2322.4 2324.6 2316.7 2319.9 2322.9 2330.7 2333.7 8809.0 8943.7 9012.0 9012.9 9046.0 5216.3 5384.9 5410.9 5415.9 5422.6 3100.4 3454.7 3563.6 3639.7 3655.8 910.7 1049.4 1081.5 1084.7 1103.8 9732.8 10226.6 10367.9 11533.0 11668.4 4089.0 4597.6 4622.3 4825.9 4836.9 9897.8 9957.6 9962.7 9973.5 9992.7 5301.4 5366.4 5367.6 5378.2 5386.6 4392.3 4534.5 4755.1 4882.9 4922.0 3909.2 3916.6 3979.4 4002.0 4059.8 1284.8 1288.0 1289.3 1298.4 1308.9 799.4 843.4 843.4 903.5 962.5 5775.7 5816.5 5825.4 5996.6 6094.3 423.9 428.9 433.3 440.4 443.4 3911.5 3982.5 3986.7 4005.4 4022.5 10251.6 10692.0 10697.1 10866.3 10868.4 10820.1 10829.8 10832.2 10833.8 10845.5 8079.6 8246.3 8265.7 8287.9 8304.2 5744.8 5951.9 6093.3 6103.5 6170.9 9201.0 9987.9 10118.4 10132.4 10149.9 6992.9 9039.3 9407.3 10119.2 10344.7 6018.5 6078.0 6975.3 7558.4 7696.9 7147.9 8247.0 8425.2 8565.6 8572.8 7519.7 7594.3 7635.8 7663.4 7741.1 7352.2 7362.7 7404.2 7528.3 7577.0 5989.6 6967.5 7112.4 7138.3 7207.3 4247.7 5061.2 5117.8 5170.4 5184.4 4941.0 4997.1 5017.4 5114.3 5200.5 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:4460.45 Median:4792.43 stddev:1409.30 (29.4%) stddev-sans-first:1378.19 06:07:07 INFO - PROCESS | 1950 | Values: 298.3 307.3 316.2 320.1 322.7 4982.4 5001.8 5479.9 5558.6 5567.9 5340.7 5385.6 5444.4 5523.0 5528.5 4166.4 4168.8 4179.2 4180.7 4181.4 3634.7 4082.0 4138.3 4162.4 4197.6 4078.8 4129.3 4161.2 4165.9 4174.0 3723.7 4079.3 4151.3 4183.8 4201.5 2761.8 3489.0 3639.7 3645.3 3655.2 4239.0 4780.3 4965.5 5141.0 5466.7 3720.3 4257.9 4548.8 4712.2 4764.9 5711.5 5750.3 5787.8 5805.6 5829.3 3591.6 3620.3 3756.7 3861.3 3976.0 4930.9 5645.6 5864.6 5944.2 5963.8 3505.5 3883.3 3885.6 3921.2 3925.3 3336.1 3511.8 3822.0 3986.2 3992.8 4134.7 4665.5 4670.3 4678.2 4681.3 4650.5 5665.0 5743.6 5747.8 5830.9 1114.8 1272.3 1345.5 1353.5 1354.5 700.5 749.6 816.1 824.6 830.9 3838.7 3930.7 3971.9 4570.6 4597.9 5026.0 5028.0 5045.8 5054.1 5056.7 4137.4 4479.6 4580.0 4586.4 4590.8 2257.7 2278.3 2590.9 2595.1 2663.0 4596.4 4739.5 5046.4 5055.2 5075.7 4692.7 4843.8 4988.6 5010.4 5153.8 5103.9 5284.9 5484.9 6053.4 6226.2 5065.3 5395.0 6007.4 6020.5 6033.9 4583.5 4987.3 5278.7 5746.9 5921.5 5824.3 5947.2 6176.3 6199.1 6258.4 3293.3 3464.9 3956.6 3989.0 3989.8 4976.2 5708.6 5942.3 5946.6 5981.4 4425.6 5543.0 5659.5 5730.5 5749.7 5148.4 5792.5 5840.7 5877.8 5927.9 5437.2 5575.7 5601.2 5626.8 5645.7 4592.6 4804.5 5443.0 5711.7 5787.1 4627.9 4650.0 5580.6 5605.4 5622.6 06:07:07 INFO - PROCESS | 1950 | 06:07:07 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:25460.52 Median:3627.77 stddev:41650.12 (1148.1%) stddev-sans-first:41725.55 06:07:07 INFO - PROCESS | 1950 | Values: 723.9 734.1 777.8 778.9 793.6 15311.2 16282.2 16286.2 16324.9 16345.0 14687.2 14716.8 14836.9 14863.6 14910.6 639.1 751.7 854.5 888.7 922.9 901.5 903.9 909.3 918.9 919.1 985.3 986.3 988.0 991.2 992.1 976.8 977.0 979.9 981.2 981.5 713.4 733.9 736.4 736.8 741.9 14171.0 14443.1 14449.5 14449.7 14454.7 13991.3 14765.6 14856.4 14882.7 14940.9 11848.1 11950.8 11984.0 11987.6 12255.4 3742.7 3773.5 3823.5 3900.9 3958.4 90171.0 115562.8 115832.0 117257.2 117860.4 11188.8 11426.6 11465.5 11641.8 11747.9 23892.5 24315.3 24568.5 24768.3 24880.9 8010.5 8017.8 8111.4 8250.3 8300.5 4416.2 4515.9 4527.1 4533.5 4592.0 3496.8 3624.3 3627.6 3627.9 3639.1 618.5 636.3 645.1 647.6 658.2 542.5 636.9 649.7 666.8 689.3 9490.6 9659.7 9751.6 9776.3 9793.0 647.9 667.3 673.9 681.9 682.6 3450.4 3732.8 4334.8 5383.8 5520.9 159122.0 159918.0 160349.0 161527.0 162852.0 134594.0 134719.0 136451.0 142653.0 143113.1 94314.0 120747.0 123846.1 127406.0 129315.0 9704.7 10054.4 10062.3 10077.2 10098.3 47992.8 59854.0 60097.3 62247.0 62249.7 62470.0 77194.8 89625.0 90043.0 93036.6 9731.8 10971.6 12639.6 13138.3 13377.3 11442.9 13981.7 14399.6 14916.1 15080.4 11128.7 11319.9 13863.6 14227.4 14399.8 11604.0 14020.4 14027.6 14171.0 14190.0 12073.3 13056.1 13535.4 13729.9 14038.0 9617.3 10767.8 11470.7 11647.2 11998.5 7912.9 8583.7 9887.3 10797.5 11789.5 06:07:07 INFO - PROCESS | 1950 | -------- Summary: end -------- 06:07:07 INFO - PROCESS | 1950 | 06:07:08 INFO - TEST-INFO | 1950: exit 0 06:07:08 INFO - TEST-OK | dromaeo_css | took 1115318ms 06:07:08 INFO - TEST-START | kraken 06:07:08 INFO - operating with platform_type : mac_ 06:07:08 INFO - Initialising browser for kraken test... 06:07:08 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpQ7eOjY/profile http://localhost:54406/getInfo.html) 06:07:10 INFO - PROCESS | 1956 | 2016-10-17 06:07:10.684 plugin-container[1957:19526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 06:07:10 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:10 INFO - PROCESS | 1956 | 2016-10-17 06:07:10.689 plugin-container[1957:19526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.CFPasteboardClient' 06:07:10 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:10 INFO - PROCESS | 1956 | 2016-10-17 06:07:10.689 plugin-container[1957:19526] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:10 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 06:07:10 INFO - PROCESS | 1956 | colorDepth:24 06:07:10 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 06:07:10 INFO - PROCESS | 1956 | __metrics 06:07:10 ERROR - PROCESS | 1956 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 06:07:11 INFO - TEST-INFO | 1956: exit 0 06:07:11 INFO - Browser initialized. 06:07:11 INFO - Running cycle 1/1 for kraken test... 06:07:11 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpQ7eOjY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:13 INFO - PROCESS | 1958 | 2016-10-17 06:07:13.925 plugin-container[1959:19639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 06:07:13 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:13 INFO - PROCESS | 1958 | 2016-10-17 06:07:13.925 plugin-container[1959:19639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:07:13 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:13 INFO - PROCESS | 1958 | 2016-10-17 06:07:13.925 plugin-container[1959:19639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:23 INFO - PROCESS | 1958 | RSS: Main: 237137920 06:07:23 INFO - PROCESS | 1958 | 06:07:26 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54406/tests/kraken/driver.html?testName=audio-beat-detection) 06:07:27 INFO - PROCESS | 1958 | RSS: Main: 249819136 06:07:27 INFO - PROCESS | 1958 | 06:07:30 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54406/tests/kraken/driver.html?testName=audio-dft) 06:07:30 INFO - PROCESS | 1958 | RSS: Main: 258285568 06:07:30 INFO - PROCESS | 1958 | 06:07:34 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54406/tests/kraken/driver.html?testName=audio-fft) 06:07:34 INFO - PROCESS | 1958 | RSS: Main: 260468736 06:07:34 INFO - PROCESS | 1958 | 06:07:36 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54406/tests/kraken/driver.html?testName=audio-oscillator) 06:07:37 INFO - PROCESS | 1958 | RSS: Main: 262582272 06:07:37 INFO - PROCESS | 1958 | 06:07:39 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54406/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:07:39 INFO - PROCESS | 1958 | RSS: Main: 262856704 06:07:39 INFO - PROCESS | 1958 | 06:07:42 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54406/tests/kraken/driver.html?testName=imaging-darkroom) 06:07:43 INFO - PROCESS | 1958 | RSS: Main: 263856128 06:07:43 INFO - PROCESS | 1958 | 06:07:46 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54406/tests/kraken/driver.html?testName=imaging-desaturate) 06:07:46 INFO - PROCESS | 1958 | RSS: Main: 263917568 06:07:46 INFO - PROCESS | 1958 | 06:07:49 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54406/tests/kraken/driver.html?testName=json-parse-financial) 06:07:50 INFO - PROCESS | 1958 | RSS: Main: 264355840 06:07:50 INFO - PROCESS | 1958 | 06:07:51 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54406/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:07:52 INFO - PROCESS | 1958 | RSS: Main: 263319552 06:07:52 INFO - PROCESS | 1958 | 06:07:54 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:07:55 INFO - PROCESS | 1958 | RSS: Main: 263376896 06:07:55 INFO - PROCESS | 1958 | 06:07:57 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:07:57 INFO - PROCESS | 1958 | RSS: Main: 263413760 06:07:57 INFO - PROCESS | 1958 | 06:08:01 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:01 INFO - PROCESS | 1958 | RSS: Main: 263786496 06:08:01 INFO - PROCESS | 1958 | 06:08:04 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:05 INFO - PROCESS | 1958 | RSS: Main: 263987200 06:08:05 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:54406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54406/tests/kraken/driver.html?testName=ai-astar) 06:08:07 INFO - PROCESS | 1958 | RSS: Main: 265535488 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | __start_tp_report 06:08:07 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 06:08:07 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 06:08:07 INFO - PROCESS | 1958 | |i|pagename|runs| 06:08:07 INFO - PROCESS | 1958 | |0;ai-astar;108;119;118;95;169;176;135;115;119;116 06:08:07 INFO - PROCESS | 1958 | |1;audio-beat-detection;149;118;116;149;116;117;117;115;140;115 06:08:07 INFO - PROCESS | 1958 | |2;audio-dft;204;218;293;275;203;194;197;197;200;200 06:08:07 INFO - PROCESS | 1958 | |3;audio-fft;82;58;61;85;56;59;59;59;77;60 06:08:07 INFO - PROCESS | 1958 | |4;audio-oscillator;95;71;88;72;69;70;89;69;72;87 06:08:07 INFO - PROCESS | 1958 | |5;imaging-gaussian-blur;88;85;89;85;88;86;89;85;89;86 06:08:07 INFO - PROCESS | 1958 | |6;imaging-darkroom;109;134;101;108;95;96;108;94;119;152 06:08:07 INFO - PROCESS | 1958 | |7;imaging-desaturate;88;93;88;93;88;89;91;92;90;89 06:08:07 INFO - PROCESS | 1958 | |8;json-parse-financial;55;56;54;55;56;58;57;58;54;57 06:08:07 INFO - PROCESS | 1958 | |9;json-stringify-tinderbox;78;67;70;80;69;68;70;72;73;70 06:08:07 INFO - PROCESS | 1958 | |10;stanford-crypto-aes;91;82;80;109;122;112;78;111;117;108 06:08:07 INFO - PROCESS | 1958 | |11;stanford-crypto-ccm;165;162;144;164;159;169;164;130;219;170 06:08:07 INFO - PROCESS | 1958 | |12;stanford-crypto-pbkdf2;173;176;177;182;225;175;174;175;171;177 06:08:07 INFO - PROCESS | 1958 | |13;stanford-crypto-sha256-iterative;73;72;72;67;71;70;78;73;88;72 06:08:07 INFO - PROCESS | 1958 | __end_tp_report 06:08:07 INFO - PROCESS | 1958 | __start_cc_report 06:08:07 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,941 06:08:07 INFO - PROCESS | 1958 | __end_cc_report 06:08:07 INFO - PROCESS | 1958 | __startTimestamp1476709687504__endTimestamp 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | ------- Summary: start ------- 06:08:07 INFO - PROCESS | 1958 | Number of tests: 14 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#0] ai-astar Cycles:10 Average:127.00 Median:127.00 stddev:26.01 (20.5%) stddev-sans-first:26.66 06:08:07 INFO - PROCESS | 1958 | Values: 108.0 119.0 118.0 95.0 169.0 176.0 135.0 115.0 119.0 116.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:117.50 stddev:14.59 (12.4%) stddev-sans-first:12.68 06:08:07 INFO - PROCESS | 1958 | Values: 149.0 118.0 116.0 149.0 116.0 117.0 117.0 115.0 140.0 115.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#2] audio-dft Cycles:10 Average:218.10 Median:203.50 stddev:35.59 (17.5%) stddev-sans-first:37.38 06:08:07 INFO - PROCESS | 1958 | Values: 204.0 218.0 293.0 275.0 203.0 194.0 197.0 197.0 200.0 200.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#3] audio-fft Cycles:10 Average:65.60 Median:60.50 stddev:11.10 (18.3%) stddev-sans-first:10.06 06:08:07 INFO - PROCESS | 1958 | Values: 82.0 58.0 61.0 85.0 56.0 59.0 59.0 59.0 77.0 60.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:79.50 stddev:10.21 (12.8%) stddev-sans-first:8.83 06:08:07 INFO - PROCESS | 1958 | Values: 95.0 71.0 88.0 72.0 69.0 70.0 89.0 69.0 72.0 87.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.76 (2.0%) stddev-sans-first:1.83 06:08:07 INFO - PROCESS | 1958 | Values: 88.0 85.0 89.0 85.0 88.0 86.0 89.0 85.0 89.0 86.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:143.00 stddev:18.75 (13.1%) stddev-sans-first:19.86 06:08:07 INFO - PROCESS | 1958 | Values: 109.0 134.0 101.0 108.0 95.0 96.0 108.0 94.0 119.0 152.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#7] imaging-desaturate Cycles:10 Average:90.10 Median:90.50 stddev:2.02 (2.2%) stddev-sans-first:2.00 06:08:07 INFO - PROCESS | 1958 | Values: 88.0 93.0 88.0 93.0 88.0 89.0 91.0 92.0 90.0 89.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:1.49 (2.6%) stddev-sans-first:1.54 06:08:07 INFO - PROCESS | 1958 | Values: 55.0 56.0 54.0 55.0 56.0 58.0 57.0 58.0 54.0 57.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.00 stddev:4.24 (6.0%) stddev-sans-first:3.84 06:08:07 INFO - PROCESS | 1958 | Values: 78.0 67.0 70.0 80.0 69.0 68.0 70.0 72.0 73.0 70.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#10] stanford-crypto-aes Cycles:10 Average:101.00 Median:100.00 stddev:16.54 (16.5%) stddev-sans-first:17.14 06:08:07 INFO - PROCESS | 1958 | Values: 91.0 82.0 80.0 109.0 122.0 112.0 78.0 111.0 117.0 108.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#11] stanford-crypto-ccm Cycles:10 Average:164.60 Median:164.50 stddev:22.78 (13.8%) stddev-sans-first:24.16 06:08:07 INFO - PROCESS | 1958 | Values: 165.0 162.0 144.0 164.0 159.0 169.0 164.0 130.0 219.0 170.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:176.50 stddev:15.90 (9.0%) stddev-sans-first:16.64 06:08:07 INFO - PROCESS | 1958 | Values: 173.0 176.0 177.0 182.0 225.0 175.0 174.0 175.0 171.0 177.0 06:08:07 INFO - PROCESS | 1958 | 06:08:07 INFO - PROCESS | 1958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:72.50 stddev:5.76 (7.9%) stddev-sans-first:6.10 06:08:07 INFO - PROCESS | 1958 | Values: 73.0 72.0 72.0 67.0 71.0 70.0 78.0 73.0 88.0 72.0 06:08:07 INFO - PROCESS | 1958 | -------- Summary: end -------- 06:08:07 INFO - PROCESS | 1958 | 06:08:08 INFO - TEST-INFO | 1958: exit 0 06:08:08 INFO - TEST-OK | kraken | took 60205ms 06:08:08 INFO - SUITE-END | took 1175s 06:08:08 INFO - Completed test suite (00:19:35) 06:08:08 INFO - javascript benchmark 06:08:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7319.039709270374, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3070.846457677116, 3281.5077738339246, 3320.800751954883, 3345.866165353386, 3379.949300760489, 5491.780328786854, 5551.777928882845, 5579.86050348741, 5589.441055894414, 5645.887082258352, 4375.64994800416, 4694.765261736917, 5557.083081291582, 5642.633228840123, 5644.294463192101, 4890.535399137094, 5102.795888164469, 5122.462141475129, 5249.947500525003, 5281.0, 5100.9489905100845, 5284.894302113975, 5300.125479295912, 5325.627206095575, 5366.0, 5080.288759573663, 5121.718305493197, 5192.376914770214, 5203.453637368059, 5267.947320526803, 4211.810468528924, 4434.623057040156, 5075.213341932006, 5161.896762064775, 5254.448282930313, 5124.231365295198, 5162.22566615007, 5182.144946083891, 5197.220416937452, 5271.525562699337, 4999.0501804657, 5266.7366631668265, 5525.668459892379, 5733.340665823442, 5740.712964351766, 5492.203630473599, 6007.219061522018, 6110.255008202027, 6135.509159267248, 6213.347598502138, 6178.165947597062, 6246.219222597176, 6268.059791031336, 6283.7800676976085, 6285.120083188357, 5728.484436400744, 5747.195392645033, 5764.250647415809, 5764.769409223584, 5805.158252053428, 6144.126041557279, 6152.0, 6158.876822463526, 6186.319504854462, 6187.319394866561, 6076.54425918049, 6100.157317757059, 6100.702285529923, 6104.084387341934, 6116.396640261069, 6017.06735456005, 6138.877222455526, 6199.690015499207, 6214.689265536796, 6231.626102433868, 4990.2764099205415, 6000.669963151981, 6036.667983260875, 6121.173641558422, 6165.229346331708, 5084.694918304914, 6126.571140020156, 6166.321704612489, 6171.197744293303, 6178.196834411498, 4871.074495845778, 4882.975585122052, 4887.437944636412, 4891.461939186686, 4916.311716359713, 3743.887683369503, 4327.437433133672, 4331.177076355482, 4383.430154079949, 4437.977810110993, 5630.183623374588, 5642.0, 5657.4908258256955, 5666.320041594952, 5676.035074037423, 6184.319724830265, 6221.751129954841, 6259.436650701367, 6290.24517057956, 6301.0, 4668.5798278155125, 5526.171074338881, 5606.074997625427, 5641.8589535261945, 5653.23681303027, 5405.108157154025, 5416.377116631637, 5434.239206511092, 5441.020616289027, 5462.235287059815, 6299.307076221612, 6303.0, 6305.4009869062365, 6326.905096423558, 6331.620102793847, 6116.633002019893, 6141.332587233645, 6180.196574445294, 6280.717367718372, 6319.368063193644, 6251.337830101872, 6271.78048768291, 6275.905861412174, 6298.685065746603, 6312.684365781601, 6150.077488376779, 6178.876422471435, 6189.876202476015, 6202.193714817045, 6228.4705800007505, 6100.115483254992, 6127.647695153045, 6290.477332693707, 6294.5279104068295, 6298.212723409574, 6007.8197654070445, 6024.186734790747, 6052.394760523913, 6121.331035925228, 6134.957077260823, 5936.0, 6018.518518518596, 6056.333803281547, 6118.694065296806, 6150.261968563801, 5700.030994730822, 5831.504322132666, 5888.263967004124, 5917.852053698692, 5988.461038506555, 6040.697965101639, 6117.877642447037, 6120.877582448237, 6126.927787637056, 6142.539309551712, 5953.077273022688, 5954.523638108854, 5978.880422391441, 5988.820335389945, 5996.100584912124], "value": 5606.300547125114, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.60049558370974, 496.1639637211299, 508.4102441168241, 510.93979991517597, 532.2468706779906, 40732.79633601835, 42379.364309535384, 42420.727378178664, 42421.0, 42639.36040959384, 43452.78273608635, 43626.34560481595, 43731.90670233246, 43773.34339984895, 43892.12215755691, 9491.6203351866, 9660.855087173699, 9664.178544823699, 9695.951520242388, 9732.805343893118, 9472.621095156186, 9567.473788941605, 9573.425594464354, 9584.137427631511, 9595.992420795821, 9552.71341859742, 9614.086661767122, 9623.759406014835, 9630.903690963105, 9646.228301735879, 8010.27907488323, 9532.189763870045, 9545.188658963996, 9582.90417095827, 9587.280953928448, 8611.784705382353, 9489.383190092622, 9501.952490237505, 9522.714318570454, 9529.666461673878, 41319.7934010328, 41336.793316033225, 41495.585044149775, 41503.7924810377, 41550.58449415497, 3325.833708314599, 3437.535932649085, 3600.981995090034, 3605.0446631642635, 3658.5426821647293, 24495.02019919201, 29724.0, 31103.533446998314, 31347.686523134933, 32208.516872246935, 2454.5459090068393, 3137.62348518177, 3218.7746857719967, 3259.7555183361105, 3307.371599396107, 479216.0, 480489.0, 485290.0, 485625.14374856505, 486343.56828215986, 60437.18688439354, 60601.0, 60650.0, 60707.696461518295, 60726.4818379544, 63844.0, 64060.039099413545, 64261.67869160624, 64306.0, 64471.35528644654, 16541.84207105491, 16755.497335079966, 16773.58066048334, 16780.328786848633, 16790.58023549421, 12287.508499660093, 12304.692382690504, 12333.260004399765, 12340.568080117153, 12341.136120471481, 15024.474143404928, 16781.0, 17013.48959531216, 17081.914590426968, 17100.0594967278, 2645.5105805426056, 2755.5728862026417, 6109.816705498753, 6823.351781580742, 6900.758473453506, 841.869510225916, 900.2797761790672, 966.0822218892081, 1072.5763323487179, 1111.927724697892, 23936.0, 26886.5967010495, 30466.08601741907, 30676.38647227043, 31313.904013359424, 935.2410389351305, 955.8422860227985, 992.6228033347281, 1017.1659239423601, 1110.5113749949996, 15420.537383878502, 15879.206039697969, 15944.963577367434, 15987.600309992344, 16323.0, 692944.0, 700616.0, 701234.0, 704476.0, 709529.0, 58183.41816581865, 58550.0, 58574.7071264641, 58674.11988820171, 58736.11895821566, 1349.8323949783367, 1397.5457976157586, 1403.410567561606, 1411.5976946570179, 1412.3573773932835, 26128.0, 26882.462350752485, 29790.80836766592, 30396.544051839683, 30438.086857394315, 50876.745616271684, 54242.728786357395, 56985.715071426035, 57018.0, 60193.39806601878, 641393.0, 648733.0, 650064.0, 652253.0, 654651.0, 25827.837747301794, 42630.65531395803, 42736.0, 42809.5719042818, 42905.57094429016, 44852.77573612111, 45130.77434612806, 45531.54468455406, 45709.31436028529, 46403.53596464127, 37457.43813842849, 37479.87560373193, 37575.0, 38778.80610597042, 39044.41433378445, 37903.43144852774, 38082.0, 39245.6075439242, 39627.0, 40037.39943900902, 36322.4551631731, 37066.62933370632, 37542.624573755005, 37723.0, 38529.422058669705, 33820.830895846346, 34166.658333416344, 34498.0, 34631.0, 34656.0, 23212.883935580216, 24909.377265567782, 24993.000279988595, 25149.24552263435, 25632.615510767726], "value": 20012.790959115013, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.8186143205533, 377.5299751808997, 381.8568036986129, 383.5550761117103, 386.84911889892186, 19377.903110484447, 19925.40223793285, 20155.899220503914, 20550.177992880286, 20714.171433142696, 9800.705978820646, 11737.589184378548, 11916.821247681291, 12108.33404162773, 12135.514579416813, 4824.7105173689515, 5221.738913054351, 5432.782688692448, 5609.57928155388, 5613.158026296068, 3944.3097457944746, 4167.249895018896, 5449.809256676017, 5591.30108736408, 7523.623818809065, 7533.35966442853, 8107.391945604103, 8144.51132932023, 8179.304759095484, 8214.712485063024, 8001.759947201594, 8096.595170241523, 8103.473274237214, 8114.9594252028655, 8140.674373025072, 7497.0, 7704.922950770477, 7776.377889768805, 7779.144294127642, 7810.0237470316215, 28623.57064644032, 28787.28031799201, 28960.855195723885, 28978.42043159146, 29064.0, 2350.717913850349, 2456.9754302457104, 2552.1577879299784, 2599.272203782944, 3034.2111051126644, 16404.0, 16606.25271862757, 16694.248758805883, 17366.56583585408, 17658.823411765847, 1814.872958892878, 1933.2943475631434, 1960.6176795524877, 1983.9206431742714, 2075.6575165097743, 86104.56947715284, 108422.0, 108750.0, 140309.0, 142571.14857702891, 9328.39365441244, 9926.503674816231, 9941.602335906628, 9953.402795832273, 21723.239686610894, 12307.4461649226, 12335.93832030852, 12355.93822030902, 12361.134720569653, 12432.813507797391, 8076.3942704297415, 8181.427300088937, 8183.631736571869, 8239.258466738022, 8329.12544182852, 8544.855744963688, 8597.52713600758, 8619.310455163572, 8780.912190878138, 8789.780255493535, 3921.098147426108, 3940.192260586573, 3947.4078888167005, 3968.761874287552, 4014.0767623446186, 1501.5045035138378, 1716.48505448365, 1854.840724547158, 1913.8086191381028, 1916.6837471817273, 855.3713020929612, 887.5118684723376, 910.8360495110811, 938.1650331205233, 948.9335746497679, 15747.448839290799, 19697.113629886688, 20439.693404598944, 21298.574028519342, 21350.57298854014, 883.3330835219368, 892.5091199840062, 914.8902131744101, 942.9669961551313, 954.2080073538946, 9107.408018478778, 11009.724756881142, 11095.278806877526, 11386.658400248005, 11406.885931140583, 463759.0, 466584.0, 530754.3462282742, 597117.0, 603665.9816700888, 78584.60707696425, 87636.0, 115311.42344288225, 121526.0, 139739.12217461425, 928.9860652090224, 954.737447202025, 977.1547611316303, 1026.0867827633413, 1032.5250384822916, 19676.50808729793, 19980.90009549943, 20224.797752022292, 20250.392488225378, 20282.188712451338, 37693.81153094217, 51209.463716088576, 53557.0, 58154.7092264536, 58333.708331458074, 68974.0, 79476.41047178909, 81999.5900020496, 83511.0, 84382.57808710917, 7760.223977602194, 8029.558374289237, 8078.7172448964, 8232.300254478436, 8343.749687509384, 8378.0, 9259.305552083486, 10543.94728026355, 12742.299173545172, 13133.408996594984, 10731.517081731185, 11169.888301116885, 11619.070474361863, 11670.416479176178, 11781.882181178078, 11206.43967801623, 11427.314361138358, 11441.656750297505, 11748.17762756633, 12061.51753929833, 11494.655160345203, 11685.591004314809, 11846.289222646668, 11886.34625095628, 11998.820017699567, 5847.415258474119, 8106.756797296091, 8386.580670966548, 8470.745877623682, 8535.061143274359, 7414.777556673308, 10401.53193106297, 11509.942450287695, 11706.941465292619, 11827.994620457013], "value": 9633.399124468866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1552.4799192270584, 1568.647054412757, 1833.834954854063, 1868.9532761680948, 1952.0239880059978, 6641.667916604175, 7961.7213397531095, 8226.218509241628, 9120.087991200877, 9120.680776172827, 8511.404201705884, 8788.25299849512, 8795.076516965706, 8902.418298693387, 8937.374383793138, 2335.708036495438, 2347.424880904181, 2362.7123217846315, 2367.2188177901253, 2370.8814559272055, 2301.1255722825304, 2321.570509455756, 2346.3195673254736, 2350.236173243694, 2350.7061617297836, 2306.850054746436, 2307.826912981525, 2326.4067662745974, 2328.8253380996407, 2329.0, 2290.4960908600083, 2311.838171328008, 2311.90752369905, 2322.419395151212, 2324.604817181075, 2316.733575638806, 2319.884005799703, 2322.93031209064, 2330.697009388786, 2333.661619065226, 8809.03100658927, 8943.731688049304, 9012.0086790453, 9012.909870901338, 9046.004939456654, 5216.3218781558535, 5384.91922621161, 5410.91883621746, 5415.891682166334, 5422.566194704454, 3100.410921924827, 3454.6890779829937, 3563.610192025112, 3639.727020473475, 3655.7623754455876, 910.7131253655143, 1049.3769324463549, 1081.475484390078, 1084.6637542361893, 1103.8178700514407, 9732.805343893153, 10226.590936362536, 10367.948160259226, 11533.019693325994, 11668.416579171093, 4088.97775556111, 4597.586217240464, 4622.329762184464, 4825.927611085836, 4836.854894353175, 9897.80204395908, 9957.601695932226, 9962.651307204213, 9973.451460169616, 9992.700218993441, 5301.363836339664, 5366.436524164985, 5367.604422850009, 5378.2470454136455, 5386.649867758583, 4392.31919052541, 4534.478534968521, 4755.120302744003, 4882.926756098661, 4921.975390123026, 3909.198614284122, 3916.6083391661177, 3979.3633018716864, 4002.039510517455, 4059.837606495707, 1284.8265484159706, 1287.9678008049873, 1289.265118882247, 1298.3897568142959, 1308.9214647121203, 799.4044436894435, 843.3674743942044, 843.3969711656196, 903.4714691905177, 962.4706411473801, 5775.653460792282, 5816.505597024216, 5825.417458254141, 5996.550258730613, 6094.329623741385, 423.8919075635755, 428.87991362418575, 433.26561478294616, 440.3856620015125, 443.4479073553447, 3911.4523966644692, 3982.482277303932, 3986.740861843971, 4005.3591425372383, 4022.4770779798437, 10251.641192558225, 10691.984261495145, 10697.144228461704, 10866.348019118877, 10868.402237876835, 10820.13438924884, 10829.783404331869, 10832.24174307791, 10833.83749243762, 10845.457727113771, 8079.636416361278, 8246.299064579458, 8265.710700125346, 8287.875681864776, 8304.169583041648, 5744.798932037359, 5951.851203719942, 6093.268807743099, 6103.542234332354, 6170.876582468415, 9200.95399522998, 9987.850182247126, 10118.443485608068, 10132.392056476636, 10149.949250253701, 6992.895106573304, 9039.322050846082, 9407.294452915921, 10119.241056920908, 10344.689659310232, 6018.458338749533, 6077.997130473423, 6975.337342952412, 7558.433117516099, 7696.884546731915, 7147.892076728115, 8247.0, 8425.199606037417, 8565.57172141403, 8572.828543429221, 7519.736809211651, 7594.278543538355, 7635.770926872203, 7663.425243106678, 7741.148473668005, 7352.154502232312, 7362.6686799093095, 7404.185539590749, 7528.284812942762, 7576.962115189609, 5989.580729348903, 6967.477439191979, 7112.395446386908, 7138.286171382821, 7207.279272072751, 4247.745135291892, 5061.240813877947, 5117.769700363567, 5170.353705786777, 5184.429712731672, 4941.0, 4997.075541024922, 5017.397912250553, 5114.2840002400435, 5200.531952124327], "value": 4464.9046989188555, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.2543640897757, 307.2579719976257, 316.19055218640284, 320.10051754569645, 322.701501111472, 4982.402111746586, 5001.774920128594, 5479.917801232985, 5558.610897237195, 5567.944320556788, 5340.652857564265, 5385.623006389555, 5444.428335024825, 5522.950639378515, 5528.530074943625, 4166.354215096657, 4168.77021278722, 4179.164167166564, 4180.745776267123, 4181.414601955728, 3634.67287944085, 4082.020220193204, 4138.255114079465, 4162.375643653446, 4197.580241975793, 4078.7552746835286, 4129.339305711087, 4161.230172418097, 4165.875403544184, 4174.0, 3723.6648701616987, 4079.306517891936, 4151.3150330195485, 4183.832646694129, 4201.4538110045805, 2761.7710118997056, 3489.0, 3639.6724294813594, 3645.2527231917393, 3655.214128962286, 4238.980647480555, 4780.330753694486, 4965.510346895953, 5141.041398123424, 5466.699331536764, 3720.330340538702, 4257.87226383209, 4548.772561371951, 4712.198926182557, 4764.857054288343, 5711.514521265697, 5750.30996280449, 5787.8424315137145, 5805.606654402955, 5829.300483941912, 3591.586967498745, 3620.2759448110482, 3756.69946404287, 3861.2856621524825, 3975.980120099381, 4930.8520744377365, 5645.576581756384, 5864.5894787364905, 5944.195714742568, 5963.821085367403, 3505.4566542186003, 3883.310759819431, 3885.6000999125763, 3921.176552923861, 3925.2738243425056, 3336.1484376170974, 3511.754177207571, 3821.961780382216, 3986.1828325193264, 3992.7604343739467, 4134.669226461876, 4665.510121437268, 4670.252759558454, 4678.228092364721, 4681.344611754357, 4650.465196502378, 5664.971675141598, 5743.62666426681, 5747.827565173052, 5830.854228644318, 1114.788190243851, 1272.3320256865036, 1345.5088892554147, 1353.499205294028, 1354.4852955876704, 700.5411455496658, 749.5727435361791, 816.1348970091722, 824.6083110522454, 830.9127541608165, 3838.731288809812, 3930.7051971102283, 3971.920561588752, 4570.611498022706, 4597.908041839211, 5026.0, 5027.99440111979, 5045.823396181116, 5054.09026375256, 5056.699201863144, 4137.40007698882, 4479.596836284685, 4579.992401671627, 4586.403767510269, 4590.839320623762, 2257.7064981552294, 2278.259565641173, 2590.8575028373243, 2595.065776320523, 2663.0, 4596.448426188878, 4739.502352253033, 5046.417043898799, 5055.205402082208, 5075.670081444694, 4692.718436893797, 4843.813265749914, 4988.60546937472, 5010.394594810678, 5153.845384638467, 5103.87240318995, 5284.947150528523, 5484.911378097438, 6053.3946605339115, 6226.221722284715, 5065.3161823153405, 5395.001924643953, 6007.369226231182, 6020.51835853122, 6033.8535678221, 4583.510359133362, 4987.251912213197, 5278.736063196901, 5746.913796292975, 5921.526277897672, 5824.301083869963, 5947.197128387612, 6176.320604733565, 6199.1011303359855, 6258.405451482102, 3293.3084052349277, 3464.9307013860084, 3956.6043395660204, 3989.0, 3989.8005099744314, 4976.2286845539, 5708.57185711065, 5942.266557383652, 5946.583739138218, 5981.431763982415, 4425.645948324191, 5542.972285138549, 5659.462351076641, 5730.541556675373, 5749.712514374348, 5148.382194136728, 5792.536597072309, 5840.678762668094, 5877.764889404376, 5927.911081333869, 5437.238786569963, 5575.721213939206, 5601.215829783915, 5626.774929002794, 5645.689487078086, 4592.645169675021, 4804.5195480451675, 5442.972785136049, 5711.714414279352, 5787.148112603926, 4627.93058104122, 4650.0, 5580.5535557156145, 5605.411431799603, 5622.606417550732], "value": 4009.142150982015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.9348458638722, 734.060402684564, 777.8172129549558, 778.928972662589, 793.5635400529709, 15311.157886316249, 16282.185890705476, 16286.185690715447, 16324.938878972873, 16345.0, 14687.192204428753, 14716.779248311284, 14836.925815370934, 14863.628409289773, 14910.627234319147, 639.1467391032977, 751.6692655231708, 854.521677497929, 888.7200531832484, 922.8569571716395, 901.4591245252861, 903.8824952756165, 909.3498148823589, 918.857577075551, 919.0809190809191, 985.3053597213983, 986.2701600815417, 987.9604815807359, 991.1971303244359, 992.092235604421, 976.7753416714124, 976.9609215631366, 979.9118079372855, 981.2444417798256, 981.5484876956644, 713.4292565947221, 733.9119305683353, 736.4108713029555, 736.8084298082483, 741.910970683516, 14171.008029437944, 14443.133411995208, 14449.494267700686, 14449.711005779931, 14454.71090578193, 13991.300434978311, 14765.557033289018, 14856.405743770236, 14882.702345953021, 14940.925295373563, 11848.052155827512, 11950.820737688942, 11984.0, 11987.64037078889, 12255.448504817216, 3742.6631603155574, 3773.5471743390694, 3823.5220597425323, 3900.8829735107706, 3958.4458175855116, 90171.0, 115562.84437155604, 115832.0, 117257.24114138295, 117860.41069794682, 11188.832167517494, 11426.649985907019, 11465.541378344773, 11641.825372619418, 11747.88252117485, 23892.522149556913, 24315.270541883772, 24568.508629827662, 24768.25695229146, 24880.880360383828, 8010.479318844256, 8017.839643207103, 8111.351091912633, 8250.308686719209, 8300.460470069425, 4416.24923762961, 4515.909681806411, 4527.139843429738, 4533.5013148553635, 4591.977040114845, 3496.8426420811124, 3624.257027309395, 3627.619099994515, 3627.927441451209, 3639.1266096136737, 618.5268269773627, 636.328673249726, 645.0936434309803, 647.6308504152588, 658.1871388834785, 542.5090293284584, 636.8918082536353, 649.7498463091744, 666.776629829003, 689.3210187964851, 9490.620375184915, 9659.71020869375, 9751.609935602639, 9776.315657903879, 9792.951035244922, 647.8727014993971, 667.3493343989571, 673.8618583190434, 681.8602186551745, 682.5938566552893, 3450.4306789379975, 3732.8133593320767, 4334.848280310174, 5383.81156659515, 5520.91718624221, 159122.0, 159918.0, 160349.0, 161527.0, 162852.0, 134594.0, 134719.0, 136451.0, 142653.0, 143113.13773724466, 94313.99999999863, 120747.0, 123846.14230786545, 127406.0, 129315.0, 9704.660336888175, 10054.396736195851, 10062.345947513388, 10077.193824493876, 10098.293119481566, 47992.760036199594, 59854.0, 60097.295621696256, 62247.0, 62249.68875155595, 62470.0, 77194.84207736776, 89625.0, 90043.0, 93036.60445093193, 9731.756706082404, 10971.561137554409, 12639.620811375673, 13138.343082846011, 13377.264250466318, 11442.94278528602, 13981.650458738613, 14399.640008999859, 14916.105033698013, 15080.396784128512, 11128.666140015812, 11319.943400283275, 13863.584092477242, 14227.430902763774, 14399.78400324017, 11604.0, 14020.439182432588, 14027.579172624837, 14171.0, 14190.0, 12073.335966521925, 13056.086073974731, 13535.390907409386, 13729.931350343584, 14038.0, 9617.326787124834, 10767.784644307227, 11470.71323216893, 11647.184697071123, 11998.520059197534, 7912.9208707912185, 8583.699570515002, 9887.258455615714, 10797.460126993461, 11789.469473873525], "value": 7944.176975163081, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 118.0, 95.0, 169.0, 176.0, 135.0, 115.0, 119.0, 116.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 116.0, 149.0, 116.0, 117.0, 117.0, 115.0, 140.0, 115.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 218.0, 293.0, 275.0, 203.0, 194.0, 197.0, 197.0, 200.0, 200.0], "value": 218.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 61.0, 85.0, 56.0, 59.0, 59.0, 59.0, 77.0, 60.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 71.0, 88.0, 72.0, 69.0, 70.0, 89.0, 69.0, 72.0, 87.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 88.0, 86.0, 89.0, 85.0, 89.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 134.0, 101.0, 108.0, 95.0, 96.0, 108.0, 94.0, 119.0, 152.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 88.0, 93.0, 88.0, 89.0, 91.0, 92.0, 90.0, 89.0], "value": 90.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 55.0, 56.0, 58.0, 57.0, 58.0, 54.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 67.0, 70.0, 80.0, 69.0, 68.0, 70.0, 72.0, 73.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 82.0, 80.0, 109.0, 122.0, 112.0, 78.0, 111.0, 117.0, 108.0], "value": 101.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 144.0, 164.0, 159.0, 169.0, 164.0, 130.0, 219.0, 170.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 177.0, 182.0, 225.0, 175.0, 174.0, 175.0, 171.0, 177.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 67.0, 71.0, 70.0, 78.0, 73.0, 88.0, 72.0], "value": 73.6, "unit": "score"}]}]} 06:08:08 INFO - Return code: 0 06:08:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:08:08 ERROR - # TBPL SUCCESS # 06:08:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:08 INFO - 2016-10-17 06:08:08.832 screenresolution[1963:20016] starting screenresolution argv=screenresolution get 06:08:08 INFO - 2016-10-17 06:08:08.834 screenresolution[1963:20016] Display 0: 1600x1200x32@60 06:08:08 INFO - 2016-10-17 06:08:08.840 screenresolution[1964:20019] starting screenresolution argv=screenresolution list 06:08:08 INFO - Available Modes on Display 0 06:08:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:09 INFO - Intel Iris: 06:08:09 INFO - Chipset Model: Intel Iris 06:08:09 INFO - Type: GPU 06:08:09 INFO - Bus: Built-In 06:08:09 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:09 INFO - Vendor: Intel (0x8086) 06:08:09 INFO - Device ID: 0x0a2e 06:08:09 INFO - Revision ID: 0x0009 06:08:09 INFO - Displays: 06:08:09 INFO - Display: 06:08:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:09 INFO - Display Serial Number: 1600x1200 60 06:08:09 INFO - Main Display: Yes 06:08:09 INFO - Mirror: Off 06:08:09 INFO - Online: Yes 06:08:09 INFO - Rotation: Supported 06:08:09 INFO - Return code: 0 06:08:09 INFO - Running post-action listener: _resource_record_post_action 06:08:09 INFO - [mozharness: 2016-10-17 13:08:09.075879Z] Finished run-tests step (success) 06:08:09 INFO - Running post-run listener: _resource_record_post_run 06:08:09 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 1566208; Write bytes: 711441920; Read time: 16; Write time: 2105 06:08:09 INFO - TinderboxPrint: CPU usage
54.8% 06:08:09 INFO - TinderboxPrint: I/O read bytes / time
1,566,208 / 16 06:08:09 INFO - TinderboxPrint: I/O write bytes / time
711,441,920 / 2,105 06:08:09 INFO - TinderboxPrint: CPU idle
2,191.8 (45.2%) 06:08:09 INFO - TinderboxPrint: CPU system
983.4 (20.3%) 06:08:09 INFO - TinderboxPrint: CPU user
1,670.6 (34.5%) 06:08:09 INFO - TinderboxPrint: Swap in / out
408,707,072 / 0 06:08:09 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393355776; Write bytes: 387589632; Read time: 29052; Write time: 724 06:08:09 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1566208; Write bytes: 300204032; Read time: 16; Write time: 1324 06:08:09 INFO - Running post-run listener: _upload_blobber_files 06:08:09 INFO - Blob upload gear active. 06:08:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:08:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:08:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:10 INFO - (blobuploader) - INFO - Done attempting. 06:08:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:08:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:08:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:11 INFO - (blobuploader) - INFO - Done attempting. 06:08:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:08:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:08:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:14 INFO - (blobuploader) - INFO - Done attempting. 06:08:14 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:14 INFO - Return code: 0 06:08:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cfe67f374d65a935835bcec88d9f2d9a3cbefcc45be6d33332a3342f0fc33bcf2079f13c9be48c5691cee52e1833b9f78f975111b2d22eee906c29b274aadcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb496dd02cbca8c7589e225c30e54b37f3c200ae24873c3e655f96ef3fa2c9eeaf4fb650c5acb5ce2b8c063b79ebcceb01f4859b15e014970797f45e92ab04e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9348fca4a2696ca1f26fdf399a10ddffd36342ea0bba23450b1d1969bc4ba0c3bf743139885976d5546ffd50abe9bf9167c67342104ae7ee2472cdcdcc923c6b"} 06:08:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:14 INFO - Contents: 06:08:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cfe67f374d65a935835bcec88d9f2d9a3cbefcc45be6d33332a3342f0fc33bcf2079f13c9be48c5691cee52e1833b9f78f975111b2d22eee906c29b274aadcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb496dd02cbca8c7589e225c30e54b37f3c200ae24873c3e655f96ef3fa2c9eeaf4fb650c5acb5ce2b8c063b79ebcceb01f4859b15e014970797f45e92ab04e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9348fca4a2696ca1f26fdf399a10ddffd36342ea0bba23450b1d1969bc4ba0c3bf743139885976d5546ffd50abe9bf9167c67342104ae7ee2472cdcdcc923c6b"} 06:08:14 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:14 INFO - Copying logs to upload dir... 06:08:14 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.420370 ========= master_lag: 3.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-17 06:08:17.664709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 06:08:17.671486) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZbwbHJhfo/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cfe67f374d65a935835bcec88d9f2d9a3cbefcc45be6d33332a3342f0fc33bcf2079f13c9be48c5691cee52e1833b9f78f975111b2d22eee906c29b274aadcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb496dd02cbca8c7589e225c30e54b37f3c200ae24873c3e655f96ef3fa2c9eeaf4fb650c5acb5ce2b8c063b79ebcceb01f4859b15e014970797f45e92ab04e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9348fca4a2696ca1f26fdf399a10ddffd36342ea0bba23450b1d1969bc4ba0c3bf743139885976d5546ffd50abe9bf9167c67342104ae7ee2472cdcdcc923c6b"} build_url:https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/NoXEHwksRqqR-60PYTRmkg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cfe67f374d65a935835bcec88d9f2d9a3cbefcc45be6d33332a3342f0fc33bcf2079f13c9be48c5691cee52e1833b9f78f975111b2d22eee906c29b274aadcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb496dd02cbca8c7589e225c30e54b37f3c200ae24873c3e655f96ef3fa2c9eeaf4fb650c5acb5ce2b8c063b79ebcceb01f4859b15e014970797f45e92ab04e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9348fca4a2696ca1f26fdf399a10ddffd36342ea0bba23450b1d1969bc4ba0c3bf743139885976d5546ffd50abe9bf9167c67342104ae7ee2472cdcdcc923c6b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 06:08:17.717124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 06:08:17.717541) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oZbwbHJhfo/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.2bwH00K4H1/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005329 ========= master_lag: 0.40 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 06:08:18.124447) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 06:08:18.124881) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 06:08:18.125678) ========= ========= Total master_lag: 3.89 =========