builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0225 starttime: 1495623770.16 results: success (0) revision: ffaa07672466b06cd748b07a34cf95377afdde41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.156698) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.157339) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.157817) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.184989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.185644) ========= 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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-24 04:02:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2017-05-24 04:02:50 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139974 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.478550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.478855) ========= 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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.026306 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:50.525508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 04:02:50.525837) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ffaa07672466b06cd748b07a34cf95377afdde41 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev ffaa07672466b06cd748b07a34cf95377afdde41 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-24 04:02:50,623 truncating revision to first 12 chars 2017-05-24 04:02:50,623 Setting DEBUG logging. 2017-05-24 04:02:50,623 attempt 1/10 2017-05-24 04:02:50,623 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ffaa07672466?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 04:02:51,690 unpacking tar archive at: mozilla-central-ffaa07672466/testing/mozharness/ program finished with exit code 0 elapsedTime=1.366400 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 04:02:51.913186) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:51.913518) ========= script_repo_revision: ffaa07672466b06cd748b07a34cf95377afdde41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:51.913889) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:51.914154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 04:02:51.932374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-24 04:02:51.932675) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:02:52 INFO - MultiFileLogger online at 20170524 04:02:52 in /builds/slave/test 04:02:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:02:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:02:52 INFO - {'append_to_log': False, 04:02:52 INFO - 'base_work_dir': '/builds/slave/test', 04:02:52 INFO - 'blob_upload_branch': 'Firefox', 04:02:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:02:52 INFO - 'branch': 'Firefox', 04:02:52 INFO - 'buildbot_json_path': 'buildprops.json', 04:02:52 INFO - 'config_files': ('talos/mac_config.py',), 04:02:52 INFO - 'default_actions': ('clobber', 04:02:52 INFO - 'read-buildbot-config', 04:02:52 INFO - 'download-and-extract', 04:02:52 INFO - 'populate-webroot', 04:02:52 INFO - 'create-virtualenv', 04:02:52 INFO - 'install', 04:02:52 INFO - 'run-tests'), 04:02:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:02:52 INFO - 'download_minidump_stackwalk': True, 04:02:52 INFO - 'download_symbols': 'ondemand', 04:02:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:02:52 INFO - 'tooltool.py': '/tools/tooltool.py', 04:02:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:02:52 INFO - '/tools/misc-python/virtualenv.py')}, 04:02:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:02:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:02:52 INFO - 'gecko_profile': False, 04:02:52 INFO - 'gecko_profile_interval': 0, 04:02:52 INFO - 'installer_path': 'installer.exe', 04:02:52 INFO - 'log_level': 'info', 04:02:52 INFO - 'log_name': 'talos', 04:02:52 INFO - 'log_to_console': True, 04:02:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:02:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:02:52 INFO - 'opt_config_files': (), 04:02:52 INFO - 'pip_index': False, 04:02:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:02:52 INFO - 'cmd': ('bash', 04:02:52 INFO - '-c', 04:02:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:02:52 INFO - 'enabled': True, 04:02:52 INFO - 'halt_on_failure': False, 04:02:52 INFO - 'name': 'check_screen_resolution'},), 04:02:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:02:52 INFO - 'cmd': ('bash', 04:02:52 INFO - '-c', 04:02:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:02:52 INFO - 'enabled': True, 04:02:52 INFO - 'halt_on_failure': False, 04:02:52 INFO - 'name': 'check_screen_resolution'},), 04:02:52 INFO - 'run_cmd_checks_enabled': True, 04:02:52 INFO - 'suite': 'dromaeojs-e10s', 04:02:52 INFO - 'system_bits': '32', 04:02:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:02:52 INFO - 'title': 't-yosemite-r7-0225', 04:02:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:02:52 INFO - 'use_talos_json': True, 04:02:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:02:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:02:52 INFO - 'work_dir': 'build'} 04:02:52 INFO - [mozharness: 2017-05-24 11:02:52.139500Z] Running clobber step. 04:02:52 INFO - Running pre-action listener: _resource_record_pre_action 04:02:52 INFO - Running main action method: clobber 04:02:52 INFO - rmtree: /builds/slave/test/build 04:02:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:02:54 INFO - Running post-action listener: _resource_record_post_action 04:02:54 INFO - [mozharness: 2017-05-24 11:02:54.592889Z] Finished clobber step (success) 04:02:54 INFO - [mozharness: 2017-05-24 11:02:54.593023Z] Running read-buildbot-config step. 04:02:54 INFO - Running pre-action listener: _resource_record_pre_action 04:02:54 INFO - Running main action method: read_buildbot_config 04:02:54 INFO - Using buildbot properties: 04:02:54 INFO - { 04:02:54 INFO - "project": "", 04:02:54 INFO - "product": "firefox", 04:02:54 INFO - "who": "archaeopteryx@coole-files.de", 04:02:54 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 04:02:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:02:54 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:02:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:02:54 INFO - "stage_platform": "macosx64", 04:02:54 INFO - "basedir": "/builds/slave/test", 04:02:54 INFO - "buildnumber": 222, 04:02:54 INFO - "platform": "yosemite_r7", 04:02:54 INFO - "parent_task_id": "Nfsu5xIdSamUsIARqt6c_A", 04:02:54 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:02:54 INFO - "slavebuilddir": "test", 04:02:54 INFO - "taskId": "dDNW8K58TDaoXBdCR-xNlQ", 04:02:54 INFO - "branch": "mozilla-central", 04:02:54 INFO - "script_repo_revision": "production", 04:02:54 INFO - "revision": "ffaa07672466b06cd748b07a34cf95377afdde41", 04:02:54 INFO - "slavename": "t-yosemite-r7-0225", 04:02:54 INFO - "repo_path": "mozilla-central" 04:02:54 INFO - } 04:02:54 INFO - Finding installer, test and symbols from parent task. 04:02:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDNW8K58TDaoXBdCR-xNlQ'}, attempt #1 04:02:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDNW8K58TDaoXBdCR-xNlQ'}, attempt #1 04:02:55 INFO - Task dependencies: SBCPBZRLQGuAN3yMXDwhwg Nfsu5xIdSamUsIARqt6c_A 04:02:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A'}, attempt #1 04:02:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:02:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/test_packages.json 04:02:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/target.crashreporter-symbols.zip 04:02:56 INFO - Running post-action listener: _resource_record_post_action 04:02:56 INFO - [mozharness: 2017-05-24 11:02:56.327435Z] Finished read-buildbot-config step (success) 04:02:56 INFO - [mozharness: 2017-05-24 11:02:56.327556Z] Running download-and-extract step. 04:02:56 INFO - Running pre-action listener: _resource_record_pre_action 04:02:56 INFO - Running main action method: download_and_extract 04:02:56 INFO - mkdir: /builds/slave/test/build/tests 04:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:02:56 INFO - trying https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:02:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/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 04:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/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 04:02:56 INFO - Downloaded 1325 bytes. 04:02:56 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:02:56 INFO - Using the following test package requirements: 04:02:56 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:02:56 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:02:56 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:02:56 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:02:56 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'jsshell-mac.zip'], 04:02:56 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:02:56 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:02:56 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:02:56 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:02:56 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:02:56 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:02:56 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:02:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:02:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:02:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:02:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:02:58 INFO - Content-Length response header: 57625585 04:02:58 INFO - Bytes received: 57625585 04:03:16 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 04:03:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:03:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:03:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:03:18 INFO - Content-Length response header: 57625585 04:03:18 INFO - Bytes received: 57625585 04:03:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:03:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:03:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:03:36 INFO - Content-Length response header: 14411859 04:03:36 INFO - Bytes received: 14411859 04:03:37 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')]} 04:03:37 INFO - trying https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:03:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:03:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/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 04:03:38 INFO - Downloaded 68022102 bytes. 04:03:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:03:38 INFO - mkdir: /builds/slave/test/properties 04:03:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:03:38 INFO - Writing to file /builds/slave/test/properties/build_url 04:03:38 INFO - Contents: 04:03:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:03:38 INFO - Running post-action listener: _resource_record_post_action 04:03:38 INFO - Running post-action listener: set_extra_try_arguments 04:03:38 INFO - [mozharness: 2017-05-24 11:03:38.984101Z] Finished download-and-extract step (success) 04:03:38 INFO - [mozharness: 2017-05-24 11:03:38.984258Z] Running populate-webroot step. 04:03:38 INFO - Running pre-action listener: _resource_record_pre_action 04:03:38 INFO - Running main action method: populate_webroot 04:03:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:03:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:03:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:03:38 INFO - '--tppagecycles', 04:03:38 INFO - '7'], 04:03:38 INFO - 'tests': ['tsvgm']}}, 04:03:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:03:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:03:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:03:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:03:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:03:38 INFO - 'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:03:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:03:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:03:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:03:38 INFO - 'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['damp', 'tps']}, 04:03:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:03:38 INFO - 'tests': ['damp', 'tps']}, 04:03:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['dromaeo_dom']}, 04:03:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:03:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:03:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:03:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:03:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:03:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:03:38 INFO - 'tests': ['a11yr', 04:03:38 INFO - 'ts_paint', 04:03:38 INFO - 'tpaint', 04:03:38 INFO - 'sessionrestore', 04:03:38 INFO - 'sessionrestore_no_auto_restore', 04:03:38 INFO - 'tabpaint']}, 04:03:38 INFO - 'other-e10s': {'tests': ['a11yr', 04:03:38 INFO - 'ts_paint', 04:03:38 INFO - 'tpaint', 04:03:38 INFO - 'sessionrestore', 04:03:38 INFO - 'sessionrestore_no_auto_restore', 04:03:38 INFO - 'tabpaint']}, 04:03:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:03:38 INFO - 'ts_paint', 04:03:38 INFO - 'tpaint', 04:03:38 INFO - 'sessionrestore', 04:03:38 INFO - 'sessionrestore_no_auto_restore', 04:03:38 INFO - 'tabpaint']}, 04:03:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:03:38 INFO - 'ts_paint', 04:03:39 INFO - 'tpaint', 04:03:39 INFO - 'sessionrestore', 04:03:39 INFO - 'sessionrestore_no_auto_restore', 04:03:39 INFO - 'tabpaint']}, 04:03:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:03:39 INFO - 'tests': ['a11yr', 04:03:39 INFO - 'ts_paint', 04:03:39 INFO - 'tpaint', 04:03:39 INFO - 'sessionrestore', 04:03:39 INFO - 'sessionrestore_no_auto_restore', 04:03:39 INFO - 'tabpaint']}, 04:03:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:03:39 INFO - 'tests': ['a11yr', 04:03:39 INFO - 'ts_paint', 04:03:39 INFO - 'tpaint', 04:03:39 INFO - 'sessionrestore', 04:03:39 INFO - 'sessionrestore_no_auto_restore', 04:03:39 INFO - 'tabpaint']}, 04:03:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:03:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:03:39 INFO - 'bloom_basic_ref']}, 04:03:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:03:39 INFO - 'tests': ['tsvgx', 04:03:39 INFO - 'tsvgr_opacity', 04:03:39 INFO - 'tart', 04:03:39 INFO - 'tscrollx', 04:03:39 INFO - 'cart', 04:03:39 INFO - 'tsvg_static']}, 04:03:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:03:39 INFO - 'tsvgr_opacity', 04:03:39 INFO - 'tart', 04:03:39 INFO - 'tscrollx', 04:03:39 INFO - 'cart', 04:03:39 INFO - 'tsvg_static']}, 04:03:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:03:39 INFO - 'talos_options': ['--disable-e10s'], 04:03:39 INFO - 'tests': ['tp5o']}, 04:03:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:03:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:03:39 INFO - 'talos_options': ['--disable-e10s', 04:03:39 INFO - '--xperf_path', 04:03:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:03:39 INFO - 'tests': ['tp5n']}, 04:03:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:03:39 INFO - 'talos_options': ['--xperf_path', 04:03:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:03:39 INFO - 'tests': ['tp5n']}}, 04:03:39 INFO - 'talos.zip': {'path': '', 04:03:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:03:39 INFO - Running post-action listener: _resource_record_post_action 04:03:39 INFO - [mozharness: 2017-05-24 11:03:39.006079Z] Finished populate-webroot step (success) 04:03:39 INFO - [mozharness: 2017-05-24 11:03:39.006224Z] Running create-virtualenv step. 04:03:39 INFO - Running pre-action listener: _resource_record_pre_action 04:03:39 INFO - Running main action method: create_virtualenv 04:03:39 INFO - Creating virtualenv /builds/slave/test/build/venv 04:03:39 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 04:03:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:03:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:03:39 INFO - Using real prefix '/tools/python27' 04:03:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:03:39 INFO - Installing distribute.............................................................................................................................................................................................done. 04:03:42 INFO - Installing pip.................done. 04:03:42 INFO - Return code: 0 04:03:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:03:43 INFO - Reading from file tmpfile_stdout 04:03:43 INFO - Output received: 04:03:43 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) 04:03:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:43 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:43 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 04:03:43 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 04:03:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:43 INFO - 'HOME': '/Users/cltbld', 04:03:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:43 INFO - 'LOGNAME': 'cltbld', 04:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:43 INFO - 'MOZ_NO_REMOTE': '1', 04:03:43 INFO - 'NO_EM_RESTART': '1', 04:03:43 INFO - 'PAGER': '/bin/cat', 04:03:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:43 INFO - 'PWD': '/builds/slave/test', 04:03:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:43 INFO - 'SHELL': '/bin/bash', 04:03:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:43 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:43 INFO - 'USER': 'cltbld', 04:03:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:43 INFO - 'XPC_FLAGS': '0x0', 04:03:43 INFO - 'XPC_SERVICE_NAME': '0', 04:03:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:43 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 04:03:43 INFO - Cleaning up... 04:03:43 INFO - Return code: 0 04:03:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:43 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:43 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 04:03:43 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 04:03:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:43 INFO - 'HOME': '/Users/cltbld', 04:03:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:43 INFO - 'LOGNAME': 'cltbld', 04:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:43 INFO - 'MOZ_NO_REMOTE': '1', 04:03:43 INFO - 'NO_EM_RESTART': '1', 04:03:43 INFO - 'PAGER': '/bin/cat', 04:03:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:43 INFO - 'PWD': '/builds/slave/test', 04:03:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:43 INFO - 'SHELL': '/bin/bash', 04:03:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:43 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:43 INFO - 'USER': 'cltbld', 04:03:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:43 INFO - 'XPC_FLAGS': '0x0', 04:03:43 INFO - 'XPC_SERVICE_NAME': '0', 04:03:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:43 INFO - Downloading/unpacking psutil>=3.1.1 04:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:03:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:03:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:03:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:03:45 INFO - Installing collected packages: psutil 04:03:45 INFO - Running setup.py install for psutil 04:03:45 INFO - building 'psutil._psutil_osx' extension 04:03:45 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 04:03:46 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 04:03:46 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 04:03:46 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 04:03:46 INFO - building 'psutil._psutil_posix' extension 04:03:46 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 04:03:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:03:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:03:46 INFO - ^ 04:03:46 INFO - 1 warning generated. 04:03:46 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 04:03:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:03:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:03:46 INFO - Successfully installed psutil 04:03:46 INFO - Cleaning up... 04:03:46 INFO - Return code: 0 04:03:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:03:46 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')]} 04:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:47 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:47 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 04:03:47 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 04:03:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:47 INFO - 'HOME': '/Users/cltbld', 04:03:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:47 INFO - 'LOGNAME': 'cltbld', 04:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:47 INFO - 'MOZ_NO_REMOTE': '1', 04:03:47 INFO - 'NO_EM_RESTART': '1', 04:03:47 INFO - 'PAGER': '/bin/cat', 04:03:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:47 INFO - 'PWD': '/builds/slave/test', 04:03:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:47 INFO - 'SHELL': '/bin/bash', 04:03:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:47 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:47 INFO - 'USER': 'cltbld', 04:03:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:47 INFO - 'XPC_FLAGS': '0x0', 04:03:47 INFO - 'XPC_SERVICE_NAME': '0', 04:03:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:03:47 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 04:03:47 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 04:03:47 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 04:03:47 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 04:03:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:03:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:03:49 INFO - Installing collected packages: mozsystemmonitor 04:03:49 INFO - Running setup.py install for mozsystemmonitor 04:03:49 INFO - Successfully installed mozsystemmonitor 04:03:49 INFO - Cleaning up... 04:03:49 INFO - Return code: 0 04:03:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:03:49 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')]} 04:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:49 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')]} 04:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:49 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:49 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 04:03:49 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 04:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:49 INFO - 'HOME': '/Users/cltbld', 04:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:49 INFO - 'LOGNAME': 'cltbld', 04:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:49 INFO - 'MOZ_NO_REMOTE': '1', 04:03:49 INFO - 'NO_EM_RESTART': '1', 04:03:49 INFO - 'PAGER': '/bin/cat', 04:03:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:49 INFO - 'PWD': '/builds/slave/test', 04:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:49 INFO - 'SHELL': '/bin/bash', 04:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:49 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:49 INFO - 'USER': 'cltbld', 04:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:49 INFO - 'XPC_FLAGS': '0x0', 04:03:49 INFO - 'XPC_SERVICE_NAME': '0', 04:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:49 INFO - Downloading/unpacking jsonschema==2.5.1 04:03:49 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 04:03:49 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 04:03:49 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 04:03:49 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 04:03:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:03:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:03:51 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 04:03:51 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 04:03:51 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 04:03:51 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 04:03:51 INFO - Downloading functools32-3.2.3-2.tar.gz 04:03:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:03:51 INFO - warning: no files found matching '*.txt' 04:03:51 INFO - no previously-included directories found matching 'build' 04:03:51 INFO - no previously-included directories found matching 'dist' 04:03:51 INFO - no previously-included directories found matching '.git*' 04:03:51 INFO - Installing collected packages: jsonschema, functools32 04:03:51 INFO - Running setup.py install for functools32 04:03:52 INFO - warning: no files found matching '*.txt' 04:03:52 INFO - no previously-included directories found matching 'build' 04:03:52 INFO - no previously-included directories found matching 'dist' 04:03:52 INFO - no previously-included directories found matching '.git*' 04:03:52 INFO - Successfully installed jsonschema functools32 04:03:52 INFO - Cleaning up... 04:03:52 INFO - Return code: 0 04:03:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:52 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:52 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 04:03:52 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 04:03:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:52 INFO - 'HOME': '/Users/cltbld', 04:03:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:52 INFO - 'LOGNAME': 'cltbld', 04:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:52 INFO - 'MOZ_NO_REMOTE': '1', 04:03:52 INFO - 'NO_EM_RESTART': '1', 04:03:52 INFO - 'PAGER': '/bin/cat', 04:03:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:52 INFO - 'PWD': '/builds/slave/test', 04:03:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:52 INFO - 'SHELL': '/bin/bash', 04:03:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:52 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:52 INFO - 'USER': 'cltbld', 04:03:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:52 INFO - 'XPC_FLAGS': '0x0', 04:03:52 INFO - 'XPC_SERVICE_NAME': '0', 04:03:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:03:52 INFO - Cleaning up... 04:03:52 INFO - Return code: 0 04:03:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:52 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:52 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 04:03:52 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 04:03:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:52 INFO - 'HOME': '/Users/cltbld', 04:03:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:52 INFO - 'LOGNAME': 'cltbld', 04:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:52 INFO - 'MOZ_NO_REMOTE': '1', 04:03:52 INFO - 'NO_EM_RESTART': '1', 04:03:52 INFO - 'PAGER': '/bin/cat', 04:03:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:52 INFO - 'PWD': '/builds/slave/test', 04:03:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:52 INFO - 'SHELL': '/bin/bash', 04:03:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:52 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:52 INFO - 'USER': 'cltbld', 04:03:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:52 INFO - 'XPC_FLAGS': '0x0', 04:03:52 INFO - 'XPC_SERVICE_NAME': '0', 04:03:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:52 INFO - Downloading/unpacking blobuploader==1.2.4 04:03:52 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 04:03:52 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 04:03:52 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 04:03:52 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 04:03:54 INFO - Downloading blobuploader-1.2.4.tar.gz 04:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:03:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:03:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:03:54 INFO - Downloading docopt-0.6.1.tar.gz 04:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:03:55 INFO - Installing collected packages: blobuploader, requests, docopt 04:03:55 INFO - Running setup.py install for blobuploader 04:03:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:03:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:03:55 INFO - Running setup.py install for requests 04:03:55 INFO - Running setup.py install for docopt 04:03:55 INFO - Successfully installed blobuploader requests docopt 04:03:55 INFO - Cleaning up... 04:03:55 INFO - Return code: 0 04:03:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:03:55 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')]} 04:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:55 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')]} 04:03:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:55 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:03:55 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 04:03:55 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 04:03:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:03:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:03:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:03:55 INFO - 'HOME': '/Users/cltbld', 04:03:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:03:55 INFO - 'LOGNAME': 'cltbld', 04:03:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:55 INFO - 'MOZ_NO_REMOTE': '1', 04:03:55 INFO - 'NO_EM_RESTART': '1', 04:03:55 INFO - 'PAGER': '/bin/cat', 04:03:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:03:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:55 INFO - 'PWD': '/builds/slave/test', 04:03:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:03:55 INFO - 'SHELL': '/bin/bash', 04:03:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:03:55 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:03:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:03:55 INFO - 'USER': 'cltbld', 04:03:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:03:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:03:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:55 INFO - 'XPC_FLAGS': '0x0', 04:03:55 INFO - 'XPC_SERVICE_NAME': '0', 04:03:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:03:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-dI2qze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-I8sH8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-ySI9OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-hyOM3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-qECt4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-pyZW06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-NkriPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-wYukpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:03:56 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-gWtytr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-sckhym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-ZsL3sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-AULmy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Dcjtwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-2qWTMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-STFasg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-1pAqb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:03:57 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-CmZcsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:03:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:03:57 INFO - Running setup.py install for manifestparser 04:03:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:03:58 INFO - Running setup.py install for mozcrash 04:03:58 INFO - Running setup.py install for mozdebug 04:03:58 INFO - Running setup.py install for mozdevice 04:03:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:03:58 INFO - Running setup.py install for mozfile 04:03:58 INFO - Running setup.py install for mozhttpd 04:03:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:03:58 INFO - Running setup.py install for mozinfo 04:03:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:03:58 INFO - Running setup.py install for mozInstall 04:03:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozleak 04:03:59 INFO - Running setup.py install for mozlog 04:03:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for moznetwork 04:03:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozprocess 04:03:59 INFO - Running setup.py install for mozprofile 04:03:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozrunner 04:03:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:04:00 INFO - Running setup.py install for mozscreenshot 04:04:00 INFO - Running setup.py install for moztest 04:04:00 INFO - Running setup.py install for mozversion 04:04:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:04:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:04:00 INFO - Cleaning up... 04:04:00 INFO - Return code: 0 04:04:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04:00 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')]} 04:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:00 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')]} 04:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:00 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:04:00 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 04:04:00 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 04:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:00 INFO - 'HOME': '/Users/cltbld', 04:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:00 INFO - 'LOGNAME': 'cltbld', 04:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:00 INFO - 'MOZ_NO_REMOTE': '1', 04:04:00 INFO - 'NO_EM_RESTART': '1', 04:04:00 INFO - 'PAGER': '/bin/cat', 04:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:00 INFO - 'PWD': '/builds/slave/test', 04:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:00 INFO - 'SHELL': '/bin/bash', 04:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:04:00 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:00 INFO - 'USER': 'cltbld', 04:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:00 INFO - 'XPC_FLAGS': '0x0', 04:04:00 INFO - 'XPC_SERVICE_NAME': '0', 04:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:04:00 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-azVfkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:04:00 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)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:04:00 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Dx3eay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:04:00 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)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:04:00 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-UQIcFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-WjayGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-oKQxoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-1Igcyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-VcaawE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-MpBG__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-3wcIyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-GOsesz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-v4Ugkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:04:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-bASDGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:04:01 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)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:04:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-4j1D7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:04:02 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)) 04:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:04:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-eb6wbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:04:02 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)) 04:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-T6lvBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:02 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)) 04:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:04:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-FrB1DY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:04:02 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)) 04:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:04:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-npD3xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:04:02 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)) 04:04:02 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)) 04:04:02 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)) 04:04:02 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)) 04:04:02 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:04:02 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:04:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:04:02 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 04:04:02 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 04:04:02 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 04:04:02 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 04:04:03 INFO - Downloading blessings-1.6.tar.gz 04:04:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:04:04 INFO - Installing collected packages: blessings 04:04:04 INFO - Running setup.py install for blessings 04:04:04 INFO - Successfully installed blessings 04:04:04 INFO - Cleaning up... 04:04:04 INFO - Return code: 0 04:04:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:04:04 INFO - Reading from file tmpfile_stdout 04:04:04 INFO - Current package versions: 04:04:04 INFO - blessings == 1.6 04:04:04 INFO - blobuploader == 1.2.4 04:04:04 INFO - docopt == 0.6.1 04:04:04 INFO - functools32 == 3.2.3-2 04:04:04 INFO - jsonschema == 2.5.1 04:04:04 INFO - manifestparser == 1.1 04:04:04 INFO - mozInstall == 1.12 04:04:04 INFO - mozcrash == 1.0 04:04:04 INFO - mozdebug == 0.1 04:04:04 INFO - mozdevice == 0.50 04:04:04 INFO - mozfile == 1.2 04:04:04 INFO - mozhttpd == 0.7 04:04:04 INFO - mozinfo == 0.9 04:04:04 INFO - mozleak == 0.1 04:04:04 INFO - mozlog == 3.4 04:04:04 INFO - moznetwork == 0.27 04:04:04 INFO - mozprocess == 0.25 04:04:04 INFO - mozprofile == 0.28 04:04:04 INFO - mozrunner == 6.13 04:04:04 INFO - mozscreenshot == 0.1 04:04:04 INFO - mozsystemmonitor == 0.3 04:04:04 INFO - moztest == 0.8 04:04:04 INFO - mozversion == 1.4 04:04:04 INFO - psutil == 3.1.1 04:04:04 INFO - requests == 1.2.3 04:04:04 INFO - wsgiref == 0.1.2 04:04:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04:04 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')]} 04:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:04 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')]} 04:04:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:04 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:04:04 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 04:04:04 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 04:04:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:04:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:04 INFO - 'HOME': '/Users/cltbld', 04:04:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:04 INFO - 'LOGNAME': 'cltbld', 04:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:04 INFO - 'MOZ_NO_REMOTE': '1', 04:04:04 INFO - 'NO_EM_RESTART': '1', 04:04:04 INFO - 'PAGER': '/bin/cat', 04:04:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:04 INFO - 'PWD': '/builds/slave/test', 04:04:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:04 INFO - 'SHELL': '/bin/bash', 04:04:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:04:04 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:04:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:04 INFO - 'USER': 'cltbld', 04:04:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:04 INFO - 'XPC_FLAGS': '0x0', 04:04:04 INFO - 'XPC_SERVICE_NAME': '0', 04:04:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 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)) 04:04:04 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:04:04 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 04:04:04 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 04:04:04 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 04:04:04 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 04:04:06 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:04:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:04:06 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)) 04:04:06 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)) 04:04:06 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)) 04:04:06 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)) 04:04:06 INFO - Installing collected packages: marionette-driver 04:04:06 INFO - Running setup.py install for marionette-driver 04:04:06 INFO - Successfully installed marionette-driver 04:04:06 INFO - Cleaning up... 04:04:06 INFO - Return code: 0 04:04:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:04: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')]} 04:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04: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')]} 04:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:06 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 0x109467b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a114baab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, '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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 04:04:06 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 04:04:06 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 04:04:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:04:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:06 INFO - 'HOME': '/Users/cltbld', 04:04:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:06 INFO - 'LOGNAME': 'cltbld', 04:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:06 INFO - 'MOZ_NO_REMOTE': '1', 04:04:06 INFO - 'NO_EM_RESTART': '1', 04:04:06 INFO - 'PAGER': '/bin/cat', 04:04:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:06 INFO - 'PWD': '/builds/slave/test', 04:04:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:06 INFO - 'SHELL': '/bin/bash', 04:04:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:04:06 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:04:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:06 INFO - 'USER': 'cltbld', 04:04:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:06 INFO - 'XPC_FLAGS': '0x0', 04:04:06 INFO - 'XPC_SERVICE_NAME': '0', 04:04:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:04:06 INFO - Cleaning up... 04:04:06 INFO - Return code: 0 04:04:06 INFO - Running post-action listener: _resource_record_post_action 04:04:06 INFO - Running post-action listener: _start_resource_monitoring 04:04:06 INFO - Starting resource monitoring. 04:04:06 INFO - [mozharness: 2017-05-24 11:04:06.873468Z] Finished create-virtualenv step (success) 04:04:06 INFO - [mozharness: 2017-05-24 11:04:06.874434Z] Running install step. 04:04:06 INFO - Running pre-action listener: _resource_record_pre_action 04:04:06 INFO - Running main action method: install 04:04:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:04:07 INFO - Reading from file tmpfile_stdout 04:04:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:04:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:04:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:04:07 INFO - Reading from file tmpfile_stdout 04:04:07 INFO - Output received: 04:04:07 INFO - Usage: mozinstall [options] installer 04:04:07 INFO - Options: 04:04:07 INFO - -h, --help show this help message and exit 04:04:07 INFO - -d DEST, --destination=DEST 04:04:07 INFO - Directory to install application into. [default: 04:04:07 INFO - "/builds/slave/test"] 04:04:07 INFO - --app=APP Application being installed. [default: firefox] 04:04:07 INFO - mkdir: /builds/slave/test/build/application 04:04:07 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'] 04:04:07 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 04:04:28 INFO - Reading from file tmpfile_stdout 04:04:28 INFO - Output received: 04:04:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:04:28 INFO - Running post-action listener: _resource_record_post_action 04:04:28 INFO - [mozharness: 2017-05-24 11:04:28.810696Z] Finished install step (success) 04:04:28 INFO - [mozharness: 2017-05-24 11:04:28.810860Z] Running run-tests step. 04:04:28 INFO - Running pre-action listener: _resource_record_pre_action 04:04:28 INFO - Running main action method: run_tests 04:04:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:04:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:04:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:04:28 INFO - 2017-05-24 04:04:28.825 screenresolution[1627:7330] starting screenresolution argv=screenresolution get 04:04:28 INFO - 2017-05-24 04:04:28.828 screenresolution[1627:7330] Display 0: 1600x1200x32@60 04:04:28 INFO - 2017-05-24 04:04:28.833 screenresolution[1628:7332] starting screenresolution argv=screenresolution list 04:04:28 INFO - Available Modes on Display 0 04:04:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:04:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:04:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:04:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:04:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:04:29 INFO - Intel Iris: 04:04:29 INFO - Chipset Model: Intel Iris 04:04:29 INFO - Type: GPU 04:04:29 INFO - Bus: Built-In 04:04:29 INFO - VRAM (Dynamic, Max): 1536 MB 04:04:29 INFO - Vendor: Intel (0x8086) 04:04:29 INFO - Device ID: 0x0a2e 04:04:29 INFO - Revision ID: 0x0009 04:04:29 INFO - Displays: 04:04:29 INFO - Display: 04:04:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:04:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:04:29 INFO - Display Serial Number: 1600x1200 60 04:04:29 INFO - Main Display: Yes 04:04:29 INFO - Mirror: Off 04:04:29 INFO - Online: Yes 04:04:29 INFO - Rotation: Supported 04:04:29 INFO - Return code: 0 04:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:04:29 INFO - Python 2.7.3 04:04:29 INFO - Return code: 0 04:04:29 INFO - grabbing minidump binary from tooltool 04:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:29 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 0x109177d30>, '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 0x10912bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10916cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:04:29 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 04:04:29 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 04:04:29 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 04:04:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:04:29 INFO - Return code: 0 04:04:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:04:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:04:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:04:29 INFO - ENV: RUST_BACKTRACE is now 1 04:04:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:04:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--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 04:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0225 --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 04:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FfvkUlX0fh/Render', 04:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:04:29 INFO - 'HOME': '/Users/cltbld', 04:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:04:29 INFO - 'LOGNAME': 'cltbld', 04:04:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:04:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:29 INFO - 'MOZ_NO_REMOTE': '1', 04:04:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:04:29 INFO - 'NO_EM_RESTART': '1', 04:04:29 INFO - 'PAGER': '/bin/cat', 04:04:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:29 INFO - 'PWD': '/builds/slave/test', 04:04:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:04:29 INFO - 'RUST_BACKTRACE': '1', 04:04:29 INFO - 'SHELL': '/bin/bash', 04:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FY5XdxTQIQ/Listeners', 04:04:29 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 04:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:04:29 INFO - 'USER': 'cltbld', 04:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:29 INFO - 'XPC_FLAGS': '0x0', 04:04:29 INFO - 'XPC_SERVICE_NAME': '0', 04:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:04:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--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 04:04:29 INFO - mozversion application_buildid: 20170524022623 04:04:29 INFO - mozversion application_changeset: ffaa07672466b06cd748b07a34cf95377afdde41 04:04:29 INFO - mozversion application_display_name: Nightly 04:04:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:04:29 INFO - mozversion application_name: Firefox 04:04:29 INFO - mozversion application_remotingname: firefox 04:04:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:04:29 INFO - mozversion application_vendor: Mozilla 04:04:29 INFO - mozversion application_version: 55.0a1 04:04:29 INFO - mozversion platform_buildid: 20170524022623 04:04:29 INFO - mozversion platform_changeset: ffaa07672466b06cd748b07a34cf95377afdde41 04:04:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:04:29 INFO - mozversion platform_version: 55.0a1 04:04:29 INFO - using testdate: 1495623869 04:04:29 INFO - actual date: 1495623869 04:04:29 INFO - starting webserver on 'localhost:49228' 04:04:29 INFO - SUITE-START | Running 2 tests 04:04:29 INFO - TEST-START | dromaeo_css 04:04:29 INFO - operating with platform_type : mac_ 04:04:29 INFO - Initialising browser for dromaeo_css test... 04:04:29 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpa8fwMr/profile 04:04:29 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 04:05:02 INFO - TEST-INFO | 1638: killed by SIGTERM 04:05:02 INFO - Browser initialized. 04:05:02 INFO - Running cycle 1/1 for dromaeo_css test... 04:05:02 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpa8fwMr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:04 INFO - PID 1648 | 2017-05-24 04:05:04.694 plugin-container[1649:7893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 04:05:04 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:05:04 INFO - PID 1648 | 2017-05-24 04:05:04.696 plugin-container[1649:7893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:05:04 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:05:04 INFO - PID 1648 | 2017-05-24 04:05:04.696 plugin-container[1649:7893] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:05:05 INFO - PID 1648 | 2017-05-24 04:05:05.418 plugin-container[1650:7937] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 04:05:05 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:05:05 INFO - PID 1648 | 2017-05-24 04:05:05.418 plugin-container[1650:7937] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 04:05:05 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:05:05 INFO - PID 1648 | 2017-05-24 04:05:05.418 plugin-container[1650:7937] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:05:14 INFO - PID 1648 | RSS: Main: 248651776 04:05:14 INFO - PID 1648 | 04:08:16 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 04:08:18 INFO - PID 1648 | RSS: Main: 260100096 04:08:18 INFO - PID 1648 | 04:11:20 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 04:11:21 INFO - PID 1648 | RSS: Main: 274571264 04:11:21 INFO - PID 1648 | 04:14:22 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 04:14:23 INFO - PID 1648 | RSS: Main: 273661952 04:14:23 INFO - PID 1648 | 04:17:25 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 04:17:25 INFO - PID 1648 | RSS: Main: 277999616 04:17:25 INFO - PID 1648 | 04:20:28 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 04:20:29 INFO - PID 1648 | RSS: Main: 278155264 04:20:29 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 04:23:30 INFO - PID 1648 | RSS: Main: 280006656 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | __start_tp_report 04:23:30 INFO - PID 1648 | _x_x_mozilla_page_load 04:23:30 INFO - PID 1648 | _x_x_mozilla_page_load_details 04:23:30 INFO - PID 1648 | |i|pagename|runs| 04:23:30 INFO - PID 1648 | |0;dojo.html;2860.213441303641;3575.249197668488;3582;3597.2625611749595;3623.384024715798;6145.2178868128185;6208.441240288374;6268.965620672583;6272.310045894954;6287.08837218603;6177.752889884399;6221.533384996121;6231.968840155804;6249.4687951524065;6267.874642507147;5235.869103272411;5421.728913554316;5426.83719488414;5452.236686863842;5492.972535137339;5477.835664930039;5514.3658479275;5528.723563821813;5533;5577;5368.4363141869935;5411.783528658849;5434.99999999998;5451.236826844245;5552.472515111099;5463.180522921554;5471.480209380143;5503.587230957694;5508.063629183049;5537.916931246034;5419.5393391561765;5447.428020057877;5455.5908306877145;5457.099578569531;5494.230807686927;6145.924463218964;6190.876182476371;6238.781642642486;6289.622622642656;6305.684715764193;6613.900791488131;6707.7652282170375;6717.42901853343;6727.730890764364;6745.392914637706;6782.186137663462;6855.622940738257;6882.139732533434;6892.035115083842;6914.654267286666;6275.278342990523;6334.809955701337;6376.7130479128555;6404.135441715402;6405.910995130839;6799.966000169968;6894.862102757917;6949.687264073129;6983.790486285419;7017.157941047107;6244.313125556185;6275.341089185661;6448.258450278185;6459.160309159779;6631.0716499689115;6730.461563074942;6834.624095674786;6834.8722460793415;6845.041694162821;6845.5550389224545;5965.582409231312;6465.321141280192;6698.8995165072565;6700.597964122168;6743.696533655997;5442.265294185314;6498.837529061717;6622.9006564901565;6658.4340331071435;6726.226483954408;5377.247185394048;5377.354717433933;5388.46115388458;5390.9191362129595;5432.972835135799;4693.389859318335;4748.050389922029;4754.072955773591;4763.880902977384;4783.306420568998;5282.31329927107;6236.656983865931;6283.246010478681;6322.525810564226;6363.204599425072;6202.34875338083;6877;6881.552699074644;6894.069300644449;6897.62063086525;4662.6269898408045;6251.749930002749;6286.868363694579;6348.841278968062;6360.936390636034;4771.5228477152705;5099.923501147486;5901.380355062743;5970.925233457932;6008.038713805858;5672;6919.342662447059;6941.201761797458;6974.6861391238535;6982.1272340957385;6871.896921546081;6877.931220687929;6888.483363747639;6893.896591551231;6914.9308506916295;6751.358620931003;6932.376086152271;6963.825904352431;6987.09167808202;6989.580625162506;6584.604923704593;6604.63674497888;6707.262201157771;6710.228323742636;6729.1252137221845;6863.245043045161;6871.862562748817;6874.2782007890455;6874.6562671867205;6887.035814986006;6426.967865160645;6480;6512.251091124582;6631.635260060743;6672.633005184762;6644.767433139817;6657.167854018247;6664.933350666432;6667.19993600771;6694.799156025327;6443.838904027437;6514.837129071811;6542.640154791532;6548.116004339353;6549.443297319781;6390.4248617624635;6447.70985305653;6666.466682665278;6684.565503242274;6702.530822842355;6387.55287129915;6416.069669897745;6429.196350456193;6459.903101453576;6462.579932304385 04:23:30 INFO - PID 1648 | |1;ext.html;768.55039801716;809.0897740042452;825.5046971816911;1237.857646370667;1302.719915218228;38847.611523884756;39241;39301.60698393015;39325.80337098318;39340.21319573607;39094.82715518539;39217;39360.40959385604;39466.85598012203;39502.60497395025;7698.34564062054;7870.685172593089;8288.58557072147;8448.619812108469;8488.575571221414;8301.252887240147;8374.539400332978;8416.116307787686;8429.831403371929;8434.831303373929;8379.45533540321;8416.579171041454;8465.280451161658;8473.152684731509;8479.19447652472;8301.128381519913;8388.035375931755;8419.789505262355;8424.368172387034;8449.070602233749;8209.179082091803;8283.668653253862;8297.294729947962;8305.086440491541;8355.582220888931;36955;37174.07064823374;37292.62707372946;37338.62661373406;37401.064973375614;3505.141240396094;3518.6305437928904;3530.6116327203986;3541.097020259843;3544.3620148373284;24363.512729745482;30063.549046764318;30394.696053039406;30470.695293047007;30481.780728770824;2954.734073933335;2994.8203107813383;2999.7750168737434;3019.6980301969847;3026.6216722909635;223907.88046059664;239015;276525;294004;294952;47216;47597.76201119042;47642.5235747645;47766.761166193945;47883.521164787904;49162.55862089691;49246;49409;49653.25520117201;49733.50266497361;13913.443462261599;14031.719365612631;14137;14143.151410915172;14155.716885662228;11408.714782130513;11464.25482343645;11499.712507187387;11500.309981401142;11548.307101573933;12007.919287264189;13330.53343133005;13361.66595835112;13395.464181432633;13541.39063742134;5818.301803783488;6204.627722336674;6255.218097737783;6256.655883926337;6256.655883926428;889.4974339498162;1014.4623349624711;1022.6501018655304;1034.9793004139874;1066.8826429092794;23754.524909501713;23921.880390598282;24108;24178.637320440208;24240.1515946941;1119.3172164979355;1119.6696974392573;1147.225622704671;1150.4477850631743;1153.4866984191954;12028.195770634475;12353.444095015746;12357.75284494305;12377.443015064344;12389.504419823286;293982;294686;296538;297164;298049;56231.15653265204;56594.717026414604;56600.433995659514;56607.86784264292;56661.43338566644;1489.255372313843;1514.5002149290708;1517.4082107977897;1519.3694616734153;1523.6800271943016;27973;28819.855900720362;29882.551761724026;29954.85022574873;30282.54576181403;37073.629263707015;37184.62815371811;37314.44028339631;37416.2516749658;37462;280112;282583;283376;284012.579937099;284177;33398.332033359686;34484.65515344815;34978.825105874304;35054.824725876206;35123.47314790229;42383;42649.78675106604;44395.556044439145;44422.777886110365;44451;29539.409211816073;29860;30463.238419039702;30587.54118688266;30911.845440772653;24875.87562062178;25169.748302516742;25353.366165845262;30018;30519.84740076374;36788.26423471462;36788.44817327688;36805.26389472142;36831.63168368389;36839.26321473609;28308.009219677213;28732.85633571819;30732.84633576818;30879.69120308858;31074.689253108085;23022.07911683514;23653.172138975053;24041.879790601633;24103.879480602485;24184 04:23:30 INFO - PID 1648 | |2;jquery.html;406.8657343076785;408.88346821155966;422.78437996621716;423.9639630631396;452.0461825548093;19086;21173.788262117374;21488.247911323087;22045.11819527219;22925.85370731465;21759.45601359967;22752.544949101008;22874;22955;23050.65424018641;5346.492083252104;5893.26334208225;6171.4445699887;6224.782132625359;6239.625622462644;5894.086416605433;6039.788607398742;6050.394960503936;6079.331273559927;6090.939090609104;5970.701464926737;6019.217501724792;6022.247219097613;6034.4568988791;6034.939650603482;5878.5297176225795;6095.908561371583;6124.6325220486915;6158.815235542941;6161.322254551996;5424.484673955968;5483.122700367961;5491.560675145979;5491.780328786844;5497.340319161686;27553.448931021267;28212;28388.574171387445;28622.570661440095;28772.856135719187;2143.95712085759;2230.9665355019524;2233.095596283508;2234.128689810975;2234.452559123009;17937.910310448493;17973;17983.910080449645;18169.273229070823;18293.810902291443;2145.013293884815;2147.8389120816;2180.6728990651372;2194.3307291276155;2198.428408613756;162399;164858.1757091219;166301;166795;167257;12666.303353315472;12727.299998499986;12981.610551683465;13023.609291721263;13066.281354525592;16311.102889340962;16573.83426165747;16577.17114144312;16604.003759774452;16607.50177494677;7489.925100748923;8682.565871706389;9018.59416326267;9154.221891139328;9177.311701622404;8483.642617181222;8505.61724722389;8506.276966457921;8705.477671339613;8707.216350528483;7866.292033716993;8009.279164875189;8019.318357939641;8040.638171282307;8055.033395992401;2164.7943445372666;2166.1118941234017;2169.2516081951703;2188.0591345721173;2347.1667558016998;922.9723108306762;928.7863791328033;950.3680052764834;987.2595553334999;987.4223579206244;20265.797342026686;20970;21031;21036.26373076935;21161.25935592247;991.8147813362962;1000.2048371544639;1001.3191030099457;1017.8320577104843;1025.8666373371414;9582.66460673873;10187.134093602126;10287.331323463952;10753.247272690836;10797.24419290642;530266;530605;531228;537960;538133;129541;131447.51248631364;133624.33187834;134461;135618;1183.248637115438;1215.6596153077153;1237.3998610673996;1239.1821397877538;1243.042857000105;21138.57722845565;22212.111515539844;22444.32667019992;22464.8767561618;22530.436739081655;47261.29108063313;49465.75267123641;53630.73184634052;57534.7123264381;57699;74279.51440971259;78405;78994;80216;80350;8649.091845356399;8720.345974051845;8925.196732294125;9249.907500925174;9295.628174872929;14068.08557443763;14229.786553201504;14274.71450570962;14601.634959126106;14637.85362146365;11964.521419143137;11971.700707482034;12078.818817717565;12216.26702397859;12225.266484010988;11200.663980080612;11208.27146235492;11555.942220288845;11560.537578496765;11567.652970410902;11846.763064738829;11960.880391195977;12223;12304.692382690504;12312.50749970027;8752.299816014576;8753.25597324209;8853.29173666118;9150.267978561566;9193.494357810117;11714.414279285831;11860.584879529175;12103.334316612318;12142.75714485723;12196.634100976986 04:23:30 INFO - PID 1648 | |3;mootools.html;1205.4575441051527;1421.4740545997981;1428.0003997201954;1431.5132854829355;1441.6756229848281;11337.433128343591;11383.430828458575;11852.585159519418;11857.46641401137;12134.39328033597;11838.7632247355;11973.940130299336;11994.400279985988;11994.520219191243;12013.399330033486;2532.227670560483;2562.7949764018927;2587.79297656188;2591.1319707897783;2592.494463579603;2477.318737347226;2528.962065569018;2535.036686059295;2536.251805717318;2539.4540173862615;2513.1204078572537;2513.472170844125;2559.4881023795224;2565.6536367590415;2571.3828681116584;2509.9749002509925;2526.431552900601;2541.4535874786916;2564.4101856572906;2573.716891141973;2474.270090323341;2504.474060447308;2527.721950585434;2578.5487539680666;2579.3938424470234;9764.267679924034;11789.469473873696;11809.114316426303;11811.173217874752;11837.053035757119;6499.35006499351;6668.566543174727;6767.458603311772;6775.390214880685;6789.558678685869;3934.7639141651307;4261.339492378686;4279.97860010698;4288.270993931008;4335.3063509838275;1185.2532904270254;1188.5780547905456;1228.5945637939458;1229.6802831263847;1234.703671118934;15052.698946021019;15421.537353879514;16126.435574754945;16181.757273640906;16285.185740712917;5327.200919862052;5466;5647.915281270784;5651.180578816049;5665.5467562594895;11727.589534366256;11739.647810565697;11757.059435245159;11788.174827761974;11807.527698891949;6422.678866056678;6470.870582588416;6500.382463665944;6516.6415847128865;6520.0871877937125;6103.206583144163;6160.559329597822;6224.595401298901;6259.280182779039;6266.655333956585;4246.575342465729;4381.16757816014;4417;4423.336499525097;4435.82256709728;1124.3815901254277;1234.1176059117774;1257.7798885195236;1265.2724683307135;1270.834791477102;687.388224480213;767.5893397032672;788.4638445856758;791.6674996501483;807.4146243973073;6177.289611694712;6256.436920677161;6265.937340626627;6357.777477788255;6399.456046236122;587.3891153200709;590.094205394717;610.6031079798078;613.2793967088653;613.349849159892;3976.443297938291;4553.635709143261;4624.884377890579;4703.7883295251795;4749.572538471554;11629.825552616718;14769.409223630935;14776.704465910621;14787.778183327258;14858.628534286729;13010.21938683682;13965.79051314231;14628;14869.776953345709;14873.958822882294;8489.61796719149;8619.870701939475;8704.173103555153;8714.869276960851;8740.694075707446;6719.193696756225;6974.819247070556;6975.1978522468535;6999.475039371966;7055.223925368103;13126.803097953347;13160.47358105665;13265.734685306434;13268.867311326763;13465.932670336586;13677.931610341884;13725.107867988549;13830.446782128602;13865.584032479042;13967.511137110152;9167.312451566026;9317.11487408695;9416.152546270594;9454.33819632619;9457.148856602938;10437.791244175225;10560.706578211126;10639.308444951053;10657.840132397865;10682.03861652455;8597.26923211534;9019.81960360776;9056.320775941698;9090.772730681785;9103.681371151977;7713.768586942177;8923.196912277926;8923.464592124234;8925.776855578662;8984.820303594022;7027.754028608974;8124.796880078045;8124.918750812652;8263.917360826556;8268.627911744106;5979.617872336174;6017.127516510169;6101.450869421773;6179.598326108789;6230.940740074107;6056.333803281547;6185.041318595625;6277.027060805583;6287.685615719104;6317.873642527215 04:23:30 INFO - PID 1648 | |4;prototype.html;344.51595508310817;391.8323396279087;395.0912900329243;397.87268074216246;403.46742300163794;6247.6251424914535;6366.172397588312;6405.605029334454;6454.419102280794;6455.128012876266;6034.469835146445;6233.40782625651;6242.656653884034;6253.280872699641;6263.874722505547;3735.719821013421;4546.772661366935;4556.817727290904;4564.292534657134;4564.9087018259615;3355.748318876082;4342.782860856961;4424.093060922519;4487.708298960557;4521.5930566249035;3894.922101557967;4479.193745125876;4485.618722408599;4485.842995495158;4489.551044895501;4235.470566179228;4509.706869053501;4513.368128461985;4514.909701805978;4515.841945531907;3766.5291838520184;3824.808759562011;3845.327067763158;3872.283627528888;3880.844766209348;6035.827102243189;6105.084237364386;6109.663968481732;6115.266168059817;6128.448439640454;4528.275475923836;5429.6742195468005;5444.482774136451;5455.699936503491;5512.503874651301;6400.679966001728;6668.833279168011;6702.296258892795;6731.562448440835;6748.561343512656;3927.2341893330813;4210.926213815488;4411.889702757425;4428.756418397019;4431.0251744616135;5488.4785945335125;6702.798916032527;7016.877299632034;7074.858502829915;7082.291770822928;5015.322931404214;5039.294498770175;5072.923906141411;5073.568746656576;5082.847514574569;5164.8967020659375;5179.300794392784;5204.34945631796;5206.245094461282;5220.347456567929;5624.718764061862;5673.971630141823;5722.370539240764;5759.827205183851;5809.796657116981;6793.932060679429;6802.829929251809;6835.69239384219;6842.794716158523;6842.863142737117;1576.022865823197;1613.3707853936974;1756.2448147296784;1770.9197389592339;1779.5817982774038;1090.506006770729;1213.5691829400437;1230;1236.3076677060874;1241.7268200995948;5584.860378490489;5828.417158284137;6054.969725151346;6110.725017374229;6385.776497822554;6881.483888708367;6956.686949087202;6973.755918542826;6977.82555436108;7005.824854378579;6103.389661033861;6311.337309582428;6340;6365.681715914278;6404.007378856285;3340.6325304216516;3464.4110501214855;3613.2412193439673;3667.9266414671556;3675.6691897729334;6356.841078973062;6547.508936829757;7000.194977577542;7152.249013853574;7159.606221657858;5959.404059594006;6342.778002769973;6924.480663950123;7015.579065256101;7162.242536623437;6209.937900620936;6877.65611719402;7174.919273410653;7175.425965922714;7229.74695885652;5549.972250138723;6851.314868513109;7029.894551581628;7039.436845052281;7084.929150708427;6860.245373009065;6868.484863635147;6875.243723190545;6878.69045892926;6904.723811047502;6964.930350696631;7142.214356420894;7184.281571842774;7185.317394847481;7224.096987876515;4453.977730111458;4461.866144015685;4462.732236065716;4472.546422412794;4492.550744925612;6887.7244910203035;6996.720131194696;7001.579905205704;7018.298170182941;7032.402245809163;5533;6595.208574971034;6652.767153149616;6715.832104197434;6721.193456785217;5946.405359464019;6697.698603562949;6699.39705426514;6709.530332876847;6712.39588437043;6296.748130074746;6502.414782669393;6547.803565893031;6547.9672601636685;6594.899717760956;5600.467955544217;6688.163979502562;6736.32636736342;6737.932620673927;6761.763338283136;6343.207099112611;6356.2054743157105;6501.642409667514;6529.575577587631;6539.901901471387 04:23:30 INFO - PID 1648 | |5;yui.html;827.424939666931;838.8741688746687;855.6106971328046;862.3101518784974;869.6086760957571;16466.752998705015;16914.23885925133;16926.5768355791;18688.15903284352;19244;16773.748393774073;16989.745153822703;17755.28978840845;18269.177886995083;18302;991.1921783746242;1006.7936073105033;1010.7927874785688;1018.7860549284659;1020.015684864106;1019.2406657040515;1022.3405903192437;1023.1150055202271;1024.109025148122;1026.856240126383;1055.2349546578746;1080.400377790326;1082.3722241100181;1083.8753543342539;1088.0588291128156;1079.1690398393232;1082.3938594387168;1085.9954542048574;1089.831076183189;1090.547422819529;701.989470157943;772.5673622771266;783.0212234706617;784.1766145547152;785.8821176823465;16964.151792410332;17123.74314385285;17173.31306747741;17317.393891213873;17323.740143897852;15562.299696513684;16659.583510412216;17382.739258911126;17849.910750446164;17855.910720446445;13945.721085578232;14156;14428.134311941318;14463.566093017227;14477.34851931655;3473.201163732356;3523.365794157051;3539.8584056637706;3541.1324225564645;3551;91280.54359728159;114171;119214.4039279798;119915;120614;13556.525521606696;13818.239996800072;13833;13856.861431385558;13865.653358666113;21048.473788155417;24869.129580464596;26240.34399140037;26247;26273.080442184433;6151.384861513813;7781.8832717509285;8010.5994700264755;8035.638396272182;8036.276735093753;3933.213357328546;4008.819603117867;4010.7794071326357;4012.0371110933747;4012.4382586437923;4394.077243778778;4680.765961701969;4796.976015119972;4808.951910480921;4844.903101937942;954.7517645412242;977.8777652793401;987.629638885418;993.7614972406715;994.945278009702;629.2166253015025;657.6580178307254;666.4335314982226;671.4225765841372;684.1550684904139;11101.722456938642;11756.58851940178;11988.580399685969;12079.396030198455;12171.208871423329;570.1903297317818;637.7959053103056;639.9552031357761;648.5103746671233;673.2695025896957;5419.864503387447;5868.589198756046;6027.126066720389;6051.152838602599;6073.908891366633;105666.4716676427;106251;107084;107486.46256768823;108231;91288;91890.54054729684;92210;93547.0645293552;94345;73955.26044739483;73965.63017184987;74653;74677.25322746811;75337.62331188309;12110.273383597012;12436.564720234748;12451.128421010442;12470.875291246972;12498.437570309175;40688.3896741555;41404.58595414007;41442.1711565761;41647.37528937008;41660.37509437421;49624.255636164766;49965.75017124891;49974.00103995799;50268.743281418254;50853.49146508636;16280.511584652479;16307.59231019234;16346.591335216715;16600.916995414944;16659.833401665826;18179.54551136232;18521.72217416767;18555.814441855407;18607.534811629277;18756.718649220544;16395.83604163943;16842.831571684128;16942.40701575439;17064.57338566546;17133.400330988356;13985.650358740706;16215.35138594443;16401.91799041045;16476.588085297484;16699.24853381625;12996.545120920722;13763.449462021406;13957;16420.589485262964;16446.588835279214;11692.47383867745;11741.530338786695;11951.22317049389;12069.034477241976;12109.878901210875;12037.638870833889;12359.32023738667;12859.228446293253;12920.612381628565;12968.546100886424 04:23:30 INFO - PID 1648 | __end_tp_report 04:23:30 INFO - PID 1648 | __start_cc_report 04:23:30 INFO - PID 1648 | _x_x_mozilla_cycle_collect,404 04:23:30 INFO - PID 1648 | __end_cc_report 04:23:30 INFO - PID 1648 | __startTimestamp1495625010947__endTimestamp 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | ------- Summary: start ------- 04:23:30 INFO - PID 1648 | Number of tests: 6 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6175.20 Median:6405.91 stddev:778.55 (12.2%) stddev-sans-first:736.22 04:23:30 INFO - PID 1648 | Values: 2860.2 3575.2 3582.0 3597.3 3623.4 6145.2 6208.4 6269.0 6272.3 6287.1 6177.8 6221.5 6232.0 6249.5 6267.9 5235.9 5421.7 5426.8 5452.2 5493.0 5477.8 5514.4 5528.7 5533.0 5577.0 5368.4 5411.8 5435.0 5451.2 5552.5 5463.2 5471.5 5503.6 5508.1 5537.9 5419.5 5447.4 5455.6 5457.1 5494.2 6145.9 6190.9 6238.8 6289.6 6305.7 6613.9 6707.8 6717.4 6727.7 6745.4 6782.2 6855.6 6882.1 6892.0 6914.7 6275.3 6334.8 6376.7 6404.1 6405.9 6800.0 6894.9 6949.7 6983.8 7017.2 6244.3 6275.3 6448.3 6459.2 6631.1 6730.5 6834.6 6834.9 6845.0 6845.6 5965.6 6465.3 6698.9 6700.6 6743.7 5442.3 6498.8 6622.9 6658.4 6726.2 5377.2 5377.4 5388.5 5390.9 5433.0 4693.4 4748.1 4754.1 4763.9 4783.3 5282.3 6236.7 6283.2 6322.5 6363.2 6202.3 6877.0 6881.6 6894.1 6897.6 4662.6 6251.7 6286.9 6348.8 6360.9 4771.5 5099.9 5901.4 5970.9 6008.0 5672.0 6919.3 6941.2 6974.7 6982.1 6871.9 6877.9 6888.5 6893.9 6914.9 6751.4 6932.4 6963.8 6987.1 6989.6 6584.6 6604.6 6707.3 6710.2 6729.1 6863.2 6871.9 6874.3 6874.7 6887.0 6427.0 6480.0 6512.3 6631.6 6672.6 6644.8 6657.2 6664.9 6667.2 6694.8 6443.8 6514.8 6542.6 6548.1 6549.4 6390.4 6447.7 6666.5 6684.6 6702.5 6387.6 6416.1 6429.2 6459.9 6462.6 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:43615.08 Median:32236.51 stddev:73869.17 (229.1%) stddev-sans-first:74006.34 04:23:30 INFO - PID 1648 | Values: 768.6 809.1 825.5 1237.9 1302.7 38847.6 39241.0 39301.6 39325.8 39340.2 39094.8 39217.0 39360.4 39466.9 39502.6 7698.3 7870.7 8288.6 8448.6 8488.6 8301.3 8374.5 8416.1 8429.8 8434.8 8379.5 8416.6 8465.3 8473.2 8479.2 8301.1 8388.0 8419.8 8424.4 8449.1 8209.2 8283.7 8297.3 8305.1 8355.6 36955.0 37174.1 37292.6 37338.6 37401.1 3505.1 3518.6 3530.6 3541.1 3544.4 24363.5 30063.5 30394.7 30470.7 30481.8 2954.7 2994.8 2999.8 3019.7 3026.6 223907.9 239015.0 276525.0 294004.0 294952.0 47216.0 47597.8 47642.5 47766.8 47883.5 49162.6 49246.0 49409.0 49653.3 49733.5 13913.4 14031.7 14137.0 14143.2 14155.7 11408.7 11464.3 11499.7 11500.3 11548.3 12007.9 13330.5 13361.7 13395.5 13541.4 5818.3 6204.6 6255.2 6256.7 6256.7 889.5 1014.5 1022.7 1035.0 1066.9 23754.5 23921.9 24108.0 24178.6 24240.2 1119.3 1119.7 1147.2 1150.4 1153.5 12028.2 12353.4 12357.8 12377.4 12389.5 293982.0 294686.0 296538.0 297164.0 298049.0 56231.2 56594.7 56600.4 56607.9 56661.4 1489.3 1514.5 1517.4 1519.4 1523.7 27973.0 28819.9 29882.6 29954.9 30282.5 37073.6 37184.6 37314.4 37416.3 37462.0 280112.0 282583.0 283376.0 284012.6 284177.0 33398.3 34484.7 34978.8 35054.8 35123.5 42383.0 42649.8 44395.6 44422.8 44451.0 29539.4 29860.0 30463.2 30587.5 30911.8 24875.9 25169.7 25353.4 30018.0 30519.8 36788.3 36788.4 36805.3 36831.6 36839.3 28308.0 28732.9 30732.8 30879.7 31074.7 23022.1 23653.2 24041.9 24103.9 24184.0 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:35607.14 Median:22497.66 stddev:91256.33 (405.6%) stddev-sans-first:91474.06 04:23:30 INFO - PID 1648 | Values: 406.9 408.9 422.8 424.0 452.0 19086.0 21173.8 21488.2 22045.1 22925.9 21759.5 22752.5 22874.0 22955.0 23050.7 5346.5 5893.3 6171.4 6224.8 6239.6 5894.1 6039.8 6050.4 6079.3 6090.9 5970.7 6019.2 6022.2 6034.5 6034.9 5878.5 6095.9 6124.6 6158.8 6161.3 5424.5 5483.1 5491.6 5491.8 5497.3 27553.4 28212.0 28388.6 28622.6 28772.9 2144.0 2231.0 2233.1 2234.1 2234.5 17937.9 17973.0 17983.9 18169.3 18293.8 2145.0 2147.8 2180.7 2194.3 2198.4 162399.0 164858.2 166301.0 166795.0 167257.0 12666.3 12727.3 12981.6 13023.6 13066.3 16311.1 16573.8 16577.2 16604.0 16607.5 7489.9 8682.6 9018.6 9154.2 9177.3 8483.6 8505.6 8506.3 8705.5 8707.2 7866.3 8009.3 8019.3 8040.6 8055.0 2164.8 2166.1 2169.3 2188.1 2347.2 923.0 928.8 950.4 987.3 987.4 20265.8 20970.0 21031.0 21036.3 21161.3 991.8 1000.2 1001.3 1017.8 1025.9 9582.7 10187.1 10287.3 10753.2 10797.2 530266.0 530605.0 531228.0 537960.0 538133.0 129541.0 131447.5 133624.3 134461.0 135618.0 1183.2 1215.7 1237.4 1239.2 1243.0 21138.6 22212.1 22444.3 22464.9 22530.4 47261.3 49465.8 53630.7 57534.7 57699.0 74279.5 78405.0 78994.0 80216.0 80350.0 8649.1 8720.3 8925.2 9249.9 9295.6 14068.1 14229.8 14274.7 14601.6 14637.9 11964.5 11971.7 12078.8 12216.3 12225.3 11200.7 11208.3 11555.9 11560.5 11567.7 11846.8 11960.9 12223.0 12304.7 12312.5 8752.3 8753.3 8853.3 9150.3 9193.5 11714.4 11860.6 12103.3 12142.8 12196.6 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:7054.06 Median:4111.51 stddev:4396.97 (106.9%) stddev-sans-first:4387.33 04:23:30 INFO - PID 1648 | Values: 1205.5 1421.5 1428.0 1431.5 1441.7 11337.4 11383.4 11852.6 11857.5 12134.4 11838.8 11973.9 11994.4 11994.5 12013.4 2532.2 2562.8 2587.8 2591.1 2592.5 2477.3 2529.0 2535.0 2536.3 2539.5 2513.1 2513.5 2559.5 2565.7 2571.4 2510.0 2526.4 2541.5 2564.4 2573.7 2474.3 2504.5 2527.7 2578.5 2579.4 9764.3 11789.5 11809.1 11811.2 11837.1 6499.4 6668.6 6767.5 6775.4 6789.6 3934.8 4261.3 4280.0 4288.3 4335.3 1185.3 1188.6 1228.6 1229.7 1234.7 15052.7 15421.5 16126.4 16181.8 16285.2 5327.2 5466.0 5647.9 5651.2 5665.5 11727.6 11739.6 11757.1 11788.2 11807.5 6422.7 6470.9 6500.4 6516.6 6520.1 6103.2 6160.6 6224.6 6259.3 6266.7 4246.6 4381.2 4417.0 4423.3 4435.8 1124.4 1234.1 1257.8 1265.3 1270.8 687.4 767.6 788.5 791.7 807.4 6177.3 6256.4 6265.9 6357.8 6399.5 587.4 590.1 610.6 613.3 613.3 3976.4 4553.6 4624.9 4703.8 4749.6 11629.8 14769.4 14776.7 14787.8 14858.6 13010.2 13965.8 14628.0 14869.8 14874.0 8489.6 8619.9 8704.2 8714.9 8740.7 6719.2 6974.8 6975.2 6999.5 7055.2 13126.8 13160.5 13265.7 13268.9 13465.9 13677.9 13725.1 13830.4 13865.6 13967.5 9167.3 9317.1 9416.2 9454.3 9457.1 10437.8 10560.7 10639.3 10657.8 10682.0 8597.3 9019.8 9056.3 9090.8 9103.7 7713.8 8923.2 8923.5 8925.8 8984.8 7027.8 8124.8 8124.9 8263.9 8268.6 5979.6 6017.1 6101.5 6179.6 6230.9 6056.3 6185.0 6277.0 6287.7 6317.9 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5467.78 Median:6121.86 stddev:1701.89 (27.8%) stddev-sans-first:1662.66 04:23:30 INFO - PID 1648 | Values: 344.5 391.8 395.1 397.9 403.5 6247.6 6366.2 6405.6 6454.4 6455.1 6034.5 6233.4 6242.7 6253.3 6263.9 3735.7 4546.8 4556.8 4564.3 4564.9 3355.7 4342.8 4424.1 4487.7 4521.6 3894.9 4479.2 4485.6 4485.8 4489.6 4235.5 4509.7 4513.4 4514.9 4515.8 3766.5 3824.8 3845.3 3872.3 3880.8 6035.8 6105.1 6109.7 6115.3 6128.4 4528.3 5429.7 5444.5 5455.7 5512.5 6400.7 6668.8 6702.3 6731.6 6748.6 3927.2 4210.9 4411.9 4428.8 4431.0 5488.5 6702.8 7016.9 7074.9 7082.3 5015.3 5039.3 5072.9 5073.6 5082.8 5164.9 5179.3 5204.3 5206.2 5220.3 5624.7 5674.0 5722.4 5759.8 5809.8 6793.9 6802.8 6835.7 6842.8 6842.9 1576.0 1613.4 1756.2 1770.9 1779.6 1090.5 1213.6 1230.0 1236.3 1241.7 5584.9 5828.4 6055.0 6110.7 6385.8 6881.5 6956.7 6973.8 6977.8 7005.8 6103.4 6311.3 6340.0 6365.7 6404.0 3340.6 3464.4 3613.2 3667.9 3675.7 6356.8 6547.5 7000.2 7152.2 7159.6 5959.4 6342.8 6924.5 7015.6 7162.2 6209.9 6877.7 7174.9 7175.4 7229.7 5550.0 6851.3 7029.9 7039.4 7084.9 6860.2 6868.5 6875.2 6878.7 6904.7 6964.9 7142.2 7184.3 7185.3 7224.1 4454.0 4461.9 4462.7 4472.5 4492.6 6887.7 6996.7 7001.6 7018.3 7032.4 5533.0 6595.2 6652.8 6715.8 6721.2 5946.4 6697.7 6699.4 6709.5 6712.4 6296.7 6502.4 6547.8 6548.0 6594.9 5600.5 6688.2 6736.3 6737.9 6761.8 6343.2 6356.2 6501.6 6529.6 6539.9 04:23:30 INFO - PID 1648 | 04:23:30 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:21285.03 Median:17569.01 stddev:29304.67 (166.8%) stddev-sans-first:29346.62 04:23:30 INFO - PID 1648 | Values: 827.4 838.9 855.6 862.3 869.6 16466.8 16914.2 16926.6 18688.2 19244.0 16773.7 16989.7 17755.3 18269.2 18302.0 991.2 1006.8 1010.8 1018.8 1020.0 1019.2 1022.3 1023.1 1024.1 1026.9 1055.2 1080.4 1082.4 1083.9 1088.1 1079.2 1082.4 1086.0 1089.8 1090.5 702.0 772.6 783.0 784.2 785.9 16964.2 17123.7 17173.3 17317.4 17323.7 15562.3 16659.6 17382.7 17849.9 17855.9 13945.7 14156.0 14428.1 14463.6 14477.3 3473.2 3523.4 3539.9 3541.1 3551.0 91280.5 114171.0 119214.4 119915.0 120614.0 13556.5 13818.2 13833.0 13856.9 13865.7 21048.5 24869.1 26240.3 26247.0 26273.1 6151.4 7781.9 8010.6 8035.6 8036.3 3933.2 4008.8 4010.8 4012.0 4012.4 4394.1 4680.8 4797.0 4809.0 4844.9 954.8 977.9 987.6 993.8 994.9 629.2 657.7 666.4 671.4 684.2 11101.7 11756.6 11988.6 12079.4 12171.2 570.2 637.8 640.0 648.5 673.3 5419.9 5868.6 6027.1 6051.2 6073.9 105666.5 106251.0 107084.0 107486.5 108231.0 91288.0 91890.5 92210.0 93547.1 94345.0 73955.3 73965.6 74653.0 74677.3 75337.6 12110.3 12436.6 12451.1 12470.9 12498.4 40688.4 41404.6 41442.2 41647.4 41660.4 49624.3 49965.8 49974.0 50268.7 50853.5 16280.5 16307.6 16346.6 16600.9 16659.8 18179.5 18521.7 18555.8 18607.5 18756.7 16395.8 16842.8 16942.4 17064.6 17133.4 13985.7 16215.4 16401.9 16476.6 16699.2 12996.5 13763.4 13957.0 16420.6 16446.6 11692.5 11741.5 11951.2 12069.0 12109.9 12037.6 12359.3 12859.2 12920.6 12968.5 04:23:30 INFO - PID 1648 | -------- Summary: end -------- 04:23:30 INFO - PID 1648 | 04:23:31 INFO - TEST-INFO | 1648: exit 0 04:23:31 INFO - TEST-OK | dromaeo_css | took 1141957ms 04:23:31 INFO - TEST-START | kraken 04:23:31 INFO - operating with platform_type : mac_ 04:23:31 INFO - Initialising browser for kraken test... 04:23:31 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpvW3AlH/profile 04:23:31 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 04:24:04 INFO - TEST-INFO | 1657: killed by SIGTERM 04:24:04 INFO - Browser initialized. 04:24:04 INFO - Running cycle 1/1 for kraken test... 04:24:04 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpvW3AlH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:24:06 INFO - PID 1661 | 2017-05-24 04:24:06.889 plugin-container[1662:10519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:24:06 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:06 INFO - PID 1661 | 2017-05-24 04:24:06.890 plugin-container[1662:10519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 04:24:06 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:06 INFO - PID 1661 | 2017-05-24 04:24:06.890 plugin-container[1662:10519] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:24:07 INFO - PID 1661 | 2017-05-24 04:24:07.451 plugin-container[1663:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 04:24:07 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:07 INFO - PID 1661 | 2017-05-24 04:24:07.452 plugin-container[1663:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 04:24:07 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:24:07 INFO - PID 1661 | 2017-05-24 04:24:07.452 plugin-container[1663:10563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:24:16 INFO - PID 1661 | RSS: Main: 249176064 04:24:16 INFO - PID 1661 | 04:24:19 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 04:24:20 INFO - PID 1661 | RSS: Main: 262893568 04:24:20 INFO - PID 1661 | 04:24:23 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 04:24:23 INFO - PID 1661 | RSS: Main: 272064512 04:24:23 INFO - PID 1661 | 04:24:27 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 04:24:27 INFO - PID 1661 | RSS: Main: 275107840 04:24:27 INFO - PID 1661 | 04:24:29 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 04:24:30 INFO - PID 1661 | RSS: Main: 277032960 04:24:30 INFO - PID 1661 | 04:24:32 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:24:33 INFO - PID 1661 | RSS: Main: 277553152 04:24:33 INFO - PID 1661 | 04:24:36 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 04:24:36 INFO - PID 1661 | RSS: Main: 283385856 04:24:36 INFO - PID 1661 | 04:24:39 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 04:24:40 INFO - PID 1661 | RSS: Main: 285593600 04:24:40 INFO - PID 1661 | 04:24:43 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 04:24:43 INFO - PID 1661 | RSS: Main: 283660288 04:24:43 INFO - PID 1661 | 04:24:45 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:24:45 INFO - PID 1661 | RSS: Main: 283774976 04:24:45 INFO - PID 1661 | 04:24:47 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:24:48 INFO - PID 1661 | RSS: Main: 283656192 04:24:48 INFO - PID 1661 | 04:24:50 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:24:51 INFO - PID 1661 | RSS: Main: 283684864 04:24:51 INFO - PID 1661 | 04:24:54 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:24:54 INFO - PID 1661 | RSS: Main: 283410432 04:24:54 INFO - PID 1661 | 04:24:58 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:24:58 INFO - PID 1661 | RSS: Main: 283414528 04:24:58 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 04:25:00 INFO - PID 1661 | RSS: Main: 285278208 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | __start_tp_report 04:25:00 INFO - PID 1661 | _x_x_mozilla_page_load 04:25:00 INFO - PID 1661 | _x_x_mozilla_page_load_details 04:25:00 INFO - PID 1661 | |i|pagename|runs| 04:25:00 INFO - PID 1661 | |0;ai-astar;100;167;172;131;115;115;116;117;116;115 04:25:00 INFO - PID 1661 | |1;audio-beat-detection;142;101;113;150;111;111;110;112;137;134 04:25:00 INFO - PID 1661 | |2;audio-dft;210;282;197;204;218;215;246;271;198;197 04:25:00 INFO - PID 1661 | |3;audio-fft;72;57;60;81;61;86;73;57;81;49 04:25:00 INFO - PID 1661 | |4;audio-oscillator;79;72;106;98;70;73;102;104;102;101 04:25:00 INFO - PID 1661 | |5;imaging-gaussian-blur;90;90;92;92;92;89;91;110;137;132 04:25:00 INFO - PID 1661 | |6;imaging-darkroom;98;97;103;97;96;99;98;97;97;97 04:25:00 INFO - PID 1661 | |7;imaging-desaturate;91;94;89;91;93;90;93;94;92;88 04:25:00 INFO - PID 1661 | |8;json-parse-financial;50;51;52;51;52;50;52;52;52;53 04:25:00 INFO - PID 1661 | |9;json-stringify-tinderbox;57;61;72;65;62;54;56;55;57;70 04:25:00 INFO - PID 1661 | |10;stanford-crypto-aes;86;87;87;87;85;77;112;114;112;102 04:25:00 INFO - PID 1661 | |11;stanford-crypto-ccm;138;144;118;147;161;157;151;137;146;139 04:25:00 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;186;241;187;183;170;189;241;228;193;233 04:25:00 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;69;68;67;68;65;70;66;69;70;68 04:25:00 INFO - PID 1661 | __end_tp_report 04:25:00 INFO - PID 1661 | __start_cc_report 04:25:00 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1063 04:25:00 INFO - PID 1661 | __end_cc_report 04:25:00 INFO - PID 1661 | __startTimestamp1495625100726__endTimestamp 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | ------- Summary: start ------- 04:25:00 INFO - PID 1661 | Number of tests: 14 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:126.40 Median:116.50 stddev:23.90 (20.5%) stddev-sans-first:23.36 04:25:00 INFO - PID 1661 | Values: 100.0 167.0 172.0 131.0 115.0 115.0 116.0 117.0 116.0 115.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:123.50 stddev:16.87 (13.7%) stddev-sans-first:16.28 04:25:00 INFO - PID 1661 | Values: 142.0 101.0 113.0 150.0 111.0 111.0 110.0 112.0 137.0 134.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:223.80 Median:216.50 stddev:31.45 (14.5%) stddev-sans-first:32.96 04:25:00 INFO - PID 1661 | Values: 210.0 282.0 197.0 204.0 218.0 215.0 246.0 271.0 198.0 197.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:67.70 Median:72.50 stddev:12.55 (17.3%) stddev-sans-first:13.22 04:25:00 INFO - PID 1661 | Values: 72.0 57.0 60.0 81.0 61.0 86.0 73.0 57.0 81.0 49.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:71.00 stddev:15.11 (21.3%) stddev-sans-first:15.42 04:25:00 INFO - PID 1661 | Values: 79.0 72.0 106.0 98.0 70.0 73.0 102.0 104.0 102.0 101.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:101.50 Median:90.50 stddev:18.45 (20.4%) stddev-sans-first:19.10 04:25:00 INFO - PID 1661 | Values: 90.0 90.0 92.0 92.0 92.0 89.0 91.0 110.0 137.0 132.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:97.00 stddev:1.97 (2.0%) stddev-sans-first:2.09 04:25:00 INFO - PID 1661 | Values: 98.0 97.0 103.0 97.0 96.0 99.0 98.0 97.0 97.0 97.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.50 stddev:2.07 (2.2%) stddev-sans-first:2.19 04:25:00 INFO - PID 1661 | Values: 91.0 94.0 89.0 91.0 93.0 90.0 93.0 94.0 92.0 88.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:0.97 (1.9%) stddev-sans-first:0.87 04:25:00 INFO - PID 1661 | Values: 50.0 51.0 52.0 51.0 52.0 50.0 52.0 52.0 52.0 53.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.50 stddev:6.33 (10.3%) stddev-sans-first:6.56 04:25:00 INFO - PID 1661 | Values: 57.0 61.0 72.0 65.0 62.0 54.0 56.0 55.0 57.0 70.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:85.50 stddev:13.68 (16.0%) stddev-sans-first:14.13 04:25:00 INFO - PID 1661 | Values: 86.0 87.0 87.0 87.0 85.0 77.0 112.0 114.0 112.0 102.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:146.50 stddev:12.04 (8.2%) stddev-sans-first:12.59 04:25:00 INFO - PID 1661 | Values: 138.0 144.0 118.0 147.0 161.0 157.0 151.0 137.0 146.0 139.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.10 Median:210.50 stddev:27.28 (13.0%) stddev-sans-first:28.05 04:25:00 INFO - PID 1661 | Values: 186.0 241.0 187.0 183.0 170.0 189.0 241.0 228.0 193.0 233.0 04:25:00 INFO - PID 1661 | 04:25:00 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.50 stddev:1.63 (2.4%) stddev-sans-first:1.69 04:25:00 INFO - PID 1661 | Values: 69.0 68.0 67.0 68.0 65.0 70.0 66.0 69.0 70.0 68.0 04:25:00 INFO - PID 1661 | -------- Summary: end -------- 04:25:00 INFO - PID 1661 | 04:25:01 INFO - TEST-INFO | 1661: exit 0 04:25:01 INFO - TEST-OK | kraken | took 90036ms 04:25:01 INFO - SUITE-END | took 1231s 04:25:01 INFO - Completed test suite (00:20:32) 04:25:01 INFO - javascript benchmark 04:25:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7928.392046851625, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2860.213441303641, 3575.249197668488, 3582.0, 3597.2625611749595, 3623.384024715798, 6145.2178868128185, 6208.441240288374, 6268.965620672583, 6272.310045894954, 6287.08837218603, 6177.752889884399, 6221.533384996121, 6231.968840155804, 6249.4687951524065, 6267.874642507147, 5235.869103272411, 5421.728913554316, 5426.83719488414, 5452.236686863842, 5492.972535137339, 5477.835664930039, 5514.3658479275, 5528.723563821813, 5533.0, 5577.0, 5368.4363141869935, 5411.783528658849, 5434.99999999998, 5451.236826844245, 5552.472515111099, 5463.180522921554, 5471.480209380143, 5503.587230957694, 5508.063629183049, 5537.916931246034, 5419.5393391561765, 5447.428020057877, 5455.5908306877145, 5457.099578569531, 5494.230807686927, 6145.924463218964, 6190.876182476371, 6238.781642642486, 6289.622622642656, 6305.684715764193, 6613.900791488131, 6707.7652282170375, 6717.42901853343, 6727.730890764364, 6745.392914637706, 6782.186137663462, 6855.622940738257, 6882.139732533434, 6892.035115083842, 6914.654267286666, 6275.278342990523, 6334.809955701337, 6376.7130479128555, 6404.135441715402, 6405.910995130839, 6799.966000169968, 6894.862102757917, 6949.687264073129, 6983.790486285419, 7017.157941047107, 6244.313125556185, 6275.341089185661, 6448.258450278185, 6459.160309159779, 6631.0716499689115, 6730.461563074942, 6834.624095674786, 6834.8722460793415, 6845.041694162821, 6845.5550389224545, 5965.582409231312, 6465.321141280192, 6698.8995165072565, 6700.597964122168, 6743.696533655997, 5442.265294185314, 6498.837529061717, 6622.9006564901565, 6658.4340331071435, 6726.226483954408, 5377.247185394048, 5377.354717433933, 5388.46115388458, 5390.9191362129595, 5432.972835135799, 4693.389859318335, 4748.050389922029, 4754.072955773591, 4763.880902977384, 4783.306420568998, 5282.31329927107, 6236.656983865931, 6283.246010478681, 6322.525810564226, 6363.204599425072, 6202.34875338083, 6877.0, 6881.552699074644, 6894.069300644449, 6897.62063086525, 4662.6269898408045, 6251.749930002749, 6286.868363694579, 6348.841278968062, 6360.936390636034, 4771.5228477152705, 5099.923501147486, 5901.380355062743, 5970.925233457932, 6008.038713805858, 5672.0, 6919.342662447059, 6941.201761797458, 6974.6861391238535, 6982.1272340957385, 6871.896921546081, 6877.931220687929, 6888.483363747639, 6893.896591551231, 6914.9308506916295, 6751.358620931003, 6932.376086152271, 6963.825904352431, 6987.09167808202, 6989.580625162506, 6584.604923704593, 6604.63674497888, 6707.262201157771, 6710.228323742636, 6729.1252137221845, 6863.245043045161, 6871.862562748817, 6874.2782007890455, 6874.6562671867205, 6887.035814986006, 6426.967865160645, 6480.0, 6512.251091124582, 6631.635260060743, 6672.633005184762, 6644.767433139817, 6657.167854018247, 6664.933350666432, 6667.19993600771, 6694.799156025327, 6443.838904027437, 6514.837129071811, 6542.640154791532, 6548.116004339353, 6549.443297319781, 6390.4248617624635, 6447.70985305653, 6666.466682665278, 6684.565503242274, 6702.530822842355, 6387.55287129915, 6416.069669897745, 6429.196350456193, 6459.903101453576, 6462.579932304385], "value": 6121.787713014574, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.55039801716, 809.0897740042452, 825.5046971816911, 1237.857646370667, 1302.719915218228, 38847.611523884756, 39241.0, 39301.60698393015, 39325.80337098318, 39340.21319573607, 39094.82715518539, 39217.0, 39360.40959385604, 39466.85598012203, 39502.60497395025, 7698.34564062054, 7870.685172593089, 8288.58557072147, 8448.619812108469, 8488.575571221414, 8301.252887240147, 8374.539400332978, 8416.116307787686, 8429.831403371929, 8434.831303373929, 8379.45533540321, 8416.579171041454, 8465.280451161658, 8473.152684731509, 8479.19447652472, 8301.128381519913, 8388.035375931755, 8419.789505262355, 8424.368172387034, 8449.070602233749, 8209.179082091803, 8283.668653253862, 8297.294729947962, 8305.086440491541, 8355.582220888931, 36955.0, 37174.07064823374, 37292.62707372946, 37338.62661373406, 37401.064973375614, 3505.141240396094, 3518.6305437928904, 3530.6116327203986, 3541.097020259843, 3544.3620148373284, 24363.512729745482, 30063.549046764318, 30394.696053039406, 30470.695293047007, 30481.780728770824, 2954.734073933335, 2994.8203107813383, 2999.7750168737434, 3019.6980301969847, 3026.6216722909635, 223907.88046059664, 239015.0, 276525.0, 294004.0, 294952.0, 47216.0, 47597.76201119042, 47642.5235747645, 47766.761166193945, 47883.521164787904, 49162.55862089691, 49246.0, 49409.0, 49653.25520117201, 49733.50266497361, 13913.443462261599, 14031.719365612631, 14137.0, 14143.151410915172, 14155.716885662228, 11408.714782130513, 11464.25482343645, 11499.712507187387, 11500.309981401142, 11548.307101573933, 12007.919287264189, 13330.53343133005, 13361.66595835112, 13395.464181432633, 13541.39063742134, 5818.301803783488, 6204.627722336674, 6255.218097737783, 6256.655883926337, 6256.655883926428, 889.4974339498162, 1014.4623349624711, 1022.6501018655304, 1034.9793004139874, 1066.8826429092794, 23754.524909501713, 23921.880390598282, 24108.0, 24178.637320440208, 24240.1515946941, 1119.3172164979355, 1119.6696974392573, 1147.225622704671, 1150.4477850631743, 1153.4866984191954, 12028.195770634475, 12353.444095015746, 12357.75284494305, 12377.443015064344, 12389.504419823286, 293982.0, 294686.0, 296538.0, 297164.0, 298049.0, 56231.15653265204, 56594.717026414604, 56600.433995659514, 56607.86784264292, 56661.43338566644, 1489.255372313843, 1514.5002149290708, 1517.4082107977897, 1519.3694616734153, 1523.6800271943016, 27973.0, 28819.855900720362, 29882.551761724026, 29954.85022574873, 30282.54576181403, 37073.629263707015, 37184.62815371811, 37314.44028339631, 37416.2516749658, 37462.0, 280112.0, 282583.0, 283376.0, 284012.579937099, 284177.0, 33398.332033359686, 34484.65515344815, 34978.825105874304, 35054.824725876206, 35123.47314790229, 42383.0, 42649.78675106604, 44395.556044439145, 44422.777886110365, 44451.0, 29539.409211816073, 29860.0, 30463.238419039702, 30587.54118688266, 30911.845440772653, 24875.87562062178, 25169.748302516742, 25353.366165845262, 30018.0, 30519.84740076374, 36788.26423471462, 36788.44817327688, 36805.26389472142, 36831.63168368389, 36839.26321473609, 28308.009219677213, 28732.85633571819, 30732.84633576818, 30879.69120308858, 31074.689253108085, 23022.07911683514, 23653.172138975053, 24041.879790601633, 24103.879480602485, 24184.0], "value": 17444.748720253945, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.8657343076785, 408.88346821155966, 422.78437996621716, 423.9639630631396, 452.0461825548093, 19086.0, 21173.788262117374, 21488.247911323087, 22045.11819527219, 22925.85370731465, 21759.45601359967, 22752.544949101008, 22874.0, 22955.0, 23050.65424018641, 5346.492083252104, 5893.26334208225, 6171.4445699887, 6224.782132625359, 6239.625622462644, 5894.086416605433, 6039.788607398742, 6050.394960503936, 6079.331273559927, 6090.939090609104, 5970.701464926737, 6019.217501724792, 6022.247219097613, 6034.4568988791, 6034.939650603482, 5878.5297176225795, 6095.908561371583, 6124.6325220486915, 6158.815235542941, 6161.322254551996, 5424.484673955968, 5483.122700367961, 5491.560675145979, 5491.780328786844, 5497.340319161686, 27553.448931021267, 28212.0, 28388.574171387445, 28622.570661440095, 28772.856135719187, 2143.95712085759, 2230.9665355019524, 2233.095596283508, 2234.128689810975, 2234.452559123009, 17937.910310448493, 17973.0, 17983.910080449645, 18169.273229070823, 18293.810902291443, 2145.013293884815, 2147.8389120816, 2180.6728990651372, 2194.3307291276155, 2198.428408613756, 162399.0, 164858.1757091219, 166301.0, 166795.0, 167257.0, 12666.303353315472, 12727.299998499986, 12981.610551683465, 13023.609291721263, 13066.281354525592, 16311.102889340962, 16573.83426165747, 16577.17114144312, 16604.003759774452, 16607.50177494677, 7489.925100748923, 8682.565871706389, 9018.59416326267, 9154.221891139328, 9177.311701622404, 8483.642617181222, 8505.61724722389, 8506.276966457921, 8705.477671339613, 8707.216350528483, 7866.292033716993, 8009.279164875189, 8019.318357939641, 8040.638171282307, 8055.033395992401, 2164.7943445372666, 2166.1118941234017, 2169.2516081951703, 2188.0591345721173, 2347.1667558016998, 922.9723108306762, 928.7863791328033, 950.3680052764834, 987.2595553334999, 987.4223579206244, 20265.797342026686, 20970.0, 21031.0, 21036.26373076935, 21161.25935592247, 991.8147813362962, 1000.2048371544639, 1001.3191030099457, 1017.8320577104843, 1025.8666373371414, 9582.66460673873, 10187.134093602126, 10287.331323463952, 10753.247272690836, 10797.24419290642, 530266.0, 530605.0, 531228.0, 537960.0, 538133.0, 129541.0, 131447.51248631364, 133624.33187834, 134461.0, 135618.0, 1183.248637115438, 1215.6596153077153, 1237.3998610673996, 1239.1821397877538, 1243.042857000105, 21138.57722845565, 22212.111515539844, 22444.32667019992, 22464.8767561618, 22530.436739081655, 47261.29108063313, 49465.75267123641, 53630.73184634052, 57534.7123264381, 57699.0, 74279.51440971259, 78405.0, 78994.0, 80216.0, 80350.0, 8649.091845356399, 8720.345974051845, 8925.196732294125, 9249.907500925174, 9295.628174872929, 14068.08557443763, 14229.786553201504, 14274.71450570962, 14601.634959126106, 14637.85362146365, 11964.521419143137, 11971.700707482034, 12078.818817717565, 12216.26702397859, 12225.266484010988, 11200.663980080612, 11208.27146235492, 11555.942220288845, 11560.537578496765, 11567.652970410902, 11846.763064738829, 11960.880391195977, 12223.0, 12304.692382690504, 12312.50749970027, 8752.299816014576, 8753.25597324209, 8853.29173666118, 9150.267978561566, 9193.494357810117, 11714.414279285831, 11860.584879529175, 12103.334316612318, 12142.75714485723, 12196.634100976986], "value": 10536.564741548329, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1205.4575441051527, 1421.4740545997981, 1428.0003997201954, 1431.5132854829355, 1441.6756229848281, 11337.433128343591, 11383.430828458575, 11852.585159519418, 11857.46641401137, 12134.39328033597, 11838.7632247355, 11973.940130299336, 11994.400279985988, 11994.520219191243, 12013.399330033486, 2532.227670560483, 2562.7949764018927, 2587.79297656188, 2591.1319707897783, 2592.494463579603, 2477.318737347226, 2528.962065569018, 2535.036686059295, 2536.251805717318, 2539.4540173862615, 2513.1204078572537, 2513.472170844125, 2559.4881023795224, 2565.6536367590415, 2571.3828681116584, 2509.9749002509925, 2526.431552900601, 2541.4535874786916, 2564.4101856572906, 2573.716891141973, 2474.270090323341, 2504.474060447308, 2527.721950585434, 2578.5487539680666, 2579.3938424470234, 9764.267679924034, 11789.469473873696, 11809.114316426303, 11811.173217874752, 11837.053035757119, 6499.35006499351, 6668.566543174727, 6767.458603311772, 6775.390214880685, 6789.558678685869, 3934.7639141651307, 4261.339492378686, 4279.97860010698, 4288.270993931008, 4335.3063509838275, 1185.2532904270254, 1188.5780547905456, 1228.5945637939458, 1229.6802831263847, 1234.703671118934, 15052.698946021019, 15421.537353879514, 16126.435574754945, 16181.757273640906, 16285.185740712917, 5327.200919862052, 5466.0, 5647.915281270784, 5651.180578816049, 5665.5467562594895, 11727.589534366256, 11739.647810565697, 11757.059435245159, 11788.174827761974, 11807.527698891949, 6422.678866056678, 6470.870582588416, 6500.382463665944, 6516.6415847128865, 6520.0871877937125, 6103.206583144163, 6160.559329597822, 6224.595401298901, 6259.280182779039, 6266.655333956585, 4246.575342465729, 4381.16757816014, 4417.0, 4423.336499525097, 4435.82256709728, 1124.3815901254277, 1234.1176059117774, 1257.7798885195236, 1265.2724683307135, 1270.834791477102, 687.388224480213, 767.5893397032672, 788.4638445856758, 791.6674996501483, 807.4146243973073, 6177.289611694712, 6256.436920677161, 6265.937340626627, 6357.777477788255, 6399.456046236122, 587.3891153200709, 590.094205394717, 610.6031079798078, 613.2793967088653, 613.349849159892, 3976.443297938291, 4553.635709143261, 4624.884377890579, 4703.7883295251795, 4749.572538471554, 11629.825552616718, 14769.409223630935, 14776.704465910621, 14787.778183327258, 14858.628534286729, 13010.21938683682, 13965.79051314231, 14628.0, 14869.776953345709, 14873.958822882294, 8489.61796719149, 8619.870701939475, 8704.173103555153, 8714.869276960851, 8740.694075707446, 6719.193696756225, 6974.819247070556, 6975.1978522468535, 6999.475039371966, 7055.223925368103, 13126.803097953347, 13160.47358105665, 13265.734685306434, 13268.867311326763, 13465.932670336586, 13677.931610341884, 13725.107867988549, 13830.446782128602, 13865.584032479042, 13967.511137110152, 9167.312451566026, 9317.11487408695, 9416.152546270594, 9454.33819632619, 9457.148856602938, 10437.791244175225, 10560.706578211126, 10639.308444951053, 10657.840132397865, 10682.03861652455, 8597.26923211534, 9019.81960360776, 9056.320775941698, 9090.772730681785, 9103.681371151977, 7713.768586942177, 8923.196912277926, 8923.464592124234, 8925.776855578662, 8984.820303594022, 7027.754028608974, 8124.796880078045, 8124.918750812652, 8263.917360826556, 8268.627911744106, 5979.617872336174, 6017.127516510169, 6101.450869421773, 6179.598326108789, 6230.940740074107, 6056.333803281547, 6185.041318595625, 6277.027060805583, 6287.685615719104, 6317.873642527215], "value": 5307.482783505731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.51595508310817, 391.8323396279087, 395.0912900329243, 397.87268074216246, 403.46742300163794, 6247.6251424914535, 6366.172397588312, 6405.605029334454, 6454.419102280794, 6455.128012876266, 6034.469835146445, 6233.40782625651, 6242.656653884034, 6253.280872699641, 6263.874722505547, 3735.719821013421, 4546.772661366935, 4556.817727290904, 4564.292534657134, 4564.9087018259615, 3355.748318876082, 4342.782860856961, 4424.093060922519, 4487.708298960557, 4521.5930566249035, 3894.922101557967, 4479.193745125876, 4485.618722408599, 4485.842995495158, 4489.551044895501, 4235.470566179228, 4509.706869053501, 4513.368128461985, 4514.909701805978, 4515.841945531907, 3766.5291838520184, 3824.808759562011, 3845.327067763158, 3872.283627528888, 3880.844766209348, 6035.827102243189, 6105.084237364386, 6109.663968481732, 6115.266168059817, 6128.448439640454, 4528.275475923836, 5429.6742195468005, 5444.482774136451, 5455.699936503491, 5512.503874651301, 6400.679966001728, 6668.833279168011, 6702.296258892795, 6731.562448440835, 6748.561343512656, 3927.2341893330813, 4210.926213815488, 4411.889702757425, 4428.756418397019, 4431.0251744616135, 5488.4785945335125, 6702.798916032527, 7016.877299632034, 7074.858502829915, 7082.291770822928, 5015.322931404214, 5039.294498770175, 5072.923906141411, 5073.568746656576, 5082.847514574569, 5164.8967020659375, 5179.300794392784, 5204.34945631796, 5206.245094461282, 5220.347456567929, 5624.718764061862, 5673.971630141823, 5722.370539240764, 5759.827205183851, 5809.796657116981, 6793.932060679429, 6802.829929251809, 6835.69239384219, 6842.794716158523, 6842.863142737117, 1576.022865823197, 1613.3707853936974, 1756.2448147296784, 1770.9197389592339, 1779.5817982774038, 1090.506006770729, 1213.5691829400437, 1230.0, 1236.3076677060874, 1241.7268200995948, 5584.860378490489, 5828.417158284137, 6054.969725151346, 6110.725017374229, 6385.776497822554, 6881.483888708367, 6956.686949087202, 6973.755918542826, 6977.82555436108, 7005.824854378579, 6103.389661033861, 6311.337309582428, 6340.0, 6365.681715914278, 6404.007378856285, 3340.6325304216516, 3464.4110501214855, 3613.2412193439673, 3667.9266414671556, 3675.6691897729334, 6356.841078973062, 6547.508936829757, 7000.194977577542, 7152.249013853574, 7159.606221657858, 5959.404059594006, 6342.778002769973, 6924.480663950123, 7015.579065256101, 7162.242536623437, 6209.937900620936, 6877.65611719402, 7174.919273410653, 7175.425965922714, 7229.74695885652, 5549.972250138723, 6851.314868513109, 7029.894551581628, 7039.436845052281, 7084.929150708427, 6860.245373009065, 6868.484863635147, 6875.243723190545, 6878.69045892926, 6904.723811047502, 6964.930350696631, 7142.214356420894, 7184.281571842774, 7185.317394847481, 7224.096987876515, 4453.977730111458, 4461.866144015685, 4462.732236065716, 4472.546422412794, 4492.550744925612, 6887.7244910203035, 6996.720131194696, 7001.579905205704, 7018.298170182941, 7032.402245809163, 5533.0, 6595.208574971034, 6652.767153149616, 6715.832104197434, 6721.193456785217, 5946.405359464019, 6697.698603562949, 6699.39705426514, 6709.530332876847, 6712.39588437043, 6296.748130074746, 6502.414782669393, 6547.803565893031, 6547.9672601636685, 6594.899717760956, 5600.467955544217, 6688.163979502562, 6736.32636736342, 6737.932620673927, 6761.763338283136, 6343.207099112611, 6356.2054743157105, 6501.642409667514, 6529.575577587631, 6539.901901471387], "value": 4942.189089922254, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.424939666931, 838.8741688746687, 855.6106971328046, 862.3101518784974, 869.6086760957571, 16466.752998705015, 16914.23885925133, 16926.5768355791, 18688.15903284352, 19244.0, 16773.748393774073, 16989.745153822703, 17755.28978840845, 18269.177886995083, 18302.0, 991.1921783746242, 1006.7936073105033, 1010.7927874785688, 1018.7860549284659, 1020.015684864106, 1019.2406657040515, 1022.3405903192437, 1023.1150055202271, 1024.109025148122, 1026.856240126383, 1055.2349546578746, 1080.400377790326, 1082.3722241100181, 1083.8753543342539, 1088.0588291128156, 1079.1690398393232, 1082.3938594387168, 1085.9954542048574, 1089.831076183189, 1090.547422819529, 701.989470157943, 772.5673622771266, 783.0212234706617, 784.1766145547152, 785.8821176823465, 16964.151792410332, 17123.74314385285, 17173.31306747741, 17317.393891213873, 17323.740143897852, 15562.299696513684, 16659.583510412216, 17382.739258911126, 17849.910750446164, 17855.910720446445, 13945.721085578232, 14156.0, 14428.134311941318, 14463.566093017227, 14477.34851931655, 3473.201163732356, 3523.365794157051, 3539.8584056637706, 3541.1324225564645, 3551.0, 91280.54359728159, 114171.0, 119214.4039279798, 119915.0, 120614.0, 13556.525521606696, 13818.239996800072, 13833.0, 13856.861431385558, 13865.653358666113, 21048.473788155417, 24869.129580464596, 26240.34399140037, 26247.0, 26273.080442184433, 6151.384861513813, 7781.8832717509285, 8010.5994700264755, 8035.638396272182, 8036.276735093753, 3933.213357328546, 4008.819603117867, 4010.7794071326357, 4012.0371110933747, 4012.4382586437923, 4394.077243778778, 4680.765961701969, 4796.976015119972, 4808.951910480921, 4844.903101937942, 954.7517645412242, 977.8777652793401, 987.629638885418, 993.7614972406715, 994.945278009702, 629.2166253015025, 657.6580178307254, 666.4335314982226, 671.4225765841372, 684.1550684904139, 11101.722456938642, 11756.58851940178, 11988.580399685969, 12079.396030198455, 12171.208871423329, 570.1903297317818, 637.7959053103056, 639.9552031357761, 648.5103746671233, 673.2695025896957, 5419.864503387447, 5868.589198756046, 6027.126066720389, 6051.152838602599, 6073.908891366633, 105666.4716676427, 106251.0, 107084.0, 107486.46256768823, 108231.0, 91288.0, 91890.54054729684, 92210.0, 93547.0645293552, 94345.0, 73955.26044739483, 73965.63017184987, 74653.0, 74677.25322746811, 75337.62331188309, 12110.273383597012, 12436.564720234748, 12451.128421010442, 12470.875291246972, 12498.437570309175, 40688.3896741555, 41404.58595414007, 41442.1711565761, 41647.37528937008, 41660.37509437421, 49624.255636164766, 49965.75017124891, 49974.00103995799, 50268.743281418254, 50853.49146508636, 16280.511584652479, 16307.59231019234, 16346.591335216715, 16600.916995414944, 16659.833401665826, 18179.54551136232, 18521.72217416767, 18555.814441855407, 18607.534811629277, 18756.718649220544, 16395.83604163943, 16842.831571684128, 16942.40701575439, 17064.57338566546, 17133.400330988356, 13985.650358740706, 16215.35138594443, 16401.91799041045, 16476.588085297484, 16699.24853381625, 12996.545120920722, 13763.449462021406, 13957.0, 16420.589485262964, 16446.588835279214, 11692.47383867745, 11741.530338786695, 11951.22317049389, 12069.034477241976, 12109.878901210875, 12037.638870833889, 12359.32023738667, 12859.228446293253, 12920.612381628565, 12968.546100886424], "value": 8414.552577026989, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 167.0, 172.0, 131.0, 115.0, 115.0, 116.0, 117.0, 116.0, 115.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 101.0, 113.0, 150.0, 111.0, 111.0, 110.0, 112.0, 137.0, 134.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 282.0, 197.0, 204.0, 218.0, 215.0, 246.0, 271.0, 198.0, 197.0], "value": 223.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 57.0, 60.0, 81.0, 61.0, 86.0, 73.0, 57.0, 81.0, 49.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 106.0, 98.0, 70.0, 73.0, 102.0, 104.0, 102.0, 101.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 92.0, 92.0, 89.0, 91.0, 110.0, 137.0, 132.0], "value": 101.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 103.0, 97.0, 96.0, 99.0, 98.0, 97.0, 97.0, 97.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 89.0, 91.0, 93.0, 90.0, 93.0, 94.0, 92.0, 88.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 52.0, 51.0, 52.0, 50.0, 52.0, 52.0, 52.0, 53.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 72.0, 65.0, 62.0, 54.0, 56.0, 55.0, 57.0, 70.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 87.0, 85.0, 77.0, 112.0, 114.0, 112.0, 102.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 118.0, 147.0, 161.0, 157.0, 151.0, 137.0, 146.0, 139.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 241.0, 187.0, 183.0, 170.0, 189.0, 241.0, 228.0, 193.0, 233.0], "value": 205.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 68.0, 65.0, 70.0, 66.0, 69.0, 70.0, 68.0], "value": 68.0, "unit": "score"}]}]} 04:25:01 INFO - Return code: 0 04:25:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:25:02 INFO - # TBPL SUCCESS # 04:25:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:02 INFO - 2017-05-24 04:25:02.066 screenresolution[1668:10942] starting screenresolution argv=screenresolution get 04:25:02 INFO - 2017-05-24 04:25:02.068 screenresolution[1668:10942] Display 0: 1600x1200x32@60 04:25:02 INFO - 2017-05-24 04:25:02.074 screenresolution[1669:10945] starting screenresolution argv=screenresolution list 04:25:02 INFO - Available Modes on Display 0 04:25:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:02 INFO - Intel Iris: 04:25:02 INFO - Chipset Model: Intel Iris 04:25:02 INFO - Type: GPU 04:25:02 INFO - Bus: Built-In 04:25:02 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:02 INFO - Vendor: Intel (0x8086) 04:25:02 INFO - Device ID: 0x0a2e 04:25:02 INFO - Revision ID: 0x0009 04:25:02 INFO - Displays: 04:25:02 INFO - Display: 04:25:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:02 INFO - Display Serial Number: 1600x1200 60 04:25:02 INFO - Main Display: Yes 04:25:02 INFO - Mirror: Off 04:25:02 INFO - Online: Yes 04:25:02 INFO - Rotation: Supported 04:25:02 INFO - Return code: 0 04:25:02 INFO - Running post-action listener: _resource_record_post_action 04:25:02 INFO - [mozharness: 2017-05-24 11:25:02.309250Z] Finished run-tests step (success) 04:25:02 INFO - Running post-run listener: _resource_record_post_run 04:25:02 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 39738368; Write bytes: 561970176; Read time: 260; Write time: 1734 04:25:02 INFO - TinderboxPrint: CPU usage
53.3% 04:25:02 INFO - TinderboxPrint: I/O read bytes / time
39,738,368 / 260 04:25:02 INFO - TinderboxPrint: I/O write bytes / time
561,970,176 / 1,734 04:25:02 INFO - TinderboxPrint: CPU idle
2,342.1 (46.7%) 04:25:02 INFO - TinderboxPrint: CPU system
1,014.5 (20.2%) 04:25:02 INFO - TinderboxPrint: CPU user
1,661.1 (33.1%) 04:25:02 INFO - TinderboxPrint: Swap in / out
286,076,928 / 0 04:25:02 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 225587712; Write bytes: 219399680; Read time: 16538; Write time: 452 04:25:02 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 38788096; Write bytes: 314213888; Read time: 248; Write time: 1209 04:25:02 INFO - Running post-run listener: _upload_blobber_files 04:25:02 INFO - Blob upload gear active. 04:25:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:25:02 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 04:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:25:03 INFO - (blobuploader) - INFO - Open directory for files ... 04:25:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:25:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:25:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:04 INFO - (blobuploader) - INFO - Done attempting. 04:25:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:25:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:25:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:06 INFO - (blobuploader) - INFO - Done attempting. 04:25:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:25:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:25:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:07 INFO - (blobuploader) - INFO - Done attempting. 04:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:25:09 INFO - (blobuploader) - INFO - Done attempting. 04:25:09 INFO - (blobuploader) - INFO - Iteration through files over. 04:25:09 INFO - Return code: 0 04:25:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:25:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:25:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a513a5089ba295f3722dca43b25fb523930225b960d869e3c95e7ea090bb2d0818b4c36ab51e010fd8fc6d35eb574647f291c0330827a4433fa9a334a00aaba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a67f09d17f34a9b9475c71f0e2daa9735b7a1cccea639a8084851abb99a044f42b7346bb70093b53a00f3fa87f5f48d934afb84487bf8c8c5bd1320bdf127d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeca0e3f34817169d97d29cceb77f29251038b4d6431f885d343bdaa60a6c43c7ca0036c8f7b47de074bac4a167226d999dc5f1771d3b841a65c1539ac975296", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d44a799bd0bf62f6aa20fa7fc424a0a825d65679c49f3235c949231a70e128e812316548972cfa35f52ec5bd4e46670f91783407ad2dae53d63e08f8feac6e38"} 04:25:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:25:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:25:09 INFO - Contents: 04:25:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a513a5089ba295f3722dca43b25fb523930225b960d869e3c95e7ea090bb2d0818b4c36ab51e010fd8fc6d35eb574647f291c0330827a4433fa9a334a00aaba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a67f09d17f34a9b9475c71f0e2daa9735b7a1cccea639a8084851abb99a044f42b7346bb70093b53a00f3fa87f5f48d934afb84487bf8c8c5bd1320bdf127d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeca0e3f34817169d97d29cceb77f29251038b4d6431f885d343bdaa60a6c43c7ca0036c8f7b47de074bac4a167226d999dc5f1771d3b841a65c1539ac975296", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d44a799bd0bf62f6aa20fa7fc424a0a825d65679c49f3235c949231a70e128e812316548972cfa35f52ec5bd4e46670f91783407ad2dae53d63e08f8feac6e38"} 04:25:09 INFO - Running post-run listener: copy_logs_to_upload_dir 04:25:09 INFO - Copying logs to upload dir... 04:25:09 INFO - mkdir: /builds/slave/test/build/upload/logs 04:25:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.720978 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-24 04:25:09.693655) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 04:25:09.697049) ========= 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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a513a5089ba295f3722dca43b25fb523930225b960d869e3c95e7ea090bb2d0818b4c36ab51e010fd8fc6d35eb574647f291c0330827a4433fa9a334a00aaba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a67f09d17f34a9b9475c71f0e2daa9735b7a1cccea639a8084851abb99a044f42b7346bb70093b53a00f3fa87f5f48d934afb84487bf8c8c5bd1320bdf127d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeca0e3f34817169d97d29cceb77f29251038b4d6431f885d343bdaa60a6c43c7ca0036c8f7b47de074bac4a167226d999dc5f1771d3b841a65c1539ac975296", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d44a799bd0bf62f6aa20fa7fc424a0a825d65679c49f3235c949231a70e128e812316548972cfa35f52ec5bd4e46670f91783407ad2dae53d63e08f8feac6e38"} build_url:https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010471 build_url: 'https://queue.taskcluster.net/v1/task/Nfsu5xIdSamUsIARqt6c_A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a513a5089ba295f3722dca43b25fb523930225b960d869e3c95e7ea090bb2d0818b4c36ab51e010fd8fc6d35eb574647f291c0330827a4433fa9a334a00aaba9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a67f09d17f34a9b9475c71f0e2daa9735b7a1cccea639a8084851abb99a044f42b7346bb70093b53a00f3fa87f5f48d934afb84487bf8c8c5bd1320bdf127d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aeca0e3f34817169d97d29cceb77f29251038b4d6431f885d343bdaa60a6c43c7ca0036c8f7b47de074bac4a167226d999dc5f1771d3b841a65c1539ac975296", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d44a799bd0bf62f6aa20fa7fc424a0a825d65679c49f3235c949231a70e128e812316548972cfa35f52ec5bd4e46670f91783407ad2dae53d63e08f8feac6e38"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 04:25:09.730057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:25:09.730425) ========= 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.FfvkUlX0fh/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.FY5XdxTQIQ/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.005111 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:25:09.780618) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 04:25:09.780961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 04:25:09.781339) ========= ========= Total master_lag: 0.30 =========