builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0007 starttime: 1494446311.61 results: success (0) revision: ce2218406119c36a551e3faea4e192186ee46cc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.611897) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.612679) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.613172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.642595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.642984) ========= 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.QJL6ScWh15/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.59lujX6zDD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-05-10 12:58:31-- 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% 15.5M=0.001s 2017-05-10 12:58:31 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120820 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.788090) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.788439) ========= 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.QJL6ScWh15/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.59lujX6zDD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.028879 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:31.838703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 12:58:31.839083) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ce2218406119c36a551e3faea4e192186ee46cc5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev ce2218406119c36a551e3faea4e192186ee46cc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QJL6ScWh15/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.59lujX6zDD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-05-10 12:58:31,938 truncating revision to first 12 chars 2017-05-10 12:58:31,938 Setting DEBUG logging. 2017-05-10 12:58:31,939 attempt 1/10 2017-05-10 12:58:31,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ce2218406119?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-10 12:58:33,024 unpacking tar archive at: mozilla-central-ce2218406119/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376165 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 12:58:33.240461) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:33.240983) ========= script_repo_revision: ce2218406119c36a551e3faea4e192186ee46cc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:33.241617) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:33.242079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 12:58:33.263286) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-10 12:58:33.263679) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QJL6ScWh15/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.59lujX6zDD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:58:33 INFO - MultiFileLogger online at 20170510 12:58:33 in /builds/slave/test 12:58:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:58:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:58:33 INFO - {'append_to_log': False, 12:58:33 INFO - 'base_work_dir': '/builds/slave/test', 12:58:33 INFO - 'blob_upload_branch': 'Firefox', 12:58:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:58:33 INFO - 'branch': 'Firefox', 12:58:33 INFO - 'buildbot_json_path': 'buildprops.json', 12:58:33 INFO - 'config_files': ('talos/mac_config.py',), 12:58:33 INFO - 'default_actions': ('clobber', 12:58:33 INFO - 'read-buildbot-config', 12:58:33 INFO - 'download-and-extract', 12:58:33 INFO - 'populate-webroot', 12:58:33 INFO - 'create-virtualenv', 12:58:33 INFO - 'install', 12:58:33 INFO - 'run-tests'), 12:58:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:58:33 INFO - 'download_minidump_stackwalk': True, 12:58:33 INFO - 'download_symbols': 'ondemand', 12:58:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:58:33 INFO - 'tooltool.py': '/tools/tooltool.py', 12:58:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:58:33 INFO - '/tools/misc-python/virtualenv.py')}, 12:58:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:58:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:58:33 INFO - 'gecko_profile': False, 12:58:33 INFO - 'gecko_profile_interval': 0, 12:58:33 INFO - 'installer_path': 'installer.exe', 12:58:33 INFO - 'log_level': 'info', 12:58:33 INFO - 'log_name': 'talos', 12:58:33 INFO - 'log_to_console': True, 12:58:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:58:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:58:33 INFO - 'opt_config_files': (), 12:58:33 INFO - 'pip_index': False, 12:58:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:33 INFO - 'cmd': ('bash', 12:58:33 INFO - '-c', 12:58:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:33 INFO - 'enabled': True, 12:58:33 INFO - 'halt_on_failure': False, 12:58:33 INFO - 'name': 'check_screen_resolution'},), 12:58:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:33 INFO - 'cmd': ('bash', 12:58:33 INFO - '-c', 12:58:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:33 INFO - 'enabled': True, 12:58:33 INFO - 'halt_on_failure': False, 12:58:33 INFO - 'name': 'check_screen_resolution'},), 12:58:33 INFO - 'run_cmd_checks_enabled': True, 12:58:33 INFO - 'suite': 'dromaeojs-e10s', 12:58:33 INFO - 'system_bits': '32', 12:58:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:58:33 INFO - 'title': 't-yosemite-r7-0007', 12:58:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:58:33 INFO - 'use_talos_json': True, 12:58:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:58:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:58:33 INFO - 'work_dir': 'build'} 12:58:33 INFO - [mozharness: 2017-05-10 19:58:33.472680Z] Running clobber step. 12:58:33 INFO - Running pre-action listener: _resource_record_pre_action 12:58:33 INFO - Running main action method: clobber 12:58:33 INFO - rmtree: /builds/slave/test/build 12:58:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:58:36 INFO - Running post-action listener: _resource_record_post_action 12:58:36 INFO - [mozharness: 2017-05-10 19:58:36.016158Z] Finished clobber step (success) 12:58:36 INFO - [mozharness: 2017-05-10 19:58:36.016300Z] Running read-buildbot-config step. 12:58:36 INFO - Running pre-action listener: _resource_record_pre_action 12:58:36 INFO - Running main action method: read_buildbot_config 12:58:36 INFO - Using buildbot properties: 12:58:36 INFO - { 12:58:36 INFO - "project": "", 12:58:36 INFO - "product": "firefox", 12:58:36 INFO - "who": "kwierso@gmail.com", 12:58:36 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 12:58:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:58:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:58:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:58:36 INFO - "stage_platform": "macosx64", 12:58:36 INFO - "basedir": "/builds/slave/test", 12:58:36 INFO - "buildnumber": 141, 12:58:36 INFO - "platform": "yosemite_r7", 12:58:36 INFO - "parent_task_id": "NH2o-xTKT5GU4FLlBoobSg", 12:58:36 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:58:36 INFO - "slavebuilddir": "test", 12:58:36 INFO - "taskId": "ZzUE4fRuQS2G4Sw-U611fg", 12:58:36 INFO - "branch": "mozilla-central", 12:58:36 INFO - "script_repo_revision": "production", 12:58:36 INFO - "revision": "ce2218406119c36a551e3faea4e192186ee46cc5", 12:58:36 INFO - "slavename": "t-yosemite-r7-0007", 12:58:36 INFO - "repo_path": "mozilla-central" 12:58:36 INFO - } 12:58:36 INFO - Finding installer, test and symbols from parent task. 12:58:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZzUE4fRuQS2G4Sw-U611fg'}, attempt #1 12:58:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZzUE4fRuQS2G4Sw-U611fg'}, attempt #1 12:58:37 INFO - Task dependencies: Qhwf44WxRNuSpFMOFYCnoA 12:58:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg'}, attempt #1 12:58:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:58:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/test_packages.json 12:58:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/target.crashreporter-symbols.zip 12:58:37 INFO - Running post-action listener: _resource_record_post_action 12:58:37 INFO - [mozharness: 2017-05-10 19:58:37.778226Z] Finished read-buildbot-config step (success) 12:58:37 INFO - [mozharness: 2017-05-10 19:58:37.778379Z] Running download-and-extract step. 12:58:37 INFO - Running pre-action listener: _resource_record_pre_action 12:58:37 INFO - Running main action method: download_and_extract 12:58:37 INFO - mkdir: /builds/slave/test/build/tests 12:58:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:37 INFO - trying https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:58:37 INFO - Downloading https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:58:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:58:38 INFO - Downloaded 1325 bytes. 12:58:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:58:38 INFO - Using the following test package requirements: 12:58:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:58:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:58:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:58:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:58:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'jsshell-mac.zip'], 12:58:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:58:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:58:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:58:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:58:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:58:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:58:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:58:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:58:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:58:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:58:38 INFO - Fetch https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:58:40 INFO - Content-Length response header: 52707929 12:58:40 INFO - Bytes received: 52707929 12:58:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:58:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:58:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:58:56 INFO - Fetch https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:58:58 INFO - Content-Length response header: 52707929 12:58:58 INFO - Bytes received: 52707929 12:59:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:59:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:59:14 INFO - Fetch https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:59:15 INFO - Content-Length response header: 14409362 12:59:15 INFO - Bytes received: 14409362 12:59: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')]} 12:59:15 INFO - trying https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:59:15 INFO - Downloading https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:59:18 INFO - Downloaded 75505175 bytes. 12:59:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:59:18 INFO - mkdir: /builds/slave/test/properties 12:59:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:59:18 INFO - Writing to file /builds/slave/test/properties/build_url 12:59:18 INFO - Contents: 12:59:18 INFO - build_url:https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:59:18 INFO - Running post-action listener: _resource_record_post_action 12:59:18 INFO - Running post-action listener: set_extra_try_arguments 12:59:18 INFO - [mozharness: 2017-05-10 19:59:18.005450Z] Finished download-and-extract step (success) 12:59:18 INFO - [mozharness: 2017-05-10 19:59:18.005579Z] Running populate-webroot step. 12:59:18 INFO - Running pre-action listener: _resource_record_pre_action 12:59:18 INFO - Running main action method: populate_webroot 12:59:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:59:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:59:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:59:18 INFO - '--tppagecycles', 12:59:18 INFO - '7'], 12:59:18 INFO - 'tests': ['tsvgm']}}, 12:59:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:59:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:59:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:59:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:59:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['damp', 'tps']}, 12:59:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'tests': ['damp', 'tps']}, 12:59:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['dromaeo_dom']}, 12:59:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:59:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:59:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:59:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['a11yr', 12:59:18 INFO - 'ts_paint', 12:59:18 INFO - 'tpaint', 12:59:18 INFO - 'sessionrestore', 12:59:18 INFO - 'sessionrestore_no_auto_restore', 12:59:18 INFO - 'tabpaint']}, 12:59:18 INFO - 'other-e10s': {'tests': ['a11yr', 12:59:18 INFO - 'ts_paint', 12:59:18 INFO - 'tpaint', 12:59:18 INFO - 'sessionrestore', 12:59:18 INFO - 'sessionrestore_no_auto_restore', 12:59:18 INFO - 'tabpaint']}, 12:59:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:59:18 INFO - 'ts_paint', 12:59:18 INFO - 'tpaint', 12:59:18 INFO - 'sessionrestore', 12:59:18 INFO - 'sessionrestore_no_auto_restore', 12:59:18 INFO - 'tabpaint']}, 12:59:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:59:18 INFO - 'ts_paint', 12:59:18 INFO - 'tpaint', 12:59:18 INFO - 'sessionrestore', 12:59:18 INFO - 'sessionrestore_no_auto_restore', 12:59:18 INFO - 'tabpaint']}, 12:59:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['a11yr', 12:59:18 INFO - 'ts_paint', 12:59:18 INFO - 'tpaint', 12:59:18 INFO - 'sessionrestore', 12:59:18 INFO - 'sessionrestore_no_auto_restore', 12:59:18 INFO - 'tabpaint']}, 12:59:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['a11yr', 12:59:18 INFO - 'ts_paint', 12:59:18 INFO - 'tpaint', 12:59:18 INFO - 'sessionrestore', 12:59:18 INFO - 'sessionrestore_no_auto_restore', 12:59:18 INFO - 'tabpaint']}, 12:59:18 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:59:18 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:59:18 INFO - 'bloom_basic_ref']}, 12:59:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['tsvgx', 12:59:18 INFO - 'tsvgr_opacity', 12:59:18 INFO - 'tart', 12:59:18 INFO - 'tscrollx', 12:59:18 INFO - 'cart', 12:59:18 INFO - 'tsvg_static']}, 12:59:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:59:18 INFO - 'tsvgr_opacity', 12:59:18 INFO - 'tart', 12:59:18 INFO - 'tscrollx', 12:59:18 INFO - 'cart', 12:59:18 INFO - 'tsvg_static']}, 12:59:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'talos_options': ['--disable-e10s'], 12:59:18 INFO - 'tests': ['tp5o']}, 12:59:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:59:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'talos_options': ['--disable-e10s', 12:59:18 INFO - '--xperf_path', 12:59:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:18 INFO - 'tests': ['tp5n']}, 12:59:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:59:18 INFO - 'talos_options': ['--xperf_path', 12:59:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:18 INFO - 'tests': ['tp5n']}}, 12:59:18 INFO - 'talos.zip': {'path': '', 12:59:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:59:18 INFO - Running post-action listener: _resource_record_post_action 12:59:18 INFO - [mozharness: 2017-05-10 19:59:18.026237Z] Finished populate-webroot step (success) 12:59:18 INFO - [mozharness: 2017-05-10 19:59:18.026416Z] Running create-virtualenv step. 12:59:18 INFO - Running pre-action listener: _resource_record_pre_action 12:59:18 INFO - Running main action method: create_virtualenv 12:59:18 INFO - Creating virtualenv /builds/slave/test/build/venv 12:59:18 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 12:59:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:59:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:59:18 INFO - Using real prefix '/tools/python27' 12:59:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:59:18 INFO - Installing distribute.............................................................................................................................................................................................done. 12:59:21 INFO - Installing pip.................done. 12:59:21 INFO - Return code: 0 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:59:22 INFO - Reading from file tmpfile_stdout 12:59:22 INFO - Output received: 12:59:22 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) 12:59:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:59:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:22 INFO - 'HOME': '/Users/cltbld', 12:59:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:22 INFO - 'LOGNAME': 'cltbld', 12:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:22 INFO - 'MOZ_NO_REMOTE': '1', 12:59:22 INFO - 'NO_EM_RESTART': '1', 12:59:22 INFO - 'PAGER': '/bin/cat', 12:59:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:22 INFO - 'PWD': '/builds/slave/test', 12:59:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:22 INFO - 'SHELL': '/bin/bash', 12:59:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:22 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:22 INFO - 'USER': 'cltbld', 12:59:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:22 INFO - 'XPC_FLAGS': '0x0', 12:59:22 INFO - 'XPC_SERVICE_NAME': '0', 12:59:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:59:22 INFO - Cleaning up... 12:59:22 INFO - Return code: 0 12:59:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:59:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:22 INFO - 'HOME': '/Users/cltbld', 12:59:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:22 INFO - 'LOGNAME': 'cltbld', 12:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:22 INFO - 'MOZ_NO_REMOTE': '1', 12:59:22 INFO - 'NO_EM_RESTART': '1', 12:59:22 INFO - 'PAGER': '/bin/cat', 12:59:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:22 INFO - 'PWD': '/builds/slave/test', 12:59:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:22 INFO - 'SHELL': '/bin/bash', 12:59:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:22 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:22 INFO - 'USER': 'cltbld', 12:59:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:22 INFO - 'XPC_FLAGS': '0x0', 12:59:22 INFO - 'XPC_SERVICE_NAME': '0', 12:59:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:22 INFO - Downloading/unpacking psutil>=3.1.1 12:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:59:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:59:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:59:24 INFO - Installing collected packages: psutil 12:59:24 INFO - Running setup.py install for psutil 12:59:24 INFO - building 'psutil._psutil_osx' extension 12:59:24 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 12:59:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:59:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:59:25 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 12:59:25 INFO - building 'psutil._psutil_posix' extension 12:59:25 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 12:59:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:59:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:59:25 INFO - ^ 12:59:25 INFO - 1 warning generated. 12:59:25 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 12:59:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:59:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:59:25 INFO - Successfully installed psutil 12:59:25 INFO - Cleaning up... 12:59:26 INFO - Return code: 0 12:59:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:26 INFO - 'HOME': '/Users/cltbld', 12:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:26 INFO - 'LOGNAME': 'cltbld', 12:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:26 INFO - 'MOZ_NO_REMOTE': '1', 12:59:26 INFO - 'NO_EM_RESTART': '1', 12:59:26 INFO - 'PAGER': '/bin/cat', 12:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:26 INFO - 'PWD': '/builds/slave/test', 12:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:26 INFO - 'SHELL': '/bin/bash', 12:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:26 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:26 INFO - 'USER': 'cltbld', 12:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:26 INFO - 'XPC_FLAGS': '0x0', 12:59:26 INFO - 'XPC_SERVICE_NAME': '0', 12:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:59:26 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 12:59:26 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 12:59:26 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 12:59:26 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 12:59:26 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 12:59:26 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 12:59:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:59:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:59:27 INFO - Installing collected packages: mozsystemmonitor 12:59:27 INFO - Running setup.py install for mozsystemmonitor 12:59:28 INFO - Successfully installed mozsystemmonitor 12:59:28 INFO - Cleaning up... 12:59:28 INFO - Return code: 0 12:59:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:59:28 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')]} 12:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:28 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')]} 12:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:28 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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:28 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 12:59:28 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 12:59:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:28 INFO - 'HOME': '/Users/cltbld', 12:59:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:28 INFO - 'LOGNAME': 'cltbld', 12:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:28 INFO - 'MOZ_NO_REMOTE': '1', 12:59:28 INFO - 'NO_EM_RESTART': '1', 12:59:28 INFO - 'PAGER': '/bin/cat', 12:59:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:28 INFO - 'PWD': '/builds/slave/test', 12:59:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:28 INFO - 'SHELL': '/bin/bash', 12:59:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:28 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:28 INFO - 'USER': 'cltbld', 12:59:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:28 INFO - 'XPC_FLAGS': '0x0', 12:59:28 INFO - 'XPC_SERVICE_NAME': '0', 12:59:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:29 INFO - Downloading/unpacking jsonschema==2.5.1 12:59:29 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 12:59:29 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 12:59:29 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 12:59:29 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 12:59:29 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 12:59:29 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 12:59:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:59:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:32 INFO - Downloading functools32-3.2.3-2.tar.gz 12:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:59:32 INFO - warning: no files found matching '*.txt' 12:59:32 INFO - no previously-included directories found matching 'build' 12:59:32 INFO - no previously-included directories found matching 'dist' 12:59:32 INFO - no previously-included directories found matching '.git*' 12:59:32 INFO - Installing collected packages: jsonschema, functools32 12:59:32 INFO - Running setup.py install for functools32 12:59:32 INFO - warning: no files found matching '*.txt' 12:59:32 INFO - no previously-included directories found matching 'build' 12:59:32 INFO - no previously-included directories found matching 'dist' 12:59:32 INFO - no previously-included directories found matching '.git*' 12:59:32 INFO - Successfully installed jsonschema functools32 12:59:32 INFO - Cleaning up... 12:59:32 INFO - Return code: 0 12:59:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:32 INFO - 'HOME': '/Users/cltbld', 12:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:32 INFO - 'LOGNAME': 'cltbld', 12:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:32 INFO - 'MOZ_NO_REMOTE': '1', 12:59:32 INFO - 'NO_EM_RESTART': '1', 12:59:32 INFO - 'PAGER': '/bin/cat', 12:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:32 INFO - 'PWD': '/builds/slave/test', 12:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:32 INFO - 'SHELL': '/bin/bash', 12:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:32 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:32 INFO - 'USER': 'cltbld', 12:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:32 INFO - 'XPC_FLAGS': '0x0', 12:59:32 INFO - 'XPC_SERVICE_NAME': '0', 12:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:59:32 INFO - Cleaning up... 12:59:32 INFO - Return code: 0 12:59:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:32 INFO - 'HOME': '/Users/cltbld', 12:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:32 INFO - 'LOGNAME': 'cltbld', 12:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:32 INFO - 'MOZ_NO_REMOTE': '1', 12:59:32 INFO - 'NO_EM_RESTART': '1', 12:59:32 INFO - 'PAGER': '/bin/cat', 12:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:32 INFO - 'PWD': '/builds/slave/test', 12:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:32 INFO - 'SHELL': '/bin/bash', 12:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:32 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:32 INFO - 'USER': 'cltbld', 12:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:32 INFO - 'XPC_FLAGS': '0x0', 12:59:32 INFO - 'XPC_SERVICE_NAME': '0', 12:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:32 INFO - Downloading/unpacking blobuploader==1.2.4 12:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:34 INFO - Downloading blobuploader-1.2.4.tar.gz 12:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:59:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:59:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:35 INFO - Downloading docopt-0.6.1.tar.gz 12:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:59:35 INFO - Installing collected packages: blobuploader, requests, docopt 12:59:35 INFO - Running setup.py install for blobuploader 12:59:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:59:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:59:35 INFO - Running setup.py install for requests 12:59:35 INFO - Running setup.py install for docopt 12:59:36 INFO - Successfully installed blobuploader requests docopt 12:59:36 INFO - Cleaning up... 12:59:36 INFO - Return code: 0 12:59:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:36 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')]} 12:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:36 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')]} 12:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:36 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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:36 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 12:59:36 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 12:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:36 INFO - 'HOME': '/Users/cltbld', 12:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:36 INFO - 'LOGNAME': 'cltbld', 12:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:36 INFO - 'MOZ_NO_REMOTE': '1', 12:59:36 INFO - 'NO_EM_RESTART': '1', 12:59:36 INFO - 'PAGER': '/bin/cat', 12:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:36 INFO - 'PWD': '/builds/slave/test', 12:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:36 INFO - 'SHELL': '/bin/bash', 12:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:36 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:36 INFO - 'USER': 'cltbld', 12:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:36 INFO - 'XPC_FLAGS': '0x0', 12:59:36 INFO - 'XPC_SERVICE_NAME': '0', 12:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-jqllml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-GBBF27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ML2xlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-0I7WhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_PJ1MF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-mIH4GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-mVLMgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-laD1HY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-fJPbh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-vb0GtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Uxdo3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-6h7Haw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-YLjHpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SMLRXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UEDSeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-pOBEYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-d4loI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:59:38 INFO - Running setup.py install for manifestparser 12:59:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:59:38 INFO - Running setup.py install for mozcrash 12:59:38 INFO - Running setup.py install for mozdebug 12:59:38 INFO - Running setup.py install for mozdevice 12:59:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:59:38 INFO - Running setup.py install for mozfile 12:59:38 INFO - Running setup.py install for mozhttpd 12:59:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Running setup.py install for mozinfo 12:59:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Running setup.py install for mozInstall 12:59:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Running setup.py install for mozleak 12:59:39 INFO - Running setup.py install for mozlog 12:59:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Running setup.py install for moznetwork 12:59:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:59:39 INFO - Running setup.py install for mozprocess 12:59:39 INFO - Running setup.py install for mozprofile 12:59:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:59:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:59:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:59:40 INFO - Running setup.py install for mozrunner 12:59:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:59:40 INFO - Running setup.py install for mozscreenshot 12:59:40 INFO - Running setup.py install for moztest 12:59:40 INFO - Running setup.py install for mozversion 12:59:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:59:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:59:40 INFO - Cleaning up... 12:59:40 INFO - Return code: 0 12:59:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:40 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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:40 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 12:59:40 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 12:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:40 INFO - 'HOME': '/Users/cltbld', 12:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:40 INFO - 'LOGNAME': 'cltbld', 12:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:40 INFO - 'MOZ_NO_REMOTE': '1', 12:59:40 INFO - 'NO_EM_RESTART': '1', 12:59:40 INFO - 'PAGER': '/bin/cat', 12:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:40 INFO - 'PWD': '/builds/slave/test', 12:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:40 INFO - 'SHELL': '/bin/bash', 12:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:40 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:40 INFO - 'USER': 'cltbld', 12:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:40 INFO - 'XPC_FLAGS': '0x0', 12:59:40 INFO - 'XPC_SERVICE_NAME': '0', 12:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PB7NjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-7gJGp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-fgXFuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_Vtddz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-RzhS45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-sdN0Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-gj5qe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-FvOFD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-0OUYvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:41 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)) 12:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-4EVQ2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:42 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-BTQDlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:42 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-6ZtbZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SagQtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:42 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Vw2EGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:42 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-eYHaZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:42 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-4YtWJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:42 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)) 12:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PHwIQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:42 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)) 12:59:42 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)) 12:59:42 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)) 12:59:42 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)) 12:59:42 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:59:42 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:59:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:44 INFO - Downloading blessings-1.6.tar.gz 12:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:59:44 INFO - Installing collected packages: blessings 12:59:44 INFO - Running setup.py install for blessings 12:59:44 INFO - Successfully installed blessings 12:59:44 INFO - Cleaning up... 12:59:44 INFO - Return code: 0 12:59:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:59:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:44 INFO - Reading from file tmpfile_stdout 12:59:44 INFO - Current package versions: 12:59:44 INFO - blessings == 1.6 12:59:44 INFO - blobuploader == 1.2.4 12:59:44 INFO - docopt == 0.6.1 12:59:44 INFO - functools32 == 3.2.3-2 12:59:44 INFO - jsonschema == 2.5.1 12:59:44 INFO - manifestparser == 1.1 12:59:44 INFO - mozInstall == 1.12 12:59:44 INFO - mozcrash == 1.0 12:59:44 INFO - mozdebug == 0.1 12:59:44 INFO - mozdevice == 0.50 12:59:44 INFO - mozfile == 1.2 12:59:44 INFO - mozhttpd == 0.7 12:59:44 INFO - mozinfo == 0.9 12:59:44 INFO - mozleak == 0.1 12:59:44 INFO - mozlog == 3.4 12:59:44 INFO - moznetwork == 0.27 12:59:44 INFO - mozprocess == 0.25 12:59:44 INFO - mozprofile == 0.28 12:59:44 INFO - mozrunner == 6.13 12:59:44 INFO - mozscreenshot == 0.1 12:59:44 INFO - mozsystemmonitor == 0.3 12:59:44 INFO - moztest == 0.8 12:59:44 INFO - mozversion == 1.4 12:59:44 INFO - psutil == 3.1.1 12:59:44 INFO - requests == 1.2.3 12:59:44 INFO - wsgiref == 0.1.2 12:59:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:44 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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:44 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 12:59:44 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 12:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:44 INFO - 'HOME': '/Users/cltbld', 12:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:44 INFO - 'LOGNAME': 'cltbld', 12:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:44 INFO - 'MOZ_NO_REMOTE': '1', 12:59:44 INFO - 'NO_EM_RESTART': '1', 12:59:44 INFO - 'PAGER': '/bin/cat', 12:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:44 INFO - 'PWD': '/builds/slave/test', 12:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:44 INFO - 'SHELL': '/bin/bash', 12:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:44 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:44 INFO - 'USER': 'cltbld', 12:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:44 INFO - 'XPC_FLAGS': '0x0', 12:59:44 INFO - 'XPC_SERVICE_NAME': '0', 12:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 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)) 12:59:45 INFO - Cleaning up... 12:59:45 INFO - Return code: 0 12:59:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:59:45 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')]} 12:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:45 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')]} 12:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:45 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 0x109defb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109df2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff131c91340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, '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.QJL6ScWh15/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.59lujX6zDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:59:45 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 12:59:45 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 12:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 12:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:45 INFO - 'HOME': '/Users/cltbld', 12:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:45 INFO - 'LOGNAME': 'cltbld', 12:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:45 INFO - 'MOZ_NO_REMOTE': '1', 12:59:45 INFO - 'NO_EM_RESTART': '1', 12:59:45 INFO - 'PAGER': '/bin/cat', 12:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:45 INFO - 'PWD': '/builds/slave/test', 12:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:45 INFO - 'SHELL': '/bin/bash', 12:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 12:59:45 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:45 INFO - 'USER': 'cltbld', 12:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:45 INFO - 'XPC_FLAGS': '0x0', 12:59:45 INFO - 'XPC_SERVICE_NAME': '0', 12:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:59:45 INFO - Cleaning up... 12:59:45 INFO - Return code: 0 12:59:45 INFO - Running post-action listener: _resource_record_post_action 12:59:45 INFO - Running post-action listener: _start_resource_monitoring 12:59:45 INFO - Starting resource monitoring. 12:59:45 INFO - [mozharness: 2017-05-10 19:59:45.437646Z] Finished create-virtualenv step (success) 12:59:45 INFO - [mozharness: 2017-05-10 19:59:45.438575Z] Running install step. 12:59:45 INFO - Running pre-action listener: _resource_record_pre_action 12:59:45 INFO - Running main action method: install 12:59:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:45 INFO - Reading from file tmpfile_stdout 12:59:45 INFO - Detecting whether we're running mozinstall >=1.0... 12:59:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:59:45 INFO - Reading from file tmpfile_stdout 12:59:45 INFO - Output received: 12:59:45 INFO - Usage: mozinstall [options] installer 12:59:45 INFO - Options: 12:59:45 INFO - -h, --help show this help message and exit 12:59:45 INFO - -d DEST, --destination=DEST 12:59:45 INFO - Directory to install application into. [default: 12:59:45 INFO - "/builds/slave/test"] 12:59:45 INFO - --app=APP Application being installed. [default: firefox] 12:59:45 INFO - mkdir: /builds/slave/test/build/application 12:59:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:00:06 INFO - Reading from file tmpfile_stdout 13:00:06 INFO - Output received: 13:00:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:00:06 INFO - Running post-action listener: _resource_record_post_action 13:00:06 INFO - [mozharness: 2017-05-10 20:00:06.029644Z] Finished install step (success) 13:00:06 INFO - [mozharness: 2017-05-10 20:00:06.029780Z] Running run-tests step. 13:00:06 INFO - Running pre-action listener: _resource_record_pre_action 13:00:06 INFO - Running main action method: run_tests 13:00:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:00:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:00:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:00:06 INFO - 2017-05-10 13:00:06.043 screenresolution[1624:7410] starting screenresolution argv=screenresolution get 13:00:06 INFO - 2017-05-10 13:00:06.045 screenresolution[1624:7410] Display 0: 1600x1200x32@60 13:00:06 INFO - 2017-05-10 13:00:06.051 screenresolution[1625:7413] starting screenresolution argv=screenresolution list 13:00:06 INFO - Available Modes on Display 0 13:00:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:00:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:00:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:00:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:00:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:00:06 INFO - Intel Iris: 13:00:06 INFO - Chipset Model: Intel Iris 13:00:06 INFO - Type: GPU 13:00:06 INFO - Bus: Built-In 13:00:06 INFO - VRAM (Dynamic, Max): 1536 MB 13:00:06 INFO - Vendor: Intel (0x8086) 13:00:06 INFO - Device ID: 0x0a2e 13:00:06 INFO - Revision ID: 0x0009 13:00:06 INFO - Displays: 13:00:06 INFO - Display: 13:00:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:00:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:00:06 INFO - Display Serial Number: 1600x1200 60 13:00:06 INFO - Main Display: Yes 13:00:06 INFO - Mirror: Off 13:00:06 INFO - Online: Yes 13:00:06 INFO - Rotation: Supported 13:00:06 INFO - Return code: 0 13:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:00:06 INFO - Python 2.7.3 13:00:06 INFO - Return code: 0 13:00:06 INFO - grabbing minidump binary from tooltool 13:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:06 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 0x109b00d30>, '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 0x109ab3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109af4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:00:06 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 13:00:06 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 13:00:06 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 13:00:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:00:06 INFO - Return code: 0 13:00:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:00:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:00:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:00:06 INFO - ENV: RUST_BACKTRACE is now 1 13:00:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:00:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 13:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 13:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QJL6ScWh15/Render', 13:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:06 INFO - 'HOME': '/Users/cltbld', 13:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:06 INFO - 'LOGNAME': 'cltbld', 13:00:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:00:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:06 INFO - 'MOZ_NO_REMOTE': '1', 13:00:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:00:06 INFO - 'NO_EM_RESTART': '1', 13:00:06 INFO - 'PAGER': '/bin/cat', 13:00:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:06 INFO - 'PWD': '/builds/slave/test', 13:00:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:06 INFO - 'RUST_BACKTRACE': '1', 13:00:06 INFO - 'SHELL': '/bin/bash', 13:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.59lujX6zDD/Listeners', 13:00:06 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 13:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:06 INFO - 'USER': 'cltbld', 13:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:06 INFO - 'XPC_FLAGS': '0x0', 13:00:06 INFO - 'XPC_SERVICE_NAME': '0', 13:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 13:00:06 INFO - mozversion application_buildid: 20170510113606 13:00:06 INFO - mozversion application_changeset: ce2218406119c36a551e3faea4e192186ee46cc5 13:00:06 INFO - mozversion application_display_name: Nightly 13:00:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:00:06 INFO - mozversion application_name: Firefox 13:00:06 INFO - mozversion application_remotingname: firefox 13:00:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:00:06 INFO - mozversion application_vendor: Mozilla 13:00:06 INFO - mozversion application_version: 55.0a1 13:00:06 INFO - mozversion platform_buildid: 20170510113606 13:00:06 INFO - mozversion platform_changeset: ce2218406119c36a551e3faea4e192186ee46cc5 13:00:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:00:06 INFO - mozversion platform_version: 55.0a1 13:00:06 INFO - using testdate: 1494446406 13:00:06 INFO - actual date: 1494446406 13:00:06 INFO - starting webserver on 'localhost:49241' 13:00:06 INFO - SUITE-START | Running 2 tests 13:00:06 INFO - TEST-START | dromaeo_css 13:00:06 INFO - operating with platform_type : mac_ 13:00:06 INFO - Initialising browser for dromaeo_css test... 13:00:06 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp_K8wc8/profile http://localhost:49241/getInfo.html) 13:00:09 INFO - PID 1635 | 2017-05-10 13:00:09.128 plugin-container[1637:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 13:00:09 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:09 INFO - PID 1635 | 2017-05-10 13:00:09.132 plugin-container[1637:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 13:00:09 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:09 INFO - PID 1635 | 2017-05-10 13:00:09.132 plugin-container[1637:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:00:09 INFO - PID 1635 | __metrics Screen width/height:1600/1200 13:00:09 INFO - PID 1635 | colorDepth:24 13:00:09 INFO - PID 1635 | Browser inner width/height: 1024/691 13:00:09 INFO - PID 1635 | __metrics 13:00:09 INFO - PID 1635 | 13:00:09 INFO - PID 1635 | ###!!! [Child][MessageChannel] Error: (msgtype=0x7C0029,name=PHal::Msg_EnableWakeLockNotifications) Channel closing: too late to send/recv, messages will be lost 13:00:09 INFO - PID 1635 | 13:00:09 INFO - PID 1635 | 2017-05-10 13:00:09.822 plugin-container[1638:7649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 13:00:09 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:09 INFO - PID 1635 | 2017-05-10 13:00:09.823 plugin-container[1638:7649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4527, name = 'com.apple.CFPasteboardClient' 13:00:09 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:09 INFO - PID 1635 | 2017-05-10 13:00:09.823 plugin-container[1638:7649] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:00:10 INFO - TEST-INFO | 1635: exit 0 13:00:10 INFO - Browser initialized. 13:00:10 INFO - Running cycle 1/1 for dromaeo_css test... 13:00:10 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp_K8wc8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:12 INFO - PID 1639 | 2017-05-10 13:00:12.746 plugin-container[1640:7753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 13:00:12 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:12 INFO - PID 1639 | 2017-05-10 13:00:12.746 plugin-container[1640:7753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 13:00:12 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:12 INFO - PID 1639 | 2017-05-10 13:00:12.747 plugin-container[1640:7753] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:00:13 INFO - PID 1639 | 2017-05-10 13:00:13.162 plugin-container[1641:7799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 13:00:13 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:13 INFO - PID 1639 | 2017-05-10 13:00:13.163 plugin-container[1641:7799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 13:00:13 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:13 INFO - PID 1639 | 2017-05-10 13:00:13.163 plugin-container[1641:7799] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:00:22 INFO - PID 1639 | RSS: Main: 228327424 13:00:22 INFO - PID 1639 | 13:03:25 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 13:03:27 INFO - PID 1639 | RSS: Main: 254930944 13:03:27 INFO - PID 1639 | 13:06:29 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 13:06:29 INFO - PID 1639 | RSS: Main: 260501504 13:06:29 INFO - PID 1639 | 13:09:31 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 13:09:32 INFO - PID 1639 | RSS: Main: 270135296 13:09:32 INFO - PID 1639 | 13:12:34 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 13:12:34 INFO - PID 1639 | RSS: Main: 263012352 13:12:34 INFO - PID 1639 | 13:15:37 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 13:15:38 INFO - PID 1639 | RSS: Main: 266674176 13:15:38 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 13:18:39 INFO - PID 1639 | RSS: Main: 265359360 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | __start_tp_report 13:18:39 INFO - PID 1639 | _x_x_mozilla_page_load 13:18:39 INFO - PID 1639 | _x_x_mozilla_page_load_details 13:18:39 INFO - PID 1639 | |i|pagename|runs| 13:18:39 INFO - PID 1639 | |0;dojo.html;2775.944481110379;3067.202527342891;3612.133088058865;3613.7831730096214;3636.7999760013204;6144.293406258282;6155.753769849206;6168.845778855525;6214.875702485948;6223.813285601434;6136.7335323661255;6162.845928851781;6166.876662466748;6195.5663103582765;6219.937800621992;4950.95616469603;5240.633155679104;5368.62419630626;5390.245365648812;5403.2705584746045;5467.589930755189;5481.671099734009;5507.669539827603;5531.612787104904;5541.445855414467;4715.552022557851;5366.4633536646415;5413.918791218135;5414.91877621836;5452.809151679712;4399.626031787302;5392.110301800199;5408.2428460015235;5414.675119492843;5429.56563474921;5317.813876514342;5336.466353364672;5342.91985620216;5362.1420572708175;5374.140137578008;4655.79048942795;5051.570616497602;5967.910481342783;6144.801763656089;6189.535784816157;6643.40209381158;6681.632510211936;6686.665666716644;6693.698783554703;6696.330366963313;6787.626680532569;6792.76225332111;6793.932060679429;6805.455563554953;6810.931890681129;6145.893739127;6159.907601385983;6250.968745156336;6319.14691516644;6327.936720632827;6900.551464154814;6929.44564434844;6964.477664175207;6969.825754356181;6982.476314276348;6369.267534233622;6449.290578036413;6465;6550.050242714875;6638.369354911276;6695.531312808057;6703.430208432241;6755.459563234929;6778.152730908637;6795.966020169867;6400.615963042233;6486.44865186455;6648.767293144717;6697.22981857083;6701.262861085277;6523;6573.539852210395;6646;6671.266160722316;6699.76550820719;4992.425871024879;5329.467053294717;5336.226247194135;5352.411234764173;5396.892062158735;4708.317293992421;4712.222483290218;4731.7634118293945;4756.928646070242;4773.474917759044;6256.687165641791;6307.3377295383325;6322.146510221153;6363.9045414318825;6367.4906007519285;6799.388055074968;6835.897461538081;6852.725890964306;6857.2114206866845;6857.657117144223;6334;6343.365663433712;6407.519436042316;6452.35476452351;6461.127747753993;5808.941910580925;5891.234139561915;5894.557908156906;6023.849403764941;6053.545984051214;6849.109615750021;6931.7227310907;6980.057692211486;6982.266861979622;7007.544509606859;6770.5599136056;6809.650670518663;6822.317768223138;6825.35159159879;6830.872905970658;6892.862142757217;6953.33943275388;6997.860042799217;7012.298770122947;7018.368346848808;6417.294097649348;6560.40956313934;6563.113979612691;6590.209174899042;6650.002499625095;6361.77733779334;6476.125723027331;6709.966450167718;6836.111305530249;6838.726450942106;6262.060690896402;6410.102585637921;6411.166548348685;6506.707198175999;6588;6412.294647588668;6423.518236132215;6580.703868325841;6584.604923704593;6596.835079122869;5365.758540865749;5492.5331346835965;6268.780592679234;6342;6482.124913136666;6398.776042838478;6415.166028416276;6542.116814230209;6578.342165783383;6645.966770166118;6433.806985790434;6465.903011454926;6466.547341686037;6470.838229044311;6491.4482269005775 13:18:39 INFO - PID 1639 | |1;ext.html;730.9802825059043;777.1102088109114;820.1306614988112;1189.7263629365245;1240.206267988487;35792.642073579256;36856.63143368565;36891.26217475656;36957.260854782886;37083.258334833285;29770.25574360642;36958.63041369586;37051.44422833659;37214.8139259304;37224.81387593058;7707.845843083135;7848.4113691473085;8190.672373105099;8277.296429803473;8394.664213431455;8221.588920553979;8252.092269850311;8310.418270721051;8328.791780205513;8334.91646086011;6647.6343801090925;8269;8276.917230827676;8335.49987000782;8363.874541881876;6944.965275173642;7042.260562640951;8209.055958564782;8309.584520773968;8317.376196785235;8269.21442462971;8332.958335208285;8334;8348;8349.123342049059;36959;37104.81447592772;37123.81438092819;37396.81301593502;37459.43810842812;3397.3205358928067;3397.779144355634;3436.226848958989;3438.9312213755584;3441.48377743338;30098.84950575255;30371.544426833614;30448.54327185094;30521.847390763127;30541.541876871866;3133.27934575049;3134.965461397733;3159.7472202224;3164.7309978651842;3180.554722338851;226946;231934.680653193;286989;295284;295569;37649;38279;38924;47534.286985695246;48142.5185748145;39763;46067.539324606325;49622.75188624034;49651.50348496541;49813;14179.936504762185;14292.571222863331;14333.283335833166;14343.354549045318;14382.568522944328;11555.711107222387;11664;11767.76464470723;11770.470328835223;11791.879771421702;13228;13404.396802143927;13412.731745365038;13425.925925925903;13535.32323383827;5969.52243820502;6007.339192688801;6014.09788531715;6042.879142417127;6056.636601803818;998.1116806042628;1021.9795604087876;1025.4923812712755;1053.8524606555088;1054.283087500507;23080.65379019316;24368;24368.14711485125;24409.389765255655;25266.621000685005;1085.8425528298353;1103.7240689827543;1123.3035517978742;1136.8351589019546;1159.866615339247;12331.75336493265;12423.192492487959;12433.502659893684;12436.316002619762;12514.436850341757;304854;307150;307543;307947;308519;52839.47160528423;53082;53100.73449632804;53229.46770532245;53343.93312133736;1472.7054589082227;1525.4279645133297;1536.3700882638288;1539.2303848075962;1553.8756899448242;19039.238430462625;20061.598768024265;23825;23832.927518261375;23842.523149537257;36823;36996.89009329725;37142.88571342864;37157.628423716495;37319.067023324635;273261.63369183027;273811;273834;274411;275085;32020.35959280848;32240.516392254605;32268.838655806572;33057;33223;41535.58464415438;43201.78399107984;43374;43495;43689.56310436855;29395.85302073476;29824.552631710107;30376.392472150874;30547.54178687366;31036.068917932498;29731.256718582208;29860.402791944474;30536.083917482512;30708.53937190899;30771;29086.545672715856;36438.27123457463;36652;36671.81664091662;36774.63225367819;28678.85660571684;28774;29644.25889352697;30146.944856929902;30769.384612308077;18402.907985459988;22982.422982422795;24027.038918443766;24133.879330603235;24301.39246518851 13:18:39 INFO - PID 1639 | |2;jquery.html;432.46374495625435;437.7329828804429;441.59814568742433;449.94600647922255;459.8367579509274;21024;21091;21192.682109768346;21477.248296309634;21493;18139.093045347712;18307.084645767725;22209.44476388091;22914.65628015577;23165.652515212285;5731.796322772223;6068.059450785113;6297.937020629804;6301.558890877641;6304.180456540644;5352.437994010631;5882.794102206423;6241.1574437451045;6278.654673992928;6292.056191571255;4624.167649823047;4967.602591792648;5724.856878578027;6190.721417536221;6270.278917924428;6080.726367313481;6155.784547540815;6171.197744293236;6176.857281402955;6187.226596675416;5226.503482169188;5420.83737487876;5437.34751829779;5454.945450545483;5456.972715136439;24269.635955460682;26425;27527.86236068807;27642.308942276402;27771.583426248624;2264.2527965771255;2309.93070207894;2326.302109367183;2330.755270696586;2333.57995560799;12514.06144539154;17207.483775486755;17406.7388989164;17840;18019.369322073795;2001.8598698090993;2117.4177101297114;2153.569286142762;2153.7307836520436;2156.094440335063;155405;162894;164852.17573912174;167239;168211.99999999878;12662.430190641444;12665.240085594894;12734.55429059997;12929.224246545238;13061;16337.01977881331;16613.750793738105;16618.75071873923;16622.916885415496;16625.7506137408;8664.696735614223;8921.910780892238;8996.460212387277;9085.591148398338;9105.544722763836;8438.451500652438;8476.406651534333;8497.022842373082;8615.181557752003;8671.913280867237;7761;7890;7926.722564710208;7928;7932.167122452175;1795.5780391607962;1821.5172979160534;1992.8306093982173;2096.2663067926105;2134.51973306008;932.1098351074708;946.186279799372;967.6468089147411;975.0152346130459;986.6300137448457;13823.377947992365;17730.20214090369;19957.20171193165;20309;20980.89509552473;949.193185792085;1018.0684673523643;1032.3547782635853;1035.8187317219606;1045.5086109528509;9534.237261019101;10636.255462117731;10720.035196832167;10903.67288981316;10942.4528773561;538004;540812.4387587131;551224;553818;557714;121407;123484;127150;128410.35794820965;134091;1184;1222.358261912503;1246.8503779546513;1256.597888675615;1261.255859043169;21780.45548861291;22152.335429937128;22171.864572231287;22245.555088897807;22250.776207308107;52753;53330.2000470001;54241;54417;54922;74132;74796.13009674802;75440.62279688567;75451.62274188593;75505.24494755201;8765.649374024968;9142.177204051668;9235.861462077939;12805.487780488676;12895.355232238539;13830.585082447542;14018.369173387018;14253.64365890861;14406.567802965928;14514.85485145135;11727.413629318671;11826.408679565815;12078.637640870787;12141.08941829384;12145.757084858431;8675.961149129824;11271.04196143337;11376.317420954769;11408.600698975495;11597.594084207012;11831.40842957866;12109.81835272454;12190.451429685509;12290;12363.62909112728;12954.546590869275;13035.608931731684;13299.800502992657;13359.53241636538;13364.331783410986;11830.40847957616;11944.343061131374;12084.516619335129;12148.696282592653;12186.562687462365 13:18:39 INFO - PID 1639 | |3;mootools.html;1382.6405134664988;1412.668023014592;1434.7345741037916;1441.1929319581036;1452.6005348529154;11219.551217951283;11426.37154956477;11696.707582310446;11724.531018759239;11730;11420.257683250604;11610.12924030697;11612;11613.535458581646;11623.941880290588;2469.3579669286023;2502.6621406110216;2513.3339664988794;2514.710808257046;2515.119708102168;2449.289705985262;2488.203774792067;2499.762522560354;2504.474060447308;2506.1980166346775;2458.9508209835794;2463.0394146282965;2509.924702258935;2516.987415062922;2517.6349429332736;2443.609022556391;2456.0421435640205;2494.7131079925857;2498.1756020513276;2503.5493611149986;2198.043850924837;2400.543896659629;2480.04518260471;2483.925482235518;2487.3035550045834;9042.728718138467;9850.950745246228;11664.183507154503;11671.241369310968;11671.766564668744;6337.873242535124;6727.529072964894;6760.763373281911;6767.323267673193;6776.593404395752;3943.428202910562;4238.512571054306;4323.4163387942535;4325.480942286946;4364.170807546588;1239.374116071387;1255.2468518888593;1257.9685507862284;1292.0761655416422;1296.1834044551965;14799.520047995222;15503.844961550465;16496.42262520818;16586.170691465377;16616;5311.176767601028;5486.3690675572025;5674.744636491285;5681.0910254359105;5698.515626171739;11323.773524529463;11565.595204167814;11584.47869845859;11601.419929003516;11602.6519204424;6415.1981002374705;6497.122888410004;6498.447631951242;6510.641914694643;6511.316311787289;5997.640141591519;6093.756249750049;6135.815925522242;6187.907181392283;6234.501239900797;3921.529416469985;3988.4615576897327;4323.416338794223;4358.171947330061;4372.103718737651;1254.912156149079;1263.0088919015866;1269.1306455078302;1270.3521204185747;1276.0365923727527;794.4200733464603;797.3740613618281;814.7759366174349;818.3207937411934;839.8278352937633;6050.485708714808;6123.142760013601;6162.907556386658;6330.715117819709;6434.64609446485;489.454258501768;497.32363984980395;553.6352890125859;581.348889244041;601.3865856826101;3939.2515422069714;4206.936895946563;4312.547182545787;4314.9352759708;4423.491298500714;14548.127112373291;15057;15167.089974601558;15170.469033583771;15266.847331526764;13897.16617002983;14514.274286285643;14592.197429141499;14981.176035318162;15185.392584296504;8345.499270043816;8567.357448191535;8586.527740974181;8587.65649374018;8601.354898382646;6053.303870055;6779.72881084751;7040.8943865843075;7094.503384763224;7132.572045677276;12092.093093017882;12474;12592.118551701293;12602.054845886412;12706.809397858855;12810.697040980562;12906.354682266037;12930.870691292967;12935;13242;9141.268698504236;9318.207952324128;9380.20268277204;9386.48374339418;9388.248940084914;10350.896491035295;10447.320924139907;10571.36571805694;10599;10630.787384252426;8887.600057997277;9084.727458176265;9112.863307050533;9147.954260228922;9149.725508234764;8727.869081963649;8905.866412003696;8957;8971.461712297283;9110.681126160553;8144.959275203586;8259.628316725883;8302.792430189294;8303.6678532858;8305.750827475185;5679.34687510925;5996.640201587918;6151.96924015377;6163.630182189083;6253.155823963889;5463.672179669233;6091.664958427329;6209.2548894133;6253.09330147117;6354.26925903527 13:18:39 INFO - PID 1639 | |4;prototype.html;358.44083230160953;401.91559772447783;404.9149678567501;411.2186844994512;412.69666794905737;5971.31329897062;6249.3438188990185;6344.904826427596;6350.809475715724;6405.0072238803;4846.612271018319;5997.910031349533;6120.26556813183;6170.074488826678;6239.282482514513;4660.953390466086;4672.018876036028;4684.789184486689;4701.260533602572;4707.904982282886;4555.20283950309;4694.319323698079;4702.036082603076;4705.458872229686;4721.811127554894;3831.3678243089857;4470.619997300233;4569.880379307057;4575.33657619645;4678.485366609671;4588.669285907083;4607.838725644587;4644.767761611906;4666.813327466897;4674.906501869944;3271.9345613087603;3647.9270414591824;3864.265789499986;4110;4146.025683964266;5615.876824635089;6010.677650916791;6010.729517171738;6028.638281703112;6127.356627554132;4546.772661366918;5291.444398338158;5370.785168593252;5505.862353441196;5579.6652200868075;6497.837554061139;6570;6683.866322673519;6705.865882682368;6713.530052896299;3901.453796468497;4705.764711764432;4708.929366059512;4713.410823647044;4735.952640473585;5746.959829260594;6547.842120253673;6981.406580440668;7052.753153639598;7058.6823592938445;3495.632958539368;3637.308911306843;4234.569998301386;4349.695521313478;4383.60547550722;3786.5645450772963;4184.8744537663915;4468.843590474317;4473.373727678128;4479.5744404282195;5269.4730526947;5283.735813209324;5307.4692530746615;5346;5402.324709411324;5170.741462926839;6754.8649027020165;6769.120014398195;6770.255271920085;6770.916653335443;1283.8074288856747;1378.9933348655538;1547.597624617585;1568.7333153363957;1571.6385231396614;845.7843249971341;974.8440249560036;1004.8042829033427;1024.2676486312323;1029.8712660917386;6438.645874476855;6442.8389290268115;6464.448532352201;6465.676716164173;6484.967575162094;5404.32445944257;6907.240203577602;6942.375186233263;6989.266127056688;7017.228104908456;6100.620757904421;6391.42477177047;6403.391677790602;6414.454771344488;6492.610443373413;3226.7738259461253;3308.7849289796086;3318.900432987014;3638.0541059324764;3674.9816250919107;6580.638064906476;6970.372666460043;7149.320814522612;7217.963910180415;7243.710251589878;6189.731105123439;7183;7191;7200.855982880418;7207.315305046117;6471.437636466374;6489.545731798823;7214.89177662325;7217.128438304124;7218.458615603746;6127.816165515042;6450.9354906450335;6951.7914462566205;7021.297870212938;7021.648917554204;6628.602283862983;6776.322367763184;6883.109094180356;6932.341427564374;6940;7076.398506127037;7285.890711639436;7328.267173282629;7332.340089391981;7342.3758980487255;3854.9807250963568;4468.173387923244;4499.842505512292;4523.592876641118;4527.818887244473;6120.357362477054;6534.575252608565;6918.273581274095;7081;7146.248694252854;6188.566800323934;6813.318668133147;6815.829604259933;6841.144856892888;6861.348171923659;5582.888342233214;6643.6781609195405;6748.797536073926;6806.591604503746;6813.7955861324235;6644.4020038196795;6663.400293973566;6697.732090716317;6724.058631791651;6727.529072964846;6473.449756770728;6711.731530738716;6830.043793868763;6840.400647753821;6841.8973715395305;5768.019436695688;6307.495400368051;6338.271098823694;6621.702023408862;6641.800745977628 13:18:39 INFO - PID 1639 | |5;yui.html;760.2587477209718;809.7044578728764;820.7004443378169;847.1063028504929;867.583559891252;14364.712705745906;14959.326830292635;16191.190440477973;16711.24799384029;16769.580760480978;16385;16977.83022169778;17041.318347266126;17089.40187093452;17627.206775695093;820.7742870710573;987.4223579206155;1002.8144793213243;1005.5826831864767;1009.0716540782498;836.3476488339074;995.8257304971637;1003.1373019203479;1007.50632190227;1011.9038691324313;860.470810451574;1020.7703266764993;1032.8140934631763;1048.6644273832376;1050.837120246363;888.5201990924893;1020.443858097335;1036.5905467340433;1038.1331588123926;1042.7497400623872;711.5801677010537;771.1594362145289;771.6064806948441;773.8877862709932;774.570113586959;13781.862181378157;16246;16477.928934619213;17010.234539445755;17031.892926959707;16550;16740.16299185036;16853.494395168163;17027.48917532476;17246.482605521855;13508.594742157751;13787.793183102262;14034.228117453536;14066.788998165035;14073.085249458753;3320.966790332114;3325.285063711314;3325.8004519728893;3362.8318584070944;3367.44437167867;115216;129702.0544691821;130824;131530;132201;11990.940045299893;12178;12200.206986545845;12281.69295767595;12293.692657683629;20213.393598192077;21160.259390921245;21230.04464799088;21536.138554457648;21587.89206053991;7569.962150189324;7933.365330773524;8031.357491400674;8031.879521807177;8054.275115239657;3900.1029763154065;3962.9603703962593;3978.661813745864;3989.2447323177707;3992.3612222044335;4055.8174882130374;4117.382392641128;4166.562510936369;4178.101708132766;4181.184668989578;865.3466632692277;897.1701176411923;897.5871099294397;904.6652738486671;905.3798148268457;645.5319893077482;651.9706613202322;657.1293036726261;660.8182749743859;689.7137687859482;10736.516856741466;11206.271592346635;11952.103592230444;12067.698307542381;12354.444050017772;653.3597074866657;660.6927778582982;663.3797399431547;668.1914882991634;683.993160068393;5545.556355491551;5667.631603945731;5668.206451096849;5684.062129748628;5724.942750572524;107028;107196;109730;110388.4480577608;112109;75895;95102.52448737712;95339;97114.5144274274;97912;82219.5889020563;85155.57422212849;85343.57328213319;85472;85959;10697.679069627924;11216.158788091088;11242.66272011841;11263;11444.313341199555;38468.23063538658;38846.22307553777;38922.2215555693;39075;39267.60732392639;43074.78462607667;50799.492005079475;51061.97876042533;51549.226761597856;52177.73911130572;13749.793753093913;13922.23427711445;13989;14068.929655351658;14112.788308175592;15231.466898658493;15481.84518154804;15804.60488487797;15904.76142857835;15937.20313984273;14114.78827817563;14416.414194438394;14429.711405772036;14522;14705.485308014167;14494.565163045125;14777.039492432958;14816.333265003317;14864.702705946036;14944.178070206242;14336.56990290293;14416.639584010483;14594.85405145935;14782.260886955824;14841.554753357417;8577.485350878967;10521.631742888962;10532.315399499008;10702.304350217211;10703;10234.82299535563;11744.471498782746;11851.525938962344;11971.940140299243;11972.521099155936 13:18:39 INFO - PID 1639 | __end_tp_report 13:18:39 INFO - PID 1639 | __start_cc_report 13:18:39 INFO - PID 1639 | _x_x_mozilla_cycle_collect,408 13:18:39 INFO - PID 1639 | __end_cc_report 13:18:39 INFO - PID 1639 | __startTimestamp1494447519965__endTimestamp 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | ------- Summary: start ------- 13:18:39 INFO - PID 1639 | Number of tests: 6 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6133.49 Median:6410.10 stddev:801.69 (12.5%) stddev-sans-first:759.65 13:18:39 INFO - PID 1639 | Values: 2775.9 3067.2 3612.1 3613.8 3636.8 6144.3 6155.8 6168.8 6214.9 6223.8 6136.7 6162.8 6166.9 6195.6 6219.9 4951.0 5240.6 5368.6 5390.2 5403.3 5467.6 5481.7 5507.7 5531.6 5541.4 4715.6 5366.5 5413.9 5414.9 5452.8 4399.6 5392.1 5408.2 5414.7 5429.6 5317.8 5336.5 5342.9 5362.1 5374.1 4655.8 5051.6 5967.9 6144.8 6189.5 6643.4 6681.6 6686.7 6693.7 6696.3 6787.6 6792.8 6793.9 6805.5 6810.9 6145.9 6159.9 6251.0 6319.1 6327.9 6900.6 6929.4 6964.5 6969.8 6982.5 6369.3 6449.3 6465.0 6550.1 6638.4 6695.5 6703.4 6755.5 6778.2 6796.0 6400.6 6486.4 6648.8 6697.2 6701.3 6523.0 6573.5 6646.0 6671.3 6699.8 4992.4 5329.5 5336.2 5352.4 5396.9 4708.3 4712.2 4731.8 4756.9 4773.5 6256.7 6307.3 6322.1 6363.9 6367.5 6799.4 6835.9 6852.7 6857.2 6857.7 6334.0 6343.4 6407.5 6452.4 6461.1 5808.9 5891.2 5894.6 6023.8 6053.5 6849.1 6931.7 6980.1 6982.3 7007.5 6770.6 6809.7 6822.3 6825.4 6830.9 6892.9 6953.3 6997.9 7012.3 7018.4 6417.3 6560.4 6563.1 6590.2 6650.0 6361.8 6476.1 6710.0 6836.1 6838.7 6262.1 6410.1 6411.2 6506.7 6588.0 6412.3 6423.5 6580.7 6584.6 6596.8 5365.8 5492.5 6268.8 6342.0 6482.1 6398.8 6415.2 6542.1 6578.3 6646.0 6433.8 6465.9 6466.5 6470.8 6491.4 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:42986.04 Median:3162.24 stddev:74390.11 (2352.5%) stddev-sans-first:74531.14 13:18:39 INFO - PID 1639 | Values: 731.0 777.1 820.1 1189.7 1240.2 35792.6 36856.6 36891.3 36957.3 37083.3 29770.3 36958.6 37051.4 37214.8 37224.8 7707.8 7848.4 8190.7 8277.3 8394.7 8221.6 8252.1 8310.4 8328.8 8334.9 6647.6 8269.0 8276.9 8335.5 8363.9 6945.0 7042.3 8209.1 8309.6 8317.4 8269.2 8333.0 8334.0 8348.0 8349.1 36959.0 37104.8 37123.8 37396.8 37459.4 3397.3 3397.8 3436.2 3438.9 3441.5 30098.8 30371.5 30448.5 30521.8 30541.5 3133.3 3135.0 3159.7 3164.7 3180.6 226946.0 231934.7 286989.0 295284.0 295569.0 37649.0 38279.0 38924.0 47534.3 48142.5 39763.0 46067.5 49622.8 49651.5 49813.0 14179.9 14292.6 14333.3 14343.4 14382.6 11555.7 11664.0 11767.8 11770.5 11791.9 13228.0 13404.4 13412.7 13425.9 13535.3 5969.5 6007.3 6014.1 6042.9 6056.6 998.1 1022.0 1025.5 1053.9 1054.3 23080.7 24368.0 24368.1 24409.4 25266.6 1085.8 1103.7 1123.3 1136.8 1159.9 12331.8 12423.2 12433.5 12436.3 12514.4 304854.0 307150.0 307543.0 307947.0 308519.0 52839.5 53082.0 53100.7 53229.5 53343.9 1472.7 1525.4 1536.4 1539.2 1553.9 19039.2 20061.6 23825.0 23832.9 23842.5 36823.0 36996.9 37142.9 37157.6 37319.1 273261.6 273811.0 273834.0 274411.0 275085.0 32020.4 32240.5 32268.8 33057.0 33223.0 41535.6 43201.8 43374.0 43495.0 43689.6 29395.9 29824.6 30376.4 30547.5 31036.1 29731.3 29860.4 30536.1 30708.5 30771.0 29086.5 36438.3 36652.0 36671.8 36774.6 28678.9 28774.0 29644.3 30146.9 30769.4 18402.9 22982.4 24027.0 24133.9 24301.4 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:35660.78 Median:11968.27 stddev:93228.05 (779.0%) stddev-sans-first:93452.05 13:18:39 INFO - PID 1639 | Values: 432.5 437.7 441.6 449.9 459.8 21024.0 21091.0 21192.7 21477.2 21493.0 18139.1 18307.1 22209.4 22914.7 23165.7 5731.8 6068.1 6297.9 6301.6 6304.2 5352.4 5882.8 6241.2 6278.7 6292.1 4624.2 4967.6 5724.9 6190.7 6270.3 6080.7 6155.8 6171.2 6176.9 6187.2 5226.5 5420.8 5437.3 5454.9 5457.0 24269.6 26425.0 27527.9 27642.3 27771.6 2264.3 2309.9 2326.3 2330.8 2333.6 12514.1 17207.5 17406.7 17840.0 18019.4 2001.9 2117.4 2153.6 2153.7 2156.1 155405.0 162894.0 164852.2 167239.0 168212.0 12662.4 12665.2 12734.6 12929.2 13061.0 16337.0 16613.8 16618.8 16622.9 16625.8 8664.7 8921.9 8996.5 9085.6 9105.5 8438.5 8476.4 8497.0 8615.2 8671.9 7761.0 7890.0 7926.7 7928.0 7932.2 1795.6 1821.5 1992.8 2096.3 2134.5 932.1 946.2 967.6 975.0 986.6 13823.4 17730.2 19957.2 20309.0 20980.9 949.2 1018.1 1032.4 1035.8 1045.5 9534.2 10636.3 10720.0 10903.7 10942.5 538004.0 540812.4 551224.0 553818.0 557714.0 121407.0 123484.0 127150.0 128410.4 134091.0 1184.0 1222.4 1246.9 1256.6 1261.3 21780.5 22152.3 22171.9 22245.6 22250.8 52753.0 53330.2 54241.0 54417.0 54922.0 74132.0 74796.1 75440.6 75451.6 75505.2 8765.6 9142.2 9235.9 12805.5 12895.4 13830.6 14018.4 14253.6 14406.6 14514.9 11727.4 11826.4 12078.6 12141.1 12145.8 8676.0 11271.0 11376.3 11408.6 11597.6 11831.4 12109.8 12190.5 12290.0 12363.6 12954.5 13035.6 13299.8 13359.5 13364.3 11830.4 11944.3 12084.5 12148.7 12186.6 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:6989.70 Median:3965.94 stddev:4379.54 (110.4%) stddev-sans-first:4371.56 13:18:39 INFO - PID 1639 | Values: 1382.6 1412.7 1434.7 1441.2 1452.6 11219.6 11426.4 11696.7 11724.5 11730.0 11420.3 11610.1 11612.0 11613.5 11623.9 2469.4 2502.7 2513.3 2514.7 2515.1 2449.3 2488.2 2499.8 2504.5 2506.2 2459.0 2463.0 2509.9 2517.0 2517.6 2443.6 2456.0 2494.7 2498.2 2503.5 2198.0 2400.5 2480.0 2483.9 2487.3 9042.7 9851.0 11664.2 11671.2 11671.8 6337.9 6727.5 6760.8 6767.3 6776.6 3943.4 4238.5 4323.4 4325.5 4364.2 1239.4 1255.2 1258.0 1292.1 1296.2 14799.5 15503.8 16496.4 16586.2 16616.0 5311.2 5486.4 5674.7 5681.1 5698.5 11323.8 11565.6 11584.5 11601.4 11602.7 6415.2 6497.1 6498.4 6510.6 6511.3 5997.6 6093.8 6135.8 6187.9 6234.5 3921.5 3988.5 4323.4 4358.2 4372.1 1254.9 1263.0 1269.1 1270.4 1276.0 794.4 797.4 814.8 818.3 839.8 6050.5 6123.1 6162.9 6330.7 6434.6 489.5 497.3 553.6 581.3 601.4 3939.3 4206.9 4312.5 4314.9 4423.5 14548.1 15057.0 15167.1 15170.5 15266.8 13897.2 14514.3 14592.2 14981.2 15185.4 8345.5 8567.4 8586.5 8587.7 8601.4 6053.3 6779.7 7040.9 7094.5 7132.6 12092.1 12474.0 12592.1 12602.1 12706.8 12810.7 12906.4 12930.9 12935.0 13242.0 9141.3 9318.2 9380.2 9386.5 9388.2 10350.9 10447.3 10571.4 10599.0 10630.8 8887.6 9084.7 9112.9 9148.0 9149.7 8727.9 8905.9 8957.0 8971.5 9110.7 8145.0 8259.6 8302.8 8303.7 8305.8 5679.3 5996.6 6152.0 6163.6 6253.2 5463.7 6091.7 6209.3 6253.1 6354.3 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5421.19 Median:6179.32 stddev:1755.27 (28.4%) stddev-sans-first:1718.57 13:18:39 INFO - PID 1639 | Values: 358.4 401.9 404.9 411.2 412.7 5971.3 6249.3 6344.9 6350.8 6405.0 4846.6 5997.9 6120.3 6170.1 6239.3 4661.0 4672.0 4684.8 4701.3 4707.9 4555.2 4694.3 4702.0 4705.5 4721.8 3831.4 4470.6 4569.9 4575.3 4678.5 4588.7 4607.8 4644.8 4666.8 4674.9 3271.9 3647.9 3864.3 4110.0 4146.0 5615.9 6010.7 6010.7 6028.6 6127.4 4546.8 5291.4 5370.8 5505.9 5579.7 6497.8 6570.0 6683.9 6705.9 6713.5 3901.5 4705.8 4708.9 4713.4 4736.0 5747.0 6547.8 6981.4 7052.8 7058.7 3495.6 3637.3 4234.6 4349.7 4383.6 3786.6 4184.9 4468.8 4473.4 4479.6 5269.5 5283.7 5307.5 5346.0 5402.3 5170.7 6754.9 6769.1 6770.3 6770.9 1283.8 1379.0 1547.6 1568.7 1571.6 845.8 974.8 1004.8 1024.3 1029.9 6438.6 6442.8 6464.4 6465.7 6485.0 5404.3 6907.2 6942.4 6989.3 7017.2 6100.6 6391.4 6403.4 6414.5 6492.6 3226.8 3308.8 3318.9 3638.1 3675.0 6580.6 6970.4 7149.3 7218.0 7243.7 6189.7 7183.0 7191.0 7200.9 7207.3 6471.4 6489.5 7214.9 7217.1 7218.5 6127.8 6450.9 6951.8 7021.3 7021.6 6628.6 6776.3 6883.1 6932.3 6940.0 7076.4 7285.9 7328.3 7332.3 7342.4 3855.0 4468.2 4499.8 4523.6 4527.8 6120.4 6534.6 6918.3 7081.0 7146.2 6188.6 6813.3 6815.8 6841.1 6861.3 5582.9 6643.7 6748.8 6806.6 6813.8 6644.4 6663.4 6697.7 6724.1 6727.5 6473.4 6711.7 6830.0 6840.4 6841.9 5768.0 6307.5 6338.3 6621.7 6641.8 13:18:39 INFO - PID 1639 | 13:18:39 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:21300.15 Median:16994.03 stddev:31443.26 (185.0%) stddev-sans-first:31493.65 13:18:39 INFO - PID 1639 | Values: 760.3 809.7 820.7 847.1 867.6 14364.7 14959.3 16191.2 16711.2 16769.6 16385.0 16977.8 17041.3 17089.4 17627.2 820.8 987.4 1002.8 1005.6 1009.1 836.3 995.8 1003.1 1007.5 1011.9 860.5 1020.8 1032.8 1048.7 1050.8 888.5 1020.4 1036.6 1038.1 1042.7 711.6 771.2 771.6 773.9 774.6 13781.9 16246.0 16477.9 17010.2 17031.9 16550.0 16740.2 16853.5 17027.5 17246.5 13508.6 13787.8 14034.2 14066.8 14073.1 3321.0 3325.3 3325.8 3362.8 3367.4 115216.0 129702.1 130824.0 131530.0 132201.0 11990.9 12178.0 12200.2 12281.7 12293.7 20213.4 21160.3 21230.0 21536.1 21587.9 7570.0 7933.4 8031.4 8031.9 8054.3 3900.1 3963.0 3978.7 3989.2 3992.4 4055.8 4117.4 4166.6 4178.1 4181.2 865.3 897.2 897.6 904.7 905.4 645.5 652.0 657.1 660.8 689.7 10736.5 11206.3 11952.1 12067.7 12354.4 653.4 660.7 663.4 668.2 684.0 5545.6 5667.6 5668.2 5684.1 5724.9 107028.0 107196.0 109730.0 110388.4 112109.0 75895.0 95102.5 95339.0 97114.5 97912.0 82219.6 85155.6 85343.6 85472.0 85959.0 10697.7 11216.2 11242.7 11263.0 11444.3 38468.2 38846.2 38922.2 39075.0 39267.6 43074.8 50799.5 51062.0 51549.2 52177.7 13749.8 13922.2 13989.0 14068.9 14112.8 15231.5 15481.8 15804.6 15904.8 15937.2 14114.8 14416.4 14429.7 14522.0 14705.5 14494.6 14777.0 14816.3 14864.7 14944.2 14336.6 14416.6 14594.9 14782.3 14841.6 8577.5 10521.6 10532.3 10702.3 10703.0 10234.8 11744.5 11851.5 11971.9 11972.5 13:18:39 INFO - PID 1639 | -------- Summary: end -------- 13:18:39 INFO - PID 1639 | 13:18:40 INFO - TEST-INFO | 1639: exit 0 13:18:40 INFO - TEST-OK | dromaeo_css | took 1114042ms 13:18:40 INFO - TEST-START | kraken 13:18:40 INFO - operating with platform_type : mac_ 13:18:40 INFO - Initialising browser for kraken test... 13:18:40 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpWA3t_5/profile http://localhost:49241/getInfo.html) 13:18:43 INFO - PID 1653 | 2017-05-10 13:18:43.344 plugin-container[1654:10204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 13:18:43 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:18:43 INFO - PID 1653 | 2017-05-10 13:18:43.348 plugin-container[1654:10204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 13:18:43 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:18:43 INFO - PID 1653 | 2017-05-10 13:18:43.348 plugin-container[1654:10204] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:18:43 INFO - PID 1653 | __metrics Screen width/height:1600/1200 13:18:43 INFO - PID 1653 | colorDepth:24 13:18:43 INFO - PID 1653 | Browser inner width/height: 1024/691 13:18:43 INFO - PID 1653 | __metrics 13:18:44 INFO - TEST-INFO | 1653: exit 0 13:18:44 INFO - Browser initialized. 13:18:44 INFO - Running cycle 1/1 for kraken test... 13:18:44 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpWA3t_5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:46 INFO - PID 1656 | 2017-05-10 13:18:46.444 plugin-container[1657:10361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 13:18:46 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:18:46 INFO - PID 1656 | 2017-05-10 13:18:46.445 plugin-container[1657:10361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 13:18:46 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:18:46 INFO - PID 1656 | 2017-05-10 13:18:46.445 plugin-container[1657:10361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:18:47 INFO - PID 1656 | 2017-05-10 13:18:47.170 plugin-container[1658:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 13:18:47 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:18:47 INFO - PID 1656 | 2017-05-10 13:18:47.171 plugin-container[1658:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 13:18:47 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:18:47 INFO - PID 1656 | 2017-05-10 13:18:47.171 plugin-container[1658:10405] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:18:56 INFO - PID 1656 | RSS: Main: 230387712 13:18:56 INFO - PID 1656 | 13:18:59 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 13:19:00 INFO - PID 1656 | RSS: Main: 242262016 13:19:00 INFO - PID 1656 | 13:19:03 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 13:19:03 INFO - PID 1656 | RSS: Main: 250707968 13:19:03 INFO - PID 1656 | 13:19:07 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 13:19:07 INFO - PID 1656 | RSS: Main: 253157376 13:19:07 INFO - PID 1656 | 13:19:09 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 13:19:10 INFO - PID 1656 | RSS: Main: 254083072 13:19:10 INFO - PID 1656 | 13:19:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:19:13 INFO - PID 1656 | RSS: Main: 254447616 13:19:13 INFO - PID 1656 | 13:19:16 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 13:19:16 INFO - PID 1656 | RSS: Main: 260513792 13:19:16 INFO - PID 1656 | 13:19:19 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 13:19:20 INFO - PID 1656 | RSS: Main: 261111808 13:19:20 INFO - PID 1656 | 13:19:23 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 13:19:24 INFO - PID 1656 | RSS: Main: 261193728 13:19:24 INFO - PID 1656 | 13:19:25 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:19:26 INFO - PID 1656 | RSS: Main: 260878336 13:19:26 INFO - PID 1656 | 13:19:28 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:19:29 INFO - PID 1656 | RSS: Main: 260890624 13:19:29 INFO - PID 1656 | 13:19:31 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:19:32 INFO - PID 1656 | RSS: Main: 260526080 13:19:32 INFO - PID 1656 | 13:19:34 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:19:35 INFO - PID 1656 | RSS: Main: 262152192 13:19:35 INFO - PID 1656 | 13:19:38 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:19:39 INFO - PID 1656 | RSS: Main: 262057984 13:19:39 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 13:19:41 INFO - PID 1656 | RSS: Main: 263856128 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | __start_tp_report 13:19:41 INFO - PID 1656 | _x_x_mozilla_page_load 13:19:41 INFO - PID 1656 | _x_x_mozilla_page_load_details 13:19:41 INFO - PID 1656 | |i|pagename|runs| 13:19:41 INFO - PID 1656 | |0;ai-astar;110;175;162;114;113;112;114;116;115;114 13:19:41 INFO - PID 1656 | |1;audio-beat-detection;183;153;116;151;147;150;144;115;139;109 13:19:41 INFO - PID 1656 | |2;audio-dft;197;206;271;225;279;282;198;194;199;200 13:19:41 INFO - PID 1656 | |3;audio-fft;82;56;57;83;57;61;62;71;74;57 13:19:41 INFO - PID 1656 | |4;audio-oscillator;87;71;88;59;65;79;108;105;108;116 13:19:41 INFO - PID 1656 | |5;imaging-gaussian-blur;101;126;136;93;108;126;104;89;89;93 13:19:41 INFO - PID 1656 | |6;imaging-darkroom;97;98;92;97;97;107;96;136;112;102 13:19:41 INFO - PID 1656 | |7;imaging-desaturate;128;92;90;95;131;92;130;133;94;90 13:19:41 INFO - PID 1656 | |8;json-parse-financial;48;49;51;60;46;41;53;55;77;77 13:19:41 INFO - PID 1656 | |9;json-stringify-tinderbox;83;74;55;58;60;62;54;58;84;81 13:19:41 INFO - PID 1656 | |10;stanford-crypto-aes;80;83;90;79;75;75;110;108;108;102 13:19:41 INFO - PID 1656 | |11;stanford-crypto-ccm;136;139;135;137;137;138;137;148;192;175 13:19:41 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;185;187;187;185;184;186;184;203;239;188 13:19:41 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;68;69;69;66;84;67;69;67;71;66 13:19:41 INFO - PID 1656 | __end_tp_report 13:19:41 INFO - PID 1656 | __start_cc_report 13:19:41 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1009 13:19:41 INFO - PID 1656 | __end_cc_report 13:19:41 INFO - PID 1656 | __startTimestamp1494447581307__endTimestamp 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | ------- Summary: start ------- 13:19:41 INFO - PID 1656 | Number of tests: 14 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:124.50 Median:114.50 stddev:23.45 (20.5%) stddev-sans-first:24.28 13:19:41 INFO - PID 1656 | Values: 110.0 175.0 162.0 114.0 113.0 112.0 114.0 116.0 115.0 114.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:148.50 stddev:22.27 (15.0%) stddev-sans-first:17.59 13:19:41 INFO - PID 1656 | Values: 183.0 153.0 116.0 151.0 147.0 150.0 144.0 115.0 139.0 109.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:225.10 Median:215.50 stddev:37.14 (17.2%) stddev-sans-first:37.98 13:19:41 INFO - PID 1656 | Values: 197.0 206.0 271.0 225.0 279.0 282.0 198.0 194.0 199.0 200.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:66.00 Median:66.50 stddev:10.64 (16.0%) stddev-sans-first:9.58 13:19:41 INFO - PID 1656 | Values: 82.0 56.0 57.0 83.0 57.0 61.0 62.0 71.0 74.0 57.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:68.00 stddev:20.03 (29.5%) stddev-sans-first:21.24 13:19:41 INFO - PID 1656 | Values: 87.0 71.0 88.0 59.0 65.0 79.0 108.0 105.0 108.0 116.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:106.50 Median:112.50 stddev:17.15 (15.2%) stddev-sans-first:18.07 13:19:41 INFO - PID 1656 | Values: 101.0 126.0 136.0 93.0 108.0 126.0 104.0 89.0 89.0 93.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:96.50 stddev:12.86 (13.3%) stddev-sans-first:13.43 13:19:41 INFO - PID 1656 | Values: 97.0 98.0 92.0 97.0 97.0 107.0 96.0 136.0 112.0 102.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:91.00 stddev:19.89 (21.9%) stddev-sans-first:19.66 13:19:41 INFO - PID 1656 | Values: 128.0 92.0 90.0 95.0 131.0 92.0 130.0 133.0 94.0 90.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:54.00 stddev:12.34 (22.8%) stddev-sans-first:12.77 13:19:41 INFO - PID 1656 | Values: 48.0 49.0 51.0 60.0 46.0 41.0 53.0 55.0 77.0 77.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:68.00 stddev:12.20 (17.9%) stddev-sans-first:11.46 13:19:41 INFO - PID 1656 | Values: 83.0 74.0 55.0 58.0 60.0 62.0 54.0 58.0 84.0 81.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:77.00 stddev:14.54 (18.9%) stddev-sans-first:14.86 13:19:41 INFO - PID 1656 | Values: 80.0 83.0 90.0 79.0 75.0 75.0 110.0 108.0 108.0 102.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:138.50 stddev:19.77 (14.3%) stddev-sans-first:20.54 13:19:41 INFO - PID 1656 | Values: 136.0 139.0 135.0 137.0 137.0 138.0 137.0 148.0 192.0 175.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:187.00 stddev:17.16 (9.2%) stddev-sans-first:17.97 13:19:41 INFO - PID 1656 | Values: 185.0 187.0 187.0 185.0 184.0 186.0 184.0 203.0 239.0 188.0 13:19:41 INFO - PID 1656 | 13:19:41 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.00 stddev:5.30 (7.7%) stddev-sans-first:5.59 13:19:41 INFO - PID 1656 | Values: 68.0 69.0 69.0 66.0 84.0 67.0 69.0 67.0 71.0 66.0 13:19:41 INFO - PID 1656 | -------- Summary: end -------- 13:19:41 INFO - PID 1656 | 13:19:42 INFO - TEST-INFO | 1656: exit 0 13:19:42 INFO - TEST-OK | kraken | took 61541ms 13:19:42 INFO - SUITE-END | took 1175s 13:19:42 INFO - Completed test suite (00:19:35) 13:19:42 INFO - javascript benchmark 13:19:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7790.385106616158, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2775.944481110379, 3067.202527342891, 3612.133088058865, 3613.7831730096214, 3636.7999760013204, 6144.293406258282, 6155.753769849206, 6168.845778855525, 6214.875702485948, 6223.813285601434, 6136.7335323661255, 6162.845928851781, 6166.876662466748, 6195.5663103582765, 6219.937800621992, 4950.95616469603, 5240.633155679104, 5368.62419630626, 5390.245365648812, 5403.2705584746045, 5467.589930755189, 5481.671099734009, 5507.669539827603, 5531.612787104904, 5541.445855414467, 4715.552022557851, 5366.4633536646415, 5413.918791218135, 5414.91877621836, 5452.809151679712, 4399.626031787302, 5392.110301800199, 5408.2428460015235, 5414.675119492843, 5429.56563474921, 5317.813876514342, 5336.466353364672, 5342.91985620216, 5362.1420572708175, 5374.140137578008, 4655.79048942795, 5051.570616497602, 5967.910481342783, 6144.801763656089, 6189.535784816157, 6643.40209381158, 6681.632510211936, 6686.665666716644, 6693.698783554703, 6696.330366963313, 6787.626680532569, 6792.76225332111, 6793.932060679429, 6805.455563554953, 6810.931890681129, 6145.893739127, 6159.907601385983, 6250.968745156336, 6319.14691516644, 6327.936720632827, 6900.551464154814, 6929.44564434844, 6964.477664175207, 6969.825754356181, 6982.476314276348, 6369.267534233622, 6449.290578036413, 6465.0, 6550.050242714875, 6638.369354911276, 6695.531312808057, 6703.430208432241, 6755.459563234929, 6778.152730908637, 6795.966020169867, 6400.615963042233, 6486.44865186455, 6648.767293144717, 6697.22981857083, 6701.262861085277, 6523.0, 6573.539852210395, 6646.0, 6671.266160722316, 6699.76550820719, 4992.425871024879, 5329.467053294717, 5336.226247194135, 5352.411234764173, 5396.892062158735, 4708.317293992421, 4712.222483290218, 4731.7634118293945, 4756.928646070242, 4773.474917759044, 6256.687165641791, 6307.3377295383325, 6322.146510221153, 6363.9045414318825, 6367.4906007519285, 6799.388055074968, 6835.897461538081, 6852.725890964306, 6857.2114206866845, 6857.657117144223, 6334.0, 6343.365663433712, 6407.519436042316, 6452.35476452351, 6461.127747753993, 5808.941910580925, 5891.234139561915, 5894.557908156906, 6023.849403764941, 6053.545984051214, 6849.109615750021, 6931.7227310907, 6980.057692211486, 6982.266861979622, 7007.544509606859, 6770.5599136056, 6809.650670518663, 6822.317768223138, 6825.35159159879, 6830.872905970658, 6892.862142757217, 6953.33943275388, 6997.860042799217, 7012.298770122947, 7018.368346848808, 6417.294097649348, 6560.40956313934, 6563.113979612691, 6590.209174899042, 6650.002499625095, 6361.77733779334, 6476.125723027331, 6709.966450167718, 6836.111305530249, 6838.726450942106, 6262.060690896402, 6410.102585637921, 6411.166548348685, 6506.707198175999, 6588.0, 6412.294647588668, 6423.518236132215, 6580.703868325841, 6584.604923704593, 6596.835079122869, 5365.758540865749, 5492.5331346835965, 6268.780592679234, 6342.0, 6482.124913136666, 6398.776042838478, 6415.166028416276, 6542.116814230209, 6578.342165783383, 6645.966770166118, 6433.806985790434, 6465.903011454926, 6466.547341686037, 6470.838229044311, 6491.4482269005775], "value": 6074.517868568895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.9802825059043, 777.1102088109114, 820.1306614988112, 1189.7263629365245, 1240.206267988487, 35792.642073579256, 36856.63143368565, 36891.26217475656, 36957.260854782886, 37083.258334833285, 29770.25574360642, 36958.63041369586, 37051.44422833659, 37214.8139259304, 37224.81387593058, 7707.845843083135, 7848.4113691473085, 8190.672373105099, 8277.296429803473, 8394.664213431455, 8221.588920553979, 8252.092269850311, 8310.418270721051, 8328.791780205513, 8334.91646086011, 6647.6343801090925, 8269.0, 8276.917230827676, 8335.49987000782, 8363.874541881876, 6944.965275173642, 7042.260562640951, 8209.055958564782, 8309.584520773968, 8317.376196785235, 8269.21442462971, 8332.958335208285, 8334.0, 8348.0, 8349.123342049059, 36959.0, 37104.81447592772, 37123.81438092819, 37396.81301593502, 37459.43810842812, 3397.3205358928067, 3397.779144355634, 3436.226848958989, 3438.9312213755584, 3441.48377743338, 30098.84950575255, 30371.544426833614, 30448.54327185094, 30521.847390763127, 30541.541876871866, 3133.27934575049, 3134.965461397733, 3159.7472202224, 3164.7309978651842, 3180.554722338851, 226946.0, 231934.680653193, 286989.0, 295284.0, 295569.0, 37649.0, 38279.0, 38924.0, 47534.286985695246, 48142.5185748145, 39763.0, 46067.539324606325, 49622.75188624034, 49651.50348496541, 49813.0, 14179.936504762185, 14292.571222863331, 14333.283335833166, 14343.354549045318, 14382.568522944328, 11555.711107222387, 11664.0, 11767.76464470723, 11770.470328835223, 11791.879771421702, 13228.0, 13404.396802143927, 13412.731745365038, 13425.925925925903, 13535.32323383827, 5969.52243820502, 6007.339192688801, 6014.09788531715, 6042.879142417127, 6056.636601803818, 998.1116806042628, 1021.9795604087876, 1025.4923812712755, 1053.8524606555088, 1054.283087500507, 23080.65379019316, 24368.0, 24368.14711485125, 24409.389765255655, 25266.621000685005, 1085.8425528298353, 1103.7240689827543, 1123.3035517978742, 1136.8351589019546, 1159.866615339247, 12331.75336493265, 12423.192492487959, 12433.502659893684, 12436.316002619762, 12514.436850341757, 304854.0, 307150.0, 307543.0, 307947.0, 308519.0, 52839.47160528423, 53082.0, 53100.73449632804, 53229.46770532245, 53343.93312133736, 1472.7054589082227, 1525.4279645133297, 1536.3700882638288, 1539.2303848075962, 1553.8756899448242, 19039.238430462625, 20061.598768024265, 23825.0, 23832.927518261375, 23842.523149537257, 36823.0, 36996.89009329725, 37142.88571342864, 37157.628423716495, 37319.067023324635, 273261.63369183027, 273811.0, 273834.0, 274411.0, 275085.0, 32020.35959280848, 32240.516392254605, 32268.838655806572, 33057.0, 33223.0, 41535.58464415438, 43201.78399107984, 43374.0, 43495.0, 43689.56310436855, 29395.85302073476, 29824.552631710107, 30376.392472150874, 30547.54178687366, 31036.068917932498, 29731.256718582208, 29860.402791944474, 30536.083917482512, 30708.53937190899, 30771.0, 29086.545672715856, 36438.27123457463, 36652.0, 36671.81664091662, 36774.63225367819, 28678.85660571684, 28774.0, 29644.25889352697, 30146.944856929902, 30769.384612308077, 18402.907985459988, 22982.422982422795, 24027.038918443766, 24133.879330603235, 24301.39246518851], "value": 17059.517157338927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.46374495625435, 437.7329828804429, 441.59814568742433, 449.94600647922255, 459.8367579509274, 21024.0, 21091.0, 21192.682109768346, 21477.248296309634, 21493.0, 18139.093045347712, 18307.084645767725, 22209.44476388091, 22914.65628015577, 23165.652515212285, 5731.796322772223, 6068.059450785113, 6297.937020629804, 6301.558890877641, 6304.180456540644, 5352.437994010631, 5882.794102206423, 6241.1574437451045, 6278.654673992928, 6292.056191571255, 4624.167649823047, 4967.602591792648, 5724.856878578027, 6190.721417536221, 6270.278917924428, 6080.726367313481, 6155.784547540815, 6171.197744293236, 6176.857281402955, 6187.226596675416, 5226.503482169188, 5420.83737487876, 5437.34751829779, 5454.945450545483, 5456.972715136439, 24269.635955460682, 26425.0, 27527.86236068807, 27642.308942276402, 27771.583426248624, 2264.2527965771255, 2309.93070207894, 2326.302109367183, 2330.755270696586, 2333.57995560799, 12514.06144539154, 17207.483775486755, 17406.7388989164, 17840.0, 18019.369322073795, 2001.8598698090993, 2117.4177101297114, 2153.569286142762, 2153.7307836520436, 2156.094440335063, 155405.0, 162894.0, 164852.17573912174, 167239.0, 168211.99999999878, 12662.430190641444, 12665.240085594894, 12734.55429059997, 12929.224246545238, 13061.0, 16337.01977881331, 16613.750793738105, 16618.75071873923, 16622.916885415496, 16625.7506137408, 8664.696735614223, 8921.910780892238, 8996.460212387277, 9085.591148398338, 9105.544722763836, 8438.451500652438, 8476.406651534333, 8497.022842373082, 8615.181557752003, 8671.913280867237, 7761.0, 7890.0, 7926.722564710208, 7928.0, 7932.167122452175, 1795.5780391607962, 1821.5172979160534, 1992.8306093982173, 2096.2663067926105, 2134.51973306008, 932.1098351074708, 946.186279799372, 967.6468089147411, 975.0152346130459, 986.6300137448457, 13823.377947992365, 17730.20214090369, 19957.20171193165, 20309.0, 20980.89509552473, 949.193185792085, 1018.0684673523643, 1032.3547782635853, 1035.8187317219606, 1045.5086109528509, 9534.237261019101, 10636.255462117731, 10720.035196832167, 10903.67288981316, 10942.4528773561, 538004.0, 540812.4387587131, 551224.0, 553818.0, 557714.0, 121407.0, 123484.0, 127150.0, 128410.35794820965, 134091.0, 1184.0, 1222.358261912503, 1246.8503779546513, 1256.597888675615, 1261.255859043169, 21780.45548861291, 22152.335429937128, 22171.864572231287, 22245.555088897807, 22250.776207308107, 52753.0, 53330.2000470001, 54241.0, 54417.0, 54922.0, 74132.0, 74796.13009674802, 75440.62279688567, 75451.62274188593, 75505.24494755201, 8765.649374024968, 9142.177204051668, 9235.861462077939, 12805.487780488676, 12895.355232238539, 13830.585082447542, 14018.369173387018, 14253.64365890861, 14406.567802965928, 14514.85485145135, 11727.413629318671, 11826.408679565815, 12078.637640870787, 12141.08941829384, 12145.757084858431, 8675.961149129824, 11271.04196143337, 11376.317420954769, 11408.600698975495, 11597.594084207012, 11831.40842957866, 12109.81835272454, 12190.451429685509, 12290.0, 12363.62909112728, 12954.546590869275, 13035.608931731684, 13299.800502992657, 13359.53241636538, 13364.331783410986, 11830.40847957616, 11944.343061131374, 12084.516619335129, 12148.696282592653, 12186.562687462365], "value": 10544.49526222749, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.6405134664988, 1412.668023014592, 1434.7345741037916, 1441.1929319581036, 1452.6005348529154, 11219.551217951283, 11426.37154956477, 11696.707582310446, 11724.531018759239, 11730.0, 11420.257683250604, 11610.12924030697, 11612.0, 11613.535458581646, 11623.941880290588, 2469.3579669286023, 2502.6621406110216, 2513.3339664988794, 2514.710808257046, 2515.119708102168, 2449.289705985262, 2488.203774792067, 2499.762522560354, 2504.474060447308, 2506.1980166346775, 2458.9508209835794, 2463.0394146282965, 2509.924702258935, 2516.987415062922, 2517.6349429332736, 2443.609022556391, 2456.0421435640205, 2494.7131079925857, 2498.1756020513276, 2503.5493611149986, 2198.043850924837, 2400.543896659629, 2480.04518260471, 2483.925482235518, 2487.3035550045834, 9042.728718138467, 9850.950745246228, 11664.183507154503, 11671.241369310968, 11671.766564668744, 6337.873242535124, 6727.529072964894, 6760.763373281911, 6767.323267673193, 6776.593404395752, 3943.428202910562, 4238.512571054306, 4323.4163387942535, 4325.480942286946, 4364.170807546588, 1239.374116071387, 1255.2468518888593, 1257.9685507862284, 1292.0761655416422, 1296.1834044551965, 14799.520047995222, 15503.844961550465, 16496.42262520818, 16586.170691465377, 16616.0, 5311.176767601028, 5486.3690675572025, 5674.744636491285, 5681.0910254359105, 5698.515626171739, 11323.773524529463, 11565.595204167814, 11584.47869845859, 11601.419929003516, 11602.6519204424, 6415.1981002374705, 6497.122888410004, 6498.447631951242, 6510.641914694643, 6511.316311787289, 5997.640141591519, 6093.756249750049, 6135.815925522242, 6187.907181392283, 6234.501239900797, 3921.529416469985, 3988.4615576897327, 4323.416338794223, 4358.171947330061, 4372.103718737651, 1254.912156149079, 1263.0088919015866, 1269.1306455078302, 1270.3521204185747, 1276.0365923727527, 794.4200733464603, 797.3740613618281, 814.7759366174349, 818.3207937411934, 839.8278352937633, 6050.485708714808, 6123.142760013601, 6162.907556386658, 6330.715117819709, 6434.64609446485, 489.454258501768, 497.32363984980395, 553.6352890125859, 581.348889244041, 601.3865856826101, 3939.2515422069714, 4206.936895946563, 4312.547182545787, 4314.9352759708, 4423.491298500714, 14548.127112373291, 15057.0, 15167.089974601558, 15170.469033583771, 15266.847331526764, 13897.16617002983, 14514.274286285643, 14592.197429141499, 14981.176035318162, 15185.392584296504, 8345.499270043816, 8567.357448191535, 8586.527740974181, 8587.65649374018, 8601.354898382646, 6053.303870055, 6779.72881084751, 7040.8943865843075, 7094.503384763224, 7132.572045677276, 12092.093093017882, 12474.0, 12592.118551701293, 12602.054845886412, 12706.809397858855, 12810.697040980562, 12906.354682266037, 12930.870691292967, 12935.0, 13242.0, 9141.268698504236, 9318.207952324128, 9380.20268277204, 9386.48374339418, 9388.248940084914, 10350.896491035295, 10447.320924139907, 10571.36571805694, 10599.0, 10630.787384252426, 8887.600057997277, 9084.727458176265, 9112.863307050533, 9147.954260228922, 9149.725508234764, 8727.869081963649, 8905.866412003696, 8957.0, 8971.461712297283, 9110.681126160553, 8144.959275203586, 8259.628316725883, 8302.792430189294, 8303.6678532858, 8305.750827475185, 5679.34687510925, 5996.640201587918, 6151.96924015377, 6163.630182189083, 6253.155823963889, 5463.672179669233, 6091.664958427329, 6209.2548894133, 6253.09330147117, 6354.26925903527], "value": 5255.196648366785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.44083230160953, 401.91559772447783, 404.9149678567501, 411.2186844994512, 412.69666794905737, 5971.31329897062, 6249.3438188990185, 6344.904826427596, 6350.809475715724, 6405.0072238803, 4846.612271018319, 5997.910031349533, 6120.26556813183, 6170.074488826678, 6239.282482514513, 4660.953390466086, 4672.018876036028, 4684.789184486689, 4701.260533602572, 4707.904982282886, 4555.20283950309, 4694.319323698079, 4702.036082603076, 4705.458872229686, 4721.811127554894, 3831.3678243089857, 4470.619997300233, 4569.880379307057, 4575.33657619645, 4678.485366609671, 4588.669285907083, 4607.838725644587, 4644.767761611906, 4666.813327466897, 4674.906501869944, 3271.9345613087603, 3647.9270414591824, 3864.265789499986, 4110.0, 4146.025683964266, 5615.876824635089, 6010.677650916791, 6010.729517171738, 6028.638281703112, 6127.356627554132, 4546.772661366918, 5291.444398338158, 5370.785168593252, 5505.862353441196, 5579.6652200868075, 6497.837554061139, 6570.0, 6683.866322673519, 6705.865882682368, 6713.530052896299, 3901.453796468497, 4705.764711764432, 4708.929366059512, 4713.410823647044, 4735.952640473585, 5746.959829260594, 6547.842120253673, 6981.406580440668, 7052.753153639598, 7058.6823592938445, 3495.632958539368, 3637.308911306843, 4234.569998301386, 4349.695521313478, 4383.60547550722, 3786.5645450772963, 4184.8744537663915, 4468.843590474317, 4473.373727678128, 4479.5744404282195, 5269.4730526947, 5283.735813209324, 5307.4692530746615, 5346.0, 5402.324709411324, 5170.741462926839, 6754.8649027020165, 6769.120014398195, 6770.255271920085, 6770.916653335443, 1283.8074288856747, 1378.9933348655538, 1547.597624617585, 1568.7333153363957, 1571.6385231396614, 845.7843249971341, 974.8440249560036, 1004.8042829033427, 1024.2676486312323, 1029.8712660917386, 6438.645874476855, 6442.8389290268115, 6464.448532352201, 6465.676716164173, 6484.967575162094, 5404.32445944257, 6907.240203577602, 6942.375186233263, 6989.266127056688, 7017.228104908456, 6100.620757904421, 6391.42477177047, 6403.391677790602, 6414.454771344488, 6492.610443373413, 3226.7738259461253, 3308.7849289796086, 3318.900432987014, 3638.0541059324764, 3674.9816250919107, 6580.638064906476, 6970.372666460043, 7149.320814522612, 7217.963910180415, 7243.710251589878, 6189.731105123439, 7183.0, 7191.0, 7200.855982880418, 7207.315305046117, 6471.437636466374, 6489.545731798823, 7214.89177662325, 7217.128438304124, 7218.458615603746, 6127.816165515042, 6450.9354906450335, 6951.7914462566205, 7021.297870212938, 7021.648917554204, 6628.602283862983, 6776.322367763184, 6883.109094180356, 6932.341427564374, 6940.0, 7076.398506127037, 7285.890711639436, 7328.267173282629, 7332.340089391981, 7342.3758980487255, 3854.9807250963568, 4468.173387923244, 4499.842505512292, 4523.592876641118, 4527.818887244473, 6120.357362477054, 6534.575252608565, 6918.273581274095, 7081.0, 7146.248694252854, 6188.566800323934, 6813.318668133147, 6815.829604259933, 6841.144856892888, 6861.348171923659, 5582.888342233214, 6643.6781609195405, 6748.797536073926, 6806.591604503746, 6813.7955861324235, 6644.4020038196795, 6663.400293973566, 6697.732090716317, 6724.058631791651, 6727.529072964846, 6473.449756770728, 6711.731530738716, 6830.043793868763, 6840.400647753821, 6841.8973715395305, 5768.019436695688, 6307.495400368051, 6338.271098823694, 6621.702023408862, 6641.800745977628], "value": 4861.531664331051, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.2587477209718, 809.7044578728764, 820.7004443378169, 847.1063028504929, 867.583559891252, 14364.712705745906, 14959.326830292635, 16191.190440477973, 16711.24799384029, 16769.580760480978, 16385.0, 16977.83022169778, 17041.318347266126, 17089.40187093452, 17627.206775695093, 820.7742870710573, 987.4223579206155, 1002.8144793213243, 1005.5826831864767, 1009.0716540782498, 836.3476488339074, 995.8257304971637, 1003.1373019203479, 1007.50632190227, 1011.9038691324313, 860.470810451574, 1020.7703266764993, 1032.8140934631763, 1048.6644273832376, 1050.837120246363, 888.5201990924893, 1020.443858097335, 1036.5905467340433, 1038.1331588123926, 1042.7497400623872, 711.5801677010537, 771.1594362145289, 771.6064806948441, 773.8877862709932, 774.570113586959, 13781.862181378157, 16246.0, 16477.928934619213, 17010.234539445755, 17031.892926959707, 16550.0, 16740.16299185036, 16853.494395168163, 17027.48917532476, 17246.482605521855, 13508.594742157751, 13787.793183102262, 14034.228117453536, 14066.788998165035, 14073.085249458753, 3320.966790332114, 3325.285063711314, 3325.8004519728893, 3362.8318584070944, 3367.44437167867, 115216.0, 129702.0544691821, 130824.0, 131530.0, 132201.0, 11990.940045299893, 12178.0, 12200.206986545845, 12281.69295767595, 12293.692657683629, 20213.393598192077, 21160.259390921245, 21230.04464799088, 21536.138554457648, 21587.89206053991, 7569.962150189324, 7933.365330773524, 8031.357491400674, 8031.879521807177, 8054.275115239657, 3900.1029763154065, 3962.9603703962593, 3978.661813745864, 3989.2447323177707, 3992.3612222044335, 4055.8174882130374, 4117.382392641128, 4166.562510936369, 4178.101708132766, 4181.184668989578, 865.3466632692277, 897.1701176411923, 897.5871099294397, 904.6652738486671, 905.3798148268457, 645.5319893077482, 651.9706613202322, 657.1293036726261, 660.8182749743859, 689.7137687859482, 10736.516856741466, 11206.271592346635, 11952.103592230444, 12067.698307542381, 12354.444050017772, 653.3597074866657, 660.6927778582982, 663.3797399431547, 668.1914882991634, 683.993160068393, 5545.556355491551, 5667.631603945731, 5668.206451096849, 5684.062129748628, 5724.942750572524, 107028.0, 107196.0, 109730.0, 110388.4480577608, 112109.0, 75895.0, 95102.52448737712, 95339.0, 97114.5144274274, 97912.0, 82219.5889020563, 85155.57422212849, 85343.57328213319, 85472.0, 85959.0, 10697.679069627924, 11216.158788091088, 11242.66272011841, 11263.0, 11444.313341199555, 38468.23063538658, 38846.22307553777, 38922.2215555693, 39075.0, 39267.60732392639, 43074.78462607667, 50799.492005079475, 51061.97876042533, 51549.226761597856, 52177.73911130572, 13749.793753093913, 13922.23427711445, 13989.0, 14068.929655351658, 14112.788308175592, 15231.466898658493, 15481.84518154804, 15804.60488487797, 15904.76142857835, 15937.20313984273, 14114.78827817563, 14416.414194438394, 14429.711405772036, 14522.0, 14705.485308014167, 14494.565163045125, 14777.039492432958, 14816.333265003317, 14864.702705946036, 14944.178070206242, 14336.56990290293, 14416.639584010483, 14594.85405145935, 14782.260886955824, 14841.554753357417, 8577.485350878967, 10521.631742888962, 10532.315399499008, 10702.304350217211, 10703.0, 10234.82299535563, 11744.471498782746, 11851.525938962344, 11971.940140299243, 11972.521099155936], "value": 8006.768371429411, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 175.0, 162.0, 114.0, 113.0, 112.0, 114.0, 116.0, 115.0, 114.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 153.0, 116.0, 151.0, 147.0, 150.0, 144.0, 115.0, 139.0, 109.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 206.0, 271.0, 225.0, 279.0, 282.0, 198.0, 194.0, 199.0, 200.0], "value": 225.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 56.0, 57.0, 83.0, 57.0, 61.0, 62.0, 71.0, 74.0, 57.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 59.0, 65.0, 79.0, 108.0, 105.0, 108.0, 116.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 126.0, 136.0, 93.0, 108.0, 126.0, 104.0, 89.0, 89.0, 93.0], "value": 106.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 92.0, 97.0, 97.0, 107.0, 96.0, 136.0, 112.0, 102.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 92.0, 90.0, 95.0, 131.0, 92.0, 130.0, 133.0, 94.0, 90.0], "value": 107.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 51.0, 60.0, 46.0, 41.0, 53.0, 55.0, 77.0, 77.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 74.0, 55.0, 58.0, 60.0, 62.0, 54.0, 58.0, 84.0, 81.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 90.0, 79.0, 75.0, 75.0, 110.0, 108.0, 108.0, 102.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 139.0, 135.0, 137.0, 137.0, 138.0, 137.0, 148.0, 192.0, 175.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 187.0, 185.0, 184.0, 186.0, 184.0, 203.0, 239.0, 188.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 66.0, 84.0, 67.0, 69.0, 67.0, 71.0, 66.0], "value": 69.6, "unit": "score"}]}]} 13:19:42 INFO - Return code: 0 13:19:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:19:42 INFO - # TBPL SUCCESS # 13:19:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:19:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:19:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:19:42 INFO - 2017-05-10 13:19:42.653 screenresolution[1663:10798] starting screenresolution argv=screenresolution get 13:19:42 INFO - 2017-05-10 13:19:42.655 screenresolution[1663:10798] Display 0: 1600x1200x32@60 13:19:42 INFO - 2017-05-10 13:19:42.662 screenresolution[1664:10801] starting screenresolution argv=screenresolution list 13:19:42 INFO - Available Modes on Display 0 13:19:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:19:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:19:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:19:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:19:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:19:42 INFO - Intel Iris: 13:19:42 INFO - Chipset Model: Intel Iris 13:19:42 INFO - Type: GPU 13:19:42 INFO - Bus: Built-In 13:19:42 INFO - VRAM (Dynamic, Max): 1536 MB 13:19:42 INFO - Vendor: Intel (0x8086) 13:19:42 INFO - Device ID: 0x0a2e 13:19:42 INFO - Revision ID: 0x0009 13:19:42 INFO - Displays: 13:19:42 INFO - Display: 13:19:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:19:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:19:42 INFO - Display Serial Number: 1600x1200 60 13:19:42 INFO - Main Display: Yes 13:19:42 INFO - Mirror: Off 13:19:42 INFO - Online: Yes 13:19:42 INFO - Rotation: Supported 13:19:42 INFO - Return code: 0 13:19:42 INFO - Running post-action listener: _resource_record_post_action 13:19:42 INFO - [mozharness: 2017-05-10 20:19:42.897100Z] Finished run-tests step (success) 13:19:42 INFO - Running post-run listener: _resource_record_post_run 13:19:42 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 47586304; Write bytes: 527009280; Read time: 508; Write time: 1782 13:19:42 INFO - TinderboxPrint: CPU usage
53.9% 13:19:42 INFO - TinderboxPrint: I/O read bytes / time
47,586,304 / 508 13:19:42 INFO - TinderboxPrint: I/O write bytes / time
527,009,280 / 1,782 13:19:42 INFO - TinderboxPrint: CPU idle
2,207.1 (46.1%) 13:19:42 INFO - TinderboxPrint: CPU system
959.4 (20.0%) 13:19:42 INFO - TinderboxPrint: CPU user
1,619.1 (33.8%) 13:19:42 INFO - TinderboxPrint: Swap in / out
254,185,472 / 0 13:19:42 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206840320; Write bytes: 206661120; Read time: 15814; Write time: 447 13:19:42 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 45689856; Write bytes: 309600256; Read time: 489; Write time: 1301 13:19:43 INFO - Running post-run listener: _upload_blobber_files 13:19:43 INFO - Blob upload gear active. 13:19:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:19:43 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 13:19:43 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:19:43 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:19:43 INFO - Traceback (most recent call last): 13:19:43 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 13:19:43 INFO - main() 13:19:43 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 232, in main 13:19:43 INFO - execfile(args['--auth'], credentials) 13:19:43 INFO - File "/builds/slave/test/oauth.txt", line 30 13:19:43 INFO - 'win7_vm_devedition' """w1nd3rs""",: 13:19:43 INFO - ^ 13:19:43 INFO - SyntaxError: invalid syntax 13:19:43 ERROR - Return code: 1 13:19:43 INFO - Setting buildbot property blobber_files to {} 13:19:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:19:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:19:43 INFO - Contents: 13:19:43 INFO - blobber_files:{} 13:19:43 INFO - Running post-run listener: copy_logs_to_upload_dir 13:19:43 INFO - Copying logs to upload dir... 13:19:43 INFO - mkdir: /builds/slave/test/build/upload/logs 13:19:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.243082 ========= master_lag: 3.62 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-10 13:19:47.126274) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-10 13:19:47.131638) ========= 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.QJL6ScWh15/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.59lujX6zDD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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:{} build_url:https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/NH2o-xTKT5GU4FLlBoobSg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{}' ========= master_lag: 1.46 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-10 13:19:48.604002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 13:19:48.604435) ========= 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.QJL6ScWh15/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.59lujX6zDD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005000 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 13:19:48.632468) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 13:19:48.632790) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 13:19:48.633107) ========= ========= Total master_lag: 5.17 =========