builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0328 starttime: 1496342304.35 results: success (0) revision: 0e9853e31da9848ab638bdd0df4eb734a5ebc232 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.354693) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.355204) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.355486) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.380402) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.380758) ========= 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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 11:38:24-- 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% 18.8M=0.001s 2017-06-01 11:38:24 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.549536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.549886) ========= 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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.030476 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.602156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:24.602668) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0e9853e31da9848ab638bdd0df4eb734a5ebc232 --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 0e9853e31da9848ab638bdd0df4eb734a5ebc232 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mBeEWlo8cu/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.Z9pzKREEj3/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 11:38:24,702 truncating revision to first 12 chars 2017-06-01 11:38:24,702 Setting DEBUG logging. 2017-06-01 11:38:24,702 attempt 1/10 2017-06-01 11:38:24,702 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0e9853e31da9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 11:38:25,010 unpacking tar archive at: mozilla-central-0e9853e31da9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.600871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:25.226391) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:25.226705) ========= script_repo_revision: 0e9853e31da9848ab638bdd0df4eb734a5ebc232 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:25.227101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:25.227373) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 11:38:25.246456) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-01 11:38:25.246911) ========= /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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:38:25 INFO - MultiFileLogger online at 20170601 11:38:25 in /builds/slave/test 11:38:25 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 11:38:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:38:25 INFO - {'append_to_log': False, 11:38:25 INFO - 'base_work_dir': '/builds/slave/test', 11:38:25 INFO - 'blob_upload_branch': 'Firefox', 11:38:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:38:25 INFO - 'branch': 'Firefox', 11:38:25 INFO - 'buildbot_json_path': 'buildprops.json', 11:38:25 INFO - 'config_files': ('talos/mac_config.py',), 11:38:25 INFO - 'default_actions': ('clobber', 11:38:25 INFO - 'read-buildbot-config', 11:38:25 INFO - 'download-and-extract', 11:38:25 INFO - 'populate-webroot', 11:38:25 INFO - 'create-virtualenv', 11:38:25 INFO - 'install', 11:38:25 INFO - 'setup-mitmproxy', 11:38:25 INFO - 'run-tests'), 11:38:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:38:25 INFO - 'download_minidump_stackwalk': True, 11:38:25 INFO - 'download_symbols': 'ondemand', 11:38:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:38:25 INFO - 'tooltool.py': '/tools/tooltool.py', 11:38:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:38:25 INFO - '/tools/misc-python/virtualenv.py')}, 11:38:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:38:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:38:25 INFO - 'gecko_profile': False, 11:38:25 INFO - 'gecko_profile_interval': 0, 11:38:25 INFO - 'installer_path': 'installer.exe', 11:38:25 INFO - 'log_level': 'info', 11:38:25 INFO - 'log_name': 'talos', 11:38:25 INFO - 'log_to_console': True, 11:38:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:38:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:38:25 INFO - 'opt_config_files': (), 11:38:25 INFO - 'pip_index': False, 11:38:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:38:25 INFO - 'cmd': ('bash', 11:38:25 INFO - '-c', 11:38:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:38:25 INFO - 'enabled': True, 11:38:25 INFO - 'halt_on_failure': False, 11:38:25 INFO - 'name': 'check_screen_resolution'},), 11:38:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:38:25 INFO - 'cmd': ('bash', 11:38:25 INFO - '-c', 11:38:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:38:25 INFO - 'enabled': True, 11:38:25 INFO - 'halt_on_failure': False, 11:38:25 INFO - 'name': 'check_screen_resolution'},), 11:38:25 INFO - 'run_cmd_checks_enabled': True, 11:38:25 INFO - 'suite': 'dromaeojs-e10s', 11:38:25 INFO - 'system_bits': '32', 11:38:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:38:25 INFO - 'title': 't-yosemite-r7-0328', 11:38:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:38:25 INFO - 'use_talos_json': True, 11:38:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:38:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:38:25 INFO - 'work_dir': 'build'} 11:38:25 INFO - [mozharness: 2017-06-01 18:38:25.458246Z] Running clobber step. 11:38:25 INFO - Running pre-action listener: _resource_record_pre_action 11:38:25 INFO - Running main action method: clobber 11:38:25 INFO - rmtree: /builds/slave/test/build 11:38:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:38:28 INFO - Running post-action listener: _resource_record_post_action 11:38:28 INFO - [mozharness: 2017-06-01 18:38:28.066167Z] Finished clobber step (success) 11:38:28 INFO - [mozharness: 2017-06-01 18:38:28.066425Z] Running read-buildbot-config step. 11:38:28 INFO - Running pre-action listener: _resource_record_pre_action 11:38:28 INFO - Running main action method: read_buildbot_config 11:38:28 INFO - Using buildbot properties: 11:38:28 INFO - { 11:38:28 INFO - "project": "", 11:38:28 INFO - "product": "firefox", 11:38:28 INFO - "who": "ryanvm@gmail.com", 11:38:28 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 11:38:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:38:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:38:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:38:28 INFO - "stage_platform": "macosx64", 11:38:28 INFO - "basedir": "/builds/slave/test", 11:38:28 INFO - "buildnumber": 229, 11:38:28 INFO - "platform": "yosemite_r7", 11:38:28 INFO - "parent_task_id": "CInbAlgrSA2QGwJeKab35A", 11:38:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:38:28 INFO - "slavebuilddir": "test", 11:38:28 INFO - "taskId": "UI0r5vYjS8-FZVNavmfhmg", 11:38:28 INFO - "branch": "mozilla-central", 11:38:28 INFO - "script_repo_revision": "production", 11:38:28 INFO - "revision": "0e9853e31da9848ab638bdd0df4eb734a5ebc232", 11:38:28 INFO - "slavename": "t-yosemite-r7-0328", 11:38:28 INFO - "repo_path": "mozilla-central" 11:38:28 INFO - } 11:38:28 INFO - Finding installer, test and symbols from parent task. 11:38:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UI0r5vYjS8-FZVNavmfhmg'}, attempt #1 11:38:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UI0r5vYjS8-FZVNavmfhmg'}, attempt #1 11:38:29 INFO - Task dependencies: SgAt213aS6C6LfeJvQD6KQ CInbAlgrSA2QGwJeKab35A 11:38:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A'}, attempt #1 11:38:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:38:29 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/test_packages.json 11:38:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/target.crashreporter-symbols.zip 11:38:29 INFO - Running post-action listener: _resource_record_post_action 11:38:29 INFO - [mozharness: 2017-06-01 18:38:29.556518Z] Finished read-buildbot-config step (success) 11:38:29 INFO - [mozharness: 2017-06-01 18:38:29.556635Z] Running download-and-extract step. 11:38:29 INFO - Running pre-action listener: _resource_record_pre_action 11:38:29 INFO - Running main action method: download_and_extract 11:38:29 INFO - mkdir: /builds/slave/test/build/tests 11:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:29 INFO - trying https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:38:29 INFO - Downloading https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/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 11:38:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/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 11:38:30 INFO - Downloaded 1325 bytes. 11:38:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:38:30 INFO - Using the following test package requirements: 11:38:30 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:38:30 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:38:30 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:38:30 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:38:30 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'jsshell-mac.zip'], 11:38:30 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:38:30 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:38:30 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:38:30 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:38:30 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:38:30 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:38:30 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:38:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:38:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:38:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:38:30 INFO - Fetch https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:38:32 INFO - Content-Length response header: 57632626 11:38:32 INFO - Bytes received: 57632626 11:38:50 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 11:38:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:38:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:38:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:38:51 INFO - Content-Length response header: 57632626 11:38:51 INFO - Bytes received: 57632626 11:39:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:39:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:39:08 INFO - Fetch https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:39:10 INFO - Content-Length response header: 14447425 11:39:10 INFO - Bytes received: 14447425 11:39:10 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')]} 11:39:10 INFO - trying https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:39:10 INFO - Downloading https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:39:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/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 11:39:12 INFO - Downloaded 67919713 bytes. 11:39:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:39:12 INFO - mkdir: /builds/slave/test/properties 11:39:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:39:12 INFO - Writing to file /builds/slave/test/properties/build_url 11:39:12 INFO - Contents: 11:39:12 INFO - build_url:https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:39:12 INFO - Running post-action listener: _resource_record_post_action 11:39:12 INFO - Running post-action listener: set_extra_try_arguments 11:39:12 INFO - [mozharness: 2017-06-01 18:39:12.969871Z] Finished download-and-extract step (success) 11:39:12 INFO - [mozharness: 2017-06-01 18:39:12.969991Z] Running populate-webroot step. 11:39:12 INFO - Running pre-action listener: _resource_record_pre_action 11:39:12 INFO - Running main action method: populate_webroot 11:39:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:39:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:39:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:39:12 INFO - '--tppagecycles', 11:39:12 INFO - '7'], 11:39:12 INFO - 'tests': ['tsvgm']}}, 11:39:12 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:39:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:39:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:39:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['damp', 'tps']}, 11:39:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'tests': ['damp', 'tps']}, 11:39:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['dromaeo_dom']}, 11:39:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:39:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:39:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:39:12 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:39:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['a11yr', 11:39:12 INFO - 'ts_paint', 11:39:12 INFO - 'tpaint', 11:39:12 INFO - 'sessionrestore', 11:39:12 INFO - 'sessionrestore_no_auto_restore', 11:39:12 INFO - 'tabpaint']}, 11:39:12 INFO - 'other-e10s': {'tests': ['a11yr', 11:39:12 INFO - 'ts_paint', 11:39:12 INFO - 'tpaint', 11:39:12 INFO - 'sessionrestore', 11:39:12 INFO - 'sessionrestore_no_auto_restore', 11:39:12 INFO - 'tabpaint']}, 11:39:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:39:12 INFO - 'ts_paint', 11:39:12 INFO - 'tpaint', 11:39:12 INFO - 'sessionrestore', 11:39:12 INFO - 'sessionrestore_no_auto_restore', 11:39:12 INFO - 'tabpaint']}, 11:39:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:39:12 INFO - 'ts_paint', 11:39:12 INFO - 'tpaint', 11:39:12 INFO - 'sessionrestore', 11:39:12 INFO - 'sessionrestore_no_auto_restore', 11:39:12 INFO - 'tabpaint']}, 11:39:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['a11yr', 11:39:12 INFO - 'ts_paint', 11:39:12 INFO - 'tpaint', 11:39:12 INFO - 'sessionrestore', 11:39:12 INFO - 'sessionrestore_no_auto_restore', 11:39:12 INFO - 'tabpaint']}, 11:39:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['a11yr', 11:39:12 INFO - 'ts_paint', 11:39:12 INFO - 'tpaint', 11:39:12 INFO - 'sessionrestore', 11:39:12 INFO - 'sessionrestore_no_auto_restore', 11:39:12 INFO - 'tabpaint']}, 11:39:12 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:39:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:39:12 INFO - 'bloom_basic_ref']}, 11:39:12 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 11:39:12 INFO - 'talos_options': ['--mitmproxy', 11:39:12 INFO - 'mitmproxy-recording-1.mp', 11:39:12 INFO - '--firstNonBlankPaint'], 11:39:12 INFO - 'tests': ['Quantum_1']}, 11:39:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['tsvgx', 11:39:12 INFO - 'tsvgr_opacity', 11:39:12 INFO - 'tart', 11:39:12 INFO - 'tscrollx', 11:39:12 INFO - 'cart', 11:39:12 INFO - 'tsvg_static']}, 11:39:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:39:12 INFO - 'tsvgr_opacity', 11:39:12 INFO - 'tart', 11:39:12 INFO - 'tscrollx', 11:39:12 INFO - 'cart', 11:39:12 INFO - 'tsvg_static']}, 11:39:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'talos_options': ['--disable-e10s'], 11:39:12 INFO - 'tests': ['tp5o']}, 11:39:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:39:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'talos_options': ['--disable-e10s', 11:39:12 INFO - '--xperf_path', 11:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:12 INFO - 'tests': ['tp5n']}, 11:39:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:39:12 INFO - 'talos_options': ['--xperf_path', 11:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:12 INFO - 'tests': ['tp5n']}}, 11:39:12 INFO - 'talos.zip': {'path': '', 11:39:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:39:12 INFO - Running post-action listener: _resource_record_post_action 11:39:12 INFO - [mozharness: 2017-06-01 18:39:12.992375Z] Finished populate-webroot step (success) 11:39:12 INFO - [mozharness: 2017-06-01 18:39:12.992513Z] Running create-virtualenv step. 11:39:12 INFO - Running pre-action listener: _resource_record_pre_action 11:39:12 INFO - Running main action method: create_virtualenv 11:39:12 INFO - Creating virtualenv /builds/slave/test/build/venv 11:39:12 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 11:39:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:39:12 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:39:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:39:13 INFO - Using real prefix '/tools/python27' 11:39:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:39:13 INFO - Installing distribute.............................................................................................................................................................................................done. 11:39:16 INFO - Installing pip.................done. 11:39:16 INFO - Return code: 0 11:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:39:16 INFO - Reading from file tmpfile_stdout 11:39:16 INFO - Output received: 11:39:16 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) 11:39:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:39:16 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')]} 11:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:16 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')]} 11:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:16 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:16 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 11:39:16 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 11:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:16 INFO - 'HOME': '/Users/cltbld', 11:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:16 INFO - 'LOGNAME': 'cltbld', 11:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:16 INFO - 'MOZ_NO_REMOTE': '1', 11:39:16 INFO - 'NO_EM_RESTART': '1', 11:39:16 INFO - 'PAGER': '/bin/cat', 11:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:16 INFO - 'PWD': '/builds/slave/test', 11:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:16 INFO - 'SHELL': '/bin/bash', 11:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:16 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:16 INFO - 'USER': 'cltbld', 11:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:16 INFO - 'XPC_FLAGS': '0x0', 11:39:16 INFO - 'XPC_SERVICE_NAME': '0', 11:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:17 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 11:39:17 INFO - Cleaning up... 11:39:17 INFO - Return code: 0 11:39:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:39:17 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')]} 11:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:17 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')]} 11:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:17 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:17 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 11:39:17 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 11:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:17 INFO - 'HOME': '/Users/cltbld', 11:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:17 INFO - 'LOGNAME': 'cltbld', 11:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:17 INFO - 'MOZ_NO_REMOTE': '1', 11:39:17 INFO - 'NO_EM_RESTART': '1', 11:39:17 INFO - 'PAGER': '/bin/cat', 11:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:17 INFO - 'PWD': '/builds/slave/test', 11:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:17 INFO - 'SHELL': '/bin/bash', 11:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:17 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:17 INFO - 'USER': 'cltbld', 11:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:17 INFO - 'XPC_FLAGS': '0x0', 11:39:17 INFO - 'XPC_SERVICE_NAME': '0', 11:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:17 INFO - Downloading/unpacking psutil>=3.1.1 11:39:17 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 11:39:17 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 11:39:17 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 11:39:17 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 11:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:39:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:19 INFO - Installing collected packages: psutil 11:39:19 INFO - Running setup.py install for psutil 11:39:19 INFO - building 'psutil._psutil_osx' extension 11:39:19 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 11:39:20 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 11:39:20 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 11:39:20 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 11:39:20 INFO - building 'psutil._psutil_posix' extension 11:39:20 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 11:39:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:39:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:39:20 INFO - ^ 11:39:20 INFO - 1 warning generated. 11:39:20 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 11:39:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:20 INFO - Successfully installed psutil 11:39:20 INFO - Cleaning up... 11:39:20 INFO - Return code: 0 11:39:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:20 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:20 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 11:39:20 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 11:39:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:20 INFO - 'HOME': '/Users/cltbld', 11:39:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:20 INFO - 'LOGNAME': 'cltbld', 11:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:20 INFO - 'MOZ_NO_REMOTE': '1', 11:39:20 INFO - 'NO_EM_RESTART': '1', 11:39:20 INFO - 'PAGER': '/bin/cat', 11:39:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:20 INFO - 'PWD': '/builds/slave/test', 11:39:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:20 INFO - 'SHELL': '/bin/bash', 11:39:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:20 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:20 INFO - 'USER': 'cltbld', 11:39:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:20 INFO - 'XPC_FLAGS': '0x0', 11:39:20 INFO - 'XPC_SERVICE_NAME': '0', 11:39:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:39:20 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 11:39:20 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 11:39:20 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 11:39:20 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 11:39:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:39:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:39:22 INFO - Installing collected packages: mozsystemmonitor 11:39:22 INFO - Running setup.py install for mozsystemmonitor 11:39:22 INFO - Successfully installed mozsystemmonitor 11:39:22 INFO - Cleaning up... 11:39:22 INFO - Return code: 0 11:39:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:23 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')]} 11:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39: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', '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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:39: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 jsonschema==2.5.1 11:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:23 INFO - 'HOME': '/Users/cltbld', 11:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:23 INFO - 'LOGNAME': 'cltbld', 11:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:23 INFO - 'MOZ_NO_REMOTE': '1', 11:39:23 INFO - 'NO_EM_RESTART': '1', 11:39:23 INFO - 'PAGER': '/bin/cat', 11:39:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:23 INFO - 'PWD': '/builds/slave/test', 11:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:23 INFO - 'SHELL': '/bin/bash', 11:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:23 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:23 INFO - 'USER': 'cltbld', 11:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:23 INFO - 'XPC_FLAGS': '0x0', 11:39:23 INFO - 'XPC_SERVICE_NAME': '0', 11:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:23 INFO - Downloading/unpacking jsonschema==2.5.1 11:39: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 11:39: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 11:39: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 11:39: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 11:39:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:39:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:39:25 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 11:39:25 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 11:39:25 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 11:39:25 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 11:39:25 INFO - Downloading functools32-3.2.3-2.tar.gz 11:39:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:39:25 INFO - warning: no files found matching '*.txt' 11:39:25 INFO - no previously-included directories found matching 'build' 11:39:25 INFO - no previously-included directories found matching 'dist' 11:39:25 INFO - no previously-included directories found matching '.git*' 11:39:25 INFO - Installing collected packages: jsonschema, functools32 11:39:25 INFO - Running setup.py install for functools32 11:39:25 INFO - warning: no files found matching '*.txt' 11:39:25 INFO - no previously-included directories found matching 'build' 11:39:25 INFO - no previously-included directories found matching 'dist' 11:39:25 INFO - no previously-included directories found matching '.git*' 11:39:25 INFO - Successfully installed jsonschema functools32 11:39:25 INFO - Cleaning up... 11:39:25 INFO - Return code: 0 11:39:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:39:25 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')]} 11:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:25 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')]} 11:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:25 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:25 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 11:39:25 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 11:39:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:25 INFO - 'HOME': '/Users/cltbld', 11:39:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:25 INFO - 'LOGNAME': 'cltbld', 11:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:25 INFO - 'MOZ_NO_REMOTE': '1', 11:39:25 INFO - 'NO_EM_RESTART': '1', 11:39:25 INFO - 'PAGER': '/bin/cat', 11:39:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:25 INFO - 'PWD': '/builds/slave/test', 11:39:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:25 INFO - 'SHELL': '/bin/bash', 11:39:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:25 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:25 INFO - 'USER': 'cltbld', 11:39:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:25 INFO - 'XPC_FLAGS': '0x0', 11:39:25 INFO - 'XPC_SERVICE_NAME': '0', 11:39:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:39:25 INFO - Cleaning up... 11:39:25 INFO - Return code: 0 11:39:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:39:25 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')]} 11:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:25 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')]} 11:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:25 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:25 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 11:39:25 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 11:39:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:25 INFO - 'HOME': '/Users/cltbld', 11:39:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:25 INFO - 'LOGNAME': 'cltbld', 11:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:25 INFO - 'MOZ_NO_REMOTE': '1', 11:39:25 INFO - 'NO_EM_RESTART': '1', 11:39:25 INFO - 'PAGER': '/bin/cat', 11:39:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:25 INFO - 'PWD': '/builds/slave/test', 11:39:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:25 INFO - 'SHELL': '/bin/bash', 11:39:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:25 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:25 INFO - 'USER': 'cltbld', 11:39:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:25 INFO - 'XPC_FLAGS': '0x0', 11:39:25 INFO - 'XPC_SERVICE_NAME': '0', 11:39:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:26 INFO - Downloading/unpacking blobuploader==1.2.4 11:39: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 11:39: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 11:39: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 11:39: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 11:39:28 INFO - Downloading blobuploader-1.2.4.tar.gz 11:39:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:39:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:39: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 11:39: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 11:39: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 11:39: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 11:39:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:39:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:39: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 11:39: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 11:39: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 11:39: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 11:39:29 INFO - Downloading docopt-0.6.1.tar.gz 11:39:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:39:29 INFO - Installing collected packages: blobuploader, requests, docopt 11:39:29 INFO - Running setup.py install for blobuploader 11:39:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:39:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:39:29 INFO - Running setup.py install for requests 11:39:30 INFO - Running setup.py install for docopt 11:39:30 INFO - Successfully installed blobuploader requests docopt 11:39:30 INFO - Cleaning up... 11:39:30 INFO - Return code: 0 11:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:30 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:30 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 11:39:30 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 11:39:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:30 INFO - 'HOME': '/Users/cltbld', 11:39:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:30 INFO - 'LOGNAME': 'cltbld', 11:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:30 INFO - 'MOZ_NO_REMOTE': '1', 11:39:30 INFO - 'NO_EM_RESTART': '1', 11:39:30 INFO - 'PAGER': '/bin/cat', 11:39:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:30 INFO - 'PWD': '/builds/slave/test', 11:39:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:30 INFO - 'SHELL': '/bin/bash', 11:39:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:30 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:30 INFO - 'USER': 'cltbld', 11:39:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:30 INFO - 'XPC_FLAGS': '0x0', 11:39:30 INFO - 'XPC_SERVICE_NAME': '0', 11:39:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:30 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-T4u_bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:30 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-h5E9Ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:30 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Cwml_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:30 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-8g93TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-zsAUXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-KxUutG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-5vSaFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-keH8Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-U4b9fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-HGZ8Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-II84Il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-ZVi5ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Lq3Zxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:31 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-lWEuJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:32 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-oP6849-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:32 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Xj4Xwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:32 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-FOCM4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:39:32 INFO - Running setup.py install for manifestparser 11:39:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for mozcrash 11:39:32 INFO - Running setup.py install for mozdebug 11:39:32 INFO - Running setup.py install for mozdevice 11:39:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for mozfile 11:39:33 INFO - Running setup.py install for mozhttpd 11:39:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for mozinfo 11:39:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for mozInstall 11:39:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for mozleak 11:39:33 INFO - Running setup.py install for mozlog 11:39:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for moznetwork 11:39:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for mozprocess 11:39:34 INFO - Running setup.py install for mozprofile 11:39:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:39:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:39:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:39:34 INFO - Running setup.py install for mozrunner 11:39:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:39:34 INFO - Running setup.py install for mozscreenshot 11:39:34 INFO - Running setup.py install for moztest 11:39:34 INFO - Running setup.py install for mozversion 11:39:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:39:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:39:34 INFO - Cleaning up... 11:39:34 INFO - Return code: 0 11:39:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39: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')]} 11:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:34 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:34 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 11:39:34 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 11:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:34 INFO - 'HOME': '/Users/cltbld', 11:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:34 INFO - 'LOGNAME': 'cltbld', 11:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:34 INFO - 'MOZ_NO_REMOTE': '1', 11:39:34 INFO - 'NO_EM_RESTART': '1', 11:39:34 INFO - 'PAGER': '/bin/cat', 11:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:34 INFO - 'PWD': '/builds/slave/test', 11:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:34 INFO - 'SHELL': '/bin/bash', 11:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:34 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:34 INFO - 'USER': 'cltbld', 11:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:34 INFO - 'XPC_FLAGS': '0x0', 11:39:34 INFO - 'XPC_SERVICE_NAME': '0', 11:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-ZKB8y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-HdRpjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-h2wsK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-OEa3X7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-HwxBfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-KkiRuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-CsOhCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:35 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)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:35 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-f1VuQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-l_GHl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-MrwPi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-HU6SPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-tzlJGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-t_Jdhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-aVB78D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-pAjeub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-u_PhNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:36 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)) 11:39:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:36 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-itAJGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:36 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)) 11:39:36 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)) 11:39:36 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)) 11:39:36 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)) 11:39:36 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)) 11:39:36 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)) 11:39:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:38 INFO - Downloading blessings-1.6.tar.gz 11:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:39:38 INFO - Installing collected packages: blessings 11:39:38 INFO - Running setup.py install for blessings 11:39:38 INFO - Successfully installed blessings 11:39:38 INFO - Cleaning up... 11:39:38 INFO - Return code: 0 11:39:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:39:38 INFO - Reading from file tmpfile_stdout 11:39:38 INFO - Current package versions: 11:39:38 INFO - blessings == 1.6 11:39:38 INFO - blobuploader == 1.2.4 11:39:38 INFO - docopt == 0.6.1 11:39:38 INFO - functools32 == 3.2.3-2 11:39:38 INFO - jsonschema == 2.5.1 11:39:38 INFO - manifestparser == 1.1 11:39:38 INFO - mozInstall == 1.12 11:39:38 INFO - mozcrash == 1.0 11:39:38 INFO - mozdebug == 0.1 11:39:38 INFO - mozdevice == 0.51 11:39:38 INFO - mozfile == 1.2 11:39:38 INFO - mozhttpd == 0.7 11:39:38 INFO - mozinfo == 0.9 11:39:38 INFO - mozleak == 0.1 11:39:38 INFO - mozlog == 3.4 11:39:38 INFO - moznetwork == 0.27 11:39:38 INFO - mozprocess == 0.25 11:39:38 INFO - mozprofile == 0.28 11:39:38 INFO - mozrunner == 6.13 11:39:38 INFO - mozscreenshot == 0.1 11:39:38 INFO - mozsystemmonitor == 0.3 11:39:38 INFO - moztest == 0.8 11:39:38 INFO - mozversion == 1.4 11:39:38 INFO - psutil == 3.1.1 11:39:38 INFO - requests == 1.2.3 11:39:38 INFO - wsgiref == 0.1.2 11:39:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:38 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 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:38 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 11:39:38 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 11:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:38 INFO - 'HOME': '/Users/cltbld', 11:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:38 INFO - 'LOGNAME': 'cltbld', 11:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:38 INFO - 'MOZ_NO_REMOTE': '1', 11:39:38 INFO - 'NO_EM_RESTART': '1', 11:39:38 INFO - 'PAGER': '/bin/cat', 11:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:38 INFO - 'PWD': '/builds/slave/test', 11:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:38 INFO - 'SHELL': '/bin/bash', 11:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:38 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:38 INFO - 'USER': 'cltbld', 11:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:38 INFO - 'XPC_FLAGS': '0x0', 11:39:38 INFO - 'XPC_SERVICE_NAME': '0', 11:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 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)) 11:39:39 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:39:39 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 11:39:39 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 11:39:39 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 11:39:39 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 11:39:40 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:39:40 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)) 11:39:40 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)) 11:39:40 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)) 11:39:40 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)) 11:39:40 INFO - Installing collected packages: marionette-driver 11:39:40 INFO - Running setup.py install for marionette-driver 11:39:40 INFO - Successfully installed marionette-driver 11:39:40 INFO - Cleaning up... 11:39:41 INFO - Return code: 0 11:39:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a416c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a41a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf205cb800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, '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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 11:39:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:41 INFO - 'HOME': '/Users/cltbld', 11:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:41 INFO - 'LOGNAME': 'cltbld', 11:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:41 INFO - 'MOZ_NO_REMOTE': '1', 11:39:41 INFO - 'NO_EM_RESTART': '1', 11:39:41 INFO - 'PAGER': '/bin/cat', 11:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:41 INFO - 'PWD': '/builds/slave/test', 11:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:41 INFO - 'SHELL': '/bin/bash', 11:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:39:41 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:41 INFO - 'USER': 'cltbld', 11:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:41 INFO - 'XPC_FLAGS': '0x0', 11:39:41 INFO - 'XPC_SERVICE_NAME': '0', 11:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:39:41 INFO - Cleaning up... 11:39:41 INFO - Return code: 0 11:39:41 INFO - Running post-action listener: _resource_record_post_action 11:39:41 INFO - Running post-action listener: _start_resource_monitoring 11:39:41 INFO - Starting resource monitoring. 11:39:41 INFO - [mozharness: 2017-06-01 18:39:41.342002Z] Finished create-virtualenv step (success) 11:39:41 INFO - [mozharness: 2017-06-01 18:39:41.342968Z] Running install step. 11:39:41 INFO - Running pre-action listener: _resource_record_pre_action 11:39:41 INFO - Running main action method: install 11:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:39:41 INFO - Reading from file tmpfile_stdout 11:39:41 INFO - Detecting whether we're running mozinstall >=1.0... 11:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:39:41 INFO - Reading from file tmpfile_stdout 11:39:41 INFO - Output received: 11:39:41 INFO - Usage: mozinstall [options] installer 11:39:41 INFO - Options: 11:39:41 INFO - -h, --help show this help message and exit 11:39:41 INFO - -d DEST, --destination=DEST 11:39:41 INFO - Directory to install application into. [default: 11:39:41 INFO - "/builds/slave/test"] 11:39:41 INFO - --app=APP Application being installed. [default: firefox] 11:39:41 INFO - mkdir: /builds/slave/test/build/application 11:39:41 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'] 11:39:41 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 11:40:02 INFO - Reading from file tmpfile_stdout 11:40:02 INFO - Output received: 11:40:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:40:02 INFO - Running post-action listener: _resource_record_post_action 11:40:02 INFO - [mozharness: 2017-06-01 18:40:02.950646Z] Finished install step (success) 11:40:02 INFO - [mozharness: 2017-06-01 18:40:02.950804Z] Running setup-mitmproxy step. 11:40:02 INFO - Running pre-action listener: _resource_record_pre_action 11:40:02 INFO - Running main action method: setup_mitmproxy 11:40:02 INFO - Skipping: mitmproxy is not required 11:40:02 INFO - Running post-action listener: _resource_record_post_action 11:40:02 INFO - [mozharness: 2017-06-01 18:40:02.951479Z] Finished setup-mitmproxy step (success) 11:40:02 INFO - [mozharness: 2017-06-01 18:40:02.951596Z] Running run-tests step. 11:40:02 INFO - Running pre-action listener: _resource_record_pre_action 11:40:02 INFO - Running main action method: run_tests 11:40:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:40:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:40:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:40:02 INFO - 2017-06-01 11:40:02.966 screenresolution[1621:7611] starting screenresolution argv=screenresolution get 11:40:02 INFO - 2017-06-01 11:40:02.968 screenresolution[1621:7611] Display 0: 1600x1200x32@60 11:40:02 INFO - 2017-06-01 11:40:02.974 screenresolution[1622:7614] starting screenresolution argv=screenresolution list 11:40:02 INFO - Available Modes on Display 0 11:40:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:40:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:40:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:40:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:40:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:40:03 INFO - Intel Iris: 11:40:03 INFO - Chipset Model: Intel Iris 11:40:03 INFO - Type: GPU 11:40:03 INFO - Bus: Built-In 11:40:03 INFO - VRAM (Dynamic, Max): 1536 MB 11:40:03 INFO - Vendor: Intel (0x8086) 11:40:03 INFO - Device ID: 0x0a2e 11:40:03 INFO - Revision ID: 0x0009 11:40:03 INFO - Displays: 11:40:03 INFO - Display: 11:40:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:40:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:40:03 INFO - Display Serial Number: 1600x1200 60 11:40:03 INFO - Main Display: Yes 11:40:03 INFO - Mirror: Off 11:40:03 INFO - Online: Yes 11:40:03 INFO - Rotation: Supported 11:40:03 INFO - Return code: 0 11:40:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:40:03 INFO - Python 2.7.3 11:40:03 INFO - Return code: 0 11:40:03 INFO - grabbing minidump binary from tooltool 11:40:03 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')]} 11:40:03 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 0x10a39fc60>, '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 0x10a134cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:40:03 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 11:40:03 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 11:40:03 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 11:40:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:40:03 INFO - Return code: 0 11:40:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:40:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:40:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:40:03 INFO - ENV: RUST_BACKTRACE is now 1 11:40:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:40:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:40:03 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/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 11:40:03 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/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0328 --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 11:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mBeEWlo8cu/Render', 11:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:03 INFO - 'HOME': '/Users/cltbld', 11:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:03 INFO - 'LOGNAME': 'cltbld', 11:40:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:03 INFO - 'MOZ_NO_REMOTE': '1', 11:40:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:03 INFO - 'NO_EM_RESTART': '1', 11:40:03 INFO - 'PAGER': '/bin/cat', 11:40:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:03 INFO - 'PWD': '/builds/slave/test', 11:40:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:03 INFO - 'RUST_BACKTRACE': '1', 11:40:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:40:03 INFO - 'SHELL': '/bin/bash', 11:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z9pzKREEj3/Listeners', 11:40:03 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 11:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:03 INFO - 'USER': 'cltbld', 11:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:03 INFO - 'XPC_FLAGS': '0x0', 11:40:03 INFO - 'XPC_SERVICE_NAME': '0', 11:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:03 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/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 11:40:03 INFO - mozversion application_buildid: 20170601094922 11:40:03 INFO - mozversion application_changeset: 0e9853e31da9848ab638bdd0df4eb734a5ebc232 11:40:03 INFO - mozversion application_display_name: Nightly 11:40:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:40:03 INFO - mozversion application_name: Firefox 11:40:03 INFO - mozversion application_remotingname: firefox 11:40:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:40:03 INFO - mozversion application_vendor: Mozilla 11:40:03 INFO - mozversion application_version: 55.0a1 11:40:03 INFO - mozversion platform_buildid: 20170601094922 11:40:03 INFO - mozversion platform_changeset: 0e9853e31da9848ab638bdd0df4eb734a5ebc232 11:40:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:40:03 INFO - mozversion platform_version: 55.0a1 11:40:03 INFO - using testdate: 1496342403 11:40:03 INFO - actual date: 1496342403 11:40:03 INFO - starting webserver on 'localhost:49221' 11:40:03 INFO - SUITE-START | Running 2 tests 11:40:03 INFO - TEST-START | dromaeo_css 11:40:03 INFO - operating with platform_type : mac_ 11:40:03 INFO - Initialising browser for dromaeo_css test... 11:40:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpDx7y_V/profile 11:40:03 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 11:40:37 INFO - TEST-INFO | 1632: killed by SIGTERM 11:40:37 INFO - Browser initialized. 11:40:37 INFO - Running cycle 1/1 for dromaeo_css test... 11:40:37 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpDx7y_V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:39 INFO - PID 1642 | 2017-06-01 11:40:39.964 plugin-container[1643:8187] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 11:40:39 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:40:39 INFO - PID 1642 | 2017-06-01 11:40:39.965 plugin-container[1643:8187] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 11:40:39 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:40:39 INFO - PID 1642 | 2017-06-01 11:40:39.965 plugin-container[1643:8187] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:40:40 INFO - PID 1642 | 2017-06-01 11:40:40.440 plugin-container[1644:8234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a43, name = 'com.apple.tsm.portname' 11:40:40 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:40:40 INFO - PID 1642 | 2017-06-01 11:40:40.441 plugin-container[1644:8234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 11:40:40 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:40:40 INFO - PID 1642 | 2017-06-01 11:40:40.441 plugin-container[1644:8234] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:40:49 INFO - PID 1642 | RSS: Main: 268922880 11:40:49 INFO - PID 1642 | 11:43:51 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 11:43:53 INFO - PID 1642 | RSS: Main: 271351808 11:43:53 INFO - PID 1642 | 11:46:55 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 11:46:55 INFO - PID 1642 | RSS: Main: 286023680 11:46:55 INFO - PID 1642 | 11:49:57 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 11:49:58 INFO - PID 1642 | RSS: Main: 284848128 11:49:58 INFO - PID 1642 | 11:52:59 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 11:53:00 INFO - PID 1642 | RSS: Main: 288546816 11:53:00 INFO - PID 1642 | 11:56:03 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 11:56:04 INFO - PID 1642 | RSS: Main: 288657408 11:56:04 INFO - PID 1642 | 11:59:05 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 11:59:06 INFO - PID 1642 | RSS: Main: 291471360 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | __start_tp_report 11:59:06 INFO - PID 1642 | _x_x_mozilla_page_load 11:59:06 INFO - PID 1642 | _x_x_mozilla_page_load_details 11:59:06 INFO - PID 1642 | |i|pagename|runs| 11:59:06 INFO - PID 1642 | |0;dojo.html;2823.6329277193963;3598.6041535431114;3631.7639353442023;3653.926921461571;3657.3233951718917;5135.972805438913;5724.771009159634;6144.047672610742;6238.282597501288;6238.84402889928;6153.446189842914;6198.318184999646;6198.318184999646;6271.498280137578;6284.842878928029;5052.393712754475;5288.15389537674;5389.622726409153;5447.972760136194;5453.754581043842;5546.278983732109;5565.30433695788;5570.387257401682;5580.107182850745;5635.154726790973;5350.0904846176245;5438.320209973754;5454.53636440903;5480.04099282628;5483.479069488392;5497.5601951843755;5499.092649712793;5524.309461317335;5526.281583394173;5562.137868630369;5408.567314614821;5428.0500912340185;5433.429489903582;5438.728063596844;5453.182022696587;6143.235544005215;6184.628922264678;6280.748770049193;6292.937070629281;6295.653739044351;6541.803745887631;6618.7021584028835;6725.663716814189;6736.360045795642;6758;6859.348361905611;6860.5540639858245;6866.485013623998;6866.828329291758;6882.070920425778;6218.844528886769;6309.400606942333;6333.556651034383;6338.461230795342;6360.840978975516;6821.590704557742;6876;6916.273791251846;6960.617166055814;6971.163460384786;6430.3891130342545;6493.51298652603;6509.446697030806;6595.868082638321;6627.204735431683;6757.358050985148;6826.24911259761;6830.248672646005;6839.0425340452375;6844.247132815386;6688.933110668831;6698;6720.764773232913;6724.798256052326;6731.225909020525;6410.455111315498;6451.258105317855;6651.534392592472;6698.7320507179165;6717.899231511531;5392.865178370494;5397.298351214396;5407.459254074561;5417.756200970965;5421.674699518042;4768.690035147704;4775.379200703956;4803.063402636522;4810.037992401533;4828.517148285144;5646.181303710939;5909.877123346636;6286.654234017082;6299.275583307978;6346.650934198663;5834.824955251349;6141.670122563672;6496.870062598816;6851.588904665637;6904.378605925491;5452.345718513724;6318.462930650946;6326.082718005955;6390;6397.776077837253;5116.104681680691;5265.236540701653;5910.320313163955;6002.54980876436;6084.117802918552;5782;7015.9298407016295;7025.789226323219;7035.929640703732;7080.079589653312;6030.638161710312;6674.83312917181;6846.726130954905;6852.0064590635075;6863.485238607224;5608.943910560843;6900.102986611709;6935.410490108397;6966.721331146698;6981.02265682815;5440.972795135999;6648.90026649591;6695.564788288746;6728.495362847904;6739.123913891162;5897.616654917417;6659.901116315948;6865.759698410531;6881.071055407456;6903.723851046103;6456.096146539582;6521.804345869631;6557.344265573404;6654.367835055662;6662.83342916431;6604.14146160996;6658.267590564937;6672.132622759011;6680.432163265982;6686;5523.420040895809;6452.580582262138;6528.086067950396;6552.148220731275;6563.409293163638;6434.453071488976;6526.216853977363;6639.4356479697835;6673.9332606673315;6674.232463266787;6398.456131228898;6467.773627923;6476;6498.285188629153;6512.6743662815725 11:59:06 INFO - PID 1642 | |1;ext.html;784.8901153838464;822.481836443041;847.449158047269;1256.6418570707356;1319.3601103464812;39167;40176.59823401761;40234.39648405276;40259.59740402595;40301.79094627158;39677.206455870866;39712.80143599285;40017.59982400175;40149.99625009376;40463.59536404635;7813.531188128731;7954.960225198866;8181.222783835526;8378.748637540884;8410.36922230832;7356.852862942738;8319.584020798935;8347.165283471664;8481.067082620906;8490.320774338039;8391.370647201455;8421.115782842806;8426.115257897924;8479.2792612628;8511.787205319886;8394;8435.83128337433;8465.53439560824;8471.534065626389;8483.448575842582;8220.424570280084;8272.793180170484;8304.418690691655;8322.8335433291;8330.333573314121;37491.25017499662;37788.05529861748;37795;37798.622013779786;37843.4323485148;3515.2090779574646;3565.358235517606;3570.785752854837;3571.535700358963;3574.463830425431;30743;30808.537871931938;30859.691403085908;30955.845220773976;30958.84520577405;2846;3074.8462576871066;3115.9688403115906;3125.687431256879;3132.451820931328;236689;253679;255608.7219563909;302009;303859.4807025951;48463;48635.51364486381;48892;49109.26336104961;49155.50844491581;50402.73993150127;50670.493295066575;50943;53725;55151.172732409046;14194.645133871736;14325.856741432452;14369.425222990963;14391.136531808124;14419.990600658062;11377.943110284396;11442.485088171052;11455.59905403307;11460.025897798614;11462.828057579143;13872.583822485341;13919.23444210578;13933.442662293597;14004.43982240699;14110.294485275695;6167;6169.598976066542;6194.38056194386;6207.162033125561;6218.595791273553;1000.6497725795913;1011.5296387179997;1039.2205845615788;1068.8450174724621;1090.8145615245428;24102.15642452506;25025.499490010097;25355.87322063415;25441.491170176494;25442.49115017689;1137.5677242647741;1146.340854008948;1177.6408195500455;1183.2841131115724;1185.6265276437894;10768.923107689168;12913;13037.869621303666;13096.279704616147;13132.277724725041;321330;322585;323285;323431;323855;47943.760281198374;56099;56115;56646.716766415906;56851;1516.1661086402655;1518.8177418709824;1556.2218890554723;1560.2666746629066;1563.1715190948814;23120.61276323426;24250.757492425557;25143.49713005766;25160.874195629636;25201.24396268115;40431.79784101061;50660.74669626628;52945.470545294054;53138.734306328224;53484;282760;284925.5753721301;285952;286200;286399.56800215866;34631.653683463854;36403.635963641085;36765.81617091897;36926.26147476982;37248;44659.553404465536;45176.32235516404;46433.30350044819;46801.53198468108;47039.76480117578;27438.17685469439;30210.9426170083;30807.383852323277;30924.453777311493;31581.368372632878;26025.349366265997;31074.533881991338;31285.374292514476;31745.04764857058;32234.194145146557;36972.44541331828;38274.80862595669;38339;38341.80829095837;38371.80814096023;30487.390252195277;30730.53904191394;32171;32330.83834580812;32368.67631323751;24060.639090414006;24677.62983555284;24771.504569908142;25154;25181 11:59:06 INFO - PID 1642 | |2;jquery.html;394.3473551272644;416.5896591857022;433.22885264229683;443.842435935243;467.60487388157014;14300.356483958221;15009.924950375233;21899;22318.10727570897;22590.54818903621;18457;23460.061597536078;23500.764992350072;23533.882330588367;23587.174448894293;5984.88030239395;6077.4226448487325;6150.538709596798;6151.3233544310315;6180.72186751595;6088.147659327676;6124.6325220486915;6153.200083989098;6167.876642467148;6181.320054793991;4764.499727528594;5698.458646428583;5903.734331955073;6099.9935010723175;6110.419510146551;5876.0598304271325;5928.347881733006;5939.406059394069;5949.6430214187285;5951.018082016462;5273.789048438058;5283.154695248781;5304.973475132639;5307;5324.41431442541;27778.583321250197;27946.580801287997;28281;28287.717122828715;28292.575611365846;2062.26789489732;2150.6451435513122;2154.601398741238;2155.504234026183;2177.7713340099376;18290.811097278634;18505.444836654788;18578.442646720483;18584.349547765763;18620.348287809862;2117.6400011998;2148.2803260907613;2162.351294611626;2168.826493880486;2191.1345018717675;204614;205374.94625053857;209532.9046709544;209919.9999999985;210077;12474.688132796753;12733.68165795844;12753.61739147827;12770.9361453194;12915.935420322838;15933.760993585105;16212.35150593963;16275.430359937582;16291;16305.184740763152;8702.738917832476;8820.382573219813;8949.686760963335;8970.282377409922;8973.865392019125;8578.399512034222;8602.31181505478;8607.61265743043;8615.526146062026;8669.436486628349;6676.899846502306;7612.1246056703085;8045.356371490267;8199.22107399796;8260.380471464665;1929.6526625207598;2266.5806825736963;2268.9319320420414;2282.0529480265504;2469.1111199967995;932.8320902237663;963.9710808675751;973.8101070291225;978.1753344423271;1007.1539906478594;18550.628987420445;19966.70049949252;20294.39116826468;21052.578948420945;21291.935403229778;930.469632309577;1002.7693630465034;1027.8920713325142;1028.8785224105761;1038.7922415516775;10272.33229840058;10800.027997480265;10830.458477076114;10919.454027298762;11059.72350691223;518024;527102;530947;531264;534321;141968.58031419554;144986;145691.0731695127;147224;147305;1043.1811028342715;1142.9257098288585;1180.1267062373781;1184.2420850655587;1188.8276199950958;15811.683766324379;16166.757498637766;17657.558561036076;18268;18901.43295701131;42387;55877.16184257321;66149.41100452232;67059.66470167619;68934.65532672305;91699.62450563381;91755.08244917466;91981.54009229911;92526.53736731273;92703.53648231715;9832.901670983485;10080.899191007997;10107.494625268855;10115.443650599287;10124.746881328027;12922.935385323013;13140.868591313965;17014.74477882808;18042;18107;14309.284535773379;14751.778723319374;14767.704645906806;14831.961762676508;14836.258187090387;12695.174813637084;12860.421281042563;13255.204687718768;13272.734545308847;13349.199048057148;14733.778993315324;14775.335109919864;14948.626284342978;14985.775213372026;15013.474528391454;9871.901280987098;9951.502424878872;9998.800023999625;10007.299489035697;10024.14794742455;13616.251106189255;14582.343794529059;14812;14884.10695358282;14944.551663450115 11:59:06 INFO - PID 1642 | |3;mootools.html;1335.5659410691528;1359.5989183190957;1379.7585422551053;1467.2077078377677;1473.1382141447257;11750.941245293761;11837.348945807988;12367.257964522123;12391.566295179671;12496.875031249685;12227.816582751266;12366.01071914247;12402.937985310084;12444.377781110954;12450.875491245084;2532.5061612985473;2579.690437147545;2592.792576593868;2594.403287243935;2600.4539046800196;2200.9779902200935;2519.911803086892;2548.566743653583;2563.987180064097;2572.665553478055;2515.3585835611893;2534.3664083979006;2540.27602133392;2548.9490210195786;2555.080171138398;2491.3397949543296;2511.2717311979504;2511.7488251174736;2515.5472015037285;2518.0431436054273;2373.074500944633;2380.392999785061;2447.1801946347996;2554.463562651845;2560.3343130785943;11893.40532973348;11906.166568340219;11985.340806255652;11985.981191598725;12006.159568830186;6439.581427207217;6649.567778094409;6665;6709.261981182066;6724;3904.141088960424;4065.4714887064492;4067.5525692173837;4130.987907962538;4139.254934111858;1240.193873981919;1259.0682894657978;1287.3563218390805;1287.5557932513268;1289.6969212235117;15499.5350139496;16044.957077790023;16595.087270200132;16729.330826766916;16739.91190572609;4577.244754615518;5505.642133261325;5729.88540229193;5775.393583673737;5805.419458054161;12271.018318534496;12279.447424865903;12282.32447215394;12302.138850280573;12318.630441086782;6406.743730250831;6418.26189988148;6446.258680251737;6499.902501462482;6503.577267477599;6034.577579569475;6102.633841969496;6162.907556386658;6163.106349579286;6196.256449226032;4314.956850431518;4400.845970391021;4485.484169320504;4487.080148569536;4518.9548104518535;1278.5525066226746;1299.3957809618573;1300.9674758130934;1307.0589175793414;1317.8220940173146;789.0373744032271;799.5602418669824;802.7350974178507;805.8952336196257;812.7642983534827;6232.283287421914;6887.483438742114;7091.680874360666;7203.783886483414;7266.781996540112;849.1169184048521;896.8071864549154;918.1323649151487;930.3115694386241;941.0567178775806;3632.146445585285;3870.6129387061633;4229.851955181553;4405.0462306876325;4435.778211089432;13959.511417100353;14935.850641493664;15173.317200725995;15246.847531524765;15272.77090843616;14672.633184170481;15048.021878578076;15200.011999220016;15383.538493845203;15520.146391948325;8119.147489513516;8299.585020748938;8470.195331443503;8483.854679618173;8502.44734092282;6157.753689852356;6620.3710647488415;6953.75661851853;7021.43828493709;7101.396381307647;13055;13090.345482725636;13672.453101876212;13837.30813459303;13858.43618930501;13975;14078.225697586322;14194.645133871736;14375.78436323435;14402.127723395892;9620.470874101991;9761.951190244003;9806.80386392254;9811.264155188532;9840.11438970496;10764.407957562096;10901.563937442414;11008.559657613605;11023.503942322453;11035.503402346752;8246.0929297776;8960.86558701607;9319.906800931903;9349.626014959325;9351.625934962525;8080.676772929252;8978.820423591622;9057.456552606865;9130.634774609207;9243.58403871814;8167.264946154874;8291.129431409856;8294.668213271643;8309.252167304972;8342.624581893951;5215.791368345223;5933.941228000333;5960;6094.725737341919;6164.7842325518395;5996.820095397145;6048.818535443944;6173.290071641639;6175.289841668266;6392.169018027626 11:59:06 INFO - PID 1642 | |4;prototype.html;344.4540403460515;375.36937944253646;392.82715605133757;402.1394216376952;405.9776712280825;5540.362858271295;6197.659128747917;6262.530310226734;6309.810705678826;6365.726854629075;5906.700525884298;5934.762609495626;6106.664133472657;6169.871394368946;6180.969095154529;4520.547945205486;4553.408056952591;4555.703879247855;4563.475200351969;4575.702579332333;3585.6593623605845;4552.339910712944;4557.179707652621;4565.132624801294;4581.816727330919;3732.2722069196516;4482.417285752864;4513.593776560109;4523.253663145593;4562.8631141065825;4234.9153016939645;4515.4129963104915;4534;4537.909241815179;4565.748883811389;3877.476540667002;3908.4332771748223;3910.667593254577;3918.2163567286657;3932.980335098306;5984.414130255485;6108.5418593605655;6113.174721412642;6128;6128.632282063046;4850.902981940377;5443.755031023614;5446.291982042309;5446.692793729477;5454.972725136388;5375.838724838261;6335.7328534292865;6343.651099189543;6657.40083392497;6712.563683360566;3368.938784282943;3384.560007199073;4344.109457561192;4407.162639098561;4444.622207112367;6403.551751377406;6945.40964018054;7096.645167741593;7132.572045677224;7169.928300716978;4143.627073563393;4204.33928598202;4508.098380323882;4538.818447262139;4542.909141817145;3777.962220377816;4239.766812825263;4595.586397224266;4622.14490319292;4627.9768601156775;5251.317328747238;5417.566594672417;5426.941746359422;5438.972805136028;5447.673139611636;5563.276774019432;5906.426611899825;6758.5655778643095;6761.599632130621;6767.458603311723;1570.2384343593349;1574.7401678722883;1575.1966497086569;1581.897176683535;1582.7784110224577;875.5228400521731;888.05865782271;1008.3949630221955;1044.8537204791335;1062.4793851012948;5544.805931792449;6010.759569617254;6262.12330273762;6309.463695585927;6313.621182729051;5762.567807414461;5781.699117698484;6114.571979961449;6706.530542861953;6998.790036298919;6264.874702505925;6282.717277722422;6295.401936815995;6313.305536390993;6321.494280457552;3614.204874927564;3642.562892452923;3666.908327291839;3678.779273243614;3691.8523259069875;6257.687115644199;7067.82330441743;7138;7144.749933752398;7200.795645937612;6530.542861999615;7191.964040179871;7202.53183543068;7222.96388518054;7228.566286022856;6785.660716964132;7045.238690327418;7069.48580107517;7148.535345202546;7256.7822965311125;6122.663253521099;6922.550034247758;7029.753958611424;7038.085048943604;7066.964665176642;6295.811125666238;6799.796006119825;6901.585904845725;6927.376536111774;6946.6179360135675;6661.733530658719;6933.479989000946;7212.386947109554;7264.128304603481;7264.67308971087;4007.479027726377;4104.809605214455;4509.143262780061;4523.547645235451;4547.953420960647;6362.618242905441;6909.30906909305;6912.516123871482;6944.37500624926;7046.067752573117;5467.863303417446;6030.195913965484;6260.7495700171485;6289.591176573508;6707.195136583641;6201.410865967726;6331.271903730945;6662.333766623493;6681.866362672817;6702.530822842549;6303.527235457451;6544.770933017321;6572;6669.7665581704405;6676;5558.388577256579;6697.430718388797;6701.765438209639;6723.226828914541;6724.394804467622;6059.606125601822;6480.902786458113;6542.280349161683;6559.311272316338;6561.770338038145 11:59:06 INFO - PID 1642 | |5;yui.html;692.7575348627981;798.5727635714894;813.446856137826;838.7670124916375;843.3084870406266;14529.927350363234;15073.246337683127;16733.83266167338;17231.741523877135;17236.396726114574;16526.504204873843;17839.018853963025;18331.633367332677;18436;18452.354167604102;979.123684302547;980.8332583460793;987.8518222266681;1008.5965613754483;1011.5903059261011;1029.629333439961;1030.1192480429238;1030.9793804123913;1033.7105610429091;1033.7904651557697;930.0745757970794;1048.8846226915034;1049.6063976008998;1061.506399524221;1067.765091679829;1036.0675392147052;1036.2435422141841;1036.419605021183;1036.429963520061;1040.8750949885987;785.5051317669831;788.0385929166408;790.5730905311125;791.7189397763826;793.6150966781106;16801.83198168015;16971.830281697148;17484.12579371039;17595.296188152457;17655.5586110347;16993.235304411206;17358.91320543389;17667.73498397525;17790.46628601144;17837.910810445865;13865.722685546232;14154.787678184835;14157;14163.858361416356;14188.787168192486;3559.982200089009;3570.535830342065;3591.3715099857423;3592.7485076044686;3594.658507441815;105133;113115;136005.3199734005;136929;137109.3144534271;13424.127431716906;13928.303584820718;14037.5086871959;14054.929725351512;14075.2962351882;22794;24614.507709845704;24992.00031998736;25442.745572544038;25666;5954.345022047572;6651.534392592472;6673.865442874723;7299;7535.6985720570565;3634.436662317345;3753.11801726594;3754.249150169977;3757.8496860125833;3761.3981762918347;4026.8389264429093;4110.301248787713;4116.197341518435;4121.031557583965;4123.443335149776;831.9168083191754;926.5274709898;974.0843607009389;974.9902500974899;976.4580657734883;649.5647915896361;661.3155384177353;662.552776875607;668.5921587831419;673.8248055505505;11191.888081119247;11362.091032717362;12430.440630171664;12633.242005479517;12878.227306361649;641.351726064011;641.8908785506381;649.0977541217711;669.4075742967509;672.6132473827569;5639.436056394327;5653.378128405872;5819.592628516048;5867.2959244890035;5915.645061296336;121907;122750.38624806819;126377;126989.73010269964;132712;104806.47596761967;107664.92335076706;108271.1728827111;109447;109915;80154.19845801585;91496;96939;96958;97170.51414742881;11048.447577621248;12368.567100151451;12807.487700491876;12822.487100515875;12885.677858053623;40670;41393.58606413897;41691.58308416877;42018.789906050275;42685.78657106819;44173.33739993967;44635.33047004363;52669.47330526799;55354.67871927697;56224.43775562192;16548.75176872324;16610.750838737185;16823.158842057604;17014.74477882808;17058.402955896483;15667.686646267239;18244.635107298047;18385.632287354445;18904.905475472533;18990;13962.371693273624;17051.658966820843;17571.472855814347;17753.378631747826;17855.28578856831;16920.57698557546;17119.657606848043;17121.400750973655;17176.05531695769;17238.05190714523;16485.670286594443;16620;16643.83356166423;17036.359364298554;17043.914780426017;11855.99224065964;12022.939885300517;12071.698207544881;12280.754384912074;12292.692682682648;13389.493459643762;13559.983001274746;13602.13747874655;13603.931980340036;13670.11144275619 11:59:06 INFO - PID 1642 | __end_tp_report 11:59:06 INFO - PID 1642 | __start_cc_report 11:59:06 INFO - PID 1642 | _x_x_mozilla_cycle_collect,452 11:59:06 INFO - PID 1642 | __end_cc_report 11:59:06 INFO - PID 1642 | __startTimestamp1496343546118__endTimestamp 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | ------- Summary: start ------- 11:59:06 INFO - PID 1642 | Number of tests: 6 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6150.71 Median:6397.78 stddev:761.16 (11.9%) stddev-sans-first:717.35 11:59:06 INFO - PID 1642 | Values: 2823.6 3598.6 3631.8 3653.9 3657.3 5136.0 5724.8 6144.0 6238.3 6238.8 6153.4 6198.3 6198.3 6271.5 6284.8 5052.4 5288.2 5389.6 5448.0 5453.8 5546.3 5565.3 5570.4 5580.1 5635.2 5350.1 5438.3 5454.5 5480.0 5483.5 5497.6 5499.1 5524.3 5526.3 5562.1 5408.6 5428.1 5433.4 5438.7 5453.2 6143.2 6184.6 6280.7 6292.9 6295.7 6541.8 6618.7 6725.7 6736.4 6758.0 6859.3 6860.6 6866.5 6866.8 6882.1 6218.8 6309.4 6333.6 6338.5 6360.8 6821.6 6876.0 6916.3 6960.6 6971.2 6430.4 6493.5 6509.4 6595.9 6627.2 6757.4 6826.2 6830.2 6839.0 6844.2 6688.9 6698.0 6720.8 6724.8 6731.2 6410.5 6451.3 6651.5 6698.7 6717.9 5392.9 5397.3 5407.5 5417.8 5421.7 4768.7 4775.4 4803.1 4810.0 4828.5 5646.2 5909.9 6286.7 6299.3 6346.7 5834.8 6141.7 6496.9 6851.6 6904.4 5452.3 6318.5 6326.1 6390.0 6397.8 5116.1 5265.2 5910.3 6002.5 6084.1 5782.0 7015.9 7025.8 7035.9 7080.1 6030.6 6674.8 6846.7 6852.0 6863.5 5608.9 6900.1 6935.4 6966.7 6981.0 5441.0 6648.9 6695.6 6728.5 6739.1 5897.6 6659.9 6865.8 6881.1 6903.7 6456.1 6521.8 6557.3 6654.4 6662.8 6604.1 6658.3 6672.1 6680.4 6686.0 5523.4 6452.6 6528.1 6552.1 6563.4 6434.5 6526.2 6639.4 6673.9 6674.2 6398.5 6467.8 6476.0 6498.3 6512.7 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:45323.97 Median:178111.84 stddev:77035.93 (43.3%) stddev-sans-first:77179.45 11:59:06 INFO - PID 1642 | Values: 784.9 822.5 847.4 1256.6 1319.4 39167.0 40176.6 40234.4 40259.6 40301.8 39677.2 39712.8 40017.6 40150.0 40463.6 7813.5 7955.0 8181.2 8378.7 8410.4 7356.9 8319.6 8347.2 8481.1 8490.3 8391.4 8421.1 8426.1 8479.3 8511.8 8394.0 8435.8 8465.5 8471.5 8483.4 8220.4 8272.8 8304.4 8322.8 8330.3 37491.3 37788.1 37795.0 37798.6 37843.4 3515.2 3565.4 3570.8 3571.5 3574.5 30743.0 30808.5 30859.7 30955.8 30958.8 2846.0 3074.8 3116.0 3125.7 3132.5 236689.0 253679.0 255608.7 302009.0 303859.5 48463.0 48635.5 48892.0 49109.3 49155.5 50402.7 50670.5 50943.0 53725.0 55151.2 14194.6 14325.9 14369.4 14391.1 14420.0 11377.9 11442.5 11455.6 11460.0 11462.8 13872.6 13919.2 13933.4 14004.4 14110.3 6167.0 6169.6 6194.4 6207.2 6218.6 1000.6 1011.5 1039.2 1068.8 1090.8 24102.2 25025.5 25355.9 25441.5 25442.5 1137.6 1146.3 1177.6 1183.3 1185.6 10768.9 12913.0 13037.9 13096.3 13132.3 321330.0 322585.0 323285.0 323431.0 323855.0 47943.8 56099.0 56115.0 56646.7 56851.0 1516.2 1518.8 1556.2 1560.3 1563.2 23120.6 24250.8 25143.5 25160.9 25201.2 40431.8 50660.7 52945.5 53138.7 53484.0 282760.0 284925.6 285952.0 286200.0 286399.6 34631.7 36403.6 36765.8 36926.3 37248.0 44659.6 45176.3 46433.3 46801.5 47039.8 27438.2 30210.9 30807.4 30924.5 31581.4 26025.3 31074.5 31285.4 31745.0 32234.2 36972.4 38274.8 38339.0 38341.8 38371.8 30487.4 30730.5 32171.0 32330.8 32368.7 24060.6 24677.6 24771.5 25154.0 25181.0 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:37700.40 Median:2158.93 stddev:92986.42 (4307.1%) stddev-sans-first:93205.09 11:59:06 INFO - PID 1642 | Values: 394.3 416.6 433.2 443.8 467.6 14300.4 15009.9 21899.0 22318.1 22590.5 18457.0 23460.1 23500.8 23533.9 23587.2 5984.9 6077.4 6150.5 6151.3 6180.7 6088.1 6124.6 6153.2 6167.9 6181.3 4764.5 5698.5 5903.7 6100.0 6110.4 5876.1 5928.3 5939.4 5949.6 5951.0 5273.8 5283.2 5305.0 5307.0 5324.4 27778.6 27946.6 28281.0 28287.7 28292.6 2062.3 2150.6 2154.6 2155.5 2177.8 18290.8 18505.4 18578.4 18584.3 18620.3 2117.6 2148.3 2162.4 2168.8 2191.1 204614.0 205374.9 209532.9 209920.0 210077.0 12474.7 12733.7 12753.6 12770.9 12915.9 15933.8 16212.4 16275.4 16291.0 16305.2 8702.7 8820.4 8949.7 8970.3 8973.9 8578.4 8602.3 8607.6 8615.5 8669.4 6676.9 7612.1 8045.4 8199.2 8260.4 1929.7 2266.6 2268.9 2282.1 2469.1 932.8 964.0 973.8 978.2 1007.2 18550.6 19966.7 20294.4 21052.6 21291.9 930.5 1002.8 1027.9 1028.9 1038.8 10272.3 10800.0 10830.5 10919.5 11059.7 518024.0 527102.0 530947.0 531264.0 534321.0 141968.6 144986.0 145691.1 147224.0 147305.0 1043.2 1142.9 1180.1 1184.2 1188.8 15811.7 16166.8 17657.6 18268.0 18901.4 42387.0 55877.2 66149.4 67059.7 68934.7 91699.6 91755.1 91981.5 92526.5 92703.5 9832.9 10080.9 10107.5 10115.4 10124.7 12922.9 13140.9 17014.7 18042.0 18107.0 14309.3 14751.8 14767.7 14832.0 14836.3 12695.2 12860.4 13255.2 13272.7 13349.2 14733.8 14775.3 14948.6 14985.8 15013.5 9871.9 9951.5 9998.8 10007.3 10024.1 13616.3 14582.3 14812.0 14884.1 14944.6 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7209.38 Median:3751.38 stddev:4593.54 (122.4%) stddev-sans-first:4585.22 11:59:06 INFO - PID 1642 | Values: 1335.6 1359.6 1379.8 1467.2 1473.1 11750.9 11837.3 12367.3 12391.6 12496.9 12227.8 12366.0 12402.9 12444.4 12450.9 2532.5 2579.7 2592.8 2594.4 2600.5 2201.0 2519.9 2548.6 2564.0 2572.7 2515.4 2534.4 2540.3 2548.9 2555.1 2491.3 2511.3 2511.7 2515.5 2518.0 2373.1 2380.4 2447.2 2554.5 2560.3 11893.4 11906.2 11985.3 11986.0 12006.2 6439.6 6649.6 6665.0 6709.3 6724.0 3904.1 4065.5 4067.6 4131.0 4139.3 1240.2 1259.1 1287.4 1287.6 1289.7 15499.5 16045.0 16595.1 16729.3 16739.9 4577.2 5505.6 5729.9 5775.4 5805.4 12271.0 12279.4 12282.3 12302.1 12318.6 6406.7 6418.3 6446.3 6499.9 6503.6 6034.6 6102.6 6162.9 6163.1 6196.3 4315.0 4400.8 4485.5 4487.1 4519.0 1278.6 1299.4 1301.0 1307.1 1317.8 789.0 799.6 802.7 805.9 812.8 6232.3 6887.5 7091.7 7203.8 7266.8 849.1 896.8 918.1 930.3 941.1 3632.1 3870.6 4229.9 4405.0 4435.8 13959.5 14935.9 15173.3 15246.8 15272.8 14672.6 15048.0 15200.0 15383.5 15520.1 8119.1 8299.6 8470.2 8483.9 8502.4 6157.8 6620.4 6953.8 7021.4 7101.4 13055.0 13090.3 13672.5 13837.3 13858.4 13975.0 14078.2 14194.6 14375.8 14402.1 9620.5 9762.0 9806.8 9811.3 9840.1 10764.4 10901.6 11008.6 11023.5 11035.5 8246.1 8960.9 9319.9 9349.6 9351.6 8080.7 8978.8 9057.5 9130.6 9243.6 8167.3 8291.1 8294.7 8309.3 8342.6 5215.8 5933.9 5960.0 6094.7 6164.8 5996.8 6048.8 6173.3 6175.3 6392.2 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5371.25 Median:6113.87 stddev:1721.18 (28.2%) stddev-sans-first:1684.15 11:59:06 INFO - PID 1642 | Values: 344.5 375.4 392.8 402.1 406.0 5540.4 6197.7 6262.5 6309.8 6365.7 5906.7 5934.8 6106.7 6169.9 6181.0 4520.5 4553.4 4555.7 4563.5 4575.7 3585.7 4552.3 4557.2 4565.1 4581.8 3732.3 4482.4 4513.6 4523.3 4562.9 4234.9 4515.4 4534.0 4537.9 4565.7 3877.5 3908.4 3910.7 3918.2 3933.0 5984.4 6108.5 6113.2 6128.0 6128.6 4850.9 5443.8 5446.3 5446.7 5455.0 5375.8 6335.7 6343.7 6657.4 6712.6 3368.9 3384.6 4344.1 4407.2 4444.6 6403.6 6945.4 7096.6 7132.6 7169.9 4143.6 4204.3 4508.1 4538.8 4542.9 3778.0 4239.8 4595.6 4622.1 4628.0 5251.3 5417.6 5426.9 5439.0 5447.7 5563.3 5906.4 6758.6 6761.6 6767.5 1570.2 1574.7 1575.2 1581.9 1582.8 875.5 888.1 1008.4 1044.9 1062.5 5544.8 6010.8 6262.1 6309.5 6313.6 5762.6 5781.7 6114.6 6706.5 6998.8 6264.9 6282.7 6295.4 6313.3 6321.5 3614.2 3642.6 3666.9 3678.8 3691.9 6257.7 7067.8 7138.0 7144.7 7200.8 6530.5 7192.0 7202.5 7223.0 7228.6 6785.7 7045.2 7069.5 7148.5 7256.8 6122.7 6922.6 7029.8 7038.1 7067.0 6295.8 6799.8 6901.6 6927.4 6946.6 6661.7 6933.5 7212.4 7264.1 7264.7 4007.5 4104.8 4509.1 4523.5 4548.0 6362.6 6909.3 6912.5 6944.4 7046.1 5467.9 6030.2 6260.7 6289.6 6707.2 6201.4 6331.3 6662.3 6681.9 6702.5 6303.5 6544.8 6572.0 6669.8 6676.0 5558.4 6697.4 6701.8 6723.2 6724.4 6059.6 6480.9 6542.3 6559.3 6561.8 11:59:06 INFO - PID 1642 | 11:59:06 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:23165.89 Median:17583.38 stddev:34096.22 (193.9%) stddev-sans-first:34150.11 11:59:06 INFO - PID 1642 | Values: 692.8 798.6 813.4 838.8 843.3 14529.9 15073.2 16733.8 17231.7 17236.4 16526.5 17839.0 18331.6 18436.0 18452.4 979.1 980.8 987.9 1008.6 1011.6 1029.6 1030.1 1031.0 1033.7 1033.8 930.1 1048.9 1049.6 1061.5 1067.8 1036.1 1036.2 1036.4 1036.4 1040.9 785.5 788.0 790.6 791.7 793.6 16801.8 16971.8 17484.1 17595.3 17655.6 16993.2 17358.9 17667.7 17790.5 17837.9 13865.7 14154.8 14157.0 14163.9 14188.8 3560.0 3570.5 3591.4 3592.7 3594.7 105133.0 113115.0 136005.3 136929.0 137109.3 13424.1 13928.3 14037.5 14054.9 14075.3 22794.0 24614.5 24992.0 25442.7 25666.0 5954.3 6651.5 6673.9 7299.0 7535.7 3634.4 3753.1 3754.2 3757.8 3761.4 4026.8 4110.3 4116.2 4121.0 4123.4 831.9 926.5 974.1 975.0 976.5 649.6 661.3 662.6 668.6 673.8 11191.9 11362.1 12430.4 12633.2 12878.2 641.4 641.9 649.1 669.4 672.6 5639.4 5653.4 5819.6 5867.3 5915.6 121907.0 122750.4 126377.0 126989.7 132712.0 104806.5 107664.9 108271.2 109447.0 109915.0 80154.2 91496.0 96939.0 96958.0 97170.5 11048.4 12368.6 12807.5 12822.5 12885.7 40670.0 41393.6 41691.6 42018.8 42685.8 44173.3 44635.3 52669.5 55354.7 56224.4 16548.8 16610.8 16823.2 17014.7 17058.4 15667.7 18244.6 18385.6 18904.9 18990.0 13962.4 17051.7 17571.5 17753.4 17855.3 16920.6 17119.7 17121.4 17176.1 17238.1 16485.7 16620.0 16643.8 17036.4 17043.9 11856.0 12022.9 12071.7 12280.8 12292.7 13389.5 13560.0 13602.1 13603.9 13670.1 11:59:06 INFO - PID 1642 | -------- Summary: end -------- 11:59:06 INFO - PID 1642 | 11:59:06 INFO - TEST-INFO | 1642: exit 0 11:59:06 INFO - TEST-OK | dromaeo_css | took 1143232ms 11:59:06 INFO - TEST-START | kraken 11:59:06 INFO - operating with platform_type : mac_ 11:59:06 INFO - Initialising browser for kraken test... 11:59:06 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmplYFNHf/profile 11:59:06 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 11:59:40 INFO - TEST-INFO | 1652: killed by SIGTERM 11:59:40 INFO - Browser initialized. 11:59:40 INFO - Running cycle 1/1 for kraken test... 11:59:40 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmplYFNHf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:42 INFO - PID 1656 | 2017-06-01 11:59:42.588 plugin-container[1657:10851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 11:59:42 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:42 INFO - PID 1656 | 2017-06-01 11:59:42.588 plugin-container[1657:10851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4627, name = 'com.apple.CFPasteboardClient' 11:59:42 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:42 INFO - PID 1656 | 2017-06-01 11:59:42.589 plugin-container[1657:10851] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:59:43 INFO - PID 1656 | 2017-06-01 11:59:43.321 plugin-container[1658:10898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 11:59:43 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:43 INFO - PID 1656 | 2017-06-01 11:59:43.321 plugin-container[1658:10898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 11:59:43 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:59:43 INFO - PID 1656 | 2017-06-01 11:59:43.321 plugin-container[1658:10898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:59:52 INFO - PID 1656 | RSS: Main: 260255744 11:59:52 INFO - PID 1656 | 11:59:55 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 11:59:56 INFO - PID 1656 | RSS: Main: 270929920 11:59:56 INFO - PID 1656 | 11:59:59 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 11:59:59 INFO - PID 1656 | RSS: Main: 278765568 11:59:59 INFO - PID 1656 | 12:00:03 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 12:00:03 INFO - PID 1656 | RSS: Main: 282021888 12:00:03 INFO - PID 1656 | 12:00:05 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 12:00:06 INFO - PID 1656 | RSS: Main: 282845184 12:00:06 INFO - PID 1656 | 12:00:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:00:09 INFO - PID 1656 | RSS: Main: 283258880 12:00:09 INFO - PID 1656 | 12:00:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 12:00:12 INFO - PID 1656 | RSS: Main: 292581376 12:00:12 INFO - PID 1656 | 12:00:15 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 12:00:16 INFO - PID 1656 | RSS: Main: 290705408 12:00:16 INFO - PID 1656 | 12:00:19 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 12:00:19 INFO - PID 1656 | RSS: Main: 289587200 12:00:19 INFO - PID 1656 | 12:00:21 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:00:22 INFO - PID 1656 | RSS: Main: 289599488 12:00:22 INFO - PID 1656 | 12:00:23 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:00:24 INFO - PID 1656 | RSS: Main: 289738752 12:00:24 INFO - PID 1656 | 12:00:26 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:00:27 INFO - PID 1656 | RSS: Main: 290824192 12:00:27 INFO - PID 1656 | 12:00:30 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:00:30 INFO - PID 1656 | RSS: Main: 291241984 12:00:30 INFO - PID 1656 | 12:00:33 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:00:34 INFO - PID 1656 | RSS: Main: 291336192 12:00:34 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 12:00:36 INFO - PID 1656 | RSS: Main: 292782080 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | __start_tp_report 12:00:36 INFO - PID 1656 | _x_x_mozilla_page_load 12:00:36 INFO - PID 1656 | _x_x_mozilla_page_load_details 12:00:36 INFO - PID 1656 | |i|pagename|runs| 12:00:36 INFO - PID 1656 | |0;ai-astar;101;116;115;122;124;150;169;170;118;119 12:00:36 INFO - PID 1656 | |1;audio-beat-detection;141;109;106;139;146;146;95;146;167;96 12:00:36 INFO - PID 1656 | |2;audio-dft;200;194;202;215;212;230;196;202;208;194 12:00:36 INFO - PID 1656 | |3;audio-fft;73;58;77;77;62;80;91;50;77;89 12:00:36 INFO - PID 1656 | |4;audio-oscillator;79;74;109;101;102;73;86;70;70;89 12:00:36 INFO - PID 1656 | |5;imaging-gaussian-blur;126;137;89;89;91;89;113;126;90;96 12:00:36 INFO - PID 1656 | |6;imaging-darkroom;97;97;95;97;97;96;96;107;97;98 12:00:36 INFO - PID 1656 | |7;imaging-desaturate;90;94;91;91;92;90;91;90;90;91 12:00:36 INFO - PID 1656 | |8;json-parse-financial;51;50;50;51;50;53;51;50;50;54 12:00:36 INFO - PID 1656 | |9;json-stringify-tinderbox;54;55;64;56;60;58;55;57;56;61 12:00:36 INFO - PID 1656 | |10;stanford-crypto-aes;89;87;88;89;88;86;88;90;146;112 12:00:36 INFO - PID 1656 | |11;stanford-crypto-ccm;139;136;127;138;135;146;136;152;148;152 12:00:36 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;193;186;179;187;183;189;182;184;185;193 12:00:36 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;65;70;65;64;69;68;66;66;70;65 12:00:36 INFO - PID 1656 | __end_tp_report 12:00:36 INFO - PID 1656 | __start_cc_report 12:00:36 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1182 12:00:36 INFO - PID 1656 | __end_cc_report 12:00:36 INFO - PID 1656 | __startTimestamp1496343636405__endTimestamp 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | ------- Summary: start ------- 12:00:36 INFO - PID 1656 | Number of tests: 14 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:130.40 Median:123.00 stddev:23.91 (19.4%) stddev-sans-first:22.87 12:00:36 INFO - PID 1656 | Values: 101.0 116.0 115.0 122.0 124.0 150.0 169.0 170.0 118.0 119.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:146.00 stddev:25.23 (17.3%) stddev-sans-first:26.39 12:00:36 INFO - PID 1656 | Values: 141.0 109.0 106.0 139.0 146.0 146.0 95.0 146.0 167.0 96.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:205.30 Median:205.00 stddev:11.29 (5.5%) stddev-sans-first:11.82 12:00:36 INFO - PID 1656 | Values: 200.0 194.0 202.0 215.0 212.0 230.0 196.0 202.0 208.0 194.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:73.40 Median:77.00 stddev:13.13 (17.0%) stddev-sans-first:13.92 12:00:36 INFO - PID 1656 | Values: 73.0 58.0 77.0 77.0 62.0 80.0 91.0 50.0 77.0 89.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:73.50 stddev:14.48 (19.7%) stddev-sans-first:15.18 12:00:36 INFO - PID 1656 | Values: 79.0 74.0 109.0 101.0 102.0 73.0 86.0 70.0 70.0 89.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:89.00 stddev:18.97 (21.3%) stddev-sans-first:18.47 12:00:36 INFO - PID 1656 | Values: 126.0 137.0 89.0 89.0 91.0 89.0 113.0 126.0 90.0 96.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:97.00 stddev:3.37 (3.5%) stddev-sans-first:3.56 12:00:36 INFO - PID 1656 | Values: 97.0 97.0 95.0 97.0 97.0 96.0 96.0 107.0 97.0 98.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:91.00 stddev:1.25 (1.4%) stddev-sans-first:1.27 12:00:36 INFO - PID 1656 | Values: 90.0 94.0 91.0 91.0 92.0 90.0 91.0 90.0 90.0 91.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.41 (2.8%) stddev-sans-first:1.50 12:00:36 INFO - PID 1656 | Values: 51.0 50.0 50.0 51.0 50.0 53.0 51.0 50.0 50.0 54.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:57.50 stddev:3.17 (5.5%) stddev-sans-first:3.08 12:00:36 INFO - PID 1656 | Values: 54.0 55.0 64.0 56.0 60.0 58.0 55.0 57.0 56.0 61.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:96.30 Median:88.00 stddev:19.04 (21.6%) stddev-sans-first:20.01 12:00:36 INFO - PID 1656 | Values: 89.0 87.0 88.0 89.0 88.0 86.0 88.0 90.0 146.0 112.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:142.50 stddev:8.24 (5.8%) stddev-sans-first:8.71 12:00:36 INFO - PID 1656 | Values: 139.0 136.0 127.0 138.0 135.0 146.0 136.0 152.0 148.0 152.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.10 Median:186.50 stddev:4.56 (2.4%) stddev-sans-first:4.09 12:00:36 INFO - PID 1656 | Values: 193.0 186.0 179.0 187.0 183.0 189.0 182.0 184.0 185.0 193.0 12:00:36 INFO - PID 1656 | 12:00:36 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:2.25 (3.4%) stddev-sans-first:2.29 12:00:36 INFO - PID 1656 | Values: 65.0 70.0 65.0 64.0 69.0 68.0 66.0 66.0 70.0 65.0 12:00:36 INFO - PID 1656 | -------- Summary: end -------- 12:00:36 INFO - PID 1656 | 12:00:37 INFO - TEST-INFO | 1656: exit 0 12:00:37 INFO - TEST-OK | kraken | took 90450ms 12:00:37 INFO - SUITE-END | took 1233s 12:00:37 INFO - Completed test suite (00:20:34) 12:00:37 INFO - javascript benchmark 12:00:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8018.612708511792, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2823.6329277193963, 3598.6041535431114, 3631.7639353442023, 3653.926921461571, 3657.3233951718917, 5135.972805438913, 5724.771009159634, 6144.047672610742, 6238.282597501288, 6238.84402889928, 6153.446189842914, 6198.318184999646, 6198.318184999646, 6271.498280137578, 6284.842878928029, 5052.393712754475, 5288.15389537674, 5389.622726409153, 5447.972760136194, 5453.754581043842, 5546.278983732109, 5565.30433695788, 5570.387257401682, 5580.107182850745, 5635.154726790973, 5350.0904846176245, 5438.320209973754, 5454.53636440903, 5480.04099282628, 5483.479069488392, 5497.5601951843755, 5499.092649712793, 5524.309461317335, 5526.281583394173, 5562.137868630369, 5408.567314614821, 5428.0500912340185, 5433.429489903582, 5438.728063596844, 5453.182022696587, 6143.235544005215, 6184.628922264678, 6280.748770049193, 6292.937070629281, 6295.653739044351, 6541.803745887631, 6618.7021584028835, 6725.663716814189, 6736.360045795642, 6758.0, 6859.348361905611, 6860.5540639858245, 6866.485013623998, 6866.828329291758, 6882.070920425778, 6218.844528886769, 6309.400606942333, 6333.556651034383, 6338.461230795342, 6360.840978975516, 6821.590704557742, 6876.0, 6916.273791251846, 6960.617166055814, 6971.163460384786, 6430.3891130342545, 6493.51298652603, 6509.446697030806, 6595.868082638321, 6627.204735431683, 6757.358050985148, 6826.24911259761, 6830.248672646005, 6839.0425340452375, 6844.247132815386, 6688.933110668831, 6698.0, 6720.764773232913, 6724.798256052326, 6731.225909020525, 6410.455111315498, 6451.258105317855, 6651.534392592472, 6698.7320507179165, 6717.899231511531, 5392.865178370494, 5397.298351214396, 5407.459254074561, 5417.756200970965, 5421.674699518042, 4768.690035147704, 4775.379200703956, 4803.063402636522, 4810.037992401533, 4828.517148285144, 5646.181303710939, 5909.877123346636, 6286.654234017082, 6299.275583307978, 6346.650934198663, 5834.824955251349, 6141.670122563672, 6496.870062598816, 6851.588904665637, 6904.378605925491, 5452.345718513724, 6318.462930650946, 6326.082718005955, 6390.0, 6397.776077837253, 5116.104681680691, 5265.236540701653, 5910.320313163955, 6002.54980876436, 6084.117802918552, 5782.0, 7015.9298407016295, 7025.789226323219, 7035.929640703732, 7080.079589653312, 6030.638161710312, 6674.83312917181, 6846.726130954905, 6852.0064590635075, 6863.485238607224, 5608.943910560843, 6900.102986611709, 6935.410490108397, 6966.721331146698, 6981.02265682815, 5440.972795135999, 6648.90026649591, 6695.564788288746, 6728.495362847904, 6739.123913891162, 5897.616654917417, 6659.901116315948, 6865.759698410531, 6881.071055407456, 6903.723851046103, 6456.096146539582, 6521.804345869631, 6557.344265573404, 6654.367835055662, 6662.83342916431, 6604.14146160996, 6658.267590564937, 6672.132622759011, 6680.432163265982, 6686.0, 5523.420040895809, 6452.580582262138, 6528.086067950396, 6552.148220731275, 6563.409293163638, 6434.453071488976, 6526.216853977363, 6639.4356479697835, 6673.9332606673315, 6674.232463266787, 6398.456131228898, 6467.773627923, 6476.0, 6498.285188629153, 6512.6743662815725], "value": 6100.793268887137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.8901153838464, 822.481836443041, 847.449158047269, 1256.6418570707356, 1319.3601103464812, 39167.0, 40176.59823401761, 40234.39648405276, 40259.59740402595, 40301.79094627158, 39677.206455870866, 39712.80143599285, 40017.59982400175, 40149.99625009376, 40463.59536404635, 7813.531188128731, 7954.960225198866, 8181.222783835526, 8378.748637540884, 8410.36922230832, 7356.852862942738, 8319.584020798935, 8347.165283471664, 8481.067082620906, 8490.320774338039, 8391.370647201455, 8421.115782842806, 8426.115257897924, 8479.2792612628, 8511.787205319886, 8394.0, 8435.83128337433, 8465.53439560824, 8471.534065626389, 8483.448575842582, 8220.424570280084, 8272.793180170484, 8304.418690691655, 8322.8335433291, 8330.333573314121, 37491.25017499662, 37788.05529861748, 37795.0, 37798.622013779786, 37843.4323485148, 3515.2090779574646, 3565.358235517606, 3570.785752854837, 3571.535700358963, 3574.463830425431, 30743.0, 30808.537871931938, 30859.691403085908, 30955.845220773976, 30958.84520577405, 2846.0, 3074.8462576871066, 3115.9688403115906, 3125.687431256879, 3132.451820931328, 236689.0, 253679.0, 255608.7219563909, 302009.0, 303859.4807025951, 48463.0, 48635.51364486381, 48892.0, 49109.26336104961, 49155.50844491581, 50402.73993150127, 50670.493295066575, 50943.0, 53725.0, 55151.172732409046, 14194.645133871736, 14325.856741432452, 14369.425222990963, 14391.136531808124, 14419.990600658062, 11377.943110284396, 11442.485088171052, 11455.59905403307, 11460.025897798614, 11462.828057579143, 13872.583822485341, 13919.23444210578, 13933.442662293597, 14004.43982240699, 14110.294485275695, 6167.0, 6169.598976066542, 6194.38056194386, 6207.162033125561, 6218.595791273553, 1000.6497725795913, 1011.5296387179997, 1039.2205845615788, 1068.8450174724621, 1090.8145615245428, 24102.15642452506, 25025.499490010097, 25355.87322063415, 25441.491170176494, 25442.49115017689, 1137.5677242647741, 1146.340854008948, 1177.6408195500455, 1183.2841131115724, 1185.6265276437894, 10768.923107689168, 12913.0, 13037.869621303666, 13096.279704616147, 13132.277724725041, 321330.0, 322585.0, 323285.0, 323431.0, 323855.0, 47943.760281198374, 56099.0, 56115.0, 56646.716766415906, 56851.0, 1516.1661086402655, 1518.8177418709824, 1556.2218890554723, 1560.2666746629066, 1563.1715190948814, 23120.61276323426, 24250.757492425557, 25143.49713005766, 25160.874195629636, 25201.24396268115, 40431.79784101061, 50660.74669626628, 52945.470545294054, 53138.734306328224, 53484.0, 282760.0, 284925.5753721301, 285952.0, 286200.0, 286399.56800215866, 34631.653683463854, 36403.635963641085, 36765.81617091897, 36926.26147476982, 37248.0, 44659.553404465536, 45176.32235516404, 46433.30350044819, 46801.53198468108, 47039.76480117578, 27438.17685469439, 30210.9426170083, 30807.383852323277, 30924.453777311493, 31581.368372632878, 26025.349366265997, 31074.533881991338, 31285.374292514476, 31745.04764857058, 32234.194145146557, 36972.44541331828, 38274.80862595669, 38339.0, 38341.80829095837, 38371.80814096023, 30487.390252195277, 30730.53904191394, 32171.0, 32330.83834580812, 32368.67631323751, 24060.639090414006, 24677.62983555284, 24771.504569908142, 25154.0, 25181.0], "value": 17927.36837387635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.3473551272644, 416.5896591857022, 433.22885264229683, 443.842435935243, 467.60487388157014, 14300.356483958221, 15009.924950375233, 21899.0, 22318.10727570897, 22590.54818903621, 18457.0, 23460.061597536078, 23500.764992350072, 23533.882330588367, 23587.174448894293, 5984.88030239395, 6077.4226448487325, 6150.538709596798, 6151.3233544310315, 6180.72186751595, 6088.147659327676, 6124.6325220486915, 6153.200083989098, 6167.876642467148, 6181.320054793991, 4764.499727528594, 5698.458646428583, 5903.734331955073, 6099.9935010723175, 6110.419510146551, 5876.0598304271325, 5928.347881733006, 5939.406059394069, 5949.6430214187285, 5951.018082016462, 5273.789048438058, 5283.154695248781, 5304.973475132639, 5307.0, 5324.41431442541, 27778.583321250197, 27946.580801287997, 28281.0, 28287.717122828715, 28292.575611365846, 2062.26789489732, 2150.6451435513122, 2154.601398741238, 2155.504234026183, 2177.7713340099376, 18290.811097278634, 18505.444836654788, 18578.442646720483, 18584.349547765763, 18620.348287809862, 2117.6400011998, 2148.2803260907613, 2162.351294611626, 2168.826493880486, 2191.1345018717675, 204614.0, 205374.94625053857, 209532.9046709544, 209919.9999999985, 210077.0, 12474.688132796753, 12733.68165795844, 12753.61739147827, 12770.9361453194, 12915.935420322838, 15933.760993585105, 16212.35150593963, 16275.430359937582, 16291.0, 16305.184740763152, 8702.738917832476, 8820.382573219813, 8949.686760963335, 8970.282377409922, 8973.865392019125, 8578.399512034222, 8602.31181505478, 8607.61265743043, 8615.526146062026, 8669.436486628349, 6676.899846502306, 7612.1246056703085, 8045.356371490267, 8199.22107399796, 8260.380471464665, 1929.6526625207598, 2266.5806825736963, 2268.9319320420414, 2282.0529480265504, 2469.1111199967995, 932.8320902237663, 963.9710808675751, 973.8101070291225, 978.1753344423271, 1007.1539906478594, 18550.628987420445, 19966.70049949252, 20294.39116826468, 21052.578948420945, 21291.935403229778, 930.469632309577, 1002.7693630465034, 1027.8920713325142, 1028.8785224105761, 1038.7922415516775, 10272.33229840058, 10800.027997480265, 10830.458477076114, 10919.454027298762, 11059.72350691223, 518024.0, 527102.0, 530947.0, 531264.0, 534321.0, 141968.58031419554, 144986.0, 145691.0731695127, 147224.0, 147305.0, 1043.1811028342715, 1142.9257098288585, 1180.1267062373781, 1184.2420850655587, 1188.8276199950958, 15811.683766324379, 16166.757498637766, 17657.558561036076, 18268.0, 18901.43295701131, 42387.0, 55877.16184257321, 66149.41100452232, 67059.66470167619, 68934.65532672305, 91699.62450563381, 91755.08244917466, 91981.54009229911, 92526.53736731273, 92703.53648231715, 9832.901670983485, 10080.899191007997, 10107.494625268855, 10115.443650599287, 10124.746881328027, 12922.935385323013, 13140.868591313965, 17014.74477882808, 18042.0, 18107.0, 14309.284535773379, 14751.778723319374, 14767.704645906806, 14831.961762676508, 14836.258187090387, 12695.174813637084, 12860.421281042563, 13255.204687718768, 13272.734545308847, 13349.199048057148, 14733.778993315324, 14775.335109919864, 14948.626284342978, 14985.775213372026, 15013.474528391454, 9871.901280987098, 9951.502424878872, 9998.800023999625, 10007.299489035697, 10024.14794742455, 13616.251106189255, 14582.343794529059, 14812.0, 14884.10695358282, 14944.551663450115], "value": 10879.080191608668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.5659410691528, 1359.5989183190957, 1379.7585422551053, 1467.2077078377677, 1473.1382141447257, 11750.941245293761, 11837.348945807988, 12367.257964522123, 12391.566295179671, 12496.875031249685, 12227.816582751266, 12366.01071914247, 12402.937985310084, 12444.377781110954, 12450.875491245084, 2532.5061612985473, 2579.690437147545, 2592.792576593868, 2594.403287243935, 2600.4539046800196, 2200.9779902200935, 2519.911803086892, 2548.566743653583, 2563.987180064097, 2572.665553478055, 2515.3585835611893, 2534.3664083979006, 2540.27602133392, 2548.9490210195786, 2555.080171138398, 2491.3397949543296, 2511.2717311979504, 2511.7488251174736, 2515.5472015037285, 2518.0431436054273, 2373.074500944633, 2380.392999785061, 2447.1801946347996, 2554.463562651845, 2560.3343130785943, 11893.40532973348, 11906.166568340219, 11985.340806255652, 11985.981191598725, 12006.159568830186, 6439.581427207217, 6649.567778094409, 6665.0, 6709.261981182066, 6724.0, 3904.141088960424, 4065.4714887064492, 4067.5525692173837, 4130.987907962538, 4139.254934111858, 1240.193873981919, 1259.0682894657978, 1287.3563218390805, 1287.5557932513268, 1289.6969212235117, 15499.5350139496, 16044.957077790023, 16595.087270200132, 16729.330826766916, 16739.91190572609, 4577.244754615518, 5505.642133261325, 5729.88540229193, 5775.393583673737, 5805.419458054161, 12271.018318534496, 12279.447424865903, 12282.32447215394, 12302.138850280573, 12318.630441086782, 6406.743730250831, 6418.26189988148, 6446.258680251737, 6499.902501462482, 6503.577267477599, 6034.577579569475, 6102.633841969496, 6162.907556386658, 6163.106349579286, 6196.256449226032, 4314.956850431518, 4400.845970391021, 4485.484169320504, 4487.080148569536, 4518.9548104518535, 1278.5525066226746, 1299.3957809618573, 1300.9674758130934, 1307.0589175793414, 1317.8220940173146, 789.0373744032271, 799.5602418669824, 802.7350974178507, 805.8952336196257, 812.7642983534827, 6232.283287421914, 6887.483438742114, 7091.680874360666, 7203.783886483414, 7266.781996540112, 849.1169184048521, 896.8071864549154, 918.1323649151487, 930.3115694386241, 941.0567178775806, 3632.146445585285, 3870.6129387061633, 4229.851955181553, 4405.0462306876325, 4435.778211089432, 13959.511417100353, 14935.850641493664, 15173.317200725995, 15246.847531524765, 15272.77090843616, 14672.633184170481, 15048.021878578076, 15200.011999220016, 15383.538493845203, 15520.146391948325, 8119.147489513516, 8299.585020748938, 8470.195331443503, 8483.854679618173, 8502.44734092282, 6157.753689852356, 6620.3710647488415, 6953.75661851853, 7021.43828493709, 7101.396381307647, 13055.0, 13090.345482725636, 13672.453101876212, 13837.30813459303, 13858.43618930501, 13975.0, 14078.225697586322, 14194.645133871736, 14375.78436323435, 14402.127723395892, 9620.470874101991, 9761.951190244003, 9806.80386392254, 9811.264155188532, 9840.11438970496, 10764.407957562096, 10901.563937442414, 11008.559657613605, 11023.503942322453, 11035.503402346752, 8246.0929297776, 8960.86558701607, 9319.906800931903, 9349.626014959325, 9351.625934962525, 8080.676772929252, 8978.820423591622, 9057.456552606865, 9130.634774609207, 9243.58403871814, 8167.264946154874, 8291.129431409856, 8294.668213271643, 8309.252167304972, 8342.624581893951, 5215.791368345223, 5933.941228000333, 5960.0, 6094.725737341919, 6164.7842325518395, 5996.820095397145, 6048.818535443944, 6173.290071641639, 6175.289841668266, 6392.169018027626], "value": 5432.924332181784, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.4540403460515, 375.36937944253646, 392.82715605133757, 402.1394216376952, 405.9776712280825, 5540.362858271295, 6197.659128747917, 6262.530310226734, 6309.810705678826, 6365.726854629075, 5906.700525884298, 5934.762609495626, 6106.664133472657, 6169.871394368946, 6180.969095154529, 4520.547945205486, 4553.408056952591, 4555.703879247855, 4563.475200351969, 4575.702579332333, 3585.6593623605845, 4552.339910712944, 4557.179707652621, 4565.132624801294, 4581.816727330919, 3732.2722069196516, 4482.417285752864, 4513.593776560109, 4523.253663145593, 4562.8631141065825, 4234.9153016939645, 4515.4129963104915, 4534.0, 4537.909241815179, 4565.748883811389, 3877.476540667002, 3908.4332771748223, 3910.667593254577, 3918.2163567286657, 3932.980335098306, 5984.414130255485, 6108.5418593605655, 6113.174721412642, 6128.0, 6128.632282063046, 4850.902981940377, 5443.755031023614, 5446.291982042309, 5446.692793729477, 5454.972725136388, 5375.838724838261, 6335.7328534292865, 6343.651099189543, 6657.40083392497, 6712.563683360566, 3368.938784282943, 3384.560007199073, 4344.109457561192, 4407.162639098561, 4444.622207112367, 6403.551751377406, 6945.40964018054, 7096.645167741593, 7132.572045677224, 7169.928300716978, 4143.627073563393, 4204.33928598202, 4508.098380323882, 4538.818447262139, 4542.909141817145, 3777.962220377816, 4239.766812825263, 4595.586397224266, 4622.14490319292, 4627.9768601156775, 5251.317328747238, 5417.566594672417, 5426.941746359422, 5438.972805136028, 5447.673139611636, 5563.276774019432, 5906.426611899825, 6758.5655778643095, 6761.599632130621, 6767.458603311723, 1570.2384343593349, 1574.7401678722883, 1575.1966497086569, 1581.897176683535, 1582.7784110224577, 875.5228400521731, 888.05865782271, 1008.3949630221955, 1044.8537204791335, 1062.4793851012948, 5544.805931792449, 6010.759569617254, 6262.12330273762, 6309.463695585927, 6313.621182729051, 5762.567807414461, 5781.699117698484, 6114.571979961449, 6706.530542861953, 6998.790036298919, 6264.874702505925, 6282.717277722422, 6295.401936815995, 6313.305536390993, 6321.494280457552, 3614.204874927564, 3642.562892452923, 3666.908327291839, 3678.779273243614, 3691.8523259069875, 6257.687115644199, 7067.82330441743, 7138.0, 7144.749933752398, 7200.795645937612, 6530.542861999615, 7191.964040179871, 7202.53183543068, 7222.96388518054, 7228.566286022856, 6785.660716964132, 7045.238690327418, 7069.48580107517, 7148.535345202546, 7256.7822965311125, 6122.663253521099, 6922.550034247758, 7029.753958611424, 7038.085048943604, 7066.964665176642, 6295.811125666238, 6799.796006119825, 6901.585904845725, 6927.376536111774, 6946.6179360135675, 6661.733530658719, 6933.479989000946, 7212.386947109554, 7264.128304603481, 7264.67308971087, 4007.479027726377, 4104.809605214455, 4509.143262780061, 4523.547645235451, 4547.953420960647, 6362.618242905441, 6909.30906909305, 6912.516123871482, 6944.37500624926, 7046.067752573117, 5467.863303417446, 6030.195913965484, 6260.7495700171485, 6289.591176573508, 6707.195136583641, 6201.410865967726, 6331.271903730945, 6662.333766623493, 6681.866362672817, 6702.530822842549, 6303.527235457451, 6544.770933017321, 6572.0, 6669.7665581704405, 6676.0, 5558.388577256579, 6697.430718388797, 6701.765438209639, 6723.226828914541, 6724.394804467622, 6059.606125601822, 6480.902786458113, 6542.280349161683, 6559.311272316338, 6561.770338038145], "value": 4825.669871315069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.7575348627981, 798.5727635714894, 813.446856137826, 838.7670124916375, 843.3084870406266, 14529.927350363234, 15073.246337683127, 16733.83266167338, 17231.741523877135, 17236.396726114574, 16526.504204873843, 17839.018853963025, 18331.633367332677, 18436.0, 18452.354167604102, 979.123684302547, 980.8332583460793, 987.8518222266681, 1008.5965613754483, 1011.5903059261011, 1029.629333439961, 1030.1192480429238, 1030.9793804123913, 1033.7105610429091, 1033.7904651557697, 930.0745757970794, 1048.8846226915034, 1049.6063976008998, 1061.506399524221, 1067.765091679829, 1036.0675392147052, 1036.2435422141841, 1036.419605021183, 1036.429963520061, 1040.8750949885987, 785.5051317669831, 788.0385929166408, 790.5730905311125, 791.7189397763826, 793.6150966781106, 16801.83198168015, 16971.830281697148, 17484.12579371039, 17595.296188152457, 17655.5586110347, 16993.235304411206, 17358.91320543389, 17667.73498397525, 17790.46628601144, 17837.910810445865, 13865.722685546232, 14154.787678184835, 14157.0, 14163.858361416356, 14188.787168192486, 3559.982200089009, 3570.535830342065, 3591.3715099857423, 3592.7485076044686, 3594.658507441815, 105133.0, 113115.0, 136005.3199734005, 136929.0, 137109.3144534271, 13424.127431716906, 13928.303584820718, 14037.5086871959, 14054.929725351512, 14075.2962351882, 22794.0, 24614.507709845704, 24992.00031998736, 25442.745572544038, 25666.0, 5954.345022047572, 6651.534392592472, 6673.865442874723, 7299.0, 7535.6985720570565, 3634.436662317345, 3753.11801726594, 3754.249150169977, 3757.8496860125833, 3761.3981762918347, 4026.8389264429093, 4110.301248787713, 4116.197341518435, 4121.031557583965, 4123.443335149776, 831.9168083191754, 926.5274709898, 974.0843607009389, 974.9902500974899, 976.4580657734883, 649.5647915896361, 661.3155384177353, 662.552776875607, 668.5921587831419, 673.8248055505505, 11191.888081119247, 11362.091032717362, 12430.440630171664, 12633.242005479517, 12878.227306361649, 641.351726064011, 641.8908785506381, 649.0977541217711, 669.4075742967509, 672.6132473827569, 5639.436056394327, 5653.378128405872, 5819.592628516048, 5867.2959244890035, 5915.645061296336, 121907.0, 122750.38624806819, 126377.0, 126989.73010269964, 132712.0, 104806.47596761967, 107664.92335076706, 108271.1728827111, 109447.0, 109915.0, 80154.19845801585, 91496.0, 96939.0, 96958.0, 97170.51414742881, 11048.447577621248, 12368.567100151451, 12807.487700491876, 12822.487100515875, 12885.677858053623, 40670.0, 41393.58606413897, 41691.58308416877, 42018.789906050275, 42685.78657106819, 44173.33739993967, 44635.33047004363, 52669.47330526799, 55354.67871927697, 56224.43775562192, 16548.75176872324, 16610.750838737185, 16823.158842057604, 17014.74477882808, 17058.402955896483, 15667.686646267239, 18244.635107298047, 18385.632287354445, 18904.905475472533, 18990.0, 13962.371693273624, 17051.658966820843, 17571.472855814347, 17753.378631747826, 17855.28578856831, 16920.57698557546, 17119.657606848043, 17121.400750973655, 17176.05531695769, 17238.05190714523, 16485.670286594443, 16620.0, 16643.83356166423, 17036.359364298554, 17043.914780426017, 11855.99224065964, 12022.939885300517, 12071.698207544881, 12280.754384912074, 12292.692682682648, 13389.493459643762, 13559.983001274746, 13602.13747874655, 13603.931980340036, 13670.11144275619], "value": 8520.750944391593, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 115.0, 122.0, 124.0, 150.0, 169.0, 170.0, 118.0, 119.0], "value": 130.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 106.0, 139.0, 146.0, 146.0, 95.0, 146.0, 167.0, 96.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 194.0, 202.0, 215.0, 212.0, 230.0, 196.0, 202.0, 208.0, 194.0], "value": 205.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 58.0, 77.0, 77.0, 62.0, 80.0, 91.0, 50.0, 77.0, 89.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 109.0, 101.0, 102.0, 73.0, 86.0, 70.0, 70.0, 89.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 137.0, 89.0, 89.0, 91.0, 89.0, 113.0, 126.0, 90.0, 96.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 95.0, 97.0, 97.0, 96.0, 96.0, 107.0, 97.0, 98.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 91.0, 91.0, 92.0, 90.0, 91.0, 90.0, 90.0, 91.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 51.0, 50.0, 53.0, 51.0, 50.0, 50.0, 54.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 64.0, 56.0, 60.0, 58.0, 55.0, 57.0, 56.0, 61.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 89.0, 88.0, 86.0, 88.0, 90.0, 146.0, 112.0], "value": 96.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 127.0, 138.0, 135.0, 146.0, 136.0, 152.0, 148.0, 152.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 186.0, 179.0, 187.0, 183.0, 189.0, 182.0, 184.0, 185.0, 193.0], "value": 186.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 65.0, 64.0, 69.0, 68.0, 66.0, 66.0, 70.0, 65.0], "value": 66.8, "unit": "score"}]}]} 12:00:37 INFO - Return code: 0 12:00:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:00:37 INFO - # TBPL SUCCESS # 12:00:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:00:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:00:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:00:37 INFO - 2017-06-01 12:00:37.825 screenresolution[1665:11300] starting screenresolution argv=screenresolution get 12:00:37 INFO - 2017-06-01 12:00:37.828 screenresolution[1665:11300] Display 0: 1600x1200x32@60 12:00:37 INFO - 2017-06-01 12:00:37.834 screenresolution[1666:11302] starting screenresolution argv=screenresolution list 12:00:37 INFO - Available Modes on Display 0 12:00:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:00:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:00:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:00:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:00:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:00:38 INFO - Intel Iris: 12:00:38 INFO - Chipset Model: Intel Iris 12:00:38 INFO - Type: GPU 12:00:38 INFO - Bus: Built-In 12:00:38 INFO - VRAM (Dynamic, Max): 1536 MB 12:00:38 INFO - Vendor: Intel (0x8086) 12:00:38 INFO - Device ID: 0x0a2e 12:00:38 INFO - Revision ID: 0x0009 12:00:38 INFO - Displays: 12:00:38 INFO - Display: 12:00:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:00:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:00:38 INFO - Display Serial Number: 1600x1200 60 12:00:38 INFO - Main Display: Yes 12:00:38 INFO - Mirror: Off 12:00:38 INFO - Online: Yes 12:00:38 INFO - Rotation: Supported 12:00:38 INFO - Return code: 0 12:00:38 INFO - Running post-action listener: _resource_record_post_action 12:00:38 INFO - [mozharness: 2017-06-01 19:00:38.069670Z] Finished run-tests step (success) 12:00:38 INFO - Running post-run listener: _resource_record_post_run 12:00:38 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40592896; Write bytes: 571478016; Read time: 282; Write time: 1788 12:00:38 INFO - TinderboxPrint: CPU usage
53.4% 12:00:38 INFO - TinderboxPrint: I/O read bytes / time
40,592,896 / 282 12:00:38 INFO - TinderboxPrint: I/O write bytes / time
571,478,016 / 1,788 12:00:38 INFO - TinderboxPrint: CPU idle
2,341.6 (46.6%) 12:00:38 INFO - TinderboxPrint: CPU system
1,016.6 (20.2%) 12:00:38 INFO - TinderboxPrint: CPU user
1,667.4 (33.2%) 12:00:38 INFO - TinderboxPrint: Swap in / out
286,732,288 / 0 12:00:38 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233099776; Write bytes: 230340608; Read time: 16765; Write time: 506 12:00:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:00:38 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 38757888; Write bytes: 321804288; Read time: 266; Write time: 1231 12:00:38 INFO - Running post-run listener: _upload_blobber_files 12:00:38 INFO - Blob upload gear active. 12:00:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:00:38 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 12:00:38 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'] 12:00:38 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 12:00:38 INFO - (blobuploader) - INFO - Open directory for files ... 12:00:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:00:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:00:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:40 INFO - (blobuploader) - INFO - Done attempting. 12:00:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:00:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:00:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:41 INFO - (blobuploader) - INFO - Done attempting. 12:00:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:00:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:00:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:42 INFO - (blobuploader) - INFO - Done attempting. 12:00:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:00:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:00:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:00:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:00:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:00:44 INFO - (blobuploader) - INFO - Done attempting. 12:00:44 INFO - (blobuploader) - INFO - Iteration through files over. 12:00:44 INFO - Return code: 0 12:00:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:00:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:00:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c2002681fa7956f0c97a696e12fefc28e212374ef972c58bc8749f09b87126a50f0daaffefa37d509c86cf03ebac004cf7866abd73f210834865ef7e20b94a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/136cd2f38904536eed2f25b070b0e6dcc6bec78712b47dbf6d0acf8faa44e5cfa54acc2fea920f6b8cf3715ccd8c0a0821f8772d314c857abaf0d04224570262", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2067d833d55d74c1488f77b1bb503da2a586ae6f542fb112a685115cff184f383f131233ca098582799974c8f29727566565334c45881cc186a382c0beb7dcce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c700edd6b042b656f727eb5ae8a9c08f76dd31d53ab9a7001ba878a2701edeaf281ff6bd44119fdba7525c7197c8e7e04894168bab6e8a62fe5b6859e3810d"} 12:00:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:00:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:00:44 INFO - Contents: 12:00:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c2002681fa7956f0c97a696e12fefc28e212374ef972c58bc8749f09b87126a50f0daaffefa37d509c86cf03ebac004cf7866abd73f210834865ef7e20b94a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/136cd2f38904536eed2f25b070b0e6dcc6bec78712b47dbf6d0acf8faa44e5cfa54acc2fea920f6b8cf3715ccd8c0a0821f8772d314c857abaf0d04224570262", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2067d833d55d74c1488f77b1bb503da2a586ae6f542fb112a685115cff184f383f131233ca098582799974c8f29727566565334c45881cc186a382c0beb7dcce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c700edd6b042b656f727eb5ae8a9c08f76dd31d53ab9a7001ba878a2701edeaf281ff6bd44119fdba7525c7197c8e7e04894168bab6e8a62fe5b6859e3810d"} 12:00:44 INFO - Running post-run listener: copy_logs_to_upload_dir 12:00:44 INFO - Copying logs to upload dir... 12:00:44 INFO - mkdir: /builds/slave/test/build/upload/logs 12:00:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.031196 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-01 12:00:44.317556) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 12:00:44.320993) ========= 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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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/3c2002681fa7956f0c97a696e12fefc28e212374ef972c58bc8749f09b87126a50f0daaffefa37d509c86cf03ebac004cf7866abd73f210834865ef7e20b94a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/136cd2f38904536eed2f25b070b0e6dcc6bec78712b47dbf6d0acf8faa44e5cfa54acc2fea920f6b8cf3715ccd8c0a0821f8772d314c857abaf0d04224570262", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2067d833d55d74c1488f77b1bb503da2a586ae6f542fb112a685115cff184f383f131233ca098582799974c8f29727566565334c45881cc186a382c0beb7dcce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c700edd6b042b656f727eb5ae8a9c08f76dd31d53ab9a7001ba878a2701edeaf281ff6bd44119fdba7525c7197c8e7e04894168bab6e8a62fe5b6859e3810d"} build_url:https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011072 build_url: 'https://queue.taskcluster.net/v1/task/CInbAlgrSA2QGwJeKab35A/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/3c2002681fa7956f0c97a696e12fefc28e212374ef972c58bc8749f09b87126a50f0daaffefa37d509c86cf03ebac004cf7866abd73f210834865ef7e20b94a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/136cd2f38904536eed2f25b070b0e6dcc6bec78712b47dbf6d0acf8faa44e5cfa54acc2fea920f6b8cf3715ccd8c0a0821f8772d314c857abaf0d04224570262", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2067d833d55d74c1488f77b1bb503da2a586ae6f542fb112a685115cff184f383f131233ca098582799974c8f29727566565334c45881cc186a382c0beb7dcce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c700edd6b042b656f727eb5ae8a9c08f76dd31d53ab9a7001ba878a2701edeaf281ff6bd44119fdba7525c7197c8e7e04894168bab6e8a62fe5b6859e3810d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 12:00:44.353518) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:00:44.353840) ========= 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.mBeEWlo8cu/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.Z9pzKREEj3/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:00:44.402449) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 12:00:44.402757) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 12:00:44.403057) ========= ========= Total master_lag: 0.17 =========