builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0130 starttime: 1479746101.24 results: success (0) buildid: 20161121061609 builduid: 0d832ffe026f486d9c7038b146bfd613 revision: bb1124d7df770a08acb6255289276cf5575db0b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.237717) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.238284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.238946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.265595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.266205) ========= 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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-11-21 08:35:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.2M=0.001s 2016-11-21 08:35:01 (16.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.367871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.658391) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.658727) ========= 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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.026089 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:01.709349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 08:35:01.709981) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bb1124d7df770a08acb6255289276cf5575db0b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bb1124d7df770a08acb6255289276cf5575db0b1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PKH4xmdmub/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.r2nu7ZCD9i/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-11-21 08:35:01,808 truncating revision to first 12 chars 2016-11-21 08:35:01,808 Setting DEBUG logging. 2016-11-21 08:35:01,809 attempt 1/10 2016-11-21 08:35:01,809 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bb1124d7df77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 08:35:03,465 unpacking tar archive at: mozilla-aurora-bb1124d7df77/testing/mozharness/ program finished with exit code 0 elapsedTime=1.930916 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 08:35:03.663807) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:03.664147) ========= script_repo_revision: bb1124d7df770a08acb6255289276cf5575db0b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:03.664564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:03.664833) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-21 08:35:03.683761) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-21 08:35:03.684166) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PKH4xmdmub/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.r2nu7ZCD9i/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:03 INFO - MultiFileLogger online at 20161121 08:35:03 in /builds/slave/test 08:35:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:35:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:35:03 INFO - {'append_to_log': False, 08:35:03 INFO - 'base_work_dir': '/builds/slave/test', 08:35:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:35:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:35:03 INFO - 'branch': 'Mozilla-Aurora', 08:35:03 INFO - 'buildbot_json_path': 'buildprops.json', 08:35:03 INFO - 'config_files': ('talos/mac_config.py',), 08:35:03 INFO - 'default_actions': ('clobber', 08:35:03 INFO - 'read-buildbot-config', 08:35:03 INFO - 'download-and-extract', 08:35:03 INFO - 'populate-webroot', 08:35:03 INFO - 'create-virtualenv', 08:35:03 INFO - 'install', 08:35:03 INFO - 'run-tests'), 08:35:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:35:03 INFO - 'download_minidump_stackwalk': True, 08:35:03 INFO - 'download_symbols': 'ondemand', 08:35:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:35:03 INFO - 'tooltool.py': '/tools/tooltool.py', 08:35:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:35:03 INFO - '/tools/misc-python/virtualenv.py')}, 08:35:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:35:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:35:03 INFO - 'installer_path': 'installer.exe', 08:35:03 INFO - 'log_level': 'info', 08:35:03 INFO - 'log_name': 'talos', 08:35:03 INFO - 'log_to_console': True, 08:35:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:35:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:35:03 INFO - 'opt_config_files': (), 08:35:03 INFO - 'pip_index': False, 08:35:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:35:03 INFO - 'cmd': ('bash', 08:35:03 INFO - '-c', 08:35:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:35:03 INFO - 'enabled': True, 08:35:03 INFO - 'halt_on_failure': False, 08:35:03 INFO - 'name': 'check_screen_resolution'},), 08:35:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:35:03 INFO - 'cmd': ('bash', 08:35:03 INFO - '-c', 08:35:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:35:03 INFO - 'enabled': True, 08:35:03 INFO - 'halt_on_failure': False, 08:35:03 INFO - 'name': 'check_screen_resolution'},), 08:35:03 INFO - 'run_cmd_checks_enabled': True, 08:35:03 INFO - 'sps_profile': False, 08:35:03 INFO - 'sps_profile_interval': 0, 08:35:03 INFO - 'suite': 'dromaeojs-e10s', 08:35:03 INFO - 'system_bits': '32', 08:35:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:35:03 INFO - 'title': 't-yosemite-r7-0130', 08:35:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:35:03 INFO - 'use_talos_json': True, 08:35:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:35:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:35:03 INFO - 'work_dir': 'build'} 08:35:03 INFO - [mozharness: 2016-11-21 16:35:03.891472Z] Running clobber step. 08:35:03 INFO - Running pre-action listener: _resource_record_pre_action 08:35:03 INFO - Running main action method: clobber 08:35:03 INFO - rmtree: /builds/slave/test/build 08:35:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:35:06 INFO - Running post-action listener: _resource_record_post_action 08:35:06 INFO - [mozharness: 2016-11-21 16:35:06.141429Z] Finished clobber step (success) 08:35:06 INFO - [mozharness: 2016-11-21 16:35:06.141646Z] Running read-buildbot-config step. 08:35:06 INFO - Running pre-action listener: _resource_record_pre_action 08:35:06 INFO - Running main action method: read_buildbot_config 08:35:06 INFO - Using buildbot properties: 08:35:06 INFO - { 08:35:06 INFO - "project": "", 08:35:06 INFO - "product": "firefox", 08:35:06 INFO - "script_repo_revision": "production", 08:35:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:35:06 INFO - "repository": "", 08:35:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:35:06 INFO - "buildid": "20161121061609", 08:35:06 INFO - "pgo_build": "False", 08:35:06 INFO - "basedir": "/builds/slave/test", 08:35:06 INFO - "buildnumber": 45, 08:35:06 INFO - "slavename": "t-yosemite-r7-0130", 08:35:06 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:35:06 INFO - "platform": "yosemite_r7", 08:35:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:35:06 INFO - "branch": "mozilla-aurora", 08:35:06 INFO - "repo_path": "releases/mozilla-aurora", 08:35:06 INFO - "revision": "bb1124d7df770a08acb6255289276cf5575db0b1", 08:35:06 INFO - "stage_platform": "macosx64", 08:35:06 INFO - "builduid": "0d832ffe026f486d9c7038b146bfd613", 08:35:06 INFO - "slavebuilddir": "test" 08:35:06 INFO - } 08:35:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:35:06 INFO - Running post-action listener: _resource_record_post_action 08:35:06 INFO - [mozharness: 2016-11-21 16:35:06.145069Z] Finished read-buildbot-config step (success) 08:35:06 INFO - [mozharness: 2016-11-21 16:35:06.145201Z] Running download-and-extract step. 08:35:06 INFO - Running pre-action listener: _resource_record_pre_action 08:35:06 INFO - Running main action method: download_and_extract 08:35:06 INFO - mkdir: /builds/slave/test/build/tests 08:35: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')]} 08:35:06 INFO - trying https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:35:06 INFO - Downloading https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:35:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:35:06 INFO - Downloaded 1199 bytes. 08:35:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:35:06 INFO - Using the following test package requirements: 08:35:06 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:35:06 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:35:06 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:35:06 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'jsshell-mac.zip'], 08:35:06 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:35:06 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:35:06 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:35:06 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:35:06 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:35:06 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:35:06 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:35:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:35:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:35:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:35:06 INFO - Fetch https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:35:07 INFO - Http code: 200 08:35:07 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 08:35:07 INFO - x-amz-cf-id: bCK_hztpDKRwc0ijkohy50R2pKRYuHqwv68rwpsQGSmuHHKQwOBVDA== 08:35:07 INFO - x-amz-version-id: h.ZCeyERSfC_mO3Vt6eHvnYX7qACzwhb 08:35:07 INFO - Expected file size: 16274862 08:35:07 INFO - Obtained file size: 16274862 08:35:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:35:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:35:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:35:14 INFO - Fetch https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:35:15 INFO - Http code: 200 08:35:15 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:35:15 INFO - x-amz-cf-id: RCqPX_XTjkCvkO4c3kjC0w29PWNvtQDrt1cAEIqHubSOd_xMCfJFgg== 08:35:15 INFO - x-amz-version-id: h.ZCeyERSfC_mO3Vt6eHvnYX7qACzwhb 08:35:15 INFO - Expected file size: 16274862 08:35:15 INFO - Obtained file size: 16274862 08:35:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:35:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:35:21 INFO - Fetch https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:35:23 INFO - Http code: 200 08:35:23 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 08:35:23 INFO - x-amz-cf-id: ihtqy0-q4Yd3ENQ1ALrxInSbHl7GKzU3kCvBS6tHWFswhpWXllJSfg== 08:35:23 INFO - x-amz-version-id: XV4CvW0qRAOjwPz131QlNc7svwS6OFgw 08:35:23 INFO - Expected file size: 14409038 08:35:23 INFO - Obtained file size: 14409038 08:35:23 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')]} 08:35:23 INFO - trying https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:35:23 INFO - Downloading https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:35:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:35:26 INFO - Downloaded 114499984 bytes. 08:35:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:35:26 INFO - mkdir: /builds/slave/test/properties 08:35:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:35:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:35:26 INFO - Contents: 08:35:26 INFO - build_url:https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:35:28 INFO - Running post-action listener: _resource_record_post_action 08:35:28 INFO - Running post-action listener: set_extra_try_arguments 08:35:28 INFO - [mozharness: 2016-11-21 16:35:28.006785Z] Finished download-and-extract step (success) 08:35:28 INFO - [mozharness: 2016-11-21 16:35:28.006933Z] Running populate-webroot step. 08:35:28 INFO - Running pre-action listener: _resource_record_pre_action 08:35:28 INFO - Running main action method: populate_webroot 08:35:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:35:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:35:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:35:28 INFO - '--tppagecycles', 08:35:28 INFO - '7'], 08:35:28 INFO - 'tests': ['tsvgm']}}, 08:35:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:35:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:35:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:35:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:35:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['damp', 'tps']}, 08:35:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'tests': ['damp', 'tps']}, 08:35:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['dromaeo_dom']}, 08:35:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:35:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['basic_compositor_video']}, 08:35:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:35:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['a11yr', 08:35:28 INFO - 'ts_paint', 08:35:28 INFO - 'tpaint', 08:35:28 INFO - 'sessionrestore', 08:35:28 INFO - 'sessionrestore_no_auto_restore', 08:35:28 INFO - 'tabpaint']}, 08:35:28 INFO - 'other-e10s': {'tests': ['a11yr', 08:35:28 INFO - 'ts_paint', 08:35:28 INFO - 'tpaint', 08:35:28 INFO - 'sessionrestore', 08:35:28 INFO - 'sessionrestore_no_auto_restore', 08:35:28 INFO - 'tabpaint']}, 08:35:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:35:28 INFO - 'ts_paint', 08:35:28 INFO - 'tpaint', 08:35:28 INFO - 'sessionrestore', 08:35:28 INFO - 'sessionrestore_no_auto_restore', 08:35:28 INFO - 'tabpaint']}, 08:35:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:35:28 INFO - 'ts_paint', 08:35:28 INFO - 'tpaint', 08:35:28 INFO - 'sessionrestore', 08:35:28 INFO - 'sessionrestore_no_auto_restore', 08:35:28 INFO - 'tabpaint']}, 08:35:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['a11yr', 08:35:28 INFO - 'ts_paint', 08:35:28 INFO - 'tpaint', 08:35:28 INFO - 'sessionrestore', 08:35:28 INFO - 'sessionrestore_no_auto_restore', 08:35:28 INFO - 'tabpaint']}, 08:35:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['a11yr', 08:35:28 INFO - 'ts_paint', 08:35:28 INFO - 'tpaint', 08:35:28 INFO - 'sessionrestore', 08:35:28 INFO - 'sessionrestore_no_auto_restore', 08:35:28 INFO - 'tabpaint']}, 08:35:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['tsvgx', 08:35:28 INFO - 'tsvgr_opacity', 08:35:28 INFO - 'tart', 08:35:28 INFO - 'tscrollx', 08:35:28 INFO - 'cart']}, 08:35:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:35:28 INFO - 'tsvgr_opacity', 08:35:28 INFO - 'tart', 08:35:28 INFO - 'tscrollx', 08:35:28 INFO - 'cart']}, 08:35:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'talos_options': ['--disable-e10s'], 08:35:28 INFO - 'tests': ['tp5o']}, 08:35:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'tests': ['tp5o']}, 08:35:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'talos_options': ['--disable-e10s', 08:35:28 INFO - '--xperf_path', 08:35:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:28 INFO - 'tests': ['tp5n']}, 08:35:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:28 INFO - 'talos_options': ['--xperf_path', 08:35:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:28 INFO - 'tests': ['tp5n']}}, 08:35:28 INFO - 'talos.zip': {'path': '', 08:35:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:35:28 INFO - Running post-action listener: _resource_record_post_action 08:35:28 INFO - [mozharness: 2016-11-21 16:35:28.025429Z] Finished populate-webroot step (success) 08:35:28 INFO - [mozharness: 2016-11-21 16:35:28.025532Z] Running create-virtualenv step. 08:35:28 INFO - Running pre-action listener: _resource_record_pre_action 08:35:28 INFO - Running main action method: create_virtualenv 08:35:28 INFO - Creating virtualenv /builds/slave/test/build/venv 08:35:28 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 08:35:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:35:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:35:28 INFO - Using real prefix '/tools/python27' 08:35:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:35:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:35:31 INFO - Installing pip.................done. 08:35:31 INFO - Return code: 0 08:35:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:35:32 INFO - Reading from file tmpfile_stdout 08:35:32 INFO - Output received: 08:35:32 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) 08:35:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:32 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:32 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 08:35:32 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 08:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:32 INFO - 'HOME': '/Users/cltbld', 08:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:32 INFO - 'LOGNAME': 'cltbld', 08:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:32 INFO - 'MOZ_NO_REMOTE': '1', 08:35:32 INFO - 'NO_EM_RESTART': '1', 08:35:32 INFO - 'PAGER': '/bin/cat', 08:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:32 INFO - 'PWD': '/builds/slave/test', 08:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:32 INFO - 'SHELL': '/bin/bash', 08:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:32 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:32 INFO - 'USER': 'cltbld', 08:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:32 INFO - 'XPC_FLAGS': '0x0', 08:35:32 INFO - 'XPC_SERVICE_NAME': '0', 08:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:32 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 08:35:32 INFO - Cleaning up... 08:35:32 INFO - Return code: 0 08:35:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:32 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:32 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 08:35:32 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 08:35:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:32 INFO - 'HOME': '/Users/cltbld', 08:35:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:32 INFO - 'LOGNAME': 'cltbld', 08:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:32 INFO - 'MOZ_NO_REMOTE': '1', 08:35:32 INFO - 'NO_EM_RESTART': '1', 08:35:32 INFO - 'PAGER': '/bin/cat', 08:35:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:32 INFO - 'PWD': '/builds/slave/test', 08:35:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:32 INFO - 'SHELL': '/bin/bash', 08:35:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:32 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:32 INFO - 'USER': 'cltbld', 08:35:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:32 INFO - 'XPC_FLAGS': '0x0', 08:35:32 INFO - 'XPC_SERVICE_NAME': '0', 08:35:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:32 INFO - Downloading/unpacking psutil>=3.1.1 08:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:35:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:36 INFO - Installing collected packages: psutil 08:35:36 INFO - Running setup.py install for psutil 08:35:36 INFO - building 'psutil._psutil_osx' extension 08:35:36 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 08:35:36 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 08:35:36 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 08:35:36 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 08:35:36 INFO - building 'psutil._psutil_posix' extension 08:35:36 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 08:35:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:35:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:35:37 INFO - ^ 08:35:37 INFO - 1 warning generated. 08:35:37 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 08:35:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:37 INFO - Successfully installed psutil 08:35:37 INFO - Cleaning up... 08:35:37 INFO - Return code: 0 08:35:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:35:37 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')]} 08:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:37 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')]} 08:35:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:37 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:37 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 08:35:37 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 08:35:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:37 INFO - 'HOME': '/Users/cltbld', 08:35:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:37 INFO - 'LOGNAME': 'cltbld', 08:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:37 INFO - 'MOZ_NO_REMOTE': '1', 08:35:37 INFO - 'NO_EM_RESTART': '1', 08:35:37 INFO - 'PAGER': '/bin/cat', 08:35:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:37 INFO - 'PWD': '/builds/slave/test', 08:35:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:37 INFO - 'SHELL': '/bin/bash', 08:35:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:37 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:37 INFO - 'USER': 'cltbld', 08:35:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:37 INFO - 'XPC_FLAGS': '0x0', 08:35:37 INFO - 'XPC_SERVICE_NAME': '0', 08:35:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:35:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:35:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:35:40 INFO - Installing collected packages: mozsystemmonitor 08:35:40 INFO - Running setup.py install for mozsystemmonitor 08:35:40 INFO - Successfully installed mozsystemmonitor 08:35:40 INFO - Cleaning up... 08:35:40 INFO - Return code: 0 08:35:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:35:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:35:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:35:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:40 INFO - 'HOME': '/Users/cltbld', 08:35:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:40 INFO - 'LOGNAME': 'cltbld', 08:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:40 INFO - 'MOZ_NO_REMOTE': '1', 08:35:40 INFO - 'NO_EM_RESTART': '1', 08:35:40 INFO - 'PAGER': '/bin/cat', 08:35:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:40 INFO - 'PWD': '/builds/slave/test', 08:35:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:40 INFO - 'SHELL': '/bin/bash', 08:35:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:40 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:40 INFO - 'USER': 'cltbld', 08:35:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:40 INFO - 'XPC_FLAGS': '0x0', 08:35:40 INFO - 'XPC_SERVICE_NAME': '0', 08:35:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:35:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:35:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:35:43 INFO - warning: no files found matching '*.txt' 08:35:43 INFO - no previously-included directories found matching 'build' 08:35:43 INFO - no previously-included directories found matching 'dist' 08:35:43 INFO - no previously-included directories found matching '.git*' 08:35:43 INFO - Installing collected packages: jsonschema, functools32 08:35:43 INFO - Running setup.py install for functools32 08:35:43 INFO - warning: no files found matching '*.txt' 08:35:43 INFO - no previously-included directories found matching 'build' 08:35:43 INFO - no previously-included directories found matching 'dist' 08:35:43 INFO - no previously-included directories found matching '.git*' 08:35:43 INFO - Successfully installed jsonschema functools32 08:35:43 INFO - Cleaning up... 08:35:43 INFO - Return code: 0 08:35:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:43 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:43 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 08:35:43 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 08:35:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:43 INFO - 'HOME': '/Users/cltbld', 08:35:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:43 INFO - 'LOGNAME': 'cltbld', 08:35:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:43 INFO - 'MOZ_NO_REMOTE': '1', 08:35:43 INFO - 'NO_EM_RESTART': '1', 08:35:43 INFO - 'PAGER': '/bin/cat', 08:35:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:43 INFO - 'PWD': '/builds/slave/test', 08:35:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:43 INFO - 'SHELL': '/bin/bash', 08:35:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:43 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:43 INFO - 'USER': 'cltbld', 08:35:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:43 INFO - 'XPC_FLAGS': '0x0', 08:35:43 INFO - 'XPC_SERVICE_NAME': '0', 08:35:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:35:44 INFO - Cleaning up... 08:35:44 INFO - Return code: 0 08:35:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:35:44 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')]} 08:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:44 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')]} 08:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:44 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:44 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 08:35:44 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 08:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:44 INFO - 'HOME': '/Users/cltbld', 08:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:44 INFO - 'LOGNAME': 'cltbld', 08:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:44 INFO - 'MOZ_NO_REMOTE': '1', 08:35:44 INFO - 'NO_EM_RESTART': '1', 08:35:44 INFO - 'PAGER': '/bin/cat', 08:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:44 INFO - 'PWD': '/builds/slave/test', 08:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:44 INFO - 'SHELL': '/bin/bash', 08:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:44 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:44 INFO - 'USER': 'cltbld', 08:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:44 INFO - 'XPC_FLAGS': '0x0', 08:35:44 INFO - 'XPC_SERVICE_NAME': '0', 08:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:47 INFO - Downloading blobuploader-1.2.4.tar.gz 08:35:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:35:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:35:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 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 08:35:47 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 08:35:48 INFO - Downloading docopt-0.6.1.tar.gz 08:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:35:48 INFO - Installing collected packages: blobuploader, requests, docopt 08:35:48 INFO - Running setup.py install for blobuploader 08:35:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:35:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:35:48 INFO - Running setup.py install for requests 08:35:49 INFO - Running setup.py install for docopt 08:35:49 INFO - Successfully installed blobuploader requests docopt 08:35:49 INFO - Cleaning up... 08:35:49 INFO - Return code: 0 08:35:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:49 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')]} 08:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:49 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')]} 08:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:49 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:49 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 08:35:49 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 08:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:49 INFO - 'HOME': '/Users/cltbld', 08:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:49 INFO - 'LOGNAME': 'cltbld', 08:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:49 INFO - 'MOZ_NO_REMOTE': '1', 08:35:49 INFO - 'NO_EM_RESTART': '1', 08:35:49 INFO - 'PAGER': '/bin/cat', 08:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:49 INFO - 'PWD': '/builds/slave/test', 08:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:49 INFO - 'SHELL': '/bin/bash', 08:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:49 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:49 INFO - 'USER': 'cltbld', 08:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:49 INFO - 'XPC_FLAGS': '0x0', 08:35:49 INFO - 'XPC_SERVICE_NAME': '0', 08:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:49 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-htZGOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:49 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-wMvQyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:49 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-hDkVP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:49 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Zth5yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:49 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-xi757b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-FkxrKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-E8d5V8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-qslT3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Iycjww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Qgm4Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-PiPueD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-U47Ifj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-uW9VJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-cD5zKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:50 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-XZ6p5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:51 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-deUn3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:51 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-3j2fgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:35:51 INFO - Running setup.py install for manifestparser 08:35:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:35:51 INFO - Running setup.py install for mozcrash 08:35:51 INFO - Running setup.py install for mozdebug 08:35:51 INFO - Running setup.py install for mozdevice 08:35:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:35:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:35:51 INFO - Running setup.py install for mozfile 08:35:51 INFO - Running setup.py install for mozhttpd 08:35:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Running setup.py install for mozinfo 08:35:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Running setup.py install for mozInstall 08:35:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Running setup.py install for mozleak 08:35:52 INFO - Running setup.py install for mozlog 08:35:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Running setup.py install for moznetwork 08:35:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:35:52 INFO - Running setup.py install for mozprocess 08:35:53 INFO - Running setup.py install for mozprofile 08:35:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:35:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:35:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:35:53 INFO - Running setup.py install for mozrunner 08:35:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:35:53 INFO - Running setup.py install for mozscreenshot 08:35:53 INFO - Running setup.py install for moztest 08:35:53 INFO - Running setup.py install for mozversion 08:35:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:35:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:35:53 INFO - Cleaning up... 08:35:53 INFO - Return code: 0 08:35:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:53 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')]} 08:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:53 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')]} 08:35:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:53 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:53 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 08:35:53 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 08:35:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:53 INFO - 'HOME': '/Users/cltbld', 08:35:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:53 INFO - 'LOGNAME': 'cltbld', 08:35:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:53 INFO - 'MOZ_NO_REMOTE': '1', 08:35:53 INFO - 'NO_EM_RESTART': '1', 08:35:53 INFO - 'PAGER': '/bin/cat', 08:35:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:53 INFO - 'PWD': '/builds/slave/test', 08:35:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:53 INFO - 'SHELL': '/bin/bash', 08:35:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:53 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:53 INFO - 'USER': 'cltbld', 08:35:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:53 INFO - 'XPC_FLAGS': '0x0', 08:35:53 INFO - 'XPC_SERVICE_NAME': '0', 08:35:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Spewu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-j5WRDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-cCc3_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-vTvYKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-TkQrud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-hgMX7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-w_T4lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-OraWkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:54 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)) 08:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:54 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Nne2fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-8Di_OC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Dedkpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-PFkxlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-_8VZyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-HDGF3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-fUDNu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-KCykjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:55 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)) 08:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:55 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-xs2idQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:55 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)) 08:35:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:55 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)) 08:35:55 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)) 08:35:55 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)) 08:35:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:35: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 08:35: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 08:35: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 08:35: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 08:35: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 08:35: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 08:35:58 INFO - Downloading blessings-1.6.tar.gz 08:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:35:58 INFO - Installing collected packages: blessings 08:35:58 INFO - Running setup.py install for blessings 08:35:58 INFO - Successfully installed blessings 08:35:58 INFO - Cleaning up... 08:35:58 INFO - Return code: 0 08:35:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:35:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:59 INFO - Reading from file tmpfile_stdout 08:35:59 INFO - Current package versions: 08:35:59 INFO - blessings == 1.6 08:35:59 INFO - blobuploader == 1.2.4 08:35:59 INFO - docopt == 0.6.1 08:35:59 INFO - functools32 == 3.2.3-2 08:35:59 INFO - jsonschema == 2.5.1 08:35:59 INFO - manifestparser == 1.1 08:35:59 INFO - mozInstall == 1.12 08:35:59 INFO - mozcrash == 1.0 08:35:59 INFO - mozdebug == 0.1 08:35:59 INFO - mozdevice == 0.48 08:35:59 INFO - mozfile == 1.2 08:35:59 INFO - mozhttpd == 0.7 08:35:59 INFO - mozinfo == 0.9 08:35:59 INFO - mozleak == 0.1 08:35:59 INFO - mozlog == 3.4 08:35:59 INFO - moznetwork == 0.27 08:35:59 INFO - mozprocess == 0.23 08:35:59 INFO - mozprofile == 0.28 08:35:59 INFO - mozrunner == 6.13 08:35:59 INFO - mozscreenshot == 0.1 08:35:59 INFO - mozsystemmonitor == 0.3 08:35:59 INFO - moztest == 0.7 08:35:59 INFO - mozversion == 1.4 08:35:59 INFO - psutil == 3.1.1 08:35:59 INFO - requests == 1.2.3 08:35:59 INFO - wsgiref == 0.1.2 08:35:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35: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')]} 08:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35: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')]} 08:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:59 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:35:59 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 08:35:59 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 08:35:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:35:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:59 INFO - 'HOME': '/Users/cltbld', 08:35:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:59 INFO - 'LOGNAME': 'cltbld', 08:35:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:59 INFO - 'MOZ_NO_REMOTE': '1', 08:35:59 INFO - 'NO_EM_RESTART': '1', 08:35:59 INFO - 'PAGER': '/bin/cat', 08:35:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:59 INFO - 'PWD': '/builds/slave/test', 08:35:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:59 INFO - 'SHELL': '/bin/bash', 08:35:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:35:59 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:35:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:59 INFO - 'USER': 'cltbld', 08:35:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:59 INFO - 'XPC_FLAGS': '0x0', 08:35:59 INFO - 'XPC_SERVICE_NAME': '0', 08:35:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 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)) 08:36:00 INFO - Cleaning up... 08:36:00 INFO - Return code: 0 08:36:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:36:00 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')]} 08:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:00 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')]} 08:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:00 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 0x1076dc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107652570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfd3c867e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, '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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:36:00 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 08:36:00 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 08:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:00 INFO - 'HOME': '/Users/cltbld', 08:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:00 INFO - 'LOGNAME': 'cltbld', 08:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:00 INFO - 'MOZ_NO_REMOTE': '1', 08:36:00 INFO - 'NO_EM_RESTART': '1', 08:36:00 INFO - 'PAGER': '/bin/cat', 08:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:00 INFO - 'PWD': '/builds/slave/test', 08:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:00 INFO - 'SHELL': '/bin/bash', 08:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:36:00 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:00 INFO - 'USER': 'cltbld', 08:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:00 INFO - 'XPC_FLAGS': '0x0', 08:36:00 INFO - 'XPC_SERVICE_NAME': '0', 08:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:36:00 INFO - Cleaning up... 08:36:00 INFO - Return code: 0 08:36:00 INFO - Running post-action listener: _resource_record_post_action 08:36:00 INFO - Running post-action listener: _start_resource_monitoring 08:36:00 INFO - Starting resource monitoring. 08:36:00 INFO - [mozharness: 2016-11-21 16:36:00.800766Z] Finished create-virtualenv step (success) 08:36:00 INFO - [mozharness: 2016-11-21 16:36:00.801790Z] Running install step. 08:36:00 INFO - Running pre-action listener: _resource_record_pre_action 08:36:00 INFO - Running main action method: install 08:36:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:36:01 INFO - Reading from file tmpfile_stdout 08:36:01 INFO - Detecting whether we're running mozinstall >=1.0... 08:36:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:36:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:36:01 INFO - Reading from file tmpfile_stdout 08:36:01 INFO - Output received: 08:36:01 INFO - Usage: mozinstall [options] installer 08:36:01 INFO - Options: 08:36:01 INFO - -h, --help show this help message and exit 08:36:01 INFO - -d DEST, --destination=DEST 08:36:01 INFO - Directory to install application into. [default: 08:36:01 INFO - "/builds/slave/test"] 08:36:01 INFO - --app=APP Application being installed. [default: firefox] 08:36:01 INFO - mkdir: /builds/slave/test/build/application 08:36:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:36:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:36:33 INFO - Reading from file tmpfile_stdout 08:36:33 INFO - Output received: 08:36:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:36:33 INFO - Running post-action listener: _resource_record_post_action 08:36:33 INFO - [mozharness: 2016-11-21 16:36:33.498472Z] Finished install step (success) 08:36:33 INFO - [mozharness: 2016-11-21 16:36:33.498618Z] Running run-tests step. 08:36:33 INFO - Running pre-action listener: _resource_record_pre_action 08:36:33 INFO - Running main action method: run_tests 08:36:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:33 INFO - 2016-11-21 08:36:33.513 screenresolution[1627:7430] starting screenresolution argv=screenresolution get 08:36:33 INFO - 2016-11-21 08:36:33.516 screenresolution[1627:7430] Display 0: 1600x1200x32@60 08:36:33 INFO - 2016-11-21 08:36:33.521 screenresolution[1628:7432] starting screenresolution argv=screenresolution list 08:36:33 INFO - Available Modes on Display 0 08:36:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:33 INFO - Intel Iris: 08:36:33 INFO - Chipset Model: Intel Iris 08:36:33 INFO - Type: GPU 08:36:33 INFO - Bus: Built-In 08:36:33 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:33 INFO - Vendor: Intel (0x8086) 08:36:33 INFO - Device ID: 0x0a2e 08:36:33 INFO - Revision ID: 0x0009 08:36:33 INFO - Displays: 08:36:33 INFO - Display: 08:36:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:33 INFO - Display Serial Number: 1600x1200 60 08:36:33 INFO - Main Display: Yes 08:36:33 INFO - Mirror: Off 08:36:33 INFO - Online: Yes 08:36:33 INFO - Rotation: Supported 08:36:33 INFO - Return code: 0 08:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:36:33 INFO - Python 2.7.3 08:36:33 INFO - Return code: 0 08:36:33 INFO - grabbing minidump binary from tooltool 08:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:36:33 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 0x1074f0d30>, '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 0x107356db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:36:33 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 08:36:33 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 08:36:33 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 08:36:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:36:33 INFO - Return code: 0 08:36:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:36:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:36:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:36:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:36:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0130 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PKH4xmdmub/Render', 08:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:33 INFO - 'HOME': '/Users/cltbld', 08:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:33 INFO - 'LOGNAME': 'cltbld', 08:36:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:36:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:33 INFO - 'MOZ_NO_REMOTE': '1', 08:36:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:36:33 INFO - 'NO_EM_RESTART': '1', 08:36:33 INFO - 'PAGER': '/bin/cat', 08:36:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:33 INFO - 'PWD': '/builds/slave/test', 08:36:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:33 INFO - 'SHELL': '/bin/bash', 08:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r2nu7ZCD9i/Listeners', 08:36:33 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:33 INFO - 'USER': 'cltbld', 08:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:33 INFO - 'XPC_FLAGS': '0x0', 08:36:33 INFO - 'XPC_SERVICE_NAME': '0', 08:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:36:34 INFO - mozversion application_buildid: 20161121061609 08:36:34 INFO - mozversion application_changeset: bb1124d7df770a08acb6255289276cf5575db0b1 08:36:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:36:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:36:34 INFO - mozversion application_name: Firefox 08:36:34 INFO - mozversion application_remotingname: firefox-dev 08:36:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:36:34 INFO - mozversion application_vendor: Mozilla 08:36:34 INFO - mozversion application_version: 52.0a2 08:36:34 INFO - mozversion platform_buildid: 20161121061609 08:36:34 INFO - mozversion platform_changeset: bb1124d7df770a08acb6255289276cf5575db0b1 08:36:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:36:34 INFO - mozversion platform_version: 52.0a2 08:36:34 INFO - using testdate: 1479746194 08:36:34 INFO - actual date: 1479746194 08:36:34 INFO - starting webserver on 'localhost:49239' 08:36:34 INFO - SUITE-START | Running 2 tests 08:36:34 INFO - TEST-START | dromaeo_css 08:36:34 INFO - operating with platform_type : mac_ 08:36:34 INFO - Initialising browser for dromaeo_css test... 08:36:34 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpy8oa0p/profile http://localhost:49239/getInfo.html) 08:36:35 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 08:36:37 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 08:36:37 INFO - PROCESS | 1638 | colorDepth:24 08:36:37 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 08:36:37 INFO - PROCESS | 1638 | __metrics 08:36:37 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 08:36:37 INFO - TEST-INFO | 1638: exit 0 08:36:37 INFO - Browser initialized. 08:36:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:36:37 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpy8oa0p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:49 INFO - PROCESS | 1641 | RSS: Main: 223997952 08:36:49 INFO - PROCESS | 1641 | 08:37:39 INFO - PROCESS | 1641 | 1479746259535 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] 08:39:53 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 08:39:55 INFO - PROCESS | 1641 | RSS: Main: 235433984 08:39:55 INFO - PROCESS | 1641 | 08:42:57 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 08:42:58 INFO - PROCESS | 1641 | RSS: Main: 248057856 08:42:58 INFO - PROCESS | 1641 | 08:46:00 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 08:46:00 INFO - PROCESS | 1641 | RSS: Main: 250884096 08:46:00 INFO - PROCESS | 1641 | 08:49:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 08:49:03 INFO - PROCESS | 1641 | RSS: Main: 250056704 08:49:03 INFO - PROCESS | 1641 | 08:52:06 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 08:52:06 INFO - PROCESS | 1641 | RSS: Main: 254001152 08:52:06 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 08:55:09 INFO - PROCESS | 1641 | RSS: Main: 255320064 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | __start_tp_report 08:55:09 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:55:09 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:55:09 INFO - PROCESS | 1641 | |i|pagename|runs| 08:55:09 INFO - PROCESS | 1641 | |0;dojo.html;2733.384988377615;3383.780054296472;3421.349943510733;3455.378031954247;3466.497357883107;5780.4219578042175;5782.450667186616;5869.354371019184;5903.085021821625;5907.438793314628;5876.294844618641;5948.524118070555;5952.970235148829;6015.6992150392525;6020.879582408349;5335.173048177529;5377.973110134444;5425.077736784737;5449.3188351456065;5491.670499770006;5423.376311724143;5444.101723215665;5455.699936503491;5463.344398672165;5468.070428027225;5280.603954703393;5284.894302113956;5445.591580631449;5451.700156491392;5520.227168196456;5347.850212204376;5420.997115533639;5432.864178395532;5455.481729235717;5505.6696598204235;5316.308879845634;5385.5960802939935;5396.973015134939;5415.187721841716;5423.972880135613;5975.671338076404;5977.940220597782;6029.366916473751;6058.454739073504;6076.969615151896;5927.199828023205;5951.642901425928;5954;6160;6224.128621992924;6125.845966769323;6158.784442544535;6158.815235542941;6183.628982261079;6197.349278325756;5086.593072554187;5671.149327600893;5671.574631902624;5678.886422271614;5695.373508914016;4854.271859221074;6174.753009879644;6242.781502647476;6273.466755325849;6290.8427289317215;6195.876082478325;6252.280987686383;6254.530910181755;6285.874282514415;6310.968445157837;4664.696794708401;5377.596680248997;6311.274203466568;6360.332165122595;6377.489800815923;5262.526372626482;6157.983932651152;6237;6254.874902501924;6289.622622642656;6142.1304578115505;6158.199434073542;6176.752929882844;6188.133661287423;6199.969000154971;4992.251162325608;5046.722430266297;5047;5063.620228482879;5085.7202853842655;4411.470623525198;4472.552744725566;4482.170798402306;4506.076254367847;4525.977370113128;4559.475660299042;5790.392008839095;5968.492678122321;5978.940210597925;6028.487578555784;6279.654618995908;6317.46301564363;6332.33510481402;6346.904796428057;6357.809265722036;5905.704714764331;5949.6430214187285;5986;6021.066734656135;6071.301800292935;5539.501444869981;5568.6658800472105;5589.105743081088;5623.325200975827;5623.550115990711;6220.0047992321015;6237.688115594293;6244.094606281973;6272.996320588684;6351.968240158677;4738.668293219442;6299.464545513682;6312.936870631419;6337.04944258365;6365.363463653597;6380.862386233613;6429.22849258092;6430.839229019312;6433.517486188461;6439.935600644121;6183.041628547582;6224.128621992834;6259.2488901332135;6264.467520260829;6268.373162683695;6288.151099601495;6302.527310451643;6309.33751956038;6314.747410103544;6320.273168585672;5344.786208551614;5750.223719797774;6222.12890195364;6255.093011513396;6273.247210334789;5365.812196573257;6129.049997250433;6135.07955838608;6189.597676151036;6241.656708881055;5608.859778505407;5923.970380148072;6142.047982562532;6158.599691020069;6215.81352559424;6225.3774622539195;6246.593971391845;6264.248290205204;6273.964795808641;6284.4344009039405;6069.332373439007;6140.416660417253;6170.7223174958135;6178.135061091541;6203.844903877439 08:55:09 INFO - PROCESS | 1641 | |1;ext.html;470.9709285211811;476.07166026248825;515.6519349439129;522.0681084264588;524.1875093604914;38116;39267;39528.60471395285;39537.60462395375;41348.79325603375;40628.79685601575;40658.59341406585;41040.58959410405;41087;41148.58851411485;7769.300762931335;7883.487573307745;7895.092064412609;9251.953740231324;9397.859032114524;9213.262938964866;9224.308176886727;9297.442153470813;9308.58111384986;9323.62705491783;9307.534623268843;9375.249980001583;9408.905910940872;9409.388389754644;9441.858372124389;9304.06959304071;9314.301427392938;9370.953145234298;9372.29707771913;9384.483853388061;9182.86225706615;9272.490013049279;9279.582418791171;9294.349395542315;9294.953525232399;38582.22835543301;38931.8053409734;38943.22113557713;39025.41461878074;39166.60833391687;3270.198801293698;3275.0993476793833;3319.4191016572004;3711.64739349764;3874.3219936511277;28141.85929070362;28520.572191417144;29883.70116298831;30543.541846872315;30726.84636576825;3166.176794033545;3203.19920019995;3212.421764082464;3282.83585820708;3312.5362449257123;352086;354744;501767;506569;507899.46050269884;56661.15008274879;56712.716436417555;56850.71574642183;56888.86222275614;58077;58708.706456467444;59013.70493147507;59038.70480647569;59335.40664593385;59589.70205148947;15660.295286712126;15785;15906.284217210254;15929.442469513511;16030.519084427486;12403.193792403645;12423.378831058411;12458.75082498345;12459.688507787196;12480.43838027291;15033.022853514487;15438.456154384696;15681.215939202993;15695.529134125994;15708.68582628341;2649.3244222723083;3218.3885061838178;5930.88628470048;5931.673757943355;5951.851203719942;849.7493239494364;962.3456049886005;990.7424069741775;1028.6296933104077;1062.1874266186373;27040.834178942598;27340.179794606192;27734.722652773213;29922.251943701147;30317.393652126833;903.701778413122;990.9950450247846;1038.9428581427942;1107.457345900503;1114.2645853736658;15093.47172848967;15496.767548486781;15581.376744930303;15589.844101559067;15612.141332226836;664066;668794;669356;669518;671095;48271.79320517015;54463.45536544663;55803.720981394836;56457.71771141118;56496.43503564994;1312.934353282332;1322.7751282282036;1331.2877610478347;1334.3328335832084;1341.242198158018;28986.13041608755;29541.704582953895;29570.817167313926;30111.698883011766;30275;48276.758616206695;48732.5126748728;48763.756181218865;48885.75557122192;48958.51041489682;572742;572752;574632;577527;578409;25736.8713156433;25827.354316141496;26010.349741255865;26057.95768169252;26178.86910565435;43324.35013474737;44930.55069449264;45604.543954561355;45713.62859114232;45905.081898361175;34802.477962830075;36522.634773652986;36842.44736328904;38053.61946380611;39121.021974450865;36885.81557092197;37171.442428364135;37663.81168094142;37672.05819854526;38185.42721859119;37118.8144059278;37150.44274335941;37389.81305093457;37823.81088094542;38034.42948355832;25121.371965701004;32733.85431509886;33548.329033419686;34396.14009649779;34624.307513849075;25033.374165646004;25472.108476203244;25535.489290214464;25593.744062559137;25950.22149335523 08:55:09 INFO - PROCESS | 1641 | |2;jquery.html;334.9112485191424;369.30939143801095;380.3761830597819;385.24684242306296;405.41620067103366;18879.528011799714;19145.42563723087;19225.134868930898;19777.30779422722;19935.401937941886;11528.193026488148;11718.707032324197;11873.16887817853;11905.940470297657;11913.702157446069;4961.627877909163;5223.63434559579;5280.656757310762;5335.5198032177095;5352.866178345553;3826.789526576037;4033.899152521181;5081.237814327844;5284.128118860383;5328.307320048389;4006.4390985262085;6436.871262574746;6497.642629655367;6519.608823470583;6534.8693026139445;6294.402031806971;6368.140301059344;6461;6493.480521558287;6508.837279068014;5727.2268243786975;5732.197492351074;5739.397363276879;5749.913751293734;5753.194552762617;12817.448461308653;12843.100982931199;13140.6714832129;13242.337883105902;13283.136596121221;1843.5298998755366;2216.3018649125474;2269.6822444857735;2662.7869770418515;2745.32739478827;5601.523870471015;5660.830175094754;8040.959795200986;9268.953655231748;10360.430176340298;1781.6703909776604;1806.223323970692;1827.6710192165406;1862.6554087493857;1873.7564116664882;107817.46091269494;111275.44362278217;111633.44183279031;114009.42995285054;114724.42637786758;8794;9773.51132443375;9807;9948.850767238497;10353.327033742782;11089.558357413645;11504.194706370474;12341.814872776915;12449.128561000643;12476.376181191086;7855.5286682799215;7924.128345881948;7993.440459167802;8084;8159.26566609008;6716.865662686719;6909.723611055501;8177.795555111051;8404.78988025292;8434.367422443218;3343.4650455926935;3727.8071017274756;3910.159315747071;3916.6279203475624;3961.742486738353;1920.9711854322197;1945.6789629710938;1947.2113793913488;1949.1131535151471;1958.608278344337;804.8792681097882;857.1471385970999;912.9452232866049;920.3649481857497;966.8832498464285;16813.747793783103;19829.10769015398;20670.44683144988;20723.378298651063;20856.687149692767;896.0815164456354;896.2426749396749;907.7685190276569;949.5015117063459;966.7873067925045;8620.396572240008;10746.823244215288;10761.892381076246;10818.837717434244;10886.292390994719;588550;622799;627489;628955;638295;102957;126728.36635816762;130554;135440.32279838537;142119.28940355233;932.244881645869;980.5342462330336;988.2439933450916;992.712113487095;996.0935548650693;17257.56856078608;17738.379156729454;18003.90998045054;18243.54391140179;18332.99168545743;38819.805900970314;43083.353749694405;51646;55475;57649.71175144098;80024;81681;82085.17914820775;83046;84502.7324590144;7862.72480463181;7950.681972721027;8119.75640730779;8148.755537333889;8172.6730930762105;9757.365771224848;12175.208611440228;12285.201461904948;12471.438785254504;12479.500819967463;9470.337076404585;10589.576416943544;10728.570857165625;10960.945195273973;11086.667399978014;8883.24492418126;10508.264421490423;10619.415932123498;10771.946140269249;10931.562737490729;7170.892436613351;9189.402688825205;10737;10882.727931801768;11001.449927503432;6440.806775796734;6943.722251109899;7026.243439140215;7825.804354891174;8085.272325490499;9484.430934143973;10712.732181695208;10728.517216725422;10883;10981.011708946233 08:55:09 INFO - PROCESS | 1641 | |3;mootools.html;1446.515417335193;1473.8968272220945;1632.9875477204134;1753.5353131420168;1783.1619139004672;7566.659500322485;7843.490173138742;8121.918780812198;8567.871481927776;8590.828183436335;8001.199880011995;8288.502689838599;8318.001839779236;8466.195711407421;8471.195236452528;2305.654151877219;2319.814414846817;2327.72067351918;2328.988355058222;2337.8947947342413;2331.720193576765;2340.5435939991708;2371.727251366098;2382.1543352109925;2389.0921449849034;2308.3421224950894;2308.572914010905;2343.3087239264373;2344.2615576093563;2347.4835536182013;2208.3706143749037;2216.8004879560917;2229.843910926236;2235.8099561537288;2243.2933625907867;2345.7185137783404;2356.976430235693;2358.3278765551736;2364.066193853418;2366.349253955159;8472.74581762548;8480.915190848136;8498.235158835672;8526.957365213197;8533.701320453816;5035.672681275742;5264.6051546134195;5264.684118952875;5274.446183150791;5281.52466278033;3344.665533446636;3447.7414193935556;3669.743117981742;3678.2459595782802;3693.926121477555;1039.5166247694858;1041.2607048995224;1091.1489038549944;1093.578972095741;1096.9122470202444;10836.512356943957;10943.343399396063;10948.671539853816;10971.780564388748;10991.505382257817;4518.209313370147;4617.792199351037;4707.741074240881;4712.081144176853;4801;9401.059894010545;9417.246620270362;9474.621015159455;9482.762930926781;9486.715398537917;5248.606354523426;5249.632525723163;5253.763580638804;5258.6581872178185;5265.842024739264;4280.871573852789;4289.785510724451;4784.162771514971;4837.879053023703;4839.370881785346;3802.3155831950517;3847.576766555677;3884.7280690351404;3925.666318362971;3944.8027598619956;1271.6630093025356;1291.2123604601186;1292.3667402972476;1296.7471343088196;1301.0307321045693;867.1112110087086;879.147227189625;886.7942388266499;887.9289656827439;911.3074063711495;4848.8302909398;5425.701586412785;5658.179563963202;5659.207710920475;5680.4319568042865;339.9512503925418;370.6867696796203;421.8165098182239;424.37404827878635;458.1935793004336;3197.344544368399;3358.261182539837;3437.570303712036;3757.7933213672964;3858.7105967052585;9934.304598678023;10126.443045632413;10327.690169294932;10354;10361.585536578603;10004.049615286536;10004.799904001879;10132.797344053077;10221.131203847608;10226.977302269714;7678.155402905676;7807.18024607408;7911.881321780178;7912.762617121495;7960.880586791202;4097.569755175664;4412.646988240869;5361.490658387447;5529.723513824212;5531.557475402039;7652.693892244471;9284.767880802763;9351.3454058218;9381.530923453663;9468.100530449596;9189.632414703337;9428.340016199074;9523.238140948846;9579.658847761459;9618.374805637612;6486.902696459462;6722.058911752456;6890.621015844176;6954.547954382949;7131.750388736369;7820.608969551614;8016.1182269949095;8017.559034253172;8035.879461808195;8044.034715834137;6996;7119.323664251888;7145.892811607934;7177.892331615134;7244.492885497964;6952.339527744856;7002.75490357835;7031.542949708252;7063.1524217094275;7086.539374940612;6647.767328143492;6782.525223234524;6788.96605516989;6796.864062718619;6823.829404264933;4862.100511405401;4887.291342755351;4888.437829649493;4889.168841296917;4932.802687892444;4845.394325709286;4846.95153048465;4868.9756551217015;4968.180250258812;4981.975090124526 08:55:09 INFO - PROCESS | 1641 | |4;prototype.html;283.1618409507857;290.1006878676103;295.4829049163963;295.70872690399966;304.3578050313838;4681.953180468198;5155.613329000331;5305.310309659749;5328.227407025984;5401.513863752267;4970.055689419008;5073.909109541448;5200.815657423882;5289.523942845143;5300.549453296474;3368.3768502826933;4062.4718786557705;4070.3894415837567;4086;4097.79511024449;3401.9319613607713;3900.8829735107993;3983.362661974085;4047.8785636430807;4126.442930204414;3616.62025487324;3709.5733990591025;3863.9374172102616;4005.9584508027833;4012.7993600320015;3771.66055055045;3866.6326698963553;4024;4033.2538480381077;4050.9189816203807;3536.9115772105647;3568.1267334649256;3580.2839432113683;3587.3184095022125;3588.8743893963588;4996.850094497135;5144.356955380577;5158.638895277332;5174.974125129388;5184.7148406837605;4616.714726210514;4617.859855298396;4627.375304333939;4628.629709623222;4640.934784021112;5397.568194544467;5594.524465420403;5614.399896029653;5644.74598643062;5666.093425051972;2986.22358186873;3284.6058472983154;3406.148462884279;3701.2782507411066;3912.726109172359;5135.691858488465;5320.8560159565495;5591.189277554732;5841.415858414167;5861.85345366365;3287.260366417585;3348.5981682198294;3575.785452872836;3817.751846129993;3992.1416895367634;3542.4863394807608;3958.2701703288967;4040.575739547364;4041.656459201001;4089.44792453021;3661.5972243053243;3907.2612687353853;4570.382998295254;4622.583967442946;4641.143542582926;5630.971845140748;5649.943500565024;5652.85867853307;5670.347909990322;5691.800786972519;1075.9031687148195;1128.5316593613559;1301.128244076471;1307.4116647508658;1321.2270821569489;874.7200895713311;898.6764764684708;901.985266575103;902.7517432705927;912.9224015958717;4818.8795280118275;4865.854024379274;4987.4015118185325;4995.8534416434995;4997.575206107449;4860.3681521401995;5309.601779866525;5474.056709868019;5481.451854814487;5514.365847927459;4444.799984000728;4567.49272739995;4625.32932724753;4752.42970843501;4818.7831547580445;2841.6502161473136;2862.6545523603872;2880.5967164596973;2882.394697113588;2888.393437378174;4578.381918441034;5228;5336.224079448872;5547.33431988164;5591.608587398924;4939.96260785232;5128.820491282787;5368.8657783554945;5448.155535891864;6004.0693692477735;5970.910436343458;5973.804014852033;5988.461038506555;5997.940020599825;6013.7293821779;5261.447548007404;5813.912791308218;5876.882462350814;5904.261967254093;5911.280810827067;5319.946800531945;5463.098588732814;5738.110592858114;5796.8550786229;5827.9417205829095;4902.279235024595;5561.916571251515;5803.9419605803405;5828.446297601721;5999.1901093351835;3460.653934606519;3871.5934826842777;3896.181801821536;3916.118873253495;3935.1736135411884;5080.390353157497;5131;5450.618456708151;5770.769169233352;5831.017454065638;5238.292830467838;5528.889422211614;5645.745941432563;5650.463205995424;5684.346300175533;4814.903701925872;4890.7554622268035;5506.421825708243;5674.858128546819;5740.454656807597;4751.61987041029;5041.773120209526;5430.429804870431;5563.8887222256135;5615.831525054255;4596.6322694185055;5535.8892822142525;5541.340156724876;5610.551155907599;5622.184783206494;4562.110388474216;4594.058218065222;5065.3161823153405;5368.194770784414;5462.098753705594 08:55:09 INFO - PROCESS | 1641 | |5;yui.html;622.5890911998081;678.7760039187069;727.9344858962693;732.008128985225;756.8524137793129;12125.211861229025;13925.791113133311;14259.073160244578;14880.925595372035;14881.553553393389;11064.557417703303;13365.599032029031;13463.461461541527;13483.730325393486;13522.459101635923;630.7445484578753;651.9282878883319;778.4161878591043;779.298631231893;794.9841003179932;800.8078061265284;802.8273921106961;803.4135081390559;804.4730701390598;807.4347956430493;844.1263292492272;848.2959143910571;848.4018766769443;850.5322072859933;852.0967774159379;839.4291881520563;843.9535825529593;844.9873251901226;846.2214762418558;846.2426128614886;620.3734228429274;626.6522080245462;626.9686515674198;627.1658693937098;627.4353082225988;13554.525591604246;13572.660683482893;13729.725405491934;13754.93122534381;14411;13522.93238533801;13747;13878.722425551534;13970.091944023607;14000.238843995112;11058;11191.66425007251;11310.151738619636;11380.260283081574;11430.828537571942;3628.419452887552;3641.7814931104217;3673.9265214695556;3693.2428852085527;3779.981100094482;56654.999999999585;89595;93099.06900930972;95093;95147.52426237894;10584.841227381596;10692.305000174963;10711.46442677863;10788.946055269831;10848.132149428027;16513.83486165123;18184.727069105036;19946.30187943415;21051.157953681683;21051.78948210498;6435.903461447989;7352.595607241653;7642;7717.421193410517;7800.375969922393;3991.7405368650943;4082.693797965105;4152.896177595584;4208.22147902639;4229.894252643709;3373.493975903585;3494.8602055917477;3495.667911548399;3502.439609662442;3503.7197024238;596.4870211618005;599.0804115682406;612.3906712820772;614.5145335185153;638.556203438615;510.43596825508024;604.1330690459117;611.0254144639293;643.8004218692221;681.5501768832547;8735.126487351214;9196.816063678689;9214.861777073214;9227.492487913229;9251.814963700688;550.856777237913;592.7315544734272;646.1761254400677;664.1863716946701;664.2792570061538;3411.846466908995;4298.699091063596;4300.8709738707885;4314.482262128502;4386.71486353386;75483.24516754872;100689.48276293004;105588;108446;112523;64268.10793514303;67622.66188669026;81250;91701.5414922921;100390;60952.39047609467;86050.56974715211;86371.70442443369;87164;88555;8266.462679925786;8435.493870367798;8472.110428405174;8523.616437260462;8525.317974561896;55705.72147139238;56777.432225677214;56978.715106424206;57817;57917.84164316775;79808;81088;81977.77033344386;83170.16829831866;83460;11197.944010279896;11265.662030139109;11736;11969.64091077269;12243.69390765238;12038.879611203776;12365.134440589072;12500;12512.812307815208;12527.49890004426;11513.942430287794;11606.419679015846;11671.241369310968;11708.306810948414;11909.047276218054;9861.457619830979;11605.187636865676;11983.22109062943;12099.939500302442;12248.693782655504;10586.947065264625;10816.56733730642;11140.610078647533;11363.715907102389;11445.42772861337;9661.13049825519;9892;9932.900670993487;10606.681799546026;11432.82850757256;10703.598787105037;10855;10894.945525272324;10918.50866710984;10990.505427255634 08:55:09 INFO - PROCESS | 1641 | __end_tp_report 08:55:09 INFO - PROCESS | 1641 | __start_cc_report 08:55:09 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,388 08:55:09 INFO - PROCESS | 1641 | __end_cc_report 08:55:09 INFO - PROCESS | 1641 | __startTimestamp1479747309711__endTimestamp 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:55:09 INFO - PROCESS | 1641 | Number of tests: 6 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5787.31 Median:6021.07 stddev:657.12 (10.9%) stddev-sans-first:613.91 08:55:09 INFO - PROCESS | 1641 | Values: 2733.4 3383.8 3421.3 3455.4 3466.5 5780.4 5782.5 5869.4 5903.1 5907.4 5876.3 5948.5 5953.0 6015.7 6020.9 5335.2 5378.0 5425.1 5449.3 5491.7 5423.4 5444.1 5455.7 5463.3 5468.1 5280.6 5284.9 5445.6 5451.7 5520.2 5347.9 5421.0 5432.9 5455.5 5505.7 5316.3 5385.6 5397.0 5415.2 5424.0 5975.7 5977.9 6029.4 6058.5 6077.0 5927.2 5951.6 5954.0 6160.0 6224.1 6125.8 6158.8 6158.8 6183.6 6197.3 5086.6 5671.1 5671.6 5678.9 5695.4 4854.3 6174.8 6242.8 6273.5 6290.8 6195.9 6252.3 6254.5 6285.9 6311.0 4664.7 5377.6 6311.3 6360.3 6377.5 5262.5 6158.0 6237.0 6254.9 6289.6 6142.1 6158.2 6176.8 6188.1 6200.0 4992.3 5046.7 5047.0 5063.6 5085.7 4411.5 4472.6 4482.2 4506.1 4526.0 4559.5 5790.4 5968.5 5978.9 6028.5 6279.7 6317.5 6332.3 6346.9 6357.8 5905.7 5949.6 5986.0 6021.1 6071.3 5539.5 5568.7 5589.1 5623.3 5623.6 6220.0 6237.7 6244.1 6273.0 6352.0 4738.7 6299.5 6312.9 6337.0 6365.4 6380.9 6429.2 6430.8 6433.5 6439.9 6183.0 6224.1 6259.2 6264.5 6268.4 6288.2 6302.5 6309.3 6314.7 6320.3 5344.8 5750.2 6222.1 6255.1 6273.2 5365.8 6129.0 6135.1 6189.6 6241.7 5608.9 5924.0 6142.0 6158.6 6215.8 6225.4 6246.6 6264.2 6274.0 6284.4 6069.3 6140.4 6170.7 6178.1 6203.8 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:68771.69 Median:38937.51 stddev:153195.49 (393.4%) stddev-sans-first:153539.42 08:55:09 INFO - PROCESS | 1641 | Values: 471.0 476.1 515.7 522.1 524.2 38116.0 39267.0 39528.6 39537.6 41348.8 40628.8 40658.6 41040.6 41087.0 41148.6 7769.3 7883.5 7895.1 9252.0 9397.9 9213.3 9224.3 9297.4 9308.6 9323.6 9307.5 9375.2 9408.9 9409.4 9441.9 9304.1 9314.3 9371.0 9372.3 9384.5 9182.9 9272.5 9279.6 9294.3 9295.0 38582.2 38931.8 38943.2 39025.4 39166.6 3270.2 3275.1 3319.4 3711.6 3874.3 28141.9 28520.6 29883.7 30543.5 30726.8 3166.2 3203.2 3212.4 3282.8 3312.5 352086.0 354744.0 501767.0 506569.0 507899.5 56661.2 56712.7 56850.7 56888.9 58077.0 58708.7 59013.7 59038.7 59335.4 59589.7 15660.3 15785.0 15906.3 15929.4 16030.5 12403.2 12423.4 12458.8 12459.7 12480.4 15033.0 15438.5 15681.2 15695.5 15708.7 2649.3 3218.4 5930.9 5931.7 5951.9 849.7 962.3 990.7 1028.6 1062.2 27040.8 27340.2 27734.7 29922.3 30317.4 903.7 991.0 1038.9 1107.5 1114.3 15093.5 15496.8 15581.4 15589.8 15612.1 664066.0 668794.0 669356.0 669518.0 671095.0 48271.8 54463.5 55803.7 56457.7 56496.4 1312.9 1322.8 1331.3 1334.3 1341.2 28986.1 29541.7 29570.8 30111.7 30275.0 48276.8 48732.5 48763.8 48885.8 48958.5 572742.0 572752.0 574632.0 577527.0 578409.0 25736.9 25827.4 26010.3 26058.0 26178.9 43324.4 44930.6 45604.5 45713.6 45905.1 34802.5 36522.6 36842.4 38053.6 39121.0 36885.8 37171.4 37663.8 37672.1 38185.4 37118.8 37150.4 37389.8 37823.8 38034.4 25121.4 32733.9 33548.3 34396.1 34624.3 25033.4 25472.1 25535.5 25593.7 25950.2 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:34427.43 Median:3984.09 stddev:103646.06 (2601.5%) stddev-sans-first:103905.20 08:55:09 INFO - PROCESS | 1641 | Values: 334.9 369.3 380.4 385.2 405.4 18879.5 19145.4 19225.1 19777.3 19935.4 11528.2 11718.7 11873.2 11905.9 11913.7 4961.6 5223.6 5280.7 5335.5 5352.9 3826.8 4033.9 5081.2 5284.1 5328.3 4006.4 6436.9 6497.6 6519.6 6534.9 6294.4 6368.1 6461.0 6493.5 6508.8 5727.2 5732.2 5739.4 5749.9 5753.2 12817.4 12843.1 13140.7 13242.3 13283.1 1843.5 2216.3 2269.7 2662.8 2745.3 5601.5 5660.8 8041.0 9269.0 10360.4 1781.7 1806.2 1827.7 1862.7 1873.8 107817.5 111275.4 111633.4 114009.4 114724.4 8794.0 9773.5 9807.0 9948.9 10353.3 11089.6 11504.2 12341.8 12449.1 12476.4 7855.5 7924.1 7993.4 8084.0 8159.3 6716.9 6909.7 8177.8 8404.8 8434.4 3343.5 3727.8 3910.2 3916.6 3961.7 1921.0 1945.7 1947.2 1949.1 1958.6 804.9 857.1 912.9 920.4 966.9 16813.7 19829.1 20670.4 20723.4 20856.7 896.1 896.2 907.8 949.5 966.8 8620.4 10746.8 10761.9 10818.8 10886.3 588550.0 622799.0 627489.0 628955.0 638295.0 102957.0 126728.4 130554.0 135440.3 142119.3 932.2 980.5 988.2 992.7 996.1 17257.6 17738.4 18003.9 18243.5 18333.0 38819.8 43083.4 51646.0 55475.0 57649.7 80024.0 81681.0 82085.2 83046.0 84502.7 7862.7 7950.7 8119.8 8148.8 8172.7 9757.4 12175.2 12285.2 12471.4 12479.5 9470.3 10589.6 10728.6 10960.9 11086.7 8883.2 10508.3 10619.4 10771.9 10931.6 7170.9 9189.4 10737.0 10882.7 11001.4 6440.8 6943.7 7026.2 7825.8 8085.3 9484.4 10712.7 10728.5 10883.0 10981.0 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5433.90 Median:4878.13 stddev:3014.94 (61.8%) stddev-sans-first:3008.51 08:55:09 INFO - PROCESS | 1641 | Values: 1446.5 1473.9 1633.0 1753.5 1783.2 7566.7 7843.5 8121.9 8567.9 8590.8 8001.2 8288.5 8318.0 8466.2 8471.2 2305.7 2319.8 2327.7 2329.0 2337.9 2331.7 2340.5 2371.7 2382.2 2389.1 2308.3 2308.6 2343.3 2344.3 2347.5 2208.4 2216.8 2229.8 2235.8 2243.3 2345.7 2357.0 2358.3 2364.1 2366.3 8472.7 8480.9 8498.2 8527.0 8533.7 5035.7 5264.6 5264.7 5274.4 5281.5 3344.7 3447.7 3669.7 3678.2 3693.9 1039.5 1041.3 1091.1 1093.6 1096.9 10836.5 10943.3 10948.7 10971.8 10991.5 4518.2 4617.8 4707.7 4712.1 4801.0 9401.1 9417.2 9474.6 9482.8 9486.7 5248.6 5249.6 5253.8 5258.7 5265.8 4280.9 4289.8 4784.2 4837.9 4839.4 3802.3 3847.6 3884.7 3925.7 3944.8 1271.7 1291.2 1292.4 1296.7 1301.0 867.1 879.1 886.8 887.9 911.3 4848.8 5425.7 5658.2 5659.2 5680.4 340.0 370.7 421.8 424.4 458.2 3197.3 3358.3 3437.6 3757.8 3858.7 9934.3 10126.4 10327.7 10354.0 10361.6 10004.0 10004.8 10132.8 10221.1 10227.0 7678.2 7807.2 7911.9 7912.8 7960.9 4097.6 4412.6 5361.5 5529.7 5531.6 7652.7 9284.8 9351.3 9381.5 9468.1 9189.6 9428.3 9523.2 9579.7 9618.4 6486.9 6722.1 6890.6 6954.5 7131.8 7820.6 8016.1 8017.6 8035.9 8044.0 6996.0 7119.3 7145.9 7177.9 7244.5 6952.3 7002.8 7031.5 7063.2 7086.5 6647.8 6782.5 6789.0 6796.9 6823.8 4862.1 4887.3 4888.4 4889.2 4932.8 4845.4 4847.0 4869.0 4968.2 4982.0 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4472.13 Median:4996.35 stddev:1387.44 (27.8%) stddev-sans-first:1355.24 08:55:09 INFO - PROCESS | 1641 | Values: 283.2 290.1 295.5 295.7 304.4 4682.0 5155.6 5305.3 5328.2 5401.5 4970.1 5073.9 5200.8 5289.5 5300.5 3368.4 4062.5 4070.4 4086.0 4097.8 3401.9 3900.9 3983.4 4047.9 4126.4 3616.6 3709.6 3863.9 4006.0 4012.8 3771.7 3866.6 4024.0 4033.3 4050.9 3536.9 3568.1 3580.3 3587.3 3588.9 4996.9 5144.4 5158.6 5175.0 5184.7 4616.7 4617.9 4627.4 4628.6 4640.9 5397.6 5594.5 5614.4 5644.7 5666.1 2986.2 3284.6 3406.1 3701.3 3912.7 5135.7 5320.9 5591.2 5841.4 5861.9 3287.3 3348.6 3575.8 3817.8 3992.1 3542.5 3958.3 4040.6 4041.7 4089.4 3661.6 3907.3 4570.4 4622.6 4641.1 5631.0 5649.9 5652.9 5670.3 5691.8 1075.9 1128.5 1301.1 1307.4 1321.2 874.7 898.7 902.0 902.8 912.9 4818.9 4865.9 4987.4 4995.9 4997.6 4860.4 5309.6 5474.1 5481.5 5514.4 4444.8 4567.5 4625.3 4752.4 4818.8 2841.7 2862.7 2880.6 2882.4 2888.4 4578.4 5228.0 5336.2 5547.3 5591.6 4940.0 5128.8 5368.9 5448.2 6004.1 5970.9 5973.8 5988.5 5997.9 6013.7 5261.4 5813.9 5876.9 5904.3 5911.3 5319.9 5463.1 5738.1 5796.9 5827.9 4902.3 5561.9 5803.9 5828.4 5999.2 3460.7 3871.6 3896.2 3916.1 3935.2 5080.4 5131.0 5450.6 5770.8 5831.0 5238.3 5528.9 5645.7 5650.5 5684.3 4814.9 4890.8 5506.4 5674.9 5740.5 4751.6 5041.8 5430.4 5563.9 5615.8 4596.6 5535.9 5541.3 5610.6 5622.2 4562.1 4594.1 5065.3 5368.2 5462.1 08:55:09 INFO - PROCESS | 1641 | 08:55:09 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19738.72 Median:2448.58 stddev:28915.31 (1180.9%) stddev-sans-first:28960.80 08:55:09 INFO - PROCESS | 1641 | Values: 622.6 678.8 727.9 732.0 756.9 12125.2 13925.8 14259.1 14880.9 14881.6 11064.6 13365.6 13463.5 13483.7 13522.5 630.7 651.9 778.4 779.3 795.0 800.8 802.8 803.4 804.5 807.4 844.1 848.3 848.4 850.5 852.1 839.4 844.0 845.0 846.2 846.2 620.4 626.7 627.0 627.2 627.4 13554.5 13572.7 13729.7 13754.9 14411.0 13522.9 13747.0 13878.7 13970.1 14000.2 11058.0 11191.7 11310.2 11380.3 11430.8 3628.4 3641.8 3673.9 3693.2 3780.0 56655.0 89595.0 93099.1 95093.0 95147.5 10584.8 10692.3 10711.5 10788.9 10848.1 16513.8 18184.7 19946.3 21051.2 21051.8 6435.9 7352.6 7642.0 7717.4 7800.4 3991.7 4082.7 4152.9 4208.2 4229.9 3373.5 3494.9 3495.7 3502.4 3503.7 596.5 599.1 612.4 614.5 638.6 510.4 604.1 611.0 643.8 681.6 8735.1 9196.8 9214.9 9227.5 9251.8 550.9 592.7 646.2 664.2 664.3 3411.8 4298.7 4300.9 4314.5 4386.7 75483.2 100689.5 105588.0 108446.0 112523.0 64268.1 67622.7 81250.0 91701.5 100390.0 60952.4 86050.6 86371.7 87164.0 88555.0 8266.5 8435.5 8472.1 8523.6 8525.3 55705.7 56777.4 56978.7 57817.0 57917.8 79808.0 81088.0 81977.8 83170.2 83460.0 11197.9 11265.7 11736.0 11969.6 12243.7 12038.9 12365.1 12500.0 12512.8 12527.5 11513.9 11606.4 11671.2 11708.3 11909.0 9861.5 11605.2 11983.2 12099.9 12248.7 10586.9 10816.6 11140.6 11363.7 11445.4 9661.1 9892.0 9932.9 10606.7 11432.8 10703.6 10855.0 10894.9 10918.5 10990.5 08:55:09 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:55:09 INFO - PROCESS | 1641 | 08:55:10 INFO - TEST-INFO | 1641: exit 0 08:55:10 INFO - TEST-OK | dromaeo_css | took 1116334ms 08:55:10 INFO - TEST-START | kraken 08:55:10 INFO - operating with platform_type : mac_ 08:55:10 INFO - Initialising browser for kraken test... 08:55:10 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpNVnfm1/profile http://localhost:49239/getInfo.html) 08:55:11 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 08:55:13 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 08:55:13 INFO - PROCESS | 1652 | colorDepth:24 08:55:13 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 08:55:13 INFO - PROCESS | 1652 | __metrics 08:55:13 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 08:55:13 INFO - TEST-INFO | 1652: exit 0 08:55:13 INFO - Browser initialized. 08:55:13 INFO - Running cycle 1/1 for kraken test... 08:55:13 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpNVnfm1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:25 INFO - PROCESS | 1654 | RSS: Main: 224190464 08:55:25 INFO - PROCESS | 1654 | 08:55:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 08:55:29 INFO - PROCESS | 1654 | RSS: Main: 236527616 08:55:29 INFO - PROCESS | 1654 | 08:55:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 08:55:32 INFO - PROCESS | 1654 | RSS: Main: 244146176 08:55:32 INFO - PROCESS | 1654 | 08:55:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 08:55:36 INFO - PROCESS | 1654 | RSS: Main: 247779328 08:55:36 INFO - PROCESS | 1654 | 08:55:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 08:55:39 INFO - PROCESS | 1654 | RSS: Main: 248176640 08:55:39 INFO - PROCESS | 1654 | 08:55:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:55:41 INFO - PROCESS | 1654 | RSS: Main: 248340480 08:55:41 INFO - PROCESS | 1654 | 08:55:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 08:55:45 INFO - PROCESS | 1654 | RSS: Main: 252256256 08:55:45 INFO - PROCESS | 1654 | 08:55:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 08:55:49 INFO - PROCESS | 1654 | RSS: Main: 252760064 08:55:49 INFO - PROCESS | 1654 | 08:55:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 08:55:52 INFO - PROCESS | 1654 | RSS: Main: 253001728 08:55:52 INFO - PROCESS | 1654 | 08:55:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:55:55 INFO - PROCESS | 1654 | RSS: Main: 252067840 08:55:55 INFO - PROCESS | 1654 | 08:55:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:55:57 INFO - PROCESS | 1654 | RSS: Main: 252055552 08:55:57 INFO - PROCESS | 1654 | 08:56:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:56:00 INFO - PROCESS | 1654 | RSS: Main: 252592128 08:56:00 INFO - PROCESS | 1654 | 08:56:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:56:04 INFO - PROCESS | 1654 | RSS: Main: 252293120 08:56:04 INFO - PROCESS | 1654 | 08:56:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:56:08 INFO - PROCESS | 1654 | RSS: Main: 252436480 08:56:08 INFO - PROCESS | 1654 | 08:56:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 08:56:10 INFO - PROCESS | 1654 | RSS: Main: 254377984 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | __start_tp_report 08:56:10 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:56:10 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:56:10 INFO - PROCESS | 1654 | |i|pagename|runs| 08:56:10 INFO - PROCESS | 1654 | |0;ai-astar;107;116;118;116;120;117;117;118;118;128 08:56:10 INFO - PROCESS | 1654 | |1;audio-beat-detection;146;118;119;151;122;126;114;117;143;119 08:56:10 INFO - PROCESS | 1654 | |2;audio-dft;192;199;197;199;194;193;194;198;205;197 08:56:10 INFO - PROCESS | 1654 | |3;audio-fft;81;59;60;88;62;59;61;61;82;60 08:56:10 INFO - PROCESS | 1654 | |4;audio-oscillator;89;70;92;70;70;70;86;70;87;122 08:56:10 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;87;89;87;90;86;119;131;105;90 08:56:10 INFO - PROCESS | 1654 | |6;imaging-darkroom;157;90;149;99;101;135;135;131;139;122 08:56:10 INFO - PROCESS | 1654 | |7;imaging-desaturate;97;122;131;93;131;71;97;80;135;141 08:56:10 INFO - PROCESS | 1654 | |8;json-parse-financial;57;50;48;74;95;92;94;59;61;59 08:56:10 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;71;75;69;90;66;104;97;95;64;97 08:56:10 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;84;87;85;85;89;84;84;89;85 08:56:10 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;162;161;150;157;161;184;145;162;225;173 08:56:10 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;171;173;166;172;183;227;174;169;168;167 08:56:10 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;65;74;93;94;91;91;71;67;68;66 08:56:10 INFO - PROCESS | 1654 | __end_tp_report 08:56:10 INFO - PROCESS | 1654 | __start_cc_report 08:56:10 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1000 08:56:10 INFO - PROCESS | 1654 | __end_cc_report 08:56:10 INFO - PROCESS | 1654 | __startTimestamp1479747370208__endTimestamp 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:56:10 INFO - PROCESS | 1654 | Number of tests: 14 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:117.50 Median:118.00 stddev:5.08 (4.3%) stddev-sans-first:3.71 08:56:10 INFO - PROCESS | 1654 | Values: 107.0 116.0 118.0 116.0 120.0 117.0 117.0 118.0 118.0 128.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:124.00 stddev:13.72 (11.1%) stddev-sans-first:12.82 08:56:10 INFO - PROCESS | 1654 | Values: 146.0 118.0 119.0 151.0 122.0 126.0 114.0 117.0 143.0 119.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:196.80 Median:197.50 stddev:3.82 (1.9%) stddev-sans-first:3.64 08:56:10 INFO - PROCESS | 1654 | Values: 192.0 199.0 197.0 199.0 194.0 193.0 194.0 198.0 205.0 197.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:67.30 Median:61.50 stddev:11.47 (18.7%) stddev-sans-first:11.04 08:56:10 INFO - PROCESS | 1654 | Values: 81.0 59.0 60.0 88.0 62.0 59.0 61.0 61.0 82.0 60.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:78.00 stddev:16.69 (21.4%) stddev-sans-first:17.54 08:56:10 INFO - PROCESS | 1654 | Values: 89.0 70.0 92.0 70.0 70.0 70.0 86.0 70.0 87.0 122.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:88.00 stddev:15.76 (17.9%) stddev-sans-first:16.48 08:56:10 INFO - PROCESS | 1654 | Values: 90.0 87.0 89.0 87.0 90.0 86.0 119.0 131.0 105.0 90.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:125.80 Median:144.00 stddev:22.39 (15.5%) stddev-sans-first:20.71 08:56:10 INFO - PROCESS | 1654 | Values: 157.0 90.0 149.0 99.0 101.0 135.0 135.0 131.0 139.0 122.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:109.80 Median:75.50 stddev:25.08 (33.2%) stddev-sans-first:26.17 08:56:10 INFO - PROCESS | 1654 | Values: 97.0 122.0 131.0 93.0 131.0 71.0 97.0 80.0 135.0 141.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:67.50 stddev:18.45 (27.3%) stddev-sans-first:19.06 08:56:10 INFO - PROCESS | 1654 | Values: 57.0 50.0 48.0 74.0 95.0 92.0 94.0 59.0 61.0 59.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:82.50 stddev:15.20 (18.4%) stddev-sans-first:15.51 08:56:10 INFO - PROCESS | 1654 | Values: 71.0 75.0 69.0 90.0 66.0 104.0 97.0 95.0 64.0 97.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:1.95 (2.3%) stddev-sans-first:2.05 08:56:10 INFO - PROCESS | 1654 | Values: 85.0 84.0 87.0 85.0 85.0 89.0 84.0 84.0 89.0 85.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:168.00 Median:162.00 stddev:22.79 (14.1%) stddev-sans-first:24.07 08:56:10 INFO - PROCESS | 1654 | Values: 162.0 161.0 150.0 157.0 161.0 184.0 145.0 162.0 225.0 173.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:172.50 stddev:18.22 (10.6%) stddev-sans-first:19.20 08:56:10 INFO - PROCESS | 1654 | Values: 171.0 173.0 166.0 172.0 183.0 227.0 174.0 169.0 168.0 167.0 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:82.50 stddev:12.55 (15.2%) stddev-sans-first:12.40 08:56:10 INFO - PROCESS | 1654 | Values: 65.0 74.0 93.0 94.0 91.0 91.0 71.0 67.0 68.0 66.0 08:56:10 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:56:10 INFO - PROCESS | 1654 | 08:56:10 INFO - TEST-INFO | 1654: exit 0 08:56:11 INFO - TEST-OK | kraken | took 60606ms 08:56:11 INFO - SUITE-END | took 1176s 08:56:11 INFO - Completed test suite (00:19:37) 08:56:11 INFO - javascript benchmark 08:56:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6999.876477087036, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2733.384988377615, 3383.780054296472, 3421.349943510733, 3455.378031954247, 3466.497357883107, 5780.4219578042175, 5782.450667186616, 5869.354371019184, 5903.085021821625, 5907.438793314628, 5876.294844618641, 5948.524118070555, 5952.970235148829, 6015.6992150392525, 6020.879582408349, 5335.173048177529, 5377.973110134444, 5425.077736784737, 5449.3188351456065, 5491.670499770006, 5423.376311724143, 5444.101723215665, 5455.699936503491, 5463.344398672165, 5468.070428027225, 5280.603954703393, 5284.894302113956, 5445.591580631449, 5451.700156491392, 5520.227168196456, 5347.850212204376, 5420.997115533639, 5432.864178395532, 5455.481729235717, 5505.6696598204235, 5316.308879845634, 5385.5960802939935, 5396.973015134939, 5415.187721841716, 5423.972880135613, 5975.671338076404, 5977.940220597782, 6029.366916473751, 6058.454739073504, 6076.969615151896, 5927.199828023205, 5951.642901425928, 5954.0, 6160.0, 6224.128621992924, 6125.845966769323, 6158.784442544535, 6158.815235542941, 6183.628982261079, 6197.349278325756, 5086.593072554187, 5671.149327600893, 5671.574631902624, 5678.886422271614, 5695.373508914016, 4854.271859221074, 6174.753009879644, 6242.781502647476, 6273.466755325849, 6290.8427289317215, 6195.876082478325, 6252.280987686383, 6254.530910181755, 6285.874282514415, 6310.968445157837, 4664.696794708401, 5377.596680248997, 6311.274203466568, 6360.332165122595, 6377.489800815923, 5262.526372626482, 6157.983932651152, 6237.0, 6254.874902501924, 6289.622622642656, 6142.1304578115505, 6158.199434073542, 6176.752929882844, 6188.133661287423, 6199.969000154971, 4992.251162325608, 5046.722430266297, 5047.0, 5063.620228482879, 5085.7202853842655, 4411.470623525198, 4472.552744725566, 4482.170798402306, 4506.076254367847, 4525.977370113128, 4559.475660299042, 5790.392008839095, 5968.492678122321, 5978.940210597925, 6028.487578555784, 6279.654618995908, 6317.46301564363, 6332.33510481402, 6346.904796428057, 6357.809265722036, 5905.704714764331, 5949.6430214187285, 5986.0, 6021.066734656135, 6071.301800292935, 5539.501444869981, 5568.6658800472105, 5589.105743081088, 5623.325200975827, 5623.550115990711, 6220.0047992321015, 6237.688115594293, 6244.094606281973, 6272.996320588684, 6351.968240158677, 4738.668293219442, 6299.464545513682, 6312.936870631419, 6337.04944258365, 6365.363463653597, 6380.862386233613, 6429.22849258092, 6430.839229019312, 6433.517486188461, 6439.935600644121, 6183.041628547582, 6224.128621992834, 6259.2488901332135, 6264.467520260829, 6268.373162683695, 6288.151099601495, 6302.527310451643, 6309.33751956038, 6314.747410103544, 6320.273168585672, 5344.786208551614, 5750.223719797774, 6222.12890195364, 6255.093011513396, 6273.247210334789, 5365.812196573257, 6129.049997250433, 6135.07955838608, 6189.597676151036, 6241.656708881055, 5608.859778505407, 5923.970380148072, 6142.047982562532, 6158.599691020069, 6215.81352559424, 6225.3774622539195, 6246.593971391845, 6264.248290205204, 6273.964795808641, 6284.4344009039405, 6069.332373439007, 6140.416660417253, 6170.7223174958135, 6178.135061091541, 6203.844903877439], "value": 5748.259774675501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.9709285211811, 476.07166026248825, 515.6519349439129, 522.0681084264588, 524.1875093604914, 38116.0, 39267.0, 39528.60471395285, 39537.60462395375, 41348.79325603375, 40628.79685601575, 40658.59341406585, 41040.58959410405, 41087.0, 41148.58851411485, 7769.300762931335, 7883.487573307745, 7895.092064412609, 9251.953740231324, 9397.859032114524, 9213.262938964866, 9224.308176886727, 9297.442153470813, 9308.58111384986, 9323.62705491783, 9307.534623268843, 9375.249980001583, 9408.905910940872, 9409.388389754644, 9441.858372124389, 9304.06959304071, 9314.301427392938, 9370.953145234298, 9372.29707771913, 9384.483853388061, 9182.86225706615, 9272.490013049279, 9279.582418791171, 9294.349395542315, 9294.953525232399, 38582.22835543301, 38931.8053409734, 38943.22113557713, 39025.41461878074, 39166.60833391687, 3270.198801293698, 3275.0993476793833, 3319.4191016572004, 3711.64739349764, 3874.3219936511277, 28141.85929070362, 28520.572191417144, 29883.70116298831, 30543.541846872315, 30726.84636576825, 3166.176794033545, 3203.19920019995, 3212.421764082464, 3282.83585820708, 3312.5362449257123, 352086.0, 354744.0, 501767.0, 506569.0, 507899.46050269884, 56661.15008274879, 56712.716436417555, 56850.71574642183, 56888.86222275614, 58077.0, 58708.706456467444, 59013.70493147507, 59038.70480647569, 59335.40664593385, 59589.70205148947, 15660.295286712126, 15785.0, 15906.284217210254, 15929.442469513511, 16030.519084427486, 12403.193792403645, 12423.378831058411, 12458.75082498345, 12459.688507787196, 12480.43838027291, 15033.022853514487, 15438.456154384696, 15681.215939202993, 15695.529134125994, 15708.68582628341, 2649.3244222723083, 3218.3885061838178, 5930.88628470048, 5931.673757943355, 5951.851203719942, 849.7493239494364, 962.3456049886005, 990.7424069741775, 1028.6296933104077, 1062.1874266186373, 27040.834178942598, 27340.179794606192, 27734.722652773213, 29922.251943701147, 30317.393652126833, 903.701778413122, 990.9950450247846, 1038.9428581427942, 1107.457345900503, 1114.2645853736658, 15093.47172848967, 15496.767548486781, 15581.376744930303, 15589.844101559067, 15612.141332226836, 664066.0, 668794.0, 669356.0, 669518.0, 671095.0, 48271.79320517015, 54463.45536544663, 55803.720981394836, 56457.71771141118, 56496.43503564994, 1312.934353282332, 1322.7751282282036, 1331.2877610478347, 1334.3328335832084, 1341.242198158018, 28986.13041608755, 29541.704582953895, 29570.817167313926, 30111.698883011766, 30275.0, 48276.758616206695, 48732.5126748728, 48763.756181218865, 48885.75557122192, 48958.51041489682, 572742.0, 572752.0, 574632.0, 577527.0, 578409.0, 25736.8713156433, 25827.354316141496, 26010.349741255865, 26057.95768169252, 26178.86910565435, 43324.35013474737, 44930.55069449264, 45604.543954561355, 45713.62859114232, 45905.081898361175, 34802.477962830075, 36522.634773652986, 36842.44736328904, 38053.61946380611, 39121.021974450865, 36885.81557092197, 37171.442428364135, 37663.81168094142, 37672.05819854526, 38185.42721859119, 37118.8144059278, 37150.44274335941, 37389.81305093457, 37823.81088094542, 38034.42948355832, 25121.371965701004, 32733.85431509886, 33548.329033419686, 34396.14009649779, 34624.307513849075, 25033.374165646004, 25472.108476203244, 25535.489290214464, 25593.744062559137, 25950.22149335523], "value": 19100.1098306373, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.9112485191424, 369.30939143801095, 380.3761830597819, 385.24684242306296, 405.41620067103366, 18879.528011799714, 19145.42563723087, 19225.134868930898, 19777.30779422722, 19935.401937941886, 11528.193026488148, 11718.707032324197, 11873.16887817853, 11905.940470297657, 11913.702157446069, 4961.627877909163, 5223.63434559579, 5280.656757310762, 5335.5198032177095, 5352.866178345553, 3826.789526576037, 4033.899152521181, 5081.237814327844, 5284.128118860383, 5328.307320048389, 4006.4390985262085, 6436.871262574746, 6497.642629655367, 6519.608823470583, 6534.8693026139445, 6294.402031806971, 6368.140301059344, 6461.0, 6493.480521558287, 6508.837279068014, 5727.2268243786975, 5732.197492351074, 5739.397363276879, 5749.913751293734, 5753.194552762617, 12817.448461308653, 12843.100982931199, 13140.6714832129, 13242.337883105902, 13283.136596121221, 1843.5298998755366, 2216.3018649125474, 2269.6822444857735, 2662.7869770418515, 2745.32739478827, 5601.523870471015, 5660.830175094754, 8040.959795200986, 9268.953655231748, 10360.430176340298, 1781.6703909776604, 1806.223323970692, 1827.6710192165406, 1862.6554087493857, 1873.7564116664882, 107817.46091269494, 111275.44362278217, 111633.44183279031, 114009.42995285054, 114724.42637786758, 8794.0, 9773.51132443375, 9807.0, 9948.850767238497, 10353.327033742782, 11089.558357413645, 11504.194706370474, 12341.814872776915, 12449.128561000643, 12476.376181191086, 7855.5286682799215, 7924.128345881948, 7993.440459167802, 8084.0, 8159.26566609008, 6716.865662686719, 6909.723611055501, 8177.795555111051, 8404.78988025292, 8434.367422443218, 3343.4650455926935, 3727.8071017274756, 3910.159315747071, 3916.6279203475624, 3961.742486738353, 1920.9711854322197, 1945.6789629710938, 1947.2113793913488, 1949.1131535151471, 1958.608278344337, 804.8792681097882, 857.1471385970999, 912.9452232866049, 920.3649481857497, 966.8832498464285, 16813.747793783103, 19829.10769015398, 20670.44683144988, 20723.378298651063, 20856.687149692767, 896.0815164456354, 896.2426749396749, 907.7685190276569, 949.5015117063459, 966.7873067925045, 8620.396572240008, 10746.823244215288, 10761.892381076246, 10818.837717434244, 10886.292390994719, 588550.0, 622799.0, 627489.0, 628955.0, 638295.0, 102957.0, 126728.36635816762, 130554.0, 135440.32279838537, 142119.28940355233, 932.244881645869, 980.5342462330336, 988.2439933450916, 992.712113487095, 996.0935548650693, 17257.56856078608, 17738.379156729454, 18003.90998045054, 18243.54391140179, 18332.99168545743, 38819.805900970314, 43083.353749694405, 51646.0, 55475.0, 57649.71175144098, 80024.0, 81681.0, 82085.17914820775, 83046.0, 84502.7324590144, 7862.72480463181, 7950.681972721027, 8119.75640730779, 8148.755537333889, 8172.6730930762105, 9757.365771224848, 12175.208611440228, 12285.201461904948, 12471.438785254504, 12479.500819967463, 9470.337076404585, 10589.576416943544, 10728.570857165625, 10960.945195273973, 11086.667399978014, 8883.24492418126, 10508.264421490423, 10619.415932123498, 10771.946140269249, 10931.562737490729, 7170.892436613351, 9189.402688825205, 10737.0, 10882.727931801768, 11001.449927503432, 6440.806775796734, 6943.722251109899, 7026.243439140215, 7825.804354891174, 8085.272325490499, 9484.430934143973, 10712.732181695208, 10728.517216725422, 10883.0, 10981.011708946233], "value": 8775.143609127974, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.515417335193, 1473.8968272220945, 1632.9875477204134, 1753.5353131420168, 1783.1619139004672, 7566.659500322485, 7843.490173138742, 8121.918780812198, 8567.871481927776, 8590.828183436335, 8001.199880011995, 8288.502689838599, 8318.001839779236, 8466.195711407421, 8471.195236452528, 2305.654151877219, 2319.814414846817, 2327.72067351918, 2328.988355058222, 2337.8947947342413, 2331.720193576765, 2340.5435939991708, 2371.727251366098, 2382.1543352109925, 2389.0921449849034, 2308.3421224950894, 2308.572914010905, 2343.3087239264373, 2344.2615576093563, 2347.4835536182013, 2208.3706143749037, 2216.8004879560917, 2229.843910926236, 2235.8099561537288, 2243.2933625907867, 2345.7185137783404, 2356.976430235693, 2358.3278765551736, 2364.066193853418, 2366.349253955159, 8472.74581762548, 8480.915190848136, 8498.235158835672, 8526.957365213197, 8533.701320453816, 5035.672681275742, 5264.6051546134195, 5264.684118952875, 5274.446183150791, 5281.52466278033, 3344.665533446636, 3447.7414193935556, 3669.743117981742, 3678.2459595782802, 3693.926121477555, 1039.5166247694858, 1041.2607048995224, 1091.1489038549944, 1093.578972095741, 1096.9122470202444, 10836.512356943957, 10943.343399396063, 10948.671539853816, 10971.780564388748, 10991.505382257817, 4518.209313370147, 4617.792199351037, 4707.741074240881, 4712.081144176853, 4801.0, 9401.059894010545, 9417.246620270362, 9474.621015159455, 9482.762930926781, 9486.715398537917, 5248.606354523426, 5249.632525723163, 5253.763580638804, 5258.6581872178185, 5265.842024739264, 4280.871573852789, 4289.785510724451, 4784.162771514971, 4837.879053023703, 4839.370881785346, 3802.3155831950517, 3847.576766555677, 3884.7280690351404, 3925.666318362971, 3944.8027598619956, 1271.6630093025356, 1291.2123604601186, 1292.3667402972476, 1296.7471343088196, 1301.0307321045693, 867.1112110087086, 879.147227189625, 886.7942388266499, 887.9289656827439, 911.3074063711495, 4848.8302909398, 5425.701586412785, 5658.179563963202, 5659.207710920475, 5680.4319568042865, 339.9512503925418, 370.6867696796203, 421.8165098182239, 424.37404827878635, 458.1935793004336, 3197.344544368399, 3358.261182539837, 3437.570303712036, 3757.7933213672964, 3858.7105967052585, 9934.304598678023, 10126.443045632413, 10327.690169294932, 10354.0, 10361.585536578603, 10004.049615286536, 10004.799904001879, 10132.797344053077, 10221.131203847608, 10226.977302269714, 7678.155402905676, 7807.18024607408, 7911.881321780178, 7912.762617121495, 7960.880586791202, 4097.569755175664, 4412.646988240869, 5361.490658387447, 5529.723513824212, 5531.557475402039, 7652.693892244471, 9284.767880802763, 9351.3454058218, 9381.530923453663, 9468.100530449596, 9189.632414703337, 9428.340016199074, 9523.238140948846, 9579.658847761459, 9618.374805637612, 6486.902696459462, 6722.058911752456, 6890.621015844176, 6954.547954382949, 7131.750388736369, 7820.608969551614, 8016.1182269949095, 8017.559034253172, 8035.879461808195, 8044.034715834137, 6996.0, 7119.323664251888, 7145.892811607934, 7177.892331615134, 7244.492885497964, 6952.339527744856, 7002.75490357835, 7031.542949708252, 7063.1524217094275, 7086.539374940612, 6647.767328143492, 6782.525223234524, 6788.96605516989, 6796.864062718619, 6823.829404264933, 4862.100511405401, 4887.291342755351, 4888.437829649493, 4889.168841296917, 4932.802687892444, 4845.394325709286, 4846.95153048465, 4868.9756551217015, 4968.180250258812, 4981.975090124526], "value": 4323.420480658876, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.1618409507857, 290.1006878676103, 295.4829049163963, 295.70872690399966, 304.3578050313838, 4681.953180468198, 5155.613329000331, 5305.310309659749, 5328.227407025984, 5401.513863752267, 4970.055689419008, 5073.909109541448, 5200.815657423882, 5289.523942845143, 5300.549453296474, 3368.3768502826933, 4062.4718786557705, 4070.3894415837567, 4086.0, 4097.79511024449, 3401.9319613607713, 3900.8829735107993, 3983.362661974085, 4047.8785636430807, 4126.442930204414, 3616.62025487324, 3709.5733990591025, 3863.9374172102616, 4005.9584508027833, 4012.7993600320015, 3771.66055055045, 3866.6326698963553, 4024.0, 4033.2538480381077, 4050.9189816203807, 3536.9115772105647, 3568.1267334649256, 3580.2839432113683, 3587.3184095022125, 3588.8743893963588, 4996.850094497135, 5144.356955380577, 5158.638895277332, 5174.974125129388, 5184.7148406837605, 4616.714726210514, 4617.859855298396, 4627.375304333939, 4628.629709623222, 4640.934784021112, 5397.568194544467, 5594.524465420403, 5614.399896029653, 5644.74598643062, 5666.093425051972, 2986.22358186873, 3284.6058472983154, 3406.148462884279, 3701.2782507411066, 3912.726109172359, 5135.691858488465, 5320.8560159565495, 5591.189277554732, 5841.415858414167, 5861.85345366365, 3287.260366417585, 3348.5981682198294, 3575.785452872836, 3817.751846129993, 3992.1416895367634, 3542.4863394807608, 3958.2701703288967, 4040.575739547364, 4041.656459201001, 4089.44792453021, 3661.5972243053243, 3907.2612687353853, 4570.382998295254, 4622.583967442946, 4641.143542582926, 5630.971845140748, 5649.943500565024, 5652.85867853307, 5670.347909990322, 5691.800786972519, 1075.9031687148195, 1128.5316593613559, 1301.128244076471, 1307.4116647508658, 1321.2270821569489, 874.7200895713311, 898.6764764684708, 901.985266575103, 902.7517432705927, 912.9224015958717, 4818.8795280118275, 4865.854024379274, 4987.4015118185325, 4995.8534416434995, 4997.575206107449, 4860.3681521401995, 5309.601779866525, 5474.056709868019, 5481.451854814487, 5514.365847927459, 4444.799984000728, 4567.49272739995, 4625.32932724753, 4752.42970843501, 4818.7831547580445, 2841.6502161473136, 2862.6545523603872, 2880.5967164596973, 2882.394697113588, 2888.393437378174, 4578.381918441034, 5228.0, 5336.224079448872, 5547.33431988164, 5591.608587398924, 4939.96260785232, 5128.820491282787, 5368.8657783554945, 5448.155535891864, 6004.0693692477735, 5970.910436343458, 5973.804014852033, 5988.461038506555, 5997.940020599825, 6013.7293821779, 5261.447548007404, 5813.912791308218, 5876.882462350814, 5904.261967254093, 5911.280810827067, 5319.946800531945, 5463.098588732814, 5738.110592858114, 5796.8550786229, 5827.9417205829095, 4902.279235024595, 5561.916571251515, 5803.9419605803405, 5828.446297601721, 5999.1901093351835, 3460.653934606519, 3871.5934826842777, 3896.181801821536, 3916.118873253495, 3935.1736135411884, 5080.390353157497, 5131.0, 5450.618456708151, 5770.769169233352, 5831.017454065638, 5238.292830467838, 5528.889422211614, 5645.745941432563, 5650.463205995424, 5684.346300175533, 4814.903701925872, 4890.7554622268035, 5506.421825708243, 5674.858128546819, 5740.454656807597, 4751.61987041029, 5041.773120209526, 5430.429804870431, 5563.8887222256135, 5615.831525054255, 4596.6322694185055, 5535.8892822142525, 5541.340156724876, 5610.551155907599, 5622.184783206494, 4562.110388474216, 4594.058218065222, 5065.3161823153405, 5368.194770784414, 5462.098753705594], "value": 4025.9052633580764, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.5890911998081, 678.7760039187069, 727.9344858962693, 732.008128985225, 756.8524137793129, 12125.211861229025, 13925.791113133311, 14259.073160244578, 14880.925595372035, 14881.553553393389, 11064.557417703303, 13365.599032029031, 13463.461461541527, 13483.730325393486, 13522.459101635923, 630.7445484578753, 651.9282878883319, 778.4161878591043, 779.298631231893, 794.9841003179932, 800.8078061265284, 802.8273921106961, 803.4135081390559, 804.4730701390598, 807.4347956430493, 844.1263292492272, 848.2959143910571, 848.4018766769443, 850.5322072859933, 852.0967774159379, 839.4291881520563, 843.9535825529593, 844.9873251901226, 846.2214762418558, 846.2426128614886, 620.3734228429274, 626.6522080245462, 626.9686515674198, 627.1658693937098, 627.4353082225988, 13554.525591604246, 13572.660683482893, 13729.725405491934, 13754.93122534381, 14411.0, 13522.93238533801, 13747.0, 13878.722425551534, 13970.091944023607, 14000.238843995112, 11058.0, 11191.66425007251, 11310.151738619636, 11380.260283081574, 11430.828537571942, 3628.419452887552, 3641.7814931104217, 3673.9265214695556, 3693.2428852085527, 3779.981100094482, 56654.999999999585, 89595.0, 93099.06900930972, 95093.0, 95147.52426237894, 10584.841227381596, 10692.305000174963, 10711.46442677863, 10788.946055269831, 10848.132149428027, 16513.83486165123, 18184.727069105036, 19946.30187943415, 21051.157953681683, 21051.78948210498, 6435.903461447989, 7352.595607241653, 7642.0, 7717.421193410517, 7800.375969922393, 3991.7405368650943, 4082.693797965105, 4152.896177595584, 4208.22147902639, 4229.894252643709, 3373.493975903585, 3494.8602055917477, 3495.667911548399, 3502.439609662442, 3503.7197024238, 596.4870211618005, 599.0804115682406, 612.3906712820772, 614.5145335185153, 638.556203438615, 510.43596825508024, 604.1330690459117, 611.0254144639293, 643.8004218692221, 681.5501768832547, 8735.126487351214, 9196.816063678689, 9214.861777073214, 9227.492487913229, 9251.814963700688, 550.856777237913, 592.7315544734272, 646.1761254400677, 664.1863716946701, 664.2792570061538, 3411.846466908995, 4298.699091063596, 4300.8709738707885, 4314.482262128502, 4386.71486353386, 75483.24516754872, 100689.48276293004, 105588.0, 108446.0, 112523.0, 64268.10793514303, 67622.66188669026, 81250.0, 91701.5414922921, 100390.0, 60952.39047609467, 86050.56974715211, 86371.70442443369, 87164.0, 88555.0, 8266.462679925786, 8435.493870367798, 8472.110428405174, 8523.616437260462, 8525.317974561896, 55705.72147139238, 56777.432225677214, 56978.715106424206, 57817.0, 57917.84164316775, 79808.0, 81088.0, 81977.77033344386, 83170.16829831866, 83460.0, 11197.944010279896, 11265.662030139109, 11736.0, 11969.64091077269, 12243.69390765238, 12038.879611203776, 12365.134440589072, 12500.0, 12512.812307815208, 12527.49890004426, 11513.942430287794, 11606.419679015846, 11671.241369310968, 11708.306810948414, 11909.047276218054, 9861.457619830979, 11605.187636865676, 11983.22109062943, 12099.939500302442, 12248.693782655504, 10586.947065264625, 10816.56733730642, 11140.610078647533, 11363.715907102389, 11445.42772861337, 9661.13049825519, 9892.0, 9932.900670993487, 10606.681799546026, 11432.82850757256, 10703.598787105037, 10855.0, 10894.945525272324, 10918.50866710984, 10990.505427255634], "value": 7014.21372960447, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 118.0, 116.0, 120.0, 117.0, 117.0, 118.0, 118.0, 128.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 119.0, 151.0, 122.0, 126.0, 114.0, 117.0, 143.0, 119.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 199.0, 197.0, 199.0, 194.0, 193.0, 194.0, 198.0, 205.0, 197.0], "value": 196.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 62.0, 59.0, 61.0, 61.0, 82.0, 60.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 92.0, 70.0, 70.0, 70.0, 86.0, 70.0, 87.0, 122.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 90.0, 86.0, 119.0, 131.0, 105.0, 90.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 90.0, 149.0, 99.0, 101.0, 135.0, 135.0, 131.0, 139.0, 122.0], "value": 125.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 122.0, 131.0, 93.0, 131.0, 71.0, 97.0, 80.0, 135.0, 141.0], "value": 109.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 50.0, 48.0, 74.0, 95.0, 92.0, 94.0, 59.0, 61.0, 59.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 69.0, 90.0, 66.0, 104.0, 97.0, 95.0, 64.0, 97.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 85.0, 85.0, 89.0, 84.0, 84.0, 89.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 150.0, 157.0, 161.0, 184.0, 145.0, 162.0, 225.0, 173.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 166.0, 172.0, 183.0, 227.0, 174.0, 169.0, 168.0, 167.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 93.0, 94.0, 91.0, 91.0, 71.0, 67.0, 68.0, 66.0], "value": 78.0, "unit": "score"}]}]} 08:56:11 INFO - Return code: 0 08:56:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:56:11 ERROR - # TBPL SUCCESS # 08:56:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:56:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:56:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:56:11 INFO - 2016-11-21 08:56:11.543 screenresolution[1659:10790] starting screenresolution argv=screenresolution get 08:56:11 INFO - 2016-11-21 08:56:11.545 screenresolution[1659:10790] Display 0: 1600x1200x32@60 08:56:11 INFO - 2016-11-21 08:56:11.551 screenresolution[1660:10793] starting screenresolution argv=screenresolution list 08:56:11 INFO - Available Modes on Display 0 08:56:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:56:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:56:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:56:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:56:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:56:11 INFO - Intel Iris: 08:56:11 INFO - Chipset Model: Intel Iris 08:56:11 INFO - Type: GPU 08:56:11 INFO - Bus: Built-In 08:56:11 INFO - VRAM (Dynamic, Max): 1536 MB 08:56:11 INFO - Vendor: Intel (0x8086) 08:56:11 INFO - Device ID: 0x0a2e 08:56:11 INFO - Revision ID: 0x0009 08:56:11 INFO - Displays: 08:56:11 INFO - Display: 08:56:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:56:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:56:11 INFO - Display Serial Number: 1600x1200 60 08:56:11 INFO - Main Display: Yes 08:56:11 INFO - Mirror: Off 08:56:11 INFO - Online: Yes 08:56:11 INFO - Rotation: Supported 08:56:11 INFO - Return code: 0 08:56:11 INFO - Running post-action listener: _resource_record_post_action 08:56:11 INFO - [mozharness: 2016-11-21 16:56:11.791241Z] Finished run-tests step (success) 08:56:11 INFO - Running post-run listener: _resource_record_post_run 08:56:11 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38589440; Write bytes: 658645504; Read time: 436; Write time: 1942 08:56:11 INFO - TinderboxPrint: CPU usage
54.2% 08:56:11 INFO - TinderboxPrint: I/O read bytes / time
38,589,440 / 436 08:56:11 INFO - TinderboxPrint: I/O write bytes / time
658,645,504 / 1,942 08:56:11 INFO - TinderboxPrint: CPU idle
2,218.4 (45.8%) 08:56:11 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 08:56:11 INFO - TinderboxPrint: CPU user
1,644.0 (34.0%) 08:56:11 INFO - TinderboxPrint: Swap in / out
391,618,560 / 0 08:56:11 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 346409472; Write bytes: 349306368; Read time: 27691; Write time: 660 08:56:11 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37651456; Write bytes: 299856896; Read time: 366; Write time: 1257 08:56:12 INFO - Running post-run listener: _upload_blobber_files 08:56:12 INFO - Blob upload gear active. 08:56:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:56:12 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 08:56:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:56:12 INFO - (blobuploader) - INFO - Open directory for files ... 08:56:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:56:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:56:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:13 INFO - (blobuploader) - INFO - Done attempting. 08:56:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:56:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:56:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:14 INFO - (blobuploader) - INFO - Done attempting. 08:56:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:56:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:56:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:17 INFO - (blobuploader) - INFO - Done attempting. 08:56:17 INFO - (blobuploader) - INFO - Iteration through files over. 08:56:17 INFO - Return code: 0 08:56:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:56:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:56:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5187f9e219caa3136d8c277dcc84b02f0c0d1ff37b4d53cb273505c3811c093fe9d485df41902ecff25ae9623b241325f1b2a984e4be7035a8d4c1213feee9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb748751413befc1928e1407078ac226786764d7c45dffd622cb711fca61c0f4670148b1a1414f6c0dfceb85bd5eb32f67a2dc80430ca4863ff7ef2ed4263f22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/905b1ac78875be9d828a4ff3860a756a96142d39752eae9b87fb7486b830e77c025665715db3a313526819e83e548e73ed42f3cb55ceb8be14cda17a4c7dc06a"} 08:56:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:56:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:56:17 INFO - Contents: 08:56:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5187f9e219caa3136d8c277dcc84b02f0c0d1ff37b4d53cb273505c3811c093fe9d485df41902ecff25ae9623b241325f1b2a984e4be7035a8d4c1213feee9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb748751413befc1928e1407078ac226786764d7c45dffd622cb711fca61c0f4670148b1a1414f6c0dfceb85bd5eb32f67a2dc80430ca4863ff7ef2ed4263f22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/905b1ac78875be9d828a4ff3860a756a96142d39752eae9b87fb7486b830e77c025665715db3a313526819e83e548e73ed42f3cb55ceb8be14cda17a4c7dc06a"} 08:56:17 INFO - Running post-run listener: copy_logs_to_upload_dir 08:56:17 INFO - Copying logs to upload dir... 08:56:17 INFO - mkdir: /builds/slave/test/build/upload/logs 08:56:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.711875 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-21 08:56:17.432696) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 08:56:17.435808) ========= 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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5187f9e219caa3136d8c277dcc84b02f0c0d1ff37b4d53cb273505c3811c093fe9d485df41902ecff25ae9623b241325f1b2a984e4be7035a8d4c1213feee9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb748751413befc1928e1407078ac226786764d7c45dffd622cb711fca61c0f4670148b1a1414f6c0dfceb85bd5eb32f67a2dc80430ca4863ff7ef2ed4263f22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/905b1ac78875be9d828a4ff3860a756a96142d39752eae9b87fb7486b830e77c025665715db3a313526819e83e548e73ed42f3cb55ceb8be14cda17a4c7dc06a"} build_url:https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/IqTWc9X1S9ibI0iiJscB_w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5187f9e219caa3136d8c277dcc84b02f0c0d1ff37b4d53cb273505c3811c093fe9d485df41902ecff25ae9623b241325f1b2a984e4be7035a8d4c1213feee9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb748751413befc1928e1407078ac226786764d7c45dffd622cb711fca61c0f4670148b1a1414f6c0dfceb85bd5eb32f67a2dc80430ca4863ff7ef2ed4263f22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/905b1ac78875be9d828a4ff3860a756a96142d39752eae9b87fb7486b830e77c025665715db3a313526819e83e548e73ed42f3cb55ceb8be14cda17a4c7dc06a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 08:56:17.468850) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:56:17.469183) ========= 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.PKH4xmdmub/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.r2nu7ZCD9i/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.005095 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 08:56:17.517699) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 08:56:17.518146) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 08:56:17.518796) ========= ========= Total master_lag: 0.17 =========