builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0254 starttime: 1484782743.26 results: success (0) buildid: 20170118143333 builduid: e2dfc39c4d2943feb3efe792b80a5e4a revision: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.258636) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.259051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.259321) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.285941) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.286322) ========= 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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 --2017-01-18 15:39:03-- 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% 43.0M=0s 2017-01-18 15:39:03 (43.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135521 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.446311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.446712) ========= 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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.026386 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:03.495947) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-18 15:39:03.496308) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w2c4DL9ErN/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.puJ6mmyT0X/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 2017-01-18 15:39:03,601 truncating revision to first 12 chars 2017-01-18 15:39:03,602 Setting DEBUG logging. 2017-01-18 15:39:03,602 attempt 1/10 2017-01-18 15:39:03,602 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ed6e5d97950a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-18 15:39:05,525 unpacking tar archive at: mozilla-aurora-ed6e5d97950a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.196042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-18 15:39:05.715997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:05.716343) ========= script_repo_revision: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:05.716763) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:05.717040) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 15:39:05.736930) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-18 15:39:05.737331) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w2c4DL9ErN/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.puJ6mmyT0X/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:05 INFO - MultiFileLogger online at 20170118 15:39:05 in /builds/slave/test 15:39:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:39:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:05 INFO - {'append_to_log': False, 15:39:05 INFO - 'base_work_dir': '/builds/slave/test', 15:39:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:39:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:05 INFO - 'branch': 'Mozilla-Aurora', 15:39:05 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:05 INFO - 'config_files': ('talos/mac_config.py',), 15:39:05 INFO - 'default_actions': ('clobber', 15:39:05 INFO - 'read-buildbot-config', 15:39:05 INFO - 'download-and-extract', 15:39:05 INFO - 'populate-webroot', 15:39:05 INFO - 'create-virtualenv', 15:39:05 INFO - 'install', 15:39:05 INFO - 'run-tests'), 15:39:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:05 INFO - 'download_minidump_stackwalk': True, 15:39:05 INFO - 'download_symbols': 'ondemand', 15:39:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:05 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:05 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:05 INFO - 'installer_path': 'installer.exe', 15:39:05 INFO - 'log_level': 'info', 15:39:05 INFO - 'log_name': 'talos', 15:39:05 INFO - 'log_to_console': True, 15:39:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:39:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:39:05 INFO - 'opt_config_files': (), 15:39:05 INFO - 'pip_index': False, 15:39:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:05 INFO - 'cmd': ('bash', 15:39:05 INFO - '-c', 15:39:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:05 INFO - 'enabled': True, 15:39:05 INFO - 'halt_on_failure': False, 15:39:05 INFO - 'name': 'check_screen_resolution'},), 15:39:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:05 INFO - 'cmd': ('bash', 15:39:05 INFO - '-c', 15:39:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:05 INFO - 'enabled': True, 15:39:05 INFO - 'halt_on_failure': False, 15:39:05 INFO - 'name': 'check_screen_resolution'},), 15:39:05 INFO - 'run_cmd_checks_enabled': True, 15:39:05 INFO - 'sps_profile': False, 15:39:05 INFO - 'sps_profile_interval': 0, 15:39:05 INFO - 'suite': 'dromaeojs-e10s', 15:39:05 INFO - 'system_bits': '32', 15:39:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:05 INFO - 'title': 't-yosemite-r7-0254', 15:39:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:05 INFO - 'use_talos_json': True, 15:39:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:05 INFO - 'work_dir': 'build'} 15:39:05 INFO - [mozharness: 2017-01-18 23:39:05.951483Z] Running clobber step. 15:39:05 INFO - Running pre-action listener: _resource_record_pre_action 15:39:05 INFO - Running main action method: clobber 15:39:05 INFO - rmtree: /builds/slave/test/build 15:39:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:08 INFO - Running post-action listener: _resource_record_post_action 15:39:08 INFO - [mozharness: 2017-01-18 23:39:08.252669Z] Finished clobber step (success) 15:39:08 INFO - [mozharness: 2017-01-18 23:39:08.252791Z] Running read-buildbot-config step. 15:39:08 INFO - Running pre-action listener: _resource_record_pre_action 15:39:08 INFO - Running main action method: read_buildbot_config 15:39:08 INFO - Using buildbot properties: 15:39:08 INFO - { 15:39:08 INFO - "project": "", 15:39:08 INFO - "product": "firefox", 15:39:08 INFO - "script_repo_revision": "production", 15:39:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:39:08 INFO - "repository": "", 15:39:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:39:08 INFO - "buildid": "20170118143333", 15:39:08 INFO - "pgo_build": "False", 15:39:08 INFO - "basedir": "/builds/slave/test", 15:39:08 INFO - "buildnumber": 41, 15:39:08 INFO - "slavename": "t-yosemite-r7-0254", 15:39:08 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:39:08 INFO - "platform": "yosemite_r7", 15:39:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:39:08 INFO - "branch": "mozilla-aurora", 15:39:08 INFO - "repo_path": "releases/mozilla-aurora", 15:39:08 INFO - "revision": "ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b", 15:39:08 INFO - "stage_platform": "macosx64", 15:39:08 INFO - "builduid": "e2dfc39c4d2943feb3efe792b80a5e4a", 15:39:08 INFO - "slavebuilddir": "test" 15:39:08 INFO - } 15:39:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:39:08 INFO - Running post-action listener: _resource_record_post_action 15:39:08 INFO - [mozharness: 2017-01-18 23:39:08.256156Z] Finished read-buildbot-config step (success) 15:39:08 INFO - [mozharness: 2017-01-18 23:39:08.256288Z] Running download-and-extract step. 15:39:08 INFO - Running pre-action listener: _resource_record_pre_action 15:39:08 INFO - Running main action method: download_and_extract 15:39:08 INFO - mkdir: /builds/slave/test/build/tests 15:39:08 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')]} 15:39:08 INFO - trying https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:39:08 INFO - Downloading https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:39:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 15:39:09 INFO - Downloaded 1199 bytes. 15:39:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:39:09 INFO - Using the following test package requirements: 15:39:09 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:39:09 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:39:09 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:39:09 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'jsshell-mac.zip'], 15:39:09 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:39:09 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:39:09 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:39:09 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:39:09 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:39:09 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:39:09 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:39:09 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:39:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:39:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:39:09 INFO - Fetch https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:39:10 INFO - Http code: 200 15:39:10 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 15:39:10 INFO - x-amz-cf-id: u8DzMUIVRufAEzVMZ3KmDHOKo9r4PgacVkHIN0lfvCzTL_vb-3fDnA== 15:39:10 INFO - x-amz-version-id: sbyXcrSpsgywg.1yAkx5MFaMQ0_7PT.G 15:39:10 INFO - Expected file size: 16275843 15:39:10 INFO - Obtained file size: 16275843 15:39:17 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:39:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:39:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:39:17 INFO - Fetch https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:39:17 INFO - Http code: 200 15:39:17 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 15:39:17 INFO - x-amz-cf-id: QfmZ-bbIiZUS9THe7NeWruyumq76Gh-JTqo6bjfjiD0aJJVhl7P0lw== 15:39:17 INFO - x-amz-version-id: sbyXcrSpsgywg.1yAkx5MFaMQ0_7PT.G 15:39:17 INFO - Expected file size: 16275843 15:39:17 INFO - Obtained file size: 16275843 15:39:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:39:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:39:24 INFO - Fetch https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:39:25 INFO - Http code: 200 15:39:25 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 15:39:25 INFO - x-amz-cf-id: Yg7qqifJ1KF3w83wLl6Lt-D6EyMFegfDuRnjm50nkZgPBHPPxo1bBQ== 15:39:25 INFO - x-amz-version-id: z9HreXKrtQhR.q1PGRe0R1juWb36cUIz 15:39:26 INFO - Expected file size: 14409038 15:39:26 INFO - Obtained file size: 14409038 15:39:26 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')]} 15:39:26 INFO - trying https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:39:26 INFO - Downloading https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:39:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 15:39:29 INFO - Downloaded 110007319 bytes. 15:39:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:39:29 INFO - mkdir: /builds/slave/test/properties 15:39:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:29 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:29 INFO - Contents: 15:39:29 INFO - build_url:https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:39:30 INFO - Running post-action listener: _resource_record_post_action 15:39:30 INFO - Running post-action listener: set_extra_try_arguments 15:39:30 INFO - [mozharness: 2017-01-18 23:39:30.117263Z] Finished download-and-extract step (success) 15:39:30 INFO - [mozharness: 2017-01-18 23:39:30.117417Z] Running populate-webroot step. 15:39:30 INFO - Running pre-action listener: _resource_record_pre_action 15:39:30 INFO - Running main action method: populate_webroot 15:39:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:39:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:39:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:39:30 INFO - '--tppagecycles', 15:39:30 INFO - '7'], 15:39:30 INFO - 'tests': ['tsvgm']}}, 15:39:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:39:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:39:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['damp', 'tps']}, 15:39:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'tests': ['damp', 'tps']}, 15:39:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['dromaeo_dom']}, 15:39:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['basic_compositor_video']}, 15:39:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:39:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['a11yr', 15:39:30 INFO - 'ts_paint', 15:39:30 INFO - 'tpaint', 15:39:30 INFO - 'sessionrestore', 15:39:30 INFO - 'sessionrestore_no_auto_restore', 15:39:30 INFO - 'tabpaint']}, 15:39:30 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:30 INFO - 'ts_paint', 15:39:30 INFO - 'tpaint', 15:39:30 INFO - 'sessionrestore', 15:39:30 INFO - 'sessionrestore_no_auto_restore', 15:39:30 INFO - 'tabpaint']}, 15:39:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:39:30 INFO - 'ts_paint', 15:39:30 INFO - 'tpaint', 15:39:30 INFO - 'sessionrestore', 15:39:30 INFO - 'sessionrestore_no_auto_restore', 15:39:30 INFO - 'tabpaint']}, 15:39:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:39:30 INFO - 'ts_paint', 15:39:30 INFO - 'tpaint', 15:39:30 INFO - 'sessionrestore', 15:39:30 INFO - 'sessionrestore_no_auto_restore', 15:39:30 INFO - 'tabpaint']}, 15:39:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['a11yr', 15:39:30 INFO - 'ts_paint', 15:39:30 INFO - 'tpaint', 15:39:30 INFO - 'sessionrestore', 15:39:30 INFO - 'sessionrestore_no_auto_restore', 15:39:30 INFO - 'tabpaint']}, 15:39:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['a11yr', 15:39:30 INFO - 'ts_paint', 15:39:30 INFO - 'tpaint', 15:39:30 INFO - 'sessionrestore', 15:39:30 INFO - 'sessionrestore_no_auto_restore', 15:39:30 INFO - 'tabpaint']}, 15:39:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['tsvgx', 15:39:30 INFO - 'tsvgr_opacity', 15:39:30 INFO - 'tart', 15:39:30 INFO - 'tscrollx', 15:39:30 INFO - 'cart']}, 15:39:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:30 INFO - 'tsvgr_opacity', 15:39:30 INFO - 'tart', 15:39:30 INFO - 'tscrollx', 15:39:30 INFO - 'cart']}, 15:39:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'talos_options': ['--disable-e10s'], 15:39:30 INFO - 'tests': ['tp5o']}, 15:39:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'tests': ['tp5o']}, 15:39:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'talos_options': ['--disable-e10s', 15:39:30 INFO - '--xperf_path', 15:39:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:30 INFO - 'tests': ['tp5n']}, 15:39:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:30 INFO - 'talos_options': ['--xperf_path', 15:39:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:30 INFO - 'tests': ['tp5n']}}, 15:39:30 INFO - 'talos.zip': {'path': '', 15:39:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:39:30 INFO - Running post-action listener: _resource_record_post_action 15:39:30 INFO - [mozharness: 2017-01-18 23:39:30.136357Z] Finished populate-webroot step (success) 15:39:30 INFO - [mozharness: 2017-01-18 23:39:30.136455Z] Running create-virtualenv step. 15:39:30 INFO - Running pre-action listener: _resource_record_pre_action 15:39:30 INFO - Running main action method: create_virtualenv 15:39:30 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:30 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 15:39:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:39:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:30 INFO - Using real prefix '/tools/python27' 15:39:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:30 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:33 INFO - Installing pip.................done. 15:39:33 INFO - Return code: 0 15:39:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:39:34 INFO - Reading from file tmpfile_stdout 15:39:34 INFO - Output received: 15:39:34 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) 15:39:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:34 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')]} 15:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:34 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')]} 15:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:34 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:34 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 15:39:34 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 15:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:34 INFO - 'HOME': '/Users/cltbld', 15:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:34 INFO - 'LOGNAME': 'cltbld', 15:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:34 INFO - 'MOZ_NO_REMOTE': '1', 15:39:34 INFO - 'NO_EM_RESTART': '1', 15:39:34 INFO - 'PAGER': '/bin/cat', 15:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:34 INFO - 'PWD': '/builds/slave/test', 15:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:34 INFO - 'SHELL': '/bin/bash', 15:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:34 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:34 INFO - 'USER': 'cltbld', 15:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:34 INFO - 'XPC_FLAGS': '0x0', 15:39:34 INFO - 'XPC_SERVICE_NAME': '0', 15:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:34 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 15:39:34 INFO - Cleaning up... 15:39:34 INFO - Return code: 0 15:39:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:34 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')]} 15:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:34 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')]} 15:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:34 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:34 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 15:39:34 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 15:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:34 INFO - 'HOME': '/Users/cltbld', 15:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:34 INFO - 'LOGNAME': 'cltbld', 15:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:34 INFO - 'MOZ_NO_REMOTE': '1', 15:39:34 INFO - 'NO_EM_RESTART': '1', 15:39:34 INFO - 'PAGER': '/bin/cat', 15:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:34 INFO - 'PWD': '/builds/slave/test', 15:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:34 INFO - 'SHELL': '/bin/bash', 15:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:34 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:34 INFO - 'USER': 'cltbld', 15:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:34 INFO - 'XPC_FLAGS': '0x0', 15:39:34 INFO - 'XPC_SERVICE_NAME': '0', 15:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:34 INFO - Downloading/unpacking psutil>=3.1.1 15:39:34 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 15:39:34 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 15:39:34 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 15:39:34 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 15:39:34 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 15:39:34 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 15:39:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:38 INFO - Installing collected packages: psutil 15:39:38 INFO - Running setup.py install for psutil 15:39:38 INFO - building 'psutil._psutil_osx' extension 15:39:38 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 15:39:38 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 15:39:38 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 15:39:38 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 15:39:38 INFO - building 'psutil._psutil_posix' extension 15:39:38 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 15:39:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:39:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:39:38 INFO - ^ 15:39:38 INFO - 1 warning generated. 15:39:38 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 15:39:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:39 INFO - Successfully installed psutil 15:39:39 INFO - Cleaning up... 15:39:39 INFO - Return code: 0 15:39:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:39 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')]} 15:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:39 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')]} 15:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:39 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:39 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 15:39:39 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 15:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:39 INFO - 'HOME': '/Users/cltbld', 15:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:39 INFO - 'LOGNAME': 'cltbld', 15:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:39 INFO - 'MOZ_NO_REMOTE': '1', 15:39:39 INFO - 'NO_EM_RESTART': '1', 15:39:39 INFO - 'PAGER': '/bin/cat', 15:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:39 INFO - 'PWD': '/builds/slave/test', 15:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:39 INFO - 'SHELL': '/bin/bash', 15:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:39 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:39 INFO - 'USER': 'cltbld', 15:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:39 INFO - 'XPC_FLAGS': '0x0', 15:39:39 INFO - 'XPC_SERVICE_NAME': '0', 15:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39: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 15:39: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 15:39: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 15:39: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 15:39: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 15:39: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 15:39:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:42 INFO - Installing collected packages: mozsystemmonitor 15:39:42 INFO - Running setup.py install for mozsystemmonitor 15:39:42 INFO - Successfully installed mozsystemmonitor 15:39:42 INFO - Cleaning up... 15:39:42 INFO - Return code: 0 15:39:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:42 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')]} 15:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:42 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')]} 15:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:42 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:42 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 15:39:42 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 15:39:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:42 INFO - 'HOME': '/Users/cltbld', 15:39:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:42 INFO - 'LOGNAME': 'cltbld', 15:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:42 INFO - 'MOZ_NO_REMOTE': '1', 15:39:42 INFO - 'NO_EM_RESTART': '1', 15:39:42 INFO - 'PAGER': '/bin/cat', 15:39:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:42 INFO - 'PWD': '/builds/slave/test', 15:39:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:42 INFO - 'SHELL': '/bin/bash', 15:39:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:42 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:42 INFO - 'USER': 'cltbld', 15:39:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:42 INFO - 'XPC_FLAGS': '0x0', 15:39:42 INFO - 'XPC_SERVICE_NAME': '0', 15:39:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:42 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 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 15:39:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:45 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 15:39:45 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 15:39:45 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 15:39:45 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 15:39:45 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 15:39:45 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 15:39:45 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:45 INFO - warning: no files found matching '*.txt' 15:39:45 INFO - no previously-included directories found matching 'build' 15:39:45 INFO - no previously-included directories found matching 'dist' 15:39:45 INFO - no previously-included directories found matching '.git*' 15:39:45 INFO - Installing collected packages: jsonschema, functools32 15:39:45 INFO - Running setup.py install for functools32 15:39:46 INFO - warning: no files found matching '*.txt' 15:39:46 INFO - no previously-included directories found matching 'build' 15:39:46 INFO - no previously-included directories found matching 'dist' 15:39:46 INFO - no previously-included directories found matching '.git*' 15:39:46 INFO - Successfully installed jsonschema functools32 15:39:46 INFO - Cleaning up... 15:39:46 INFO - Return code: 0 15:39:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:46 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')]} 15:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:46 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')]} 15:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:46 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:46 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 15:39:46 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 15:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:46 INFO - 'HOME': '/Users/cltbld', 15:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:46 INFO - 'LOGNAME': 'cltbld', 15:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:46 INFO - 'MOZ_NO_REMOTE': '1', 15:39:46 INFO - 'NO_EM_RESTART': '1', 15:39:46 INFO - 'PAGER': '/bin/cat', 15:39:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:46 INFO - 'PWD': '/builds/slave/test', 15:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:46 INFO - 'SHELL': '/bin/bash', 15:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:46 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:46 INFO - 'USER': 'cltbld', 15:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:46 INFO - 'XPC_FLAGS': '0x0', 15:39:46 INFO - 'XPC_SERVICE_NAME': '0', 15:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:46 INFO - Cleaning up... 15:39:46 INFO - Return code: 0 15:39:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:46 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')]} 15:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:46 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')]} 15:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:46 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:46 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 15:39:46 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 15:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:46 INFO - 'HOME': '/Users/cltbld', 15:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:46 INFO - 'LOGNAME': 'cltbld', 15:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:46 INFO - 'MOZ_NO_REMOTE': '1', 15:39:46 INFO - 'NO_EM_RESTART': '1', 15:39:46 INFO - 'PAGER': '/bin/cat', 15:39:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:46 INFO - 'PWD': '/builds/slave/test', 15:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:46 INFO - 'SHELL': '/bin/bash', 15:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:46 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:46 INFO - 'USER': 'cltbld', 15:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:46 INFO - 'XPC_FLAGS': '0x0', 15:39:46 INFO - 'XPC_SERVICE_NAME': '0', 15:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:46 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:46 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 15:39:46 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 15:39:46 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 15:39:46 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 15:39:46 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 15:39:46 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 15:39:49 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 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 15:39:49 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 15:39:50 INFO - Downloading docopt-0.6.1.tar.gz 15:39:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:50 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:50 INFO - Running setup.py install for blobuploader 15:39:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:50 INFO - Running setup.py install for requests 15:39:51 INFO - Running setup.py install for docopt 15:39:51 INFO - Successfully installed blobuploader requests docopt 15:39:51 INFO - Cleaning up... 15:39:51 INFO - Return code: 0 15:39:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:51 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')]} 15:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:51 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')]} 15:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:51 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:51 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 15:39:51 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 15:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:51 INFO - 'HOME': '/Users/cltbld', 15:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:51 INFO - 'LOGNAME': 'cltbld', 15:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:51 INFO - 'MOZ_NO_REMOTE': '1', 15:39:51 INFO - 'NO_EM_RESTART': '1', 15:39:51 INFO - 'PAGER': '/bin/cat', 15:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:51 INFO - 'PWD': '/builds/slave/test', 15:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:51 INFO - 'SHELL': '/bin/bash', 15:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:51 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:51 INFO - 'USER': 'cltbld', 15:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:51 INFO - 'XPC_FLAGS': '0x0', 15:39:51 INFO - 'XPC_SERVICE_NAME': '0', 15:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:51 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-qjQYvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:51 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0W6Leo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:51 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-MB5P1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-lXA6Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-GwWSl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-VhmNJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-MKn03w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3coOqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9lmTLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-rP7RIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-lC6qFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-QuoPPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:52 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-nWKlYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:53 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-zGqUqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:53 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-cPKDG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:53 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-eiEsH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:53 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-VGxSmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:53 INFO - Running setup.py install for manifestparser 15:39:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:53 INFO - Running setup.py install for mozcrash 15:39:53 INFO - Running setup.py install for mozdebug 15:39:53 INFO - Running setup.py install for mozdevice 15:39:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Running setup.py install for mozfile 15:39:54 INFO - Running setup.py install for mozhttpd 15:39:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Running setup.py install for mozinfo 15:39:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Running setup.py install for mozInstall 15:39:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Running setup.py install for mozleak 15:39:54 INFO - Running setup.py install for mozlog 15:39:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:54 INFO - Running setup.py install for moznetwork 15:39:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:55 INFO - Running setup.py install for mozprocess 15:39:55 INFO - Running setup.py install for mozprofile 15:39:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:55 INFO - Running setup.py install for mozrunner 15:39:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:55 INFO - Running setup.py install for mozscreenshot 15:39:55 INFO - Running setup.py install for moztest 15:39:55 INFO - Running setup.py install for mozversion 15:39:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:56 INFO - Cleaning up... 15:39:56 INFO - Return code: 0 15:39:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39: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')]} 15:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:56 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:39:56 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 15:39:56 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 15:39:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:39:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:56 INFO - 'HOME': '/Users/cltbld', 15:39:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:56 INFO - 'LOGNAME': 'cltbld', 15:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:56 INFO - 'MOZ_NO_REMOTE': '1', 15:39:56 INFO - 'NO_EM_RESTART': '1', 15:39:56 INFO - 'PAGER': '/bin/cat', 15:39:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:56 INFO - 'PWD': '/builds/slave/test', 15:39:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:56 INFO - 'SHELL': '/bin/bash', 15:39:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:39:56 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:39:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:56 INFO - 'USER': 'cltbld', 15:39:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:56 INFO - 'XPC_FLAGS': '0x0', 15:39:56 INFO - 'XPC_SERVICE_NAME': '0', 15:39:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-u6L9fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:56 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)) 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-zspGs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ibE8DX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:56 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)) 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-8MFj7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:56 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)) 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-sTS5hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:56 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)) 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-T5ST_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:56 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)) 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:56 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0if8v_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-HjGtSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-TvUCBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-jm0HYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-y1c7TT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-4OFyfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-42YOdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0LdMRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-xqF5Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:57 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)) 15:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:57 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-WOiZdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-pRFeCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:58 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)) 15:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:58 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)) 15:39:58 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)) 15:39:58 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)) 15:39:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:58 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 15:39:58 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 15:39:58 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 15:39:58 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 15:39:58 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 15:39:58 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 15:40:00 INFO - Downloading blessings-1.6.tar.gz 15:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:00 INFO - Installing collected packages: blessings 15:40:00 INFO - Running setup.py install for blessings 15:40:00 INFO - Successfully installed blessings 15:40:00 INFO - Cleaning up... 15:40:01 INFO - Return code: 0 15:40:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:01 INFO - Reading from file tmpfile_stdout 15:40:01 INFO - Current package versions: 15:40:01 INFO - blessings == 1.6 15:40:01 INFO - blobuploader == 1.2.4 15:40:01 INFO - docopt == 0.6.1 15:40:01 INFO - functools32 == 3.2.3-2 15:40:01 INFO - jsonschema == 2.5.1 15:40:01 INFO - manifestparser == 1.1 15:40:01 INFO - mozInstall == 1.12 15:40:01 INFO - mozcrash == 1.0 15:40:01 INFO - mozdebug == 0.1 15:40:01 INFO - mozdevice == 0.48 15:40:01 INFO - mozfile == 1.2 15:40:01 INFO - mozhttpd == 0.7 15:40:01 INFO - mozinfo == 0.9 15:40:01 INFO - mozleak == 0.1 15:40:01 INFO - mozlog == 3.4 15:40:01 INFO - moznetwork == 0.27 15:40:01 INFO - mozprocess == 0.23 15:40:01 INFO - mozprofile == 0.28 15:40:01 INFO - mozrunner == 6.13 15:40:01 INFO - mozscreenshot == 0.1 15:40:01 INFO - mozsystemmonitor == 0.3 15:40:01 INFO - moztest == 0.8 15:40:01 INFO - mozversion == 1.4 15:40:01 INFO - psutil == 3.1.1 15:40:01 INFO - requests == 1.2.3 15:40:01 INFO - wsgiref == 0.1.2 15:40:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:01 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')]} 15:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:01 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')]} 15:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:02 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:40:02 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 15:40:02 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 15:40:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:40:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:02 INFO - 'HOME': '/Users/cltbld', 15:40:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:02 INFO - 'LOGNAME': 'cltbld', 15:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:02 INFO - 'MOZ_NO_REMOTE': '1', 15:40:02 INFO - 'NO_EM_RESTART': '1', 15:40:02 INFO - 'PAGER': '/bin/cat', 15:40:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:02 INFO - 'PWD': '/builds/slave/test', 15:40:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:02 INFO - 'SHELL': '/bin/bash', 15:40:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:40:02 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:40:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:02 INFO - 'USER': 'cltbld', 15:40:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:02 INFO - 'XPC_FLAGS': '0x0', 15:40:02 INFO - 'XPC_SERVICE_NAME': '0', 15:40:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 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)) 15:40:02 INFO - Cleaning up... 15:40:02 INFO - Return code: 0 15:40:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:02 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 0x102602490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102578570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8833cf0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, '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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 15:40:02 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 15:40:02 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 15:40:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:40:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:02 INFO - 'HOME': '/Users/cltbld', 15:40:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:02 INFO - 'LOGNAME': 'cltbld', 15:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:02 INFO - 'MOZ_NO_REMOTE': '1', 15:40:02 INFO - 'NO_EM_RESTART': '1', 15:40:02 INFO - 'PAGER': '/bin/cat', 15:40:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:02 INFO - 'PWD': '/builds/slave/test', 15:40:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:02 INFO - 'SHELL': '/bin/bash', 15:40:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:40:02 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:40:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:02 INFO - 'USER': 'cltbld', 15:40:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:02 INFO - 'XPC_FLAGS': '0x0', 15:40:02 INFO - 'XPC_SERVICE_NAME': '0', 15:40:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:02 INFO - Cleaning up... 15:40:02 INFO - Return code: 0 15:40:02 INFO - Running post-action listener: _resource_record_post_action 15:40:02 INFO - Running post-action listener: _start_resource_monitoring 15:40:03 INFO - Starting resource monitoring. 15:40:03 INFO - [mozharness: 2017-01-18 23:40:03.016814Z] Finished create-virtualenv step (success) 15:40:03 INFO - [mozharness: 2017-01-18 23:40:03.017800Z] Running install step. 15:40:03 INFO - Running pre-action listener: _resource_record_pre_action 15:40:03 INFO - Running main action method: install 15:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:03 INFO - Reading from file tmpfile_stdout 15:40:03 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:03 INFO - Reading from file tmpfile_stdout 15:40:03 INFO - Output received: 15:40:03 INFO - Usage: mozinstall [options] installer 15:40:03 INFO - Options: 15:40:03 INFO - -h, --help show this help message and exit 15:40:03 INFO - -d DEST, --destination=DEST 15:40:03 INFO - Directory to install application into. [default: 15:40:03 INFO - "/builds/slave/test"] 15:40:03 INFO - --app=APP Application being installed. [default: firefox] 15:40:03 INFO - mkdir: /builds/slave/test/build/application 15:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:40:34 INFO - Reading from file tmpfile_stdout 15:40:34 INFO - Output received: 15:40:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:40:34 INFO - Running post-action listener: _resource_record_post_action 15:40:34 INFO - [mozharness: 2017-01-18 23:40:34.026453Z] Finished install step (success) 15:40:34 INFO - [mozharness: 2017-01-18 23:40:34.026603Z] Running run-tests step. 15:40:34 INFO - Running pre-action listener: _resource_record_pre_action 15:40:34 INFO - Running main action method: run_tests 15:40:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:40:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:40:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:40:34 INFO - 2017-01-18 15:40:34.040 screenresolution[1616:7238] starting screenresolution argv=screenresolution get 15:40:34 INFO - 2017-01-18 15:40:34.043 screenresolution[1616:7238] Display 0: 1600x1200x32@60 15:40:34 INFO - 2017-01-18 15:40:34.049 screenresolution[1617:7240] starting screenresolution argv=screenresolution list 15:40:34 INFO - Available Modes on Display 0 15:40:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:40:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:40:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:40:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:40:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:40:34 INFO - Intel Iris: 15:40:34 INFO - Chipset Model: Intel Iris 15:40:34 INFO - Type: GPU 15:40:34 INFO - Bus: Built-In 15:40:34 INFO - VRAM (Dynamic, Max): 1536 MB 15:40:34 INFO - Vendor: Intel (0x8086) 15:40:34 INFO - Device ID: 0x0a2e 15:40:34 INFO - Revision ID: 0x0009 15:40:34 INFO - Displays: 15:40:34 INFO - Display: 15:40:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:40:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:40:34 INFO - Display Serial Number: 1600x1200 60 15:40:34 INFO - Main Display: Yes 15:40:34 INFO - Mirror: Off 15:40:34 INFO - Online: Yes 15:40:34 INFO - Rotation: Supported 15:40:34 INFO - Return code: 0 15:40:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:34 INFO - Python 2.7.3 15:40:34 INFO - Return code: 0 15:40:34 INFO - grabbing minidump binary from tooltool 15:40:34 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')]} 15:40:34 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 0x102416d30>, '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 0x10227cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10240eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:34 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 15:40:34 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 15:40:34 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 15:40:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:34 INFO - Return code: 0 15:40:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:40:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:40:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 15:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --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 15:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w2c4DL9ErN/Render', 15:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:34 INFO - 'HOME': '/Users/cltbld', 15:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:34 INFO - 'LOGNAME': 'cltbld', 15:40:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:34 INFO - 'MOZ_NO_REMOTE': '1', 15:40:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:34 INFO - 'NO_EM_RESTART': '1', 15:40:34 INFO - 'PAGER': '/bin/cat', 15:40:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:34 INFO - 'PWD': '/builds/slave/test', 15:40:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:34 INFO - 'SHELL': '/bin/bash', 15:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.puJ6mmyT0X/Listeners', 15:40:34 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 15:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:34 INFO - 'USER': 'cltbld', 15:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:34 INFO - 'XPC_FLAGS': '0x0', 15:40:34 INFO - 'XPC_SERVICE_NAME': '0', 15:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 15:40:34 INFO - mozversion application_buildid: 20170118143333 15:40:34 INFO - mozversion application_changeset: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b 15:40:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:40:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:34 INFO - mozversion application_name: Firefox 15:40:34 INFO - mozversion application_remotingname: firefox-dev 15:40:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:40:34 INFO - mozversion application_vendor: Mozilla 15:40:34 INFO - mozversion application_version: 52.0a2 15:40:34 INFO - mozversion platform_buildid: 20170118143333 15:40:34 INFO - mozversion platform_changeset: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b 15:40:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:40:34 INFO - mozversion platform_version: 52.0a2 15:40:34 INFO - using testdate: 1484782834 15:40:34 INFO - actual date: 1484782834 15:40:34 INFO - starting webserver on 'localhost:49243' 15:40:34 INFO - SUITE-START | Running 2 tests 15:40:34 INFO - TEST-START | dromaeo_css 15:40:34 INFO - operating with platform_type : mac_ 15:40:34 INFO - Initialising browser for dromaeo_css test... 15:40:34 INFO - TEST-INFO | started process 1627 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpoXy7jp/profile http://localhost:49243/getInfo.html) 15:40:37 INFO - PROCESS | 1627 | 2017-01-18 15:40:37.698 plugin-container[1629:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 15:40:37 INFO - PROCESS | 1627 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:37 INFO - PROCESS | 1627 | 2017-01-18 15:40:37.700 plugin-container[1629:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 15:40:37 INFO - PROCESS | 1627 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:37 INFO - PROCESS | 1627 | 2017-01-18 15:40:37.700 plugin-container[1629:7422] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:40:37 INFO - PROCESS | 1627 | __metrics Screen width/height:1600/1200 15:40:37 INFO - PROCESS | 1627 | colorDepth:24 15:40:37 INFO - PROCESS | 1627 | Browser inner width/height: 1024/689 15:40:37 INFO - PROCESS | 1627 | __metrics 15:40:37 ERROR - PROCESS | 1627 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 15:40:38 INFO - TEST-INFO | 1627: exit 0 15:40:38 INFO - Browser initialized. 15:40:38 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:38 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpoXy7jp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:41 INFO - PROCESS | 1630 | 2017-01-18 15:40:41.307 plugin-container[1631:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 15:40:41 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:41 INFO - PROCESS | 1630 | 2017-01-18 15:40:41.308 plugin-container[1631:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 15:40:41 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:41 INFO - PROCESS | 1630 | 2017-01-18 15:40:41.308 plugin-container[1631:7547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:40:50 INFO - PROCESS | 1630 | RSS: Main: 228970496 15:40:50 INFO - PROCESS | 1630 | 15:43:53 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:43:54 INFO - PROCESS | 1630 | RSS: Main: 235560960 15:43:54 INFO - PROCESS | 1630 | 15:46:56 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:46:57 INFO - PROCESS | 1630 | RSS: Main: 247717888 15:46:57 INFO - PROCESS | 1630 | 15:49:59 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:50:00 INFO - PROCESS | 1630 | RSS: Main: 250679296 15:50:00 INFO - PROCESS | 1630 | 15:53:02 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:53:02 INFO - PROCESS | 1630 | RSS: Main: 249925632 15:53:02 INFO - PROCESS | 1630 | 15:56:05 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 15:56:06 INFO - PROCESS | 1630 | RSS: Main: 253550592 15:56:06 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 15:59:08 INFO - PROCESS | 1630 | RSS: Main: 254836736 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | __start_tp_report 15:59:08 INFO - PROCESS | 1630 | _x_x_mozilla_page_load 15:59:08 INFO - PROCESS | 1630 | _x_x_mozilla_page_load_details 15:59:08 INFO - PROCESS | 1630 | |i|pagename|runs| 15:59:08 INFO - PROCESS | 1630 | |0;dojo.html;2739.42472080863;2792.7765778737676;3037.6482465302943;3332.7833690810094;3343.866245350186;5555.2778138841895;5583.83248502545;5586;5668.801591944282;5687.943120568793;5732.971335143329;5803.187553742479;5841.503472204857;5846.795362162326;5846.912296315548;5246.213068039787;5268.446813084638;5288.550473209782;5313.388960269561;5373.978944000647;5250.6587071840395;5332.680039197641;5357.142857142858;5372.7045012524295;5393.649412788176;5150.201718733602;5161.225816127573;5184.611154163453;5192.55863251624;5240.659357141773;5151.2530683050745;5153.2270159476375;5174.689518628894;5203.453637368098;5212.635115541913;4465.8612053926045;4617.7762892833425;4664.720116793003;4763.333133361332;5121.692698438105;5702.287214098238;5729.341125770548;5759.769609215627;5766.682832444214;5781.653100813965;6155.630662160284;6248.875022499525;6250.093736408196;6271.466925311308;6279.7174127163935;6296.275928268262;6320.115183874262;6341.524385671093;6359.65021923794;6365.554411191219;5693.715314234271;5700.230468886647;5754.1944127822135;5857.824265272006;5873.794417195378;6219.626822390671;6288.773689130667;6294.842628934313;6309.747610095545;6321.336259692758;6126.6017708848785;6132.202813634288;6138.232720909887;6176.7838125665385;6188.690565471798;6093.360197179232;6099.054646529795;6249.812505624838;6272.874542509125;6308.053791931247;6108.236470441195;6110.450059494667;6134.00801433197;6135.294441139326;6140.074827791203;6206.975848984957;6250.312465628777;6256.343083976117;6260.24877014752;6261.812145635638;4834.806607735652;4846.345743324677;4893.09477746618;4901.142300097468;4935.259711043373;3712.3688972874675;4309.159713855768;4354;4405.581469760367;4417.491988421309;5671.092625179951;5888.646681199141;5902.468777810018;5908.350081491032;5951.077582974559;6165.414285642857;6176.752929882754;6295.874082518324;6299.055141728686;6306.337834527309;5798.710064496759;5799.507041901401;5871.32479764824;5895.64626122434;5945.316288626863;5296.682199068068;5328.387235467893;5349.786008559692;5451.48210919962;5481.232627432163;6260.154879091447;6262.686865656608;6268.62388256697;6312.400321969314;6398.360163983564;6285.151504546828;6291.685415729286;6306.621602703853;6322.652254126067;6322.652254126067;6369.394907483782;6389.361063893573;6393.680315984089;6404.519661025349;6414.679266036773;6088.321138698958;6137.539684523768;6159.568830182024;6161.599496032743;6175.2589689237575;5508.697021663847;6276.497880169484;6288.182536270256;6312.116303717575;6314.55798094129;4731.432228132646;4978.228374601942;5422.376426710979;6198.6280823150755;6349.6507692077375;5554.333479982428;6007;6046.879062418639;6068.332483426908;6078.118672792509;5990.101484777319;6027.156198132353;6066.969665151646;6075.908861366995;6100.786472473442;6116.14373987651;6144.41628045335;6177.135201071764;6195.132681424694;6197.938020619736;5812.564057695605;5876.44173803488;5944.959632064285;6032.1856549365275;6076.422739839708 15:59:08 INFO - PROCESS | 1630 | |1;ext.html;463.94896561378266;479.84644913627653;519.1848797388101;526.5682140644672;528.0600531054722;32858.50712239315;40698.38952415716;40757.38863917044;40810.183796324054;40926.18147637045;42185;42445.7877710611;42549.36175957363;42613.57386426135;42667.78666106665;9400.38897471664;9491.335606507548;9562.139407453327;9603.135717785399;9626.711198664052;9314.953425232865;9393.201577865855;9484.952575237114;9502.47736374499;9531.714048578553;9453.196478299318;9492.145706886378;9521.476318802463;9542.236621070333;9546.045395460467;9344;9469.005754395757;9495.287853410953;9509.239260859114;9519.476428796414;9409.90590094104;9510.572024258925;9511.809763804755;9512.95243523778;9520.428774273565;38581.22837543261;40467.190656187006;40762.79618601918;40793.592064079276;40798.9800254993;3275.19757659375;3533.293341331744;3556.3065202285566;3632.54593175853;3764.73646844721;27736.509117975438;28866.431025041114;29236.269093272625;30062.54906176409;30245.243868903453;3174.4444722173525;3180.9681903180904;3190.489521676544;3205.5191721241767;3219.404410184123;383032.67869285477;481929;485603.99999999645;490335;491444;54312;58795.41204587985;59334.10998835021;59420.40579594235;59598;50756.49243507592;63562.68218658877;63829;64440.67779661072;64442.677786611705;15534.601885830329;16019.519414417353;16226.756598651029;16230.107344096188;16232.513024609281;12362;12382.442790074469;12421.378931053592;12445.875541244654;12472.501099956082;14059.383170935269;16253.106079165524;16958.915205423895;17226.05256710868;17263.56841078983;3168.6356069052354;3305.4380755271663;6081.513478921675;6534.281229064799;6578.967105164444;864.4597126795753;904.8733177355175;977.8826540815005;1043.6868939318174;1075.860138182047;26381.47237055248;29172.9789457368;29294.26764330909;29914.700852991627;30591;937.5499760115183;1078.266778590566;1083.4853444613746;1107.141964977133;1123.3147779854282;11156.553737850558;16226.701863850863;16630.916845415697;16734.665306693798;16829.495115146565;693334;696734.5163274253;702948;703101;703593;59041.523961901294;59380.70309648424;59523;59541.70229148826;59759.70120149371;1323.67569945364;1323.7617228898698;1339.3437215764206;1342.9462821487227;1353.4856754433251;30430.695693042788;30577.08268751941;31142.22144446407;31635.20911977219;31732.524012139376;50794;51274.48725512697;51294.74352628213;51361;51470;632161;633099;635072;635502;638254;25405.61891571591;26819.86590067037;27182;27256.046038388562;27295.590566141127;45021.774891126646;45053.32420013768;45642;45747.77126114348;46395.53604463912;37079.62920370762;37954.2409151821;40130.59869401269;40220.79889600533;40608.79695601503;38863.611363886;38995.80502097585;39217;39501.814945551676;39975.400368993905;38232.2353552933;38696.80651596724;38701;38868.416973744854;39065.21869562536;26933.86533067322;28869.845206192356;33599.328013440085;34840.47739283963;34886.65113348936;21537.676934845676;25606;26292;26561.937522498883;26773.464530709665 15:59:08 INFO - PROCESS | 1630 | |2;jquery.html;373.9326921154193;375.32066958804563;383.4669808965538;384.84413812405984;400.60340263139483;16038.198090095508;16480.917595412022;18130.00284984327;19393.61212775747;20084;19237.23051077955;19438.611227775436;19776.11007504662;19891.30380436685;19944.800551994475;5419.59353048521;5686.687232202237;5935.139404786303;5940.168376427304;5942.762289508414;5839.474447299742;5947.10793380992;5954.761809527614;5959.493443057346;5961.374055724152;5644.57665675074;5766.106253530709;5800.883982320351;5900.704964751767;5922.733476993546;5833.708314584275;5857.297124345085;5875.676837773942;5879.088741245114;5904.2029326040865;5030.471800460972;5142.07442660321;5160.14857548504;5301.628885977983;5385.380681221671;10816.621418250323;12737.426815793218;12977.87022129776;13003.154794938298;22942.65586016211;2374.620060790265;2402.9399265018515;2493.5760920643534;2618.2407101940416;2855.5859400386826;7605.859121131819;9895.01049895012;9972.650957216463;10081.19350451962;10097.394156350643;1803.404876390801;1828.286968082449;1853.2494339792406;1876.8780029298052;1915.339207973261;119377.40311298474;121766.99999999911;122198.3890080544;125854;125902.8524286891;8179.918200817916;9467.769190005392;9904.55429505674;10143.340682855738;10236.641717539851;12311.938440307742;12427.565035223724;12507.437165327583;12528.123031387715;12530.74938501243;6746.460283177334;7794.41541884361;7999.480033797901;8052.114267430578;8098.392620553482;6738.258791532928;7879.093904200976;8416.579171041423;8427.073021967579;8497.787555311166;3839.8080095995088;3891.571927088018;3904.2972264992572;3919.294526985113;3953.6639385652543;1916.57835276239;1965.6068786242809;1970.458124015907;1973.9802601973797;1982.930597429083;809.9757007289791;917.6604656277086;927.4435338796668;938.0994245524237;973.2846357926918;14895.70208595822;20077.598448030956;20445;20651;20675.8966205168;931.5388882503204;933.715216858851;943.5140902435252;996.7308826616772;1010.2625083688948;10325.793484130425;10690.037896589345;10744;10756.193285503463;10770.623028194132;596311;597198;603295;616501;616725;124756;128060;128148.0777788334;128651;128860;925.8518637018179;945.2013048973744;960.3325688646463;969.4328817641758;970.7427531704104;15632;17884.10579470995;18530.16614252389;18909.527261818566;19192.040397980323;36411.45382819207;38280.80859595684;56345.43654563402;56973.71513142408;58234.835303294545;80502.59748701453;84576;85904;89271;89702;7789.571573563282;7955.880661790194;8136.796580085545;8260.669573217005;8297.336213102817;7457.6271186441545;11167.329960202414;12575.622731318075;12781.680457988625;13016;7995.960020199862;8964.103589640983;11105;11231.831522527005;11415.543378264776;10717;10943.945280273547;10977.725556861142;11310.434478276218;11364.772704546029;8532.914670853212;10832;10896.182786291218;11118.277311974696;11227.943860280646;8010.599470026592;8098.635561399633;8184.419047048597;8252.711155109542;8286.171382861907;5473.233747275464;7907.446478746432;8252.504849709037;10746.462676866282;11003.22977391575 15:59:08 INFO - PROCESS | 1630 | |3;mootools.html;1407.4088882669278;1453.8400775914645;1643.7945256842895;1774.5297496163512;1804.305342443159;7599.582022988733;7856.450048496607;8101.311388531974;8588.098249683788;8590.570471476432;8064.475809072406;8264.504129752204;8370.288525475342;8483;8502.277306428945;2316.4324740438615;2333.7549557296493;2345.6012477878803;2350.905963761456;2356.3284463927785;2348.7886090251873;2369.1707902234252;2381.9166329178483;2396.4727759892794;2398.5322862041908;2207.3377986604037;2317.3974766560646;2352.1414683640432;2353.317537914003;2360.291912426274;2310.676505289261;2316.4903721181313;2332.941676458085;2336.8364214504904;2342;2349.400902769783;2369.869657168855;2371.0989823866994;2375.572396968545;2379.9885048854167;8440.071592124923;8458.323334133316;8459.492430454131;8528.061913189544;8548.145185481464;5082.872928176788;5100.515451032146;5203.375594928595;5248.475152484759;5276.261323414725;3232.353529294127;3366.1416338833687;3469.826508674556;3486.581610206766;3490.842912068963;1051.5530899367739;1053.7912057784065;1091.825307950732;1096.4243772019674;1103.6358001859448;10857.185711071701;10954.178436617285;10968.670939871816;11024.228304018721;11077.003069723683;4520.412346394947;4625.930611040837;4687.671862969628;4726.220173671374;4771.141194585008;9452.149306562305;9467.621295148117;9553.522323883779;9564.56959436827;9575.616975320909;5226;5236.188390799432;5247.973760131251;5257.08001099806;5261.605379596469;4293.205756934977;4302.698811083257;4729.69256998294;4751.3348131261655;4800.711957282574;2961.8074825136296;3133.27934575049;3482.860685572549;3884.3396622574232;3918.804059796999;1274.9298788566532;1293.780057390246;1294.3657607772125;1307.5685023942076;1308.0385916351474;756.9091708994956;865.1435079271602;893.8525143351404;915.4919019943995;915.697819719491;4668.11305851887;4859.757012149378;5628.521575666033;5728.942710572841;5877.9118313225335;795.741384050186;873.7204094689638;887.2015186332352;892.2505095719627;935.1630290889616;3793.5447746270625;3839.040239940015;3872.6514613684903;3884.0484081399673;3886.7862267574988;9997.200223982063;10279.897201028043;10300.433476158732;10343.482825858677;10364.948175259075;9428.915674697497;9491.572879220452;10244.948775256076;10290.845637315446;10336.844947325797;7308.159561650372;7427.442941779388;7463.776086717407;7842.215778422226;7883.132855385903;5777.74000169985;5779.422057794187;5853;5925.1704761332585;5999.370066143167;9134.22359099484;9289.72130836076;9460.574274157818;9516.85724714116;9548.283878708986;7295.854082918206;9138.451692898447;9576.616935322509;9589.856152157583;9656.75858103553;6606.8017959461295;6667.133272674715;6966.930330696831;7119.288071192839;7122.358987691133;7904.13054564009;7939.523628582304;7986.480878742863;8010.198980101944;8011.238932301422;7062.1878483975;7234;7262.564246145248;7263.055802745609;7310.744123955636;7014.579125252501;7051.541649792747;7082.716691332495;7201.963990180016;7211.134663840373;6491.837704057436;6572.211334639874;6592.406683398517;6621;6673.566218195801;4721.2918062289555;4739.976300118477;4773.045390921899;4774.140654682191;4776.8566942991765;4653.697509661861;4686.92969605463;4732.219183834629;4873.853784386474;4928.088303663833 15:59:08 INFO - PROCESS | 1630 | |4;prototype.html;265.71568421788686;305.3541759179336;313.692581270355;315.39286872769924;318.17751113371935;5284.630075894689;5450.073487507123;5486.341639003315;5493.91759123613;5619.77520899164;5172.45689202633;5355.651882627636;5365.54392876605;5387.973060134704;5412.621116521845;3687.723420743455;4088.6115818997146;4096.098858251187;4108.712390132692;4136.441580386654;3385.492176173569;4074.9185016299657;4087.20299541588;4102.159057393242;4182.539920608746;4007.6192761687594;4021.879343619696;4057.8173982170724;4068.3897415387783;4083;3555.6444355564236;3705.3886108792017;4046.7571945683353;4053.148838743867;4089.89775255618;2874.942501149986;2942.45564570553;3088.7528997680133;3524.700400465938;3532.5231093802263;4093.2222877653153;4995.171102283046;5171.456997015334;5234.26720259164;5245.291885595434;4599;4618.630509559227;4622.121796858586;4625.213713668684;4640.118377508263;5003.199488081889;5547.362053363834;5629.268195134648;5633.971830140864;5653.219235940665;3060.56153607804;3514.894553163409;3693.947225040877;3735.5618087036414;3887.7278590498677;5224.660397074216;5231.555317797992;5532.1425179097305;5776;5818.738156782955;3646.3801153803915;3675.2358867743096;3973.4039894016128;3983.6016398359934;3996.200759847984;3347.3305338931823;3742.8128593570214;4044.130511939947;4056.6066021047723;4071.97964010184;4644.2801365788355;4665.346851440801;4666.673332866734;4684.81260749573;4689.6717229793585;5063.493650634907;5775.826725198251;5811.796587119515;5815.912761308584;5816.47651711348;1066.2726383259014;1240.4789988204975;1266.872483489695;1294.6504443800122;1313.960581182566;869.0049892872626;882.6469412235028;885.7696998780364;892.6610084872691;893.4282059481936;4439.755813430293;4571.828381518819;4881.340344282961;5139.785375991651;5153.072446959508;5467.289252397242;5607.523360514402;5635.323761148688;5656.717164141776;5671.688057156896;4477.977610111929;4553.863384098416;5008.824691135793;5047.545720885138;5132.923006154911;2400.287674927321;2465.778079972811;2893.885853946246;2911.4177164567172;2945.631796025497;4834.685745426536;5412.810551630674;5863.2670916135485;5968.22413086296;5970.402959704082;5142.431558374732;5151.793928242903;5274.496768421059;5351.732413379315;5593.329569809173;4860.756962151878;5296.894062118813;5512.944870551403;5974.581779275496;5977.462028417463;4854.8786280341865;5213.270142180174;5228.451012643618;5713.600047996601;5739.799107031234;5446.754896029609;5491.313585801775;5532.778688852401;5584.692841893735;5592.384837667934;4998.625103117208;5223.399309079505;5863.765449381977;5881.588288819741;5913.970430147821;3115.9532607011365;3512.8594856205464;3728.925421491609;3746.475493430867;3798.7910664913693;4897.632677549127;5470.91793623104;5629.549636029026;5656.406077361818;5689.516391106802;5565.526930210977;5650.773969041193;5715.514181294637;5720;5738.22533957927;5072.797088116582;5462.995352556158;5604.719764011865;5628.664104396629;5737.827865164052;4598.195315819785;5300.920486192633;5543.584231182596;5564.551597297176;5611.410801865745;5227.477252274742;5715.971420142872;5725.484706376446;5730.627509211888;5758.884822303614;4764.04674083624;5362.008028514737;5569.35952365467;5614.382417934105;5651.411353790455 15:59:08 INFO - PROCESS | 1630 | |5;yui.html;594.5689375202978;705.1890326124959;717.2182321269814;746.2761121711941;813.8616435206014;13952.790708139373;14448.855511444908;14797.630059248524;14814.259287035658;14926.552203433886;13159.6710082248;13337.733245335088;13391.397387117579;13423.463061477529;13426.39581218845;660.9173853268342;828.7016673997372;853.159637756809;853.871919212117;855.2644725536065;676.5129107042922;825.3603457320595;834.4117397234966;850.8893843800297;853.9794945040647;838.4466252273501;877.6796469288728;886.0740526150156;886.9799730310131;886.9822603547925;776.1307335783958;830.0786127398583;874.7550685807984;875.3391189651804;877.3113106211625;619.3744318238566;648.3807963394946;650.4178760009812;652.8661624366994;657.4904449051975;13533.18800871941;13540.458381664821;13815.930920345334;13840.792388114187;13869.167849928936;12750.61748147557;13153.802692959613;13266.933665331708;13325.800112998313;13442.865571344259;10613.363198208132;10845.755781232057;11177.99398054179;11320.433978301135;11590.536378544848;3560.2167523145126;3603.100620622611;3745.138618117848;3757.680597149218;3770.1140232045445;90942.5452872738;92148.53925730396;92248;93528.53235733845;94112.52943735306;9957.601695932226;10137.493125343703;10176.491175441346;10259.384436933808;10294.3823370598;19982.20071197165;20230.595388092155;20305.898470507553;20358.88026158546;20440.591188176153;6735.595864248063;7538.170801211862;7666.501677390952;7670.884936725954;7955.443118981616;4185.100203456272;4224;4265.4881414230495;4281.691258131066;4419.867403977886;3355.681210285019;3456.3087382523595;3476.3394954958476;3481.651834816498;3488.9127771805906;624.2781472778681;628.1833616298793;629.9181106456132;631.782035197856;639.0956796133512;545.585355130104;546.5245236644145;566.623195574938;614.261639560044;615.2770494668746;9743;9781.65764198264;9881.110700037032;9971.202303815677;10232.948835255776;544.8147629805885;611.7614130489023;656.2659062943286;671.0806195512082;676.4689718570899;4017.8995525111523;4485.237331432324;4490.887727806766;4511.413516242867;4532.365468834366;112691;114543;115012;115648;116982;50452;66089;67446;67527.66236168788;81130;89381;89618;91032;91884;92047;7609.695612175672;8615.396922215636;8806.779830504289;8818.51498167607;8854.158854908774;39929.80035099806;41077.79461102795;45006.09987800291;50881;54451.18323225234;49306.75346623244;59379;67443;74871.62564187145;77578.61210693911;12177.573784917486;12245.755084898074;12348.259104453762;12486.43811028524;12695.93652031734;13823.447062117402;13846.515371961934;13925.651858703613;13930.651733706332;13953.930230348784;12209.938950305192;12363.784196487773;12388.946939510242;12401;12871.292078935749;10813.06752948539;12664.176828506117;12955.870441295467;12988.415521301375;13117.212967221998;10549.419781912069;12782.233066015697;12814.29521376296;12829.871701282867;12919.741605168032;9430.52847357643;10657.520411581652;11017.614383496539;11535;12014.699632508908;8873.689420870238;11124.276922000045;11270.436478176221;11297.039751621205;11427 15:59:08 INFO - PROCESS | 1630 | __end_tp_report 15:59:08 INFO - PROCESS | 1630 | __start_cc_report 15:59:08 INFO - PROCESS | 1630 | _x_x_mozilla_cycle_collect,367 15:59:08 INFO - PROCESS | 1630 | __end_cc_report 15:59:08 INFO - PROCESS | 1630 | __startTimestamp1484783948811__endTimestamp 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | ------- Summary: start ------- 15:59:08 INFO - PROCESS | 1630 | Number of tests: 6 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | [#0] dojo.html Cycles:165 Average:5719.60 Median:5951.08 stddev:719.03 (12.1%) stddev-sans-first:682.17 15:59:08 INFO - PROCESS | 1630 | Values: 2739.4 2792.8 3037.6 3332.8 3343.9 5555.3 5583.8 5586.0 5668.8 5687.9 5733.0 5803.2 5841.5 5846.8 5846.9 5246.2 5268.4 5288.6 5313.4 5374.0 5250.7 5332.7 5357.1 5372.7 5393.6 5150.2 5161.2 5184.6 5192.6 5240.7 5151.3 5153.2 5174.7 5203.5 5212.6 4465.9 4617.8 4664.7 4763.3 5121.7 5702.3 5729.3 5759.8 5766.7 5781.7 6155.6 6248.9 6250.1 6271.5 6279.7 6296.3 6320.1 6341.5 6359.7 6365.6 5693.7 5700.2 5754.2 5857.8 5873.8 6219.6 6288.8 6294.8 6309.7 6321.3 6126.6 6132.2 6138.2 6176.8 6188.7 6093.4 6099.1 6249.8 6272.9 6308.1 6108.2 6110.5 6134.0 6135.3 6140.1 6207.0 6250.3 6256.3 6260.2 6261.8 4834.8 4846.3 4893.1 4901.1 4935.3 3712.4 4309.2 4354.0 4405.6 4417.5 5671.1 5888.6 5902.5 5908.4 5951.1 6165.4 6176.8 6295.9 6299.1 6306.3 5798.7 5799.5 5871.3 5895.6 5945.3 5296.7 5328.4 5349.8 5451.5 5481.2 6260.2 6262.7 6268.6 6312.4 6398.4 6285.2 6291.7 6306.6 6322.7 6322.7 6369.4 6389.4 6393.7 6404.5 6414.7 6088.3 6137.5 6159.6 6161.6 6175.3 5508.7 6276.5 6288.2 6312.1 6314.6 4731.4 4978.2 5422.4 6198.6 6349.7 5554.3 6007.0 6046.9 6068.3 6078.1 5990.1 6027.2 6067.0 6075.9 6100.8 6116.1 6144.4 6177.1 6195.1 6197.9 5812.6 5876.4 5945.0 6032.2 6076.4 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | [#1] ext.html Cycles:180 Average:72592.10 Median:40537.99 stddev:163247.56 (402.7%) stddev-sans-first:163615.69 15:59:08 INFO - PROCESS | 1630 | Values: 463.9 479.8 519.2 526.6 528.1 32858.5 40698.4 40757.4 40810.2 40926.2 42185.0 42445.8 42549.4 42613.6 42667.8 9400.4 9491.3 9562.1 9603.1 9626.7 9315.0 9393.2 9485.0 9502.5 9531.7 9453.2 9492.1 9521.5 9542.2 9546.0 9344.0 9469.0 9495.3 9509.2 9519.5 9409.9 9510.6 9511.8 9513.0 9520.4 38581.2 40467.2 40762.8 40793.6 40799.0 3275.2 3533.3 3556.3 3632.5 3764.7 27736.5 28866.4 29236.3 30062.5 30245.2 3174.4 3181.0 3190.5 3205.5 3219.4 383032.7 481929.0 485604.0 490335.0 491444.0 54312.0 58795.4 59334.1 59420.4 59598.0 50756.5 63562.7 63829.0 64440.7 64442.7 15534.6 16019.5 16226.8 16230.1 16232.5 12362.0 12382.4 12421.4 12445.9 12472.5 14059.4 16253.1 16958.9 17226.1 17263.6 3168.6 3305.4 6081.5 6534.3 6579.0 864.5 904.9 977.9 1043.7 1075.9 26381.5 29173.0 29294.3 29914.7 30591.0 937.5 1078.3 1083.5 1107.1 1123.3 11156.6 16226.7 16630.9 16734.7 16829.5 693334.0 696734.5 702948.0 703101.0 703593.0 59041.5 59380.7 59523.0 59541.7 59759.7 1323.7 1323.8 1339.3 1342.9 1353.5 30430.7 30577.1 31142.2 31635.2 31732.5 50794.0 51274.5 51294.7 51361.0 51470.0 632161.0 633099.0 635072.0 635502.0 638254.0 25405.6 26819.9 27182.0 27256.0 27295.6 45021.8 45053.3 45642.0 45747.8 46395.5 37079.6 37954.2 40130.6 40220.8 40608.8 38863.6 38995.8 39217.0 39501.8 39975.4 38232.2 38696.8 38701.0 38868.4 39065.2 26933.9 28869.8 33599.3 34840.5 34886.7 21537.7 25606.0 26292.0 26561.9 26773.5 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | [#2] jquery.html Cycles:180 Average:34711.72 Median:3897.93 stddev:101446.16 (2602.6%) stddev-sans-first:101697.98 15:59:08 INFO - PROCESS | 1630 | Values: 373.9 375.3 383.5 384.8 400.6 16038.2 16480.9 18130.0 19393.6 20084.0 19237.2 19438.6 19776.1 19891.3 19944.8 5419.6 5686.7 5935.1 5940.2 5942.8 5839.5 5947.1 5954.8 5959.5 5961.4 5644.6 5766.1 5800.9 5900.7 5922.7 5833.7 5857.3 5875.7 5879.1 5904.2 5030.5 5142.1 5160.1 5301.6 5385.4 10816.6 12737.4 12977.9 13003.2 22942.7 2374.6 2402.9 2493.6 2618.2 2855.6 7605.9 9895.0 9972.7 10081.2 10097.4 1803.4 1828.3 1853.2 1876.9 1915.3 119377.4 121767.0 122198.4 125854.0 125902.9 8179.9 9467.8 9904.6 10143.3 10236.6 12311.9 12427.6 12507.4 12528.1 12530.7 6746.5 7794.4 7999.5 8052.1 8098.4 6738.3 7879.1 8416.6 8427.1 8497.8 3839.8 3891.6 3904.3 3919.3 3953.7 1916.6 1965.6 1970.5 1974.0 1982.9 810.0 917.7 927.4 938.1 973.3 14895.7 20077.6 20445.0 20651.0 20675.9 931.5 933.7 943.5 996.7 1010.3 10325.8 10690.0 10744.0 10756.2 10770.6 596311.0 597198.0 603295.0 616501.0 616725.0 124756.0 128060.0 128148.1 128651.0 128860.0 925.9 945.2 960.3 969.4 970.7 15632.0 17884.1 18530.2 18909.5 19192.0 36411.5 38280.8 56345.4 56973.7 58234.8 80502.6 84576.0 85904.0 89271.0 89702.0 7789.6 7955.9 8136.8 8260.7 8297.3 7457.6 11167.3 12575.6 12781.7 13016.0 7996.0 8964.1 11105.0 11231.8 11415.5 10717.0 10943.9 10977.7 11310.4 11364.8 8532.9 10832.0 10896.2 11118.3 11227.9 8010.6 8098.6 8184.4 8252.7 8286.2 5473.2 7907.4 8252.5 10746.5 11003.2 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | [#3] mootools.html Cycles:180 Average:5448.41 Median:5151.95 stddev:2991.01 (58.1%) stddev-sans-first:2983.98 15:59:08 INFO - PROCESS | 1630 | Values: 1407.4 1453.8 1643.8 1774.5 1804.3 7599.6 7856.5 8101.3 8588.1 8590.6 8064.5 8264.5 8370.3 8483.0 8502.3 2316.4 2333.8 2345.6 2350.9 2356.3 2348.8 2369.2 2381.9 2396.5 2398.5 2207.3 2317.4 2352.1 2353.3 2360.3 2310.7 2316.5 2332.9 2336.8 2342.0 2349.4 2369.9 2371.1 2375.6 2380.0 8440.1 8458.3 8459.5 8528.1 8548.1 5082.9 5100.5 5203.4 5248.5 5276.3 3232.4 3366.1 3469.8 3486.6 3490.8 1051.6 1053.8 1091.8 1096.4 1103.6 10857.2 10954.2 10968.7 11024.2 11077.0 4520.4 4625.9 4687.7 4726.2 4771.1 9452.1 9467.6 9553.5 9564.6 9575.6 5226.0 5236.2 5248.0 5257.1 5261.6 4293.2 4302.7 4729.7 4751.3 4800.7 2961.8 3133.3 3482.9 3884.3 3918.8 1274.9 1293.8 1294.4 1307.6 1308.0 756.9 865.1 893.9 915.5 915.7 4668.1 4859.8 5628.5 5728.9 5877.9 795.7 873.7 887.2 892.3 935.2 3793.5 3839.0 3872.7 3884.0 3886.8 9997.2 10279.9 10300.4 10343.5 10364.9 9428.9 9491.6 10244.9 10290.8 10336.8 7308.2 7427.4 7463.8 7842.2 7883.1 5777.7 5779.4 5853.0 5925.2 5999.4 9134.2 9289.7 9460.6 9516.9 9548.3 7295.9 9138.5 9576.6 9589.9 9656.8 6606.8 6667.1 6966.9 7119.3 7122.4 7904.1 7939.5 7986.5 8010.2 8011.2 7062.2 7234.0 7262.6 7263.1 7310.7 7014.6 7051.5 7082.7 7202.0 7211.1 6491.8 6572.2 6592.4 6621.0 6673.6 4721.3 4740.0 4773.0 4774.1 4776.9 4653.7 4686.9 4732.2 4873.9 4928.1 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | [#4] prototype.html Cycles:180 Average:4500.69 Median:5141.11 stddev:1405.42 (27.3%) stddev-sans-first:1372.95 15:59:08 INFO - PROCESS | 1630 | Values: 265.7 305.4 313.7 315.4 318.2 5284.6 5450.1 5486.3 5493.9 5619.8 5172.5 5355.7 5365.5 5388.0 5412.6 3687.7 4088.6 4096.1 4108.7 4136.4 3385.5 4074.9 4087.2 4102.2 4182.5 4007.6 4021.9 4057.8 4068.4 4083.0 3555.6 3705.4 4046.8 4053.1 4089.9 2874.9 2942.5 3088.8 3524.7 3532.5 4093.2 4995.2 5171.5 5234.3 5245.3 4599.0 4618.6 4622.1 4625.2 4640.1 5003.2 5547.4 5629.3 5634.0 5653.2 3060.6 3514.9 3693.9 3735.6 3887.7 5224.7 5231.6 5532.1 5776.0 5818.7 3646.4 3675.2 3973.4 3983.6 3996.2 3347.3 3742.8 4044.1 4056.6 4072.0 4644.3 4665.3 4666.7 4684.8 4689.7 5063.5 5775.8 5811.8 5815.9 5816.5 1066.3 1240.5 1266.9 1294.7 1314.0 869.0 882.6 885.8 892.7 893.4 4439.8 4571.8 4881.3 5139.8 5153.1 5467.3 5607.5 5635.3 5656.7 5671.7 4478.0 4553.9 5008.8 5047.5 5132.9 2400.3 2465.8 2893.9 2911.4 2945.6 4834.7 5412.8 5863.3 5968.2 5970.4 5142.4 5151.8 5274.5 5351.7 5593.3 4860.8 5296.9 5512.9 5974.6 5977.5 4854.9 5213.3 5228.5 5713.6 5739.8 5446.8 5491.3 5532.8 5584.7 5592.4 4998.6 5223.4 5863.8 5881.6 5914.0 3116.0 3512.9 3728.9 3746.5 3798.8 4897.6 5470.9 5629.5 5656.4 5689.5 5565.5 5650.8 5715.5 5720.0 5738.2 5072.8 5463.0 5604.7 5628.7 5737.8 4598.2 5300.9 5543.6 5564.6 5611.4 5227.5 5716.0 5725.5 5730.6 5758.9 4764.0 5362.0 5569.4 5614.4 5651.4 15:59:08 INFO - PROCESS | 1630 | 15:59:08 INFO - PROCESS | 1630 | [#5] yui.html Cycles:180 Average:19637.50 Median:4452.55 stddev:28955.43 (650.3%) stddev-sans-first:29001.36 15:59:08 INFO - PROCESS | 1630 | Values: 594.6 705.2 717.2 746.3 813.9 13952.8 14448.9 14797.6 14814.3 14926.6 13159.7 13337.7 13391.4 13423.5 13426.4 660.9 828.7 853.2 853.9 855.3 676.5 825.4 834.4 850.9 854.0 838.4 877.7 886.1 887.0 887.0 776.1 830.1 874.8 875.3 877.3 619.4 648.4 650.4 652.9 657.5 13533.2 13540.5 13815.9 13840.8 13869.2 12750.6 13153.8 13266.9 13325.8 13442.9 10613.4 10845.8 11178.0 11320.4 11590.5 3560.2 3603.1 3745.1 3757.7 3770.1 90942.5 92148.5 92248.0 93528.5 94112.5 9957.6 10137.5 10176.5 10259.4 10294.4 19982.2 20230.6 20305.9 20358.9 20440.6 6735.6 7538.2 7666.5 7670.9 7955.4 4185.1 4224.0 4265.5 4281.7 4419.9 3355.7 3456.3 3476.3 3481.7 3488.9 624.3 628.2 629.9 631.8 639.1 545.6 546.5 566.6 614.3 615.3 9743.0 9781.7 9881.1 9971.2 10232.9 544.8 611.8 656.3 671.1 676.5 4017.9 4485.2 4490.9 4511.4 4532.4 112691.0 114543.0 115012.0 115648.0 116982.0 50452.0 66089.0 67446.0 67527.7 81130.0 89381.0 89618.0 91032.0 91884.0 92047.0 7609.7 8615.4 8806.8 8818.5 8854.2 39929.8 41077.8 45006.1 50881.0 54451.2 49306.8 59379.0 67443.0 74871.6 77578.6 12177.6 12245.8 12348.3 12486.4 12695.9 13823.4 13846.5 13925.7 13930.7 13953.9 12209.9 12363.8 12388.9 12401.0 12871.3 10813.1 12664.2 12955.9 12988.4 13117.2 10549.4 12782.2 12814.3 12829.9 12919.7 9430.5 10657.5 11017.6 11535.0 12014.7 8873.7 11124.3 11270.4 11297.0 11427.0 15:59:08 INFO - PROCESS | 1630 | -------- Summary: end -------- 15:59:08 INFO - PROCESS | 1630 | 15:59:09 INFO - TEST-INFO | 1630: exit 0 15:59:09 INFO - TEST-OK | dromaeo_css | took 1114842ms 15:59:09 INFO - TEST-START | kraken 15:59:09 INFO - operating with platform_type : mac_ 15:59:09 INFO - Initialising browser for kraken test... 15:59:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpQLi4g2/profile http://localhost:49243/getInfo.html) 15:59:12 INFO - PROCESS | 1642 | 2017-01-18 15:59:12.303 plugin-container[1643:9993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 15:59:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:12 INFO - PROCESS | 1642 | 2017-01-18 15:59:12.306 plugin-container[1643:9993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.CFPasteboardClient' 15:59:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:12 INFO - PROCESS | 1642 | 2017-01-18 15:59:12.306 plugin-container[1643:9993] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:59:12 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 15:59:12 INFO - PROCESS | 1642 | colorDepth:24 15:59:12 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 15:59:12 INFO - PROCESS | 1642 | __metrics 15:59:12 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 15:59:13 INFO - TEST-INFO | 1642: exit 0 15:59:13 INFO - Browser initialized. 15:59:13 INFO - Running cycle 1/1 for kraken test... 15:59:13 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpQLi4g2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:15 INFO - PROCESS | 1644 | 2017-01-18 15:59:15.959 plugin-container[1645:10116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864b, name = 'com.apple.tsm.portname' 15:59:15 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:15 INFO - PROCESS | 1644 | 2017-01-18 15:59:15.960 plugin-container[1645:10116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 15:59:15 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:15 INFO - PROCESS | 1644 | 2017-01-18 15:59:15.960 plugin-container[1645:10116] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:59:25 INFO - PROCESS | 1644 | RSS: Main: 228638720 15:59:25 INFO - PROCESS | 1644 | 15:59:28 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:29 INFO - PROCESS | 1644 | RSS: Main: 241319936 15:59:29 INFO - PROCESS | 1644 | 15:59:32 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 15:59:32 INFO - PROCESS | 1644 | RSS: Main: 249483264 15:59:32 INFO - PROCESS | 1644 | 15:59:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 15:59:36 INFO - PROCESS | 1644 | RSS: Main: 251273216 15:59:36 INFO - PROCESS | 1644 | 15:59:38 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 15:59:39 INFO - PROCESS | 1644 | RSS: Main: 253845504 15:59:39 INFO - PROCESS | 1644 | 15:59:41 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:42 INFO - PROCESS | 1644 | RSS: Main: 254205952 15:59:42 INFO - PROCESS | 1644 | 15:59:45 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:45 INFO - PROCESS | 1644 | RSS: Main: 258109440 15:59:45 INFO - PROCESS | 1644 | 15:59:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:49 INFO - PROCESS | 1644 | RSS: Main: 257867776 15:59:49 INFO - PROCESS | 1644 | 15:59:51 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 15:59:52 INFO - PROCESS | 1644 | RSS: Main: 257896448 15:59:52 INFO - PROCESS | 1644 | 15:59:54 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:54 INFO - PROCESS | 1644 | RSS: Main: 257171456 15:59:54 INFO - PROCESS | 1644 | 15:59:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:57 INFO - PROCESS | 1644 | RSS: Main: 257044480 15:59:57 INFO - PROCESS | 1644 | 15:59:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:00 INFO - PROCESS | 1644 | RSS: Main: 256950272 16:00:00 INFO - PROCESS | 1644 | 16:00:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:04 INFO - PROCESS | 1644 | RSS: Main: 257216512 16:00:04 INFO - PROCESS | 1644 | 16:00:07 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:07 INFO - PROCESS | 1644 | RSS: Main: 257585152 16:00:07 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 16:00:09 INFO - PROCESS | 1644 | RSS: Main: 259424256 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | __start_tp_report 16:00:09 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 16:00:09 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 16:00:09 INFO - PROCESS | 1644 | |i|pagename|runs| 16:00:09 INFO - PROCESS | 1644 | |0;ai-astar;104;115;174;168;118;118;120;118;120;118 16:00:09 INFO - PROCESS | 1644 | |1;audio-beat-detection;134;122;113;141;113;129;141;128;146;98 16:00:09 INFO - PROCESS | 1644 | |2;audio-dft;191;227;192;246;236;289;219;228;275;202 16:00:09 INFO - PROCESS | 1644 | |3;audio-fft;104;86;51;84;85;83;52;57;103;85 16:00:09 INFO - PROCESS | 1644 | |4;audio-oscillator;88;71;89;73;88;67;86;93;103;124 16:00:09 INFO - PROCESS | 1644 | |5;imaging-gaussian-blur;88;86;111;82;134;131;89;86;88;86 16:00:09 INFO - PROCESS | 1644 | |6;imaging-darkroom;94;96;94;94;95;95;94;96;94;95 16:00:09 INFO - PROCESS | 1644 | |7;imaging-desaturate;87;88;88;87;89;88;89;91;90;90 16:00:09 INFO - PROCESS | 1644 | |8;json-parse-financial;57;56;56;58;61;61;60;86;96;93 16:00:09 INFO - PROCESS | 1644 | |9;json-stringify-tinderbox;76;69;70;70;77;76;72;71;73;73 16:00:09 INFO - PROCESS | 1644 | |10;stanford-crypto-aes;85;107;84;86;82;86;90;81;88;86 16:00:09 INFO - PROCESS | 1644 | |11;stanford-crypto-ccm;163;152;143;150;147;164;162;161;239;159 16:00:09 INFO - PROCESS | 1644 | |12;stanford-crypto-pbkdf2;150;229;237;204;166;186;236;237;176;228 16:00:09 INFO - PROCESS | 1644 | |13;stanford-crypto-sha256-iterative;69;66;70;70;67;69;69;68;65;69 16:00:09 INFO - PROCESS | 1644 | __end_tp_report 16:00:09 INFO - PROCESS | 1644 | __start_cc_report 16:00:09 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,949 16:00:09 INFO - PROCESS | 1644 | __end_cc_report 16:00:09 INFO - PROCESS | 1644 | __startTimestamp1484784009828__endTimestamp 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | ------- Summary: start ------- 16:00:09 INFO - PROCESS | 1644 | Number of tests: 14 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#0] ai-astar Cycles:10 Average:127.30 Median:119.00 stddev:23.53 (19.8%) stddev-sans-first:23.40 16:00:09 INFO - PROCESS | 1644 | Values: 104.0 115.0 174.0 168.0 118.0 118.0 120.0 118.0 120.0 118.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:137.50 stddev:15.14 (11.0%) stddev-sans-first:15.81 16:00:09 INFO - PROCESS | 1644 | Values: 134.0 122.0 113.0 141.0 113.0 129.0 141.0 128.0 146.0 98.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#2] audio-dft Cycles:10 Average:230.50 Median:232.00 stddev:32.79 (14.1%) stddev-sans-first:31.51 16:00:09 INFO - PROCESS | 1644 | Values: 191.0 227.0 192.0 246.0 236.0 289.0 219.0 228.0 275.0 202.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#3] audio-fft Cycles:10 Average:79.00 Median:83.50 stddev:19.32 (23.1%) stddev-sans-first:18.25 16:00:09 INFO - PROCESS | 1644 | Values: 104.0 86.0 51.0 84.0 85.0 83.0 52.0 57.0 103.0 85.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:87.00 stddev:16.69 (19.2%) stddev-sans-first:17.70 16:00:09 INFO - PROCESS | 1644 | Values: 88.0 71.0 89.0 73.0 88.0 67.0 86.0 93.0 103.0 124.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:86.00 stddev:19.78 (23.0%) stddev-sans-first:20.64 16:00:09 INFO - PROCESS | 1644 | Values: 88.0 86.0 111.0 82.0 134.0 131.0 89.0 86.0 88.0 86.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 16:00:09 INFO - PROCESS | 1644 | Values: 94.0 96.0 94.0 94.0 95.0 95.0 94.0 96.0 94.0 95.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:89.00 stddev:1.34 (1.5%) stddev-sans-first:1.27 16:00:09 INFO - PROCESS | 1644 | Values: 87.0 88.0 88.0 87.0 89.0 88.0 89.0 91.0 90.0 90.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:61.00 stddev:16.34 (26.8%) stddev-sans-first:16.80 16:00:09 INFO - PROCESS | 1644 | Values: 57.0 56.0 56.0 58.0 61.0 61.0 60.0 86.0 96.0 93.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:73.00 stddev:2.83 (3.9%) stddev-sans-first:2.74 16:00:09 INFO - PROCESS | 1644 | Values: 76.0 69.0 70.0 70.0 77.0 76.0 72.0 71.0 73.0 73.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:86.00 stddev:7.34 (8.5%) stddev-sans-first:7.73 16:00:09 INFO - PROCESS | 1644 | Values: 85.0 107.0 84.0 86.0 82.0 86.0 90.0 81.0 88.0 86.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:164.00 Median:161.50 stddev:27.35 (16.9%) stddev-sans-first:29.01 16:00:09 INFO - PROCESS | 1644 | Values: 163.0 152.0 143.0 150.0 147.0 164.0 162.0 161.0 239.0 159.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.90 Median:228.50 stddev:33.10 (14.5%) stddev-sans-first:28.54 16:00:09 INFO - PROCESS | 1644 | Values: 150.0 229.0 237.0 204.0 166.0 186.0 236.0 237.0 176.0 228.0 16:00:09 INFO - PROCESS | 1644 | 16:00:09 INFO - PROCESS | 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.69 (2.4%) stddev-sans-first:1.76 16:00:09 INFO - PROCESS | 1644 | Values: 69.0 66.0 70.0 70.0 67.0 69.0 69.0 68.0 65.0 69.0 16:00:09 INFO - PROCESS | 1644 | -------- Summary: end -------- 16:00:09 INFO - PROCESS | 1644 | 16:00:10 INFO - TEST-INFO | 1644: exit 0 16:00:10 INFO - TEST-OK | kraken | took 61405ms 16:00:10 INFO - SUITE-END | took 1176s 16:00:10 INFO - Completed test suite (00:19:36) 16:00:10 INFO - javascript benchmark 16:00:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7106.654122679123, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2739.42472080863, 2792.7765778737676, 3037.6482465302943, 3332.7833690810094, 3343.866245350186, 5555.2778138841895, 5583.83248502545, 5586.0, 5668.801591944282, 5687.943120568793, 5732.971335143329, 5803.187553742479, 5841.503472204857, 5846.795362162326, 5846.912296315548, 5246.213068039787, 5268.446813084638, 5288.550473209782, 5313.388960269561, 5373.978944000647, 5250.6587071840395, 5332.680039197641, 5357.142857142858, 5372.7045012524295, 5393.649412788176, 5150.201718733602, 5161.225816127573, 5184.611154163453, 5192.55863251624, 5240.659357141773, 5151.2530683050745, 5153.2270159476375, 5174.689518628894, 5203.453637368098, 5212.635115541913, 4465.8612053926045, 4617.7762892833425, 4664.720116793003, 4763.333133361332, 5121.692698438105, 5702.287214098238, 5729.341125770548, 5759.769609215627, 5766.682832444214, 5781.653100813965, 6155.630662160284, 6248.875022499525, 6250.093736408196, 6271.466925311308, 6279.7174127163935, 6296.275928268262, 6320.115183874262, 6341.524385671093, 6359.65021923794, 6365.554411191219, 5693.715314234271, 5700.230468886647, 5754.1944127822135, 5857.824265272006, 5873.794417195378, 6219.626822390671, 6288.773689130667, 6294.842628934313, 6309.747610095545, 6321.336259692758, 6126.6017708848785, 6132.202813634288, 6138.232720909887, 6176.7838125665385, 6188.690565471798, 6093.360197179232, 6099.054646529795, 6249.812505624838, 6272.874542509125, 6308.053791931247, 6108.236470441195, 6110.450059494667, 6134.00801433197, 6135.294441139326, 6140.074827791203, 6206.975848984957, 6250.312465628777, 6256.343083976117, 6260.24877014752, 6261.812145635638, 4834.806607735652, 4846.345743324677, 4893.09477746618, 4901.142300097468, 4935.259711043373, 3712.3688972874675, 4309.159713855768, 4354.0, 4405.581469760367, 4417.491988421309, 5671.092625179951, 5888.646681199141, 5902.468777810018, 5908.350081491032, 5951.077582974559, 6165.414285642857, 6176.752929882754, 6295.874082518324, 6299.055141728686, 6306.337834527309, 5798.710064496759, 5799.507041901401, 5871.32479764824, 5895.64626122434, 5945.316288626863, 5296.682199068068, 5328.387235467893, 5349.786008559692, 5451.48210919962, 5481.232627432163, 6260.154879091447, 6262.686865656608, 6268.62388256697, 6312.400321969314, 6398.360163983564, 6285.151504546828, 6291.685415729286, 6306.621602703853, 6322.652254126067, 6322.652254126067, 6369.394907483782, 6389.361063893573, 6393.680315984089, 6404.519661025349, 6414.679266036773, 6088.321138698958, 6137.539684523768, 6159.568830182024, 6161.599496032743, 6175.2589689237575, 5508.697021663847, 6276.497880169484, 6288.182536270256, 6312.116303717575, 6314.55798094129, 4731.432228132646, 4978.228374601942, 5422.376426710979, 6198.6280823150755, 6349.6507692077375, 5554.333479982428, 6007.0, 6046.879062418639, 6068.332483426908, 6078.118672792509, 5990.101484777319, 6027.156198132353, 6066.969665151646, 6075.908861366995, 6100.786472473442, 6116.14373987651, 6144.41628045335, 6177.135201071764, 6195.132681424694, 6197.938020619736, 5812.564057695605, 5876.44173803488, 5944.959632064285, 6032.1856549365275, 6076.422739839708], "value": 5666.527833852458, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.94896561378266, 479.84644913627653, 519.1848797388101, 526.5682140644672, 528.0600531054722, 32858.50712239315, 40698.38952415716, 40757.38863917044, 40810.183796324054, 40926.18147637045, 42185.0, 42445.7877710611, 42549.36175957363, 42613.57386426135, 42667.78666106665, 9400.38897471664, 9491.335606507548, 9562.139407453327, 9603.135717785399, 9626.711198664052, 9314.953425232865, 9393.201577865855, 9484.952575237114, 9502.47736374499, 9531.714048578553, 9453.196478299318, 9492.145706886378, 9521.476318802463, 9542.236621070333, 9546.045395460467, 9344.0, 9469.005754395757, 9495.287853410953, 9509.239260859114, 9519.476428796414, 9409.90590094104, 9510.572024258925, 9511.809763804755, 9512.95243523778, 9520.428774273565, 38581.22837543261, 40467.190656187006, 40762.79618601918, 40793.592064079276, 40798.9800254993, 3275.19757659375, 3533.293341331744, 3556.3065202285566, 3632.54593175853, 3764.73646844721, 27736.509117975438, 28866.431025041114, 29236.269093272625, 30062.54906176409, 30245.243868903453, 3174.4444722173525, 3180.9681903180904, 3190.489521676544, 3205.5191721241767, 3219.404410184123, 383032.67869285477, 481929.0, 485603.99999999645, 490335.0, 491444.0, 54312.0, 58795.41204587985, 59334.10998835021, 59420.40579594235, 59598.0, 50756.49243507592, 63562.68218658877, 63829.0, 64440.67779661072, 64442.677786611705, 15534.601885830329, 16019.519414417353, 16226.756598651029, 16230.107344096188, 16232.513024609281, 12362.0, 12382.442790074469, 12421.378931053592, 12445.875541244654, 12472.501099956082, 14059.383170935269, 16253.106079165524, 16958.915205423895, 17226.05256710868, 17263.56841078983, 3168.6356069052354, 3305.4380755271663, 6081.513478921675, 6534.281229064799, 6578.967105164444, 864.4597126795753, 904.8733177355175, 977.8826540815005, 1043.6868939318174, 1075.860138182047, 26381.47237055248, 29172.9789457368, 29294.26764330909, 29914.700852991627, 30591.0, 937.5499760115183, 1078.266778590566, 1083.4853444613746, 1107.141964977133, 1123.3147779854282, 11156.553737850558, 16226.701863850863, 16630.916845415697, 16734.665306693798, 16829.495115146565, 693334.0, 696734.5163274253, 702948.0, 703101.0, 703593.0, 59041.523961901294, 59380.70309648424, 59523.0, 59541.70229148826, 59759.70120149371, 1323.67569945364, 1323.7617228898698, 1339.3437215764206, 1342.9462821487227, 1353.4856754433251, 30430.695693042788, 30577.08268751941, 31142.22144446407, 31635.20911977219, 31732.524012139376, 50794.0, 51274.48725512697, 51294.74352628213, 51361.0, 51470.0, 632161.0, 633099.0, 635072.0, 635502.0, 638254.0, 25405.61891571591, 26819.86590067037, 27182.0, 27256.046038388562, 27295.590566141127, 45021.774891126646, 45053.32420013768, 45642.0, 45747.77126114348, 46395.53604463912, 37079.62920370762, 37954.2409151821, 40130.59869401269, 40220.79889600533, 40608.79695601503, 38863.611363886, 38995.80502097585, 39217.0, 39501.814945551676, 39975.400368993905, 38232.2353552933, 38696.80651596724, 38701.0, 38868.416973744854, 39065.21869562536, 26933.86533067322, 28869.845206192356, 33599.328013440085, 34840.47739283963, 34886.65113348936, 21537.676934845676, 25606.0, 26292.0, 26561.937522498883, 26773.464530709665], "value": 19666.211978472747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.9326921154193, 375.32066958804563, 383.4669808965538, 384.84413812405984, 400.60340263139483, 16038.198090095508, 16480.917595412022, 18130.00284984327, 19393.61212775747, 20084.0, 19237.23051077955, 19438.611227775436, 19776.11007504662, 19891.30380436685, 19944.800551994475, 5419.59353048521, 5686.687232202237, 5935.139404786303, 5940.168376427304, 5942.762289508414, 5839.474447299742, 5947.10793380992, 5954.761809527614, 5959.493443057346, 5961.374055724152, 5644.57665675074, 5766.106253530709, 5800.883982320351, 5900.704964751767, 5922.733476993546, 5833.708314584275, 5857.297124345085, 5875.676837773942, 5879.088741245114, 5904.2029326040865, 5030.471800460972, 5142.07442660321, 5160.14857548504, 5301.628885977983, 5385.380681221671, 10816.621418250323, 12737.426815793218, 12977.87022129776, 13003.154794938298, 22942.65586016211, 2374.620060790265, 2402.9399265018515, 2493.5760920643534, 2618.2407101940416, 2855.5859400386826, 7605.859121131819, 9895.01049895012, 9972.650957216463, 10081.19350451962, 10097.394156350643, 1803.404876390801, 1828.286968082449, 1853.2494339792406, 1876.8780029298052, 1915.339207973261, 119377.40311298474, 121766.99999999911, 122198.3890080544, 125854.0, 125902.8524286891, 8179.918200817916, 9467.769190005392, 9904.55429505674, 10143.340682855738, 10236.641717539851, 12311.938440307742, 12427.565035223724, 12507.437165327583, 12528.123031387715, 12530.74938501243, 6746.460283177334, 7794.41541884361, 7999.480033797901, 8052.114267430578, 8098.392620553482, 6738.258791532928, 7879.093904200976, 8416.579171041423, 8427.073021967579, 8497.787555311166, 3839.8080095995088, 3891.571927088018, 3904.2972264992572, 3919.294526985113, 3953.6639385652543, 1916.57835276239, 1965.6068786242809, 1970.458124015907, 1973.9802601973797, 1982.930597429083, 809.9757007289791, 917.6604656277086, 927.4435338796668, 938.0994245524237, 973.2846357926918, 14895.70208595822, 20077.598448030956, 20445.0, 20651.0, 20675.8966205168, 931.5388882503204, 933.715216858851, 943.5140902435252, 996.7308826616772, 1010.2625083688948, 10325.793484130425, 10690.037896589345, 10744.0, 10756.193285503463, 10770.623028194132, 596311.0, 597198.0, 603295.0, 616501.0, 616725.0, 124756.0, 128060.0, 128148.0777788334, 128651.0, 128860.0, 925.8518637018179, 945.2013048973744, 960.3325688646463, 969.4328817641758, 970.7427531704104, 15632.0, 17884.10579470995, 18530.16614252389, 18909.527261818566, 19192.040397980323, 36411.45382819207, 38280.80859595684, 56345.43654563402, 56973.71513142408, 58234.835303294545, 80502.59748701453, 84576.0, 85904.0, 89271.0, 89702.0, 7789.571573563282, 7955.880661790194, 8136.796580085545, 8260.669573217005, 8297.336213102817, 7457.6271186441545, 11167.329960202414, 12575.622731318075, 12781.680457988625, 13016.0, 7995.960020199862, 8964.103589640983, 11105.0, 11231.831522527005, 11415.543378264776, 10717.0, 10943.945280273547, 10977.725556861142, 11310.434478276218, 11364.772704546029, 8532.914670853212, 10832.0, 10896.182786291218, 11118.277311974696, 11227.943860280646, 8010.599470026592, 8098.635561399633, 8184.419047048597, 8252.711155109542, 8286.171382861907, 5473.233747275464, 7907.446478746432, 8252.504849709037, 10746.462676866282, 11003.22977391575], "value": 9070.654913187622, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.4088882669278, 1453.8400775914645, 1643.7945256842895, 1774.5297496163512, 1804.305342443159, 7599.582022988733, 7856.450048496607, 8101.311388531974, 8588.098249683788, 8590.570471476432, 8064.475809072406, 8264.504129752204, 8370.288525475342, 8483.0, 8502.277306428945, 2316.4324740438615, 2333.7549557296493, 2345.6012477878803, 2350.905963761456, 2356.3284463927785, 2348.7886090251873, 2369.1707902234252, 2381.9166329178483, 2396.4727759892794, 2398.5322862041908, 2207.3377986604037, 2317.3974766560646, 2352.1414683640432, 2353.317537914003, 2360.291912426274, 2310.676505289261, 2316.4903721181313, 2332.941676458085, 2336.8364214504904, 2342.0, 2349.400902769783, 2369.869657168855, 2371.0989823866994, 2375.572396968545, 2379.9885048854167, 8440.071592124923, 8458.323334133316, 8459.492430454131, 8528.061913189544, 8548.145185481464, 5082.872928176788, 5100.515451032146, 5203.375594928595, 5248.475152484759, 5276.261323414725, 3232.353529294127, 3366.1416338833687, 3469.826508674556, 3486.581610206766, 3490.842912068963, 1051.5530899367739, 1053.7912057784065, 1091.825307950732, 1096.4243772019674, 1103.6358001859448, 10857.185711071701, 10954.178436617285, 10968.670939871816, 11024.228304018721, 11077.003069723683, 4520.412346394947, 4625.930611040837, 4687.671862969628, 4726.220173671374, 4771.141194585008, 9452.149306562305, 9467.621295148117, 9553.522323883779, 9564.56959436827, 9575.616975320909, 5226.0, 5236.188390799432, 5247.973760131251, 5257.08001099806, 5261.605379596469, 4293.205756934977, 4302.698811083257, 4729.69256998294, 4751.3348131261655, 4800.711957282574, 2961.8074825136296, 3133.27934575049, 3482.860685572549, 3884.3396622574232, 3918.804059796999, 1274.9298788566532, 1293.780057390246, 1294.3657607772125, 1307.5685023942076, 1308.0385916351474, 756.9091708994956, 865.1435079271602, 893.8525143351404, 915.4919019943995, 915.697819719491, 4668.11305851887, 4859.757012149378, 5628.521575666033, 5728.942710572841, 5877.9118313225335, 795.741384050186, 873.7204094689638, 887.2015186332352, 892.2505095719627, 935.1630290889616, 3793.5447746270625, 3839.040239940015, 3872.6514613684903, 3884.0484081399673, 3886.7862267574988, 9997.200223982063, 10279.897201028043, 10300.433476158732, 10343.482825858677, 10364.948175259075, 9428.915674697497, 9491.572879220452, 10244.948775256076, 10290.845637315446, 10336.844947325797, 7308.159561650372, 7427.442941779388, 7463.776086717407, 7842.215778422226, 7883.132855385903, 5777.74000169985, 5779.422057794187, 5853.0, 5925.1704761332585, 5999.370066143167, 9134.22359099484, 9289.72130836076, 9460.574274157818, 9516.85724714116, 9548.283878708986, 7295.854082918206, 9138.451692898447, 9576.616935322509, 9589.856152157583, 9656.75858103553, 6606.8017959461295, 6667.133272674715, 6966.930330696831, 7119.288071192839, 7122.358987691133, 7904.13054564009, 7939.523628582304, 7986.480878742863, 8010.198980101944, 8011.238932301422, 7062.1878483975, 7234.0, 7262.564246145248, 7263.055802745609, 7310.744123955636, 7014.579125252501, 7051.541649792747, 7082.716691332495, 7201.963990180016, 7211.134663840373, 6491.837704057436, 6572.211334639874, 6592.406683398517, 6621.0, 6673.566218195801, 4721.2918062289555, 4739.976300118477, 4773.045390921899, 4774.140654682191, 4776.8566942991765, 4653.697509661861, 4686.92969605463, 4732.219183834629, 4873.853784386474, 4928.088303663833], "value": 4420.068341858392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.71568421788686, 305.3541759179336, 313.692581270355, 315.39286872769924, 318.17751113371935, 5284.630075894689, 5450.073487507123, 5486.341639003315, 5493.91759123613, 5619.77520899164, 5172.45689202633, 5355.651882627636, 5365.54392876605, 5387.973060134704, 5412.621116521845, 3687.723420743455, 4088.6115818997146, 4096.098858251187, 4108.712390132692, 4136.441580386654, 3385.492176173569, 4074.9185016299657, 4087.20299541588, 4102.159057393242, 4182.539920608746, 4007.6192761687594, 4021.879343619696, 4057.8173982170724, 4068.3897415387783, 4083.0, 3555.6444355564236, 3705.3886108792017, 4046.7571945683353, 4053.148838743867, 4089.89775255618, 2874.942501149986, 2942.45564570553, 3088.7528997680133, 3524.700400465938, 3532.5231093802263, 4093.2222877653153, 4995.171102283046, 5171.456997015334, 5234.26720259164, 5245.291885595434, 4599.0, 4618.630509559227, 4622.121796858586, 4625.213713668684, 4640.118377508263, 5003.199488081889, 5547.362053363834, 5629.268195134648, 5633.971830140864, 5653.219235940665, 3060.56153607804, 3514.894553163409, 3693.947225040877, 3735.5618087036414, 3887.7278590498677, 5224.660397074216, 5231.555317797992, 5532.1425179097305, 5776.0, 5818.738156782955, 3646.3801153803915, 3675.2358867743096, 3973.4039894016128, 3983.6016398359934, 3996.200759847984, 3347.3305338931823, 3742.8128593570214, 4044.130511939947, 4056.6066021047723, 4071.97964010184, 4644.2801365788355, 4665.346851440801, 4666.673332866734, 4684.81260749573, 4689.6717229793585, 5063.493650634907, 5775.826725198251, 5811.796587119515, 5815.912761308584, 5816.47651711348, 1066.2726383259014, 1240.4789988204975, 1266.872483489695, 1294.6504443800122, 1313.960581182566, 869.0049892872626, 882.6469412235028, 885.7696998780364, 892.6610084872691, 893.4282059481936, 4439.755813430293, 4571.828381518819, 4881.340344282961, 5139.785375991651, 5153.072446959508, 5467.289252397242, 5607.523360514402, 5635.323761148688, 5656.717164141776, 5671.688057156896, 4477.977610111929, 4553.863384098416, 5008.824691135793, 5047.545720885138, 5132.923006154911, 2400.287674927321, 2465.778079972811, 2893.885853946246, 2911.4177164567172, 2945.631796025497, 4834.685745426536, 5412.810551630674, 5863.2670916135485, 5968.22413086296, 5970.402959704082, 5142.431558374732, 5151.793928242903, 5274.496768421059, 5351.732413379315, 5593.329569809173, 4860.756962151878, 5296.894062118813, 5512.944870551403, 5974.581779275496, 5977.462028417463, 4854.8786280341865, 5213.270142180174, 5228.451012643618, 5713.600047996601, 5739.799107031234, 5446.754896029609, 5491.313585801775, 5532.778688852401, 5584.692841893735, 5592.384837667934, 4998.625103117208, 5223.399309079505, 5863.765449381977, 5881.588288819741, 5913.970430147821, 3115.9532607011365, 3512.8594856205464, 3728.925421491609, 3746.475493430867, 3798.7910664913693, 4897.632677549127, 5470.91793623104, 5629.549636029026, 5656.406077361818, 5689.516391106802, 5565.526930210977, 5650.773969041193, 5715.514181294637, 5720.0, 5738.22533957927, 5072.797088116582, 5462.995352556158, 5604.719764011865, 5628.664104396629, 5737.827865164052, 4598.195315819785, 5300.920486192633, 5543.584231182596, 5564.551597297176, 5611.410801865745, 5227.477252274742, 5715.971420142872, 5725.484706376446, 5730.627509211888, 5758.884822303614, 4764.04674083624, 5362.008028514737, 5569.35952365467, 5614.382417934105, 5651.411353790455], "value": 4048.4583114483958, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.5689375202978, 705.1890326124959, 717.2182321269814, 746.2761121711941, 813.8616435206014, 13952.790708139373, 14448.855511444908, 14797.630059248524, 14814.259287035658, 14926.552203433886, 13159.6710082248, 13337.733245335088, 13391.397387117579, 13423.463061477529, 13426.39581218845, 660.9173853268342, 828.7016673997372, 853.159637756809, 853.871919212117, 855.2644725536065, 676.5129107042922, 825.3603457320595, 834.4117397234966, 850.8893843800297, 853.9794945040647, 838.4466252273501, 877.6796469288728, 886.0740526150156, 886.9799730310131, 886.9822603547925, 776.1307335783958, 830.0786127398583, 874.7550685807984, 875.3391189651804, 877.3113106211625, 619.3744318238566, 648.3807963394946, 650.4178760009812, 652.8661624366994, 657.4904449051975, 13533.18800871941, 13540.458381664821, 13815.930920345334, 13840.792388114187, 13869.167849928936, 12750.61748147557, 13153.802692959613, 13266.933665331708, 13325.800112998313, 13442.865571344259, 10613.363198208132, 10845.755781232057, 11177.99398054179, 11320.433978301135, 11590.536378544848, 3560.2167523145126, 3603.100620622611, 3745.138618117848, 3757.680597149218, 3770.1140232045445, 90942.5452872738, 92148.53925730396, 92248.0, 93528.53235733845, 94112.52943735306, 9957.601695932226, 10137.493125343703, 10176.491175441346, 10259.384436933808, 10294.3823370598, 19982.20071197165, 20230.595388092155, 20305.898470507553, 20358.88026158546, 20440.591188176153, 6735.595864248063, 7538.170801211862, 7666.501677390952, 7670.884936725954, 7955.443118981616, 4185.100203456272, 4224.0, 4265.4881414230495, 4281.691258131066, 4419.867403977886, 3355.681210285019, 3456.3087382523595, 3476.3394954958476, 3481.651834816498, 3488.9127771805906, 624.2781472778681, 628.1833616298793, 629.9181106456132, 631.782035197856, 639.0956796133512, 545.585355130104, 546.5245236644145, 566.623195574938, 614.261639560044, 615.2770494668746, 9743.0, 9781.65764198264, 9881.110700037032, 9971.202303815677, 10232.948835255776, 544.8147629805885, 611.7614130489023, 656.2659062943286, 671.0806195512082, 676.4689718570899, 4017.8995525111523, 4485.237331432324, 4490.887727806766, 4511.413516242867, 4532.365468834366, 112691.0, 114543.0, 115012.0, 115648.0, 116982.0, 50452.0, 66089.0, 67446.0, 67527.66236168788, 81130.0, 89381.0, 89618.0, 91032.0, 91884.0, 92047.0, 7609.695612175672, 8615.396922215636, 8806.779830504289, 8818.51498167607, 8854.158854908774, 39929.80035099806, 41077.79461102795, 45006.09987800291, 50881.0, 54451.18323225234, 49306.75346623244, 59379.0, 67443.0, 74871.62564187145, 77578.61210693911, 12177.573784917486, 12245.755084898074, 12348.259104453762, 12486.43811028524, 12695.93652031734, 13823.447062117402, 13846.515371961934, 13925.651858703613, 13930.651733706332, 13953.930230348784, 12209.938950305192, 12363.784196487773, 12388.946939510242, 12401.0, 12871.292078935749, 10813.06752948539, 12664.176828506117, 12955.870441295467, 12988.415521301375, 13117.212967221998, 10549.419781912069, 12782.233066015697, 12814.29521376296, 12829.871701282867, 12919.741605168032, 9430.52847357643, 10657.520411581652, 11017.614383496539, 11535.0, 12014.699632508908, 8873.689420870238, 11124.276922000045, 11270.436478176221, 11297.039751621205, 11427.0], "value": 7121.141076017061, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1598.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 174.0, 168.0, 118.0, 118.0, 120.0, 118.0, 120.0, 118.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 113.0, 141.0, 113.0, 129.0, 141.0, 128.0, 146.0, 98.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 227.0, 192.0, 246.0, 236.0, 289.0, 219.0, 228.0, 275.0, 202.0], "value": 230.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 86.0, 51.0, 84.0, 85.0, 83.0, 52.0, 57.0, 103.0, 85.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 89.0, 73.0, 88.0, 67.0, 86.0, 93.0, 103.0, 124.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 111.0, 82.0, 134.0, 131.0, 89.0, 86.0, 88.0, 86.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 94.0, 94.0, 95.0, 95.0, 94.0, 96.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 87.0, 89.0, 88.0, 89.0, 91.0, 90.0, 90.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 58.0, 61.0, 61.0, 60.0, 86.0, 96.0, 93.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 69.0, 70.0, 70.0, 77.0, 76.0, 72.0, 71.0, 73.0, 73.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 107.0, 84.0, 86.0, 82.0, 86.0, 90.0, 81.0, 88.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 152.0, 143.0, 150.0, 147.0, 164.0, 162.0, 161.0, 239.0, 159.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 229.0, 237.0, 204.0, 166.0, 186.0, 236.0, 237.0, 176.0, 228.0], "value": 204.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 70.0, 70.0, 67.0, 69.0, 69.0, 68.0, 65.0, 69.0], "value": 68.2, "unit": "score"}]}]} 16:00:11 INFO - Return code: 0 16:00:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:11 ERROR - # TBPL SUCCESS # 16:00:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:11 INFO - 2017-01-18 16:00:11.157 screenresolution[1649:10496] starting screenresolution argv=screenresolution get 16:00:11 INFO - 2017-01-18 16:00:11.160 screenresolution[1649:10496] Display 0: 1600x1200x32@60 16:00:11 INFO - 2017-01-18 16:00:11.166 screenresolution[1650:10499] starting screenresolution argv=screenresolution list 16:00:11 INFO - Available Modes on Display 0 16:00:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:11 INFO - Intel Iris: 16:00:11 INFO - Chipset Model: Intel Iris 16:00:11 INFO - Type: GPU 16:00:11 INFO - Bus: Built-In 16:00:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:11 INFO - Vendor: Intel (0x8086) 16:00:11 INFO - Device ID: 0x0a2e 16:00:11 INFO - Revision ID: 0x0009 16:00:11 INFO - Displays: 16:00:11 INFO - Display: 16:00:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:11 INFO - Display Serial Number: 1600x1200 60 16:00:11 INFO - Main Display: Yes 16:00:11 INFO - Mirror: Off 16:00:11 INFO - Online: Yes 16:00:11 INFO - Rotation: Supported 16:00:11 INFO - Return code: 0 16:00:11 INFO - Running post-action listener: _resource_record_post_action 16:00:11 INFO - [mozharness: 2017-01-19 00:00:11.408576Z] Finished run-tests step (success) 16:00:11 INFO - Running post-run listener: _resource_record_post_run 16:00:11 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 38877696; Write bytes: 657764864; Read time: 245; Write time: 1986 16:00:11 INFO - TinderboxPrint: CPU usage
54.4% 16:00:11 INFO - TinderboxPrint: I/O read bytes / time
38,877,696 / 245 16:00:11 INFO - TinderboxPrint: I/O write bytes / time
657,764,864 / 1,986 16:00:11 INFO - TinderboxPrint: CPU idle
2,203.0 (45.6%) 16:00:11 INFO - TinderboxPrint: CPU system
977.4 (20.2%) 16:00:11 INFO - TinderboxPrint: CPU user
1,649.4 (34.2%) 16:00:11 INFO - TinderboxPrint: Swap in / out
388,599,808 / 0 16:00:11 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 333752832; Write bytes: 334202880; Read time: 25804; Write time: 748 16:00:11 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37911040; Write bytes: 305838592; Read time: 232; Write time: 1183 16:00:11 INFO - Running post-run listener: _upload_blobber_files 16:00:11 INFO - Blob upload gear active. 16:00:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:11 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 16:00:11 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:11 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:12 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:00:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:00:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:13 INFO - (blobuploader) - INFO - Done attempting. 16:00:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:00:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:00:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:14 INFO - (blobuploader) - INFO - Done attempting. 16:00:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:16 INFO - (blobuploader) - INFO - Done attempting. 16:00:16 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:16 INFO - Return code: 0 16:00:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc430dc2906a62dd40dfa6f3218243adaffe718414b006059fc2f9ff1d2e5ab69f969390be578901e44bbe72ca34dcf0e64dc1649fe3eb556f578dcd0868ff70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766d592b1f9d13c0cce9d826c3f828a9d79386381b624a8fe1f056a5467a426112306aeb0a25ec1c5bff2c010a8da35560ac124af4ef72fba41d750e63f29f8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e42e0a9a7063fac015299b578fb4b4ef4357cb9e47804257309e7718bb30ef93135bbee31d0f9f43b63532552f857e572b1ef4a076a347466b6092a0930cffba"} 16:00:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:16 INFO - Contents: 16:00:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc430dc2906a62dd40dfa6f3218243adaffe718414b006059fc2f9ff1d2e5ab69f969390be578901e44bbe72ca34dcf0e64dc1649fe3eb556f578dcd0868ff70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766d592b1f9d13c0cce9d826c3f828a9d79386381b624a8fe1f056a5467a426112306aeb0a25ec1c5bff2c010a8da35560ac124af4ef72fba41d750e63f29f8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e42e0a9a7063fac015299b578fb4b4ef4357cb9e47804257309e7718bb30ef93135bbee31d0f9f43b63532552f857e572b1ef4a076a347466b6092a0930cffba"} 16:00:16 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:16 INFO - Copying logs to upload dir... 16:00:16 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.820105 ========= master_lag: 0.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-18 16:00:17.195666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 16:00:17.199024) ========= 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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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/Mozilla-Aurora/sha512/cc430dc2906a62dd40dfa6f3218243adaffe718414b006059fc2f9ff1d2e5ab69f969390be578901e44bbe72ca34dcf0e64dc1649fe3eb556f578dcd0868ff70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766d592b1f9d13c0cce9d826c3f828a9d79386381b624a8fe1f056a5467a426112306aeb0a25ec1c5bff2c010a8da35560ac124af4ef72fba41d750e63f29f8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e42e0a9a7063fac015299b578fb4b4ef4357cb9e47804257309e7718bb30ef93135bbee31d0f9f43b63532552f857e572b1ef4a076a347466b6092a0930cffba"} build_url:https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011106 build_url: 'https://queue.taskcluster.net/v1/task/IpajhaVyQ6Kva9_kB-ogiA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc430dc2906a62dd40dfa6f3218243adaffe718414b006059fc2f9ff1d2e5ab69f969390be578901e44bbe72ca34dcf0e64dc1649fe3eb556f578dcd0868ff70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766d592b1f9d13c0cce9d826c3f828a9d79386381b624a8fe1f056a5467a426112306aeb0a25ec1c5bff2c010a8da35560ac124af4ef72fba41d750e63f29f8e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e42e0a9a7063fac015299b578fb4b4ef4357cb9e47804257309e7718bb30ef93135bbee31d0f9f43b63532552f857e572b1ef4a076a347466b6092a0930cffba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 16:00:17.234292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:00:17.234634) ========= 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.w2c4DL9ErN/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.puJ6mmyT0X/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.005198 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 16:00:17.283455) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 16:00:17.283783) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 16:00:17.284091) ========= ========= Total master_lag: 0.78 =========