builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1495794459.52 results: success (0) revision: 93f430463af9f2e366326e9083277f969e2d5237 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:39.516544) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:39.516965) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:39.517235) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:39.541660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:39.541924) ========= 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.0BQp6pQwET/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.TpUrLtohIY/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-26 03:27:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.4M=0.001s 2017-05-26 03:27:40 (12.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.466787 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:40.032934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:40.033470) ========= 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.0BQp6pQwET/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.TpUrLtohIY/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.021906 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:40.083490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 03:27:40.083874) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 93f430463af9f2e366326e9083277f969e2d5237 --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 93f430463af9f2e366326e9083277f969e2d5237 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0BQp6pQwET/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.TpUrLtohIY/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-26 03:27:40,165 truncating revision to first 12 chars 2017-05-26 03:27:40,165 Setting DEBUG logging. 2017-05-26 03:27:40,165 attempt 1/10 2017-05-26 03:27:40,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/93f430463af9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 03:27:40,741 unpacking tar archive at: mozilla-central-93f430463af9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.857792 ========= master_lag: 1.61 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 03:27:42.552901) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:42.553217) ========= script_repo_revision: 93f430463af9f2e366326e9083277f969e2d5237 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:42.553613) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:42.553875) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 03:27:43.408117) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-26 03:27:43.408895) ========= /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.0BQp6pQwET/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.TpUrLtohIY/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:27:43 INFO - MultiFileLogger online at 20170526 03:27:43 in /builds/slave/test 03:27:43 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 03:27:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:43 INFO - {'append_to_log': False, 03:27:43 INFO - 'base_work_dir': '/builds/slave/test', 03:27:43 INFO - 'blob_upload_branch': 'Firefox', 03:27:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:43 INFO - 'branch': 'Firefox', 03:27:43 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:43 INFO - 'config_files': ('talos/mac_config.py',), 03:27:43 INFO - 'default_actions': ('clobber', 03:27:43 INFO - 'read-buildbot-config', 03:27:43 INFO - 'download-and-extract', 03:27:43 INFO - 'populate-webroot', 03:27:43 INFO - 'create-virtualenv', 03:27:43 INFO - 'install', 03:27:43 INFO - 'run-tests'), 03:27:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:43 INFO - 'download_minidump_stackwalk': True, 03:27:43 INFO - 'download_symbols': 'ondemand', 03:27:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:27:43 INFO - 'tooltool.py': '/tools/tooltool.py', 03:27:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:27:43 INFO - '/tools/misc-python/virtualenv.py')}, 03:27:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:43 INFO - 'gecko_profile': False, 03:27:43 INFO - 'gecko_profile_interval': 0, 03:27:43 INFO - 'installer_path': 'installer.exe', 03:27:43 INFO - 'log_level': 'info', 03:27:43 INFO - 'log_name': 'talos', 03:27:43 INFO - 'log_to_console': True, 03:27:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:27:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:27:43 INFO - 'opt_config_files': (), 03:27:43 INFO - 'pip_index': False, 03:27:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:43 INFO - 'cmd': ('bash', 03:27:43 INFO - '-c', 03:27:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:43 INFO - 'enabled': True, 03:27:43 INFO - 'halt_on_failure': False, 03:27:43 INFO - 'name': 'check_screen_resolution'},), 03:27:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:27:43 INFO - 'cmd': ('bash', 03:27:43 INFO - '-c', 03:27:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:27:43 INFO - 'enabled': True, 03:27:43 INFO - 'halt_on_failure': False, 03:27:43 INFO - 'name': 'check_screen_resolution'},), 03:27:43 INFO - 'run_cmd_checks_enabled': True, 03:27:43 INFO - 'suite': 'dromaeojs-e10s', 03:27:43 INFO - 'system_bits': '32', 03:27:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:43 INFO - 'title': 't-yosemite-r7-0276', 03:27:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:43 INFO - 'use_talos_json': True, 03:27:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:43 INFO - 'work_dir': 'build'} 03:27:43 INFO - [mozharness: 2017-05-26 10:27:43.609826Z] Running clobber step. 03:27:43 INFO - Running pre-action listener: _resource_record_pre_action 03:27:43 INFO - Running main action method: clobber 03:27:43 INFO - rmtree: /builds/slave/test/build 03:27:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:45 INFO - Running post-action listener: _resource_record_post_action 03:27:45 INFO - [mozharness: 2017-05-26 10:27:45.448680Z] Finished clobber step (success) 03:27:45 INFO - [mozharness: 2017-05-26 10:27:45.448810Z] Running read-buildbot-config step. 03:27:45 INFO - Running pre-action listener: _resource_record_pre_action 03:27:45 INFO - Running main action method: read_buildbot_config 03:27:45 INFO - Using buildbot properties: 03:27:45 INFO - { 03:27:45 INFO - "project": "", 03:27:45 INFO - "product": "firefox", 03:27:45 INFO - "who": "cbook@mozilla.com", 03:27:45 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:27:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:27:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:27:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:27:45 INFO - "stage_platform": "macosx64", 03:27:45 INFO - "basedir": "/builds/slave/test", 03:27:45 INFO - "buildnumber": 77, 03:27:45 INFO - "platform": "yosemite_r7", 03:27:45 INFO - "parent_task_id": "VB28z78nQx25Zb4MUI3bqQ", 03:27:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:27:45 INFO - "slavebuilddir": "test", 03:27:45 INFO - "taskId": "QmNyprw0QbaBS7YVp6yAdA", 03:27:45 INFO - "branch": "mozilla-central", 03:27:45 INFO - "script_repo_revision": "production", 03:27:45 INFO - "revision": "93f430463af9f2e366326e9083277f969e2d5237", 03:27:45 INFO - "slavename": "t-yosemite-r7-0276", 03:27:45 INFO - "repo_path": "mozilla-central" 03:27:45 INFO - } 03:27:45 INFO - Finding installer, test and symbols from parent task. 03:27:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QmNyprw0QbaBS7YVp6yAdA'}, attempt #1 03:27:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QmNyprw0QbaBS7YVp6yAdA'}, attempt #1 03:27:46 INFO - Task dependencies: cvRmY4M7RF-BkwWJtjg0-A VB28z78nQx25Zb4MUI3bqQ 03:27:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ'}, attempt #1 03:27:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:27:47 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/test_packages.json 03:27:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/target.crashreporter-symbols.zip 03:27:47 INFO - Running post-action listener: _resource_record_post_action 03:27:47 INFO - [mozharness: 2017-05-26 10:27:47.278032Z] Finished read-buildbot-config step (success) 03:27:47 INFO - [mozharness: 2017-05-26 10:27:47.278145Z] Running download-and-extract step. 03:27:47 INFO - Running pre-action listener: _resource_record_pre_action 03:27:47 INFO - Running main action method: download_and_extract 03:27:47 INFO - mkdir: /builds/slave/test/build/tests 03:27:47 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')]} 03:27:47 INFO - trying https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/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 03:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/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 03:27:48 INFO - Downloaded 1325 bytes. 03:27:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:27:48 INFO - Using the following test package requirements: 03:27:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:27:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:27:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:27:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:27:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'jsshell-mac.zip'], 03:27:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:27:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:27:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:27:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:27:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:27:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:27:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:27:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:27:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:27:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:27:48 INFO - Fetch https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:27:50 INFO - Content-Length response header: 57627091 03:27:50 INFO - Bytes received: 57627091 03:28:08 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 03:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:28:10 INFO - Content-Length response header: 57627091 03:28:10 INFO - Bytes received: 57627091 03:28:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:28:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:28:27 INFO - Fetch https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:28:29 INFO - Content-Length response header: 14411888 03:28:29 INFO - Bytes received: 14411888 03:28:29 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')]} 03:28:29 INFO - trying https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:29 INFO - Downloading https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/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 03:28:31 INFO - Downloaded 67184705 bytes. 03:28:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:31 INFO - mkdir: /builds/slave/test/properties 03:28:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:28:31 INFO - Writing to file /builds/slave/test/properties/build_url 03:28:31 INFO - Contents: 03:28:31 INFO - build_url:https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:28:31 INFO - Running post-action listener: _resource_record_post_action 03:28:31 INFO - Running post-action listener: set_extra_try_arguments 03:28:31 INFO - [mozharness: 2017-05-26 10:28:31.433541Z] Finished download-and-extract step (success) 03:28:31 INFO - [mozharness: 2017-05-26 10:28:31.433687Z] Running populate-webroot step. 03:28:31 INFO - Running pre-action listener: _resource_record_pre_action 03:28:31 INFO - Running main action method: populate_webroot 03:28:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:28:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:28:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:28:31 INFO - '--tppagecycles', 03:28:31 INFO - '7'], 03:28:31 INFO - 'tests': ['tsvgm']}}, 03:28:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:28:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:28:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:28:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:28:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:28:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['damp', 'tps']}, 03:28:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'tests': ['damp', 'tps']}, 03:28:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['dromaeo_dom']}, 03:28:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:28:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:28:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:28:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:28:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['a11yr', 03:28:31 INFO - 'ts_paint', 03:28:31 INFO - 'tpaint', 03:28:31 INFO - 'sessionrestore', 03:28:31 INFO - 'sessionrestore_no_auto_restore', 03:28:31 INFO - 'tabpaint']}, 03:28:31 INFO - 'other-e10s': {'tests': ['a11yr', 03:28:31 INFO - 'ts_paint', 03:28:31 INFO - 'tpaint', 03:28:31 INFO - 'sessionrestore', 03:28:31 INFO - 'sessionrestore_no_auto_restore', 03:28:31 INFO - 'tabpaint']}, 03:28:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:28:31 INFO - 'ts_paint', 03:28:31 INFO - 'tpaint', 03:28:31 INFO - 'sessionrestore', 03:28:31 INFO - 'sessionrestore_no_auto_restore', 03:28:31 INFO - 'tabpaint']}, 03:28:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:28:31 INFO - 'ts_paint', 03:28:31 INFO - 'tpaint', 03:28:31 INFO - 'sessionrestore', 03:28:31 INFO - 'sessionrestore_no_auto_restore', 03:28:31 INFO - 'tabpaint']}, 03:28:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['a11yr', 03:28:31 INFO - 'ts_paint', 03:28:31 INFO - 'tpaint', 03:28:31 INFO - 'sessionrestore', 03:28:31 INFO - 'sessionrestore_no_auto_restore', 03:28:31 INFO - 'tabpaint']}, 03:28:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['a11yr', 03:28:31 INFO - 'ts_paint', 03:28:31 INFO - 'tpaint', 03:28:31 INFO - 'sessionrestore', 03:28:31 INFO - 'sessionrestore_no_auto_restore', 03:28:31 INFO - 'tabpaint']}, 03:28:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:28:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:28:31 INFO - 'bloom_basic_ref']}, 03:28:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['tsvgx', 03:28:31 INFO - 'tsvgr_opacity', 03:28:31 INFO - 'tart', 03:28:31 INFO - 'tscrollx', 03:28:31 INFO - 'cart', 03:28:31 INFO - 'tsvg_static']}, 03:28:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:28:31 INFO - 'tsvgr_opacity', 03:28:31 INFO - 'tart', 03:28:31 INFO - 'tscrollx', 03:28:31 INFO - 'cart', 03:28:31 INFO - 'tsvg_static']}, 03:28:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'talos_options': ['--disable-e10s'], 03:28:31 INFO - 'tests': ['tp5o']}, 03:28:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:28:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'talos_options': ['--disable-e10s', 03:28:31 INFO - '--xperf_path', 03:28:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:31 INFO - 'tests': ['tp5n']}, 03:28:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:28:31 INFO - 'talos_options': ['--xperf_path', 03:28:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:28:31 INFO - 'tests': ['tp5n']}}, 03:28:31 INFO - 'talos.zip': {'path': '', 03:28:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:28:31 INFO - Running post-action listener: _resource_record_post_action 03:28:31 INFO - [mozharness: 2017-05-26 10:28:31.456461Z] Finished populate-webroot step (success) 03:28:31 INFO - [mozharness: 2017-05-26 10:28:31.456618Z] Running create-virtualenv step. 03:28:31 INFO - Running pre-action listener: _resource_record_pre_action 03:28:31 INFO - Running main action method: create_virtualenv 03:28:31 INFO - Creating virtualenv /builds/slave/test/build/venv 03:28:31 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 03:28:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:28:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:28:31 INFO - Using real prefix '/tools/python27' 03:28:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:28:32 INFO - Installing distribute.............................................................................................................................................................................................done. 03:28:35 INFO - Installing pip.................done. 03:28:35 INFO - Return code: 0 03:28:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:28:35 INFO - Reading from file tmpfile_stdout 03:28:35 INFO - Output received: 03:28:35 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) 03:28:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:28:35 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')]} 03:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:35 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')]} 03:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:35 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:35 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 03:28:35 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 03:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:35 INFO - 'HOME': '/Users/cltbld', 03:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:35 INFO - 'LOGNAME': 'cltbld', 03:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:35 INFO - 'MOZ_NO_REMOTE': '1', 03:28:35 INFO - 'NO_EM_RESTART': '1', 03:28:35 INFO - 'PAGER': '/bin/cat', 03:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:35 INFO - 'PWD': '/builds/slave/test', 03:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:35 INFO - 'SHELL': '/bin/bash', 03:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:35 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:35 INFO - 'USER': 'cltbld', 03:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:35 INFO - 'XPC_FLAGS': '0x0', 03:28:35 INFO - 'XPC_SERVICE_NAME': '0', 03:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:35 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 03:28:35 INFO - Cleaning up... 03:28:35 INFO - Return code: 0 03:28:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:28:35 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')]} 03:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:36 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')]} 03:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:36 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:36 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 03:28:36 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 03:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:36 INFO - 'HOME': '/Users/cltbld', 03:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:36 INFO - 'LOGNAME': 'cltbld', 03:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:36 INFO - 'MOZ_NO_REMOTE': '1', 03:28:36 INFO - 'NO_EM_RESTART': '1', 03:28:36 INFO - 'PAGER': '/bin/cat', 03:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:36 INFO - 'PWD': '/builds/slave/test', 03:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:36 INFO - 'SHELL': '/bin/bash', 03:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:36 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:36 INFO - 'USER': 'cltbld', 03:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:36 INFO - 'XPC_FLAGS': '0x0', 03:28:36 INFO - 'XPC_SERVICE_NAME': '0', 03:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:36 INFO - Downloading/unpacking psutil>=3.1.1 03:28:36 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 03:28:36 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 03:28:36 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 03:28:36 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 03:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:28:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:38 INFO - Installing collected packages: psutil 03:28:38 INFO - Running setup.py install for psutil 03:28:38 INFO - building 'psutil._psutil_osx' extension 03:28:38 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 03:28:39 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 03:28:39 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 03:28:39 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 03:28:39 INFO - building 'psutil._psutil_posix' extension 03:28:39 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 03:28:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:28:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:28:39 INFO - ^ 03:28:39 INFO - 1 warning generated. 03:28:39 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 03:28:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:39 INFO - Successfully installed psutil 03:28:39 INFO - Cleaning up... 03:28:39 INFO - Return code: 0 03:28:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:28:39 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')]} 03:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:40 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')]} 03:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:40 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 03:28:40 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 03:28:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:40 INFO - 'HOME': '/Users/cltbld', 03:28:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:40 INFO - 'LOGNAME': 'cltbld', 03:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:40 INFO - 'MOZ_NO_REMOTE': '1', 03:28:40 INFO - 'NO_EM_RESTART': '1', 03:28:40 INFO - 'PAGER': '/bin/cat', 03:28:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:40 INFO - 'PWD': '/builds/slave/test', 03:28:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:40 INFO - 'SHELL': '/bin/bash', 03:28:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:40 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:40 INFO - 'USER': 'cltbld', 03:28:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:40 INFO - 'XPC_FLAGS': '0x0', 03:28:40 INFO - 'XPC_SERVICE_NAME': '0', 03:28:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:28:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:28:41 INFO - Installing collected packages: mozsystemmonitor 03:28:41 INFO - Running setup.py install for mozsystemmonitor 03:28:41 INFO - Successfully installed mozsystemmonitor 03:28:41 INFO - Cleaning up... 03:28:41 INFO - Return code: 0 03:28:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:28:41 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')]} 03:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:41 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')]} 03:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:41 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:41 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 03:28:41 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 03:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:41 INFO - 'HOME': '/Users/cltbld', 03:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:41 INFO - 'LOGNAME': 'cltbld', 03:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:41 INFO - 'MOZ_NO_REMOTE': '1', 03:28:41 INFO - 'NO_EM_RESTART': '1', 03:28:41 INFO - 'PAGER': '/bin/cat', 03:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:41 INFO - 'PWD': '/builds/slave/test', 03:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:41 INFO - 'SHELL': '/bin/bash', 03:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:41 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:41 INFO - 'USER': 'cltbld', 03:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:41 INFO - 'XPC_FLAGS': '0x0', 03:28:41 INFO - 'XPC_SERVICE_NAME': '0', 03:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:28:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:43 INFO - Downloading functools32-3.2.3-2.tar.gz 03:28:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:28:43 INFO - warning: no files found matching '*.txt' 03:28:43 INFO - no previously-included directories found matching 'build' 03:28:43 INFO - no previously-included directories found matching 'dist' 03:28:43 INFO - no previously-included directories found matching '.git*' 03:28:43 INFO - Installing collected packages: jsonschema, functools32 03:28:43 INFO - Running setup.py install for functools32 03:28:44 INFO - warning: no files found matching '*.txt' 03:28:44 INFO - no previously-included directories found matching 'build' 03:28:44 INFO - no previously-included directories found matching 'dist' 03:28:44 INFO - no previously-included directories found matching '.git*' 03:28:44 INFO - Successfully installed jsonschema functools32 03:28:44 INFO - Cleaning up... 03:28:44 INFO - Return code: 0 03:28:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:44 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 03:28:44 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 03:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:44 INFO - 'HOME': '/Users/cltbld', 03:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:44 INFO - 'LOGNAME': 'cltbld', 03:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:44 INFO - 'MOZ_NO_REMOTE': '1', 03:28:44 INFO - 'NO_EM_RESTART': '1', 03:28:44 INFO - 'PAGER': '/bin/cat', 03:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:44 INFO - 'PWD': '/builds/slave/test', 03:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:44 INFO - 'SHELL': '/bin/bash', 03:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:44 INFO - 'USER': 'cltbld', 03:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:44 INFO - 'XPC_FLAGS': '0x0', 03:28:44 INFO - 'XPC_SERVICE_NAME': '0', 03:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:28:44 INFO - Cleaning up... 03:28:44 INFO - Return code: 0 03:28:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:44 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 03:28:44 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 03:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:44 INFO - 'HOME': '/Users/cltbld', 03:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:44 INFO - 'LOGNAME': 'cltbld', 03:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:44 INFO - 'MOZ_NO_REMOTE': '1', 03:28:44 INFO - 'NO_EM_RESTART': '1', 03:28:44 INFO - 'PAGER': '/bin/cat', 03:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:44 INFO - 'PWD': '/builds/slave/test', 03:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:44 INFO - 'SHELL': '/bin/bash', 03:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:44 INFO - 'USER': 'cltbld', 03:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:44 INFO - 'XPC_FLAGS': '0x0', 03:28:44 INFO - 'XPC_SERVICE_NAME': '0', 03:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:46 INFO - Downloading blobuploader-1.2.4.tar.gz 03:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:28:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:28: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 03:28: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 03:28: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 03:28: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 03:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:28:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:28: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 03:28: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 03:28: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 03:28: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 03:28:46 INFO - Downloading docopt-0.6.1.tar.gz 03:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:28:47 INFO - Installing collected packages: blobuploader, requests, docopt 03:28:47 INFO - Running setup.py install for blobuploader 03:28:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:28:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:28:47 INFO - Running setup.py install for requests 03:28:47 INFO - Running setup.py install for docopt 03:28:47 INFO - Successfully installed blobuploader requests docopt 03:28:47 INFO - Cleaning up... 03:28:47 INFO - Return code: 0 03:28:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:47 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')]} 03:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:47 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')]} 03:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:47 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:47 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 03:28:47 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 03:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:47 INFO - 'HOME': '/Users/cltbld', 03:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:47 INFO - 'LOGNAME': 'cltbld', 03:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:47 INFO - 'MOZ_NO_REMOTE': '1', 03:28:47 INFO - 'NO_EM_RESTART': '1', 03:28:47 INFO - 'PAGER': '/bin/cat', 03:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:47 INFO - 'PWD': '/builds/slave/test', 03:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:47 INFO - 'SHELL': '/bin/bash', 03:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:47 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:47 INFO - 'USER': 'cltbld', 03:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:47 INFO - 'XPC_FLAGS': '0x0', 03:28:47 INFO - 'XPC_SERVICE_NAME': '0', 03:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Z3uxSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-cRiS1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-sjHIIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AQsAAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-aHR57K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-xzvT3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-CERFrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-aFKIR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:48 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-abHY0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-bkIhuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-GFcjzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-FvaF0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AqhifV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tHzTmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-jPcoap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-x0qpJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-xecXAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:28:49 INFO - Running setup.py install for manifestparser 03:28:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:28:50 INFO - Running setup.py install for mozcrash 03:28:50 INFO - Running setup.py install for mozdebug 03:28:50 INFO - Running setup.py install for mozdevice 03:28:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:28:50 INFO - Running setup.py install for mozfile 03:28:50 INFO - Running setup.py install for mozhttpd 03:28:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:28:50 INFO - Running setup.py install for mozinfo 03:28:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:28:50 INFO - Running setup.py install for mozInstall 03:28:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Running setup.py install for mozleak 03:28:51 INFO - Running setup.py install for mozlog 03:28:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Running setup.py install for moznetwork 03:28:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Running setup.py install for mozprocess 03:28:51 INFO - Running setup.py install for mozprofile 03:28:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:28:51 INFO - Running setup.py install for mozrunner 03:28:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:28:52 INFO - Running setup.py install for mozscreenshot 03:28:52 INFO - Running setup.py install for moztest 03:28:52 INFO - Running setup.py install for mozversion 03:28:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:28:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:28:52 INFO - Cleaning up... 03:28:52 INFO - Return code: 0 03:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:52 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')]} 03:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:52 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')]} 03:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:52 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:52 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 03:28:52 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 03:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:52 INFO - 'HOME': '/Users/cltbld', 03:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:52 INFO - 'LOGNAME': 'cltbld', 03:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:52 INFO - 'MOZ_NO_REMOTE': '1', 03:28:52 INFO - 'NO_EM_RESTART': '1', 03:28:52 INFO - 'PAGER': '/bin/cat', 03:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:52 INFO - 'PWD': '/builds/slave/test', 03:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:52 INFO - 'SHELL': '/bin/bash', 03:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:52 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:52 INFO - 'USER': 'cltbld', 03:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:52 INFO - 'XPC_FLAGS': '0x0', 03:28:52 INFO - 'XPC_SERVICE_NAME': '0', 03:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-XlWiMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:52 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)) 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-VVRs4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:52 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)) 03:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tDZ89K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-EWpWC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rYFwHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nMfX_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-aHbO5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0d2S2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-O16PPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-NFbTzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ylUj9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:53 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)) 03:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-T_O8ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:54 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)) 03:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-HOJ4ZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:54 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)) 03:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-wg38o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:54 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)) 03:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-WNPc2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:54 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)) 03:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-eIL_sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:54 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)) 03:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-XtaUWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:54 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)) 03:28:54 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)) 03:28:54 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)) 03:28:54 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)) 03:28:54 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:54 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28: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 03:28: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 03:28: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 03:28: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 03:28:55 INFO - Downloading blessings-1.6.tar.gz 03:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:28:55 INFO - Installing collected packages: blessings 03:28:55 INFO - Running setup.py install for blessings 03:28:56 INFO - Successfully installed blessings 03:28:56 INFO - Cleaning up... 03:28:56 INFO - Return code: 0 03:28:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:56 INFO - Reading from file tmpfile_stdout 03:28:56 INFO - Current package versions: 03:28:56 INFO - blessings == 1.6 03:28:56 INFO - blobuploader == 1.2.4 03:28:56 INFO - docopt == 0.6.1 03:28:56 INFO - functools32 == 3.2.3-2 03:28:56 INFO - jsonschema == 2.5.1 03:28:56 INFO - manifestparser == 1.1 03:28:56 INFO - mozInstall == 1.12 03:28:56 INFO - mozcrash == 1.0 03:28:56 INFO - mozdebug == 0.1 03:28:56 INFO - mozdevice == 0.51 03:28:56 INFO - mozfile == 1.2 03:28:56 INFO - mozhttpd == 0.7 03:28:56 INFO - mozinfo == 0.9 03:28:56 INFO - mozleak == 0.1 03:28:56 INFO - mozlog == 3.4 03:28:56 INFO - moznetwork == 0.27 03:28:56 INFO - mozprocess == 0.25 03:28:56 INFO - mozprofile == 0.28 03:28:56 INFO - mozrunner == 6.13 03:28:56 INFO - mozscreenshot == 0.1 03:28:56 INFO - mozsystemmonitor == 0.3 03:28:56 INFO - moztest == 0.8 03:28:56 INFO - mozversion == 1.4 03:28:56 INFO - psutil == 3.1.1 03:28:56 INFO - requests == 1.2.3 03:28:56 INFO - wsgiref == 0.1.2 03:28:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:56 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')]} 03:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:56 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')]} 03:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:56 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:56 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 03:28:56 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 03:28:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:56 INFO - 'HOME': '/Users/cltbld', 03:28:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:56 INFO - 'LOGNAME': 'cltbld', 03:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:56 INFO - 'MOZ_NO_REMOTE': '1', 03:28:56 INFO - 'NO_EM_RESTART': '1', 03:28:56 INFO - 'PAGER': '/bin/cat', 03:28:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:56 INFO - 'PWD': '/builds/slave/test', 03:28:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:56 INFO - 'SHELL': '/bin/bash', 03:28:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:56 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:56 INFO - 'USER': 'cltbld', 03:28:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:56 INFO - 'XPC_FLAGS': '0x0', 03:28:56 INFO - 'XPC_SERVICE_NAME': '0', 03:28:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 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)) 03:28:56 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28: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 03:28: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 03:28: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 03:28: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 03:28:58 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:28:58 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)) 03:28:58 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)) 03:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:58 INFO - Installing collected packages: marionette-driver 03:28:58 INFO - Running setup.py install for marionette-driver 03:28:58 INFO - Successfully installed marionette-driver 03:28:58 INFO - Cleaning up... 03:28:58 INFO - Return code: 0 03:28:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:58 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 0x10bb9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb704b9540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, '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.0BQp6pQwET/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.TpUrLtohIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 03:28:58 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 03:28:58 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 03:28:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:28:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:28:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:28:58 INFO - 'HOME': '/Users/cltbld', 03:28:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:28:58 INFO - 'LOGNAME': 'cltbld', 03:28:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:58 INFO - 'MOZ_NO_REMOTE': '1', 03:28:58 INFO - 'NO_EM_RESTART': '1', 03:28:58 INFO - 'PAGER': '/bin/cat', 03:28:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:28:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:58 INFO - 'PWD': '/builds/slave/test', 03:28:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:28:58 INFO - 'SHELL': '/bin/bash', 03:28:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:28:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:28:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:28:58 INFO - 'USER': 'cltbld', 03:28:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:28:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:28:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:58 INFO - 'XPC_FLAGS': '0x0', 03:28:58 INFO - 'XPC_SERVICE_NAME': '0', 03:28:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:28:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:28:58 INFO - Cleaning up... 03:28:58 INFO - Return code: 0 03:28:58 INFO - Running post-action listener: _resource_record_post_action 03:28:58 INFO - Running post-action listener: _start_resource_monitoring 03:28:58 INFO - Starting resource monitoring. 03:28:58 INFO - [mozharness: 2017-05-26 10:28:58.747243Z] Finished create-virtualenv step (success) 03:28:58 INFO - [mozharness: 2017-05-26 10:28:58.748249Z] Running install step. 03:28:58 INFO - Running pre-action listener: _resource_record_pre_action 03:28:58 INFO - Running main action method: install 03:28:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:59 INFO - Reading from file tmpfile_stdout 03:28:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:28:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:28:59 INFO - Reading from file tmpfile_stdout 03:28:59 INFO - Output received: 03:28:59 INFO - Usage: mozinstall [options] installer 03:28:59 INFO - Options: 03:28:59 INFO - -h, --help show this help message and exit 03:28:59 INFO - -d DEST, --destination=DEST 03:28:59 INFO - Directory to install application into. [default: 03:28:59 INFO - "/builds/slave/test"] 03:28:59 INFO - --app=APP Application being installed. [default: firefox] 03:28:59 INFO - mkdir: /builds/slave/test/build/application 03:28:59 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'] 03:28:59 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 03:29:19 INFO - Reading from file tmpfile_stdout 03:29:19 INFO - Output received: 03:29:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:29:20 INFO - Running post-action listener: _resource_record_post_action 03:29:20 INFO - [mozharness: 2017-05-26 10:29:20.000210Z] Finished install step (success) 03:29:20 INFO - [mozharness: 2017-05-26 10:29:20.000356Z] Running run-tests step. 03:29:20 INFO - Running pre-action listener: _resource_record_pre_action 03:29:20 INFO - Running main action method: run_tests 03:29:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:29:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:29:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:29:20 INFO - 2017-05-26 03:29:20.014 screenresolution[2539:36229] starting screenresolution argv=screenresolution get 03:29:20 INFO - 2017-05-26 03:29:20.017 screenresolution[2539:36229] Display 0: 1600x1200x32@60 03:29:20 INFO - 2017-05-26 03:29:20.023 screenresolution[2540:36231] starting screenresolution argv=screenresolution list 03:29:20 INFO - Available Modes on Display 0 03:29:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:29:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:29:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:29:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:29:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:29:20 INFO - Intel Iris: 03:29:20 INFO - Chipset Model: Intel Iris 03:29:20 INFO - Type: GPU 03:29:20 INFO - Bus: Built-In 03:29:20 INFO - VRAM (Dynamic, Max): 1536 MB 03:29:20 INFO - Vendor: Intel (0x8086) 03:29:20 INFO - Device ID: 0x0a2e 03:29:20 INFO - Revision ID: 0x0009 03:29:20 INFO - Displays: 03:29:20 INFO - Display: 03:29:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:29:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:29:20 INFO - Display Serial Number: 1600x1200 60 03:29:20 INFO - Main Display: Yes 03:29:20 INFO - Mirror: Off 03:29:20 INFO - Online: Yes 03:29:20 INFO - Rotation: Supported 03:29:20 INFO - Return code: 0 03:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:29:20 INFO - Python 2.7.3 03:29:20 INFO - Return code: 0 03:29:20 INFO - grabbing minidump binary from tooltool 03:29:20 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')]} 03:29:20 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 0x10b8aad30>, '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 0x10b85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b89fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:29:20 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 03:29:20 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 03:29:20 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 03:29:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:29:20 INFO - Return code: 0 03:29:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:29:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:29:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:29:20 INFO - ENV: RUST_BACKTRACE is now 1 03:29:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:29:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:29:20 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/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 03:29:20 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/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 03:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BQp6pQwET/Render', 03:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:20 INFO - 'HOME': '/Users/cltbld', 03:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:20 INFO - 'LOGNAME': 'cltbld', 03:29:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:29:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:20 INFO - 'MOZ_NO_REMOTE': '1', 03:29:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:29:20 INFO - 'NO_EM_RESTART': '1', 03:29:20 INFO - 'PAGER': '/bin/cat', 03:29:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:20 INFO - 'PWD': '/builds/slave/test', 03:29:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:20 INFO - 'RUST_BACKTRACE': '1', 03:29:20 INFO - 'SHELL': '/bin/bash', 03:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TpUrLtohIY/Listeners', 03:29:20 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 03:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:20 INFO - 'USER': 'cltbld', 03:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:20 INFO - 'XPC_FLAGS': '0x0', 03:29:20 INFO - 'XPC_SERVICE_NAME': '0', 03:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:20 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/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 03:29:20 INFO - mozversion application_buildid: 20170526020922 03:29:20 INFO - mozversion application_changeset: 93f430463af9f2e366326e9083277f969e2d5237 03:29:20 INFO - mozversion application_display_name: Nightly 03:29:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:29:20 INFO - mozversion application_name: Firefox 03:29:20 INFO - mozversion application_remotingname: firefox 03:29:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:29:20 INFO - mozversion application_vendor: Mozilla 03:29:20 INFO - mozversion application_version: 55.0a1 03:29:20 INFO - mozversion platform_buildid: 20170526020922 03:29:20 INFO - mozversion platform_changeset: 93f430463af9f2e366326e9083277f969e2d5237 03:29:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:29:20 INFO - mozversion platform_version: 55.0a1 03:29:20 INFO - using testdate: 1495794560 03:29:20 INFO - actual date: 1495794560 03:29:20 INFO - starting webserver on 'localhost:58546' 03:29:20 INFO - SUITE-START | Running 2 tests 03:29:20 INFO - TEST-START | dromaeo_css 03:29:20 INFO - operating with platform_type : mac_ 03:29:20 INFO - Initialising browser for dromaeo_css test... 03:29:20 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58546/getInfo.html -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpuhMYL3/profile 03:29:20 INFO - TEST-INFO | started process 2550 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58546/getInfo.html -foreground) 03:29:54 INFO - TEST-INFO | 2550: killed by SIGTERM 03:29:54 INFO - Browser initialized. 03:29:54 INFO - Running cycle 1/1 for dromaeo_css test... 03:29:54 INFO - TEST-INFO | started process 2557 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpuhMYL3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:29:56 INFO - PID 2557 | 2017-05-26 03:29:56.294 plugin-container[2558:36710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 03:29:56 INFO - PID 2557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:56 INFO - PID 2557 | 2017-05-26 03:29:56.295 plugin-container[2558:36710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 03:29:56 INFO - PID 2557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:56 INFO - PID 2557 | 2017-05-26 03:29:56.295 plugin-container[2558:36710] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:29:56 INFO - PID 2557 | 2017-05-26 03:29:56.882 plugin-container[2559:36755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:29:56 INFO - PID 2557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:56 INFO - PID 2557 | 2017-05-26 03:29:56.882 plugin-container[2559:36755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490b, name = 'com.apple.CFPasteboardClient' 03:29:56 INFO - PID 2557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:29:56 INFO - PID 2557 | 2017-05-26 03:29:56.882 plugin-container[2559:36755] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:30:06 INFO - PID 2557 | RSS: Main: 242692096 03:30:06 INFO - PID 2557 | 03:33:08 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:58546/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58546/tests/dromaeo/cssquery-ext.html) 03:33:09 INFO - PID 2557 | RSS: Main: 255037440 03:33:09 INFO - PID 2557 | 03:36:11 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:58546/tests/dromaeo/cssquery-ext.html (next: http://localhost:58546/tests/dromaeo/cssquery-jquery.html) 03:36:12 INFO - PID 2557 | RSS: Main: 270405632 03:36:12 INFO - PID 2557 | 03:39:14 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:58546/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58546/tests/dromaeo/cssquery-mootools.html) 03:39:14 INFO - PID 2557 | RSS: Main: 275914752 03:39:14 INFO - PID 2557 | 03:42:16 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:58546/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58546/tests/dromaeo/cssquery-prototype.html) 03:42:17 INFO - PID 2557 | RSS: Main: 279797760 03:42:17 INFO - PID 2557 | 03:45:19 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:58546/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58546/tests/dromaeo/cssquery-yui.html) 03:45:20 INFO - PID 2557 | RSS: Main: 280166400 03:45:20 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:58546/tests/dromaeo/cssquery-yui.html (next: http://localhost:58546/tests/dromaeo/cssquery-dojo.html) 03:48:22 INFO - PID 2557 | RSS: Main: 282763264 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | __start_tp_report 03:48:22 INFO - PID 2557 | _x_x_mozilla_page_load 03:48:22 INFO - PID 2557 | _x_x_mozilla_page_load_details 03:48:22 INFO - PID 2557 | |i|pagename|runs| 03:48:22 INFO - PID 2557 | |0;dojo.html;3574.249407624397;3574.7855128692277;3603.2072943952353;3610.494530765693;3627.056965189051;4973.254011898217;5103.744812759361;5251.842444726654;5472.398036216013;6151.600145990507;6191.6285022898655;6224.96887515563;6280.591761535497;6289.654069026202;6455.677216139186;5303.628745987782;5451.754671039813;5458.426865179159;5474.91787623186;5574.052411090124;4664.3936288282475;5625.353084395286;5634.464725851038;5634.859128521779;5635.746391412396;5403.081476149025;5478.150886612581;5485.533729632946;5524.972375138139;5560.4161563036105;5520.889582208333;5528.364238112588;5558.833235002957;5573.359063707685;5576.749046292926;5482.396936337;5503.064479038533;5504.284443022421;5517.889642207174;5523.944760552383;6183.319834818167;6188.8762224755255;6291.591046581958;6305.621662700252;6312.274088479791;6491.448226900719;6669.866602667968;6757.054012438262;6760.459163266927;6773.119494465737;6911.101556797635;6913.965430172867;6931.029655848185;6937.583744975267;6949.7567585134275;6382.648954307511;6388.584741991802;6391.105245265667;6403.167588213502;6425.421712045892;6733;6835.213950395669;6879.832852651399;6963.79108626742;6967.65161741921;5432.348118225838;6395.936040639534;6450.6774661266745;6490.578112422678;6693.765718199839;6758.121444212221;6820.04519367289;6861.4510839133745;6876.793696189123;6878.518503704692;6738.494612904052;6753;6757.898631520531;6761.357671021246;6765.492588055916;6508.837279067967;6604.306547812506;6624.768133115318;6664.233613134455;6785.5250132490255;5389.730513474311;5391.245225668409;5411.458854114557;5424.376196737347;5448.782048718086;4785.49752276006;4794.065157294364;4821.083994041121;4838.927416088762;4851.75741212945;6158;6239.875202495925;6286.465650419674;6321.146645202931;6334.461570766536;6856.451483881277;6880.65596720162;6882.518223724292;6899.17209934811;6923.9653801730665;6325.399087086719;6325.5255855811;6331.620102793847;6344.333844946214;6358.33237510064;5842.240508733922;5902.144189092557;5933.228680271537;6020.157177995085;6066.272047354258;6962.930370696431;6963.40811031068;6987;6997.615131167631;7010.123734533184;6759.188897332351;6858.691358888962;6867.725290988505;6874.759383421756;6887.311268873073;6618.834529136618;6974.61639609806;7011.684474198775;7027;7040.190378106378;6529.869402611826;6607.735690572324;6656.434203092791;6689.096971908924;6754.5947243165565;6849.760258390932;6866.896996545156;6867.553609015398;6867.656617169221;6912.135983002125;5711;6426.807195783947;6483.286838447862;6564.540482166202;6673.699683514156;6624.8012559623285;6672.165979252593;6673.599584024974;6683.231428385798;6687.09724187248;5485.780568777204;6488.896887529226;6507.414332710079;6535.803925882231;6538.967305163444;5410.83767486976;6423.132877061536;6706.93293067063;6710.362515561014;6718.294579069127;6412.871742565029;6483.902741458976;6494;6529.379708927644;6558.770443034471 03:48:22 INFO - PID 2557 | |1;ext.html;707.5471698113207;758.5107605594392;818.868980963046;1219.1526888812273;1270.1743866486788;39860.20279594406;41089.79455102728;41142.38286425702;41242.79378603103;41278.38082428766;41388.79305603475;41546.792266038625;41585;41610.791946040306;41729.374059389134;7919.604019799016;8117.391195660349;8417.831643367159;8623.223909848113;8625.611847466878;8441.324694024494;8543.700970466034;8544;8546.44448110874;8570.014448338427;8542.572871356439;8551.059383467813;8594.742157735278;8598.570071496431;8603.870941935877;8479.788005299855;8512;8547.871781923244;8564.229219370287;8568.186022327869;8354.248117669378;8389.95805020977;8397.748067557983;8406.537640429773;8442.62008209632;37993.810030949666;38012.619873801465;38125;38128.80935595332;38205;3089.165925200183;3525.1892064825233;3552.609212986569;3561.1809283864595;3574.7318951078514;30928.8453557733;31083;31118.844405778054;31229.843850780828;31381.686183138103;3135.5923729914966;3141.4502462069045;3179.380020895926;3183.8567264473154;3194.5847039884898;227119;227439;283580.99999999796;291796.5410172957;291896;48798.26802597964;48945.26582101271;48978;49007.77480563014;49094.26358604624;49986.750066249435;51124;51490;51502.74248628733;51630;13576.1854288743;13595.45618175262;13698.041137120306;13736.65658358529;13755.10591811529;10785;10876.456177191109;10911.781764364669;10930.234883558234;10938.945305273422;13988;14172.22052787086;14175.716485670435;14232.573022809333;14256.501022464372;6076.210092687922;6122.8876037766895;6150.10823430609;6161.537884658668;6169.198004259418;989.3618615992789;1012.1245122968726;1034.9223808214415;1035.342557476008;1042.4266653340633;21934.780652193273;24577.50844983126;25003.62494562583;25003.99984000656;25127;1119.770447058351;1132.5073592987078;1133.7392399748105;1162.314234601589;1163.96508104757;10891.83662245067;12261.877381226073;12782.424790884432;12811.871881281255;12828.743425131632;241314;250923;292011;296642;297415;58618;58973;58990.70504647449;59075.40924590785;59080;1291;1397.601683520192;1504.338091239873;1511.8790496760453;1516.962075948088;29064.7093529062;29681;30909.609067591562;31653.366932660756;31732;39337;39356;39450.80274598723;39621.00947476336;39626.2074758509;261036;264888;265999.0100148538;266658;266749;28999.7100028997;37132.25735485329;37680.2319768021;37689.49242030288;37766;47658.761706192636;47847.28229076497;48498.75750621224;48867;49310.54826162134;29599.26001849971;30372.544411833398;31628.841855790575;32748.672513275516;33286.500702489;32107.876224332038;32221.677783222807;32822.179445514055;33281.83359083189;33562.83218583892;37060.258794823414;38644;39927.401088983104;40542.9864253396;41095.589044109176;33271.83364083164;33658;33730.325393491505;33850.83074584611;34547;23679;25495.617565736156;26206.06908963671;26243.950241990107;26369 03:48:22 INFO - PID 2557 | |2;jquery.html;405.3089482432453;422.9090745489721;431.76253060816583;439.060410721057;457.46705088571815;21088.261910833106;21165.68251476229;21873.234436794715;21911.0140043698;22037.669434958487;22515.887420562914;23506.64740028901;23668.76331236687;23777;23873.880630596865;5896.88206235875;6163.537734669895;6165.506759459255;6180.258368995705;6186.288576813679;5870.823875283727;6035.064564992434;6039.3960603939695;6081.1790408294755;6140.35526269742;5978.940210597882;6032.939670603304;6042.214512113441;6044.576879618428;6070.878582428349;5374.381946076212;5803.477687008169;6007.339192688801;6043.335233124353;6044.425779550936;5365.624406291561;5373.973130134364;5375.139977603565;5375.435579264199;5378.623496355257;26683.466330673473;26789;27481.45037099267;27489.72510274892;27808.887644494196;2222;2287.553926984239;2288.8054515366216;2289;2300.505391340853;17596.560085997826;18107.36624218146;18279;18340.908295458572;18607.976561289124;2117.1637203304763;2133.4559687279643;2167.3281282802222;2169.5443956769095;2173.739151301854;158537;158808;158878;159728.20135899363;159930;12488.50045998168;12753.48986040548;12865.742685146244;12941.611751647277;13051.934740326427;16233.51299461018;16469.670606588043;16503.33986640545;16504.504864853832;16522.83477165213;8481.830363392697;8644.740657780276;8660.22058014769;8737;8744.994325652631;7930.286274235346;7994.36045116401;8029.116797152308;8150.673973041131;8167.346612271004;6674.799756007327;7893.723719669899;7943.324817390587;8060.435769496079;8086.43394962347;1852.4720454670355;2185.2242453928625;2213.7896899794496;2221.7778222177653;2234.653628687556;966.3042609321277;980.8136454073704;993.7217579077869;1014.5028935821394;1038.4288641247435;19658.23075923145;20593.38219853407;21364.926374807357;21556.89221553882;21717.891410542845;861.3066481482467;916.093067862811;1040.618821325734;1044.5978298355187;1071.1591400750374;8948.373613847098;10626.203034772423;10919.890801091888;10928.890711092947;10942.398168100832;512454;551767;560637;570039;572358;129410;133347.333263335;143286.5671343294;143768.28115859354;145937;1194.946227419768;1219.8414206153143;1244.813278008306;1269.8349214602042;1274.095392271479;21913.780862191175;22145.781981991146;22167.556648866608;22297.331080067623;22604.434889127246;54890.90218195694;54956.450435496736;55199.44800551943;55414.44585554093;55492.72253638706;71625.64187179031;71807.64096179485;71924.12303507801;72864.63567682127;73105.63447182943;8807.779805504913;8928.910710892807;8941.642334306554;8960.910390896008;9309.301802364715;11309.377984210947;13661.18032918028;14092.929535352258;14318.284085795876;14345.426182952982;11195.496202670736;11843.111766617365;11882;12072.577459789216;12163.635090947286;10816.729581760519;11063.668089957315;11136.109111270916;11227.494762735532;11530.59642912494;11760.70598235051;12041.397930103285;12170.57402990891;12191.146619736888;12259.141860069709;8422.32621390275;8788.51663158507;8804.119588041402;8899.688510902088;9337.57980890848;11980.400979951142;11985.520579176735;12250.387480625755;12260.509579616715;12306.384680766105 03:48:22 INFO - PID 2557 | |3;mootools.html;1351.5134551561437;1442.1347191684983;1447.073872721459;1451.3468938977455;1470.2501724120702;11662.825057624132;11869.347185904771;12185.207961482509;12235;12256.325902075394;11902.523899044028;11929.403529823518;12111.333876636781;12128.21166624171;12144.785521447873;2560.577504711725;2583.689957205138;2594.3254753764063;2607.3742301847515;2612.973870261292;2529.949401011988;2590.611408288753;2592.222333300012;2593.1183397644795;2595.286296268522;2547.3631592101974;2565.846049237052;2573.4338445541953;2573.691157061146;2573.987130064347;2111.767705552388;2516.232549072532;2516.5721827289312;2543.5421624107653;2547;2214.6456566949373;2450.8161887858305;2527.5576774064466;2542.5931850903767;2611.595202743578;11848.229865058744;11927.880721192763;11930.522779088826;11934.701632459171;11936.16446848721;6423.807285781434;6667.933320666828;6688.86622267552;6700.966495167541;6739.258681545026;4168.687348448879;4353.651707863363;4396.494403143647;4418.050119224382;4433.800478978422;1240.7146356338003;1277.9808302875465;1286.614015795267;1287.40779241549;1309.18830325199;15373.846261537354;15694.215289235493;16625.83374166267;16770.66458670832;16772.496825095266;5390.568754499631;5473.480019398151;5610.4670056344585;5614.214010038598;5633.492985631231;11845.289282643069;12003.519859205533;12008.819867701992;12021.459034343476;12029.278243305429;6383.904241436382;6497.090407342976;6499.38255865692;6505.186851643544;6505.674716264168;5340.744924942635;5999.640021598718;6088.360722124202;6156.107364432221;6210.099535567318;4344.739315641072;4421.005273813431;4466.084452687256;4502.684812063124;4518.277075667877;1255.5291765587904;1281.1224311346757;1284.3000564692265;1298.1107941060404;1301.8633043530292;800.8518424091561;801.7474495534004;801.903771547418;808.1554775259867;818.950862948225;6170.5372097092895;6211.881861265016;6404.647744374011;6411.198600174978;6460.741570337133;589.381149792716;592.733270028489;601.3925934805877;605.7032054293364;613.3222788818381;3665.725070619714;4167.291560434734;4292.420523229386;4396.098799746044;4520.52534483881;14517.927410363092;14734.189619571027;14910.403583856742;14987.025843320149;15024.924875375553;14607.123572585679;14894.255287235812;14916.403343866341;15151.696966060617;15229.390824366901;8291.461055031403;8480.787980300542;8544.14558544153;8625.482471051757;8660.437071590326;5065.670731402447;6994.860102798018;7166.426685865223;7195.748148814766;7205.207427182901;10338.741531461474;12798.104132710621;12890.226586404846;12925;12950.481980721042;10880.401577913291;13032.609021729364;13118.409671564994;13162.341882905625;13327.866721332663;9431.292653050912;9522.476263805556;9674.419534827934;9677.70966870995;9690.37012594179;10891.346519208873;10924.453777311262;10980.780384392427;11007.669769906915;11047.392393418118;7779.7666070017995;9039.954800225956;9237.445753254826;9278.72163835086;9493.810123797624;9129.908700912905;9135;9146.902371715436;9249.352545322032;9355.06449355059;8132.512049277062;8227.670893164206;8229.135940726137;8248.670053197804;8315.251627353568;4727.739974301447;6010.669413182142;6089.665068421279;6139.969300153471;6211.09939058843;5054.620903432184;6202.317745048132;6226.501879849692;6319.873602527831;6340.175777148941 03:48:22 INFO - PID 2557 | |4;prototype.html;347.8399936029426;406.1227748064182;406.6299667302755;407.3279089502321;416.76244540611845;6325.968370158149;6349.587276827003;6350.301466838644;6352.555321127523;6360.491160707138;5981.9401805981925;6043;6137.447629713325;6225.478681134136;6225.692604553049;4688.179568575502;4718.268668356393;4727.527247275262;4728.196206644879;4744.667873248874;3854.9421758673643;4603.562661547147;4729.432468103832;4740.71555706657;4751.738654374008;4692.530746925314;4697.476892330752;4699.577038066574;4702.106599746055;4705.270683044117;4630.32860235264;4658.417697787777;4689.437267527918;4711;4712.622990160796;4068.4710987571425;4120.546739858613;4134.131832315187;4136.655586934228;4139.8965025874295;6114.388561143895;6126.387361263883;6129.325774164839;6135.263768347782;6142.416470435302;5541.612087153901;5548.223248745179;5591.30108736408;5594.622285528675;5624.887502249932;5167.457416971239;6014.879702405928;6639.73441062357;6755.90566924255;6766.458683305324;4728.62602093066;4733.934864655459;4734.680006796645;4742.2886567014875;4744.525547445262;6244.718987645521;7058.435325173974;7081.185663648695;7087.89368159478;7209.243029481881;4675.836345727883;4997.30037794709;5127.2052831811125;5133.9743301284;5134.640575159703;5233.4243233244315;5238.869028274324;5247.475252474798;5247.658902171346;5260.92108618371;5618.494335509824;5787.913181302284;5822.505087067562;5844.094165404369;5872.530197584183;6816.43375852803;6844.555103918229;6930.861382772317;6933.93066069343;6949.4440444765305;1774.8668849836313;1775.973360399595;1797.2451570340488;1798.505411011956;1806.1872157529165;1254.9811752823714;1262.5581046633788;1268.3848333558217;1277.920157466939;1284.8693150027916;5576.776928922797;6166.784162554289;6396.936030639634;6400.807975760735;6442.420182183626;6208.379162083846;6933.653317334113;7020.754273600502;7068.363847253669;7088.908771912298;6274.6548939807835;6327.683615819191;6338.193614819755;6352;6352.7612115637;3655.1593133579427;3685.3182161300247;3705.8517659293325;3709.666130048309;3714;6080.385434263221;7026.43788496919;7214.927850721426;7314.597697126603;7321.63391830417;6477.222733272038;7145.392641625416;7221.53060051095;7269.600171990592;7314.707411703579;5971.880562388728;6515.7294327606605;6718.966405167943;7271.527350722186;7425.665845036986;5568.805091821767;6157.815265542041;6769.9168133098465;7049.9647501762165;7058.25888281723;5889.439298585964;6618.238902526271;6672.898037118486;6781.728730850711;6898.172219333512;5470.91793623104;7095.503314767917;7153.535020223669;7219.169795473378;7273.672684729306;4526.479454862601;4553.408056952575;4554.151741778192;4556.908861822812;4567.406237189277;6791.249543934714;7003.859922801414;7034.894476582957;7076.787696369117;7091.82270443243;5697.464628240393;6783.559068660521;6798.660066996729;6824.692888819915;6841.589504629738;5314.707691077027;5563.026470367588;6588.275289718032;6743.291954344723;6818.318168183142;5175.378954525481;6426.3573642635365;6656.500762442933;6696.4977626679165;6702.765403210864;5859.2382990211;6600.844852150951;6690.531662783558;6802.693878775566;6822.795316140524;6629.436497897733;6631.734730610914;6642.535022548375;6644.534882558174;6665.133532640726 03:48:22 INFO - PID 2557 | |5;yui.html;762.3367670126988;837.7696133563265;842.9831403371933;864.4813112132721;864.8529749942509;13044.73910521789;14593.708125837504;17050.147492625365;17468.21393037313;17528.73706894396;16465.67068658626;18126.834031656206;18354.81645183548;18370.44888653339;18375;962.1533050915202;968.6077138758814;986.2898712926682;995.1143482300758;997.4514017290461;997.4314640654829;1007.1338648762058;1007.780585491556;1010.9949450252775;1014.1633152649057;944.7496413450407;1049.664107485605;1059.0468578279533;1060.1571750458404;1064.340109132335;1050.086424810416;1051.05405135378;1052.079430498314;1052.447465080831;1053.2890299048113;769.4729110559229;784.4156103702721;787.2088551006202;787.5510958753513;787.7242964962275;16268.593285167966;17162.970221786607;17627.559311017198;17714.937103773686;17729.822701773075;14052;17723;18035.188416521156;18038.90980545102;18042.27830886763;14368.281585920662;14964.401423943138;14970.251487425585;14982.026168299135;15021.774673379909;3590.281943611288;3612.7832330060282;3618.3486972344967;3619.927601447983;3629.3467175908327;155359;155462;155869;156036;156525;12078.818817717742;13394.531191408254;13529.32353382327;13675.042747007812;13693;25799.742002579733;26200.213993580222;26640.333991649975;26708.19875403741;27026.72973270242;6765.695543700545;7000.824979375455;7449.2923172299625;7804.297613214838;7887.974563306848;3906.7851268179957;3991.820368083443;4003.199360127986;4006.759594424344;4008.0180355813013;4155.646770024582;4226.429432026698;4241.215375155606;4260.893477663021;4381.145676593033;939.2110627073291;956.6747305916022;960.9087136722001;967.579103090158;969.6024629901707;650.3236633900785;664.378805816563;673.7069374821968;673.845015646404;681.308471901011;10753.677389678322;11221.887781122248;11624;12400.81398779019;12633.747325053448;650.632392698124;659.7624855052177;667.4960404894274;671.9462443004547;672.2739441403272;5759.971200143972;5802.970985145047;5853.94146058534;5854.736536855852;5939.07944268639;137277;138001;139148;139565.60434395526;141969.29015354856;112903;123093.38453307677;123530;123787;127200;104105;105497.78008879712;105676;105752.47123764486;106301;11253.381064041556;11578.884211157781;11735.823962640738;11935.224210426295;11943.104267179822;44231.33652995143;44428.111437770414;44747;45065.54934450614;45076.32385514154;70429.64785176041;76490;77242.61378693295;77908;78109;14333.928330358282;17841.910790445963;17848.19683114289;17851.642967140844;17859.46421607354;16088.919555402148;18765.71851422257;18965;19088.61822763565;19287.710684340025;17503.737443938608;17883.73174402411;18239;18270.72593911119;18278.81721182771;17004.574885627957;17524.91237543804;18087.095645217425;18211.81788182101;18241.726374104135;16578.58553536171;17122.64899611164;17372;17599;17671.11644417748;10396.596459477874;11440.256383335056;11961.820572691242;12085.093617978511;12317.137800353888;13453.663658408617;13577;13638.318084095557;13641.249731264876;13711.725765484834 03:48:22 INFO - PID 2557 | __end_tp_report 03:48:22 INFO - PID 2557 | __start_cc_report 03:48:22 INFO - PID 2557 | _x_x_mozilla_cycle_collect,393 03:48:22 INFO - PID 2557 | __end_cc_report 03:48:22 INFO - PID 2557 | __startTimestamp1495795702428__endTimestamp 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | ------- Summary: start ------- 03:48:22 INFO - PID 2557 | Number of tests: 6 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | [#0] dojo.html Cycles:165 Average:6199.95 Median:6450.68 stddev:761.63 (11.8%) stddev-sans-first:735.59 03:48:22 INFO - PID 2557 | Values: 3574.2 3574.8 3603.2 3610.5 3627.1 4973.3 5103.7 5251.8 5472.4 6151.6 6191.6 6225.0 6280.6 6289.7 6455.7 5303.6 5451.8 5458.4 5474.9 5574.1 4664.4 5625.4 5634.5 5634.9 5635.7 5403.1 5478.2 5485.5 5525.0 5560.4 5520.9 5528.4 5558.8 5573.4 5576.7 5482.4 5503.1 5504.3 5517.9 5523.9 6183.3 6188.9 6291.6 6305.6 6312.3 6491.4 6669.9 6757.1 6760.5 6773.1 6911.1 6914.0 6931.0 6937.6 6949.8 6382.6 6388.6 6391.1 6403.2 6425.4 6733.0 6835.2 6879.8 6963.8 6967.7 5432.3 6395.9 6450.7 6490.6 6693.8 6758.1 6820.0 6861.5 6876.8 6878.5 6738.5 6753.0 6757.9 6761.4 6765.5 6508.8 6604.3 6624.8 6664.2 6785.5 5389.7 5391.2 5411.5 5424.4 5448.8 4785.5 4794.1 4821.1 4838.9 4851.8 6158.0 6239.9 6286.5 6321.1 6334.5 6856.5 6880.7 6882.5 6899.2 6924.0 6325.4 6325.5 6331.6 6344.3 6358.3 5842.2 5902.1 5933.2 6020.2 6066.3 6962.9 6963.4 6987.0 6997.6 7010.1 6759.2 6858.7 6867.7 6874.8 6887.3 6618.8 6974.6 7011.7 7027.0 7040.2 6529.9 6607.7 6656.4 6689.1 6754.6 6849.8 6866.9 6867.6 6867.7 6912.1 5711.0 6426.8 6483.3 6564.5 6673.7 6624.8 6672.2 6673.6 6683.2 6687.1 5485.8 6488.9 6507.4 6535.8 6539.0 5410.8 6423.1 6706.9 6710.4 6718.3 6412.9 6483.9 6494.0 6529.4 6558.8 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | [#1] ext.html Cycles:180 Average:43570.41 Median:33790.58 stddev:70291.12 (208.0%) stddev-sans-first:70414.63 03:48:22 INFO - PID 2557 | Values: 707.5 758.5 818.9 1219.2 1270.2 39860.2 41089.8 41142.4 41242.8 41278.4 41388.8 41546.8 41585.0 41610.8 41729.4 7919.6 8117.4 8417.8 8623.2 8625.6 8441.3 8543.7 8544.0 8546.4 8570.0 8542.6 8551.1 8594.7 8598.6 8603.9 8479.8 8512.0 8547.9 8564.2 8568.2 8354.2 8390.0 8397.7 8406.5 8442.6 37993.8 38012.6 38125.0 38128.8 38205.0 3089.2 3525.2 3552.6 3561.2 3574.7 30928.8 31083.0 31118.8 31229.8 31381.7 3135.6 3141.5 3179.4 3183.9 3194.6 227119.0 227439.0 283581.0 291796.5 291896.0 48798.3 48945.3 48978.0 49007.8 49094.3 49986.8 51124.0 51490.0 51502.7 51630.0 13576.2 13595.5 13698.0 13736.7 13755.1 10785.0 10876.5 10911.8 10930.2 10938.9 13988.0 14172.2 14175.7 14232.6 14256.5 6076.2 6122.9 6150.1 6161.5 6169.2 989.4 1012.1 1034.9 1035.3 1042.4 21934.8 24577.5 25003.6 25004.0 25127.0 1119.8 1132.5 1133.7 1162.3 1164.0 10891.8 12261.9 12782.4 12811.9 12828.7 241314.0 250923.0 292011.0 296642.0 297415.0 58618.0 58973.0 58990.7 59075.4 59080.0 1291.0 1397.6 1504.3 1511.9 1517.0 29064.7 29681.0 30909.6 31653.4 31732.0 39337.0 39356.0 39450.8 39621.0 39626.2 261036.0 264888.0 265999.0 266658.0 266749.0 28999.7 37132.3 37680.2 37689.5 37766.0 47658.8 47847.3 48498.8 48867.0 49310.5 29599.3 30372.5 31628.8 32748.7 33286.5 32107.9 32221.7 32822.2 33281.8 33562.8 37060.3 38644.0 39927.4 40543.0 41095.6 33271.8 33658.0 33730.3 33850.8 34547.0 23679.0 25495.6 26206.1 26244.0 26369.0 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | [#2] jquery.html Cycles:180 Average:35983.17 Median:12445.99 stddev:94254.87 (757.3%) stddev-sans-first:94481.42 03:48:22 INFO - PID 2557 | Values: 405.3 422.9 431.8 439.1 457.5 21088.3 21165.7 21873.2 21911.0 22037.7 22515.9 23506.6 23668.8 23777.0 23873.9 5896.9 6163.5 6165.5 6180.3 6186.3 5870.8 6035.1 6039.4 6081.2 6140.4 5978.9 6032.9 6042.2 6044.6 6070.9 5374.4 5803.5 6007.3 6043.3 6044.4 5365.6 5374.0 5375.1 5375.4 5378.6 26683.5 26789.0 27481.5 27489.7 27808.9 2222.0 2287.6 2288.8 2289.0 2300.5 17596.6 18107.4 18279.0 18340.9 18608.0 2117.2 2133.5 2167.3 2169.5 2173.7 158537.0 158808.0 158878.0 159728.2 159930.0 12488.5 12753.5 12865.7 12941.6 13051.9 16233.5 16469.7 16503.3 16504.5 16522.8 8481.8 8644.7 8660.2 8737.0 8745.0 7930.3 7994.4 8029.1 8150.7 8167.3 6674.8 7893.7 7943.3 8060.4 8086.4 1852.5 2185.2 2213.8 2221.8 2234.7 966.3 980.8 993.7 1014.5 1038.4 19658.2 20593.4 21364.9 21556.9 21717.9 861.3 916.1 1040.6 1044.6 1071.2 8948.4 10626.2 10919.9 10928.9 10942.4 512454.0 551767.0 560637.0 570039.0 572358.0 129410.0 133347.3 143286.6 143768.3 145937.0 1194.9 1219.8 1244.8 1269.8 1274.1 21913.8 22145.8 22167.6 22297.3 22604.4 54890.9 54956.5 55199.4 55414.4 55492.7 71625.6 71807.6 71924.1 72864.6 73105.6 8807.8 8928.9 8941.6 8960.9 9309.3 11309.4 13661.2 14092.9 14318.3 14345.4 11195.5 11843.1 11882.0 12072.6 12163.6 10816.7 11063.7 11136.1 11227.5 11530.6 11760.7 12041.4 12170.6 12191.1 12259.1 8422.3 8788.5 8804.1 8899.7 9337.6 11980.4 11985.5 12250.4 12260.5 12306.4 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | [#3] mootools.html Cycles:180 Average:7089.42 Median:3916.51 stddev:4465.85 (114.0%) stddev-sans-first:4457.56 03:48:22 INFO - PID 2557 | Values: 1351.5 1442.1 1447.1 1451.3 1470.3 11662.8 11869.3 12185.2 12235.0 12256.3 11902.5 11929.4 12111.3 12128.2 12144.8 2560.6 2583.7 2594.3 2607.4 2613.0 2529.9 2590.6 2592.2 2593.1 2595.3 2547.4 2565.8 2573.4 2573.7 2574.0 2111.8 2516.2 2516.6 2543.5 2547.0 2214.6 2450.8 2527.6 2542.6 2611.6 11848.2 11927.9 11930.5 11934.7 11936.2 6423.8 6667.9 6688.9 6701.0 6739.3 4168.7 4353.7 4396.5 4418.1 4433.8 1240.7 1278.0 1286.6 1287.4 1309.2 15373.8 15694.2 16625.8 16770.7 16772.5 5390.6 5473.5 5610.5 5614.2 5633.5 11845.3 12003.5 12008.8 12021.5 12029.3 6383.9 6497.1 6499.4 6505.2 6505.7 5340.7 5999.6 6088.4 6156.1 6210.1 4344.7 4421.0 4466.1 4502.7 4518.3 1255.5 1281.1 1284.3 1298.1 1301.9 800.9 801.7 801.9 808.2 819.0 6170.5 6211.9 6404.6 6411.2 6460.7 589.4 592.7 601.4 605.7 613.3 3665.7 4167.3 4292.4 4396.1 4520.5 14517.9 14734.2 14910.4 14987.0 15024.9 14607.1 14894.3 14916.4 15151.7 15229.4 8291.5 8480.8 8544.1 8625.5 8660.4 5065.7 6994.9 7166.4 7195.7 7205.2 10338.7 12798.1 12890.2 12925.0 12950.5 10880.4 13032.6 13118.4 13162.3 13327.9 9431.3 9522.5 9674.4 9677.7 9690.4 10891.3 10924.5 10980.8 11007.7 11047.4 7779.8 9040.0 9237.4 9278.7 9493.8 9129.9 9135.0 9146.9 9249.4 9355.1 8132.5 8227.7 8229.1 8248.7 8315.3 4727.7 6010.7 6089.7 6140.0 6211.1 5054.6 6202.3 6226.5 6319.9 6340.2 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | [#4] prototype.html Cycles:180 Average:5528.70 Median:6120.39 stddev:1651.26 (27.0%) stddev-sans-first:1609.45 03:48:22 INFO - PID 2557 | Values: 347.8 406.1 406.6 407.3 416.8 6326.0 6349.6 6350.3 6352.6 6360.5 5981.9 6043.0 6137.4 6225.5 6225.7 4688.2 4718.3 4727.5 4728.2 4744.7 3854.9 4603.6 4729.4 4740.7 4751.7 4692.5 4697.5 4699.6 4702.1 4705.3 4630.3 4658.4 4689.4 4711.0 4712.6 4068.5 4120.5 4134.1 4136.7 4139.9 6114.4 6126.4 6129.3 6135.3 6142.4 5541.6 5548.2 5591.3 5594.6 5624.9 5167.5 6014.9 6639.7 6755.9 6766.5 4728.6 4733.9 4734.7 4742.3 4744.5 6244.7 7058.4 7081.2 7087.9 7209.2 4675.8 4997.3 5127.2 5134.0 5134.6 5233.4 5238.9 5247.5 5247.7 5260.9 5618.5 5787.9 5822.5 5844.1 5872.5 6816.4 6844.6 6930.9 6933.9 6949.4 1774.9 1776.0 1797.2 1798.5 1806.2 1255.0 1262.6 1268.4 1277.9 1284.9 5576.8 6166.8 6396.9 6400.8 6442.4 6208.4 6933.7 7020.8 7068.4 7088.9 6274.7 6327.7 6338.2 6352.0 6352.8 3655.2 3685.3 3705.9 3709.7 3714.0 6080.4 7026.4 7214.9 7314.6 7321.6 6477.2 7145.4 7221.5 7269.6 7314.7 5971.9 6515.7 6719.0 7271.5 7425.7 5568.8 6157.8 6769.9 7050.0 7058.3 5889.4 6618.2 6672.9 6781.7 6898.2 5470.9 7095.5 7153.5 7219.2 7273.7 4526.5 4553.4 4554.2 4556.9 4567.4 6791.2 7003.9 7034.9 7076.8 7091.8 5697.5 6783.6 6798.7 6824.7 6841.6 5314.7 5563.0 6588.3 6743.3 6818.3 5175.4 6426.4 6656.5 6696.5 6702.8 5859.2 6600.8 6690.5 6802.7 6822.8 6629.4 6631.7 6642.5 6644.5 6665.1 03:48:22 INFO - PID 2557 | 03:48:22 INFO - PID 2557 | [#5] yui.html Cycles:180 Average:26020.77 Median:17959.46 stddev:40156.59 (223.6%) stddev-sans-first:40224.45 03:48:22 INFO - PID 2557 | Values: 762.3 837.8 843.0 864.5 864.9 13044.7 14593.7 17050.1 17468.2 17528.7 16465.7 18126.8 18354.8 18370.4 18375.0 962.2 968.6 986.3 995.1 997.5 997.4 1007.1 1007.8 1011.0 1014.2 944.7 1049.7 1059.0 1060.2 1064.3 1050.1 1051.1 1052.1 1052.4 1053.3 769.5 784.4 787.2 787.6 787.7 16268.6 17163.0 17627.6 17714.9 17729.8 14052.0 17723.0 18035.2 18038.9 18042.3 14368.3 14964.4 14970.3 14982.0 15021.8 3590.3 3612.8 3618.3 3619.9 3629.3 155359.0 155462.0 155869.0 156036.0 156525.0 12078.8 13394.5 13529.3 13675.0 13693.0 25799.7 26200.2 26640.3 26708.2 27026.7 6765.7 7000.8 7449.3 7804.3 7888.0 3906.8 3991.8 4003.2 4006.8 4008.0 4155.6 4226.4 4241.2 4260.9 4381.1 939.2 956.7 960.9 967.6 969.6 650.3 664.4 673.7 673.8 681.3 10753.7 11221.9 11624.0 12400.8 12633.7 650.6 659.8 667.5 671.9 672.3 5760.0 5803.0 5853.9 5854.7 5939.1 137277.0 138001.0 139148.0 139565.6 141969.3 112903.0 123093.4 123530.0 123787.0 127200.0 104105.0 105497.8 105676.0 105752.5 106301.0 11253.4 11578.9 11735.8 11935.2 11943.1 44231.3 44428.1 44747.0 45065.5 45076.3 70429.6 76490.0 77242.6 77908.0 78109.0 14333.9 17841.9 17848.2 17851.6 17859.5 16088.9 18765.7 18965.0 19088.6 19287.7 17503.7 17883.7 18239.0 18270.7 18278.8 17004.6 17524.9 18087.1 18211.8 18241.7 16578.6 17122.6 17372.0 17599.0 17671.1 10396.6 11440.3 11961.8 12085.1 12317.1 13453.7 13577.0 13638.3 13641.2 13711.7 03:48:22 INFO - PID 2557 | -------- Summary: end -------- 03:48:22 INFO - PID 2557 | 03:48:22 INFO - TEST-INFO | 2557: exit 0 03:48:22 INFO - TEST-OK | dromaeo_css | took 1142334ms 03:48:22 INFO - TEST-START | kraken 03:48:22 INFO - operating with platform_type : mac_ 03:48:22 INFO - Initialising browser for kraken test... 03:48:22 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58546/getInfo.html -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpR3FGqz/profile 03:48:23 INFO - TEST-INFO | started process 2585 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58546/getInfo.html -foreground) 03:48:56 INFO - TEST-INFO | 2585: killed by SIGTERM 03:48:56 INFO - Browser initialized. 03:48:56 INFO - Running cycle 1/1 for kraken test... 03:48:56 INFO - TEST-INFO | started process 2589 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpR3FGqz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:48:58 INFO - PID 2589 | 2017-05-26 03:48:58.491 plugin-container[2590:39104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984b, name = 'com.apple.tsm.portname' 03:48:58 INFO - PID 2589 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:58 INFO - PID 2589 | 2017-05-26 03:48:58.491 plugin-container[2590:39104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 03:48:58 INFO - PID 2589 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:58 INFO - PID 2589 | 2017-05-26 03:48:58.491 plugin-container[2590:39104] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:48:58 INFO - PID 2589 | 2017-05-26 03:48:58.961 plugin-container[2591:39148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984b, name = 'com.apple.tsm.portname' 03:48:58 INFO - PID 2589 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:58 INFO - PID 2589 | 2017-05-26 03:48:58.961 plugin-container[2591:39148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 03:48:58 INFO - PID 2589 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:48:58 INFO - PID 2589 | 2017-05-26 03:48:58.961 plugin-container[2591:39148] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:49:08 INFO - PID 2589 | RSS: Main: 242651136 03:49:08 INFO - PID 2589 | 03:49:10 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58546/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:11 INFO - PID 2589 | RSS: Main: 257089536 03:49:11 INFO - PID 2589 | 03:49:14 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58546/tests/kraken/driver.html?testName=audio-dft) 03:49:15 INFO - PID 2589 | RSS: Main: 265760768 03:49:15 INFO - PID 2589 | 03:49:18 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58546/tests/kraken/driver.html?testName=audio-fft) 03:49:19 INFO - PID 2589 | RSS: Main: 268832768 03:49:19 INFO - PID 2589 | 03:49:21 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58546/tests/kraken/driver.html?testName=audio-oscillator) 03:49:21 INFO - PID 2589 | RSS: Main: 270774272 03:49:21 INFO - PID 2589 | 03:49:23 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58546/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:24 INFO - PID 2589 | RSS: Main: 271781888 03:49:24 INFO - PID 2589 | 03:49:27 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58546/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:28 INFO - PID 2589 | RSS: Main: 276463616 03:49:28 INFO - PID 2589 | 03:49:31 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58546/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:31 INFO - PID 2589 | RSS: Main: 279396352 03:49:31 INFO - PID 2589 | 03:49:35 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58546/tests/kraken/driver.html?testName=json-parse-financial) 03:49:35 INFO - PID 2589 | RSS: Main: 278130688 03:49:35 INFO - PID 2589 | 03:49:37 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58546/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:37 INFO - PID 2589 | RSS: Main: 278282240 03:49:37 INFO - PID 2589 | 03:49:39 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:40 INFO - PID 2589 | RSS: Main: 278114304 03:49:40 INFO - PID 2589 | 03:49:42 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:43 INFO - PID 2589 | RSS: Main: 278151168 03:49:43 INFO - PID 2589 | 03:49:46 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:46 INFO - PID 2589 | RSS: Main: 278073344 03:49:46 INFO - PID 2589 | 03:49:49 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:50 INFO - PID 2589 | RSS: Main: 277975040 03:49:50 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | Cycle 1(1): loaded http://localhost:58546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58546/tests/kraken/driver.html?testName=ai-astar) 03:49:52 INFO - PID 2589 | RSS: Main: 279883776 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | __start_tp_report 03:49:52 INFO - PID 2589 | _x_x_mozilla_page_load 03:49:52 INFO - PID 2589 | _x_x_mozilla_page_load_details 03:49:52 INFO - PID 2589 | |i|pagename|runs| 03:49:52 INFO - PID 2589 | |0;ai-astar;102;117;117;116;113;113;115;115;116;114 03:49:52 INFO - PID 2589 | |1;audio-beat-detection;136;107;111;166;134;140;138;95;139;136 03:49:52 INFO - PID 2589 | |2;audio-dft;203;201;195;233;251;226;272;212;287;190 03:49:52 INFO - PID 2589 | |3;audio-fft;84;59;76;82;62;83;51;66;94;82 03:49:52 INFO - PID 2589 | |4;audio-oscillator;83;70;91;71;70;71;86;89;55;91 03:49:52 INFO - PID 2589 | |5;imaging-gaussian-blur;88;89;88;89;133;89;87;112;135;137 03:49:52 INFO - PID 2589 | |6;imaging-darkroom;96;97;96;148;96;96;96;123;98;96 03:49:52 INFO - PID 2589 | |7;imaging-desaturate;136;91;91;92;119;86;147;91;91;91 03:49:52 INFO - PID 2589 | |8;json-parse-financial;51;53;54;76;71;77;75;75;52;51 03:49:52 INFO - PID 2589 | |9;json-stringify-tinderbox;73;53;65;69;68;59;58;55;56;58 03:49:52 INFO - PID 2589 | |10;stanford-crypto-aes;88;87;87;86;88;86;85;91;143;85 03:49:52 INFO - PID 2589 | |11;stanford-crypto-ccm;140;139;138;148;148;138;147;142;150;137 03:49:52 INFO - PID 2589 | |12;stanford-crypto-pbkdf2;186;195;187;182;184;199;224;185;186;188 03:49:52 INFO - PID 2589 | |13;stanford-crypto-sha256-iterative;61;101;94;71;64;69;68;69;71;66 03:49:52 INFO - PID 2589 | __end_tp_report 03:49:52 INFO - PID 2589 | __start_cc_report 03:49:52 INFO - PID 2589 | _x_x_mozilla_cycle_collect,1044 03:49:52 INFO - PID 2589 | __end_cc_report 03:49:52 INFO - PID 2589 | __startTimestamp1495795792381__endTimestamp 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | ------- Summary: start ------- 03:49:52 INFO - PID 2589 | Number of tests: 14 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.50 stddev:4.39 (3.8%) stddev-sans-first:1.54 03:49:52 INFO - PID 2589 | Values: 102.0 117.0 117.0 116.0 113.0 113.0 115.0 115.0 116.0 114.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:138.50 stddev:20.39 (14.7%) stddev-sans-first:21.52 03:49:52 INFO - PID 2589 | Values: 136.0 107.0 111.0 166.0 134.0 140.0 138.0 95.0 139.0 136.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#2] audio-dft Cycles:10 Average:227.00 Median:229.50 stddev:33.51 (14.6%) stddev-sans-first:34.40 03:49:52 INFO - PID 2589 | Values: 203.0 201.0 195.0 233.0 251.0 226.0 272.0 212.0 287.0 190.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#3] audio-fft Cycles:10 Average:73.90 Median:82.00 stddev:13.64 (16.6%) stddev-sans-first:13.97 03:49:52 INFO - PID 2589 | Values: 84.0 59.0 76.0 82.0 62.0 83.0 51.0 66.0 94.0 82.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:84.50 stddev:12.03 (14.2%) stddev-sans-first:12.60 03:49:52 INFO - PID 2589 | Values: 83.0 70.0 91.0 71.0 70.0 71.0 86.0 89.0 55.0 91.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:88.00 stddev:22.18 (25.2%) stddev-sans-first:22.68 03:49:52 INFO - PID 2589 | Values: 88.0 89.0 88.0 89.0 133.0 89.0 87.0 112.0 135.0 137.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:96.00 stddev:17.53 (18.3%) stddev-sans-first:18.34 03:49:52 INFO - PID 2589 | Values: 96.0 97.0 96.0 148.0 96.0 96.0 96.0 123.0 98.0 96.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:91.00 stddev:22.13 (24.3%) stddev-sans-first:20.11 03:49:52 INFO - PID 2589 | Values: 136.0 91.0 91.0 92.0 119.0 86.0 147.0 91.0 91.0 91.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:73.00 stddev:12.04 (16.5%) stddev-sans-first:11.89 03:49:52 INFO - PID 2589 | Values: 51.0 53.0 54.0 76.0 71.0 77.0 75.0 75.0 52.0 51.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:62.00 stddev:6.82 (11.0%) stddev-sans-first:5.80 03:49:52 INFO - PID 2589 | Values: 73.0 53.0 65.0 69.0 68.0 59.0 58.0 55.0 56.0 58.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:87.00 stddev:17.80 (20.5%) stddev-sans-first:18.80 03:49:52 INFO - PID 2589 | Values: 88.0 87.0 87.0 86.0 88.0 86.0 85.0 91.0 143.0 85.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:144.50 stddev:5.01 (3.5%) stddev-sans-first:5.22 03:49:52 INFO - PID 2589 | Values: 140.0 139.0 138.0 148.0 148.0 138.0 147.0 142.0 150.0 137.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:187.50 stddev:12.50 (6.7%) stddev-sans-first:13.09 03:49:52 INFO - PID 2589 | Values: 186.0 195.0 187.0 182.0 184.0 199.0 224.0 185.0 186.0 188.0 03:49:52 INFO - PID 2589 | 03:49:52 INFO - PID 2589 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:69.00 stddev:13.18 (19.1%) stddev-sans-first:13.19 03:49:52 INFO - PID 2589 | Values: 61.0 101.0 94.0 71.0 64.0 69.0 68.0 69.0 71.0 66.0 03:49:52 INFO - PID 2589 | -------- Summary: end -------- 03:49:52 INFO - PID 2589 | 03:49:53 INFO - TEST-INFO | 2589: exit 0 03:49:53 INFO - TEST-OK | kraken | took 90175ms 03:49:53 INFO - SUITE-END | took 1232s 03:49:53 INFO - Completed test suite (00:20:32) 03:49:53 INFO - javascript benchmark 03:49:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8060.750572883109, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3574.249407624397, 3574.7855128692277, 3603.2072943952353, 3610.494530765693, 3627.056965189051, 4973.254011898217, 5103.744812759361, 5251.842444726654, 5472.398036216013, 6151.600145990507, 6191.6285022898655, 6224.96887515563, 6280.591761535497, 6289.654069026202, 6455.677216139186, 5303.628745987782, 5451.754671039813, 5458.426865179159, 5474.91787623186, 5574.052411090124, 4664.3936288282475, 5625.353084395286, 5634.464725851038, 5634.859128521779, 5635.746391412396, 5403.081476149025, 5478.150886612581, 5485.533729632946, 5524.972375138139, 5560.4161563036105, 5520.889582208333, 5528.364238112588, 5558.833235002957, 5573.359063707685, 5576.749046292926, 5482.396936337, 5503.064479038533, 5504.284443022421, 5517.889642207174, 5523.944760552383, 6183.319834818167, 6188.8762224755255, 6291.591046581958, 6305.621662700252, 6312.274088479791, 6491.448226900719, 6669.866602667968, 6757.054012438262, 6760.459163266927, 6773.119494465737, 6911.101556797635, 6913.965430172867, 6931.029655848185, 6937.583744975267, 6949.7567585134275, 6382.648954307511, 6388.584741991802, 6391.105245265667, 6403.167588213502, 6425.421712045892, 6733.0, 6835.213950395669, 6879.832852651399, 6963.79108626742, 6967.65161741921, 5432.348118225838, 6395.936040639534, 6450.6774661266745, 6490.578112422678, 6693.765718199839, 6758.121444212221, 6820.04519367289, 6861.4510839133745, 6876.793696189123, 6878.518503704692, 6738.494612904052, 6753.0, 6757.898631520531, 6761.357671021246, 6765.492588055916, 6508.837279067967, 6604.306547812506, 6624.768133115318, 6664.233613134455, 6785.5250132490255, 5389.730513474311, 5391.245225668409, 5411.458854114557, 5424.376196737347, 5448.782048718086, 4785.49752276006, 4794.065157294364, 4821.083994041121, 4838.927416088762, 4851.75741212945, 6158.0, 6239.875202495925, 6286.465650419674, 6321.146645202931, 6334.461570766536, 6856.451483881277, 6880.65596720162, 6882.518223724292, 6899.17209934811, 6923.9653801730665, 6325.399087086719, 6325.5255855811, 6331.620102793847, 6344.333844946214, 6358.33237510064, 5842.240508733922, 5902.144189092557, 5933.228680271537, 6020.157177995085, 6066.272047354258, 6962.930370696431, 6963.40811031068, 6987.0, 6997.615131167631, 7010.123734533184, 6759.188897332351, 6858.691358888962, 6867.725290988505, 6874.759383421756, 6887.311268873073, 6618.834529136618, 6974.61639609806, 7011.684474198775, 7027.0, 7040.190378106378, 6529.869402611826, 6607.735690572324, 6656.434203092791, 6689.096971908924, 6754.5947243165565, 6849.760258390932, 6866.896996545156, 6867.553609015398, 6867.656617169221, 6912.135983002125, 5711.0, 6426.807195783947, 6483.286838447862, 6564.540482166202, 6673.699683514156, 6624.8012559623285, 6672.165979252593, 6673.599584024974, 6683.231428385798, 6687.09724187248, 5485.780568777204, 6488.896887529226, 6507.414332710079, 6535.803925882231, 6538.967305163444, 5410.83767486976, 6423.132877061536, 6706.93293067063, 6710.362515561014, 6718.294579069127, 6412.871742565029, 6483.902741458976, 6494.0, 6529.379708927644, 6558.770443034471], "value": 6148.822827625245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.5471698113207, 758.5107605594392, 818.868980963046, 1219.1526888812273, 1270.1743866486788, 39860.20279594406, 41089.79455102728, 41142.38286425702, 41242.79378603103, 41278.38082428766, 41388.79305603475, 41546.792266038625, 41585.0, 41610.791946040306, 41729.374059389134, 7919.604019799016, 8117.391195660349, 8417.831643367159, 8623.223909848113, 8625.611847466878, 8441.324694024494, 8543.700970466034, 8544.0, 8546.44448110874, 8570.014448338427, 8542.572871356439, 8551.059383467813, 8594.742157735278, 8598.570071496431, 8603.870941935877, 8479.788005299855, 8512.0, 8547.871781923244, 8564.229219370287, 8568.186022327869, 8354.248117669378, 8389.95805020977, 8397.748067557983, 8406.537640429773, 8442.62008209632, 37993.810030949666, 38012.619873801465, 38125.0, 38128.80935595332, 38205.0, 3089.165925200183, 3525.1892064825233, 3552.609212986569, 3561.1809283864595, 3574.7318951078514, 30928.8453557733, 31083.0, 31118.844405778054, 31229.843850780828, 31381.686183138103, 3135.5923729914966, 3141.4502462069045, 3179.380020895926, 3183.8567264473154, 3194.5847039884898, 227119.0, 227439.0, 283580.99999999796, 291796.5410172957, 291896.0, 48798.26802597964, 48945.26582101271, 48978.0, 49007.77480563014, 49094.26358604624, 49986.750066249435, 51124.0, 51490.0, 51502.74248628733, 51630.0, 13576.1854288743, 13595.45618175262, 13698.041137120306, 13736.65658358529, 13755.10591811529, 10785.0, 10876.456177191109, 10911.781764364669, 10930.234883558234, 10938.945305273422, 13988.0, 14172.22052787086, 14175.716485670435, 14232.573022809333, 14256.501022464372, 6076.210092687922, 6122.8876037766895, 6150.10823430609, 6161.537884658668, 6169.198004259418, 989.3618615992789, 1012.1245122968726, 1034.9223808214415, 1035.342557476008, 1042.4266653340633, 21934.780652193273, 24577.50844983126, 25003.62494562583, 25003.99984000656, 25127.0, 1119.770447058351, 1132.5073592987078, 1133.7392399748105, 1162.314234601589, 1163.96508104757, 10891.83662245067, 12261.877381226073, 12782.424790884432, 12811.871881281255, 12828.743425131632, 241314.0, 250923.0, 292011.0, 296642.0, 297415.0, 58618.0, 58973.0, 58990.70504647449, 59075.40924590785, 59080.0, 1291.0, 1397.601683520192, 1504.338091239873, 1511.8790496760453, 1516.962075948088, 29064.7093529062, 29681.0, 30909.609067591562, 31653.366932660756, 31732.0, 39337.0, 39356.0, 39450.80274598723, 39621.00947476336, 39626.2074758509, 261036.0, 264888.0, 265999.0100148538, 266658.0, 266749.0, 28999.7100028997, 37132.25735485329, 37680.2319768021, 37689.49242030288, 37766.0, 47658.761706192636, 47847.28229076497, 48498.75750621224, 48867.0, 49310.54826162134, 29599.26001849971, 30372.544411833398, 31628.841855790575, 32748.672513275516, 33286.500702489, 32107.876224332038, 32221.677783222807, 32822.179445514055, 33281.83359083189, 33562.83218583892, 37060.258794823414, 38644.0, 39927.401088983104, 40542.9864253396, 41095.589044109176, 33271.83364083164, 33658.0, 33730.325393491505, 33850.83074584611, 34547.0, 23679.0, 25495.617565736156, 26206.06908963671, 26243.950241990107, 26369.0], "value": 17906.052344412372, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.3089482432453, 422.9090745489721, 431.76253060816583, 439.060410721057, 457.46705088571815, 21088.261910833106, 21165.68251476229, 21873.234436794715, 21911.0140043698, 22037.669434958487, 22515.887420562914, 23506.64740028901, 23668.76331236687, 23777.0, 23873.880630596865, 5896.88206235875, 6163.537734669895, 6165.506759459255, 6180.258368995705, 6186.288576813679, 5870.823875283727, 6035.064564992434, 6039.3960603939695, 6081.1790408294755, 6140.35526269742, 5978.940210597882, 6032.939670603304, 6042.214512113441, 6044.576879618428, 6070.878582428349, 5374.381946076212, 5803.477687008169, 6007.339192688801, 6043.335233124353, 6044.425779550936, 5365.624406291561, 5373.973130134364, 5375.139977603565, 5375.435579264199, 5378.623496355257, 26683.466330673473, 26789.0, 27481.45037099267, 27489.72510274892, 27808.887644494196, 2222.0, 2287.553926984239, 2288.8054515366216, 2289.0, 2300.505391340853, 17596.560085997826, 18107.36624218146, 18279.0, 18340.908295458572, 18607.976561289124, 2117.1637203304763, 2133.4559687279643, 2167.3281282802222, 2169.5443956769095, 2173.739151301854, 158537.0, 158808.0, 158878.0, 159728.20135899363, 159930.0, 12488.50045998168, 12753.48986040548, 12865.742685146244, 12941.611751647277, 13051.934740326427, 16233.51299461018, 16469.670606588043, 16503.33986640545, 16504.504864853832, 16522.83477165213, 8481.830363392697, 8644.740657780276, 8660.22058014769, 8737.0, 8744.994325652631, 7930.286274235346, 7994.36045116401, 8029.116797152308, 8150.673973041131, 8167.346612271004, 6674.799756007327, 7893.723719669899, 7943.324817390587, 8060.435769496079, 8086.43394962347, 1852.4720454670355, 2185.2242453928625, 2213.7896899794496, 2221.7778222177653, 2234.653628687556, 966.3042609321277, 980.8136454073704, 993.7217579077869, 1014.5028935821394, 1038.4288641247435, 19658.23075923145, 20593.38219853407, 21364.926374807357, 21556.89221553882, 21717.891410542845, 861.3066481482467, 916.093067862811, 1040.618821325734, 1044.5978298355187, 1071.1591400750374, 8948.373613847098, 10626.203034772423, 10919.890801091888, 10928.890711092947, 10942.398168100832, 512454.0, 551767.0, 560637.0, 570039.0, 572358.0, 129410.0, 133347.333263335, 143286.5671343294, 143768.28115859354, 145937.0, 1194.946227419768, 1219.8414206153143, 1244.813278008306, 1269.8349214602042, 1274.095392271479, 21913.780862191175, 22145.781981991146, 22167.556648866608, 22297.331080067623, 22604.434889127246, 54890.90218195694, 54956.450435496736, 55199.44800551943, 55414.44585554093, 55492.72253638706, 71625.64187179031, 71807.64096179485, 71924.12303507801, 72864.63567682127, 73105.63447182943, 8807.779805504913, 8928.910710892807, 8941.642334306554, 8960.910390896008, 9309.301802364715, 11309.377984210947, 13661.18032918028, 14092.929535352258, 14318.284085795876, 14345.426182952982, 11195.496202670736, 11843.111766617365, 11882.0, 12072.577459789216, 12163.635090947286, 10816.729581760519, 11063.668089957315, 11136.109111270916, 11227.494762735532, 11530.59642912494, 11760.70598235051, 12041.397930103285, 12170.57402990891, 12191.146619736888, 12259.141860069709, 8422.32621390275, 8788.51663158507, 8804.119588041402, 8899.688510902088, 9337.57980890848, 11980.400979951142, 11985.520579176735, 12250.387480625755, 12260.509579616715, 12306.384680766105], "value": 10488.058689722882, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.5134551561437, 1442.1347191684983, 1447.073872721459, 1451.3468938977455, 1470.2501724120702, 11662.825057624132, 11869.347185904771, 12185.207961482509, 12235.0, 12256.325902075394, 11902.523899044028, 11929.403529823518, 12111.333876636781, 12128.21166624171, 12144.785521447873, 2560.577504711725, 2583.689957205138, 2594.3254753764063, 2607.3742301847515, 2612.973870261292, 2529.949401011988, 2590.611408288753, 2592.222333300012, 2593.1183397644795, 2595.286296268522, 2547.3631592101974, 2565.846049237052, 2573.4338445541953, 2573.691157061146, 2573.987130064347, 2111.767705552388, 2516.232549072532, 2516.5721827289312, 2543.5421624107653, 2547.0, 2214.6456566949373, 2450.8161887858305, 2527.5576774064466, 2542.5931850903767, 2611.595202743578, 11848.229865058744, 11927.880721192763, 11930.522779088826, 11934.701632459171, 11936.16446848721, 6423.807285781434, 6667.933320666828, 6688.86622267552, 6700.966495167541, 6739.258681545026, 4168.687348448879, 4353.651707863363, 4396.494403143647, 4418.050119224382, 4433.800478978422, 1240.7146356338003, 1277.9808302875465, 1286.614015795267, 1287.40779241549, 1309.18830325199, 15373.846261537354, 15694.215289235493, 16625.83374166267, 16770.66458670832, 16772.496825095266, 5390.568754499631, 5473.480019398151, 5610.4670056344585, 5614.214010038598, 5633.492985631231, 11845.289282643069, 12003.519859205533, 12008.819867701992, 12021.459034343476, 12029.278243305429, 6383.904241436382, 6497.090407342976, 6499.38255865692, 6505.186851643544, 6505.674716264168, 5340.744924942635, 5999.640021598718, 6088.360722124202, 6156.107364432221, 6210.099535567318, 4344.739315641072, 4421.005273813431, 4466.084452687256, 4502.684812063124, 4518.277075667877, 1255.5291765587904, 1281.1224311346757, 1284.3000564692265, 1298.1107941060404, 1301.8633043530292, 800.8518424091561, 801.7474495534004, 801.903771547418, 808.1554775259867, 818.950862948225, 6170.5372097092895, 6211.881861265016, 6404.647744374011, 6411.198600174978, 6460.741570337133, 589.381149792716, 592.733270028489, 601.3925934805877, 605.7032054293364, 613.3222788818381, 3665.725070619714, 4167.291560434734, 4292.420523229386, 4396.098799746044, 4520.52534483881, 14517.927410363092, 14734.189619571027, 14910.403583856742, 14987.025843320149, 15024.924875375553, 14607.123572585679, 14894.255287235812, 14916.403343866341, 15151.696966060617, 15229.390824366901, 8291.461055031403, 8480.787980300542, 8544.14558544153, 8625.482471051757, 8660.437071590326, 5065.670731402447, 6994.860102798018, 7166.426685865223, 7195.748148814766, 7205.207427182901, 10338.741531461474, 12798.104132710621, 12890.226586404846, 12925.0, 12950.481980721042, 10880.401577913291, 13032.609021729364, 13118.409671564994, 13162.341882905625, 13327.866721332663, 9431.292653050912, 9522.476263805556, 9674.419534827934, 9677.70966870995, 9690.37012594179, 10891.346519208873, 10924.453777311262, 10980.780384392427, 11007.669769906915, 11047.392393418118, 7779.7666070017995, 9039.954800225956, 9237.445753254826, 9278.72163835086, 9493.810123797624, 9129.908700912905, 9135.0, 9146.902371715436, 9249.352545322032, 9355.06449355059, 8132.512049277062, 8227.670893164206, 8229.135940726137, 8248.670053197804, 8315.251627353568, 4727.739974301447, 6010.669413182142, 6089.665068421279, 6139.969300153471, 6211.09939058843, 5054.620903432184, 6202.317745048132, 6226.501879849692, 6319.873602527831, 6340.175777148941], "value": 5332.3853162228625, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8399936029426, 406.1227748064182, 406.6299667302755, 407.3279089502321, 416.76244540611845, 6325.968370158149, 6349.587276827003, 6350.301466838644, 6352.555321127523, 6360.491160707138, 5981.9401805981925, 6043.0, 6137.447629713325, 6225.478681134136, 6225.692604553049, 4688.179568575502, 4718.268668356393, 4727.527247275262, 4728.196206644879, 4744.667873248874, 3854.9421758673643, 4603.562661547147, 4729.432468103832, 4740.71555706657, 4751.738654374008, 4692.530746925314, 4697.476892330752, 4699.577038066574, 4702.106599746055, 4705.270683044117, 4630.32860235264, 4658.417697787777, 4689.437267527918, 4711.0, 4712.622990160796, 4068.4710987571425, 4120.546739858613, 4134.131832315187, 4136.655586934228, 4139.8965025874295, 6114.388561143895, 6126.387361263883, 6129.325774164839, 6135.263768347782, 6142.416470435302, 5541.612087153901, 5548.223248745179, 5591.30108736408, 5594.622285528675, 5624.887502249932, 5167.457416971239, 6014.879702405928, 6639.73441062357, 6755.90566924255, 6766.458683305324, 4728.62602093066, 4733.934864655459, 4734.680006796645, 4742.2886567014875, 4744.525547445262, 6244.718987645521, 7058.435325173974, 7081.185663648695, 7087.89368159478, 7209.243029481881, 4675.836345727883, 4997.30037794709, 5127.2052831811125, 5133.9743301284, 5134.640575159703, 5233.4243233244315, 5238.869028274324, 5247.475252474798, 5247.658902171346, 5260.92108618371, 5618.494335509824, 5787.913181302284, 5822.505087067562, 5844.094165404369, 5872.530197584183, 6816.43375852803, 6844.555103918229, 6930.861382772317, 6933.93066069343, 6949.4440444765305, 1774.8668849836313, 1775.973360399595, 1797.2451570340488, 1798.505411011956, 1806.1872157529165, 1254.9811752823714, 1262.5581046633788, 1268.3848333558217, 1277.920157466939, 1284.8693150027916, 5576.776928922797, 6166.784162554289, 6396.936030639634, 6400.807975760735, 6442.420182183626, 6208.379162083846, 6933.653317334113, 7020.754273600502, 7068.363847253669, 7088.908771912298, 6274.6548939807835, 6327.683615819191, 6338.193614819755, 6352.0, 6352.7612115637, 3655.1593133579427, 3685.3182161300247, 3705.8517659293325, 3709.666130048309, 3714.0, 6080.385434263221, 7026.43788496919, 7214.927850721426, 7314.597697126603, 7321.63391830417, 6477.222733272038, 7145.392641625416, 7221.53060051095, 7269.600171990592, 7314.707411703579, 5971.880562388728, 6515.7294327606605, 6718.966405167943, 7271.527350722186, 7425.665845036986, 5568.805091821767, 6157.815265542041, 6769.9168133098465, 7049.9647501762165, 7058.25888281723, 5889.439298585964, 6618.238902526271, 6672.898037118486, 6781.728730850711, 6898.172219333512, 5470.91793623104, 7095.503314767917, 7153.535020223669, 7219.169795473378, 7273.672684729306, 4526.479454862601, 4553.408056952575, 4554.151741778192, 4556.908861822812, 4567.406237189277, 6791.249543934714, 7003.859922801414, 7034.894476582957, 7076.787696369117, 7091.82270443243, 5697.464628240393, 6783.559068660521, 6798.660066996729, 6824.692888819915, 6841.589504629738, 5314.707691077027, 5563.026470367588, 6588.275289718032, 6743.291954344723, 6818.318168183142, 5175.378954525481, 6426.3573642635365, 6656.500762442933, 6696.4977626679165, 6702.765403210864, 5859.2382990211, 6600.844852150951, 6690.531662783558, 6802.693878775566, 6822.795316140524, 6629.436497897733, 6631.734730610914, 6642.535022548375, 6644.534882558174, 6665.133532640726], "value": 5027.717965139549, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.3367670126988, 837.7696133563265, 842.9831403371933, 864.4813112132721, 864.8529749942509, 13044.73910521789, 14593.708125837504, 17050.147492625365, 17468.21393037313, 17528.73706894396, 16465.67068658626, 18126.834031656206, 18354.81645183548, 18370.44888653339, 18375.0, 962.1533050915202, 968.6077138758814, 986.2898712926682, 995.1143482300758, 997.4514017290461, 997.4314640654829, 1007.1338648762058, 1007.780585491556, 1010.9949450252775, 1014.1633152649057, 944.7496413450407, 1049.664107485605, 1059.0468578279533, 1060.1571750458404, 1064.340109132335, 1050.086424810416, 1051.05405135378, 1052.079430498314, 1052.447465080831, 1053.2890299048113, 769.4729110559229, 784.4156103702721, 787.2088551006202, 787.5510958753513, 787.7242964962275, 16268.593285167966, 17162.970221786607, 17627.559311017198, 17714.937103773686, 17729.822701773075, 14052.0, 17723.0, 18035.188416521156, 18038.90980545102, 18042.27830886763, 14368.281585920662, 14964.401423943138, 14970.251487425585, 14982.026168299135, 15021.774673379909, 3590.281943611288, 3612.7832330060282, 3618.3486972344967, 3619.927601447983, 3629.3467175908327, 155359.0, 155462.0, 155869.0, 156036.0, 156525.0, 12078.818817717742, 13394.531191408254, 13529.32353382327, 13675.042747007812, 13693.0, 25799.742002579733, 26200.213993580222, 26640.333991649975, 26708.19875403741, 27026.72973270242, 6765.695543700545, 7000.824979375455, 7449.2923172299625, 7804.297613214838, 7887.974563306848, 3906.7851268179957, 3991.820368083443, 4003.199360127986, 4006.759594424344, 4008.0180355813013, 4155.646770024582, 4226.429432026698, 4241.215375155606, 4260.893477663021, 4381.145676593033, 939.2110627073291, 956.6747305916022, 960.9087136722001, 967.579103090158, 969.6024629901707, 650.3236633900785, 664.378805816563, 673.7069374821968, 673.845015646404, 681.308471901011, 10753.677389678322, 11221.887781122248, 11624.0, 12400.81398779019, 12633.747325053448, 650.632392698124, 659.7624855052177, 667.4960404894274, 671.9462443004547, 672.2739441403272, 5759.971200143972, 5802.970985145047, 5853.94146058534, 5854.736536855852, 5939.07944268639, 137277.0, 138001.0, 139148.0, 139565.60434395526, 141969.29015354856, 112903.0, 123093.38453307677, 123530.0, 123787.0, 127200.0, 104105.0, 105497.78008879712, 105676.0, 105752.47123764486, 106301.0, 11253.381064041556, 11578.884211157781, 11735.823962640738, 11935.224210426295, 11943.104267179822, 44231.33652995143, 44428.111437770414, 44747.0, 45065.54934450614, 45076.32385514154, 70429.64785176041, 76490.0, 77242.61378693295, 77908.0, 78109.0, 14333.928330358282, 17841.910790445963, 17848.19683114289, 17851.642967140844, 17859.46421607354, 16088.919555402148, 18765.71851422257, 18965.0, 19088.61822763565, 19287.710684340025, 17503.737443938608, 17883.73174402411, 18239.0, 18270.72593911119, 18278.81721182771, 17004.574885627957, 17524.91237543804, 18087.095645217425, 18211.81788182101, 18241.726374104135, 16578.58553536171, 17122.64899611164, 17372.0, 17599.0, 17671.11644417748, 10396.596459477874, 11440.256383335056, 11961.820572691242, 12085.093617978511, 12317.137800353888, 13453.663658408617, 13577.0, 13638.318084095557, 13641.249731264876, 13711.725765484834], "value": 8860.202884839533, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 117.0, 116.0, 113.0, 113.0, 115.0, 115.0, 116.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 107.0, 111.0, 166.0, 134.0, 140.0, 138.0, 95.0, 139.0, 136.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 201.0, 195.0, 233.0, 251.0, 226.0, 272.0, 212.0, 287.0, 190.0], "value": 227.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 76.0, 82.0, 62.0, 83.0, 51.0, 66.0, 94.0, 82.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 91.0, 71.0, 70.0, 71.0, 86.0, 89.0, 55.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 89.0, 133.0, 89.0, 87.0, 112.0, 135.0, 137.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 148.0, 96.0, 96.0, 96.0, 123.0, 98.0, 96.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 91.0, 91.0, 92.0, 119.0, 86.0, 147.0, 91.0, 91.0, 91.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 54.0, 76.0, 71.0, 77.0, 75.0, 75.0, 52.0, 51.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 53.0, 65.0, 69.0, 68.0, 59.0, 58.0, 55.0, 56.0, 58.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 86.0, 88.0, 86.0, 85.0, 91.0, 143.0, 85.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 138.0, 148.0, 148.0, 138.0, 147.0, 142.0, 150.0, 137.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 195.0, 187.0, 182.0, 184.0, 199.0, 224.0, 185.0, 186.0, 188.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 101.0, 94.0, 71.0, 64.0, 69.0, 68.0, 69.0, 71.0, 66.0], "value": 73.4, "unit": "score"}]}]} 03:49:53 INFO - Return code: 0 03:49:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:53 INFO - # TBPL SUCCESS # 03:49:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:49:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:49:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:49:53 INFO - 2017-05-26 03:49:53.693 screenresolution[2596:39535] starting screenresolution argv=screenresolution get 03:49:53 INFO - 2017-05-26 03:49:53.696 screenresolution[2596:39535] Display 0: 1600x1200x32@60 03:49:53 INFO - 2017-05-26 03:49:53.702 screenresolution[2597:39538] starting screenresolution argv=screenresolution list 03:49:53 INFO - Available Modes on Display 0 03:49:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:49:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:49:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:49:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:49:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:49:53 INFO - Intel Iris: 03:49:53 INFO - Chipset Model: Intel Iris 03:49:53 INFO - Type: GPU 03:49:53 INFO - Bus: Built-In 03:49:53 INFO - VRAM (Dynamic, Max): 1536 MB 03:49:53 INFO - Vendor: Intel (0x8086) 03:49:53 INFO - Device ID: 0x0a2e 03:49:53 INFO - Revision ID: 0x0009 03:49:53 INFO - Displays: 03:49:53 INFO - Display: 03:49:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:49:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:49:53 INFO - Display Serial Number: 1600x1200 60 03:49:53 INFO - Main Display: Yes 03:49:53 INFO - Mirror: Off 03:49:53 INFO - Online: Yes 03:49:53 INFO - Rotation: Supported 03:49:53 INFO - Return code: 0 03:49:53 INFO - Running post-action listener: _resource_record_post_action 03:49:53 INFO - [mozharness: 2017-05-26 10:49:53.934517Z] Finished run-tests step (success) 03:49:53 INFO - Running post-run listener: _resource_record_post_run 03:49:53 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 1921536; Write bytes: 564846592; Read time: 14; Write time: 1968 03:49:53 INFO - TinderboxPrint: CPU usage
53.2% 03:49:53 INFO - TinderboxPrint: I/O read bytes / time
1,921,536 / 14 03:49:53 INFO - TinderboxPrint: I/O write bytes / time
564,846,592 / 1,968 03:49:53 INFO - TinderboxPrint: CPU idle
2,347.3 (46.8%) 03:49:53 INFO - TinderboxPrint: CPU system
1,012.5 (20.2%) 03:49:53 INFO - TinderboxPrint: CPU user
1,657.9 (33.0%) 03:49:53 INFO - TinderboxPrint: Swap in / out
245,567,488 / 0 03:49:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 241222144; Write bytes: 233775104; Read time: 17233; Write time: 502 03:49:54 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 1016320; Write bytes: 318734336; Read time: 10; Write time: 1423 03:49:54 INFO - Running post-run listener: _upload_blobber_files 03:49:54 INFO - Blob upload gear active. 03:49:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:54 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 03:49:54 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'] 03:49:54 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 03:49:54 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:55 INFO - (blobuploader) - INFO - Done attempting. 03:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:56 INFO - (blobuploader) - INFO - Done attempting. 03:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:58 INFO - (blobuploader) - INFO - Done attempting. 03:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:49:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:59 INFO - (blobuploader) - INFO - Done attempting. 03:49:59 INFO - (blobuploader) - INFO - Iteration through files over. 03:49:59 INFO - Return code: 0 03:49:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:49:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:49:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af4beae8947dd9ad2412ae7cc4240e011c80b1212813cbaf67e54775f3a4e8c718706cc84458cc1a73e184202b1a72746155fd3a2ebad550f0ec7cb8c9319a48", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/495fd8c085e509ca3d811d934714c482cc6402209d59063206a32989c5487a51fd19a97a9bcaa445272b935c13b8592856636db953eba17727433de7a40b62be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6422ce059bc0b326d28a39a609e00ffc973e90c70b7616df5b89538691dc8ed466e40c733dc6fe318a18cfedd8cb17a7bb90286117b357ece0c69bfff2f4b49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1be80ea5dc2472d4b7969f282484dc25a81c22e503e20a453b26fabe07488d623b40dfd6a1b8ee4a71d8b88fa5b4993b9e083f5b80c4f1c6355ddb415a3bb12"} 03:49:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:49:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:49:59 INFO - Contents: 03:49:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af4beae8947dd9ad2412ae7cc4240e011c80b1212813cbaf67e54775f3a4e8c718706cc84458cc1a73e184202b1a72746155fd3a2ebad550f0ec7cb8c9319a48", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/495fd8c085e509ca3d811d934714c482cc6402209d59063206a32989c5487a51fd19a97a9bcaa445272b935c13b8592856636db953eba17727433de7a40b62be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6422ce059bc0b326d28a39a609e00ffc973e90c70b7616df5b89538691dc8ed466e40c733dc6fe318a18cfedd8cb17a7bb90286117b357ece0c69bfff2f4b49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1be80ea5dc2472d4b7969f282484dc25a81c22e503e20a453b26fabe07488d623b40dfd6a1b8ee4a71d8b88fa5b4993b9e083f5b80c4f1c6355ddb415a3bb12"} 03:49:59 INFO - Running post-run listener: copy_logs_to_upload_dir 03:49:59 INFO - Copying logs to upload dir... 03:49:59 INFO - mkdir: /builds/slave/test/build/upload/logs 03:49:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.587822 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-26 03:50:00.036086) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:00.040901) ========= 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.0BQp6pQwET/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.TpUrLtohIY/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/af4beae8947dd9ad2412ae7cc4240e011c80b1212813cbaf67e54775f3a4e8c718706cc84458cc1a73e184202b1a72746155fd3a2ebad550f0ec7cb8c9319a48", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/495fd8c085e509ca3d811d934714c482cc6402209d59063206a32989c5487a51fd19a97a9bcaa445272b935c13b8592856636db953eba17727433de7a40b62be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6422ce059bc0b326d28a39a609e00ffc973e90c70b7616df5b89538691dc8ed466e40c733dc6fe318a18cfedd8cb17a7bb90286117b357ece0c69bfff2f4b49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1be80ea5dc2472d4b7969f282484dc25a81c22e503e20a453b26fabe07488d623b40dfd6a1b8ee4a71d8b88fa5b4993b9e083f5b80c4f1c6355ddb415a3bb12"} build_url:https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011060 build_url: 'https://queue.taskcluster.net/v1/task/VB28z78nQx25Zb4MUI3bqQ/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/af4beae8947dd9ad2412ae7cc4240e011c80b1212813cbaf67e54775f3a4e8c718706cc84458cc1a73e184202b1a72746155fd3a2ebad550f0ec7cb8c9319a48", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/495fd8c085e509ca3d811d934714c482cc6402209d59063206a32989c5487a51fd19a97a9bcaa445272b935c13b8592856636db953eba17727433de7a40b62be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6422ce059bc0b326d28a39a609e00ffc973e90c70b7616df5b89538691dc8ed466e40c733dc6fe318a18cfedd8cb17a7bb90286117b357ece0c69bfff2f4b49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1be80ea5dc2472d4b7969f282484dc25a81c22e503e20a453b26fabe07488d623b40dfd6a1b8ee4a71d8b88fa5b4993b9e083f5b80c4f1c6355ddb415a3bb12"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:00.074679) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:00.074994) ========= 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.0BQp6pQwET/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.TpUrLtohIY/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:50:00.122924) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 03:50:00.123207) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 03:50:00.123511) ========= ========= Total master_lag: 1.77 =========