builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0209 starttime: 1496422053.14 results: success (0) revision: 95d2d23ff510bea680e9707c1ec166b0cc08dc6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.139575) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.140014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.140283) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.165211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.165634) ========= 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.tnYGleQUVy/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.baZBSO3ZQU/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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-06-02 09:47:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 73.5M=0s 2017-06-02 09:47:33 (73.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147194 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.336026) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.336351) ========= 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.tnYGleQUVy/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.baZBSO3ZQU/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.041075 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:33.398138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 09:47:33.398516) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 95d2d23ff510bea680e9707c1ec166b0cc08dc6b --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 95d2d23ff510bea680e9707c1ec166b0cc08dc6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tnYGleQUVy/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.baZBSO3ZQU/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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-06-02 09:47:33,667 truncating revision to first 12 chars 2017-06-02 09:47:33,668 Setting DEBUG logging. 2017-06-02 09:47:33,668 attempt 1/10 2017-06-02 09:47:33,668 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/95d2d23ff510?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 09:47:34,670 unpacking tar archive at: mozilla-central-95d2d23ff510/testing/mozharness/ program finished with exit code 0 elapsedTime=1.273565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 09:47:34.693424) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:34.693759) ========= script_repo_revision: 95d2d23ff510bea680e9707c1ec166b0cc08dc6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:34.694123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:34.694400) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 09:47:34.713109) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-02 09:47:34.713497) ========= /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.tnYGleQUVy/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.baZBSO3ZQU/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:47:35 INFO - MultiFileLogger online at 20170602 09:47:35 in /builds/slave/test 09:47:35 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 09:47:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:47:35 INFO - {'append_to_log': False, 09:47:35 INFO - 'base_work_dir': '/builds/slave/test', 09:47:35 INFO - 'blob_upload_branch': 'Firefox', 09:47:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:47:35 INFO - 'branch': 'Firefox', 09:47:35 INFO - 'buildbot_json_path': 'buildprops.json', 09:47:35 INFO - 'config_files': ('talos/mac_config.py',), 09:47:35 INFO - 'default_actions': ('clobber', 09:47:35 INFO - 'read-buildbot-config', 09:47:35 INFO - 'download-and-extract', 09:47:35 INFO - 'populate-webroot', 09:47:35 INFO - 'create-virtualenv', 09:47:35 INFO - 'install', 09:47:35 INFO - 'setup-mitmproxy', 09:47:35 INFO - 'run-tests'), 09:47:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:47:35 INFO - 'download_minidump_stackwalk': True, 09:47:35 INFO - 'download_symbols': 'ondemand', 09:47:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:47:35 INFO - 'tooltool.py': '/tools/tooltool.py', 09:47:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:47:35 INFO - '/tools/misc-python/virtualenv.py')}, 09:47:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:47:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:47:35 INFO - 'gecko_profile': False, 09:47:35 INFO - 'gecko_profile_interval': 0, 09:47:35 INFO - 'installer_path': 'installer.exe', 09:47:35 INFO - 'log_level': 'info', 09:47:35 INFO - 'log_name': 'talos', 09:47:35 INFO - 'log_to_console': True, 09:47:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:47:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:47:35 INFO - 'opt_config_files': (), 09:47:35 INFO - 'pip_index': False, 09:47:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:35 INFO - 'cmd': ('bash', 09:47:35 INFO - '-c', 09:47:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:35 INFO - 'enabled': True, 09:47:35 INFO - 'halt_on_failure': False, 09:47:35 INFO - 'name': 'check_screen_resolution'},), 09:47:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:35 INFO - 'cmd': ('bash', 09:47:35 INFO - '-c', 09:47:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:35 INFO - 'enabled': True, 09:47:35 INFO - 'halt_on_failure': False, 09:47:35 INFO - 'name': 'check_screen_resolution'},), 09:47:35 INFO - 'run_cmd_checks_enabled': True, 09:47:35 INFO - 'suite': 'dromaeojs-e10s', 09:47:35 INFO - 'system_bits': '32', 09:47:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:47:35 INFO - 'title': 't-yosemite-r7-0209', 09:47:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:47:35 INFO - 'use_talos_json': True, 09:47:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:47:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:47:35 INFO - 'work_dir': 'build'} 09:47:35 INFO - [mozharness: 2017-06-02 16:47:35.095054Z] Running clobber step. 09:47:35 INFO - Running pre-action listener: _resource_record_pre_action 09:47:35 INFO - Running main action method: clobber 09:47:35 INFO - rmtree: /builds/slave/test/build 09:47:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:47:37 INFO - Running post-action listener: _resource_record_post_action 09:47:37 INFO - [mozharness: 2017-06-02 16:47:37.519652Z] Finished clobber step (success) 09:47:37 INFO - [mozharness: 2017-06-02 16:47:37.519886Z] Running read-buildbot-config step. 09:47:37 INFO - Running pre-action listener: _resource_record_pre_action 09:47:37 INFO - Running main action method: read_buildbot_config 09:47:37 INFO - Using buildbot properties: 09:47:37 INFO - { 09:47:37 INFO - "project": "", 09:47:37 INFO - "product": "firefox", 09:47:37 INFO - "who": "ryanvm@gmail.com", 09:47:37 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:47:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:47:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:47:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:47:37 INFO - "stage_platform": "macosx64", 09:47:37 INFO - "basedir": "/builds/slave/test", 09:47:37 INFO - "buildnumber": 12, 09:47:37 INFO - "platform": "yosemite_r7", 09:47:37 INFO - "parent_task_id": "XcWK6AFiSaiUQWA9a9zGAA", 09:47:37 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:47:37 INFO - "slavebuilddir": "test", 09:47:37 INFO - "taskId": "OF7JFFVITtKS2A8UjaovlQ", 09:47:37 INFO - "branch": "mozilla-central", 09:47:37 INFO - "script_repo_revision": "production", 09:47:37 INFO - "revision": "95d2d23ff510bea680e9707c1ec166b0cc08dc6b", 09:47:37 INFO - "slavename": "t-yosemite-r7-0209", 09:47:37 INFO - "repo_path": "mozilla-central" 09:47:37 INFO - } 09:47:37 INFO - Finding installer, test and symbols from parent task. 09:47:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OF7JFFVITtKS2A8UjaovlQ'}, attempt #1 09:47:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OF7JFFVITtKS2A8UjaovlQ'}, attempt #1 09:47:38 INFO - Task dependencies: YklYvm7dRdmd-Ay-0KF0xA XcWK6AFiSaiUQWA9a9zGAA 09:47:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA'}, attempt #1 09:47:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:47:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/test_packages.json 09:47:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/target.crashreporter-symbols.zip 09:47:38 INFO - Running post-action listener: _resource_record_post_action 09:47:38 INFO - [mozharness: 2017-06-02 16:47:38.907545Z] Finished read-buildbot-config step (success) 09:47:38 INFO - [mozharness: 2017-06-02 16:47:38.907669Z] Running download-and-extract step. 09:47:38 INFO - Running pre-action listener: _resource_record_pre_action 09:47:38 INFO - Running main action method: download_and_extract 09:47:38 INFO - mkdir: /builds/slave/test/build/tests 09:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:38 INFO - trying https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:47:38 INFO - Downloading https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/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 09:47:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/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 09:47:39 INFO - Downloaded 1328 bytes. 09:47:39 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:47:39 INFO - Using the following test package requirements: 09:47:39 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:47:39 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:47:39 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:47:39 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:47:39 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'jsshell-mac.zip'], 09:47:39 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:47:39 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:47:39 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:47:39 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:47:39 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:47:39 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:47:39 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:47:39 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:47:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:47:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:47:39 INFO - Fetch https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:47:41 INFO - Content-Length response header: 57636449 09:47:41 INFO - Bytes received: 57636449 09:47:59 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 09:47:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:47:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:47:59 INFO - Fetch https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:48:01 INFO - Content-Length response header: 57636449 09:48:01 INFO - Bytes received: 57636449 09:48:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:48:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:48:18 INFO - Fetch https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:48:19 INFO - Content-Length response header: 14447425 09:48:19 INFO - Bytes received: 14447425 09:48: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')]} 09:48:20 INFO - trying https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:48:20 INFO - Downloading https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:48:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/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 09:48:22 INFO - Downloaded 67811625 bytes. 09:48:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:48:22 INFO - mkdir: /builds/slave/test/properties 09:48:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:22 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:22 INFO - Contents: 09:48:22 INFO - build_url:https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:48:22 INFO - Running post-action listener: _resource_record_post_action 09:48:22 INFO - Running post-action listener: set_extra_try_arguments 09:48:22 INFO - [mozharness: 2017-06-02 16:48:22.069186Z] Finished download-and-extract step (success) 09:48:22 INFO - [mozharness: 2017-06-02 16:48:22.069350Z] Running populate-webroot step. 09:48:22 INFO - Running pre-action listener: _resource_record_pre_action 09:48:22 INFO - Running main action method: populate_webroot 09:48:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:22 INFO - '--tppagecycles', 09:48:22 INFO - '7'], 09:48:22 INFO - 'tests': ['tsvgm']}}, 09:48:22 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['damp', 'tps']}, 09:48:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'tests': ['damp', 'tps']}, 09:48:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['dromaeo_dom']}, 09:48:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:48:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:48:22 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:48:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['a11yr', 09:48:22 INFO - 'ts_paint', 09:48:22 INFO - 'tpaint', 09:48:22 INFO - 'sessionrestore', 09:48:22 INFO - 'sessionrestore_no_auto_restore', 09:48:22 INFO - 'tabpaint']}, 09:48:22 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:22 INFO - 'ts_paint', 09:48:22 INFO - 'tpaint', 09:48:22 INFO - 'sessionrestore', 09:48:22 INFO - 'sessionrestore_no_auto_restore', 09:48:22 INFO - 'tabpaint']}, 09:48:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:22 INFO - 'ts_paint', 09:48:22 INFO - 'tpaint', 09:48:22 INFO - 'sessionrestore', 09:48:22 INFO - 'sessionrestore_no_auto_restore', 09:48:22 INFO - 'tabpaint']}, 09:48:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:22 INFO - 'ts_paint', 09:48:22 INFO - 'tpaint', 09:48:22 INFO - 'sessionrestore', 09:48:22 INFO - 'sessionrestore_no_auto_restore', 09:48:22 INFO - 'tabpaint']}, 09:48:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['a11yr', 09:48:22 INFO - 'ts_paint', 09:48:22 INFO - 'tpaint', 09:48:22 INFO - 'sessionrestore', 09:48:22 INFO - 'sessionrestore_no_auto_restore', 09:48:22 INFO - 'tabpaint']}, 09:48:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['a11yr', 09:48:22 INFO - 'ts_paint', 09:48:22 INFO - 'tpaint', 09:48:22 INFO - 'sessionrestore', 09:48:22 INFO - 'sessionrestore_no_auto_restore', 09:48:22 INFO - 'tabpaint']}, 09:48:22 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:48:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:48:22 INFO - 'bloom_basic_ref']}, 09:48:22 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:48:22 INFO - 'talos_options': ['--mitmproxy', 09:48:22 INFO - 'mitmproxy-recording-1.mp', 09:48:22 INFO - '--firstNonBlankPaint'], 09:48:22 INFO - 'tests': ['Quantum_1']}, 09:48:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['tsvgx', 09:48:22 INFO - 'tsvgr_opacity', 09:48:22 INFO - 'tart', 09:48:22 INFO - 'tscrollx', 09:48:22 INFO - 'cart', 09:48:22 INFO - 'tsvg_static']}, 09:48:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:22 INFO - 'tsvgr_opacity', 09:48:22 INFO - 'tart', 09:48:22 INFO - 'tscrollx', 09:48:22 INFO - 'cart', 09:48:22 INFO - 'tsvg_static']}, 09:48:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'talos_options': ['--disable-e10s'], 09:48:22 INFO - 'tests': ['tp5o']}, 09:48:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:48:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'talos_options': ['--disable-e10s', 09:48:22 INFO - '--xperf_path', 09:48:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:22 INFO - 'tests': ['tp5n']}, 09:48:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:48:22 INFO - 'talos_options': ['--xperf_path', 09:48:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:22 INFO - 'tests': ['tp5n']}}, 09:48:22 INFO - 'talos.zip': {'path': '', 09:48:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:22 INFO - Running post-action listener: _resource_record_post_action 09:48:22 INFO - [mozharness: 2017-06-02 16:48:22.092100Z] Finished populate-webroot step (success) 09:48:22 INFO - [mozharness: 2017-06-02 16:48:22.092239Z] Running create-virtualenv step. 09:48:22 INFO - Running pre-action listener: _resource_record_pre_action 09:48:22 INFO - Running main action method: create_virtualenv 09:48:22 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:22 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 09:48:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:22 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:48:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:22 INFO - Using real prefix '/tools/python27' 09:48:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:22 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:25 INFO - Installing pip.................done. 09:48:25 INFO - Return code: 0 09:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:26 INFO - Reading from file tmpfile_stdout 09:48:26 INFO - Output received: 09:48:26 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) 09:48:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:26 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')]} 09:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:26 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')]} 09:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:26 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 09:48:26 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 09:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:26 INFO - 'HOME': '/Users/cltbld', 09:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:26 INFO - 'LOGNAME': 'cltbld', 09:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:26 INFO - 'MOZ_NO_REMOTE': '1', 09:48:26 INFO - 'NO_EM_RESTART': '1', 09:48:26 INFO - 'PAGER': '/bin/cat', 09:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:26 INFO - 'PWD': '/builds/slave/test', 09:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:26 INFO - 'SHELL': '/bin/bash', 09:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:26 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:26 INFO - 'USER': 'cltbld', 09:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:26 INFO - 'XPC_FLAGS': '0x0', 09:48:26 INFO - 'XPC_SERVICE_NAME': '0', 09:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:26 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 09:48:26 INFO - Cleaning up... 09:48:26 INFO - Return code: 0 09:48:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:26 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')]} 09:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:26 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')]} 09:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:26 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 09:48:26 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 09:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:26 INFO - 'HOME': '/Users/cltbld', 09:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:26 INFO - 'LOGNAME': 'cltbld', 09:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:26 INFO - 'MOZ_NO_REMOTE': '1', 09:48:26 INFO - 'NO_EM_RESTART': '1', 09:48:26 INFO - 'PAGER': '/bin/cat', 09:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:26 INFO - 'PWD': '/builds/slave/test', 09:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:26 INFO - 'SHELL': '/bin/bash', 09:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:26 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:26 INFO - 'USER': 'cltbld', 09:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:26 INFO - 'XPC_FLAGS': '0x0', 09:48:26 INFO - 'XPC_SERVICE_NAME': '0', 09:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:26 INFO - Downloading/unpacking psutil>=3.1.1 09:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:28 INFO - Installing collected packages: psutil 09:48:28 INFO - Running setup.py install for psutil 09:48:28 INFO - building 'psutil._psutil_osx' extension 09:48:28 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 09:48:29 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 09:48:29 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 09:48:29 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 09:48:29 INFO - building 'psutil._psutil_posix' extension 09:48:29 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 09:48:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:48:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:48:29 INFO - ^ 09:48:29 INFO - 1 warning generated. 09:48:29 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 09:48:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:29 INFO - Successfully installed psutil 09:48:29 INFO - Cleaning up... 09:48:29 INFO - Return code: 0 09:48:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:30 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')]} 09:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:30 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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:30 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 09:48:30 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 09:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:30 INFO - 'HOME': '/Users/cltbld', 09:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:30 INFO - 'LOGNAME': 'cltbld', 09:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:30 INFO - 'MOZ_NO_REMOTE': '1', 09:48:30 INFO - 'NO_EM_RESTART': '1', 09:48:30 INFO - 'PAGER': '/bin/cat', 09:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:30 INFO - 'PWD': '/builds/slave/test', 09:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:30 INFO - 'SHELL': '/bin/bash', 09:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:30 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:30 INFO - 'USER': 'cltbld', 09:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:30 INFO - 'XPC_FLAGS': '0x0', 09:48:30 INFO - 'XPC_SERVICE_NAME': '0', 09:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:31 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 09:48:31 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 09:48:31 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 09:48:31 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 09:48:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:33 INFO - Installing collected packages: mozsystemmonitor 09:48:33 INFO - Running setup.py install for mozsystemmonitor 09:48:33 INFO - Successfully installed mozsystemmonitor 09:48:33 INFO - Cleaning up... 09:48:33 INFO - Return code: 0 09:48:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:33 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')]} 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:33 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')]} 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:33 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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:33 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 09:48:33 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 09:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:33 INFO - 'HOME': '/Users/cltbld', 09:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:33 INFO - 'LOGNAME': 'cltbld', 09:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:33 INFO - 'MOZ_NO_REMOTE': '1', 09:48:33 INFO - 'NO_EM_RESTART': '1', 09:48:33 INFO - 'PAGER': '/bin/cat', 09:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:33 INFO - 'PWD': '/builds/slave/test', 09:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:33 INFO - 'SHELL': '/bin/bash', 09:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:33 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:33 INFO - 'USER': 'cltbld', 09:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:33 INFO - 'XPC_FLAGS': '0x0', 09:48:33 INFO - 'XPC_SERVICE_NAME': '0', 09:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:33 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 09:48:33 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 09:48:33 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 09:48:33 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 09:48:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:35 INFO - warning: no files found matching '*.txt' 09:48:35 INFO - no previously-included directories found matching 'build' 09:48:35 INFO - no previously-included directories found matching 'dist' 09:48:35 INFO - no previously-included directories found matching '.git*' 09:48:35 INFO - Installing collected packages: jsonschema, functools32 09:48:35 INFO - Running setup.py install for functools32 09:48:35 INFO - warning: no files found matching '*.txt' 09:48:35 INFO - no previously-included directories found matching 'build' 09:48:35 INFO - no previously-included directories found matching 'dist' 09:48:35 INFO - no previously-included directories found matching '.git*' 09:48:35 INFO - Successfully installed jsonschema functools32 09:48:35 INFO - Cleaning up... 09:48:35 INFO - Return code: 0 09:48:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48: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')]} 09:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48: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', '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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48: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 functools32==3.2.3-2 09:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:35 INFO - 'HOME': '/Users/cltbld', 09:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:35 INFO - 'LOGNAME': 'cltbld', 09:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:35 INFO - 'MOZ_NO_REMOTE': '1', 09:48:35 INFO - 'NO_EM_RESTART': '1', 09:48:35 INFO - 'PAGER': '/bin/cat', 09:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:35 INFO - 'PWD': '/builds/slave/test', 09:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:35 INFO - 'SHELL': '/bin/bash', 09:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:35 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:35 INFO - 'USER': 'cltbld', 09:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:35 INFO - 'XPC_FLAGS': '0x0', 09:48:35 INFO - 'XPC_SERVICE_NAME': '0', 09:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:35 INFO - Cleaning up... 09:48:35 INFO - Return code: 0 09:48:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48: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')]} 09:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48: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', '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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:48: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 blobuploader==1.2.4 09:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:35 INFO - 'HOME': '/Users/cltbld', 09:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:35 INFO - 'LOGNAME': 'cltbld', 09:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:35 INFO - 'MOZ_NO_REMOTE': '1', 09:48:35 INFO - 'NO_EM_RESTART': '1', 09:48:35 INFO - 'PAGER': '/bin/cat', 09:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:35 INFO - 'PWD': '/builds/slave/test', 09:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:35 INFO - 'SHELL': '/bin/bash', 09:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:35 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:35 INFO - 'USER': 'cltbld', 09:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:35 INFO - 'XPC_FLAGS': '0x0', 09:48:35 INFO - 'XPC_SERVICE_NAME': '0', 09:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:48: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 09:48: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 09:48: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 09:48: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 09:48:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:38 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 09:48:38 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 09:48:38 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 09:48:38 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 09:48:38 INFO - Downloading docopt-0.6.1.tar.gz 09:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:38 INFO - Running setup.py install for blobuploader 09:48:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:38 INFO - Running setup.py install for requests 09:48:39 INFO - Running setup.py install for docopt 09:48:39 INFO - Successfully installed blobuploader requests docopt 09:48:39 INFO - Cleaning up... 09:48:39 INFO - Return code: 0 09:48:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48: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')]} 09:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:39 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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:39 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 09:48:39 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 09:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:39 INFO - 'HOME': '/Users/cltbld', 09:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:39 INFO - 'LOGNAME': 'cltbld', 09:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:39 INFO - 'MOZ_NO_REMOTE': '1', 09:48:39 INFO - 'NO_EM_RESTART': '1', 09:48:39 INFO - 'PAGER': '/bin/cat', 09:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:39 INFO - 'PWD': '/builds/slave/test', 09:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:39 INFO - 'SHELL': '/bin/bash', 09:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:39 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:39 INFO - 'USER': 'cltbld', 09:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:39 INFO - 'XPC_FLAGS': '0x0', 09:48:39 INFO - 'XPC_SERVICE_NAME': '0', 09:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:39 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-v3eRAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:39 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-EbKbay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:39 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-rQHDgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:39 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-1wHV0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:39 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8VkXTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-wPrZA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-uKEP9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-MTxsGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-w79iws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-bycKei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-S1N_RF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-5WCyzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-119UVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:40 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-v0dP2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:41 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-1HswZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:41 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-9QvEnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:41 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-RJjaCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:41 INFO - Running setup.py install for manifestparser 09:48:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Running setup.py install for mozcrash 09:48:41 INFO - Running setup.py install for mozdebug 09:48:41 INFO - Running setup.py install for mozdevice 09:48:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:41 INFO - Running setup.py install for mozfile 09:48:42 INFO - Running setup.py install for mozhttpd 09:48:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozinfo 09:48:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozInstall 09:48:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozleak 09:48:42 INFO - Running setup.py install for mozlog 09:48:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for moznetwork 09:48:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:42 INFO - Running setup.py install for mozprocess 09:48:43 INFO - Running setup.py install for mozprofile 09:48:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Running setup.py install for mozrunner 09:48:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Running setup.py install for mozscreenshot 09:48:43 INFO - Running setup.py install for moztest 09:48:43 INFO - Running setup.py install for mozversion 09:48:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:43 INFO - Cleaning up... 09:48:43 INFO - Return code: 0 09:48:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:43 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')]} 09:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:43 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')]} 09:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:43 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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:43 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 09:48:43 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 09:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:43 INFO - 'HOME': '/Users/cltbld', 09:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:43 INFO - 'LOGNAME': 'cltbld', 09:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:43 INFO - 'MOZ_NO_REMOTE': '1', 09:48:43 INFO - 'NO_EM_RESTART': '1', 09:48:43 INFO - 'PAGER': '/bin/cat', 09:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:43 INFO - 'PWD': '/builds/slave/test', 09:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:43 INFO - 'SHELL': '/bin/bash', 09:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:43 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:43 INFO - 'USER': 'cltbld', 09:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:43 INFO - 'XPC_FLAGS': '0x0', 09:48:43 INFO - 'XPC_SERVICE_NAME': '0', 09:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-PouYrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-A_8Dde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QXGjwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-HZnMQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-p82Mcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-q_OowT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-BMJqs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QuWTDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:44 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)) 09:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:44 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8HBVoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-F9zGYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Q_e7Ua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-4yIjyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-4dBevg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Px4Bw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-DNHFW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-1UQ77O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:45 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)) 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:45 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ug636h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:45 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)) 09:48:45 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)) 09:48:45 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)) 09:48:45 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)) 09:48:45 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)) 09:48:45 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)) 09:48:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:45 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 09:48:45 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 09:48:45 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 09:48:45 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 09:48:47 INFO - Downloading blessings-1.6.tar.gz 09:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:47 INFO - Installing collected packages: blessings 09:48:47 INFO - Running setup.py install for blessings 09:48:47 INFO - Successfully installed blessings 09:48:47 INFO - Cleaning up... 09:48:47 INFO - Return code: 0 09:48:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:47 INFO - Reading from file tmpfile_stdout 09:48:47 INFO - Current package versions: 09:48:47 INFO - blessings == 1.6 09:48:47 INFO - blobuploader == 1.2.4 09:48:47 INFO - docopt == 0.6.1 09:48:47 INFO - functools32 == 3.2.3-2 09:48:47 INFO - jsonschema == 2.5.1 09:48:47 INFO - manifestparser == 1.1 09:48:47 INFO - mozInstall == 1.12 09:48:47 INFO - mozcrash == 1.0 09:48:47 INFO - mozdebug == 0.1 09:48:47 INFO - mozdevice == 0.51 09:48:47 INFO - mozfile == 1.2 09:48:47 INFO - mozhttpd == 0.7 09:48:47 INFO - mozinfo == 0.9 09:48:47 INFO - mozleak == 0.1 09:48:47 INFO - mozlog == 3.4 09:48:47 INFO - moznetwork == 0.27 09:48:47 INFO - mozprocess == 0.25 09:48:47 INFO - mozprofile == 0.28 09:48:47 INFO - mozrunner == 6.13 09:48:47 INFO - mozscreenshot == 0.1 09:48:47 INFO - mozsystemmonitor == 0.3 09:48:47 INFO - moztest == 0.8 09:48:47 INFO - mozversion == 1.4 09:48:47 INFO - psutil == 3.1.1 09:48:47 INFO - requests == 1.2.3 09:48:47 INFO - wsgiref == 0.1.2 09:48:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48: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')]} 09:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:47 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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:47 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 09:48:47 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 09:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:47 INFO - 'HOME': '/Users/cltbld', 09:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:47 INFO - 'LOGNAME': 'cltbld', 09:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:47 INFO - 'MOZ_NO_REMOTE': '1', 09:48:47 INFO - 'NO_EM_RESTART': '1', 09:48:47 INFO - 'PAGER': '/bin/cat', 09:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:47 INFO - 'PWD': '/builds/slave/test', 09:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:47 INFO - 'SHELL': '/bin/bash', 09:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:47 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:47 INFO - 'USER': 'cltbld', 09:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:47 INFO - 'XPC_FLAGS': '0x0', 09:48:47 INFO - 'XPC_SERVICE_NAME': '0', 09:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 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)) 09:48:48 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:48:48 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 09:48:48 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 09:48:48 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 09:48:48 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 09:48:49 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 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)) 09:48:49 INFO - Installing collected packages: marionette-driver 09:48:49 INFO - Running setup.py install for marionette-driver 09:48:49 INFO - Successfully installed marionette-driver 09:48:49 INFO - Cleaning up... 09:48:50 INFO - Return code: 0 09:48:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:48:50 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')]} 09:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:50 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')]} 09:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:50 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 0x100d5ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97a8560210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, '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.tnYGleQUVy/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.baZBSO3ZQU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 09:48:50 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 09:48:50 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 09:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:50 INFO - 'HOME': '/Users/cltbld', 09:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:50 INFO - 'LOGNAME': 'cltbld', 09:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:50 INFO - 'MOZ_NO_REMOTE': '1', 09:48:50 INFO - 'NO_EM_RESTART': '1', 09:48:50 INFO - 'PAGER': '/bin/cat', 09:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:50 INFO - 'PWD': '/builds/slave/test', 09:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:50 INFO - 'SHELL': '/bin/bash', 09:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:48:50 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:50 INFO - 'USER': 'cltbld', 09:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:50 INFO - 'XPC_FLAGS': '0x0', 09:48:50 INFO - 'XPC_SERVICE_NAME': '0', 09:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:48:50 INFO - Cleaning up... 09:48:50 INFO - Return code: 0 09:48:50 INFO - Running post-action listener: _resource_record_post_action 09:48:50 INFO - Running post-action listener: _start_resource_monitoring 09:48:50 INFO - Starting resource monitoring. 09:48:50 INFO - [mozharness: 2017-06-02 16:48:50.349094Z] Finished create-virtualenv step (success) 09:48:50 INFO - [mozharness: 2017-06-02 16:48:50.349910Z] Running install step. 09:48:50 INFO - Running pre-action listener: _resource_record_pre_action 09:48:50 INFO - Running main action method: install 09:48:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:50 INFO - Reading from file tmpfile_stdout 09:48:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:48:50 INFO - Reading from file tmpfile_stdout 09:48:50 INFO - Output received: 09:48:50 INFO - Usage: mozinstall [options] installer 09:48:50 INFO - Options: 09:48:50 INFO - -h, --help show this help message and exit 09:48:50 INFO - -d DEST, --destination=DEST 09:48:50 INFO - Directory to install application into. [default: 09:48:50 INFO - "/builds/slave/test"] 09:48:50 INFO - --app=APP Application being installed. [default: firefox] 09:48:50 INFO - mkdir: /builds/slave/test/build/application 09:48:50 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'] 09:48:50 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 09:49:11 INFO - Reading from file tmpfile_stdout 09:49:11 INFO - Output received: 09:49:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:49:11 INFO - Running post-action listener: _resource_record_post_action 09:49:11 INFO - [mozharness: 2017-06-02 16:49:11.766342Z] Finished install step (success) 09:49:11 INFO - [mozharness: 2017-06-02 16:49:11.766512Z] Running setup-mitmproxy step. 09:49:11 INFO - Running pre-action listener: _resource_record_pre_action 09:49:11 INFO - Running main action method: setup_mitmproxy 09:49:11 INFO - Skipping: mitmproxy is not required 09:49:11 INFO - Running post-action listener: _resource_record_post_action 09:49:11 INFO - [mozharness: 2017-06-02 16:49:11.767250Z] Finished setup-mitmproxy step (success) 09:49:11 INFO - [mozharness: 2017-06-02 16:49:11.767364Z] Running run-tests step. 09:49:11 INFO - Running pre-action listener: _resource_record_pre_action 09:49:11 INFO - Running main action method: run_tests 09:49:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:11 INFO - 2017-06-02 09:49:11.781 screenresolution[1618:7459] starting screenresolution argv=screenresolution get 09:49:11 INFO - 2017-06-02 09:49:11.784 screenresolution[1618:7459] Display 0: 1600x1200x32@60 09:49:11 INFO - 2017-06-02 09:49:11.790 screenresolution[1619:7462] starting screenresolution argv=screenresolution list 09:49:11 INFO - Available Modes on Display 0 09:49:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:12 INFO - Intel Iris: 09:49:12 INFO - Chipset Model: Intel Iris 09:49:12 INFO - Type: GPU 09:49:12 INFO - Bus: Built-In 09:49:12 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:12 INFO - Vendor: Intel (0x8086) 09:49:12 INFO - Device ID: 0x0a2e 09:49:12 INFO - Revision ID: 0x0009 09:49:12 INFO - Displays: 09:49:12 INFO - Display: 09:49:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:12 INFO - Display Serial Number: 1600x1200 60 09:49:12 INFO - Main Display: Yes 09:49:12 INFO - Mirror: Off 09:49:12 INFO - Online: Yes 09:49:12 INFO - Rotation: Supported 09:49:12 INFO - Return code: 0 09:49:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:12 INFO - Python 2.7.3 09:49:12 INFO - Return code: 0 09:49:12 INFO - grabbing minidump binary from tooltool 09:49:12 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')]} 09:49:12 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 0x100ce7c60>, '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 0x100a7ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100aa5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:12 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 09:49:12 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 09:49:12 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 09:49:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:12 INFO - Return code: 0 09:49:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:49:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:12 INFO - ENV: RUST_BACKTRACE is now 1 09:49:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:49:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:12 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/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 09:49:12 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/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0209 --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 09:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tnYGleQUVy/Render', 09:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:12 INFO - 'HOME': '/Users/cltbld', 09:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:12 INFO - 'LOGNAME': 'cltbld', 09:49:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:12 INFO - 'MOZ_NO_REMOTE': '1', 09:49:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:12 INFO - 'NO_EM_RESTART': '1', 09:49:12 INFO - 'PAGER': '/bin/cat', 09:49:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:12 INFO - 'PWD': '/builds/slave/test', 09:49:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:12 INFO - 'RUST_BACKTRACE': '1', 09:49:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:49:12 INFO - 'SHELL': '/bin/bash', 09:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.baZBSO3ZQU/Listeners', 09:49:12 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 09:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:12 INFO - 'USER': 'cltbld', 09:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:12 INFO - 'XPC_FLAGS': '0x0', 09:49:12 INFO - 'XPC_SERVICE_NAME': '0', 09:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:12 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/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 09:49:12 INFO - mozversion application_buildid: 20170602081357 09:49:12 INFO - mozversion application_changeset: 95d2d23ff510bea680e9707c1ec166b0cc08dc6b 09:49:12 INFO - mozversion application_display_name: Nightly 09:49:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:12 INFO - mozversion application_name: Firefox 09:49:12 INFO - mozversion application_remotingname: firefox 09:49:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:49:12 INFO - mozversion application_vendor: Mozilla 09:49:12 INFO - mozversion application_version: 55.0a1 09:49:12 INFO - mozversion platform_buildid: 20170602081357 09:49:12 INFO - mozversion platform_changeset: 95d2d23ff510bea680e9707c1ec166b0cc08dc6b 09:49:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:49:12 INFO - mozversion platform_version: 55.0a1 09:49:12 INFO - using testdate: 1496422152 09:49:12 INFO - actual date: 1496422152 09:49:12 INFO - starting webserver on 'localhost:49220' 09:49:12 INFO - SUITE-START | Running 2 tests 09:49:12 INFO - TEST-START | dromaeo_css 09:49:12 INFO - operating with platform_type : mac_ 09:49:12 INFO - Initialising browser for dromaeo_css test... 09:49:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49220/getInfo.html -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp8WdYCz/profile 09:49:12 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49220/getInfo.html -foreground) 09:49:46 INFO - TEST-INFO | 1629: killed by SIGTERM 09:49:46 INFO - Browser initialized. 09:49:46 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:46 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp8WdYCz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:48 INFO - PID 1639 | 2017-06-02 09:49:48.675 plugin-container[1640:8209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 09:49:48 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:48 INFO - PID 1639 | 2017-06-02 09:49:48.679 plugin-container[1640:8209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 09:49:48 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:48 INFO - PID 1639 | 2017-06-02 09:49:48.680 plugin-container[1640:8209] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:49:49 INFO - PID 1639 | 2017-06-02 09:49:49.196 plugin-container[1641:8257] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 09:49:49 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:49 INFO - PID 1639 | 2017-06-02 09:49:49.197 plugin-container[1641:8257] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 09:49:49 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:49 INFO - PID 1639 | 2017-06-02 09:49:49.197 plugin-container[1641:8257] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:49:58 INFO - PID 1639 | RSS: Main: 268189696 09:49:58 INFO - PID 1639 | 09:53:00 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49220/tests/dromaeo/cssquery-ext.html) 09:53:01 INFO - PID 1639 | RSS: Main: 273457152 09:53:01 INFO - PID 1639 | 09:56:03 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-ext.html (next: http://localhost:49220/tests/dromaeo/cssquery-jquery.html) 09:56:04 INFO - PID 1639 | RSS: Main: 288079872 09:56:04 INFO - PID 1639 | 09:59:06 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49220/tests/dromaeo/cssquery-mootools.html) 09:59:07 INFO - PID 1639 | RSS: Main: 289636352 09:59:07 INFO - PID 1639 | 10:02:08 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49220/tests/dromaeo/cssquery-prototype.html) 10:02:09 INFO - PID 1639 | RSS: Main: 294379520 10:02:09 INFO - PID 1639 | 10:05:11 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49220/tests/dromaeo/cssquery-yui.html) 10:05:12 INFO - PID 1639 | RSS: Main: 294428672 10:05:12 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-yui.html (next: http://localhost:49220/tests/dromaeo/cssquery-dojo.html) 10:08:14 INFO - PID 1639 | RSS: Main: 296435712 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | __start_tp_report 10:08:14 INFO - PID 1639 | _x_x_mozilla_page_load 10:08:14 INFO - PID 1639 | _x_x_mozilla_page_load_details 10:08:14 INFO - PID 1639 | |i|pagename|runs| 10:08:14 INFO - PID 1639 | |0;dojo.html;2975.8958436454727;3169.397814415261;3632.3824949758523;3658.2866341063495;3668.0646435159047;4588.403507544023;6153.876922461559;6156.81529554113;6231.688415579226;6278.2779980302275;6196.411340922617;6217.5958562693395;6219.253689557248;6268.529860260476;6273.404026617475;5281.70950597717;5433.565314774828;5458.962797068564;5464.12573988162;5569.10894256919;5575.024370735126;5578.776848926038;5579.358373787026;5585.888282234353;5589.916151257735;5375.43557926416;5432.782688692448;5462.781488740486;5499.175123731432;5507.669539827623;4504.932426013613;5478.698671573062;5521.282233309684;5521.806736764194;5531.225628412026;5412.242286079952;5434.891302173974;5466.098093814516;5481.287432633772;5509.00837849187;6146.567404506532;6151.815445536641;6236.501079913596;6263.96868015657;6268.122462855204;6623.105880706091;6734.8989765153565;6754.493412994045;6756.729730810811;6758.966205168992;6860.833658278105;6862.039314495974;6866.622335771531;6870.8625827483675;6905.827354316132;6274.1843560336865;6329.968350158219;6340.619562826153;6363.109164716943;6386.10594516768;6803.251642319341;6837.042814006043;6948.93051069493;6952.965235173893;6967.825804354931;6398.424141827258;6566.146400967939;6587.110740050032;6636.900446493307;6642;6833.897491537631;6850.109485766918;6852.211995620468;6852.5203235774015;6852.588844669336;5498.395176530578;6389.936100639027;6695.029220763059;6719.227288861745;6737.831554211184;5548.861278468071;6520.836979075561;6594.802155935329;6696.397324240842;6714.462842972551;5300.443453437333;5346.599005074635;5360.839174824683;5367.570594352443;5397.514223719884;4786.042791441726;4788.4253889533475;4802.447718512346;4806.375171227718;4863.659543831968;6120.538559592075;6316.242050953823;6330.543974885802;6332.1135041094285;6358.682065896686;5696.829095127153;6872.759453419107;6881.277465866012;6891.552049116892;6895.069165662671;6120.326764055951;6351.174347334817;6366.267879193952;6374.936250637527;6378.617282963037;5832.154337621021;5949.524038076944;6021.939780602225;6042.758289668452;6066.241719785027;6978.162620485473;7029.894551581628;7036.507444478837;7058.894116588152;7131.572105673677;6842.110525631636;6864.588124712333;6869.175698916162;6876.5186436950935;6895.138107736533;6767;6901.723931042702;6953.582785032914;6989.7553585624255;7027.824304392431;6680.2651708313015;6696.7656132035145;6735.158105236846;6751.156105486814;6758.357955994173;6861.142357205349;6865.588064716133;6868.759593414206;6887.760203163479;6906.930930690629;6437.034444833125;6509.1863517060365;6542.084108224757;6623.867522649616;6632.071509988702;6554.672266386567;6635.834104147435;6646.767363142267;6652.467802575686;6658.300878407697;6379.872402552016;6474.805755827333;6482.189726284215;6495.740170393131;6507;6531.183602049744;6551.672416379257;6594.274629790623;6599.274079851309;6687.6321802301345;6389.265234497906;6426.036094585663;6464.612123272619;6470.611763294218;6480.740770369321 10:08:14 INFO - PID 1639 | |1;ext.html;789.2502122983163;830.2486249943801;845.8773477845713;1269.695273134448;1333.7132516508948;40710.59289407105;41260.55588054415;41286.58713412865;41330.793346033264;41361.58638413615;41562.58437415625;41826;41876.581234187724;41881.58118418815;41924.371134432935;7872.488288261274;8319.792005199888;8477.321814254845;8549.230569248766;8554.572271386436;8389.119142490012;8484.109168537307;8491.15088491149;8496.702615408461;8504.702335418262;8433.28317093048;8461.873071903927;8509.74470765878;8510.957445212765;8522.190391912758;8451.239388455038;8458.492490450593;8488.363372747008;8509.276711479532;8533.189347012023;8132.390070744658;8160.469569478024;8224.465409748347;8268;8301.086880443147;32015;38724;38745.837624871296;38902.61097389018;39100.41349379761;3612.440071788877;3651.1967367179177;3656.0859785053735;3656.542932133483;3666.7433279670436;31072.533911991337;31362.372752544823;31373.686263137533;31424;31461.74153033876;3080.27613510827;3106.409782141386;3120.3135310231714;3122.3911337289237;3125.4530457169903;247591;253754;277136;277275.6136219306;290297.09702903125;39641.60358396437;49141;49203.753981229864;49227;49237.753811230716;50204.49795501998;50986.49013509892;51335.229971550456;51669;51676;14600.780988285183;14636.902232332617;14681.458446862925;14692;14714.411423542939;11697.2981621103;11781.646550603324;11797.056235501139;11797.705057373634;11814.704632384259;13990.230537320545;14003.789943150861;14019.878409727198;14034.94737894662;14039.157650541;5371.75827087782;5403.648762830403;6120.66336351496;6126.479249263773;6186.628802271878;996.3225006995168;1018.6689325968941;1025.9846102308472;1050.4590136079926;1059.3167407022581;23873.761262387503;25139.74860251374;25147;25166;25190.874045629655;1106.690126764507;1108.9611863584735;1142.0635079234949;1145.7021174494691;1152.948117334722;12579.685507862378;12685.302308373128;12832.652571480046;12855.29295888735;12874.871251287368;309423;309515;310124;310895;311175;56345;56591;56776;56921.715391422775;56981.86036279251;1504.5787179589731;1560.0327796766078;1562.4219038955432;1566.1464501846522;1570.5602431319248;24337.51324973526;24534.263972080866;24557.017719291744;24587.13945011916;24626.630600540648;38694.80652596719;38744.22511549697;38755.80622096871;38777.806110969264;38872.6112738869;267647.66176168993;273982;279527;281278;281353;38159.85520434391;38436.42345364878;38500;38532.036699082746;38629.613703863724;46314.76842615765;46749;47501.76249118732;48459.75770121127;48814;30888.536671950387;32644.83677581597;33156.66843331632;33187;33581.664183357854;28419.174069316156;32319.676803231665;32744;32808.67191328056;33364.83317583396;38383.84848454551;39039.024024399616;39848.80075599603;40001.59998400095;40007;32301.838490807397;32651.510227346134;32653.673463266012;33169.50245746267;34052.65947340594;24403.38991525226;25342.113026044;25518.2344529657;25644;25888.352791180372 10:08:14 INFO - PID 1639 | |2;jquery.html;427.7668670574537;427.81176282435706;437.77017066040315;444.2093074327696;449.99325010124846;22040.33878983631;22697.773022269772;22703.43241418961;22734.976926038325;22869.199578014774;20187.899060504715;23873.88063059682;24845.881935312907;24990.12534561286;25003.37491562712;6239.376062393759;6347.61914285142;6387.297397286295;6388.562573420966;6395.904061439082;6282.4974002079725;6296.559240853142;6325.493960483173;6327.588706734047;6344.714487848058;5868.706564671771;6261.405166509175;6264.21697287839;6281.277653069887;6295.2760432550385;6075.17985072014;6098.69506524672;6157.1995640566665;6162.599431036968;6175.814725558241;5463.48096930791;5468.835934921919;5485.807996720096;5487.64330318532;5490.533304669108;29403.558946615816;29412.26469338262;29448.70551294481;29765.958191463196;29845.552316715268;2135.6903249028805;2149.516358819253;2193.5941850757663;2252.977470225293;2270.795628393436;18892;19168.329108481135;19176.137073831713;19216.71174932377;19223;2139.9251026214165;2189.6058709432295;2207.735071791379;2215.1471683401846;2219.9001044953015;158106.41893581033;165886;186971.6514174286;197492;206796;13004.21974681522;13266.53567125146;13286.734265314833;13321.400536975665;13475.98930080248;14889.329980150918;16600.00399975981;16981.490555283115;17037.14814259282;17112.57218569546;7670.961645191738;8994.685186018458;9279;9282.582283797245;9301.860472092923;8901;8983.146601072887;9001.18989290967;9001.864972025425;9040.36017689401;7686.46194766361;8233.711820086268;8485.193906578865;8660.653573856975;8679.566021698889;2262.162999690125;2278.6809846621486;2279.9202027929273;2293.6330187169974;2466.161505088243;922.5110691333604;933.2580598424269;955.4028732042474;965.2519297544459;968.2641192693603;21031.789682102983;21548.0303386348;21588.352349429228;21645.783542164692;21877.562448750938;903.2548147778109;982.2878413150552;985.5318723606373;1016.2428990402198;1018.4754851251612;8462.746117616482;11243.718907027389;11329.263597866113;11349.716257093638;11490.368029758443;553336.4666353365;554545;558298;563655.0893174126;567247.1637641785;154288;158594;159324;159443;161771.38228617562;1005.3867141043958;1131.2364154195884;1181.4092953523239;1205.73473835756;1210.1105687319814;22253;23531;23799.762002379754;23842.642360364956;23921.760782391953;62639.42286059898;64814.35185648272;65362;67508;67994.32005680077;60516.39483605108;76506.32227871998;88636.5568172155;89214.55392722995;89376.10623893677;7999.240072193133;10139.239557033105;10157.898421015696;10477.37135771856;10556.788864222519;18169.545761356072;18445;18516.907415462836;18528.536786580444;18700.625987480445;14862.628434288798;15048.849511504744;15124.092554446768;15280.235988200324;15352.846471535588;13590.93204533971;13643.79534307006;13864;13881.305934703023;13912.095713778572;14814.77777833355;15238.238088095773;15421.537353879401;15599.376024958874;15599.922000389925;9835.60657573689;10380.896191037993;10415.739606509595;10452.268341216042;10476.109530689977;14749.262536873328;14868.182249976355;15082.321295541946;15222.695546089237;15262.3895044197 10:08:14 INFO - PID 1639 | |3;mootools.html;1208.2267348896708;1313.113648287406;1368.9520866769665;1374.3677908162244;1380.7987051265397;11728.237664551798;11826.94086529566;12206.69483262919;12228.266304021765;12300.20048696834;11782.528698852035;11803.586874459395;11837.7632447351;11978.640640780768;11987.160898737093;2527.8736063196857;2563;2563.8718064096815;2566.1659960512816;2571.3828681116493;2504.511620234055;2542.5169217848643;2544.9745502545015;2557.693076830783;2560.4495033567778;2498.7626175513296;2532.025170309426;2535.5943049112147;2540.428403609182;2548.2737419835353;2486.664300319461;2509.473010667762;2512.1207577347964;2513.56012697779;2520.1179587144443;2476.925692229236;2494.6133349330885;2495.4884248728968;2576.433184699363;2579.6130580413087;10521.684349469528;11102.944485277603;11761.647150585497;11832.230904991147;11988.700282493008;6468.547201695883;6628.270890202074;6699.129113215299;6727.461803055744;6744;4072.552019277877;4243.363495475673;4289.828406863721;4311.202427550913;4314.137172565468;1191.6186820217533;1222.0773316146342;1250.06245316013;1250.2560976219136;1254.9960031974474;15284.465043723527;15749.999999999885;16535.586610334718;16681.08254046027;16747.16264186798;4369.628581570539;5508.614396992252;5702.7433765480655;5718.199452076713;5779.768809247667;12203.084768642388;12287.262764234174;12295.200811947196;12327.815082773766;12337.951274141798;6354.983202687547;6431.517636177306;6441;6450.193725784277;6459.709313080923;5935.347111817697;6088.634681919099;6090.969545152335;6097.420745029215;6102.481289090477;4326.567343265649;4340.631046361032;4359.062801497693;4369.191699535596;4444.288913773846;1230.7846126927752;1247.1457051919465;1258.7230809221955;1263.040089531944;1267.0940277701488;668.2148475541317;686.4919959230134;747.4431548496405;753.8165080823055;777.7555910543085;6356.713947872357;6378.617282963037;6476.028595710681;6504.707288172043;6710.798676039727;598.6168851934765;614.0359635372512;934.4907025671025;979.9657012004545;999.2156157416539;3648.5074514940675;4427.738094643011;4763.071201115818;4765.9761701191965;4908.803647854117;14897.627559311104;15106.39574417011;15245.313960871787;15381.846181538041;15438.691226175413;15034.323455444313;15224.086554806747;15231.16228607415;15235.771463428058;15256.847431525543;8607.784805379915;8699.695510657097;8775.517346545874;8775.956120219485;8819.779505512286;6210.472109870711;7609.154422535438;7673.577953212627;7783.260590243918;7810;10797.406142662228;11071.948164924319;12733.80899286493;12918.483260669844;13766.65583360424;12209.511619535118;13932.024758266823;14036;14037.087589306662;14316.85683143197;8778.209961103532;8921.910780892367;8926.196642302224;8933.731988040368;8959.462432254086;10531.420771857622;10560.577576897145;10639.680809575726;10699.518521666389;10804.135669146606;9189.035151309015;9239.307051971264;9296.72109836706;9308.627654893999;9367.016463271531;7662.348700360531;9067.637294508146;9086.45481271126;9129.224015958718;9147.222486088756;8288.627011784605;8424.157584241526;8437.578121094044;8443.915560844312;8453.788655283666;6389.201349831271;6422.293547709842;6483.610983341015;6521.380468855451;6596.60420374779;6409.461729185055;6503.577267477599;6604.4056034955165;6663.666816659244;6819.931800681929 10:08:14 INFO - PID 1639 | |4;prototype.html;390.3130490337008;391.50865663592185;400.9979950100249;403.51981142440496;409.4124930724409;5959.135925290836;6208.689565521729;6314.210723659543;6347.333529979355;6399.752048350573;5861.2087368205275;6114.419130182626;6196.194494715681;6223.782167624134;6255.593386429879;4184.351425529033;4194.8254488743205;4578.244589642705;4585.3122031695175;4587.954120458803;4572.702774319675;4577.244754615484;4590.242609969351;4604.963883126286;4632.420947381577;3955.3473676843287;4528.9547104528865;4537.228671125917;4553.180427523044;4557.293619488984;3823.5985221551614;3988.723608445299;4495.920978965058;4569.520200378945;4588.151192029485;3394.1684287349512;3846.807659617008;3931.506027709481;3946;3947.6644485218503;5358.517733403974;6194.070889366586;6198.101275315054;6201.720922558496;6215.81352559424;5449.809256675997;5455.872209838166;5461.344638643349;5476.91784623231;5489.972550137224;6674.3325667433355;6684.16447944007;6698.655470585305;6724;6760.18877734675;4459.152760975404;4469.418975533193;4472.373867658531;4485.2599321111975;4489.685721999462;6523.510736694767;6772.8306792330095;6932.202796678396;7025.332593403618;7114.324139206767;4182.849716328034;4450.332450132506;4517;4537.773111344485;4540.341650460665;4021.678265738734;4174.20690068886;4281.935770963438;4387.727559007852;4631.444226692818;5264.842054738364;5402.324709411324;5425.891482170335;5430.70131142791;5440.4287549807495;6445.484361251089;6636.435902948304;6701.1623547056615;6738.258791532928;6744.763933262312;1513.735096358155;1569.7566877134063;1573.0640269039902;1577.928993195309;1579.2735341742698;889.5552223888056;906.6554709210458;1039.954845380388;1049.4700176411015;1050.2438244463974;6042.486388656926;6089.725962331706;6271.686415679198;6301.96849015752;6322.956712142537;6153.446189842935;6904.3095690430555;6946.2011868635755;6949.509887189165;6966.094407726963;5780.248567686174;6254.781082662085;6275.937240627627;6281.309056003836;6286.52851036165;3559.1635965548076;3617.6744093031753;3641.2353405785084;3641.5448068991377;3642.617525159873;7139.89290160648;7141.464390170758;7160.641967901584;7171.856562868714;7189.856202875913;7143.535670181525;7149.285071492809;7161.355478007004;7166.498345115792;7168.677409516585;6118.908216376758;6305.369463053658;7151.928480715334;7186.712531498786;7204.3155900189395;5843.795467158629;6947.826304342432;6987.8253043672285;6989.650517474208;6998.755043573654;6054.455099041107;6402.935970640234;6421.596553826479;6435.066915297162;6740.157480314961;5491.643043202179;5740.827775166752;5938.495227905676;6796.320367963164;7217.783466495804;3954.031262340745;4552.294394368878;4556.863294101182;4558.452985641744;4586.88532786683;5656.632318899423;5851.941480585139;6168.321484636776;6445.6454894981225;6941.930580694128;6181.938180618316;6731.663416829041;6735.865282694221;6753;6755.223149337692;5875.73559189846;6122.755089796537;6684.565503242274;6701.262861085374;6708.960111182774;5599.944000560105;6443.936750436125;6532.967335163484;6550.737970481127;6591.967040164768;6624.8343791405605;6670.166229221348;6728.596284222962;6729.697163627747;6734.474572035487;5361.865953351197;5501.394846566955;6138.202033735586;6467.805965821033;6590.209174899042 10:08:14 INFO - PID 1639 | |5;yui.html;816.6937398475571;821.0516853034744;830.3523251863545;859.0679113162221;861.9698310559131;16522;16763;16783.83216167838;16995.83004169958;17007.65984680307;15201.239938003111;15580.220988950563;16110.7583386249;16272.75590866135;16396.016239025666;978.9265805064613;986.4968865878382;1000.9549570269355;1010.656376831877;1011.4437059617218;989.5645915797027;1011.0496133634397;1012.5494155100984;1015.9898401016006;1016.9491525423737;1010.3382284603597;1051.9000694933968;1052.7420781908443;1054.4727636181908;1055.7255113670462;1044.4046893270838;1044.5143008501084;1046.1473898772476;1049.0768124050787;1050.217587897018;753.7663324369463;756.8599809035346;776.3556248313943;777.3470284960661;783.3028604541962;17358.479245622653;17374.391896283694;17409.912950435293;17589.912050439794;17708.557286067822;17509.474715758548;17554.561135971704;17983.280668773234;18001.18994645244;18058.90970545152;12392.194507357082;14377.424903003868;14731.263436828223;14769.778453323208;14771.778423323658;3607.4228123500116;3637.254362855608;3641.2717456508803;3643.6902863256655;3850.0759817643566;113015.86984130136;137479;138787.30606347005;139331;140070;13504.662383440296;13882.09766365183;14008;14125.364358603887;14130.364133614012;23898.52202955931;23900;24450.633240501407;24585.508289834102;24639.630405543932;7845.293923546908;7858.646360913773;8024.839503209903;8049.913261709594;8142.796430089295;3470.774399664014;3516.0330908999717;3525.7884526928465;3528.770629909048;3529.2059286660296;4414.602685758297;4455.910881782346;4501.594856462934;4509.413776209138;4531.932021019687;872.4285592936636;961.7643677298967;975.9069841777255;1003.4932359158578;1020.7295066807302;654.2606854254757;664.4285914113858;672.4821887146869;679.1544527063837;683.1631251716707;11592;12049.216800907912;12133.69665758363;12313.507459701512;12563.937180314222;610.7068607068632;645.6061802300593;655.6918248423328;659.6404959297195;667.2493444874516;5591.7483713233005;5633.211350410946;5666.801661941812;5833.241678581757;5939.46544810969;109846.25384365294;124535;131872;132444;133648;102695.4865225669;110007.44996274967;110631;112984;114531.42734286275;97202.51398742961;97840;99490;100309.49845250727;101737.49131254296;10829.67510974672;11517.942410287895;13071.281079540717;13129.60611181666;13149.802752958905;42161.36757948572;45526.04080734742;45624.31563526616;46155.53844461512;47989;71728;88842.55578722066;89043.99302031554;90140.54929725309;90872;17471.213795378982;17546.210420531363;17705.468835934942;17712.822871771117;17755.822441775417;18553.907230463763;18809.623807524047;19242.7113593299;19302.806971930102;19469.610607788047;17286.913565432093;17910.373136940143;18111.27554897842;18565.25738970426;18568.721469178243;16492.25784839709;17217.483475495756;17283.395081172097;17371.82628173702;17371.826281737525;16938.915305423394;17061.48815535536;17458.650826983645;17949;18063.367782127563;11863.228890122115;11888;11955.880441195824;11961.401929903644;12568.622941311774;12338.259704417764;13047.412866420844;13727.588172354845;13770.173789572658;13832.792508112185 10:08:14 INFO - PID 1639 | __end_tp_report 10:08:14 INFO - PID 1639 | __start_cc_report 10:08:14 INFO - PID 1639 | _x_x_mozilla_cycle_collect,461 10:08:14 INFO - PID 1639 | __end_cc_report 10:08:14 INFO - PID 1639 | __startTimestamp1496423294841__endTimestamp 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | ------- Summary: start ------- 10:08:14 INFO - PID 1639 | Number of tests: 6 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6200.47 Median:6426.04 stddev:778.82 (12.1%) stddev-sans-first:738.99 10:08:14 INFO - PID 1639 | Values: 2975.9 3169.4 3632.4 3658.3 3668.1 4588.4 6153.9 6156.8 6231.7 6278.3 6196.4 6217.6 6219.3 6268.5 6273.4 5281.7 5433.6 5459.0 5464.1 5569.1 5575.0 5578.8 5579.4 5585.9 5589.9 5375.4 5432.8 5462.8 5499.2 5507.7 4504.9 5478.7 5521.3 5521.8 5531.2 5412.2 5434.9 5466.1 5481.3 5509.0 6146.6 6151.8 6236.5 6264.0 6268.1 6623.1 6734.9 6754.5 6756.7 6759.0 6860.8 6862.0 6866.6 6870.9 6905.8 6274.2 6330.0 6340.6 6363.1 6386.1 6803.3 6837.0 6948.9 6953.0 6967.8 6398.4 6566.1 6587.1 6636.9 6642.0 6833.9 6850.1 6852.2 6852.5 6852.6 5498.4 6389.9 6695.0 6719.2 6737.8 5548.9 6520.8 6594.8 6696.4 6714.5 5300.4 5346.6 5360.8 5367.6 5397.5 4786.0 4788.4 4802.4 4806.4 4863.7 6120.5 6316.2 6330.5 6332.1 6358.7 5696.8 6872.8 6881.3 6891.6 6895.1 6120.3 6351.2 6366.3 6374.9 6378.6 5832.2 5949.5 6021.9 6042.8 6066.2 6978.2 7029.9 7036.5 7058.9 7131.6 6842.1 6864.6 6869.2 6876.5 6895.1 6767.0 6901.7 6953.6 6989.8 7027.8 6680.3 6696.8 6735.2 6751.2 6758.4 6861.1 6865.6 6868.8 6887.8 6906.9 6437.0 6509.2 6542.1 6623.9 6632.1 6554.7 6635.8 6646.8 6652.5 6658.3 6379.9 6474.8 6482.2 6495.7 6507.0 6531.2 6551.7 6594.3 6599.3 6687.6 6389.3 6426.0 6464.6 6470.6 6480.7 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:44789.55 Median:33817.16 stddev:74795.75 (221.2%) stddev-sans-first:74932.61 10:08:14 INFO - PID 1639 | Values: 789.3 830.2 845.9 1269.7 1333.7 40710.6 41260.6 41286.6 41330.8 41361.6 41562.6 41826.0 41876.6 41881.6 41924.4 7872.5 8319.8 8477.3 8549.2 8554.6 8389.1 8484.1 8491.2 8496.7 8504.7 8433.3 8461.9 8509.7 8511.0 8522.2 8451.2 8458.5 8488.4 8509.3 8533.2 8132.4 8160.5 8224.5 8268.0 8301.1 32015.0 38724.0 38745.8 38902.6 39100.4 3612.4 3651.2 3656.1 3656.5 3666.7 31072.5 31362.4 31373.7 31424.0 31461.7 3080.3 3106.4 3120.3 3122.4 3125.5 247591.0 253754.0 277136.0 277275.6 290297.1 39641.6 49141.0 49203.8 49227.0 49237.8 50204.5 50986.5 51335.2 51669.0 51676.0 14600.8 14636.9 14681.5 14692.0 14714.4 11697.3 11781.6 11797.1 11797.7 11814.7 13990.2 14003.8 14019.9 14034.9 14039.2 5371.8 5403.6 6120.7 6126.5 6186.6 996.3 1018.7 1026.0 1050.5 1059.3 23873.8 25139.7 25147.0 25166.0 25190.9 1106.7 1109.0 1142.1 1145.7 1152.9 12579.7 12685.3 12832.7 12855.3 12874.9 309423.0 309515.0 310124.0 310895.0 311175.0 56345.0 56591.0 56776.0 56921.7 56981.9 1504.6 1560.0 1562.4 1566.1 1570.6 24337.5 24534.3 24557.0 24587.1 24626.6 38694.8 38744.2 38755.8 38777.8 38872.6 267647.7 273982.0 279527.0 281278.0 281353.0 38159.9 38436.4 38500.0 38532.0 38629.6 46314.8 46749.0 47501.8 48459.8 48814.0 30888.5 32644.8 33156.7 33187.0 33581.7 28419.2 32319.7 32744.0 32808.7 33364.8 38383.8 39039.0 39848.8 40001.6 40007.0 32301.8 32651.5 32653.7 33169.5 34052.7 24403.4 25342.1 25518.2 25644.0 25888.4 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:38700.47 Median:12506.83 stddev:96710.68 (773.3%) stddev-sans-first:96939.28 10:08:14 INFO - PID 1639 | Values: 427.8 427.8 437.8 444.2 450.0 22040.3 22697.8 22703.4 22735.0 22869.2 20187.9 23873.9 24845.9 24990.1 25003.4 6239.4 6347.6 6387.3 6388.6 6395.9 6282.5 6296.6 6325.5 6327.6 6344.7 5868.7 6261.4 6264.2 6281.3 6295.3 6075.2 6098.7 6157.2 6162.6 6175.8 5463.5 5468.8 5485.8 5487.6 5490.5 29403.6 29412.3 29448.7 29766.0 29845.6 2135.7 2149.5 2193.6 2253.0 2270.8 18892.0 19168.3 19176.1 19216.7 19223.0 2139.9 2189.6 2207.7 2215.1 2219.9 158106.4 165886.0 186971.7 197492.0 206796.0 13004.2 13266.5 13286.7 13321.4 13476.0 14889.3 16600.0 16981.5 17037.1 17112.6 7671.0 8994.7 9279.0 9282.6 9301.9 8901.0 8983.1 9001.2 9001.9 9040.4 7686.5 8233.7 8485.2 8660.7 8679.6 2262.2 2278.7 2279.9 2293.6 2466.2 922.5 933.3 955.4 965.3 968.3 21031.8 21548.0 21588.4 21645.8 21877.6 903.3 982.3 985.5 1016.2 1018.5 8462.7 11243.7 11329.3 11349.7 11490.4 553336.5 554545.0 558298.0 563655.1 567247.2 154288.0 158594.0 159324.0 159443.0 161771.4 1005.4 1131.2 1181.4 1205.7 1210.1 22253.0 23531.0 23799.8 23842.6 23921.8 62639.4 64814.4 65362.0 67508.0 67994.3 60516.4 76506.3 88636.6 89214.6 89376.1 7999.2 10139.2 10157.9 10477.4 10556.8 18169.5 18445.0 18516.9 18528.5 18700.6 14862.6 15048.8 15124.1 15280.2 15352.8 13590.9 13643.8 13864.0 13881.3 13912.1 14814.8 15238.2 15421.5 15599.4 15599.9 9835.6 10380.9 10415.7 10452.3 10476.1 14749.3 14868.2 15082.3 15222.7 15262.4 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7159.22 Median:3860.53 stddev:4495.81 (116.5%) stddev-sans-first:4486.18 10:08:14 INFO - PID 1639 | Values: 1208.2 1313.1 1369.0 1374.4 1380.8 11728.2 11826.9 12206.7 12228.3 12300.2 11782.5 11803.6 11837.8 11978.6 11987.2 2527.9 2563.0 2563.9 2566.2 2571.4 2504.5 2542.5 2545.0 2557.7 2560.4 2498.8 2532.0 2535.6 2540.4 2548.3 2486.7 2509.5 2512.1 2513.6 2520.1 2476.9 2494.6 2495.5 2576.4 2579.6 10521.7 11102.9 11761.6 11832.2 11988.7 6468.5 6628.3 6699.1 6727.5 6744.0 4072.6 4243.4 4289.8 4311.2 4314.1 1191.6 1222.1 1250.1 1250.3 1255.0 15284.5 15750.0 16535.6 16681.1 16747.2 4369.6 5508.6 5702.7 5718.2 5779.8 12203.1 12287.3 12295.2 12327.8 12338.0 6355.0 6431.5 6441.0 6450.2 6459.7 5935.3 6088.6 6091.0 6097.4 6102.5 4326.6 4340.6 4359.1 4369.2 4444.3 1230.8 1247.1 1258.7 1263.0 1267.1 668.2 686.5 747.4 753.8 777.8 6356.7 6378.6 6476.0 6504.7 6710.8 598.6 614.0 934.5 980.0 999.2 3648.5 4427.7 4763.1 4766.0 4908.8 14897.6 15106.4 15245.3 15381.8 15438.7 15034.3 15224.1 15231.2 15235.8 15256.8 8607.8 8699.7 8775.5 8776.0 8819.8 6210.5 7609.2 7673.6 7783.3 7810.0 10797.4 11071.9 12733.8 12918.5 13766.7 12209.5 13932.0 14036.0 14037.1 14316.9 8778.2 8921.9 8926.2 8933.7 8959.5 10531.4 10560.6 10639.7 10699.5 10804.1 9189.0 9239.3 9296.7 9308.6 9367.0 7662.3 9067.6 9086.5 9129.2 9147.2 8288.6 8424.2 8437.6 8443.9 8453.8 6389.2 6422.3 6483.6 6521.4 6596.6 6409.5 6503.6 6604.4 6663.7 6819.9 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5369.05 Median:6116.66 stddev:1696.82 (27.7%) stddev-sans-first:1659.92 10:08:14 INFO - PID 1639 | Values: 390.3 391.5 401.0 403.5 409.4 5959.1 6208.7 6314.2 6347.3 6399.8 5861.2 6114.4 6196.2 6223.8 6255.6 4184.4 4194.8 4578.2 4585.3 4588.0 4572.7 4577.2 4590.2 4605.0 4632.4 3955.3 4529.0 4537.2 4553.2 4557.3 3823.6 3988.7 4495.9 4569.5 4588.2 3394.2 3846.8 3931.5 3946.0 3947.7 5358.5 6194.1 6198.1 6201.7 6215.8 5449.8 5455.9 5461.3 5476.9 5490.0 6674.3 6684.2 6698.7 6724.0 6760.2 4459.2 4469.4 4472.4 4485.3 4489.7 6523.5 6772.8 6932.2 7025.3 7114.3 4182.8 4450.3 4517.0 4537.8 4540.3 4021.7 4174.2 4281.9 4387.7 4631.4 5264.8 5402.3 5425.9 5430.7 5440.4 6445.5 6636.4 6701.2 6738.3 6744.8 1513.7 1569.8 1573.1 1577.9 1579.3 889.6 906.7 1040.0 1049.5 1050.2 6042.5 6089.7 6271.7 6302.0 6323.0 6153.4 6904.3 6946.2 6949.5 6966.1 5780.2 6254.8 6275.9 6281.3 6286.5 3559.2 3617.7 3641.2 3641.5 3642.6 7139.9 7141.5 7160.6 7171.9 7189.9 7143.5 7149.3 7161.4 7166.5 7168.7 6118.9 6305.4 7151.9 7186.7 7204.3 5843.8 6947.8 6987.8 6989.7 6998.8 6054.5 6402.9 6421.6 6435.1 6740.2 5491.6 5740.8 5938.5 6796.3 7217.8 3954.0 4552.3 4556.9 4558.5 4586.9 5656.6 5851.9 6168.3 6445.6 6941.9 6181.9 6731.7 6735.9 6753.0 6755.2 5875.7 6122.8 6684.6 6701.3 6709.0 5599.9 6443.9 6533.0 6550.7 6592.0 6624.8 6670.2 6728.6 6729.7 6734.5 5361.9 5501.4 6138.2 6467.8 6590.2 10:08:14 INFO - PID 1639 | 10:08:14 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:24846.23 Median:17550.39 stddev:36590.09 (208.5%) stddev-sans-first:36648.25 10:08:14 INFO - PID 1639 | Values: 816.7 821.1 830.4 859.1 862.0 16522.0 16763.0 16783.8 16995.8 17007.7 15201.2 15580.2 16110.8 16272.8 16396.0 978.9 986.5 1001.0 1010.7 1011.4 989.6 1011.0 1012.5 1016.0 1016.9 1010.3 1051.9 1052.7 1054.5 1055.7 1044.4 1044.5 1046.1 1049.1 1050.2 753.8 756.9 776.4 777.3 783.3 17358.5 17374.4 17409.9 17589.9 17708.6 17509.5 17554.6 17983.3 18001.2 18058.9 12392.2 14377.4 14731.3 14769.8 14771.8 3607.4 3637.3 3641.3 3643.7 3850.1 113015.9 137479.0 138787.3 139331.0 140070.0 13504.7 13882.1 14008.0 14125.4 14130.4 23898.5 23900.0 24450.6 24585.5 24639.6 7845.3 7858.6 8024.8 8049.9 8142.8 3470.8 3516.0 3525.8 3528.8 3529.2 4414.6 4455.9 4501.6 4509.4 4531.9 872.4 961.8 975.9 1003.5 1020.7 654.3 664.4 672.5 679.2 683.2 11592.0 12049.2 12133.7 12313.5 12563.9 610.7 645.6 655.7 659.6 667.2 5591.7 5633.2 5666.8 5833.2 5939.5 109846.3 124535.0 131872.0 132444.0 133648.0 102695.5 110007.4 110631.0 112984.0 114531.4 97202.5 97840.0 99490.0 100309.5 101737.5 10829.7 11517.9 13071.3 13129.6 13149.8 42161.4 45526.0 45624.3 46155.5 47989.0 71728.0 88842.6 89044.0 90140.5 90872.0 17471.2 17546.2 17705.5 17712.8 17755.8 18553.9 18809.6 19242.7 19302.8 19469.6 17286.9 17910.4 18111.3 18565.3 18568.7 16492.3 17217.5 17283.4 17371.8 17371.8 16938.9 17061.5 17458.7 17949.0 18063.4 11863.2 11888.0 11955.9 11961.4 12568.6 12338.3 13047.4 13727.6 13770.2 13832.8 10:08:14 INFO - PID 1639 | -------- Summary: end -------- 10:08:14 INFO - PID 1639 | 10:08:15 INFO - TEST-INFO | 1639: exit 0 10:08:15 INFO - TEST-OK | dromaeo_css | took 1143037ms 10:08:15 INFO - TEST-START | kraken 10:08:15 INFO - operating with platform_type : mac_ 10:08:15 INFO - Initialising browser for kraken test... 10:08:15 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49220/getInfo.html -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpFbWUkd/profile 10:08:15 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49220/getInfo.html -foreground) 10:08:49 INFO - TEST-INFO | 1648: killed by SIGTERM 10:08:49 INFO - Browser initialized. 10:08:49 INFO - Running cycle 1/1 for kraken test... 10:08:49 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpFbWUkd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:51 INFO - PID 1652 | 2017-06-02 10:08:51.375 plugin-container[1653:10855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c47, name = 'com.apple.tsm.portname' 10:08:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:51 INFO - PID 1652 | 2017-06-02 10:08:51.376 plugin-container[1653:10855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00f, name = 'com.apple.CFPasteboardClient' 10:08:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:51 INFO - PID 1652 | 2017-06-02 10:08:51.376 plugin-container[1653:10855] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:08:51 INFO - PID 1652 | 2017-06-02 10:08:51.901 plugin-container[1654:10901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 10:08:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:51 INFO - PID 1652 | 2017-06-02 10:08:51.902 plugin-container[1654:10901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 10:08:51 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:51 INFO - PID 1652 | 2017-06-02 10:08:51.902 plugin-container[1654:10901] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:09:01 INFO - PID 1652 | RSS: Main: 268935168 10:09:01 INFO - PID 1652 | 10:09:03 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection) 10:09:04 INFO - PID 1652 | RSS: Main: 276512768 10:09:04 INFO - PID 1652 | 10:09:07 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-dft) 10:09:08 INFO - PID 1652 | RSS: Main: 284274688 10:09:08 INFO - PID 1652 | 10:09:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-fft) 10:09:12 INFO - PID 1652 | RSS: Main: 282509312 10:09:12 INFO - PID 1652 | 10:09:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator) 10:09:15 INFO - PID 1652 | RSS: Main: 285007872 10:09:15 INFO - PID 1652 | 10:09:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:09:17 INFO - PID 1652 | RSS: Main: 285413376 10:09:17 INFO - PID 1652 | 10:09:20 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom) 10:09:21 INFO - PID 1652 | RSS: Main: 292433920 10:09:21 INFO - PID 1652 | 10:09:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate) 10:09:25 INFO - PID 1652 | RSS: Main: 292990976 10:09:25 INFO - PID 1652 | 10:09:28 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial) 10:09:28 INFO - PID 1652 | RSS: Main: 292122624 10:09:28 INFO - PID 1652 | 10:09:30 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:09:30 INFO - PID 1652 | RSS: Main: 292532224 10:09:30 INFO - PID 1652 | 10:09:32 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:09:33 INFO - PID 1652 | RSS: Main: 292356096 10:09:33 INFO - PID 1652 | 10:09:35 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:09:36 INFO - PID 1652 | RSS: Main: 292315136 10:09:36 INFO - PID 1652 | 10:09:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:09:39 INFO - PID 1652 | RSS: Main: 292040704 10:09:39 INFO - PID 1652 | 10:09:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:43 INFO - PID 1652 | RSS: Main: 291913728 10:09:43 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49220/tests/kraken/driver.html?testName=ai-astar) 10:09:45 INFO - PID 1652 | RSS: Main: 293801984 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | __start_tp_report 10:09:45 INFO - PID 1652 | _x_x_mozilla_page_load 10:09:45 INFO - PID 1652 | _x_x_mozilla_page_load_details 10:09:45 INFO - PID 1652 | |i|pagename|runs| 10:09:45 INFO - PID 1652 | |0;ai-astar;102;115;114;114;113;117;116;116;137;138 10:09:45 INFO - PID 1652 | |1;audio-beat-detection;131;113;131;153;111;112;112;112;138;116 10:09:45 INFO - PID 1652 | |2;audio-dft;284;288;212;261;251;241;251;222;204;234 10:09:45 INFO - PID 1652 | |3;audio-fft;80;58;59;87;77;58;48;51;73;50 10:09:45 INFO - PID 1652 | |4;audio-oscillator;88;75;116;102;56;91;103;107;107;103 10:09:45 INFO - PID 1652 | |5;imaging-gaussian-blur;95;88;88;94;89;132;137;111;91;88 10:09:45 INFO - PID 1652 | |6;imaging-darkroom;98;96;96;96;105;96;96;95;98;96 10:09:45 INFO - PID 1652 | |7;imaging-desaturate;91;95;91;105;93;91;92;89;92;90 10:09:45 INFO - PID 1652 | |8;json-parse-financial;50;51;51;53;52;50;51;51;55;52 10:09:45 INFO - PID 1652 | |9;json-stringify-tinderbox;60;64;55;59;54;68;77;85;62;59 10:09:45 INFO - PID 1652 | |10;stanford-crypto-aes;86;87;88;88;89;88;89;93;126;90 10:09:45 INFO - PID 1652 | |11;stanford-crypto-ccm;134;148;131;155;152;160;135;138;135;155 10:09:45 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;189;189;183;192;182;184;191;183;184;174 10:09:45 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;68;68;70;65;71;82;91;82;68;67 10:09:45 INFO - PID 1652 | __end_tp_report 10:09:45 INFO - PID 1652 | __start_cc_report 10:09:45 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1220 10:09:45 INFO - PID 1652 | __end_cc_report 10:09:45 INFO - PID 1652 | __startTimestamp1496423385514__endTimestamp 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | ------- Summary: start ------- 10:09:45 INFO - PID 1652 | Number of tests: 14 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:118.20 Median:116.00 stddev:11.01 (9.5%) stddev-sans-first:10.00 10:09:45 INFO - PID 1652 | Values: 102.0 115.0 114.0 114.0 113.0 117.0 116.0 116.0 137.0 138.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:123.50 stddev:14.56 (11.8%) stddev-sans-first:15.15 10:09:45 INFO - PID 1652 | Values: 131.0 113.0 131.0 153.0 111.0 112.0 112.0 112.0 138.0 116.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:244.80 Median:251.00 stddev:28.15 (11.2%) stddev-sans-first:26.04 10:09:45 INFO - PID 1652 | Values: 284.0 288.0 212.0 261.0 251.0 241.0 251.0 222.0 204.0 234.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:64.10 Median:66.00 stddev:13.96 (21.1%) stddev-sans-first:13.56 10:09:45 INFO - PID 1652 | Values: 80.0 58.0 59.0 87.0 77.0 58.0 48.0 51.0 73.0 50.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:86.00 stddev:17.92 (20.8%) stddev-sans-first:18.84 10:09:45 INFO - PID 1652 | Values: 88.0 75.0 116.0 102.0 56.0 91.0 103.0 107.0 107.0 103.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:101.30 Median:88.50 stddev:18.83 (21.3%) stddev-sans-first:19.84 10:09:45 INFO - PID 1652 | Values: 95.0 88.0 88.0 94.0 89.0 132.0 137.0 111.0 91.0 88.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.00 stddev:2.90 (3.0%) stddev-sans-first:3.06 10:09:45 INFO - PID 1652 | Values: 98.0 96.0 96.0 96.0 105.0 96.0 96.0 95.0 98.0 96.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:91.50 stddev:4.56 (5.0%) stddev-sans-first:4.78 10:09:45 INFO - PID 1652 | Values: 91.0 95.0 91.0 105.0 93.0 91.0 92.0 89.0 92.0 90.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:1.51 (2.9%) stddev-sans-first:1.48 10:09:45 INFO - PID 1652 | Values: 50.0 51.0 51.0 53.0 52.0 50.0 51.0 51.0 55.0 52.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:63.00 stddev:9.87 (15.7%) stddev-sans-first:10.34 10:09:45 INFO - PID 1652 | Values: 60.0 64.0 55.0 59.0 54.0 68.0 77.0 85.0 62.0 59.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:88.50 stddev:11.96 (13.5%) stddev-sans-first:12.45 10:09:45 INFO - PID 1652 | Values: 86.0 87.0 88.0 88.0 89.0 88.0 89.0 93.0 126.0 90.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:150.00 stddev:10.77 (7.2%) stddev-sans-first:10.76 10:09:45 INFO - PID 1652 | Values: 134.0 148.0 131.0 155.0 152.0 160.0 135.0 138.0 135.0 155.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.10 Median:186.50 stddev:5.34 (2.9%) stddev-sans-first:5.48 10:09:45 INFO - PID 1652 | Values: 189.0 189.0 183.0 192.0 182.0 184.0 191.0 183.0 184.0 174.0 10:09:45 INFO - PID 1652 | 10:09:45 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:70.50 stddev:8.65 (12.3%) stddev-sans-first:8.97 10:09:45 INFO - PID 1652 | Values: 68.0 68.0 70.0 65.0 71.0 82.0 91.0 82.0 68.0 67.0 10:09:45 INFO - PID 1652 | -------- Summary: end -------- 10:09:45 INFO - PID 1652 | 10:09:46 INFO - TEST-INFO | 1652: exit 0 10:09:46 INFO - TEST-OK | kraken | took 90939ms 10:09:46 INFO - SUITE-END | took 1233s 10:09:46 INFO - Completed test suite (00:20:34) 10:09:46 INFO - javascript benchmark 10:09:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8121.835843722928, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2975.8958436454727, 3169.397814415261, 3632.3824949758523, 3658.2866341063495, 3668.0646435159047, 4588.403507544023, 6153.876922461559, 6156.81529554113, 6231.688415579226, 6278.2779980302275, 6196.411340922617, 6217.5958562693395, 6219.253689557248, 6268.529860260476, 6273.404026617475, 5281.70950597717, 5433.565314774828, 5458.962797068564, 5464.12573988162, 5569.10894256919, 5575.024370735126, 5578.776848926038, 5579.358373787026, 5585.888282234353, 5589.916151257735, 5375.43557926416, 5432.782688692448, 5462.781488740486, 5499.175123731432, 5507.669539827623, 4504.932426013613, 5478.698671573062, 5521.282233309684, 5521.806736764194, 5531.225628412026, 5412.242286079952, 5434.891302173974, 5466.098093814516, 5481.287432633772, 5509.00837849187, 6146.567404506532, 6151.815445536641, 6236.501079913596, 6263.96868015657, 6268.122462855204, 6623.105880706091, 6734.8989765153565, 6754.493412994045, 6756.729730810811, 6758.966205168992, 6860.833658278105, 6862.039314495974, 6866.622335771531, 6870.8625827483675, 6905.827354316132, 6274.1843560336865, 6329.968350158219, 6340.619562826153, 6363.109164716943, 6386.10594516768, 6803.251642319341, 6837.042814006043, 6948.93051069493, 6952.965235173893, 6967.825804354931, 6398.424141827258, 6566.146400967939, 6587.110740050032, 6636.900446493307, 6642.0, 6833.897491537631, 6850.109485766918, 6852.211995620468, 6852.5203235774015, 6852.588844669336, 5498.395176530578, 6389.936100639027, 6695.029220763059, 6719.227288861745, 6737.831554211184, 5548.861278468071, 6520.836979075561, 6594.802155935329, 6696.397324240842, 6714.462842972551, 5300.443453437333, 5346.599005074635, 5360.839174824683, 5367.570594352443, 5397.514223719884, 4786.042791441726, 4788.4253889533475, 4802.447718512346, 4806.375171227718, 4863.659543831968, 6120.538559592075, 6316.242050953823, 6330.543974885802, 6332.1135041094285, 6358.682065896686, 5696.829095127153, 6872.759453419107, 6881.277465866012, 6891.552049116892, 6895.069165662671, 6120.326764055951, 6351.174347334817, 6366.267879193952, 6374.936250637527, 6378.617282963037, 5832.154337621021, 5949.524038076944, 6021.939780602225, 6042.758289668452, 6066.241719785027, 6978.162620485473, 7029.894551581628, 7036.507444478837, 7058.894116588152, 7131.572105673677, 6842.110525631636, 6864.588124712333, 6869.175698916162, 6876.5186436950935, 6895.138107736533, 6767.0, 6901.723931042702, 6953.582785032914, 6989.7553585624255, 7027.824304392431, 6680.2651708313015, 6696.7656132035145, 6735.158105236846, 6751.156105486814, 6758.357955994173, 6861.142357205349, 6865.588064716133, 6868.759593414206, 6887.760203163479, 6906.930930690629, 6437.034444833125, 6509.1863517060365, 6542.084108224757, 6623.867522649616, 6632.071509988702, 6554.672266386567, 6635.834104147435, 6646.767363142267, 6652.467802575686, 6658.300878407697, 6379.872402552016, 6474.805755827333, 6482.189726284215, 6495.740170393131, 6507.0, 6531.183602049744, 6551.672416379257, 6594.274629790623, 6599.274079851309, 6687.6321802301345, 6389.265234497906, 6426.036094585663, 6464.612123272619, 6470.611763294218, 6480.740770369321], "value": 6145.976790218776, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.2502122983163, 830.2486249943801, 845.8773477845713, 1269.695273134448, 1333.7132516508948, 40710.59289407105, 41260.55588054415, 41286.58713412865, 41330.793346033264, 41361.58638413615, 41562.58437415625, 41826.0, 41876.581234187724, 41881.58118418815, 41924.371134432935, 7872.488288261274, 8319.792005199888, 8477.321814254845, 8549.230569248766, 8554.572271386436, 8389.119142490012, 8484.109168537307, 8491.15088491149, 8496.702615408461, 8504.702335418262, 8433.28317093048, 8461.873071903927, 8509.74470765878, 8510.957445212765, 8522.190391912758, 8451.239388455038, 8458.492490450593, 8488.363372747008, 8509.276711479532, 8533.189347012023, 8132.390070744658, 8160.469569478024, 8224.465409748347, 8268.0, 8301.086880443147, 32015.0, 38724.0, 38745.837624871296, 38902.61097389018, 39100.41349379761, 3612.440071788877, 3651.1967367179177, 3656.0859785053735, 3656.542932133483, 3666.7433279670436, 31072.533911991337, 31362.372752544823, 31373.686263137533, 31424.0, 31461.74153033876, 3080.27613510827, 3106.409782141386, 3120.3135310231714, 3122.3911337289237, 3125.4530457169903, 247591.0, 253754.0, 277136.0, 277275.6136219306, 290297.09702903125, 39641.60358396437, 49141.0, 49203.753981229864, 49227.0, 49237.753811230716, 50204.49795501998, 50986.49013509892, 51335.229971550456, 51669.0, 51676.0, 14600.780988285183, 14636.902232332617, 14681.458446862925, 14692.0, 14714.411423542939, 11697.2981621103, 11781.646550603324, 11797.056235501139, 11797.705057373634, 11814.704632384259, 13990.230537320545, 14003.789943150861, 14019.878409727198, 14034.94737894662, 14039.157650541, 5371.75827087782, 5403.648762830403, 6120.66336351496, 6126.479249263773, 6186.628802271878, 996.3225006995168, 1018.6689325968941, 1025.9846102308472, 1050.4590136079926, 1059.3167407022581, 23873.761262387503, 25139.74860251374, 25147.0, 25166.0, 25190.874045629655, 1106.690126764507, 1108.9611863584735, 1142.0635079234949, 1145.7021174494691, 1152.948117334722, 12579.685507862378, 12685.302308373128, 12832.652571480046, 12855.29295888735, 12874.871251287368, 309423.0, 309515.0, 310124.0, 310895.0, 311175.0, 56345.0, 56591.0, 56776.0, 56921.715391422775, 56981.86036279251, 1504.5787179589731, 1560.0327796766078, 1562.4219038955432, 1566.1464501846522, 1570.5602431319248, 24337.51324973526, 24534.263972080866, 24557.017719291744, 24587.13945011916, 24626.630600540648, 38694.80652596719, 38744.22511549697, 38755.80622096871, 38777.806110969264, 38872.6112738869, 267647.66176168993, 273982.0, 279527.0, 281278.0, 281353.0, 38159.85520434391, 38436.42345364878, 38500.0, 38532.036699082746, 38629.613703863724, 46314.76842615765, 46749.0, 47501.76249118732, 48459.75770121127, 48814.0, 30888.536671950387, 32644.83677581597, 33156.66843331632, 33187.0, 33581.664183357854, 28419.174069316156, 32319.676803231665, 32744.0, 32808.67191328056, 33364.83317583396, 38383.84848454551, 39039.024024399616, 39848.80075599603, 40001.59998400095, 40007.0, 32301.838490807397, 32651.510227346134, 32653.673463266012, 33169.50245746267, 34052.65947340594, 24403.38991525226, 25342.113026044, 25518.2344529657, 25644.0, 25888.352791180372], "value": 17989.030009056023, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.7668670574537, 427.81176282435706, 437.77017066040315, 444.2093074327696, 449.99325010124846, 22040.33878983631, 22697.773022269772, 22703.43241418961, 22734.976926038325, 22869.199578014774, 20187.899060504715, 23873.88063059682, 24845.881935312907, 24990.12534561286, 25003.37491562712, 6239.376062393759, 6347.61914285142, 6387.297397286295, 6388.562573420966, 6395.904061439082, 6282.4974002079725, 6296.559240853142, 6325.493960483173, 6327.588706734047, 6344.714487848058, 5868.706564671771, 6261.405166509175, 6264.21697287839, 6281.277653069887, 6295.2760432550385, 6075.17985072014, 6098.69506524672, 6157.1995640566665, 6162.599431036968, 6175.814725558241, 5463.48096930791, 5468.835934921919, 5485.807996720096, 5487.64330318532, 5490.533304669108, 29403.558946615816, 29412.26469338262, 29448.70551294481, 29765.958191463196, 29845.552316715268, 2135.6903249028805, 2149.516358819253, 2193.5941850757663, 2252.977470225293, 2270.795628393436, 18892.0, 19168.329108481135, 19176.137073831713, 19216.71174932377, 19223.0, 2139.9251026214165, 2189.6058709432295, 2207.735071791379, 2215.1471683401846, 2219.9001044953015, 158106.41893581033, 165886.0, 186971.6514174286, 197492.0, 206796.0, 13004.21974681522, 13266.53567125146, 13286.734265314833, 13321.400536975665, 13475.98930080248, 14889.329980150918, 16600.00399975981, 16981.490555283115, 17037.14814259282, 17112.57218569546, 7670.961645191738, 8994.685186018458, 9279.0, 9282.582283797245, 9301.860472092923, 8901.0, 8983.146601072887, 9001.18989290967, 9001.864972025425, 9040.36017689401, 7686.46194766361, 8233.711820086268, 8485.193906578865, 8660.653573856975, 8679.566021698889, 2262.162999690125, 2278.6809846621486, 2279.9202027929273, 2293.6330187169974, 2466.161505088243, 922.5110691333604, 933.2580598424269, 955.4028732042474, 965.2519297544459, 968.2641192693603, 21031.789682102983, 21548.0303386348, 21588.352349429228, 21645.783542164692, 21877.562448750938, 903.2548147778109, 982.2878413150552, 985.5318723606373, 1016.2428990402198, 1018.4754851251612, 8462.746117616482, 11243.718907027389, 11329.263597866113, 11349.716257093638, 11490.368029758443, 553336.4666353365, 554545.0, 558298.0, 563655.0893174126, 567247.1637641785, 154288.0, 158594.0, 159324.0, 159443.0, 161771.38228617562, 1005.3867141043958, 1131.2364154195884, 1181.4092953523239, 1205.73473835756, 1210.1105687319814, 22253.0, 23531.0, 23799.762002379754, 23842.642360364956, 23921.760782391953, 62639.42286059898, 64814.35185648272, 65362.0, 67508.0, 67994.32005680077, 60516.39483605108, 76506.32227871998, 88636.5568172155, 89214.55392722995, 89376.10623893677, 7999.240072193133, 10139.239557033105, 10157.898421015696, 10477.37135771856, 10556.788864222519, 18169.545761356072, 18445.0, 18516.907415462836, 18528.536786580444, 18700.625987480445, 14862.628434288798, 15048.849511504744, 15124.092554446768, 15280.235988200324, 15352.846471535588, 13590.93204533971, 13643.79534307006, 13864.0, 13881.305934703023, 13912.095713778572, 14814.77777833355, 15238.238088095773, 15421.537353879401, 15599.376024958874, 15599.922000389925, 9835.60657573689, 10380.896191037993, 10415.739606509595, 10452.268341216042, 10476.109530689977, 14749.262536873328, 14868.182249976355, 15082.321295541946, 15222.695546089237, 15262.3895044197], "value": 11306.159189875074, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1208.2267348896708, 1313.113648287406, 1368.9520866769665, 1374.3677908162244, 1380.7987051265397, 11728.237664551798, 11826.94086529566, 12206.69483262919, 12228.266304021765, 12300.20048696834, 11782.528698852035, 11803.586874459395, 11837.7632447351, 11978.640640780768, 11987.160898737093, 2527.8736063196857, 2563.0, 2563.8718064096815, 2566.1659960512816, 2571.3828681116493, 2504.511620234055, 2542.5169217848643, 2544.9745502545015, 2557.693076830783, 2560.4495033567778, 2498.7626175513296, 2532.025170309426, 2535.5943049112147, 2540.428403609182, 2548.2737419835353, 2486.664300319461, 2509.473010667762, 2512.1207577347964, 2513.56012697779, 2520.1179587144443, 2476.925692229236, 2494.6133349330885, 2495.4884248728968, 2576.433184699363, 2579.6130580413087, 10521.684349469528, 11102.944485277603, 11761.647150585497, 11832.230904991147, 11988.700282493008, 6468.547201695883, 6628.270890202074, 6699.129113215299, 6727.461803055744, 6744.0, 4072.552019277877, 4243.363495475673, 4289.828406863721, 4311.202427550913, 4314.137172565468, 1191.6186820217533, 1222.0773316146342, 1250.06245316013, 1250.2560976219136, 1254.9960031974474, 15284.465043723527, 15749.999999999885, 16535.586610334718, 16681.08254046027, 16747.16264186798, 4369.628581570539, 5508.614396992252, 5702.7433765480655, 5718.199452076713, 5779.768809247667, 12203.084768642388, 12287.262764234174, 12295.200811947196, 12327.815082773766, 12337.951274141798, 6354.983202687547, 6431.517636177306, 6441.0, 6450.193725784277, 6459.709313080923, 5935.347111817697, 6088.634681919099, 6090.969545152335, 6097.420745029215, 6102.481289090477, 4326.567343265649, 4340.631046361032, 4359.062801497693, 4369.191699535596, 4444.288913773846, 1230.7846126927752, 1247.1457051919465, 1258.7230809221955, 1263.040089531944, 1267.0940277701488, 668.2148475541317, 686.4919959230134, 747.4431548496405, 753.8165080823055, 777.7555910543085, 6356.713947872357, 6378.617282963037, 6476.028595710681, 6504.707288172043, 6710.798676039727, 598.6168851934765, 614.0359635372512, 934.4907025671025, 979.9657012004545, 999.2156157416539, 3648.5074514940675, 4427.738094643011, 4763.071201115818, 4765.9761701191965, 4908.803647854117, 14897.627559311104, 15106.39574417011, 15245.313960871787, 15381.846181538041, 15438.691226175413, 15034.323455444313, 15224.086554806747, 15231.16228607415, 15235.771463428058, 15256.847431525543, 8607.784805379915, 8699.695510657097, 8775.517346545874, 8775.956120219485, 8819.779505512286, 6210.472109870711, 7609.154422535438, 7673.577953212627, 7783.260590243918, 7810.0, 10797.406142662228, 11071.948164924319, 12733.80899286493, 12918.483260669844, 13766.65583360424, 12209.511619535118, 13932.024758266823, 14036.0, 14037.087589306662, 14316.85683143197, 8778.209961103532, 8921.910780892367, 8926.196642302224, 8933.731988040368, 8959.462432254086, 10531.420771857622, 10560.577576897145, 10639.680809575726, 10699.518521666389, 10804.135669146606, 9189.035151309015, 9239.307051971264, 9296.72109836706, 9308.627654893999, 9367.016463271531, 7662.348700360531, 9067.637294508146, 9086.45481271126, 9129.224015958718, 9147.222486088756, 8288.627011784605, 8424.157584241526, 8437.578121094044, 8443.915560844312, 8453.788655283666, 6389.201349831271, 6422.293547709842, 6483.610983341015, 6521.380468855451, 6596.60420374779, 6409.461729185055, 6503.577267477599, 6604.4056034955165, 6663.666816659244, 6819.931800681929], "value": 5395.0491859305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.3130490337008, 391.50865663592185, 400.9979950100249, 403.51981142440496, 409.4124930724409, 5959.135925290836, 6208.689565521729, 6314.210723659543, 6347.333529979355, 6399.752048350573, 5861.2087368205275, 6114.419130182626, 6196.194494715681, 6223.782167624134, 6255.593386429879, 4184.351425529033, 4194.8254488743205, 4578.244589642705, 4585.3122031695175, 4587.954120458803, 4572.702774319675, 4577.244754615484, 4590.242609969351, 4604.963883126286, 4632.420947381577, 3955.3473676843287, 4528.9547104528865, 4537.228671125917, 4553.180427523044, 4557.293619488984, 3823.5985221551614, 3988.723608445299, 4495.920978965058, 4569.520200378945, 4588.151192029485, 3394.1684287349512, 3846.807659617008, 3931.506027709481, 3946.0, 3947.6644485218503, 5358.517733403974, 6194.070889366586, 6198.101275315054, 6201.720922558496, 6215.81352559424, 5449.809256675997, 5455.872209838166, 5461.344638643349, 5476.91784623231, 5489.972550137224, 6674.3325667433355, 6684.16447944007, 6698.655470585305, 6724.0, 6760.18877734675, 4459.152760975404, 4469.418975533193, 4472.373867658531, 4485.2599321111975, 4489.685721999462, 6523.510736694767, 6772.8306792330095, 6932.202796678396, 7025.332593403618, 7114.324139206767, 4182.849716328034, 4450.332450132506, 4517.0, 4537.773111344485, 4540.341650460665, 4021.678265738734, 4174.20690068886, 4281.935770963438, 4387.727559007852, 4631.444226692818, 5264.842054738364, 5402.324709411324, 5425.891482170335, 5430.70131142791, 5440.4287549807495, 6445.484361251089, 6636.435902948304, 6701.1623547056615, 6738.258791532928, 6744.763933262312, 1513.735096358155, 1569.7566877134063, 1573.0640269039902, 1577.928993195309, 1579.2735341742698, 889.5552223888056, 906.6554709210458, 1039.954845380388, 1049.4700176411015, 1050.2438244463974, 6042.486388656926, 6089.725962331706, 6271.686415679198, 6301.96849015752, 6322.956712142537, 6153.446189842935, 6904.3095690430555, 6946.2011868635755, 6949.509887189165, 6966.094407726963, 5780.248567686174, 6254.781082662085, 6275.937240627627, 6281.309056003836, 6286.52851036165, 3559.1635965548076, 3617.6744093031753, 3641.2353405785084, 3641.5448068991377, 3642.617525159873, 7139.89290160648, 7141.464390170758, 7160.641967901584, 7171.856562868714, 7189.856202875913, 7143.535670181525, 7149.285071492809, 7161.355478007004, 7166.498345115792, 7168.677409516585, 6118.908216376758, 6305.369463053658, 7151.928480715334, 7186.712531498786, 7204.3155900189395, 5843.795467158629, 6947.826304342432, 6987.8253043672285, 6989.650517474208, 6998.755043573654, 6054.455099041107, 6402.935970640234, 6421.596553826479, 6435.066915297162, 6740.157480314961, 5491.643043202179, 5740.827775166752, 5938.495227905676, 6796.320367963164, 7217.783466495804, 3954.031262340745, 4552.294394368878, 4556.863294101182, 4558.452985641744, 4586.88532786683, 5656.632318899423, 5851.941480585139, 6168.321484636776, 6445.6454894981225, 6941.930580694128, 6181.938180618316, 6731.663416829041, 6735.865282694221, 6753.0, 6755.223149337692, 5875.73559189846, 6122.755089796537, 6684.565503242274, 6701.262861085374, 6708.960111182774, 5599.944000560105, 6443.936750436125, 6532.967335163484, 6550.737970481127, 6591.967040164768, 6624.8343791405605, 6670.166229221348, 6728.596284222962, 6729.697163627747, 6734.474572035487, 5361.865953351197, 5501.394846566955, 6138.202033735586, 6467.805965821033, 6590.209174899042], "value": 4834.114577599645, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.6937398475571, 821.0516853034744, 830.3523251863545, 859.0679113162221, 861.9698310559131, 16522.0, 16763.0, 16783.83216167838, 16995.83004169958, 17007.65984680307, 15201.239938003111, 15580.220988950563, 16110.7583386249, 16272.75590866135, 16396.016239025666, 978.9265805064613, 986.4968865878382, 1000.9549570269355, 1010.656376831877, 1011.4437059617218, 989.5645915797027, 1011.0496133634397, 1012.5494155100984, 1015.9898401016006, 1016.9491525423737, 1010.3382284603597, 1051.9000694933968, 1052.7420781908443, 1054.4727636181908, 1055.7255113670462, 1044.4046893270838, 1044.5143008501084, 1046.1473898772476, 1049.0768124050787, 1050.217587897018, 753.7663324369463, 756.8599809035346, 776.3556248313943, 777.3470284960661, 783.3028604541962, 17358.479245622653, 17374.391896283694, 17409.912950435293, 17589.912050439794, 17708.557286067822, 17509.474715758548, 17554.561135971704, 17983.280668773234, 18001.18994645244, 18058.90970545152, 12392.194507357082, 14377.424903003868, 14731.263436828223, 14769.778453323208, 14771.778423323658, 3607.4228123500116, 3637.254362855608, 3641.2717456508803, 3643.6902863256655, 3850.0759817643566, 113015.86984130136, 137479.0, 138787.30606347005, 139331.0, 140070.0, 13504.662383440296, 13882.09766365183, 14008.0, 14125.364358603887, 14130.364133614012, 23898.52202955931, 23900.0, 24450.633240501407, 24585.508289834102, 24639.630405543932, 7845.293923546908, 7858.646360913773, 8024.839503209903, 8049.913261709594, 8142.796430089295, 3470.774399664014, 3516.0330908999717, 3525.7884526928465, 3528.770629909048, 3529.2059286660296, 4414.602685758297, 4455.910881782346, 4501.594856462934, 4509.413776209138, 4531.932021019687, 872.4285592936636, 961.7643677298967, 975.9069841777255, 1003.4932359158578, 1020.7295066807302, 654.2606854254757, 664.4285914113858, 672.4821887146869, 679.1544527063837, 683.1631251716707, 11592.0, 12049.216800907912, 12133.69665758363, 12313.507459701512, 12563.937180314222, 610.7068607068632, 645.6061802300593, 655.6918248423328, 659.6404959297195, 667.2493444874516, 5591.7483713233005, 5633.211350410946, 5666.801661941812, 5833.241678581757, 5939.46544810969, 109846.25384365294, 124535.0, 131872.0, 132444.0, 133648.0, 102695.4865225669, 110007.44996274967, 110631.0, 112984.0, 114531.42734286275, 97202.51398742961, 97840.0, 99490.0, 100309.49845250727, 101737.49131254296, 10829.67510974672, 11517.942410287895, 13071.281079540717, 13129.60611181666, 13149.802752958905, 42161.36757948572, 45526.04080734742, 45624.31563526616, 46155.53844461512, 47989.0, 71728.0, 88842.55578722066, 89043.99302031554, 90140.54929725309, 90872.0, 17471.213795378982, 17546.210420531363, 17705.468835934942, 17712.822871771117, 17755.822441775417, 18553.907230463763, 18809.623807524047, 19242.7113593299, 19302.806971930102, 19469.610607788047, 17286.913565432093, 17910.373136940143, 18111.27554897842, 18565.25738970426, 18568.721469178243, 16492.25784839709, 17217.483475495756, 17283.395081172097, 17371.82628173702, 17371.826281737525, 16938.915305423394, 17061.48815535536, 17458.650826983645, 17949.0, 18063.367782127563, 11863.228890122115, 11888.0, 11955.880441195824, 11961.401929903644, 12568.622941311774, 12338.259704417764, 13047.412866420844, 13727.588172354845, 13770.173789572658, 13832.792508112185], "value": 8803.737691101198, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 114.0, 114.0, 113.0, 117.0, 116.0, 116.0, 137.0, 138.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 113.0, 131.0, 153.0, 111.0, 112.0, 112.0, 112.0, 138.0, 116.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [284.0, 288.0, 212.0, 261.0, 251.0, 241.0, 251.0, 222.0, 204.0, 234.0], "value": 244.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 87.0, 77.0, 58.0, 48.0, 51.0, 73.0, 50.0], "value": 64.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 75.0, 116.0, 102.0, 56.0, 91.0, 103.0, 107.0, 107.0, 103.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 88.0, 88.0, 94.0, 89.0, 132.0, 137.0, 111.0, 91.0, 88.0], "value": 101.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 96.0, 96.0, 105.0, 96.0, 96.0, 95.0, 98.0, 96.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 105.0, 93.0, 91.0, 92.0, 89.0, 92.0, 90.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 53.0, 52.0, 50.0, 51.0, 51.0, 55.0, 52.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 55.0, 59.0, 54.0, 68.0, 77.0, 85.0, 62.0, 59.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 88.0, 88.0, 89.0, 88.0, 89.0, 93.0, 126.0, 90.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 148.0, 131.0, 155.0, 152.0, 160.0, 135.0, 138.0, 135.0, 155.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 189.0, 183.0, 192.0, 182.0, 184.0, 191.0, 183.0, 184.0, 174.0], "value": 185.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 65.0, 71.0, 82.0, 91.0, 82.0, 68.0, 67.0], "value": 73.2, "unit": "score"}]}]} 10:09:46 INFO - Return code: 0 10:09:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:46 INFO - # TBPL SUCCESS # 10:09:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:09:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:09:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:09:46 INFO - 2017-06-02 10:09:46.927 screenresolution[1660:11278] starting screenresolution argv=screenresolution get 10:09:46 INFO - 2017-06-02 10:09:46.929 screenresolution[1660:11278] Display 0: 1600x1200x32@60 10:09:46 INFO - 2017-06-02 10:09:46.935 screenresolution[1661:11281] starting screenresolution argv=screenresolution list 10:09:46 INFO - Available Modes on Display 0 10:09:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:09:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:09:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:09:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:09:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:09:47 INFO - Intel Iris: 10:09:47 INFO - Chipset Model: Intel Iris 10:09:47 INFO - Type: GPU 10:09:47 INFO - Bus: Built-In 10:09:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:09:47 INFO - Vendor: Intel (0x8086) 10:09:47 INFO - Device ID: 0x0a2e 10:09:47 INFO - Revision ID: 0x0009 10:09:47 INFO - Displays: 10:09:47 INFO - Display: 10:09:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:09:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:09:47 INFO - Display Serial Number: 1600x1200 60 10:09:47 INFO - Main Display: Yes 10:09:47 INFO - Mirror: Off 10:09:47 INFO - Online: Yes 10:09:47 INFO - Rotation: Supported 10:09:47 INFO - Return code: 0 10:09:47 INFO - Running post-action listener: _resource_record_post_action 10:09:47 INFO - [mozharness: 2017-06-02 17:09:47.172206Z] Finished run-tests step (success) 10:09:47 INFO - Running post-run listener: _resource_record_post_run 10:09:47 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40770560; Write bytes: 571278336; Read time: 271; Write time: 1721 10:09:47 INFO - TinderboxPrint: CPU usage
53.5% 10:09:47 INFO - TinderboxPrint: I/O read bytes / time
40,770,560 / 271 10:09:47 INFO - TinderboxPrint: I/O write bytes / time
571,278,336 / 1,721 10:09:47 INFO - TinderboxPrint: CPU idle
2,337.7 (46.5%) 10:09:47 INFO - TinderboxPrint: CPU system
1,016.5 (20.2%) 10:09:47 INFO - TinderboxPrint: CPU user
1,671.6 (33.3%) 10:09:47 INFO - TinderboxPrint: Swap in / out
286,441,472 / 0 10:09:47 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 239450624; Write bytes: 234919936; Read time: 16930; Write time: 478 10:09:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:09:47 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 38677504; Write bytes: 320093184; Read time: 249; Write time: 1196 10:09:47 INFO - Running post-run listener: _upload_blobber_files 10:09:47 INFO - Blob upload gear active. 10:09:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:48 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:09:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:09:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:49 INFO - (blobuploader) - INFO - Done attempting. 10:09:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:09:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:09:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:50 INFO - (blobuploader) - INFO - Done attempting. 10:09:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:09:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:09:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:51 INFO - (blobuploader) - INFO - Done attempting. 10:09:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:53 INFO - (blobuploader) - INFO - Done attempting. 10:09:53 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:53 INFO - Return code: 0 10:09:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/905bed691e7fc277830b20bea9718fa525c3384a626c8abec1093b39065bf9b4450b3ad0f95b440662de78c0861a30a4f138925aed9712e4ffb70c7dd967eeed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0ea7aae29fbcb51171e3457b62f1c347d6f9baacab305c9da592703aed9f642b60dcb0018fc39aa472eff73475ec1b8dc24d5690a60bc40e644dbc38f81875d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5282c09ed3b18071e36d2d17e3c9840efea9c2cbf6c18c7943167547a20bd1b9a88ed54add82fae3338663541e3aab15a579d62cebe3a6f06c4c80643c897e6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a96a84fbcc0e97f45090e2542e504c364048241eb56d4a6d0818b280814ab9154e72ba51830c114bbeb4d45c63cbc154d924eb246841542c3e99f03e1fb39c4"} 10:09:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:53 INFO - Contents: 10:09:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/905bed691e7fc277830b20bea9718fa525c3384a626c8abec1093b39065bf9b4450b3ad0f95b440662de78c0861a30a4f138925aed9712e4ffb70c7dd967eeed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0ea7aae29fbcb51171e3457b62f1c347d6f9baacab305c9da592703aed9f642b60dcb0018fc39aa472eff73475ec1b8dc24d5690a60bc40e644dbc38f81875d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5282c09ed3b18071e36d2d17e3c9840efea9c2cbf6c18c7943167547a20bd1b9a88ed54add82fae3338663541e3aab15a579d62cebe3a6f06c4c80643c897e6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a96a84fbcc0e97f45090e2542e504c364048241eb56d4a6d0818b280814ab9154e72ba51830c114bbeb4d45c63cbc154d924eb246841542c3e99f03e1fb39c4"} 10:09:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:53 INFO - Copying logs to upload dir... 10:09:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.282037 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-02 10:09:53.163938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 10:09:53.167761) ========= 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.tnYGleQUVy/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.baZBSO3ZQU/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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/905bed691e7fc277830b20bea9718fa525c3384a626c8abec1093b39065bf9b4450b3ad0f95b440662de78c0861a30a4f138925aed9712e4ffb70c7dd967eeed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0ea7aae29fbcb51171e3457b62f1c347d6f9baacab305c9da592703aed9f642b60dcb0018fc39aa472eff73475ec1b8dc24d5690a60bc40e644dbc38f81875d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5282c09ed3b18071e36d2d17e3c9840efea9c2cbf6c18c7943167547a20bd1b9a88ed54add82fae3338663541e3aab15a579d62cebe3a6f06c4c80643c897e6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a96a84fbcc0e97f45090e2542e504c364048241eb56d4a6d0818b280814ab9154e72ba51830c114bbeb4d45c63cbc154d924eb246841542c3e99f03e1fb39c4"} build_url:https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/XcWK6AFiSaiUQWA9a9zGAA/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/905bed691e7fc277830b20bea9718fa525c3384a626c8abec1093b39065bf9b4450b3ad0f95b440662de78c0861a30a4f138925aed9712e4ffb70c7dd967eeed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0ea7aae29fbcb51171e3457b62f1c347d6f9baacab305c9da592703aed9f642b60dcb0018fc39aa472eff73475ec1b8dc24d5690a60bc40e644dbc38f81875d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5282c09ed3b18071e36d2d17e3c9840efea9c2cbf6c18c7943167547a20bd1b9a88ed54add82fae3338663541e3aab15a579d62cebe3a6f06c4c80643c897e6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a96a84fbcc0e97f45090e2542e504c364048241eb56d4a6d0818b280814ab9154e72ba51830c114bbeb4d45c63cbc154d924eb246841542c3e99f03e1fb39c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 10:09:53.201430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 10:09:53.201742) ========= 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.tnYGleQUVy/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.baZBSO3ZQU/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 10:09:53.250114) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 10:09:53.250432) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 10:09:53.250734) ========= ========= Total master_lag: 0.30 =========