builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0293 starttime: 1484164593.83 results: success (0) buildid: 20170111094025 builduid: 48a1b6d513a646c29ad02a3075704deb revision: 32d04f8230aa83e83ca6609d732c03f02c9926de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.825548) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.826117) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.826461) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.854090) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.854529) ========= 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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-11 11:56:33-- 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% 18.4M=0.001s 2017-01-11 11:56:33 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081029 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.960509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:33.960843) ========= 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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.024431 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:34.010436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 11:56:34.010798) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 32d04f8230aa83e83ca6609d732c03f02c9926de --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 32d04f8230aa83e83ca6609d732c03f02c9926de --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Zv5wkUChSK/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.T1XAkH4GMm/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-11 11:56:34,112 truncating revision to first 12 chars 2017-01-11 11:56:34,112 Setting DEBUG logging. 2017-01-11 11:56:34,112 attempt 1/10 2017-01-11 11:56:34,112 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/32d04f8230aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 11:56:35,308 unpacking tar archive at: mozilla-aurora-32d04f8230aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.449639 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 11:56:35.484515) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:35.484878) ========= script_repo_revision: 32d04f8230aa83e83ca6609d732c03f02c9926de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:35.485298) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:35.485691) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:35.506354) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-11 11:56:35.506756) ========= /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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:56:35 INFO - MultiFileLogger online at 20170111 11:56:35 in /builds/slave/test 11:56:35 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 11:56:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:56:35 INFO - {'append_to_log': False, 11:56:35 INFO - 'base_work_dir': '/builds/slave/test', 11:56:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:56:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:56:35 INFO - 'branch': 'Mozilla-Aurora', 11:56:35 INFO - 'buildbot_json_path': 'buildprops.json', 11:56:35 INFO - 'config_files': ('talos/mac_config.py',), 11:56:35 INFO - 'default_actions': ('clobber', 11:56:35 INFO - 'read-buildbot-config', 11:56:35 INFO - 'download-and-extract', 11:56:35 INFO - 'populate-webroot', 11:56:35 INFO - 'create-virtualenv', 11:56:35 INFO - 'install', 11:56:35 INFO - 'run-tests'), 11:56:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:56:35 INFO - 'download_minidump_stackwalk': True, 11:56:35 INFO - 'download_symbols': 'ondemand', 11:56:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:56:35 INFO - 'tooltool.py': '/tools/tooltool.py', 11:56:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:56:35 INFO - '/tools/misc-python/virtualenv.py')}, 11:56:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:56:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:56:35 INFO - 'installer_path': 'installer.exe', 11:56:35 INFO - 'log_level': 'info', 11:56:35 INFO - 'log_name': 'talos', 11:56:35 INFO - 'log_to_console': True, 11:56:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:56:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:56:35 INFO - 'opt_config_files': (), 11:56:35 INFO - 'pip_index': False, 11:56:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:35 INFO - 'cmd': ('bash', 11:56:35 INFO - '-c', 11:56:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:35 INFO - 'enabled': True, 11:56:35 INFO - 'halt_on_failure': False, 11:56:35 INFO - 'name': 'check_screen_resolution'},), 11:56:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:35 INFO - 'cmd': ('bash', 11:56:35 INFO - '-c', 11:56:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:35 INFO - 'enabled': True, 11:56:35 INFO - 'halt_on_failure': False, 11:56:35 INFO - 'name': 'check_screen_resolution'},), 11:56:35 INFO - 'run_cmd_checks_enabled': True, 11:56:35 INFO - 'sps_profile': False, 11:56:35 INFO - 'sps_profile_interval': 0, 11:56:35 INFO - 'suite': 'dromaeojs-e10s', 11:56:35 INFO - 'system_bits': '32', 11:56:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:56:35 INFO - 'title': 't-yosemite-r7-0293', 11:56:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:56:35 INFO - 'use_talos_json': True, 11:56:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:56:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:56:35 INFO - 'work_dir': 'build'} 11:56:35 INFO - [mozharness: 2017-01-11 19:56:35.714712Z] Running clobber step. 11:56:35 INFO - Running pre-action listener: _resource_record_pre_action 11:56:35 INFO - Running main action method: clobber 11:56:35 INFO - rmtree: /builds/slave/test/build 11:56:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:56:38 INFO - Running post-action listener: _resource_record_post_action 11:56:38 INFO - [mozharness: 2017-01-11 19:56:38.040790Z] Finished clobber step (success) 11:56:38 INFO - [mozharness: 2017-01-11 19:56:38.040940Z] Running read-buildbot-config step. 11:56:38 INFO - Running pre-action listener: _resource_record_pre_action 11:56:38 INFO - Running main action method: read_buildbot_config 11:56:38 INFO - Using buildbot properties: 11:56:38 INFO - { 11:56:38 INFO - "project": "", 11:56:38 INFO - "product": "firefox", 11:56:38 INFO - "script_repo_revision": "production", 11:56:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:56:38 INFO - "repository": "", 11:56:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:56:38 INFO - "buildid": "20170111094025", 11:56:38 INFO - "pgo_build": "False", 11:56:38 INFO - "basedir": "/builds/slave/test", 11:56:38 INFO - "buildnumber": 4, 11:56:38 INFO - "slavename": "t-yosemite-r7-0293", 11:56:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:56:38 INFO - "platform": "yosemite_r7", 11:56:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:56:38 INFO - "branch": "mozilla-aurora", 11:56:38 INFO - "repo_path": "releases/mozilla-aurora", 11:56:38 INFO - "revision": "32d04f8230aa83e83ca6609d732c03f02c9926de", 11:56:38 INFO - "stage_platform": "macosx64", 11:56:38 INFO - "builduid": "48a1b6d513a646c29ad02a3075704deb", 11:56:38 INFO - "slavebuilddir": "test" 11:56:38 INFO - } 11:56:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:56:38 INFO - Running post-action listener: _resource_record_post_action 11:56:38 INFO - [mozharness: 2017-01-11 19:56:38.044253Z] Finished read-buildbot-config step (success) 11:56:38 INFO - [mozharness: 2017-01-11 19:56:38.044383Z] Running download-and-extract step. 11:56:38 INFO - Running pre-action listener: _resource_record_pre_action 11:56:38 INFO - Running main action method: download_and_extract 11:56:38 INFO - mkdir: /builds/slave/test/build/tests 11:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:38 INFO - trying https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:56:38 INFO - Downloading https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/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 11:56:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/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 11:56:39 INFO - Downloaded 1199 bytes. 11:56:39 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:56:39 INFO - Using the following test package requirements: 11:56:39 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:56:39 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:56:39 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:56:39 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'jsshell-mac.zip'], 11:56:39 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:56:39 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:56:39 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:56:39 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:56:39 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:56:39 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:56:39 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:56:39 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:56:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:56:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:56:39 INFO - Fetch https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:56:39 INFO - Http code: 200 11:56:39 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 11:56:39 INFO - x-amz-cf-id: HmovHOpni1vAwjbGZANkvW8x-eKUtGNOsO8XYWzbiMatYvcvyI3WeQ== 11:56:39 INFO - x-amz-version-id: RBLXigTdx3D3OlOKMxMxT80mCK8bAdLA 11:56:40 INFO - Expected file size: 16272479 11:56:40 INFO - Obtained file size: 16272479 11:56:47 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 11:56:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:56:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:56:47 INFO - Fetch https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:56:47 INFO - Http code: 200 11:56:47 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 11:56:47 INFO - x-amz-cf-id: BrJwpoywm98mC4WJbFVAjj7pLMgA7n8OxsP8QPbKDADETfCAS2RzaQ== 11:56:47 INFO - x-amz-version-id: RBLXigTdx3D3OlOKMxMxT80mCK8bAdLA 11:56:47 INFO - Expected file size: 16272479 11:56:47 INFO - Obtained file size: 16272479 11:56:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:56:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:56:54 INFO - Fetch https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:56:55 INFO - Http code: 200 11:56:55 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 11:56:55 INFO - x-amz-cf-id: mzAejdhl7t_UtS4qSvXy2sVMMuEGfA_-AaNZl6lPZDUjYrAHhQLlkg== 11:56:55 INFO - x-amz-version-id: YPs4bfwhE3apDG2ATKcuRZB3feCf9aKL 11:56:55 INFO - Expected file size: 14409038 11:56:55 INFO - Obtained file size: 14409038 11:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:55 INFO - trying https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:56:55 INFO - Downloading https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:56:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/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 11:56:58 INFO - Downloaded 109959618 bytes. 11:56:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:56:58 INFO - mkdir: /builds/slave/test/properties 11:56:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:56:58 INFO - Writing to file /builds/slave/test/properties/build_url 11:56:58 INFO - Contents: 11:56:58 INFO - build_url:https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:56:59 INFO - Running post-action listener: _resource_record_post_action 11:56:59 INFO - Running post-action listener: set_extra_try_arguments 11:56:59 INFO - [mozharness: 2017-01-11 19:56:59.204924Z] Finished download-and-extract step (success) 11:56:59 INFO - [mozharness: 2017-01-11 19:56:59.205070Z] Running populate-webroot step. 11:56:59 INFO - Running pre-action listener: _resource_record_pre_action 11:56:59 INFO - Running main action method: populate_webroot 11:56:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:56:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:56:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:56:59 INFO - '--tppagecycles', 11:56:59 INFO - '7'], 11:56:59 INFO - 'tests': ['tsvgm']}}, 11:56:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:56:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:56:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:56:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:56:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['damp', 'tps']}, 11:56:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'tests': ['damp', 'tps']}, 11:56:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['dromaeo_dom']}, 11:56:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:56:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['basic_compositor_video']}, 11:56:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:56:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['a11yr', 11:56:59 INFO - 'ts_paint', 11:56:59 INFO - 'tpaint', 11:56:59 INFO - 'sessionrestore', 11:56:59 INFO - 'sessionrestore_no_auto_restore', 11:56:59 INFO - 'tabpaint']}, 11:56:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:56:59 INFO - 'ts_paint', 11:56:59 INFO - 'tpaint', 11:56:59 INFO - 'sessionrestore', 11:56:59 INFO - 'sessionrestore_no_auto_restore', 11:56:59 INFO - 'tabpaint']}, 11:56:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:56:59 INFO - 'ts_paint', 11:56:59 INFO - 'tpaint', 11:56:59 INFO - 'sessionrestore', 11:56:59 INFO - 'sessionrestore_no_auto_restore', 11:56:59 INFO - 'tabpaint']}, 11:56:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:56:59 INFO - 'ts_paint', 11:56:59 INFO - 'tpaint', 11:56:59 INFO - 'sessionrestore', 11:56:59 INFO - 'sessionrestore_no_auto_restore', 11:56:59 INFO - 'tabpaint']}, 11:56:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['a11yr', 11:56:59 INFO - 'ts_paint', 11:56:59 INFO - 'tpaint', 11:56:59 INFO - 'sessionrestore', 11:56:59 INFO - 'sessionrestore_no_auto_restore', 11:56:59 INFO - 'tabpaint']}, 11:56:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['a11yr', 11:56:59 INFO - 'ts_paint', 11:56:59 INFO - 'tpaint', 11:56:59 INFO - 'sessionrestore', 11:56:59 INFO - 'sessionrestore_no_auto_restore', 11:56:59 INFO - 'tabpaint']}, 11:56:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['tsvgx', 11:56:59 INFO - 'tsvgr_opacity', 11:56:59 INFO - 'tart', 11:56:59 INFO - 'tscrollx', 11:56:59 INFO - 'cart']}, 11:56:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:56:59 INFO - 'tsvgr_opacity', 11:56:59 INFO - 'tart', 11:56:59 INFO - 'tscrollx', 11:56:59 INFO - 'cart']}, 11:56:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'talos_options': ['--disable-e10s'], 11:56:59 INFO - 'tests': ['tp5o']}, 11:56:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'tests': ['tp5o']}, 11:56:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'talos_options': ['--disable-e10s', 11:56:59 INFO - '--xperf_path', 11:56:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:59 INFO - 'tests': ['tp5n']}, 11:56:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:59 INFO - 'talos_options': ['--xperf_path', 11:56:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:59 INFO - 'tests': ['tp5n']}}, 11:56:59 INFO - 'talos.zip': {'path': '', 11:56:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:56:59 INFO - Running post-action listener: _resource_record_post_action 11:56:59 INFO - [mozharness: 2017-01-11 19:56:59.224056Z] Finished populate-webroot step (success) 11:56:59 INFO - [mozharness: 2017-01-11 19:56:59.224166Z] Running create-virtualenv step. 11:56:59 INFO - Running pre-action listener: _resource_record_pre_action 11:56:59 INFO - Running main action method: create_virtualenv 11:56:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:56:59 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 11:56:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:56:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:56:59 INFO - Using real prefix '/tools/python27' 11:56:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:02 INFO - Installing pip.................done. 11:57:02 INFO - Return code: 0 11:57:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:57:03 INFO - Reading from file tmpfile_stdout 11:57:03 INFO - Output received: 11:57:03 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) 11:57:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57:03 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')]} 11:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:03 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')]} 11:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:03 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:03 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 11:57:03 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 11:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:03 INFO - 'HOME': '/Users/cltbld', 11:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:03 INFO - 'LOGNAME': 'cltbld', 11:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:03 INFO - 'MOZ_NO_REMOTE': '1', 11:57:03 INFO - 'NO_EM_RESTART': '1', 11:57:03 INFO - 'PAGER': '/bin/cat', 11:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:03 INFO - 'PWD': '/builds/slave/test', 11:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:03 INFO - 'SHELL': '/bin/bash', 11:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:03 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:03 INFO - 'USER': 'cltbld', 11:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:03 INFO - 'XPC_FLAGS': '0x0', 11:57:03 INFO - 'XPC_SERVICE_NAME': '0', 11:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:03 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 11:57:03 INFO - Cleaning up... 11:57:03 INFO - Return code: 0 11:57:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57:03 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')]} 11:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:03 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')]} 11:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:03 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:03 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 11:57:03 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 11:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:03 INFO - 'HOME': '/Users/cltbld', 11:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:03 INFO - 'LOGNAME': 'cltbld', 11:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:03 INFO - 'MOZ_NO_REMOTE': '1', 11:57:03 INFO - 'NO_EM_RESTART': '1', 11:57:03 INFO - 'PAGER': '/bin/cat', 11:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:03 INFO - 'PWD': '/builds/slave/test', 11:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:03 INFO - 'SHELL': '/bin/bash', 11:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:03 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:03 INFO - 'USER': 'cltbld', 11:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:03 INFO - 'XPC_FLAGS': '0x0', 11:57:03 INFO - 'XPC_SERVICE_NAME': '0', 11:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:03 INFO - Downloading/unpacking psutil>=3.1.1 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 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 11:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:07 INFO - Installing collected packages: psutil 11:57:07 INFO - Running setup.py install for psutil 11:57:07 INFO - building 'psutil._psutil_osx' extension 11:57:07 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 11:57:07 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 11:57:07 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 11:57:07 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 11:57:08 INFO - building 'psutil._psutil_posix' extension 11:57:08 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 11:57:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:57:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:57:08 INFO - ^ 11:57:08 INFO - 1 warning generated. 11:57:08 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 11:57:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:08 INFO - Successfully installed psutil 11:57:08 INFO - Cleaning up... 11:57:08 INFO - Return code: 0 11:57:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:08 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:08 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 11:57:08 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 11:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:08 INFO - 'HOME': '/Users/cltbld', 11:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:08 INFO - 'LOGNAME': 'cltbld', 11:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:08 INFO - 'MOZ_NO_REMOTE': '1', 11:57:08 INFO - 'NO_EM_RESTART': '1', 11:57:08 INFO - 'PAGER': '/bin/cat', 11:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:08 INFO - 'PWD': '/builds/slave/test', 11:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:08 INFO - 'SHELL': '/bin/bash', 11:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:08 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:08 INFO - 'USER': 'cltbld', 11:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:08 INFO - 'XPC_FLAGS': '0x0', 11:57:08 INFO - 'XPC_SERVICE_NAME': '0', 11:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:08 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 11:57:08 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 11:57:08 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 11:57:08 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 11:57:08 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 11:57:08 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 11:57:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:11 INFO - Installing collected packages: mozsystemmonitor 11:57:11 INFO - Running setup.py install for mozsystemmonitor 11:57:11 INFO - Successfully installed mozsystemmonitor 11:57:11 INFO - Cleaning up... 11:57:11 INFO - Return code: 0 11:57:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:11 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:11 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 11:57:11 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 11:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:11 INFO - 'HOME': '/Users/cltbld', 11:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:11 INFO - 'LOGNAME': 'cltbld', 11:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:11 INFO - 'MOZ_NO_REMOTE': '1', 11:57:11 INFO - 'NO_EM_RESTART': '1', 11:57:11 INFO - 'PAGER': '/bin/cat', 11:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:11 INFO - 'PWD': '/builds/slave/test', 11:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:11 INFO - 'SHELL': '/bin/bash', 11:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:11 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:11 INFO - 'USER': 'cltbld', 11:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:11 INFO - 'XPC_FLAGS': '0x0', 11:57:11 INFO - 'XPC_SERVICE_NAME': '0', 11:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:11 INFO - Downloading/unpacking jsonschema==2.5.1 11:57:11 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 11:57:11 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 11:57:11 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 11:57:11 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 11:57:11 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 11:57:11 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 11:57:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:57:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:57:14 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 11:57:14 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 11:57:14 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 11:57:14 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 11:57:14 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 11:57:14 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 11:57:14 INFO - Downloading functools32-3.2.3-2.tar.gz 11:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:57:15 INFO - warning: no files found matching '*.txt' 11:57:15 INFO - no previously-included directories found matching 'build' 11:57:15 INFO - no previously-included directories found matching 'dist' 11:57:15 INFO - no previously-included directories found matching '.git*' 11:57:15 INFO - Installing collected packages: jsonschema, functools32 11:57:15 INFO - Running setup.py install for functools32 11:57:15 INFO - warning: no files found matching '*.txt' 11:57:15 INFO - no previously-included directories found matching 'build' 11:57:15 INFO - no previously-included directories found matching 'dist' 11:57:15 INFO - no previously-included directories found matching '.git*' 11:57:15 INFO - Successfully installed jsonschema functools32 11:57:15 INFO - Cleaning up... 11:57:15 INFO - Return code: 0 11:57:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:15 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:15 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 11:57:15 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 11:57:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:15 INFO - 'HOME': '/Users/cltbld', 11:57:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:15 INFO - 'LOGNAME': 'cltbld', 11:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:15 INFO - 'MOZ_NO_REMOTE': '1', 11:57:15 INFO - 'NO_EM_RESTART': '1', 11:57:15 INFO - 'PAGER': '/bin/cat', 11:57:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:15 INFO - 'PWD': '/builds/slave/test', 11:57:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:15 INFO - 'SHELL': '/bin/bash', 11:57:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:15 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:15 INFO - 'USER': 'cltbld', 11:57:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:15 INFO - 'XPC_FLAGS': '0x0', 11:57:15 INFO - 'XPC_SERVICE_NAME': '0', 11:57:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:57:15 INFO - Cleaning up... 11:57:15 INFO - Return code: 0 11:57:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:15 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:15 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 11:57:15 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 11:57:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:15 INFO - 'HOME': '/Users/cltbld', 11:57:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:15 INFO - 'LOGNAME': 'cltbld', 11:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:15 INFO - 'MOZ_NO_REMOTE': '1', 11:57:15 INFO - 'NO_EM_RESTART': '1', 11:57:15 INFO - 'PAGER': '/bin/cat', 11:57:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:15 INFO - 'PWD': '/builds/slave/test', 11:57:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:15 INFO - 'SHELL': '/bin/bash', 11:57:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:15 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:15 INFO - 'USER': 'cltbld', 11:57:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:15 INFO - 'XPC_FLAGS': '0x0', 11:57:15 INFO - 'XPC_SERVICE_NAME': '0', 11:57:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:15 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:15 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 11:57:15 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 11:57:15 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 11:57:15 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 11:57:15 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 11:57:15 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 11:57:18 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:18 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 11:57:18 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 11:57:18 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 11:57:18 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 11:57:18 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 11:57:18 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 11:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:19 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 11:57:19 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 11:57:19 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 11:57:19 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 11:57:19 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 11:57:19 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 11:57:19 INFO - Downloading docopt-0.6.1.tar.gz 11:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:19 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:19 INFO - Running setup.py install for blobuploader 11:57:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:19 INFO - Running setup.py install for requests 11:57:20 INFO - Running setup.py install for docopt 11:57:20 INFO - Successfully installed blobuploader requests docopt 11:57:20 INFO - Cleaning up... 11:57:20 INFO - Return code: 0 11:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:20 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')]} 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:20 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')]} 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:20 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:20 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 11:57:20 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 11:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:20 INFO - 'HOME': '/Users/cltbld', 11:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:20 INFO - 'LOGNAME': 'cltbld', 11:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:20 INFO - 'MOZ_NO_REMOTE': '1', 11:57:20 INFO - 'NO_EM_RESTART': '1', 11:57:20 INFO - 'PAGER': '/bin/cat', 11:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:20 INFO - 'PWD': '/builds/slave/test', 11:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:20 INFO - 'SHELL': '/bin/bash', 11:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:20 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:20 INFO - 'USER': 'cltbld', 11:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:20 INFO - 'XPC_FLAGS': '0x0', 11:57:20 INFO - 'XPC_SERVICE_NAME': '0', 11:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:20 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-CzB4a6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-uP8Yw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-_tQTer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-gGIKRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-LCLBAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-fadT4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-NkfFnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-j47npH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-d7kqDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-pJnwVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:21 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-fAXwPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:22 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-gh2F9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:22 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-EkAOL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:22 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-nxqNZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:22 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-bAW5fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:22 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-hyIYzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:22 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-MaXT3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:22 INFO - Running setup.py install for manifestparser 11:57:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:22 INFO - Running setup.py install for mozcrash 11:57:22 INFO - Running setup.py install for mozdebug 11:57:23 INFO - Running setup.py install for mozdevice 11:57:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Running setup.py install for mozfile 11:57:23 INFO - Running setup.py install for mozhttpd 11:57:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Running setup.py install for mozinfo 11:57:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Running setup.py install for mozInstall 11:57:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:23 INFO - Running setup.py install for mozleak 11:57:23 INFO - Running setup.py install for mozlog 11:57:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:24 INFO - Running setup.py install for moznetwork 11:57:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:24 INFO - Running setup.py install for mozprocess 11:57:24 INFO - Running setup.py install for mozprofile 11:57:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:24 INFO - Running setup.py install for mozrunner 11:57:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:24 INFO - Running setup.py install for mozscreenshot 11:57:24 INFO - Running setup.py install for moztest 11:57:25 INFO - Running setup.py install for mozversion 11:57:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:25 INFO - Cleaning up... 11:57:25 INFO - Return code: 0 11:57:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:25 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')]} 11:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:25 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')]} 11:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:25 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:25 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 11:57:25 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 11:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:25 INFO - 'HOME': '/Users/cltbld', 11:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:25 INFO - 'LOGNAME': 'cltbld', 11:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:25 INFO - 'MOZ_NO_REMOTE': '1', 11:57:25 INFO - 'NO_EM_RESTART': '1', 11:57:25 INFO - 'PAGER': '/bin/cat', 11:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:25 INFO - 'PWD': '/builds/slave/test', 11:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:25 INFO - 'SHELL': '/bin/bash', 11:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:25 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:25 INFO - 'USER': 'cltbld', 11:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:25 INFO - 'XPC_FLAGS': '0x0', 11:57:25 INFO - 'XPC_SERVICE_NAME': '0', 11:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:25 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-PM3fI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:25 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)) 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:25 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-SKJXRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:25 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)) 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:25 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-8EzBY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:25 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)) 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:25 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-khjhFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:25 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)) 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:25 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-uGJHZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9PHIKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-oE0Jrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-MteThm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-KpP6iH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-yDPgxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-3AHxqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-hyx8eR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-_O7sIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:26 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)) 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:26 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-iNkzza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:27 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)) 11:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:27 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-iCgrnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:27 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)) 11:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:27 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-mte22F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:27 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-kXX3X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:27 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)) 11:57:27 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)) 11:57:27 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)) 11:57:27 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)) 11:57:27 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)) 11:57:27 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)) 11:57:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:30 INFO - Downloading blessings-1.6.tar.gz 11:57:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:57:30 INFO - Installing collected packages: blessings 11:57:30 INFO - Running setup.py install for blessings 11:57:30 INFO - Successfully installed blessings 11:57:30 INFO - Cleaning up... 11:57:30 INFO - Return code: 0 11:57:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:57:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:30 INFO - Reading from file tmpfile_stdout 11:57:30 INFO - Current package versions: 11:57:30 INFO - blessings == 1.6 11:57:30 INFO - blobuploader == 1.2.4 11:57:30 INFO - docopt == 0.6.1 11:57:30 INFO - functools32 == 3.2.3-2 11:57:30 INFO - jsonschema == 2.5.1 11:57:30 INFO - manifestparser == 1.1 11:57:30 INFO - mozInstall == 1.12 11:57:30 INFO - mozcrash == 1.0 11:57:30 INFO - mozdebug == 0.1 11:57:30 INFO - mozdevice == 0.48 11:57:30 INFO - mozfile == 1.2 11:57:30 INFO - mozhttpd == 0.7 11:57:30 INFO - mozinfo == 0.9 11:57:30 INFO - mozleak == 0.1 11:57:30 INFO - mozlog == 3.4 11:57:30 INFO - moznetwork == 0.27 11:57:30 INFO - mozprocess == 0.23 11:57:30 INFO - mozprofile == 0.28 11:57:30 INFO - mozrunner == 6.13 11:57:30 INFO - mozscreenshot == 0.1 11:57:30 INFO - mozsystemmonitor == 0.3 11:57:30 INFO - moztest == 0.8 11:57:30 INFO - mozversion == 1.4 11:57:30 INFO - psutil == 3.1.1 11:57:30 INFO - requests == 1.2.3 11:57:30 INFO - wsgiref == 0.1.2 11:57:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:31 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:31 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 11:57:31 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 11:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:31 INFO - 'HOME': '/Users/cltbld', 11:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:31 INFO - 'LOGNAME': 'cltbld', 11:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:31 INFO - 'MOZ_NO_REMOTE': '1', 11:57:31 INFO - 'NO_EM_RESTART': '1', 11:57:31 INFO - 'PAGER': '/bin/cat', 11:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:31 INFO - 'PWD': '/builds/slave/test', 11:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:31 INFO - 'SHELL': '/bin/bash', 11:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:31 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:31 INFO - 'USER': 'cltbld', 11:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:31 INFO - 'XPC_FLAGS': '0x0', 11:57:31 INFO - 'XPC_SERVICE_NAME': '0', 11:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 INFO - Cleaning up... 11:57:31 INFO - Return code: 0 11:57:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:57:31 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')]} 11:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:31 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')]} 11:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:31 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 0x104461490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043d7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd89857bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, '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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 11:57:31 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 11:57:31 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 11:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:31 INFO - 'HOME': '/Users/cltbld', 11:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:31 INFO - 'LOGNAME': 'cltbld', 11:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:31 INFO - 'MOZ_NO_REMOTE': '1', 11:57:31 INFO - 'NO_EM_RESTART': '1', 11:57:31 INFO - 'PAGER': '/bin/cat', 11:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:31 INFO - 'PWD': '/builds/slave/test', 11:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:31 INFO - 'SHELL': '/bin/bash', 11:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:57:31 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:31 INFO - 'USER': 'cltbld', 11:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:31 INFO - 'XPC_FLAGS': '0x0', 11:57:31 INFO - 'XPC_SERVICE_NAME': '0', 11:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:57:31 INFO - Cleaning up... 11:57:31 INFO - Return code: 0 11:57:31 INFO - Running post-action listener: _resource_record_post_action 11:57:31 INFO - Running post-action listener: _start_resource_monitoring 11:57:31 INFO - Starting resource monitoring. 11:57:31 INFO - [mozharness: 2017-01-11 19:57:31.922784Z] Finished create-virtualenv step (success) 11:57:31 INFO - [mozharness: 2017-01-11 19:57:31.923734Z] Running install step. 11:57:31 INFO - Running pre-action listener: _resource_record_pre_action 11:57:31 INFO - Running main action method: install 11:57:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:32 INFO - Reading from file tmpfile_stdout 11:57:32 INFO - Detecting whether we're running mozinstall >=1.0... 11:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:57:32 INFO - Reading from file tmpfile_stdout 11:57:32 INFO - Output received: 11:57:32 INFO - Usage: mozinstall [options] installer 11:57:32 INFO - Options: 11:57:32 INFO - -h, --help show this help message and exit 11:57:32 INFO - -d DEST, --destination=DEST 11:57:32 INFO - Directory to install application into. [default: 11:57:32 INFO - "/builds/slave/test"] 11:57:32 INFO - --app=APP Application being installed. [default: firefox] 11:57:32 INFO - mkdir: /builds/slave/test/build/application 11:57:32 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'] 11:57:32 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 11:58:03 INFO - Reading from file tmpfile_stdout 11:58:03 INFO - Output received: 11:58:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:58:03 INFO - Running post-action listener: _resource_record_post_action 11:58:03 INFO - [mozharness: 2017-01-11 19:58:03.981353Z] Finished install step (success) 11:58:03 INFO - [mozharness: 2017-01-11 19:58:03.981487Z] Running run-tests step. 11:58:03 INFO - Running pre-action listener: _resource_record_pre_action 11:58:03 INFO - Running main action method: run_tests 11:58:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:58:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:58:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:58:03 INFO - 2017-01-11 11:58:03.996 screenresolution[1627:7693] starting screenresolution argv=screenresolution get 11:58:03 INFO - 2017-01-11 11:58:03.998 screenresolution[1627:7693] Display 0: 1600x1200x32@60 11:58:04 INFO - 2017-01-11 11:58:04.004 screenresolution[1628:7696] starting screenresolution argv=screenresolution list 11:58:04 INFO - Available Modes on Display 0 11:58:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:58:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:58:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:58:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:58:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:58:04 INFO - Intel Iris: 11:58:04 INFO - Chipset Model: Intel Iris 11:58:04 INFO - Type: GPU 11:58:04 INFO - Bus: Built-In 11:58:04 INFO - VRAM (Dynamic, Max): 1536 MB 11:58:04 INFO - Vendor: Intel (0x8086) 11:58:04 INFO - Device ID: 0x0a2e 11:58:04 INFO - Revision ID: 0x0009 11:58:04 INFO - Displays: 11:58:04 INFO - Display: 11:58:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:58:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:58:04 INFO - Display Serial Number: 1600x1200 60 11:58:04 INFO - Main Display: Yes 11:58:04 INFO - Mirror: Off 11:58:04 INFO - Online: Yes 11:58:04 INFO - Rotation: Supported 11:58:04 INFO - Return code: 0 11:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:58:04 INFO - Python 2.7.3 11:58:04 INFO - Return code: 0 11:58:04 INFO - grabbing minidump binary from tooltool 11:58:04 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')]} 11:58:04 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 0x104275d30>, '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 0x1040dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10426db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:58:04 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 11:58:04 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 11:58:04 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 11:58:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:58:04 INFO - Return code: 0 11:58:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:58:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:58:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:58:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:58:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:58:04 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/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 11:58:04 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/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0293 --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 11:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zv5wkUChSK/Render', 11:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:04 INFO - 'HOME': '/Users/cltbld', 11:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:04 INFO - 'LOGNAME': 'cltbld', 11:58:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:58:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:04 INFO - 'MOZ_NO_REMOTE': '1', 11:58:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:58:04 INFO - 'NO_EM_RESTART': '1', 11:58:04 INFO - 'PAGER': '/bin/cat', 11:58:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:04 INFO - 'PWD': '/builds/slave/test', 11:58:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:04 INFO - 'SHELL': '/bin/bash', 11:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T1XAkH4GMm/Listeners', 11:58:04 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 11:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:04 INFO - 'USER': 'cltbld', 11:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:04 INFO - 'XPC_FLAGS': '0x0', 11:58:04 INFO - 'XPC_SERVICE_NAME': '0', 11:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:04 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/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 11:58:04 INFO - mozversion application_buildid: 20170111094025 11:58:04 INFO - mozversion application_changeset: 32d04f8230aa83e83ca6609d732c03f02c9926de 11:58:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:58:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:58:04 INFO - mozversion application_name: Firefox 11:58:04 INFO - mozversion application_remotingname: firefox-dev 11:58:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:58:04 INFO - mozversion application_vendor: Mozilla 11:58:04 INFO - mozversion application_version: 52.0a2 11:58:04 INFO - mozversion platform_buildid: 20170111094025 11:58:04 INFO - mozversion platform_changeset: 32d04f8230aa83e83ca6609d732c03f02c9926de 11:58:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:58:04 INFO - mozversion platform_version: 52.0a2 11:58:04 INFO - using testdate: 1484164684 11:58:04 INFO - actual date: 1484164684 11:58:04 INFO - starting webserver on 'localhost:49249' 11:58:04 INFO - SUITE-START | Running 2 tests 11:58:04 INFO - TEST-START | dromaeo_css 11:58:04 INFO - operating with platform_type : mac_ 11:58:04 INFO - Initialising browser for dromaeo_css test... 11:58:04 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpGU56DC/profile http://localhost:49249/getInfo.html) 11:58:07 INFO - PROCESS | 1638 | 2017-01-11 11:58:07.468 plugin-container[1640:7876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4937, name = 'com.apple.tsm.portname' 11:58:07 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:07 INFO - PROCESS | 1638 | 2017-01-11 11:58:07.470 plugin-container[1640:7876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a0b, name = 'com.apple.CFPasteboardClient' 11:58:07 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:07 INFO - PROCESS | 1638 | 2017-01-11 11:58:07.470 plugin-container[1640:7876] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:07 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 11:58:07 INFO - PROCESS | 1638 | colorDepth:24 11:58:07 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 11:58:07 INFO - PROCESS | 1638 | __metrics 11:58:07 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 11:58:08 INFO - TEST-INFO | 1638: exit 0 11:58:08 INFO - Browser initialized. 11:58:08 INFO - Running cycle 1/1 for dromaeo_css test... 11:58:08 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpGU56DC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:11 INFO - PROCESS | 1641 | 2017-01-11 11:58:11.048 plugin-container[1642:8005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 11:58:11 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:11 INFO - PROCESS | 1641 | 2017-01-11 11:58:11.049 plugin-container[1642:8005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 11:58:11 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:11 INFO - PROCESS | 1641 | 2017-01-11 11:58:11.049 plugin-container[1642:8005] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:20 INFO - PROCESS | 1641 | RSS: Main: 229347328 11:58:20 INFO - PROCESS | 1641 | 12:01:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 12:01:26 INFO - PROCESS | 1641 | RSS: Main: 234926080 12:01:26 INFO - PROCESS | 1641 | 12:04:28 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 12:04:29 INFO - PROCESS | 1641 | RSS: Main: 243990528 12:04:29 INFO - PROCESS | 1641 | 12:07:31 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 12:07:31 INFO - PROCESS | 1641 | RSS: Main: 250949632 12:07:31 INFO - PROCESS | 1641 | 12:10:33 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 12:10:34 INFO - PROCESS | 1641 | RSS: Main: 250433536 12:10:34 INFO - PROCESS | 1641 | 12:13:36 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 12:13:37 INFO - PROCESS | 1641 | RSS: Main: 253530112 12:13:37 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 12:16:40 INFO - PROCESS | 1641 | RSS: Main: 254922752 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | __start_tp_report 12:16:40 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 12:16:40 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 12:16:40 INFO - PROCESS | 1641 | |i|pagename|runs| 12:16:40 INFO - PROCESS | 1641 | |0;dojo.html;2754.5454999925014;2924.312786495172;3026.5460180972836;3317.668233176682;3376.172837654774;5075.517825806552;5547.639403438774;5621.409751976045;5636.15457681348;5661.2923384576925;5708.457696518834;5744.942550574493;5756.769729210827;5781.508571771405;5783.363829978699;4249.745015299077;5176.974115129419;5239.842804715846;5282.5245727884485;5283.682979021271;5163.328767260251;5230.293910322115;5266.025785229725;5282.947170528303;5362.3029006229135;5262.684238945656;5266.447023062581;5273.182656688219;5284.2866213061125;5322.121849894763;5065.265536497206;5105.82129625465;5153.922691159635;5203.635745497817;5306.469353064682;5092.668976516515;5146.639735218536;5159.690418574897;5161.561267292284;5164.767585458663;4907.312976183337;5460.798624302647;5679.09134538476;5721.427857214287;5859.355470898197;5924.140999555084;6148.6925653717135;6168.321484636686;6191.842682435583;6261.248650161964;6263.655498947556;6290.811275661737;6311.368863113697;6321.209848768904;6323.798478289111;5682.829515114553;5745.6552606843725;5819.912701309484;5852.882942341214;5857.912131318034;6245.5628106033055;6255.78104766331;6277.654728989949;6291.339409362043;6300.3699630037545;6036.486898613579;6176.258848938156;6178.938210617836;6184.567080304336;6187.938120618826;6121.788327166048;6188.690565471708;6207.503399728011;6219.937800622027;6239.313675495692;5204.0372531081875;5322.654027488278;5791.247137872134;6248.843778905472;6281.309056003836;6179.28938172107;6193.256809182927;6251.687415629201;6284.528660350401;6305.5901366411035;4796.35249241348;4843.903121937541;4851.369321988119;4872.926906096412;4900.191468407744;4392.978035109804;4435.223835828717;4439.889002774892;4441.622462090694;4461.7992190351515;4769.926766477515;5646.745896434671;5705.62913410627;5779.595428320062;5805.44848239425;6184.103305020837;6275.121482992385;6292.748290068437;6298.590591611623;6303.36966303366;5730.255066841284;5861.912071318934;5865.794697185578;5879.176915231871;5903.881922361528;5366.91949620756;5406.459354064641;5410.269613602192;5417.322834645669;5434.673919564839;5873.530117590582;6193.535484838745;6258.718357673824;6367.299597044229;6369.585976911486;6196.876062478635;6242.157308763441;6251;6305;6319.557630965788;6341.873162536631;6349.904751428825;6360.745570177326;6381.1704478417505;6386.265579458422;5071.264666623393;6044.969775151096;6207.037909124093;6210.068489726577;6250.218722659668;5049.924251136163;6018.3981601841215;6110.710646019097;6196.659183744938;6276.717547710456;6082.239720034996;6141.662208578571;6146.108814222002;6147.938520614736;6159.784407545714;5144.6109550421925;6097.969510152421;6123.632582044914;6141.416565426277;6158.815235542941;5628.127640215773;5693.373728889902;5836.037053886231;5907.438793314628;5913.793017244376;5959.314678811992;6027.18632984559;6031.36670649593;6081.939180608138;6125.601746185103;5877.735501902339;5910.202122713551;5913.674747888907;5967.104934259895;5969.432903874125 12:16:40 INFO - PROCESS | 1641 | |1;ext.html;493.8271604938272;515.6158661796961;518.5074179529447;518.7665550502275;569.848905211473;30719.846400767994;39278.83966381848;39327.213455730904;39394.803025984904;39444.80277598608;39526.8023659882;39691.40462893059;39797.005074873145;39900;39955.80022099893;7944.642491087886;9238.260939124853;9331;9373.578188981479;9433.245340372754;8611.052784193733;8746.562671866413;9221.631134754602;9274.39716418434;9275.86086208704;8031.598420079002;9218.492982885939;9230.86153707695;9333.766655833591;9375.968643449216;9157;9338.766530836749;9352.953235233781;9353;9357.970623231438;9168.954155229181;9179.219766319871;9245.306602004877;9325.533723313874;9326.90673093274;37428.812855935816;37582.06044848873;37796.81101594502;37850;37863.674771382866;3248.772585918987;3444.3111377724304;3566.8751593694096;3699.352613292663;3834.194819087995;27761.305967350774;28362.212141493725;28815.85592072047;28903.56644650332;29539.966101186572;3130.2174456385906;3147.213196700825;3149.165471150147;3175.4125486784787;3218.050675050842;484428;488330;491750;495524.5223773894;496084;56103.438965610636;56593.43406565882;56610;56753.43246567564;56826.43173568211;56802.14796778052;57477.42522574721;57784.71107644435;57906.420935790105;57963.71018144882;15755.369785208462;15940.963837350768;15948.043117412992;15956.042637441791;15974.840251597569;12381.62855114348;12415.317157556421;12469.625911222678;12481.56314528987;12490;14168.433262669378;15057.849421505645;15164.09015459076;15230.390784368723;15324.616884577752;6212.9378706213265;6293.590916590407;6349.301576826545;6484.578502397329;6524.380183882524;869.3523325122824;959.3428501476511;976.5019839881711;1022.2895087913829;1077.7844431113808;29394.412111757643;29412.852935735613;29688.257793555335;29866.954656586917;30090.69909300923;844.9915500844913;926.9490178040138;965.0108638645445;965.8699321793518;983.7294743945472;14542.636434089021;15083.4720784772;15227.543173704807;15229.238538073274;15321.726267815255;603032;607311;611231;612388;617295;54966.725166373915;55438.61403464946;55591.72204139034;55632;55760.72119639376;1323.1135139456587;1345.3004437692346;1359.5173713331624;1359.8232229810062;1366.2622184020518;28227.43545129045;28866.711332886403;28899.566506502;29542;30279;47321.29018064663;47574.76212618915;47583.76208118937;47667.523324767695;47706.28440573464;557112;557795;558219;560270;563160;25169.748302516742;25186.74813251844;25604.743952560235;25649.871750641127;25872.83572239216;36584.81707591445;45040.0991980152;45603.77198113988;45792.77103614461;45877;37476.625233748404;37761;37975.620243798316;38897.41653875138;38998.610013899495;31456.685433145376;37616.81191594134;38204;38260.617393825705;38545.807270963465;29534.704652954057;36455.27089458249;37150.44274335941;37411.06472338214;37563.24873502569;26758.59862102109;27024;27363.452730945668;27458.313542161606;33647.831760841036;22219.888900555394;23079.307620771404;24169.690203739407;25409.49181016406;25736.8713156433 12:16:40 INFO - PROCESS | 1641 | |2;jquery.html;369.26700499508473;371.0852747976238;377.7053897959591;381.0987015707852;393.6201565489304;18711.906440467796;19532.414027579176;19604;20236.99999999998;20301.289454869082;11441.542338306457;11492.655220343382;11863.93224609785;11873.584424545143;11875.584354547593;4820.68665536739;5408.810691625794;5411.215373770801;5451.209574611679;5470.863228419282;3912.2371137628174;4144.544100148981;4743;5402.837914862561;5448.101063324547;6080.4831589314745;6162.661053642048;6185.288691800433;6192.318844927055;6198.78304259351;6140.877182456348;6266.968665156668;6572.737090516373;6620.073189753391;6621.73513059477;4848.660593758438;5854;5857.8870014697495;5868.325142608612;5883.205767221413;12892.484300627964;13219.405126769318;13235.338233088307;13315.933420332836;13353.666158346023;2045.3454894433787;2202.735671719388;2355.582161029642;2419.020296779807;2590.4948535035674;9553.235741140692;9835.409875407498;14675.706485870223;14802.593753593392;15056.021358611655;1506.239349128694;1786.937457189005;1870.681984062699;1895.0051223107841;1910.9904450477798;113349;114990;119297.40351298275;121709.99999999911;122133;11389.487473063731;11596.536138554533;11643.47604357789;11666.766664666658;11740.823887641693;14577.562673119823;14592.781108283385;14594.905382096385;14608.415663373346;14615.926920365542;8284.295834853985;8326.45878017927;8350.08149103598;8429.873551896726;8553.82892342162;8287.50274983503;8294.958525207336;8306.293965012921;8408.747737567883;8469.364797640079;4403.735775853459;4445.799939002688;4448.065906159678;4460.416552124014;4479.126570318822;1571.2929181868205;1673.4561267588083;1749.2915369275463;1884.2557189909908;1979.059946525389;798.8961435013412;893.5308962794585;928.4614923344444;950.7052813627797;971.4074414607084;19216.03919804004;19881.61420629505;19883.900580497004;19961.900190498956;20276.290329838386;917.4724533393326;921.3320342751585;932.883389576303;950.6340059077166;965.4062751407834;8838.8067610873;8910.59902304385;9965.651202208033;10496.317739346918;10520.473976301155;477526;535667;547382;553733;561025;97445;117681;123419.76580234262;128700;129577.35211323883;931.8462453695064;950.078423928788;952.3761935931975;954.8758661373977;958.7219706285239;13943.16341019542;16607.833921661113;17769.555761106076;17934.5516362092;18146.81853181451;44690.106197876514;45746.08507829758;48563.27155092606;49386.75306623588;49581.752091239316;69070.65464672644;69231.61536769338;73690.26309737048;74752.25247747453;76724;6632;6871.8661420865;7067.116610423697;7088.220295767358;7228.891566626399;11829.585964491202;11855.466504007169;11941.701457463632;12065.396730163282;12135.453904574139;9747.561359738971;10251;10264.281500294908;10454.895451045697;10555.525001374803;9925.156361709336;10134.543945522617;10214.182865370902;10267.229957753048;10304.381737095799;8667.74317723939;10485.475726213812;10515.94742026285;10594.682159535227;10699.786004280026;7677.846443071219;7703.3067023968115;7861.960690196513;7946.165652606393;7955.681772729026;9531.428114313163;10436.217283703905;10440.895591044296;10514.57941682324;10668.1998850085 12:16:40 INFO - PROCESS | 1641 | |3;mootools.html;1469.5664778890223;1503.9473618423356;1670.582354411397;1806.7470554122417;1822.5808064145237;7454.5899975501325;7657;7942.285194332509;8429.030661473924;8442.535660538675;7870.331021863149;8141.877871831927;8180.590970451483;8250.216229458192;8304.95847520763;2057.177129148338;2232.5646498932715;2294.6328587425974;2338.707661542307;2341.531693661266;2321.3268152235833;2341.672165896776;2357;2363.196513185517;2364.657124716915;1939.2921583622017;2298.069281940817;2320.0255892525097;2340.2511196417154;2341.683872677184;2290.1297506947335;2304.7119110111234;2314.884255787204;2324.128451830555;2327.290176496168;2335.544568809083;2343.8359314847967;2349.3186975777003;2351.4003928997995;2352.6941497605203;8197.877031844528;8218.794530136734;8238.876416853753;8273.46222495542;8281.95859020707;4707.435107787053;5228.895422091537;5237.947620523784;5240.868978275574;5249.790008399659;3276.9836150819333;3322.302316513535;3560.8041557714314;3568.589612194579;3573.4997100405963;1030.20674080958;1048.9527971241312;1073.7906108308882;1077.504347999921;1091.7106966653844;10828.891711082946;10856.945715271373;10861.511231994658;10902.909709029034;10969.012788848962;4521.977390113094;4607.239805432133;4677.345171675968;4694.342792009122;4745.193317136095;9275.76810579741;9282.303827212985;9294;9301.627934882665;9301.674441394518;5110.4123025852505;5188.870278243074;5214.0875346814155;5217.478252174752;5233.764480598382;3621.5292012038794;3825.732198746061;4248;4426;4681.578657920735;3225.3226822367565;3764.322421964018;3792.677622402127;3840.9039774005314;3870.9806450968126;1279.4114707234776;1280.5198050730976;1292.0890772005714;1297.8442586889635;1300.9349532523336;630.4200135874914;812.8780682897612;894.4990805149138;896.4038914122036;903.1194585279432;5526;5591.776328946878;5609.354924183689;5617.859553511195;5633.380328163898;771.7800426878313;777.2810150610662;917.8210249001514;920.9355345125776;988.6885631026204;3741.064733816546;3799.981000094982;3838.3091043612417;3851.2297540492013;3871.806409679561;9577.425354478753;9874.40753554789;9880.95059524712;9904.653337133166;9922.255830812717;9944.1547468466;9945.701628951143;9962.800743985079;9983.351082179635;9989.900100999042;6549.7882891665195;7333.889991650023;7749.573773442402;7791.80520486981;7807.414443916729;5605.131204663284;5678;5703.08750599902;5803.593748437569;5822.243108395882;8306.6677332906;8954.597043133204;9164.129407706258;9298.39560428597;9378;9322.766930826783;9392.436453812794;9486.28852836054;9523.999980002;9538.475383853676;6529.9020514691365;6750.797476075725;6816.216135144522;6901.827454313682;7087.255838136922;7625.161232264336;7778.222177782177;7799.06411230656;7801.258880406353;7820;5917.378675239038;6930.306969303029;7095.538789978635;7097.751578694721;7230.710771569078;6979.232284448808;7011.439084873095;7052.153741551047;7066.8233294166;7093.751718689821;6494.4804415645685;6554.115194448879;6632.071509988702;6640.76757313511;6650.434713049445;4804.0872234275375;4809.014152098881;4844.346013288161;4849.054434385261;4880.585150262125;3895;3952.6047395260243;4404.119176164844;4810.975945120252;4896.608271338213 12:16:40 INFO - PROCESS | 1641 | |4;prototype.html;302.101248784865;307.6999925073054;307.9491883839167;310.8274907426379;314.10793346894815;4650.744299039257;4928.531789479994;5062.886324910177;5467.863303417412;5521.503064724174;5160.664556803805;5181.637285390024;5377.865553361168;5401.837944861651;5545.805896793613;3889.5721470638277;3899.883003509885;3999.9600004000026;4058.3303754880408;4132.566080561543;4102.8974275643195;4121.340585506319;4121.505419349683;4128.772917489552;4131.194417088669;3390.983045084771;4102.261592913274;4104.692148088905;4105.958940410587;4138.255114079465;3782.9054273643105;3903.551091624471;3917.9608203917883;4139.068709540345;4142.9378559321485;2924.268932766808;2941.2058744138963;3526.8589256429714;3528.435450327935;3532.7703699259464;4577.9771101144615;4994.650374473788;5294.311739473844;5294.629375943686;5317.835887646844;4096.877093687194;4108.50697916249;4269.758376852146;4679.60223381013;4688.437387513521;4951.3810773653295;5435.8856434430845;5530.198121272433;5754.712264386764;5796.855078623068;3456.4469684850305;3905.4337121117564;3914.628110329543;3916.941245881314;3935.60643935607;5856.472917437409;6067.18093057436;6082.878342433127;6108.847278818021;6136.631802091889;3911.5110611173604;3917.3144699678014;3937.645611894943;3953.703472239536;4005.91692319992;3262.972163768405;3997.2205419942834;4010.7593544386846;4025.993501624594;4035.6973227007506;3912.5109361329833;4697.8658780273145;4739.3601863748645;4740.075685241413;4760.761961901961;5650.26546548946;5784.4960310318775;5785.82642520725;5795.710214489259;5808.738606762706;1074.4198133008244;1216.2702378572785;1218.597862705305;1303.4590644882458;1308.7632187582642;758.5676164586243;776.9650365733555;807.3783186946137;867.9671191282354;885.1765363351517;4903.78801884766;5252.632315737938;5384.892302154013;5419.810306639249;5456.372517160498;5206.791728330824;5254.185601231815;5405.432424596902;5913.7876735269165;5962.493188078975;5065.442801291855;5408.9729551352775;5429.891402171935;5429.972850135724;5432.239486471897;2558.6801649793774;2612.843229406242;2653.7478939500716;2817.3097591090313;2917.4602698500844;5093.821716239988;5325.520703136736;5474.45802266296;5879.529637629065;5896.7641294348605;5239.768654366221;5461.798404351036;5759.769609215585;5834.883302333929;5876;4925.532074452921;5022.422421421583;5613.691246981373;5904.911426328695;6017.578769486094;5100.954304367521;5286.392064912584;5621.5221706155435;5656.406077361818;5656.88686226265;4575.58819706228;5099.69401835891;5340.706261155518;5548.3619383769765;5601.355844077983;4804.711717296974;5442.068145721183;5481.5340696041285;5886.381929897299;5910.527157827278;3352.664733526628;3574.338747331752;3717.7025837933443;3723.7579557328686;3743.350233522294;4630.606398456169;4892.920508783824;5110.540051395392;5415.4313797050745;5466.890662186814;4735;4829.589484893823;5174.094533456705;5473.698946557977;5512.944870551403;4361.078077687894;4494.55054494561;4582;5116.846494605168;5122.487751224997;4693;5016.2224855147515;5030.496950304941;5175.741212939413;5296.867578310573;5005.068993243019;5081.949180508148;5554.333479982266;5739.512141468022;5752.913706294493;4550.907782132331;5535.39110697831;5613.915791263216;5632.662040277596;5638.718064096696 12:16:40 INFO - PROCESS | 1641 | |5;yui.html;669.2270427656059;700.5551474813493;747.0475144191153;759.195253031786;792.4809249941287;13781.96635252355;14552.63618409539;14629.853701462982;14633.341499632543;14685.559433217008;13097.607071787837;13115.6721081973;13125.54060607879;13132.474701011948;13217.801732973989;642.4635429416443;667.8096742428409;786.1352512236551;805.5851236613149;824.1881746479713;820.6430202861774;822.4777266435797;822.7737372222626;823.983520329593;823.9988414076926;866.2680035370103;867.6182479708908;868.1231955724701;868.50929224988;868.5396739727922;854.2141230068352;860.8837806896114;862.4782006885838;863.4991704811226;864.1185990289932;624.6283461340495;633.6736580660963;640.5228105061713;642.1715986377613;642.7332656947361;12984.740305193938;13368.598942031755;13526.391312390966;13535.458581656721;14176.716465670628;13455.865441345559;13655.248961307125;13828.101173423796;13843.377048032762;13888.791668124986;9446.763830904214;11004.669859904216;11169.273997190157;11400.258983166068;11456.656300311004;3164.6202455705234;3577.0878426001464;3607.2785442911522;3620.2035552178477;3628.600853906068;73189.63405182993;78362.6081869587;87610.80972975663;91433.54283228541;91856.16287674209;10389.116925061304;10498.685039448828;10523.631672891412;10684.604274841155;10839.13286937043;19316.034198290312;20180.89909550443;20795.064222110042;20974;20975.475613109793;6696.933030669728;6713.160854893138;6799.490038247052;7053.400460960773;7481.476296659291;3370.79775213488;4058.904095894151;4164.375343698409;4179.770112643773;4316.395704601358;3401.0817079388426;3492.301539692072;3497.860085596548;3511.8595256189465;3526;557.6152454806171;565.9830205093854;621.0776996160698;636.3223167326782;647.7344288841647;510.2721946154387;607.9392060793886;633.240111865763;666.8766278238542;670.9194896612438;7235.131784185932;7838.804029899298;8239.546824924566;8825.426347287406;9167.358284919992;603.1856993059334;617.4443001298866;635.4146404720252;657.1752450674373;672.9125213722283;4253.21315556623;4414.27164517851;4420.138073075784;4420.889477763018;4481.551844815493;66762;98333.01666983239;109793;110370;111840;65741.35646608874;77014.61492692612;89467.55266223627;96856;100438.49780751196;54108.188377174374;57145;81160;83150;88669.55665221633;9425.481598512148;9479.383840050376;9678.75803104928;9732.562034708313;9834.704958851245;37937.24125517419;40446.1910761789;43143;51117.48882511276;52426.47573524216;50352.49647503625;70151.59696806138;73712;74310.62844685742;74643;11720.941395292968;11945.761084778429;12067.517299307929;12108;12265.754684906073;13234.272115033766;13250.801237981244;13301.201927884358;13400.06199566021;13461.326933653083;12716.173448725804;12833.935830320788;12974.156679815782;13029.804552931524;13083;12774.936125319315;12787.552435664707;12876.935615321863;12894.097413180987;13171.538996135088;12394.81407778866;12417.006639469002;12501.562445314734;12524.060695447695;12613.92781613573;10500.947495262475;10669.733256668645;10866.891331086905;11052.668419947415;11218.495167717309;10603.522841471999;10924.50839712199;11186.664400068012;11198.664040078811;11201 12:16:40 INFO - PROCESS | 1641 | __end_tp_report 12:16:40 INFO - PROCESS | 1641 | __start_cc_report 12:16:40 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,382 12:16:40 INFO - PROCESS | 1641 | __end_cc_report 12:16:40 INFO - PROCESS | 1641 | __startTimestamp1484165800323__endTimestamp 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | ------- Summary: start ------- 12:16:40 INFO - PROCESS | 1641 | Number of tests: 6 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5683.54 Median:5873.53 stddev:691.58 (11.8%) stddev-sans-first:654.42 12:16:40 INFO - PROCESS | 1641 | Values: 2754.5 2924.3 3026.5 3317.7 3376.2 5075.5 5547.6 5621.4 5636.2 5661.3 5708.5 5744.9 5756.8 5781.5 5783.4 4249.7 5177.0 5239.8 5282.5 5283.7 5163.3 5230.3 5266.0 5282.9 5362.3 5262.7 5266.4 5273.2 5284.3 5322.1 5065.3 5105.8 5153.9 5203.6 5306.5 5092.7 5146.6 5159.7 5161.6 5164.8 4907.3 5460.8 5679.1 5721.4 5859.4 5924.1 6148.7 6168.3 6191.8 6261.2 6263.7 6290.8 6311.4 6321.2 6323.8 5682.8 5745.7 5819.9 5852.9 5857.9 6245.6 6255.8 6277.7 6291.3 6300.4 6036.5 6176.3 6178.9 6184.6 6187.9 6121.8 6188.7 6207.5 6219.9 6239.3 5204.0 5322.7 5791.2 6248.8 6281.3 6179.3 6193.3 6251.7 6284.5 6305.6 4796.4 4843.9 4851.4 4872.9 4900.2 4393.0 4435.2 4439.9 4441.6 4461.8 4769.9 5646.7 5705.6 5779.6 5805.4 6184.1 6275.1 6292.7 6298.6 6303.4 5730.3 5861.9 5865.8 5879.2 5903.9 5366.9 5406.5 5410.3 5417.3 5434.7 5873.5 6193.5 6258.7 6367.3 6369.6 6196.9 6242.2 6251.0 6305.0 6319.6 6341.9 6349.9 6360.7 6381.2 6386.3 5071.3 6045.0 6207.0 6210.1 6250.2 5049.9 6018.4 6110.7 6196.7 6276.7 6082.2 6141.7 6146.1 6147.9 6159.8 5144.6 6098.0 6123.6 6141.4 6158.8 5628.1 5693.4 5836.0 5907.4 5913.8 5959.3 6027.2 6031.4 6081.9 6125.6 5877.7 5910.2 5913.7 5967.1 5969.4 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:67615.62 Median:39361.01 stddev:148575.17 (377.5%) stddev-sans-first:148906.49 12:16:40 INFO - PROCESS | 1641 | Values: 493.8 515.6 518.5 518.8 569.8 30719.8 39278.8 39327.2 39394.8 39444.8 39526.8 39691.4 39797.0 39900.0 39955.8 7944.6 9238.3 9331.0 9373.6 9433.2 8611.1 8746.6 9221.6 9274.4 9275.9 8031.6 9218.5 9230.9 9333.8 9376.0 9157.0 9338.8 9353.0 9353.0 9358.0 9169.0 9179.2 9245.3 9325.5 9326.9 37428.8 37582.1 37796.8 37850.0 37863.7 3248.8 3444.3 3566.9 3699.4 3834.2 27761.3 28362.2 28815.9 28903.6 29540.0 3130.2 3147.2 3149.2 3175.4 3218.1 484428.0 488330.0 491750.0 495524.5 496084.0 56103.4 56593.4 56610.0 56753.4 56826.4 56802.1 57477.4 57784.7 57906.4 57963.7 15755.4 15941.0 15948.0 15956.0 15974.8 12381.6 12415.3 12469.6 12481.6 12490.0 14168.4 15057.8 15164.1 15230.4 15324.6 6212.9 6293.6 6349.3 6484.6 6524.4 869.4 959.3 976.5 1022.3 1077.8 29394.4 29412.9 29688.3 29867.0 30090.7 845.0 926.9 965.0 965.9 983.7 14542.6 15083.5 15227.5 15229.2 15321.7 603032.0 607311.0 611231.0 612388.0 617295.0 54966.7 55438.6 55591.7 55632.0 55760.7 1323.1 1345.3 1359.5 1359.8 1366.3 28227.4 28866.7 28899.6 29542.0 30279.0 47321.3 47574.8 47583.8 47667.5 47706.3 557112.0 557795.0 558219.0 560270.0 563160.0 25169.7 25186.7 25604.7 25649.9 25872.8 36584.8 45040.1 45603.8 45792.8 45877.0 37476.6 37761.0 37975.6 38897.4 38998.6 31456.7 37616.8 38204.0 38260.6 38545.8 29534.7 36455.3 37150.4 37411.1 37563.2 26758.6 27024.0 27363.5 27458.3 33647.8 22219.9 23079.3 24169.7 25409.5 25736.9 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:31926.05 Median:2269.08 stddev:89919.10 (3962.8%) stddev-sans-first:90140.12 12:16:40 INFO - PROCESS | 1641 | Values: 369.3 371.1 377.7 381.1 393.6 18711.9 19532.4 19604.0 20237.0 20301.3 11441.5 11492.7 11863.9 11873.6 11875.6 4820.7 5408.8 5411.2 5451.2 5470.9 3912.2 4144.5 4743.0 5402.8 5448.1 6080.5 6162.7 6185.3 6192.3 6198.8 6140.9 6267.0 6572.7 6620.1 6621.7 4848.7 5854.0 5857.9 5868.3 5883.2 12892.5 13219.4 13235.3 13315.9 13353.7 2045.3 2202.7 2355.6 2419.0 2590.5 9553.2 9835.4 14675.7 14802.6 15056.0 1506.2 1786.9 1870.7 1895.0 1911.0 113349.0 114990.0 119297.4 121710.0 122133.0 11389.5 11596.5 11643.5 11666.8 11740.8 14577.6 14592.8 14594.9 14608.4 14615.9 8284.3 8326.5 8350.1 8429.9 8553.8 8287.5 8295.0 8306.3 8408.7 8469.4 4403.7 4445.8 4448.1 4460.4 4479.1 1571.3 1673.5 1749.3 1884.3 1979.1 798.9 893.5 928.5 950.7 971.4 19216.0 19881.6 19883.9 19961.9 20276.3 917.5 921.3 932.9 950.6 965.4 8838.8 8910.6 9965.7 10496.3 10520.5 477526.0 535667.0 547382.0 553733.0 561025.0 97445.0 117681.0 123419.8 128700.0 129577.4 931.8 950.1 952.4 954.9 958.7 13943.2 16607.8 17769.6 17934.6 18146.8 44690.1 45746.1 48563.3 49386.8 49581.8 69070.7 69231.6 73690.3 74752.3 76724.0 6632.0 6871.9 7067.1 7088.2 7228.9 11829.6 11855.5 11941.7 12065.4 12135.5 9747.6 10251.0 10264.3 10454.9 10555.5 9925.2 10134.5 10214.2 10267.2 10304.4 8667.7 10485.5 10515.9 10594.7 10699.8 7677.8 7703.3 7862.0 7946.2 7955.7 9531.4 10436.2 10440.9 10514.6 10668.2 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5367.33 Median:5558.89 stddev:2937.30 (52.8%) stddev-sans-first:2930.93 12:16:40 INFO - PROCESS | 1641 | Values: 1469.6 1503.9 1670.6 1806.7 1822.6 7454.6 7657.0 7942.3 8429.0 8442.5 7870.3 8141.9 8180.6 8250.2 8305.0 2057.2 2232.6 2294.6 2338.7 2341.5 2321.3 2341.7 2357.0 2363.2 2364.7 1939.3 2298.1 2320.0 2340.3 2341.7 2290.1 2304.7 2314.9 2324.1 2327.3 2335.5 2343.8 2349.3 2351.4 2352.7 8197.9 8218.8 8238.9 8273.5 8282.0 4707.4 5228.9 5237.9 5240.9 5249.8 3277.0 3322.3 3560.8 3568.6 3573.5 1030.2 1049.0 1073.8 1077.5 1091.7 10828.9 10856.9 10861.5 10902.9 10969.0 4522.0 4607.2 4677.3 4694.3 4745.2 9275.8 9282.3 9294.0 9301.6 9301.7 5110.4 5188.9 5214.1 5217.5 5233.8 3621.5 3825.7 4248.0 4426.0 4681.6 3225.3 3764.3 3792.7 3840.9 3871.0 1279.4 1280.5 1292.1 1297.8 1300.9 630.4 812.9 894.5 896.4 903.1 5526.0 5591.8 5609.4 5617.9 5633.4 771.8 777.3 917.8 920.9 988.7 3741.1 3800.0 3838.3 3851.2 3871.8 9577.4 9874.4 9881.0 9904.7 9922.3 9944.2 9945.7 9962.8 9983.4 9989.9 6549.8 7333.9 7749.6 7791.8 7807.4 5605.1 5678.0 5703.1 5803.6 5822.2 8306.7 8954.6 9164.1 9298.4 9378.0 9322.8 9392.4 9486.3 9524.0 9538.5 6529.9 6750.8 6816.2 6901.8 7087.3 7625.2 7778.2 7799.1 7801.3 7820.0 5917.4 6930.3 7095.5 7097.8 7230.7 6979.2 7011.4 7052.2 7066.8 7093.8 6494.5 6554.1 6632.1 6640.8 6650.4 4804.1 4809.0 4844.3 4849.1 4880.6 3895.0 3952.6 4404.1 4811.0 4896.6 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4482.83 Median:5026.46 stddev:1391.03 (27.7%) stddev-sans-first:1359.08 12:16:40 INFO - PROCESS | 1641 | Values: 302.1 307.7 307.9 310.8 314.1 4650.7 4928.5 5062.9 5467.9 5521.5 5160.7 5181.6 5377.9 5401.8 5545.8 3889.6 3899.9 4000.0 4058.3 4132.6 4102.9 4121.3 4121.5 4128.8 4131.2 3391.0 4102.3 4104.7 4106.0 4138.3 3782.9 3903.6 3918.0 4139.1 4142.9 2924.3 2941.2 3526.9 3528.4 3532.8 4578.0 4994.7 5294.3 5294.6 5317.8 4096.9 4108.5 4269.8 4679.6 4688.4 4951.4 5435.9 5530.2 5754.7 5796.9 3456.4 3905.4 3914.6 3916.9 3935.6 5856.5 6067.2 6082.9 6108.8 6136.6 3911.5 3917.3 3937.6 3953.7 4005.9 3263.0 3997.2 4010.8 4026.0 4035.7 3912.5 4697.9 4739.4 4740.1 4760.8 5650.3 5784.5 5785.8 5795.7 5808.7 1074.4 1216.3 1218.6 1303.5 1308.8 758.6 777.0 807.4 868.0 885.2 4903.8 5252.6 5384.9 5419.8 5456.4 5206.8 5254.2 5405.4 5913.8 5962.5 5065.4 5409.0 5429.9 5430.0 5432.2 2558.7 2612.8 2653.7 2817.3 2917.5 5093.8 5325.5 5474.5 5879.5 5896.8 5239.8 5461.8 5759.8 5834.9 5876.0 4925.5 5022.4 5613.7 5904.9 6017.6 5101.0 5286.4 5621.5 5656.4 5656.9 4575.6 5099.7 5340.7 5548.4 5601.4 4804.7 5442.1 5481.5 5886.4 5910.5 3352.7 3574.3 3717.7 3723.8 3743.4 4630.6 4892.9 5110.5 5415.4 5466.9 4735.0 4829.6 5174.1 5473.7 5512.9 4361.1 4494.6 4582.0 5116.8 5122.5 4693.0 5016.2 5030.5 5175.7 5296.9 5005.1 5081.9 5554.3 5739.5 5752.9 4550.9 5535.4 5613.9 5632.7 5638.7 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19039.74 Median:23729.70 stddev:27442.33 (115.6%) stddev-sans-first:27484.65 12:16:40 INFO - PROCESS | 1641 | Values: 669.2 700.6 747.0 759.2 792.5 13782.0 14552.6 14629.9 14633.3 14685.6 13097.6 13115.7 13125.5 13132.5 13217.8 642.5 667.8 786.1 805.6 824.2 820.6 822.5 822.8 824.0 824.0 866.3 867.6 868.1 868.5 868.5 854.2 860.9 862.5 863.5 864.1 624.6 633.7 640.5 642.2 642.7 12984.7 13368.6 13526.4 13535.5 14176.7 13455.9 13655.2 13828.1 13843.4 13888.8 9446.8 11004.7 11169.3 11400.3 11456.7 3164.6 3577.1 3607.3 3620.2 3628.6 73189.6 78362.6 87610.8 91433.5 91856.2 10389.1 10498.7 10523.6 10684.6 10839.1 19316.0 20180.9 20795.1 20974.0 20975.5 6696.9 6713.2 6799.5 7053.4 7481.5 3370.8 4058.9 4164.4 4179.8 4316.4 3401.1 3492.3 3497.9 3511.9 3526.0 557.6 566.0 621.1 636.3 647.7 510.3 607.9 633.2 666.9 670.9 7235.1 7838.8 8239.5 8825.4 9167.4 603.2 617.4 635.4 657.2 672.9 4253.2 4414.3 4420.1 4420.9 4481.6 66762.0 98333.0 109793.0 110370.0 111840.0 65741.4 77014.6 89467.6 96856.0 100438.5 54108.2 57145.0 81160.0 83150.0 88669.6 9425.5 9479.4 9678.8 9732.6 9834.7 37937.2 40446.2 43143.0 51117.5 52426.5 50352.5 70151.6 73712.0 74310.6 74643.0 11720.9 11945.8 12067.5 12108.0 12265.8 13234.3 13250.8 13301.2 13400.1 13461.3 12716.2 12833.9 12974.2 13029.8 13083.0 12774.9 12787.6 12876.9 12894.1 13171.5 12394.8 12417.0 12501.6 12524.1 12613.9 10500.9 10669.7 10866.9 11052.7 11218.5 10603.5 10924.5 11186.7 11198.7 11201.0 12:16:40 INFO - PROCESS | 1641 | -------- Summary: end -------- 12:16:40 INFO - PROCESS | 1641 | 12:16:40 INFO - TEST-INFO | 1641: exit 0 12:16:40 INFO - TEST-OK | dromaeo_css | took 1116361ms 12:16:40 INFO - TEST-START | kraken 12:16:40 INFO - operating with platform_type : mac_ 12:16:40 INFO - Initialising browser for kraken test... 12:16:40 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpPuNze8/profile http://localhost:49249/getInfo.html) 12:16:43 INFO - PROCESS | 1650 | 2017-01-11 12:16:43.825 plugin-container[1651:10339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 12:16:43 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:43 INFO - PROCESS | 1650 | 2017-01-11 12:16:43.828 plugin-container[1651:10339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 12:16:43 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:43 INFO - PROCESS | 1650 | 2017-01-11 12:16:43.828 plugin-container[1651:10339] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:16:43 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 12:16:43 INFO - PROCESS | 1650 | colorDepth:24 12:16:43 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 12:16:43 INFO - PROCESS | 1650 | __metrics 12:16:44 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 12:16:44 INFO - TEST-INFO | 1650: exit 0 12:16:44 INFO - Browser initialized. 12:16:44 INFO - Running cycle 1/1 for kraken test... 12:16:44 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpPuNze8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:47 INFO - PROCESS | 1652 | 2017-01-11 12:16:47.503 plugin-container[1653:10467] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853b, name = 'com.apple.tsm.portname' 12:16:47 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:47 INFO - PROCESS | 1652 | 2017-01-11 12:16:47.503 plugin-container[1653:10467] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 12:16:47 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:47 INFO - PROCESS | 1652 | 2017-01-11 12:16:47.503 plugin-container[1653:10467] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:16:57 INFO - PROCESS | 1652 | RSS: Main: 228970496 12:16:57 INFO - PROCESS | 1652 | 12:17:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 12:17:01 INFO - PROCESS | 1652 | RSS: Main: 241176576 12:17:01 INFO - PROCESS | 1652 | 12:17:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 12:17:04 INFO - PROCESS | 1652 | RSS: Main: 249237504 12:17:04 INFO - PROCESS | 1652 | 12:17:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 12:17:08 INFO - PROCESS | 1652 | RSS: Main: 252903424 12:17:08 INFO - PROCESS | 1652 | 12:17:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 12:17:10 INFO - PROCESS | 1652 | RSS: Main: 254312448 12:17:10 INFO - PROCESS | 1652 | 12:17:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:13 INFO - PROCESS | 1652 | RSS: Main: 254316544 12:17:13 INFO - PROCESS | 1652 | 12:17:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:16 INFO - PROCESS | 1652 | RSS: Main: 258310144 12:17:16 INFO - PROCESS | 1652 | 12:17:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:20 INFO - PROCESS | 1652 | RSS: Main: 257855488 12:17:20 INFO - PROCESS | 1652 | 12:17:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 12:17:23 INFO - PROCESS | 1652 | RSS: Main: 257929216 12:17:23 INFO - PROCESS | 1652 | 12:17:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:26 INFO - PROCESS | 1652 | RSS: Main: 257097728 12:17:26 INFO - PROCESS | 1652 | 12:17:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:28 INFO - PROCESS | 1652 | RSS: Main: 257134592 12:17:28 INFO - PROCESS | 1652 | 12:17:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:17:31 INFO - PROCESS | 1652 | RSS: Main: 257007616 12:17:31 INFO - PROCESS | 1652 | 12:17:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:35 INFO - PROCESS | 1652 | RSS: Main: 257073152 12:17:35 INFO - PROCESS | 1652 | 12:17:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:17:38 INFO - PROCESS | 1652 | RSS: Main: 257495040 12:17:38 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 12:17:40 INFO - PROCESS | 1652 | RSS: Main: 259268608 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | __start_tp_report 12:17:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 12:17:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 12:17:40 INFO - PROCESS | 1652 | |i|pagename|runs| 12:17:40 INFO - PROCESS | 1652 | |0;ai-astar;107;119;118;118;118;118;118;118;117;120 12:17:40 INFO - PROCESS | 1652 | |1;audio-beat-detection;144;111;115;142;116;144;138;117;140;117 12:17:40 INFO - PROCESS | 1652 | |2;audio-dft;196;199;201;199;196;196;194;195;196;194 12:17:40 INFO - PROCESS | 1652 | |3;audio-fft;79;61;59;81;64;84;50;68;105;84 12:17:40 INFO - PROCESS | 1652 | |4;audio-oscillator;83;70;94;74;72;80;124;69;70;90 12:17:40 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;91;89;90;88;96;133;114;123;91;90 12:17:40 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;125;154;150;96;97;97;105;96;97 12:17:40 INFO - PROCESS | 1652 | |7;imaging-desaturate;95;134;84;86;94;90;91;93;88;87 12:17:40 INFO - PROCESS | 1652 | |8;json-parse-financial;57;56;56;58;58;59;60;59;60;61 12:17:40 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;71;71;79;85;75;74;70;70;74;91 12:17:40 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;86;84;84;85;83;86;84;88;85;86 12:17:40 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;137;158;150;153;142;142;150;143;200;154 12:17:40 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;165;166;166;167;197;231;164;167;181;232 12:17:40 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;67;68;67;68;66;66;70;68;80 12:17:40 INFO - PROCESS | 1652 | __end_tp_report 12:17:40 INFO - PROCESS | 1652 | __start_cc_report 12:17:40 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1002 12:17:40 INFO - PROCESS | 1652 | __end_cc_report 12:17:40 INFO - PROCESS | 1652 | __startTimestamp1484165860748__endTimestamp 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | ------- Summary: start ------- 12:17:40 INFO - PROCESS | 1652 | Number of tests: 14 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:117.10 Median:118.00 stddev:3.63 (3.1%) stddev-sans-first:0.83 12:17:40 INFO - PROCESS | 1652 | Values: 107.0 119.0 118.0 118.0 118.0 118.0 118.0 118.0 117.0 120.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:139.00 stddev:14.12 (10.2%) stddev-sans-first:13.80 12:17:40 INFO - PROCESS | 1652 | Values: 144.0 111.0 115.0 142.0 116.0 144.0 138.0 117.0 140.0 117.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:196.60 Median:196.00 stddev:2.32 (1.2%) stddev-sans-first:2.45 12:17:40 INFO - PROCESS | 1652 | Values: 196.0 199.0 201.0 199.0 196.0 196.0 194.0 195.0 196.0 194.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:73.50 Median:73.50 stddev:16.12 (21.9%) stddev-sans-first:16.97 12:17:40 INFO - PROCESS | 1652 | Values: 79.0 61.0 59.0 81.0 64.0 84.0 50.0 68.0 105.0 84.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:77.00 stddev:16.98 (22.0%) stddev-sans-first:18.01 12:17:40 INFO - PROCESS | 1652 | Values: 83.0 70.0 94.0 74.0 72.0 80.0 124.0 69.0 70.0 90.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:100.50 Median:90.00 stddev:16.51 (18.3%) stddev-sans-first:17.15 12:17:40 INFO - PROCESS | 1652 | Values: 91.0 89.0 90.0 88.0 96.0 133.0 114.0 123.0 91.0 90.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:96.50 stddev:23.18 (24.0%) stddev-sans-first:23.99 12:17:40 INFO - PROCESS | 1652 | Values: 97.0 125.0 154.0 150.0 96.0 97.0 97.0 105.0 96.0 97.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:90.50 stddev:14.44 (16.0%) stddev-sans-first:15.31 12:17:40 INFO - PROCESS | 1652 | Values: 95.0 134.0 84.0 86.0 94.0 90.0 91.0 93.0 88.0 87.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:59.00 stddev:1.71 (2.9%) stddev-sans-first:1.74 12:17:40 INFO - PROCESS | 1652 | Values: 57.0 56.0 56.0 58.0 58.0 59.0 60.0 59.0 60.0 61.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:74.50 stddev:7.04 (9.4%) stddev-sans-first:7.23 12:17:40 INFO - PROCESS | 1652 | Values: 71.0 71.0 79.0 85.0 75.0 74.0 70.0 70.0 74.0 91.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.50 stddev:1.45 (1.7%) stddev-sans-first:1.50 12:17:40 INFO - PROCESS | 1652 | Values: 86.0 84.0 84.0 85.0 83.0 86.0 84.0 88.0 85.0 86.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:152.90 Median:151.50 stddev:17.80 (11.7%) stddev-sans-first:17.92 12:17:40 INFO - PROCESS | 1652 | Values: 137.0 158.0 150.0 153.0 142.0 142.0 150.0 143.0 200.0 154.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:174.00 stddev:27.24 (15.7%) stddev-sans-first:28.04 12:17:40 INFO - PROCESS | 1652 | Values: 165.0 166.0 166.0 167.0 197.0 231.0 164.0 167.0 181.0 232.0 12:17:40 INFO - PROCESS | 1652 | 12:17:40 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.00 stddev:4.20 (6.2%) stddev-sans-first:4.34 12:17:40 INFO - PROCESS | 1652 | Values: 66.0 67.0 68.0 67.0 68.0 66.0 66.0 70.0 68.0 80.0 12:17:40 INFO - PROCESS | 1652 | -------- Summary: end -------- 12:17:40 INFO - PROCESS | 1652 | 12:17:41 INFO - TEST-INFO | 1652: exit 0 12:17:41 INFO - TEST-OK | kraken | took 60874ms 12:17:41 INFO - SUITE-END | took 1177s 12:17:41 INFO - Completed test suite (00:19:37) 12:17:41 INFO - javascript benchmark 12:17:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7004.526628211804, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2754.5454999925014, 2924.312786495172, 3026.5460180972836, 3317.668233176682, 3376.172837654774, 5075.517825806552, 5547.639403438774, 5621.409751976045, 5636.15457681348, 5661.2923384576925, 5708.457696518834, 5744.942550574493, 5756.769729210827, 5781.508571771405, 5783.363829978699, 4249.745015299077, 5176.974115129419, 5239.842804715846, 5282.5245727884485, 5283.682979021271, 5163.328767260251, 5230.293910322115, 5266.025785229725, 5282.947170528303, 5362.3029006229135, 5262.684238945656, 5266.447023062581, 5273.182656688219, 5284.2866213061125, 5322.121849894763, 5065.265536497206, 5105.82129625465, 5153.922691159635, 5203.635745497817, 5306.469353064682, 5092.668976516515, 5146.639735218536, 5159.690418574897, 5161.561267292284, 5164.767585458663, 4907.312976183337, 5460.798624302647, 5679.09134538476, 5721.427857214287, 5859.355470898197, 5924.140999555084, 6148.6925653717135, 6168.321484636686, 6191.842682435583, 6261.248650161964, 6263.655498947556, 6290.811275661737, 6311.368863113697, 6321.209848768904, 6323.798478289111, 5682.829515114553, 5745.6552606843725, 5819.912701309484, 5852.882942341214, 5857.912131318034, 6245.5628106033055, 6255.78104766331, 6277.654728989949, 6291.339409362043, 6300.3699630037545, 6036.486898613579, 6176.258848938156, 6178.938210617836, 6184.567080304336, 6187.938120618826, 6121.788327166048, 6188.690565471708, 6207.503399728011, 6219.937800622027, 6239.313675495692, 5204.0372531081875, 5322.654027488278, 5791.247137872134, 6248.843778905472, 6281.309056003836, 6179.28938172107, 6193.256809182927, 6251.687415629201, 6284.528660350401, 6305.5901366411035, 4796.35249241348, 4843.903121937541, 4851.369321988119, 4872.926906096412, 4900.191468407744, 4392.978035109804, 4435.223835828717, 4439.889002774892, 4441.622462090694, 4461.7992190351515, 4769.926766477515, 5646.745896434671, 5705.62913410627, 5779.595428320062, 5805.44848239425, 6184.103305020837, 6275.121482992385, 6292.748290068437, 6298.590591611623, 6303.36966303366, 5730.255066841284, 5861.912071318934, 5865.794697185578, 5879.176915231871, 5903.881922361528, 5366.91949620756, 5406.459354064641, 5410.269613602192, 5417.322834645669, 5434.673919564839, 5873.530117590582, 6193.535484838745, 6258.718357673824, 6367.299597044229, 6369.585976911486, 6196.876062478635, 6242.157308763441, 6251.0, 6305.0, 6319.557630965788, 6341.873162536631, 6349.904751428825, 6360.745570177326, 6381.1704478417505, 6386.265579458422, 5071.264666623393, 6044.969775151096, 6207.037909124093, 6210.068489726577, 6250.218722659668, 5049.924251136163, 6018.3981601841215, 6110.710646019097, 6196.659183744938, 6276.717547710456, 6082.239720034996, 6141.662208578571, 6146.108814222002, 6147.938520614736, 6159.784407545714, 5144.6109550421925, 6097.969510152421, 6123.632582044914, 6141.416565426277, 6158.815235542941, 5628.127640215773, 5693.373728889902, 5836.037053886231, 5907.438793314628, 5913.793017244376, 5959.314678811992, 6027.18632984559, 6031.36670649593, 6081.939180608138, 6125.601746185103, 5877.735501902339, 5910.202122713551, 5913.674747888907, 5967.104934259895, 5969.432903874125], "value": 5636.489012538739, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8271604938272, 515.6158661796961, 518.5074179529447, 518.7665550502275, 569.848905211473, 30719.846400767994, 39278.83966381848, 39327.213455730904, 39394.803025984904, 39444.80277598608, 39526.8023659882, 39691.40462893059, 39797.005074873145, 39900.0, 39955.80022099893, 7944.642491087886, 9238.260939124853, 9331.0, 9373.578188981479, 9433.245340372754, 8611.052784193733, 8746.562671866413, 9221.631134754602, 9274.39716418434, 9275.86086208704, 8031.598420079002, 9218.492982885939, 9230.86153707695, 9333.766655833591, 9375.968643449216, 9157.0, 9338.766530836749, 9352.953235233781, 9353.0, 9357.970623231438, 9168.954155229181, 9179.219766319871, 9245.306602004877, 9325.533723313874, 9326.90673093274, 37428.812855935816, 37582.06044848873, 37796.81101594502, 37850.0, 37863.674771382866, 3248.772585918987, 3444.3111377724304, 3566.8751593694096, 3699.352613292663, 3834.194819087995, 27761.305967350774, 28362.212141493725, 28815.85592072047, 28903.56644650332, 29539.966101186572, 3130.2174456385906, 3147.213196700825, 3149.165471150147, 3175.4125486784787, 3218.050675050842, 484428.0, 488330.0, 491750.0, 495524.5223773894, 496084.0, 56103.438965610636, 56593.43406565882, 56610.0, 56753.43246567564, 56826.43173568211, 56802.14796778052, 57477.42522574721, 57784.71107644435, 57906.420935790105, 57963.71018144882, 15755.369785208462, 15940.963837350768, 15948.043117412992, 15956.042637441791, 15974.840251597569, 12381.62855114348, 12415.317157556421, 12469.625911222678, 12481.56314528987, 12490.0, 14168.433262669378, 15057.849421505645, 15164.09015459076, 15230.390784368723, 15324.616884577752, 6212.9378706213265, 6293.590916590407, 6349.301576826545, 6484.578502397329, 6524.380183882524, 869.3523325122824, 959.3428501476511, 976.5019839881711, 1022.2895087913829, 1077.7844431113808, 29394.412111757643, 29412.852935735613, 29688.257793555335, 29866.954656586917, 30090.69909300923, 844.9915500844913, 926.9490178040138, 965.0108638645445, 965.8699321793518, 983.7294743945472, 14542.636434089021, 15083.4720784772, 15227.543173704807, 15229.238538073274, 15321.726267815255, 603032.0, 607311.0, 611231.0, 612388.0, 617295.0, 54966.725166373915, 55438.61403464946, 55591.72204139034, 55632.0, 55760.72119639376, 1323.1135139456587, 1345.3004437692346, 1359.5173713331624, 1359.8232229810062, 1366.2622184020518, 28227.43545129045, 28866.711332886403, 28899.566506502, 29542.0, 30279.0, 47321.29018064663, 47574.76212618915, 47583.76208118937, 47667.523324767695, 47706.28440573464, 557112.0, 557795.0, 558219.0, 560270.0, 563160.0, 25169.748302516742, 25186.74813251844, 25604.743952560235, 25649.871750641127, 25872.83572239216, 36584.81707591445, 45040.0991980152, 45603.77198113988, 45792.77103614461, 45877.0, 37476.625233748404, 37761.0, 37975.620243798316, 38897.41653875138, 38998.610013899495, 31456.685433145376, 37616.81191594134, 38204.0, 38260.617393825705, 38545.807270963465, 29534.704652954057, 36455.27089458249, 37150.44274335941, 37411.06472338214, 37563.24873502569, 26758.59862102109, 27024.0, 27363.452730945668, 27458.313542161606, 33647.831760841036, 22219.888900555394, 23079.307620771404, 24169.690203739407, 25409.49181016406, 25736.8713156433], "value": 18976.571205955333, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.26700499508473, 371.0852747976238, 377.7053897959591, 381.0987015707852, 393.6201565489304, 18711.906440467796, 19532.414027579176, 19604.0, 20236.99999999998, 20301.289454869082, 11441.542338306457, 11492.655220343382, 11863.93224609785, 11873.584424545143, 11875.584354547593, 4820.68665536739, 5408.810691625794, 5411.215373770801, 5451.209574611679, 5470.863228419282, 3912.2371137628174, 4144.544100148981, 4743.0, 5402.837914862561, 5448.101063324547, 6080.4831589314745, 6162.661053642048, 6185.288691800433, 6192.318844927055, 6198.78304259351, 6140.877182456348, 6266.968665156668, 6572.737090516373, 6620.073189753391, 6621.73513059477, 4848.660593758438, 5854.0, 5857.8870014697495, 5868.325142608612, 5883.205767221413, 12892.484300627964, 13219.405126769318, 13235.338233088307, 13315.933420332836, 13353.666158346023, 2045.3454894433787, 2202.735671719388, 2355.582161029642, 2419.020296779807, 2590.4948535035674, 9553.235741140692, 9835.409875407498, 14675.706485870223, 14802.593753593392, 15056.021358611655, 1506.239349128694, 1786.937457189005, 1870.681984062699, 1895.0051223107841, 1910.9904450477798, 113349.0, 114990.0, 119297.40351298275, 121709.99999999911, 122133.0, 11389.487473063731, 11596.536138554533, 11643.47604357789, 11666.766664666658, 11740.823887641693, 14577.562673119823, 14592.781108283385, 14594.905382096385, 14608.415663373346, 14615.926920365542, 8284.295834853985, 8326.45878017927, 8350.08149103598, 8429.873551896726, 8553.82892342162, 8287.50274983503, 8294.958525207336, 8306.293965012921, 8408.747737567883, 8469.364797640079, 4403.735775853459, 4445.799939002688, 4448.065906159678, 4460.416552124014, 4479.126570318822, 1571.2929181868205, 1673.4561267588083, 1749.2915369275463, 1884.2557189909908, 1979.059946525389, 798.8961435013412, 893.5308962794585, 928.4614923344444, 950.7052813627797, 971.4074414607084, 19216.03919804004, 19881.61420629505, 19883.900580497004, 19961.900190498956, 20276.290329838386, 917.4724533393326, 921.3320342751585, 932.883389576303, 950.6340059077166, 965.4062751407834, 8838.8067610873, 8910.59902304385, 9965.651202208033, 10496.317739346918, 10520.473976301155, 477526.0, 535667.0, 547382.0, 553733.0, 561025.0, 97445.0, 117681.0, 123419.76580234262, 128700.0, 129577.35211323883, 931.8462453695064, 950.078423928788, 952.3761935931975, 954.8758661373977, 958.7219706285239, 13943.16341019542, 16607.833921661113, 17769.555761106076, 17934.5516362092, 18146.81853181451, 44690.106197876514, 45746.08507829758, 48563.27155092606, 49386.75306623588, 49581.752091239316, 69070.65464672644, 69231.61536769338, 73690.26309737048, 74752.25247747453, 76724.0, 6632.0, 6871.8661420865, 7067.116610423697, 7088.220295767358, 7228.891566626399, 11829.585964491202, 11855.466504007169, 11941.701457463632, 12065.396730163282, 12135.453904574139, 9747.561359738971, 10251.0, 10264.281500294908, 10454.895451045697, 10555.525001374803, 9925.156361709336, 10134.543945522617, 10214.182865370902, 10267.229957753048, 10304.381737095799, 8667.74317723939, 10485.475726213812, 10515.94742026285, 10594.682159535227, 10699.786004280026, 7677.846443071219, 7703.3067023968115, 7861.960690196513, 7946.165652606393, 7955.681772729026, 9531.428114313163, 10436.217283703905, 10440.895591044296, 10514.57941682324, 10668.1998850085], "value": 8917.20833943792, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1469.5664778890223, 1503.9473618423356, 1670.582354411397, 1806.7470554122417, 1822.5808064145237, 7454.5899975501325, 7657.0, 7942.285194332509, 8429.030661473924, 8442.535660538675, 7870.331021863149, 8141.877871831927, 8180.590970451483, 8250.216229458192, 8304.95847520763, 2057.177129148338, 2232.5646498932715, 2294.6328587425974, 2338.707661542307, 2341.531693661266, 2321.3268152235833, 2341.672165896776, 2357.0, 2363.196513185517, 2364.657124716915, 1939.2921583622017, 2298.069281940817, 2320.0255892525097, 2340.2511196417154, 2341.683872677184, 2290.1297506947335, 2304.7119110111234, 2314.884255787204, 2324.128451830555, 2327.290176496168, 2335.544568809083, 2343.8359314847967, 2349.3186975777003, 2351.4003928997995, 2352.6941497605203, 8197.877031844528, 8218.794530136734, 8238.876416853753, 8273.46222495542, 8281.95859020707, 4707.435107787053, 5228.895422091537, 5237.947620523784, 5240.868978275574, 5249.790008399659, 3276.9836150819333, 3322.302316513535, 3560.8041557714314, 3568.589612194579, 3573.4997100405963, 1030.20674080958, 1048.9527971241312, 1073.7906108308882, 1077.504347999921, 1091.7106966653844, 10828.891711082946, 10856.945715271373, 10861.511231994658, 10902.909709029034, 10969.012788848962, 4521.977390113094, 4607.239805432133, 4677.345171675968, 4694.342792009122, 4745.193317136095, 9275.76810579741, 9282.303827212985, 9294.0, 9301.627934882665, 9301.674441394518, 5110.4123025852505, 5188.870278243074, 5214.0875346814155, 5217.478252174752, 5233.764480598382, 3621.5292012038794, 3825.732198746061, 4248.0, 4426.0, 4681.578657920735, 3225.3226822367565, 3764.322421964018, 3792.677622402127, 3840.9039774005314, 3870.9806450968126, 1279.4114707234776, 1280.5198050730976, 1292.0890772005714, 1297.8442586889635, 1300.9349532523336, 630.4200135874914, 812.8780682897612, 894.4990805149138, 896.4038914122036, 903.1194585279432, 5526.0, 5591.776328946878, 5609.354924183689, 5617.859553511195, 5633.380328163898, 771.7800426878313, 777.2810150610662, 917.8210249001514, 920.9355345125776, 988.6885631026204, 3741.064733816546, 3799.981000094982, 3838.3091043612417, 3851.2297540492013, 3871.806409679561, 9577.425354478753, 9874.40753554789, 9880.95059524712, 9904.653337133166, 9922.255830812717, 9944.1547468466, 9945.701628951143, 9962.800743985079, 9983.351082179635, 9989.900100999042, 6549.7882891665195, 7333.889991650023, 7749.573773442402, 7791.80520486981, 7807.414443916729, 5605.131204663284, 5678.0, 5703.08750599902, 5803.593748437569, 5822.243108395882, 8306.6677332906, 8954.597043133204, 9164.129407706258, 9298.39560428597, 9378.0, 9322.766930826783, 9392.436453812794, 9486.28852836054, 9523.999980002, 9538.475383853676, 6529.9020514691365, 6750.797476075725, 6816.216135144522, 6901.827454313682, 7087.255838136922, 7625.161232264336, 7778.222177782177, 7799.06411230656, 7801.258880406353, 7820.0, 5917.378675239038, 6930.306969303029, 7095.538789978635, 7097.751578694721, 7230.710771569078, 6979.232284448808, 7011.439084873095, 7052.153741551047, 7066.8233294166, 7093.751718689821, 6494.4804415645685, 6554.115194448879, 6632.071509988702, 6640.76757313511, 6650.434713049445, 4804.0872234275375, 4809.014152098881, 4844.346013288161, 4849.054434385261, 4880.585150262125, 3895.0, 3952.6047395260243, 4404.119176164844, 4810.975945120252, 4896.608271338213], "value": 4358.622748493673, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.101248784865, 307.6999925073054, 307.9491883839167, 310.8274907426379, 314.10793346894815, 4650.744299039257, 4928.531789479994, 5062.886324910177, 5467.863303417412, 5521.503064724174, 5160.664556803805, 5181.637285390024, 5377.865553361168, 5401.837944861651, 5545.805896793613, 3889.5721470638277, 3899.883003509885, 3999.9600004000026, 4058.3303754880408, 4132.566080561543, 4102.8974275643195, 4121.340585506319, 4121.505419349683, 4128.772917489552, 4131.194417088669, 3390.983045084771, 4102.261592913274, 4104.692148088905, 4105.958940410587, 4138.255114079465, 3782.9054273643105, 3903.551091624471, 3917.9608203917883, 4139.068709540345, 4142.9378559321485, 2924.268932766808, 2941.2058744138963, 3526.8589256429714, 3528.435450327935, 3532.7703699259464, 4577.9771101144615, 4994.650374473788, 5294.311739473844, 5294.629375943686, 5317.835887646844, 4096.877093687194, 4108.50697916249, 4269.758376852146, 4679.60223381013, 4688.437387513521, 4951.3810773653295, 5435.8856434430845, 5530.198121272433, 5754.712264386764, 5796.855078623068, 3456.4469684850305, 3905.4337121117564, 3914.628110329543, 3916.941245881314, 3935.60643935607, 5856.472917437409, 6067.18093057436, 6082.878342433127, 6108.847278818021, 6136.631802091889, 3911.5110611173604, 3917.3144699678014, 3937.645611894943, 3953.703472239536, 4005.91692319992, 3262.972163768405, 3997.2205419942834, 4010.7593544386846, 4025.993501624594, 4035.6973227007506, 3912.5109361329833, 4697.8658780273145, 4739.3601863748645, 4740.075685241413, 4760.761961901961, 5650.26546548946, 5784.4960310318775, 5785.82642520725, 5795.710214489259, 5808.738606762706, 1074.4198133008244, 1216.2702378572785, 1218.597862705305, 1303.4590644882458, 1308.7632187582642, 758.5676164586243, 776.9650365733555, 807.3783186946137, 867.9671191282354, 885.1765363351517, 4903.78801884766, 5252.632315737938, 5384.892302154013, 5419.810306639249, 5456.372517160498, 5206.791728330824, 5254.185601231815, 5405.432424596902, 5913.7876735269165, 5962.493188078975, 5065.442801291855, 5408.9729551352775, 5429.891402171935, 5429.972850135724, 5432.239486471897, 2558.6801649793774, 2612.843229406242, 2653.7478939500716, 2817.3097591090313, 2917.4602698500844, 5093.821716239988, 5325.520703136736, 5474.45802266296, 5879.529637629065, 5896.7641294348605, 5239.768654366221, 5461.798404351036, 5759.769609215585, 5834.883302333929, 5876.0, 4925.532074452921, 5022.422421421583, 5613.691246981373, 5904.911426328695, 6017.578769486094, 5100.954304367521, 5286.392064912584, 5621.5221706155435, 5656.406077361818, 5656.88686226265, 4575.58819706228, 5099.69401835891, 5340.706261155518, 5548.3619383769765, 5601.355844077983, 4804.711717296974, 5442.068145721183, 5481.5340696041285, 5886.381929897299, 5910.527157827278, 3352.664733526628, 3574.338747331752, 3717.7025837933443, 3723.7579557328686, 3743.350233522294, 4630.606398456169, 4892.920508783824, 5110.540051395392, 5415.4313797050745, 5466.890662186814, 4735.0, 4829.589484893823, 5174.094533456705, 5473.698946557977, 5512.944870551403, 4361.078077687894, 4494.55054494561, 4582.0, 5116.846494605168, 5122.487751224997, 4693.0, 5016.2224855147515, 5030.496950304941, 5175.741212939413, 5296.867578310573, 5005.068993243019, 5081.949180508148, 5554.333479982266, 5739.512141468022, 5752.913706294493, 4550.907782132331, 5535.39110697831, 5613.915791263216, 5632.662040277596, 5638.718064096696], "value": 4032.301397899737, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.2270427656059, 700.5551474813493, 747.0475144191153, 759.195253031786, 792.4809249941287, 13781.96635252355, 14552.63618409539, 14629.853701462982, 14633.341499632543, 14685.559433217008, 13097.607071787837, 13115.6721081973, 13125.54060607879, 13132.474701011948, 13217.801732973989, 642.4635429416443, 667.8096742428409, 786.1352512236551, 805.5851236613149, 824.1881746479713, 820.6430202861774, 822.4777266435797, 822.7737372222626, 823.983520329593, 823.9988414076926, 866.2680035370103, 867.6182479708908, 868.1231955724701, 868.50929224988, 868.5396739727922, 854.2141230068352, 860.8837806896114, 862.4782006885838, 863.4991704811226, 864.1185990289932, 624.6283461340495, 633.6736580660963, 640.5228105061713, 642.1715986377613, 642.7332656947361, 12984.740305193938, 13368.598942031755, 13526.391312390966, 13535.458581656721, 14176.716465670628, 13455.865441345559, 13655.248961307125, 13828.101173423796, 13843.377048032762, 13888.791668124986, 9446.763830904214, 11004.669859904216, 11169.273997190157, 11400.258983166068, 11456.656300311004, 3164.6202455705234, 3577.0878426001464, 3607.2785442911522, 3620.2035552178477, 3628.600853906068, 73189.63405182993, 78362.6081869587, 87610.80972975663, 91433.54283228541, 91856.16287674209, 10389.116925061304, 10498.685039448828, 10523.631672891412, 10684.604274841155, 10839.13286937043, 19316.034198290312, 20180.89909550443, 20795.064222110042, 20974.0, 20975.475613109793, 6696.933030669728, 6713.160854893138, 6799.490038247052, 7053.400460960773, 7481.476296659291, 3370.79775213488, 4058.904095894151, 4164.375343698409, 4179.770112643773, 4316.395704601358, 3401.0817079388426, 3492.301539692072, 3497.860085596548, 3511.8595256189465, 3526.0, 557.6152454806171, 565.9830205093854, 621.0776996160698, 636.3223167326782, 647.7344288841647, 510.2721946154387, 607.9392060793886, 633.240111865763, 666.8766278238542, 670.9194896612438, 7235.131784185932, 7838.804029899298, 8239.546824924566, 8825.426347287406, 9167.358284919992, 603.1856993059334, 617.4443001298866, 635.4146404720252, 657.1752450674373, 672.9125213722283, 4253.21315556623, 4414.27164517851, 4420.138073075784, 4420.889477763018, 4481.551844815493, 66762.0, 98333.01666983239, 109793.0, 110370.0, 111840.0, 65741.35646608874, 77014.61492692612, 89467.55266223627, 96856.0, 100438.49780751196, 54108.188377174374, 57145.0, 81160.0, 83150.0, 88669.55665221633, 9425.481598512148, 9479.383840050376, 9678.75803104928, 9732.562034708313, 9834.704958851245, 37937.24125517419, 40446.1910761789, 43143.0, 51117.48882511276, 52426.47573524216, 50352.49647503625, 70151.59696806138, 73712.0, 74310.62844685742, 74643.0, 11720.941395292968, 11945.761084778429, 12067.517299307929, 12108.0, 12265.754684906073, 13234.272115033766, 13250.801237981244, 13301.201927884358, 13400.06199566021, 13461.326933653083, 12716.173448725804, 12833.935830320788, 12974.156679815782, 13029.804552931524, 13083.0, 12774.936125319315, 12787.552435664707, 12876.935615321863, 12894.097413180987, 13171.538996135088, 12394.81407778866, 12417.006639469002, 12501.562445314734, 12524.060695447695, 12613.92781613573, 10500.947495262475, 10669.733256668645, 10866.891331086905, 11052.668419947415, 11218.495167717309, 10603.522841471999, 10924.50839712199, 11186.664400068012, 11198.664040078811, 11201.0], "value": 7044.814260989156, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 120.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 115.0, 142.0, 116.0, 144.0, 138.0, 117.0, 140.0, 117.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 201.0, 199.0, 196.0, 196.0, 194.0, 195.0, 196.0, 194.0], "value": 196.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 59.0, 81.0, 64.0, 84.0, 50.0, 68.0, 105.0, 84.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 94.0, 74.0, 72.0, 80.0, 124.0, 69.0, 70.0, 90.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 90.0, 88.0, 96.0, 133.0, 114.0, 123.0, 91.0, 90.0], "value": 100.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 125.0, 154.0, 150.0, 96.0, 97.0, 97.0, 105.0, 96.0, 97.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 134.0, 84.0, 86.0, 94.0, 90.0, 91.0, 93.0, 88.0, 87.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 58.0, 58.0, 59.0, 60.0, 59.0, 60.0, 61.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 79.0, 85.0, 75.0, 74.0, 70.0, 70.0, 74.0, 91.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 83.0, 86.0, 84.0, 88.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 158.0, 150.0, 153.0, 142.0, 142.0, 150.0, 143.0, 200.0, 154.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 167.0, 197.0, 231.0, 164.0, 167.0, 181.0, 232.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 68.0, 66.0, 66.0, 70.0, 68.0, 80.0], "value": 68.6, "unit": "score"}]}]} 12:17:41 INFO - Return code: 0 12:17:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:17:42 ERROR - # TBPL SUCCESS # 12:17:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:42 INFO - 2017-01-11 12:17:42.045 screenresolution[1657:10845] starting screenresolution argv=screenresolution get 12:17:42 INFO - 2017-01-11 12:17:42.048 screenresolution[1657:10845] Display 0: 1600x1200x32@60 12:17:42 INFO - 2017-01-11 12:17:42.054 screenresolution[1658:10848] starting screenresolution argv=screenresolution list 12:17:42 INFO - Available Modes on Display 0 12:17:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:42 INFO - Intel Iris: 12:17:42 INFO - Chipset Model: Intel Iris 12:17:42 INFO - Type: GPU 12:17:42 INFO - Bus: Built-In 12:17:42 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:42 INFO - Vendor: Intel (0x8086) 12:17:42 INFO - Device ID: 0x0a2e 12:17:42 INFO - Revision ID: 0x0009 12:17:42 INFO - Displays: 12:17:42 INFO - Display: 12:17:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:42 INFO - Display Serial Number: 1600x1200 60 12:17:42 INFO - Main Display: Yes 12:17:42 INFO - Mirror: Off 12:17:42 INFO - Online: Yes 12:17:42 INFO - Rotation: Supported 12:17:42 INFO - Return code: 0 12:17:42 INFO - Running post-action listener: _resource_record_post_action 12:17:42 INFO - [mozharness: 2017-01-11 20:17:42.300068Z] Finished run-tests step (success) 12:17:42 INFO - Running post-run listener: _resource_record_post_run 12:17:42 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 38574592; Write bytes: 655545856; Read time: 247; Write time: 1889 12:17:42 INFO - TinderboxPrint: CPU usage
54.3% 12:17:42 INFO - TinderboxPrint: I/O read bytes / time
38,574,592 / 247 12:17:42 INFO - TinderboxPrint: I/O write bytes / time
655,545,856 / 1,889 12:17:42 INFO - TinderboxPrint: CPU idle
2,210.3 (45.7%) 12:17:42 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 12:17:42 INFO - TinderboxPrint: CPU user
1,649.1 (34.1%) 12:17:42 INFO - TinderboxPrint: Swap in / out
387,317,760 / 0 12:17:42 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 355559936; Write bytes: 352560640; Read time: 27846; Write time: 731 12:17:42 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29946368; Write bytes: 301645824; Read time: 178; Write time: 1152 12:17:42 INFO - Running post-run listener: _upload_blobber_files 12:17:42 INFO - Blob upload gear active. 12:17:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:17:42 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 12:17:42 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'] 12:17:42 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 12:17:43 INFO - (blobuploader) - INFO - Open directory for files ... 12:17:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:17:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:44 INFO - (blobuploader) - INFO - Done attempting. 12:17:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:17:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:17:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:45 INFO - (blobuploader) - INFO - Done attempting. 12:17:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:17:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:17:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:47 INFO - (blobuploader) - INFO - Done attempting. 12:17:47 INFO - (blobuploader) - INFO - Iteration through files over. 12:17:47 INFO - Return code: 0 12:17:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:17:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:17:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f51e1fd353de1deb0c1352ae55a6213dd2734e666eced91473c3251ff1d29fab6f63bc8121d52f9312d34bfa5727739cd501b911d0dd8d44f46bdf483fddb97f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e65be8ed1762ae8524ffe515384ba2f9cd821267fd0c33613aac0c08698824da909cd772427eb14c18417b1e98503bfb646bfc5a2012ee79e0a382c022832d97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb8e36e5c1d11be4962a861f9a257a2795909304746ecca3b5e7dad5c1a7340221295f8172651b6aeb323299508f657b92cf5177709fb6a8ec5f003e4ea1f63c"} 12:17:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:17:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:17:47 INFO - Contents: 12:17:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f51e1fd353de1deb0c1352ae55a6213dd2734e666eced91473c3251ff1d29fab6f63bc8121d52f9312d34bfa5727739cd501b911d0dd8d44f46bdf483fddb97f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e65be8ed1762ae8524ffe515384ba2f9cd821267fd0c33613aac0c08698824da909cd772427eb14c18417b1e98503bfb646bfc5a2012ee79e0a382c022832d97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb8e36e5c1d11be4962a861f9a257a2795909304746ecca3b5e7dad5c1a7340221295f8172651b6aeb323299508f657b92cf5177709fb6a8ec5f003e4ea1f63c"} 12:17:47 INFO - Running post-run listener: copy_logs_to_upload_dir 12:17:47 INFO - Copying logs to upload dir... 12:17:47 INFO - mkdir: /builds/slave/test/build/upload/logs 12:17:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.509827 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-11 12:17:48.073795) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:48.078056) ========= 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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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/f51e1fd353de1deb0c1352ae55a6213dd2734e666eced91473c3251ff1d29fab6f63bc8121d52f9312d34bfa5727739cd501b911d0dd8d44f46bdf483fddb97f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e65be8ed1762ae8524ffe515384ba2f9cd821267fd0c33613aac0c08698824da909cd772427eb14c18417b1e98503bfb646bfc5a2012ee79e0a382c022832d97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb8e36e5c1d11be4962a861f9a257a2795909304746ecca3b5e7dad5c1a7340221295f8172651b6aeb323299508f657b92cf5177709fb6a8ec5f003e4ea1f63c"} build_url:https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011004 build_url: 'https://queue.taskcluster.net/v1/task/TCyMGiBoSf2HbVEsSjVWmA/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/f51e1fd353de1deb0c1352ae55a6213dd2734e666eced91473c3251ff1d29fab6f63bc8121d52f9312d34bfa5727739cd501b911d0dd8d44f46bdf483fddb97f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e65be8ed1762ae8524ffe515384ba2f9cd821267fd0c33613aac0c08698824da909cd772427eb14c18417b1e98503bfb646bfc5a2012ee79e0a382c022832d97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb8e36e5c1d11be4962a861f9a257a2795909304746ecca3b5e7dad5c1a7340221295f8172651b6aeb323299508f657b92cf5177709fb6a8ec5f003e4ea1f63c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:48.117849) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:48.118296) ========= 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.Zv5wkUChSK/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.T1XAkH4GMm/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.005072 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:48.167614) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:17:48.167956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:17:48.168442) ========= ========= Total master_lag: 0.20 =========