builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0039 starttime: 1476777653.27 results: success (0) buildid: 20161018000149 builduid: 7de1353aab50418a95fa5cce6b03ca5b revision: 47b58740f054c12925c2bb62f65fc05a559569e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.266491) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.266960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.267290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.294807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.295215) ========= 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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-18 01:00:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-10-18 01:00:53 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103977 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.421927) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.422267) ========= 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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.028206 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.472823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:53.473270) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 47b58740f054c12925c2bb62f65fc05a559569e6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 47b58740f054c12925c2bb62f65fc05a559569e6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-18 01:00:53,550 truncating revision to first 12 chars 2016-10-18 01:00:53,550 Setting DEBUG logging. 2016-10-18 01:00:53,550 attempt 1/10 2016-10-18 01:00:53,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/47b58740f054?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 01:00:53,851 unpacking tar archive at: autoland-47b58740f054/testing/mozharness/ program finished with exit code 0 elapsedTime=0.583167 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:54.079382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:54.079774) ========= script_repo_revision: 47b58740f054c12925c2bb62f65fc05a559569e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:54.080178) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:54.080468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 01:00:54.099469) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-18 01:00:54.099917) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:00:54 INFO - MultiFileLogger online at 20161018 01:00:54 in /builds/slave/test 01:00:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 01:00:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:00:54 INFO - {'append_to_log': False, 01:00:54 INFO - 'base_work_dir': '/builds/slave/test', 01:00:54 INFO - 'blob_upload_branch': 'Autoland', 01:00:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:00:54 INFO - 'branch': 'Autoland', 01:00:54 INFO - 'buildbot_json_path': 'buildprops.json', 01:00:54 INFO - 'config_files': ('talos/mac_config.py',), 01:00:54 INFO - 'default_actions': ('clobber', 01:00:54 INFO - 'read-buildbot-config', 01:00:54 INFO - 'download-and-extract', 01:00:54 INFO - 'populate-webroot', 01:00:54 INFO - 'create-virtualenv', 01:00:54 INFO - 'install', 01:00:54 INFO - 'run-tests'), 01:00:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:00:54 INFO - 'download_minidump_stackwalk': True, 01:00:54 INFO - 'download_symbols': 'ondemand', 01:00:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:00:54 INFO - 'tooltool.py': '/tools/tooltool.py', 01:00:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:00:54 INFO - '/tools/misc-python/virtualenv.py')}, 01:00:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:00:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:00:54 INFO - 'installer_path': 'installer.exe', 01:00:54 INFO - 'log_level': 'info', 01:00:54 INFO - 'log_name': 'talos', 01:00:54 INFO - 'log_to_console': True, 01:00:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:00:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:00:54 INFO - 'opt_config_files': (), 01:00:54 INFO - 'pip_index': False, 01:00:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:00:54 INFO - 'cmd': ('bash', 01:00:54 INFO - '-c', 01:00:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:00:54 INFO - 'enabled': True, 01:00:54 INFO - 'halt_on_failure': False, 01:00:54 INFO - 'name': 'check_screen_resolution'},), 01:00:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:00:54 INFO - 'cmd': ('bash', 01:00:54 INFO - '-c', 01:00:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:00:54 INFO - 'enabled': True, 01:00:54 INFO - 'halt_on_failure': False, 01:00:54 INFO - 'name': 'check_screen_resolution'},), 01:00:54 INFO - 'run_cmd_checks_enabled': True, 01:00:54 INFO - 'sps_profile': False, 01:00:54 INFO - 'sps_profile_interval': 0, 01:00:54 INFO - 'suite': 'dromaeojs', 01:00:54 INFO - 'system_bits': '32', 01:00:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:00:54 INFO - 'title': 't-yosemite-r7-0039', 01:00:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:00:54 INFO - 'use_talos_json': True, 01:00:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:00:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:00:54 INFO - 'work_dir': 'build'} 01:00:54 INFO - [mozharness: 2016-10-18 08:00:54.309115Z] Running clobber step. 01:00:54 INFO - Running pre-action listener: _resource_record_pre_action 01:00:54 INFO - Running main action method: clobber 01:00:54 INFO - rmtree: /builds/slave/test/build 01:00:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:00:56 INFO - Running post-action listener: _resource_record_post_action 01:00:56 INFO - [mozharness: 2016-10-18 08:00:56.374428Z] Finished clobber step (success) 01:00:56 INFO - [mozharness: 2016-10-18 08:00:56.374556Z] Running read-buildbot-config step. 01:00:56 INFO - Running pre-action listener: _resource_record_pre_action 01:00:56 INFO - Running main action method: read_buildbot_config 01:00:56 INFO - Using buildbot properties: 01:00:56 INFO - { 01:00:56 INFO - "project": "", 01:00:56 INFO - "product": "firefox", 01:00:56 INFO - "script_repo_revision": "production", 01:00:56 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 01:00:56 INFO - "repository": "", 01:00:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 01:00:56 INFO - "buildid": "20161018000149", 01:00:56 INFO - "pgo_build": "False", 01:00:56 INFO - "basedir": "/builds/slave/test", 01:00:56 INFO - "buildnumber": 446, 01:00:56 INFO - "slavename": "t-yosemite-r7-0039", 01:00:56 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:00:56 INFO - "platform": "yosemite_r7", 01:00:56 INFO - "scheduler": "tests-autoland-macosx64-talos", 01:00:56 INFO - "branch": "autoland", 01:00:56 INFO - "repo_path": "integration/autoland", 01:00:56 INFO - "revision": "47b58740f054c12925c2bb62f65fc05a559569e6", 01:00:56 INFO - "stage_platform": "macosx64", 01:00:56 INFO - "builduid": "7de1353aab50418a95fa5cce6b03ca5b", 01:00:56 INFO - "slavebuilddir": "test" 01:00:56 INFO - } 01:00:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 01:00:56 INFO - Running post-action listener: _resource_record_post_action 01:00:56 INFO - [mozharness: 2016-10-18 08:00:56.377775Z] Finished read-buildbot-config step (success) 01:00:56 INFO - [mozharness: 2016-10-18 08:00:56.377877Z] Running download-and-extract step. 01:00:56 INFO - Running pre-action listener: _resource_record_pre_action 01:00:56 INFO - Running main action method: download_and_extract 01:00:56 INFO - mkdir: /builds/slave/test/build/tests 01:00: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')]} 01:00:56 INFO - trying https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 01:00:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:00:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 01:00:57 INFO - Downloaded 1199 bytes. 01:00:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:00:57 INFO - Using the following test package requirements: 01:00:57 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:00:57 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 01:00:57 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 01:00:57 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'jsshell-mac.zip'], 01:00:57 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 01:00:57 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:00:57 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 01:00:57 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 01:00:57 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 01:00:57 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:00:57 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 01:00:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:00:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:00:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:00:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:00:58 INFO - Http code: 200 01:00:58 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 01:00:58 INFO - x-amz-cf-id: fyG6T_chZGRwetJgpk5nQUDPQet23RrapnnKzOvt5BGFI5EQxERAGw== 01:00:58 INFO - x-amz-version-id: uPphLrYPO3yZVSUM7wnpgUh8gm.XJnpS 01:00:58 INFO - Expected file size: 16483799 01:00:58 INFO - Obtained file size: 16483799 01:01:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:01:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:01:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:01:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:01:05 INFO - Http code: 200 01:01:05 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 01:01:05 INFO - x-amz-cf-id: k3jxCpN-KGlGc5gdTrbollQGKAsBepdXx88wFWgEfykOaumdg092hw== 01:01:05 INFO - x-amz-version-id: uPphLrYPO3yZVSUM7wnpgUh8gm.XJnpS 01:01:06 INFO - Expected file size: 16483799 01:01:06 INFO - Obtained file size: 16483799 01:01:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 01:01:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:01:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 01:01:13 INFO - Http code: 200 01:01:13 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 01:01:13 INFO - x-amz-cf-id: 9dCC0bKWuPfu235Z2RsXrjlIEF2tzoIoF8K9RdaPLIx1CURk7YyiCA== 01:01:13 INFO - x-amz-version-id: bRKgOXHnUEkgOruDGaaf.SvI8uABT8Ll 01:01:13 INFO - Expected file size: 14409028 01:01:13 INFO - Obtained file size: 14409028 01:01:14 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')]} 01:01:14 INFO - trying https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:01:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 01:01:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 01:01:17 INFO - Downloaded 111638373 bytes. 01:01:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:01:17 INFO - mkdir: /builds/slave/test/properties 01:01:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:01:17 INFO - Writing to file /builds/slave/test/properties/build_url 01:01:17 INFO - Contents: 01:01:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:01:17 INFO - Running post-action listener: _resource_record_post_action 01:01:17 INFO - Running post-action listener: set_extra_try_arguments 01:01:17 INFO - [mozharness: 2016-10-18 08:01:17.744862Z] Finished download-and-extract step (success) 01:01:17 INFO - [mozharness: 2016-10-18 08:01:17.745023Z] Running populate-webroot step. 01:01:17 INFO - Running pre-action listener: _resource_record_pre_action 01:01:17 INFO - Running main action method: populate_webroot 01:01:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:01:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:01:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:01:17 INFO - '--tppagecycles', 01:01:17 INFO - '7'], 01:01:17 INFO - 'tests': ['tsvgm']}}, 01:01:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:01:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:01:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:01:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:01:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:01:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:01:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['damp', 'tps']}, 01:01:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'tests': ['damp', 'tps']}, 01:01:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['dromaeo_dom']}, 01:01:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:01:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['basic_compositor_video']}, 01:01:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:01:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['a11yr', 01:01:17 INFO - 'ts_paint', 01:01:17 INFO - 'tpaint', 01:01:17 INFO - 'sessionrestore', 01:01:17 INFO - 'sessionrestore_no_auto_restore', 01:01:17 INFO - 'tabpaint']}, 01:01:17 INFO - 'other-e10s': {'tests': ['a11yr', 01:01:17 INFO - 'ts_paint', 01:01:17 INFO - 'tpaint', 01:01:17 INFO - 'sessionrestore', 01:01:17 INFO - 'sessionrestore_no_auto_restore', 01:01:17 INFO - 'tabpaint']}, 01:01:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:01:17 INFO - 'ts_paint', 01:01:17 INFO - 'tpaint', 01:01:17 INFO - 'sessionrestore', 01:01:17 INFO - 'sessionrestore_no_auto_restore', 01:01:17 INFO - 'tabpaint']}, 01:01:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:01:17 INFO - 'ts_paint', 01:01:17 INFO - 'tpaint', 01:01:17 INFO - 'sessionrestore', 01:01:17 INFO - 'sessionrestore_no_auto_restore', 01:01:17 INFO - 'tabpaint']}, 01:01:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['a11yr', 01:01:17 INFO - 'ts_paint', 01:01:17 INFO - 'tpaint', 01:01:17 INFO - 'sessionrestore', 01:01:17 INFO - 'sessionrestore_no_auto_restore', 01:01:17 INFO - 'tabpaint']}, 01:01:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['a11yr', 01:01:17 INFO - 'ts_paint', 01:01:17 INFO - 'tpaint', 01:01:17 INFO - 'sessionrestore', 01:01:17 INFO - 'sessionrestore_no_auto_restore', 01:01:17 INFO - 'tabpaint']}, 01:01:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['tsvgx', 01:01:17 INFO - 'tsvgr_opacity', 01:01:17 INFO - 'tart', 01:01:17 INFO - 'tscrollx', 01:01:17 INFO - 'cart']}, 01:01:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:01:17 INFO - 'tsvgr_opacity', 01:01:17 INFO - 'tart', 01:01:17 INFO - 'tscrollx', 01:01:17 INFO - 'cart']}, 01:01:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'talos_options': ['--disable-e10s'], 01:01:17 INFO - 'tests': ['tp5o']}, 01:01:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'tests': ['tp5o']}, 01:01:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'talos_options': ['--disable-e10s', 01:01:17 INFO - '--xperf_path', 01:01:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:01:17 INFO - 'tests': ['tp5n']}, 01:01:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:01:17 INFO - 'talos_options': ['--xperf_path', 01:01:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:01:17 INFO - 'tests': ['tp5n']}}, 01:01:17 INFO - 'talos.zip': {'path': '', 01:01:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:01:17 INFO - Running post-action listener: _resource_record_post_action 01:01:17 INFO - [mozharness: 2016-10-18 08:01:17.764376Z] Finished populate-webroot step (success) 01:01:17 INFO - [mozharness: 2016-10-18 08:01:17.764477Z] Running create-virtualenv step. 01:01:17 INFO - Running pre-action listener: _resource_record_pre_action 01:01:17 INFO - Running main action method: create_virtualenv 01:01:17 INFO - Creating virtualenv /builds/slave/test/build/venv 01:01:17 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 01:01:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:01:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:01:17 INFO - Using real prefix '/tools/python27' 01:01:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:01:18 INFO - Installing distribute.............................................................................................................................................................................................done. 01:01:21 INFO - Installing pip.................done. 01:01:21 INFO - Return code: 0 01:01:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:01:21 INFO - Reading from file tmpfile_stdout 01:01:21 INFO - Output received: 01:01:21 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) 01:01:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:21 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:21 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 01:01:21 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 01:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:21 INFO - 'HOME': '/Users/cltbld', 01:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:21 INFO - 'LOGNAME': 'cltbld', 01:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:21 INFO - 'MOZ_NO_REMOTE': '1', 01:01:21 INFO - 'NO_EM_RESTART': '1', 01:01:21 INFO - 'PAGER': '/bin/cat', 01:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:21 INFO - 'PWD': '/builds/slave/test', 01:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:21 INFO - 'SHELL': '/bin/bash', 01:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:21 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:21 INFO - 'USER': 'cltbld', 01:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:21 INFO - 'XPC_FLAGS': '0x0', 01:01:21 INFO - 'XPC_SERVICE_NAME': '0', 01:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:22 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 01:01:22 INFO - Cleaning up... 01:01:22 INFO - Return code: 0 01:01:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:01:22 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')]} 01:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:22 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')]} 01:01:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:22 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:22 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 01:01:22 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 01:01:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:22 INFO - 'HOME': '/Users/cltbld', 01:01:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:22 INFO - 'LOGNAME': 'cltbld', 01:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:22 INFO - 'MOZ_NO_REMOTE': '1', 01:01:22 INFO - 'NO_EM_RESTART': '1', 01:01:22 INFO - 'PAGER': '/bin/cat', 01:01:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:22 INFO - 'PWD': '/builds/slave/test', 01:01:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:22 INFO - 'SHELL': '/bin/bash', 01:01:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:22 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:22 INFO - 'USER': 'cltbld', 01:01:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:22 INFO - 'XPC_FLAGS': '0x0', 01:01:22 INFO - 'XPC_SERVICE_NAME': '0', 01:01:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:22 INFO - Downloading/unpacking psutil>=3.1.1 01:01:22 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 01:01:22 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 01:01:22 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 01:01:22 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 01:01:22 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 01:01:22 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 01:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:01:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:01:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:01:25 INFO - Installing collected packages: psutil 01:01:25 INFO - Running setup.py install for psutil 01:01:25 INFO - building 'psutil._psutil_osx' extension 01:01:25 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 01:01:26 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 01:01:26 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 01:01:26 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 01:01:26 INFO - building 'psutil._psutil_posix' extension 01:01:26 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 01:01:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:01:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:01:26 INFO - ^ 01:01:26 INFO - 1 warning generated. 01:01:26 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 01:01:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:01:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:01:26 INFO - Successfully installed psutil 01:01:26 INFO - Cleaning up... 01:01:26 INFO - Return code: 0 01:01:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:01: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')]} 01:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01: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')]} 01:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:26 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:26 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 01:01:26 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 01:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:26 INFO - 'HOME': '/Users/cltbld', 01:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:26 INFO - 'LOGNAME': 'cltbld', 01:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:26 INFO - 'MOZ_NO_REMOTE': '1', 01:01:26 INFO - 'NO_EM_RESTART': '1', 01:01:26 INFO - 'PAGER': '/bin/cat', 01:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:26 INFO - 'PWD': '/builds/slave/test', 01:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:26 INFO - 'SHELL': '/bin/bash', 01:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:26 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:26 INFO - 'USER': 'cltbld', 01:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:26 INFO - 'XPC_FLAGS': '0x0', 01:01:26 INFO - 'XPC_SERVICE_NAME': '0', 01:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:01:27 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 01:01:27 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 01:01:27 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 01:01:27 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 01:01:27 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 01:01:27 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 01:01:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:01:29 INFO - Installing collected packages: mozsystemmonitor 01:01:29 INFO - Running setup.py install for mozsystemmonitor 01:01:30 INFO - Successfully installed mozsystemmonitor 01:01:30 INFO - Cleaning up... 01:01:30 INFO - Return code: 0 01:01:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:01:30 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')]} 01:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:30 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')]} 01:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:30 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:30 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 01:01:30 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 01:01:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:30 INFO - 'HOME': '/Users/cltbld', 01:01:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:30 INFO - 'LOGNAME': 'cltbld', 01:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:30 INFO - 'MOZ_NO_REMOTE': '1', 01:01:30 INFO - 'NO_EM_RESTART': '1', 01:01:30 INFO - 'PAGER': '/bin/cat', 01:01:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:30 INFO - 'PWD': '/builds/slave/test', 01:01:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:30 INFO - 'SHELL': '/bin/bash', 01:01:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:30 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:30 INFO - 'USER': 'cltbld', 01:01:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:30 INFO - 'XPC_FLAGS': '0x0', 01:01:30 INFO - 'XPC_SERVICE_NAME': '0', 01:01:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:30 INFO - Downloading/unpacking jsonschema==2.5.1 01:01:30 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 01:01:30 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 01:01:30 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 01:01:30 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 01:01:30 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 01:01:30 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 01:01:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:01:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:01:32 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 01:01:32 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 01:01:32 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 01:01:32 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 01:01:32 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 01:01:32 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 01:01:33 INFO - Downloading functools32-3.2.3-2.tar.gz 01:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:01:33 INFO - warning: no files found matching '*.txt' 01:01:33 INFO - no previously-included directories found matching 'build' 01:01:33 INFO - no previously-included directories found matching 'dist' 01:01:33 INFO - no previously-included directories found matching '.git*' 01:01:33 INFO - Installing collected packages: jsonschema, functools32 01:01:33 INFO - Running setup.py install for functools32 01:01:33 INFO - warning: no files found matching '*.txt' 01:01:33 INFO - no previously-included directories found matching 'build' 01:01:33 INFO - no previously-included directories found matching 'dist' 01:01:33 INFO - no previously-included directories found matching '.git*' 01:01:33 INFO - Successfully installed jsonschema functools32 01:01:33 INFO - Cleaning up... 01:01:33 INFO - Return code: 0 01:01:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:33 INFO - 'HOME': '/Users/cltbld', 01:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:33 INFO - 'LOGNAME': 'cltbld', 01:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:33 INFO - 'MOZ_NO_REMOTE': '1', 01:01:33 INFO - 'NO_EM_RESTART': '1', 01:01:33 INFO - 'PAGER': '/bin/cat', 01:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:33 INFO - 'PWD': '/builds/slave/test', 01:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:33 INFO - 'SHELL': '/bin/bash', 01:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:33 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:33 INFO - 'USER': 'cltbld', 01:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:33 INFO - 'XPC_FLAGS': '0x0', 01:01:33 INFO - 'XPC_SERVICE_NAME': '0', 01:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:01:33 INFO - Cleaning up... 01:01:33 INFO - Return code: 0 01:01:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:33 INFO - 'HOME': '/Users/cltbld', 01:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:33 INFO - 'LOGNAME': 'cltbld', 01:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:33 INFO - 'MOZ_NO_REMOTE': '1', 01:01:33 INFO - 'NO_EM_RESTART': '1', 01:01:33 INFO - 'PAGER': '/bin/cat', 01:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:33 INFO - 'PWD': '/builds/slave/test', 01:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:33 INFO - 'SHELL': '/bin/bash', 01:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:33 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:33 INFO - 'USER': 'cltbld', 01:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:33 INFO - 'XPC_FLAGS': '0x0', 01:01:33 INFO - 'XPC_SERVICE_NAME': '0', 01:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:34 INFO - Downloading/unpacking blobuploader==1.2.4 01:01: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 01:01: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 01:01: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 01:01: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 01:01: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 01:01: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 01:01:36 INFO - Downloading blobuploader-1.2.4.tar.gz 01:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:01:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:01:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:01:37 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 01:01:37 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 01:01:37 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 01:01:37 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 01:01:37 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 01:01:37 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 01:01:37 INFO - Downloading docopt-0.6.1.tar.gz 01:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:01:38 INFO - Installing collected packages: blobuploader, requests, docopt 01:01:38 INFO - Running setup.py install for blobuploader 01:01:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:01:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:01:38 INFO - Running setup.py install for requests 01:01:38 INFO - Running setup.py install for docopt 01:01:38 INFO - Successfully installed blobuploader requests docopt 01:01:38 INFO - Cleaning up... 01:01:38 INFO - Return code: 0 01:01:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:01:38 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')]} 01:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:38 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')]} 01:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:38 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:38 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 01:01:38 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 01:01:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:38 INFO - 'HOME': '/Users/cltbld', 01:01:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:38 INFO - 'LOGNAME': 'cltbld', 01:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:38 INFO - 'MOZ_NO_REMOTE': '1', 01:01:38 INFO - 'NO_EM_RESTART': '1', 01:01:38 INFO - 'PAGER': '/bin/cat', 01:01:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:38 INFO - 'PWD': '/builds/slave/test', 01:01:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:38 INFO - 'SHELL': '/bin/bash', 01:01:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:38 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:38 INFO - 'USER': 'cltbld', 01:01:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:38 INFO - 'XPC_FLAGS': '0x0', 01:01:38 INFO - 'XPC_SERVICE_NAME': '0', 01:01:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-FWjrQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-X6zF4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Vb8Mwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-E3ZVLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-CxLinE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZoddyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-M5eolS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:01:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-nOIzPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-2JFbgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-cuplko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-G9lErd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-WKsISx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tsl2Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-IKtW5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-K13diq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-NB_3Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:01:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-U9LP8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:01:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:01:40 INFO - Running setup.py install for manifestparser 01:01:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:01:41 INFO - Running setup.py install for mozcrash 01:01:41 INFO - Running setup.py install for mozdebug 01:01:41 INFO - Running setup.py install for mozdevice 01:01:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:01:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:01:41 INFO - Running setup.py install for mozfile 01:01:41 INFO - Running setup.py install for mozhttpd 01:01:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:01:41 INFO - Running setup.py install for mozinfo 01:01:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:01:41 INFO - Running setup.py install for mozInstall 01:01:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Running setup.py install for mozleak 01:01:42 INFO - Running setup.py install for mozlog 01:01:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Running setup.py install for moznetwork 01:01:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Running setup.py install for mozprocess 01:01:42 INFO - Running setup.py install for mozprofile 01:01:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:01:42 INFO - Running setup.py install for mozrunner 01:01:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:01:43 INFO - Running setup.py install for mozscreenshot 01:01:43 INFO - Running setup.py install for moztest 01:01:43 INFO - Running setup.py install for mozversion 01:01:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:01:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:01:43 INFO - Cleaning up... 01:01:43 INFO - Return code: 0 01:01:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:01:43 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')]} 01:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:43 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')]} 01:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:43 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:43 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 01:01:43 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 01:01:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:43 INFO - 'HOME': '/Users/cltbld', 01:01:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:43 INFO - 'LOGNAME': 'cltbld', 01:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:43 INFO - 'MOZ_NO_REMOTE': '1', 01:01:43 INFO - 'NO_EM_RESTART': '1', 01:01:43 INFO - 'PAGER': '/bin/cat', 01:01:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:43 INFO - 'PWD': '/builds/slave/test', 01:01:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:43 INFO - 'SHELL': '/bin/bash', 01:01:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:43 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:43 INFO - 'USER': 'cltbld', 01:01:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:43 INFO - 'XPC_FLAGS': '0x0', 01:01:43 INFO - 'XPC_SERVICE_NAME': '0', 01:01:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:01:43 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-V7M0zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:01:43 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)) 01:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:01:43 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-qO0mvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-LpMAPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-wxU0J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ltIfdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-o7ERLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-3bzyAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fJfILD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-q3ouAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-DDfIAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-OVW40m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:01:44 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)) 01:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:01:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-7Z5z2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:01:45 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)) 01:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:01:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-NNqxd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:01:45 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)) 01:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:01:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-BvdYKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:01:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-RRt4Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:01:45 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)) 01:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:01:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-8yzIZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:01:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Rib0Tz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:01:45 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)) 01:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:01:45 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)) 01:01:45 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)) 01:01:45 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)) 01:01:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:01: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 01:01: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 01:01: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 01:01: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 01:01: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 01:01: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 01:01:48 INFO - Downloading blessings-1.6.tar.gz 01:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:01:48 INFO - Installing collected packages: blessings 01:01:48 INFO - Running setup.py install for blessings 01:01:48 INFO - Successfully installed blessings 01:01:48 INFO - Cleaning up... 01:01:48 INFO - Return code: 0 01:01:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:01:48 INFO - Reading from file tmpfile_stdout 01:01:48 INFO - Current package versions: 01:01:48 INFO - blessings == 1.6 01:01:48 INFO - blobuploader == 1.2.4 01:01:48 INFO - docopt == 0.6.1 01:01:48 INFO - functools32 == 3.2.3-2 01:01:48 INFO - jsonschema == 2.5.1 01:01:48 INFO - manifestparser == 1.1 01:01:48 INFO - mozInstall == 1.12 01:01:48 INFO - mozcrash == 0.17 01:01:48 INFO - mozdebug == 0.1 01:01:48 INFO - mozdevice == 0.48 01:01:48 INFO - mozfile == 1.2 01:01:48 INFO - mozhttpd == 0.7 01:01:48 INFO - mozinfo == 0.9 01:01:48 INFO - mozleak == 0.1 01:01:48 INFO - mozlog == 3.4 01:01:48 INFO - moznetwork == 0.27 01:01:48 INFO - mozprocess == 0.23 01:01:48 INFO - mozprofile == 0.28 01:01:48 INFO - mozrunner == 6.12 01:01:48 INFO - mozscreenshot == 0.1 01:01:48 INFO - mozsystemmonitor == 0.3 01:01:48 INFO - moztest == 0.7 01:01:48 INFO - mozversion == 1.4 01:01:48 INFO - psutil == 3.1.1 01:01:48 INFO - requests == 1.2.3 01:01:48 INFO - wsgiref == 0.1.2 01:01:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:01:48 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')]} 01:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:48 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')]} 01:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:48 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:48 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 01:01:48 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 01:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:48 INFO - 'HOME': '/Users/cltbld', 01:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:48 INFO - 'LOGNAME': 'cltbld', 01:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:48 INFO - 'MOZ_NO_REMOTE': '1', 01:01:48 INFO - 'NO_EM_RESTART': '1', 01:01:48 INFO - 'PAGER': '/bin/cat', 01:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:48 INFO - 'PWD': '/builds/slave/test', 01:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:48 INFO - 'SHELL': '/bin/bash', 01:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:48 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:48 INFO - 'USER': 'cltbld', 01:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:48 INFO - 'XPC_FLAGS': '0x0', 01:01:48 INFO - 'XPC_SERVICE_NAME': '0', 01:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 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)) 01:01:48 INFO - Cleaning up... 01:01:48 INFO - Return code: 0 01:01:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:01:48 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')]} 01:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:48 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')]} 01:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:01:48 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 0x108705490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9220dd5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, '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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 01:01:48 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 01:01:48 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 01:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:01:48 INFO - 'HOME': '/Users/cltbld', 01:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:01:48 INFO - 'LOGNAME': 'cltbld', 01:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:01:48 INFO - 'MOZ_NO_REMOTE': '1', 01:01:48 INFO - 'NO_EM_RESTART': '1', 01:01:48 INFO - 'PAGER': '/bin/cat', 01:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:01:48 INFO - 'PWD': '/builds/slave/test', 01:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:01:48 INFO - 'SHELL': '/bin/bash', 01:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:01:48 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:01:48 INFO - 'USER': 'cltbld', 01:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:01:48 INFO - 'XPC_FLAGS': '0x0', 01:01:48 INFO - 'XPC_SERVICE_NAME': '0', 01:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:01:49 INFO - Cleaning up... 01:01:49 INFO - Return code: 0 01:01:49 INFO - Running post-action listener: _resource_record_post_action 01:01:49 INFO - Running post-action listener: _start_resource_monitoring 01:01:49 INFO - Starting resource monitoring. 01:01:49 INFO - [mozharness: 2016-10-18 08:01:49.267012Z] Finished create-virtualenv step (success) 01:01:49 INFO - [mozharness: 2016-10-18 08:01:49.267999Z] Running install step. 01:01:49 INFO - Running pre-action listener: _resource_record_pre_action 01:01:49 INFO - Running main action method: install 01:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:01:49 INFO - Reading from file tmpfile_stdout 01:01:49 INFO - Detecting whether we're running mozinstall >=1.0... 01:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:01:49 INFO - Reading from file tmpfile_stdout 01:01:49 INFO - Output received: 01:01:49 INFO - Usage: mozinstall [options] installer 01:01:49 INFO - Options: 01:01:49 INFO - -h, --help show this help message and exit 01:01:49 INFO - -d DEST, --destination=DEST 01:01:49 INFO - Directory to install application into. [default: 01:01:49 INFO - "/builds/slave/test"] 01:01:49 INFO - --app=APP Application being installed. [default: firefox] 01:01:49 INFO - mkdir: /builds/slave/test/build/application 01:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:02:23 INFO - Reading from file tmpfile_stdout 01:02:23 INFO - Output received: 01:02:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:02:23 INFO - Running post-action listener: _resource_record_post_action 01:02:23 INFO - [mozharness: 2016-10-18 08:02:23.867191Z] Finished install step (success) 01:02:23 INFO - [mozharness: 2016-10-18 08:02:23.867311Z] Running run-tests step. 01:02:23 INFO - Running pre-action listener: _resource_record_pre_action 01:02:23 INFO - Running main action method: run_tests 01:02:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:02:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:02:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:02:23 INFO - 2016-10-18 01:02:23.879 screenresolution[1643:7619] starting screenresolution argv=screenresolution get 01:02:23 INFO - 2016-10-18 01:02:23.882 screenresolution[1643:7619] Display 0: 1600x1200x32@60 01:02:23 INFO - 2016-10-18 01:02:23.887 screenresolution[1644:7622] starting screenresolution argv=screenresolution list 01:02:23 INFO - Available Modes on Display 0 01:02:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:02:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:02:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:02:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:02:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:02:24 INFO - Intel Iris: 01:02:24 INFO - Chipset Model: Intel Iris 01:02:24 INFO - Type: GPU 01:02:24 INFO - Bus: Built-In 01:02:24 INFO - VRAM (Dynamic, Max): 1536 MB 01:02:24 INFO - Vendor: Intel (0x8086) 01:02:24 INFO - Device ID: 0x0a2e 01:02:24 INFO - Revision ID: 0x0009 01:02:24 INFO - Displays: 01:02:24 INFO - Display: 01:02:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:02:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:02:24 INFO - Display Serial Number: 1600x1200 60 01:02:24 INFO - Main Display: Yes 01:02:24 INFO - Mirror: Off 01:02:24 INFO - Online: Yes 01:02:24 INFO - Rotation: Supported 01:02:24 INFO - Return code: 0 01:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:02:24 INFO - Python 2.7.3 01:02:24 INFO - Return code: 0 01:02:24 INFO - grabbing minidump binary from tooltool 01:02:24 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')]} 01:02:24 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 0x108519d30>, '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 0x10833fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108511b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:02:24 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 01:02:24 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 01:02:24 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 01:02:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:02:24 INFO - Return code: 0 01:02:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:02:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:02:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:02:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:02:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:02:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NgDKAVhGrB/Render', 01:02:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:02:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:02:24 INFO - 'HOME': '/Users/cltbld', 01:02:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:02:24 INFO - 'LOGNAME': 'cltbld', 01:02:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:02:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:02:24 INFO - 'MOZ_NO_REMOTE': '1', 01:02:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:02:24 INFO - 'NO_EM_RESTART': '1', 01:02:24 INFO - 'PAGER': '/bin/cat', 01:02:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:02:24 INFO - 'PWD': '/builds/slave/test', 01:02:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:02:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:02:24 INFO - 'SHELL': '/bin/bash', 01:02:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQAkfoq4TQ/Listeners', 01:02:24 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 01:02:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:02:24 INFO - 'USER': 'cltbld', 01:02:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:02:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:02:24 INFO - 'XPC_FLAGS': '0x0', 01:02:24 INFO - 'XPC_SERVICE_NAME': '0', 01:02:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:02:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:02:24 INFO - mozversion application_buildid: 20161018000149 01:02:24 INFO - mozversion application_changeset: 47b58740f054c12925c2bb62f65fc05a559569e6 01:02:24 INFO - mozversion application_display_name: Nightly 01:02:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:02:24 INFO - mozversion application_name: Firefox 01:02:24 INFO - mozversion application_remotingname: firefox 01:02:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:02:24 INFO - mozversion application_vendor: Mozilla 01:02:24 INFO - mozversion application_version: 52.0a1 01:02:24 INFO - mozversion platform_buildid: 20161018000149 01:02:24 INFO - mozversion platform_changeset: 47b58740f054c12925c2bb62f65fc05a559569e6 01:02:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:02:24 INFO - mozversion platform_version: 52.0a1 01:02:24 INFO - using testdate: 1476777744 01:02:24 INFO - actual date: 1476777744 01:02:24 INFO - starting webserver on 'localhost:49281' 01:02:24 INFO - SUITE-START | Running 2 tests 01:02:24 INFO - TEST-START | dromaeo_css 01:02:24 INFO - operating with platform_type : mac_ 01:02:24 INFO - Initialising browser for dromaeo_css test... 01:02:24 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpMPP4ea/profile http://localhost:49281/getInfo.html) 01:02:27 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 01:02:27 INFO - PROCESS | 1654 | colorDepth:24 01:02:27 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 01:02:27 INFO - PROCESS | 1654 | __metrics 01:02:27 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:02:27 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:02:27 INFO - TEST-INFO | 1654: exit 0 01:02:27 INFO - Browser initialized. 01:02:27 INFO - Running cycle 1/1 for dromaeo_css test... 01:02:27 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpMPP4ea/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:02:39 INFO - PROCESS | 1659 | RSS: Main: 231575552 01:02:39 INFO - PROCESS | 1659 | 01:03:30 INFO - PROCESS | 1659 | 1476777810950 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:05:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 01:05:45 INFO - PROCESS | 1659 | RSS: Main: 343040000 01:05:45 INFO - PROCESS | 1659 | 01:08:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 01:08:48 INFO - PROCESS | 1659 | RSS: Main: 396599296 01:08:48 INFO - PROCESS | 1659 | 01:11:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 01:11:51 INFO - PROCESS | 1659 | RSS: Main: 333221888 01:11:51 INFO - PROCESS | 1659 | 01:14:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 01:14:54 INFO - PROCESS | 1659 | RSS: Main: 333004800 01:14:54 INFO - PROCESS | 1659 | 01:17:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 01:17:58 INFO - PROCESS | 1659 | RSS: Main: 327802880 01:17:58 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 01:21:02 INFO - PROCESS | 1659 | RSS: Main: 541716480 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | __start_tp_report 01:21:02 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 01:21:02 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 01:21:02 INFO - PROCESS | 1659 | |i|pagename|runs| 01:21:02 INFO - PROCESS | 1659 | |0;dojo.html;3231.612206535216;3265.9999999999986;3290.144562413772;3293.505974103886;3296.3572103439824;5405.216243644669;5452.918206226905;5479.6164268501225;5500.972495137518;5513.09034009389;5455.618106732521;5472.808451704191;5495.477929596692;5516.696581688006;5550.195221692852;5050.772715227824;5078.8730281743065;5115.104856650071;5116.155834287338;5139.15203991341;4996.800127994894;5024.874378140539;5127.179651255799;5131.563817075553;5146.253793199984;4286.614204721574;5099.592032637399;5132.5124113209185;5147.948520514784;5192.610554208431;4238.639715624176;4968.751562421864;5119.360079990001;5139.922901156486;5166.328377310963;4930.876728081791;4963.329950456714;5033.245013248005;5033.446320904697;5059.494050594948;5428.511433970962;5429.4299098594865;5435.1847222916485;5453.100238460689;5476.534494567966;6032.849178770522;6033.125196846433;6051.183090282843;6054.212952316171;6064.818055458343;4907.730074845882;5859;5940.257467816523;5944.375840536724;6104.816855494342;5462.863428414282;5528.06022976087;5542.750576224039;5550.500454958992;5597.104463285855;6182.721777520022;6204.255489341308;6204.937950620526;6211.409916058057;6220.253569571681;5710.200571919934;5837.562182836219;5846.707664616837;5855.882882342329;5859.794907178229;5809.419058094157;5943.465088142088;6012.669303188367;6071.028635418312;6077.665728384893;5953.553483488669;5968.1942937703725;5974.551908606785;5993.340732519419;6038.365971573009;5952.821415357546;5984.3417224105315;5988.760449581968;6008.188894499274;6011.9699401503585;4696.577308042292;4726.267428548512;4753.4295884493395;4754.168020596451;4764.190087685102;4249;4263.211305908417;4296.785160741951;4321.049369138785;4378.540253273361;5554.91667624986;5581.609287349927;5583.972080139573;5586;5630.85922851932;5807.796727114531;5839.970800146057;6100.176476175749;6100.420460056288;6130.846728831815;5484.533814625722;5540.63985840919;5572.637778544382;5576.470235327719;5620.100783874642;5197.532222099953;5236.188390799432;5237.633365664443;5243.659162154448;5255.553277971415;6293.087502312231;6293.590916590407;6302.716377763011;6312.43188113072;6317.810465686037;5918.319393269829;5951.285845698544;5989.970050149721;6020.759169633166;6089.451949324582;5232.319798426181;6280.717367718372;6300.621962682254;6317.936820631919;6319.020551814292;5903.173555702119;5923.851903702442;5932.110183472513;5950.821475355746;5960.761569537343;5653.971730141323;5735;5946.73239704223;5991;6044.879102418015;5842.824715258549;5845.561582881386;5846.210761547307;5857.824265272048;5871.236739223874;5827.970860145672;5835.299764028344;5835.766569337179;5846.5615078870105;5852.853678658068;5562.137868630369;5576.972115139399;5648.88702225945;5717.599768016199;5719.542436605145;5742.799002034909;5829.329627092939;5845.09401042839;5871.354151043467;5886.499647529837;5644.181593668977;5692.373838877637;5696.658200507983;5705.828825135252;5745.540356771532 01:21:02 INFO - PROCESS | 1659 | |1;ext.html;493.8938128302414;513.5352505982088;519.4234399816205;532.6191772882388;532.9413764485906;27701.030463933766;40261.993450163725;41333.7933310333;41347.58652413475;41424.79287603565;41879.37180942288;42323.941901452476;42623;42690.14619707604;42740.14519709612;9418.387804792701;9617.95191024044;9619.182369498601;9621.134097931184;9623.855642165374;9373.67192148275;9484.19384352331;9486.241100711926;9486.430814151174;9496.52517374132;9486.43081415114;9524.666636667718;9533.237341012737;9540.666076687317;9550.474723890184;9427.764305892339;9454.669086582006;9480.762980925498;9491.38306010107;9527.857082143773;9415.62337506499;9497.335186536946;9503.049695030511;9508.429494230368;9513.381630194015;40400.15198176208;40700.389494157316;40705;40721.79639101815;40830.795846020876;3232.87068517259;3329.847103939449;3337.9499307510405;3347.5146103815055;3402.336544373848;30117.397652046835;30426.93505727289;31216.531752023737;31256.374872502423;31488.842555787305;3079.3533357994847;3102.0848849589424;3135.827529485877;3154.5741324921073;3217.7104060634656;416753;466367;469794;470379.6481017607;471457;61903.690481548205;62336.37663623306;62443;62469;62513.687431562554;25867.870660646575;34850.47724284138;65982;66061.33938660551;66319.66840165865;16193.02841829494;16275;16276.674466510603;16288.02271863692;16291.348346066261;12568.87431125677;12630.179038362478;12634.557790477289;12638.494460221673;12641.557545485864;15354.309056092503;19027.28754412082;19044.047797610063;19100.23599056022;19104.85370877724;6678.365555272241;7907.920920790719;7933;7977.242161994601;8088.150744171895;800.5596921693045;901.544719916438;903.5075883643431;974.64425484699;1067.887871773468;21566.784332156476;24706.876465617555;26500.734992650214;28718;29799.851000744857;994.22450488619;1046.858674079005;1086.2233503045202;1163.5636636261402;1192.958246461387;12883.677908052186;14825.40698372053;15451.845481545266;15692.293846776922;16061.277242524115;650249;656088.7195564087;656227;656523;657740;57110.714446427504;58059;58092.09539523007;58100.8379832401;58514.41485585175;1177.8515946951654;1305.6409487391234;1343.737971095627;1383.889288856889;1388.534840828334;29197.708022919498;29466;29466.263343416587;29521.557176642797;30362.54456183115;49590.75204623954;50516.24225636545;50563;50648.733781655756;50651;474334;474998;475090;476167;477307.6134619305;20874.165033399102;26075.60886586665;26656.933722650876;26846;27639.894404223607;46194;46791.766041169576;47116.7644161777;47514.524854752395;48010.27984580164;37519.624803752704;38998.80500597479;39044;40050.59949400585;40680;38207.04482387962;38380.80809595934;38537.80731096327;39927.00182495461;40126.398104029045;32506.67493325131;33071;33104.1723956903;36553.81723091367;38110.237795244495;28248.85875570609;30726.539101913935;32122.35755284928;34842.47736283907;34887.953361399195;24837;25063.74936250687;25352.87323563444;25788.484230314913;25894.741052589987 01:21:02 INFO - PROCESS | 1659 | |2;jquery.html;320.37047202247584;377.25492152997833;378.61003166738254;393.22142158526134;405.7159988008393;19760.90119549402;19833;20345.59308813823;20735.377938661863;20875.895620521915;11702.590409335675;11754.647360579196;12187.695307617292;12190.268583884974;12204.694882627939;5171.637985341028;5489.890202195954;5494;5501.972490137544;5506.146547285157;3907.5701672815967;4085.897852553695;4131.52487463941;4135.648469880064;5400.78396864125;4068.328725760246;4096.344584866422;6609.900851487231;6671.166104236971;6678.065070890079;6063.333033366326;6116.571839971204;6643.568168069084;6678.332166783356;6701;5939.990201665727;5942.821715348503;5946.672932988684;5951.345352011275;5952.315483719384;13120.6719832004;13228;13302.467901283937;13330.266835324053;13474.19154850712;2005.7191993120975;2330.883455827202;2353.423411264234;2555.1823416506727;2623.8032147588815;10480.423576703279;10531.368117912949;10963.671089867317;12852.293123878182;15258.343782314767;1798.2087881332302;1847.4919397165886;1887.603603243307;1907.656621808074;1914.7646538618242;108783;113998.32020157551;115127.8487215134;116226.41886790596;116426;9836.803263934824;10828.675139745821;11451.770964580663;11532;11662.766744665229;14606;14634.268286585628;14668.926655366655;14672.706545869023;14687.706245875237;8129.146439623754;8384.706535271236;8431.662733490715;8533.359998000175;8553.187447192508;7988.80027999293;8004.839903201903;8083.797905052421;8210.566294201184;8453.915460845436;3858.78776667286;4491.573300536444;4496.145732310851;4498.662600304925;4582.679212455096;1792.986962366259;1804.9441076969856;1813.3925135079633;1939.7769256535396;1952.9121189546504;858.8196478739535;903.9050899655573;917.9173874351341;936.3866667332908;1008.9091981721533;19880.403587892386;20461;20550.486237844172;20651.69022464664;20912.47718807042;828.763802316337;935.3592788939599;936.765808547863;944.5749412764284;994.7314225159312;10385.31321380914;10934.507947142398;10939.945300273448;10966.670999870017;11064.225504214788;565796;570467;574940;579006;582024;85626;129544;131616.68383316236;134805.3259733695;136889;928.1971095002909;940.1444685336311;973.7030205787303;975.6877799104218;976.2190247801872;19164.520886977378;19346;19374.515637109183;19572.804271957666;20051.796892186514;54541;55557.72221138868;55828.88342233052;56322.7183864078;56618.433815661316;77399.61300193463;77529.22470775446;78527;79265.414691707;80082.59132790973;7949.205079492004;8017.198280171936;8126.349892008743;8197.590120494071;8216.589170541329;11578.90000449956;12189.08581856382;12391;12878.67803304925;13088;10829.24195306321;10854.18593605467;11360.488778004845;11368.715782105513;11487.36819474937;8983.191512763882;10863.565457381615;10940.617078402536;11166.776664466828;11321.377324247245;9313.953430232805;10722.946385268024;11210.215284929976;11576.479058442223;11582.768344632892;7114.715411383487;7883.408744344312;7888.013998250219;8218.671253149807;8432.662693492437;9295.488748118918;9844.556994935101;11012.669619911416;11082.833757493483;11390.886091138982 01:21:02 INFO - PROCESS | 1659 | |3;mootools.html;1525.9618509537263;1562.1720488141286;1684.5535932977764;1715.2452920714893;1769.7433872088548;8063.3952453566;8875.95562022189;8877.68928087517;8885.60014799334;8916.152965468287;7871.094824095231;8061;8087;8519.148085191478;8689.261412779906;2350.435895385108;2411.360989337827;2413.746556611557;2414.6619473273754;2423.127674037345;2361.9055237790467;2387.1883559589737;2395.6765836612008;2404.579198640231;2408.7470815564375;2382.809375249976;2385.8687772172525;2396.5566370221477;2397.808175345977;2416.867072311022;2366.6331718583647;2375.4180225844693;2377.7265614454386;2383.4279772854566;2389.2473870730837;2369.4313364792497;2374;2377.2749311459997;2383.8808059597122;2388.3312672451566;8817.955910220471;8916.55417229136;8983.730488085303;8984.955075224581;8991;5174.327337446146;5357.249985002103;5367.973160134175;5368.275282836806;5374;3419.145213696576;3485.6514348565192;3630.7458477906557;3660.084978755311;3669.027707657473;1030.160419258302;1069.385103565453;1078.9892101078967;1088.2110469909333;1101.6034227678028;11671.416429178507;11794.764104717944;11802.763944721144;11815.940920295343;11828.645140645709;4548.727076375362;4614.97692511542;4798.976005120022;4801.95198048022;4821.54195351441;10070.647527336509;10071.395716256902;10076.244281678903;10106.343087699423;10108.949455252676;5423.698312404995;5434.375046869661;5437.13005919051;5443.047466693313;5444.564434845203;4438.112377524508;4505.459344878635;4914.459409464956;4951.232558953368;4982.601391888713;3903.4535165076913;3923.4507168996365;4019.879403617896;4025.718199725991;4049.149678567534;1281.423359488234;1290.9225446473242;1292.5799115287568;1303.3939218263554;1304.1588175626669;899.5457294066455;927.7031349967946;928.8528667096131;935.1350001248818;965.2712202287228;5760.308762948473;5939.3763654816485;6016.488598469092;6072.787452439144;6170.10533472653;428.6142471775427;444.46442037344923;445.64571165923167;446.84583818582536;450.6755136301857;3468.8612455502002;3937.8424863005157;3978.7214894957074;4033.3949907514107;4057.959420405817;10376.948115259527;10693.304935179189;10784.083352914933;10789.298695584763;10799.730006749895;10741.140708743282;10749.247552671395;10757.408342541079;10763.730906727238;10768.246222764332;6318.589291696025;7880.881786773203;8120.147384524658;8232.300254478436;8234.588270586328;5801.82594522165;5910.7931222407005;5948.087973053333;5996.640201587918;6000.699965001645;9664.03359664028;9980.700578982642;10032.69901902944;10077.899221007696;10108.696739097839;8661.8700719488;9821.017898210122;10075.395476271447;10136.290459667753;10193.439360835226;6897.3447522485285;7089.184743754534;7090;7446.292602202782;7545.207753185837;7921.881171782313;7964.084130325086;7975.282224599582;8168.591570421574;8293.004839419309;6433.839154021187;7344.669489873076;7468.5145465544565;7559.811004724926;7596.8480630388185;6149.815505534841;7309.870167382868;7318.268173182639;7392.26077392278;7440.851182976418;6891.827704307432;6896.689648965708;6990.09128813251;6992.825179370353;6995.370416662525;4952.232403977389;4998.575121114746;5018.29743835856;5027.74861256943;5113;4153.7507749535125;4863.885649055676;4908.993656300521;5015.774290156879;5155.896882062413 01:21:02 INFO - PROCESS | 1659 | |4;prototype.html;298.7699471407016;307.6231616270068;307.95996520452337;315.94944808830576;318.97926634768754;4439.045605194882;4954.7274899880495;5048.318477005608;5110.821121260757;5145.948540514589;4178.829927620263;4705.694129881555;5075.238714192864;5078.542931136197;5107.676007416626;3022.622172228466;3175.952360714591;3896.4350169225445;3943;4022.6178513041214;3435.1412146963257;3814.5803961564206;3989.8204580793936;4014.558398576154;4060.533038700558;3386.6951974322305;3913.119548101683;3954.9802250988705;3956.0505478685054;3980.1840622672285;3594.388953877847;3773.4717139600616;3835.290471262825;3923.901902452433;4021.7277238360234;2892.0311695582004;2997.6061131573706;3022;3321.903771755315;3544.3088597723454;4388.605025547716;4478.857891237746;5029.290041385946;5043.798248070109;5044.848654540369;3730.757500762442;3762.322781899257;4310.52584215736;4499.977500112511;4519.389882365871;4317.633001194871;5253.50091741284;5257.7108259045735;5311.787528498855;5332.760025798849;3210.213497693057;3802.923941521182;3831.1379939513663;3835.654791068789;3846.5190901502842;4964.975175124137;5188.936268065076;5328.413874473805;5701.714914254312;5711.685857277848;3267;3442.6557344265875;3864.478295430081;3881.941770873439;3887.9696880326737;3507.667086507111;3643.635636436335;3916.4712763777097;3939.9165229561527;3946.1910308386714;4495.078508905666;4520.977395113004;4543.65922555803;4549.408576884984;4586.793594288265;4749;5003.799848006039;5396.136618141157;5497.312835895513;5514.696691681916;1025.5590096258532;1110.1951085463136;1270.5616562285998;1302.6287508060157;1314.7173357728134;709.9432045436353;726.9600171990486;759.5366826235992;806.8951036365235;817.207308910356;4280.571942805695;4321.027768752068;4568.6116680081905;4886.438059623118;5036.5718913892;5503.724813759376;5521.972390138104;5548.639338442988;5554;5572.3870374258795;4051.554329023805;5050.0657378384385;5073.239014147834;5080.212567052113;5119.3088932994315;2670.5460071787843;2862.255813488466;2866.2834291427143;2871.55490898911;2895.913122606325;4948.752562371939;4967;5428.647137936022;5657.632253903483;5661.264035675335;4196.769177695256;5320.893582128336;5412.864678383072;5608.831735047955;5634.577406694514;5792.304923409133;5801.433612924487;5837.5329973601165;5873.911891321633;5925.318588362307;4582;4910.779014944407;4987.426445958761;5149.330587023663;5483.561315094863;4539.636829053602;5284.973575132253;5550.27846379968;5589.664620122806;5624.521915637217;5764.250647415977;5784.363719990713;5786.855328616818;5808.07070868659;5811.680357580373;3741.158239396114;3806.771593704276;3815.618438156273;3825.024618722208;3829.885103446901;5463.360991702552;5463.4263402342185;5475.397706252389;5584.207896051974;5659.868026394655;5387.488188622075;5396.7571459283645;5398.2442458056685;5409.6213265071065;5417.079096553516;4491.932621010622;5362.302900622894;5422.945770542244;5422.972885135549;5440.863978400572;4462.598366147063;5119.257707632298;5321.30822993024;5351.5451186648015;5352.84913743547;5092.032513822362;5129.076766181974;5456.972715136399;5471.179323101566;5487.259220005249;4858.3441235432765;4967.975939489025;5274.314339135888;5287.920681189708;5352.304200453916 01:21:02 INFO - PROCESS | 1659 | |5;yui.html;577.3129975329358;705.6295444891434;727.5016613619671;739.8446326271483;801.5311043039821;15011.474598389059;15796.842031579681;15823.920880395583;15825.287862046207;15842.683146337067;14312.928435357835;14469.92765036176;14470.20413877239;14485.855141448583;14511.782323265159;693.4798900824381;715;718.5832217313972;719.8200449887528;860.3203469259294;720.0854914258888;721.3327671903495;860.8335705119566;894.3515950935584;896.6144557840126;936.7939053408239;943.075785729985;943.8820147481565;948.7153853843854;949.0699114867434;788.357488646754;936.45217547735;939.3424602778083;940.116290686759;943.4056544377067;546.6774602984218;699.7410957945593;699.9720011199546;703.3810246982673;704.2394214248599;12311;12523.248605083723;12541.059420543404;12546.623601291976;14342.64143396413;13873.306334683326;14850.702985940221;14911.701765964728;14982.850171498254;14991.32539035746;10737.946310268399;10738.677839664822;10747.193960452998;10803.189760767895;11117.27737697047;3749.1189570450924;3857.633524815138;3909.6285852843935;3962.7622342659533;4028;80007;80837.59581202116;81539.59230203812;81570.59214703948;104848.47575762072;10743.033127018605;10965.396903170242;10997.61508347204;11141.944290278496;11339.23040709641;19206.655534112757;20158.79841201569;21661.783382166293;21823.345299641038;21863.78136218649;7561.848763024709;7688.692452301845;7757.26306000929;7963.800904977422;7970.760877173694;4149.128682976609;4214.7892605370225;4217.978910105491;4223.767692776927;4277.935830962538;3608.45873119029;3751.362268414376;3752.493413389212;3753.6434038766274;3778.905527361838;663.4393937123124;671.8219671786981;702.8067281497629;725.6589402980591;726.2519604807057;572.5448268626452;644.0082273299067;670.9396154346132;677.4275737002225;685.8285428642839;8163.836723265502;8223.05434874997;9251.028970432304;9347.252219822398;9608.183304419063;578.6267857232145;618.7617767159676;648.2027106658836;664.2693037658537;687.151368060455;4399.5820397062225;4550.863474095782;4552.88617784558;4563.95436045642;4653.232216684277;151362;153365;163512;163821.08536743917;167884;119239;122513;122940;126990;127157;111476;112912.43543782228;115983.42008289904;116405;116414.41792791321;8447.493150410995;8727.432716873383;8753.562321884008;8775.254103401283;9024.142706442877;63324;64232.67883660552;64302.67848660727;64677;64937.67531162314;94020;101037;101914;103286;105078;11444.303891658737;11554.711132221426;11558.653240402802;11890.76218475643;12085.972692321251;12713.977641722086;12915.935420323214;13001.349932503526;13183.604491865259;13449.193048417126;11809.763804724029;11812.82280765772;12090.758184836079;12319.876801231872;12470.378850749345;10830.833129489532;12105.394730263275;12180;12307.138500304893;12452.813207802072;10198.592056317664;12023.038156947598;12028.338441385808;12228.87771122313;12439.813402799145;10203.336783109371;10480.42357670305;10563.683089507327;10782.946085269523;10830.620928267474;9663.806723865624;10112.24158188124;10484.108850747773;10496.895031049591;10538.8419173714 01:21:02 INFO - PROCESS | 1659 | __end_tp_report 01:21:02 INFO - PROCESS | 1659 | __start_cc_report 01:21:02 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1254 01:21:02 INFO - PROCESS | 1659 | __end_cc_report 01:21:02 INFO - PROCESS | 1659 | __startTimestamp1476778862369__endTimestamp 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | ------- Summary: start ------- 01:21:02 INFO - PROCESS | 1659 | Number of tests: 6 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5532.68 Median:5692.37 stddev:624.57 (11.0%) stddev-sans-first:599.83 01:21:02 INFO - PROCESS | 1659 | Values: 3231.6 3266.0 3290.1 3293.5 3296.4 5405.2 5452.9 5479.6 5501.0 5513.1 5455.6 5472.8 5495.5 5516.7 5550.2 5050.8 5078.9 5115.1 5116.2 5139.2 4996.8 5024.9 5127.2 5131.6 5146.3 4286.6 5099.6 5132.5 5147.9 5192.6 4238.6 4968.8 5119.4 5139.9 5166.3 4930.9 4963.3 5033.2 5033.4 5059.5 5428.5 5429.4 5435.2 5453.1 5476.5 6032.8 6033.1 6051.2 6054.2 6064.8 4907.7 5859.0 5940.3 5944.4 6104.8 5462.9 5528.1 5542.8 5550.5 5597.1 6182.7 6204.3 6204.9 6211.4 6220.3 5710.2 5837.6 5846.7 5855.9 5859.8 5809.4 5943.5 6012.7 6071.0 6077.7 5953.6 5968.2 5974.6 5993.3 6038.4 5952.8 5984.3 5988.8 6008.2 6012.0 4696.6 4726.3 4753.4 4754.2 4764.2 4249.0 4263.2 4296.8 4321.0 4378.5 5554.9 5581.6 5584.0 5586.0 5630.9 5807.8 5840.0 6100.2 6100.4 6130.8 5484.5 5540.6 5572.6 5576.5 5620.1 5197.5 5236.2 5237.6 5243.7 5255.6 6293.1 6293.6 6302.7 6312.4 6317.8 5918.3 5951.3 5990.0 6020.8 6089.5 5232.3 6280.7 6300.6 6317.9 6319.0 5903.2 5923.9 5932.1 5950.8 5960.8 5654.0 5735.0 5946.7 5991.0 6044.9 5842.8 5845.6 5846.2 5857.8 5871.2 5828.0 5835.3 5835.8 5846.6 5852.9 5562.1 5577.0 5648.9 5717.6 5719.5 5742.8 5829.3 5845.1 5871.4 5886.5 5644.2 5692.4 5696.7 5705.8 5745.5 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:66437.68 Median:40702.69 stddev:143627.46 (352.9%) stddev-sans-first:143945.04 01:21:02 INFO - PROCESS | 1659 | Values: 493.9 513.5 519.4 532.6 532.9 27701.0 40262.0 41333.8 41347.6 41424.8 41879.4 42323.9 42623.0 42690.1 42740.1 9418.4 9618.0 9619.2 9621.1 9623.9 9373.7 9484.2 9486.2 9486.4 9496.5 9486.4 9524.7 9533.2 9540.7 9550.5 9427.8 9454.7 9480.8 9491.4 9527.9 9415.6 9497.3 9503.0 9508.4 9513.4 40400.2 40700.4 40705.0 40721.8 40830.8 3232.9 3329.8 3337.9 3347.5 3402.3 30117.4 30426.9 31216.5 31256.4 31488.8 3079.4 3102.1 3135.8 3154.6 3217.7 416753.0 466367.0 469794.0 470379.6 471457.0 61903.7 62336.4 62443.0 62469.0 62513.7 25867.9 34850.5 65982.0 66061.3 66319.7 16193.0 16275.0 16276.7 16288.0 16291.3 12568.9 12630.2 12634.6 12638.5 12641.6 15354.3 19027.3 19044.0 19100.2 19104.9 6678.4 7907.9 7933.0 7977.2 8088.2 800.6 901.5 903.5 974.6 1067.9 21566.8 24706.9 26500.7 28718.0 29799.9 994.2 1046.9 1086.2 1163.6 1193.0 12883.7 14825.4 15451.8 15692.3 16061.3 650249.0 656088.7 656227.0 656523.0 657740.0 57110.7 58059.0 58092.1 58100.8 58514.4 1177.9 1305.6 1343.7 1383.9 1388.5 29197.7 29466.0 29466.3 29521.6 30362.5 49590.8 50516.2 50563.0 50648.7 50651.0 474334.0 474998.0 475090.0 476167.0 477307.6 20874.2 26075.6 26656.9 26846.0 27639.9 46194.0 46791.8 47116.8 47514.5 48010.3 37519.6 38998.8 39044.0 40050.6 40680.0 38207.0 38380.8 38537.8 39927.0 40126.4 32506.7 33071.0 33104.2 36553.8 38110.2 28248.9 30726.5 32122.4 34842.5 34888.0 24837.0 25063.7 25352.9 25788.5 25894.7 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33624.15 Median:348.81 stddev:96086.21 (27546.6%) stddev-sans-first:96323.22 01:21:02 INFO - PROCESS | 1659 | Values: 320.4 377.3 378.6 393.2 405.7 19760.9 19833.0 20345.6 20735.4 20875.9 11702.6 11754.6 12187.7 12190.3 12204.7 5171.6 5489.9 5494.0 5502.0 5506.1 3907.6 4085.9 4131.5 4135.6 5400.8 4068.3 4096.3 6609.9 6671.2 6678.1 6063.3 6116.6 6643.6 6678.3 6701.0 5940.0 5942.8 5946.7 5951.3 5952.3 13120.7 13228.0 13302.5 13330.3 13474.2 2005.7 2330.9 2353.4 2555.2 2623.8 10480.4 10531.4 10963.7 12852.3 15258.3 1798.2 1847.5 1887.6 1907.7 1914.8 108783.0 113998.3 115127.8 116226.4 116426.0 9836.8 10828.7 11451.8 11532.0 11662.8 14606.0 14634.3 14668.9 14672.7 14687.7 8129.1 8384.7 8431.7 8533.4 8553.2 7988.8 8004.8 8083.8 8210.6 8453.9 3858.8 4491.6 4496.1 4498.7 4582.7 1793.0 1804.9 1813.4 1939.8 1952.9 858.8 903.9 917.9 936.4 1008.9 19880.4 20461.0 20550.5 20651.7 20912.5 828.8 935.4 936.8 944.6 994.7 10385.3 10934.5 10939.9 10966.7 11064.2 565796.0 570467.0 574940.0 579006.0 582024.0 85626.0 129544.0 131616.7 134805.3 136889.0 928.2 940.1 973.7 975.7 976.2 19164.5 19346.0 19374.5 19572.8 20051.8 54541.0 55557.7 55828.9 56322.7 56618.4 77399.6 77529.2 78527.0 79265.4 80082.6 7949.2 8017.2 8126.3 8197.6 8216.6 11578.9 12189.1 12391.0 12878.7 13088.0 10829.2 10854.2 11360.5 11368.7 11487.4 8983.2 10863.6 10940.6 11166.8 11321.4 9314.0 10722.9 11210.2 11576.5 11582.8 7114.7 7883.4 7888.0 8218.7 8432.7 9295.5 9844.6 11012.7 11082.8 11390.9 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5671.23 Median:4706.98 stddev:3190.09 (67.8%) stddev-sans-first:3183.83 01:21:02 INFO - PROCESS | 1659 | Values: 1526.0 1562.2 1684.6 1715.2 1769.7 8063.4 8876.0 8877.7 8885.6 8916.2 7871.1 8061.0 8087.0 8519.1 8689.3 2350.4 2411.4 2413.7 2414.7 2423.1 2361.9 2387.2 2395.7 2404.6 2408.7 2382.8 2385.9 2396.6 2397.8 2416.9 2366.6 2375.4 2377.7 2383.4 2389.2 2369.4 2374.0 2377.3 2383.9 2388.3 8818.0 8916.6 8983.7 8985.0 8991.0 5174.3 5357.2 5368.0 5368.3 5374.0 3419.1 3485.7 3630.7 3660.1 3669.0 1030.2 1069.4 1079.0 1088.2 1101.6 11671.4 11794.8 11802.8 11815.9 11828.6 4548.7 4615.0 4799.0 4802.0 4821.5 10070.6 10071.4 10076.2 10106.3 10108.9 5423.7 5434.4 5437.1 5443.0 5444.6 4438.1 4505.5 4914.5 4951.2 4982.6 3903.5 3923.5 4019.9 4025.7 4049.1 1281.4 1290.9 1292.6 1303.4 1304.2 899.5 927.7 928.9 935.1 965.3 5760.3 5939.4 6016.5 6072.8 6170.1 428.6 444.5 445.6 446.8 450.7 3468.9 3937.8 3978.7 4033.4 4058.0 10376.9 10693.3 10784.1 10789.3 10799.7 10741.1 10749.2 10757.4 10763.7 10768.2 6318.6 7880.9 8120.1 8232.3 8234.6 5801.8 5910.8 5948.1 5996.6 6000.7 9664.0 9980.7 10032.7 10077.9 10108.7 8661.9 9821.0 10075.4 10136.3 10193.4 6897.3 7089.2 7090.0 7446.3 7545.2 7921.9 7964.1 7975.3 8168.6 8293.0 6433.8 7344.7 7468.5 7559.8 7596.8 6149.8 7309.9 7318.3 7392.3 7440.9 6891.8 6896.7 6990.1 6992.8 6995.4 4952.2 4998.6 5018.3 5027.7 5113.0 4153.8 4863.9 4909.0 5015.8 5155.9 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4352.50 Median:4959.85 stddev:1358.11 (27.4%) stddev-sans-first:1327.40 01:21:02 INFO - PROCESS | 1659 | Values: 298.8 307.6 308.0 315.9 319.0 4439.0 4954.7 5048.3 5110.8 5145.9 4178.8 4705.7 5075.2 5078.5 5107.7 3022.6 3176.0 3896.4 3943.0 4022.6 3435.1 3814.6 3989.8 4014.6 4060.5 3386.7 3913.1 3955.0 3956.1 3980.2 3594.4 3773.5 3835.3 3923.9 4021.7 2892.0 2997.6 3022.0 3321.9 3544.3 4388.6 4478.9 5029.3 5043.8 5044.8 3730.8 3762.3 4310.5 4500.0 4519.4 4317.6 5253.5 5257.7 5311.8 5332.8 3210.2 3802.9 3831.1 3835.7 3846.5 4965.0 5188.9 5328.4 5701.7 5711.7 3267.0 3442.7 3864.5 3881.9 3888.0 3507.7 3643.6 3916.5 3939.9 3946.2 4495.1 4521.0 4543.7 4549.4 4586.8 4749.0 5003.8 5396.1 5497.3 5514.7 1025.6 1110.2 1270.6 1302.6 1314.7 709.9 727.0 759.5 806.9 817.2 4280.6 4321.0 4568.6 4886.4 5036.6 5503.7 5522.0 5548.6 5554.0 5572.4 4051.6 5050.1 5073.2 5080.2 5119.3 2670.5 2862.3 2866.3 2871.6 2895.9 4948.8 4967.0 5428.6 5657.6 5661.3 4196.8 5320.9 5412.9 5608.8 5634.6 5792.3 5801.4 5837.5 5873.9 5925.3 4582.0 4910.8 4987.4 5149.3 5483.6 4539.6 5285.0 5550.3 5589.7 5624.5 5764.3 5784.4 5786.9 5808.1 5811.7 3741.2 3806.8 3815.6 3825.0 3829.9 5463.4 5463.4 5475.4 5584.2 5659.9 5387.5 5396.8 5398.2 5409.6 5417.1 4491.9 5362.3 5422.9 5423.0 5440.9 4462.6 5119.3 5321.3 5351.5 5352.8 5092.0 5129.1 5457.0 5471.2 5487.3 4858.3 4968.0 5274.3 5287.9 5352.3 01:21:02 INFO - PROCESS | 1659 | 01:21:02 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24446.91 Median:3750.24 stddev:39959.98 (1065.5%) stddev-sans-first:40031.89 01:21:02 INFO - PROCESS | 1659 | Values: 577.3 705.6 727.5 739.8 801.5 15011.5 15796.8 15823.9 15825.3 15842.7 14312.9 14469.9 14470.2 14485.9 14511.8 693.5 715.0 718.6 719.8 860.3 720.1 721.3 860.8 894.4 896.6 936.8 943.1 943.9 948.7 949.1 788.4 936.5 939.3 940.1 943.4 546.7 699.7 700.0 703.4 704.2 12311.0 12523.2 12541.1 12546.6 14342.6 13873.3 14850.7 14911.7 14982.9 14991.3 10737.9 10738.7 10747.2 10803.2 11117.3 3749.1 3857.6 3909.6 3962.8 4028.0 80007.0 80837.6 81539.6 81570.6 104848.5 10743.0 10965.4 10997.6 11141.9 11339.2 19206.7 20158.8 21661.8 21823.3 21863.8 7561.8 7688.7 7757.3 7963.8 7970.8 4149.1 4214.8 4218.0 4223.8 4277.9 3608.5 3751.4 3752.5 3753.6 3778.9 663.4 671.8 702.8 725.7 726.3 572.5 644.0 670.9 677.4 685.8 8163.8 8223.1 9251.0 9347.3 9608.2 578.6 618.8 648.2 664.3 687.2 4399.6 4550.9 4552.9 4564.0 4653.2 151362.0 153365.0 163512.0 163821.1 167884.0 119239.0 122513.0 122940.0 126990.0 127157.0 111476.0 112912.4 115983.4 116405.0 116414.4 8447.5 8727.4 8753.6 8775.3 9024.1 63324.0 64232.7 64302.7 64677.0 64937.7 94020.0 101037.0 101914.0 103286.0 105078.0 11444.3 11554.7 11558.7 11890.8 12086.0 12714.0 12915.9 13001.3 13183.6 13449.2 11809.8 11812.8 12090.8 12319.9 12470.4 10830.8 12105.4 12180.0 12307.1 12452.8 10198.6 12023.0 12028.3 12228.9 12439.8 10203.3 10480.4 10563.7 10782.9 10830.6 9663.8 10112.2 10484.1 10496.9 10538.8 01:21:02 INFO - PROCESS | 1659 | -------- Summary: end -------- 01:21:02 INFO - PROCESS | 1659 | 01:21:03 INFO - TEST-INFO | 1659: exit 0 01:21:03 INFO - TEST-OK | dromaeo_css | took 1118556ms 01:21:03 INFO - TEST-START | kraken 01:21:03 INFO - operating with platform_type : mac_ 01:21:03 INFO - Initialising browser for kraken test... 01:21:03 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpTjY849/profile http://localhost:49281/getInfo.html) 01:21:05 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 01:21:05 INFO - PROCESS | 1665 | colorDepth:24 01:21:05 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 01:21:05 INFO - PROCESS | 1665 | __metrics 01:21:05 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:21:05 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:21:06 INFO - TEST-INFO | 1665: exit 0 01:21:06 INFO - Browser initialized. 01:21:06 INFO - Running cycle 1/1 for kraken test... 01:21:06 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpTjY849/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:21:18 INFO - PROCESS | 1666 | RSS: Main: 231976960 01:21:18 INFO - PROCESS | 1666 | 01:21:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 01:21:21 INFO - PROCESS | 1666 | RSS: Main: 290586624 01:21:21 INFO - PROCESS | 1666 | 01:21:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 01:21:25 INFO - PROCESS | 1666 | RSS: Main: 290643968 01:21:25 INFO - PROCESS | 1666 | 01:21:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 01:21:29 INFO - PROCESS | 1666 | RSS: Main: 384811008 01:21:29 INFO - PROCESS | 1666 | 01:21:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 01:21:32 INFO - PROCESS | 1666 | RSS: Main: 285630464 01:21:32 INFO - PROCESS | 1666 | 01:21:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:21:34 INFO - PROCESS | 1666 | RSS: Main: 289566720 01:21:34 INFO - PROCESS | 1666 | 01:21:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 01:21:37 INFO - PROCESS | 1666 | RSS: Main: 328503296 01:21:37 INFO - PROCESS | 1666 | 01:21:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 01:21:41 INFO - PROCESS | 1666 | RSS: Main: 328417280 01:21:41 INFO - PROCESS | 1666 | 01:21:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 01:21:44 INFO - PROCESS | 1666 | RSS: Main: 326950912 01:21:44 INFO - PROCESS | 1666 | 01:21:46 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:21:46 INFO - PROCESS | 1666 | RSS: Main: 303030272 01:21:46 INFO - PROCESS | 1666 | 01:21:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:21:49 INFO - PROCESS | 1666 | RSS: Main: 306724864 01:21:49 INFO - PROCESS | 1666 | 01:21:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:21:52 INFO - PROCESS | 1666 | RSS: Main: 312782848 01:21:52 INFO - PROCESS | 1666 | 01:21:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:21:55 INFO - PROCESS | 1666 | RSS: Main: 316567552 01:21:55 INFO - PROCESS | 1666 | 01:21:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:21:59 INFO - PROCESS | 1666 | RSS: Main: 317882368 01:21:59 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 01:22:01 INFO - PROCESS | 1666 | RSS: Main: 360837120 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | __start_tp_report 01:22:01 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 01:22:01 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 01:22:01 INFO - PROCESS | 1666 | |i|pagename|runs| 01:22:01 INFO - PROCESS | 1666 | |0;ai-astar;103;113;114;114;114;114;113;114;114;118 01:22:01 INFO - PROCESS | 1666 | |1;audio-beat-detection;153;123;146;183;142;113;141;105;159;144 01:22:01 INFO - PROCESS | 1666 | |2;audio-dft;183;184;184;187;182;182;182;182;183;184 01:22:01 INFO - PROCESS | 1666 | |3;audio-fft;80;58;60;89;60;58;60;58;81;58 01:22:01 INFO - PROCESS | 1666 | |4;audio-oscillator;89;72;90;69;68;69;92;67;68;90 01:22:01 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;84;89;86;88;85;88;86;89;85 01:22:01 INFO - PROCESS | 1666 | |6;imaging-darkroom;97;97;98;97;97;98;97;97;97;96 01:22:01 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;83;80;83;83;83;85;86;83;85 01:22:01 INFO - PROCESS | 1666 | |8;json-parse-financial;55;56;54;53;53;54;54;55;54;55 01:22:01 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;69;74;77;73;74;70;70;71;71;73 01:22:01 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;88;90;90;88;88;85;88;85;90;103 01:22:01 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;147;154;140;125;147;161;165;160;162;138 01:22:01 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;174;170;172;169;171;171;171;172;172;170 01:22:01 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;61;88;83;91;87;87;68;75;79;66 01:22:01 INFO - PROCESS | 1666 | __end_tp_report 01:22:01 INFO - PROCESS | 1666 | __start_cc_report 01:22:01 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2108 01:22:01 INFO - PROCESS | 1666 | __end_cc_report 01:22:01 INFO - PROCESS | 1666 | __startTimestamp1476778921569__endTimestamp 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | ------- Summary: start ------- 01:22:01 INFO - PROCESS | 1666 | Number of tests: 14 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.81 (3.3%) stddev-sans-first:1.48 01:22:01 INFO - PROCESS | 1666 | Values: 103.0 113.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 118.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:145.00 stddev:22.78 (15.7%) stddev-sans-first:23.74 01:22:01 INFO - PROCESS | 1666 | Values: 153.0 123.0 146.0 183.0 142.0 113.0 141.0 105.0 159.0 144.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.57 (0.9%) stddev-sans-first:1.66 01:22:01 INFO - PROCESS | 1666 | Values: 183.0 184.0 184.0 187.0 182.0 182.0 182.0 182.0 183.0 184.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.00 stddev:12.08 (20.1%) stddev-sans-first:11.74 01:22:01 INFO - PROCESS | 1666 | Values: 80.0 58.0 60.0 89.0 60.0 58.0 60.0 58.0 81.0 58.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:80.50 stddev:11.16 (13.9%) stddev-sans-first:11.02 01:22:01 INFO - PROCESS | 1666 | Values: 89.0 72.0 90.0 69.0 68.0 69.0 92.0 67.0 68.0 90.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 01:22:01 INFO - PROCESS | 1666 | Values: 88.0 84.0 89.0 86.0 88.0 85.0 88.0 86.0 89.0 85.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:97.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 01:22:01 INFO - PROCESS | 1666 | Values: 97.0 97.0 98.0 97.0 97.0 98.0 97.0 97.0 97.0 96.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:83.50 Median:83.50 stddev:1.65 (2.0%) stddev-sans-first:1.74 01:22:01 INFO - PROCESS | 1666 | Values: 84.0 83.0 80.0 83.0 83.0 83.0 85.0 86.0 83.0 85.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.95 (1.7%) stddev-sans-first:0.97 01:22:01 INFO - PROCESS | 1666 | Values: 55.0 56.0 54.0 53.0 53.0 54.0 54.0 55.0 54.0 55.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.00 stddev:2.44 (3.3%) stddev-sans-first:2.30 01:22:01 INFO - PROCESS | 1666 | Values: 69.0 74.0 77.0 73.0 74.0 70.0 70.0 71.0 71.0 73.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.00 stddev:5.08 (5.8%) stddev-sans-first:5.36 01:22:01 INFO - PROCESS | 1666 | Values: 88.0 90.0 90.0 88.0 88.0 85.0 88.0 85.0 90.0 103.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:157.00 stddev:12.88 (8.2%) stddev-sans-first:13.62 01:22:01 INFO - PROCESS | 1666 | Values: 147.0 154.0 140.0 125.0 147.0 161.0 165.0 160.0 162.0 138.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:171.50 stddev:1.40 (0.8%) stddev-sans-first:1.05 01:22:01 INFO - PROCESS | 1666 | Values: 174.0 170.0 172.0 169.0 171.0 171.0 171.0 172.0 172.0 170.0 01:22:01 INFO - PROCESS | 1666 | 01:22:01 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:85.00 stddev:10.52 (12.4%) stddev-sans-first:9.06 01:22:01 INFO - PROCESS | 1666 | Values: 61.0 88.0 83.0 91.0 87.0 87.0 68.0 75.0 79.0 66.0 01:22:01 INFO - PROCESS | 1666 | -------- Summary: end -------- 01:22:01 INFO - PROCESS | 1666 | 01:22:02 INFO - TEST-INFO | 1666: exit 0 01:22:02 INFO - TEST-OK | kraken | took 59611ms 01:22:02 INFO - SUITE-END | took 1178s 01:22:02 INFO - Completed test suite (00:19:38) 01:22:02 INFO - javascript benchmark 01:22:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3231.612206535216, 3265.9999999999986, 3290.144562413772, 3293.505974103886, 3296.3572103439824, 5405.216243644669, 5452.918206226905, 5479.6164268501225, 5500.972495137518, 5513.09034009389, 5455.618106732521, 5472.808451704191, 5495.477929596692, 5516.696581688006, 5550.195221692852, 5050.772715227824, 5078.8730281743065, 5115.104856650071, 5116.155834287338, 5139.15203991341, 4996.800127994894, 5024.874378140539, 5127.179651255799, 5131.563817075553, 5146.253793199984, 4286.614204721574, 5099.592032637399, 5132.5124113209185, 5147.948520514784, 5192.610554208431, 4238.639715624176, 4968.751562421864, 5119.360079990001, 5139.922901156486, 5166.328377310963, 4930.876728081791, 4963.329950456714, 5033.245013248005, 5033.446320904697, 5059.494050594948, 5428.511433970962, 5429.4299098594865, 5435.1847222916485, 5453.100238460689, 5476.534494567966, 6032.849178770522, 6033.125196846433, 6051.183090282843, 6054.212952316171, 6064.818055458343, 4907.730074845882, 5859.0, 5940.257467816523, 5944.375840536724, 6104.816855494342, 5462.863428414282, 5528.06022976087, 5542.750576224039, 5550.500454958992, 5597.104463285855, 6182.721777520022, 6204.255489341308, 6204.937950620526, 6211.409916058057, 6220.253569571681, 5710.200571919934, 5837.562182836219, 5846.707664616837, 5855.882882342329, 5859.794907178229, 5809.419058094157, 5943.465088142088, 6012.669303188367, 6071.028635418312, 6077.665728384893, 5953.553483488669, 5968.1942937703725, 5974.551908606785, 5993.340732519419, 6038.365971573009, 5952.821415357546, 5984.3417224105315, 5988.760449581968, 6008.188894499274, 6011.9699401503585, 4696.577308042292, 4726.267428548512, 4753.4295884493395, 4754.168020596451, 4764.190087685102, 4249.0, 4263.211305908417, 4296.785160741951, 4321.049369138785, 4378.540253273361, 5554.91667624986, 5581.609287349927, 5583.972080139573, 5586.0, 5630.85922851932, 5807.796727114531, 5839.970800146057, 6100.176476175749, 6100.420460056288, 6130.846728831815, 5484.533814625722, 5540.63985840919, 5572.637778544382, 5576.470235327719, 5620.100783874642, 5197.532222099953, 5236.188390799432, 5237.633365664443, 5243.659162154448, 5255.553277971415, 6293.087502312231, 6293.590916590407, 6302.716377763011, 6312.43188113072, 6317.810465686037, 5918.319393269829, 5951.285845698544, 5989.970050149721, 6020.759169633166, 6089.451949324582, 5232.319798426181, 6280.717367718372, 6300.621962682254, 6317.936820631919, 6319.020551814292, 5903.173555702119, 5923.851903702442, 5932.110183472513, 5950.821475355746, 5960.761569537343, 5653.971730141323, 5735.0, 5946.73239704223, 5991.0, 6044.879102418015, 5842.824715258549, 5845.561582881386, 5846.210761547307, 5857.824265272048, 5871.236739223874, 5827.970860145672, 5835.299764028344, 5835.766569337179, 5846.5615078870105, 5852.853678658068, 5562.137868630369, 5576.972115139399, 5648.88702225945, 5717.599768016199, 5719.542436605145, 5742.799002034909, 5829.329627092939, 5845.09401042839, 5871.354151043467, 5886.499647529837, 5644.181593668977, 5692.373838877637, 5696.658200507983, 5705.828825135252, 5745.540356771532], "value": 5493.521129533399, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8938128302414, 513.5352505982088, 519.4234399816205, 532.6191772882388, 532.9413764485906, 27701.030463933766, 40261.993450163725, 41333.7933310333, 41347.58652413475, 41424.79287603565, 41879.37180942288, 42323.941901452476, 42623.0, 42690.14619707604, 42740.14519709612, 9418.387804792701, 9617.95191024044, 9619.182369498601, 9621.134097931184, 9623.855642165374, 9373.67192148275, 9484.19384352331, 9486.241100711926, 9486.430814151174, 9496.52517374132, 9486.43081415114, 9524.666636667718, 9533.237341012737, 9540.666076687317, 9550.474723890184, 9427.764305892339, 9454.669086582006, 9480.762980925498, 9491.38306010107, 9527.857082143773, 9415.62337506499, 9497.335186536946, 9503.049695030511, 9508.429494230368, 9513.381630194015, 40400.15198176208, 40700.389494157316, 40705.0, 40721.79639101815, 40830.795846020876, 3232.87068517259, 3329.847103939449, 3337.9499307510405, 3347.5146103815055, 3402.336544373848, 30117.397652046835, 30426.93505727289, 31216.531752023737, 31256.374872502423, 31488.842555787305, 3079.3533357994847, 3102.0848849589424, 3135.827529485877, 3154.5741324921073, 3217.7104060634656, 416753.0, 466367.0, 469794.0, 470379.6481017607, 471457.0, 61903.690481548205, 62336.37663623306, 62443.0, 62469.0, 62513.687431562554, 25867.870660646575, 34850.47724284138, 65982.0, 66061.33938660551, 66319.66840165865, 16193.02841829494, 16275.0, 16276.674466510603, 16288.02271863692, 16291.348346066261, 12568.87431125677, 12630.179038362478, 12634.557790477289, 12638.494460221673, 12641.557545485864, 15354.309056092503, 19027.28754412082, 19044.047797610063, 19100.23599056022, 19104.85370877724, 6678.365555272241, 7907.920920790719, 7933.0, 7977.242161994601, 8088.150744171895, 800.5596921693045, 901.544719916438, 903.5075883643431, 974.64425484699, 1067.887871773468, 21566.784332156476, 24706.876465617555, 26500.734992650214, 28718.0, 29799.851000744857, 994.22450488619, 1046.858674079005, 1086.2233503045202, 1163.5636636261402, 1192.958246461387, 12883.677908052186, 14825.40698372053, 15451.845481545266, 15692.293846776922, 16061.277242524115, 650249.0, 656088.7195564087, 656227.0, 656523.0, 657740.0, 57110.714446427504, 58059.0, 58092.09539523007, 58100.8379832401, 58514.41485585175, 1177.8515946951654, 1305.6409487391234, 1343.737971095627, 1383.889288856889, 1388.534840828334, 29197.708022919498, 29466.0, 29466.263343416587, 29521.557176642797, 30362.54456183115, 49590.75204623954, 50516.24225636545, 50563.0, 50648.733781655756, 50651.0, 474334.0, 474998.0, 475090.0, 476167.0, 477307.6134619305, 20874.165033399102, 26075.60886586665, 26656.933722650876, 26846.0, 27639.894404223607, 46194.0, 46791.766041169576, 47116.7644161777, 47514.524854752395, 48010.27984580164, 37519.624803752704, 38998.80500597479, 39044.0, 40050.59949400585, 40680.0, 38207.04482387962, 38380.80809595934, 38537.80731096327, 39927.00182495461, 40126.398104029045, 32506.67493325131, 33071.0, 33104.1723956903, 36553.81723091367, 38110.237795244495, 28248.85875570609, 30726.539101913935, 32122.35755284928, 34842.47736283907, 34887.953361399195, 24837.0, 25063.74936250687, 25352.87323563444, 25788.484230314913, 25894.741052589987], "value": 19505.156478965648, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.37047202247584, 377.25492152997833, 378.61003166738254, 393.22142158526134, 405.7159988008393, 19760.90119549402, 19833.0, 20345.59308813823, 20735.377938661863, 20875.895620521915, 11702.590409335675, 11754.647360579196, 12187.695307617292, 12190.268583884974, 12204.694882627939, 5171.637985341028, 5489.890202195954, 5494.0, 5501.972490137544, 5506.146547285157, 3907.5701672815967, 4085.897852553695, 4131.52487463941, 4135.648469880064, 5400.78396864125, 4068.328725760246, 4096.344584866422, 6609.900851487231, 6671.166104236971, 6678.065070890079, 6063.333033366326, 6116.571839971204, 6643.568168069084, 6678.332166783356, 6701.0, 5939.990201665727, 5942.821715348503, 5946.672932988684, 5951.345352011275, 5952.315483719384, 13120.6719832004, 13228.0, 13302.467901283937, 13330.266835324053, 13474.19154850712, 2005.7191993120975, 2330.883455827202, 2353.423411264234, 2555.1823416506727, 2623.8032147588815, 10480.423576703279, 10531.368117912949, 10963.671089867317, 12852.293123878182, 15258.343782314767, 1798.2087881332302, 1847.4919397165886, 1887.603603243307, 1907.656621808074, 1914.7646538618242, 108783.0, 113998.32020157551, 115127.8487215134, 116226.41886790596, 116426.0, 9836.803263934824, 10828.675139745821, 11451.770964580663, 11532.0, 11662.766744665229, 14606.0, 14634.268286585628, 14668.926655366655, 14672.706545869023, 14687.706245875237, 8129.146439623754, 8384.706535271236, 8431.662733490715, 8533.359998000175, 8553.187447192508, 7988.80027999293, 8004.839903201903, 8083.797905052421, 8210.566294201184, 8453.915460845436, 3858.78776667286, 4491.573300536444, 4496.145732310851, 4498.662600304925, 4582.679212455096, 1792.986962366259, 1804.9441076969856, 1813.3925135079633, 1939.7769256535396, 1952.9121189546504, 858.8196478739535, 903.9050899655573, 917.9173874351341, 936.3866667332908, 1008.9091981721533, 19880.403587892386, 20461.0, 20550.486237844172, 20651.69022464664, 20912.47718807042, 828.763802316337, 935.3592788939599, 936.765808547863, 944.5749412764284, 994.7314225159312, 10385.31321380914, 10934.507947142398, 10939.945300273448, 10966.670999870017, 11064.225504214788, 565796.0, 570467.0, 574940.0, 579006.0, 582024.0, 85626.0, 129544.0, 131616.68383316236, 134805.3259733695, 136889.0, 928.1971095002909, 940.1444685336311, 973.7030205787303, 975.6877799104218, 976.2190247801872, 19164.520886977378, 19346.0, 19374.515637109183, 19572.804271957666, 20051.796892186514, 54541.0, 55557.72221138868, 55828.88342233052, 56322.7183864078, 56618.433815661316, 77399.61300193463, 77529.22470775446, 78527.0, 79265.414691707, 80082.59132790973, 7949.205079492004, 8017.198280171936, 8126.349892008743, 8197.590120494071, 8216.589170541329, 11578.90000449956, 12189.08581856382, 12391.0, 12878.67803304925, 13088.0, 10829.24195306321, 10854.18593605467, 11360.488778004845, 11368.715782105513, 11487.36819474937, 8983.191512763882, 10863.565457381615, 10940.617078402536, 11166.776664466828, 11321.377324247245, 9313.953430232805, 10722.946385268024, 11210.215284929976, 11576.479058442223, 11582.768344632892, 7114.715411383487, 7883.408744344312, 7888.013998250219, 8218.671253149807, 8432.662693492437, 9295.488748118918, 9844.556994935101, 11012.669619911416, 11082.833757493483, 11390.886091138982], "value": 9153.077305658859, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.9618509537263, 1562.1720488141286, 1684.5535932977764, 1715.2452920714893, 1769.7433872088548, 8063.3952453566, 8875.95562022189, 8877.68928087517, 8885.60014799334, 8916.152965468287, 7871.094824095231, 8061.0, 8087.0, 8519.148085191478, 8689.261412779906, 2350.435895385108, 2411.360989337827, 2413.746556611557, 2414.6619473273754, 2423.127674037345, 2361.9055237790467, 2387.1883559589737, 2395.6765836612008, 2404.579198640231, 2408.7470815564375, 2382.809375249976, 2385.8687772172525, 2396.5566370221477, 2397.808175345977, 2416.867072311022, 2366.6331718583647, 2375.4180225844693, 2377.7265614454386, 2383.4279772854566, 2389.2473870730837, 2369.4313364792497, 2374.0, 2377.2749311459997, 2383.8808059597122, 2388.3312672451566, 8817.955910220471, 8916.55417229136, 8983.730488085303, 8984.955075224581, 8991.0, 5174.327337446146, 5357.249985002103, 5367.973160134175, 5368.275282836806, 5374.0, 3419.145213696576, 3485.6514348565192, 3630.7458477906557, 3660.084978755311, 3669.027707657473, 1030.160419258302, 1069.385103565453, 1078.9892101078967, 1088.2110469909333, 1101.6034227678028, 11671.416429178507, 11794.764104717944, 11802.763944721144, 11815.940920295343, 11828.645140645709, 4548.727076375362, 4614.97692511542, 4798.976005120022, 4801.95198048022, 4821.54195351441, 10070.647527336509, 10071.395716256902, 10076.244281678903, 10106.343087699423, 10108.949455252676, 5423.698312404995, 5434.375046869661, 5437.13005919051, 5443.047466693313, 5444.564434845203, 4438.112377524508, 4505.459344878635, 4914.459409464956, 4951.232558953368, 4982.601391888713, 3903.4535165076913, 3923.4507168996365, 4019.879403617896, 4025.718199725991, 4049.149678567534, 1281.423359488234, 1290.9225446473242, 1292.5799115287568, 1303.3939218263554, 1304.1588175626669, 899.5457294066455, 927.7031349967946, 928.8528667096131, 935.1350001248818, 965.2712202287228, 5760.308762948473, 5939.3763654816485, 6016.488598469092, 6072.787452439144, 6170.10533472653, 428.6142471775427, 444.46442037344923, 445.64571165923167, 446.84583818582536, 450.6755136301857, 3468.8612455502002, 3937.8424863005157, 3978.7214894957074, 4033.3949907514107, 4057.959420405817, 10376.948115259527, 10693.304935179189, 10784.083352914933, 10789.298695584763, 10799.730006749895, 10741.140708743282, 10749.247552671395, 10757.408342541079, 10763.730906727238, 10768.246222764332, 6318.589291696025, 7880.881786773203, 8120.147384524658, 8232.300254478436, 8234.588270586328, 5801.82594522165, 5910.7931222407005, 5948.087973053333, 5996.640201587918, 6000.699965001645, 9664.03359664028, 9980.700578982642, 10032.69901902944, 10077.899221007696, 10108.696739097839, 8661.8700719488, 9821.017898210122, 10075.395476271447, 10136.290459667753, 10193.439360835226, 6897.3447522485285, 7089.184743754534, 7090.0, 7446.292602202782, 7545.207753185837, 7921.881171782313, 7964.084130325086, 7975.282224599582, 8168.591570421574, 8293.004839419309, 6433.839154021187, 7344.669489873076, 7468.5145465544565, 7559.811004724926, 7596.8480630388185, 6149.815505534841, 7309.870167382868, 7318.268173182639, 7392.26077392278, 7440.851182976418, 6891.827704307432, 6896.689648965708, 6990.09128813251, 6992.825179370353, 6995.370416662525, 4952.232403977389, 4998.575121114746, 5018.29743835856, 5027.74861256943, 5113.0, 4153.7507749535125, 4863.885649055676, 4908.993656300521, 5015.774290156879, 5155.896882062413], "value": 4497.105140358256, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7699471407016, 307.6231616270068, 307.95996520452337, 315.94944808830576, 318.97926634768754, 4439.045605194882, 4954.7274899880495, 5048.318477005608, 5110.821121260757, 5145.948540514589, 4178.829927620263, 4705.694129881555, 5075.238714192864, 5078.542931136197, 5107.676007416626, 3022.622172228466, 3175.952360714591, 3896.4350169225445, 3943.0, 4022.6178513041214, 3435.1412146963257, 3814.5803961564206, 3989.8204580793936, 4014.558398576154, 4060.533038700558, 3386.6951974322305, 3913.119548101683, 3954.9802250988705, 3956.0505478685054, 3980.1840622672285, 3594.388953877847, 3773.4717139600616, 3835.290471262825, 3923.901902452433, 4021.7277238360234, 2892.0311695582004, 2997.6061131573706, 3022.0, 3321.903771755315, 3544.3088597723454, 4388.605025547716, 4478.857891237746, 5029.290041385946, 5043.798248070109, 5044.848654540369, 3730.757500762442, 3762.322781899257, 4310.52584215736, 4499.977500112511, 4519.389882365871, 4317.633001194871, 5253.50091741284, 5257.7108259045735, 5311.787528498855, 5332.760025798849, 3210.213497693057, 3802.923941521182, 3831.1379939513663, 3835.654791068789, 3846.5190901502842, 4964.975175124137, 5188.936268065076, 5328.413874473805, 5701.714914254312, 5711.685857277848, 3267.0, 3442.6557344265875, 3864.478295430081, 3881.941770873439, 3887.9696880326737, 3507.667086507111, 3643.635636436335, 3916.4712763777097, 3939.9165229561527, 3946.1910308386714, 4495.078508905666, 4520.977395113004, 4543.65922555803, 4549.408576884984, 4586.793594288265, 4749.0, 5003.799848006039, 5396.136618141157, 5497.312835895513, 5514.696691681916, 1025.5590096258532, 1110.1951085463136, 1270.5616562285998, 1302.6287508060157, 1314.7173357728134, 709.9432045436353, 726.9600171990486, 759.5366826235992, 806.8951036365235, 817.207308910356, 4280.571942805695, 4321.027768752068, 4568.6116680081905, 4886.438059623118, 5036.5718913892, 5503.724813759376, 5521.972390138104, 5548.639338442988, 5554.0, 5572.3870374258795, 4051.554329023805, 5050.0657378384385, 5073.239014147834, 5080.212567052113, 5119.3088932994315, 2670.5460071787843, 2862.255813488466, 2866.2834291427143, 2871.55490898911, 2895.913122606325, 4948.752562371939, 4967.0, 5428.647137936022, 5657.632253903483, 5661.264035675335, 4196.769177695256, 5320.893582128336, 5412.864678383072, 5608.831735047955, 5634.577406694514, 5792.304923409133, 5801.433612924487, 5837.5329973601165, 5873.911891321633, 5925.318588362307, 4582.0, 4910.779014944407, 4987.426445958761, 5149.330587023663, 5483.561315094863, 4539.636829053602, 5284.973575132253, 5550.27846379968, 5589.664620122806, 5624.521915637217, 5764.250647415977, 5784.363719990713, 5786.855328616818, 5808.07070868659, 5811.680357580373, 3741.158239396114, 3806.771593704276, 3815.618438156273, 3825.024618722208, 3829.885103446901, 5463.360991702552, 5463.4263402342185, 5475.397706252389, 5584.207896051974, 5659.868026394655, 5387.488188622075, 5396.7571459283645, 5398.2442458056685, 5409.6213265071065, 5417.079096553516, 4491.932621010622, 5362.302900622894, 5422.945770542244, 5422.972885135549, 5440.863978400572, 4462.598366147063, 5119.257707632298, 5321.30822993024, 5351.5451186648015, 5352.84913743547, 5092.032513822362, 5129.076766181974, 5456.972715136399, 5471.179323101566, 5487.259220005249, 4858.3441235432765, 4967.975939489025, 5274.314339135888, 5287.920681189708, 5352.304200453916], "value": 3916.2191186610976, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.3129975329358, 705.6295444891434, 727.5016613619671, 739.8446326271483, 801.5311043039821, 15011.474598389059, 15796.842031579681, 15823.920880395583, 15825.287862046207, 15842.683146337067, 14312.928435357835, 14469.92765036176, 14470.20413877239, 14485.855141448583, 14511.782323265159, 693.4798900824381, 715.0, 718.5832217313972, 719.8200449887528, 860.3203469259294, 720.0854914258888, 721.3327671903495, 860.8335705119566, 894.3515950935584, 896.6144557840126, 936.7939053408239, 943.075785729985, 943.8820147481565, 948.7153853843854, 949.0699114867434, 788.357488646754, 936.45217547735, 939.3424602778083, 940.116290686759, 943.4056544377067, 546.6774602984218, 699.7410957945593, 699.9720011199546, 703.3810246982673, 704.2394214248599, 12311.0, 12523.248605083723, 12541.059420543404, 12546.623601291976, 14342.64143396413, 13873.306334683326, 14850.702985940221, 14911.701765964728, 14982.850171498254, 14991.32539035746, 10737.946310268399, 10738.677839664822, 10747.193960452998, 10803.189760767895, 11117.27737697047, 3749.1189570450924, 3857.633524815138, 3909.6285852843935, 3962.7622342659533, 4028.0, 80007.0, 80837.59581202116, 81539.59230203812, 81570.59214703948, 104848.47575762072, 10743.033127018605, 10965.396903170242, 10997.61508347204, 11141.944290278496, 11339.23040709641, 19206.655534112757, 20158.79841201569, 21661.783382166293, 21823.345299641038, 21863.78136218649, 7561.848763024709, 7688.692452301845, 7757.26306000929, 7963.800904977422, 7970.760877173694, 4149.128682976609, 4214.7892605370225, 4217.978910105491, 4223.767692776927, 4277.935830962538, 3608.45873119029, 3751.362268414376, 3752.493413389212, 3753.6434038766274, 3778.905527361838, 663.4393937123124, 671.8219671786981, 702.8067281497629, 725.6589402980591, 726.2519604807057, 572.5448268626452, 644.0082273299067, 670.9396154346132, 677.4275737002225, 685.8285428642839, 8163.836723265502, 8223.05434874997, 9251.028970432304, 9347.252219822398, 9608.183304419063, 578.6267857232145, 618.7617767159676, 648.2027106658836, 664.2693037658537, 687.151368060455, 4399.5820397062225, 4550.863474095782, 4552.88617784558, 4563.95436045642, 4653.232216684277, 151362.0, 153365.0, 163512.0, 163821.08536743917, 167884.0, 119239.0, 122513.0, 122940.0, 126990.0, 127157.0, 111476.0, 112912.43543782228, 115983.42008289904, 116405.0, 116414.41792791321, 8447.493150410995, 8727.432716873383, 8753.562321884008, 8775.254103401283, 9024.142706442877, 63324.0, 64232.67883660552, 64302.67848660727, 64677.0, 64937.67531162314, 94020.0, 101037.0, 101914.0, 103286.0, 105078.0, 11444.303891658737, 11554.711132221426, 11558.653240402802, 11890.76218475643, 12085.972692321251, 12713.977641722086, 12915.935420323214, 13001.349932503526, 13183.604491865259, 13449.193048417126, 11809.763804724029, 11812.82280765772, 12090.758184836079, 12319.876801231872, 12470.378850749345, 10830.833129489532, 12105.394730263275, 12180.0, 12307.138500304893, 12452.813207802072, 10198.592056317664, 12023.038156947598, 12028.338441385808, 12228.87771122313, 12439.813402799145, 10203.336783109371, 10480.42357670305, 10563.683089507327, 10782.946085269523, 10830.620928267474, 9663.806723865624, 10112.24158188124, 10484.108850747773, 10496.895031049591, 10538.8419173714], "value": 7583.314151240704, "unit": "score"}], "name": "dromaeo_css", "value": 7126.545277807737, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 118.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 146.0, 183.0, 142.0, 113.0, 141.0, 105.0, 159.0, 144.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 187.0, 182.0, 182.0, 182.0, 182.0, 183.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 89.0, 60.0, 58.0, 60.0, 58.0, 81.0, 58.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 90.0, 69.0, 68.0, 69.0, 92.0, 67.0, 68.0, 90.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 89.0, 86.0, 88.0, 85.0, 88.0, 86.0, 89.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 97.0, 97.0, 98.0, 97.0, 97.0, 97.0, 96.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 80.0, 83.0, 83.0, 83.0, 85.0, 86.0, 83.0, 85.0], "value": 83.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 53.0, 53.0, 54.0, 54.0, 55.0, 54.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 77.0, 73.0, 74.0, 70.0, 70.0, 71.0, 71.0, 73.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 90.0, 88.0, 88.0, 85.0, 88.0, 85.0, 90.0, 103.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 154.0, 140.0, 125.0, 147.0, 161.0, 165.0, 160.0, 162.0, 138.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 172.0, 169.0, 171.0, 171.0, 171.0, 172.0, 172.0, 170.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 88.0, 83.0, 91.0, 87.0, 87.0, 68.0, 75.0, 79.0, 66.0], "value": 78.5, "unit": "score"}], "name": "kraken", "value": 1463.9, "alertThreshold": 2.0}]} 01:22:02 INFO - Return code: 0 01:22:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:22:02 INFO - # TBPL SUCCESS # 01:22:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:22:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:22:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:22:02 INFO - 2016-10-18 01:22:02.889 screenresolution[1670:9950] starting screenresolution argv=screenresolution get 01:22:02 INFO - 2016-10-18 01:22:02.892 screenresolution[1670:9950] Display 0: 1600x1200x32@60 01:22:02 INFO - 2016-10-18 01:22:02.898 screenresolution[1671:9953] starting screenresolution argv=screenresolution list 01:22:02 INFO - Available Modes on Display 0 01:22:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:22:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:22:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:22:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:22:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:22:03 INFO - Intel Iris: 01:22:03 INFO - Chipset Model: Intel Iris 01:22:03 INFO - Type: GPU 01:22:03 INFO - Bus: Built-In 01:22:03 INFO - VRAM (Dynamic, Max): 1536 MB 01:22:03 INFO - Vendor: Intel (0x8086) 01:22:03 INFO - Device ID: 0x0a2e 01:22:03 INFO - Revision ID: 0x0009 01:22:03 INFO - Displays: 01:22:03 INFO - Display: 01:22:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:22:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:22:03 INFO - Display Serial Number: 1600x1200 60 01:22:03 INFO - Main Display: Yes 01:22:03 INFO - Mirror: Off 01:22:03 INFO - Online: Yes 01:22:03 INFO - Rotation: Supported 01:22:03 INFO - Return code: 0 01:22:03 INFO - Running post-action listener: _resource_record_post_action 01:22:03 INFO - [mozharness: 2016-10-18 08:22:03.142351Z] Finished run-tests step (success) 01:22:03 INFO - Running post-run listener: _resource_record_post_run 01:22:03 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38683648; Write bytes: 708261376; Read time: 377; Write time: 1992 01:22:03 INFO - TinderboxPrint: CPU usage
53.4% 01:22:03 INFO - TinderboxPrint: I/O read bytes / time
38,683,648 / 377 01:22:03 INFO - TinderboxPrint: I/O write bytes / time
708,261,376 / 1,992 01:22:03 INFO - TinderboxPrint: CPU idle
2,264.0 (46.6%) 01:22:03 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 01:22:03 INFO - TinderboxPrint: CPU user
1,620.6 (33.4%) 01:22:03 INFO - TinderboxPrint: Swap in / out
444,981,248 / 0 01:22:03 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395479552; Write bytes: 390301696; Read time: 29195; Write time: 705 01:22:03 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36856832; Write bytes: 297668096; Read time: 358; Write time: 1244 01:22:03 INFO - Running post-run listener: _upload_blobber_files 01:22:03 INFO - Blob upload gear active. 01:22:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:22:03 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 01:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:22:04 INFO - (blobuploader) - INFO - Open directory for files ... 01:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:22:04 INFO - (blobuploader) - INFO - Done attempting. 01:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:22:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:22:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:22:05 INFO - (blobuploader) - INFO - Done attempting. 01:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:22:07 INFO - (blobuploader) - INFO - Done attempting. 01:22:07 INFO - (blobuploader) - INFO - Iteration through files over. 01:22:07 INFO - Return code: 0 01:22:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:22:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:22:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/059b6e072b72c784824c02cd45cfaa20463532cd1ca3cb32d0428a8bb9b51ffaf966214e7c776e5b0a2c112abebc502eac8a43bd091b846a4eb49164a1df3c9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4957771e923d682d4986b97d80914dd6ed1bca05016c407006e6634ae0d4bbcb54f89143fc4d59498b9938988de842660f7238bcf321ab22251e13636704c9d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93bf7ae24d846316b254e6e25c7b8e3bb7c704bec14e0e8ab385225196ea834d59f1430d79868d5005d316354b9efb7d564f0f199622ffbccf0e9ff01047ef04"} 01:22:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:22:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:22:07 INFO - Contents: 01:22:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/059b6e072b72c784824c02cd45cfaa20463532cd1ca3cb32d0428a8bb9b51ffaf966214e7c776e5b0a2c112abebc502eac8a43bd091b846a4eb49164a1df3c9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4957771e923d682d4986b97d80914dd6ed1bca05016c407006e6634ae0d4bbcb54f89143fc4d59498b9938988de842660f7238bcf321ab22251e13636704c9d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93bf7ae24d846316b254e6e25c7b8e3bb7c704bec14e0e8ab385225196ea834d59f1430d79868d5005d316354b9efb7d564f0f199622ffbccf0e9ff01047ef04"} 01:22:07 INFO - Running post-run listener: copy_logs_to_upload_dir 01:22:07 INFO - Copying logs to upload dir... 01:22:07 INFO - mkdir: /builds/slave/test/build/upload/logs 01:22:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.881244 ========= master_lag: 2.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-18 01:22:09.999920) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 01:22:10.004279) ========= 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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/059b6e072b72c784824c02cd45cfaa20463532cd1ca3cb32d0428a8bb9b51ffaf966214e7c776e5b0a2c112abebc502eac8a43bd091b846a4eb49164a1df3c9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4957771e923d682d4986b97d80914dd6ed1bca05016c407006e6634ae0d4bbcb54f89143fc4d59498b9938988de842660f7238bcf321ab22251e13636704c9d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93bf7ae24d846316b254e6e25c7b8e3bb7c704bec14e0e8ab385225196ea834d59f1430d79868d5005d316354b9efb7d564f0f199622ffbccf0e9ff01047ef04"} build_url:https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011131 build_url: 'https://queue.taskcluster.net/v1/task/Mvw5YP56S7mEV6A5wQ5FSQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/059b6e072b72c784824c02cd45cfaa20463532cd1ca3cb32d0428a8bb9b51ffaf966214e7c776e5b0a2c112abebc502eac8a43bd091b846a4eb49164a1df3c9f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4957771e923d682d4986b97d80914dd6ed1bca05016c407006e6634ae0d4bbcb54f89143fc4d59498b9938988de842660f7238bcf321ab22251e13636704c9d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93bf7ae24d846316b254e6e25c7b8e3bb7c704bec14e0e8ab385225196ea834d59f1430d79868d5005d316354b9efb7d564f0f199622ffbccf0e9ff01047ef04"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 01:22:10.038022) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:22:10.038351) ========= 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.NgDKAVhGrB/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.oQAkfoq4TQ/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005027 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 01:22:10.089279) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 01:22:10.089684) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 01:22:10.090153) ========= ========= Total master_lag: 2.16 =========