builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0265 starttime: 1494609653.15 results: success (0) revision: 1e2fe13035e13b7b4001ade3b48f226957cef5fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.154296) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.154730) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.155026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.534241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.534556) ========= 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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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-12 10:20:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.4M=0s 2017-05-12 10:20:53 (39.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135983 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.695455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.695849) ========= 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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.026982 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:53.745142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-12 10:20:53.745542) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1e2fe13035e13b7b4001ade3b48f226957cef5fc --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 1e2fe13035e13b7b4001ade3b48f226957cef5fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u5hqt2aBz4/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.fO0SmEwiMS/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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-12 10:20:53,852 truncating revision to first 12 chars 2017-05-12 10:20:53,852 Setting DEBUG logging. 2017-05-12 10:20:53,852 attempt 1/10 2017-05-12 10:20:53,853 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1e2fe13035e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 10:20:55,628 unpacking tar archive at: mozilla-central-1e2fe13035e1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.045815 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-12 10:20:55.814855) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:55.815223) ========= script_repo_revision: 1e2fe13035e13b7b4001ade3b48f226957cef5fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:55.815604) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:55.815897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 10:20:55.835489) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-12 10:20:55.835915) ========= /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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:20:56 INFO - MultiFileLogger online at 20170512 10:20:56 in /builds/slave/test 10:20:56 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 10:20:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:56 INFO - {'append_to_log': False, 10:20:56 INFO - 'base_work_dir': '/builds/slave/test', 10:20:56 INFO - 'blob_upload_branch': 'Firefox', 10:20:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:56 INFO - 'branch': 'Firefox', 10:20:56 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:56 INFO - 'config_files': ('talos/mac_config.py',), 10:20:56 INFO - 'default_actions': ('clobber', 10:20:56 INFO - 'read-buildbot-config', 10:20:56 INFO - 'download-and-extract', 10:20:56 INFO - 'populate-webroot', 10:20:56 INFO - 'create-virtualenv', 10:20:56 INFO - 'install', 10:20:56 INFO - 'run-tests'), 10:20:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:56 INFO - 'download_minidump_stackwalk': True, 10:20:56 INFO - 'download_symbols': 'ondemand', 10:20:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:56 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:56 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:56 INFO - 'gecko_profile': False, 10:20:56 INFO - 'gecko_profile_interval': 0, 10:20:56 INFO - 'installer_path': 'installer.exe', 10:20:56 INFO - 'log_level': 'info', 10:20:56 INFO - 'log_name': 'talos', 10:20:56 INFO - 'log_to_console': True, 10:20:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:20:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:20:56 INFO - 'opt_config_files': (), 10:20:56 INFO - 'pip_index': False, 10:20:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:56 INFO - 'cmd': ('bash', 10:20:56 INFO - '-c', 10:20:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:56 INFO - 'enabled': True, 10:20:56 INFO - 'halt_on_failure': False, 10:20:56 INFO - 'name': 'check_screen_resolution'},), 10:20:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:56 INFO - 'cmd': ('bash', 10:20:56 INFO - '-c', 10:20:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:56 INFO - 'enabled': True, 10:20:56 INFO - 'halt_on_failure': False, 10:20:56 INFO - 'name': 'check_screen_resolution'},), 10:20:56 INFO - 'run_cmd_checks_enabled': True, 10:20:56 INFO - 'suite': 'dromaeojs-e10s', 10:20:56 INFO - 'system_bits': '32', 10:20:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:56 INFO - 'title': 't-yosemite-r7-0265', 10:20:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:56 INFO - 'use_talos_json': True, 10:20:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:56 INFO - 'work_dir': 'build'} 10:20:56 INFO - [mozharness: 2017-05-12 17:20:56.049987Z] Running clobber step. 10:20:56 INFO - Running pre-action listener: _resource_record_pre_action 10:20:56 INFO - Running main action method: clobber 10:20:56 INFO - rmtree: /builds/slave/test/build 10:20:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:57 INFO - Running post-action listener: _resource_record_post_action 10:20:57 INFO - [mozharness: 2017-05-12 17:20:57.414080Z] Finished clobber step (success) 10:20:57 INFO - [mozharness: 2017-05-12 17:20:57.414321Z] Running read-buildbot-config step. 10:20:57 INFO - Running pre-action listener: _resource_record_pre_action 10:20:57 INFO - Running main action method: read_buildbot_config 10:20:57 INFO - Using buildbot properties: 10:20:57 INFO - { 10:20:57 INFO - "project": "", 10:20:57 INFO - "product": "firefox", 10:20:57 INFO - "who": "ryanvm@gmail.com", 10:20:57 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:20:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:20:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:20:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:20:57 INFO - "stage_platform": "macosx64", 10:20:57 INFO - "basedir": "/builds/slave/test", 10:20:57 INFO - "buildnumber": 156, 10:20:57 INFO - "platform": "yosemite_r7", 10:20:57 INFO - "parent_task_id": "TSuTwOIoR9epgWsUd-p85w", 10:20:57 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:20:57 INFO - "slavebuilddir": "test", 10:20:57 INFO - "taskId": "U4xItNupQy2kp0eSi75jaw", 10:20:57 INFO - "branch": "mozilla-central", 10:20:57 INFO - "script_repo_revision": "production", 10:20:57 INFO - "revision": "1e2fe13035e13b7b4001ade3b48f226957cef5fc", 10:20:57 INFO - "slavename": "t-yosemite-r7-0265", 10:20:57 INFO - "repo_path": "mozilla-central" 10:20:57 INFO - } 10:20:57 INFO - Finding installer, test and symbols from parent task. 10:20:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U4xItNupQy2kp0eSi75jaw'}, attempt #1 10:20:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U4xItNupQy2kp0eSi75jaw'}, attempt #1 10:20:58 INFO - Task dependencies: VuLvxllzQpeRHA5BIGyEeQ 10:20:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w'}, attempt #1 10:20:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:20:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/test_packages.json 10:20:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip 10:20:58 INFO - Running post-action listener: _resource_record_post_action 10:20:58 INFO - [mozharness: 2017-05-12 17:20:58.884557Z] Finished read-buildbot-config step (success) 10:20:58 INFO - [mozharness: 2017-05-12 17:20:58.884698Z] Running download-and-extract step. 10:20:58 INFO - Running pre-action listener: _resource_record_pre_action 10:20:58 INFO - Running main action method: download_and_extract 10:20:58 INFO - mkdir: /builds/slave/test/build/tests 10:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:20:58 INFO - trying https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:58 INFO - Downloading https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/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 10:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/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 10:20:59 INFO - Downloaded 1325 bytes. 10:20:59 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:20:59 INFO - Using the following test package requirements: 10:20:59 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:20:59 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:20:59 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:20:59 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:20:59 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'jsshell-mac.zip'], 10:20:59 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:20:59 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:20:59 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:20:59 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:20:59 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:20:59 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:20:59 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:20:59 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:20:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:20:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:59 INFO - Fetch https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:21:01 INFO - Content-Length response header: 56563229 10:21:01 INFO - Bytes received: 56563229 10:21:18 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 10:21:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:21:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:21:18 INFO - Fetch https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:21:20 INFO - Content-Length response header: 56563229 10:21:20 INFO - Bytes received: 56563229 10:21:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:21:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:21:37 INFO - Fetch https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:21:38 INFO - Content-Length response header: 14409411 10:21:38 INFO - Bytes received: 14409411 10:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:38 INFO - trying https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:38 INFO - Downloading https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/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 10:21:40 INFO - Downloaded 75903814 bytes. 10:21:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:40 INFO - mkdir: /builds/slave/test/properties 10:21:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:21:40 INFO - Writing to file /builds/slave/test/properties/build_url 10:21:40 INFO - Contents: 10:21:40 INFO - build_url:https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:21:40 INFO - Running post-action listener: _resource_record_post_action 10:21:40 INFO - Running post-action listener: set_extra_try_arguments 10:21:40 INFO - [mozharness: 2017-05-12 17:21:40.801421Z] Finished download-and-extract step (success) 10:21:40 INFO - [mozharness: 2017-05-12 17:21:40.801546Z] Running populate-webroot step. 10:21:40 INFO - Running pre-action listener: _resource_record_pre_action 10:21:40 INFO - Running main action method: populate_webroot 10:21:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:21:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:21:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:21:40 INFO - '--tppagecycles', 10:21:40 INFO - '7'], 10:21:40 INFO - 'tests': ['tsvgm']}}, 10:21:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:21:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:21:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:21:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:21:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['damp', 'tps']}, 10:21:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'tests': ['damp', 'tps']}, 10:21:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['dromaeo_dom']}, 10:21:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:21:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:21:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:21:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['a11yr', 10:21:40 INFO - 'ts_paint', 10:21:40 INFO - 'tpaint', 10:21:40 INFO - 'sessionrestore', 10:21:40 INFO - 'sessionrestore_no_auto_restore', 10:21:40 INFO - 'tabpaint']}, 10:21:40 INFO - 'other-e10s': {'tests': ['a11yr', 10:21:40 INFO - 'ts_paint', 10:21:40 INFO - 'tpaint', 10:21:40 INFO - 'sessionrestore', 10:21:40 INFO - 'sessionrestore_no_auto_restore', 10:21:40 INFO - 'tabpaint']}, 10:21:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:21:40 INFO - 'ts_paint', 10:21:40 INFO - 'tpaint', 10:21:40 INFO - 'sessionrestore', 10:21:40 INFO - 'sessionrestore_no_auto_restore', 10:21:40 INFO - 'tabpaint']}, 10:21:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:21:40 INFO - 'ts_paint', 10:21:40 INFO - 'tpaint', 10:21:40 INFO - 'sessionrestore', 10:21:40 INFO - 'sessionrestore_no_auto_restore', 10:21:40 INFO - 'tabpaint']}, 10:21:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['a11yr', 10:21:40 INFO - 'ts_paint', 10:21:40 INFO - 'tpaint', 10:21:40 INFO - 'sessionrestore', 10:21:40 INFO - 'sessionrestore_no_auto_restore', 10:21:40 INFO - 'tabpaint']}, 10:21:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['a11yr', 10:21:40 INFO - 'ts_paint', 10:21:40 INFO - 'tpaint', 10:21:40 INFO - 'sessionrestore', 10:21:40 INFO - 'sessionrestore_no_auto_restore', 10:21:40 INFO - 'tabpaint']}, 10:21:40 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:21:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:21:40 INFO - 'bloom_basic_ref']}, 10:21:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['tsvgx', 10:21:40 INFO - 'tsvgr_opacity', 10:21:40 INFO - 'tart', 10:21:40 INFO - 'tscrollx', 10:21:40 INFO - 'cart', 10:21:40 INFO - 'tsvg_static']}, 10:21:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:21:40 INFO - 'tsvgr_opacity', 10:21:40 INFO - 'tart', 10:21:40 INFO - 'tscrollx', 10:21:40 INFO - 'cart', 10:21:40 INFO - 'tsvg_static']}, 10:21:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'talos_options': ['--disable-e10s'], 10:21:40 INFO - 'tests': ['tp5o']}, 10:21:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:21:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'talos_options': ['--disable-e10s', 10:21:40 INFO - '--xperf_path', 10:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:40 INFO - 'tests': ['tp5n']}, 10:21:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:21:40 INFO - 'talos_options': ['--xperf_path', 10:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:40 INFO - 'tests': ['tp5n']}}, 10:21:40 INFO - 'talos.zip': {'path': '', 10:21:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:21:40 INFO - Running post-action listener: _resource_record_post_action 10:21:40 INFO - [mozharness: 2017-05-12 17:21:40.822771Z] Finished populate-webroot step (success) 10:21:40 INFO - [mozharness: 2017-05-12 17:21:40.822920Z] Running create-virtualenv step. 10:21:40 INFO - Running pre-action listener: _resource_record_pre_action 10:21:40 INFO - Running main action method: create_virtualenv 10:21:40 INFO - Creating virtualenv /builds/slave/test/build/venv 10:21:40 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 10:21:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:21:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:21:40 INFO - Using real prefix '/tools/python27' 10:21:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:21:41 INFO - Installing distribute.............................................................................................................................................................................................done. 10:21:44 INFO - Installing pip.................done. 10:21:44 INFO - Return code: 0 10:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:21:44 INFO - Reading from file tmpfile_stdout 10:21:44 INFO - Output received: 10:21:44 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) 10:21:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21: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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:45 INFO - 'HOME': '/Users/cltbld', 10:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:45 INFO - 'LOGNAME': 'cltbld', 10:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:45 INFO - 'MOZ_NO_REMOTE': '1', 10:21:45 INFO - 'NO_EM_RESTART': '1', 10:21:45 INFO - 'PAGER': '/bin/cat', 10:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:45 INFO - 'PWD': '/builds/slave/test', 10:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:45 INFO - 'SHELL': '/bin/bash', 10:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:45 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:45 INFO - 'USER': 'cltbld', 10:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:45 INFO - 'XPC_FLAGS': '0x0', 10:21:45 INFO - 'XPC_SERVICE_NAME': '0', 10:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:45 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 10:21:45 INFO - Cleaning up... 10:21:45 INFO - Return code: 0 10:21:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:46 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:21:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:46 INFO - 'HOME': '/Users/cltbld', 10:21:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:46 INFO - 'LOGNAME': 'cltbld', 10:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:46 INFO - 'MOZ_NO_REMOTE': '1', 10:21:46 INFO - 'NO_EM_RESTART': '1', 10:21:46 INFO - 'PAGER': '/bin/cat', 10:21:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:46 INFO - 'PWD': '/builds/slave/test', 10:21:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:46 INFO - 'SHELL': '/bin/bash', 10:21:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:46 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:46 INFO - 'USER': 'cltbld', 10:21:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:46 INFO - 'XPC_FLAGS': '0x0', 10:21:46 INFO - 'XPC_SERVICE_NAME': '0', 10:21:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:46 INFO - Downloading/unpacking psutil>=3.1.1 10:21:46 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 10:21:46 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 10:21:46 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 10:21:46 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 10:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:21:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:48 INFO - Installing collected packages: psutil 10:21:48 INFO - Running setup.py install for psutil 10:21:48 INFO - building 'psutil._psutil_osx' extension 10:21:48 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 10:21:49 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 10:21:49 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 10:21:49 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 10:21:49 INFO - building 'psutil._psutil_posix' extension 10:21:49 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 10:21:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:21:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:21:49 INFO - ^ 10:21:49 INFO - 1 warning generated. 10:21:49 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 10:21:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:21:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:21:49 INFO - Successfully installed psutil 10:21:49 INFO - Cleaning up... 10:21:49 INFO - Return code: 0 10:21:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:49 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:49 INFO - 'HOME': '/Users/cltbld', 10:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:49 INFO - 'LOGNAME': 'cltbld', 10:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:49 INFO - 'MOZ_NO_REMOTE': '1', 10:21:49 INFO - 'NO_EM_RESTART': '1', 10:21:49 INFO - 'PAGER': '/bin/cat', 10:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:49 INFO - 'PWD': '/builds/slave/test', 10:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:49 INFO - 'SHELL': '/bin/bash', 10:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:49 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:49 INFO - 'USER': 'cltbld', 10:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:49 INFO - 'XPC_FLAGS': '0x0', 10:21:49 INFO - 'XPC_SERVICE_NAME': '0', 10:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:21:50 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 10:21:50 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 10:21:50 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 10:21:50 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 10:21:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:21:51 INFO - Installing collected packages: mozsystemmonitor 10:21:51 INFO - Running setup.py install for mozsystemmonitor 10:21:51 INFO - Successfully installed mozsystemmonitor 10:21:51 INFO - Cleaning up... 10:21:51 INFO - Return code: 0 10:21:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:51 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:51 INFO - 'HOME': '/Users/cltbld', 10:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:51 INFO - 'LOGNAME': 'cltbld', 10:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:51 INFO - 'MOZ_NO_REMOTE': '1', 10:21:51 INFO - 'NO_EM_RESTART': '1', 10:21:51 INFO - 'PAGER': '/bin/cat', 10:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:51 INFO - 'PWD': '/builds/slave/test', 10:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:51 INFO - 'SHELL': '/bin/bash', 10:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:51 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:51 INFO - 'USER': 'cltbld', 10:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:51 INFO - 'XPC_FLAGS': '0x0', 10:21:51 INFO - 'XPC_SERVICE_NAME': '0', 10:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:51 INFO - Downloading/unpacking jsonschema==2.5.1 10:21:51 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 10:21:51 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 10:21:51 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 10:21:51 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 10:21:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:21:53 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 10:21:53 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 10:21:53 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 10:21:53 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 10:21:53 INFO - Downloading functools32-3.2.3-2.tar.gz 10:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:21:53 INFO - warning: no files found matching '*.txt' 10:21:53 INFO - no previously-included directories found matching 'build' 10:21:53 INFO - no previously-included directories found matching 'dist' 10:21:53 INFO - no previously-included directories found matching '.git*' 10:21:53 INFO - Installing collected packages: jsonschema, functools32 10:21:53 INFO - Running setup.py install for functools32 10:21:53 INFO - warning: no files found matching '*.txt' 10:21:53 INFO - no previously-included directories found matching 'build' 10:21:53 INFO - no previously-included directories found matching 'dist' 10:21:53 INFO - no previously-included directories found matching '.git*' 10:21:53 INFO - Successfully installed jsonschema functools32 10:21:53 INFO - Cleaning up... 10:21:53 INFO - Return code: 0 10:21:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:53 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:53 INFO - 'HOME': '/Users/cltbld', 10:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:53 INFO - 'LOGNAME': 'cltbld', 10:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:53 INFO - 'MOZ_NO_REMOTE': '1', 10:21:53 INFO - 'NO_EM_RESTART': '1', 10:21:53 INFO - 'PAGER': '/bin/cat', 10:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:53 INFO - 'PWD': '/builds/slave/test', 10:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:53 INFO - 'SHELL': '/bin/bash', 10:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:53 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:53 INFO - 'USER': 'cltbld', 10:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:53 INFO - 'XPC_FLAGS': '0x0', 10:21:53 INFO - 'XPC_SERVICE_NAME': '0', 10:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:21:54 INFO - Cleaning up... 10:21:54 INFO - Return code: 0 10:21:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:54 INFO - 'HOME': '/Users/cltbld', 10:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:54 INFO - 'LOGNAME': 'cltbld', 10:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:54 INFO - 'MOZ_NO_REMOTE': '1', 10:21:54 INFO - 'NO_EM_RESTART': '1', 10:21:54 INFO - 'PAGER': '/bin/cat', 10:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:54 INFO - 'PWD': '/builds/slave/test', 10:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:54 INFO - 'SHELL': '/bin/bash', 10:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:54 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:54 INFO - 'USER': 'cltbld', 10:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:54 INFO - 'XPC_FLAGS': '0x0', 10:21:54 INFO - 'XPC_SERVICE_NAME': '0', 10:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:54 INFO - Downloading/unpacking blobuploader==1.2.4 10:21:54 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 10:21:54 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 10:21:54 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 10:21:54 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 10:21:55 INFO - Downloading blobuploader-1.2.4.tar.gz 10:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:21:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:21:56 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 10:21:56 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 10:21:56 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 10:21:56 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 10:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:21:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:21:56 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 10:21:56 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 10:21:56 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 10:21:56 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 10:21:56 INFO - Downloading docopt-0.6.1.tar.gz 10:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:21:56 INFO - Installing collected packages: blobuploader, requests, docopt 10:21:56 INFO - Running setup.py install for blobuploader 10:21:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:21:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:21:57 INFO - Running setup.py install for requests 10:21:57 INFO - Running setup.py install for docopt 10:21:57 INFO - Successfully installed blobuploader requests docopt 10:21:57 INFO - Cleaning up... 10:21:57 INFO - Return code: 0 10:21:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:57 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:21:57 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:21:57 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:57 INFO - 'HOME': '/Users/cltbld', 10:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:57 INFO - 'LOGNAME': 'cltbld', 10:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:57 INFO - 'MOZ_NO_REMOTE': '1', 10:21:57 INFO - 'NO_EM_RESTART': '1', 10:21:57 INFO - 'PAGER': '/bin/cat', 10:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:57 INFO - 'PWD': '/builds/slave/test', 10:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:57 INFO - 'SHELL': '/bin/bash', 10:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:21:57 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:57 INFO - 'USER': 'cltbld', 10:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:57 INFO - 'XPC_FLAGS': '0x0', 10:21:57 INFO - 'XPC_SERVICE_NAME': '0', 10:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:57 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xMqvlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-w53jsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-5l3SMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-FBoeZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-dQAAaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Wa1ERS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-nCaaUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-tU4j2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xGfiho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-FFdlhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:58 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Fmri5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-VrUMXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-IbmeDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xVHwI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-CrBHx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-_waLv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:59 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-XfAe2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:21:59 INFO - Running setup.py install for manifestparser 10:21:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:21:59 INFO - Running setup.py install for mozcrash 10:21:59 INFO - Running setup.py install for mozdebug 10:22:00 INFO - Running setup.py install for mozdevice 10:22:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Running setup.py install for mozfile 10:22:00 INFO - Running setup.py install for mozhttpd 10:22:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Running setup.py install for mozinfo 10:22:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Running setup.py install for mozInstall 10:22:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:22:00 INFO - Running setup.py install for mozleak 10:22:00 INFO - Running setup.py install for mozlog 10:22:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:22:01 INFO - Running setup.py install for moznetwork 10:22:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:22:01 INFO - Running setup.py install for mozprocess 10:22:01 INFO - Running setup.py install for mozprofile 10:22:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:22:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:22:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:22:01 INFO - Running setup.py install for mozrunner 10:22:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:22:01 INFO - Running setup.py install for mozscreenshot 10:22:01 INFO - Running setup.py install for moztest 10:22:02 INFO - Running setup.py install for mozversion 10:22:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:22:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:22:02 INFO - Cleaning up... 10:22:02 INFO - Return code: 0 10:22:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:02 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:22:02 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:22:02 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:02 INFO - 'HOME': '/Users/cltbld', 10:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:02 INFO - 'LOGNAME': 'cltbld', 10:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:02 INFO - 'MOZ_NO_REMOTE': '1', 10:22:02 INFO - 'NO_EM_RESTART': '1', 10:22:02 INFO - 'PAGER': '/bin/cat', 10:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:02 INFO - 'PWD': '/builds/slave/test', 10:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:02 INFO - 'SHELL': '/bin/bash', 10:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:22:02 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:02 INFO - 'USER': 'cltbld', 10:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:02 INFO - 'XPC_FLAGS': '0x0', 10:22:02 INFO - 'XPC_SERVICE_NAME': '0', 10:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:02 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-YXAi6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:02 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)) 10:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:02 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-CbxFeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:02 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)) 10:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:02 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Dp8ePy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:02 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)) 10:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:02 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Uyun_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:02 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)) 10:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:02 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-6rZ27Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-UCt161-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Pwqgc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-9sYBLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-k3jpPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-cF16sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-jfGea5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-1eSPlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-UunQ6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:03 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)) 10:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:03 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Osl3zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:04 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)) 10:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:04 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-MLCSxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:04 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)) 10:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:04 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-TT6XyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:04 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)) 10:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:04 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-4pvK3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:04 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)) 10:22:04 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)) 10:22:04 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)) 10:22:04 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)) 10:22:04 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)) 10:22:04 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)) 10:22:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:22:04 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 10:22:04 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 10:22:04 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 10:22:04 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 10:22:05 INFO - Downloading blessings-1.6.tar.gz 10:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:22:05 INFO - Installing collected packages: blessings 10:22:05 INFO - Running setup.py install for blessings 10:22:05 INFO - Successfully installed blessings 10:22:05 INFO - Cleaning up... 10:22:06 INFO - Return code: 0 10:22:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:06 INFO - Reading from file tmpfile_stdout 10:22:06 INFO - Current package versions: 10:22:06 INFO - blessings == 1.6 10:22:06 INFO - blobuploader == 1.2.4 10:22:06 INFO - docopt == 0.6.1 10:22:06 INFO - functools32 == 3.2.3-2 10:22:06 INFO - jsonschema == 2.5.1 10:22:06 INFO - manifestparser == 1.1 10:22:06 INFO - mozInstall == 1.12 10:22:06 INFO - mozcrash == 1.0 10:22:06 INFO - mozdebug == 0.1 10:22:06 INFO - mozdevice == 0.50 10:22:06 INFO - mozfile == 1.2 10:22:06 INFO - mozhttpd == 0.7 10:22:06 INFO - mozinfo == 0.9 10:22:06 INFO - mozleak == 0.1 10:22:06 INFO - mozlog == 3.4 10:22:06 INFO - moznetwork == 0.27 10:22:06 INFO - mozprocess == 0.25 10:22:06 INFO - mozprofile == 0.28 10:22:06 INFO - mozrunner == 6.13 10:22:06 INFO - mozscreenshot == 0.1 10:22:06 INFO - mozsystemmonitor == 0.3 10:22:06 INFO - moztest == 0.8 10:22:06 INFO - mozversion == 1.4 10:22:06 INFO - psutil == 3.1.1 10:22:06 INFO - requests == 1.2.3 10:22:06 INFO - wsgiref == 0.1.2 10:22:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:06 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/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:22:06 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:22:06 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:06 INFO - 'HOME': '/Users/cltbld', 10:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:06 INFO - 'LOGNAME': 'cltbld', 10:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:06 INFO - 'MOZ_NO_REMOTE': '1', 10:22:06 INFO - 'NO_EM_RESTART': '1', 10:22:06 INFO - 'PAGER': '/bin/cat', 10:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:06 INFO - 'PWD': '/builds/slave/test', 10:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:06 INFO - 'SHELL': '/bin/bash', 10:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:22:06 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:06 INFO - 'USER': 'cltbld', 10:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:06 INFO - 'XPC_FLAGS': '0x0', 10:22:06 INFO - 'XPC_SERVICE_NAME': '0', 10:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 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)) 10:22:06 INFO - Cleaning up... 10:22:06 INFO - Return code: 0 10:22:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10ebffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc49a563f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, '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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:06 INFO - 'HOME': '/Users/cltbld', 10:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:06 INFO - 'LOGNAME': 'cltbld', 10:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:06 INFO - 'MOZ_NO_REMOTE': '1', 10:22:06 INFO - 'NO_EM_RESTART': '1', 10:22:06 INFO - 'PAGER': '/bin/cat', 10:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:06 INFO - 'PWD': '/builds/slave/test', 10:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:06 INFO - 'SHELL': '/bin/bash', 10:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:22:06 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:06 INFO - 'USER': 'cltbld', 10:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:06 INFO - 'XPC_FLAGS': '0x0', 10:22:06 INFO - 'XPC_SERVICE_NAME': '0', 10:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:22:06 INFO - Cleaning up... 10:22:06 INFO - Return code: 0 10:22:06 INFO - Running post-action listener: _resource_record_post_action 10:22:06 INFO - Running post-action listener: _start_resource_monitoring 10:22:06 INFO - Starting resource monitoring. 10:22:06 INFO - [mozharness: 2017-05-12 17:22:06.863155Z] Finished create-virtualenv step (success) 10:22:06 INFO - [mozharness: 2017-05-12 17:22:06.864073Z] Running install step. 10:22:06 INFO - Running pre-action listener: _resource_record_pre_action 10:22:06 INFO - Running main action method: install 10:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:07 INFO - Reading from file tmpfile_stdout 10:22:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:22:07 INFO - Reading from file tmpfile_stdout 10:22:07 INFO - Output received: 10:22:07 INFO - Usage: mozinstall [options] installer 10:22:07 INFO - Options: 10:22:07 INFO - -h, --help show this help message and exit 10:22:07 INFO - -d DEST, --destination=DEST 10:22:07 INFO - Directory to install application into. [default: 10:22:07 INFO - "/builds/slave/test"] 10:22:07 INFO - --app=APP Application being installed. [default: firefox] 10:22:07 INFO - mkdir: /builds/slave/test/build/application 10:22:07 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'] 10:22:07 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 10:22:27 INFO - Reading from file tmpfile_stdout 10:22:27 INFO - Output received: 10:22:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:22:27 INFO - Running post-action listener: _resource_record_post_action 10:22:27 INFO - [mozharness: 2017-05-12 17:22:27.659099Z] Finished install step (success) 10:22:27 INFO - [mozharness: 2017-05-12 17:22:27.659234Z] Running run-tests step. 10:22:27 INFO - Running pre-action listener: _resource_record_pre_action 10:22:27 INFO - Running main action method: run_tests 10:22:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:22:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:22:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:22:27 INFO - 2017-05-12 10:22:27.673 screenresolution[1624:7511] starting screenresolution argv=screenresolution get 10:22:27 INFO - 2017-05-12 10:22:27.676 screenresolution[1624:7511] Display 0: 1600x1200x32@60 10:22:27 INFO - 2017-05-12 10:22:27.682 screenresolution[1625:7513] starting screenresolution argv=screenresolution list 10:22:27 INFO - Available Modes on Display 0 10:22:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:22:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:22:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:22:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:22:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:22:27 INFO - Intel Iris: 10:22:27 INFO - Chipset Model: Intel Iris 10:22:27 INFO - Type: GPU 10:22:27 INFO - Bus: Built-In 10:22:27 INFO - VRAM (Dynamic, Max): 1536 MB 10:22:27 INFO - Vendor: Intel (0x8086) 10:22:27 INFO - Device ID: 0x0a2e 10:22:27 INFO - Revision ID: 0x0009 10:22:27 INFO - Displays: 10:22:27 INFO - Display: 10:22:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:22:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:22:27 INFO - Display Serial Number: 1600x1200 60 10:22:27 INFO - Main Display: Yes 10:22:27 INFO - Mirror: Off 10:22:27 INFO - Online: Yes 10:22:27 INFO - Rotation: Supported 10:22:27 INFO - Return code: 0 10:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:22:27 INFO - Python 2.7.3 10:22:27 INFO - Return code: 0 10:22:27 INFO - grabbing minidump binary from tooltool 10:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:22:27 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 0x10e910d30>, '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 0x10e8c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e904be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:22:27 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 10:22:27 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 10:22:27 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 10:22:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:22:28 INFO - Return code: 0 10:22:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:22:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:22:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:22:28 INFO - ENV: RUST_BACKTRACE is now 1 10:22:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:22:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:22:28 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/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 10:22:28 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/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0265 --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 10:22:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5hqt2aBz4/Render', 10:22:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:28 INFO - 'HOME': '/Users/cltbld', 10:22:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:28 INFO - 'LOGNAME': 'cltbld', 10:22:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:22:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:28 INFO - 'MOZ_NO_REMOTE': '1', 10:22:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:22:28 INFO - 'NO_EM_RESTART': '1', 10:22:28 INFO - 'PAGER': '/bin/cat', 10:22:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:28 INFO - 'PWD': '/builds/slave/test', 10:22:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:22:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:28 INFO - 'RUST_BACKTRACE': '1', 10:22:28 INFO - 'SHELL': '/bin/bash', 10:22:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO0SmEwiMS/Listeners', 10:22:28 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:22:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:28 INFO - 'USER': 'cltbld', 10:22:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:28 INFO - 'XPC_FLAGS': '0x0', 10:22:28 INFO - 'XPC_SERVICE_NAME': '0', 10:22:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:28 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/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 10:22:28 INFO - mozversion application_buildid: 20170512085216 10:22:28 INFO - mozversion application_changeset: 1e2fe13035e13b7b4001ade3b48f226957cef5fc 10:22:28 INFO - mozversion application_display_name: Nightly 10:22:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:22:28 INFO - mozversion application_name: Firefox 10:22:28 INFO - mozversion application_remotingname: firefox 10:22:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:22:28 INFO - mozversion application_vendor: Mozilla 10:22:28 INFO - mozversion application_version: 55.0a1 10:22:28 INFO - mozversion platform_buildid: 20170512085216 10:22:28 INFO - mozversion platform_changeset: 1e2fe13035e13b7b4001ade3b48f226957cef5fc 10:22:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:22:28 INFO - mozversion platform_version: 55.0a1 10:22:28 INFO - using testdate: 1494609748 10:22:28 INFO - actual date: 1494609748 10:22:28 INFO - starting webserver on 'localhost:49226' 10:22:28 INFO - SUITE-START | Running 2 tests 10:22:28 INFO - TEST-START | dromaeo_css 10:22:28 INFO - operating with platform_type : mac_ 10:22:28 INFO - Initialising browser for dromaeo_css test... 10:22:28 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmp7rMEGe/profile http://localhost:49226/getInfo.html) 10:22:30 INFO - PID 1635 | 2017-05-12 10:22:30.879 plugin-container[1637:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 10:22:30 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:30 INFO - PID 1635 | 2017-05-12 10:22:30.884 plugin-container[1637:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 10:22:30 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:30 INFO - PID 1635 | 2017-05-12 10:22:30.884 plugin-container[1637:7689] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:31 INFO - PID 1635 | __metrics Screen width/height:1600/1200 10:22:31 INFO - PID 1635 | colorDepth:24 10:22:31 INFO - PID 1635 | Browser inner width/height: 1024/691 10:22:31 INFO - PID 1635 | __metrics 10:22:31 INFO - PID 1635 | 2017-05-12 10:22:31.385 plugin-container[1638:7744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 10:22:31 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:31 INFO - PID 1635 | 2017-05-12 10:22:31.385 plugin-container[1638:7744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 10:22:31 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:31 INFO - PID 1635 | 2017-05-12 10:22:31.385 plugin-container[1638:7744] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:32 INFO - TEST-INFO | 1635: exit 0 10:22:32 INFO - Browser initialized. 10:22:32 INFO - Running cycle 1/1 for dromaeo_css test... 10:22:32 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmp7rMEGe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:34 INFO - PID 1639 | 2017-05-12 10:22:34.321 plugin-container[1640:7858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 10:22:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:34 INFO - PID 1639 | 2017-05-12 10:22:34.321 plugin-container[1640:7858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 10:22:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:34 INFO - PID 1639 | 2017-05-12 10:22:34.322 plugin-container[1640:7858] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:34 INFO - PID 1639 | 2017-05-12 10:22:34.821 plugin-container[1641:7901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 10:22:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:34 INFO - PID 1639 | 2017-05-12 10:22:34.821 plugin-container[1641:7901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 10:22:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:34 INFO - PID 1639 | 2017-05-12 10:22:34.821 plugin-container[1641:7901] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:44 INFO - PID 1639 | RSS: Main: 228102144 10:22:44 INFO - PID 1639 | 10:25:47 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 10:25:49 INFO - PID 1639 | RSS: Main: 253571072 10:25:49 INFO - PID 1639 | 10:28:51 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 10:28:52 INFO - PID 1639 | RSS: Main: 258957312 10:28:52 INFO - PID 1639 | 10:31:53 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 10:31:54 INFO - PID 1639 | RSS: Main: 267816960 10:31:54 INFO - PID 1639 | 10:34:56 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 10:34:56 INFO - PID 1639 | RSS: Main: 260841472 10:34:56 INFO - PID 1639 | 10:37:59 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 10:38:00 INFO - PID 1639 | RSS: Main: 264151040 10:38:00 INFO - PID 1639 | 10:41:01 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 10:41:02 INFO - PID 1639 | RSS: Main: 264114176 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | __start_tp_report 10:41:02 INFO - PID 1639 | _x_x_mozilla_page_load 10:41:02 INFO - PID 1639 | _x_x_mozilla_page_load_details 10:41:02 INFO - PID 1639 | |i|pagename|runs| 10:41:02 INFO - PID 1639 | |0;dojo.html;3541.929161416772;3576.392013357729;3580.91047723807;3612.6748592626673;3616.6021737608858;4972.900541989158;6218.129461875341;6226.501879849612;6235.719392627331;6275;6223.502119830414;6228.1591985082005;6248.187735594366;6258.593191442553;6265.185525881629;5380.24676545283;5450.400455949843;5500.147477141049;5534.640248383842;5626.803061892834;5468.863278418032;5586;5617.8033768818095;5629.240052592889;5632.943670563303;4600.976995115003;5486.725663716798;5523.2543606613;5606.271184745978;5610.074337734269;5499.202615620753;5567.3040869891265;5599.244102046253;5604.719764011783;5609.074502707049;5484.204790305424;5515.586331025149;5530.695811730353;5533.750981205855;5549.445055494459;6038.788642397495;6160.6919654017565;6202.844928876769;6274.592151510137;6307.558470907038;5947.583669143161;6501.829670659279;6602.471802255808;6667.599944003376;6699.229588597276;6794.592324460548;6802.2857599951785;6803.659817009179;6814.693338799766;6821.795346139624;6208.627482351073;6343.619382837091;6345.428911397997;6348.333424990402;6366.872662546723;6705.731770729116;6849.520533562602;6919.930800692029;6924.549904256207;6945.40964018054;6362.950113231265;6439.645819479973;6489.610623362613;6603.80188594343;6614.272430032693;6672.633005184664;6686.398224159849;6749.122614060141;6758.763443279461;6762.763303284361;6391.712372943229;6619.536632435684;6646.468282537386;6675.933240667531;6709.899351509731;6470.708818103197;6484.254310754323;6508.934910650928;6532.706028228646;6592.901106483407;5290.470952904679;5300.70846103468;5308.601854860824;5331.733413329318;5332.893342133213;4041.989502624344;4123.443335149716;4739.3601863748645;4746.501617330199;4780.1873681474235;6250.499960003189;6256.781012664626;6318.968405158037;6329.050642403677;6372.617642941438;5343;6760.0535924970545;6774.8645027100165;6783.186017677812;6820.079289295981;6262.09199666055;6277.5605707600025;6284.5600807943;6321.273053598803;6352.841178970562;5787.565932554991;5866.032104702676;5931.288245410492;5939.970300148471;5955.76176952917;5867;6825.112735344473;6850.760223392157;6901.654917254017;6991.720331186695;6809.625470599165;6810.250872403933;6811.318868113149;6835.179778426621;6835.179778426621;6905.309469053054;6919.342662447059;6934.7226110955;6957.826054348682;6968.5122041458635;6416.903746443901;6510.804675859732;6584.275729669828;6695.497837662292;6703.664816759045;5615.494605485526;6642.2361428434415;6840.726370945106;6844.247132815486;6845.383915447634;6285.96857015712;6423.96788016057;6530.640814755234;6590.406863382319;6596.472282217315;6517.674116294072;6537.444317233088;6553.180852393451;6560.507961902781;6604.405603495708;6251.031090181029;6313.96843015782;6422.486201103807;6442.581232219891;6445.613263204223;6428.292887782247;6482.902756458562;6519.967400162969;6560.27836937946;6602;6432.6462044588;6437.839054023687;6438.806835794933;6439.839004024937;6455.483561315177 10:41:02 INFO - PID 1639 | |1;ext.html;618.5021058048269;807.7818988873004;837.0415873824473;1225.577175874323;1265.183956348156;37945.81027094864;38526.42210366847;38542.614573854255;38603.61396386042;38614.806925965364;38783;38950.61049389505;39206.82379528612;39209.60790392102;39223.21553568927;7889.171636978121;8025.157358477363;8258.967629046369;8443.240108390244;8556.70051548196;8399.832003359928;8433.789155271137;8508.574571271443;8526.147385261485;8548.74353769388;8468.364872634545;8496.702615408461;8545.273651739579;8550.102239264881;8558.52928088958;8496;8507.191816777397;8523.786905327355;8531.957340213321;8546;8204.261616454547;8229.711960081428;8268.545230012407;8283.25450709439;8285.62714677835;32831.01506954795;33432.49851252233;36993.63006369929;37121.25757484862;37334.43998340027;3545.9113522161892;3574.7318951078514;3607.8556857725657;3609.7112231021456;3623.8947121128044;24121.638175427383;30206.69793302061;30481;30664.38671226563;30685.846570767226;2644.9471010579873;3119.7192252697364;3128.8122712637314;3140.560321554984;3200.5839240898536;278914.21085789084;286634.9999999979;288068;295238;303167;42038.79612038771;46221;48333.275000875015;48417.273740893914;48509.5149048512;43315.35026974598;50342;50551.49448505542;54064;54313.45686543084;14105.435782568788;14224.644383890278;14321.212333321559;14373.353199106064;14377.353019114165;11393.259438136494;11394.373309467894;11482.598109066143;11504.53981840717;11533.884661153448;14032.158070515801;14053.78919316211;14058.437662493387;14058.789118163235;14127.103943691518;6216.601264715403;6245.281792593819;6250.624962502265;6253.405926436982;6259.405356491126;978.3787295067541;1005.261133067195;1033.478093562746;1068.941208233539;1071.3625392891217;24588.631170532455;24707.25878223656;24756.25731228066;24783.628245576332;24810.751892481207;990.4305024610877;1110;1142.2404101272728;1146.8165093584987;1156.9942150289194;9446.716598502057;10859.78280434387;11492.94253528727;12668.98648108149;12695.555655552196;315969;317204;317207;318401;319243;54136;54519.45480545223;54553.81784728646;54637.72681136569;54671.45328546743;1491.358715752237;1491.8209814822292;1535.3704980957755;1540.4762380790362;1566.1542766905743;17793.5765138786;21966.670499942833;23800.761992379856;24370.390740231636;24490.87754561216;38053.61946380611;38257.617423826516;38268.80865595654;38339.80830095831;38375;272585;273119;273637;274564;275604;35861.46207806937;35921.46117808183;35941.28117437584;36012.09969750777;36042.8197859009;35134.64865351314;44050.77974610106;44618.10763784771;45310.093798124515;45635.77182114068;25503.872480637478;30042.399152017275;30411.695883040888;30668.07995760131;30818.383632327677;30006.663948040994;30518;30533;30716.846415767777;30886.382272354876;36675.44986825253;37438.2512349746;37509.4373584391;37569.81215093907;37631;22721.545569088194;29643.85178074096;30149.492525374084;30653.386932260782;31679.52480712837;21398.465038374165;22512.212072577382;24228.51542969166;24320.51358972846;25048.749512505372 10:41:02 INFO - PID 1639 | |2;jquery.html;402.9744300754579;403.9050823056582;440.317507862813;443.58081612875844;459.6322941646683;20029.898355590456;21834.126634934604;22360.329190124303;22464.326070217878;22700.65949010766;19623.90188049061;22664.88667556664;23592;23668.289951301445;23750;5990.640561566309;6176.042713379434;6206.6586337751405;6229.252489701242;6231.096491008823;6410.358964103576;6728.327167283257;6798.150231221098;6809.693563789641;6818.5908845469185;6580.572262802926;6673.332666733337;6687.431568316699;6765.966170169142;6807.489438292123;5762.394948530386;6688.4649228061635;6804.319568043205;6804.591724496521;6817.420519255869;6363.204599425072;6594.571352862096;6631.900521492182;6632.336766323329;6641.80074597758;30175;30323.090307290815;31353.68646313553;31453.685463145306;31491.84254078715;2361.445060410802;2421.5278020785954;2421.7457166997388;2429.5748244057218;2431.4407686232266;19201.32795352156;19526.023698815145;19943.700844487343;20119.893405862673;20163.495912602157;2211.4803021289986;2343.156463673093;2376.964345534818;2377.6909001829827;2380.73811880693;166925;168289;174043;174061;174153;18452.81547184511;24690;25430.32981536949;25765.8711706444;25835.741642583333;42554.57445425568;43150.78424607857;43313.78343108328;43354.56645433568;43486;11141.331520108819;11233.438328083726;11388.544458221744;11403.486843091914;11690;7883.659777837781;8291.58542072906;8605.956970215235;8807.955960220157;9256.981732009473;9980.652611897302;11064.446777661085;11213;11492.482838272297;11671.35807530577;4070.674346052309;4135.8966025848995;4685.851966268286;4758.882297377703;4929.880151534874;935.8315503209351;976.2287792643874;993.9950300248453;1040.095117247984;1043.7912417516527;18568.535786605175;19351.12919918607;21779.346619601438;21785.782142178694;21817.563648726937;936.6534382278601;1007.3653598233067;1017.3743147963949;1038.11760003996;1049.9002594753485;8917.699269490586;9802.313838031412;10330.328528645765;10354.585816567253;10823.945880270705;500032;501756;505068;506227;508251;124997.37501312436;128886;129117;130178.34910825574;130385;1201.8437603111715;1221.1451983611507;1226.957056503018;1228.2507670321277;1233.6052463211686;18842.3405180818;18990.905045475236;19034.668932936405;19093;19152.425427237205;44322.778386107864;56809;56969.71515142563;57062.85874282574;57759;73243.63378183075;73956;75063;75289.62355188408;75888.62055689686;12998.870011299767;13091.232683587594;13478.932605336911;13534.255615941218;13549.796753048515;11206.755733314738;11992.280463171888;13784.793228101787;14224.502142424966;14671;11904.345261010381;11912.40437978115;11978.880211197777;11986.700332491757;12120.969717573753;11089.390083545482;11118.832522585011;11142.220044596801;11479.25384849982;11536.769264614828;12007.339596322287;12098.758024839279;12147.33189674541;12239.571614993432;12341.568045118736;10744.83882741775;12773.297468639314;12842.222976615907;13048;13124.687531246798;11830.46762895689;11990.700232494257;12191.695207619881;12220.633380998584;12258.570950017063 10:41:02 INFO - PID 1639 | |3;mootools.html;1300.7723648361537;1429.7140571885618;1448.3192899337307;1453.5639308207535;1460.1020372470928;10836.783264334728;11593.362365069917;11719.238249513786;11984.880151198486;12256.142070055099;12114.818277725819;12148.69628259294;12344.135910486248;12348.19736717115;12356.50573977042;2565.4484285878534;2613.1376645706964;2636.3145582148586;2636.8417894926365;2636.8681565921725;2555.6038813983864;2592.2223333000024;2596.1822026061823;2614.647022651937;2618.48939456806;2567.2298310506867;2567.768900798937;2610.504004239198;2617.254082586463;2619.423726780115;2555.718870924206;2558.0279493792427;2584.65107210527;2597.2467984284535;2597.5194589000907;2413.1553956115395;2571.6271140684685;2595.610658401236;2598.246508512529;2599.4281258123183;11903.583374581847;11938.403079845973;11962.342071186082;11974.922256996912;11999.160058795887;6527.608343499405;6701.597904125768;6787.966060169668;6798.830029249259;6804.251532331439;4087.2847251730827;4400.845970391021;4475.351074094238;4481.170983368087;4487.304467807495;1269.156011252516;1270.104576273725;1290.5483080921697;1306.601486546603;1315.5724389573427;16765.66468670632;16766.664666706718;16842.326306947707;16881;16951.49145525636;4611.2852507861335;5660.773569057192;5752.223449834302;5760.2223699800825;5918.852028699231;11791.410429478492;11849;11868.287902725864;11880.821787673192;11885.5840045598;6571.34286571339;6591.34086591337;6605.471562275006;6605.603663780189;6605.86788264232;6016.849578760566;6082.817515474543;6237.09562113491;6252.6873656317;6277.964135917614;2423.5637585234826;3804.5395742679034;3817.7401457518954;4296.162248361602;4386.692931494828;1305.6083175047447;1311.7114234868313;1313.2055106660332;1332.840059192903;1333.2600406774334;758.491810486975;774.9302562769378;800.5276886636913;800.8278220182689;802.1096582793095;6568.376004279586;6707.329267073351;6891.207511136183;6911.343422374866;6926.757563485254;834.9582520873932;938.4556956965081;964.3056998960737;966.79213968997;976.4239098931664;3040.300730831921;3221.64561898191;3878.4596089561383;4214.578542145761;4262;10061.899381006242;14695.044822086531;15066.171360575054;15173.089614622937;15184.696306073816;14389.28053597316;15096.320665569856;15178;15183.62040948963;15344.693106137815;8631.870521942175;8649.740507784776;8736.519491427905;8791.692290769792;8849.69026084084;5916.142159386779;6081.27024757032;6618.172728408949;6670.066190733204;6748.208771772097;9916.752081198028;13473.258970756702;13648.385822638204;13742.175469471864;13817.447302107803;13931.233782142079;14052.578422647337;14075.436982520585;14202.902862696645;14253.429862805371;8335.666573337;8572.35707321941;8596.269317108116;8600.956995214983;8619.267362274277;10822.080123189302;10919.563217471212;10938.890611093788;10964.561417543528;10968.341899486057;9041.095890410907;9290.860637090314;9331;9355.719328420158;9379.812403752023;9017.90982090197;9104.453732776055;9183.403078799856;9204.125608067223;9324.533773311172;7177.641117944187;7546.584937828253;7932.484388514728;8022.398320126131;8046.959765201136;5167.767450464663;5262.210668399617;6083.939160608337;6117.694115294306;6137.3862613738265;5797.971010144923;6079.392060793885;6210.596311239755;6255.53083518747;6374.617522948638 10:41:02 INFO - PID 1639 | |4;prototype.html;342.4161804123969;370.39070728651365;407.8919086442093;408.22844823284015;410.99511693908386;5190.610704197186;6228.283747369054;6245;6290.710404367105;6305.432511074008;4750.691205071668;6012.278526576806;6162.537809664271;6176.444120029197;6178.536609754276;4297.871063868089;4491.91016179677;4538.273876179812;4544.204764166274;4546.249868771649;4415.492218394877;4487.326900964865;4510.729356238619;4542.704724192933;4561.156186105564;3947.5460322063045;3967.821448034845;4390.670699697535;4464.241079016559;4472.597466228039;4217.882261200784;4501.582001669496;4504.617107545862;4529.7282163069995;4537.183307004738;3801.1067399161175;3815.7328986970924;3849.3840985442193;3858.594847540996;3867.651911327966;5993.460588547052;5997.370276120988;5997.8800423991715;6010.429009244115;6053.364396738367;5254.4220135785035;5341.9134362212935;5347.171188465794;5361.624686271963;5382.977234325465;5532.031894418461;5858.765649374062;6597.9443289073515;6603.603783772941;6635.625143633672;3716.5168528091444;3739.2147648993473;4343.674224433149;4345;4360.149770794696;5877.794277200277;6387.584806987577;6481.675916204171;6731.384467727759;6736.295037729229;3661.6521430464063;4192.538820729778;4379.5401482843845;4422.20400327944;4447.3773671685985;4111.938320925188;4313.935290970638;4323.718958267703;4503.684742068024;4526.185286648369;4733.644976626767;5235.65968212065;5325.680459172462;5327.121025030904;5353.875686105961;6583;6583.539152259392;6588.934110658928;6614.669266536654;6622.437092847065;1490.2921112471706;1529.7017081668969;1529.9311530981133;1531.4257153567412;1545.6213227759047;937.7702462896634;1004.8090862736203;1010.7018429563296;1012.7974405119006;1015.5176291261591;5011.387256218191;5540.9168862467095;6248.562600618004;6273.737656418728;6313.747450101944;6457.192850893638;6705.430038446787;6736.225334086643;6736.753700565411;6911.550749201286;6086.847828804315;6271.996480563088;6302.464290535264;6306.779262725782;6320.462760665303;3386.356592247465;3468.7745296555645;3503.491993660956;3624.4200927851944;3690.151265209017;5837.394716453009;5981.790637327673;7026.227115017344;7175.569465831963;7224.868663744208;5451.400345961941;6487.448566871869;6790.830229244309;7048.083749112582;7082.327178917994;6547.738090476518;6751.763688270887;7121.28787121294;7124.145102587722;7150.678219480136;6038.822429626181;6119.418655227746;6271.78048768291;6662;6990.790276291719;5988.431099045583;6540.411362977355;6824.249332573512;6873.347032031949;6912.654367281515;6743.494237932273;7133.750318738819;7186.748463803742;7205.747798827016;7373.741919032808;4211.305134652748;4556.8177272908715;4618.792154353129;4815.6388270880525;5091.745412729423;5720.227769251098;6067.938110830499;6714.362135597111;6867.313268673292;6940.201876784235;6398.968005160131;6618.635975021228;6694.430973367318;6714.146487742787;6727.697253623696;5422.105352616774;6554.541182117397;6651.368120028589;6668.599884006975;6706.798796036127;6533.314002029909;6551.279359270381;6552.541322107407;6562.310957349409;6574.31838170626;6040.728167232573;6093.451589356979;6204.689765511796;6496;6727.400840550282;5936.940630593639;6301.779437719658;6486.248712847599;6560.278369379269;6561.442277406474 10:41:02 INFO - PID 1639 | |5;yui.html;780.6135962698462;814.3647954595417;849.5539841583168;850.9149085091487;875.6409871952501;16471;16813.747793783088;16924.576885577866;18705.43883683488;19287.324943626976;18139.002354870478;18585.349512767058;18594.53513662159;18681.906590467064;18835.964021978787;1020.1736593359366;1046.863907691999;1051.416463862556;1052.1687866585426;1057.762003549203;1022.7494263905317;1032.726327523207;1041.953112109957;1042.249580302185;1049.9107575856062;1063.1176123817213;1093.3057508482132;1094.518411898766;1095.200503632349;1096.4243772019674;1073.98508409553;1087.3584585094793;1089.1776708584969;1093.1418836213613;1093.4095588382263;769.96920123195;781.6131015147538;797.9985118676025;803.5419810707899;803.9678412863478;16332;17326;17408.65182696339;17611.471655850346;17613.911930440394;13977.161370317814;16981.3207471701;17026.23381947815;17353;17415.564610884703;14108.506202282872;14127.858721412756;14381;14414.42342306317;14422.567322980327;3535.1338921964025;3603.8198090095652;3607.7113830893463;3610.963890361089;3624.9093772655633;124238.37880810628;124660.37669811683;125127;126919.73080269263;128059.35970320088;13043.543475978295;13054.34728263602;13161.67095822593;13164.341782910817;13222.272774997275;25668.10161644371;25724.099656512306;25743.74256257451;26089.825957831945;26138.73861261401;7745.806354841174;7793.688252469951;7812.375009999187;7850.332721718718;7869.803254918558;3322.4684050551796;3993.7803420812133;4024.2756303865;4047.3119569673227;4052;4276.48682158143;4277.422547956048;4312.676549258818;4357.803898824561;4359.825606975685;837.949723016621;856.0797143071223;860.5052095045374;861.9138086191331;863.218786997768;654.4698793976894;660.6663634864459;672.2135101930753;685.0888318536335;688.9871888324155;11159;11414.516112905269;11654.53381864716;12144.939275303566;12153.392330383445;606.9908951365733;645.6190847400056;652.6280020388429;654.1202083198091;666.6600033982704;5429.809956651498;5648.63283886546;5743.195952566644;5764.827055188351;5840.941590584125;94090.05909940813;103242;105294.47352763341;106408.46795765971;106635.46682266695;89802.65296020565;91063;92690.14619707703;93088.53455732814;94618.5269073664;79031.41937161339;80644.59677701691;81721.95695107551;82610.58694706488;82732;9023.90976090257;10760.569577216824;11092.611758588411;11499.885001150216;11575.71060723455;41211;41394;41725.37411938763;42033.369499458146;42402.575974239866;53828.730856345464;54214;54603.90792184213;54847.72576137094;55983.28200615789;14604.707905842037;14718.0433271837;14739.92630036843;14740.262986850828;14767.852321477078;17793;18824.811751882855;18832.7175092371;18852.622947541247;18905.960172190662;16546.338146474005;17323;17606.82393176103;17666.293348265925;17966.640667186846;16789;16911.11055888283;17381.478555643353;17594.73607895857;17673.91163044228;14028.859711402756;14466.20435876037;14480.565583032527;14512.346944387316;15799.447019354267;11769.52921883115;12001.39993000364;12035.939820300842;12065.396730163633;12209.572664956684;13052.15161014531;13090.80363794563;13098.083134180515;13213.094110822849;13270.601881943558 10:41:02 INFO - PID 1639 | __end_tp_report 10:41:02 INFO - PID 1639 | __start_cc_report 10:41:02 INFO - PID 1639 | _x_x_mozilla_cycle_collect,413 10:41:02 INFO - PID 1639 | __end_cc_report 10:41:02 INFO - PID 1639 | __startTimestamp1494610862103__endTimestamp 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | ------- Summary: start ------- 10:41:02 INFO - PID 1639 | Number of tests: 6 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6156.24 Median:6372.62 stddev:740.16 (11.6%) stddev-sans-first:713.45 10:41:02 INFO - PID 1639 | Values: 3541.9 3576.4 3580.9 3612.7 3616.6 4972.9 6218.1 6226.5 6235.7 6275.0 6223.5 6228.2 6248.2 6258.6 6265.2 5380.2 5450.4 5500.1 5534.6 5626.8 5468.9 5586.0 5617.8 5629.2 5632.9 4601.0 5486.7 5523.3 5606.3 5610.1 5499.2 5567.3 5599.2 5604.7 5609.1 5484.2 5515.6 5530.7 5533.8 5549.4 6038.8 6160.7 6202.8 6274.6 6307.6 5947.6 6501.8 6602.5 6667.6 6699.2 6794.6 6802.3 6803.7 6814.7 6821.8 6208.6 6343.6 6345.4 6348.3 6366.9 6705.7 6849.5 6919.9 6924.5 6945.4 6363.0 6439.6 6489.6 6603.8 6614.3 6672.6 6686.4 6749.1 6758.8 6762.8 6391.7 6619.5 6646.5 6675.9 6709.9 6470.7 6484.3 6508.9 6532.7 6592.9 5290.5 5300.7 5308.6 5331.7 5332.9 4042.0 4123.4 4739.4 4746.5 4780.2 6250.5 6256.8 6319.0 6329.1 6372.6 5343.0 6760.1 6774.9 6783.2 6820.1 6262.1 6277.6 6284.6 6321.3 6352.8 5787.6 5866.0 5931.3 5940.0 5955.8 5867.0 6825.1 6850.8 6901.7 6991.7 6809.6 6810.3 6811.3 6835.2 6835.2 6905.3 6919.3 6934.7 6957.8 6968.5 6416.9 6510.8 6584.3 6695.5 6703.7 5615.5 6642.2 6840.7 6844.2 6845.4 6286.0 6424.0 6530.6 6590.4 6596.5 6517.7 6537.4 6553.2 6560.5 6604.4 6251.0 6314.0 6422.5 6442.6 6445.6 6428.3 6482.9 6520.0 6560.3 6602.0 6432.6 6437.8 6438.8 6439.8 6455.5 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:44470.18 Median:34283.57 stddev:77206.27 (225.2%) stddev-sans-first:77352.65 10:41:02 INFO - PID 1639 | Values: 618.5 807.8 837.0 1225.6 1265.2 37945.8 38526.4 38542.6 38603.6 38614.8 38783.0 38950.6 39206.8 39209.6 39223.2 7889.2 8025.2 8259.0 8443.2 8556.7 8399.8 8433.8 8508.6 8526.1 8548.7 8468.4 8496.7 8545.3 8550.1 8558.5 8496.0 8507.2 8523.8 8532.0 8546.0 8204.3 8229.7 8268.5 8283.3 8285.6 32831.0 33432.5 36993.6 37121.3 37334.4 3545.9 3574.7 3607.9 3609.7 3623.9 24121.6 30206.7 30481.0 30664.4 30685.8 2644.9 3119.7 3128.8 3140.6 3200.6 278914.2 286635.0 288068.0 295238.0 303167.0 42038.8 46221.0 48333.3 48417.3 48509.5 43315.4 50342.0 50551.5 54064.0 54313.5 14105.4 14224.6 14321.2 14373.4 14377.4 11393.3 11394.4 11482.6 11504.5 11533.9 14032.2 14053.8 14058.4 14058.8 14127.1 6216.6 6245.3 6250.6 6253.4 6259.4 978.4 1005.3 1033.5 1068.9 1071.4 24588.6 24707.3 24756.3 24783.6 24810.8 990.4 1110.0 1142.2 1146.8 1157.0 9446.7 10859.8 11492.9 12669.0 12695.6 315969.0 317204.0 317207.0 318401.0 319243.0 54136.0 54519.5 54553.8 54637.7 54671.5 1491.4 1491.8 1535.4 1540.5 1566.2 17793.6 21966.7 23800.8 24370.4 24490.9 38053.6 38257.6 38268.8 38339.8 38375.0 272585.0 273119.0 273637.0 274564.0 275604.0 35861.5 35921.5 35941.3 36012.1 36042.8 35134.6 44050.8 44618.1 45310.1 45635.8 25503.9 30042.4 30411.7 30668.1 30818.4 30006.7 30518.0 30533.0 30716.8 30886.4 36675.4 37438.3 37509.4 37569.8 37631.0 22721.5 29643.9 30149.5 30653.4 31679.5 21398.5 22512.2 24228.5 24320.5 25048.7 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:36366.29 Median:22682.77 stddev:86618.62 (381.9%) stddev-sans-first:86819.53 10:41:02 INFO - PID 1639 | Values: 403.0 403.9 440.3 443.6 459.6 20029.9 21834.1 22360.3 22464.3 22700.7 19623.9 22664.9 23592.0 23668.3 23750.0 5990.6 6176.0 6206.7 6229.3 6231.1 6410.4 6728.3 6798.2 6809.7 6818.6 6580.6 6673.3 6687.4 6766.0 6807.5 5762.4 6688.5 6804.3 6804.6 6817.4 6363.2 6594.6 6631.9 6632.3 6641.8 30175.0 30323.1 31353.7 31453.7 31491.8 2361.4 2421.5 2421.7 2429.6 2431.4 19201.3 19526.0 19943.7 20119.9 20163.5 2211.5 2343.2 2377.0 2377.7 2380.7 166925.0 168289.0 174043.0 174061.0 174153.0 18452.8 24690.0 25430.3 25765.9 25835.7 42554.6 43150.8 43313.8 43354.6 43486.0 11141.3 11233.4 11388.5 11403.5 11690.0 7883.7 8291.6 8606.0 8808.0 9257.0 9980.7 11064.4 11213.0 11492.5 11671.4 4070.7 4135.9 4685.9 4758.9 4929.9 935.8 976.2 994.0 1040.1 1043.8 18568.5 19351.1 21779.3 21785.8 21817.6 936.7 1007.4 1017.4 1038.1 1049.9 8917.7 9802.3 10330.3 10354.6 10823.9 500032.0 501756.0 505068.0 506227.0 508251.0 124997.4 128886.0 129117.0 130178.3 130385.0 1201.8 1221.1 1227.0 1228.3 1233.6 18842.3 18990.9 19034.7 19093.0 19152.4 44322.8 56809.0 56969.7 57062.9 57759.0 73243.6 73956.0 75063.0 75289.6 75888.6 12998.9 13091.2 13478.9 13534.3 13549.8 11206.8 11992.3 13784.8 14224.5 14671.0 11904.3 11912.4 11978.9 11986.7 12121.0 11089.4 11118.8 11142.2 11479.3 11536.8 12007.3 12098.8 12147.3 12239.6 12341.6 10744.8 12773.3 12842.2 13048.0 13124.7 11830.5 11990.7 12191.7 12220.6 12258.6 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7104.77 Median:3130.97 stddev:4533.43 (144.8%) stddev-sans-first:4525.17 10:41:02 INFO - PID 1639 | Values: 1300.8 1429.7 1448.3 1453.6 1460.1 10836.8 11593.4 11719.2 11984.9 12256.1 12114.8 12148.7 12344.1 12348.2 12356.5 2565.4 2613.1 2636.3 2636.8 2636.9 2555.6 2592.2 2596.2 2614.6 2618.5 2567.2 2567.8 2610.5 2617.3 2619.4 2555.7 2558.0 2584.7 2597.2 2597.5 2413.2 2571.6 2595.6 2598.2 2599.4 11903.6 11938.4 11962.3 11974.9 11999.2 6527.6 6701.6 6788.0 6798.8 6804.3 4087.3 4400.8 4475.4 4481.2 4487.3 1269.2 1270.1 1290.5 1306.6 1315.6 16765.7 16766.7 16842.3 16881.0 16951.5 4611.3 5660.8 5752.2 5760.2 5918.9 11791.4 11849.0 11868.3 11880.8 11885.6 6571.3 6591.3 6605.5 6605.6 6605.9 6016.8 6082.8 6237.1 6252.7 6278.0 2423.6 3804.5 3817.7 4296.2 4386.7 1305.6 1311.7 1313.2 1332.8 1333.3 758.5 774.9 800.5 800.8 802.1 6568.4 6707.3 6891.2 6911.3 6926.8 835.0 938.5 964.3 966.8 976.4 3040.3 3221.6 3878.5 4214.6 4262.0 10061.9 14695.0 15066.2 15173.1 15184.7 14389.3 15096.3 15178.0 15183.6 15344.7 8631.9 8649.7 8736.5 8791.7 8849.7 5916.1 6081.3 6618.2 6670.1 6748.2 9916.8 13473.3 13648.4 13742.2 13817.4 13931.2 14052.6 14075.4 14202.9 14253.4 8335.7 8572.4 8596.3 8601.0 8619.3 10822.1 10919.6 10938.9 10964.6 10968.3 9041.1 9290.9 9331.0 9355.7 9379.8 9017.9 9104.5 9183.4 9204.1 9324.5 7177.6 7546.6 7932.5 8022.4 8047.0 5167.8 5262.2 6083.9 6117.7 6137.4 5798.0 6079.4 6210.6 6255.5 6374.6 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5304.44 Median:6004.15 stddev:1683.65 (28.0%) stddev-sans-first:1646.67 10:41:02 INFO - PID 1639 | Values: 342.4 370.4 407.9 408.2 411.0 5190.6 6228.3 6245.0 6290.7 6305.4 4750.7 6012.3 6162.5 6176.4 6178.5 4297.9 4491.9 4538.3 4544.2 4546.2 4415.5 4487.3 4510.7 4542.7 4561.2 3947.5 3967.8 4390.7 4464.2 4472.6 4217.9 4501.6 4504.6 4529.7 4537.2 3801.1 3815.7 3849.4 3858.6 3867.7 5993.5 5997.4 5997.9 6010.4 6053.4 5254.4 5341.9 5347.2 5361.6 5383.0 5532.0 5858.8 6597.9 6603.6 6635.6 3716.5 3739.2 4343.7 4345.0 4360.1 5877.8 6387.6 6481.7 6731.4 6736.3 3661.7 4192.5 4379.5 4422.2 4447.4 4111.9 4313.9 4323.7 4503.7 4526.2 4733.6 5235.7 5325.7 5327.1 5353.9 6583.0 6583.5 6588.9 6614.7 6622.4 1490.3 1529.7 1529.9 1531.4 1545.6 937.8 1004.8 1010.7 1012.8 1015.5 5011.4 5540.9 6248.6 6273.7 6313.7 6457.2 6705.4 6736.2 6736.8 6911.6 6086.8 6272.0 6302.5 6306.8 6320.5 3386.4 3468.8 3503.5 3624.4 3690.2 5837.4 5981.8 7026.2 7175.6 7224.9 5451.4 6487.4 6790.8 7048.1 7082.3 6547.7 6751.8 7121.3 7124.1 7150.7 6038.8 6119.4 6271.8 6662.0 6990.8 5988.4 6540.4 6824.2 6873.3 6912.7 6743.5 7133.8 7186.7 7205.7 7373.7 4211.3 4556.8 4618.8 4815.6 5091.7 5720.2 6067.9 6714.4 6867.3 6940.2 6399.0 6618.6 6694.4 6714.1 6727.7 5422.1 6554.5 6651.4 6668.6 6706.8 6533.3 6551.3 6552.5 6562.3 6574.3 6040.7 6093.5 6204.7 6496.0 6727.4 5936.9 6301.8 6486.2 6560.3 6561.4 10:41:02 INFO - PID 1639 | 10:41:02 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:21833.80 Median:17879.82 stddev:30622.54 (171.3%) stddev-sans-first:30667.64 10:41:02 INFO - PID 1639 | Values: 780.6 814.4 849.6 850.9 875.6 16471.0 16813.7 16924.6 18705.4 19287.3 18139.0 18585.3 18594.5 18681.9 18836.0 1020.2 1046.9 1051.4 1052.2 1057.8 1022.7 1032.7 1042.0 1042.2 1049.9 1063.1 1093.3 1094.5 1095.2 1096.4 1074.0 1087.4 1089.2 1093.1 1093.4 770.0 781.6 798.0 803.5 804.0 16332.0 17326.0 17408.7 17611.5 17613.9 13977.2 16981.3 17026.2 17353.0 17415.6 14108.5 14127.9 14381.0 14414.4 14422.6 3535.1 3603.8 3607.7 3611.0 3624.9 124238.4 124660.4 125127.0 126919.7 128059.4 13043.5 13054.3 13161.7 13164.3 13222.3 25668.1 25724.1 25743.7 26089.8 26138.7 7745.8 7793.7 7812.4 7850.3 7869.8 3322.5 3993.8 4024.3 4047.3 4052.0 4276.5 4277.4 4312.7 4357.8 4359.8 837.9 856.1 860.5 861.9 863.2 654.5 660.7 672.2 685.1 689.0 11159.0 11414.5 11654.5 12144.9 12153.4 607.0 645.6 652.6 654.1 666.7 5429.8 5648.6 5743.2 5764.8 5840.9 94090.1 103242.0 105294.5 106408.5 106635.5 89802.7 91063.0 92690.1 93088.5 94618.5 79031.4 80644.6 81722.0 82610.6 82732.0 9023.9 10760.6 11092.6 11499.9 11575.7 41211.0 41394.0 41725.4 42033.4 42402.6 53828.7 54214.0 54603.9 54847.7 55983.3 14604.7 14718.0 14739.9 14740.3 14767.9 17793.0 18824.8 18832.7 18852.6 18906.0 16546.3 17323.0 17606.8 17666.3 17966.6 16789.0 16911.1 17381.5 17594.7 17673.9 14028.9 14466.2 14480.6 14512.3 15799.4 11769.5 12001.4 12035.9 12065.4 12209.6 13052.2 13090.8 13098.1 13213.1 13270.6 10:41:02 INFO - PID 1639 | -------- Summary: end -------- 10:41:02 INFO - PID 1639 | 10:41:02 INFO - TEST-INFO | 1639: exit 0 10:41:02 INFO - TEST-OK | dromaeo_css | took 1114480ms 10:41:02 INFO - TEST-START | kraken 10:41:02 INFO - operating with platform_type : mac_ 10:41:02 INFO - Initialising browser for kraken test... 10:41:02 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpEh50Gu/profile http://localhost:49226/getInfo.html) 10:41:05 INFO - PID 1653 | 2017-05-12 10:41:05.167 plugin-container[1654:10291] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 10:41:05 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:41:05 INFO - PID 1653 | 2017-05-12 10:41:05.170 plugin-container[1654:10291] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 10:41:05 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:41:05 INFO - PID 1653 | 2017-05-12 10:41:05.170 plugin-container[1654:10291] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:41:05 INFO - PID 1653 | __metrics Screen width/height:1600/1200 10:41:05 INFO - PID 1653 | colorDepth:24 10:41:05 INFO - PID 1653 | Browser inner width/height: 1024/691 10:41:05 INFO - PID 1653 | __metrics 10:41:06 INFO - TEST-INFO | 1653: exit 0 10:41:06 INFO - Browser initialized. 10:41:06 INFO - Running cycle 1/1 for kraken test... 10:41:06 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpEh50Gu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:41:08 INFO - PID 1656 | 2017-05-12 10:41:08.641 plugin-container[1657:10446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4537, name = 'com.apple.tsm.portname' 10:41:08 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:41:08 INFO - PID 1656 | 2017-05-12 10:41:08.642 plugin-container[1657:10446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 10:41:08 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:41:08 INFO - PID 1656 | 2017-05-12 10:41:08.642 plugin-container[1657:10446] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:41:09 INFO - PID 1656 | 2017-05-12 10:41:09.193 plugin-container[1658:10490] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:41:09 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:41:09 INFO - PID 1656 | 2017-05-12 10:41:09.194 plugin-container[1658:10490] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 10:41:09 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:41:09 INFO - PID 1656 | 2017-05-12 10:41:09.194 plugin-container[1658:10490] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:41:18 INFO - PID 1656 | RSS: Main: 230207488 10:41:18 INFO - PID 1656 | 10:41:21 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 10:41:22 INFO - PID 1656 | RSS: Main: 243286016 10:41:22 INFO - PID 1656 | 10:41:24 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 10:41:25 INFO - PID 1656 | RSS: Main: 250961920 10:41:25 INFO - PID 1656 | 10:41:28 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 10:41:29 INFO - PID 1656 | RSS: Main: 254078976 10:41:29 INFO - PID 1656 | 10:41:31 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 10:41:32 INFO - PID 1656 | RSS: Main: 255942656 10:41:32 INFO - PID 1656 | 10:41:34 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:41:34 INFO - PID 1656 | RSS: Main: 256770048 10:41:34 INFO - PID 1656 | 10:41:37 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 10:41:38 INFO - PID 1656 | RSS: Main: 262512640 10:41:38 INFO - PID 1656 | 10:41:41 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 10:41:42 INFO - PID 1656 | RSS: Main: 263979008 10:41:42 INFO - PID 1656 | 10:41:45 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 10:41:45 INFO - PID 1656 | RSS: Main: 263557120 10:41:45 INFO - PID 1656 | 10:41:47 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:41:47 INFO - PID 1656 | RSS: Main: 262639616 10:41:47 INFO - PID 1656 | 10:41:49 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:41:50 INFO - PID 1656 | RSS: Main: 263139328 10:41:50 INFO - PID 1656 | 10:41:52 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:41:53 INFO - PID 1656 | RSS: Main: 262758400 10:41:53 INFO - PID 1656 | 10:41:55 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:41:56 INFO - PID 1656 | RSS: Main: 263020544 10:41:56 INFO - PID 1656 | 10:41:59 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:42:00 INFO - PID 1656 | RSS: Main: 263151616 10:42:00 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 10:42:02 INFO - PID 1656 | RSS: Main: 265105408 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | __start_tp_report 10:42:02 INFO - PID 1656 | _x_x_mozilla_page_load 10:42:02 INFO - PID 1656 | _x_x_mozilla_page_load_details 10:42:02 INFO - PID 1656 | |i|pagename|runs| 10:42:02 INFO - PID 1656 | |0;ai-astar;101;112;114;112;114;116;114;115;115;115 10:42:02 INFO - PID 1656 | |1;audio-beat-detection;140;111;109;152;130;150;148;102;169;98 10:42:02 INFO - PID 1656 | |2;audio-dft;291;261;215;251;230;196;254;197;192;202 10:42:02 INFO - PID 1656 | |3;audio-fft;84;56;58;84;58;61;60;61;76;59 10:42:02 INFO - PID 1656 | |4;audio-oscillator;84;70;67;84;94;103;107;108;81;94 10:42:02 INFO - PID 1656 | |5;imaging-gaussian-blur;88;109;130;83;77;130;136;103;91;88 10:42:02 INFO - PID 1656 | |6;imaging-darkroom;152;111;96;111;96;95;96;99;126;97 10:42:02 INFO - PID 1656 | |7;imaging-desaturate;91;92;87;91;96;93;93;91;92;97 10:42:02 INFO - PID 1656 | |8;json-parse-financial;49;51;50;51;47;49;49;52;49;49 10:42:02 INFO - PID 1656 | |9;json-stringify-tinderbox;55;56;61;56;65;59;55;56;57;54 10:42:02 INFO - PID 1656 | |10;stanford-crypto-aes;83;84;82;101;108;78;83;85;82;83 10:42:02 INFO - PID 1656 | |11;stanford-crypto-ccm;153;143;135;142;129;153;159;163;192;146 10:42:02 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;188;194;186;192;180;182;184;185;187;184 10:42:02 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;68;71;68;70;71;69;70;72;70;84 10:42:02 INFO - PID 1656 | __end_tp_report 10:42:02 INFO - PID 1656 | __start_cc_report 10:42:02 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1000 10:42:02 INFO - PID 1656 | __end_cc_report 10:42:02 INFO - PID 1656 | __startTimestamp1494610922421__endTimestamp 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | ------- Summary: start ------- 10:42:02 INFO - PID 1656 | Number of tests: 14 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.50 stddev:4.34 (3.8%) stddev-sans-first:1.36 10:42:02 INFO - PID 1656 | Values: 101.0 112.0 114.0 112.0 114.0 116.0 114.0 115.0 115.0 115.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:149.00 stddev:24.56 (16.5%) stddev-sans-first:25.83 10:42:02 INFO - PID 1656 | Values: 140.0 111.0 109.0 152.0 130.0 150.0 148.0 102.0 169.0 98.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:228.90 Median:240.50 stddev:33.97 (14.1%) stddev-sans-first:27.61 10:42:02 INFO - PID 1656 | Values: 291.0 261.0 215.0 251.0 230.0 196.0 254.0 197.0 192.0 202.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:65.70 Median:61.00 stddev:11.11 (18.2%) stddev-sans-first:9.60 10:42:02 INFO - PID 1656 | Values: 84.0 56.0 58.0 84.0 58.0 61.0 60.0 61.0 76.0 59.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:82.50 stddev:14.49 (17.6%) stddev-sans-first:15.25 10:42:02 INFO - PID 1656 | Values: 84.0 70.0 67.0 84.0 94.0 103.0 107.0 108.0 81.0 94.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:80.00 stddev:21.73 (27.2%) stddev-sans-first:22.31 10:42:02 INFO - PID 1656 | Values: 88.0 109.0 130.0 83.0 77.0 130.0 136.0 103.0 91.0 88.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:96.00 stddev:18.50 (19.3%) stddev-sans-first:10.72 10:42:02 INFO - PID 1656 | Values: 152.0 111.0 96.0 111.0 96.0 95.0 96.0 99.0 126.0 97.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:92.50 stddev:2.79 (3.0%) stddev-sans-first:2.92 10:42:02 INFO - PID 1656 | Values: 91.0 92.0 87.0 91.0 96.0 93.0 93.0 91.0 92.0 97.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:49.50 stddev:1.43 (2.9%) stddev-sans-first:1.50 10:42:02 INFO - PID 1656 | Values: 49.0 51.0 50.0 51.0 47.0 49.0 49.0 52.0 49.0 49.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:56.50 stddev:3.37 (6.0%) stddev-sans-first:3.46 10:42:02 INFO - PID 1656 | Values: 55.0 56.0 61.0 56.0 65.0 59.0 55.0 56.0 57.0 54.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:83.00 stddev:9.60 (11.6%) stddev-sans-first:10.07 10:42:02 INFO - PID 1656 | Values: 83.0 84.0 82.0 101.0 108.0 78.0 83.0 85.0 82.0 83.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:153.00 stddev:17.65 (11.5%) stddev-sans-first:18.71 10:42:02 INFO - PID 1656 | Values: 153.0 143.0 135.0 142.0 129.0 153.0 159.0 163.0 192.0 146.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.20 Median:186.50 stddev:4.29 (2.3%) stddev-sans-first:4.50 10:42:02 INFO - PID 1656 | Values: 188.0 194.0 186.0 192.0 180.0 182.0 184.0 185.0 187.0 184.0 10:42:02 INFO - PID 1656 | 10:42:02 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:70.50 stddev:4.64 (6.6%) stddev-sans-first:4.77 10:42:02 INFO - PID 1656 | Values: 68.0 71.0 68.0 70.0 71.0 69.0 70.0 72.0 70.0 84.0 10:42:02 INFO - PID 1656 | -------- Summary: end -------- 10:42:02 INFO - PID 1656 | 10:42:03 INFO - TEST-INFO | 1656: exit 0 10:42:03 INFO - TEST-OK | kraken | took 60767ms 10:42:03 INFO - SUITE-END | took 1175s 10:42:03 INFO - Completed test suite (00:19:35) 10:42:03 INFO - javascript benchmark 10:42:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8053.305061073873, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3541.929161416772, 3576.392013357729, 3580.91047723807, 3612.6748592626673, 3616.6021737608858, 4972.900541989158, 6218.129461875341, 6226.501879849612, 6235.719392627331, 6275.0, 6223.502119830414, 6228.1591985082005, 6248.187735594366, 6258.593191442553, 6265.185525881629, 5380.24676545283, 5450.400455949843, 5500.147477141049, 5534.640248383842, 5626.803061892834, 5468.863278418032, 5586.0, 5617.8033768818095, 5629.240052592889, 5632.943670563303, 4600.976995115003, 5486.725663716798, 5523.2543606613, 5606.271184745978, 5610.074337734269, 5499.202615620753, 5567.3040869891265, 5599.244102046253, 5604.719764011783, 5609.074502707049, 5484.204790305424, 5515.586331025149, 5530.695811730353, 5533.750981205855, 5549.445055494459, 6038.788642397495, 6160.6919654017565, 6202.844928876769, 6274.592151510137, 6307.558470907038, 5947.583669143161, 6501.829670659279, 6602.471802255808, 6667.599944003376, 6699.229588597276, 6794.592324460548, 6802.2857599951785, 6803.659817009179, 6814.693338799766, 6821.795346139624, 6208.627482351073, 6343.619382837091, 6345.428911397997, 6348.333424990402, 6366.872662546723, 6705.731770729116, 6849.520533562602, 6919.930800692029, 6924.549904256207, 6945.40964018054, 6362.950113231265, 6439.645819479973, 6489.610623362613, 6603.80188594343, 6614.272430032693, 6672.633005184664, 6686.398224159849, 6749.122614060141, 6758.763443279461, 6762.763303284361, 6391.712372943229, 6619.536632435684, 6646.468282537386, 6675.933240667531, 6709.899351509731, 6470.708818103197, 6484.254310754323, 6508.934910650928, 6532.706028228646, 6592.901106483407, 5290.470952904679, 5300.70846103468, 5308.601854860824, 5331.733413329318, 5332.893342133213, 4041.989502624344, 4123.443335149716, 4739.3601863748645, 4746.501617330199, 4780.1873681474235, 6250.499960003189, 6256.781012664626, 6318.968405158037, 6329.050642403677, 6372.617642941438, 5343.0, 6760.0535924970545, 6774.8645027100165, 6783.186017677812, 6820.079289295981, 6262.09199666055, 6277.5605707600025, 6284.5600807943, 6321.273053598803, 6352.841178970562, 5787.565932554991, 5866.032104702676, 5931.288245410492, 5939.970300148471, 5955.76176952917, 5867.0, 6825.112735344473, 6850.760223392157, 6901.654917254017, 6991.720331186695, 6809.625470599165, 6810.250872403933, 6811.318868113149, 6835.179778426621, 6835.179778426621, 6905.309469053054, 6919.342662447059, 6934.7226110955, 6957.826054348682, 6968.5122041458635, 6416.903746443901, 6510.804675859732, 6584.275729669828, 6695.497837662292, 6703.664816759045, 5615.494605485526, 6642.2361428434415, 6840.726370945106, 6844.247132815486, 6845.383915447634, 6285.96857015712, 6423.96788016057, 6530.640814755234, 6590.406863382319, 6596.472282217315, 6517.674116294072, 6537.444317233088, 6553.180852393451, 6560.507961902781, 6604.405603495708, 6251.031090181029, 6313.96843015782, 6422.486201103807, 6442.581232219891, 6445.613263204223, 6428.292887782247, 6482.902756458562, 6519.967400162969, 6560.27836937946, 6602.0, 6432.6462044588, 6437.839054023687, 6438.806835794933, 6439.839004024937, 6455.483561315177], "value": 6107.478634425585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.5021058048269, 807.7818988873004, 837.0415873824473, 1225.577175874323, 1265.183956348156, 37945.81027094864, 38526.42210366847, 38542.614573854255, 38603.61396386042, 38614.806925965364, 38783.0, 38950.61049389505, 39206.82379528612, 39209.60790392102, 39223.21553568927, 7889.171636978121, 8025.157358477363, 8258.967629046369, 8443.240108390244, 8556.70051548196, 8399.832003359928, 8433.789155271137, 8508.574571271443, 8526.147385261485, 8548.74353769388, 8468.364872634545, 8496.702615408461, 8545.273651739579, 8550.102239264881, 8558.52928088958, 8496.0, 8507.191816777397, 8523.786905327355, 8531.957340213321, 8546.0, 8204.261616454547, 8229.711960081428, 8268.545230012407, 8283.25450709439, 8285.62714677835, 32831.01506954795, 33432.49851252233, 36993.63006369929, 37121.25757484862, 37334.43998340027, 3545.9113522161892, 3574.7318951078514, 3607.8556857725657, 3609.7112231021456, 3623.8947121128044, 24121.638175427383, 30206.69793302061, 30481.0, 30664.38671226563, 30685.846570767226, 2644.9471010579873, 3119.7192252697364, 3128.8122712637314, 3140.560321554984, 3200.5839240898536, 278914.21085789084, 286634.9999999979, 288068.0, 295238.0, 303167.0, 42038.79612038771, 46221.0, 48333.275000875015, 48417.273740893914, 48509.5149048512, 43315.35026974598, 50342.0, 50551.49448505542, 54064.0, 54313.45686543084, 14105.435782568788, 14224.644383890278, 14321.212333321559, 14373.353199106064, 14377.353019114165, 11393.259438136494, 11394.373309467894, 11482.598109066143, 11504.53981840717, 11533.884661153448, 14032.158070515801, 14053.78919316211, 14058.437662493387, 14058.789118163235, 14127.103943691518, 6216.601264715403, 6245.281792593819, 6250.624962502265, 6253.405926436982, 6259.405356491126, 978.3787295067541, 1005.261133067195, 1033.478093562746, 1068.941208233539, 1071.3625392891217, 24588.631170532455, 24707.25878223656, 24756.25731228066, 24783.628245576332, 24810.751892481207, 990.4305024610877, 1110.0, 1142.2404101272728, 1146.8165093584987, 1156.9942150289194, 9446.716598502057, 10859.78280434387, 11492.94253528727, 12668.98648108149, 12695.555655552196, 315969.0, 317204.0, 317207.0, 318401.0, 319243.0, 54136.0, 54519.45480545223, 54553.81784728646, 54637.72681136569, 54671.45328546743, 1491.358715752237, 1491.8209814822292, 1535.3704980957755, 1540.4762380790362, 1566.1542766905743, 17793.5765138786, 21966.670499942833, 23800.761992379856, 24370.390740231636, 24490.87754561216, 38053.61946380611, 38257.617423826516, 38268.80865595654, 38339.80830095831, 38375.0, 272585.0, 273119.0, 273637.0, 274564.0, 275604.0, 35861.46207806937, 35921.46117808183, 35941.28117437584, 36012.09969750777, 36042.8197859009, 35134.64865351314, 44050.77974610106, 44618.10763784771, 45310.093798124515, 45635.77182114068, 25503.872480637478, 30042.399152017275, 30411.695883040888, 30668.07995760131, 30818.383632327677, 30006.663948040994, 30518.0, 30533.0, 30716.846415767777, 30886.382272354876, 36675.44986825253, 37438.2512349746, 37509.4373584391, 37569.81215093907, 37631.0, 22721.545569088194, 29643.85178074096, 30149.492525374084, 30653.386932260782, 31679.52480712837, 21398.465038374165, 22512.212072577382, 24228.51542969166, 24320.51358972846, 25048.749512505372], "value": 17422.70204161692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.9744300754579, 403.9050823056582, 440.317507862813, 443.58081612875844, 459.6322941646683, 20029.898355590456, 21834.126634934604, 22360.329190124303, 22464.326070217878, 22700.65949010766, 19623.90188049061, 22664.88667556664, 23592.0, 23668.289951301445, 23750.0, 5990.640561566309, 6176.042713379434, 6206.6586337751405, 6229.252489701242, 6231.096491008823, 6410.358964103576, 6728.327167283257, 6798.150231221098, 6809.693563789641, 6818.5908845469185, 6580.572262802926, 6673.332666733337, 6687.431568316699, 6765.966170169142, 6807.489438292123, 5762.394948530386, 6688.4649228061635, 6804.319568043205, 6804.591724496521, 6817.420519255869, 6363.204599425072, 6594.571352862096, 6631.900521492182, 6632.336766323329, 6641.80074597758, 30175.0, 30323.090307290815, 31353.68646313553, 31453.685463145306, 31491.84254078715, 2361.445060410802, 2421.5278020785954, 2421.7457166997388, 2429.5748244057218, 2431.4407686232266, 19201.32795352156, 19526.023698815145, 19943.700844487343, 20119.893405862673, 20163.495912602157, 2211.4803021289986, 2343.156463673093, 2376.964345534818, 2377.6909001829827, 2380.73811880693, 166925.0, 168289.0, 174043.0, 174061.0, 174153.0, 18452.81547184511, 24690.0, 25430.32981536949, 25765.8711706444, 25835.741642583333, 42554.57445425568, 43150.78424607857, 43313.78343108328, 43354.56645433568, 43486.0, 11141.331520108819, 11233.438328083726, 11388.544458221744, 11403.486843091914, 11690.0, 7883.659777837781, 8291.58542072906, 8605.956970215235, 8807.955960220157, 9256.981732009473, 9980.652611897302, 11064.446777661085, 11213.0, 11492.482838272297, 11671.35807530577, 4070.674346052309, 4135.8966025848995, 4685.851966268286, 4758.882297377703, 4929.880151534874, 935.8315503209351, 976.2287792643874, 993.9950300248453, 1040.095117247984, 1043.7912417516527, 18568.535786605175, 19351.12919918607, 21779.346619601438, 21785.782142178694, 21817.563648726937, 936.6534382278601, 1007.3653598233067, 1017.3743147963949, 1038.11760003996, 1049.9002594753485, 8917.699269490586, 9802.313838031412, 10330.328528645765, 10354.585816567253, 10823.945880270705, 500032.0, 501756.0, 505068.0, 506227.0, 508251.0, 124997.37501312436, 128886.0, 129117.0, 130178.34910825574, 130385.0, 1201.8437603111715, 1221.1451983611507, 1226.957056503018, 1228.2507670321277, 1233.6052463211686, 18842.3405180818, 18990.905045475236, 19034.668932936405, 19093.0, 19152.425427237205, 44322.778386107864, 56809.0, 56969.71515142563, 57062.85874282574, 57759.0, 73243.63378183075, 73956.0, 75063.0, 75289.62355188408, 75888.62055689686, 12998.870011299767, 13091.232683587594, 13478.932605336911, 13534.255615941218, 13549.796753048515, 11206.755733314738, 11992.280463171888, 13784.793228101787, 14224.502142424966, 14671.0, 11904.345261010381, 11912.40437978115, 11978.880211197777, 11986.700332491757, 12120.969717573753, 11089.390083545482, 11118.832522585011, 11142.220044596801, 11479.25384849982, 11536.769264614828, 12007.339596322287, 12098.758024839279, 12147.33189674541, 12239.571614993432, 12341.568045118736, 10744.83882741775, 12773.297468639314, 12842.222976615907, 13048.0, 13124.687531246798, 11830.46762895689, 11990.700232494257, 12191.695207619881, 12220.633380998584, 12258.570950017063], "value": 11837.259407569445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.7723648361537, 1429.7140571885618, 1448.3192899337307, 1453.5639308207535, 1460.1020372470928, 10836.783264334728, 11593.362365069917, 11719.238249513786, 11984.880151198486, 12256.142070055099, 12114.818277725819, 12148.69628259294, 12344.135910486248, 12348.19736717115, 12356.50573977042, 2565.4484285878534, 2613.1376645706964, 2636.3145582148586, 2636.8417894926365, 2636.8681565921725, 2555.6038813983864, 2592.2223333000024, 2596.1822026061823, 2614.647022651937, 2618.48939456806, 2567.2298310506867, 2567.768900798937, 2610.504004239198, 2617.254082586463, 2619.423726780115, 2555.718870924206, 2558.0279493792427, 2584.65107210527, 2597.2467984284535, 2597.5194589000907, 2413.1553956115395, 2571.6271140684685, 2595.610658401236, 2598.246508512529, 2599.4281258123183, 11903.583374581847, 11938.403079845973, 11962.342071186082, 11974.922256996912, 11999.160058795887, 6527.608343499405, 6701.597904125768, 6787.966060169668, 6798.830029249259, 6804.251532331439, 4087.2847251730827, 4400.845970391021, 4475.351074094238, 4481.170983368087, 4487.304467807495, 1269.156011252516, 1270.104576273725, 1290.5483080921697, 1306.601486546603, 1315.5724389573427, 16765.66468670632, 16766.664666706718, 16842.326306947707, 16881.0, 16951.49145525636, 4611.2852507861335, 5660.773569057192, 5752.223449834302, 5760.2223699800825, 5918.852028699231, 11791.410429478492, 11849.0, 11868.287902725864, 11880.821787673192, 11885.5840045598, 6571.34286571339, 6591.34086591337, 6605.471562275006, 6605.603663780189, 6605.86788264232, 6016.849578760566, 6082.817515474543, 6237.09562113491, 6252.6873656317, 6277.964135917614, 2423.5637585234826, 3804.5395742679034, 3817.7401457518954, 4296.162248361602, 4386.692931494828, 1305.6083175047447, 1311.7114234868313, 1313.2055106660332, 1332.840059192903, 1333.2600406774334, 758.491810486975, 774.9302562769378, 800.5276886636913, 800.8278220182689, 802.1096582793095, 6568.376004279586, 6707.329267073351, 6891.207511136183, 6911.343422374866, 6926.757563485254, 834.9582520873932, 938.4556956965081, 964.3056998960737, 966.79213968997, 976.4239098931664, 3040.300730831921, 3221.64561898191, 3878.4596089561383, 4214.578542145761, 4262.0, 10061.899381006242, 14695.044822086531, 15066.171360575054, 15173.089614622937, 15184.696306073816, 14389.28053597316, 15096.320665569856, 15178.0, 15183.62040948963, 15344.693106137815, 8631.870521942175, 8649.740507784776, 8736.519491427905, 8791.692290769792, 8849.69026084084, 5916.142159386779, 6081.27024757032, 6618.172728408949, 6670.066190733204, 6748.208771772097, 9916.752081198028, 13473.258970756702, 13648.385822638204, 13742.175469471864, 13817.447302107803, 13931.233782142079, 14052.578422647337, 14075.436982520585, 14202.902862696645, 14253.429862805371, 8335.666573337, 8572.35707321941, 8596.269317108116, 8600.956995214983, 8619.267362274277, 10822.080123189302, 10919.563217471212, 10938.890611093788, 10964.561417543528, 10968.341899486057, 9041.095890410907, 9290.860637090314, 9331.0, 9355.719328420158, 9379.812403752023, 9017.90982090197, 9104.453732776055, 9183.403078799856, 9204.125608067223, 9324.533773311172, 7177.641117944187, 7546.584937828253, 7932.484388514728, 8022.398320126131, 8046.959765201136, 5167.767450464663, 5262.210668399617, 6083.939160608337, 6117.694115294306, 6137.3862613738265, 5797.971010144923, 6079.392060793885, 6210.596311239755, 6255.53083518747, 6374.617522948638], "value": 5384.4776208854755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.4161804123969, 370.39070728651365, 407.8919086442093, 408.22844823284015, 410.99511693908386, 5190.610704197186, 6228.283747369054, 6245.0, 6290.710404367105, 6305.432511074008, 4750.691205071668, 6012.278526576806, 6162.537809664271, 6176.444120029197, 6178.536609754276, 4297.871063868089, 4491.91016179677, 4538.273876179812, 4544.204764166274, 4546.249868771649, 4415.492218394877, 4487.326900964865, 4510.729356238619, 4542.704724192933, 4561.156186105564, 3947.5460322063045, 3967.821448034845, 4390.670699697535, 4464.241079016559, 4472.597466228039, 4217.882261200784, 4501.582001669496, 4504.617107545862, 4529.7282163069995, 4537.183307004738, 3801.1067399161175, 3815.7328986970924, 3849.3840985442193, 3858.594847540996, 3867.651911327966, 5993.460588547052, 5997.370276120988, 5997.8800423991715, 6010.429009244115, 6053.364396738367, 5254.4220135785035, 5341.9134362212935, 5347.171188465794, 5361.624686271963, 5382.977234325465, 5532.031894418461, 5858.765649374062, 6597.9443289073515, 6603.603783772941, 6635.625143633672, 3716.5168528091444, 3739.2147648993473, 4343.674224433149, 4345.0, 4360.149770794696, 5877.794277200277, 6387.584806987577, 6481.675916204171, 6731.384467727759, 6736.295037729229, 3661.6521430464063, 4192.538820729778, 4379.5401482843845, 4422.20400327944, 4447.3773671685985, 4111.938320925188, 4313.935290970638, 4323.718958267703, 4503.684742068024, 4526.185286648369, 4733.644976626767, 5235.65968212065, 5325.680459172462, 5327.121025030904, 5353.875686105961, 6583.0, 6583.539152259392, 6588.934110658928, 6614.669266536654, 6622.437092847065, 1490.2921112471706, 1529.7017081668969, 1529.9311530981133, 1531.4257153567412, 1545.6213227759047, 937.7702462896634, 1004.8090862736203, 1010.7018429563296, 1012.7974405119006, 1015.5176291261591, 5011.387256218191, 5540.9168862467095, 6248.562600618004, 6273.737656418728, 6313.747450101944, 6457.192850893638, 6705.430038446787, 6736.225334086643, 6736.753700565411, 6911.550749201286, 6086.847828804315, 6271.996480563088, 6302.464290535264, 6306.779262725782, 6320.462760665303, 3386.356592247465, 3468.7745296555645, 3503.491993660956, 3624.4200927851944, 3690.151265209017, 5837.394716453009, 5981.790637327673, 7026.227115017344, 7175.569465831963, 7224.868663744208, 5451.400345961941, 6487.448566871869, 6790.830229244309, 7048.083749112582, 7082.327178917994, 6547.738090476518, 6751.763688270887, 7121.28787121294, 7124.145102587722, 7150.678219480136, 6038.822429626181, 6119.418655227746, 6271.78048768291, 6662.0, 6990.790276291719, 5988.431099045583, 6540.411362977355, 6824.249332573512, 6873.347032031949, 6912.654367281515, 6743.494237932273, 7133.750318738819, 7186.748463803742, 7205.747798827016, 7373.741919032808, 4211.305134652748, 4556.8177272908715, 4618.792154353129, 4815.6388270880525, 5091.745412729423, 5720.227769251098, 6067.938110830499, 6714.362135597111, 6867.313268673292, 6940.201876784235, 6398.968005160131, 6618.635975021228, 6694.430973367318, 6714.146487742787, 6727.697253623696, 5422.105352616774, 6554.541182117397, 6651.368120028589, 6668.599884006975, 6706.798796036127, 6533.314002029909, 6551.279359270381, 6552.541322107407, 6562.310957349409, 6574.31838170626, 6040.728167232573, 6093.451589356979, 6204.689765511796, 6496.0, 6727.400840550282, 5936.940630593639, 6301.779437719658, 6486.248712847599, 6560.278369379269, 6561.442277406474], "value": 4774.903972248232, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.6135962698462, 814.3647954595417, 849.5539841583168, 850.9149085091487, 875.6409871952501, 16471.0, 16813.747793783088, 16924.576885577866, 18705.43883683488, 19287.324943626976, 18139.002354870478, 18585.349512767058, 18594.53513662159, 18681.906590467064, 18835.964021978787, 1020.1736593359366, 1046.863907691999, 1051.416463862556, 1052.1687866585426, 1057.762003549203, 1022.7494263905317, 1032.726327523207, 1041.953112109957, 1042.249580302185, 1049.9107575856062, 1063.1176123817213, 1093.3057508482132, 1094.518411898766, 1095.200503632349, 1096.4243772019674, 1073.98508409553, 1087.3584585094793, 1089.1776708584969, 1093.1418836213613, 1093.4095588382263, 769.96920123195, 781.6131015147538, 797.9985118676025, 803.5419810707899, 803.9678412863478, 16332.0, 17326.0, 17408.65182696339, 17611.471655850346, 17613.911930440394, 13977.161370317814, 16981.3207471701, 17026.23381947815, 17353.0, 17415.564610884703, 14108.506202282872, 14127.858721412756, 14381.0, 14414.42342306317, 14422.567322980327, 3535.1338921964025, 3603.8198090095652, 3607.7113830893463, 3610.963890361089, 3624.9093772655633, 124238.37880810628, 124660.37669811683, 125127.0, 126919.73080269263, 128059.35970320088, 13043.543475978295, 13054.34728263602, 13161.67095822593, 13164.341782910817, 13222.272774997275, 25668.10161644371, 25724.099656512306, 25743.74256257451, 26089.825957831945, 26138.73861261401, 7745.806354841174, 7793.688252469951, 7812.375009999187, 7850.332721718718, 7869.803254918558, 3322.4684050551796, 3993.7803420812133, 4024.2756303865, 4047.3119569673227, 4052.0, 4276.48682158143, 4277.422547956048, 4312.676549258818, 4357.803898824561, 4359.825606975685, 837.949723016621, 856.0797143071223, 860.5052095045374, 861.9138086191331, 863.218786997768, 654.4698793976894, 660.6663634864459, 672.2135101930753, 685.0888318536335, 688.9871888324155, 11159.0, 11414.516112905269, 11654.53381864716, 12144.939275303566, 12153.392330383445, 606.9908951365733, 645.6190847400056, 652.6280020388429, 654.1202083198091, 666.6600033982704, 5429.809956651498, 5648.63283886546, 5743.195952566644, 5764.827055188351, 5840.941590584125, 94090.05909940813, 103242.0, 105294.47352763341, 106408.46795765971, 106635.46682266695, 89802.65296020565, 91063.0, 92690.14619707703, 93088.53455732814, 94618.5269073664, 79031.41937161339, 80644.59677701691, 81721.95695107551, 82610.58694706488, 82732.0, 9023.90976090257, 10760.569577216824, 11092.611758588411, 11499.885001150216, 11575.71060723455, 41211.0, 41394.0, 41725.37411938763, 42033.369499458146, 42402.575974239866, 53828.730856345464, 54214.0, 54603.90792184213, 54847.72576137094, 55983.28200615789, 14604.707905842037, 14718.0433271837, 14739.92630036843, 14740.262986850828, 14767.852321477078, 17793.0, 18824.811751882855, 18832.7175092371, 18852.622947541247, 18905.960172190662, 16546.338146474005, 17323.0, 17606.82393176103, 17666.293348265925, 17966.640667186846, 16789.0, 16911.11055888283, 17381.478555643353, 17594.73607895857, 17673.91163044228, 14028.859711402756, 14466.20435876037, 14480.565583032527, 14512.346944387316, 15799.447019354267, 11769.52921883115, 12001.39993000364, 12035.939820300842, 12065.396730163633, 12209.572664956684, 13052.15161014531, 13090.80363794563, 13098.083134180515, 13213.094110822849, 13270.601881943558], "value": 8423.17324450971, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 114.0, 112.0, 114.0, 116.0, 114.0, 115.0, 115.0, 115.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 109.0, 152.0, 130.0, 150.0, 148.0, 102.0, 169.0, 98.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [291.0, 261.0, 215.0, 251.0, 230.0, 196.0, 254.0, 197.0, 192.0, 202.0], "value": 228.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 56.0, 58.0, 84.0, 58.0, 61.0, 60.0, 61.0, 76.0, 59.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 67.0, 84.0, 94.0, 103.0, 107.0, 108.0, 81.0, 94.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 109.0, 130.0, 83.0, 77.0, 130.0, 136.0, 103.0, 91.0, 88.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 111.0, 96.0, 111.0, 96.0, 95.0, 96.0, 99.0, 126.0, 97.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 87.0, 91.0, 96.0, 93.0, 93.0, 91.0, 92.0, 97.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 50.0, 51.0, 47.0, 49.0, 49.0, 52.0, 49.0, 49.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 61.0, 56.0, 65.0, 59.0, 55.0, 56.0, 57.0, 54.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 101.0, 108.0, 78.0, 83.0, 85.0, 82.0, 83.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 143.0, 135.0, 142.0, 129.0, 153.0, 159.0, 163.0, 192.0, 146.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 194.0, 186.0, 192.0, 180.0, 182.0, 184.0, 185.0, 187.0, 184.0], "value": 186.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 68.0, 70.0, 71.0, 69.0, 70.0, 72.0, 70.0, 84.0], "value": 71.3, "unit": "score"}]}]} 10:42:03 INFO - Return code: 0 10:42:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:42:03 INFO - # TBPL SUCCESS # 10:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:03 INFO - 2017-05-12 10:42:03.712 screenresolution[1663:10866] starting screenresolution argv=screenresolution get 10:42:03 INFO - 2017-05-12 10:42:03.714 screenresolution[1663:10866] Display 0: 1600x1200x32@60 10:42:03 INFO - 2017-05-12 10:42:03.720 screenresolution[1664:10869] starting screenresolution argv=screenresolution list 10:42:03 INFO - Available Modes on Display 0 10:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:03 INFO - Intel Iris: 10:42:03 INFO - Chipset Model: Intel Iris 10:42:03 INFO - Type: GPU 10:42:03 INFO - Bus: Built-In 10:42:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:03 INFO - Vendor: Intel (0x8086) 10:42:03 INFO - Device ID: 0x0a2e 10:42:03 INFO - Revision ID: 0x0009 10:42:03 INFO - Displays: 10:42:03 INFO - Display: 10:42:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:03 INFO - Display Serial Number: 1600x1200 60 10:42:03 INFO - Main Display: Yes 10:42:03 INFO - Mirror: Off 10:42:03 INFO - Online: Yes 10:42:03 INFO - Rotation: Supported 10:42:03 INFO - Return code: 0 10:42:03 INFO - Running post-action listener: _resource_record_post_action 10:42:03 INFO - [mozharness: 2017-05-12 17:42:03.965643Z] Finished run-tests step (success) 10:42:03 INFO - Running post-run listener: _resource_record_post_run 10:42:04 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39226368; Write bytes: 527186944; Read time: 263; Write time: 1643 10:42:04 INFO - TinderboxPrint: CPU usage
54.2% 10:42:04 INFO - TinderboxPrint: I/O read bytes / time
39,226,368 / 263 10:42:04 INFO - TinderboxPrint: I/O write bytes / time
527,186,944 / 1,643 10:42:04 INFO - TinderboxPrint: CPU idle
2,190.6 (45.8%) 10:42:04 INFO - TinderboxPrint: CPU system
964.9 (20.2%) 10:42:04 INFO - TinderboxPrint: CPU user
1,630.2 (34.1%) 10:42:04 INFO - TinderboxPrint: Swap in / out
253,652,992 / 0 10:42:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206809600; Write bytes: 207554560; Read time: 15764; Write time: 450 10:42:04 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38259712; Write bytes: 309756928; Read time: 251; Write time: 1158 10:42:04 INFO - Running post-run listener: _upload_blobber_files 10:42:04 INFO - Blob upload gear active. 10:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:42:04 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 10:42:04 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'] 10:42:04 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 10:42:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:06 INFO - (blobuploader) - INFO - Done attempting. 10:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:42:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:07 INFO - (blobuploader) - INFO - Done attempting. 10:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:42:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:08 INFO - (blobuploader) - INFO - Done attempting. 10:42:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:42:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:42:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:10 INFO - (blobuploader) - INFO - Done attempting. 10:42:10 INFO - (blobuploader) - INFO - Iteration through files over. 10:42:10 INFO - Return code: 0 10:42:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:42:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:42:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0cdfca192eaac517c9e53f979258f925004de9ebf0606488c86f39c5bed98508c2ba275bc526878efd3bb8f87d93183482023b70c59d8fa72c0bccda43a69d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6567efbdd8e861e910a353a32bbc2a1b75c7b2ed9fdc9ce3a5a1f7b0f43489b00e24f2aa252311ef2cd13c931f224d9f8bc4f07bf060ca4003d28f514f76a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59c5e8a59127bcf601f50b50f50f38bd31b6635b9756d789c7b743b9d14a0438ea6f6134a39386c2531e0f8a4d11cfc45c52bb1a585c2233acc5ce688a278b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b3d7d1eb5a7fffd85b66e7ce13734e06e4b9b6d4c9a63bf828bb3e03746ba573039f25e478ea247ae0e59cf3b913a97c929269c93150a5ee0c8f638b78997a"} 10:42:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:42:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:42:10 INFO - Contents: 10:42:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0cdfca192eaac517c9e53f979258f925004de9ebf0606488c86f39c5bed98508c2ba275bc526878efd3bb8f87d93183482023b70c59d8fa72c0bccda43a69d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6567efbdd8e861e910a353a32bbc2a1b75c7b2ed9fdc9ce3a5a1f7b0f43489b00e24f2aa252311ef2cd13c931f224d9f8bc4f07bf060ca4003d28f514f76a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59c5e8a59127bcf601f50b50f50f38bd31b6635b9756d789c7b743b9d14a0438ea6f6134a39386c2531e0f8a4d11cfc45c52bb1a585c2233acc5ce688a278b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b3d7d1eb5a7fffd85b66e7ce13734e06e4b9b6d4c9a63bf828bb3e03746ba573039f25e478ea247ae0e59cf3b913a97c929269c93150a5ee0c8f638b78997a"} 10:42:10 INFO - Running post-run listener: copy_logs_to_upload_dir 10:42:10 INFO - Copying logs to upload dir... 10:42:10 INFO - mkdir: /builds/slave/test/build/upload/logs 10:42:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.571469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-12 10:42:10.445245) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:42:10.448426) ========= 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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0cdfca192eaac517c9e53f979258f925004de9ebf0606488c86f39c5bed98508c2ba275bc526878efd3bb8f87d93183482023b70c59d8fa72c0bccda43a69d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6567efbdd8e861e910a353a32bbc2a1b75c7b2ed9fdc9ce3a5a1f7b0f43489b00e24f2aa252311ef2cd13c931f224d9f8bc4f07bf060ca4003d28f514f76a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59c5e8a59127bcf601f50b50f50f38bd31b6635b9756d789c7b743b9d14a0438ea6f6134a39386c2531e0f8a4d11cfc45c52bb1a585c2233acc5ce688a278b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b3d7d1eb5a7fffd85b66e7ce13734e06e4b9b6d4c9a63bf828bb3e03746ba573039f25e478ea247ae0e59cf3b913a97c929269c93150a5ee0c8f638b78997a"} build_url:https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/TSuTwOIoR9epgWsUd-p85w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0cdfca192eaac517c9e53f979258f925004de9ebf0606488c86f39c5bed98508c2ba275bc526878efd3bb8f87d93183482023b70c59d8fa72c0bccda43a69d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6567efbdd8e861e910a353a32bbc2a1b75c7b2ed9fdc9ce3a5a1f7b0f43489b00e24f2aa252311ef2cd13c931f224d9f8bc4f07bf060ca4003d28f514f76a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59c5e8a59127bcf601f50b50f50f38bd31b6635b9756d789c7b743b9d14a0438ea6f6134a39386c2531e0f8a4d11cfc45c52bb1a585c2233acc5ce688a278b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b3d7d1eb5a7fffd85b66e7ce13734e06e4b9b6d4c9a63bf828bb3e03746ba573039f25e478ea247ae0e59cf3b913a97c929269c93150a5ee0c8f638b78997a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 10:42:10.482765) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:42:10.483255) ========= 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.u5hqt2aBz4/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.fO0SmEwiMS/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.005114 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 10:42:10.533107) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:42:10.533505) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 10:42:10.533835) ========= ========= Total master_lag: 0.18 =========