builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0330 starttime: 1496870487.64 results: success (0) revision: 2064f302f3e71abc41e7f75a0341a85028510297 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.636290) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.636748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.637046) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.662239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.662637) ========= 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.p6StzO4zYk/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.ysbYftJ0L6/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-07 14:21:27-- 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% 21.8M=0.001s 2017-06-07 14:21:27 (21.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126123 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.812134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.812461) ========= 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.p6StzO4zYk/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.ysbYftJ0L6/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.027223 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:27.860943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-07 14:21:27.861293) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 2064f302f3e71abc41e7f75a0341a85028510297 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 2064f302f3e71abc41e7f75a0341a85028510297 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p6StzO4zYk/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.ysbYftJ0L6/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-07 14:21:27,957 truncating revision to first 12 chars 2017-06-07 14:21:27,958 Setting DEBUG logging. 2017-06-07 14:21:27,958 attempt 1/10 2017-06-07 14:21:27,958 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/2064f302f3e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-07 14:21:29,333 unpacking tar archive at: cedar-2064f302f3e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.640061 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-07 14:21:29.525233) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:29.525644) ========= script_repo_revision: 2064f302f3e71abc41e7f75a0341a85028510297 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:29.526087) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:29.526373) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 14:21:29.545571) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-07 14:21:29.545982) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p6StzO4zYk/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.ysbYftJ0L6/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:21:29 INFO - MultiFileLogger online at 20170607 14:21:29 in /builds/slave/test 14:21:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 14:21:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:21:29 INFO - {'append_to_log': False, 14:21:29 INFO - 'base_work_dir': '/builds/slave/test', 14:21:29 INFO - 'blob_upload_branch': 'Cedar', 14:21:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:21:29 INFO - 'branch': 'Cedar', 14:21:29 INFO - 'buildbot_json_path': 'buildprops.json', 14:21:29 INFO - 'config_files': ('talos/mac_config.py',), 14:21:29 INFO - 'default_actions': ('clobber', 14:21:29 INFO - 'read-buildbot-config', 14:21:29 INFO - 'download-and-extract', 14:21:29 INFO - 'populate-webroot', 14:21:29 INFO - 'create-virtualenv', 14:21:29 INFO - 'install', 14:21:29 INFO - 'setup-mitmproxy', 14:21:29 INFO - 'run-tests'), 14:21:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:21:29 INFO - 'download_minidump_stackwalk': True, 14:21:29 INFO - 'download_symbols': 'ondemand', 14:21:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:21:29 INFO - 'tooltool.py': '/tools/tooltool.py', 14:21:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:21:29 INFO - '/tools/misc-python/virtualenv.py')}, 14:21:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:21:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:21:29 INFO - 'gecko_profile': False, 14:21:29 INFO - 'gecko_profile_interval': 0, 14:21:29 INFO - 'installer_path': 'installer.exe', 14:21:29 INFO - 'log_level': 'info', 14:21:29 INFO - 'log_name': 'talos', 14:21:29 INFO - 'log_to_console': True, 14:21:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:21:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:21:29 INFO - 'opt_config_files': (), 14:21:29 INFO - 'pip_index': False, 14:21:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:21:29 INFO - 'cmd': ('bash', 14:21:29 INFO - '-c', 14:21:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:21:29 INFO - 'enabled': True, 14:21:29 INFO - 'halt_on_failure': False, 14:21:29 INFO - 'name': 'check_screen_resolution'},), 14:21:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:21:29 INFO - 'cmd': ('bash', 14:21:29 INFO - '-c', 14:21:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:21:29 INFO - 'enabled': True, 14:21:29 INFO - 'halt_on_failure': False, 14:21:29 INFO - 'name': 'check_screen_resolution'},), 14:21:29 INFO - 'run_cmd_checks_enabled': True, 14:21:29 INFO - 'suite': 'dromaeojs-e10s', 14:21:29 INFO - 'system_bits': '32', 14:21:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:21:29 INFO - 'title': 't-yosemite-r7-0330', 14:21:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:21:29 INFO - 'use_talos_json': True, 14:21:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:21:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:21:29 INFO - 'work_dir': 'build'} 14:21:29 INFO - [mozharness: 2017-06-07 21:21:29.752854Z] Running clobber step. 14:21:29 INFO - Running pre-action listener: _resource_record_pre_action 14:21:29 INFO - Running main action method: clobber 14:21:29 INFO - rmtree: /builds/slave/test/build 14:21:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:21:32 INFO - Running post-action listener: _resource_record_post_action 14:21:32 INFO - [mozharness: 2017-06-07 21:21:32.162227Z] Finished clobber step (success) 14:21:32 INFO - [mozharness: 2017-06-07 21:21:32.162429Z] Running read-buildbot-config step. 14:21:32 INFO - Running pre-action listener: _resource_record_pre_action 14:21:32 INFO - Running main action method: read_buildbot_config 14:21:32 INFO - Using buildbot properties: 14:21:32 INFO - { 14:21:32 INFO - "project": "", 14:21:32 INFO - "product": "firefox", 14:21:32 INFO - "who": "mconley@mozilla.com", 14:21:32 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:21:32 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 14:21:32 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 14:21:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 14:21:32 INFO - "stage_platform": "macosx64", 14:21:32 INFO - "basedir": "/builds/slave/test", 14:21:32 INFO - "buildnumber": 0, 14:21:32 INFO - "platform": "yosemite_r7", 14:21:32 INFO - "parent_task_id": "X9nf__I8Sfe2uimwu7se9Q", 14:21:32 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:21:32 INFO - "slavebuilddir": "test", 14:21:32 INFO - "taskId": "EfXdb8fMRiWyPhg1EoJ4zw", 14:21:32 INFO - "branch": "cedar", 14:21:32 INFO - "script_repo_revision": "default", 14:21:32 INFO - "revision": "2064f302f3e71abc41e7f75a0341a85028510297", 14:21:32 INFO - "slavename": "t-yosemite-r7-0330", 14:21:32 INFO - "repo_path": "projects/cedar" 14:21:32 INFO - } 14:21:32 INFO - Finding installer, test and symbols from parent task. 14:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EfXdb8fMRiWyPhg1EoJ4zw'}, attempt #1 14:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EfXdb8fMRiWyPhg1EoJ4zw'}, attempt #1 14:21:33 INFO - Task dependencies: SRV0ua_2Rzyvat60krwo2A X9nf__I8Sfe2uimwu7se9Q 14:21:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q'}, attempt #1 14:21:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:21:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/test_packages.json 14:21:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/target.crashreporter-symbols.zip 14:21:33 INFO - Running post-action listener: _resource_record_post_action 14:21:33 INFO - [mozharness: 2017-06-07 21:21:33.596973Z] Finished read-buildbot-config step (success) 14:21:33 INFO - [mozharness: 2017-06-07 21:21:33.597094Z] Running download-and-extract step. 14:21:33 INFO - Running pre-action listener: _resource_record_pre_action 14:21:33 INFO - Running main action method: download_and_extract 14:21:33 INFO - mkdir: /builds/slave/test/build/tests 14:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:21:33 INFO - trying https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/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 14:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/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 14:21:34 INFO - Downloaded 1328 bytes. 14:21:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:21:34 INFO - Using the following test package requirements: 14:21:34 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:21:34 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:21:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:21:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:21:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'jsshell-mac.zip'], 14:21:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:21:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:21:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:21:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:21:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 14:21:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:21:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:21:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:21:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:21:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:21:34 INFO - Fetch https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:21:36 INFO - Content-Length response header: 57640038 14:21:36 INFO - Bytes received: 57640038 14:21:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:21:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:21:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:21:54 INFO - Fetch https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:21:55 INFO - Content-Length response header: 57640038 14:21:55 INFO - Bytes received: 57640038 14:22:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:22:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:22:13 INFO - Fetch https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:22:14 INFO - Content-Length response header: 14447563 14:22:14 INFO - Bytes received: 14447563 14:22:14 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')]} 14:22:14 INFO - trying https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:22:14 INFO - Downloading https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:22:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/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 14:22:16 INFO - Downloaded 68032500 bytes. 14:22:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:22:16 INFO - mkdir: /builds/slave/test/properties 14:22:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:22:16 INFO - Writing to file /builds/slave/test/properties/build_url 14:22:16 INFO - Contents: 14:22:16 INFO - build_url:https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:22:16 INFO - Running post-action listener: _resource_record_post_action 14:22:16 INFO - Running post-action listener: set_extra_try_arguments 14:22:16 INFO - [mozharness: 2017-06-07 21:22:16.813691Z] Finished download-and-extract step (success) 14:22:16 INFO - [mozharness: 2017-06-07 21:22:16.813818Z] Running populate-webroot step. 14:22:16 INFO - Running pre-action listener: _resource_record_pre_action 14:22:16 INFO - Running main action method: populate_webroot 14:22:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:22:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:22:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:22:16 INFO - '--tppagecycles', 14:22:16 INFO - '7'], 14:22:16 INFO - 'tests': ['tsvgm']}}, 14:22:16 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:22:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:22:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:22:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['damp', 'tps']}, 14:22:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'tests': ['damp', 'tps']}, 14:22:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['dromaeo_dom']}, 14:22:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:22:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:22:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:22:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:22:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['a11yr', 14:22:16 INFO - 'ts_paint', 14:22:16 INFO - 'tpaint', 14:22:16 INFO - 'sessionrestore', 14:22:16 INFO - 'sessionrestore_no_auto_restore', 14:22:16 INFO - 'tabpaint']}, 14:22:16 INFO - 'other-e10s': {'tests': ['a11yr', 14:22:16 INFO - 'ts_paint', 14:22:16 INFO - 'tpaint', 14:22:16 INFO - 'sessionrestore', 14:22:16 INFO - 'sessionrestore_no_auto_restore', 14:22:16 INFO - 'tabpaint']}, 14:22:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:22:16 INFO - 'ts_paint', 14:22:16 INFO - 'tpaint', 14:22:16 INFO - 'sessionrestore', 14:22:16 INFO - 'sessionrestore_no_auto_restore', 14:22:16 INFO - 'tabpaint']}, 14:22:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:22:16 INFO - 'ts_paint', 14:22:16 INFO - 'tpaint', 14:22:16 INFO - 'sessionrestore', 14:22:16 INFO - 'sessionrestore_no_auto_restore', 14:22:16 INFO - 'tabpaint']}, 14:22:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['a11yr', 14:22:16 INFO - 'ts_paint', 14:22:16 INFO - 'tpaint', 14:22:16 INFO - 'sessionrestore', 14:22:16 INFO - 'sessionrestore_no_auto_restore', 14:22:16 INFO - 'tabpaint']}, 14:22:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['a11yr', 14:22:16 INFO - 'ts_paint', 14:22:16 INFO - 'tpaint', 14:22:16 INFO - 'sessionrestore', 14:22:16 INFO - 'sessionrestore_no_auto_restore', 14:22:16 INFO - 'tabpaint']}, 14:22:16 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:22:16 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:22:16 INFO - 'bloom_basic_ref']}, 14:22:16 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 14:22:16 INFO - 'talos_options': ['--mitmproxy', 14:22:16 INFO - 'mitmproxy-recording-1.mp', 14:22:16 INFO - '--firstNonBlankPaint'], 14:22:16 INFO - 'tests': ['Quantum_1']}, 14:22:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['tsvgx', 14:22:16 INFO - 'tsvgr_opacity', 14:22:16 INFO - 'tart', 14:22:16 INFO - 'tscrollx', 14:22:16 INFO - 'cart', 14:22:16 INFO - 'tsvg_static']}, 14:22:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:22:16 INFO - 'tsvgr_opacity', 14:22:16 INFO - 'tart', 14:22:16 INFO - 'tscrollx', 14:22:16 INFO - 'cart', 14:22:16 INFO - 'tsvg_static']}, 14:22:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'talos_options': ['--disable-e10s'], 14:22:16 INFO - 'tests': ['tp5o']}, 14:22:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:22:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'talos_options': ['--disable-e10s', 14:22:16 INFO - '--xperf_path', 14:22:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:16 INFO - 'tests': ['tp5n']}, 14:22:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:22:16 INFO - 'talos_options': ['--xperf_path', 14:22:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:16 INFO - 'tests': ['tp5n']}}, 14:22:16 INFO - 'talos.zip': {'path': '', 14:22:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:22:16 INFO - Running post-action listener: _resource_record_post_action 14:22:16 INFO - [mozharness: 2017-06-07 21:22:16.836405Z] Finished populate-webroot step (success) 14:22:16 INFO - [mozharness: 2017-06-07 21:22:16.836545Z] Running create-virtualenv step. 14:22:16 INFO - Running pre-action listener: _resource_record_pre_action 14:22:16 INFO - Running main action method: create_virtualenv 14:22:16 INFO - Creating virtualenv /builds/slave/test/build/venv 14:22:16 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 14:22:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:22:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:22:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:22:16 INFO - Using real prefix '/tools/python27' 14:22:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:22:17 INFO - Installing distribute.............................................................................................................................................................................................done. 14:22:20 INFO - Installing pip.................done. 14:22:20 INFO - Return code: 0 14:22:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:22:20 INFO - Reading from file tmpfile_stdout 14:22:20 INFO - Output received: 14:22:20 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) 14:22:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:22: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')]} 14:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22: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')]} 14:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22: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', 'pip>=1.5'] in /builds/slave/test/build 14:22: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 pip>=1.5 14:22:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:20 INFO - 'HOME': '/Users/cltbld', 14:22:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:20 INFO - 'LOGNAME': 'cltbld', 14:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:20 INFO - 'MOZ_NO_REMOTE': '1', 14:22:20 INFO - 'NO_EM_RESTART': '1', 14:22:20 INFO - 'PAGER': '/bin/cat', 14:22:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:20 INFO - 'PWD': '/builds/slave/test', 14:22:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:20 INFO - 'SHELL': '/bin/bash', 14:22:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:20 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:20 INFO - 'USER': 'cltbld', 14:22:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:20 INFO - 'XPC_FLAGS': '0x0', 14:22:20 INFO - 'XPC_SERVICE_NAME': '0', 14:22:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:21 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 14:22:21 INFO - Cleaning up... 14:22:21 INFO - Return code: 0 14:22:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:21 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 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:21 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 14:22:21 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 14:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:21 INFO - 'HOME': '/Users/cltbld', 14:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:21 INFO - 'LOGNAME': 'cltbld', 14:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:21 INFO - 'MOZ_NO_REMOTE': '1', 14:22:21 INFO - 'NO_EM_RESTART': '1', 14:22:21 INFO - 'PAGER': '/bin/cat', 14:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:21 INFO - 'PWD': '/builds/slave/test', 14:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:21 INFO - 'SHELL': '/bin/bash', 14:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:21 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:21 INFO - 'USER': 'cltbld', 14:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:21 INFO - 'XPC_FLAGS': '0x0', 14:22:21 INFO - 'XPC_SERVICE_NAME': '0', 14:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:21 INFO - Downloading/unpacking psutil>=3.1.1 14:22:21 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 14:22:21 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 14:22:21 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 14:22:21 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 14:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:22:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:23 INFO - Installing collected packages: psutil 14:22:23 INFO - Running setup.py install for psutil 14:22:23 INFO - building 'psutil._psutil_osx' extension 14:22:23 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 14:22:23 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 14:22:24 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 14:22:24 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 14:22:24 INFO - building 'psutil._psutil_posix' extension 14:22:24 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 14:22:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:22:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:22:24 INFO - ^ 14:22:24 INFO - 1 warning generated. 14:22:24 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 14:22:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:24 INFO - Successfully installed psutil 14:22:24 INFO - Cleaning up... 14:22:24 INFO - Return code: 0 14:22:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:22:24 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')]} 14:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22: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')]} 14:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:22: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 mozsystemmonitor==0.3 14:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:25 INFO - 'HOME': '/Users/cltbld', 14:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:25 INFO - 'LOGNAME': 'cltbld', 14:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:25 INFO - 'MOZ_NO_REMOTE': '1', 14:22:25 INFO - 'NO_EM_RESTART': '1', 14:22:25 INFO - 'PAGER': '/bin/cat', 14:22:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:25 INFO - 'PWD': '/builds/slave/test', 14:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:25 INFO - 'SHELL': '/bin/bash', 14:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:25 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:25 INFO - 'USER': 'cltbld', 14:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:25 INFO - 'XPC_FLAGS': '0x0', 14:22:25 INFO - 'XPC_SERVICE_NAME': '0', 14:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:22: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 14:22: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 14:22: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 14:22: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 14:22:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:22:27 INFO - Installing collected packages: mozsystemmonitor 14:22:27 INFO - Running setup.py install for mozsystemmonitor 14:22:27 INFO - Successfully installed mozsystemmonitor 14:22:27 INFO - Cleaning up... 14:22:27 INFO - Return code: 0 14:22:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:27 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 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:27 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 14:22:27 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 14:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:27 INFO - 'HOME': '/Users/cltbld', 14:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:27 INFO - 'LOGNAME': 'cltbld', 14:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:27 INFO - 'MOZ_NO_REMOTE': '1', 14:22:27 INFO - 'NO_EM_RESTART': '1', 14:22:27 INFO - 'PAGER': '/bin/cat', 14:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:27 INFO - 'PWD': '/builds/slave/test', 14:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:27 INFO - 'SHELL': '/bin/bash', 14:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:27 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:27 INFO - 'USER': 'cltbld', 14:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:27 INFO - 'XPC_FLAGS': '0x0', 14:22:27 INFO - 'XPC_SERVICE_NAME': '0', 14:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:28 INFO - Downloading/unpacking jsonschema==2.5.1 14:22: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 14:22: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 14:22: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 14:22: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 14:22:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:22:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:22: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 14:22: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 14:22: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 14:22: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 14:22:29 INFO - Downloading functools32-3.2.3-2.tar.gz 14:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:22:29 INFO - warning: no files found matching '*.txt' 14:22:29 INFO - no previously-included directories found matching 'build' 14:22:29 INFO - no previously-included directories found matching 'dist' 14:22:29 INFO - no previously-included directories found matching '.git*' 14:22:29 INFO - Installing collected packages: jsonschema, functools32 14:22:29 INFO - Running setup.py install for functools32 14:22:29 INFO - warning: no files found matching '*.txt' 14:22:29 INFO - no previously-included directories found matching 'build' 14:22:29 INFO - no previously-included directories found matching 'dist' 14:22:29 INFO - no previously-included directories found matching '.git*' 14:22:30 INFO - Successfully installed jsonschema functools32 14:22:30 INFO - Cleaning up... 14:22:30 INFO - Return code: 0 14:22:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:22: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')]} 14:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22: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')]} 14:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:30 INFO - 'HOME': '/Users/cltbld', 14:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:30 INFO - 'LOGNAME': 'cltbld', 14:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:30 INFO - 'MOZ_NO_REMOTE': '1', 14:22:30 INFO - 'NO_EM_RESTART': '1', 14:22:30 INFO - 'PAGER': '/bin/cat', 14:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:30 INFO - 'PWD': '/builds/slave/test', 14:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:30 INFO - 'SHELL': '/bin/bash', 14:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:30 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:30 INFO - 'USER': 'cltbld', 14:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:30 INFO - 'XPC_FLAGS': '0x0', 14:22:30 INFO - 'XPC_SERVICE_NAME': '0', 14:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:22:30 INFO - Cleaning up... 14:22:30 INFO - Return code: 0 14:22:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:22: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')]} 14:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22: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')]} 14:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:30 INFO - 'HOME': '/Users/cltbld', 14:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:30 INFO - 'LOGNAME': 'cltbld', 14:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:30 INFO - 'MOZ_NO_REMOTE': '1', 14:22:30 INFO - 'NO_EM_RESTART': '1', 14:22:30 INFO - 'PAGER': '/bin/cat', 14:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:30 INFO - 'PWD': '/builds/slave/test', 14:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:30 INFO - 'SHELL': '/bin/bash', 14:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:30 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:30 INFO - 'USER': 'cltbld', 14:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:30 INFO - 'XPC_FLAGS': '0x0', 14:22:30 INFO - 'XPC_SERVICE_NAME': '0', 14:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:30 INFO - Downloading/unpacking blobuploader==1.2.4 14:22:30 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 14:22:30 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 14:22:30 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 14:22:30 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 14:22:31 INFO - Downloading blobuploader-1.2.4.tar.gz 14:22:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:22:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:22:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:32 INFO - Downloading docopt-0.6.1.tar.gz 14:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:32 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:32 INFO - Running setup.py install for blobuploader 14:22:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:22:33 INFO - Running setup.py install for requests 14:22:33 INFO - Running setup.py install for docopt 14:22:33 INFO - Successfully installed blobuploader requests docopt 14:22:33 INFO - Cleaning up... 14:22:33 INFO - Return code: 0 14:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:33 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 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:33 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 14:22:33 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 14:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:33 INFO - 'HOME': '/Users/cltbld', 14:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:33 INFO - 'LOGNAME': 'cltbld', 14:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:33 INFO - 'MOZ_NO_REMOTE': '1', 14:22:33 INFO - 'NO_EM_RESTART': '1', 14:22:33 INFO - 'PAGER': '/bin/cat', 14:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:33 INFO - 'PWD': '/builds/slave/test', 14:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:33 INFO - 'SHELL': '/bin/bash', 14:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:33 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:33 INFO - 'USER': 'cltbld', 14:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:33 INFO - 'XPC_FLAGS': '0x0', 14:22:33 INFO - 'XPC_SERVICE_NAME': '0', 14:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-wRtqqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-0IGeT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-pyraw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-bkb93l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-sXedZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Xe7N_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ubNSoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-COcfbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-3PbKsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XnWhpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-M7zG4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-oXKNjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-TDtnCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ZwqGlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-KTFQkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-N54GUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:35 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-DtE5gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:22:35 INFO - Running setup.py install for manifestparser 14:22:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozcrash 14:22:36 INFO - Running setup.py install for mozdebug 14:22:36 INFO - Running setup.py install for mozdevice 14:22:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozfile 14:22:36 INFO - Running setup.py install for mozhttpd 14:22:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozinfo 14:22:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozInstall 14:22:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:22:36 INFO - Running setup.py install for mozleak 14:22:37 INFO - Running setup.py install for mozlog 14:22:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:22:37 INFO - Running setup.py install for moznetwork 14:22:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:22:37 INFO - Running setup.py install for mozprocess 14:22:37 INFO - Running setup.py install for mozprofile 14:22:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:22:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:22:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:22:37 INFO - Running setup.py install for mozrunner 14:22:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:22:37 INFO - Running setup.py install for mozscreenshot 14:22:38 INFO - Running setup.py install for moztest 14:22:38 INFO - Running setup.py install for mozversion 14:22:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:22:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:22:38 INFO - Cleaning up... 14:22:38 INFO - Return code: 0 14:22:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22: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')]} 14:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22: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')]} 14:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:38 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 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:38 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 14:22:38 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 14:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:38 INFO - 'HOME': '/Users/cltbld', 14:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:38 INFO - 'LOGNAME': 'cltbld', 14:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:38 INFO - 'MOZ_NO_REMOTE': '1', 14:22:38 INFO - 'NO_EM_RESTART': '1', 14:22:38 INFO - 'PAGER': '/bin/cat', 14:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:38 INFO - 'PWD': '/builds/slave/test', 14:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:38 INFO - 'SHELL': '/bin/bash', 14:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:38 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:38 INFO - 'USER': 'cltbld', 14:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:38 INFO - 'XPC_FLAGS': '0x0', 14:22:38 INFO - 'XPC_SERVICE_NAME': '0', 14:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-N3YXr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:38 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)) 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-qvSBhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:38 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)) 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-FLDVtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:38 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)) 14:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9JfGMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-dJTou8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-AM2evw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zEDSBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-M3cVSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:39 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)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CyrQqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:39 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)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-cxmIYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:39 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)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-jVwVkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:39 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)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-IZ3Vnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:39 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)) 14:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:39 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-5A7vTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:40 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-cU_QYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:40 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-90KakS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:40 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-DAJuJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:40 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-mRKIpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 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)) 14:22:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:41 INFO - Downloading blessings-1.6.tar.gz 14:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:22:42 INFO - Installing collected packages: blessings 14:22:42 INFO - Running setup.py install for blessings 14:22:42 INFO - Successfully installed blessings 14:22:42 INFO - Cleaning up... 14:22:42 INFO - Return code: 0 14:22:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:42 INFO - Reading from file tmpfile_stdout 14:22:42 INFO - Current package versions: 14:22:42 INFO - blessings == 1.6 14:22:42 INFO - blobuploader == 1.2.4 14:22:42 INFO - docopt == 0.6.1 14:22:42 INFO - functools32 == 3.2.3-2 14:22:42 INFO - jsonschema == 2.5.1 14:22:42 INFO - manifestparser == 1.1 14:22:42 INFO - mozInstall == 1.12 14:22:42 INFO - mozcrash == 1.0 14:22:42 INFO - mozdebug == 0.1 14:22:42 INFO - mozdevice == 0.51 14:22:42 INFO - mozfile == 1.2 14:22:42 INFO - mozhttpd == 0.7 14:22:42 INFO - mozinfo == 0.10 14:22:42 INFO - mozleak == 0.1 14:22:42 INFO - mozlog == 3.4 14:22:42 INFO - moznetwork == 0.27 14:22:42 INFO - mozprocess == 0.25 14:22:42 INFO - mozprofile == 0.28 14:22:42 INFO - mozrunner == 6.13 14:22:42 INFO - mozscreenshot == 0.1 14:22:42 INFO - mozsystemmonitor == 0.3 14:22:42 INFO - moztest == 0.8 14:22:42 INFO - mozversion == 1.4 14:22:42 INFO - psutil == 3.1.1 14:22:42 INFO - requests == 1.2.3 14:22:42 INFO - wsgiref == 0.1.2 14:22:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:42 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')]} 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:42 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')]} 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:42 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 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:42 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 14:22:42 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 14:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:42 INFO - 'HOME': '/Users/cltbld', 14:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:42 INFO - 'LOGNAME': 'cltbld', 14:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:42 INFO - 'MOZ_NO_REMOTE': '1', 14:22:42 INFO - 'NO_EM_RESTART': '1', 14:22:42 INFO - 'PAGER': '/bin/cat', 14:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:42 INFO - 'PWD': '/builds/slave/test', 14:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:42 INFO - 'SHELL': '/bin/bash', 14:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:42 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:42 INFO - 'USER': 'cltbld', 14:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:42 INFO - 'XPC_FLAGS': '0x0', 14:22:42 INFO - 'XPC_SERVICE_NAME': '0', 14:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 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)) 14:22:42 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:22:44 INFO - Installing collected packages: marionette-driver 14:22:44 INFO - Running setup.py install for marionette-driver 14:22:44 INFO - Successfully installed marionette-driver 14:22:44 INFO - Cleaning up... 14:22:44 INFO - Return code: 0 14:22:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ef92c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef96768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb562579ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, '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.p6StzO4zYk/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.ysbYftJ0L6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 14:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:44 INFO - 'HOME': '/Users/cltbld', 14:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:44 INFO - 'LOGNAME': 'cltbld', 14:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:44 INFO - 'MOZ_NO_REMOTE': '1', 14:22:44 INFO - 'NO_EM_RESTART': '1', 14:22:44 INFO - 'PAGER': '/bin/cat', 14:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:44 INFO - 'PWD': '/builds/slave/test', 14:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:44 INFO - 'SHELL': '/bin/bash', 14:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:22:44 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:44 INFO - 'USER': 'cltbld', 14:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:44 INFO - 'XPC_FLAGS': '0x0', 14:22:44 INFO - 'XPC_SERVICE_NAME': '0', 14:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:22:44 INFO - Cleaning up... 14:22:44 INFO - Return code: 0 14:22:44 INFO - Running post-action listener: _resource_record_post_action 14:22:44 INFO - Running post-action listener: _start_resource_monitoring 14:22:44 INFO - Starting resource monitoring. 14:22:44 INFO - [mozharness: 2017-06-07 21:22:44.839258Z] Finished create-virtualenv step (success) 14:22:44 INFO - [mozharness: 2017-06-07 21:22:44.840223Z] Running install step. 14:22:44 INFO - Running pre-action listener: _resource_record_pre_action 14:22:44 INFO - Running main action method: install 14:22:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:45 INFO - Reading from file tmpfile_stdout 14:22:45 INFO - Detecting whether we're running mozinstall >=1.0... 14:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:22:45 INFO - Reading from file tmpfile_stdout 14:22:45 INFO - Output received: 14:22:45 INFO - Usage: mozinstall [options] installer 14:22:45 INFO - Options: 14:22:45 INFO - -h, --help show this help message and exit 14:22:45 INFO - -d DEST, --destination=DEST 14:22:45 INFO - Directory to install application into. [default: 14:22:45 INFO - "/builds/slave/test"] 14:22:45 INFO - --app=APP Application being installed. [default: firefox] 14:22:45 INFO - mkdir: /builds/slave/test/build/application 14:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:23:06 INFO - Reading from file tmpfile_stdout 14:23:06 INFO - Output received: 14:23:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:23:06 INFO - Running post-action listener: _resource_record_post_action 14:23:06 INFO - [mozharness: 2017-06-07 21:23:06.600305Z] Finished install step (success) 14:23:06 INFO - [mozharness: 2017-06-07 21:23:06.600436Z] Running setup-mitmproxy step. 14:23:06 INFO - Running pre-action listener: _resource_record_pre_action 14:23:06 INFO - Running main action method: setup_mitmproxy 14:23:06 INFO - Skipping: mitmproxy is not required 14:23:06 INFO - Running post-action listener: _resource_record_post_action 14:23:06 INFO - [mozharness: 2017-06-07 21:23:06.601032Z] Finished setup-mitmproxy step (success) 14:23:06 INFO - [mozharness: 2017-06-07 21:23:06.601139Z] Running run-tests step. 14:23:06 INFO - Running pre-action listener: _resource_record_pre_action 14:23:06 INFO - Running main action method: run_tests 14:23:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:23:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:23:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:23:06 INFO - 2017-06-07 14:23:06.613 screenresolution[1636:7451] starting screenresolution argv=screenresolution get 14:23:06 INFO - 2017-06-07 14:23:06.616 screenresolution[1636:7451] Display 0: 1600x1200x32@60 14:23:06 INFO - 2017-06-07 14:23:06.621 screenresolution[1637:7453] starting screenresolution argv=screenresolution list 14:23:06 INFO - Available Modes on Display 0 14:23:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:23:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:23:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:23:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:23:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:23:06 INFO - Intel Iris: 14:23:06 INFO - Chipset Model: Intel Iris 14:23:06 INFO - Type: GPU 14:23:06 INFO - Bus: Built-In 14:23:06 INFO - VRAM (Dynamic, Max): 1536 MB 14:23:06 INFO - Vendor: Intel (0x8086) 14:23:06 INFO - Device ID: 0x0a2e 14:23:06 INFO - Revision ID: 0x0009 14:23:06 INFO - Displays: 14:23:06 INFO - Display: 14:23:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:23:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:23:06 INFO - Display Serial Number: 1600x1200 60 14:23:06 INFO - Main Display: Yes 14:23:06 INFO - Mirror: Off 14:23:06 INFO - Online: Yes 14:23:06 INFO - Rotation: Supported 14:23:06 INFO - Return code: 0 14:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:23:06 INFO - Python 2.7.3 14:23:06 INFO - Return code: 0 14:23:06 INFO - grabbing minidump binary from tooltool 14:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:06 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 0x10ece8d30>, '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 0x10ecb2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecd9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:23:06 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 14:23:06 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 14:23:06 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 14:23:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:23:06 INFO - Return code: 0 14:23:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:23:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:23:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:23:06 INFO - ENV: RUST_BACKTRACE is now 1 14:23:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:23:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 14:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0330 --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 14:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6StzO4zYk/Render', 14:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:06 INFO - 'HOME': '/Users/cltbld', 14:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:06 INFO - 'LOGNAME': 'cltbld', 14:23:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:23:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:06 INFO - 'MOZ_NO_REMOTE': '1', 14:23:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:23:06 INFO - 'NO_EM_RESTART': '1', 14:23:06 INFO - 'PAGER': '/bin/cat', 14:23:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:06 INFO - 'PWD': '/builds/slave/test', 14:23:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:06 INFO - 'RUST_BACKTRACE': '1', 14:23:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:23:06 INFO - 'SHELL': '/bin/bash', 14:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ysbYftJ0L6/Listeners', 14:23:06 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 14:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:06 INFO - 'USER': 'cltbld', 14:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:06 INFO - 'XPC_FLAGS': '0x0', 14:23:06 INFO - 'XPC_SERVICE_NAME': '0', 14:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 14:23:07 INFO - mozversion application_buildid: 20170607125634 14:23:07 INFO - mozversion application_changeset: 2064f302f3e71abc41e7f75a0341a85028510297 14:23:07 INFO - mozversion application_display_name: Nightly 14:23:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:23:07 INFO - mozversion application_name: Firefox 14:23:07 INFO - mozversion application_remotingname: firefox 14:23:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 14:23:07 INFO - mozversion application_vendor: Mozilla 14:23:07 INFO - mozversion application_version: 55.0a1 14:23:07 INFO - mozversion platform_buildid: 20170607125634 14:23:07 INFO - mozversion platform_changeset: 2064f302f3e71abc41e7f75a0341a85028510297 14:23:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 14:23:07 INFO - mozversion platform_version: 55.0a1 14:23:07 INFO - using testdate: 1496870587 14:23:07 INFO - actual date: 1496870587 14:23:07 INFO - starting webserver on 'localhost:49221' 14:23:07 INFO - SUITE-START | Running 2 tests 14:23:07 INFO - TEST-START | dromaeo_css 14:23:07 INFO - operating with platform_type : mac_ 14:23:07 INFO - Initialising browser for dromaeo_css test... 14:23:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpV3zRBf/profile 14:23:07 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 14:23:41 INFO - TEST-INFO | 1647: killed by SIGTERM 14:23:41 INFO - Browser initialized. 14:23:41 INFO - Running cycle 1/1 for dromaeo_css test... 14:23:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpV3zRBf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:43 INFO - PID 1657 | 2017-06-07 14:23:43.250 plugin-container[1658:8018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:23:43 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:43 INFO - PID 1657 | 2017-06-07 14:23:43.251 plugin-container[1658:8018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d1f, name = 'com.apple.CFPasteboardClient' 14:23:43 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:43 INFO - PID 1657 | 2017-06-07 14:23:43.251 plugin-container[1658:8018] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:23:43 INFO - PID 1657 | 2017-06-07 14:23:43.744 plugin-container[1659:8065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:23:43 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:43 INFO - PID 1657 | 2017-06-07 14:23:43.744 plugin-container[1659:8065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.CFPasteboardClient' 14:23:43 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:43 INFO - PID 1657 | 2017-06-07 14:23:43.744 plugin-container[1659:8065] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:23:53 INFO - PID 1657 | RSS: Main: 252317696 14:23:53 INFO - PID 1657 | 14:26:54 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 14:26:56 INFO - PID 1657 | RSS: Main: 265965568 14:26:56 INFO - PID 1657 | 14:29:58 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 14:29:59 INFO - PID 1657 | RSS: Main: 277975040 14:29:59 INFO - PID 1657 | 14:33:00 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 14:33:01 INFO - PID 1657 | RSS: Main: 280911872 14:33:01 INFO - PID 1657 | 14:36:03 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 14:36:03 INFO - PID 1657 | RSS: Main: 281534464 14:36:03 INFO - PID 1657 | 14:39:06 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 14:39:07 INFO - PID 1657 | RSS: Main: 283144192 14:39:07 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 14:42:09 INFO - PID 1657 | RSS: Main: 281853952 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | __start_tp_report 14:42:09 INFO - PID 1657 | _x_x_mozilla_page_load 14:42:09 INFO - PID 1657 | _x_x_mozilla_page_load_details 14:42:09 INFO - PID 1657 | |i|pagename|runs| 14:42:09 INFO - PID 1657 | |0;dojo.html;3546.4857595648627;3577.320309141263;3593.3531964246426;3597.85608575657;3606.3688854450475;4522.90954180917;6124.289394097922;6156.969215153924;6243.406876346751;6335.524835637329;6193.907091393633;6205.379462053792;6221.502279817615;6291.245050593935;6313.589616674913;4979.7261150636705;5222.399424066243;5339.946600533984;5381.112116500793;5414.810481633143;5481.671099734009;5489.780408783644;5500.862478438031;5512.558995280388;5531.474509921572;5314.893702125974;5378.408375078739;5394.649347792381;5419.214213938996;5420.349558053059;5413.215083812865;5415.079436495806;5435;5445.727713614343;5458.2904222451225;5335.919961200585;5359.062164121302;5415.268938693265;5420.512153906128;5424.376196737387;6131.080337949299;6148.862460444832;6247.87504249917;6277;6282.591631543935;6564.573302735307;6653.068570400148;6654;6679.499037572202;6701.832454188684;6768.221654509745;6798.796036118924;6800.591964482147;6807.795766127024;6830.795076147723;5696.658200507983;5952.065578686944;6232.25212974446;6346.555741098125;6351.682415879188;6762.188537375547;6809.659517024129;6877.58734475923;6901.965490172517;6933.791986240421;6228.377162283736;6404.679766011681;6407.359264073555;6536.346365363521;6554.573952693059;6674.2658307586125;6747.460203183734;6751.189857217068;6758.459323254128;6767.864642707217;6628.33716628343;6631.668416579152;6635.270120286765;6650.035744816974;6672.9332706673285;6417.06952491886;6432.099506069154;6537.673116344164;6592.9340706593275;6609.1408116944485;5348.491893270133;5351;5354.759035843396;5359.517643412111;5373.247745315659;4749.26386410107;4771.71369717818;4774.594159496412;4777.4983626718695;4804.183288840905;6127.632342059491;6318.842028949313;6325.399087086719;6327.303996560375;6387.648679322589;6753.392194702402;6763.256041835394;6791.117154769849;6808.285130061371;6834.282400347892;6298.279843911777;6350.491960643138;6354.936450635527;6389.361063893573;6395.168628078413;5741.1388291756575;5824.242848429677;5890.2342695449315;5896.498797602166;5947.464728174485;6925.722971081101;6941.930580694229;6954.930450695429;6972.442204623517;6976.407005404598;5566.860828479321;6795.524313298021;6806.76176333826;6817.5909445433435;6832.521723479309;6753.2909044549615;6882.449404047564;6925;6944.270851560514;6959;6470.38531339522;6552.8361790955605;6612.107365505595;6641.070250165078;6668.233153187445;6774.085498457645;6782.728690852508;6786.049953006682;6788.9660551696925;6800.965995170191;6342.365763423621;6389.329120442472;6416.326285740117;6437.485001199799;6482.805515834532;6483.448906842971;6506.219253689587;6520.250171230369;6521.9673901630185;6550.868982620225;6008.639481631116;6388.233411990591;6393.264774550986;6399.8080057598345;6404.423601875854;6361.459275961595;6556.639384833689;6564.146660934048;6573.80278591643;6585.341465853377;6295.622262664072;6337.366263373626;6345.619262844244;6347.650879201688;6358.682065896779 14:42:09 INFO - PID 1657 | |1;ext.html;786.5398741736084;823.193270594817;842.1157784326458;1236.962891113267;1277.814716866054;38742.8062859686;38746.61253387472;38836.22327553454;38837.223255534875;38849.028774280654;38866.41700374489;39024.609753902456;39049.023774405585;39119.804400977955;39140.825775226716;6914.481413893953;7812.609369531515;8341.16588341164;8341.165883411672;8343.16568343167;8331.208535189178;8425.957870210641;8428.072911979678;8441.113683063282;8452.323814094858;8363.247307742302;8396.790080247982;8422.663093476283;8452.8732069019;8462.280706139985;8408.201220884006;8417.074121846592;8438.873416898752;8442;8451.070382257947;8223.383246256555;8267.379946503976;8275.54484503352;8282.71010514635;8284.83430331396;31121.22196945072;36305.63694363049;36481.81759091214;36700.26599468022;36772.26455470902;3307.2393349529502;3364.0916952422954;3375.3080618473155;3377.392069427502;3381.8647254109806;26687.73312266872;30456.086317410514;30726.84636576825;30749.23126921845;30780.84609576938;3041.376517813843;3044.604201453819;3065.172403451078;3091.1653853459666;3104.8292343921075;211786.70533236637;235741;260686.69656651784;288632;289577;47570.5242947573;47753.28370074452;48195.554133376056;48238;48342.75828620834;49379.50620493821;49468.50531494711;49933.50066499361;49933.75033124811;50143;14535.27323633814;14628.414863405558;14721.631959201106;14724.969252152461;14746.336414861356;11700.531978720926;11703.23928944616;11800.645980620424;11819.527218911318;11829.349385783693;14185.574432767033;14242.715145697028;14264.42942282297;14266;14362.928185359215;6045.00514830105;6085;6112.3191122441685;6114.877702445926;6115.174451448997;987.7283746969641;997.6258902911409;1008.4503945349802;1049.2130901823632;1050.1756121444782;21316.680249796267;22053.669194962087;23146.421339466648;24635.137770177957;30460.847695761822;1030.7629245273479;1091.8635170603675;1129.2264798612975;1132.5639628742829;1134.7673726886135;11694.532218711156;11750.42097846884;11890.88109118915;12503.187292825936;12509.687257818627;306788;307686;308726;308952;309113;52389.47610523922;52616;52658.736706316224;52917.470825292025;52925;1286.8455785305823;1466.7139907718106;1501;1503.7819516170314;1530.1813529761523;22442.326730197467;22598.435039124153;23663.408414789767;23733.64399533974;23748.287551372796;38106;38207.808960955015;38342.616573833904;38355;38410.42384364181;273741;275323;275651;276181.8572721451;276280;34158.31683366369;34405;34405.48391774175;34520.309593807484;34584.65415345814;41136.1772764549;42121;42829.78585107055;42888;43170;28078.85960570184;29106.12681619555;29634.259143521584;29662.851685742295;30408.695913040585;29030.983915562854;29161.85419072891;29188.978385756396;30171;30503.847480762455;35806;36166;36223.456648149775;36247.27505449929;36310.636893630726;24124.39689007789;25881.611775823756;27821.860890696225;28108.43783124367;30518.847405763718;23298.76701232966;23931.640906224628;24061.75938240595;24143;24156.758432415452 14:42:09 INFO - PID 1657 | |2;jquery.html;381.3022169429943;421.18290516398184;431.69781153192775;443.5697373547659;458.5574920202004;22137;23036.769632303673;23121.190758323442;23208.187713430034;24352.391190220253;23763;23943.28170154898;24293.39266518338;24328.39179020521;24331.513369732595;5471.699056551888;6134.877302453937;6226.785776773531;6289;6300.716467758939;5566.55467562596;6156.95331793594;6170.1670274513035;6217.409346112112;6222.475493504098;5965.970170149165;6059.454649081582;6078.939210607881;6107;6130.1111338855835;6062.181605483247;6126.969365153191;6131.908021379683;6133.478654314344;6136.938630613704;5453.23654688344;5459.153831156177;5459.317585301837;5475.808346707846;5485.177223416479;27637.447251055066;28010;28304.575431368547;28397.432051359065;28549.57175642367;2197.0772275644267;2266.3994041578994;2271.806896413807;2272.704548408713;2276.46503071778;17817.93092414459;18070;18237.817621823746;18283.177257023464;18369.173387197607;2122.2253877334815;2151.1717988574355;2183.5196256823474;2190.4961858772413;2212.8672279663274;119810;134360.32819835935;159105;163908;165789.9999999988;11521.308721476571;12877.29174895371;12935;13162.210267383987;13235.66910827237;16757.748633770505;16863.747043794352;16869.493915182564;16876.324947001984;16910.40813571519;8082.838343233102;8336.874946875801;8869.645214191489;9023.278137748965;9221.769455763526;7305.89041164383;8010.319122874621;8233.394488074768;8535.445196062235;8806.295496360275;8319.126491718403;8374.2881855043;8394.496330220085;8444.324454043663;8458.450200736932;2177.2379667116697;2177.4882902517897;2180.9890950545146;2263.230501629421;2331.4521087544413;943.5093751249449;957.7310064165009;967.6675384834248;996.6661168508485;1006.3609607899036;20607.278745243846;20935.581288374146;21571.56856862854;21761;21853;982.3418309732353;1000.0899181744578;1032.2980373346047;1033.2715435617872;1039.5581877701945;10918.344899305908;10965.122790176767;11325.980661740317;11350.48922798476;11372.090232781358;462100;474721;476031;490125;490962;137014;138913.30543347218;139387;139745;140379;1136.6476392318407;1142.4801715219555;1157.1552766480347;1159.7390587117832;1172.4489489939886;17696.29214831392;17848.107594620476;18192.72710909311;18901;19186.520336991685;61233.69383153234;64191.67904160449;66081.00878486922;66492;66597.66804663977;74441;78925;79168;79559;85506;10106.545205465625;10165.84751228747;10245.692629221136;10352.120069793851;10360.171186304926;17816.376426824998;17970.730439043164;18079.18643661012;18192.36326728611;18330.358437454626;13874.791878122038;13997.790033149307;14238.288085595472;14264.358103885143;14374;12810.67973300675;12846.871531284567;13257.933710331386;13366.398512066786;13406.061575689608;14589.05171163871;14637.487687930872;14746.188959606896;14805.925970370079;14897.478588249793;10199.28604997643;10200.540975655966;10228.693139205836;10228.772547294373;10256.487175641338;14024.579262622137;14228.359723812246;14284.571542845631;14367.20980346091;14435.422583096979 14:42:09 INFO - PID 1657 | |3;mootools.html;1385.4458216713313;1417.8085958395613;1428.3572392423405;1446.604027113835;1460.423132862519;10419.218558608107;11937.58218462354;12256.26462412256;12310.322932238723;12362.938185309073;11793.882061179385;11981.820272695917;11985.64043078709;11994.700132496691;12018.759624807499;2534.885930133139;2592.935176620581;2593.8443693378363;2595;2599.8180127391092;1770.2653398839466;2201.72478440195;2493.6010238361864;2503.2865633294514;2589.741025897414;2562.5771747661615;2574.7553982371737;2583.883725232369;2598.7791037761813;2599.57107077332;2544.3384719972755;2550.3496608364935;2551.1581178211145;2552.119518766023;2564.064116597447;2097.423208617627;2500.9374765631005;2569.588865781466;2570.6144078388206;2575.240304110292;11904.880951190464;11918.642440726791;12020.759584808255;12033.338166400845;12043.578474753342;6504.284528701886;6715.294894036105;6742.4268937140405;6773.085633439472;6775.085363475917;3887.8142166639163;4022.5374081980654;4224.988839839281;4290.892727681801;4299.828006879721;961.274237950343;1252.1923359433206;1252.7431876465303;1268.7335659511514;1272.7772639788;13835.584932451942;16855;17114.48656540306;17137.971721696736;17151.65696686059;5592.909535452284;5642.830715078554;5659.745311460994;5767.625104368202;5802.1296805479515;12370.567030154081;12434.253944763343;12444.688882778002;12450.501979920882;12455;6375.203099612549;6622.966885165543;6625.4037136657935;6626.105475760807;6634.104395906587;5448.863778405493;6114.908276375858;6165.722542485599;6178.938210617926;6193.6903154842075;4022.3363145080734;4274.21565939444;4344.022594916119;4382.320740285261;4387.749491394937;1252.7494501099816;1294.5792617399384;1319.7096638739463;1320.821689071963;1321.4714114354142;826.8883700700328;827.6978902700471;854.0647990450447;863.1153068105242;895.6775560798108;5895;7138.393236574845;7580.128285247157;7586.772396828104;7690.692372305157;928.1646518133599;946.4415994563241;965.9130678238993;977.4428575711918;988.3032462114193;3917.92164156721;4304.440422745086;4505.909881802346;4625.398698169217;4643.74459404736;14979.550613481613;15209;15471.922640386727;15484.148371839432;15488.07071575709;14837;15442.84178686603;15465;15483.767743483857;15490.535283941499;8487.787805304917;8649.1350864913;8687.565621718888;8707.216350528483;8709.04200537953;5814;7041.612711300722;7501.5874126923545;7672.38620910337;7874.13384527713;13747.381367838272;13847.446102155802;13864;13943.442262309394;14064.226467544384;14219.50231741884;14226.288685565472;14316.355763990437;14371.92814035923;14490.85509144895;9203.263738901005;9644.373115747454;9827.066428689262;9845.901540984498;9864.112229899343;8338.374621903502;10133.949330253301;10511.474426278808;10588.311759735592;10729.980651838063;8287.476492683514;9173.35786494939;9309;9309.44143351401;9380.296477764332;9031.187193152648;9037.728868133967;9055.818883622422;9173.724788256362;9196.356255062348;8419.621117049624;8459.111793261618;8504.574771261288;8517.48895066298;8552.871706924278;6213.720382582885;6359.141515895295;6390.584612000205;6402;6566.244881838458;6316.084167795736;6374.713137908712;6491.642959637265;6525.184351956005;6645.900311495235 14:42:09 INFO - PID 1657 | |4;prototype.html;329.8767695995136;389.28371795895566;397.11839715830865;405.54578871663733;409.0775301694678;6236.594621349609;6298.937010629892;6391.552591318609;6399;6499.15510983572;5175.40482844473;5937.753071854916;6155.199824022882;6212.285587157478;6306.495480361572;3974.728087012157;4300.6989510734265;4514.616257618107;4529.34324522944;4539.477960034589;4154.605312495308;4263.2965560682605;4275.657947364203;4533.591976722094;4544.136614043337;4516.616087632555;4519.435070616173;4522.3894774205555;4525.660575456837;4533.591976722094;3724.3296206682785;3890.863819766323;3924.6860251180124;4341.719192838136;4542.545745425464;3832.06114501946;3902.824372903226;3909.051857623792;3919.529656441217;3927.8821635350987;4939.3578834751615;5167.328247327823;6132.969335153296;6156.292026416974;6170.981788004974;5376.946230537684;5418.322709661293;5431.293931788842;5445.945540544583;5466.180072989084;6729.25978142404;6734.528582999192;6735.8989615155815;6736.5958042517605;6763.661816909184;4097.160082183145;4275.123599662062;4430.29115341547;4440.578145076212;4464.6874718769695;6729.629870357128;6970.825729356756;7060.364567188978;7137.893626487903;7141.428685705131;3865.613438656112;4480.350349199348;4484.461864576207;4537.591616754508;4546.431696037996;4583.564561366665;4588.95411045892;4618.49196588375;4624.630029597624;4651.279051746985;4050.3519436889956;5224.738763061831;5225.608079393985;5291.624177713745;5352.143657014859;5513.724313784295;6223;6644.269130395653;6718.126643536309;6738.258791532928;1271.3960868587533;1347.9123856949268;1504.3005002673808;1595.9122248276224;1625.4960962101786;969.6412327438893;1020.9438480883474;1050.2910535388583;1054.18300816866;1058.5075044187813;6273.37266273369;6340.714667839959;6363.5863668861375;6369.038397145257;6393.552451328363;5541.231962265698;6967.965160174167;6992.510524263253;6993.685284162225;7023.403010744144;5517.282753242133;5577.944220557823;5919.875223707481;6272.184615999982;6393.648349340738;3602.837872295753;3636.636336366395;3651.7808931464197;3674.81625918703;3692.436798489417;5961.2846458425265;7204.855902881913;7218.3503484686635;7228.638568071576;7235.855282894313;6492.285848556655;6687.76592819249;7135.250798666169;7174.282571742784;7250.891236631454;7183.173934997542;7190.137183538009;7233.3236702420445;7246.5289756165685;7261.346478816932;6576.771466683902;6629.204495460575;6801.435669795876;6991.763524751857;7078.256783037707;6838.153698501476;6873.621950792754;6879.518433709793;6905.413039891666;6914.204866440424;5568.749406276646;5761.91357129635;6595.318193860535;7154.964225178841;7257.201707812242;4509.2559727644575;4568;4569.560588414639;4574.10804893056;4604.1712491751805;6826.692798823966;6902.723891044302;7033;7078.0889159927565;7114.21743608213;6498.155239818793;6533.346665333429;6670.466362691071;6818.761343352959;6845.931540684529;5673.602847800615;6551.70517326731;6641.933580664131;6796.320367963362;6824.4199243064895;6084.90872636902;6648.601083934979;6680.9331906682255;6682.131322927989;6706.6981985811735;6149.84625384369;6236.906446403398;6771.356721111487;6773.62745049027;6785.285885645584;6447;6451.83866903943;6574.441172500391;6587.670616469253;6610.3720146586 14:42:09 INFO - PID 1657 | |5;yui.html;849.9447535910161;852.3138873207066;855.2431098477847;877.8149498177461;888.5157589113933;13498.055136140474;14391.568252952402;16595.66808663826;17005.97964122152;17032.233549490273;16042;17657.64684706305;17679.38122165721;17701.911490442562;17717.114144292766;971.863939048532;983.5180761426885;992.1071036067561;1006.0894890124449;1027.019196667181;1028.7942411517688;1031.9174466042737;1033.152814691953;1033.4161198922623;1036.1141224253267;921.4655499810127;1040.1834559870504;1040.7606250562337;1049.8950104989517;1051.1170616682023;1017.9949100254526;1024.027174184524;1037.7198156497702;1040.47976011994;1043.1029314789275;741.529129003087;763.5609524523366;767.0948281028384;768.1166658342895;768.1895600141848;16992.490225293262;17090.230939607747;17254.396096136574;17281.740773888403;17349.306027758874;15689.98015129013;17535.298588056463;17994.5501362467;18031.72952405715;18059.909700451415;12231.204971676812;14013.089149205269;14979.475718349915;15007.924960375238;15039.849601503955;3580.8030558319283;3625.1118475973553;3645.76302540334;3655.5613326401003;3658.432942893856;139172;140610;141052;142878;142943.28528357396;12491.812622810665;13819.516316928859;14226.28868556568;14370.640733981525;14389.78415323771;26782.19653410401;26940.92236310565;27122.728772712417;27190.048348307715;27884.442311154067;7630.7710768677025;7893.565853877977;8027.5584842834205;8040.477368970998;8147.266745992771;3888.8444462221196;3939.4287828265315;3966.5636779954184;3970;3977.5823538528616;4377.365282034088;4480.507144214133;4489.191945449785;4491.528389519119;4491.752953587518;925.3245131054368;959.4627008875052;965.652365148546;967.5936106835146;971.4948226922103;674.4402146218627;677.3159109299643;681.6012632609978;688.6006116452546;692.4079911675545;9817.607295708234;10159.238057145743;11579.015783658484;13137.540186093442;13142;633.5501793726506;639.2137670665107;670.9530332876652;693.5076095971816;696.314130581377;5099.719515426687;5488.4785945335125;5635.633683810539;5947.226860508106;6061.393860613904;129237;133268;151803.2409837966;153291.2335438338;154875.22562387117;109667.80664386667;111876.44061779638;116080;116956.41521792336;117928.8207117935;92309.53845230732;94953.5252323734;96219;96462.51768741252;97646.51176744071;10108.89891101109;11864.82202766942;12351.93824030874;12609.43257553394;12633.725929332808;41314.79342603388;43957.340639889786;44293.557064430235;44653;45836.31245531386;56366.71816640891;57256;57728;57855.421445785;57869.13196301975;16433.09617970975;16833.494995150166;16889;17054.573635659206;17100;18751.437456876316;18787.248510059446;18856.905715471334;18858.434246972614;18997.14512846953;14332.815363773823;14540.927295363455;14789.334479948644;14978.700425991201;15008.6998260032;16711.749323760396;16908.746368804233;17029;17288.962662239803;17322.39371621987;16582.66834663324;16669.666606667557;16877.578060548585;17074.573135671708;17111.65776684484;11386.700008723097;11738.295702257892;12321.44553495077;12405.193662411913;12555.811662824883;12938.094333396948;13293.601191964257;13325;13846.307684615527;13858.02993790425 14:42:09 INFO - PID 1657 | __end_tp_report 14:42:09 INFO - PID 1657 | __start_cc_report 14:42:09 INFO - PID 1657 | _x_x_mozilla_cycle_collect,447 14:42:09 INFO - PID 1657 | __end_cc_report 14:42:09 INFO - PID 1657 | __startTimestamp1496871729529__endTimestamp 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | ------- Summary: start ------- 14:42:09 INFO - PID 1657 | Number of tests: 6 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | [#0] dojo.html Cycles:165 Average:6151.37 Median:6387.65 stddev:738.25 (11.6%) stddev-sans-first:711.67 14:42:09 INFO - PID 1657 | Values: 3546.5 3577.3 3593.4 3597.9 3606.4 4522.9 6124.3 6157.0 6243.4 6335.5 6193.9 6205.4 6221.5 6291.2 6313.6 4979.7 5222.4 5339.9 5381.1 5414.8 5481.7 5489.8 5500.9 5512.6 5531.5 5314.9 5378.4 5394.6 5419.2 5420.3 5413.2 5415.1 5435.0 5445.7 5458.3 5335.9 5359.1 5415.3 5420.5 5424.4 6131.1 6148.9 6247.9 6277.0 6282.6 6564.6 6653.1 6654.0 6679.5 6701.8 6768.2 6798.8 6800.6 6807.8 6830.8 5696.7 5952.1 6232.3 6346.6 6351.7 6762.2 6809.7 6877.6 6902.0 6933.8 6228.4 6404.7 6407.4 6536.3 6554.6 6674.3 6747.5 6751.2 6758.5 6767.9 6628.3 6631.7 6635.3 6650.0 6672.9 6417.1 6432.1 6537.7 6592.9 6609.1 5348.5 5351.0 5354.8 5359.5 5373.2 4749.3 4771.7 4774.6 4777.5 4804.2 6127.6 6318.8 6325.4 6327.3 6387.6 6753.4 6763.3 6791.1 6808.3 6834.3 6298.3 6350.5 6354.9 6389.4 6395.2 5741.1 5824.2 5890.2 5896.5 5947.5 6925.7 6941.9 6954.9 6972.4 6976.4 5566.9 6795.5 6806.8 6817.6 6832.5 6753.3 6882.4 6925.0 6944.3 6959.0 6470.4 6552.8 6612.1 6641.1 6668.2 6774.1 6782.7 6786.0 6789.0 6801.0 6342.4 6389.3 6416.3 6437.5 6482.8 6483.4 6506.2 6520.3 6522.0 6550.9 6008.6 6388.2 6393.3 6399.8 6404.4 6361.5 6556.6 6564.1 6573.8 6585.3 6295.6 6337.4 6345.6 6347.7 6358.7 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | [#1] ext.html Cycles:180 Average:43196.14 Median:17214.23 stddev:73736.35 (428.3%) stddev-sans-first:73874.45 14:42:09 INFO - PID 1657 | Values: 786.5 823.2 842.1 1237.0 1277.8 38742.8 38746.6 38836.2 38837.2 38849.0 38866.4 39024.6 39049.0 39119.8 39140.8 6914.5 7812.6 8341.2 8341.2 8343.2 8331.2 8426.0 8428.1 8441.1 8452.3 8363.2 8396.8 8422.7 8452.9 8462.3 8408.2 8417.1 8438.9 8442.0 8451.1 8223.4 8267.4 8275.5 8282.7 8284.8 31121.2 36305.6 36481.8 36700.3 36772.3 3307.2 3364.1 3375.3 3377.4 3381.9 26687.7 30456.1 30726.8 30749.2 30780.8 3041.4 3044.6 3065.2 3091.2 3104.8 211786.7 235741.0 260686.7 288632.0 289577.0 47570.5 47753.3 48195.6 48238.0 48342.8 49379.5 49468.5 49933.5 49933.8 50143.0 14535.3 14628.4 14721.6 14725.0 14746.3 11700.5 11703.2 11800.6 11819.5 11829.3 14185.6 14242.7 14264.4 14266.0 14362.9 6045.0 6085.0 6112.3 6114.9 6115.2 987.7 997.6 1008.5 1049.2 1050.2 21316.7 22053.7 23146.4 24635.1 30460.8 1030.8 1091.9 1129.2 1132.6 1134.8 11694.5 11750.4 11890.9 12503.2 12509.7 306788.0 307686.0 308726.0 308952.0 309113.0 52389.5 52616.0 52658.7 52917.5 52925.0 1286.8 1466.7 1501.0 1503.8 1530.2 22442.3 22598.4 23663.4 23733.6 23748.3 38106.0 38207.8 38342.6 38355.0 38410.4 273741.0 275323.0 275651.0 276181.9 276280.0 34158.3 34405.0 34405.5 34520.3 34584.7 41136.2 42121.0 42829.8 42888.0 43170.0 28078.9 29106.1 29634.3 29662.9 30408.7 29031.0 29161.9 29189.0 30171.0 30503.8 35806.0 36166.0 36223.5 36247.3 36310.6 24124.4 25881.6 27821.9 28108.4 30518.8 23298.8 23931.6 24061.8 24143.0 24156.8 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | [#2] jquery.html Cycles:180 Average:34588.87 Median:12021.75 stddev:82548.25 (686.7%) stddev-sans-first:82739.87 14:42:09 INFO - PID 1657 | Values: 381.3 421.2 431.7 443.6 458.6 22137.0 23036.8 23121.2 23208.2 24352.4 23763.0 23943.3 24293.4 24328.4 24331.5 5471.7 6134.9 6226.8 6289.0 6300.7 5566.6 6157.0 6170.2 6217.4 6222.5 5966.0 6059.5 6078.9 6107.0 6130.1 6062.2 6127.0 6131.9 6133.5 6136.9 5453.2 5459.2 5459.3 5475.8 5485.2 27637.4 28010.0 28304.6 28397.4 28549.6 2197.1 2266.4 2271.8 2272.7 2276.5 17817.9 18070.0 18237.8 18283.2 18369.2 2122.2 2151.2 2183.5 2190.5 2212.9 119810.0 134360.3 159105.0 163908.0 165790.0 11521.3 12877.3 12935.0 13162.2 13235.7 16757.7 16863.7 16869.5 16876.3 16910.4 8082.8 8336.9 8869.6 9023.3 9221.8 7305.9 8010.3 8233.4 8535.4 8806.3 8319.1 8374.3 8394.5 8444.3 8458.5 2177.2 2177.5 2181.0 2263.2 2331.5 943.5 957.7 967.7 996.7 1006.4 20607.3 20935.6 21571.6 21761.0 21853.0 982.3 1000.1 1032.3 1033.3 1039.6 10918.3 10965.1 11326.0 11350.5 11372.1 462100.0 474721.0 476031.0 490125.0 490962.0 137014.0 138913.3 139387.0 139745.0 140379.0 1136.6 1142.5 1157.2 1159.7 1172.4 17696.3 17848.1 18192.7 18901.0 19186.5 61233.7 64191.7 66081.0 66492.0 66597.7 74441.0 78925.0 79168.0 79559.0 85506.0 10106.5 10165.8 10245.7 10352.1 10360.2 17816.4 17970.7 18079.2 18192.4 18330.4 13874.8 13997.8 14238.3 14264.4 14374.0 12810.7 12846.9 13257.9 13366.4 13406.1 14589.1 14637.5 14746.2 14805.9 14897.5 10199.3 10200.5 10228.7 10228.8 10256.5 14024.6 14228.4 14284.6 14367.2 14435.4 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | [#3] mootools.html Cycles:180 Average:7274.37 Median:4022.44 stddev:4614.56 (114.7%) stddev-sans-first:4606.29 14:42:09 INFO - PID 1657 | Values: 1385.4 1417.8 1428.4 1446.6 1460.4 10419.2 11937.6 12256.3 12310.3 12362.9 11793.9 11981.8 11985.6 11994.7 12018.8 2534.9 2592.9 2593.8 2595.0 2599.8 1770.3 2201.7 2493.6 2503.3 2589.7 2562.6 2574.8 2583.9 2598.8 2599.6 2544.3 2550.3 2551.2 2552.1 2564.1 2097.4 2500.9 2569.6 2570.6 2575.2 11904.9 11918.6 12020.8 12033.3 12043.6 6504.3 6715.3 6742.4 6773.1 6775.1 3887.8 4022.5 4225.0 4290.9 4299.8 961.3 1252.2 1252.7 1268.7 1272.8 13835.6 16855.0 17114.5 17138.0 17151.7 5592.9 5642.8 5659.7 5767.6 5802.1 12370.6 12434.3 12444.7 12450.5 12455.0 6375.2 6623.0 6625.4 6626.1 6634.1 5448.9 6114.9 6165.7 6178.9 6193.7 4022.3 4274.2 4344.0 4382.3 4387.7 1252.7 1294.6 1319.7 1320.8 1321.5 826.9 827.7 854.1 863.1 895.7 5895.0 7138.4 7580.1 7586.8 7690.7 928.2 946.4 965.9 977.4 988.3 3917.9 4304.4 4505.9 4625.4 4643.7 14979.6 15209.0 15471.9 15484.1 15488.1 14837.0 15442.8 15465.0 15483.8 15490.5 8487.8 8649.1 8687.6 8707.2 8709.0 5814.0 7041.6 7501.6 7672.4 7874.1 13747.4 13847.4 13864.0 13943.4 14064.2 14219.5 14226.3 14316.4 14371.9 14490.9 9203.3 9644.4 9827.1 9845.9 9864.1 8338.4 10133.9 10511.5 10588.3 10730.0 8287.5 9173.4 9309.0 9309.4 9380.3 9031.2 9037.7 9055.8 9173.7 9196.4 8419.6 8459.1 8504.6 8517.5 8552.9 6213.7 6359.1 6390.6 6402.0 6566.2 6316.1 6374.7 6491.6 6525.2 6645.9 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | [#4] prototype.html Cycles:180 Average:5399.32 Median:6155.75 stddev:1741.22 (28.3%) stddev-sans-first:1704.02 14:42:09 INFO - PID 1657 | Values: 329.9 389.3 397.1 405.5 409.1 6236.6 6298.9 6391.6 6399.0 6499.2 5175.4 5937.8 6155.2 6212.3 6306.5 3974.7 4300.7 4514.6 4529.3 4539.5 4154.6 4263.3 4275.7 4533.6 4544.1 4516.6 4519.4 4522.4 4525.7 4533.6 3724.3 3890.9 3924.7 4341.7 4542.5 3832.1 3902.8 3909.1 3919.5 3927.9 4939.4 5167.3 6133.0 6156.3 6171.0 5376.9 5418.3 5431.3 5445.9 5466.2 6729.3 6734.5 6735.9 6736.6 6763.7 4097.2 4275.1 4430.3 4440.6 4464.7 6729.6 6970.8 7060.4 7137.9 7141.4 3865.6 4480.4 4484.5 4537.6 4546.4 4583.6 4589.0 4618.5 4624.6 4651.3 4050.4 5224.7 5225.6 5291.6 5352.1 5513.7 6223.0 6644.3 6718.1 6738.3 1271.4 1347.9 1504.3 1595.9 1625.5 969.6 1020.9 1050.3 1054.2 1058.5 6273.4 6340.7 6363.6 6369.0 6393.6 5541.2 6968.0 6992.5 6993.7 7023.4 5517.3 5577.9 5919.9 6272.2 6393.6 3602.8 3636.6 3651.8 3674.8 3692.4 5961.3 7204.9 7218.4 7228.6 7235.9 6492.3 6687.8 7135.3 7174.3 7250.9 7183.2 7190.1 7233.3 7246.5 7261.3 6576.8 6629.2 6801.4 6991.8 7078.3 6838.2 6873.6 6879.5 6905.4 6914.2 5568.7 5761.9 6595.3 7155.0 7257.2 4509.3 4568.0 4569.6 4574.1 4604.2 6826.7 6902.7 7033.0 7078.1 7114.2 6498.2 6533.3 6670.5 6818.8 6845.9 5673.6 6551.7 6641.9 6796.3 6824.4 6084.9 6648.6 6680.9 6682.1 6706.7 6149.8 6236.9 6771.4 6773.6 6785.3 6447.0 6451.8 6574.4 6587.7 6610.4 14:42:09 INFO - PID 1657 | 14:42:09 INFO - PID 1657 | [#5] yui.html Cycles:180 Average:24647.80 Median:17335.85 stddev:37767.78 (217.9%) stddev-sans-first:37831.46 14:42:09 INFO - PID 1657 | Values: 849.9 852.3 855.2 877.8 888.5 13498.1 14391.6 16595.7 17006.0 17032.2 16042.0 17657.6 17679.4 17701.9 17717.1 971.9 983.5 992.1 1006.1 1027.0 1028.8 1031.9 1033.2 1033.4 1036.1 921.5 1040.2 1040.8 1049.9 1051.1 1018.0 1024.0 1037.7 1040.5 1043.1 741.5 763.6 767.1 768.1 768.2 16992.5 17090.2 17254.4 17281.7 17349.3 15690.0 17535.3 17994.6 18031.7 18059.9 12231.2 14013.1 14979.5 15007.9 15039.8 3580.8 3625.1 3645.8 3655.6 3658.4 139172.0 140610.0 141052.0 142878.0 142943.3 12491.8 13819.5 14226.3 14370.6 14389.8 26782.2 26940.9 27122.7 27190.0 27884.4 7630.8 7893.6 8027.6 8040.5 8147.3 3888.8 3939.4 3966.6 3970.0 3977.6 4377.4 4480.5 4489.2 4491.5 4491.8 925.3 959.5 965.7 967.6 971.5 674.4 677.3 681.6 688.6 692.4 9817.6 10159.2 11579.0 13137.5 13142.0 633.6 639.2 671.0 693.5 696.3 5099.7 5488.5 5635.6 5947.2 6061.4 129237.0 133268.0 151803.2 153291.2 154875.2 109667.8 111876.4 116080.0 116956.4 117928.8 92309.5 94953.5 96219.0 96462.5 97646.5 10108.9 11864.8 12351.9 12609.4 12633.7 41314.8 43957.3 44293.6 44653.0 45836.3 56366.7 57256.0 57728.0 57855.4 57869.1 16433.1 16833.5 16889.0 17054.6 17100.0 18751.4 18787.2 18856.9 18858.4 18997.1 14332.8 14540.9 14789.3 14978.7 15008.7 16711.7 16908.7 17029.0 17289.0 17322.4 16582.7 16669.7 16877.6 17074.6 17111.7 11386.7 11738.3 12321.4 12405.2 12555.8 12938.1 13293.6 13325.0 13846.3 13858.0 14:42:09 INFO - PID 1657 | -------- Summary: end -------- 14:42:09 INFO - PID 1657 | 14:42:10 INFO - TEST-INFO | 1657: exit 0 14:42:10 INFO - TEST-OK | dromaeo_css | took 1142953ms 14:42:10 INFO - TEST-START | kraken 14:42:10 INFO - operating with platform_type : mac_ 14:42:10 INFO - Initialising browser for kraken test... 14:42:10 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpALjwjC/profile 14:42:10 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 14:42:44 INFO - TEST-INFO | 1667: killed by SIGTERM 14:42:44 INFO - Browser initialized. 14:42:44 INFO - Running cycle 1/1 for kraken test... 14:42:44 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpALjwjC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:42:46 INFO - PID 1671 | 2017-06-07 14:42:46.185 plugin-container[1672:10675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 14:42:46 INFO - PID 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:46 INFO - PID 1671 | 2017-06-07 14:42:46.185 plugin-container[1672:10675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 14:42:46 INFO - PID 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:46 INFO - PID 1671 | 2017-06-07 14:42:46.185 plugin-container[1672:10675] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:42:46 INFO - PID 1671 | 2017-06-07 14:42:46.754 plugin-container[1673:10719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 14:42:46 INFO - PID 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:46 INFO - PID 1671 | 2017-06-07 14:42:46.755 plugin-container[1673:10719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 14:42:46 INFO - PID 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:46 INFO - PID 1671 | 2017-06-07 14:42:46.755 plugin-container[1673:10719] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:42:56 INFO - PID 1671 | RSS: Main: 253313024 14:42:56 INFO - PID 1671 | 14:42:58 INFO - PID 1671 | 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) 14:42:59 INFO - PID 1671 | RSS: Main: 265650176 14:42:59 INFO - PID 1671 | 14:43:02 INFO - PID 1671 | 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) 14:43:02 INFO - PID 1671 | RSS: Main: 273788928 14:43:02 INFO - PID 1671 | 14:43:06 INFO - PID 1671 | 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) 14:43:06 INFO - PID 1671 | RSS: Main: 276230144 14:43:06 INFO - PID 1671 | 14:43:08 INFO - PID 1671 | 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) 14:43:09 INFO - PID 1671 | RSS: Main: 279109632 14:43:09 INFO - PID 1671 | 14:43:11 INFO - PID 1671 | 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) 14:43:11 INFO - PID 1671 | RSS: Main: 281001984 14:43:11 INFO - PID 1671 | 14:43:14 INFO - PID 1671 | 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) 14:43:15 INFO - PID 1671 | RSS: Main: 285573120 14:43:15 INFO - PID 1671 | 14:43:18 INFO - PID 1671 | 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) 14:43:19 INFO - PID 1671 | RSS: Main: 287580160 14:43:19 INFO - PID 1671 | 14:43:22 INFO - PID 1671 | 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) 14:43:22 INFO - PID 1671 | RSS: Main: 286580736 14:43:22 INFO - PID 1671 | 14:43:24 INFO - PID 1671 | 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) 14:43:25 INFO - PID 1671 | RSS: Main: 286310400 14:43:25 INFO - PID 1671 | 14:43:26 INFO - PID 1671 | 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) 14:43:27 INFO - PID 1671 | RSS: Main: 286142464 14:43:27 INFO - PID 1671 | 14:43:29 INFO - PID 1671 | 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) 14:43:30 INFO - PID 1671 | RSS: Main: 286072832 14:43:30 INFO - PID 1671 | 14:43:33 INFO - PID 1671 | 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) 14:43:34 INFO - PID 1671 | RSS: Main: 285626368 14:43:34 INFO - PID 1671 | 14:43:37 INFO - PID 1671 | 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) 14:43:37 INFO - PID 1671 | RSS: Main: 285970432 14:43:37 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | 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) 14:43:39 INFO - PID 1671 | RSS: Main: 287760384 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | __start_tp_report 14:43:39 INFO - PID 1671 | _x_x_mozilla_page_load 14:43:39 INFO - PID 1671 | _x_x_mozilla_page_load_details 14:43:39 INFO - PID 1671 | |i|pagename|runs| 14:43:39 INFO - PID 1671 | |0;ai-astar;103;112;115;115;114;117;114;115;116;114 14:43:39 INFO - PID 1671 | |1;audio-beat-detection;142;107;112;145;110;107;110;107;145;147 14:43:39 INFO - PID 1671 | |2;audio-dft;194;205;193;204;207;210;210;260;198;193 14:43:39 INFO - PID 1671 | |3;audio-fft;70;58;59;83;63;58;58;59;78;71 14:43:39 INFO - PID 1671 | |4;audio-oscillator;87;76;104;103;89;68;85;69;70;89 14:43:39 INFO - PID 1671 | |5;imaging-gaussian-blur;89;88;111;89;88;92;89;85;125;101 14:43:39 INFO - PID 1671 | |6;imaging-darkroom;96;95;97;94;98;119;152;96;127;95 14:43:39 INFO - PID 1671 | |7;imaging-desaturate;92;94;91;104;92;93;88;93;93;91 14:43:39 INFO - PID 1671 | |8;json-parse-financial;50;48;51;53;53;52;51;52;52;54 14:43:39 INFO - PID 1671 | |9;json-stringify-tinderbox;59;63;61;67;65;62;59;56;79;87 14:43:39 INFO - PID 1671 | |10;stanford-crypto-aes;83;84;84;82;84;82;86;86;110;87 14:43:39 INFO - PID 1671 | |11;stanford-crypto-ccm;145;134;139;166;149;146;153;151;158;140 14:43:39 INFO - PID 1671 | |12;stanford-crypto-pbkdf2;224;219;179;180;179;180;188;219;227;176 14:43:39 INFO - PID 1671 | |13;stanford-crypto-sha256-iterative;64;66;66;62;67;67;63;67;68;67 14:43:39 INFO - PID 1671 | __end_tp_report 14:43:39 INFO - PID 1671 | __start_cc_report 14:43:39 INFO - PID 1671 | _x_x_mozilla_cycle_collect,1193 14:43:39 INFO - PID 1671 | __end_cc_report 14:43:39 INFO - PID 1671 | __startTimestamp1496871819685__endTimestamp 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | ------- Summary: start ------- 14:43:39 INFO - PID 1671 | Number of tests: 14 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#0] ai-astar Cycles:10 Average:113.50 Median:115.00 stddev:3.92 (3.4%) stddev-sans-first:1.41 14:43:39 INFO - PID 1671 | Values: 103.0 112.0 115.0 115.0 114.0 117.0 114.0 115.0 116.0 114.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:127.00 stddev:18.65 (14.7%) stddev-sans-first:18.50 14:43:39 INFO - PID 1671 | Values: 142.0 107.0 112.0 145.0 110.0 107.0 110.0 107.0 145.0 147.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#2] audio-dft Cycles:10 Average:207.40 Median:206.00 stddev:19.66 (9.5%) stddev-sans-first:20.25 14:43:39 INFO - PID 1671 | Values: 194.0 205.0 193.0 204.0 207.0 210.0 210.0 260.0 198.0 193.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#3] audio-fft Cycles:10 Average:65.70 Median:66.50 stddev:9.26 (13.9%) stddev-sans-first:9.69 14:43:39 INFO - PID 1671 | Values: 70.0 58.0 59.0 83.0 63.0 58.0 58.0 59.0 78.0 71.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:80.50 stddev:13.17 (16.4%) stddev-sans-first:13.93 14:43:39 INFO - PID 1671 | Values: 87.0 76.0 104.0 103.0 89.0 68.0 85.0 69.0 70.0 89.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:88.50 stddev:12.92 (14.6%) stddev-sans-first:13.47 14:43:39 INFO - PID 1671 | Values: 89.0 88.0 111.0 89.0 88.0 92.0 89.0 85.0 125.0 101.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:95.50 stddev:19.58 (20.5%) stddev-sans-first:20.36 14:43:39 INFO - PID 1671 | Values: 96.0 95.0 97.0 94.0 98.0 119.0 152.0 96.0 127.0 95.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:92.50 stddev:4.18 (4.5%) stddev-sans-first:4.41 14:43:39 INFO - PID 1671 | Values: 92.0 94.0 91.0 104.0 92.0 93.0 88.0 93.0 93.0 91.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:1.71 (3.3%) stddev-sans-first:1.72 14:43:39 INFO - PID 1671 | Values: 50.0 48.0 51.0 53.0 53.0 52.0 51.0 52.0 52.0 54.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:64.00 stddev:9.77 (15.3%) stddev-sans-first:10.05 14:43:39 INFO - PID 1671 | Values: 59.0 63.0 61.0 67.0 65.0 62.0 59.0 56.0 79.0 87.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.00 stddev:8.32 (9.9%) stddev-sans-first:8.71 14:43:39 INFO - PID 1671 | Values: 83.0 84.0 84.0 82.0 84.0 82.0 86.0 86.0 110.0 87.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:150.00 stddev:9.50 (6.3%) stddev-sans-first:10.01 14:43:39 INFO - PID 1671 | Values: 145.0 134.0 139.0 166.0 149.0 146.0 153.0 151.0 158.0 140.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.10 Median:203.50 stddev:21.97 (10.8%) stddev-sans-first:21.04 14:43:39 INFO - PID 1671 | Values: 224.0 219.0 179.0 180.0 179.0 180.0 188.0 219.0 227.0 176.0 14:43:39 INFO - PID 1671 | 14:43:39 INFO - PID 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:67.00 stddev:2.00 (3.0%) stddev-sans-first:2.03 14:43:39 INFO - PID 1671 | Values: 64.0 66.0 66.0 62.0 67.0 67.0 63.0 67.0 68.0 67.0 14:43:39 INFO - PID 1671 | -------- Summary: end -------- 14:43:39 INFO - PID 1671 | 14:43:40 INFO - TEST-INFO | 1671: exit 0 14:43:40 INFO - TEST-OK | kraken | took 90316ms 14:43:40 INFO - SUITE-END | took 1233s 14:43:40 INFO - Completed test suite (00:20:33) 14:43:40 INFO - javascript benchmark 14:43:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8023.727236499851, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3546.4857595648627, 3577.320309141263, 3593.3531964246426, 3597.85608575657, 3606.3688854450475, 4522.90954180917, 6124.289394097922, 6156.969215153924, 6243.406876346751, 6335.524835637329, 6193.907091393633, 6205.379462053792, 6221.502279817615, 6291.245050593935, 6313.589616674913, 4979.7261150636705, 5222.399424066243, 5339.946600533984, 5381.112116500793, 5414.810481633143, 5481.671099734009, 5489.780408783644, 5500.862478438031, 5512.558995280388, 5531.474509921572, 5314.893702125974, 5378.408375078739, 5394.649347792381, 5419.214213938996, 5420.349558053059, 5413.215083812865, 5415.079436495806, 5435.0, 5445.727713614343, 5458.2904222451225, 5335.919961200585, 5359.062164121302, 5415.268938693265, 5420.512153906128, 5424.376196737387, 6131.080337949299, 6148.862460444832, 6247.87504249917, 6277.0, 6282.591631543935, 6564.573302735307, 6653.068570400148, 6654.0, 6679.499037572202, 6701.832454188684, 6768.221654509745, 6798.796036118924, 6800.591964482147, 6807.795766127024, 6830.795076147723, 5696.658200507983, 5952.065578686944, 6232.25212974446, 6346.555741098125, 6351.682415879188, 6762.188537375547, 6809.659517024129, 6877.58734475923, 6901.965490172517, 6933.791986240421, 6228.377162283736, 6404.679766011681, 6407.359264073555, 6536.346365363521, 6554.573952693059, 6674.2658307586125, 6747.460203183734, 6751.189857217068, 6758.459323254128, 6767.864642707217, 6628.33716628343, 6631.668416579152, 6635.270120286765, 6650.035744816974, 6672.9332706673285, 6417.06952491886, 6432.099506069154, 6537.673116344164, 6592.9340706593275, 6609.1408116944485, 5348.491893270133, 5351.0, 5354.759035843396, 5359.517643412111, 5373.247745315659, 4749.26386410107, 4771.71369717818, 4774.594159496412, 4777.4983626718695, 4804.183288840905, 6127.632342059491, 6318.842028949313, 6325.399087086719, 6327.303996560375, 6387.648679322589, 6753.392194702402, 6763.256041835394, 6791.117154769849, 6808.285130061371, 6834.282400347892, 6298.279843911777, 6350.491960643138, 6354.936450635527, 6389.361063893573, 6395.168628078413, 5741.1388291756575, 5824.242848429677, 5890.2342695449315, 5896.498797602166, 5947.464728174485, 6925.722971081101, 6941.930580694229, 6954.930450695429, 6972.442204623517, 6976.407005404598, 5566.860828479321, 6795.524313298021, 6806.76176333826, 6817.5909445433435, 6832.521723479309, 6753.2909044549615, 6882.449404047564, 6925.0, 6944.270851560514, 6959.0, 6470.38531339522, 6552.8361790955605, 6612.107365505595, 6641.070250165078, 6668.233153187445, 6774.085498457645, 6782.728690852508, 6786.049953006682, 6788.9660551696925, 6800.965995170191, 6342.365763423621, 6389.329120442472, 6416.326285740117, 6437.485001199799, 6482.805515834532, 6483.448906842971, 6506.219253689587, 6520.250171230369, 6521.9673901630185, 6550.868982620225, 6008.639481631116, 6388.233411990591, 6393.264774550986, 6399.8080057598345, 6404.423601875854, 6361.459275961595, 6556.639384833689, 6564.146660934048, 6573.80278591643, 6585.341465853377, 6295.622262664072, 6337.366263373626, 6345.619262844244, 6347.650879201688, 6358.682065896779], "value": 6102.168601920333, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.5398741736084, 823.193270594817, 842.1157784326458, 1236.962891113267, 1277.814716866054, 38742.8062859686, 38746.61253387472, 38836.22327553454, 38837.223255534875, 38849.028774280654, 38866.41700374489, 39024.609753902456, 39049.023774405585, 39119.804400977955, 39140.825775226716, 6914.481413893953, 7812.609369531515, 8341.16588341164, 8341.165883411672, 8343.16568343167, 8331.208535189178, 8425.957870210641, 8428.072911979678, 8441.113683063282, 8452.323814094858, 8363.247307742302, 8396.790080247982, 8422.663093476283, 8452.8732069019, 8462.280706139985, 8408.201220884006, 8417.074121846592, 8438.873416898752, 8442.0, 8451.070382257947, 8223.383246256555, 8267.379946503976, 8275.54484503352, 8282.71010514635, 8284.83430331396, 31121.22196945072, 36305.63694363049, 36481.81759091214, 36700.26599468022, 36772.26455470902, 3307.2393349529502, 3364.0916952422954, 3375.3080618473155, 3377.392069427502, 3381.8647254109806, 26687.73312266872, 30456.086317410514, 30726.84636576825, 30749.23126921845, 30780.84609576938, 3041.376517813843, 3044.604201453819, 3065.172403451078, 3091.1653853459666, 3104.8292343921075, 211786.70533236637, 235741.0, 260686.69656651784, 288632.0, 289577.0, 47570.5242947573, 47753.28370074452, 48195.554133376056, 48238.0, 48342.75828620834, 49379.50620493821, 49468.50531494711, 49933.50066499361, 49933.75033124811, 50143.0, 14535.27323633814, 14628.414863405558, 14721.631959201106, 14724.969252152461, 14746.336414861356, 11700.531978720926, 11703.23928944616, 11800.645980620424, 11819.527218911318, 11829.349385783693, 14185.574432767033, 14242.715145697028, 14264.42942282297, 14266.0, 14362.928185359215, 6045.00514830105, 6085.0, 6112.3191122441685, 6114.877702445926, 6115.174451448997, 987.7283746969641, 997.6258902911409, 1008.4503945349802, 1049.2130901823632, 1050.1756121444782, 21316.680249796267, 22053.669194962087, 23146.421339466648, 24635.137770177957, 30460.847695761822, 1030.7629245273479, 1091.8635170603675, 1129.2264798612975, 1132.5639628742829, 1134.7673726886135, 11694.532218711156, 11750.42097846884, 11890.88109118915, 12503.187292825936, 12509.687257818627, 306788.0, 307686.0, 308726.0, 308952.0, 309113.0, 52389.47610523922, 52616.0, 52658.736706316224, 52917.470825292025, 52925.0, 1286.8455785305823, 1466.7139907718106, 1501.0, 1503.7819516170314, 1530.1813529761523, 22442.326730197467, 22598.435039124153, 23663.408414789767, 23733.64399533974, 23748.287551372796, 38106.0, 38207.808960955015, 38342.616573833904, 38355.0, 38410.42384364181, 273741.0, 275323.0, 275651.0, 276181.8572721451, 276280.0, 34158.31683366369, 34405.0, 34405.48391774175, 34520.309593807484, 34584.65415345814, 41136.1772764549, 42121.0, 42829.78585107055, 42888.0, 43170.0, 28078.85960570184, 29106.12681619555, 29634.259143521584, 29662.851685742295, 30408.695913040585, 29030.983915562854, 29161.85419072891, 29188.978385756396, 30171.0, 30503.847480762455, 35806.0, 36166.0, 36223.456648149775, 36247.27505449929, 36310.636893630726, 24124.39689007789, 25881.611775823756, 27821.860890696225, 28108.43783124367, 30518.847405763718, 23298.76701232966, 23931.640906224628, 24061.75938240595, 24143.0, 24156.758432415452], "value": 17263.06840112394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.3022169429943, 421.18290516398184, 431.69781153192775, 443.5697373547659, 458.5574920202004, 22137.0, 23036.769632303673, 23121.190758323442, 23208.187713430034, 24352.391190220253, 23763.0, 23943.28170154898, 24293.39266518338, 24328.39179020521, 24331.513369732595, 5471.699056551888, 6134.877302453937, 6226.785776773531, 6289.0, 6300.716467758939, 5566.55467562596, 6156.95331793594, 6170.1670274513035, 6217.409346112112, 6222.475493504098, 5965.970170149165, 6059.454649081582, 6078.939210607881, 6107.0, 6130.1111338855835, 6062.181605483247, 6126.969365153191, 6131.908021379683, 6133.478654314344, 6136.938630613704, 5453.23654688344, 5459.153831156177, 5459.317585301837, 5475.808346707846, 5485.177223416479, 27637.447251055066, 28010.0, 28304.575431368547, 28397.432051359065, 28549.57175642367, 2197.0772275644267, 2266.3994041578994, 2271.806896413807, 2272.704548408713, 2276.46503071778, 17817.93092414459, 18070.0, 18237.817621823746, 18283.177257023464, 18369.173387197607, 2122.2253877334815, 2151.1717988574355, 2183.5196256823474, 2190.4961858772413, 2212.8672279663274, 119810.0, 134360.32819835935, 159105.0, 163908.0, 165789.9999999988, 11521.308721476571, 12877.29174895371, 12935.0, 13162.210267383987, 13235.66910827237, 16757.748633770505, 16863.747043794352, 16869.493915182564, 16876.324947001984, 16910.40813571519, 8082.838343233102, 8336.874946875801, 8869.645214191489, 9023.278137748965, 9221.769455763526, 7305.89041164383, 8010.319122874621, 8233.394488074768, 8535.445196062235, 8806.295496360275, 8319.126491718403, 8374.2881855043, 8394.496330220085, 8444.324454043663, 8458.450200736932, 2177.2379667116697, 2177.4882902517897, 2180.9890950545146, 2263.230501629421, 2331.4521087544413, 943.5093751249449, 957.7310064165009, 967.6675384834248, 996.6661168508485, 1006.3609607899036, 20607.278745243846, 20935.581288374146, 21571.56856862854, 21761.0, 21853.0, 982.3418309732353, 1000.0899181744578, 1032.2980373346047, 1033.2715435617872, 1039.5581877701945, 10918.344899305908, 10965.122790176767, 11325.980661740317, 11350.48922798476, 11372.090232781358, 462100.0, 474721.0, 476031.0, 490125.0, 490962.0, 137014.0, 138913.30543347218, 139387.0, 139745.0, 140379.0, 1136.6476392318407, 1142.4801715219555, 1157.1552766480347, 1159.7390587117832, 1172.4489489939886, 17696.29214831392, 17848.107594620476, 18192.72710909311, 18901.0, 19186.520336991685, 61233.69383153234, 64191.67904160449, 66081.00878486922, 66492.0, 66597.66804663977, 74441.0, 78925.0, 79168.0, 79559.0, 85506.0, 10106.545205465625, 10165.84751228747, 10245.692629221136, 10352.120069793851, 10360.171186304926, 17816.376426824998, 17970.730439043164, 18079.18643661012, 18192.36326728611, 18330.358437454626, 13874.791878122038, 13997.790033149307, 14238.288085595472, 14264.358103885143, 14374.0, 12810.67973300675, 12846.871531284567, 13257.933710331386, 13366.398512066786, 13406.061575689608, 14589.05171163871, 14637.487687930872, 14746.188959606896, 14805.925970370079, 14897.478588249793, 10199.28604997643, 10200.540975655966, 10228.693139205836, 10228.772547294373, 10256.487175641338, 14024.579262622137, 14228.359723812246, 14284.571542845631, 14367.20980346091, 14435.422583096979], "value": 10928.96053656463, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.4458216713313, 1417.8085958395613, 1428.3572392423405, 1446.604027113835, 1460.423132862519, 10419.218558608107, 11937.58218462354, 12256.26462412256, 12310.322932238723, 12362.938185309073, 11793.882061179385, 11981.820272695917, 11985.64043078709, 11994.700132496691, 12018.759624807499, 2534.885930133139, 2592.935176620581, 2593.8443693378363, 2595.0, 2599.8180127391092, 1770.2653398839466, 2201.72478440195, 2493.6010238361864, 2503.2865633294514, 2589.741025897414, 2562.5771747661615, 2574.7553982371737, 2583.883725232369, 2598.7791037761813, 2599.57107077332, 2544.3384719972755, 2550.3496608364935, 2551.1581178211145, 2552.119518766023, 2564.064116597447, 2097.423208617627, 2500.9374765631005, 2569.588865781466, 2570.6144078388206, 2575.240304110292, 11904.880951190464, 11918.642440726791, 12020.759584808255, 12033.338166400845, 12043.578474753342, 6504.284528701886, 6715.294894036105, 6742.4268937140405, 6773.085633439472, 6775.085363475917, 3887.8142166639163, 4022.5374081980654, 4224.988839839281, 4290.892727681801, 4299.828006879721, 961.274237950343, 1252.1923359433206, 1252.7431876465303, 1268.7335659511514, 1272.7772639788, 13835.584932451942, 16855.0, 17114.48656540306, 17137.971721696736, 17151.65696686059, 5592.909535452284, 5642.830715078554, 5659.745311460994, 5767.625104368202, 5802.1296805479515, 12370.567030154081, 12434.253944763343, 12444.688882778002, 12450.501979920882, 12455.0, 6375.203099612549, 6622.966885165543, 6625.4037136657935, 6626.105475760807, 6634.104395906587, 5448.863778405493, 6114.908276375858, 6165.722542485599, 6178.938210617926, 6193.6903154842075, 4022.3363145080734, 4274.21565939444, 4344.022594916119, 4382.320740285261, 4387.749491394937, 1252.7494501099816, 1294.5792617399384, 1319.7096638739463, 1320.821689071963, 1321.4714114354142, 826.8883700700328, 827.6978902700471, 854.0647990450447, 863.1153068105242, 895.6775560798108, 5895.0, 7138.393236574845, 7580.128285247157, 7586.772396828104, 7690.692372305157, 928.1646518133599, 946.4415994563241, 965.9130678238993, 977.4428575711918, 988.3032462114193, 3917.92164156721, 4304.440422745086, 4505.909881802346, 4625.398698169217, 4643.74459404736, 14979.550613481613, 15209.0, 15471.922640386727, 15484.148371839432, 15488.07071575709, 14837.0, 15442.84178686603, 15465.0, 15483.767743483857, 15490.535283941499, 8487.787805304917, 8649.1350864913, 8687.565621718888, 8707.216350528483, 8709.04200537953, 5814.0, 7041.612711300722, 7501.5874126923545, 7672.38620910337, 7874.13384527713, 13747.381367838272, 13847.446102155802, 13864.0, 13943.442262309394, 14064.226467544384, 14219.50231741884, 14226.288685565472, 14316.355763990437, 14371.92814035923, 14490.85509144895, 9203.263738901005, 9644.373115747454, 9827.066428689262, 9845.901540984498, 9864.112229899343, 8338.374621903502, 10133.949330253301, 10511.474426278808, 10588.311759735592, 10729.980651838063, 8287.476492683514, 9173.35786494939, 9309.0, 9309.44143351401, 9380.296477764332, 9031.187193152648, 9037.728868133967, 9055.818883622422, 9173.724788256362, 9196.356255062348, 8419.621117049624, 8459.111793261618, 8504.574771261288, 8517.48895066298, 8552.871706924278, 6213.720382582885, 6359.141515895295, 6390.584612000205, 6402.0, 6566.244881838458, 6316.084167795736, 6374.713137908712, 6491.642959637265, 6525.184351956005, 6645.900311495235], "value": 5493.115117519101, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.8767695995136, 389.28371795895566, 397.11839715830865, 405.54578871663733, 409.0775301694678, 6236.594621349609, 6298.937010629892, 6391.552591318609, 6399.0, 6499.15510983572, 5175.40482844473, 5937.753071854916, 6155.199824022882, 6212.285587157478, 6306.495480361572, 3974.728087012157, 4300.6989510734265, 4514.616257618107, 4529.34324522944, 4539.477960034589, 4154.605312495308, 4263.2965560682605, 4275.657947364203, 4533.591976722094, 4544.136614043337, 4516.616087632555, 4519.435070616173, 4522.3894774205555, 4525.660575456837, 4533.591976722094, 3724.3296206682785, 3890.863819766323, 3924.6860251180124, 4341.719192838136, 4542.545745425464, 3832.06114501946, 3902.824372903226, 3909.051857623792, 3919.529656441217, 3927.8821635350987, 4939.3578834751615, 5167.328247327823, 6132.969335153296, 6156.292026416974, 6170.981788004974, 5376.946230537684, 5418.322709661293, 5431.293931788842, 5445.945540544583, 5466.180072989084, 6729.25978142404, 6734.528582999192, 6735.8989615155815, 6736.5958042517605, 6763.661816909184, 4097.160082183145, 4275.123599662062, 4430.29115341547, 4440.578145076212, 4464.6874718769695, 6729.629870357128, 6970.825729356756, 7060.364567188978, 7137.893626487903, 7141.428685705131, 3865.613438656112, 4480.350349199348, 4484.461864576207, 4537.591616754508, 4546.431696037996, 4583.564561366665, 4588.95411045892, 4618.49196588375, 4624.630029597624, 4651.279051746985, 4050.3519436889956, 5224.738763061831, 5225.608079393985, 5291.624177713745, 5352.143657014859, 5513.724313784295, 6223.0, 6644.269130395653, 6718.126643536309, 6738.258791532928, 1271.3960868587533, 1347.9123856949268, 1504.3005002673808, 1595.9122248276224, 1625.4960962101786, 969.6412327438893, 1020.9438480883474, 1050.2910535388583, 1054.18300816866, 1058.5075044187813, 6273.37266273369, 6340.714667839959, 6363.5863668861375, 6369.038397145257, 6393.552451328363, 5541.231962265698, 6967.965160174167, 6992.510524263253, 6993.685284162225, 7023.403010744144, 5517.282753242133, 5577.944220557823, 5919.875223707481, 6272.184615999982, 6393.648349340738, 3602.837872295753, 3636.636336366395, 3651.7808931464197, 3674.81625918703, 3692.436798489417, 5961.2846458425265, 7204.855902881913, 7218.3503484686635, 7228.638568071576, 7235.855282894313, 6492.285848556655, 6687.76592819249, 7135.250798666169, 7174.282571742784, 7250.891236631454, 7183.173934997542, 7190.137183538009, 7233.3236702420445, 7246.5289756165685, 7261.346478816932, 6576.771466683902, 6629.204495460575, 6801.435669795876, 6991.763524751857, 7078.256783037707, 6838.153698501476, 6873.621950792754, 6879.518433709793, 6905.413039891666, 6914.204866440424, 5568.749406276646, 5761.91357129635, 6595.318193860535, 7154.964225178841, 7257.201707812242, 4509.2559727644575, 4568.0, 4569.560588414639, 4574.10804893056, 4604.1712491751805, 6826.692798823966, 6902.723891044302, 7033.0, 7078.0889159927565, 7114.21743608213, 6498.155239818793, 6533.346665333429, 6670.466362691071, 6818.761343352959, 6845.931540684529, 5673.602847800615, 6551.70517326731, 6641.933580664131, 6796.320367963362, 6824.4199243064895, 6084.90872636902, 6648.601083934979, 6680.9331906682255, 6682.131322927989, 6706.6981985811735, 6149.84625384369, 6236.906446403398, 6771.356721111487, 6773.62745049027, 6785.285885645584, 6447.0, 6451.83866903943, 6574.441172500391, 6587.670616469253, 6610.3720146586], "value": 4847.450429483458, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.9447535910161, 852.3138873207066, 855.2431098477847, 877.8149498177461, 888.5157589113933, 13498.055136140474, 14391.568252952402, 16595.66808663826, 17005.97964122152, 17032.233549490273, 16042.0, 17657.64684706305, 17679.38122165721, 17701.911490442562, 17717.114144292766, 971.863939048532, 983.5180761426885, 992.1071036067561, 1006.0894890124449, 1027.019196667181, 1028.7942411517688, 1031.9174466042737, 1033.152814691953, 1033.4161198922623, 1036.1141224253267, 921.4655499810127, 1040.1834559870504, 1040.7606250562337, 1049.8950104989517, 1051.1170616682023, 1017.9949100254526, 1024.027174184524, 1037.7198156497702, 1040.47976011994, 1043.1029314789275, 741.529129003087, 763.5609524523366, 767.0948281028384, 768.1166658342895, 768.1895600141848, 16992.490225293262, 17090.230939607747, 17254.396096136574, 17281.740773888403, 17349.306027758874, 15689.98015129013, 17535.298588056463, 17994.5501362467, 18031.72952405715, 18059.909700451415, 12231.204971676812, 14013.089149205269, 14979.475718349915, 15007.924960375238, 15039.849601503955, 3580.8030558319283, 3625.1118475973553, 3645.76302540334, 3655.5613326401003, 3658.432942893856, 139172.0, 140610.0, 141052.0, 142878.0, 142943.28528357396, 12491.812622810665, 13819.516316928859, 14226.28868556568, 14370.640733981525, 14389.78415323771, 26782.19653410401, 26940.92236310565, 27122.728772712417, 27190.048348307715, 27884.442311154067, 7630.7710768677025, 7893.565853877977, 8027.5584842834205, 8040.477368970998, 8147.266745992771, 3888.8444462221196, 3939.4287828265315, 3966.5636779954184, 3970.0, 3977.5823538528616, 4377.365282034088, 4480.507144214133, 4489.191945449785, 4491.528389519119, 4491.752953587518, 925.3245131054368, 959.4627008875052, 965.652365148546, 967.5936106835146, 971.4948226922103, 674.4402146218627, 677.3159109299643, 681.6012632609978, 688.6006116452546, 692.4079911675545, 9817.607295708234, 10159.238057145743, 11579.015783658484, 13137.540186093442, 13142.0, 633.5501793726506, 639.2137670665107, 670.9530332876652, 693.5076095971816, 696.314130581377, 5099.719515426687, 5488.4785945335125, 5635.633683810539, 5947.226860508106, 6061.393860613904, 129237.0, 133268.0, 151803.2409837966, 153291.2335438338, 154875.22562387117, 109667.80664386667, 111876.44061779638, 116080.0, 116956.41521792336, 117928.8207117935, 92309.53845230732, 94953.5252323734, 96219.0, 96462.51768741252, 97646.51176744071, 10108.89891101109, 11864.82202766942, 12351.93824030874, 12609.43257553394, 12633.725929332808, 41314.79342603388, 43957.340639889786, 44293.557064430235, 44653.0, 45836.31245531386, 56366.71816640891, 57256.0, 57728.0, 57855.421445785, 57869.13196301975, 16433.09617970975, 16833.494995150166, 16889.0, 17054.573635659206, 17100.0, 18751.437456876316, 18787.248510059446, 18856.905715471334, 18858.434246972614, 18997.14512846953, 14332.815363773823, 14540.927295363455, 14789.334479948644, 14978.700425991201, 15008.6998260032, 16711.749323760396, 16908.746368804233, 17029.0, 17288.962662239803, 17322.39371621987, 16582.66834663324, 16669.666606667557, 16877.578060548585, 17074.573135671708, 17111.65776684484, 11386.700008723097, 11738.295702257892, 12321.44553495077, 12405.193662411913, 12555.811662824883, 12938.094333396948, 13293.601191964257, 13325.0, 13846.307684615527, 13858.02993790425], "value": 8703.901329746763, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 115.0, 115.0, 114.0, 117.0, 114.0, 115.0, 116.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 112.0, 145.0, 110.0, 107.0, 110.0, 107.0, 145.0, 147.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 205.0, 193.0, 204.0, 207.0, 210.0, 210.0, 260.0, 198.0, 193.0], "value": 207.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 58.0, 59.0, 83.0, 63.0, 58.0, 58.0, 59.0, 78.0, 71.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 76.0, 104.0, 103.0, 89.0, 68.0, 85.0, 69.0, 70.0, 89.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 111.0, 89.0, 88.0, 92.0, 89.0, 85.0, 125.0, 101.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 94.0, 98.0, 119.0, 152.0, 96.0, 127.0, 95.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 104.0, 92.0, 93.0, 88.0, 93.0, 93.0, 91.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 51.0, 53.0, 53.0, 52.0, 51.0, 52.0, 52.0, 54.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 61.0, 67.0, 65.0, 62.0, 59.0, 56.0, 79.0, 87.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 82.0, 84.0, 82.0, 86.0, 86.0, 110.0, 87.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 134.0, 139.0, 166.0, 149.0, 146.0, 153.0, 151.0, 158.0, 140.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 219.0, 179.0, 180.0, 179.0, 180.0, 188.0, 219.0, 227.0, 176.0], "value": 197.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 62.0, 67.0, 67.0, 63.0, 67.0, 68.0, 67.0], "value": 65.7, "unit": "score"}]}]} 14:43:41 INFO - Return code: 0 14:43:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:43:41 INFO - # TBPL SUCCESS # 14:43:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:43:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:43:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:43:41 INFO - 2017-06-07 14:43:41.098 screenresolution[1679:11108] starting screenresolution argv=screenresolution get 14:43:41 INFO - 2017-06-07 14:43:41.101 screenresolution[1679:11108] Display 0: 1600x1200x32@60 14:43:41 INFO - 2017-06-07 14:43:41.107 screenresolution[1680:11111] starting screenresolution argv=screenresolution list 14:43:41 INFO - Available Modes on Display 0 14:43:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:43:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:43:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:43:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:43:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:43:41 INFO - Intel Iris: 14:43:41 INFO - Chipset Model: Intel Iris 14:43:41 INFO - Type: GPU 14:43:41 INFO - Bus: Built-In 14:43:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:43:41 INFO - Vendor: Intel (0x8086) 14:43:41 INFO - Device ID: 0x0a2e 14:43:41 INFO - Revision ID: 0x0009 14:43:41 INFO - Displays: 14:43:41 INFO - Display: 14:43:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:43:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:43:41 INFO - Display Serial Number: 1600x1200 60 14:43:41 INFO - Main Display: Yes 14:43:41 INFO - Mirror: Off 14:43:41 INFO - Online: Yes 14:43:41 INFO - Rotation: Supported 14:43:41 INFO - Return code: 0 14:43:41 INFO - Running post-action listener: _resource_record_post_action 14:43:41 INFO - [mozharness: 2017-06-07 21:43:41.350015Z] Finished run-tests step (success) 14:43:41 INFO - Running post-run listener: _resource_record_post_run 14:43:41 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40188928; Write bytes: 569875968; Read time: 257; Write time: 1752 14:43:41 INFO - TinderboxPrint: CPU usage
53.2% 14:43:41 INFO - TinderboxPrint: I/O read bytes / time
40,188,928 / 257 14:43:41 INFO - TinderboxPrint: I/O write bytes / time
569,875,968 / 1,752 14:43:41 INFO - TinderboxPrint: CPU idle
2,349.6 (46.8%) 14:43:41 INFO - TinderboxPrint: CPU system
1,017.1 (20.2%) 14:43:41 INFO - TinderboxPrint: CPU user
1,659.1 (33.0%) 14:43:41 INFO - TinderboxPrint: Swap in / out
286,744,576 / 0 14:43:41 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231741952; Write bytes: 230655488; Read time: 16850; Write time: 476 14:43:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:43:41 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 39234560; Write bytes: 320772096; Read time: 244; Write time: 1226 14:43:41 INFO - Running post-run listener: _upload_blobber_files 14:43:41 INFO - Blob upload gear active. 14:43:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:43:42 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:43:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:43:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:43 INFO - (blobuploader) - INFO - Done attempting. 14:43:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:43:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:44 INFO - (blobuploader) - INFO - Done attempting. 14:43:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:43:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:43:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:45 INFO - (blobuploader) - INFO - Done attempting. 14:43:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:43:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:43:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:47 INFO - (blobuploader) - INFO - Done attempting. 14:43:47 INFO - (blobuploader) - INFO - Iteration through files over. 14:43:47 INFO - Return code: 0 14:43:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:43:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:43:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/73375ea52221f7424e2492564f3eb4cc06ee5ea752437c292195e24ac68b506eff69f4b4f7707230ad99e4fda06d37da2b2f04cd0b5530f4f216ad2833d623bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/017cda9beaffcd60941e55e9b9d6e1482ab2c622338babf674f195cb655f489d5f14835db8c06adca100da7445ccb01958d31945b5543c78069dde0f50a97962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/aecad05a60c1b34f63da42dedabc5597439c4a72cd20820d73350d1789321127bcc8496baebd36faca21f1d5b9b87677f5a93ee9d39d2bab34001313ad5b2d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/19753984377454aa0c312db44cf8d91358bf159ee16cc2bb26f47c06eefc4172abef7528724459be97a888f66b4cd7d87696dfbbc9d50de9a57c4c1ee79195ec"} 14:43:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:43:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:43:47 INFO - Contents: 14:43:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/73375ea52221f7424e2492564f3eb4cc06ee5ea752437c292195e24ac68b506eff69f4b4f7707230ad99e4fda06d37da2b2f04cd0b5530f4f216ad2833d623bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/017cda9beaffcd60941e55e9b9d6e1482ab2c622338babf674f195cb655f489d5f14835db8c06adca100da7445ccb01958d31945b5543c78069dde0f50a97962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/aecad05a60c1b34f63da42dedabc5597439c4a72cd20820d73350d1789321127bcc8496baebd36faca21f1d5b9b87677f5a93ee9d39d2bab34001313ad5b2d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/19753984377454aa0c312db44cf8d91358bf159ee16cc2bb26f47c06eefc4172abef7528724459be97a888f66b4cd7d87696dfbbc9d50de9a57c4c1ee79195ec"} 14:43:47 INFO - Running post-run listener: copy_logs_to_upload_dir 14:43:47 INFO - Copying logs to upload dir... 14:43:47 INFO - mkdir: /builds/slave/test/build/upload/logs 14:43:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.419978 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-07 14:43:48.004811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 14:43:48.008505) ========= 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.p6StzO4zYk/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.ysbYftJ0L6/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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/Cedar/sha512/73375ea52221f7424e2492564f3eb4cc06ee5ea752437c292195e24ac68b506eff69f4b4f7707230ad99e4fda06d37da2b2f04cd0b5530f4f216ad2833d623bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/017cda9beaffcd60941e55e9b9d6e1482ab2c622338babf674f195cb655f489d5f14835db8c06adca100da7445ccb01958d31945b5543c78069dde0f50a97962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/aecad05a60c1b34f63da42dedabc5597439c4a72cd20820d73350d1789321127bcc8496baebd36faca21f1d5b9b87677f5a93ee9d39d2bab34001313ad5b2d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/19753984377454aa0c312db44cf8d91358bf159ee16cc2bb26f47c06eefc4172abef7528724459be97a888f66b4cd7d87696dfbbc9d50de9a57c4c1ee79195ec"} build_url:https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/X9nf__I8Sfe2uimwu7se9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/73375ea52221f7424e2492564f3eb4cc06ee5ea752437c292195e24ac68b506eff69f4b4f7707230ad99e4fda06d37da2b2f04cd0b5530f4f216ad2833d623bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/017cda9beaffcd60941e55e9b9d6e1482ab2c622338babf674f195cb655f489d5f14835db8c06adca100da7445ccb01958d31945b5543c78069dde0f50a97962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/aecad05a60c1b34f63da42dedabc5597439c4a72cd20820d73350d1789321127bcc8496baebd36faca21f1d5b9b87677f5a93ee9d39d2bab34001313ad5b2d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/19753984377454aa0c312db44cf8d91358bf159ee16cc2bb26f47c06eefc4172abef7528724459be97a888f66b4cd7d87696dfbbc9d50de9a57c4c1ee79195ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 14:43:48.043300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 14:43:48.043780) ========= 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.p6StzO4zYk/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.ysbYftJ0L6/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005265 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 14:43:48.094451) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 14:43:48.095019) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 14:43:48.095384) ========= ========= Total master_lag: 0.18 =========