builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0039 starttime: 1495749214.28 results: success (0) revision: 55e5723b1e62190a38c00927eda796e2ad14778f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.282316) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.282805) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.283090) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.307042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.307474) ========= 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.rRN3CQOWOO/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.66gtr68bNY/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-25 14:53:34-- 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% 41.0M=0s 2017-05-25 14:53:34 (41.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131979 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.464035) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.464364) ========= 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.rRN3CQOWOO/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.66gtr68bNY/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.021500 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:34.513347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 14:53:34.513737) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 55e5723b1e62190a38c00927eda796e2ad14778f --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 55e5723b1e62190a38c00927eda796e2ad14778f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rRN3CQOWOO/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.66gtr68bNY/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-25 14:53:34,759 truncating revision to first 12 chars 2017-05-25 14:53:34,759 Setting DEBUG logging. 2017-05-25 14:53:34,759 attempt 1/10 2017-05-25 14:53:34,759 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/55e5723b1e62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 14:53:36,170 unpacking tar archive at: mozilla-central-55e5723b1e62/testing/mozharness/ program finished with exit code 0 elapsedTime=1.673659 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 14:53:36.209333) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:36.209696) ========= script_repo_revision: 55e5723b1e62190a38c00927eda796e2ad14778f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:36.210119) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:36.210397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 14:53:36.229584) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-25 14:53:36.229941) ========= /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.rRN3CQOWOO/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.66gtr68bNY/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:53:36 INFO - MultiFileLogger online at 20170525 14:53:36 in /builds/slave/test 14:53:36 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 14:53:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:53:36 INFO - {'append_to_log': False, 14:53:36 INFO - 'base_work_dir': '/builds/slave/test', 14:53:36 INFO - 'blob_upload_branch': 'Firefox', 14:53:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:53:36 INFO - 'branch': 'Firefox', 14:53:36 INFO - 'buildbot_json_path': 'buildprops.json', 14:53:36 INFO - 'config_files': ('talos/mac_config.py',), 14:53:36 INFO - 'default_actions': ('clobber', 14:53:36 INFO - 'read-buildbot-config', 14:53:36 INFO - 'download-and-extract', 14:53:36 INFO - 'populate-webroot', 14:53:36 INFO - 'create-virtualenv', 14:53:36 INFO - 'install', 14:53:36 INFO - 'run-tests'), 14:53:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:53:36 INFO - 'download_minidump_stackwalk': True, 14:53:36 INFO - 'download_symbols': 'ondemand', 14:53:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:53:36 INFO - 'tooltool.py': '/tools/tooltool.py', 14:53:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:53:36 INFO - '/tools/misc-python/virtualenv.py')}, 14:53:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:53:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:53:36 INFO - 'gecko_profile': False, 14:53:36 INFO - 'gecko_profile_interval': 0, 14:53:36 INFO - 'installer_path': 'installer.exe', 14:53:36 INFO - 'log_level': 'info', 14:53:36 INFO - 'log_name': 'talos', 14:53:36 INFO - 'log_to_console': True, 14:53:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:53:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:53:36 INFO - 'opt_config_files': (), 14:53:36 INFO - 'pip_index': False, 14:53:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:53:36 INFO - 'cmd': ('bash', 14:53:36 INFO - '-c', 14:53:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:53:36 INFO - 'enabled': True, 14:53:36 INFO - 'halt_on_failure': False, 14:53:36 INFO - 'name': 'check_screen_resolution'},), 14:53:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:53:36 INFO - 'cmd': ('bash', 14:53:36 INFO - '-c', 14:53:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:53:36 INFO - 'enabled': True, 14:53:36 INFO - 'halt_on_failure': False, 14:53:36 INFO - 'name': 'check_screen_resolution'},), 14:53:36 INFO - 'run_cmd_checks_enabled': True, 14:53:36 INFO - 'suite': 'dromaeojs-e10s', 14:53:36 INFO - 'system_bits': '32', 14:53:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:53:36 INFO - 'title': 't-yosemite-r7-0039', 14:53:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:53:36 INFO - 'use_talos_json': True, 14:53:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:53:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:53:36 INFO - 'work_dir': 'build'} 14:53:36 INFO - [mozharness: 2017-05-25 21:53:36.602463Z] Running clobber step. 14:53:36 INFO - Running pre-action listener: _resource_record_pre_action 14:53:36 INFO - Running main action method: clobber 14:53:36 INFO - rmtree: /builds/slave/test/build 14:53:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:53:38 INFO - Running post-action listener: _resource_record_post_action 14:53:38 INFO - [mozharness: 2017-05-25 21:53:38.671599Z] Finished clobber step (success) 14:53:38 INFO - [mozharness: 2017-05-25 21:53:38.671797Z] Running read-buildbot-config step. 14:53:38 INFO - Running pre-action listener: _resource_record_pre_action 14:53:38 INFO - Running main action method: read_buildbot_config 14:53:38 INFO - Using buildbot properties: 14:53:38 INFO - { 14:53:38 INFO - "project": "", 14:53:38 INFO - "product": "firefox", 14:53:38 INFO - "who": "ryanvm@gmail.com", 14:53:38 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:53:38 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:53:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:53:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:53:38 INFO - "stage_platform": "macosx64", 14:53:38 INFO - "basedir": "/builds/slave/test", 14:53:38 INFO - "buildnumber": 76, 14:53:38 INFO - "platform": "yosemite_r7", 14:53:38 INFO - "parent_task_id": "MLd0plWeQBGUu_8RADkh2A", 14:53:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:53:38 INFO - "slavebuilddir": "test", 14:53:38 INFO - "taskId": "VBSVE9XDRpmWPSbwLs6qSQ", 14:53:38 INFO - "branch": "mozilla-central", 14:53:38 INFO - "script_repo_revision": "production", 14:53:38 INFO - "revision": "55e5723b1e62190a38c00927eda796e2ad14778f", 14:53:38 INFO - "slavename": "t-yosemite-r7-0039", 14:53:38 INFO - "repo_path": "mozilla-central" 14:53:38 INFO - } 14:53:38 INFO - Finding installer, test and symbols from parent task. 14:53:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VBSVE9XDRpmWPSbwLs6qSQ'}, attempt #1 14:53:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VBSVE9XDRpmWPSbwLs6qSQ'}, attempt #1 14:53:39 INFO - Task dependencies: MgW8fHH1SkqJvG1P6Z8dpw MLd0plWeQBGUu_8RADkh2A 14:53:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A'}, attempt #1 14:53:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:53:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/test_packages.json 14:53:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/target.crashreporter-symbols.zip 14:53:40 INFO - Running post-action listener: _resource_record_post_action 14:53:40 INFO - [mozharness: 2017-05-25 21:53:40.345796Z] Finished read-buildbot-config step (success) 14:53:40 INFO - [mozharness: 2017-05-25 21:53:40.345917Z] Running download-and-extract step. 14:53:40 INFO - Running pre-action listener: _resource_record_pre_action 14:53:40 INFO - Running main action method: download_and_extract 14:53:40 INFO - mkdir: /builds/slave/test/build/tests 14:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:40 INFO - trying https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:53:40 INFO - Downloading https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:53:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:53:40 INFO - Downloaded 1325 bytes. 14:53:40 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:53:40 INFO - Using the following test package requirements: 14:53:40 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:53:40 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:53:40 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:53:40 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:53:40 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'jsshell-mac.zip'], 14:53:40 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:53:40 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:53:40 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:53:40 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:53:40 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:53:40 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:53:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:53:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:53:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:53:40 INFO - Fetch https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:53:42 INFO - Content-Length response header: 57627073 14:53:42 INFO - Bytes received: 57627073 14:54:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:54:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:54:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:54:00 INFO - Fetch https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:54:02 INFO - Content-Length response header: 57627073 14:54:02 INFO - Bytes received: 57627073 14:54:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:54:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:54:20 INFO - Fetch https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:54:20 INFO - Content-Length response header: 14411888 14:54:20 INFO - Bytes received: 14411888 14:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:21 INFO - trying https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:54:21 INFO - Downloading https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:54:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:54:23 INFO - Downloaded 67190450 bytes. 14:54:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:54:23 INFO - mkdir: /builds/slave/test/properties 14:54:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:54:23 INFO - Writing to file /builds/slave/test/properties/build_url 14:54:23 INFO - Contents: 14:54:23 INFO - build_url:https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:54:23 INFO - Running post-action listener: _resource_record_post_action 14:54:23 INFO - Running post-action listener: set_extra_try_arguments 14:54:23 INFO - [mozharness: 2017-05-25 21:54:23.171216Z] Finished download-and-extract step (success) 14:54:23 INFO - [mozharness: 2017-05-25 21:54:23.171369Z] Running populate-webroot step. 14:54:23 INFO - Running pre-action listener: _resource_record_pre_action 14:54:23 INFO - Running main action method: populate_webroot 14:54:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:54:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:54:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:54:23 INFO - '--tppagecycles', 14:54:23 INFO - '7'], 14:54:23 INFO - 'tests': ['tsvgm']}}, 14:54:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:54:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:54:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:54:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:54:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['damp', 'tps']}, 14:54:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'tests': ['damp', 'tps']}, 14:54:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['dromaeo_dom']}, 14:54:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:54:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:54:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:54:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:54:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['a11yr', 14:54:23 INFO - 'ts_paint', 14:54:23 INFO - 'tpaint', 14:54:23 INFO - 'sessionrestore', 14:54:23 INFO - 'sessionrestore_no_auto_restore', 14:54:23 INFO - 'tabpaint']}, 14:54:23 INFO - 'other-e10s': {'tests': ['a11yr', 14:54:23 INFO - 'ts_paint', 14:54:23 INFO - 'tpaint', 14:54:23 INFO - 'sessionrestore', 14:54:23 INFO - 'sessionrestore_no_auto_restore', 14:54:23 INFO - 'tabpaint']}, 14:54:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:54:23 INFO - 'ts_paint', 14:54:23 INFO - 'tpaint', 14:54:23 INFO - 'sessionrestore', 14:54:23 INFO - 'sessionrestore_no_auto_restore', 14:54:23 INFO - 'tabpaint']}, 14:54:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:54:23 INFO - 'ts_paint', 14:54:23 INFO - 'tpaint', 14:54:23 INFO - 'sessionrestore', 14:54:23 INFO - 'sessionrestore_no_auto_restore', 14:54:23 INFO - 'tabpaint']}, 14:54:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['a11yr', 14:54:23 INFO - 'ts_paint', 14:54:23 INFO - 'tpaint', 14:54:23 INFO - 'sessionrestore', 14:54:23 INFO - 'sessionrestore_no_auto_restore', 14:54:23 INFO - 'tabpaint']}, 14:54:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['a11yr', 14:54:23 INFO - 'ts_paint', 14:54:23 INFO - 'tpaint', 14:54:23 INFO - 'sessionrestore', 14:54:23 INFO - 'sessionrestore_no_auto_restore', 14:54:23 INFO - 'tabpaint']}, 14:54:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:54:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:54:23 INFO - 'bloom_basic_ref']}, 14:54:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['tsvgx', 14:54:23 INFO - 'tsvgr_opacity', 14:54:23 INFO - 'tart', 14:54:23 INFO - 'tscrollx', 14:54:23 INFO - 'cart', 14:54:23 INFO - 'tsvg_static']}, 14:54:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:54:23 INFO - 'tsvgr_opacity', 14:54:23 INFO - 'tart', 14:54:23 INFO - 'tscrollx', 14:54:23 INFO - 'cart', 14:54:23 INFO - 'tsvg_static']}, 14:54:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'talos_options': ['--disable-e10s'], 14:54:23 INFO - 'tests': ['tp5o']}, 14:54:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:54:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'talos_options': ['--disable-e10s', 14:54:23 INFO - '--xperf_path', 14:54:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:23 INFO - 'tests': ['tp5n']}, 14:54:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:54:23 INFO - 'talos_options': ['--xperf_path', 14:54:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:23 INFO - 'tests': ['tp5n']}}, 14:54:23 INFO - 'talos.zip': {'path': '', 14:54:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:54:23 INFO - Running post-action listener: _resource_record_post_action 14:54:23 INFO - [mozharness: 2017-05-25 21:54:23.192587Z] Finished populate-webroot step (success) 14:54:23 INFO - [mozharness: 2017-05-25 21:54:23.192738Z] Running create-virtualenv step. 14:54:23 INFO - Running pre-action listener: _resource_record_pre_action 14:54:23 INFO - Running main action method: create_virtualenv 14:54:23 INFO - Creating virtualenv /builds/slave/test/build/venv 14:54:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:54:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:54:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:54:23 INFO - Using real prefix '/tools/python27' 14:54:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:54:24 INFO - Installing distribute.............................................................................................................................................................................................done. 14:54:26 INFO - Installing pip.................done. 14:54:26 INFO - Return code: 0 14:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:54:27 INFO - Reading from file tmpfile_stdout 14:54:27 INFO - Output received: 14:54:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:54:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:27 INFO - 'HOME': '/Users/cltbld', 14:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:27 INFO - 'LOGNAME': 'cltbld', 14:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:27 INFO - 'MOZ_NO_REMOTE': '1', 14:54:27 INFO - 'NO_EM_RESTART': '1', 14:54:27 INFO - 'PAGER': '/bin/cat', 14:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:27 INFO - 'PWD': '/builds/slave/test', 14:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:27 INFO - 'SHELL': '/bin/bash', 14:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:27 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:27 INFO - 'USER': 'cltbld', 14:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:27 INFO - 'XPC_FLAGS': '0x0', 14:54:27 INFO - 'XPC_SERVICE_NAME': '0', 14:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:54:27 INFO - Cleaning up... 14:54:27 INFO - Return code: 0 14:54:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:27 INFO - 'HOME': '/Users/cltbld', 14:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:27 INFO - 'LOGNAME': 'cltbld', 14:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:27 INFO - 'MOZ_NO_REMOTE': '1', 14:54:27 INFO - 'NO_EM_RESTART': '1', 14:54:27 INFO - 'PAGER': '/bin/cat', 14:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:27 INFO - 'PWD': '/builds/slave/test', 14:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:27 INFO - 'SHELL': '/bin/bash', 14:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:27 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:27 INFO - 'USER': 'cltbld', 14:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:27 INFO - 'XPC_FLAGS': '0x0', 14:54:27 INFO - 'XPC_SERVICE_NAME': '0', 14:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:27 INFO - Downloading/unpacking psutil>=3.1.1 14:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:54:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:29 INFO - Installing collected packages: psutil 14:54:29 INFO - Running setup.py install for psutil 14:54:29 INFO - building 'psutil._psutil_osx' extension 14:54:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:54:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:54:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:54:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:54:30 INFO - building 'psutil._psutil_posix' extension 14:54:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:54:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:54:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:54:30 INFO - ^ 14:54:30 INFO - 1 warning generated. 14:54:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:54:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:30 INFO - Successfully installed psutil 14:54:30 INFO - Cleaning up... 14:54:30 INFO - Return code: 0 14:54:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:30 INFO - 'HOME': '/Users/cltbld', 14:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:30 INFO - 'LOGNAME': 'cltbld', 14:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:30 INFO - 'MOZ_NO_REMOTE': '1', 14:54:30 INFO - 'NO_EM_RESTART': '1', 14:54:30 INFO - 'PAGER': '/bin/cat', 14:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:30 INFO - 'PWD': '/builds/slave/test', 14:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:30 INFO - 'SHELL': '/bin/bash', 14:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:30 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:30 INFO - 'USER': 'cltbld', 14:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:30 INFO - 'XPC_FLAGS': '0x0', 14:54:30 INFO - 'XPC_SERVICE_NAME': '0', 14:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:54:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:54:32 INFO - Installing collected packages: mozsystemmonitor 14:54:32 INFO - Running setup.py install for mozsystemmonitor 14:54:32 INFO - Successfully installed mozsystemmonitor 14:54:32 INFO - Cleaning up... 14:54:32 INFO - Return code: 0 14:54:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:54:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:54:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:33 INFO - 'HOME': '/Users/cltbld', 14:54:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:33 INFO - 'LOGNAME': 'cltbld', 14:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:33 INFO - 'MOZ_NO_REMOTE': '1', 14:54:33 INFO - 'NO_EM_RESTART': '1', 14:54:33 INFO - 'PAGER': '/bin/cat', 14:54:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:33 INFO - 'PWD': '/builds/slave/test', 14:54:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:33 INFO - 'SHELL': '/bin/bash', 14:54:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:33 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:33 INFO - 'USER': 'cltbld', 14:54:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:33 INFO - 'XPC_FLAGS': '0x0', 14:54:33 INFO - 'XPC_SERVICE_NAME': '0', 14:54:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:33 INFO - Downloading/unpacking jsonschema==2.5.1 14:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:54:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:35 INFO - Downloading functools32-3.2.3-2.tar.gz 14:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:54:35 INFO - warning: no files found matching '*.txt' 14:54:35 INFO - no previously-included directories found matching 'build' 14:54:35 INFO - no previously-included directories found matching 'dist' 14:54:35 INFO - no previously-included directories found matching '.git*' 14:54:35 INFO - Installing collected packages: jsonschema, functools32 14:54:35 INFO - Running setup.py install for functools32 14:54:35 INFO - warning: no files found matching '*.txt' 14:54:35 INFO - no previously-included directories found matching 'build' 14:54:35 INFO - no previously-included directories found matching 'dist' 14:54:35 INFO - no previously-included directories found matching '.git*' 14:54:35 INFO - Successfully installed jsonschema functools32 14:54:35 INFO - Cleaning up... 14:54:35 INFO - Return code: 0 14:54:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:35 INFO - 'HOME': '/Users/cltbld', 14:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:35 INFO - 'LOGNAME': 'cltbld', 14:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:35 INFO - 'MOZ_NO_REMOTE': '1', 14:54:35 INFO - 'NO_EM_RESTART': '1', 14:54:35 INFO - 'PAGER': '/bin/cat', 14:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:35 INFO - 'PWD': '/builds/slave/test', 14:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:35 INFO - 'SHELL': '/bin/bash', 14:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:35 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:35 INFO - 'USER': 'cltbld', 14:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:35 INFO - 'XPC_FLAGS': '0x0', 14:54:35 INFO - 'XPC_SERVICE_NAME': '0', 14:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:54:35 INFO - Cleaning up... 14:54:35 INFO - Return code: 0 14:54:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:35 INFO - 'HOME': '/Users/cltbld', 14:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:35 INFO - 'LOGNAME': 'cltbld', 14:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:35 INFO - 'MOZ_NO_REMOTE': '1', 14:54:35 INFO - 'NO_EM_RESTART': '1', 14:54:35 INFO - 'PAGER': '/bin/cat', 14:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:35 INFO - 'PWD': '/builds/slave/test', 14:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:35 INFO - 'SHELL': '/bin/bash', 14:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:35 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:35 INFO - 'USER': 'cltbld', 14:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:35 INFO - 'XPC_FLAGS': '0x0', 14:54:35 INFO - 'XPC_SERVICE_NAME': '0', 14:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:35 INFO - Downloading/unpacking blobuploader==1.2.4 14:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:37 INFO - Downloading blobuploader-1.2.4.tar.gz 14:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:54:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:54:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:38 INFO - Downloading docopt-0.6.1.tar.gz 14:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:54:38 INFO - Installing collected packages: blobuploader, requests, docopt 14:54:38 INFO - Running setup.py install for blobuploader 14:54:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:54:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:54:38 INFO - Running setup.py install for requests 14:54:39 INFO - Running setup.py install for docopt 14:54:39 INFO - Successfully installed blobuploader requests docopt 14:54:39 INFO - Cleaning up... 14:54:39 INFO - Return code: 0 14:54:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:54:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:39 INFO - 'HOME': '/Users/cltbld', 14:54:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:39 INFO - 'LOGNAME': 'cltbld', 14:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:39 INFO - 'MOZ_NO_REMOTE': '1', 14:54:39 INFO - 'NO_EM_RESTART': '1', 14:54:39 INFO - 'PAGER': '/bin/cat', 14:54:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:39 INFO - 'PWD': '/builds/slave/test', 14:54:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:39 INFO - 'SHELL': '/bin/bash', 14:54:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:39 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:39 INFO - 'USER': 'cltbld', 14:54:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:39 INFO - 'XPC_FLAGS': '0x0', 14:54:39 INFO - 'XPC_SERVICE_NAME': '0', 14:54:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-CzJXlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-1m0i_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-FECifI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Qy3qbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZLy4v1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:39 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-K3fryi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-t0QzvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ikVst2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-7Md9HS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-yZaxB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-5ofMlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-urclbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fxluOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-pY7Z2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:40 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-pmx7vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:41 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-0xYCAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:41 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-6q3Sw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:54:41 INFO - Running setup.py install for manifestparser 14:54:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:54:41 INFO - Running setup.py install for mozcrash 14:54:41 INFO - Running setup.py install for mozdebug 14:54:41 INFO - Running setup.py install for mozdevice 14:54:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:54:41 INFO - Running setup.py install for mozfile 14:54:41 INFO - Running setup.py install for mozhttpd 14:54:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Running setup.py install for mozinfo 14:54:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Running setup.py install for mozInstall 14:54:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Running setup.py install for mozleak 14:54:42 INFO - Running setup.py install for mozlog 14:54:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Running setup.py install for moznetwork 14:54:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:54:42 INFO - Running setup.py install for mozprocess 14:54:42 INFO - Running setup.py install for mozprofile 14:54:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:54:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:54:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:54:43 INFO - Running setup.py install for mozrunner 14:54:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:54:43 INFO - Running setup.py install for mozscreenshot 14:54:43 INFO - Running setup.py install for moztest 14:54:43 INFO - Running setup.py install for mozversion 14:54:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:54:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:54:43 INFO - Cleaning up... 14:54:43 INFO - Return code: 0 14:54:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54: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')]} 14:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:43 INFO - 'HOME': '/Users/cltbld', 14:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:43 INFO - 'LOGNAME': 'cltbld', 14:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:43 INFO - 'MOZ_NO_REMOTE': '1', 14:54:43 INFO - 'NO_EM_RESTART': '1', 14:54:43 INFO - 'PAGER': '/bin/cat', 14:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:43 INFO - 'PWD': '/builds/slave/test', 14:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:43 INFO - 'SHELL': '/bin/bash', 14:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:43 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:43 INFO - 'USER': 'cltbld', 14:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:43 INFO - 'XPC_FLAGS': '0x0', 14:54:43 INFO - 'XPC_SERVICE_NAME': '0', 14:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-o_JvzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-g1C8X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-2X8HwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-0Cr4wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-xhsHid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-57PEt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Y6bC3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-8bw6oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:44 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-A5MtVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-OBXr1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-8RpiHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-niAhcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-yU28CD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Cq2M1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fWg9jJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-uGvee0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:45 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jNt6Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:54:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:47 INFO - Downloading blessings-1.6.tar.gz 14:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:54:47 INFO - Installing collected packages: blessings 14:54:47 INFO - Running setup.py install for blessings 14:54:47 INFO - Successfully installed blessings 14:54:47 INFO - Cleaning up... 14:54:47 INFO - Return code: 0 14:54:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:47 INFO - Reading from file tmpfile_stdout 14:54:47 INFO - Current package versions: 14:54:47 INFO - blessings == 1.6 14:54:47 INFO - blobuploader == 1.2.4 14:54:47 INFO - docopt == 0.6.1 14:54:47 INFO - functools32 == 3.2.3-2 14:54:47 INFO - jsonschema == 2.5.1 14:54:47 INFO - manifestparser == 1.1 14:54:47 INFO - mozInstall == 1.12 14:54:47 INFO - mozcrash == 1.0 14:54:47 INFO - mozdebug == 0.1 14:54:47 INFO - mozdevice == 0.51 14:54:47 INFO - mozfile == 1.2 14:54:47 INFO - mozhttpd == 0.7 14:54:47 INFO - mozinfo == 0.9 14:54:47 INFO - mozleak == 0.1 14:54:47 INFO - mozlog == 3.4 14:54:47 INFO - moznetwork == 0.27 14:54:47 INFO - mozprocess == 0.25 14:54:47 INFO - mozprofile == 0.28 14:54:47 INFO - mozrunner == 6.13 14:54:47 INFO - mozscreenshot == 0.1 14:54:47 INFO - mozsystemmonitor == 0.3 14:54:47 INFO - moztest == 0.8 14:54:47 INFO - mozversion == 1.4 14:54:47 INFO - psutil == 3.1.1 14:54:47 INFO - requests == 1.2.3 14:54:47 INFO - wsgiref == 0.1.2 14:54:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54: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')]} 14:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:47 INFO - 'HOME': '/Users/cltbld', 14:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:47 INFO - 'LOGNAME': 'cltbld', 14:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:47 INFO - 'MOZ_NO_REMOTE': '1', 14:54:47 INFO - 'NO_EM_RESTART': '1', 14:54:47 INFO - 'PAGER': '/bin/cat', 14:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:47 INFO - 'PWD': '/builds/slave/test', 14:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:47 INFO - 'SHELL': '/bin/bash', 14:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:47 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:47 INFO - 'USER': 'cltbld', 14:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:47 INFO - 'XPC_FLAGS': '0x0', 14:54:47 INFO - 'XPC_SERVICE_NAME': '0', 14:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:54:48 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:49 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:54:49 INFO - Installing collected packages: marionette-driver 14:54:49 INFO - Running setup.py install for marionette-driver 14:54:49 INFO - Successfully installed marionette-driver 14:54:49 INFO - Cleaning up... 14:54:49 INFO - Return code: 0 14:54:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54: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')]} 14:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca01768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd73c7fa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, '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.rRN3CQOWOO/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.66gtr68bNY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:54: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'] in /builds/slave/test/build 14:54: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 14:54:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:54:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:49 INFO - 'HOME': '/Users/cltbld', 14:54:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:49 INFO - 'LOGNAME': 'cltbld', 14:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:49 INFO - 'MOZ_NO_REMOTE': '1', 14:54:49 INFO - 'NO_EM_RESTART': '1', 14:54:49 INFO - 'PAGER': '/bin/cat', 14:54:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:49 INFO - 'PWD': '/builds/slave/test', 14:54:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:49 INFO - 'SHELL': '/bin/bash', 14:54:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:54:49 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:54:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:49 INFO - 'USER': 'cltbld', 14:54:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:49 INFO - 'XPC_FLAGS': '0x0', 14:54:49 INFO - 'XPC_SERVICE_NAME': '0', 14:54:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:54:50 INFO - Cleaning up... 14:54:50 INFO - Return code: 0 14:54:50 INFO - Running post-action listener: _resource_record_post_action 14:54:50 INFO - Running post-action listener: _start_resource_monitoring 14:54:50 INFO - Starting resource monitoring. 14:54:50 INFO - [mozharness: 2017-05-25 21:54:50.249185Z] Finished create-virtualenv step (success) 14:54:50 INFO - [mozharness: 2017-05-25 21:54:50.250164Z] Running install step. 14:54:50 INFO - Running pre-action listener: _resource_record_pre_action 14:54:50 INFO - Running main action method: install 14:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:50 INFO - Reading from file tmpfile_stdout 14:54:50 INFO - Detecting whether we're running mozinstall >=1.0... 14:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:54:50 INFO - Reading from file tmpfile_stdout 14:54:50 INFO - Output received: 14:54:50 INFO - Usage: mozinstall [options] installer 14:54:50 INFO - Options: 14:54:50 INFO - -h, --help show this help message and exit 14:54:50 INFO - -d DEST, --destination=DEST 14:54:50 INFO - Directory to install application into. [default: 14:54:50 INFO - "/builds/slave/test"] 14:54:50 INFO - --app=APP Application being installed. [default: firefox] 14:54:50 INFO - mkdir: /builds/slave/test/build/application 14:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:55:11 INFO - Reading from file tmpfile_stdout 14:55:11 INFO - Output received: 14:55:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:55:11 INFO - Running post-action listener: _resource_record_post_action 14:55:11 INFO - [mozharness: 2017-05-25 21:55:11.993153Z] Finished install step (success) 14:55:11 INFO - [mozharness: 2017-05-25 21:55:11.993331Z] Running run-tests step. 14:55:11 INFO - Running pre-action listener: _resource_record_pre_action 14:55:11 INFO - Running main action method: run_tests 14:55:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:55:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:55:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:55:12 INFO - 2017-05-25 14:55:12.007 screenresolution[2416:34417] starting screenresolution argv=screenresolution get 14:55:12 INFO - 2017-05-25 14:55:12.010 screenresolution[2416:34417] Display 0: 1600x1200x32@60 14:55:12 INFO - 2017-05-25 14:55:12.016 screenresolution[2417:34419] starting screenresolution argv=screenresolution list 14:55:12 INFO - Available Modes on Display 0 14:55:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:55:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:55:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:55:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:55:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:55:12 INFO - Intel Iris: 14:55:12 INFO - Chipset Model: Intel Iris 14:55:12 INFO - Type: GPU 14:55:12 INFO - Bus: Built-In 14:55:12 INFO - VRAM (Dynamic, Max): 1536 MB 14:55:12 INFO - Vendor: Intel (0x8086) 14:55:12 INFO - Device ID: 0x0a2e 14:55:12 INFO - Revision ID: 0x0009 14:55:12 INFO - Displays: 14:55:12 INFO - Display: 14:55:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:55:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:55:12 INFO - Display Serial Number: 1600x1200 60 14:55:12 INFO - Main Display: Yes 14:55:12 INFO - Mirror: Off 14:55:12 INFO - Online: Yes 14:55:12 INFO - Rotation: Supported 14:55:12 INFO - Return code: 0 14:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:55:12 INFO - Python 2.7.3 14:55:12 INFO - Return code: 0 14:55:12 INFO - grabbing minidump binary from tooltool 14:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70dd30>, '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 0x10c6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c702be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:55:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:55:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:55:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:55:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:55:12 INFO - Return code: 0 14:55:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:55:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:55:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:55:12 INFO - ENV: RUST_BACKTRACE is now 1 14:55:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:55:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:55:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRN3CQOWOO/Render', 14:55:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:55:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:55:12 INFO - 'HOME': '/Users/cltbld', 14:55:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:55:12 INFO - 'LOGNAME': 'cltbld', 14:55:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:55:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:12 INFO - 'MOZ_NO_REMOTE': '1', 14:55:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:55:12 INFO - 'NO_EM_RESTART': '1', 14:55:12 INFO - 'PAGER': '/bin/cat', 14:55:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:12 INFO - 'PWD': '/builds/slave/test', 14:55:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:55:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:55:12 INFO - 'RUST_BACKTRACE': '1', 14:55:12 INFO - 'SHELL': '/bin/bash', 14:55:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66gtr68bNY/Listeners', 14:55:12 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 14:55:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:55:12 INFO - 'USER': 'cltbld', 14:55:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:55:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:12 INFO - 'XPC_FLAGS': '0x0', 14:55:12 INFO - 'XPC_SERVICE_NAME': '0', 14:55:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:55:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:55:12 INFO - mozversion application_buildid: 20170525133757 14:55:12 INFO - mozversion application_changeset: 55e5723b1e62190a38c00927eda796e2ad14778f 14:55:12 INFO - mozversion application_display_name: Nightly 14:55:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:55:12 INFO - mozversion application_name: Firefox 14:55:12 INFO - mozversion application_remotingname: firefox 14:55:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:55:12 INFO - mozversion application_vendor: Mozilla 14:55:12 INFO - mozversion application_version: 55.0a1 14:55:12 INFO - mozversion platform_buildid: 20170525133757 14:55:12 INFO - mozversion platform_changeset: 55e5723b1e62190a38c00927eda796e2ad14778f 14:55:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:55:12 INFO - mozversion platform_version: 55.0a1 14:55:12 INFO - using testdate: 1495749312 14:55:12 INFO - actual date: 1495749312 14:55:12 INFO - starting webserver on 'localhost:58437' 14:55:12 INFO - SUITE-START | Running 2 tests 14:55:12 INFO - TEST-START | dromaeo_css 14:55:12 INFO - operating with platform_type : mac_ 14:55:12 INFO - Initialising browser for dromaeo_css test... 14:55:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58437/getInfo.html -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpSTx0Kh/profile 14:55:12 INFO - TEST-INFO | started process 2427 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58437/getInfo.html -foreground) 14:55:46 INFO - TEST-INFO | 2427: killed by SIGTERM 14:55:46 INFO - Browser initialized. 14:55:46 INFO - Running cycle 1/1 for dromaeo_css test... 14:55:46 INFO - TEST-INFO | started process 2434 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpSTx0Kh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:55:48 INFO - PID 2434 | 2017-05-25 14:55:48.301 plugin-container[2435:34884] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 14:55:48 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:55:48 INFO - PID 2434 | 2017-05-25 14:55:48.302 plugin-container[2435:34884] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 14:55:48 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:55:48 INFO - PID 2434 | 2017-05-25 14:55:48.302 plugin-container[2435:34884] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:55:48 INFO - PID 2434 | 2017-05-25 14:55:48.735 plugin-container[2436:34929] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 14:55:48 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:55:48 INFO - PID 2434 | 2017-05-25 14:55:48.736 plugin-container[2436:34929] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9827, name = 'com.apple.CFPasteboardClient' 14:55:48 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:55:48 INFO - PID 2434 | 2017-05-25 14:55:48.736 plugin-container[2436:34929] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:55:58 INFO - PID 2434 | RSS: Main: 242311168 14:55:58 INFO - PID 2434 | 14:59:00 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:58437/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58437/tests/dromaeo/cssquery-ext.html) 14:59:01 INFO - PID 2434 | RSS: Main: 254443520 14:59:01 INFO - PID 2434 | 15:02:03 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:58437/tests/dromaeo/cssquery-ext.html (next: http://localhost:58437/tests/dromaeo/cssquery-jquery.html) 15:02:04 INFO - PID 2434 | RSS: Main: 269737984 15:02:04 INFO - PID 2434 | 15:05:06 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:58437/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58437/tests/dromaeo/cssquery-mootools.html) 15:05:06 INFO - PID 2434 | RSS: Main: 269950976 15:05:06 INFO - PID 2434 | 15:08:08 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:58437/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58437/tests/dromaeo/cssquery-prototype.html) 15:08:09 INFO - PID 2434 | RSS: Main: 273616896 15:08:09 INFO - PID 2434 | 15:11:12 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:58437/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58437/tests/dromaeo/cssquery-yui.html) 15:11:12 INFO - PID 2434 | RSS: Main: 274268160 15:11:12 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:58437/tests/dromaeo/cssquery-yui.html (next: http://localhost:58437/tests/dromaeo/cssquery-dojo.html) 15:14:14 INFO - PID 2434 | RSS: Main: 275206144 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | __start_tp_report 15:14:14 INFO - PID 2434 | _x_x_mozilla_page_load 15:14:14 INFO - PID 2434 | _x_x_mozilla_page_load_details 15:14:14 INFO - PID 2434 | |i|pagename|runs| 15:14:14 INFO - PID 2434 | |0;dojo.html;3009.4883869742143;3520.067182196718;3569.411047177216;3593.4070878305083;3616.8553257869685;6107;6161.845953851156;6212.037134244194;6214.720337584814;6224.595401298912;6102.701334035026;6113.810786210604;6153.569250152491;6197.628142311453;6234.875302493947;5371.409144994048;5440;5471.753771080291;5479.671219726829;5497.752601132958;5483.122700367942;5546.334439867222;5574.414686457924;5584;5601.80393686221;5383.300170977748;5490.231367608538;5516.972415137939;5550.306211723535;5572.5263352615075;5434.565234781208;5479.671219726829;5480.013597552439;5501.477359650827;5521.089020311684;4642.373279607244;5355.732213389315;5441.319835020622;5477.972610136963;5524.751386187631;6178.258608966908;6197.1324014637985;6231.252249730016;6239.407256310643;6245.46913512352;6372.681365931685;6452.709628066748;6469.35306469354;6522.249941256769;6611.50413718973;6704.5306828522025;6728.865422691568;6737.764178253738;6743;6750.62871542065;6285.748570057192;6290.716917738667;6307.96846015767;6308.716107775115;6312.463440607508;6664.067030615718;6744.258131605519;6796.4562834973085;6874;6910.412614927687;6326.715297811517;6375.6174629522375;6483.092367068614;6483.3192514786215;6537.836554086186;6610.867782644416;6708.295628958889;6711.798646040626;6717.1267735194115;6747.359000894907;6485.286618471964;6576.704048317742;6612.636305003271;6622.602643841385;6625.635590042594;6345.809625711236;6419.967900160469;6610.338966103352;6628.370304821035;6629.469642428594;4746.59653929413;5298.470152984671;5341.092014357569;5394.083005889008;5407.324084489439;4232.195882782262;4774.641901857305;4785.353977213032;4814.470408255089;4842.22524396102;5504.422035686275;6179.5056395488255;6305.653189074553;6311.084892690534;6323.272823625342;5538.750756215899;6497.967510162419;6585.407313341822;6621.437177839937;6686.264510903797;5349.759260833272;6166.7224974876235;6305.054241863665;6320.304766475684;6323.430891219812;4707.811687532529;4722.24444088951;5945.435183657545;5981.641101533922;5982.341942386334;6731.259561448237;6800.081988931629;6847.48643851723;6929.861402771816;6937.583744975318;6719.899201511884;6777.32226777338;6792.388685018372;6795.150606174228;6827;6853.760118395832;6930.618815964968;6960.47796415281;6987.371136597731;7046.295370462913;6448.451881590117;6542.705578248895;6633.005049242653;6648.301928297459;6684.16447944007;6649.933500665125;6726.125603671491;6728.3271672832325;6767.864642707217;6768.4585233182215;6484.28672845996;6562.934370656232;6567.474602031731;6568.770093046721;6581;5338.733063346895;6372.490200783834;6432.6462044588;6483.546151769331;6511.023346498063;6333.714982825876;6392.264889537762;6406.071119687713;6427.453666438218;6428.067930150196;6531.640759758259;6544.574602650812;6546.607203567801;6550.901736473861;6561.737530498917;6233.532485063729;6297.622142671455;6332.271788744353;6349.809505714836;6403.64779937108 15:14:14 INFO - PID 2434 | |1;ext.html;746.123305116488;771.1132197972331;821.6672247739664;1138.2487558211583;1222.7860124478213;38531.80734096329;38894.41658375126;39186.41220381693;39202.80398598006;39216;34826;39377;39424.80287598558;39465.21069578607;39488.40767388492;8186.222308880661;8351.248387645112;8436.282915952152;8436.45163064399;8443.029051659045;8316.916830831706;8338.83322333553;8362.958185209065;8424.115467875878;8428.410011299211;8357.12250213728;8381.580920953958;8422.368322375816;8436;8443.873341899845;8421.326293896504;8422.536760478171;8438.704645337384;8444.155584441569;8468.32253419725;8220.424570280084;8263.669453221924;8266.462679925786;8266.710665126751;8268.751937441886;37427.438588421195;37681.62318376836;37742.43386349207;37753.811230943946;37862.81068594667;3082.2602575381748;3490.2844916791996;3508.245727168671;3523.436250199956;3539.3629146753574;30467.847660761556;30562.69437305621;30579.847100764575;30621.38757224843;30950.38099238025;3066.1414803854955;3095.4582947984013;3110.3623757129735;3115.0966219796233;3119.1266445395327;200122;234376;240553;278775;279804;47216.7639161802;47494.76252618715;47614.523854761705;47649.04701905943;47771.56685299447;49597.008059839325;49797.502024980015;49916.25125623118;53820;54092.188617170774;14307.57077287683;14353.92823035899;14434.422623095168;14442.927785361006;14532.273386330853;11420.31478111316;11465;11498.54005839757;11593.130515211393;11604.6518604442;13894.791578126336;14075.366608502642;14133.929330353283;14154.71690566183;14251.50119745804;5396.97457483077;6158.353372895782;6164.4143806338325;6176.722047507873;6212.347703491068;959.7552624080814;1025.410389026313;1025.9487025648689;1046.2362475392854;1071.7213524483534;21888.671669924963;23517.29448116559;24859.875700621382;25231.74768252294;25250.873745631154;1056.6580442837553;1133.9092872570175;1166.3177041430688;1172.6833754886134;1181.1141643767176;12416.81374779379;13034.175215469842;13039.739205215843;13045.673858153623;13088.08383413171;313535;315220;315520;315812;317436;53747;54153.64615884634;54253.72873135609;54315.72842135764;54354.7282263594;1474.3954978458994;1503.2634009335347;1533.961650958735;1534.99232503839;1537.1699282387608;24398.512029759662;24638.87680561586;25100.870460829672;29941.401171976002;31179;50143.74928125336;50511;50756.74621626868;51267.974640507724;51409.971800564526;275098.24901750725;277226;277403;277656;278153;36326.09184770296;36493.45259821052;36522.8173859129;36679;36712;43229;43559.56440435682;43563;44783;45013.549864500936;29949.251268718457;30749.385012299183;31261.218469538442;31387.215319617193;31724.524132137576;30876.382472350877;31124.844375777975;31385.686143138275;31654;31782.84108579442;36228.81885590555;37679.62320376871;37718.8114059428;37746.62253377541;37816.62183378131;28484.003059892806;29791.957281495044;31250.531242030935;31745.682543174273;32357.838210809736;21919.561608767417;24284.635730463702;24672.383190420383;24744.628830567915;25063.99744010292 15:14:14 INFO - PID 2434 | |2;jquery.html;423.1053155701766;423.57007637248176;434.39185140802874;439.964802815775;447.4853917994307;17939.910300448497;18419.72370414443;18651.16069776858;22352.21767238147;23630.88184559075;23537.76391242829;23775.5244895102;23793.5241295174;23912.76087239127;23936;5977.491913187384;6205.565610407273;6216.689165541728;6240.375962403746;6267.905981410283;5919.733611987449;6065.2721673399255;6096.96951515244;6146.907796383058;6194.101855230989;5262.342207224097;6113.052476866093;6121.173641558377;6123.846903827418;6136.079588061804;5087.974560127194;5868.207791948075;6031.879362412771;6102.664353460558;6125;5414.214938833847;5421.159720243369;5431.674099553999;5438.293021907166;5447.128459446509;22614.643121412602;27135;27978;28026.859865700746;28253;2064.762552306489;2150.5053837617434;2155.245664017597;2161.891905404739;2165.523584811339;17899.463016109406;18010.63978720418;18252.726209106742;18386.356477523357;18601.44195674132;2130.3608917324896;2133.306675330524;2174.8151407130413;2182.225310014938;2184.213683074092;159139.81720365645;161597.19201404037;161783;162039.9999999988;163287;12606.936965315299;12823.35883205836;12930.676733081747;12984.805227921588;13064.738705225842;16186.514404567883;16490.58773530647;16508.42220522276;16513.33946642145;16536;8770.910093984612;9074;9076.8638470423;9151.313651476166;9201.953990230006;8656.69701560455;8656.740297791077;8663.48019118855;8830.514321712239;8842.93884733836;7954.522728636301;8103.027636683636;8130.59347032646;8146.714864979697;8170.101288858221;2000.3998800360125;2208.3374987503685;2210.1380461619983;2212.9114835406403;2214.4685275533757;903.3450748207497;923.2890674180843;927.2998885841282;960.6013504395597;988.5205675247499;16996.660066798595;19217.90391048036;20531.897340513202;20854.688911401812;21073.578528429345;985.4481490365418;991.5290237137447;1003.3578509753763;1019.6838979916249;1021.8518314844306;10749.301295415773;10970.122390208764;11016.669499915015;12135.575254866213;14320.355583998537;487791.4156438281;542700;544186;544502;553611;108933.99999999841;116949.8305016939;125087;131099.34450327686;131989;1120;1215.696075980987;1226.6442731607913;1233.2292317301687;1243.384524660299;21524.27805775503;22168.55662886701;22388.992495337425;22585.5482890338;22586.435339116655;53868;54804.45195547993;55089;55168.89662206814;55221.17168242399;47755.76122119367;79326.60336698279;83941.16058839334;84469.57765211134;84730.72903906323;7433.442491813027;9010.324225682969;9120.179183873483;9146.817063658822;9160.31297652692;14073.155610663393;14091;14278.357473913906;14360.784588230976;14387.712245755236;11526.423678816192;11879.22785018971;11894.464749086139;11904.76190476203;11992.040636749214;10985.450727463754;11008.449577521253;11226.66320010401;11448.713782155179;11591.956723894553;11870.26983793715;11978.940105299765;12163.45264463119;12271.325077120844;12709.682257943625;7472.701091956261;8851.716501107432;8994.730158095268;9049.683261085831;9072.455652660861;11694.239874408137;11800.468978895799;11993.280403175837;12034.338111403957;12143.332116733665 15:14:14 INFO - PID 2434 | |3;mootools.html;1263.601965380905;1388.201783974215;1393.4983405973849;1394.9442022319115;1408.3380811018817;11131.332120072791;11292.491837867296;11613.535458581668;11662.941685291573;11744.001759850413;11414.771704565903;11578.47896844642;11606.883931160686;11610.18728688992;11627.709307267323;2122.8089471947524;2484.6148846928754;2536.5307418127613;2547.337692200032;2550.3496608364935;2466.1861585676684;2508.4481414088873;2516.421223118675;2516.748325167487;2523.3817714659936;2452.399162205262;2464.3962229253857;2479.727230004698;2484.416162201881;2484.627305904111;2439.7438268981678;2455.1529722245796;2465.3590066582638;2468.839525430841;2469.469064151216;2417.7944874685672;2434.294054724128;2454.0919859651863;2454.742252063543;2522.5585522533484;11255;11301.434928253637;11348.829767553494;11371.545138194462;11376.146788990776;6294.622322660655;6517.087607734921;6548.017797330391;6556.541042127053;6560;3721.9020388985314;3896.772516657244;3948.9210215795524;4010.8195131219163;4189.832406703728;1020.9642662506776;1208.3474923541278;1214.6356093172012;1216.9574064907686;1231.7905955987503;14758;15150;15832.525024249291;15876.682466350609;15971.840281597268;4207.852725154605;5399.082156033483;5531.225628412026;5609.186667933209;5628.296462942132;10647.787044259072;11146.944265278784;11594.420278986017;11601.12991525622;11612.071034317234;6285.214348206474;6328.145700330397;6392.552521323556;6402.679866006681;6406.519511036691;5987.221661184105;6002.729877155539;6036.275646922398;6061.484773794189;6111;4333.826646934151;4358.760268185217;4381.6494680425485;4403.361512580658;4403.691741578059;1240.3550153919912;1252.611685391015;1255.8618551959278;1260.9243445393306;1270.1680399338447;766.6128605054523;774.3340726974798;784.5096814490943;797.4696826610356;807.2976510435958;6599.934000660028;6644.800655980328;6888.242293347727;6957.234704182536;6999.160100787836;878.578282424427;947.6825263536792;953.9141477267079;963.6241865672393;964.3297907954045;4335.7615331157085;4338.457692788402;4396.428464299621;4445.955540444619;4489.438820147481;12372.81440778389;13936.442542298195;14402.063865848686;14479.05886117399;14593.78109328361;14053.367598458093;14448.349824257934;14453.927730361282;14461.421543138367;14470.855291447162;8347.582620869054;8436.578171091422;8482.915170848337;8532.445391049561;8536.74389768308;6065.8180254592435;6724.361185687352;6913.343232392915;6981.917802740583;6989.231184569591;12256.509739610672;12517.874821251671;12540.686482838002;12632.6841828955;12641.494340226289;12492.93810026158;12731.745365092831;12787.488500459878;12799;12823.230606163661;8917.331200159884;9066.22937050358;9068.455892646462;9075;9105.043970383014;10368.066873981377;10597.841032384675;10679.932006799258;10701.983311585387;10724.571017159225;9048.095190481163;9080.137386948229;9082.046385129466;9125.726228213165;9130.726078217664;8804.339674524308;8932.732018039469;8955.328350373878;9030.774230644287;9054.683086091954;8031.477953932976;8184.672613095409;8191.713290034821;8196.426250162669;8199.754007379788;5978.282606087298;6008.5493587981955;6055.878882422415;6099.84750381244;6233.812985610439;6019.307779605401;6108.267007958895;6156.138140660221;6261.154744109487;6277.65842647687 15:14:14 INFO - PID 2434 | |4;prototype.html;346.3073852295409;356.0724313164207;383.3349139243414;393.5297319702954;402.51496946179856;6397.3922477364695;6437.45281651059;6455.483561315095;6489.675516224188;6514.478841692665;5428.782848686058;5829.533637309016;6042.848928776772;6186.56694031418;6308.400701933309;4748.643851711118;4764.832616009083;4765.404324459442;4766.7616619169075;4784.497627749089;4779.211430114027;4788.545088216614;4791.808327666889;4797.136515427222;4797.40032495938;4153.397757325186;4461.375407442961;4513.323001549761;4700.200965835799;4730.574248317651;3905.3556163233034;3986.8205930733043;4099.323611604112;4245.936310955338;4682.484926658082;3807.9428808567895;4149.004238982652;4189.455370801777;4197.286461301586;4223.72545784523;5740.368559458456;6077.392260773887;6183.7835675751585;6185.35053819347;6191.133241346215;5186.870328241786;5359.705216213107;5410.810621628263;5450.454954504517;5478.123500239943;6296.527760417987;6507.544471886969;6718.529702920798;6742.561733487357;6762.966185169092;3575.5340310472843;4366.6506679465565;4393.209222339978;4405.889852753707;4426.889327766799;6296.401841825019;6757.087793147961;7112.803445966852;7141.535800172972;7210.315020073084;4370.169667763115;4431.8005689743395;4452.510223875371;4465.464144302705;4502.407544304856;4237.894052648647;4540.704854184468;4558.430196225472;4567.908641827211;4576.222042252825;5289.629725919149;5343.171808369709;5408.459154084639;5411.377691565521;5430.239766432703;5639.379668236491;6725.529212955144;6748.79432269668;6750.392464678104;6755.628440435725;1432.44134787433;1475.5057055886398;1536.7694845773224;1541.7610270408104;1552.906825590468;872.7425409504211;960.4957397366438;1004.437514991607;1024.1653052761976;1027.173125633872;6396.136521569529;6408.67956602168;6415.647139407284;6426.260979987268;6446.484281257488;6717.9664101680155;7011.5793052416;7022.92977070233;7028.613426261502;7084.574925504487;5103.004914041726;6310.109665009562;6370.713317900706;6375.234971803413;6386.860401765368;3105.5186446100893;3367.1077164550925;3499.6228639363135;3659.7621154624862;3664.6518580734787;6651.600903945779;6911.3779759821855;7173.210946795848;7273.0181425507935;7286;6404.7117879695525;7255.839065749455;7262.237465066198;7267.65548373542;7314.305141011595;5538.944610553843;6102.481289090477;6582.647744926074;7138.678759455837;7216.762803530923;6484.870302594016;6617.966910165418;6871.4502839773695;7011.789646310619;7085.362317391459;6208.255009398901;6324.873502530016;6670.499712521678;6885.793426197222;6920.204176519564;5746.7701291949525;5972.104184372379;6239.1681109185365;7172.49792514519;7296.233895440902;4426.225410553076;4456.153330867125;4457.465104187519;4473.575010374009;4485.304777759453;6467.450266727381;6822.590644561342;6844.178698556205;6981.476389270723;7026.824329391806;6746;6750.29121942189;6820;6824.385805277549;6830.726770929107;5811.27359080115;6148.200733904564;6572.8356791080605;6724.4284235840505;6743.865122697617;5795.072788353843;6599.637019963948;6610.206775187008;6622.768203112868;6638.767643132467;6108.664023478574;6129.743402602367;6669.1330127083165;6692.698828552824;6753.425958793558;6606.306337834458;6609.058968758856;6613.933860661332;6632.635205063768;6646.767363142267 15:14:14 INFO - PID 2434 | |5;yui.html;819.139903101743;833.3166803221353;833.5248908122372;845.4504572028181;866.3502373220083;12517.624471265864;14683.33924973376;15206.467773627925;15615.84384156158;16549.503514894557;17246.741298880526;18126.274949002058;18143.4556963291;18146.455606331798;18196.81803181968;817.8854960305562;951.2675240065146;977.1498796047449;988.6589126751295;990.0940639315019;999.3204620857814;1004.9949750251275;1005.36661903001;1005.8088963097025;1006.5671761142705;1039.8804137524169;1040.1938497664296;1041.229490177271;1044;1048.7168464514577;1038.216146809158;1038.8026275007724;1039.6725031615056;1041.4896700616719;1041.536516250269;768.9346405555534;769.6075001749148;773.6711897443564;774.5894675821824;774.8992630957996;17117.058561779097;17506.737398939025;17615.471535853816;17639.823601764074;17675.73486397705;14147.575572732834;17638.735418968725;18035.458936231935;18038.549036274067;18063.819361806345;14520.564383068524;14681.265936703123;14707.926460367737;14790.778138327934;14829.18439485828;3536.9212390220973;3569.37535931211;3594.784312941206;3621.655942685467;3648.908777280563;110452.89547104506;122393;124829.37585312106;131577.99999999904;149720;13597.456101755819;13794.93102534481;13981.440742370394;14020.929895350459;14088.225147616775;22640;24137;24194;25518.872405637852;26152.607710884353;6738.427233685094;6918.515703900779;7686.884696729554;7810.921890781133;7827;3927.7250592458254;4012.9398059028554;4043.8786836394365;4047.170330082326;4052.1895620875944;4282.272013757669;4339.479262488521;4364.585364390441;4369.12617476506;4369.431973843443;932.934694571387;938.863864739609;953.6137864165024;954.160338901762;954.9522523873917;639.4309064932121;653.5098675993005;662.3078882567728;663.0154220981838;667.4193451697055;11167.83248251277;12377.88599026092;12378.876211237952;12440.937795310965;12448.688782780502;592.6710675575097;637.9744810207633;648.5881465269493;648.7859006527834;658.9703463344161;4535.31970204472;5610.102383618601;5758.165066065397;5799.217105690678;5820.679862607597;140822.2958885199;141412.878806818;141629;141986;142506;82620;96226;107602.46198769113;112291;119591;102513.48743256385;102519.487402564;102605;102846;104889;10172.949135254275;11385.829212561985;11628.53485860556;11653.76692466163;11787.41062946832;44846.77576612096;45284.09431811411;45566.31650525311;45644.77177614091;45713;52543.474565253855;62224.755504888744;62346;62722.68638656777;63452;14340.856591434369;14434.783478247624;14693.412263509768;14765;14834.703305934037;13326.400311986177;15465.458708945132;15547.922260388626;16308.266128024505;16316.102614356325;15012.399504019717;15019.549413517612;15078.0199287046;15151.469698560497;15182.240887955779;12538.185017973803;14731.631709206928;14984.250787460802;15052.924735376253;15094.867884908455;14375.20936348469;14498.275086245856;14917.955743098313;14989.10065396036;15035.699286014436;9917.900820991697;10729.034386905216;10760.730981725519;10959.232853700165;11082.501287441923;11692.181547291608;11873.881261187278;11880.702982425508;11956.402179891145;12114.75770484603 15:14:14 INFO - PID 2434 | __end_tp_report 15:14:14 INFO - PID 2434 | __start_cc_report 15:14:14 INFO - PID 2434 | _x_x_mozilla_cycle_collect,398 15:14:14 INFO - PID 2434 | __end_cc_report 15:14:14 INFO - PID 2434 | __startTimestamp1495750454626__endTimestamp 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | ------- Summary: start ------- 15:14:14 INFO - PID 2434 | Number of tests: 6 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | [#0] dojo.html Cycles:165 Average:6116.15 Median:6345.81 stddev:752.63 (11.9%) stddev-sans-first:714.39 15:14:14 INFO - PID 2434 | Values: 3009.5 3520.1 3569.4 3593.4 3616.9 6107.0 6161.8 6212.0 6214.7 6224.6 6102.7 6113.8 6153.6 6197.6 6234.9 5371.4 5440.0 5471.8 5479.7 5497.8 5483.1 5546.3 5574.4 5584.0 5601.8 5383.3 5490.2 5517.0 5550.3 5572.5 5434.6 5479.7 5480.0 5501.5 5521.1 4642.4 5355.7 5441.3 5478.0 5524.8 6178.3 6197.1 6231.3 6239.4 6245.5 6372.7 6452.7 6469.4 6522.2 6611.5 6704.5 6728.9 6737.8 6743.0 6750.6 6285.7 6290.7 6308.0 6308.7 6312.5 6664.1 6744.3 6796.5 6874.0 6910.4 6326.7 6375.6 6483.1 6483.3 6537.8 6610.9 6708.3 6711.8 6717.1 6747.4 6485.3 6576.7 6612.6 6622.6 6625.6 6345.8 6420.0 6610.3 6628.4 6629.5 4746.6 5298.5 5341.1 5394.1 5407.3 4232.2 4774.6 4785.4 4814.5 4842.2 5504.4 6179.5 6305.7 6311.1 6323.3 5538.8 6498.0 6585.4 6621.4 6686.3 5349.8 6166.7 6305.1 6320.3 6323.4 4707.8 4722.2 5945.4 5981.6 5982.3 6731.3 6800.1 6847.5 6929.9 6937.6 6719.9 6777.3 6792.4 6795.2 6827.0 6853.8 6930.6 6960.5 6987.4 7046.3 6448.5 6542.7 6633.0 6648.3 6684.2 6649.9 6726.1 6728.3 6767.9 6768.5 6484.3 6562.9 6567.5 6568.8 6581.0 5338.7 6372.5 6432.6 6483.5 6511.0 6333.7 6392.3 6406.1 6427.5 6428.1 6531.6 6544.6 6546.6 6550.9 6561.7 6233.5 6297.6 6332.3 6349.8 6403.6 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | [#1] ext.html Cycles:180 Average:44100.23 Median:32070.34 stddev:73705.80 (229.8%) stddev-sans-first:73840.68 15:14:14 INFO - PID 2434 | Values: 746.1 771.1 821.7 1138.2 1222.8 38531.8 38894.4 39186.4 39202.8 39216.0 34826.0 39377.0 39424.8 39465.2 39488.4 8186.2 8351.2 8436.3 8436.5 8443.0 8316.9 8338.8 8363.0 8424.1 8428.4 8357.1 8381.6 8422.4 8436.0 8443.9 8421.3 8422.5 8438.7 8444.2 8468.3 8220.4 8263.7 8266.5 8266.7 8268.8 37427.4 37681.6 37742.4 37753.8 37862.8 3082.3 3490.3 3508.2 3523.4 3539.4 30467.8 30562.7 30579.8 30621.4 30950.4 3066.1 3095.5 3110.4 3115.1 3119.1 200122.0 234376.0 240553.0 278775.0 279804.0 47216.8 47494.8 47614.5 47649.0 47771.6 49597.0 49797.5 49916.3 53820.0 54092.2 14307.6 14353.9 14434.4 14442.9 14532.3 11420.3 11465.0 11498.5 11593.1 11604.7 13894.8 14075.4 14133.9 14154.7 14251.5 5397.0 6158.4 6164.4 6176.7 6212.3 959.8 1025.4 1025.9 1046.2 1071.7 21888.7 23517.3 24859.9 25231.7 25250.9 1056.7 1133.9 1166.3 1172.7 1181.1 12416.8 13034.2 13039.7 13045.7 13088.1 313535.0 315220.0 315520.0 315812.0 317436.0 53747.0 54153.6 54253.7 54315.7 54354.7 1474.4 1503.3 1534.0 1535.0 1537.2 24398.5 24638.9 25100.9 29941.4 31179.0 50143.7 50511.0 50756.7 51268.0 51410.0 275098.2 277226.0 277403.0 277656.0 278153.0 36326.1 36493.5 36522.8 36679.0 36712.0 43229.0 43559.6 43563.0 44783.0 45013.5 29949.3 30749.4 31261.2 31387.2 31724.5 30876.4 31124.8 31385.7 31654.0 31782.8 36228.8 37679.6 37718.8 37746.6 37816.6 28484.0 29792.0 31250.5 31745.7 32357.8 21919.6 24284.6 24672.4 24744.6 25064.0 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | [#2] jquery.html Cycles:180 Average:35176.30 Median:22585.99 stddev:91061.17 (403.2%) stddev-sans-first:91279.23 15:14:14 INFO - PID 2434 | Values: 423.1 423.6 434.4 440.0 447.5 17939.9 18419.7 18651.2 22352.2 23630.9 23537.8 23775.5 23793.5 23912.8 23936.0 5977.5 6205.6 6216.7 6240.4 6267.9 5919.7 6065.3 6097.0 6146.9 6194.1 5262.3 6113.1 6121.2 6123.8 6136.1 5088.0 5868.2 6031.9 6102.7 6125.0 5414.2 5421.2 5431.7 5438.3 5447.1 22614.6 27135.0 27978.0 28026.9 28253.0 2064.8 2150.5 2155.2 2161.9 2165.5 17899.5 18010.6 18252.7 18386.4 18601.4 2130.4 2133.3 2174.8 2182.2 2184.2 159139.8 161597.2 161783.0 162040.0 163287.0 12606.9 12823.4 12930.7 12984.8 13064.7 16186.5 16490.6 16508.4 16513.3 16536.0 8770.9 9074.0 9076.9 9151.3 9202.0 8656.7 8656.7 8663.5 8830.5 8842.9 7954.5 8103.0 8130.6 8146.7 8170.1 2000.4 2208.3 2210.1 2212.9 2214.5 903.3 923.3 927.3 960.6 988.5 16996.7 19217.9 20531.9 20854.7 21073.6 985.4 991.5 1003.4 1019.7 1021.9 10749.3 10970.1 11016.7 12135.6 14320.4 487791.4 542700.0 544186.0 544502.0 553611.0 108934.0 116949.8 125087.0 131099.3 131989.0 1120.0 1215.7 1226.6 1233.2 1243.4 21524.3 22168.6 22389.0 22585.5 22586.4 53868.0 54804.5 55089.0 55168.9 55221.2 47755.8 79326.6 83941.2 84469.6 84730.7 7433.4 9010.3 9120.2 9146.8 9160.3 14073.2 14091.0 14278.4 14360.8 14387.7 11526.4 11879.2 11894.5 11904.8 11992.0 10985.5 11008.4 11226.7 11448.7 11592.0 11870.3 11978.9 12163.5 12271.3 12709.7 7472.7 8851.7 8994.7 9049.7 9072.5 11694.2 11800.5 11993.3 12034.3 12143.3 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | [#3] mootools.html Cycles:180 Average:6936.53 Median:3809.34 stddev:4271.89 (112.1%) stddev-sans-first:4262.60 15:14:14 INFO - PID 2434 | Values: 1263.6 1388.2 1393.5 1394.9 1408.3 11131.3 11292.5 11613.5 11662.9 11744.0 11414.8 11578.5 11606.9 11610.2 11627.7 2122.8 2484.6 2536.5 2547.3 2550.3 2466.2 2508.4 2516.4 2516.7 2523.4 2452.4 2464.4 2479.7 2484.4 2484.6 2439.7 2455.2 2465.4 2468.8 2469.5 2417.8 2434.3 2454.1 2454.7 2522.6 11255.0 11301.4 11348.8 11371.5 11376.1 6294.6 6517.1 6548.0 6556.5 6560.0 3721.9 3896.8 3948.9 4010.8 4189.8 1021.0 1208.3 1214.6 1217.0 1231.8 14758.0 15150.0 15832.5 15876.7 15971.8 4207.9 5399.1 5531.2 5609.2 5628.3 10647.8 11146.9 11594.4 11601.1 11612.1 6285.2 6328.1 6392.6 6402.7 6406.5 5987.2 6002.7 6036.3 6061.5 6111.0 4333.8 4358.8 4381.6 4403.4 4403.7 1240.4 1252.6 1255.9 1260.9 1270.2 766.6 774.3 784.5 797.5 807.3 6599.9 6644.8 6888.2 6957.2 6999.2 878.6 947.7 953.9 963.6 964.3 4335.8 4338.5 4396.4 4446.0 4489.4 12372.8 13936.4 14402.1 14479.1 14593.8 14053.4 14448.3 14453.9 14461.4 14470.9 8347.6 8436.6 8482.9 8532.4 8536.7 6065.8 6724.4 6913.3 6981.9 6989.2 12256.5 12517.9 12540.7 12632.7 12641.5 12492.9 12731.7 12787.5 12799.0 12823.2 8917.3 9066.2 9068.5 9075.0 9105.0 10368.1 10597.8 10679.9 10702.0 10724.6 9048.1 9080.1 9082.0 9125.7 9130.7 8804.3 8932.7 8955.3 9030.8 9054.7 8031.5 8184.7 8191.7 8196.4 8199.8 5978.3 6008.5 6055.9 6099.8 6233.8 6019.3 6108.3 6156.1 6261.2 6277.7 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | [#4] prototype.html Cycles:180 Average:5423.59 Median:6185.96 stddev:1721.12 (27.8%) stddev-sans-first:1683.23 15:14:14 INFO - PID 2434 | Values: 346.3 356.1 383.3 393.5 402.5 6397.4 6437.5 6455.5 6489.7 6514.5 5428.8 5829.5 6042.8 6186.6 6308.4 4748.6 4764.8 4765.4 4766.8 4784.5 4779.2 4788.5 4791.8 4797.1 4797.4 4153.4 4461.4 4513.3 4700.2 4730.6 3905.4 3986.8 4099.3 4245.9 4682.5 3807.9 4149.0 4189.5 4197.3 4223.7 5740.4 6077.4 6183.8 6185.4 6191.1 5186.9 5359.7 5410.8 5450.5 5478.1 6296.5 6507.5 6718.5 6742.6 6763.0 3575.5 4366.7 4393.2 4405.9 4426.9 6296.4 6757.1 7112.8 7141.5 7210.3 4370.2 4431.8 4452.5 4465.5 4502.4 4237.9 4540.7 4558.4 4567.9 4576.2 5289.6 5343.2 5408.5 5411.4 5430.2 5639.4 6725.5 6748.8 6750.4 6755.6 1432.4 1475.5 1536.8 1541.8 1552.9 872.7 960.5 1004.4 1024.2 1027.2 6396.1 6408.7 6415.6 6426.3 6446.5 6718.0 7011.6 7022.9 7028.6 7084.6 5103.0 6310.1 6370.7 6375.2 6386.9 3105.5 3367.1 3499.6 3659.8 3664.7 6651.6 6911.4 7173.2 7273.0 7286.0 6404.7 7255.8 7262.2 7267.7 7314.3 5538.9 6102.5 6582.6 7138.7 7216.8 6484.9 6618.0 6871.5 7011.8 7085.4 6208.3 6324.9 6670.5 6885.8 6920.2 5746.8 5972.1 6239.2 7172.5 7296.2 4426.2 4456.2 4457.5 4473.6 4485.3 6467.5 6822.6 6844.2 6981.5 7026.8 6746.0 6750.3 6820.0 6824.4 6830.7 5811.3 6148.2 6572.8 6724.4 6743.9 5795.1 6599.6 6610.2 6622.8 6638.8 6108.7 6129.7 6669.1 6692.7 6753.4 6606.3 6609.1 6613.9 6632.6 6646.8 15:14:14 INFO - PID 2434 | 15:14:14 INFO - PID 2434 | [#5] yui.html Cycles:180 Average:23782.01 Median:16432.80 stddev:36407.29 (221.6%) stddev-sans-first:36468.60 15:14:14 INFO - PID 2434 | Values: 819.1 833.3 833.5 845.5 866.4 12517.6 14683.3 15206.5 15615.8 16549.5 17246.7 18126.3 18143.5 18146.5 18196.8 817.9 951.3 977.1 988.7 990.1 999.3 1005.0 1005.4 1005.8 1006.6 1039.9 1040.2 1041.2 1044.0 1048.7 1038.2 1038.8 1039.7 1041.5 1041.5 768.9 769.6 773.7 774.6 774.9 17117.1 17506.7 17615.5 17639.8 17675.7 14147.6 17638.7 18035.5 18038.5 18063.8 14520.6 14681.3 14707.9 14790.8 14829.2 3536.9 3569.4 3594.8 3621.7 3648.9 110452.9 122393.0 124829.4 131578.0 149720.0 13597.5 13794.9 13981.4 14020.9 14088.2 22640.0 24137.0 24194.0 25518.9 26152.6 6738.4 6918.5 7686.9 7810.9 7827.0 3927.7 4012.9 4043.9 4047.2 4052.2 4282.3 4339.5 4364.6 4369.1 4369.4 932.9 938.9 953.6 954.2 955.0 639.4 653.5 662.3 663.0 667.4 11167.8 12377.9 12378.9 12440.9 12448.7 592.7 638.0 648.6 648.8 659.0 4535.3 5610.1 5758.2 5799.2 5820.7 140822.3 141412.9 141629.0 141986.0 142506.0 82620.0 96226.0 107602.5 112291.0 119591.0 102513.5 102519.5 102605.0 102846.0 104889.0 10172.9 11385.8 11628.5 11653.8 11787.4 44846.8 45284.1 45566.3 45644.8 45713.0 52543.5 62224.8 62346.0 62722.7 63452.0 14340.9 14434.8 14693.4 14765.0 14834.7 13326.4 15465.5 15547.9 16308.3 16316.1 15012.4 15019.5 15078.0 15151.5 15182.2 12538.2 14731.6 14984.3 15052.9 15094.9 14375.2 14498.3 14918.0 14989.1 15035.7 9917.9 10729.0 10760.7 10959.2 11082.5 11692.2 11873.9 11880.7 11956.4 12114.8 15:14:14 INFO - PID 2434 | -------- Summary: end -------- 15:14:14 INFO - PID 2434 | 15:14:15 INFO - TEST-INFO | 2434: exit 0 15:14:15 INFO - TEST-OK | dromaeo_css | took 1142488ms 15:14:15 INFO - TEST-START | kraken 15:14:15 INFO - operating with platform_type : mac_ 15:14:15 INFO - Initialising browser for kraken test... 15:14:15 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58437/getInfo.html -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmptLyMyd/profile 15:14:15 INFO - TEST-INFO | started process 2439 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58437/getInfo.html -foreground) 15:14:48 INFO - TEST-INFO | 2439: killed by SIGTERM 15:14:48 INFO - Browser initialized. 15:14:48 INFO - Running cycle 1/1 for kraken test... 15:14:48 INFO - TEST-INFO | started process 2443 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmptLyMyd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:50 INFO - PID 2443 | 2017-05-25 15:14:50.691 plugin-container[2444:37283] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 15:14:50 INFO - PID 2443 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:50 INFO - PID 2443 | 2017-05-25 15:14:50.692 plugin-container[2444:37283] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 15:14:50 INFO - PID 2443 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:50 INFO - PID 2443 | 2017-05-25 15:14:50.692 plugin-container[2444:37283] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:14:51 INFO - PID 2443 | 2017-05-25 15:14:51.139 plugin-container[2445:37327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 15:14:51 INFO - PID 2443 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:51 INFO - PID 2443 | 2017-05-25 15:14:51.140 plugin-container[2445:37327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x932f, name = 'com.apple.CFPasteboardClient' 15:14:51 INFO - PID 2443 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:14:51 INFO - PID 2443 | 2017-05-25 15:14:51.140 plugin-container[2445:37327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:15:00 INFO - PID 2443 | RSS: Main: 241618944 15:15:00 INFO - PID 2443 | 15:15:03 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58437/tests/kraken/driver.html?testName=audio-beat-detection) 15:15:04 INFO - PID 2443 | RSS: Main: 256598016 15:15:04 INFO - PID 2443 | 15:15:07 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58437/tests/kraken/driver.html?testName=audio-dft) 15:15:07 INFO - PID 2443 | RSS: Main: 266121216 15:15:07 INFO - PID 2443 | 15:15:11 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58437/tests/kraken/driver.html?testName=audio-fft) 15:15:11 INFO - PID 2443 | RSS: Main: 268718080 15:15:11 INFO - PID 2443 | 15:15:13 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58437/tests/kraken/driver.html?testName=audio-oscillator) 15:15:14 INFO - PID 2443 | RSS: Main: 270716928 15:15:14 INFO - PID 2443 | 15:15:16 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58437/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:15:16 INFO - PID 2443 | RSS: Main: 271527936 15:15:16 INFO - PID 2443 | 15:15:19 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58437/tests/kraken/driver.html?testName=imaging-darkroom) 15:15:20 INFO - PID 2443 | RSS: Main: 280694784 15:15:20 INFO - PID 2443 | 15:15:23 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58437/tests/kraken/driver.html?testName=imaging-desaturate) 15:15:24 INFO - PID 2443 | RSS: Main: 279367680 15:15:24 INFO - PID 2443 | 15:15:27 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58437/tests/kraken/driver.html?testName=json-parse-financial) 15:15:27 INFO - PID 2443 | RSS: Main: 278118400 15:15:27 INFO - PID 2443 | 15:15:29 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58437/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:15:29 INFO - PID 2443 | RSS: Main: 278024192 15:15:29 INFO - PID 2443 | 15:15:31 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:15:32 INFO - PID 2443 | RSS: Main: 278241280 15:15:32 INFO - PID 2443 | 15:15:34 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:15:35 INFO - PID 2443 | RSS: Main: 277950464 15:15:35 INFO - PID 2443 | 15:15:38 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:15:38 INFO - PID 2443 | RSS: Main: 278044672 15:15:38 INFO - PID 2443 | 15:15:41 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:15:42 INFO - PID 2443 | RSS: Main: 277704704 15:15:42 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | Cycle 1(1): loaded http://localhost:58437/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58437/tests/kraken/driver.html?testName=ai-astar) 15:15:44 INFO - PID 2443 | RSS: Main: 279617536 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | __start_tp_report 15:15:44 INFO - PID 2443 | _x_x_mozilla_page_load 15:15:44 INFO - PID 2443 | _x_x_mozilla_page_load_details 15:15:44 INFO - PID 2443 | |i|pagename|runs| 15:15:44 INFO - PID 2443 | |0;ai-astar;103;117;130;117;95;164;168;97;158;169 15:15:44 INFO - PID 2443 | |1;audio-beat-detection;151;113;118;146;109;114;126;142;159;112 15:15:44 INFO - PID 2443 | |2;audio-dft;204;202;207;221;209;246;217;201;198;196 15:15:44 INFO - PID 2443 | |3;audio-fft;84;57;61;86;64;60;69;70;76;60 15:15:44 INFO - PID 2443 | |4;audio-oscillator;88;69;82;83;103;100;88;71;73;91 15:15:44 INFO - PID 2443 | |5;imaging-gaussian-blur;91;92;90;135;136;90;91;91;95;135 15:15:44 INFO - PID 2443 | |6;imaging-darkroom;98;99;98;97;98;104;99;98;98;98 15:15:44 INFO - PID 2443 | |7;imaging-desaturate;93;87;93;86;96;91;93;94;91;98 15:15:44 INFO - PID 2443 | |8;json-parse-financial;51;50;53;52;52;52;52;50;54;69 15:15:44 INFO - PID 2443 | |9;json-stringify-tinderbox;56;64;66;62;67;66;61;57;55;56 15:15:44 INFO - PID 2443 | |10;stanford-crypto-aes;86;89;90;108;112;110;101;87;143;87 15:15:44 INFO - PID 2443 | |11;stanford-crypto-ccm;154;139;145;152;152;139;138;138;148;171 15:15:44 INFO - PID 2443 | |12;stanford-crypto-pbkdf2;187;187;198;169;224;218;194;181;187;180 15:15:44 INFO - PID 2443 | |13;stanford-crypto-sha256-iterative;66;67;66;68;68;66;67;71;69;66 15:15:44 INFO - PID 2443 | __end_tp_report 15:15:44 INFO - PID 2443 | __start_cc_report 15:15:44 INFO - PID 2443 | _x_x_mozilla_cycle_collect,1043 15:15:44 INFO - PID 2443 | __end_cc_report 15:15:44 INFO - PID 2443 | __startTimestamp1495750544480__endTimestamp 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | ------- Summary: start ------- 15:15:44 INFO - PID 2443 | Number of tests: 14 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#0] ai-astar Cycles:10 Average:131.80 Median:166.00 stddev:30.28 (18.2%) stddev-sans-first:30.27 15:15:44 INFO - PID 2443 | Values: 103.0 117.0 130.0 117.0 95.0 164.0 168.0 97.0 158.0 169.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:134.00 stddev:18.68 (13.9%) stddev-sans-first:18.04 15:15:44 INFO - PID 2443 | Values: 151.0 113.0 118.0 146.0 109.0 114.0 126.0 142.0 159.0 112.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#2] audio-dft Cycles:10 Average:210.10 Median:208.00 stddev:14.90 (7.2%) stddev-sans-first:15.63 15:15:44 INFO - PID 2443 | Values: 204.0 202.0 207.0 221.0 209.0 246.0 217.0 201.0 198.0 196.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#3] audio-fft Cycles:10 Average:68.70 Median:69.50 stddev:10.32 (14.8%) stddev-sans-first:9.34 15:15:44 INFO - PID 2443 | Values: 84.0 57.0 61.0 86.0 64.0 60.0 69.0 70.0 76.0 60.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:82.50 stddev:11.60 (14.1%) stddev-sans-first:12.25 15:15:44 INFO - PID 2443 | Values: 88.0 69.0 82.0 83.0 103.0 100.0 88.0 71.0 73.0 91.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:91.00 stddev:21.26 (23.4%) stddev-sans-first:21.97 15:15:44 INFO - PID 2443 | Values: 91.0 92.0 90.0 135.0 136.0 90.0 91.0 91.0 95.0 135.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:98.00 stddev:1.95 (2.0%) stddev-sans-first:2.05 15:15:44 INFO - PID 2443 | Values: 98.0 99.0 98.0 97.0 98.0 104.0 99.0 98.0 98.0 98.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:93.00 stddev:3.68 (4.0%) stddev-sans-first:3.89 15:15:44 INFO - PID 2443 | Values: 93.0 87.0 93.0 86.0 96.0 91.0 93.0 94.0 91.0 98.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:52.00 stddev:5.58 (10.7%) stddev-sans-first:5.85 15:15:44 INFO - PID 2443 | Values: 51.0 50.0 53.0 52.0 52.0 52.0 52.0 50.0 54.0 69.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:63.00 stddev:4.69 (7.4%) stddev-sans-first:4.61 15:15:44 INFO - PID 2443 | Values: 56.0 64.0 66.0 62.0 67.0 66.0 61.0 57.0 55.0 56.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#10] stanford-crypto-aes Cycles:10 Average:101.30 Median:86.50 stddev:17.94 (20.7%) stddev-sans-first:18.15 15:15:44 INFO - PID 2443 | Values: 86.0 89.0 90.0 108.0 112.0 110.0 101.0 87.0 143.0 87.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:150.00 stddev:10.36 (6.9%) stddev-sans-first:10.73 15:15:44 INFO - PID 2443 | Values: 154.0 139.0 145.0 152.0 152.0 139.0 138.0 138.0 148.0 171.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.50 Median:190.50 stddev:17.02 (8.9%) stddev-sans-first:17.93 15:15:44 INFO - PID 2443 | Values: 187.0 187.0 198.0 169.0 224.0 218.0 194.0 181.0 187.0 180.0 15:15:44 INFO - PID 2443 | 15:15:44 INFO - PID 2443 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.65 (2.4%) stddev-sans-first:1.67 15:15:44 INFO - PID 2443 | Values: 66.0 67.0 66.0 68.0 68.0 66.0 67.0 71.0 69.0 66.0 15:15:44 INFO - PID 2443 | -------- Summary: end -------- 15:15:44 INFO - PID 2443 | 15:15:45 INFO - TEST-INFO | 2443: exit 0 15:15:45 INFO - TEST-OK | kraken | took 90125ms 15:15:45 INFO - SUITE-END | took 1232s 15:15:45 INFO - Completed test suite (00:20:32) 15:15:45 INFO - javascript benchmark 15:15:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7905.480168991251, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3009.4883869742143, 3520.067182196718, 3569.411047177216, 3593.4070878305083, 3616.8553257869685, 6107.0, 6161.845953851156, 6212.037134244194, 6214.720337584814, 6224.595401298912, 6102.701334035026, 6113.810786210604, 6153.569250152491, 6197.628142311453, 6234.875302493947, 5371.409144994048, 5440.0, 5471.753771080291, 5479.671219726829, 5497.752601132958, 5483.122700367942, 5546.334439867222, 5574.414686457924, 5584.0, 5601.80393686221, 5383.300170977748, 5490.231367608538, 5516.972415137939, 5550.306211723535, 5572.5263352615075, 5434.565234781208, 5479.671219726829, 5480.013597552439, 5501.477359650827, 5521.089020311684, 4642.373279607244, 5355.732213389315, 5441.319835020622, 5477.972610136963, 5524.751386187631, 6178.258608966908, 6197.1324014637985, 6231.252249730016, 6239.407256310643, 6245.46913512352, 6372.681365931685, 6452.709628066748, 6469.35306469354, 6522.249941256769, 6611.50413718973, 6704.5306828522025, 6728.865422691568, 6737.764178253738, 6743.0, 6750.62871542065, 6285.748570057192, 6290.716917738667, 6307.96846015767, 6308.716107775115, 6312.463440607508, 6664.067030615718, 6744.258131605519, 6796.4562834973085, 6874.0, 6910.412614927687, 6326.715297811517, 6375.6174629522375, 6483.092367068614, 6483.3192514786215, 6537.836554086186, 6610.867782644416, 6708.295628958889, 6711.798646040626, 6717.1267735194115, 6747.359000894907, 6485.286618471964, 6576.704048317742, 6612.636305003271, 6622.602643841385, 6625.635590042594, 6345.809625711236, 6419.967900160469, 6610.338966103352, 6628.370304821035, 6629.469642428594, 4746.59653929413, 5298.470152984671, 5341.092014357569, 5394.083005889008, 5407.324084489439, 4232.195882782262, 4774.641901857305, 4785.353977213032, 4814.470408255089, 4842.22524396102, 5504.422035686275, 6179.5056395488255, 6305.653189074553, 6311.084892690534, 6323.272823625342, 5538.750756215899, 6497.967510162419, 6585.407313341822, 6621.437177839937, 6686.264510903797, 5349.759260833272, 6166.7224974876235, 6305.054241863665, 6320.304766475684, 6323.430891219812, 4707.811687532529, 4722.24444088951, 5945.435183657545, 5981.641101533922, 5982.341942386334, 6731.259561448237, 6800.081988931629, 6847.48643851723, 6929.861402771816, 6937.583744975318, 6719.899201511884, 6777.32226777338, 6792.388685018372, 6795.150606174228, 6827.0, 6853.760118395832, 6930.618815964968, 6960.47796415281, 6987.371136597731, 7046.295370462913, 6448.451881590117, 6542.705578248895, 6633.005049242653, 6648.301928297459, 6684.16447944007, 6649.933500665125, 6726.125603671491, 6728.3271672832325, 6767.864642707217, 6768.4585233182215, 6484.28672845996, 6562.934370656232, 6567.474602031731, 6568.770093046721, 6581.0, 5338.733063346895, 6372.490200783834, 6432.6462044588, 6483.546151769331, 6511.023346498063, 6333.714982825876, 6392.264889537762, 6406.071119687713, 6427.453666438218, 6428.067930150196, 6531.640759758259, 6544.574602650812, 6546.607203567801, 6550.901736473861, 6561.737530498917, 6233.532485063729, 6297.622142671455, 6332.271788744353, 6349.809505714836, 6403.64779937108], "value": 6065.136932677682, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.123305116488, 771.1132197972331, 821.6672247739664, 1138.2487558211583, 1222.7860124478213, 38531.80734096329, 38894.41658375126, 39186.41220381693, 39202.80398598006, 39216.0, 34826.0, 39377.0, 39424.80287598558, 39465.21069578607, 39488.40767388492, 8186.222308880661, 8351.248387645112, 8436.282915952152, 8436.45163064399, 8443.029051659045, 8316.916830831706, 8338.83322333553, 8362.958185209065, 8424.115467875878, 8428.410011299211, 8357.12250213728, 8381.580920953958, 8422.368322375816, 8436.0, 8443.873341899845, 8421.326293896504, 8422.536760478171, 8438.704645337384, 8444.155584441569, 8468.32253419725, 8220.424570280084, 8263.669453221924, 8266.462679925786, 8266.710665126751, 8268.751937441886, 37427.438588421195, 37681.62318376836, 37742.43386349207, 37753.811230943946, 37862.81068594667, 3082.2602575381748, 3490.2844916791996, 3508.245727168671, 3523.436250199956, 3539.3629146753574, 30467.847660761556, 30562.69437305621, 30579.847100764575, 30621.38757224843, 30950.38099238025, 3066.1414803854955, 3095.4582947984013, 3110.3623757129735, 3115.0966219796233, 3119.1266445395327, 200122.0, 234376.0, 240553.0, 278775.0, 279804.0, 47216.7639161802, 47494.76252618715, 47614.523854761705, 47649.04701905943, 47771.56685299447, 49597.008059839325, 49797.502024980015, 49916.25125623118, 53820.0, 54092.188617170774, 14307.57077287683, 14353.92823035899, 14434.422623095168, 14442.927785361006, 14532.273386330853, 11420.31478111316, 11465.0, 11498.54005839757, 11593.130515211393, 11604.6518604442, 13894.791578126336, 14075.366608502642, 14133.929330353283, 14154.71690566183, 14251.50119745804, 5396.97457483077, 6158.353372895782, 6164.4143806338325, 6176.722047507873, 6212.347703491068, 959.7552624080814, 1025.410389026313, 1025.9487025648689, 1046.2362475392854, 1071.7213524483534, 21888.671669924963, 23517.29448116559, 24859.875700621382, 25231.74768252294, 25250.873745631154, 1056.6580442837553, 1133.9092872570175, 1166.3177041430688, 1172.6833754886134, 1181.1141643767176, 12416.81374779379, 13034.175215469842, 13039.739205215843, 13045.673858153623, 13088.08383413171, 313535.0, 315220.0, 315520.0, 315812.0, 317436.0, 53747.0, 54153.64615884634, 54253.72873135609, 54315.72842135764, 54354.7282263594, 1474.3954978458994, 1503.2634009335347, 1533.961650958735, 1534.99232503839, 1537.1699282387608, 24398.512029759662, 24638.87680561586, 25100.870460829672, 29941.401171976002, 31179.0, 50143.74928125336, 50511.0, 50756.74621626868, 51267.974640507724, 51409.971800564526, 275098.24901750725, 277226.0, 277403.0, 277656.0, 278153.0, 36326.09184770296, 36493.45259821052, 36522.8173859129, 36679.0, 36712.0, 43229.0, 43559.56440435682, 43563.0, 44783.0, 45013.549864500936, 29949.251268718457, 30749.385012299183, 31261.218469538442, 31387.215319617193, 31724.524132137576, 30876.382472350877, 31124.844375777975, 31385.686143138275, 31654.0, 31782.84108579442, 36228.81885590555, 37679.62320376871, 37718.8114059428, 37746.62253377541, 37816.62183378131, 28484.003059892806, 29791.957281495044, 31250.531242030935, 31745.682543174273, 32357.838210809736, 21919.561608767417, 24284.635730463702, 24672.383190420383, 24744.628830567915, 25063.99744010292], "value": 17759.785588424587, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.1053155701766, 423.57007637248176, 434.39185140802874, 439.964802815775, 447.4853917994307, 17939.910300448497, 18419.72370414443, 18651.16069776858, 22352.21767238147, 23630.88184559075, 23537.76391242829, 23775.5244895102, 23793.5241295174, 23912.76087239127, 23936.0, 5977.491913187384, 6205.565610407273, 6216.689165541728, 6240.375962403746, 6267.905981410283, 5919.733611987449, 6065.2721673399255, 6096.96951515244, 6146.907796383058, 6194.101855230989, 5262.342207224097, 6113.052476866093, 6121.173641558377, 6123.846903827418, 6136.079588061804, 5087.974560127194, 5868.207791948075, 6031.879362412771, 6102.664353460558, 6125.0, 5414.214938833847, 5421.159720243369, 5431.674099553999, 5438.293021907166, 5447.128459446509, 22614.643121412602, 27135.0, 27978.0, 28026.859865700746, 28253.0, 2064.762552306489, 2150.5053837617434, 2155.245664017597, 2161.891905404739, 2165.523584811339, 17899.463016109406, 18010.63978720418, 18252.726209106742, 18386.356477523357, 18601.44195674132, 2130.3608917324896, 2133.306675330524, 2174.8151407130413, 2182.225310014938, 2184.213683074092, 159139.81720365645, 161597.19201404037, 161783.0, 162039.9999999988, 163287.0, 12606.936965315299, 12823.35883205836, 12930.676733081747, 12984.805227921588, 13064.738705225842, 16186.514404567883, 16490.58773530647, 16508.42220522276, 16513.33946642145, 16536.0, 8770.910093984612, 9074.0, 9076.8638470423, 9151.313651476166, 9201.953990230006, 8656.69701560455, 8656.740297791077, 8663.48019118855, 8830.514321712239, 8842.93884733836, 7954.522728636301, 8103.027636683636, 8130.59347032646, 8146.714864979697, 8170.101288858221, 2000.3998800360125, 2208.3374987503685, 2210.1380461619983, 2212.9114835406403, 2214.4685275533757, 903.3450748207497, 923.2890674180843, 927.2998885841282, 960.6013504395597, 988.5205675247499, 16996.660066798595, 19217.90391048036, 20531.897340513202, 20854.688911401812, 21073.578528429345, 985.4481490365418, 991.5290237137447, 1003.3578509753763, 1019.6838979916249, 1021.8518314844306, 10749.301295415773, 10970.122390208764, 11016.669499915015, 12135.575254866213, 14320.355583998537, 487791.4156438281, 542700.0, 544186.0, 544502.0, 553611.0, 108933.99999999841, 116949.8305016939, 125087.0, 131099.34450327686, 131989.0, 1120.0, 1215.696075980987, 1226.6442731607913, 1233.2292317301687, 1243.384524660299, 21524.27805775503, 22168.55662886701, 22388.992495337425, 22585.5482890338, 22586.435339116655, 53868.0, 54804.45195547993, 55089.0, 55168.89662206814, 55221.17168242399, 47755.76122119367, 79326.60336698279, 83941.16058839334, 84469.57765211134, 84730.72903906323, 7433.442491813027, 9010.324225682969, 9120.179183873483, 9146.817063658822, 9160.31297652692, 14073.155610663393, 14091.0, 14278.357473913906, 14360.784588230976, 14387.712245755236, 11526.423678816192, 11879.22785018971, 11894.464749086139, 11904.76190476203, 11992.040636749214, 10985.450727463754, 11008.449577521253, 11226.66320010401, 11448.713782155179, 11591.956723894553, 11870.26983793715, 11978.940105299765, 12163.45264463119, 12271.325077120844, 12709.682257943625, 7472.701091956261, 8851.716501107432, 8994.730158095268, 9049.683261085831, 9072.455652660861, 11694.239874408137, 11800.468978895799, 11993.280403175837, 12034.338111403957, 12143.332116733665], "value": 10483.088346472001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.601965380905, 1388.201783974215, 1393.4983405973849, 1394.9442022319115, 1408.3380811018817, 11131.332120072791, 11292.491837867296, 11613.535458581668, 11662.941685291573, 11744.001759850413, 11414.771704565903, 11578.47896844642, 11606.883931160686, 11610.18728688992, 11627.709307267323, 2122.8089471947524, 2484.6148846928754, 2536.5307418127613, 2547.337692200032, 2550.3496608364935, 2466.1861585676684, 2508.4481414088873, 2516.421223118675, 2516.748325167487, 2523.3817714659936, 2452.399162205262, 2464.3962229253857, 2479.727230004698, 2484.416162201881, 2484.627305904111, 2439.7438268981678, 2455.1529722245796, 2465.3590066582638, 2468.839525430841, 2469.469064151216, 2417.7944874685672, 2434.294054724128, 2454.0919859651863, 2454.742252063543, 2522.5585522533484, 11255.0, 11301.434928253637, 11348.829767553494, 11371.545138194462, 11376.146788990776, 6294.622322660655, 6517.087607734921, 6548.017797330391, 6556.541042127053, 6560.0, 3721.9020388985314, 3896.772516657244, 3948.9210215795524, 4010.8195131219163, 4189.832406703728, 1020.9642662506776, 1208.3474923541278, 1214.6356093172012, 1216.9574064907686, 1231.7905955987503, 14758.0, 15150.0, 15832.525024249291, 15876.682466350609, 15971.840281597268, 4207.852725154605, 5399.082156033483, 5531.225628412026, 5609.186667933209, 5628.296462942132, 10647.787044259072, 11146.944265278784, 11594.420278986017, 11601.12991525622, 11612.071034317234, 6285.214348206474, 6328.145700330397, 6392.552521323556, 6402.679866006681, 6406.519511036691, 5987.221661184105, 6002.729877155539, 6036.275646922398, 6061.484773794189, 6111.0, 4333.826646934151, 4358.760268185217, 4381.6494680425485, 4403.361512580658, 4403.691741578059, 1240.3550153919912, 1252.611685391015, 1255.8618551959278, 1260.9243445393306, 1270.1680399338447, 766.6128605054523, 774.3340726974798, 784.5096814490943, 797.4696826610356, 807.2976510435958, 6599.934000660028, 6644.800655980328, 6888.242293347727, 6957.234704182536, 6999.160100787836, 878.578282424427, 947.6825263536792, 953.9141477267079, 963.6241865672393, 964.3297907954045, 4335.7615331157085, 4338.457692788402, 4396.428464299621, 4445.955540444619, 4489.438820147481, 12372.81440778389, 13936.442542298195, 14402.063865848686, 14479.05886117399, 14593.78109328361, 14053.367598458093, 14448.349824257934, 14453.927730361282, 14461.421543138367, 14470.855291447162, 8347.582620869054, 8436.578171091422, 8482.915170848337, 8532.445391049561, 8536.74389768308, 6065.8180254592435, 6724.361185687352, 6913.343232392915, 6981.917802740583, 6989.231184569591, 12256.509739610672, 12517.874821251671, 12540.686482838002, 12632.6841828955, 12641.494340226289, 12492.93810026158, 12731.745365092831, 12787.488500459878, 12799.0, 12823.230606163661, 8917.331200159884, 9066.22937050358, 9068.455892646462, 9075.0, 9105.043970383014, 10368.066873981377, 10597.841032384675, 10679.932006799258, 10701.983311585387, 10724.571017159225, 9048.095190481163, 9080.137386948229, 9082.046385129466, 9125.726228213165, 9130.726078217664, 8804.339674524308, 8932.732018039469, 8955.328350373878, 9030.774230644287, 9054.683086091954, 8031.477953932976, 8184.672613095409, 8191.713290034821, 8196.426250162669, 8199.754007379788, 5978.282606087298, 6008.5493587981955, 6055.878882422415, 6099.84750381244, 6233.812985610439, 6019.307779605401, 6108.267007958895, 6156.138140660221, 6261.154744109487, 6277.65842647687], "value": 5287.353618986138, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.3073852295409, 356.0724313164207, 383.3349139243414, 393.5297319702954, 402.51496946179856, 6397.3922477364695, 6437.45281651059, 6455.483561315095, 6489.675516224188, 6514.478841692665, 5428.782848686058, 5829.533637309016, 6042.848928776772, 6186.56694031418, 6308.400701933309, 4748.643851711118, 4764.832616009083, 4765.404324459442, 4766.7616619169075, 4784.497627749089, 4779.211430114027, 4788.545088216614, 4791.808327666889, 4797.136515427222, 4797.40032495938, 4153.397757325186, 4461.375407442961, 4513.323001549761, 4700.200965835799, 4730.574248317651, 3905.3556163233034, 3986.8205930733043, 4099.323611604112, 4245.936310955338, 4682.484926658082, 3807.9428808567895, 4149.004238982652, 4189.455370801777, 4197.286461301586, 4223.72545784523, 5740.368559458456, 6077.392260773887, 6183.7835675751585, 6185.35053819347, 6191.133241346215, 5186.870328241786, 5359.705216213107, 5410.810621628263, 5450.454954504517, 5478.123500239943, 6296.527760417987, 6507.544471886969, 6718.529702920798, 6742.561733487357, 6762.966185169092, 3575.5340310472843, 4366.6506679465565, 4393.209222339978, 4405.889852753707, 4426.889327766799, 6296.401841825019, 6757.087793147961, 7112.803445966852, 7141.535800172972, 7210.315020073084, 4370.169667763115, 4431.8005689743395, 4452.510223875371, 4465.464144302705, 4502.407544304856, 4237.894052648647, 4540.704854184468, 4558.430196225472, 4567.908641827211, 4576.222042252825, 5289.629725919149, 5343.171808369709, 5408.459154084639, 5411.377691565521, 5430.239766432703, 5639.379668236491, 6725.529212955144, 6748.79432269668, 6750.392464678104, 6755.628440435725, 1432.44134787433, 1475.5057055886398, 1536.7694845773224, 1541.7610270408104, 1552.906825590468, 872.7425409504211, 960.4957397366438, 1004.437514991607, 1024.1653052761976, 1027.173125633872, 6396.136521569529, 6408.67956602168, 6415.647139407284, 6426.260979987268, 6446.484281257488, 6717.9664101680155, 7011.5793052416, 7022.92977070233, 7028.613426261502, 7084.574925504487, 5103.004914041726, 6310.109665009562, 6370.713317900706, 6375.234971803413, 6386.860401765368, 3105.5186446100893, 3367.1077164550925, 3499.6228639363135, 3659.7621154624862, 3664.6518580734787, 6651.600903945779, 6911.3779759821855, 7173.210946795848, 7273.0181425507935, 7286.0, 6404.7117879695525, 7255.839065749455, 7262.237465066198, 7267.65548373542, 7314.305141011595, 5538.944610553843, 6102.481289090477, 6582.647744926074, 7138.678759455837, 7216.762803530923, 6484.870302594016, 6617.966910165418, 6871.4502839773695, 7011.789646310619, 7085.362317391459, 6208.255009398901, 6324.873502530016, 6670.499712521678, 6885.793426197222, 6920.204176519564, 5746.7701291949525, 5972.104184372379, 6239.1681109185365, 7172.49792514519, 7296.233895440902, 4426.225410553076, 4456.153330867125, 4457.465104187519, 4473.575010374009, 4485.304777759453, 6467.450266727381, 6822.590644561342, 6844.178698556205, 6981.476389270723, 7026.824329391806, 6746.0, 6750.29121942189, 6820.0, 6824.385805277549, 6830.726770929107, 5811.27359080115, 6148.200733904564, 6572.8356791080605, 6724.4284235840505, 6743.865122697617, 5795.072788353843, 6599.637019963948, 6610.206775187008, 6622.768203112868, 6638.767643132467, 6108.664023478574, 6129.743402602367, 6669.1330127083165, 6692.698828552824, 6753.425958793558, 6606.306337834458, 6609.058968758856, 6613.933860661332, 6632.635205063768, 6646.767363142267], "value": 4867.688623222597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.139903101743, 833.3166803221353, 833.5248908122372, 845.4504572028181, 866.3502373220083, 12517.624471265864, 14683.33924973376, 15206.467773627925, 15615.84384156158, 16549.503514894557, 17246.741298880526, 18126.274949002058, 18143.4556963291, 18146.455606331798, 18196.81803181968, 817.8854960305562, 951.2675240065146, 977.1498796047449, 988.6589126751295, 990.0940639315019, 999.3204620857814, 1004.9949750251275, 1005.36661903001, 1005.8088963097025, 1006.5671761142705, 1039.8804137524169, 1040.1938497664296, 1041.229490177271, 1044.0, 1048.7168464514577, 1038.216146809158, 1038.8026275007724, 1039.6725031615056, 1041.4896700616719, 1041.536516250269, 768.9346405555534, 769.6075001749148, 773.6711897443564, 774.5894675821824, 774.8992630957996, 17117.058561779097, 17506.737398939025, 17615.471535853816, 17639.823601764074, 17675.73486397705, 14147.575572732834, 17638.735418968725, 18035.458936231935, 18038.549036274067, 18063.819361806345, 14520.564383068524, 14681.265936703123, 14707.926460367737, 14790.778138327934, 14829.18439485828, 3536.9212390220973, 3569.37535931211, 3594.784312941206, 3621.655942685467, 3648.908777280563, 110452.89547104506, 122393.0, 124829.37585312106, 131577.99999999904, 149720.0, 13597.456101755819, 13794.93102534481, 13981.440742370394, 14020.929895350459, 14088.225147616775, 22640.0, 24137.0, 24194.0, 25518.872405637852, 26152.607710884353, 6738.427233685094, 6918.515703900779, 7686.884696729554, 7810.921890781133, 7827.0, 3927.7250592458254, 4012.9398059028554, 4043.8786836394365, 4047.170330082326, 4052.1895620875944, 4282.272013757669, 4339.479262488521, 4364.585364390441, 4369.12617476506, 4369.431973843443, 932.934694571387, 938.863864739609, 953.6137864165024, 954.160338901762, 954.9522523873917, 639.4309064932121, 653.5098675993005, 662.3078882567728, 663.0154220981838, 667.4193451697055, 11167.83248251277, 12377.88599026092, 12378.876211237952, 12440.937795310965, 12448.688782780502, 592.6710675575097, 637.9744810207633, 648.5881465269493, 648.7859006527834, 658.9703463344161, 4535.31970204472, 5610.102383618601, 5758.165066065397, 5799.217105690678, 5820.679862607597, 140822.2958885199, 141412.878806818, 141629.0, 141986.0, 142506.0, 82620.0, 96226.0, 107602.46198769113, 112291.0, 119591.0, 102513.48743256385, 102519.487402564, 102605.0, 102846.0, 104889.0, 10172.949135254275, 11385.829212561985, 11628.53485860556, 11653.76692466163, 11787.41062946832, 44846.77576612096, 45284.09431811411, 45566.31650525311, 45644.77177614091, 45713.0, 52543.474565253855, 62224.755504888744, 62346.0, 62722.68638656777, 63452.0, 14340.856591434369, 14434.783478247624, 14693.412263509768, 14765.0, 14834.703305934037, 13326.400311986177, 15465.458708945132, 15547.922260388626, 16308.266128024505, 16316.102614356325, 15012.399504019717, 15019.549413517612, 15078.0199287046, 15151.469698560497, 15182.240887955779, 12538.185017973803, 14731.631709206928, 14984.250787460802, 15052.924735376253, 15094.867884908455, 14375.20936348469, 14498.275086245856, 14917.955743098313, 14989.10065396036, 15035.699286014436, 9917.900820991697, 10729.034386905216, 10760.730981725519, 10959.232853700165, 11082.501287441923, 11692.181547291608, 11873.881261187278, 11880.702982425508, 11956.402179891145, 12114.75770484603], "value": 8398.657447475945, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 130.0, 117.0, 95.0, 164.0, 168.0, 97.0, 158.0, 169.0], "value": 131.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 118.0, 146.0, 109.0, 114.0, 126.0, 142.0, 159.0, 112.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 202.0, 207.0, 221.0, 209.0, 246.0, 217.0, 201.0, 198.0, 196.0], "value": 210.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 57.0, 61.0, 86.0, 64.0, 60.0, 69.0, 70.0, 76.0, 60.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 82.0, 83.0, 103.0, 100.0, 88.0, 71.0, 73.0, 91.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 135.0, 136.0, 90.0, 91.0, 91.0, 95.0, 135.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 97.0, 98.0, 104.0, 99.0, 98.0, 98.0, 98.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 93.0, 86.0, 96.0, 91.0, 93.0, 94.0, 91.0, 98.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 53.0, 52.0, 52.0, 52.0, 52.0, 50.0, 54.0, 69.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 66.0, 62.0, 67.0, 66.0, 61.0, 57.0, 55.0, 56.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 90.0, 108.0, 112.0, 110.0, 101.0, 87.0, 143.0, 87.0], "value": 101.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 139.0, 145.0, 152.0, 152.0, 139.0, 138.0, 138.0, 148.0, 171.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 198.0, 169.0, 224.0, 218.0, 194.0, 181.0, 187.0, 180.0], "value": 192.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 68.0, 68.0, 66.0, 67.0, 71.0, 69.0, 66.0], "value": 67.4, "unit": "score"}]}]} 15:15:45 INFO - Return code: 0 15:15:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:15:45 INFO - # TBPL SUCCESS # 15:15:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:15:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:15:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:15:45 INFO - 2017-05-25 15:15:45.797 screenresolution[2450:37706] starting screenresolution argv=screenresolution get 15:15:45 INFO - 2017-05-25 15:15:45.800 screenresolution[2450:37706] Display 0: 1600x1200x32@60 15:15:45 INFO - 2017-05-25 15:15:45.806 screenresolution[2451:37709] starting screenresolution argv=screenresolution list 15:15:45 INFO - Available Modes on Display 0 15:15:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:15:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:15:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:15:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:15:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:15:46 INFO - Intel Iris: 15:15:46 INFO - Chipset Model: Intel Iris 15:15:46 INFO - Type: GPU 15:15:46 INFO - Bus: Built-In 15:15:46 INFO - VRAM (Dynamic, Max): 1536 MB 15:15:46 INFO - Vendor: Intel (0x8086) 15:15:46 INFO - Device ID: 0x0a2e 15:15:46 INFO - Revision ID: 0x0009 15:15:46 INFO - Displays: 15:15:46 INFO - Display: 15:15:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:15:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:15:46 INFO - Display Serial Number: 1600x1200 60 15:15:46 INFO - Main Display: Yes 15:15:46 INFO - Mirror: Off 15:15:46 INFO - Online: Yes 15:15:46 INFO - Rotation: Supported 15:15:46 INFO - Return code: 0 15:15:46 INFO - Running post-action listener: _resource_record_post_action 15:15:46 INFO - [mozharness: 2017-05-25 22:15:46.040551Z] Finished run-tests step (success) 15:15:46 INFO - Running post-run listener: _resource_record_post_run 15:15:46 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 1966592; Write bytes: 566812672; Read time: 15; Write time: 1860 15:15:46 INFO - TinderboxPrint: CPU usage
53.3% 15:15:46 INFO - TinderboxPrint: I/O read bytes / time
1,966,592 / 15 15:15:46 INFO - TinderboxPrint: I/O write bytes / time
566,812,672 / 1,860 15:15:46 INFO - TinderboxPrint: CPU idle
2,347.1 (46.7%) 15:15:46 INFO - TinderboxPrint: CPU system
1,016.0 (20.2%) 15:15:46 INFO - TinderboxPrint: CPU user
1,658.6 (33.0%) 15:15:46 INFO - TinderboxPrint: Swap in / out
245,649,408 / 0 15:15:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228626944; Write bytes: 231151616; Read time: 16825; Write time: 476 15:15:46 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 1966592; Write bytes: 317151232; Read time: 15; Write time: 1350 15:15:46 INFO - Running post-run listener: _upload_blobber_files 15:15:46 INFO - Blob upload gear active. 15:15:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:15:46 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 15:15:46 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'] 15:15:46 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 15:15:46 INFO - (blobuploader) - INFO - Open directory for files ... 15:15:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:15:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:15:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:15:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:15:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:15:47 INFO - (blobuploader) - INFO - Done attempting. 15:15:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:15:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:15:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:15:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:15:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:15:48 INFO - (blobuploader) - INFO - Done attempting. 15:15:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:15:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:15:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:15:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:15:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:15:49 INFO - (blobuploader) - INFO - Done attempting. 15:15:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:15:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:15:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:15:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:15:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:15:51 INFO - (blobuploader) - INFO - Done attempting. 15:15:51 INFO - (blobuploader) - INFO - Iteration through files over. 15:15:51 INFO - Return code: 0 15:15:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:15:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:15:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc49bc6da192a164156ba50d463245d8cc41674e77c23ef6d9b6357985ab24cbb94a326b37f06a431dcac50af27d4d1e8b07957963737accf1643e56001053ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b04bd2b7dee2c5b74d8185b8050d608e1d98c89a04c9c9d9dc70c7409f64c2e2a135419a8ce82c0aeb8a514d57b608164d963b98b8fc5dc590bbe7f2d1fa30f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e50071b33c33297ffa993631d31f5cb309c7b8e2b65b305248f41eae598e8d178fc5c7c450837c3fa1ed47800993716647e9fa90875b2cad62bc3567e1e4c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277a1dd4045936082c8795bd88ba1b14881df041995e795a476f1f15513756ba2ef55824fbafe60360ba2452973522639ccc15c7f0c5eeae710fcdb560700f77"} 15:15:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:15:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:15:51 INFO - Contents: 15:15:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc49bc6da192a164156ba50d463245d8cc41674e77c23ef6d9b6357985ab24cbb94a326b37f06a431dcac50af27d4d1e8b07957963737accf1643e56001053ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b04bd2b7dee2c5b74d8185b8050d608e1d98c89a04c9c9d9dc70c7409f64c2e2a135419a8ce82c0aeb8a514d57b608164d963b98b8fc5dc590bbe7f2d1fa30f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e50071b33c33297ffa993631d31f5cb309c7b8e2b65b305248f41eae598e8d178fc5c7c450837c3fa1ed47800993716647e9fa90875b2cad62bc3567e1e4c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277a1dd4045936082c8795bd88ba1b14881df041995e795a476f1f15513756ba2ef55824fbafe60360ba2452973522639ccc15c7f0c5eeae710fcdb560700f77"} 15:15:51 INFO - Running post-run listener: copy_logs_to_upload_dir 15:15:51 INFO - Copying logs to upload dir... 15:15:51 INFO - mkdir: /builds/slave/test/build/upload/logs 15:15:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.210069 ========= master_lag: 0.54 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-25 15:15:51.984322) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 15:15:51.993227) ========= 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.rRN3CQOWOO/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.66gtr68bNY/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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/cc49bc6da192a164156ba50d463245d8cc41674e77c23ef6d9b6357985ab24cbb94a326b37f06a431dcac50af27d4d1e8b07957963737accf1643e56001053ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b04bd2b7dee2c5b74d8185b8050d608e1d98c89a04c9c9d9dc70c7409f64c2e2a135419a8ce82c0aeb8a514d57b608164d963b98b8fc5dc590bbe7f2d1fa30f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e50071b33c33297ffa993631d31f5cb309c7b8e2b65b305248f41eae598e8d178fc5c7c450837c3fa1ed47800993716647e9fa90875b2cad62bc3567e1e4c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277a1dd4045936082c8795bd88ba1b14881df041995e795a476f1f15513756ba2ef55824fbafe60360ba2452973522639ccc15c7f0c5eeae710fcdb560700f77"} build_url:https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011729 build_url: 'https://queue.taskcluster.net/v1/task/MLd0plWeQBGUu_8RADkh2A/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/cc49bc6da192a164156ba50d463245d8cc41674e77c23ef6d9b6357985ab24cbb94a326b37f06a431dcac50af27d4d1e8b07957963737accf1643e56001053ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b04bd2b7dee2c5b74d8185b8050d608e1d98c89a04c9c9d9dc70c7409f64c2e2a135419a8ce82c0aeb8a514d57b608164d963b98b8fc5dc590bbe7f2d1fa30f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e50071b33c33297ffa993631d31f5cb309c7b8e2b65b305248f41eae598e8d178fc5c7c450837c3fa1ed47800993716647e9fa90875b2cad62bc3567e1e4c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277a1dd4045936082c8795bd88ba1b14881df041995e795a476f1f15513756ba2ef55824fbafe60360ba2452973522639ccc15c7f0c5eeae710fcdb560700f77"}' ========= master_lag: 0.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 15:15:52.651824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 15:15:52.652605) ========= 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.rRN3CQOWOO/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.66gtr68bNY/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005123 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 15:15:52.705855) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 15:15:52.706298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 15:15:52.706681) ========= ========= Total master_lag: 1.31 =========