builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0124 starttime: 1495794931.1 results: success (0) revision: 44e41de60c48a94a004494dd48f57dd1d8c157d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.099602) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.100056) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.100330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.123957) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.124360) ========= 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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-26 03:35:31-- 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% 8.13M=0.001s 2017-05-26 03:35:31 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126310 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.273675) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.274055) ========= 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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.021670 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.323413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:31.323746) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 44e41de60c48a94a004494dd48f57dd1d8c157d8 --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 44e41de60c48a94a004494dd48f57dd1d8c157d8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tVnUZswGvL/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.JiPsdZ5gVN/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-26 03:35:31,395 truncating revision to first 12 chars 2017-05-26 03:35:31,395 Setting DEBUG logging. 2017-05-26 03:35:31,395 attempt 1/10 2017-05-26 03:35:31,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/44e41de60c48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 03:35:31,896 unpacking tar archive at: mozilla-central-44e41de60c48/testing/mozharness/ program finished with exit code 0 elapsedTime=0.761425 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:32.107969) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:32.108395) ========= script_repo_revision: 44e41de60c48a94a004494dd48f57dd1d8c157d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:32.108823) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:32.109268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 03:35:32.129357) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 14 secs) (at 2017-05-26 03:35:32.129821) ========= /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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:35:32 INFO - MultiFileLogger online at 20170526 03:35:32 in /builds/slave/test 03:35:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:35:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:32 INFO - {'append_to_log': False, 03:35:32 INFO - 'base_work_dir': '/builds/slave/test', 03:35:32 INFO - 'blob_upload_branch': 'Firefox', 03:35:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:32 INFO - 'branch': 'Firefox', 03:35:32 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:32 INFO - 'config_files': ('talos/mac_config.py',), 03:35:32 INFO - 'default_actions': ('clobber', 03:35:32 INFO - 'read-buildbot-config', 03:35:32 INFO - 'download-and-extract', 03:35:32 INFO - 'populate-webroot', 03:35:32 INFO - 'create-virtualenv', 03:35:32 INFO - 'install', 03:35:32 INFO - 'run-tests'), 03:35:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:32 INFO - 'download_minidump_stackwalk': True, 03:35:32 INFO - 'download_symbols': 'ondemand', 03:35:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:32 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:32 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:32 INFO - 'gecko_profile': False, 03:35:32 INFO - 'gecko_profile_interval': 0, 03:35:32 INFO - 'installer_path': 'installer.exe', 03:35:32 INFO - 'log_level': 'info', 03:35:32 INFO - 'log_name': 'talos', 03:35:32 INFO - 'log_to_console': True, 03:35:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:35:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:35:32 INFO - 'opt_config_files': (), 03:35:32 INFO - 'pip_index': False, 03:35:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:35:32 INFO - 'cmd': ('bash', 03:35:32 INFO - '-c', 03:35:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:35:32 INFO - 'enabled': True, 03:35:32 INFO - 'halt_on_failure': False, 03:35:32 INFO - 'name': 'check_screen_resolution'},), 03:35:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:35:32 INFO - 'cmd': ('bash', 03:35:32 INFO - '-c', 03:35:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:35:32 INFO - 'enabled': True, 03:35:32 INFO - 'halt_on_failure': False, 03:35:32 INFO - 'name': 'check_screen_resolution'},), 03:35:32 INFO - 'run_cmd_checks_enabled': True, 03:35:32 INFO - 'suite': 'dromaeojs-e10s', 03:35:32 INFO - 'system_bits': '32', 03:35:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:32 INFO - 'title': 't-yosemite-r7-0124', 03:35:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:32 INFO - 'use_talos_json': True, 03:35:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:32 INFO - 'work_dir': 'build'} 03:35:32 INFO - [mozharness: 2017-05-26 10:35:32.334082Z] Running clobber step. 03:35:32 INFO - Running pre-action listener: _resource_record_pre_action 03:35:32 INFO - Running main action method: clobber 03:35:32 INFO - rmtree: /builds/slave/test/build 03:35:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:32 INFO - Running post-action listener: _resource_record_post_action 03:35:32 INFO - [mozharness: 2017-05-26 10:35:32.492991Z] Finished clobber step (success) 03:35:32 INFO - [mozharness: 2017-05-26 10:35:32.493122Z] Running read-buildbot-config step. 03:35:32 INFO - Running pre-action listener: _resource_record_pre_action 03:35:32 INFO - Running main action method: read_buildbot_config 03:35:32 INFO - Using buildbot properties: 03:35:32 INFO - { 03:35:32 INFO - "project": "", 03:35:32 INFO - "product": "firefox", 03:35:32 INFO - "who": "cbook@mozilla.com", 03:35:32 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:35:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:35:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:35:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:35:32 INFO - "stage_platform": "macosx64", 03:35:32 INFO - "basedir": "/builds/slave/test", 03:35:32 INFO - "buildnumber": 225, 03:35:32 INFO - "platform": "yosemite_r7", 03:35:32 INFO - "parent_task_id": "JqCDfQt-RyGpJhCBrYa10A", 03:35:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:35:32 INFO - "slavebuilddir": "test", 03:35:32 INFO - "taskId": "NjzdEhKxQYKis9_L11dPtw", 03:35:32 INFO - "branch": "mozilla-central", 03:35:32 INFO - "script_repo_revision": "production", 03:35:32 INFO - "revision": "44e41de60c48a94a004494dd48f57dd1d8c157d8", 03:35:32 INFO - "slavename": "t-yosemite-r7-0124", 03:35:32 INFO - "repo_path": "mozilla-central" 03:35:32 INFO - } 03:35:32 INFO - Finding installer, test and symbols from parent task. 03:35:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NjzdEhKxQYKis9_L11dPtw'}, attempt #1 03:35:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NjzdEhKxQYKis9_L11dPtw'}, attempt #1 03:35:33 INFO - Task dependencies: N29vk-zjS0KkNAKQMJR0dw JqCDfQt-RyGpJhCBrYa10A 03:35:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A'}, attempt #1 03:35:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:35:34 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/test_packages.json 03:35:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/target.crashreporter-symbols.zip 03:35:34 INFO - Running post-action listener: _resource_record_post_action 03:35:34 INFO - [mozharness: 2017-05-26 10:35:34.377237Z] Finished read-buildbot-config step (success) 03:35:34 INFO - [mozharness: 2017-05-26 10:35:34.377385Z] Running download-and-extract step. 03:35:34 INFO - Running pre-action listener: _resource_record_pre_action 03:35:34 INFO - Running main action method: download_and_extract 03:35:34 INFO - mkdir: /builds/slave/test/build/tests 03:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:34 INFO - trying https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:35:34 INFO - Downloading https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:35:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 03:35:34 INFO - Downloaded 1325 bytes. 03:35:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:35:34 INFO - Using the following test package requirements: 03:35:34 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:35:34 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:35:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:35:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:35:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'jsshell-mac.zip'], 03:35:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:35:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:35:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:35:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:35:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:35:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:35:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:35:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:35:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:35:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:35:34 INFO - Fetch https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:35:36 INFO - Content-Length response header: 57627226 03:35:36 INFO - Bytes received: 57627226 03:35:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:35:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:35:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:35:54 INFO - Fetch https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:35:56 INFO - Content-Length response header: 57627226 03:35:56 INFO - Bytes received: 57627226 03:36:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:36:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:36:14 INFO - Fetch https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:36:15 INFO - Content-Length response header: 14411888 03:36:15 INFO - Bytes received: 14411888 03:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:15 INFO - trying https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:36:15 INFO - Downloading https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 03:36:17 INFO - Downloaded 67187267 bytes. 03:36:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:36:17 INFO - mkdir: /builds/slave/test/properties 03:36:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:36:17 INFO - Writing to file /builds/slave/test/properties/build_url 03:36:17 INFO - Contents: 03:36:17 INFO - build_url:https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:36:17 INFO - Running post-action listener: _resource_record_post_action 03:36:17 INFO - Running post-action listener: set_extra_try_arguments 03:36:17 INFO - [mozharness: 2017-05-26 10:36:17.629383Z] Finished download-and-extract step (success) 03:36:17 INFO - [mozharness: 2017-05-26 10:36:17.629537Z] Running populate-webroot step. 03:36:17 INFO - Running pre-action listener: _resource_record_pre_action 03:36:17 INFO - Running main action method: populate_webroot 03:36:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:36:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:36:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:36:17 INFO - '--tppagecycles', 03:36:17 INFO - '7'], 03:36:17 INFO - 'tests': ['tsvgm']}}, 03:36:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:36:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:36:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:36:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:36:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:36:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:36:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['damp', 'tps']}, 03:36:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'tests': ['damp', 'tps']}, 03:36:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['dromaeo_dom']}, 03:36:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:36:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:36:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:36:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:36:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['a11yr', 03:36:17 INFO - 'ts_paint', 03:36:17 INFO - 'tpaint', 03:36:17 INFO - 'sessionrestore', 03:36:17 INFO - 'sessionrestore_no_auto_restore', 03:36:17 INFO - 'tabpaint']}, 03:36:17 INFO - 'other-e10s': {'tests': ['a11yr', 03:36:17 INFO - 'ts_paint', 03:36:17 INFO - 'tpaint', 03:36:17 INFO - 'sessionrestore', 03:36:17 INFO - 'sessionrestore_no_auto_restore', 03:36:17 INFO - 'tabpaint']}, 03:36:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:36:17 INFO - 'ts_paint', 03:36:17 INFO - 'tpaint', 03:36:17 INFO - 'sessionrestore', 03:36:17 INFO - 'sessionrestore_no_auto_restore', 03:36:17 INFO - 'tabpaint']}, 03:36:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:36:17 INFO - 'ts_paint', 03:36:17 INFO - 'tpaint', 03:36:17 INFO - 'sessionrestore', 03:36:17 INFO - 'sessionrestore_no_auto_restore', 03:36:17 INFO - 'tabpaint']}, 03:36:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['a11yr', 03:36:17 INFO - 'ts_paint', 03:36:17 INFO - 'tpaint', 03:36:17 INFO - 'sessionrestore', 03:36:17 INFO - 'sessionrestore_no_auto_restore', 03:36:17 INFO - 'tabpaint']}, 03:36:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['a11yr', 03:36:17 INFO - 'ts_paint', 03:36:17 INFO - 'tpaint', 03:36:17 INFO - 'sessionrestore', 03:36:17 INFO - 'sessionrestore_no_auto_restore', 03:36:17 INFO - 'tabpaint']}, 03:36:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:36:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:36:17 INFO - 'bloom_basic_ref']}, 03:36:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['tsvgx', 03:36:17 INFO - 'tsvgr_opacity', 03:36:17 INFO - 'tart', 03:36:17 INFO - 'tscrollx', 03:36:17 INFO - 'cart', 03:36:17 INFO - 'tsvg_static']}, 03:36:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:36:17 INFO - 'tsvgr_opacity', 03:36:17 INFO - 'tart', 03:36:17 INFO - 'tscrollx', 03:36:17 INFO - 'cart', 03:36:17 INFO - 'tsvg_static']}, 03:36:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'talos_options': ['--disable-e10s'], 03:36:17 INFO - 'tests': ['tp5o']}, 03:36:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:36:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'talos_options': ['--disable-e10s', 03:36:17 INFO - '--xperf_path', 03:36:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:36:17 INFO - 'tests': ['tp5n']}, 03:36:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:36:17 INFO - 'talos_options': ['--xperf_path', 03:36:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:36:17 INFO - 'tests': ['tp5n']}}, 03:36:17 INFO - 'talos.zip': {'path': '', 03:36:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:36:17 INFO - Running post-action listener: _resource_record_post_action 03:36:17 INFO - [mozharness: 2017-05-26 10:36:17.650783Z] Finished populate-webroot step (success) 03:36:17 INFO - [mozharness: 2017-05-26 10:36:17.650935Z] Running create-virtualenv step. 03:36:17 INFO - Running pre-action listener: _resource_record_pre_action 03:36:17 INFO - Running main action method: create_virtualenv 03:36:17 INFO - Creating virtualenv /builds/slave/test/build/venv 03:36:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:36:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:36:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:36:17 INFO - Using real prefix '/tools/python27' 03:36:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:36:18 INFO - Installing distribute.............................................................................................................................................................................................done. 03:36:21 INFO - Installing pip.................done. 03:36:21 INFO - Return code: 0 03:36:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:36:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:36:21 INFO - Reading from file tmpfile_stdout 03:36:21 INFO - Output received: 03:36:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:36:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:36:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:36:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:22 INFO - 'HOME': '/Users/cltbld', 03:36:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:22 INFO - 'LOGNAME': 'cltbld', 03:36:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:22 INFO - 'MOZ_NO_REMOTE': '1', 03:36:22 INFO - 'NO_EM_RESTART': '1', 03:36:22 INFO - 'PAGER': '/bin/cat', 03:36:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:22 INFO - 'PWD': '/builds/slave/test', 03:36:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:22 INFO - 'SHELL': '/bin/bash', 03:36:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:22 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:22 INFO - 'USER': 'cltbld', 03:36:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:22 INFO - 'XPC_FLAGS': '0x0', 03:36:22 INFO - 'XPC_SERVICE_NAME': '0', 03:36:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:36:22 INFO - Cleaning up... 03:36:22 INFO - Return code: 0 03:36:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:23 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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:23 INFO - 'HOME': '/Users/cltbld', 03:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:23 INFO - 'LOGNAME': 'cltbld', 03:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:23 INFO - 'MOZ_NO_REMOTE': '1', 03:36:23 INFO - 'NO_EM_RESTART': '1', 03:36:23 INFO - 'PAGER': '/bin/cat', 03:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:23 INFO - 'PWD': '/builds/slave/test', 03:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:23 INFO - 'SHELL': '/bin/bash', 03:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:23 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:23 INFO - 'USER': 'cltbld', 03:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:23 INFO - 'XPC_FLAGS': '0x0', 03:36:23 INFO - 'XPC_SERVICE_NAME': '0', 03:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:23 INFO - Downloading/unpacking psutil>=3.1.1 03:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:36:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:36:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:36:25 INFO - Installing collected packages: psutil 03:36:25 INFO - Running setup.py install for psutil 03:36:25 INFO - building 'psutil._psutil_osx' extension 03:36:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:36:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:36:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:36:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:36:26 INFO - building 'psutil._psutil_posix' extension 03:36:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:36:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:36:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:36:26 INFO - ^ 03:36:26 INFO - 1 warning generated. 03:36:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:36:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:36:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:36:26 INFO - Successfully installed psutil 03:36:26 INFO - Cleaning up... 03:36:26 INFO - Return code: 0 03:36:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:36: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')]} 03:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36: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')]} 03:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:36: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 mozsystemmonitor==0.3 03:36:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:26 INFO - 'HOME': '/Users/cltbld', 03:36:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:26 INFO - 'LOGNAME': 'cltbld', 03:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:26 INFO - 'MOZ_NO_REMOTE': '1', 03:36:26 INFO - 'NO_EM_RESTART': '1', 03:36:26 INFO - 'PAGER': '/bin/cat', 03:36:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:26 INFO - 'PWD': '/builds/slave/test', 03:36:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:26 INFO - 'SHELL': '/bin/bash', 03:36:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:26 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:26 INFO - 'USER': 'cltbld', 03:36:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:26 INFO - 'XPC_FLAGS': '0x0', 03:36:26 INFO - 'XPC_SERVICE_NAME': '0', 03:36:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:36: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 03:36: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 03:36: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 03:36: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 03:36:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:36:28 INFO - Installing collected packages: mozsystemmonitor 03:36:28 INFO - Running setup.py install for mozsystemmonitor 03:36:28 INFO - Successfully installed mozsystemmonitor 03:36:28 INFO - Cleaning up... 03:36:28 INFO - Return code: 0 03:36:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:28 INFO - 'HOME': '/Users/cltbld', 03:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:28 INFO - 'LOGNAME': 'cltbld', 03:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:28 INFO - 'MOZ_NO_REMOTE': '1', 03:36:28 INFO - 'NO_EM_RESTART': '1', 03:36:28 INFO - 'PAGER': '/bin/cat', 03:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:28 INFO - 'PWD': '/builds/slave/test', 03:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:28 INFO - 'SHELL': '/bin/bash', 03:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:28 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:28 INFO - 'USER': 'cltbld', 03:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:28 INFO - 'XPC_FLAGS': '0x0', 03:36:28 INFO - 'XPC_SERVICE_NAME': '0', 03:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:28 INFO - Downloading/unpacking jsonschema==2.5.1 03:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:36:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:30 INFO - Downloading functools32-3.2.3-2.tar.gz 03:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:36:30 INFO - warning: no files found matching '*.txt' 03:36:30 INFO - no previously-included directories found matching 'build' 03:36:30 INFO - no previously-included directories found matching 'dist' 03:36:30 INFO - no previously-included directories found matching '.git*' 03:36:30 INFO - Installing collected packages: jsonschema, functools32 03:36:30 INFO - Running setup.py install for functools32 03:36:30 INFO - warning: no files found matching '*.txt' 03:36:30 INFO - no previously-included directories found matching 'build' 03:36:30 INFO - no previously-included directories found matching 'dist' 03:36:30 INFO - no previously-included directories found matching '.git*' 03:36:30 INFO - Successfully installed jsonschema functools32 03:36:30 INFO - Cleaning up... 03:36:30 INFO - Return code: 0 03:36:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:36: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')]} 03:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36: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')]} 03:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36: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', '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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:36: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 functools32==3.2.3-2 03:36:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:30 INFO - 'HOME': '/Users/cltbld', 03:36:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:30 INFO - 'LOGNAME': 'cltbld', 03:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:30 INFO - 'MOZ_NO_REMOTE': '1', 03:36:30 INFO - 'NO_EM_RESTART': '1', 03:36:30 INFO - 'PAGER': '/bin/cat', 03:36:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:30 INFO - 'PWD': '/builds/slave/test', 03:36:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:30 INFO - 'SHELL': '/bin/bash', 03:36:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:30 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:30 INFO - 'USER': 'cltbld', 03:36:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:30 INFO - 'XPC_FLAGS': '0x0', 03:36:30 INFO - 'XPC_SERVICE_NAME': '0', 03:36:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:36:30 INFO - Cleaning up... 03:36:30 INFO - Return code: 0 03:36:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:36: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')]} 03:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36: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')]} 03:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36: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', '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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:36: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 blobuploader==1.2.4 03:36:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:30 INFO - 'HOME': '/Users/cltbld', 03:36:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:30 INFO - 'LOGNAME': 'cltbld', 03:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:30 INFO - 'MOZ_NO_REMOTE': '1', 03:36:30 INFO - 'NO_EM_RESTART': '1', 03:36:30 INFO - 'PAGER': '/bin/cat', 03:36:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:30 INFO - 'PWD': '/builds/slave/test', 03:36:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:30 INFO - 'SHELL': '/bin/bash', 03:36:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:30 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:30 INFO - 'USER': 'cltbld', 03:36:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:30 INFO - 'XPC_FLAGS': '0x0', 03:36:30 INFO - 'XPC_SERVICE_NAME': '0', 03:36:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:31 INFO - Downloading/unpacking blobuploader==1.2.4 03:36: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 03:36: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 03:36: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 03:36: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 03:36:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:36:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:36:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:36: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 03:36: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 03:36: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 03:36: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 03:36:33 INFO - Downloading docopt-0.6.1.tar.gz 03:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:36:33 INFO - Installing collected packages: blobuploader, requests, docopt 03:36:33 INFO - Running setup.py install for blobuploader 03:36:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:36:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:36:33 INFO - Running setup.py install for requests 03:36:34 INFO - Running setup.py install for docopt 03:36:34 INFO - Successfully installed blobuploader requests docopt 03:36:34 INFO - Cleaning up... 03:36:34 INFO - Return code: 0 03:36:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:34 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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:36:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:34 INFO - 'HOME': '/Users/cltbld', 03:36:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:34 INFO - 'LOGNAME': 'cltbld', 03:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:34 INFO - 'MOZ_NO_REMOTE': '1', 03:36:34 INFO - 'NO_EM_RESTART': '1', 03:36:34 INFO - 'PAGER': '/bin/cat', 03:36:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:34 INFO - 'PWD': '/builds/slave/test', 03:36:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:34 INFO - 'SHELL': '/bin/bash', 03:36:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:34 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:34 INFO - 'USER': 'cltbld', 03:36:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:34 INFO - 'XPC_FLAGS': '0x0', 03:36:34 INFO - 'XPC_SERVICE_NAME': '0', 03:36:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:36:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-eKJx4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:36:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-qleOal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:36:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-_LODev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:36:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-LoBNgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-HNeIpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-7UdoMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-1149cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-1hkr1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-I0Gn2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Fiq5gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-dsfSmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-LFIVBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:36:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-EWlCuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:36:36 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-3sXrlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:36 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-dFC48m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:36:36 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-T5UDoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:36:36 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-SMRZsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:36:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:36:36 INFO - Running setup.py install for manifestparser 03:36:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:36:36 INFO - Running setup.py install for mozcrash 03:36:36 INFO - Running setup.py install for mozdebug 03:36:36 INFO - Running setup.py install for mozdevice 03:36:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Running setup.py install for mozfile 03:36:37 INFO - Running setup.py install for mozhttpd 03:36:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Running setup.py install for mozinfo 03:36:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Running setup.py install for mozInstall 03:36:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Running setup.py install for mozleak 03:36:37 INFO - Running setup.py install for mozlog 03:36:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:36:37 INFO - Running setup.py install for moznetwork 03:36:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:36:38 INFO - Running setup.py install for mozprocess 03:36:38 INFO - Running setup.py install for mozprofile 03:36:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:36:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:36:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:36:38 INFO - Running setup.py install for mozrunner 03:36:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:36:38 INFO - Running setup.py install for mozscreenshot 03:36:38 INFO - Running setup.py install for moztest 03:36:38 INFO - Running setup.py install for mozversion 03:36:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:36:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:36:39 INFO - Cleaning up... 03:36:39 INFO - Return code: 0 03:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:39 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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:39 INFO - 'HOME': '/Users/cltbld', 03:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:39 INFO - 'LOGNAME': 'cltbld', 03:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:39 INFO - 'MOZ_NO_REMOTE': '1', 03:36:39 INFO - 'NO_EM_RESTART': '1', 03:36:39 INFO - 'PAGER': '/bin/cat', 03:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:39 INFO - 'PWD': '/builds/slave/test', 03:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:39 INFO - 'SHELL': '/bin/bash', 03:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:39 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:39 INFO - 'USER': 'cltbld', 03:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:39 INFO - 'XPC_FLAGS': '0x0', 03:36:39 INFO - 'XPC_SERVICE_NAME': '0', 03:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-_SUkp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-siEuAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ybYg6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-E1qNwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-0eeZ9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-iNspMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:36:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-hUgbq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-K0zaQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-yRrSc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-4Kwp_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-HKbEAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-KFI65u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-EJ2upQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-1y585W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-H2zALq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-f5xHqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:36:40 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-IdsqgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:36:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:42 INFO - Downloading blessings-1.6.tar.gz 03:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:36:42 INFO - Installing collected packages: blessings 03:36:42 INFO - Running setup.py install for blessings 03:36:42 INFO - Successfully installed blessings 03:36:42 INFO - Cleaning up... 03:36:42 INFO - Return code: 0 03:36:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:36:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:36:43 INFO - Reading from file tmpfile_stdout 03:36:43 INFO - Current package versions: 03:36:43 INFO - blessings == 1.6 03:36:43 INFO - blobuploader == 1.2.4 03:36:43 INFO - docopt == 0.6.1 03:36:43 INFO - functools32 == 3.2.3-2 03:36:43 INFO - jsonschema == 2.5.1 03:36:43 INFO - manifestparser == 1.1 03:36:43 INFO - mozInstall == 1.12 03:36:43 INFO - mozcrash == 1.0 03:36:43 INFO - mozdebug == 0.1 03:36:43 INFO - mozdevice == 0.51 03:36:43 INFO - mozfile == 1.2 03:36:43 INFO - mozhttpd == 0.7 03:36:43 INFO - mozinfo == 0.9 03:36:43 INFO - mozleak == 0.1 03:36:43 INFO - mozlog == 3.4 03:36:43 INFO - moznetwork == 0.27 03:36:43 INFO - mozprocess == 0.25 03:36:43 INFO - mozprofile == 0.28 03:36:43 INFO - mozrunner == 6.13 03:36:43 INFO - mozscreenshot == 0.1 03:36:43 INFO - mozsystemmonitor == 0.3 03:36:43 INFO - moztest == 0.8 03:36:43 INFO - mozversion == 1.4 03:36:43 INFO - psutil == 3.1.1 03:36:43 INFO - requests == 1.2.3 03:36:43 INFO - wsgiref == 0.1.2 03:36:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36: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')]} 03:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36: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')]} 03:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:43 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 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:36:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:43 INFO - 'HOME': '/Users/cltbld', 03:36:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:43 INFO - 'LOGNAME': 'cltbld', 03:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:43 INFO - 'MOZ_NO_REMOTE': '1', 03:36:43 INFO - 'NO_EM_RESTART': '1', 03:36:43 INFO - 'PAGER': '/bin/cat', 03:36:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:43 INFO - 'PWD': '/builds/slave/test', 03:36:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:43 INFO - 'SHELL': '/bin/bash', 03:36:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:43 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:43 INFO - 'USER': 'cltbld', 03:36:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:43 INFO - 'XPC_FLAGS': '0x0', 03:36:43 INFO - 'XPC_SERVICE_NAME': '0', 03:36:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:36:43 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:44 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:36:44 INFO - Installing collected packages: marionette-driver 03:36:44 INFO - Running setup.py install for marionette-driver 03:36:45 INFO - Successfully installed marionette-driver 03:36:45 INFO - Cleaning up... 03:36:45 INFO - Return code: 0 03:36:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103251b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103255768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9835604b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, '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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:45 INFO - 'HOME': '/Users/cltbld', 03:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:45 INFO - 'LOGNAME': 'cltbld', 03:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:45 INFO - 'MOZ_NO_REMOTE': '1', 03:36:45 INFO - 'NO_EM_RESTART': '1', 03:36:45 INFO - 'PAGER': '/bin/cat', 03:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:45 INFO - 'PWD': '/builds/slave/test', 03:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:45 INFO - 'SHELL': '/bin/bash', 03:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:36:45 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:45 INFO - 'USER': 'cltbld', 03:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:45 INFO - 'XPC_FLAGS': '0x0', 03:36:45 INFO - 'XPC_SERVICE_NAME': '0', 03:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:36:45 INFO - Cleaning up... 03:36:45 INFO - Return code: 0 03:36:45 INFO - Running post-action listener: _resource_record_post_action 03:36:45 INFO - Running post-action listener: _start_resource_monitoring 03:36:45 INFO - Starting resource monitoring. 03:36:45 INFO - [mozharness: 2017-05-26 10:36:45.342966Z] Finished create-virtualenv step (success) 03:36:45 INFO - [mozharness: 2017-05-26 10:36:45.343950Z] Running install step. 03:36:45 INFO - Running pre-action listener: _resource_record_pre_action 03:36:45 INFO - Running main action method: install 03:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:36:45 INFO - Reading from file tmpfile_stdout 03:36:45 INFO - Detecting whether we're running mozinstall >=1.0... 03:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:36:45 INFO - Reading from file tmpfile_stdout 03:36:45 INFO - Output received: 03:36:45 INFO - Usage: mozinstall [options] installer 03:36:45 INFO - Options: 03:36:45 INFO - -h, --help show this help message and exit 03:36:45 INFO - -d DEST, --destination=DEST 03:36:45 INFO - Directory to install application into. [default: 03:36:45 INFO - "/builds/slave/test"] 03:36:45 INFO - --app=APP Application being installed. [default: firefox] 03:36:45 INFO - mkdir: /builds/slave/test/build/application 03:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:37:07 INFO - Reading from file tmpfile_stdout 03:37:07 INFO - Output received: 03:37:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:37:07 INFO - Running post-action listener: _resource_record_post_action 03:37:07 INFO - [mozharness: 2017-05-26 10:37:07.294765Z] Finished install step (success) 03:37:07 INFO - [mozharness: 2017-05-26 10:37:07.294903Z] Running run-tests step. 03:37:07 INFO - Running pre-action listener: _resource_record_pre_action 03:37:07 INFO - Running main action method: run_tests 03:37:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:37:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:37:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:37:07 INFO - 2017-05-26 03:37:07.309 screenresolution[1906:8722] starting screenresolution argv=screenresolution get 03:37:07 INFO - 2017-05-26 03:37:07.312 screenresolution[1906:8722] Display 0: 1600x1200x32@60 03:37:07 INFO - 2017-05-26 03:37:07.318 screenresolution[1907:8724] starting screenresolution argv=screenresolution list 03:37:07 INFO - Available Modes on Display 0 03:37:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:37:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:37:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:37:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:37:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:37:07 INFO - Intel Iris: 03:37:07 INFO - Chipset Model: Intel Iris 03:37:07 INFO - Type: GPU 03:37:07 INFO - Bus: Built-In 03:37:07 INFO - VRAM (Dynamic, Max): 1536 MB 03:37:07 INFO - Vendor: Intel (0x8086) 03:37:07 INFO - Device ID: 0x0a2e 03:37:07 INFO - Revision ID: 0x0009 03:37:07 INFO - Displays: 03:37:07 INFO - Display: 03:37:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:37:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:37:07 INFO - Display Serial Number: 1600x1200 60 03:37:07 INFO - Main Display: Yes 03:37:07 INFO - Mirror: Off 03:37:07 INFO - Online: Yes 03:37:07 INFO - Rotation: Supported 03:37:07 INFO - Return code: 0 03:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:37:07 INFO - Python 2.7.3 03:37:07 INFO - Return code: 0 03:37:07 INFO - grabbing minidump binary from tooltool 03:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:07 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 0x102f61d30>, '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 0x102f15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f56be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:37:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:37:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:37:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:37:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:37:07 INFO - Return code: 0 03:37:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:37:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:37:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:37:07 INFO - ENV: RUST_BACKTRACE is now 1 03:37:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:37:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:37:07 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/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:37:07 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/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0124 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tVnUZswGvL/Render', 03:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:07 INFO - 'HOME': '/Users/cltbld', 03:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:07 INFO - 'LOGNAME': 'cltbld', 03:37:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:37:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:07 INFO - 'MOZ_NO_REMOTE': '1', 03:37:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:37:07 INFO - 'NO_EM_RESTART': '1', 03:37:07 INFO - 'PAGER': '/bin/cat', 03:37:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:07 INFO - 'PWD': '/builds/slave/test', 03:37:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:07 INFO - 'RUST_BACKTRACE': '1', 03:37:07 INFO - 'SHELL': '/bin/bash', 03:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiPsdZ5gVN/Listeners', 03:37:07 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 03:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:07 INFO - 'USER': 'cltbld', 03:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:07 INFO - 'XPC_FLAGS': '0x0', 03:37:07 INFO - 'XPC_SERVICE_NAME': '0', 03:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:07 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/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:37:07 INFO - mozversion application_buildid: 20170526021254 03:37:07 INFO - mozversion application_changeset: 44e41de60c48a94a004494dd48f57dd1d8c157d8 03:37:07 INFO - mozversion application_display_name: Nightly 03:37:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:37:07 INFO - mozversion application_name: Firefox 03:37:07 INFO - mozversion application_remotingname: firefox 03:37:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:37:07 INFO - mozversion application_vendor: Mozilla 03:37:07 INFO - mozversion application_version: 55.0a1 03:37:07 INFO - mozversion platform_buildid: 20170526021254 03:37:07 INFO - mozversion platform_changeset: 44e41de60c48a94a004494dd48f57dd1d8c157d8 03:37:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:37:07 INFO - mozversion platform_version: 55.0a1 03:37:07 INFO - using testdate: 1495795027 03:37:07 INFO - actual date: 1495795027 03:37:07 INFO - starting webserver on 'localhost:49346' 03:37:07 INFO - SUITE-START | Running 2 tests 03:37:07 INFO - TEST-START | dromaeo_css 03:37:07 INFO - operating with platform_type : mac_ 03:37:07 INFO - Initialising browser for dromaeo_css test... 03:37:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpWzxPQb/profile 03:37:07 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground) 03:37:41 INFO - TEST-INFO | 1917: killed by SIGTERM 03:37:41 INFO - Browser initialized. 03:37:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:37:41 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpWzxPQb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:37:43 INFO - PID 1924 | 2017-05-26 03:37:43.456 plugin-container[1925:9196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 03:37:43 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:37:43 INFO - PID 1924 | 2017-05-26 03:37:43.456 plugin-container[1925:9196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 03:37:43 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:37:43 INFO - PID 1924 | 2017-05-26 03:37:43.456 plugin-container[1925:9196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:37:44 INFO - PID 1924 | 2017-05-26 03:37:44.019 plugin-container[1926:9242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 03:37:44 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:37:44 INFO - PID 1924 | 2017-05-26 03:37:44.019 plugin-container[1926:9242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 03:37:44 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:37:44 INFO - PID 1924 | 2017-05-26 03:37:44.020 plugin-container[1926:9242] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:37:53 INFO - PID 1924 | RSS: Main: 242552832 03:37:53 INFO - PID 1924 | 03:40:55 INFO - PID 1924 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49346/tests/dromaeo/cssquery-ext.html) 03:40:57 INFO - PID 1924 | RSS: Main: 260968448 03:40:57 INFO - PID 1924 | 03:43:59 INFO - PID 1924 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-ext.html (next: http://localhost:49346/tests/dromaeo/cssquery-jquery.html) 03:43:59 INFO - PID 1924 | RSS: Main: 274649088 03:43:59 INFO - PID 1924 | 03:47:01 INFO - PID 1924 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49346/tests/dromaeo/cssquery-mootools.html) 03:47:02 INFO - PID 1924 | RSS: Main: 274817024 03:47:02 INFO - PID 1924 | 03:50:03 INFO - PID 1924 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49346/tests/dromaeo/cssquery-prototype.html) 03:50:04 INFO - PID 1924 | RSS: Main: 280514560 03:50:04 INFO - PID 1924 | 03:53:07 INFO - PID 1924 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49346/tests/dromaeo/cssquery-yui.html) 03:53:07 INFO - PID 1924 | RSS: Main: 280645632 03:53:07 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | Cycle 1(1): loaded http://localhost:49346/tests/dromaeo/cssquery-yui.html (next: http://localhost:49346/tests/dromaeo/cssquery-dojo.html) 03:56:09 INFO - PID 1924 | RSS: Main: 282349568 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | __start_tp_report 03:56:09 INFO - PID 1924 | _x_x_mozilla_page_load 03:56:09 INFO - PID 1924 | _x_x_mozilla_page_load_details 03:56:09 INFO - PID 1924 | |i|pagename|runs| 03:56:09 INFO - PID 1924 | |0;dojo.html;2926.6634337051237;3597.46038094286;3609.0616439725663;3631.110377957401;3641.817909104546;6101.35935726749;6103.398280005564;6177.752889884405;6189.288231853344;6196.504279657628;4555.9544404555945;6159.568830181889;6171.13604095427;6176;6256.186695729549;5197.220416937452;5381.112116500773;5392.083345831198;5400.189971504276;5449.128139497681;5510.283663123788;5519.806806761764;5535.865147644723;5538.252335934638;5544.833654990337;4616.861494155164;5356.4375740547075;5417.26866872975;5417.45825417459;5427.83716488506;5452.236686863842;5466.289382380265;5480.314960629921;5490.80782172626;5497.697626630534;5371.140617501181;5382.730863456851;5399.919001214985;5407.297051383295;5423.349198096214;4994.925076123861;6109.2528441424865;6211.906821397637;6236.064590311445;6238.4697300729495;5865.296164460249;6517.706703198368;6548.639824809634;6639.50203734717;6660.267370589231;6011.248593925759;6449.32282110381;6786.694598743068;6802.217744959344;6832.180138383376;6268.341824108403;6270.968645156745;6293.370662933716;6308.179936608258;6312.747490100436;6701.195856497252;6773.6613169341335;6834.384905358542;6893.4829887758615;6913.688884000131;6343.080253363237;6359.459445947054;6420.582662126947;6571.737130514726;6602.603843769293;6672.032555279555;6768.289329620319;6782.966085169642;6789.981502774524;6809.897851532231;6610.768623098168;6643;6662.43369313604;6663.566868153554;6684.2981486944145;6427.22873255203;6497.9675101625135;6570;6662.067310576524;6694.799156025327;5343.5190832824455;5361;5372.382176049726;5384.999999999922;5413.160960051198;4762.4285085789925;4762.833300834454;4791.976040119846;4808.3989501312335;4838.104950584153;6191.659458729723;6199.535034872402;6255.374462553799;6283.937160628335;6317.936820631827;6705.2624211336715;6734.595924244561;6797.422219111431;6805.3875151236625;6811;6274.6548939807835;6288.779892703734;6293.496520278367;6306.527010474141;6312.652804095819;5562.943040822231;5755.1367294906095;5807;6002.249718785152;6022.969885150546;6794.932050679528;6822.181338239542;6917;6918.0314755933205;6950;5923.911141332969;6523.956167013255;6726.3609957054005;6818.488613353919;6820;6918.308169183041;6964.094667693167;6976.616286104313;6989.720411183495;6993.860122797414;5267.683938963674;6241.937580624136;6497.285298617243;6567.638779867153;6606.735730570724;6760.222574404006;6800.353966373186;6810.6594670265295;6811.89782153278;6834.521583489108;6376.39424254695;6450.838729031624;6546.705398257185;6574.11249481333;6605.504587155887;6465.612063276219;6507.707153178024;6521.413072823469;6537.0521274413995;6559.212894452697;6403.551751377358;6445.452136568444;6464.515161362822;6470.094186813944;6474.870502589828;6390.456811170917;6605.405513503808;6605.636689982097;6620.205575330991;6640.169978752656;6277.3408792078;6329.303776584664;6399.520035997226;6447.580907241015;6448.290688024407 03:56:09 INFO - PID 1924 | |1;ext.html;752.9458757739164;797.4338219863896;813.8535063688536;1216.568118317997;1287.5429222625962;38200.42699359509;39189.60810391895;39200.80399598005;39221.21557568847;39371.80314098425;39573.20853582927;39702.60297397025;39953.20093598134;39959;39999.800005999794;7759.767206983771;7913.564753938531;8251.257386835183;8456.83086338273;8471.36464765142;8346.624401901929;8361.874571881426;8446.83106337873;8450.450720703135;8453.661853525851;8387.83224335513;8447.98624165098;8464.153584641548;8474.830503389929;8490.066092729794;8388.245057944814;8399.160083991614;8445.57772111395;8450.02824675164;8460.407771456;8144.104148543655;8250.38122140842;8304.750857474286;8307.83384332316;8310.293625041879;36756.264874702625;37024.629753702386;37041;37075.81462092699;37079.33143008544;3542.1852973816167;3542.36237477254;3576.7675101118343;3577.83899724513;3577.9105522362147;24165;29317.120486385444;29380.559291610643;29505.70494295051;29540.704592954226;3067.907962761121;3111.766617503696;3119.578856854341;3130.1861516005774;3135.216195951012;246235;248430;268094;268978;269106.30893691204;47029.29456058162;47245;47245.29132063022;47260.8184795376;47463.52536474591;48832.75583622059;48954.75522622364;49035.26447103296;53404.73297633487;53942.46057539453;14468.348924298221;14542.927285363718;14701.338439770023;14726.779098313535;14739.852601473847;11671.708207294885;11680.474378652982;11741.4716337765;11780.057595392347;11803.291802491878;13967.930160349133;13993.30033498321;13993.790093148611;13995.230262335466;14095.929520352332;5443.265159203536;5859.970700146472;6026.5178785697035;6112.908306375408;6176.876462470816;967.3083745122271;990.1435258501489;997.2719914462338;1022.1465076660922;1036.9533370998324;24073.27780166598;24138.87930560336;24173.274801755975;24225.757742422702;24284;1080.5623722392443;1088.3361149698678;1099.8570185875944;1110.655696734015;1115.5649296774266;12317.93841030807;12321.93839030799;12331.198472099284;12331.75336493265;12365.876341236652;325322;326297;326533;328395;328514;54299.72850135803;54528.45471545313;54648.72675636596;54794.712317261095;54869.45130548723;1476.512750792236;1486.1306135910613;1513.8032055832891;1525.2373813093454;1546.327347603796;20910.47723806917;22068.78621675823;23733;23741.64387534154;23951.28146155618;36655.26689466249;37030.629693702715;37155.28447155371;37162.25675486421;37231.44152837764;266385;267894;269004;269274;272584;35007.12482187965;35345.823270883484;35533.466997995565;35557.46663799993;35567.28865422625;43003.784981074896;43285.13429731451;43731.90670233142;45177;45304.320435192836;29320.706792931796;30362.696373035986;30390.544141837447;30587.694123058485;31543.684563154075;26913.730862691908;28981.42037159287;29680;30506.542401863546;30555.54166687546;29719.25701857471;29980.550291745207;36390;36888.63111368852;37208.44187337246;22497.325080247618;30293.69706302997;30985.69014309828;31132;31336.843315783277;19025.71461428052;20966.580668386243;23994.40013999664;24508.387290317885;25402.364940876625 03:56:09 INFO - PID 1924 | |2;jquery.html;422.7717032802287;426.8911427585965;432.2457311990576;448.4887228559441;450.24134333647805;21241.68137477937;21448.249311274107;22102.894855257233;22860.314190574267;23075.192368267115;16671;22817;23445;23457.530849383;23689.644655330183;5890.558208134396;6180.505559555248;6223.502119830426;6224.035274532454;6234.8441288967915;4618.907621847561;5908.1433192187105;6030.939690603103;6080.817575472743;6165.475934545547;6089.604175728586;6095.299040610343;6095.542834287424;6107.022876339786;6118.969405152968;5855.180274761516;6025.126356678279;6089.969550152266;6095.359987201325;6128.816135515897;5438.809641662562;5458.61789674723;5466.945330546683;5486.972565137189;5495.642783219078;27432.588511172347;27709.307267318276;28031.579526307123;28073.578896316572;28254.434911301658;2182.8035476807004;2273.8749368784875;2279.985406494111;2282.6690129931235;2283.0183021300836;17636.559086022822;17859.374921877672;17963.101844907833;18078.367257146067;18260.908695456437;2138.540213854029;2149.7097891784565;2160.2331172433874;2165.5560610074895;2182.7776445190743;113214;172069.13965430218;174374.1281293598;175183;176373.1181344098;12560.874391256153;12991.870081299256;13024.934875325564;13091.738165236642;13108.868911310765;16767.077810720282;16849;16875.15624218784;16890.662186756195;16901.661966760596;8839.823203535892;9174.587143578421;9213.769655758526;9301.39540929851;9311.301652376098;8769.079246679134;8776.517291548898;8776.868346974672;8933.776655583662;8935.955320223356;7002;7975.481593696507;8131.9186808132345;8157.306628936488;8551.657933682707;2190.113004233288;2194.4294483434423;2200.130948275454;2202.757696653367;2321.68657231275;925.8935222449504;937.7421209167397;969.9466529340813;999.2712607189603;1002.754325190333;21215.045322960505;21715.782842171375;21755.564888702138;21761.03183369416;21833;866.6749968761715;891.9564109991326;1007.2244371833793;1012.2357619996856;1013.8023085498257;8288.254057134887;8597.828043439222;8988.370814042953;10915.945420272848;10942.288751231144;524750;529448;532013;534333;542112;122149.77850221562;124814;125068.24249332566;126891;127092.36453817856;1095.5289225632896;1194.6774370919977;1227.4364663270235;1257.264500267354;1261.9558315458914;22070;22501.987410566235;22545.66181507246;22635.547289054455;22642.886785565966;38738.80630596829;40199.79900100481;41618;51176.48823511866;54705.72647136739;50866.74566627143;56763.14855277257;63670.68164659147;73829.52340953043;74172.54964702135;7456.55260684339;8832.205101540892;8929.821403572023;9035.864462032943;9057.68298109563;11105.722356941142;12853.742925141258;13763.243021633907;14105.576832695035;14129.364178611782;11633;11694.064474841822;11822.113341499595;11827.17209795306;11919.880801191877;10974.945125274322;11115.555377784798;11177;11195.160362972973;11429.371384573597;11668.649940501798;11876.703082422662;11897.940510297392;11967.581134660246;12036.338001410006;8819.647214111365;8993.32550058735;9002.369834111549;9059.68291109808;9082.228010619172;9798.363106398061;11605.709857253636;11650.252462130748;11713.472893719974;11762.647120586396 03:56:09 INFO - PID 1924 | |3;mootools.html;1250.8561515425727;1441.5819412370413;1445.710857828434;1461.0065155694124;1462.378489142115;11357.886421135787;11616.825747613782;12125.454354554044;12240.44917978692;12267.202631828924;11567.710807229823;11805.40972951351;11891.940540297286;11906.583269585566;11938.283702977806;2541.4789968056552;2598.8310759800647;2602.6096085587124;2604.257786530839;2609.856457894815;2526.431552900601;2561.7950563954837;2564.397366618843;2565.9101931432406;2574.948501029978;2531.1520640585427;2547.46503234321;2557.577999630059;2559.7568231018117;2560.8463492190435;2486.552420564307;2518.6725725655638;2524.6465494830736;2528.355269406308;2534.2397280815776;2478.491909158618;2491.7757401833924;2509.2597683683357;2514.2959971208093;2587.8188526803133;11564.13269004828;11594.76810463786;11661.88338116625;11671.358075305854;11703.824442633368;6438.581492202992;6614.636195009272;6648.833779155511;6690.096836927001;6722;4007.458993035932;4220.409142720022;4299.419578356913;4306.138772245564;4308.763018034007;1265.816456613786;1265.8227848101274;1269.225772278909;1269.5683467621054;1279.948802047917;15194.924025379913;15565.922170389076;16349.42777002811;16353.264103115389;16694.49916502507;5849.912251316234;5939.287285525678;6147.538934579924;6159.84600384985;6161.168242287324;13791.241481718602;14039.017268791291;14046;14047.929760351337;14065.71868562623;6608.570442921195;6713.932860671331;6716;6729.226138994079;6751.628660423626;5060.84817455477;6025.879482410327;6155.045967874897;6180.690965451709;6226.3151053385;4176.519700234451;4649.163150632919;4677.345171675968;4718.4809670936165;4826.0347930414055;1382.8755412012765;1415.929203539819;1450.151661278164;1453.3532578002687;1469.1111877314286;801.1707882341748;808.9838203235902;824.4805772363493;826.3595713322103;849.4903058165052;6155.507559395238;6191.25704915413;6306.211723534559;6454.322296158835;6467.676616169173;515.8349328214936;592.7095723095653;612.4916319454842;928.8606708993705;994.5524513968743;4226.408302837605;4468.888277793081;4502.212112880233;4599.770011499411;4631.652626052992;14516.782248266285;14609.853901461061;14923.626909327353;15161.393544258106;15211.847881521264;14568.49010284635;15056.849431505763;15149.924250378677;15191.316390762442;15194.240287985556;7462.216467270859;8114.431989760778;8535.573221338907;8568.742937711879;8579.742607721653;6547.607143571401;7098.36114749675;7296.160941491797;7342.9632851835395;7374.668139933823;13075.280859552817;13111.475540978636;13338.19970801755;13559.932200338935;13733.969952253821;13586.32068396596;13969.720605587629;14130.78803817923;14138;14214.360353784312;10670.253082284165;10993.780124397308;11005.669829905115;11016;11024;10671.89328106709;10962.780744385227;10975.396353200651;10978.615748448767;11097.500612472297;9191.080891911022;9335.066493350882;9377.437353758796;9391.530423478935;9420.57607407682;7472.589007604634;8686.196623634558;8908.910910891067;8912.509811960144;9225.58484868169;8217.260446559838;8250.752477425687;8328.583570821556;8334.458260213067;8379.287760540423;6118.112873633388;6137.601055931529;6208.596441231305;6293.842653933688;6356.77751278703;6205.720742566505;6286.591371560834;6315.747370105144;6391.712372943136;6454.48364130859 03:56:09 INFO - PID 1924 | |4;prototype.html;356.6611718867076;392.9862454814082;395.9663428608568;396.55387688850044;410.5463462873525;5247.816326428576;5471.835844924649;5983.192269043678;6187.443130118294;6247.71885265163;4968.130577148993;5179.974100129494;5195.766190521426;5731.799387021455;5988.3712210217955;4527.52460991596;4618.237990731525;4639.141758774637;4646.930296045553;4651.65091628727;4590.724556526602;4627.722336659811;4639.976800115995;4649.44206695197;4656.185167595674;3236.433624115782;3815.214893128018;4158.251514727348;4500.347449619803;4593.356930029799;4567.474740404863;4574.9542504574865;4589.196890544158;4597.264437689971;4600.7929643166135;4050.4936882889638;4052.2300762855257;4055.837766489337;4060.9593904060876;4075.3071977763557;4315.913681726379;5179.430262671103;5496.1755736639425;5856.707164641751;5972.99904531978;4523.796429160663;4655.324977878188;5413.215083812865;5422.620416570802;5432.239486471897;6556;6658.800235992928;6716.2276338221245;6720.361565651256;6740.460763138937;4379.978100109511;4400.691951563392;4408.655360115164;4410.62509686677;4413.7351758894565;5921.615095018767;6734.528582999143;6938.577591593711;6952.374286314205;7073.292670732885;4293.849715259951;4431.357453169272;4440.955590444119;4453.51011388747;4456.908057525927;3646.288973650113;3824.043989002749;4518.525554816762;4529.13946350199;4537.319402089647;5123.462961111652;5155.6391052626705;5327.467253274642;5358.839234822962;5429.267048948421;5727.885442291131;6660.666966651648;6709.463242940553;6715.966420167868;6776.28848970851;1303.589369348652;1532.5478983699613;1542.5449492399769;1554.0054365206277;1558.4311726220076;983.2183414185739;998.9250806189565;1012.2610494339174;1014.660088870222;1019.928604997643;4930.704157750546;6019.217501724747;6207.6585787781205;6263.812085637438;6292.528060395397;5322.988632159954;6773.695183716744;6915.4121899638085;6924.688389022506;6926.826829329207;5736.942630573641;5960.761569537257;6142.754289828356;6283.59156654816;6298.929182038973;2833.7591304739326;3135.404273188087;3301.752368572367;3414.965850341465;3637.9636203637624;5818.738156782955;6013.819585412444;6440.54916155874;6863.764522386018;7069.151701795818;5340.359156901197;6315.894718424349;6487.448566871774;7106.436583951522;7180.5691658500655;5903.645781253139;6219.533534984804;6892.965535172292;6972.372486476242;7084.149902011689;6246.406591373994;6837.555558888856;6883.414909732729;6913.481488888454;6987.056747339044;5287.365516138088;6252.874942501034;6348.460380867678;6726.39462448382;6795.694193761392;6444.8388790280615;6916.827079322856;7108.111486064242;7119.430445564446;7136.393406560501;4500;4817.614590832795;4966.230234313687;5030.270610761492;5059.16523773588;6764.458843292623;6871.278515755974;6881.763426886182;6936.2023367311385;7034.824129396806;5819.534437244925;6419.748149111066;6634.601923884582;6652.800415987528;6660.83347916306;6433.903491447539;6602.801915942529;6635.777014700129;6645.235797883305;6660;6522.706478208397;6545.312742162195;6591.110200122922;6593.637349945799;6700.46396288286;5287.889543195972;6446.8388290293115;6655.169828297064;6678.499112566674;6700.396964273239;6220.471259942956;6540.280569137486;6553.705083271169;6605.636689982097;6682.599044057372 03:56:09 INFO - PID 1924 | |5;yui.html;753.1827966656177;823.5223570329209;829.7718127514936;859.7850537365658;866.571047331571;16888.32446702132;17349.132543372827;17389.13054347284;17571.033593152373;18054.5486362841;15474.922625386886;17918.462446126636;18097.1856266468;18295.634087318245;18516.722249166272;816.2490508731951;951.6954574536134;957.6313119448993;968.2302569457263;975.3611384543135;982.4694664880956;986.8865080515761;995.7162208770502;998.610541888664;999.4303247149128;1044.399470304578;1044.6552637629602;1052.2213561964168;1053.157474020784;1053.778706471642;1036.4092467293647;1047.3715770537653;1050.9316894401877;1052.4369462337652;1054.910332621728;759.2104211619964;771.6797529025448;772.1622040086513;772.9912464234172;773.2383602151876;17396.56508587283;17951.73072403915;18036.09819509019;18199.909000454914;18320.633587328313;17394.565135871577;17472.563185920328;17577.29690812366;17976.91011544947;18020.36928707489;14763.557093287218;14792.334344954503;14920.925395372953;14957.102573845605;14957.70084598302;3418.5214070030215;3516.6659167379057;3541.610422853484;3558.323918455511;3566.144125409909;96757;104733;112204.87795122107;142173.2891335547;145845.27077364546;13093.345332733325;13352.465901363837;13409.664758380923;13543.864561354458;13558.983076269122;23888.28335149948;25730.970761169345;26665.066722665;26972.190834275003;26986.595201072;7391.817309230497;7799.33705635016;8035.316998055114;8098.149694282133;8119.106898241189;3821.7133714971487;3972.801359931992;4024.1348110156455;4029.5768944261017;4031.596840315945;4424.3142312941545;4504.81980720774;4506.918339598473;4516.706414083074;4522.841700540465;863.5509535041743;897.6140259688397;902.8194361127801;904.7331037344;905.8278927003848;658.7101675262869;665.5707068940507;679.2121139478181;680.5474359550942;680.9445359692456;10076.395416275047;10708.755211435286;11167.218294719454;11989.580364687194;12317.630471085882;649.0880313160018;651.4007113455538;653.0922018394435;666.2571233075087;681.2574294019444;4982.8754281143265;5210.713410762369;5326.36083669962;5610.5231055360755;5854.092615644582;98161;145104;147536;150026;150601.24699376652;102557.43606409899;121483.17775233378;127247.36376318059;129514;130067.34966325297;95787.35160158375;105578.47210764051;108940;112396;113334.29998550193;10581.62964296246;10957.342559446459;11290.661280161608;11447.542098315975;11623;39647.40528892011;41219;44974.1005179888;45321.093578128915;45741;62181.68909155425;62438.375616243255;63361.0495842572;63430.048549272724;64320.67839660772;14218.786718199442;14426.495072672406;14862.925685371503;14871.925640371728;14932.477363292233;13607.455701771818;13688;16141;16241.269142888363;16481.505554833373;12337.691557711129;12459.875401246234;13463.461461541428;15196.012259203117;15326;14308.713825723218;14653.780193297322;14915.955883088514;14979.625509362353;15189.012714173543;14286.857131428553;14613.70772584521;14826.481073162387;14901;14963.40146394132;9363.859542106738;10214.72315960505;10713.571457141627;11111.888881111085;11146.944265278622;11789.941050294694;11888.9894358977;11925.165238433226;11940.582079627171;12018.879811201776 03:56:09 INFO - PID 1924 | __end_tp_report 03:56:09 INFO - PID 1924 | __start_cc_report 03:56:09 INFO - PID 1924 | _x_x_mozilla_cycle_collect,405 03:56:09 INFO - PID 1924 | __end_cc_report 03:56:09 INFO - PID 1924 | __startTimestamp1495796169699__endTimestamp 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | ------- Summary: start ------- 03:56:09 INFO - PID 1924 | Number of tests: 6 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | [#0] dojo.html Cycles:165 Average:6128.91 Median:6343.08 stddev:752.24 (11.9%) stddev-sans-first:711.37 03:56:09 INFO - PID 1924 | Values: 2926.7 3597.5 3609.1 3631.1 3641.8 6101.4 6103.4 6177.8 6189.3 6196.5 4556.0 6159.6 6171.1 6176.0 6256.2 5197.2 5381.1 5392.1 5400.2 5449.1 5510.3 5519.8 5535.9 5538.3 5544.8 4616.9 5356.4 5417.3 5417.5 5427.8 5452.2 5466.3 5480.3 5490.8 5497.7 5371.1 5382.7 5399.9 5407.3 5423.3 4994.9 6109.3 6211.9 6236.1 6238.5 5865.3 6517.7 6548.6 6639.5 6660.3 6011.2 6449.3 6786.7 6802.2 6832.2 6268.3 6271.0 6293.4 6308.2 6312.7 6701.2 6773.7 6834.4 6893.5 6913.7 6343.1 6359.5 6420.6 6571.7 6602.6 6672.0 6768.3 6783.0 6790.0 6809.9 6610.8 6643.0 6662.4 6663.6 6684.3 6427.2 6498.0 6570.0 6662.1 6694.8 5343.5 5361.0 5372.4 5385.0 5413.2 4762.4 4762.8 4792.0 4808.4 4838.1 6191.7 6199.5 6255.4 6283.9 6317.9 6705.3 6734.6 6797.4 6805.4 6811.0 6274.7 6288.8 6293.5 6306.5 6312.7 5562.9 5755.1 5807.0 6002.2 6023.0 6794.9 6822.2 6917.0 6918.0 6950.0 5923.9 6524.0 6726.4 6818.5 6820.0 6918.3 6964.1 6976.6 6989.7 6993.9 5267.7 6241.9 6497.3 6567.6 6606.7 6760.2 6800.4 6810.7 6811.9 6834.5 6376.4 6450.8 6546.7 6574.1 6605.5 6465.6 6507.7 6521.4 6537.1 6559.2 6403.6 6445.5 6464.5 6470.1 6474.9 6390.5 6605.4 6605.6 6620.2 6640.2 6277.3 6329.3 6399.5 6447.6 6448.3 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | [#1] ext.html Cycles:180 Average:43728.97 Median:181760.56 stddev:75212.39 (41.4%) stddev-sans-first:75354.16 03:56:09 INFO - PID 1924 | Values: 752.9 797.4 813.9 1216.6 1287.5 38200.4 39189.6 39200.8 39221.2 39371.8 39573.2 39702.6 39953.2 39959.0 39999.8 7759.8 7913.6 8251.3 8456.8 8471.4 8346.6 8361.9 8446.8 8450.5 8453.7 8387.8 8448.0 8464.2 8474.8 8490.1 8388.2 8399.2 8445.6 8450.0 8460.4 8144.1 8250.4 8304.8 8307.8 8310.3 36756.3 37024.6 37041.0 37075.8 37079.3 3542.2 3542.4 3576.8 3577.8 3577.9 24165.0 29317.1 29380.6 29505.7 29540.7 3067.9 3111.8 3119.6 3130.2 3135.2 246235.0 248430.0 268094.0 268978.0 269106.3 47029.3 47245.0 47245.3 47260.8 47463.5 48832.8 48954.8 49035.3 53404.7 53942.5 14468.3 14542.9 14701.3 14726.8 14739.9 11671.7 11680.5 11741.5 11780.1 11803.3 13967.9 13993.3 13993.8 13995.2 14095.9 5443.3 5860.0 6026.5 6112.9 6176.9 967.3 990.1 997.3 1022.1 1037.0 24073.3 24138.9 24173.3 24225.8 24284.0 1080.6 1088.3 1099.9 1110.7 1115.6 12317.9 12321.9 12331.2 12331.8 12365.9 325322.0 326297.0 326533.0 328395.0 328514.0 54299.7 54528.5 54648.7 54794.7 54869.5 1476.5 1486.1 1513.8 1525.2 1546.3 20910.5 22068.8 23733.0 23741.6 23951.3 36655.3 37030.6 37155.3 37162.3 37231.4 266385.0 267894.0 269004.0 269274.0 272584.0 35007.1 35345.8 35533.5 35557.5 35567.3 43003.8 43285.1 43731.9 45177.0 45304.3 29320.7 30362.7 30390.5 30587.7 31543.7 26913.7 28981.4 29680.0 30506.5 30555.5 29719.3 29980.6 36390.0 36888.6 37208.4 22497.3 30293.7 30985.7 31132.0 31336.8 19025.7 20966.6 23994.4 24508.4 25402.4 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | [#2] jquery.html Cycles:180 Average:34592.83 Median:12698.44 stddev:90666.32 (714.0%) stddev-sans-first:90884.36 03:56:09 INFO - PID 1924 | Values: 422.8 426.9 432.2 448.5 450.2 21241.7 21448.2 22102.9 22860.3 23075.2 16671.0 22817.0 23445.0 23457.5 23689.6 5890.6 6180.5 6223.5 6224.0 6234.8 4618.9 5908.1 6030.9 6080.8 6165.5 6089.6 6095.3 6095.5 6107.0 6119.0 5855.2 6025.1 6090.0 6095.4 6128.8 5438.8 5458.6 5466.9 5487.0 5495.6 27432.6 27709.3 28031.6 28073.6 28254.4 2182.8 2273.9 2280.0 2282.7 2283.0 17636.6 17859.4 17963.1 18078.4 18260.9 2138.5 2149.7 2160.2 2165.6 2182.8 113214.0 172069.1 174374.1 175183.0 176373.1 12560.9 12991.9 13024.9 13091.7 13108.9 16767.1 16849.0 16875.2 16890.7 16901.7 8839.8 9174.6 9213.8 9301.4 9311.3 8769.1 8776.5 8776.9 8933.8 8936.0 7002.0 7975.5 8131.9 8157.3 8551.7 2190.1 2194.4 2200.1 2202.8 2321.7 925.9 937.7 969.9 999.3 1002.8 21215.0 21715.8 21755.6 21761.0 21833.0 866.7 892.0 1007.2 1012.2 1013.8 8288.3 8597.8 8988.4 10915.9 10942.3 524750.0 529448.0 532013.0 534333.0 542112.0 122149.8 124814.0 125068.2 126891.0 127092.4 1095.5 1194.7 1227.4 1257.3 1262.0 22070.0 22502.0 22545.7 22635.5 22642.9 38738.8 40199.8 41618.0 51176.5 54705.7 50866.7 56763.1 63670.7 73829.5 74172.5 7456.6 8832.2 8929.8 9035.9 9057.7 11105.7 12853.7 13763.2 14105.6 14129.4 11633.0 11694.1 11822.1 11827.2 11919.9 10974.9 11115.6 11177.0 11195.2 11429.4 11668.6 11876.7 11897.9 11967.6 12036.3 8819.6 8993.3 9002.4 9059.7 9082.2 9798.4 11605.7 11650.3 11713.5 11762.6 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | [#3] mootools.html Cycles:180 Average:7282.08 Median:2593.32 stddev:4584.59 (176.8%) stddev-sans-first:4575.05 03:56:09 INFO - PID 1924 | Values: 1250.9 1441.6 1445.7 1461.0 1462.4 11357.9 11616.8 12125.5 12240.4 12267.2 11567.7 11805.4 11891.9 11906.6 11938.3 2541.5 2598.8 2602.6 2604.3 2609.9 2526.4 2561.8 2564.4 2565.9 2574.9 2531.2 2547.5 2557.6 2559.8 2560.8 2486.6 2518.7 2524.6 2528.4 2534.2 2478.5 2491.8 2509.3 2514.3 2587.8 11564.1 11594.8 11661.9 11671.4 11703.8 6438.6 6614.6 6648.8 6690.1 6722.0 4007.5 4220.4 4299.4 4306.1 4308.8 1265.8 1265.8 1269.2 1269.6 1279.9 15194.9 15565.9 16349.4 16353.3 16694.5 5849.9 5939.3 6147.5 6159.8 6161.2 13791.2 14039.0 14046.0 14047.9 14065.7 6608.6 6713.9 6716.0 6729.2 6751.6 5060.8 6025.9 6155.0 6180.7 6226.3 4176.5 4649.2 4677.3 4718.5 4826.0 1382.9 1415.9 1450.2 1453.4 1469.1 801.2 809.0 824.5 826.4 849.5 6155.5 6191.3 6306.2 6454.3 6467.7 515.8 592.7 612.5 928.9 994.6 4226.4 4468.9 4502.2 4599.8 4631.7 14516.8 14609.9 14923.6 15161.4 15211.8 14568.5 15056.8 15149.9 15191.3 15194.2 7462.2 8114.4 8535.6 8568.7 8579.7 6547.6 7098.4 7296.2 7343.0 7374.7 13075.3 13111.5 13338.2 13559.9 13734.0 13586.3 13969.7 14130.8 14138.0 14214.4 10670.3 10993.8 11005.7 11016.0 11024.0 10671.9 10962.8 10975.4 10978.6 11097.5 9191.1 9335.1 9377.4 9391.5 9420.6 7472.6 8686.2 8908.9 8912.5 9225.6 8217.3 8250.8 8328.6 8334.5 8379.3 6118.1 6137.6 6208.6 6293.8 6356.8 6205.7 6286.6 6315.7 6391.7 6454.5 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | [#4] prototype.html Cycles:180 Average:5290.29 Median:5838.12 stddev:1681.54 (28.8%) stddev-sans-first:1644.98 03:56:09 INFO - PID 1924 | Values: 356.7 393.0 396.0 396.6 410.5 5247.8 5471.8 5983.2 6187.4 6247.7 4968.1 5180.0 5195.8 5731.8 5988.4 4527.5 4618.2 4639.1 4646.9 4651.7 4590.7 4627.7 4640.0 4649.4 4656.2 3236.4 3815.2 4158.3 4500.3 4593.4 4567.5 4575.0 4589.2 4597.3 4600.8 4050.5 4052.2 4055.8 4061.0 4075.3 4315.9 5179.4 5496.2 5856.7 5973.0 4523.8 4655.3 5413.2 5422.6 5432.2 6556.0 6658.8 6716.2 6720.4 6740.5 4380.0 4400.7 4408.7 4410.6 4413.7 5921.6 6734.5 6938.6 6952.4 7073.3 4293.8 4431.4 4441.0 4453.5 4456.9 3646.3 3824.0 4518.5 4529.1 4537.3 5123.5 5155.6 5327.5 5358.8 5429.3 5727.9 6660.7 6709.5 6716.0 6776.3 1303.6 1532.5 1542.5 1554.0 1558.4 983.2 998.9 1012.3 1014.7 1019.9 4930.7 6019.2 6207.7 6263.8 6292.5 5323.0 6773.7 6915.4 6924.7 6926.8 5736.9 5960.8 6142.8 6283.6 6298.9 2833.8 3135.4 3301.8 3415.0 3638.0 5818.7 6013.8 6440.5 6863.8 7069.2 5340.4 6315.9 6487.4 7106.4 7180.6 5903.6 6219.5 6893.0 6972.4 7084.1 6246.4 6837.6 6883.4 6913.5 6987.1 5287.4 6252.9 6348.5 6726.4 6795.7 6444.8 6916.8 7108.1 7119.4 7136.4 4500.0 4817.6 4966.2 5030.3 5059.2 6764.5 6871.3 6881.8 6936.2 7034.8 5819.5 6419.7 6634.6 6652.8 6660.8 6433.9 6602.8 6635.8 6645.2 6660.0 6522.7 6545.3 6591.1 6593.6 6700.5 5287.9 6446.8 6655.2 6678.5 6700.4 6220.5 6540.3 6553.7 6605.6 6682.6 03:56:09 INFO - PID 1924 | 03:56:09 INFO - PID 1924 | [#5] yui.html Cycles:180 Average:24188.75 Median:17935.10 stddev:37316.02 (208.1%) stddev-sans-first:37379.21 03:56:09 INFO - PID 1924 | Values: 753.2 823.5 829.8 859.8 866.6 16888.3 17349.1 17389.1 17571.0 18054.5 15474.9 17918.5 18097.2 18295.6 18516.7 816.2 951.7 957.6 968.2 975.4 982.5 986.9 995.7 998.6 999.4 1044.4 1044.7 1052.2 1053.2 1053.8 1036.4 1047.4 1050.9 1052.4 1054.9 759.2 771.7 772.2 773.0 773.2 17396.6 17951.7 18036.1 18199.9 18320.6 17394.6 17472.6 17577.3 17976.9 18020.4 14763.6 14792.3 14920.9 14957.1 14957.7 3418.5 3516.7 3541.6 3558.3 3566.1 96757.0 104733.0 112204.9 142173.3 145845.3 13093.3 13352.5 13409.7 13543.9 13559.0 23888.3 25731.0 26665.1 26972.2 26986.6 7391.8 7799.3 8035.3 8098.1 8119.1 3821.7 3972.8 4024.1 4029.6 4031.6 4424.3 4504.8 4506.9 4516.7 4522.8 863.6 897.6 902.8 904.7 905.8 658.7 665.6 679.2 680.5 680.9 10076.4 10708.8 11167.2 11989.6 12317.6 649.1 651.4 653.1 666.3 681.3 4982.9 5210.7 5326.4 5610.5 5854.1 98161.0 145104.0 147536.0 150026.0 150601.2 102557.4 121483.2 127247.4 129514.0 130067.3 95787.4 105578.5 108940.0 112396.0 113334.3 10581.6 10957.3 11290.7 11447.5 11623.0 39647.4 41219.0 44974.1 45321.1 45741.0 62181.7 62438.4 63361.0 63430.0 64320.7 14218.8 14426.5 14862.9 14871.9 14932.5 13607.5 13688.0 16141.0 16241.3 16481.5 12337.7 12459.9 13463.5 15196.0 15326.0 14308.7 14653.8 14916.0 14979.6 15189.0 14286.9 14613.7 14826.5 14901.0 14963.4 9363.9 10214.7 10713.6 11111.9 11146.9 11789.9 11889.0 11925.2 11940.6 12018.9 03:56:09 INFO - PID 1924 | -------- Summary: end -------- 03:56:09 INFO - PID 1924 | 03:56:10 INFO - TEST-INFO | 1924: exit 0 03:56:10 INFO - TEST-OK | dromaeo_css | took 1142334ms 03:56:10 INFO - TEST-START | kraken 03:56:10 INFO - operating with platform_type : mac_ 03:56:10 INFO - Initialising browser for kraken test... 03:56:10 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmphM1V7n/profile 03:56:10 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49346/getInfo.html -foreground) 03:56:43 INFO - TEST-INFO | 1935: killed by SIGTERM 03:56:43 INFO - Browser initialized. 03:56:43 INFO - Running cycle 1/1 for kraken test... 03:56:43 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmphM1V7n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:45 INFO - PID 1939 | 2017-05-26 03:56:45.818 plugin-container[1940:11620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 03:56:45 INFO - PID 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:45 INFO - PID 1939 | 2017-05-26 03:56:45.818 plugin-container[1940:11620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 03:56:45 INFO - PID 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:45 INFO - PID 1939 | 2017-05-26 03:56:45.818 plugin-container[1940:11620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:56:46 INFO - PID 1939 | 2017-05-26 03:56:46.427 plugin-container[1941:11664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:56:46 INFO - PID 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:46 INFO - PID 1939 | 2017-05-26 03:56:46.427 plugin-container[1941:11664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 03:56:46 INFO - PID 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:46 INFO - PID 1939 | 2017-05-26 03:56:46.427 plugin-container[1941:11664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:56:55 INFO - PID 1939 | RSS: Main: 242528256 03:56:55 INFO - PID 1939 | 03:56:58 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-beat-detection) 03:56:59 INFO - PID 1939 | RSS: Main: 256516096 03:56:59 INFO - PID 1939 | 03:57:01 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-dft) 03:57:02 INFO - PID 1939 | RSS: Main: 265625600 03:57:02 INFO - PID 1939 | 03:57:06 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-fft) 03:57:06 INFO - PID 1939 | RSS: Main: 268738560 03:57:06 INFO - PID 1939 | 03:57:08 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49346/tests/kraken/driver.html?testName=audio-oscillator) 03:57:09 INFO - PID 1939 | RSS: Main: 269606912 03:57:09 INFO - PID 1939 | 03:57:11 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49346/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:57:11 INFO - PID 1939 | RSS: Main: 270110720 03:57:11 INFO - PID 1939 | 03:57:14 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49346/tests/kraken/driver.html?testName=imaging-darkroom) 03:57:15 INFO - PID 1939 | RSS: Main: 276320256 03:57:15 INFO - PID 1939 | 03:57:18 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49346/tests/kraken/driver.html?testName=imaging-desaturate) 03:57:18 INFO - PID 1939 | RSS: Main: 279150592 03:57:18 INFO - PID 1939 | 03:57:22 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49346/tests/kraken/driver.html?testName=json-parse-financial) 03:57:22 INFO - PID 1939 | RSS: Main: 278163456 03:57:22 INFO - PID 1939 | 03:57:24 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49346/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:57:24 INFO - PID 1939 | RSS: Main: 277852160 03:57:24 INFO - PID 1939 | 03:57:26 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:57:27 INFO - PID 1939 | RSS: Main: 277897216 03:57:27 INFO - PID 1939 | 03:57:29 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:57:30 INFO - PID 1939 | RSS: Main: 277721088 03:57:30 INFO - PID 1939 | 03:57:32 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:57:33 INFO - PID 1939 | RSS: Main: 277319680 03:57:33 INFO - PID 1939 | 03:57:36 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:57:37 INFO - PID 1939 | RSS: Main: 277544960 03:57:37 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | Cycle 1(1): loaded http://localhost:49346/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49346/tests/kraken/driver.html?testName=ai-astar) 03:57:39 INFO - PID 1939 | RSS: Main: 279400448 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | __start_tp_report 03:57:39 INFO - PID 1939 | _x_x_mozilla_page_load 03:57:39 INFO - PID 1939 | _x_x_mozilla_page_load_details 03:57:39 INFO - PID 1939 | |i|pagename|runs| 03:57:39 INFO - PID 1939 | |0;ai-astar;143;174;162;116;115;116;115;117;116;115 03:57:39 INFO - PID 1939 | |1;audio-beat-detection;136;112;120;153;99;91;87;131;140;137 03:57:39 INFO - PID 1939 | |2;audio-dft;199;196;197;199;203;219;266;290;280;200 03:57:39 INFO - PID 1939 | |3;audio-fft;73;58;58;84;61;60;58;61;86;85 03:57:39 INFO - PID 1939 | |4;audio-oscillator;86;71;82;71;78;104;99;73;71;91 03:57:39 INFO - PID 1939 | |5;imaging-gaussian-blur;93;91;92;92;91;91;92;91;96;91 03:57:39 INFO - PID 1939 | |6;imaging-darkroom;99;99;99;97;98;98;106;100;99;99 03:57:39 INFO - PID 1939 | |7;imaging-desaturate;90;90;92;122;83;148;152;92;86;88 03:57:39 INFO - PID 1939 | |8;json-parse-financial;51;51;52;51;56;71;78;78;78;67 03:57:39 INFO - PID 1939 | |9;json-stringify-tinderbox;63;57;69;60;63;59;56;58;58;59 03:57:39 INFO - PID 1939 | |10;stanford-crypto-aes;86;87;87;85;89;90;88;89;122;87 03:57:39 INFO - PID 1939 | |11;stanford-crypto-ccm;149;139;134;141;137;141;146;125;157;140 03:57:39 INFO - PID 1939 | |12;stanford-crypto-pbkdf2;189;192;265;216;185;186;189;209;221;221 03:57:39 INFO - PID 1939 | |13;stanford-crypto-sha256-iterative;68;68;67;71;68;70;62;70;98;97 03:57:39 INFO - PID 1939 | __end_tp_report 03:57:39 INFO - PID 1939 | __start_cc_report 03:57:39 INFO - PID 1939 | _x_x_mozilla_cycle_collect,1050 03:57:39 INFO - PID 1939 | __end_cc_report 03:57:39 INFO - PID 1939 | __startTimestamp1495796259559__endTimestamp 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | ------- Summary: start ------- 03:57:39 INFO - PID 1939 | Number of tests: 14 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#0] ai-astar Cycles:10 Average:128.90 Median:116.50 stddev:22.48 (19.3%) stddev-sans-first:23.26 03:57:39 INFO - PID 1939 | Values: 143.0 174.0 162.0 116.0 115.0 116.0 115.0 117.0 116.0 115.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#1] audio-beat-detection Cycles:10 Average:120.60 Median:146.50 stddev:22.57 (15.4%) stddev-sans-first:23.25 03:57:39 INFO - PID 1939 | Values: 136.0 112.0 120.0 153.0 99.0 91.0 87.0 131.0 140.0 137.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#2] audio-dft Cycles:10 Average:224.90 Median:211.00 stddev:38.08 (18.0%) stddev-sans-first:39.22 03:57:39 INFO - PID 1939 | Values: 199.0 196.0 197.0 199.0 203.0 219.0 266.0 290.0 280.0 200.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#3] audio-fft Cycles:10 Average:68.40 Median:67.00 stddev:12.27 (18.3%) stddev-sans-first:12.90 03:57:39 INFO - PID 1939 | Values: 73.0 58.0 58.0 84.0 61.0 60.0 58.0 61.0 86.0 85.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:80.00 stddev:12.14 (15.2%) stddev-sans-first:12.81 03:57:39 INFO - PID 1939 | Values: 86.0 71.0 82.0 71.0 78.0 104.0 99.0 73.0 71.0 91.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#5] imaging-gaussian-blur Cycles:10 Average:92.00 Median:92.00 stddev:1.56 (1.7%) stddev-sans-first:1.62 03:57:39 INFO - PID 1939 | Values: 93.0 91.0 92.0 92.0 91.0 91.0 92.0 91.0 96.0 91.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:99.00 stddev:2.46 (2.5%) stddev-sans-first:2.60 03:57:39 INFO - PID 1939 | Values: 99.0 99.0 99.0 97.0 98.0 98.0 106.0 100.0 99.0 99.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#7] imaging-desaturate Cycles:10 Average:104.30 Median:89.00 stddev:26.38 (29.6%) stddev-sans-first:27.47 03:57:39 INFO - PID 1939 | Values: 90.0 90.0 92.0 122.0 83.0 148.0 152.0 92.0 86.0 88.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:69.00 stddev:12.28 (17.8%) stddev-sans-first:12.19 03:57:39 INFO - PID 1939 | Values: 51.0 51.0 52.0 51.0 56.0 71.0 78.0 78.0 78.0 67.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:59.50 stddev:3.85 (6.5%) stddev-sans-first:3.95 03:57:39 INFO - PID 1939 | Values: 63.0 57.0 69.0 60.0 63.0 59.0 56.0 58.0 58.0 59.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:87.50 stddev:10.99 (12.6%) stddev-sans-first:11.51 03:57:39 INFO - PID 1939 | Values: 86.0 87.0 87.0 85.0 89.0 90.0 88.0 89.0 122.0 87.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:141.00 stddev:8.63 (6.1%) stddev-sans-first:8.65 03:57:39 INFO - PID 1939 | Values: 149.0 139.0 134.0 141.0 137.0 141.0 146.0 125.0 157.0 140.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.30 Median:212.50 stddev:25.03 (11.8%) stddev-sans-first:25.66 03:57:39 INFO - PID 1939 | Values: 189.0 192.0 265.0 216.0 185.0 186.0 189.0 209.0 221.0 221.0 03:57:39 INFO - PID 1939 | 03:57:39 INFO - PID 1939 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:70.00 stddev:12.68 (18.1%) stddev-sans-first:13.27 03:57:39 INFO - PID 1939 | Values: 68.0 68.0 67.0 71.0 68.0 70.0 62.0 70.0 98.0 97.0 03:57:39 INFO - PID 1939 | -------- Summary: end -------- 03:57:39 INFO - PID 1939 | 03:57:40 INFO - TEST-INFO | 1939: exit 0 03:57:40 INFO - TEST-OK | kraken | took 89998ms 03:57:40 INFO - SUITE-END | took 1232s 03:57:40 INFO - Completed test suite (00:20:32) 03:57:40 INFO - javascript benchmark 03:57:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7888.228615364735, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2926.6634337051237, 3597.46038094286, 3609.0616439725663, 3631.110377957401, 3641.817909104546, 6101.35935726749, 6103.398280005564, 6177.752889884405, 6189.288231853344, 6196.504279657628, 4555.9544404555945, 6159.568830181889, 6171.13604095427, 6176.0, 6256.186695729549, 5197.220416937452, 5381.112116500773, 5392.083345831198, 5400.189971504276, 5449.128139497681, 5510.283663123788, 5519.806806761764, 5535.865147644723, 5538.252335934638, 5544.833654990337, 4616.861494155164, 5356.4375740547075, 5417.26866872975, 5417.45825417459, 5427.83716488506, 5452.236686863842, 5466.289382380265, 5480.314960629921, 5490.80782172626, 5497.697626630534, 5371.140617501181, 5382.730863456851, 5399.919001214985, 5407.297051383295, 5423.349198096214, 4994.925076123861, 6109.2528441424865, 6211.906821397637, 6236.064590311445, 6238.4697300729495, 5865.296164460249, 6517.706703198368, 6548.639824809634, 6639.50203734717, 6660.267370589231, 6011.248593925759, 6449.32282110381, 6786.694598743068, 6802.217744959344, 6832.180138383376, 6268.341824108403, 6270.968645156745, 6293.370662933716, 6308.179936608258, 6312.747490100436, 6701.195856497252, 6773.6613169341335, 6834.384905358542, 6893.4829887758615, 6913.688884000131, 6343.080253363237, 6359.459445947054, 6420.582662126947, 6571.737130514726, 6602.603843769293, 6672.032555279555, 6768.289329620319, 6782.966085169642, 6789.981502774524, 6809.897851532231, 6610.768623098168, 6643.0, 6662.43369313604, 6663.566868153554, 6684.2981486944145, 6427.22873255203, 6497.9675101625135, 6570.0, 6662.067310576524, 6694.799156025327, 5343.5190832824455, 5361.0, 5372.382176049726, 5384.999999999922, 5413.160960051198, 4762.4285085789925, 4762.833300834454, 4791.976040119846, 4808.3989501312335, 4838.104950584153, 6191.659458729723, 6199.535034872402, 6255.374462553799, 6283.937160628335, 6317.936820631827, 6705.2624211336715, 6734.595924244561, 6797.422219111431, 6805.3875151236625, 6811.0, 6274.6548939807835, 6288.779892703734, 6293.496520278367, 6306.527010474141, 6312.652804095819, 5562.943040822231, 5755.1367294906095, 5807.0, 6002.249718785152, 6022.969885150546, 6794.932050679528, 6822.181338239542, 6917.0, 6918.0314755933205, 6950.0, 5923.911141332969, 6523.956167013255, 6726.3609957054005, 6818.488613353919, 6820.0, 6918.308169183041, 6964.094667693167, 6976.616286104313, 6989.720411183495, 6993.860122797414, 5267.683938963674, 6241.937580624136, 6497.285298617243, 6567.638779867153, 6606.735730570724, 6760.222574404006, 6800.353966373186, 6810.6594670265295, 6811.89782153278, 6834.521583489108, 6376.39424254695, 6450.838729031624, 6546.705398257185, 6574.11249481333, 6605.504587155887, 6465.612063276219, 6507.707153178024, 6521.413072823469, 6537.0521274413995, 6559.212894452697, 6403.551751377358, 6445.452136568444, 6464.515161362822, 6470.094186813944, 6474.870502589828, 6390.456811170917, 6605.405513503808, 6605.636689982097, 6620.205575330991, 6640.169978752656, 6277.3408792078, 6329.303776584664, 6399.520035997226, 6447.580907241015, 6448.290688024407], "value": 6078.667191733439, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.9458757739164, 797.4338219863896, 813.8535063688536, 1216.568118317997, 1287.5429222625962, 38200.42699359509, 39189.60810391895, 39200.80399598005, 39221.21557568847, 39371.80314098425, 39573.20853582927, 39702.60297397025, 39953.20093598134, 39959.0, 39999.800005999794, 7759.767206983771, 7913.564753938531, 8251.257386835183, 8456.83086338273, 8471.36464765142, 8346.624401901929, 8361.874571881426, 8446.83106337873, 8450.450720703135, 8453.661853525851, 8387.83224335513, 8447.98624165098, 8464.153584641548, 8474.830503389929, 8490.066092729794, 8388.245057944814, 8399.160083991614, 8445.57772111395, 8450.02824675164, 8460.407771456, 8144.104148543655, 8250.38122140842, 8304.750857474286, 8307.83384332316, 8310.293625041879, 36756.264874702625, 37024.629753702386, 37041.0, 37075.81462092699, 37079.33143008544, 3542.1852973816167, 3542.36237477254, 3576.7675101118343, 3577.83899724513, 3577.9105522362147, 24165.0, 29317.120486385444, 29380.559291610643, 29505.70494295051, 29540.704592954226, 3067.907962761121, 3111.766617503696, 3119.578856854341, 3130.1861516005774, 3135.216195951012, 246235.0, 248430.0, 268094.0, 268978.0, 269106.30893691204, 47029.29456058162, 47245.0, 47245.29132063022, 47260.8184795376, 47463.52536474591, 48832.75583622059, 48954.75522622364, 49035.26447103296, 53404.73297633487, 53942.46057539453, 14468.348924298221, 14542.927285363718, 14701.338439770023, 14726.779098313535, 14739.852601473847, 11671.708207294885, 11680.474378652982, 11741.4716337765, 11780.057595392347, 11803.291802491878, 13967.930160349133, 13993.30033498321, 13993.790093148611, 13995.230262335466, 14095.929520352332, 5443.265159203536, 5859.970700146472, 6026.5178785697035, 6112.908306375408, 6176.876462470816, 967.3083745122271, 990.1435258501489, 997.2719914462338, 1022.1465076660922, 1036.9533370998324, 24073.27780166598, 24138.87930560336, 24173.274801755975, 24225.757742422702, 24284.0, 1080.5623722392443, 1088.3361149698678, 1099.8570185875944, 1110.655696734015, 1115.5649296774266, 12317.93841030807, 12321.93839030799, 12331.198472099284, 12331.75336493265, 12365.876341236652, 325322.0, 326297.0, 326533.0, 328395.0, 328514.0, 54299.72850135803, 54528.45471545313, 54648.72675636596, 54794.712317261095, 54869.45130548723, 1476.512750792236, 1486.1306135910613, 1513.8032055832891, 1525.2373813093454, 1546.327347603796, 20910.47723806917, 22068.78621675823, 23733.0, 23741.64387534154, 23951.28146155618, 36655.26689466249, 37030.629693702715, 37155.28447155371, 37162.25675486421, 37231.44152837764, 266385.0, 267894.0, 269004.0, 269274.0, 272584.0, 35007.12482187965, 35345.823270883484, 35533.466997995565, 35557.46663799993, 35567.28865422625, 43003.784981074896, 43285.13429731451, 43731.90670233142, 45177.0, 45304.320435192836, 29320.706792931796, 30362.696373035986, 30390.544141837447, 30587.694123058485, 31543.684563154075, 26913.730862691908, 28981.42037159287, 29680.0, 30506.542401863546, 30555.54166687546, 29719.25701857471, 29980.550291745207, 36390.0, 36888.63111368852, 37208.44187337246, 22497.325080247618, 30293.69706302997, 30985.69014309828, 31132.0, 31336.843315783277, 19025.71461428052, 20966.580668386243, 23994.40013999664, 24508.387290317885, 25402.364940876625], "value": 17344.971790984357, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.7717032802287, 426.8911427585965, 432.2457311990576, 448.4887228559441, 450.24134333647805, 21241.68137477937, 21448.249311274107, 22102.894855257233, 22860.314190574267, 23075.192368267115, 16671.0, 22817.0, 23445.0, 23457.530849383, 23689.644655330183, 5890.558208134396, 6180.505559555248, 6223.502119830426, 6224.035274532454, 6234.8441288967915, 4618.907621847561, 5908.1433192187105, 6030.939690603103, 6080.817575472743, 6165.475934545547, 6089.604175728586, 6095.299040610343, 6095.542834287424, 6107.022876339786, 6118.969405152968, 5855.180274761516, 6025.126356678279, 6089.969550152266, 6095.359987201325, 6128.816135515897, 5438.809641662562, 5458.61789674723, 5466.945330546683, 5486.972565137189, 5495.642783219078, 27432.588511172347, 27709.307267318276, 28031.579526307123, 28073.578896316572, 28254.434911301658, 2182.8035476807004, 2273.8749368784875, 2279.985406494111, 2282.6690129931235, 2283.0183021300836, 17636.559086022822, 17859.374921877672, 17963.101844907833, 18078.367257146067, 18260.908695456437, 2138.540213854029, 2149.7097891784565, 2160.2331172433874, 2165.5560610074895, 2182.7776445190743, 113214.0, 172069.13965430218, 174374.1281293598, 175183.0, 176373.1181344098, 12560.874391256153, 12991.870081299256, 13024.934875325564, 13091.738165236642, 13108.868911310765, 16767.077810720282, 16849.0, 16875.15624218784, 16890.662186756195, 16901.661966760596, 8839.823203535892, 9174.587143578421, 9213.769655758526, 9301.39540929851, 9311.301652376098, 8769.079246679134, 8776.517291548898, 8776.868346974672, 8933.776655583662, 8935.955320223356, 7002.0, 7975.481593696507, 8131.9186808132345, 8157.306628936488, 8551.657933682707, 2190.113004233288, 2194.4294483434423, 2200.130948275454, 2202.757696653367, 2321.68657231275, 925.8935222449504, 937.7421209167397, 969.9466529340813, 999.2712607189603, 1002.754325190333, 21215.045322960505, 21715.782842171375, 21755.564888702138, 21761.03183369416, 21833.0, 866.6749968761715, 891.9564109991326, 1007.2244371833793, 1012.2357619996856, 1013.8023085498257, 8288.254057134887, 8597.828043439222, 8988.370814042953, 10915.945420272848, 10942.288751231144, 524750.0, 529448.0, 532013.0, 534333.0, 542112.0, 122149.77850221562, 124814.0, 125068.24249332566, 126891.0, 127092.36453817856, 1095.5289225632896, 1194.6774370919977, 1227.4364663270235, 1257.264500267354, 1261.9558315458914, 22070.0, 22501.987410566235, 22545.66181507246, 22635.547289054455, 22642.886785565966, 38738.80630596829, 40199.79900100481, 41618.0, 51176.48823511866, 54705.72647136739, 50866.74566627143, 56763.14855277257, 63670.68164659147, 73829.52340953043, 74172.54964702135, 7456.55260684339, 8832.205101540892, 8929.821403572023, 9035.864462032943, 9057.68298109563, 11105.722356941142, 12853.742925141258, 13763.243021633907, 14105.576832695035, 14129.364178611782, 11633.0, 11694.064474841822, 11822.113341499595, 11827.17209795306, 11919.880801191877, 10974.945125274322, 11115.555377784798, 11177.0, 11195.160362972973, 11429.371384573597, 11668.649940501798, 11876.703082422662, 11897.940510297392, 11967.581134660246, 12036.338001410006, 8819.647214111365, 8993.32550058735, 9002.369834111549, 9059.68291109808, 9082.228010619172, 9798.363106398061, 11605.709857253636, 11650.252462130748, 11713.472893719974, 11762.647120586396], "value": 10350.785412929998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.8561515425727, 1441.5819412370413, 1445.710857828434, 1461.0065155694124, 1462.378489142115, 11357.886421135787, 11616.825747613782, 12125.454354554044, 12240.44917978692, 12267.202631828924, 11567.710807229823, 11805.40972951351, 11891.940540297286, 11906.583269585566, 11938.283702977806, 2541.4789968056552, 2598.8310759800647, 2602.6096085587124, 2604.257786530839, 2609.856457894815, 2526.431552900601, 2561.7950563954837, 2564.397366618843, 2565.9101931432406, 2574.948501029978, 2531.1520640585427, 2547.46503234321, 2557.577999630059, 2559.7568231018117, 2560.8463492190435, 2486.552420564307, 2518.6725725655638, 2524.6465494830736, 2528.355269406308, 2534.2397280815776, 2478.491909158618, 2491.7757401833924, 2509.2597683683357, 2514.2959971208093, 2587.8188526803133, 11564.13269004828, 11594.76810463786, 11661.88338116625, 11671.358075305854, 11703.824442633368, 6438.581492202992, 6614.636195009272, 6648.833779155511, 6690.096836927001, 6722.0, 4007.458993035932, 4220.409142720022, 4299.419578356913, 4306.138772245564, 4308.763018034007, 1265.816456613786, 1265.8227848101274, 1269.225772278909, 1269.5683467621054, 1279.948802047917, 15194.924025379913, 15565.922170389076, 16349.42777002811, 16353.264103115389, 16694.49916502507, 5849.912251316234, 5939.287285525678, 6147.538934579924, 6159.84600384985, 6161.168242287324, 13791.241481718602, 14039.017268791291, 14046.0, 14047.929760351337, 14065.71868562623, 6608.570442921195, 6713.932860671331, 6716.0, 6729.226138994079, 6751.628660423626, 5060.84817455477, 6025.879482410327, 6155.045967874897, 6180.690965451709, 6226.3151053385, 4176.519700234451, 4649.163150632919, 4677.345171675968, 4718.4809670936165, 4826.0347930414055, 1382.8755412012765, 1415.929203539819, 1450.151661278164, 1453.3532578002687, 1469.1111877314286, 801.1707882341748, 808.9838203235902, 824.4805772363493, 826.3595713322103, 849.4903058165052, 6155.507559395238, 6191.25704915413, 6306.211723534559, 6454.322296158835, 6467.676616169173, 515.8349328214936, 592.7095723095653, 612.4916319454842, 928.8606708993705, 994.5524513968743, 4226.408302837605, 4468.888277793081, 4502.212112880233, 4599.770011499411, 4631.652626052992, 14516.782248266285, 14609.853901461061, 14923.626909327353, 15161.393544258106, 15211.847881521264, 14568.49010284635, 15056.849431505763, 15149.924250378677, 15191.316390762442, 15194.240287985556, 7462.216467270859, 8114.431989760778, 8535.573221338907, 8568.742937711879, 8579.742607721653, 6547.607143571401, 7098.36114749675, 7296.160941491797, 7342.9632851835395, 7374.668139933823, 13075.280859552817, 13111.475540978636, 13338.19970801755, 13559.932200338935, 13733.969952253821, 13586.32068396596, 13969.720605587629, 14130.78803817923, 14138.0, 14214.360353784312, 10670.253082284165, 10993.780124397308, 11005.669829905115, 11016.0, 11024.0, 10671.89328106709, 10962.780744385227, 10975.396353200651, 10978.615748448767, 11097.500612472297, 9191.080891911022, 9335.066493350882, 9377.437353758796, 9391.530423478935, 9420.57607407682, 7472.589007604634, 8686.196623634558, 8908.910910891067, 8912.509811960144, 9225.58484868169, 8217.260446559838, 8250.752477425687, 8328.583570821556, 8334.458260213067, 8379.287760540423, 6118.112873633388, 6137.601055931529, 6208.596441231305, 6293.842653933688, 6356.77751278703, 6205.720742566505, 6286.591371560834, 6315.747370105144, 6391.712372943136, 6454.48364130859], "value": 5488.931050494124, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.6611718867076, 392.9862454814082, 395.9663428608568, 396.55387688850044, 410.5463462873525, 5247.816326428576, 5471.835844924649, 5983.192269043678, 6187.443130118294, 6247.71885265163, 4968.130577148993, 5179.974100129494, 5195.766190521426, 5731.799387021455, 5988.3712210217955, 4527.52460991596, 4618.237990731525, 4639.141758774637, 4646.930296045553, 4651.65091628727, 4590.724556526602, 4627.722336659811, 4639.976800115995, 4649.44206695197, 4656.185167595674, 3236.433624115782, 3815.214893128018, 4158.251514727348, 4500.347449619803, 4593.356930029799, 4567.474740404863, 4574.9542504574865, 4589.196890544158, 4597.264437689971, 4600.7929643166135, 4050.4936882889638, 4052.2300762855257, 4055.837766489337, 4060.9593904060876, 4075.3071977763557, 4315.913681726379, 5179.430262671103, 5496.1755736639425, 5856.707164641751, 5972.99904531978, 4523.796429160663, 4655.324977878188, 5413.215083812865, 5422.620416570802, 5432.239486471897, 6556.0, 6658.800235992928, 6716.2276338221245, 6720.361565651256, 6740.460763138937, 4379.978100109511, 4400.691951563392, 4408.655360115164, 4410.62509686677, 4413.7351758894565, 5921.615095018767, 6734.528582999143, 6938.577591593711, 6952.374286314205, 7073.292670732885, 4293.849715259951, 4431.357453169272, 4440.955590444119, 4453.51011388747, 4456.908057525927, 3646.288973650113, 3824.043989002749, 4518.525554816762, 4529.13946350199, 4537.319402089647, 5123.462961111652, 5155.6391052626705, 5327.467253274642, 5358.839234822962, 5429.267048948421, 5727.885442291131, 6660.666966651648, 6709.463242940553, 6715.966420167868, 6776.28848970851, 1303.589369348652, 1532.5478983699613, 1542.5449492399769, 1554.0054365206277, 1558.4311726220076, 983.2183414185739, 998.9250806189565, 1012.2610494339174, 1014.660088870222, 1019.928604997643, 4930.704157750546, 6019.217501724747, 6207.6585787781205, 6263.812085637438, 6292.528060395397, 5322.988632159954, 6773.695183716744, 6915.4121899638085, 6924.688389022506, 6926.826829329207, 5736.942630573641, 5960.761569537257, 6142.754289828356, 6283.59156654816, 6298.929182038973, 2833.7591304739326, 3135.404273188087, 3301.752368572367, 3414.965850341465, 3637.9636203637624, 5818.738156782955, 6013.819585412444, 6440.54916155874, 6863.764522386018, 7069.151701795818, 5340.359156901197, 6315.894718424349, 6487.448566871774, 7106.436583951522, 7180.5691658500655, 5903.645781253139, 6219.533534984804, 6892.965535172292, 6972.372486476242, 7084.149902011689, 6246.406591373994, 6837.555558888856, 6883.414909732729, 6913.481488888454, 6987.056747339044, 5287.365516138088, 6252.874942501034, 6348.460380867678, 6726.39462448382, 6795.694193761392, 6444.8388790280615, 6916.827079322856, 7108.111486064242, 7119.430445564446, 7136.393406560501, 4500.0, 4817.614590832795, 4966.230234313687, 5030.270610761492, 5059.16523773588, 6764.458843292623, 6871.278515755974, 6881.763426886182, 6936.2023367311385, 7034.824129396806, 5819.534437244925, 6419.748149111066, 6634.601923884582, 6652.800415987528, 6660.83347916306, 6433.903491447539, 6602.801915942529, 6635.777014700129, 6645.235797883305, 6660.0, 6522.706478208397, 6545.312742162195, 6591.110200122922, 6593.637349945799, 6700.46396288286, 5287.889543195972, 6446.8388290293115, 6655.169828297064, 6678.499112566674, 6700.396964273239, 6220.471259942956, 6540.280569137486, 6553.705083271169, 6605.636689982097, 6682.599044057372], "value": 4764.743924148279, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.1827966656177, 823.5223570329209, 829.7718127514936, 859.7850537365658, 866.571047331571, 16888.32446702132, 17349.132543372827, 17389.13054347284, 17571.033593152373, 18054.5486362841, 15474.922625386886, 17918.462446126636, 18097.1856266468, 18295.634087318245, 18516.722249166272, 816.2490508731951, 951.6954574536134, 957.6313119448993, 968.2302569457263, 975.3611384543135, 982.4694664880956, 986.8865080515761, 995.7162208770502, 998.610541888664, 999.4303247149128, 1044.399470304578, 1044.6552637629602, 1052.2213561964168, 1053.157474020784, 1053.778706471642, 1036.4092467293647, 1047.3715770537653, 1050.9316894401877, 1052.4369462337652, 1054.910332621728, 759.2104211619964, 771.6797529025448, 772.1622040086513, 772.9912464234172, 773.2383602151876, 17396.56508587283, 17951.73072403915, 18036.09819509019, 18199.909000454914, 18320.633587328313, 17394.565135871577, 17472.563185920328, 17577.29690812366, 17976.91011544947, 18020.36928707489, 14763.557093287218, 14792.334344954503, 14920.925395372953, 14957.102573845605, 14957.70084598302, 3418.5214070030215, 3516.6659167379057, 3541.610422853484, 3558.323918455511, 3566.144125409909, 96757.0, 104733.0, 112204.87795122107, 142173.2891335547, 145845.27077364546, 13093.345332733325, 13352.465901363837, 13409.664758380923, 13543.864561354458, 13558.983076269122, 23888.28335149948, 25730.970761169345, 26665.066722665, 26972.190834275003, 26986.595201072, 7391.817309230497, 7799.33705635016, 8035.316998055114, 8098.149694282133, 8119.106898241189, 3821.7133714971487, 3972.801359931992, 4024.1348110156455, 4029.5768944261017, 4031.596840315945, 4424.3142312941545, 4504.81980720774, 4506.918339598473, 4516.706414083074, 4522.841700540465, 863.5509535041743, 897.6140259688397, 902.8194361127801, 904.7331037344, 905.8278927003848, 658.7101675262869, 665.5707068940507, 679.2121139478181, 680.5474359550942, 680.9445359692456, 10076.395416275047, 10708.755211435286, 11167.218294719454, 11989.580364687194, 12317.630471085882, 649.0880313160018, 651.4007113455538, 653.0922018394435, 666.2571233075087, 681.2574294019444, 4982.8754281143265, 5210.713410762369, 5326.36083669962, 5610.5231055360755, 5854.092615644582, 98161.0, 145104.0, 147536.0, 150026.0, 150601.24699376652, 102557.43606409899, 121483.17775233378, 127247.36376318059, 129514.0, 130067.34966325297, 95787.35160158375, 105578.47210764051, 108940.0, 112396.0, 113334.29998550193, 10581.62964296246, 10957.342559446459, 11290.661280161608, 11447.542098315975, 11623.0, 39647.40528892011, 41219.0, 44974.1005179888, 45321.093578128915, 45741.0, 62181.68909155425, 62438.375616243255, 63361.0495842572, 63430.048549272724, 64320.67839660772, 14218.786718199442, 14426.495072672406, 14862.925685371503, 14871.925640371728, 14932.477363292233, 13607.455701771818, 13688.0, 16141.0, 16241.269142888363, 16481.505554833373, 12337.691557711129, 12459.875401246234, 13463.461461541428, 15196.012259203117, 15326.0, 14308.713825723218, 14653.780193297322, 14915.955883088514, 14979.625509362353, 15189.012714173543, 14286.857131428553, 14613.70772584521, 14826.481073162387, 14901.0, 14963.40146394132, 9363.859542106738, 10214.72315960505, 10713.571457141627, 11111.888881111085, 11146.944265278622, 11789.941050294694, 11888.9894358977, 11925.165238433226, 11940.582079627171, 12018.879811201776], "value": 8440.428615768542, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 174.0, 162.0, 116.0, 115.0, 116.0, 115.0, 117.0, 116.0, 115.0], "value": 128.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 112.0, 120.0, 153.0, 99.0, 91.0, 87.0, 131.0, 140.0, 137.0], "value": 120.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 196.0, 197.0, 199.0, 203.0, 219.0, 266.0, 290.0, 280.0, 200.0], "value": 224.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 58.0, 58.0, 84.0, 61.0, 60.0, 58.0, 61.0, 86.0, 85.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 82.0, 71.0, 78.0, 104.0, 99.0, 73.0, 71.0, 91.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 92.0, 92.0, 91.0, 91.0, 92.0, 91.0, 96.0, 91.0], "value": 92.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 97.0, 98.0, 98.0, 106.0, 100.0, 99.0, 99.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 122.0, 83.0, 148.0, 152.0, 92.0, 86.0, 88.0], "value": 104.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 56.0, 71.0, 78.0, 78.0, 78.0, 67.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 57.0, 69.0, 60.0, 63.0, 59.0, 56.0, 58.0, 58.0, 59.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 85.0, 89.0, 90.0, 88.0, 89.0, 122.0, 87.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 139.0, 134.0, 141.0, 137.0, 141.0, 146.0, 125.0, 157.0, 140.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 192.0, 265.0, 216.0, 185.0, 186.0, 189.0, 209.0, 221.0, 221.0], "value": 207.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 71.0, 68.0, 70.0, 62.0, 70.0, 98.0, 97.0], "value": 73.9, "unit": "score"}]}]} 03:57:40 INFO - Return code: 0 03:57:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:57:40 INFO - # TBPL SUCCESS # 03:57:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:57:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:57:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:57:40 INFO - 2017-05-26 03:57:40.846 screenresolution[1946:12053] starting screenresolution argv=screenresolution get 03:57:40 INFO - 2017-05-26 03:57:40.848 screenresolution[1946:12053] Display 0: 1600x1200x32@60 03:57:40 INFO - 2017-05-26 03:57:40.854 screenresolution[1947:12056] starting screenresolution argv=screenresolution list 03:57:40 INFO - Available Modes on Display 0 03:57:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:57:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:57:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:57:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:57:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:57:41 INFO - Intel Iris: 03:57:41 INFO - Chipset Model: Intel Iris 03:57:41 INFO - Type: GPU 03:57:41 INFO - Bus: Built-In 03:57:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:57:41 INFO - Vendor: Intel (0x8086) 03:57:41 INFO - Device ID: 0x0a2e 03:57:41 INFO - Revision ID: 0x0009 03:57:41 INFO - Displays: 03:57:41 INFO - Display: 03:57:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:57:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:57:41 INFO - Display Serial Number: 1600x1200 60 03:57:41 INFO - Main Display: Yes 03:57:41 INFO - Mirror: Off 03:57:41 INFO - Online: Yes 03:57:41 INFO - Rotation: Supported 03:57:41 INFO - Return code: 0 03:57:41 INFO - Running post-action listener: _resource_record_post_action 03:57:41 INFO - [mozharness: 2017-05-26 10:57:41.090596Z] Finished run-tests step (success) 03:57:41 INFO - Running post-run listener: _resource_record_post_run 03:57:41 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 3938304; Write bytes: 563728384; Read time: 43; Write time: 1868 03:57:41 INFO - TinderboxPrint: CPU usage
53.2% 03:57:41 INFO - TinderboxPrint: I/O read bytes / time
3,938,304 / 43 03:57:41 INFO - TinderboxPrint: I/O write bytes / time
563,728,384 / 1,868 03:57:41 INFO - TinderboxPrint: CPU idle
2,349.9 (46.8%) 03:57:41 INFO - TinderboxPrint: CPU system
1,019.6 (20.3%) 03:57:41 INFO - TinderboxPrint: CPU user
1,652.2 (32.9%) 03:57:41 INFO - TinderboxPrint: Swap in / out
248,401,920 / 0 03:57:41 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 224023040; Write bytes: 219244544; Read time: 16886; Write time: 478 03:57:41 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 3926016; Write bytes: 316160000; Read time: 41; Write time: 1320 03:57:41 INFO - Running post-run listener: _upload_blobber_files 03:57:41 INFO - Blob upload gear active. 03:57:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:57:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:57:41 INFO - (blobuploader) - INFO - Open directory for files ... 03:57:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:57:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:57:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:42 INFO - (blobuploader) - INFO - Done attempting. 03:57:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:57:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:57:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:43 INFO - (blobuploader) - INFO - Done attempting. 03:57:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:57:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:57:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:44 INFO - (blobuploader) - INFO - Done attempting. 03:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:46 INFO - (blobuploader) - INFO - Done attempting. 03:57:46 INFO - (blobuploader) - INFO - Iteration through files over. 03:57:46 INFO - Return code: 0 03:57:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:57:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:57:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a71aa30cb62443ec6f698774ee46f2817d29fe84cfc7bde069d8245f6d993b94b1d2063d7bead9e271bd179e88f7b60ebc8aa092a9fe221f501e2c4f78cb0987", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7684f7191b9d504ff5d47d7330932a1a4b930fd6bc2c9aa7b59c9ed9ceef10602e3003712876c88e0fd96f4e9a49bf2f9cd5532f6a851fa9e620fab5777416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a9ca30ed58da356aff6d5e3fcddc5d3cfc75ac715cd5aff992654ca1b11195e114ac37cff09413271da6857299de1eb46362d6bb0ff0e4b11c83ad40aeca2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a69aa354c22c1e0b5a6644341d6df07a77374e78ef4b0292926952052e16a5ed06b70ef8e8f64effb9273f21de4fc56df1b4f2a23b8c40fb7503694d5db7f26"} 03:57:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:57:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:57:46 INFO - Contents: 03:57:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a71aa30cb62443ec6f698774ee46f2817d29fe84cfc7bde069d8245f6d993b94b1d2063d7bead9e271bd179e88f7b60ebc8aa092a9fe221f501e2c4f78cb0987", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7684f7191b9d504ff5d47d7330932a1a4b930fd6bc2c9aa7b59c9ed9ceef10602e3003712876c88e0fd96f4e9a49bf2f9cd5532f6a851fa9e620fab5777416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a9ca30ed58da356aff6d5e3fcddc5d3cfc75ac715cd5aff992654ca1b11195e114ac37cff09413271da6857299de1eb46362d6bb0ff0e4b11c83ad40aeca2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a69aa354c22c1e0b5a6644341d6df07a77374e78ef4b0292926952052e16a5ed06b70ef8e8f64effb9273f21de4fc56df1b4f2a23b8c40fb7503694d5db7f26"} 03:57:46 INFO - Running post-run listener: copy_logs_to_upload_dir 03:57:46 INFO - Copying logs to upload dir... 03:57:46 INFO - mkdir: /builds/slave/test/build/upload/logs 03:57:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1334.456144 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 14 secs) (at 2017-05-26 03:57:46.630079) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 03:57:46.635692) ========= 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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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/a71aa30cb62443ec6f698774ee46f2817d29fe84cfc7bde069d8245f6d993b94b1d2063d7bead9e271bd179e88f7b60ebc8aa092a9fe221f501e2c4f78cb0987", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7684f7191b9d504ff5d47d7330932a1a4b930fd6bc2c9aa7b59c9ed9ceef10602e3003712876c88e0fd96f4e9a49bf2f9cd5532f6a851fa9e620fab5777416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a9ca30ed58da356aff6d5e3fcddc5d3cfc75ac715cd5aff992654ca1b11195e114ac37cff09413271da6857299de1eb46362d6bb0ff0e4b11c83ad40aeca2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a69aa354c22c1e0b5a6644341d6df07a77374e78ef4b0292926952052e16a5ed06b70ef8e8f64effb9273f21de4fc56df1b4f2a23b8c40fb7503694d5db7f26"} build_url:https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010851 build_url: 'https://queue.taskcluster.net/v1/task/JqCDfQt-RyGpJhCBrYa10A/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/a71aa30cb62443ec6f698774ee46f2817d29fe84cfc7bde069d8245f6d993b94b1d2063d7bead9e271bd179e88f7b60ebc8aa092a9fe221f501e2c4f78cb0987", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7684f7191b9d504ff5d47d7330932a1a4b930fd6bc2c9aa7b59c9ed9ceef10602e3003712876c88e0fd96f4e9a49bf2f9cd5532f6a851fa9e620fab5777416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a9ca30ed58da356aff6d5e3fcddc5d3cfc75ac715cd5aff992654ca1b11195e114ac37cff09413271da6857299de1eb46362d6bb0ff0e4b11c83ad40aeca2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a69aa354c22c1e0b5a6644341d6df07a77374e78ef4b0292926952052e16a5ed06b70ef8e8f64effb9273f21de4fc56df1b4f2a23b8c40fb7503694d5db7f26"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 03:57:46.672772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:57:46.673186) ========= 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.tVnUZswGvL/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.JiPsdZ5gVN/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.005105 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 03:57:46.721775) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 03:57:46.722127) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 03:57:46.722474) ========= ========= Total master_lag: 0.19 =========