builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0249 starttime: 1475742085.58 results: success (0) buildid: 20161005233801 builduid: 71b029082f104e07aa1f53f001910e92 revision: 3b73442fc24c4275d388b0ac497dbe868754f41d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.578446) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.579479) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.580133) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.658067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.658443) ========= 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.lYvrGPaanN/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.I4cH1Byasu/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-06 01:21:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 141M=0s 2016-10-06 01:21:25 (141 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093952 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.774906) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.775682) ========= 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.lYvrGPaanN/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.I4cH1Byasu/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.021805 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:25.824738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-06 01:21:25.825058) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3b73442fc24c4275d388b0ac497dbe868754f41d --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 3b73442fc24c4275d388b0ac497dbe868754f41d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lYvrGPaanN/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.I4cH1Byasu/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-06 01:21:25,905 truncating revision to first 12 chars 2016-10-06 01:21:25,905 Setting DEBUG logging. 2016-10-06 01:21:25,905 attempt 1/10 2016-10-06 01:21:25,905 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3b73442fc24c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-06 01:21:27,217 unpacking tar archive at: autoland-3b73442fc24c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.595682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-06 01:21:27.441838) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:27.442155) ========= script_repo_revision: 3b73442fc24c4275d388b0ac497dbe868754f41d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:27.442572) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:27.442835) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 01:21:27.460599) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-06 01:21:27.460868) ========= /tools/buildbot/bin/python 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', '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.lYvrGPaanN/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.I4cH1Byasu/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:21:27 INFO - MultiFileLogger online at 20161006 01:21:27 in /builds/slave/test 01:21:27 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:21:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:21:27 INFO - {'append_to_log': False, 01:21:27 INFO - 'base_work_dir': '/builds/slave/test', 01:21:27 INFO - 'blob_upload_branch': 'Autoland', 01:21:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:21:27 INFO - 'branch': 'Autoland', 01:21:27 INFO - 'buildbot_json_path': 'buildprops.json', 01:21:27 INFO - 'config_files': ('talos/mac_config.py',), 01:21:27 INFO - 'default_actions': ('clobber', 01:21:27 INFO - 'read-buildbot-config', 01:21:27 INFO - 'download-and-extract', 01:21:27 INFO - 'populate-webroot', 01:21:27 INFO - 'create-virtualenv', 01:21:27 INFO - 'install', 01:21:27 INFO - 'run-tests'), 01:21:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:21:27 INFO - 'download_minidump_stackwalk': True, 01:21:27 INFO - 'download_symbols': 'ondemand', 01:21:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:21:27 INFO - 'tooltool.py': '/tools/tooltool.py', 01:21:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:21:27 INFO - '/tools/misc-python/virtualenv.py')}, 01:21:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:21:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:21:27 INFO - 'installer_path': 'installer.exe', 01:21:27 INFO - 'log_level': 'info', 01:21:27 INFO - 'log_name': 'talos', 01:21:27 INFO - 'log_to_console': True, 01:21:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:21:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:21:27 INFO - 'opt_config_files': (), 01:21:27 INFO - 'pip_index': False, 01:21:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:21:27 INFO - 'cmd': ('bash', 01:21:27 INFO - '-c', 01:21:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:21:27 INFO - 'enabled': True, 01:21:27 INFO - 'halt_on_failure': False, 01:21:27 INFO - 'name': 'check_screen_resolution'},), 01:21:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:21:27 INFO - 'cmd': ('bash', 01:21:27 INFO - '-c', 01:21:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:21:27 INFO - 'enabled': True, 01:21:27 INFO - 'halt_on_failure': False, 01:21:27 INFO - 'name': 'check_screen_resolution'},), 01:21:27 INFO - 'run_cmd_checks_enabled': True, 01:21:27 INFO - 'sps_profile': False, 01:21:27 INFO - 'sps_profile_interval': 0, 01:21:27 INFO - 'suite': 'dromaeojs', 01:21:27 INFO - 'system_bits': '32', 01:21:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:21:27 INFO - 'title': 't-yosemite-r7-0249', 01:21:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:21:27 INFO - 'use_talos_json': True, 01:21:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:21:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:21:27 INFO - 'work_dir': 'build'} 01:21:27 INFO - [mozharness: 2016-10-06 08:21:27.661827Z] Running clobber step. 01:21:27 INFO - Running pre-action listener: _resource_record_pre_action 01:21:27 INFO - Running main action method: clobber 01:21:27 INFO - rmtree: /builds/slave/test/build 01:21:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:21:29 INFO - Running post-action listener: _resource_record_post_action 01:21:29 INFO - [mozharness: 2016-10-06 08:21:29.313764Z] Finished clobber step (success) 01:21:29 INFO - [mozharness: 2016-10-06 08:21:29.313889Z] Running read-buildbot-config step. 01:21:29 INFO - Running pre-action listener: _resource_record_pre_action 01:21:29 INFO - Running main action method: read_buildbot_config 01:21:29 INFO - Using buildbot properties: 01:21:29 INFO - { 01:21:29 INFO - "project": "", 01:21:29 INFO - "product": "firefox", 01:21:29 INFO - "script_repo_revision": "production", 01:21:29 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 01:21:29 INFO - "repository": "", 01:21:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 01:21:29 INFO - "buildid": "20161005233801", 01:21:29 INFO - "pgo_build": "False", 01:21:29 INFO - "basedir": "/builds/slave/test", 01:21:29 INFO - "buildnumber": 389, 01:21:29 INFO - "slavename": "t-yosemite-r7-0249", 01:21:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:21:29 INFO - "platform": "yosemite_r7", 01:21:29 INFO - "scheduler": "tests-autoland-macosx64-talos", 01:21:29 INFO - "branch": "autoland", 01:21:29 INFO - "repo_path": "integration/autoland", 01:21:29 INFO - "revision": "3b73442fc24c4275d388b0ac497dbe868754f41d", 01:21:29 INFO - "stage_platform": "macosx64", 01:21:29 INFO - "builduid": "71b029082f104e07aa1f53f001910e92", 01:21:29 INFO - "slavebuilddir": "test" 01:21:29 INFO - } 01:21:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 01:21:29 INFO - Running post-action listener: _resource_record_post_action 01:21:29 INFO - [mozharness: 2016-10-06 08:21:29.317127Z] Finished read-buildbot-config step (success) 01:21:29 INFO - [mozharness: 2016-10-06 08:21:29.317215Z] Running download-and-extract step. 01:21:29 INFO - Running pre-action listener: _resource_record_pre_action 01:21:29 INFO - Running main action method: download_and_extract 01:21:29 INFO - mkdir: /builds/slave/test/build/tests 01:21:29 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:21:29 INFO - trying https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 01:21:29 INFO - Downloading https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/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:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/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:21:30 INFO - Downloaded 1199 bytes. 01:21:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:21:30 INFO - Using the following test package requirements: 01:21:30 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:21:30 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 01:21:30 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 01:21:30 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'jsshell-mac.zip'], 01:21:30 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 01:21:30 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:21:30 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 01:21:30 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 01:21:30 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 01:21:30 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:21:30 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 01:21:30 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:21:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:21:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:21:30 INFO - Fetch https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:21:31 INFO - Http code: 200 01:21:31 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 01:21:31 INFO - x-amz-cf-id: wNrTDiKY-WDX4_uY64jE_srFeum5oY29KiLJEsde5N-SeL2vBn8BrQ== 01:21:31 INFO - x-amz-version-id: 73qMDPMjP93_.ZcUtZAbQpl2FTrcULzs 01:21:31 INFO - Expected file size: 16477173 01:21:31 INFO - Obtained file size: 16477173 01:21:38 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:21:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:21:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:21:38 INFO - Fetch https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:21:38 INFO - Http code: 200 01:21:38 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 01:21:38 INFO - x-amz-cf-id: MzgICXgiuJLr4RLI6Oi3wOMqJYGTE3eeusBxfaUIY7RoLtfIrcbukg== 01:21:38 INFO - x-amz-version-id: 73qMDPMjP93_.ZcUtZAbQpl2FTrcULzs 01:21:38 INFO - Expected file size: 16477173 01:21:38 INFO - Obtained file size: 16477173 01:21:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 01:21:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:21:45 INFO - Fetch https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 01:21:45 INFO - Http code: 200 01:21:45 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 01:21:45 INFO - x-amz-cf-id: V93IsQU7YqkNbGMdb2i1UYDM_fw8gyA8VGamZk4oog7qqHj4zY-RXA== 01:21:45 INFO - x-amz-version-id: K.W0KF_933TAPgNqXWgwyzvVPEBeOuMV 01:21:46 INFO - Expected file size: 14409006 01:21:46 INFO - Obtained file size: 14409006 01:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:46 INFO - trying https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:21:46 INFO - Downloading https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 01:21:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/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:21:50 INFO - Downloaded 111630917 bytes. 01:21:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:21:50 INFO - mkdir: /builds/slave/test/properties 01:21:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:21:50 INFO - Writing to file /builds/slave/test/properties/build_url 01:21:50 INFO - Contents: 01:21:50 INFO - build_url:https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:21:50 INFO - Running post-action listener: _resource_record_post_action 01:21:50 INFO - Running post-action listener: set_extra_try_arguments 01:21:50 INFO - [mozharness: 2016-10-06 08:21:50.759274Z] Finished download-and-extract step (success) 01:21:50 INFO - [mozharness: 2016-10-06 08:21:50.759380Z] Running populate-webroot step. 01:21:50 INFO - Running pre-action listener: _resource_record_pre_action 01:21:50 INFO - Running main action method: populate_webroot 01:21:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:21:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:21:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:21:50 INFO - '--tppagecycles', 01:21:50 INFO - '7'], 01:21:50 INFO - 'tests': ['tsvgm']}}, 01:21:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:21:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:21:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:21:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:21:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:21:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['damp', 'tps']}, 01:21:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'tests': ['damp', 'tps']}, 01:21:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['dromaeo_dom']}, 01:21:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:21:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['basic_compositor_video']}, 01:21:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:21:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other-e10s': {'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['a11yr', 01:21:50 INFO - 'ts_paint', 01:21:50 INFO - 'tpaint', 01:21:50 INFO - 'sessionrestore', 01:21:50 INFO - 'sessionrestore_no_auto_restore', 01:21:50 INFO - 'tabpaint']}, 01:21:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tsvgx', 01:21:50 INFO - 'tsvgr_opacity', 01:21:50 INFO - 'tart', 01:21:50 INFO - 'tscrollx', 01:21:50 INFO - 'cart']}, 01:21:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:21:50 INFO - 'tsvgr_opacity', 01:21:50 INFO - 'tart', 01:21:50 INFO - 'tscrollx', 01:21:50 INFO - 'cart']}, 01:21:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s'], 01:21:50 INFO - 'tests': ['tp5o']}, 01:21:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'tests': ['tp5o']}, 01:21:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--disable-e10s', 01:21:50 INFO - '--xperf_path', 01:21:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:50 INFO - 'tests': ['tp5n']}, 01:21:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:21:50 INFO - 'talos_options': ['--xperf_path', 01:21:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:21:50 INFO - 'tests': ['tp5n']}}, 01:21:50 INFO - 'talos.zip': {'path': '', 01:21:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:21:50 INFO - Running post-action listener: _resource_record_post_action 01:21:50 INFO - [mozharness: 2016-10-06 08:21:50.777885Z] Finished populate-webroot step (success) 01:21:50 INFO - [mozharness: 2016-10-06 08:21:50.778014Z] Running create-virtualenv step. 01:21:50 INFO - Running pre-action listener: _resource_record_pre_action 01:21:50 INFO - Running main action method: create_virtualenv 01:21:50 INFO - Creating virtualenv /builds/slave/test/build/venv 01:21:50 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:21:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:21:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:21:50 INFO - Using real prefix '/tools/python27' 01:21:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:21:51 INFO - Installing distribute.............................................................................................................................................................................................done. 01:21:54 INFO - Installing pip.................done. 01:21:54 INFO - Return code: 0 01:21:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:21:54 INFO - Reading from file tmpfile_stdout 01:21:54 INFO - Output received: 01:21:54 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:21:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:21:54 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:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:54 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:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:54 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:21:54 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:21:54 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:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:21:54 INFO - 'HOME': '/Users/cltbld', 01:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:21:54 INFO - 'LOGNAME': 'cltbld', 01:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:54 INFO - 'MOZ_NO_REMOTE': '1', 01:21:54 INFO - 'NO_EM_RESTART': '1', 01:21:54 INFO - 'PAGER': '/bin/cat', 01:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:54 INFO - 'PWD': '/builds/slave/test', 01:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:21:54 INFO - 'SHELL': '/bin/bash', 01:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:21:54 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:21:54 INFO - 'USER': 'cltbld', 01:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:54 INFO - 'XPC_FLAGS': '0x0', 01:21:54 INFO - 'XPC_SERVICE_NAME': '0', 01:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:54 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:21:54 INFO - Cleaning up... 01:21:55 INFO - Return code: 0 01:21:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:55 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:21:55 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:21:55 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:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:21:55 INFO - 'HOME': '/Users/cltbld', 01:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:21:55 INFO - 'LOGNAME': 'cltbld', 01:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:55 INFO - 'MOZ_NO_REMOTE': '1', 01:21:55 INFO - 'NO_EM_RESTART': '1', 01:21:55 INFO - 'PAGER': '/bin/cat', 01:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:55 INFO - 'PWD': '/builds/slave/test', 01:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:21:55 INFO - 'SHELL': '/bin/bash', 01:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:21:55 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:21:55 INFO - 'USER': 'cltbld', 01:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:55 INFO - 'XPC_FLAGS': '0x0', 01:21:55 INFO - 'XPC_SERVICE_NAME': '0', 01:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:55 INFO - Downloading/unpacking psutil>=3.1.1 01:21:55 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:21:55 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:21:55 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:21:55 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:21:55 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:21:55 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:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:21:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:58 INFO - Installing collected packages: psutil 01:21:58 INFO - Running setup.py install for psutil 01:21:58 INFO - building 'psutil._psutil_osx' extension 01:21:58 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:21:59 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:21:59 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:21:59 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:21:59 INFO - building 'psutil._psutil_posix' extension 01:21:59 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:21:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:21:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:21:59 INFO - ^ 01:21:59 INFO - 1 warning generated. 01:21:59 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:21:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:21:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:21:59 INFO - Successfully installed psutil 01:21:59 INFO - Cleaning up... 01:21:59 INFO - Return code: 0 01:21:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:21:59 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:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:59 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:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:21:59 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:21:59 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:21:59 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:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:21:59 INFO - 'HOME': '/Users/cltbld', 01:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:21:59 INFO - 'LOGNAME': 'cltbld', 01:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:21:59 INFO - 'MOZ_NO_REMOTE': '1', 01:21:59 INFO - 'NO_EM_RESTART': '1', 01:21:59 INFO - 'PAGER': '/bin/cat', 01:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:21:59 INFO - 'PWD': '/builds/slave/test', 01:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:21:59 INFO - 'SHELL': '/bin/bash', 01:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:21:59 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:21:59 INFO - 'USER': 'cltbld', 01:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:21:59 INFO - 'XPC_FLAGS': '0x0', 01:21:59 INFO - 'XPC_SERVICE_NAME': '0', 01:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:21:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:21:59 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:21:59 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:21:59 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:21:59 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:21:59 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:21:59 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:22:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:22:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:22:02 INFO - Installing collected packages: mozsystemmonitor 01:22:02 INFO - Running setup.py install for mozsystemmonitor 01:22:02 INFO - Successfully installed mozsystemmonitor 01:22:02 INFO - Cleaning up... 01:22:02 INFO - Return code: 0 01:22:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:02 INFO - 'HOME': '/Users/cltbld', 01:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:02 INFO - 'LOGNAME': 'cltbld', 01:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:02 INFO - 'MOZ_NO_REMOTE': '1', 01:22:02 INFO - 'NO_EM_RESTART': '1', 01:22:02 INFO - 'PAGER': '/bin/cat', 01:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:02 INFO - 'PWD': '/builds/slave/test', 01:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:02 INFO - 'SHELL': '/bin/bash', 01:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:02 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:02 INFO - 'USER': 'cltbld', 01:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:02 INFO - 'XPC_FLAGS': '0x0', 01:22:02 INFO - 'XPC_SERVICE_NAME': '0', 01:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:02 INFO - Downloading/unpacking jsonschema==2.5.1 01:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:02 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:22:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:22:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 INFO - Downloading functools32-3.2.3-2.tar.gz 01:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:22:05 INFO - warning: no files found matching '*.txt' 01:22:05 INFO - no previously-included directories found matching 'build' 01:22:05 INFO - no previously-included directories found matching 'dist' 01:22:05 INFO - no previously-included directories found matching '.git*' 01:22:05 INFO - Installing collected packages: jsonschema, functools32 01:22:05 INFO - Running setup.py install for functools32 01:22:06 INFO - warning: no files found matching '*.txt' 01:22:06 INFO - no previously-included directories found matching 'build' 01:22:06 INFO - no previously-included directories found matching 'dist' 01:22:06 INFO - no previously-included directories found matching '.git*' 01:22:06 INFO - Successfully installed jsonschema functools32 01:22:06 INFO - Cleaning up... 01:22:06 INFO - Return code: 0 01:22:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:22:06 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:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:06 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:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:06 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22:06 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:22:06 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:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:06 INFO - 'HOME': '/Users/cltbld', 01:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:06 INFO - 'LOGNAME': 'cltbld', 01:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:06 INFO - 'MOZ_NO_REMOTE': '1', 01:22:06 INFO - 'NO_EM_RESTART': '1', 01:22:06 INFO - 'PAGER': '/bin/cat', 01:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:06 INFO - 'PWD': '/builds/slave/test', 01:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:06 INFO - 'SHELL': '/bin/bash', 01:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:06 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:06 INFO - 'USER': 'cltbld', 01:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:06 INFO - 'XPC_FLAGS': '0x0', 01:22:06 INFO - 'XPC_SERVICE_NAME': '0', 01:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:22:06 INFO - Cleaning up... 01:22:06 INFO - Return code: 0 01:22:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:22:06 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:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:06 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:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:06 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22:06 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:22:06 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:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:06 INFO - 'HOME': '/Users/cltbld', 01:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:06 INFO - 'LOGNAME': 'cltbld', 01:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:06 INFO - 'MOZ_NO_REMOTE': '1', 01:22:06 INFO - 'NO_EM_RESTART': '1', 01:22:06 INFO - 'PAGER': '/bin/cat', 01:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:06 INFO - 'PWD': '/builds/slave/test', 01:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:06 INFO - 'SHELL': '/bin/bash', 01:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:06 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:06 INFO - 'USER': 'cltbld', 01:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:06 INFO - 'XPC_FLAGS': '0x0', 01:22:06 INFO - 'XPC_SERVICE_NAME': '0', 01:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:06 INFO - Downloading/unpacking blobuploader==1.2.4 01:22:06 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:22:06 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:22:06 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:22:06 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:22:06 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:22:06 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:22:09 INFO - Downloading blobuploader-1.2.4.tar.gz 01:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:22:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:22:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:09 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:22:10 INFO - Downloading docopt-0.6.1.tar.gz 01:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:22:10 INFO - Installing collected packages: blobuploader, requests, docopt 01:22:10 INFO - Running setup.py install for blobuploader 01:22:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:22:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:22:10 INFO - Running setup.py install for requests 01:22:11 INFO - Running setup.py install for docopt 01:22:11 INFO - Successfully installed blobuploader requests docopt 01:22:11 INFO - Cleaning up... 01:22:11 INFO - Return code: 0 01:22:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:11 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:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:11 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:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:11 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22:11 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:22:11 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:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:11 INFO - 'HOME': '/Users/cltbld', 01:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:11 INFO - 'LOGNAME': 'cltbld', 01:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:11 INFO - 'MOZ_NO_REMOTE': '1', 01:22:11 INFO - 'NO_EM_RESTART': '1', 01:22:11 INFO - 'PAGER': '/bin/cat', 01:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:11 INFO - 'PWD': '/builds/slave/test', 01:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:11 INFO - 'SHELL': '/bin/bash', 01:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:11 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:11 INFO - 'USER': 'cltbld', 01:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:11 INFO - 'XPC_FLAGS': '0x0', 01:22:11 INFO - 'XPC_SERVICE_NAME': '0', 01:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-LZBq7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-tKfwdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-apRVUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-hZSS6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-lbLqVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ztgJlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Gy08J0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-z6w8sE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4A1WUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4LMTbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-LIDBUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-9Vomnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4C69Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-tOQ5QW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-hvlMLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-vz5Oti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-fPcZGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:22:13 INFO - Running setup.py install for manifestparser 01:22:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:22:13 INFO - Running setup.py install for mozcrash 01:22:13 INFO - Running setup.py install for mozdebug 01:22:13 INFO - Running setup.py install for mozdevice 01:22:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:22:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:22:13 INFO - Running setup.py install for mozfile 01:22:14 INFO - Running setup.py install for mozhttpd 01:22:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Running setup.py install for mozinfo 01:22:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Running setup.py install for mozInstall 01:22:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Running setup.py install for mozleak 01:22:14 INFO - Running setup.py install for mozlog 01:22:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Running setup.py install for moznetwork 01:22:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:22:14 INFO - Running setup.py install for mozprocess 01:22:15 INFO - Running setup.py install for mozprofile 01:22:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:22:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:22:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:22:15 INFO - Running setup.py install for mozrunner 01:22:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:22:15 INFO - Running setup.py install for mozscreenshot 01:22:15 INFO - Running setup.py install for moztest 01:22:15 INFO - Running setup.py install for mozversion 01:22:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:22:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:22:15 INFO - Cleaning up... 01:22:15 INFO - Return code: 0 01:22:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:15 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:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:15 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:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:15 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22:15 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:22:15 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:22:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:15 INFO - 'HOME': '/Users/cltbld', 01:22:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:15 INFO - 'LOGNAME': 'cltbld', 01:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:15 INFO - 'MOZ_NO_REMOTE': '1', 01:22:15 INFO - 'NO_EM_RESTART': '1', 01:22:15 INFO - 'PAGER': '/bin/cat', 01:22:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:15 INFO - 'PWD': '/builds/slave/test', 01:22:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:15 INFO - 'SHELL': '/bin/bash', 01:22:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:15 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:15 INFO - 'USER': 'cltbld', 01:22:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:15 INFO - 'XPC_FLAGS': '0x0', 01:22:15 INFO - 'XPC_SERVICE_NAME': '0', 01:22:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Puogcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-D97Ust-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Oa06eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-FJgJFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-KqBjpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-8IvJFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cA4usb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:16 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:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Dlc6MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-v7lksB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-QkJmXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-XnjO23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-voFwKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-yYHtCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-rhJfxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-JxHUVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-flfbX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:17 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:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-HhKdSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:17 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:22:20 INFO - Downloading blessings-1.6.tar.gz 01:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:22:20 INFO - Installing collected packages: blessings 01:22:20 INFO - Running setup.py install for blessings 01:22:21 INFO - Successfully installed blessings 01:22:21 INFO - Cleaning up... 01:22:21 INFO - Return code: 0 01:22:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:21 INFO - Reading from file tmpfile_stdout 01:22:21 INFO - Current package versions: 01:22:21 INFO - blessings == 1.6 01:22:21 INFO - blobuploader == 1.2.4 01:22:21 INFO - docopt == 0.6.1 01:22:21 INFO - functools32 == 3.2.3-2 01:22:21 INFO - jsonschema == 2.5.1 01:22:21 INFO - manifestparser == 1.1 01:22:21 INFO - mozInstall == 1.12 01:22:21 INFO - mozcrash == 0.17 01:22:21 INFO - mozdebug == 0.1 01:22:21 INFO - mozdevice == 0.48 01:22:21 INFO - mozfile == 1.2 01:22:21 INFO - mozhttpd == 0.7 01:22:21 INFO - mozinfo == 0.9 01:22:21 INFO - mozleak == 0.1 01:22:21 INFO - mozlog == 3.4 01:22:21 INFO - moznetwork == 0.27 01:22:21 INFO - mozprocess == 0.23 01:22:21 INFO - mozprofile == 0.28 01:22:21 INFO - mozrunner == 6.12 01:22:21 INFO - mozscreenshot == 0.1 01:22:21 INFO - mozsystemmonitor == 0.3 01:22:21 INFO - moztest == 0.7 01:22:21 INFO - mozversion == 1.4 01:22:21 INFO - psutil == 3.1.1 01:22:21 INFO - requests == 1.2.3 01:22:21 INFO - wsgiref == 0.1.2 01:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22: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:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:21 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 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22:21 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:22:21 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:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:21 INFO - 'HOME': '/Users/cltbld', 01:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:21 INFO - 'LOGNAME': 'cltbld', 01:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:21 INFO - 'MOZ_NO_REMOTE': '1', 01:22:21 INFO - 'NO_EM_RESTART': '1', 01:22:21 INFO - 'PAGER': '/bin/cat', 01:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:21 INFO - 'PWD': '/builds/slave/test', 01:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:21 INFO - 'SHELL': '/bin/bash', 01:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:21 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:21 INFO - 'USER': 'cltbld', 01:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:21 INFO - 'XPC_FLAGS': '0x0', 01:22:21 INFO - 'XPC_SERVICE_NAME': '0', 01:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 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:22:22 INFO - Cleaning up... 01:22:22 INFO - Return code: 0 01:22:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:22: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:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6d2574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, '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.lYvrGPaanN/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.I4cH1Byasu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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:22: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', 'jsonschema'] in /builds/slave/test/build 01:22: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 jsonschema 01:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:22 INFO - 'HOME': '/Users/cltbld', 01:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:22 INFO - 'LOGNAME': 'cltbld', 01:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:22 INFO - 'MOZ_NO_REMOTE': '1', 01:22:22 INFO - 'NO_EM_RESTART': '1', 01:22:22 INFO - 'PAGER': '/bin/cat', 01:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:22 INFO - 'PWD': '/builds/slave/test', 01:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:22 INFO - 'SHELL': '/bin/bash', 01:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:22 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:22 INFO - 'USER': 'cltbld', 01:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:22 INFO - 'XPC_FLAGS': '0x0', 01:22:22 INFO - 'XPC_SERVICE_NAME': '0', 01:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:22:22 INFO - Cleaning up... 01:22:22 INFO - Return code: 0 01:22:22 INFO - Running post-action listener: _resource_record_post_action 01:22:22 INFO - Running post-action listener: _start_resource_monitoring 01:22:22 INFO - Starting resource monitoring. 01:22:22 INFO - [mozharness: 2016-10-06 08:22:22.413317Z] Finished create-virtualenv step (success) 01:22:22 INFO - [mozharness: 2016-10-06 08:22:22.414384Z] Running install step. 01:22:22 INFO - Running pre-action listener: _resource_record_pre_action 01:22:22 INFO - Running main action method: install 01:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:22 INFO - Reading from file tmpfile_stdout 01:22:22 INFO - Detecting whether we're running mozinstall >=1.0... 01:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:22:22 INFO - Reading from file tmpfile_stdout 01:22:22 INFO - Output received: 01:22:22 INFO - Usage: mozinstall [options] installer 01:22:22 INFO - Options: 01:22:22 INFO - -h, --help show this help message and exit 01:22:22 INFO - -d DEST, --destination=DEST 01:22:22 INFO - Directory to install application into. [default: 01:22:22 INFO - "/builds/slave/test"] 01:22:22 INFO - --app=APP Application being installed. [default: firefox] 01:22:22 INFO - mkdir: /builds/slave/test/build/application 01:22:22 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:22:22 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:22:56 INFO - Reading from file tmpfile_stdout 01:22:56 INFO - Output received: 01:22:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:22:56 INFO - Running post-action listener: _resource_record_post_action 01:22:56 INFO - [mozharness: 2016-10-06 08:22:56.991960Z] Finished install step (success) 01:22:56 INFO - [mozharness: 2016-10-06 08:22:56.992090Z] Running run-tests step. 01:22:56 INFO - Running pre-action listener: _resource_record_pre_action 01:22:56 INFO - Running main action method: run_tests 01:22:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:22:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:22:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:22:57 INFO - 2016-10-06 01:22:57.007 screenresolution[1938:13450] starting screenresolution argv=screenresolution get 01:22:57 INFO - 2016-10-06 01:22:57.009 screenresolution[1938:13450] Display 0: 1600x1200x32@60 01:22:57 INFO - 2016-10-06 01:22:57.015 screenresolution[1939:13452] starting screenresolution argv=screenresolution list 01:22:57 INFO - Available Modes on Display 0 01:22:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:22:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:22:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:22:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:22:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:22:57 INFO - Intel Iris: 01:22:57 INFO - Chipset Model: Intel Iris 01:22:57 INFO - Type: GPU 01:22:57 INFO - Bus: Built-In 01:22:57 INFO - VRAM (Dynamic, Max): 1536 MB 01:22:57 INFO - Vendor: Intel (0x8086) 01:22:57 INFO - Device ID: 0x0a2e 01:22:57 INFO - Revision ID: 0x0009 01:22:57 INFO - Displays: 01:22:57 INFO - Display: 01:22:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:22:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:22:57 INFO - Display Serial Number: 1600x1200 60 01:22:57 INFO - Main Display: Yes 01:22:57 INFO - Mirror: Off 01:22:57 INFO - Online: Yes 01:22:57 INFO - Rotation: Supported 01:22:57 INFO - Return code: 0 01:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:22:57 INFO - Python 2.7.3 01:22:57 INFO - Return code: 0 01:22:57 INFO - grabbing minidump binary from tooltool 01:22:57 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:22:57 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 0x10ba5ed30>, '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 0x10b884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba56b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:22:57 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:22:57 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:22:57 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:22:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:22:57 INFO - Return code: 0 01:22:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:22:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:22:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:22:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:22:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:22:57 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/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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:22:57 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/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --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:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lYvrGPaanN/Render', 01:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:57 INFO - 'HOME': '/Users/cltbld', 01:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:57 INFO - 'LOGNAME': 'cltbld', 01:22:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:22:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:57 INFO - 'MOZ_NO_REMOTE': '1', 01:22:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:22:57 INFO - 'NO_EM_RESTART': '1', 01:22:57 INFO - 'PAGER': '/bin/cat', 01:22:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:57 INFO - 'PWD': '/builds/slave/test', 01:22:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:57 INFO - 'SHELL': '/bin/bash', 01:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4cH1Byasu/Listeners', 01:22:57 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 01:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:57 INFO - 'USER': 'cltbld', 01:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:57 INFO - 'XPC_FLAGS': '0x0', 01:22:57 INFO - 'XPC_SERVICE_NAME': '0', 01:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:57 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/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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:22:57 INFO - mozversion application_buildid: 20161005233801 01:22:57 INFO - mozversion application_changeset: 3b73442fc24c4275d388b0ac497dbe868754f41d 01:22:57 INFO - mozversion application_display_name: Nightly 01:22:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:22:57 INFO - mozversion application_name: Firefox 01:22:57 INFO - mozversion application_remotingname: firefox 01:22:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:22:57 INFO - mozversion application_vendor: Mozilla 01:22:57 INFO - mozversion application_version: 52.0a1 01:22:57 INFO - mozversion platform_buildid: 20161005233801 01:22:57 INFO - mozversion platform_changeset: 3b73442fc24c4275d388b0ac497dbe868754f41d 01:22:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:22:57 INFO - mozversion platform_version: 52.0a1 01:22:57 INFO - using testdate: 1475742177 01:22:57 INFO - actual date: 1475742177 01:22:57 INFO - starting webserver on 'localhost:50927' 01:22:57 INFO - SUITE-START | Running 2 tests 01:22:57 INFO - TEST-START | dromaeo_css 01:22:57 INFO - operating with platform_type : mac_ 01:22:57 INFO - Initialising browser for dromaeo_css test... 01:22:57 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpxgWuNT/profile http://localhost:50927/getInfo.html) 01:22:59 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 01:22:59 INFO - PROCESS | 1949 | colorDepth:24 01:22:59 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 01:22:59 INFO - PROCESS | 1949 | __metrics 01:22:59 INFO - PROCESS | 1949 | 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:22:59 INFO - PROCESS | 1949 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:23:00 INFO - TEST-INFO | 1949: exit 0 01:23:00 INFO - Browser initialized. 01:23:00 INFO - Running cycle 1/1 for dromaeo_css test... 01:23:00 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpxgWuNT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:23:12 INFO - PROCESS | 1951 | RSS: Main: 235569152 01:23:12 INFO - PROCESS | 1951 | 01:24:04 INFO - PROCESS | 1951 | 1475742244158 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:26:17 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50927/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50927/tests/dromaeo/cssquery-ext.html) 01:26:18 INFO - PROCESS | 1951 | RSS: Main: 348631040 01:26:18 INFO - PROCESS | 1951 | 01:29:21 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50927/tests/dromaeo/cssquery-ext.html (next: http://localhost:50927/tests/dromaeo/cssquery-jquery.html) 01:29:21 INFO - PROCESS | 1951 | RSS: Main: 374431744 01:29:21 INFO - PROCESS | 1951 | 01:32:24 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50927/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50927/tests/dromaeo/cssquery-mootools.html) 01:32:24 INFO - PROCESS | 1951 | RSS: Main: 339116032 01:32:24 INFO - PROCESS | 1951 | 01:35:27 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50927/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50927/tests/dromaeo/cssquery-prototype.html) 01:35:27 INFO - PROCESS | 1951 | RSS: Main: 343764992 01:35:27 INFO - PROCESS | 1951 | 01:38:30 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50927/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50927/tests/dromaeo/cssquery-yui.html) 01:38:31 INFO - PROCESS | 1951 | RSS: Main: 330428416 01:38:31 INFO - PROCESS | 1951 | 01:41:34 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50927/tests/dromaeo/cssquery-yui.html (next: http://localhost:50927/tests/dromaeo/cssquery-dojo.html) 01:41:35 INFO - PROCESS | 1951 | RSS: Main: 410779648 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | __start_tp_report 01:41:35 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 01:41:35 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 01:41:35 INFO - PROCESS | 1951 | |i|pagename|runs| 01:41:35 INFO - PROCESS | 1951 | |0;dojo.html;2893.5659651052338;3284.375968565973;3314.4696848504245;3317.6018877734678;3341.949870751939;5521.889562208753;5573.08044172711;5582.553395728342;5647.887042259153;5671.631343962645;5548.9722551387285;5615.494605485505;5624.100143976964;5648.74580643871;5651.039323315036;5076.771545280471;5130.666506677054;5167.095758242312;5189.532942035216;5223.71269580173;5163.535281824635;5241.659292145999;5244.79020839166;5246.68519888806;5265.552428043621;5163.664361816489;5175.430702622709;5195.298634684307;5238.685678859261;5248.265242866002;5235.0053489837;5249.238860365225;5262.92105618416;5273.789048438058;5292.894142117136;5042.899142017176;5090.134677104864;5131.358580177478;5183.403908550489;5201.739913004335;5535.059039963176;5597.552195824365;5606.411326810708;5607.523360514361;5654.660720356792;6110.26676798787;6175.907361389583;6184.134221209034;6201.81394558164;6208.50331973441;6096.998148966061;6108.389161083856;6123.923976812198;6178.41305076017;6223.906641400383;5693.316801983747;5720.256366672306;5724.656520608693;5774.364819869811;5778.3354914184565;6297.905531417033;6301.40136687014;6301.495880329654;6310.368963103653;6312.936870631235;5816.418358164235;5964.433378829004;6018.578699491081;6032.095185722177;6040.033594624839;5427.158790387496;6120.204373431426;6142.468080853438;6178.135061091451;6184.969075154595;5337.519623233928;6066.181065556093;6079.604825686316;6110.633361998294;6136.938630613726;5829.213056237439;6084.665343406155;6087.817365479043;6102.206713127265;6123.295820980555;4136.296829538986;4754.0491901619125;4829.227323628203;4865.440474345495;4923.704577725348;3955.0310174007554;4217.34631132175;4380.299152135707;4397.032652816375;4432.268675668543;5610.13042978339;5629.549636029108;5642.802501912413;5659.349174844863;5681.204631351614;6044.093385992136;6100.816975490742;6111.816645500642;6119.289302195883;6141.723622437001;5531.0873705838085;5645.491905728504;5666.603337766317;5671.177679236487;5677.460641239076;5337.626366154332;5344.118220493575;5348.1175606025345;5361.973190134025;5365.892682146336;6260.718267677966;6266;6278.591891527036;6284.780032698834;6286.9371306288185;6009.429104235091;6095.024796032613;6119.654885716854;6132.5084813409785;6138.143176733788;6288.245410550763;6295.433410993032;6299.496040316855;6300.243970723543;6323.873522529616;6069.453749162597;6101.938980610137;6109.083637454418;6123.877522449437;6132.110843927695;6124.6325220486915;6131.499004853578;6169.074638804215;6184.288806787277;6188.9690551546955;4976.576990955809;6042.0332746760305;6066.757329706762;6083.878322433438;6120.663363515049;5977.491913187428;5998.0403135499855;6002.279726432856;6012.248468941382;6021.066734656135;4548.47692515365;5036.471170527042;5761.049426844524;5801.274840644919;5848.853778655568;5945.40545945402;5951.1073338999495;5983.790567330123;5985.311689155628;6031.185789918305;5835.620684655484;5856.619319744374;5874.060150375919;5886;5922.348541660328 01:41:35 INFO - PROCESS | 1951 | |1;ext.html;460.1739876920927;477.60120299549874;484.2155707753439;489.4689262150566;540.8431554849094;30733;39490.81527554175;40600.79699601505;40777.79611101944;40799;34881.65118348816;42349.15301693964;42358.576414235846;42452.15095698084;42470.15059698804;9586.08932151448;9771.804563908718;9775.120239178472;9777.12005919467;9784.315097943147;9577.76055598612;9688.612455501807;9693.612255509772;9695.175910047654;9698.806023879519;9595.13643772064;9632.036796320346;9642.37324573904;9645.421274723503;9675.322727409084;9636.42181469114;9690.176335011533;9692.46691431971;9693.66072187477;9696.903030969741;9603.903960960371;9673.37123086997;9677.758056048584;9685.951570242103;9698.224142068617;40169.59830401688;40583;40611.390829137585;40642.18715625701;40678.38982415266;3238.1904523869034;3318.386098571748;3318.386098571748;3324.2354258520436;3426.811525366104;30364;31511.527327090113;31517.36965260705;31544.36911261785;31896.362072758417;3150.3384289299274;3172.7779055466126;3268.0359294008326;3337.332533493287;3392.406328892434;464719;465037;465275.6736216331;465516.9999999966;476082;60308.69845650744;60798.69600651968;60812.39187608156;61042;61315;63523.047154292726;63601;63697.68151159215;63709;63925.36074639194;15480.380784768484;15585.610359741097;15613.843861561467;15617.062976221236;15628.2185890705;12571.308578028113;12639.052071094704;12645.304508252135;12647.747045059232;12668;15306.540803775904;15557;17265.741013884803;17349.306027759;17516.474505764847;5508.669479831223;6516.989866570689;6962.895556566656;7040.542364746275;7120.501564890408;881.3433991676242;923.7598224461689;940.581441258647;965.6958058211641;1058.7617785998243;27981.300467488476;29063.56404653932;29241;29984.250393739894;30101.849490752407;1001.689476262361;1034.425893629028;1039.0544604409952;1056.3767377247461;1089.8801131875487;14310;14480.710385792225;14662.63343416423;14677.853221467862;15140.772888406682;637880;641655;642374;642472;643329.5838020247;55049;57485;57849.71075144597;57900;58083.709581451825;1066.6959916423782;1216.197309775544;1275.776739070659;1347.0570600579633;1367.7127803161447;28974.420511590073;30834.229144271572;30893.439180946745;31006.22484437907;31739.52390714095;48939.26591101207;49542;49632.50367496279;49696.751516242184;49837;537627;541111.1222214408;541530.2923485357;543303;545705;32900;33884;35781.56873725022;36631.81684091563;41330.793346033075;46454.76772616251;46674.29988550243;47209.76395118002;47233.29150062683;47866.52133478621;38626.0343491415;38781;38892.61107389003;40253.39619905645;40292.798536008304;38494;38540.80729596334;38810.6118938807;39949.60050399459;40010.599894001854;37680.24639507249;38188.809055954545;38332.80833595814;38576.80711596424;38604;32895.83552082224;33498;34412.31175376529;34509.827450862584;34519.30961380809;20315.492112697302;23782.643260351455;24953;25402;25616.231513054638 01:41:35 INFO - PROCESS | 1951 | |2;jquery.html;315.8641784032866;350.3081414206943;369.4034134872181;371.1130398347948;401.73886973467245;15088.773668394982;15466.922665386672;15544.766828497568;20906.477338066557;20989;19838.603227935433;19885.3040143595;20831.958402079872;21063.2627858025;21344.679829802528;5689.118186681061;5996.97001514994;5999.250093738283;6000.519958403317;6007.309159446676;4014.578469260715;6486.4162225399705;6583.07836902834;6621.073049773012;6633.535652504326;5762.193292938971;6559.1145195398485;6573.244076931166;6577.309382514815;6598.93401065988;5988.490978266853;6143.692815359259;6144.5698801083945;6147.170132032208;6160.661163635976;5697.951595896217;5857.531397488234;5945.732442040118;5953.821385358447;5995.250593675791;9357.859632105525;12172.634820955385;12351.567695130627;12352.62942111738;24311.63532547013;2045.8977051147533;2241.002753774571;2309.307207837642;2336.4509340305017;2389.689340385756;9742.512874356255;9754.439289713682;9766.706998789976;9814.558344874498;10374.221933354953;1622.8620567251799;1868.588910439701;1873.9250429982785;1901.7147427885789;1941.4855063408208;92250;101568.49215753874;112288.13983021192;115920.42039789831;118704.40647796792;9775.826900771954;10868.891311086787;11206.887931120747;11467.713307167387;11478.598249061242;14443;14449.34977925996;14477.05899116554;14478.85521144796;14497.492587759378;8275.586220688941;8443.451175673561;8460.830783384297;8564.48613083217;8575.1424857515;8299.751007469786;8318.62566184511;8339.708110216234;8501.489910605384;8517.74446766598;4365.323374876899;4454.621357184676;4479.910401791946;4546.363509108727;4547.226971414867;1697.5162078807728;1823.8540916726895;1881.6142690748363;1884.575970406675;1886.141805478504;651.5668631709584;780.6760194519325;787.7361084036912;791.2325044706625;972.6352617768337;15550.377984880704;21020.474488137614;21081;21231;21378.679319810217;858.8454078265972;910.1217325281135;937.381328323317;949.8290307744534;976.2629214942815;10319.793604128026;11117.11063114965;11222.551097956153;11256.380899050468;11259.380734059705;597089;597558;598168;608271;609180;79564.4087118266;80230.59884700539;121075;131252.68747312596;139942.01159768086;946.654471118037;949.0604301741364;951.6954574536082;1024.7233247023262;1026.1944373666786;13858.86141138616;18132.818671813115;19142.138603762585;19798.307059252857;19868.70196947018;54525.72737136289;55193.724031379585;55787.72106139443;56100.71949640226;56259.15611265916;74684;75898.62050689712;76744.61627691826;76968;78226.43547128912;7659.31066204022;7858.096318923397;7940.086890007723;7963.761087167394;8126.837463250584;6292.905606415999;9217.893852737714;9659.227261818896;11863.34751588671;12558.120931534704;10480.213983951082;10509.054185123376;10793.730156746144;10793.784124317313;10972.835407468734;10470.895291046992;10506.684799456028;10773.730656733644;10779.730506737395;11029.558817647525;10659.626913058006;10886.29239099456;11201.60794372193;11205.991460768244;11317.603883864025;7837.882431763642;7863.370930325675;7890.447668663139;7934.801629959066;8062.395320351115;10306.536205870603;10430.322029068086;10700.250982431155;10766.354018758899;10981.341119532854 01:41:35 INFO - PROCESS | 1951 | |3;mootools.html;1514.659201679622;1539.9153046582437;1740.5387572293344;1883.660941030614;1905.9332923347677;8539.10339414361;9017.63929442823;9022;9023.54882255887;9038.72883813485;7872.212778722125;8138.186181381859;8504.574771261443;8785.121487851227;8796.472211667304;2313.5025969416615;2352.070931981866;2352.176738141654;2354.105439932823;2358.5518751437257;2316.837821352506;2332.941676458085;2357.95284094318;2360.0913648245464;2360.1975328388344;2186.814120799734;2333.8132949364094;2350.74141844397;2359.256834097254;2362.492064206195;2310.653401989698;2323.2217207235594;2335.369450248432;2338.543983923136;2338.7427382987858;2236.194969810867;2245.2141750387395;2249.336445748508;2252.9436764080865;2256.435891027243;8809.295256379473;8869.866951995726;8882.955585222033;8901.643934242622;8907.777305567348;5191.402988656278;5365.222042803811;5366.570674346082;5370.43610420904;5375.784968601251;2967.896123635684;3410.6248312685584;3468.809215493147;3618.7828730276005;3620.945685814715;1036.7978244242377;1073.613499140309;1077.1113831089383;1093.1418836213613;1101.3226865477754;11684.415779211005;11779.352135632536;11808.291502509877;11809.752318112502;11852.288862668267;3713.2387860488534;4539.863804085882;4738.123447162286;4745.83389581363;4746.2168742157155;9988.800223995479;9990;10008.39949603026;10010.099091081838;10010.449425281533;5295.6557823742105;5306.09796334624;5307;5317.574594032468;5323.30796996388;4337.240982827992;4390.780460976938;4513.81944722214;4818.614510839125;4857.854264372075;3760.924781504409;3811.3520701480816;3882.650561449483;3931.4495970564144;3932.11527406337;1069.8178512743461;1276.24701426159;1280.6414204022888;1294.540438144464;1303.2897071096274;748.6930358552967;861.0012385632637;905.9139381758722;906.2840356118725;940.9247260219166;5670.943290567042;5745.511631511285;5871.236739223874;5876.794312199053;6146.6004709693725;904.6517090919912;917.9082091790767;923.7228831350568;967.7000129959735;1010.7068950004564;3684.9313699027516;3888.4556162137324;3906.8046597670573;4009.538903026131;4013.3578627419474;9641.807163856824;10897.618583349546;11027.393493357782;11032.779344413068;11036.779264414667;10945.069669078217;10964.671059868217;10987.340759554292;10987.560497580007;11027.724306892393;7672.194419585975;8113.107558168596;8116.715914942949;8196.262336389753;8340.207680270365;5822.446867547576;5885.440883116097;6019.4883434908525;6059.272887253382;6109.75560977556;10006.549705263134;10104;10179.84730229062;10205.540750666089;10211.336263142872;9964.10323070927;10186.745331366776;10186.796264074825;10341.4312212829;10393.16854651611;6907.481938854506;7149.106361704787;7380.114386273467;7444.292792184734;7471.514351567131;8409.20112589303;8466.957665211634;8476.194761497647;8480.83038339242;8509.531975741394;7334.266573342623;7491.213422590549;7529.322360987538;7596.354309883722;7629.694812207449;7206.783796486114;7286.562806231643;7326.450516211199;7358.77923662291;7509.324160825552;6833.58998460094;7024.964875175591;7025.016497690225;7033.613151276524;7053.153621565445;4843.951560484491;4945.67853089548;5043.092243396077;5120.18077107661;5121.07820592297;4924.65527413092;5035.043341765053;5083.415407228216;5144.202648589475;5228.921566176435 01:41:35 INFO - PROCESS | 1951 | |4;prototype.html;299.6090102416347;301.70583680911113;307.4911022258163;314.46540880503153;317.7354130560368;5034.647574669774;5173.275741396203;5180.430152683211;5250.661081424236;5255.632105752594;5013;5072.844041282458;5079.822206222783;5123.923975965043;5254.110239368351;3740.6259374062647;4023.47108098922;4026.8187931543002;4035.959640403588;4076.4700588923397;3380.4281009330657;3862.246861861938;3998.820642749694;4076.612721791425;4094.651954583864;3166.3350696353677;3317.6018877734705;3741.457488664142;3875.0893540018074;4066.471358723362;3869.342211823983;4007.29689881802;4018.959810401917;4019.7990100495003;4024.517057953035;3535.3636345457808;3537.4870643756767;3541.866602687141;3551.5383000210068;3576.1059735066233;4955.141821816818;5119.923201151949;5206.099773582653;5211.413955718981;5212.661177023481;4578.519255478193;4613.238815595423;4642.930356044662;4651.906961860777;4654.720716756971;4887.804487820483;5508.421615730371;5551.250581171531;5570.805021824217;5573.721313934287;3871.4386413969814;3892.2215556888455;3916.353801622729;3924.7448915820382;3948.545917219528;5100.872478188075;5455.399906010336;5690.800821971211;5775.88448231033;5795.246617939641;3917.6865850731874;3922.4508568800393;3926.9803650982135;3939.625735555118;4010.63638362958;3520.7325362869346;3998.3402738547816;4002.9799851000557;4020.959790402117;4068.247374235776;4174.666026717855;4530.614897733663;4534.115847409789;4644.9767751161025;4647.930281045787;4510.684252102387;5433.19676226643;5449.964506743706;5463.890722185534;5497.39528651848;1194.8745381734966;1301.4989229146847;1310.2073245686413;1321.881030707241;1322.8081928120562;755.4522970846202;799.0211990311822;811.2617518058538;830.1449507007792;832.9042160151539;3804.600516945736;4205.516365617993;4241.2610829559735;4245.57544245573;4287.756550600353;4229.896999910144;4266.07268604762;4430.446194225722;4628.652851036186;4709.128811169944;4211.915761684749;4265.87202383929;4317.568243175658;4930.580900623487;5096.210087436453;2926.1221633509863;2940.3531223130876;2945.2195168280423;2963.851807409621;3010.819350838957;5475.616706830563;5790.018140657565;5809.385776400637;5812.651240925558;5848.736806843703;5025.447200807908;5764.337101233412;5771.8557036074435;5774.960507108761;5836.445537673914;4738.407699037621;5052.5705315048635;5324.733763311819;5641.041023026013;5896.7936122235515;4928.81708389991;5106.183010718267;5268.2624432578705;5336.73316334174;5389.622726409114;4345.543717909574;4629.999999999865;5501.752421141138;5538.47384498472;5577.832665019893;5167.61242906776;5758.971205143947;5769.480746732815;5834.358220595816;5851.590388672752;3368.983155084209;3619.7647152934974;3784.9810750946067;3788.9012186465616;3818.4081467372603;5609.915851262316;5618.634788738719;5635.830925072254;5644.294463192101;5646.407127251579;5449.618526703092;5497.8075767347955;5512.503874651301;5535.723213839373;5578.553715702653;4657.673962822705;5121.8719532012;5456.918146227889;5548.917960997566;5566.805161819317;4762.380890484214;5413.293353323338;5414.83755487336;5493.533049690821;5505.889882202414;4393.92425302296;5550.334899530206;5605.467480589337;5607.607467477237;5611.438856114356;5179.533841954242;5435.945640543544;5498.223397184396;5499.2576002240785;5516.034693928626 01:41:35 INFO - PROCESS | 1951 | |5;yui.html;660.9768658096965;689.4312192441237;713.1869668577822;749.6364263332287;820.9958950205248;15280.69438611227;16901.83098169018;17547.999999999967;17571.56071098223;17684.911575442104;16037.919810400932;16178.83821161788;16198.757018644701;16203.1088290144;16225.918870405661;696.20632478974;869.7216890594997;895.7268033249857;904.7557159566913;905.868649045888;712.3517598984924;722.1298335505703;855.1064137975831;885.3050355470957;904.5386852705102;940.1773448232797;944.9905500944972;945.1068740040664;948.2745699539857;949.6486300068984;935.0182308575968;936.798588303518;937.8686983822271;938.6104766521885;940.1726480696977;540.2652392745863;558.1794761700294;666.4035688059159;686.5983399711125;698.9580625162506;15320.157391343471;15346.92571519994;15371.61570960735;15389.999650022824;15407.152606606633;13205.141665791693;13649.863501364958;13752.65618359539;13763.862361376458;14018.859811401857;12535.122541422104;12809.10336276461;12924.676883077904;13406.994475414383;13446.932765336209;3677.595464498903;4512.638988880914;4577.496475387705;4729.290606409032;4734.621230301602;110759.44620276846;111313.44343278313;111633.04366076823;112070;113234;10923;10929.508172132273;10937.56249750001;11161.107111431065;11211.439428028565;21867.234646787285;22278;22278.77721222767;22285.442863928532;22286;7769.339606133542;7887.921120788718;7962.004749406325;7979.441439099207;8076.515409075474;4385.368778732799;4463.66522510813;4510.14307281622;4534.750588717652;4630.0739852029055;3600.6939410149907;3662.1393972416463;3736.962630373716;3751.3435148848844;3772.981135094307;564.5286186034707;673.7574473189648;675.4596322941627;678.5724993254219;688.1570076656056;523.3876364653364;614.8256829455718;634.5843472525503;644.5503269853378;664.299164381582;9173.816523669622;9293.814123717488;9327.067293270755;9529.237660987104;9613.375130616627;537.2719964448195;537.5726297593422;587.2483221476517;620.0203678188451;666.3969107957306;3917.8236979335998;4438.979034821945;4558.840440584564;4593.724376537419;4611.423572053493;165613.17193413957;165779;166947;169885;174937.13537266207;117180;140777.29611352083;140964;145548.27225863803;147973.26013369864;122916;123097;124992.50014999649;125682;125973;8174.427790054639;8844.823103537765;8882.993048005696;8889.288856891304;9073.092690730875;67864.6606766963;68081.65959170173;68665.65667171632;70630.8179936605;72327.19182020491;98146.5092674532;103074;103468;108242;109478.35782463111;11483.023942964941;11660.766784664429;11786.292822430682;11975.64073077809;12035;13058.34708264564;13212.009099317398;13514.864851351755;13718.17690938547;13739.587812365646;11769.293842369485;11965.940170299093;12142.271463712206;12252.387380631111;12429.937850310691;11849.201048654764;11973.880261197277;12058.638240852788;12356.38218089074;12460.12779105454;11755.118366122404;11919.702007449883;12070.758584828078;12126.575569855013;12249.816252756395;9725.367851089939;9844.60621575129;10124.797504050026;10125;10257.692269231935;10114.696559103239;10184.626282681116;10226.897731022595;10236.129928956123;10264.127549158406 01:41:35 INFO - PROCESS | 1951 | __end_tp_report 01:41:35 INFO - PROCESS | 1951 | __start_cc_report 01:41:35 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,1148 01:41:35 INFO - PROCESS | 1951 | __end_cc_report 01:41:35 INFO - PROCESS | 1951 | __startTimestamp1475743295189__endTimestamp 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | ------- Summary: start ------- 01:41:35 INFO - PROCESS | 1951 | Number of tests: 6 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | [#0] dojo.html Cycles:165 Average:5640.89 Median:5816.42 stddev:656.21 (11.3%) stddev-sans-first:621.83 01:41:35 INFO - PROCESS | 1951 | Values: 2893.6 3284.4 3314.5 3317.6 3341.9 5521.9 5573.1 5582.6 5647.9 5671.6 5549.0 5615.5 5624.1 5648.7 5651.0 5076.8 5130.7 5167.1 5189.5 5223.7 5163.5 5241.7 5244.8 5246.7 5265.6 5163.7 5175.4 5195.3 5238.7 5248.3 5235.0 5249.2 5262.9 5273.8 5292.9 5042.9 5090.1 5131.4 5183.4 5201.7 5535.1 5597.6 5606.4 5607.5 5654.7 6110.3 6175.9 6184.1 6201.8 6208.5 6097.0 6108.4 6123.9 6178.4 6223.9 5693.3 5720.3 5724.7 5774.4 5778.3 6297.9 6301.4 6301.5 6310.4 6312.9 5816.4 5964.4 6018.6 6032.1 6040.0 5427.2 6120.2 6142.5 6178.1 6185.0 5337.5 6066.2 6079.6 6110.6 6136.9 5829.2 6084.7 6087.8 6102.2 6123.3 4136.3 4754.0 4829.2 4865.4 4923.7 3955.0 4217.3 4380.3 4397.0 4432.3 5610.1 5629.5 5642.8 5659.3 5681.2 6044.1 6100.8 6111.8 6119.3 6141.7 5531.1 5645.5 5666.6 5671.2 5677.5 5337.6 5344.1 5348.1 5362.0 5365.9 6260.7 6266.0 6278.6 6284.8 6286.9 6009.4 6095.0 6119.7 6132.5 6138.1 6288.2 6295.4 6299.5 6300.2 6323.9 6069.5 6101.9 6109.1 6123.9 6132.1 6124.6 6131.5 6169.1 6184.3 6189.0 4976.6 6042.0 6066.8 6083.9 6120.7 5977.5 5998.0 6002.3 6012.2 6021.1 4548.5 5036.5 5761.0 5801.3 5848.9 5945.4 5951.1 5983.8 5985.3 6031.2 5835.6 5856.6 5874.1 5886.0 5922.3 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | [#1] ext.html Cycles:180 Average:68781.59 Median:40606.09 stddev:148139.42 (364.8%) stddev-sans-first:148466.18 01:41:35 INFO - PROCESS | 1951 | Values: 460.2 477.6 484.2 489.5 540.8 30733.0 39490.8 40600.8 40777.8 40799.0 34881.7 42349.2 42358.6 42452.2 42470.2 9586.1 9771.8 9775.1 9777.1 9784.3 9577.8 9688.6 9693.6 9695.2 9698.8 9595.1 9632.0 9642.4 9645.4 9675.3 9636.4 9690.2 9692.5 9693.7 9696.9 9603.9 9673.4 9677.8 9686.0 9698.2 40169.6 40583.0 40611.4 40642.2 40678.4 3238.2 3318.4 3318.4 3324.2 3426.8 30364.0 31511.5 31517.4 31544.4 31896.4 3150.3 3172.8 3268.0 3337.3 3392.4 464719.0 465037.0 465275.7 465517.0 476082.0 60308.7 60798.7 60812.4 61042.0 61315.0 63523.0 63601.0 63697.7 63709.0 63925.4 15480.4 15585.6 15613.8 15617.1 15628.2 12571.3 12639.1 12645.3 12647.7 12668.0 15306.5 15557.0 17265.7 17349.3 17516.5 5508.7 6517.0 6962.9 7040.5 7120.5 881.3 923.8 940.6 965.7 1058.8 27981.3 29063.6 29241.0 29984.3 30101.8 1001.7 1034.4 1039.1 1056.4 1089.9 14310.0 14480.7 14662.6 14677.9 15140.8 637880.0 641655.0 642374.0 642472.0 643329.6 55049.0 57485.0 57849.7 57900.0 58083.7 1066.7 1216.2 1275.8 1347.1 1367.7 28974.4 30834.2 30893.4 31006.2 31739.5 48939.3 49542.0 49632.5 49696.8 49837.0 537627.0 541111.1 541530.3 543303.0 545705.0 32900.0 33884.0 35781.6 36631.8 41330.8 46454.8 46674.3 47209.8 47233.3 47866.5 38626.0 38781.0 38892.6 40253.4 40292.8 38494.0 38540.8 38810.6 39949.6 40010.6 37680.2 38188.8 38332.8 38576.8 38604.0 32895.8 33498.0 34412.3 34509.8 34519.3 20315.5 23782.6 24953.0 25402.0 25616.2 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | [#2] jquery.html Cycles:180 Average:33866.34 Median:4189.95 stddev:100015.12 (2387.0%) stddev-sans-first:100263.96 01:41:35 INFO - PROCESS | 1951 | Values: 315.9 350.3 369.4 371.1 401.7 15088.8 15466.9 15544.8 20906.5 20989.0 19838.6 19885.3 20832.0 21063.3 21344.7 5689.1 5997.0 5999.3 6000.5 6007.3 4014.6 6486.4 6583.1 6621.1 6633.5 5762.2 6559.1 6573.2 6577.3 6598.9 5988.5 6143.7 6144.6 6147.2 6160.7 5698.0 5857.5 5945.7 5953.8 5995.3 9357.9 12172.6 12351.6 12352.6 24311.6 2045.9 2241.0 2309.3 2336.5 2389.7 9742.5 9754.4 9766.7 9814.6 10374.2 1622.9 1868.6 1873.9 1901.7 1941.5 92250.0 101568.5 112288.1 115920.4 118704.4 9775.8 10868.9 11206.9 11467.7 11478.6 14443.0 14449.3 14477.1 14478.9 14497.5 8275.6 8443.5 8460.8 8564.5 8575.1 8299.8 8318.6 8339.7 8501.5 8517.7 4365.3 4454.6 4479.9 4546.4 4547.2 1697.5 1823.9 1881.6 1884.6 1886.1 651.6 780.7 787.7 791.2 972.6 15550.4 21020.5 21081.0 21231.0 21378.7 858.8 910.1 937.4 949.8 976.3 10319.8 11117.1 11222.6 11256.4 11259.4 597089.0 597558.0 598168.0 608271.0 609180.0 79564.4 80230.6 121075.0 131252.7 139942.0 946.7 949.1 951.7 1024.7 1026.2 13858.9 18132.8 19142.1 19798.3 19868.7 54525.7 55193.7 55787.7 56100.7 56259.2 74684.0 75898.6 76744.6 76968.0 78226.4 7659.3 7858.1 7940.1 7963.8 8126.8 6292.9 9217.9 9659.2 11863.3 12558.1 10480.2 10509.1 10793.7 10793.8 10972.8 10470.9 10506.7 10773.7 10779.7 11029.6 10659.6 10886.3 11201.6 11206.0 11317.6 7837.9 7863.4 7890.4 7934.8 8062.4 10306.5 10430.3 10700.3 10766.4 10981.3 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | [#3] mootools.html Cycles:180 Average:5723.31 Median:4831.28 stddev:3245.92 (67.2%) stddev-sans-first:3239.62 01:41:35 INFO - PROCESS | 1951 | Values: 1514.7 1539.9 1740.5 1883.7 1905.9 8539.1 9017.6 9022.0 9023.5 9038.7 7872.2 8138.2 8504.6 8785.1 8796.5 2313.5 2352.1 2352.2 2354.1 2358.6 2316.8 2332.9 2358.0 2360.1 2360.2 2186.8 2333.8 2350.7 2359.3 2362.5 2310.7 2323.2 2335.4 2338.5 2338.7 2236.2 2245.2 2249.3 2252.9 2256.4 8809.3 8869.9 8883.0 8901.6 8907.8 5191.4 5365.2 5366.6 5370.4 5375.8 2967.9 3410.6 3468.8 3618.8 3620.9 1036.8 1073.6 1077.1 1093.1 1101.3 11684.4 11779.4 11808.3 11809.8 11852.3 3713.2 4539.9 4738.1 4745.8 4746.2 9988.8 9990.0 10008.4 10010.1 10010.4 5295.7 5306.1 5307.0 5317.6 5323.3 4337.2 4390.8 4513.8 4818.6 4857.9 3760.9 3811.4 3882.7 3931.4 3932.1 1069.8 1276.2 1280.6 1294.5 1303.3 748.7 861.0 905.9 906.3 940.9 5670.9 5745.5 5871.2 5876.8 6146.6 904.7 917.9 923.7 967.7 1010.7 3684.9 3888.5 3906.8 4009.5 4013.4 9641.8 10897.6 11027.4 11032.8 11036.8 10945.1 10964.7 10987.3 10987.6 11027.7 7672.2 8113.1 8116.7 8196.3 8340.2 5822.4 5885.4 6019.5 6059.3 6109.8 10006.5 10104.0 10179.8 10205.5 10211.3 9964.1 10186.7 10186.8 10341.4 10393.2 6907.5 7149.1 7380.1 7444.3 7471.5 8409.2 8467.0 8476.2 8480.8 8509.5 7334.3 7491.2 7529.3 7596.4 7629.7 7206.8 7286.6 7326.5 7358.8 7509.3 6833.6 7025.0 7025.0 7033.6 7053.2 4844.0 4945.7 5043.1 5120.2 5121.1 4924.7 5035.0 5083.4 5144.2 5228.9 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | [#4] prototype.html Cycles:180 Average:4418.48 Median:4908.31 stddev:1344.64 (27.4%) stddev-sans-first:1312.39 01:41:35 INFO - PROCESS | 1951 | Values: 299.6 301.7 307.5 314.5 317.7 5034.6 5173.3 5180.4 5250.7 5255.6 5013.0 5072.8 5079.8 5123.9 5254.1 3740.6 4023.5 4026.8 4036.0 4076.5 3380.4 3862.2 3998.8 4076.6 4094.7 3166.3 3317.6 3741.5 3875.1 4066.5 3869.3 4007.3 4019.0 4019.8 4024.5 3535.4 3537.5 3541.9 3551.5 3576.1 4955.1 5119.9 5206.1 5211.4 5212.7 4578.5 4613.2 4642.9 4651.9 4654.7 4887.8 5508.4 5551.3 5570.8 5573.7 3871.4 3892.2 3916.4 3924.7 3948.5 5100.9 5455.4 5690.8 5775.9 5795.2 3917.7 3922.5 3927.0 3939.6 4010.6 3520.7 3998.3 4003.0 4021.0 4068.2 4174.7 4530.6 4534.1 4645.0 4647.9 4510.7 5433.2 5450.0 5463.9 5497.4 1194.9 1301.5 1310.2 1321.9 1322.8 755.5 799.0 811.3 830.1 832.9 3804.6 4205.5 4241.3 4245.6 4287.8 4229.9 4266.1 4430.4 4628.7 4709.1 4211.9 4265.9 4317.6 4930.6 5096.2 2926.1 2940.4 2945.2 2963.9 3010.8 5475.6 5790.0 5809.4 5812.7 5848.7 5025.4 5764.3 5771.9 5775.0 5836.4 4738.4 5052.6 5324.7 5641.0 5896.8 4928.8 5106.2 5268.3 5336.7 5389.6 4345.5 4630.0 5501.8 5538.5 5577.8 5167.6 5759.0 5769.5 5834.4 5851.6 3369.0 3619.8 3785.0 3788.9 3818.4 5609.9 5618.6 5635.8 5644.3 5646.4 5449.6 5497.8 5512.5 5535.7 5578.6 4657.7 5121.9 5456.9 5548.9 5566.8 4762.4 5413.3 5414.8 5493.5 5505.9 4393.9 5550.3 5605.5 5607.6 5611.4 5179.5 5435.9 5498.2 5499.3 5516.0 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - PROCESS | 1951 | [#5] yui.html Cycles:180 Average:26600.01 Median:22285.72 stddev:43928.73 (197.1%) stddev-sans-first:44008.79 01:41:35 INFO - PROCESS | 1951 | Values: 661.0 689.4 713.2 749.6 821.0 15280.7 16901.8 17548.0 17571.6 17684.9 16037.9 16178.8 16198.8 16203.1 16225.9 696.2 869.7 895.7 904.8 905.9 712.4 722.1 855.1 885.3 904.5 940.2 945.0 945.1 948.3 949.6 935.0 936.8 937.9 938.6 940.2 540.3 558.2 666.4 686.6 699.0 15320.2 15346.9 15371.6 15390.0 15407.2 13205.1 13649.9 13752.7 13763.9 14018.9 12535.1 12809.1 12924.7 13407.0 13446.9 3677.6 4512.6 4577.5 4729.3 4734.6 110759.4 111313.4 111633.0 112070.0 113234.0 10923.0 10929.5 10937.6 11161.1 11211.4 21867.2 22278.0 22278.8 22285.4 22286.0 7769.3 7887.9 7962.0 7979.4 8076.5 4385.4 4463.7 4510.1 4534.8 4630.1 3600.7 3662.1 3737.0 3751.3 3773.0 564.5 673.8 675.5 678.6 688.2 523.4 614.8 634.6 644.6 664.3 9173.8 9293.8 9327.1 9529.2 9613.4 537.3 537.6 587.2 620.0 666.4 3917.8 4439.0 4558.8 4593.7 4611.4 165613.2 165779.0 166947.0 169885.0 174937.1 117180.0 140777.3 140964.0 145548.3 147973.3 122916.0 123097.0 124992.5 125682.0 125973.0 8174.4 8844.8 8883.0 8889.3 9073.1 67864.7 68081.7 68665.7 70630.8 72327.2 98146.5 103074.0 103468.0 108242.0 109478.4 11483.0 11660.8 11786.3 11975.6 12035.0 13058.3 13212.0 13514.9 13718.2 13739.6 11769.3 11965.9 12142.3 12252.4 12429.9 11849.2 11973.9 12058.6 12356.4 12460.1 11755.1 11919.7 12070.8 12126.6 12249.8 9725.4 9844.6 10124.8 10125.0 10257.7 10114.7 10184.6 10226.9 10236.1 10264.1 01:41:35 INFO - PROCESS | 1951 | -------- Summary: end -------- 01:41:35 INFO - PROCESS | 1951 | 01:41:35 INFO - TEST-INFO | 1951: exit 0 01:41:35 INFO - TEST-OK | dromaeo_css | took 1118226ms 01:41:35 INFO - TEST-START | kraken 01:41:35 INFO - operating with platform_type : mac_ 01:41:35 INFO - Initialising browser for kraken test... 01:41:35 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpQHHchl/profile http://localhost:50927/getInfo.html) 01:41:38 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 01:41:38 INFO - PROCESS | 1955 | colorDepth:24 01:41:38 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 01:41:38 INFO - PROCESS | 1955 | __metrics 01:41:38 INFO - PROCESS | 1955 | 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:41:38 INFO - PROCESS | 1955 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:38 INFO - TEST-INFO | 1955: exit 0 01:41:38 INFO - Browser initialized. 01:41:38 INFO - Running cycle 1/1 for kraken test... 01:41:38 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpQHHchl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:50 INFO - PROCESS | 1956 | RSS: Main: 235749376 01:41:50 INFO - PROCESS | 1956 | 01:41:53 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50927/tests/kraken/driver.html?testName=audio-beat-detection) 01:41:54 INFO - PROCESS | 1956 | RSS: Main: 294645760 01:41:54 INFO - PROCESS | 1956 | 01:41:57 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50927/tests/kraken/driver.html?testName=audio-dft) 01:41:57 INFO - PROCESS | 1956 | RSS: Main: 293670912 01:41:57 INFO - PROCESS | 1956 | 01:42:01 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50927/tests/kraken/driver.html?testName=audio-fft) 01:42:02 INFO - PROCESS | 1956 | RSS: Main: 385105920 01:42:02 INFO - PROCESS | 1956 | 01:42:04 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50927/tests/kraken/driver.html?testName=audio-oscillator) 01:42:04 INFO - PROCESS | 1956 | RSS: Main: 285020160 01:42:04 INFO - PROCESS | 1956 | 01:42:06 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50927/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:42:07 INFO - PROCESS | 1956 | RSS: Main: 290426880 01:42:07 INFO - PROCESS | 1956 | 01:42:10 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50927/tests/kraken/driver.html?testName=imaging-darkroom) 01:42:10 INFO - PROCESS | 1956 | RSS: Main: 327565312 01:42:10 INFO - PROCESS | 1956 | 01:42:13 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50927/tests/kraken/driver.html?testName=imaging-desaturate) 01:42:14 INFO - PROCESS | 1956 | RSS: Main: 328986624 01:42:14 INFO - PROCESS | 1956 | 01:42:17 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50927/tests/kraken/driver.html?testName=json-parse-financial) 01:42:17 INFO - PROCESS | 1956 | RSS: Main: 329269248 01:42:17 INFO - PROCESS | 1956 | 01:42:19 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50927/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:42:20 INFO - PROCESS | 1956 | RSS: Main: 303738880 01:42:20 INFO - PROCESS | 1956 | 01:42:21 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:42:22 INFO - PROCESS | 1956 | RSS: Main: 306278400 01:42:22 INFO - PROCESS | 1956 | 01:42:24 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:42:25 INFO - PROCESS | 1956 | RSS: Main: 311930880 01:42:25 INFO - PROCESS | 1956 | 01:42:28 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:42:29 INFO - PROCESS | 1956 | RSS: Main: 320188416 01:42:29 INFO - PROCESS | 1956 | 01:42:32 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:42:32 INFO - PROCESS | 1956 | RSS: Main: 319930368 01:42:32 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:50927/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50927/tests/kraken/driver.html?testName=ai-astar) 01:42:34 INFO - PROCESS | 1956 | RSS: Main: 363495424 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | __start_tp_report 01:42:34 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 01:42:34 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 01:42:34 INFO - PROCESS | 1956 | |i|pagename|runs| 01:42:34 INFO - PROCESS | 1956 | |0;ai-astar;112;113;114;111;113;114;111;112;112;114 01:42:34 INFO - PROCESS | 1956 | |1;audio-beat-detection;152;115;115;150;114;115;112;114;175;142 01:42:34 INFO - PROCESS | 1956 | |2;audio-dft;182;183;183;207;262;181;184;181;186;184 01:42:34 INFO - PROCESS | 1956 | |3;audio-fft;81;58;60;88;60;58;59;58;81;58 01:42:34 INFO - PROCESS | 1956 | |4;audio-oscillator;89;72;90;71;69;69;94;93;101;125 01:42:34 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;88;85;94;131;88;85;88;85;89;84 01:42:34 INFO - PROCESS | 1956 | |6;imaging-darkroom;92;93;94;94;93;92;93;93;92;92 01:42:34 INFO - PROCESS | 1956 | |7;imaging-desaturate;132;133;96;86;85;86;85;84;85;81 01:42:34 INFO - PROCESS | 1956 | |8;json-parse-financial;58;55;55;56;54;55;54;54;55;55 01:42:34 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;64;69;71;73;74;69;69;74;70;70 01:42:34 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;86;91;87;91;87;90;87;85;96;108 01:42:34 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;145;147;147;148;168;157;159;161;182;147 01:42:34 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;171;171;229;161;212;237;229;171;174;174 01:42:34 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;70;69;68;68;69;69;69;70;74;86 01:42:34 INFO - PROCESS | 1956 | __end_tp_report 01:42:34 INFO - PROCESS | 1956 | __start_cc_report 01:42:34 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1940 01:42:34 INFO - PROCESS | 1956 | __end_cc_report 01:42:34 INFO - PROCESS | 1956 | __startTimestamp1475743354976__endTimestamp 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | ------- Summary: start ------- 01:42:34 INFO - PROCESS | 1956 | Number of tests: 14 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:1.17 (1.0%) stddev-sans-first:1.22 01:42:34 INFO - PROCESS | 1956 | Values: 112.0 113.0 114.0 111.0 113.0 114.0 111.0 112.0 112.0 114.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:128.50 stddev:22.52 (17.5%) stddev-sans-first:22.48 01:42:34 INFO - PROCESS | 1956 | Values: 152.0 115.0 115.0 150.0 114.0 115.0 112.0 114.0 175.0 142.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:193.30 Median:184.00 stddev:25.33 (13.8%) stddev-sans-first:26.54 01:42:34 INFO - PROCESS | 1956 | Values: 182.0 183.0 183.0 207.0 262.0 181.0 184.0 181.0 186.0 184.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.07 (20.1%) stddev-sans-first:11.53 01:42:34 INFO - PROCESS | 1956 | Values: 81.0 58.0 60.0 88.0 60.0 58.0 59.0 58.0 81.0 58.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:80.50 stddev:17.85 (22.2%) stddev-sans-first:18.92 01:42:34 INFO - PROCESS | 1956 | Values: 89.0 72.0 90.0 71.0 69.0 69.0 94.0 93.0 101.0 125.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:88.00 stddev:14.11 (16.0%) stddev-sans-first:14.90 01:42:34 INFO - PROCESS | 1956 | Values: 88.0 85.0 94.0 131.0 88.0 85.0 88.0 85.0 89.0 84.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 01:42:34 INFO - PROCESS | 1956 | Values: 92.0 93.0 94.0 94.0 93.0 92.0 93.0 93.0 92.0 92.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:85.00 stddev:19.98 (23.5%) stddev-sans-first:16.18 01:42:34 INFO - PROCESS | 1956 | Values: 132.0 133.0 96.0 86.0 85.0 86.0 85.0 84.0 85.0 81.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:1.20 (2.2%) stddev-sans-first:0.67 01:42:34 INFO - PROCESS | 1956 | Values: 58.0 55.0 55.0 56.0 54.0 55.0 54.0 54.0 55.0 55.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:2.98 (4.2%) stddev-sans-first:2.12 01:42:34 INFO - PROCESS | 1956 | Values: 64.0 69.0 71.0 73.0 74.0 69.0 69.0 74.0 70.0 70.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:88.50 stddev:6.86 (7.8%) stddev-sans-first:7.05 01:42:34 INFO - PROCESS | 1956 | Values: 86.0 91.0 87.0 91.0 87.0 90.0 87.0 85.0 96.0 108.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:158.00 stddev:11.94 (7.6%) stddev-sans-first:11.97 01:42:34 INFO - PROCESS | 1956 | Values: 145.0 147.0 147.0 148.0 168.0 157.0 159.0 161.0 182.0 147.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.90 Median:193.00 stddev:29.98 (15.5%) stddev-sans-first:30.73 01:42:34 INFO - PROCESS | 1956 | Values: 171.0 171.0 229.0 161.0 212.0 237.0 229.0 171.0 174.0 174.0 01:42:34 INFO - PROCESS | 1956 | 01:42:34 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:69.50 stddev:5.47 (7.9%) stddev-sans-first:5.79 01:42:34 INFO - PROCESS | 1956 | Values: 70.0 69.0 68.0 68.0 69.0 69.0 69.0 70.0 74.0 86.0 01:42:34 INFO - PROCESS | 1956 | -------- Summary: end -------- 01:42:34 INFO - PROCESS | 1956 | 01:42:35 INFO - TEST-INFO | 1956: exit 0 01:42:35 INFO - TEST-OK | kraken | took 60067ms 01:42:35 INFO - SUITE-END | took 1178s 01:42:36 INFO - Completed test suite (00:19:38) 01:42:36 INFO - javascript benchmark 01:42:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2893.5659651052338, 3284.375968565973, 3314.4696848504245, 3317.6018877734678, 3341.949870751939, 5521.889562208753, 5573.08044172711, 5582.553395728342, 5647.887042259153, 5671.631343962645, 5548.9722551387285, 5615.494605485505, 5624.100143976964, 5648.74580643871, 5651.039323315036, 5076.771545280471, 5130.666506677054, 5167.095758242312, 5189.532942035216, 5223.71269580173, 5163.535281824635, 5241.659292145999, 5244.79020839166, 5246.68519888806, 5265.552428043621, 5163.664361816489, 5175.430702622709, 5195.298634684307, 5238.685678859261, 5248.265242866002, 5235.0053489837, 5249.238860365225, 5262.92105618416, 5273.789048438058, 5292.894142117136, 5042.899142017176, 5090.134677104864, 5131.358580177478, 5183.403908550489, 5201.739913004335, 5535.059039963176, 5597.552195824365, 5606.411326810708, 5607.523360514361, 5654.660720356792, 6110.26676798787, 6175.907361389583, 6184.134221209034, 6201.81394558164, 6208.50331973441, 6096.998148966061, 6108.389161083856, 6123.923976812198, 6178.41305076017, 6223.906641400383, 5693.316801983747, 5720.256366672306, 5724.656520608693, 5774.364819869811, 5778.3354914184565, 6297.905531417033, 6301.40136687014, 6301.495880329654, 6310.368963103653, 6312.936870631235, 5816.418358164235, 5964.433378829004, 6018.578699491081, 6032.095185722177, 6040.033594624839, 5427.158790387496, 6120.204373431426, 6142.468080853438, 6178.135061091451, 6184.969075154595, 5337.519623233928, 6066.181065556093, 6079.604825686316, 6110.633361998294, 6136.938630613726, 5829.213056237439, 6084.665343406155, 6087.817365479043, 6102.206713127265, 6123.295820980555, 4136.296829538986, 4754.0491901619125, 4829.227323628203, 4865.440474345495, 4923.704577725348, 3955.0310174007554, 4217.34631132175, 4380.299152135707, 4397.032652816375, 4432.268675668543, 5610.13042978339, 5629.549636029108, 5642.802501912413, 5659.349174844863, 5681.204631351614, 6044.093385992136, 6100.816975490742, 6111.816645500642, 6119.289302195883, 6141.723622437001, 5531.0873705838085, 5645.491905728504, 5666.603337766317, 5671.177679236487, 5677.460641239076, 5337.626366154332, 5344.118220493575, 5348.1175606025345, 5361.973190134025, 5365.892682146336, 6260.718267677966, 6266.0, 6278.591891527036, 6284.780032698834, 6286.9371306288185, 6009.429104235091, 6095.024796032613, 6119.654885716854, 6132.5084813409785, 6138.143176733788, 6288.245410550763, 6295.433410993032, 6299.496040316855, 6300.243970723543, 6323.873522529616, 6069.453749162597, 6101.938980610137, 6109.083637454418, 6123.877522449437, 6132.110843927695, 6124.6325220486915, 6131.499004853578, 6169.074638804215, 6184.288806787277, 6188.9690551546955, 4976.576990955809, 6042.0332746760305, 6066.757329706762, 6083.878322433438, 6120.663363515049, 5977.491913187428, 5998.0403135499855, 6002.279726432856, 6012.248468941382, 6021.066734656135, 4548.47692515365, 5036.471170527042, 5761.049426844524, 5801.274840644919, 5848.853778655568, 5945.40545945402, 5951.1073338999495, 5983.790567330123, 5985.311689155628, 6031.185789918305, 5835.620684655484, 5856.619319744374, 5874.060150375919, 5886.0, 5922.348541660328], "value": 5597.828698206369, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.1739876920927, 477.60120299549874, 484.2155707753439, 489.4689262150566, 540.8431554849094, 30733.0, 39490.81527554175, 40600.79699601505, 40777.79611101944, 40799.0, 34881.65118348816, 42349.15301693964, 42358.576414235846, 42452.15095698084, 42470.15059698804, 9586.08932151448, 9771.804563908718, 9775.120239178472, 9777.12005919467, 9784.315097943147, 9577.76055598612, 9688.612455501807, 9693.612255509772, 9695.175910047654, 9698.806023879519, 9595.13643772064, 9632.036796320346, 9642.37324573904, 9645.421274723503, 9675.322727409084, 9636.42181469114, 9690.176335011533, 9692.46691431971, 9693.66072187477, 9696.903030969741, 9603.903960960371, 9673.37123086997, 9677.758056048584, 9685.951570242103, 9698.224142068617, 40169.59830401688, 40583.0, 40611.390829137585, 40642.18715625701, 40678.38982415266, 3238.1904523869034, 3318.386098571748, 3318.386098571748, 3324.2354258520436, 3426.811525366104, 30364.0, 31511.527327090113, 31517.36965260705, 31544.36911261785, 31896.362072758417, 3150.3384289299274, 3172.7779055466126, 3268.0359294008326, 3337.332533493287, 3392.406328892434, 464719.0, 465037.0, 465275.6736216331, 465516.9999999966, 476082.0, 60308.69845650744, 60798.69600651968, 60812.39187608156, 61042.0, 61315.0, 63523.047154292726, 63601.0, 63697.68151159215, 63709.0, 63925.36074639194, 15480.380784768484, 15585.610359741097, 15613.843861561467, 15617.062976221236, 15628.2185890705, 12571.308578028113, 12639.052071094704, 12645.304508252135, 12647.747045059232, 12668.0, 15306.540803775904, 15557.0, 17265.741013884803, 17349.306027759, 17516.474505764847, 5508.669479831223, 6516.989866570689, 6962.895556566656, 7040.542364746275, 7120.501564890408, 881.3433991676242, 923.7598224461689, 940.581441258647, 965.6958058211641, 1058.7617785998243, 27981.300467488476, 29063.56404653932, 29241.0, 29984.250393739894, 30101.849490752407, 1001.689476262361, 1034.425893629028, 1039.0544604409952, 1056.3767377247461, 1089.8801131875487, 14310.0, 14480.710385792225, 14662.63343416423, 14677.853221467862, 15140.772888406682, 637880.0, 641655.0, 642374.0, 642472.0, 643329.5838020247, 55049.0, 57485.0, 57849.71075144597, 57900.0, 58083.709581451825, 1066.6959916423782, 1216.197309775544, 1275.776739070659, 1347.0570600579633, 1367.7127803161447, 28974.420511590073, 30834.229144271572, 30893.439180946745, 31006.22484437907, 31739.52390714095, 48939.26591101207, 49542.0, 49632.50367496279, 49696.751516242184, 49837.0, 537627.0, 541111.1222214408, 541530.2923485357, 543303.0, 545705.0, 32900.0, 33884.0, 35781.56873725022, 36631.81684091563, 41330.793346033075, 46454.76772616251, 46674.29988550243, 47209.76395118002, 47233.29150062683, 47866.52133478621, 38626.0343491415, 38781.0, 38892.61107389003, 40253.39619905645, 40292.798536008304, 38494.0, 38540.80729596334, 38810.6118938807, 39949.60050399459, 40010.599894001854, 37680.24639507249, 38188.809055954545, 38332.80833595814, 38576.80711596424, 38604.0, 32895.83552082224, 33498.0, 34412.31175376529, 34509.827450862584, 34519.30961380809, 20315.492112697302, 23782.643260351455, 24953.0, 25402.0, 25616.231513054638], "value": 19795.01993301734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.8641784032866, 350.3081414206943, 369.4034134872181, 371.1130398347948, 401.73886973467245, 15088.773668394982, 15466.922665386672, 15544.766828497568, 20906.477338066557, 20989.0, 19838.603227935433, 19885.3040143595, 20831.958402079872, 21063.2627858025, 21344.679829802528, 5689.118186681061, 5996.97001514994, 5999.250093738283, 6000.519958403317, 6007.309159446676, 4014.578469260715, 6486.4162225399705, 6583.07836902834, 6621.073049773012, 6633.535652504326, 5762.193292938971, 6559.1145195398485, 6573.244076931166, 6577.309382514815, 6598.93401065988, 5988.490978266853, 6143.692815359259, 6144.5698801083945, 6147.170132032208, 6160.661163635976, 5697.951595896217, 5857.531397488234, 5945.732442040118, 5953.821385358447, 5995.250593675791, 9357.859632105525, 12172.634820955385, 12351.567695130627, 12352.62942111738, 24311.63532547013, 2045.8977051147533, 2241.002753774571, 2309.307207837642, 2336.4509340305017, 2389.689340385756, 9742.512874356255, 9754.439289713682, 9766.706998789976, 9814.558344874498, 10374.221933354953, 1622.8620567251799, 1868.588910439701, 1873.9250429982785, 1901.7147427885789, 1941.4855063408208, 92250.0, 101568.49215753874, 112288.13983021192, 115920.42039789831, 118704.40647796792, 9775.826900771954, 10868.891311086787, 11206.887931120747, 11467.713307167387, 11478.598249061242, 14443.0, 14449.34977925996, 14477.05899116554, 14478.85521144796, 14497.492587759378, 8275.586220688941, 8443.451175673561, 8460.830783384297, 8564.48613083217, 8575.1424857515, 8299.751007469786, 8318.62566184511, 8339.708110216234, 8501.489910605384, 8517.74446766598, 4365.323374876899, 4454.621357184676, 4479.910401791946, 4546.363509108727, 4547.226971414867, 1697.5162078807728, 1823.8540916726895, 1881.6142690748363, 1884.575970406675, 1886.141805478504, 651.5668631709584, 780.6760194519325, 787.7361084036912, 791.2325044706625, 972.6352617768337, 15550.377984880704, 21020.474488137614, 21081.0, 21231.0, 21378.679319810217, 858.8454078265972, 910.1217325281135, 937.381328323317, 949.8290307744534, 976.2629214942815, 10319.793604128026, 11117.11063114965, 11222.551097956153, 11256.380899050468, 11259.380734059705, 597089.0, 597558.0, 598168.0, 608271.0, 609180.0, 79564.4087118266, 80230.59884700539, 121075.0, 131252.68747312596, 139942.01159768086, 946.654471118037, 949.0604301741364, 951.6954574536082, 1024.7233247023262, 1026.1944373666786, 13858.86141138616, 18132.818671813115, 19142.138603762585, 19798.307059252857, 19868.70196947018, 54525.72737136289, 55193.724031379585, 55787.72106139443, 56100.71949640226, 56259.15611265916, 74684.0, 75898.62050689712, 76744.61627691826, 76968.0, 78226.43547128912, 7659.31066204022, 7858.096318923397, 7940.086890007723, 7963.761087167394, 8126.837463250584, 6292.905606415999, 9217.893852737714, 9659.227261818896, 11863.34751588671, 12558.120931534704, 10480.213983951082, 10509.054185123376, 10793.730156746144, 10793.784124317313, 10972.835407468734, 10470.895291046992, 10506.684799456028, 10773.730656733644, 10779.730506737395, 11029.558817647525, 10659.626913058006, 10886.29239099456, 11201.60794372193, 11205.991460768244, 11317.603883864025, 7837.882431763642, 7863.370930325675, 7890.447668663139, 7934.801629959066, 8062.395320351115, 10306.536205870603, 10430.322029068086, 10700.250982431155, 10766.354018758899, 10981.341119532854], "value": 9169.26787725774, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.659201679622, 1539.9153046582437, 1740.5387572293344, 1883.660941030614, 1905.9332923347677, 8539.10339414361, 9017.63929442823, 9022.0, 9023.54882255887, 9038.72883813485, 7872.212778722125, 8138.186181381859, 8504.574771261443, 8785.121487851227, 8796.472211667304, 2313.5025969416615, 2352.070931981866, 2352.176738141654, 2354.105439932823, 2358.5518751437257, 2316.837821352506, 2332.941676458085, 2357.95284094318, 2360.0913648245464, 2360.1975328388344, 2186.814120799734, 2333.8132949364094, 2350.74141844397, 2359.256834097254, 2362.492064206195, 2310.653401989698, 2323.2217207235594, 2335.369450248432, 2338.543983923136, 2338.7427382987858, 2236.194969810867, 2245.2141750387395, 2249.336445748508, 2252.9436764080865, 2256.435891027243, 8809.295256379473, 8869.866951995726, 8882.955585222033, 8901.643934242622, 8907.777305567348, 5191.402988656278, 5365.222042803811, 5366.570674346082, 5370.43610420904, 5375.784968601251, 2967.896123635684, 3410.6248312685584, 3468.809215493147, 3618.7828730276005, 3620.945685814715, 1036.7978244242377, 1073.613499140309, 1077.1113831089383, 1093.1418836213613, 1101.3226865477754, 11684.415779211005, 11779.352135632536, 11808.291502509877, 11809.752318112502, 11852.288862668267, 3713.2387860488534, 4539.863804085882, 4738.123447162286, 4745.83389581363, 4746.2168742157155, 9988.800223995479, 9990.0, 10008.39949603026, 10010.099091081838, 10010.449425281533, 5295.6557823742105, 5306.09796334624, 5307.0, 5317.574594032468, 5323.30796996388, 4337.240982827992, 4390.780460976938, 4513.81944722214, 4818.614510839125, 4857.854264372075, 3760.924781504409, 3811.3520701480816, 3882.650561449483, 3931.4495970564144, 3932.11527406337, 1069.8178512743461, 1276.24701426159, 1280.6414204022888, 1294.540438144464, 1303.2897071096274, 748.6930358552967, 861.0012385632637, 905.9139381758722, 906.2840356118725, 940.9247260219166, 5670.943290567042, 5745.511631511285, 5871.236739223874, 5876.794312199053, 6146.6004709693725, 904.6517090919912, 917.9082091790767, 923.7228831350568, 967.7000129959735, 1010.7068950004564, 3684.9313699027516, 3888.4556162137324, 3906.8046597670573, 4009.538903026131, 4013.3578627419474, 9641.807163856824, 10897.618583349546, 11027.393493357782, 11032.779344413068, 11036.779264414667, 10945.069669078217, 10964.671059868217, 10987.340759554292, 10987.560497580007, 11027.724306892393, 7672.194419585975, 8113.107558168596, 8116.715914942949, 8196.262336389753, 8340.207680270365, 5822.446867547576, 5885.440883116097, 6019.4883434908525, 6059.272887253382, 6109.75560977556, 10006.549705263134, 10104.0, 10179.84730229062, 10205.540750666089, 10211.336263142872, 9964.10323070927, 10186.745331366776, 10186.796264074825, 10341.4312212829, 10393.16854651611, 6907.481938854506, 7149.106361704787, 7380.114386273467, 7444.292792184734, 7471.514351567131, 8409.20112589303, 8466.957665211634, 8476.194761497647, 8480.83038339242, 8509.531975741394, 7334.266573342623, 7491.213422590549, 7529.322360987538, 7596.354309883722, 7629.694812207449, 7206.783796486114, 7286.562806231643, 7326.450516211199, 7358.77923662291, 7509.324160825552, 6833.58998460094, 7024.964875175591, 7025.016497690225, 7033.613151276524, 7053.153621565445, 4843.951560484491, 4945.67853089548, 5043.092243396077, 5120.18077107661, 5121.07820592297, 4924.65527413092, 5035.043341765053, 5083.415407228216, 5144.202648589475, 5228.921566176435], "value": 4582.288030632932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.6090102416347, 301.70583680911113, 307.4911022258163, 314.46540880503153, 317.7354130560368, 5034.647574669774, 5173.275741396203, 5180.430152683211, 5250.661081424236, 5255.632105752594, 5013.0, 5072.844041282458, 5079.822206222783, 5123.923975965043, 5254.110239368351, 3740.6259374062647, 4023.47108098922, 4026.8187931543002, 4035.959640403588, 4076.4700588923397, 3380.4281009330657, 3862.246861861938, 3998.820642749694, 4076.612721791425, 4094.651954583864, 3166.3350696353677, 3317.6018877734705, 3741.457488664142, 3875.0893540018074, 4066.471358723362, 3869.342211823983, 4007.29689881802, 4018.959810401917, 4019.7990100495003, 4024.517057953035, 3535.3636345457808, 3537.4870643756767, 3541.866602687141, 3551.5383000210068, 3576.1059735066233, 4955.141821816818, 5119.923201151949, 5206.099773582653, 5211.413955718981, 5212.661177023481, 4578.519255478193, 4613.238815595423, 4642.930356044662, 4651.906961860777, 4654.720716756971, 4887.804487820483, 5508.421615730371, 5551.250581171531, 5570.805021824217, 5573.721313934287, 3871.4386413969814, 3892.2215556888455, 3916.353801622729, 3924.7448915820382, 3948.545917219528, 5100.872478188075, 5455.399906010336, 5690.800821971211, 5775.88448231033, 5795.246617939641, 3917.6865850731874, 3922.4508568800393, 3926.9803650982135, 3939.625735555118, 4010.63638362958, 3520.7325362869346, 3998.3402738547816, 4002.9799851000557, 4020.959790402117, 4068.247374235776, 4174.666026717855, 4530.614897733663, 4534.115847409789, 4644.9767751161025, 4647.930281045787, 4510.684252102387, 5433.19676226643, 5449.964506743706, 5463.890722185534, 5497.39528651848, 1194.8745381734966, 1301.4989229146847, 1310.2073245686413, 1321.881030707241, 1322.8081928120562, 755.4522970846202, 799.0211990311822, 811.2617518058538, 830.1449507007792, 832.9042160151539, 3804.600516945736, 4205.516365617993, 4241.2610829559735, 4245.57544245573, 4287.756550600353, 4229.896999910144, 4266.07268604762, 4430.446194225722, 4628.652851036186, 4709.128811169944, 4211.915761684749, 4265.87202383929, 4317.568243175658, 4930.580900623487, 5096.210087436453, 2926.1221633509863, 2940.3531223130876, 2945.2195168280423, 2963.851807409621, 3010.819350838957, 5475.616706830563, 5790.018140657565, 5809.385776400637, 5812.651240925558, 5848.736806843703, 5025.447200807908, 5764.337101233412, 5771.8557036074435, 5774.960507108761, 5836.445537673914, 4738.407699037621, 5052.5705315048635, 5324.733763311819, 5641.041023026013, 5896.7936122235515, 4928.81708389991, 5106.183010718267, 5268.2624432578705, 5336.73316334174, 5389.622726409114, 4345.543717909574, 4629.999999999865, 5501.752421141138, 5538.47384498472, 5577.832665019893, 5167.61242906776, 5758.971205143947, 5769.480746732815, 5834.358220595816, 5851.590388672752, 3368.983155084209, 3619.7647152934974, 3784.9810750946067, 3788.9012186465616, 3818.4081467372603, 5609.915851262316, 5618.634788738719, 5635.830925072254, 5644.294463192101, 5646.407127251579, 5449.618526703092, 5497.8075767347955, 5512.503874651301, 5535.723213839373, 5578.553715702653, 4657.673962822705, 5121.8719532012, 5456.918146227889, 5548.917960997566, 5566.805161819317, 4762.380890484214, 5413.293353323338, 5414.83755487336, 5493.533049690821, 5505.889882202414, 4393.92425302296, 5550.334899530206, 5605.467480589337, 5607.607467477237, 5611.438856114356, 5179.533841954242, 5435.945640543544, 5498.223397184396, 5499.2576002240785, 5516.034693928626], "value": 3987.642372729776, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.9768658096965, 689.4312192441237, 713.1869668577822, 749.6364263332287, 820.9958950205248, 15280.69438611227, 16901.83098169018, 17547.999999999967, 17571.56071098223, 17684.911575442104, 16037.919810400932, 16178.83821161788, 16198.757018644701, 16203.1088290144, 16225.918870405661, 696.20632478974, 869.7216890594997, 895.7268033249857, 904.7557159566913, 905.868649045888, 712.3517598984924, 722.1298335505703, 855.1064137975831, 885.3050355470957, 904.5386852705102, 940.1773448232797, 944.9905500944972, 945.1068740040664, 948.2745699539857, 949.6486300068984, 935.0182308575968, 936.798588303518, 937.8686983822271, 938.6104766521885, 940.1726480696977, 540.2652392745863, 558.1794761700294, 666.4035688059159, 686.5983399711125, 698.9580625162506, 15320.157391343471, 15346.92571519994, 15371.61570960735, 15389.999650022824, 15407.152606606633, 13205.141665791693, 13649.863501364958, 13752.65618359539, 13763.862361376458, 14018.859811401857, 12535.122541422104, 12809.10336276461, 12924.676883077904, 13406.994475414383, 13446.932765336209, 3677.595464498903, 4512.638988880914, 4577.496475387705, 4729.290606409032, 4734.621230301602, 110759.44620276846, 111313.44343278313, 111633.04366076823, 112070.0, 113234.0, 10923.0, 10929.508172132273, 10937.56249750001, 11161.107111431065, 11211.439428028565, 21867.234646787285, 22278.0, 22278.77721222767, 22285.442863928532, 22286.0, 7769.339606133542, 7887.921120788718, 7962.004749406325, 7979.441439099207, 8076.515409075474, 4385.368778732799, 4463.66522510813, 4510.14307281622, 4534.750588717652, 4630.0739852029055, 3600.6939410149907, 3662.1393972416463, 3736.962630373716, 3751.3435148848844, 3772.981135094307, 564.5286186034707, 673.7574473189648, 675.4596322941627, 678.5724993254219, 688.1570076656056, 523.3876364653364, 614.8256829455718, 634.5843472525503, 644.5503269853378, 664.299164381582, 9173.816523669622, 9293.814123717488, 9327.067293270755, 9529.237660987104, 9613.375130616627, 537.2719964448195, 537.5726297593422, 587.2483221476517, 620.0203678188451, 666.3969107957306, 3917.8236979335998, 4438.979034821945, 4558.840440584564, 4593.724376537419, 4611.423572053493, 165613.17193413957, 165779.0, 166947.0, 169885.0, 174937.13537266207, 117180.0, 140777.29611352083, 140964.0, 145548.27225863803, 147973.26013369864, 122916.0, 123097.0, 124992.50014999649, 125682.0, 125973.0, 8174.427790054639, 8844.823103537765, 8882.993048005696, 8889.288856891304, 9073.092690730875, 67864.6606766963, 68081.65959170173, 68665.65667171632, 70630.8179936605, 72327.19182020491, 98146.5092674532, 103074.0, 103468.0, 108242.0, 109478.35782463111, 11483.023942964941, 11660.766784664429, 11786.292822430682, 11975.64073077809, 12035.0, 13058.34708264564, 13212.009099317398, 13514.864851351755, 13718.17690938547, 13739.587812365646, 11769.293842369485, 11965.940170299093, 12142.271463712206, 12252.387380631111, 12429.937850310691, 11849.201048654764, 11973.880261197277, 12058.638240852788, 12356.38218089074, 12460.12779105454, 11755.118366122404, 11919.702007449883, 12070.758584828078, 12126.575569855013, 12249.816252756395, 9725.367851089939, 9844.60621575129, 10124.797504050026, 10125.0, 10257.692269231935, 10114.696559103239, 10184.626282681116, 10226.897731022595, 10236.129928956123, 10264.127549158406], "value": 7872.998496409632, "unit": "score"}], "name": "dromaeo_css", "value": 7257.982960112002, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 111.0, 113.0, 114.0, 111.0, 112.0, 112.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 115.0, 150.0, 114.0, 115.0, 112.0, 114.0, 175.0, 142.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 183.0, 207.0, 262.0, 181.0, 184.0, 181.0, 186.0, 184.0], "value": 193.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 88.0, 60.0, 58.0, 59.0, 58.0, 81.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 90.0, 71.0, 69.0, 69.0, 94.0, 93.0, 101.0, 125.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 94.0, 131.0, 88.0, 85.0, 88.0, 85.0, 89.0, 84.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 94.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 133.0, 96.0, 86.0, 85.0, 86.0, 85.0, 84.0, 85.0, 81.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 55.0, 56.0, 54.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 71.0, 73.0, 74.0, 69.0, 69.0, 74.0, 70.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 87.0, 91.0, 87.0, 90.0, 87.0, 85.0, 96.0, 108.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 147.0, 148.0, 168.0, 157.0, 159.0, 161.0, 182.0, 147.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 229.0, 161.0, 212.0, 237.0, 229.0, 171.0, 174.0, 174.0], "value": 192.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 68.0, 69.0, 69.0, 69.0, 70.0, 74.0, 86.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1505.8999999999999, "alertThreshold": 2.0}]} 01:42:36 INFO - Return code: 0 01:42:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:42:36 INFO - # TBPL SUCCESS # 01:42:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:42:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:42:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:42:36 INFO - 2016-10-06 01:42:36.278 screenresolution[1960:15595] starting screenresolution argv=screenresolution get 01:42:36 INFO - 2016-10-06 01:42:36.283 screenresolution[1960:15595] Display 0: 1600x1200x32@60 01:42:36 INFO - 2016-10-06 01:42:36.289 screenresolution[1961:15598] starting screenresolution argv=screenresolution list 01:42:36 INFO - Available Modes on Display 0 01:42:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:42:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:42:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:42:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:42:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:42:36 INFO - Intel Iris: 01:42:36 INFO - Chipset Model: Intel Iris 01:42:36 INFO - Type: GPU 01:42:36 INFO - Bus: Built-In 01:42:36 INFO - VRAM (Dynamic, Max): 1536 MB 01:42:36 INFO - Vendor: Intel (0x8086) 01:42:36 INFO - Device ID: 0x0a2e 01:42:36 INFO - Revision ID: 0x0009 01:42:36 INFO - Displays: 01:42:36 INFO - Display: 01:42:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:42:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:42:36 INFO - Display Serial Number: 1600x1200 60 01:42:36 INFO - Main Display: Yes 01:42:36 INFO - Mirror: Off 01:42:36 INFO - Online: Yes 01:42:36 INFO - Rotation: Supported 01:42:36 INFO - Return code: 0 01:42:36 INFO - Running post-action listener: _resource_record_post_action 01:42:36 INFO - [mozharness: 2016-10-06 08:42:36.525831Z] Finished run-tests step (success) 01:42:36 INFO - Running post-run listener: _resource_record_post_run 01:42:36 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 790528; Write bytes: 707542016; Read time: 8; Write time: 1984 01:42:36 INFO - TinderboxPrint: CPU usage
53.7% 01:42:36 INFO - TinderboxPrint: I/O read bytes / time
790,528 / 8 01:42:36 INFO - TinderboxPrint: I/O write bytes / time
707,542,016 / 1,984 01:42:36 INFO - TinderboxPrint: CPU idle
2,246.6 (46.3%) 01:42:36 INFO - TinderboxPrint: CPU system
973.6 (20.1%) 01:42:36 INFO - TinderboxPrint: CPU user
1,633.6 (33.7%) 01:42:36 INFO - TinderboxPrint: Swap in / out
408,350,720 / 0 01:42:36 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397871616; Write bytes: 392128512; Read time: 29887; Write time: 822 01:42:36 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 790528; Write bytes: 296498176; Read time: 8; Write time: 1115 01:42:37 INFO - Running post-run listener: _upload_blobber_files 01:42:37 INFO - Blob upload gear active. 01:42:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:42:37 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:42:37 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:42:37 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:42:37 INFO - (blobuploader) - INFO - Open directory for files ... 01:42:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:42:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:38 INFO - (blobuploader) - INFO - Done attempting. 01:42:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:42:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:42:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:39 INFO - (blobuploader) - INFO - Done attempting. 01:42:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:42:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:42:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:41 INFO - (blobuploader) - INFO - Done attempting. 01:42:41 INFO - (blobuploader) - INFO - Iteration through files over. 01:42:41 INFO - Return code: 0 01:42:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:42:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:42:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25ac43736f1a7002b76fa7e3d910963d4ff64071b03a4d2ff5b7cd7c6dc3c1e670f595e23c1ac3187a2008856cd4c2fb68057c4842e3fcc4b09ab19b339bf7ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2866373143f623d413f3beb12d3f23ab45a2b4e57f18468b6f0d28b951ab5f7e7de25973e89cc5de4bbbefb79ba34200f82bdc93a3858358240ee78eab58a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24ddcdb654454d9195465ddb5e529eeb1ed79781b5f9ccf7941d736a807de0e212d386b87ef095add7af8b3e8b043548363af286430abb95c2a876b0104c1981"} 01:42:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:42:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:42:41 INFO - Contents: 01:42:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25ac43736f1a7002b76fa7e3d910963d4ff64071b03a4d2ff5b7cd7c6dc3c1e670f595e23c1ac3187a2008856cd4c2fb68057c4842e3fcc4b09ab19b339bf7ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2866373143f623d413f3beb12d3f23ab45a2b4e57f18468b6f0d28b951ab5f7e7de25973e89cc5de4bbbefb79ba34200f82bdc93a3858358240ee78eab58a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24ddcdb654454d9195465ddb5e529eeb1ed79781b5f9ccf7941d736a807de0e212d386b87ef095add7af8b3e8b043548363af286430abb95c2a876b0104c1981"} 01:42:41 INFO - Running post-run listener: copy_logs_to_upload_dir 01:42:41 INFO - Copying logs to upload dir... 01:42:41 INFO - mkdir: /builds/slave/test/build/upload/logs 01:42:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.271215 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-06 01:42:41.776434) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 01:42:41.779626) ========= 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.lYvrGPaanN/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.I4cH1Byasu/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/25ac43736f1a7002b76fa7e3d910963d4ff64071b03a4d2ff5b7cd7c6dc3c1e670f595e23c1ac3187a2008856cd4c2fb68057c4842e3fcc4b09ab19b339bf7ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2866373143f623d413f3beb12d3f23ab45a2b4e57f18468b6f0d28b951ab5f7e7de25973e89cc5de4bbbefb79ba34200f82bdc93a3858358240ee78eab58a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24ddcdb654454d9195465ddb5e529eeb1ed79781b5f9ccf7941d736a807de0e212d386b87ef095add7af8b3e8b043548363af286430abb95c2a876b0104c1981"} build_url:https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/AsVeyqlsSDyoOxmshGIDJA/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/25ac43736f1a7002b76fa7e3d910963d4ff64071b03a4d2ff5b7cd7c6dc3c1e670f595e23c1ac3187a2008856cd4c2fb68057c4842e3fcc4b09ab19b339bf7ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2866373143f623d413f3beb12d3f23ab45a2b4e57f18468b6f0d28b951ab5f7e7de25973e89cc5de4bbbefb79ba34200f82bdc93a3858358240ee78eab58a045", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24ddcdb654454d9195465ddb5e529eeb1ed79781b5f9ccf7941d736a807de0e212d386b87ef095add7af8b3e8b043548363af286430abb95c2a876b0104c1981"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 01:42:41.815383) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 01:42:41.816021) ========= 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.lYvrGPaanN/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.I4cH1Byasu/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.005276 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 01:42:41.868968) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 01:42:41.869736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 01:42:41.870394) ========= ========= Total master_lag: 0.19 =========