builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1488515006.02 results: retry (5) revision: d21b59d7e732335ff1376ecde7d5b80c2b6b3c14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.024185) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.024669) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.024987) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.039857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.040188) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-02 20:23:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (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% 46.3M=0s 2017-03-02 20:23:26 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123422 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.185048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.185411) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018609 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.235964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:26.236340) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d21b59d7e732335ff1376ecde7d5b80c2b6b3c14 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d21b59d7e732335ff1376ecde7d5b80c2b6b3c14 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-02 20:23:26,718 truncating revision to first 12 chars 2017-03-02 20:23:26,718 Setting DEBUG logging. 2017-03-02 20:23:26,718 attempt 1/10 2017-03-02 20:23:26,718 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d21b59d7e732?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-02 20:23:27,211 unpacking tar archive at: mozilla-aurora-d21b59d7e732/testing/mozharness/ program finished with exit code 0 elapsedTime=0.797921 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:27.147918) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:27.148346) ========= script_repo_revision: d21b59d7e732335ff1376ecde7d5b80c2b6b3c14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:27.148811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:27.149250) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 20:23:27.174252) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 35 secs) (at 2017-03-02 20:23:27.174649) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:23:27 INFO - MultiFileLogger online at 20170302 20:23:27 in /builds/slave/test 20:23:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 20:23:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:23:27 INFO - {'append_to_log': False, 20:23:27 INFO - 'base_work_dir': '/builds/slave/test', 20:23:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 20:23:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:23:27 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 20:23:27 INFO - 'buildbot_json_path': 'buildprops.json', 20:23:27 INFO - 'config_files': ('talos/linux_config.py',), 20:23:27 INFO - 'default_actions': ('clobber', 20:23:27 INFO - 'read-buildbot-config', 20:23:27 INFO - 'download-and-extract', 20:23:27 INFO - 'populate-webroot', 20:23:27 INFO - 'create-virtualenv', 20:23:27 INFO - 'install', 20:23:27 INFO - 'run-tests'), 20:23:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:23:27 INFO - 'download_minidump_stackwalk': True, 20:23:27 INFO - 'download_symbols': 'ondemand', 20:23:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:23:27 INFO - 'tooltool.py': '/tools/tooltool.py', 20:23:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:23:27 INFO - '/tools/misc-python/virtualenv.py')}, 20:23:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:23:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:23:27 INFO - 'installer_path': 'installer.exe', 20:23:27 INFO - 'log_level': 'info', 20:23:27 INFO - 'log_name': 'talos', 20:23:27 INFO - 'log_to_console': True, 20:23:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:23:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:23:27 INFO - 'opt_config_files': (), 20:23:27 INFO - 'pip_index': False, 20:23:27 INFO - 'sps_profile': False, 20:23:27 INFO - 'sps_profile_interval': 0, 20:23:27 INFO - 'suite': 'dromaeojs', 20:23:27 INFO - 'system_bits': '32', 20:23:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:23:27 INFO - 'title': 'talos-linux64-ix-006', 20:23:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:23:27 INFO - 'use_talos_json': True, 20:23:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:23:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:23:27 INFO - 'work_dir': 'build'} 20:23:27 INFO - [mozharness: 2017-03-03 04:23:27.754450Z] Running clobber step. 20:23:27 INFO - Running pre-action listener: _resource_record_pre_action 20:23:27 INFO - Running main action method: clobber 20:23:27 INFO - rmtree: /builds/slave/test/build 20:23:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:23:28 INFO - Running post-action listener: _resource_record_post_action 20:23:28 INFO - [mozharness: 2017-03-03 04:23:28.681049Z] Finished clobber step (success) 20:23:28 INFO - [mozharness: 2017-03-03 04:23:28.681150Z] Running read-buildbot-config step. 20:23:28 INFO - Running pre-action listener: _resource_record_pre_action 20:23:28 INFO - Running main action method: read_buildbot_config 20:23:28 INFO - Using buildbot properties: 20:23:28 INFO - { 20:23:28 INFO - "project": "", 20:23:28 INFO - "product": "firefox", 20:23:28 INFO - "who": "ryanvm@gmail.com", 20:23:28 INFO - "installer_path": "public/build/target.tar.bz2", 20:23:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 20:23:28 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 20:23:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 20:23:28 INFO - "stage_platform": "linux64", 20:23:28 INFO - "basedir": "/builds/slave/test", 20:23:28 INFO - "buildnumber": 57, 20:23:28 INFO - "platform": "ubuntu64_hw", 20:23:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:23:28 INFO - "slavebuilddir": "test", 20:23:28 INFO - "taskId": "YgA-WYafSU2ul6243Tvq5Q", 20:23:28 INFO - "branch": "mozilla-aurora", 20:23:28 INFO - "script_repo_revision": "production", 20:23:28 INFO - "revision": "d21b59d7e732335ff1376ecde7d5b80c2b6b3c14", 20:23:28 INFO - "slavename": "talos-linux64-ix-006", 20:23:28 INFO - "repo_path": "releases/mozilla-aurora" 20:23:28 INFO - } 20:23:28 INFO - Finding installer, test and symbols from parent task. 20:23:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YgA-WYafSU2ul6243Tvq5Q'}, attempt #1 20:23:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YgA-WYafSU2ul6243Tvq5Q'}, attempt #1 20:23:29 INFO - Task dependencies: dBYxySc4S7aSQ5K_8xoWhA 20:23:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA'}, attempt #1 20:23:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2 20:23:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/test_packages.json 20:23:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.crashreporter-symbols.zip 20:23:30 INFO - Running post-action listener: _resource_record_post_action 20:23:30 INFO - [mozharness: 2017-03-03 04:23:30.051206Z] Finished read-buildbot-config step (success) 20:23:30 INFO - [mozharness: 2017-03-03 04:23:30.051422Z] Running download-and-extract step. 20:23:30 INFO - Running pre-action listener: _resource_record_pre_action 20:23:30 INFO - Running main action method: download_and_extract 20:23:30 INFO - mkdir: /builds/slave/test/build/tests 20:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:30 INFO - trying https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.test_packages.json 20:23:30 INFO - Downloading https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:23:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:23:30 INFO - Downloaded 896 bytes. 20:23:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:23:30 INFO - Using the following test package requirements: 20:23:30 INFO - {u'common': [u'target.common.tests.zip'], 20:23:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:23:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:23:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:23:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:23:30 INFO - u'mozbase': [u'target.common.tests.zip'], 20:23:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:23:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:23:30 INFO - u'web-platform': [u'target.common.tests.zip', 20:23:30 INFO - u'target.web-platform.tests.zip'], 20:23:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:23:30 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:23:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.common.tests.zip 20:23:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:23:30 INFO - Fetch https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.common.tests.zip into memory 20:23:31 INFO - Expected file size: 21860360 20:23:31 INFO - Obtained file size: 21860360 20:23:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:23:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.common.tests.zip 20:23:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:23:35 INFO - Fetch https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.common.tests.zip into memory 20:23:36 INFO - Expected file size: 21860360 20:23:36 INFO - Obtained file size: 21860360 20:23:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.talos.tests.zip 20:23:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:23:40 INFO - Fetch https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.talos.tests.zip into memory 20:23:41 INFO - Expected file size: 14403650 20:23:41 INFO - Obtained file size: 14403650 20:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:41 INFO - trying https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2 20:23:41 INFO - Downloading https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:23:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:23:42 INFO - Downloaded 66157489 bytes. 20:23:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2 20:23:42 INFO - mkdir: /builds/slave/test/properties 20:23:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:23:42 INFO - Writing to file /builds/slave/test/properties/build_url 20:23:42 INFO - Contents: 20:23:42 INFO - build_url:https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2 20:23:42 INFO - Running post-action listener: _resource_record_post_action 20:23:42 INFO - Running post-action listener: set_extra_try_arguments 20:23:42 INFO - [mozharness: 2017-03-03 04:23:42.948631Z] Finished download-and-extract step (success) 20:23:42 INFO - [mozharness: 2017-03-03 04:23:42.948727Z] Running populate-webroot step. 20:23:42 INFO - Running pre-action listener: _resource_record_pre_action 20:23:42 INFO - Running main action method: populate_webroot 20:23:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:23:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:23:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:23:42 INFO - '--tppagecycles', 20:23:42 INFO - '7'], 20:23:42 INFO - 'tests': ['tsvgm']}}, 20:23:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:23:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:23:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:23:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:23:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:23:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:23:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['damp', 'tps']}, 20:23:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'tests': ['damp', 'tps']}, 20:23:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['dromaeo_dom']}, 20:23:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:23:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['basic_compositor_video']}, 20:23:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:23:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['a11yr', 20:23:42 INFO - 'ts_paint', 20:23:42 INFO - 'tpaint', 20:23:42 INFO - 'sessionrestore', 20:23:42 INFO - 'sessionrestore_no_auto_restore', 20:23:42 INFO - 'tabpaint']}, 20:23:42 INFO - 'other-e10s': {'tests': ['a11yr', 20:23:42 INFO - 'ts_paint', 20:23:42 INFO - 'tpaint', 20:23:42 INFO - 'sessionrestore', 20:23:42 INFO - 'sessionrestore_no_auto_restore', 20:23:42 INFO - 'tabpaint']}, 20:23:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:23:42 INFO - 'ts_paint', 20:23:42 INFO - 'tpaint', 20:23:42 INFO - 'sessionrestore', 20:23:42 INFO - 'sessionrestore_no_auto_restore', 20:23:42 INFO - 'tabpaint']}, 20:23:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:23:42 INFO - 'ts_paint', 20:23:42 INFO - 'tpaint', 20:23:42 INFO - 'sessionrestore', 20:23:42 INFO - 'sessionrestore_no_auto_restore', 20:23:42 INFO - 'tabpaint']}, 20:23:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['a11yr', 20:23:42 INFO - 'ts_paint', 20:23:42 INFO - 'tpaint', 20:23:42 INFO - 'sessionrestore', 20:23:42 INFO - 'sessionrestore_no_auto_restore', 20:23:42 INFO - 'tabpaint']}, 20:23:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['a11yr', 20:23:42 INFO - 'ts_paint', 20:23:42 INFO - 'tpaint', 20:23:42 INFO - 'sessionrestore', 20:23:42 INFO - 'sessionrestore_no_auto_restore', 20:23:42 INFO - 'tabpaint']}, 20:23:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['tsvgx', 20:23:42 INFO - 'tsvgr_opacity', 20:23:42 INFO - 'tart', 20:23:42 INFO - 'tscrollx', 20:23:42 INFO - 'cart', 20:23:42 INFO - 'tsvg_static']}, 20:23:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:23:42 INFO - 'tsvgr_opacity', 20:23:42 INFO - 'tart', 20:23:42 INFO - 'tscrollx', 20:23:42 INFO - 'cart', 20:23:42 INFO - 'tsvg_static']}, 20:23:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'talos_options': ['--disable-e10s'], 20:23:42 INFO - 'tests': ['tp5o']}, 20:23:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:23:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'talos_options': ['--disable-e10s', 20:23:42 INFO - '--xperf_path', 20:23:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:23:42 INFO - 'tests': ['tp5n']}, 20:23:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:23:42 INFO - 'talos_options': ['--xperf_path', 20:23:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:23:42 INFO - 'tests': ['tp5n']}}, 20:23:42 INFO - 'talos.zip': {'path': '', 20:23:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:23:42 INFO - Running post-action listener: _resource_record_post_action 20:23:42 INFO - [mozharness: 2017-03-03 04:23:42.961723Z] Finished populate-webroot step (success) 20:23:42 INFO - [mozharness: 2017-03-03 04:23:42.961813Z] Running create-virtualenv step. 20:23:42 INFO - Running pre-action listener: _resource_record_pre_action 20:23:42 INFO - Running main action method: create_virtualenv 20:23:42 INFO - Creating virtualenv /builds/slave/test/build/venv 20:23:42 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 20:23:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:23:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:23:43 INFO - Using real prefix '/usr' 20:23:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:23:43 INFO - Installing distribute.............................................................................................................................................................................................done. 20:23:45 INFO - Installing pip.................done. 20:23:45 INFO - Return code: 0 20:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:23:45 INFO - Reading from file tmpfile_stdout 20:23:45 INFO - Output received: 20:23:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:23:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:23:45 INFO - Using env: {'DISPLAY': ':0', 20:23:45 INFO - 'HOME': '/home/cltbld', 20:23:45 INFO - 'LANG': 'en_US.UTF-8', 20:23:45 INFO - 'LANGUAGE': 'en_US:en', 20:23:45 INFO - 'LOGNAME': 'cltbld', 20:23:45 INFO - 'MAIL': '/var/mail/cltbld', 20:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:45 INFO - 'MOZ_NO_REMOTE': '1', 20:23:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:45 INFO - 'NO_EM_RESTART': '1', 20:23:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:45 INFO - 'PWD': '/builds/slave/test', 20:23:45 INFO - 'SHELL': '/bin/bash', 20:23:45 INFO - 'SHLVL': '1', 20:23:45 INFO - 'TERM': 'linux', 20:23:45 INFO - 'TMOUT': '86400', 20:23:45 INFO - 'USER': 'cltbld', 20:23:45 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:45 INFO - '_': '/tools/buildbot/bin/python'} 20:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:45 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 20:23:45 INFO - Cleaning up... 20:23:46 INFO - Return code: 0 20:23:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:23:46 INFO - Using env: {'DISPLAY': ':0', 20:23:46 INFO - 'HOME': '/home/cltbld', 20:23:46 INFO - 'LANG': 'en_US.UTF-8', 20:23:46 INFO - 'LANGUAGE': 'en_US:en', 20:23:46 INFO - 'LOGNAME': 'cltbld', 20:23:46 INFO - 'MAIL': '/var/mail/cltbld', 20:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:46 INFO - 'MOZ_NO_REMOTE': '1', 20:23:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:46 INFO - 'NO_EM_RESTART': '1', 20:23:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:46 INFO - 'PWD': '/builds/slave/test', 20:23:46 INFO - 'SHELL': '/bin/bash', 20:23:46 INFO - 'SHLVL': '1', 20:23:46 INFO - 'TERM': 'linux', 20:23:46 INFO - 'TMOUT': '86400', 20:23:46 INFO - 'USER': 'cltbld', 20:23:46 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:46 INFO - '_': '/tools/buildbot/bin/python'} 20:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:46 INFO - Downloading/unpacking psutil>=3.1.1 20:23:46 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 20:23:46 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 20:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:46 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 20:23:46 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 20:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:23:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:48 INFO - Installing collected packages: psutil 20:23:48 INFO - Running setup.py install for psutil 20:23:48 INFO - building 'psutil._psutil_linux' extension 20:23:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:23:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:23:48 INFO - building 'psutil._psutil_posix' extension 20:23:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:23:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:23:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:48 INFO - Successfully installed psutil 20:23:48 INFO - Cleaning up... 20:23:48 INFO - Return code: 0 20:23:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:23:48 INFO - Using env: {'DISPLAY': ':0', 20:23:48 INFO - 'HOME': '/home/cltbld', 20:23:48 INFO - 'LANG': 'en_US.UTF-8', 20:23:48 INFO - 'LANGUAGE': 'en_US:en', 20:23:48 INFO - 'LOGNAME': 'cltbld', 20:23:48 INFO - 'MAIL': '/var/mail/cltbld', 20:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:48 INFO - 'MOZ_NO_REMOTE': '1', 20:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:48 INFO - 'NO_EM_RESTART': '1', 20:23:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:48 INFO - 'PWD': '/builds/slave/test', 20:23:48 INFO - 'SHELL': '/bin/bash', 20:23:48 INFO - 'SHLVL': '1', 20:23:48 INFO - 'TERM': 'linux', 20:23:48 INFO - 'TMOUT': '86400', 20:23:48 INFO - 'USER': 'cltbld', 20:23:48 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:48 INFO - '_': '/tools/buildbot/bin/python'} 20:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:23:51 INFO - Installing collected packages: mozsystemmonitor 20:23:51 INFO - Running setup.py install for mozsystemmonitor 20:23:51 INFO - Successfully installed mozsystemmonitor 20:23:51 INFO - Cleaning up... 20:23:51 INFO - Return code: 0 20:23:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:51 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:23:51 INFO - Using env: {'DISPLAY': ':0', 20:23:51 INFO - 'HOME': '/home/cltbld', 20:23:51 INFO - 'LANG': 'en_US.UTF-8', 20:23:51 INFO - 'LANGUAGE': 'en_US:en', 20:23:51 INFO - 'LOGNAME': 'cltbld', 20:23:51 INFO - 'MAIL': '/var/mail/cltbld', 20:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:51 INFO - 'MOZ_NO_REMOTE': '1', 20:23:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:51 INFO - 'NO_EM_RESTART': '1', 20:23:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:51 INFO - 'PWD': '/builds/slave/test', 20:23:51 INFO - 'SHELL': '/bin/bash', 20:23:51 INFO - 'SHLVL': '1', 20:23:51 INFO - 'TERM': 'linux', 20:23:51 INFO - 'TMOUT': '86400', 20:23:51 INFO - 'USER': 'cltbld', 20:23:51 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:51 INFO - '_': '/tools/buildbot/bin/python'} 20:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:51 INFO - Downloading/unpacking jsonschema==2.5.1 20:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:23:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:23:53 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 20:23:53 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 20:23:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:53 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 20:23:53 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 20:23:53 INFO - Downloading functools32-3.2.3-2.tar.gz 20:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:23:53 INFO - warning: no files found matching '*.txt' 20:23:53 INFO - no previously-included directories found matching 'build' 20:23:53 INFO - no previously-included directories found matching 'dist' 20:23:53 INFO - no previously-included directories found matching '.git*' 20:23:53 INFO - Installing collected packages: jsonschema, functools32 20:23:53 INFO - Running setup.py install for functools32 20:23:53 INFO - warning: no files found matching '*.txt' 20:23:53 INFO - no previously-included directories found matching 'build' 20:23:53 INFO - no previously-included directories found matching 'dist' 20:23:53 INFO - no previously-included directories found matching '.git*' 20:23:53 INFO - Successfully installed jsonschema functools32 20:23:53 INFO - Cleaning up... 20:23:54 INFO - Return code: 0 20:23:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:23:54 INFO - Using env: {'DISPLAY': ':0', 20:23:54 INFO - 'HOME': '/home/cltbld', 20:23:54 INFO - 'LANG': 'en_US.UTF-8', 20:23:54 INFO - 'LANGUAGE': 'en_US:en', 20:23:54 INFO - 'LOGNAME': 'cltbld', 20:23:54 INFO - 'MAIL': '/var/mail/cltbld', 20:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:54 INFO - 'MOZ_NO_REMOTE': '1', 20:23:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:54 INFO - 'NO_EM_RESTART': '1', 20:23:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:54 INFO - 'PWD': '/builds/slave/test', 20:23:54 INFO - 'SHELL': '/bin/bash', 20:23:54 INFO - 'SHLVL': '1', 20:23:54 INFO - 'TERM': 'linux', 20:23:54 INFO - 'TMOUT': '86400', 20:23:54 INFO - 'USER': 'cltbld', 20:23:54 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:54 INFO - '_': '/tools/buildbot/bin/python'} 20:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:23:54 INFO - Cleaning up... 20:23:54 INFO - Return code: 0 20:23:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:23:54 INFO - Using env: {'DISPLAY': ':0', 20:23:54 INFO - 'HOME': '/home/cltbld', 20:23:54 INFO - 'LANG': 'en_US.UTF-8', 20:23:54 INFO - 'LANGUAGE': 'en_US:en', 20:23:54 INFO - 'LOGNAME': 'cltbld', 20:23:54 INFO - 'MAIL': '/var/mail/cltbld', 20:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:54 INFO - 'MOZ_NO_REMOTE': '1', 20:23:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:54 INFO - 'NO_EM_RESTART': '1', 20:23:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:54 INFO - 'PWD': '/builds/slave/test', 20:23:54 INFO - 'SHELL': '/bin/bash', 20:23:54 INFO - 'SHLVL': '1', 20:23:54 INFO - 'TERM': 'linux', 20:23:54 INFO - 'TMOUT': '86400', 20:23:54 INFO - 'USER': 'cltbld', 20:23:54 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:54 INFO - '_': '/tools/buildbot/bin/python'} 20:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:54 INFO - Downloading/unpacking blobuploader==1.2.4 20:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:56 INFO - Downloading blobuploader-1.2.4.tar.gz 20:23:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:23:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:23:56 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 20:23:56 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 20:23:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:56 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 20:23:56 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 20:23:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:23:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:23:56 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 20:23:56 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 20:23:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:56 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 20:23:56 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 20:23:57 INFO - Downloading docopt-0.6.1.tar.gz 20:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:23:57 INFO - Installing collected packages: blobuploader, requests, docopt 20:23:57 INFO - Running setup.py install for blobuploader 20:23:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:23:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:23:57 INFO - Running setup.py install for requests 20:23:57 INFO - Running setup.py install for docopt 20:23:57 INFO - Successfully installed blobuploader requests docopt 20:23:57 INFO - Cleaning up... 20:23:57 INFO - Return code: 0 20:23:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:23:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:23:57 INFO - Using env: {'DISPLAY': ':0', 20:23:57 INFO - 'HOME': '/home/cltbld', 20:23:57 INFO - 'LANG': 'en_US.UTF-8', 20:23:57 INFO - 'LANGUAGE': 'en_US:en', 20:23:57 INFO - 'LOGNAME': 'cltbld', 20:23:57 INFO - 'MAIL': '/var/mail/cltbld', 20:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:57 INFO - 'MOZ_NO_REMOTE': '1', 20:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:57 INFO - 'NO_EM_RESTART': '1', 20:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:57 INFO - 'PWD': '/builds/slave/test', 20:23:57 INFO - 'SHELL': '/bin/bash', 20:23:57 INFO - 'SHLVL': '1', 20:23:57 INFO - 'TERM': 'linux', 20:23:57 INFO - 'TMOUT': '86400', 20:23:57 INFO - 'USER': 'cltbld', 20:23:57 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:57 INFO - '_': '/tools/buildbot/bin/python'} 20:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:58 INFO - Running setup.py (path:/tmp/pip-0KdXZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:58 INFO - Running setup.py (path:/tmp/pip-vNsLCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:58 INFO - Running setup.py (path:/tmp/pip-XCUHVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:58 INFO - Running setup.py (path:/tmp/pip-Jb5HvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:58 INFO - Running setup.py (path:/tmp/pip-yB3r6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:58 INFO - Running setup.py (path:/tmp/pip-wWqTei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:58 INFO - Running setup.py (path:/tmp/pip-_trYYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:58 INFO - Running setup.py (path:/tmp/pip-HV7PYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:58 INFO - Running setup.py (path:/tmp/pip-8fxZWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:58 INFO - Running setup.py (path:/tmp/pip-3hFFoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:58 INFO - Running setup.py (path:/tmp/pip-l0z3_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:58 INFO - Running setup.py (path:/tmp/pip-6kQMs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:58 INFO - Running setup.py (path:/tmp/pip-wfmN2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:59 INFO - Running setup.py (path:/tmp/pip-qQitGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:59 INFO - Running setup.py (path:/tmp/pip-YqeRN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:59 INFO - Running setup.py (path:/tmp/pip-4FkQfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:59 INFO - Running setup.py (path:/tmp/pip-c7uLZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:23:59 INFO - Running setup.py install for manifestparser 20:23:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:23:59 INFO - Running setup.py install for mozcrash 20:23:59 INFO - Running setup.py install for mozdebug 20:23:59 INFO - Running setup.py install for mozdevice 20:23:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:23:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:23:59 INFO - Running setup.py install for mozfile 20:23:59 INFO - Running setup.py install for mozhttpd 20:23:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:23:59 INFO - Running setup.py install for mozinfo 20:24:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Running setup.py install for mozInstall 20:24:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Running setup.py install for mozleak 20:24:00 INFO - Running setup.py install for mozlog 20:24:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Running setup.py install for moznetwork 20:24:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Running setup.py install for mozprocess 20:24:00 INFO - Running setup.py install for mozprofile 20:24:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Running setup.py install for mozrunner 20:24:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:24:00 INFO - Running setup.py install for mozscreenshot 20:24:00 INFO - Running setup.py install for moztest 20:24:00 INFO - Running setup.py install for mozversion 20:24:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:24:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:24:01 INFO - Cleaning up... 20:24:01 INFO - Return code: 0 20:24:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:24:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:24:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:24:01 INFO - Using env: {'DISPLAY': ':0', 20:24:01 INFO - 'HOME': '/home/cltbld', 20:24:01 INFO - 'LANG': 'en_US.UTF-8', 20:24:01 INFO - 'LANGUAGE': 'en_US:en', 20:24:01 INFO - 'LOGNAME': 'cltbld', 20:24:01 INFO - 'MAIL': '/var/mail/cltbld', 20:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:01 INFO - 'MOZ_NO_REMOTE': '1', 20:24:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:24:01 INFO - 'NO_EM_RESTART': '1', 20:24:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:01 INFO - 'PWD': '/builds/slave/test', 20:24:01 INFO - 'SHELL': '/bin/bash', 20:24:01 INFO - 'SHLVL': '1', 20:24:01 INFO - 'TERM': 'linux', 20:24:01 INFO - 'TMOUT': '86400', 20:24:01 INFO - 'USER': 'cltbld', 20:24:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:01 INFO - '_': '/tools/buildbot/bin/python'} 20:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:24:01 INFO - Running setup.py (path:/tmp/pip-B4Wboy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:24:01 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)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:24:01 INFO - Running setup.py (path:/tmp/pip-PlTAFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:24:01 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)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:24:01 INFO - Running setup.py (path:/tmp/pip-YRPXyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:24:01 INFO - Running setup.py (path:/tmp/pip-_6m5CL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:24:01 INFO - Running setup.py (path:/tmp/pip-sVp5bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:24:01 INFO - Running setup.py (path:/tmp/pip-fNcQgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:24:01 INFO - Running setup.py (path:/tmp/pip-WEHm7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:24:01 INFO - Running setup.py (path:/tmp/pip-4I4Kdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:24:01 INFO - Running setup.py (path:/tmp/pip-SSQNQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:24:01 INFO - Running setup.py (path:/tmp/pip-clZDZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:24:02 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)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:24:02 INFO - Running setup.py (path:/tmp/pip-s1smbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:24:02 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)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:24:02 INFO - Running setup.py (path:/tmp/pip-8UCkWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:24:02 INFO - Running setup.py (path:/tmp/pip-5pOxey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:24:02 INFO - Running setup.py (path:/tmp/pip-k2_msG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:24:02 INFO - Running setup.py (path:/tmp/pip-6pTaOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:24:02 INFO - Running setup.py (path:/tmp/pip-qf5IFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:24:02 INFO - Running setup.py (path:/tmp/pip-ljDabQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:24:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:24:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:24:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:24:04 INFO - Downloading blessings-1.6.tar.gz 20:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:24:04 INFO - Installing collected packages: blessings 20:24:04 INFO - Running setup.py install for blessings 20:24:04 INFO - Successfully installed blessings 20:24:04 INFO - Cleaning up... 20:24:04 INFO - Return code: 0 20:24:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:24:05 INFO - Reading from file tmpfile_stdout 20:24:05 INFO - Current package versions: 20:24:05 INFO - argparse == 1.2.1 20:24:05 INFO - blessings == 1.6 20:24:05 INFO - blobuploader == 1.2.4 20:24:05 INFO - docopt == 0.6.1 20:24:05 INFO - functools32 == 3.2.3-2 20:24:05 INFO - jsonschema == 2.5.1 20:24:05 INFO - manifestparser == 1.1 20:24:05 INFO - mozInstall == 1.12 20:24:05 INFO - mozcrash == 1.0 20:24:05 INFO - mozdebug == 0.1 20:24:05 INFO - mozdevice == 0.49 20:24:05 INFO - mozfile == 1.2 20:24:05 INFO - mozhttpd == 0.7 20:24:05 INFO - mozinfo == 0.9 20:24:05 INFO - mozleak == 0.1 20:24:05 INFO - mozlog == 3.4 20:24:05 INFO - moznetwork == 0.27 20:24:05 INFO - mozprocess == 0.24 20:24:05 INFO - mozprofile == 0.28 20:24:05 INFO - mozrunner == 6.13 20:24:05 INFO - mozscreenshot == 0.1 20:24:05 INFO - mozsystemmonitor == 0.3 20:24:05 INFO - moztest == 0.8 20:24:05 INFO - mozversion == 1.4 20:24:05 INFO - psutil == 3.1.1 20:24:05 INFO - requests == 1.2.3 20:24:05 INFO - wsgiref == 0.1.2 20:24:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:24:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 20:24:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:24:05 INFO - Using env: {'DISPLAY': ':0', 20:24:05 INFO - 'HOME': '/home/cltbld', 20:24:05 INFO - 'LANG': 'en_US.UTF-8', 20:24:05 INFO - 'LANGUAGE': 'en_US:en', 20:24:05 INFO - 'LOGNAME': 'cltbld', 20:24:05 INFO - 'MAIL': '/var/mail/cltbld', 20:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:05 INFO - 'MOZ_NO_REMOTE': '1', 20:24:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:24:05 INFO - 'NO_EM_RESTART': '1', 20:24:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:05 INFO - 'PWD': '/builds/slave/test', 20:24:05 INFO - 'SHELL': '/bin/bash', 20:24:05 INFO - 'SHLVL': '1', 20:24:05 INFO - 'TERM': 'linux', 20:24:05 INFO - 'TMOUT': '86400', 20:24:05 INFO - 'USER': 'cltbld', 20:24:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:05 INFO - '_': '/tools/buildbot/bin/python'} 20:24:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 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)) 20:24:05 INFO - Cleaning up... 20:24:05 INFO - Return code: 0 20:24:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x25ef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2961980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:24:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:24:05 INFO - Using env: {'DISPLAY': ':0', 20:24:05 INFO - 'HOME': '/home/cltbld', 20:24:05 INFO - 'LANG': 'en_US.UTF-8', 20:24:05 INFO - 'LANGUAGE': 'en_US:en', 20:24:05 INFO - 'LOGNAME': 'cltbld', 20:24:05 INFO - 'MAIL': '/var/mail/cltbld', 20:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:05 INFO - 'MOZ_NO_REMOTE': '1', 20:24:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:24:05 INFO - 'NO_EM_RESTART': '1', 20:24:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:05 INFO - 'PWD': '/builds/slave/test', 20:24:05 INFO - 'SHELL': '/bin/bash', 20:24:05 INFO - 'SHLVL': '1', 20:24:05 INFO - 'TERM': 'linux', 20:24:05 INFO - 'TMOUT': '86400', 20:24:05 INFO - 'USER': 'cltbld', 20:24:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:05 INFO - '_': '/tools/buildbot/bin/python'} 20:24:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:24:05 INFO - Cleaning up... 20:24:05 INFO - Return code: 0 20:24:05 INFO - Running post-action listener: _resource_record_post_action 20:24:05 INFO - Running post-action listener: _start_resource_monitoring 20:24:05 INFO - Starting resource monitoring. 20:24:05 INFO - [mozharness: 2017-03-03 04:24:05.533904Z] Finished create-virtualenv step (success) 20:24:05 INFO - [mozharness: 2017-03-03 04:24:05.534416Z] Running install step. 20:24:05 INFO - Running pre-action listener: _resource_record_pre_action 20:24:05 INFO - Running main action method: install 20:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:24:05 INFO - Reading from file tmpfile_stdout 20:24:05 INFO - Detecting whether we're running mozinstall >=1.0... 20:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:24:05 INFO - Reading from file tmpfile_stdout 20:24:05 INFO - Output received: 20:24:05 INFO - Usage: mozinstall [options] installer 20:24:05 INFO - Options: 20:24:05 INFO - -h, --help show this help message and exit 20:24:05 INFO - -d DEST, --destination=DEST 20:24:05 INFO - Directory to install application into. [default: 20:24:05 INFO - "/builds/slave/test"] 20:24:05 INFO - --app=APP Application being installed. [default: firefox] 20:24:05 INFO - mkdir: /builds/slave/test/build/application 20:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:24:22 INFO - Reading from file tmpfile_stdout 20:24:22 INFO - Output received: 20:24:22 INFO - /builds/slave/test/build/application/firefox/firefox 20:24:22 INFO - Running post-action listener: _resource_record_post_action 20:24:22 INFO - [mozharness: 2017-03-03 04:24:22.369016Z] Finished install step (success) 20:24:22 INFO - [mozharness: 2017-03-03 04:24:22.369162Z] Running run-tests step. 20:24:22 INFO - Running pre-action listener: _resource_record_pre_action 20:24:22 INFO - Running main action method: run_tests 20:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:24:22 INFO - Python 2.7.3 20:24:22 INFO - Return code: 0 20:24:22 INFO - grabbing minidump binary from tooltool 20:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 20:24:22 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/linux64/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 0x295ed00>, '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 0x295fa00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295feb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:24:22 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:24:22 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/linux64/releng.manifest -o -c /builds/tooltool_cache 20:24:22 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:24:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:24:22 INFO - Return code: 0 20:24:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:24:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:24:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:24:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:24:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:24:22 INFO - Using env: {'DISPLAY': ':0', 20:24:22 INFO - 'HOME': '/home/cltbld', 20:24:22 INFO - 'LANG': 'en_US.UTF-8', 20:24:22 INFO - 'LANGUAGE': 'en_US:en', 20:24:22 INFO - 'LOGNAME': 'cltbld', 20:24:22 INFO - 'MAIL': '/var/mail/cltbld', 20:24:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:24:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:22 INFO - 'MOZ_NO_REMOTE': '1', 20:24:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:24:22 INFO - 'NO_EM_RESTART': '1', 20:24:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:22 INFO - 'PWD': '/builds/slave/test', 20:24:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:24:22 INFO - 'SHELL': '/bin/bash', 20:24:22 INFO - 'SHLVL': '1', 20:24:22 INFO - 'TERM': 'linux', 20:24:22 INFO - 'TMOUT': '86400', 20:24:22 INFO - 'USER': 'cltbld', 20:24:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089', 20:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:22 INFO - '_': '/tools/buildbot/bin/python'} 20:24:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:24:22 INFO - mozversion application_buildid: 20170303032649 20:24:22 INFO - mozversion application_changeset: d21b59d7e732335ff1376ecde7d5b80c2b6b3c14 20:24:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:24:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:24:22 INFO - mozversion application_name: Firefox 20:24:22 INFO - mozversion application_remotingname: firefox-dev 20:24:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:24:22 INFO - mozversion application_vendor: Mozilla 20:24:22 INFO - mozversion application_version: 53.0a2 20:24:22 INFO - mozversion platform_buildid: 20170303032649 20:24:22 INFO - mozversion platform_changeset: d21b59d7e732335ff1376ecde7d5b80c2b6b3c14 20:24:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:24:22 INFO - mozversion platform_version: 53.0a2 20:24:22 INFO - using testdate: 1488515062 20:24:22 INFO - actual date: 1488515062 20:24:22 INFO - starting webserver on 'localhost:58864' 20:24:22 INFO - SUITE-START | Running 2 tests 20:24:22 INFO - TEST-START | dromaeo_css 20:24:22 INFO - operating with platform_type : linux_ 20:24:22 INFO - Initialising browser for dromaeo_css test... 20:24:22 INFO - TEST-INFO | started process 4638 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6dvw90/profile http://localhost:58864/getInfo.html) 20:24:26 INFO - PROCESS | 4638 | __metrics Screen width/height:1600/1200 20:24:26 INFO - PROCESS | 4638 | colorDepth:24 20:24:26 INFO - PROCESS | 4638 | Browser inner width/height: 1024/697 20:24:26 INFO - PROCESS | 4638 | __metrics 20:24:27 INFO - PROCESS | 4638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:24:27 INFO - PROCESS | 4638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:24:28 INFO - TEST-INFO | 4638: exit 0 20:24:28 INFO - Browser initialized. 20:24:28 INFO - Running cycle 1/1 for dromaeo_css test... 20:24:28 INFO - TEST-INFO | started process 4729 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6dvw90/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:40 INFO - PROCESS | 4729 | RSS: Main: 137998336 20:24:40 INFO - PROCESS | 4729 | 20:27:40 INFO - PROCESS | 4729 | Cycle 1(1): loaded http://localhost:58864/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58864/tests/dromaeo/cssquery-ext.html) 20:27:42 INFO - PROCESS | 4729 | RSS: Main: 263782400 20:27:42 INFO - PROCESS | 4729 | 20:29:30 INFO - PROCESS | 4729 | ************************* 20:29:30 INFO - PROCESS | 4729 | A coding exception was thrown and uncaught in a Task. 20:29:30 INFO - PROCESS | 4729 | 20:29:30 ERROR - PROCESS | 4729 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:29:30 INFO - PROCESS | 4729 | Full stack: 20:29:30 INFO - PROCESS | 4729 | ************************* 20:30:44 INFO - PROCESS | 4729 | Cycle 1(1): loaded http://localhost:58864/tests/dromaeo/cssquery-ext.html (next: http://localhost:58864/tests/dromaeo/cssquery-jquery.html) 20:30:44 INFO - PROCESS | 4729 | RSS: Main: 228298752 20:30:44 INFO - PROCESS | 4729 | 20:33:46 INFO - PROCESS | 4729 | Cycle 1(1): loaded http://localhost:58864/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58864/tests/dromaeo/cssquery-mootools.html) 20:33:47 INFO - PROCESS | 4729 | RSS: Main: 173608960 20:33:47 INFO - PROCESS | 4729 | 20:36:49 INFO - PROCESS | 4729 | Cycle 1(1): loaded http://localhost:58864/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58864/tests/dromaeo/cssquery-prototype.html) 20:36:49 INFO - PROCESS | 4729 | RSS: Main: 170795008 20:36:49 INFO - PROCESS | 4729 | 20:39:52 INFO - PROCESS | 4729 | Cycle 1(1): loaded http://localhost:58864/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58864/tests/dromaeo/cssquery-yui.html) 20:39:53 INFO - PROCESS | 4729 | RSS: Main: 171765760 20:39:53 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | Cycle 1(1): loaded http://localhost:58864/tests/dromaeo/cssquery-yui.html (next: http://localhost:58864/tests/dromaeo/cssquery-dojo.html) 20:42:55 INFO - PROCESS | 4729 | RSS: Main: 267780096 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | __start_tp_report 20:42:55 INFO - PROCESS | 4729 | _x_x_mozilla_page_load 20:42:55 INFO - PROCESS | 4729 | _x_x_mozilla_page_load_details 20:42:55 INFO - PROCESS | 4729 | |i|pagename|runs| 20:42:55 INFO - PROCESS | 4729 | |0;dojo.html;3301.9999999999995;3315.983420082899;3322.418576749069;3346.397648423286;3357.0935847321225;5406.594505412095;5473.452654734525;5487.5609951203905;5518.779248830047;5521.585881058927;5479.342478902538;5521.5306698930535;5534.695591742463;5535.474129957647;5558.082916318813;4923.113839508896;4941.925871111936;4976.676516026465;4984.551390374865;5048.242763585473;4878.292647566101;4964.255361695738;5000.599952003831;5015.69905805651;5021.924671129936;4839.322494850724;4909.189983652693;4939.8024079036795;4948.38145231846;5037.244413337992;5006.374203224597;5046.646734728571;5050.621203409759;5052.873678158057;5074.59403247743;4964.726940018298;4989.57588604969;4996.975015124937;5011.824586139504;5029.371328583927;5248.107821670325;5350.35795704514;5407.864803379907;5469.835904922819;5476.808311709071;5943.851403714899;5950.375210602868;5958.851028724273;5961.850953726148;5993.8801223975715;5930;5972.432618901197;5986.760529578812;6008.879822403527;6017.157597936292;5388.353397592275;5422.295101636841;5456.290682211287;5598.552115830724;5604.075327570946;5954.7320370582565;6023.849403764853;6025.6083354581815;6064.06007068905;6064.878702426015;5745.683987380734;5800.709964501758;5802.593818432669;5806.941930580725;5809.041508151192;5738.770449181986;5800.8259752207505;5896.911546326808;5913.083472061838;5914.112883067574;5740.54075673945;5747.626404283708;5749.281339832521;5785.797497087582;5869.589128760946;5815.244018277597;5816.47651711348;5820.243368362086;5823.272090988627;5841.912371314434;4468.240399132155;4513.413256276663;4532.750698711537;4539.0921815637;4572.542745725401;4019.3167161582014;4043.9191216175514;4085;4091.6522095622204;4096.078382363944;5688.402717714663;5695.316562012585;5719;5809.419058094157;5841.123831425235;5769.94230057694;5805;6005.519558435315;6012.909806352908;6019.608725432833;5649.689267090267;5652.123920792284;5686.061799802987;5687.601867869293;5706.25818643582;4938.061768264019;4982.651214415028;5007.849764506997;5070.873228169326;5071.54356107952;6070.908936365958;6126.334466362936;6127.458317119216;6144.832481828438;6149.13912052313;5935.080062590306;5960.5231581474245;5967.731452084753;5967.76128954837;5975.372585878594;5951.1073338999495;5976.7908123215475;6138.815835524942;6139.938600613937;6143.662098584442;5766.106253530709;5778.595498315246;5803.361630220588;5874.324452687995;5899.882002360015;5932.733027013881;5942.138389933349;5987.550933679904;5991.730372133177;5993.70031498432;5525.917111243254;5763.971180144073;5764.164196191613;5764.682942438206;5770.221020162225;5613.663180209201;5654.46282603152;5710.274511365859;5738.540916726568;5845;5789.797357092481;5799.652020878761;5800.8259752207505;5818.127280907897;5825.70871456434;5833.300003999547;5868.4424979626865;5871.354151043467;5872.61827981197;5873.3832947539895;5517.586181036519;5544.584156188222;5663.037283661705;5711.029125048834;5724.4847963681805 20:42:55 INFO - PROCESS | 4729 | |1;ext.html;570.218800243666;597.7101105963608;616.6331033035344;617.706589370049;641.4066988036066;37247;40595.59404405955;40698.38952415716;40909.18181636369;47927.760361198234;47643.76178119113;52139.871607703564;52231;52708;52715.73642131794;9431.245500359972;9601.855972160423;9621.374610650286;9625.422474651508;9625.663101791439;8676.783080422976;8931.955340223321;9333.579988900516;9459.10138536838;9464.1009104135;11191.608293709722;11530.596429124982;11537.192396532246;11859.288442693423;11873.525058997673;11510.769784604303;11566;11575.305481671126;11597.536098556047;11610.187286889963;11206.719832004184;11278;11311.095112390989;11315.717107072307;11319.094472442268;48062;48292;48459.27311090336;48465.75767121142;48484;4552.954470455286;4553.362529245908;4610.95389046112;4730.053989202173;4738.47876733559;34060.48909266363;34664.480032799525;34883.30233395318;36069.45895811565;36284.455733164024;4657.11514812188;4680.9297860532115;4706.694064885772;4721.244600863845;4734.597559207472;343315;344181.2790936054;344925;345911;346145;69294;69734.65132674406;69766.65116674385;69888.6505567479;69940.6502967482;74246;74437.25562744412;74483;74767.62616186884;74834.25165748382;19401.514962125777;19767.110480028434;19859.503512412015;19931.10310036052;19932.60134797296;15207.163606001775;15443.382264709511;15452.227388630523;15458;15467.458638947583;16379.918100409423;17096.82903170977;18121.637567248847;18787.718184227248;19878.801211987986;6450.580712253689;6496.155499785093;6544.7382104716235;6625.238097618739;6626.271110177877;973.4791886340773;975.6292608808608;986.8914419413859;991.7619771254847;1006.0492834271544;33828.66171338255;34483;34855.6821284095;34881.82559087239;35322;1061.532925512772;1175.5944199251244;1187.3884949251142;1188.3582865252667;1226.88957993781;17128.229229684694;18260.908695456703;18348.541286467676;18372.173252203684;18534.16596253172;177527;177637;177959;178177.1091144536;178284;72756.63621681857;72788;72931.63534182296;73129.26870731331;73167;1489.1883923261848;1508.8717459016107;1535.462588094158;1540.283768047863;1540.9306581203873;31498.37003259968;32982.835085824416;33175.004749857544;33182.17044573905;33277.667223328426;59837;60188;60483;60550;60580.69709651423;389624;391419;395288;395525;395647;28385.716142838308;29585.408291834472;29593.556096658136;30655.927042553405;46121.30818037665;40301.39547906725;44242.3363649552;47326;51322;52191.47808521866;38520.6147938517;39469.21061578695;39548.604513954495;39723;40759.38860917148;40284;41461.37807932823;41521.47852147852;41974;42050.78974605107;41046.768596942136;41116.1776764469;42067.7896610515;42521.1495770089;42590.361144583476;32914.34171316608;33836.830815845766;35034.474482883285;35802.28395432025;36002.09994750152;28066.298342540784;28407.573886391307;28520.429591408472;28559.714402856538;28566.42867142687 20:42:55 INFO - PROCESS | 4729 | |2;jquery.html;427.8203154675038;442.7830363122071;444.13172248254665;446.7817287169206;446.85030514777554;20526;21143.154273829048;22303.442413939618;22477.43806404841;22574.097036118557;22547.887260563675;22744.20395286165;22825.771742282574;22906.77093229067;23377;6002.609830361034;6051.394860513958;6158.322584515699;6183.907241391383;6197.659128747917;6061.242344706911;6194.070889366586;6196.504279657639;6198.473129783952;6202.720877560498;5970.73131709072;6164.07538869171;6169.691515424233;6169.969150154243;6261.592996455239;6286.057091436275;6319.715612797435;6323.93676063238;6326.525510586702;6334.923063079287;5990.2212712347555;6191.81424557264;6220.875582488325;6228.096925945758;6246.312905580383;14605.342759575844;14899.70200595982;20035;25620.743792562207;25936.481270374676;3097.9380412391624;3122.7970181938103;3139.1210461070937;3197.4084794312894;3232.2565809863627;10894.836577451266;10913.83629245562;10949.507272172692;11119.27724697892;11544.191906566544;1985.7815640279557;2020.7575090989028;2027.908744106504;2051.7948205179514;2087.373787863635;64308;82393;93406;101850.98149018489;102394.48802756013;12252.693682657826;12285.938570307091;12294;12408.937955310166;12736.172148810298;17930.19314130867;17942.37201697934;17974.730379044326;17982.730259045864;18006.5498362542;9221.262299016196;9233.399829011092;9347.112024357675;9453.101955314234;9552.617895284111;8994.730158095268;8996.460212387146;9076.183143516982;9085.338209138137;9233.815323693489;4780.4741478437345;4890.168671325883;4902.068606964665;5068.265101560253;5088.643794934393;1976.6343226502856;2052.5279185787354;2107.8840663763335;2166.739991201066;2186.8031877131134;939.6664184214642;950.4107453378949;961.4087336288133;971.4220039076738;973.7955029443755;20269.594608107756;21205;21306.573868522544;21453.498255122304;21943.890280548494;962.5235508423235;976.5361453309621;978.7308490165261;984.4339504784778;985.5811280205884;11347.148963827745;11801.232919860182;11928.701782455508;11987.940060299818;12088.335141567299;712026;740021;746259.1721029028;754164;768269;106643.4667826656;110916.4454177724;111896;113397;113613;1101.5758932810925;1106.303029091667;1141.263884794303;1142.017864636412;1144.1704764046167;17718.82281177172;17832.732509012636;17886;18103.414053195607;18230.17964191641;44669.329960049974;44787;45273.32090018586;45871;46388.768056159504;58261.12608310964;61762.69118654378;62196;62618.37381626126;62638;8559.700410485602;8647.0920553341;9090.272778177598;9139.725808225763;9163.77090572741;10954.890451095705;13020.088593798344;13155.868441315464;13334.86665133375;13418.664533386744;10886.945565272123;11450.713732156762;11859.881401185878;11938.164328496921;12131.696707582028;11375.54497820078;11492.782021034123;11622.82565761531;11669.824952625548;11937.940310298392;11804.645860624196;11877.109216808947;12180.939095304468;12284.631461056182;12361.258324500559;8533.786655333666;8764.517951512473;9022.774430639287;9146.496942668216;9424.481653509123;11403.600873969372;11704.122190835891;11796.410179491162;11865.169438139248;12095.21381110225 20:42:55 INFO - PROCESS | 4729 | |3;mootools.html;1502.106246783164;1504.9247537623116;1506.1189204315474;1524.0474703310438;1539.6689711711979;8066.838663226732;8513.489190648565;8520.318374530038;8523.360747943914;8531.488110713353;7512.549247045181;7696.538207707542;7702.6918923243;7911.92088079119;8386.87419688705;2191.495955930137;2407.38611654028;2473.4558397152596;2478.0583378316214;2503.6119401492797;2463;2496.5256601245796;2506.8997240110375;2512.246326102171;2515.107136966378;2436.488337449138;2440.328909549879;2451.105346548506;2453.288546321565;2458.299384675368;2292.472731271817;2297.023764899919;2321.4892723600783;2326.360250930991;2329.1382188590246;2393.5930891748444;2425.236050644059;2447.0823441209545;2453.0433131078717;2458.9016439342404;8943.64225430982;9007.234385077276;9010.684626038123;9013.774655633662;9021.458712477206;5208.5051920067535;5345.385280692732;5348.759305831247;5354.036273470773;5354.571634269249;3292.901212963615;3388.6611338865914;3425.6231814500384;3439.7420193485336;3467.167879708877;1283.4866053578644;1347.8382594088673;1352.2900477249425;1399.6081097292774;1403.9789403158961;12086.153969222158;12115.878841211563;12160.51357945681;12165.635030949086;12201.816972745415;4750.537002058279;4810.230363141881;4868.41579010521;4895.412550493964;5032.421271553745;10488.00363965422;10496.055355017932;10498.055175034131;10498.895011049945;10507.317024393544;5689.715514224272;5697.914531282034;5699.601027928088;5704.543636509069;5704.686242256633;5016.9715208382195;5054.014467178865;5077.416097148801;5112.130937740593;5157.896842063138;3747.7001839852746;3780.243951209769;3951.1702542465828;4053.250148722496;4056.553779084298;1186.0452335869709;1194.2595590733802;1195.1514424758345;1198.1253684809963;1200.8138738495547;1051.8264486359817;1065.4672663668166;1071.517816982361;1071.935683858971;1083.7778255457613;5747.453991870766;5869.119632055226;5880.617759845596;5934.19888315072;6012.759489620454;1002.4085789384153;1071.464267866067;1079.3308148947547;1088.2654208409292;1094.1301665176204;3911.7457365271166;4013.6387725104883;4108.233867078934;4125.9891326624565;4232.492100947906;10665.573377064986;11005.17461190414;11012.284201526874;11015.228933974706;11018.50416731249;10767.084797792275;10839.891601083888;10858.837117443245;10859.674209773719;10881.782364352668;8284.337253019865;8509.361797865186;8521.872171917426;8534.530600817016;8540.44487108336;5664.745086471201;5688.886222275449;5791.507721843691;5815.534757219497;5831.0378787499585;9869.407835529892;9889.406635601887;9891.060349266809;9969.052939970692;10011.699649010541;9680.5643746033;9868.703938881845;9991.800163996824;10125.898741012496;10137.138343240907;7378.483506154517;7555.584442855476;7584.886226706714;7585.355244804254;7594.962025189839;7433.219511951167;7702.614869256627;8326.125756795449;8552.615132318937;8620.698275560326;7258.346748792634;7285.453590980804;7339.596322202116;7341.375983041501;7492.063492063492;7612.429067820047;7622.428317876071;7668.463207575416;7697.730579429693;7809.101953275446;6452.516061295328;6562.704678289393;6563.343665633399;6569.343065693392;6576.8355791105605;4980.228064649985;5012.097822392005;5080.771365288645;5127.307813445137;5138.486151384832;5123.92314115296;5216.843494695166;5298.5338235513855;5328.547073498796;5406.053940560465 20:42:55 INFO - PROCESS | 4729 | |4;prototype.html;405.1431222963432;411.11199808413824;412.40613516536183;418.2659432695619;421.30064093604625;5327.307450031495;5379.892402151954;5384.946150538493;5420;5426.67439953603;4796.928046079312;5113.821016264432;5360.195970604412;5361.874006458638;5376.086065368887;4102.692298077641;4178.958210417887;4188.183304255656;4201.495820501529;4250.3624456331645;4242.639375653058;4261.147770445908;4264.275073237557;4270.274053410928;4276.722013069156;4067.7355971861884;4249.617534421902;4251.7882403515005;4262.25410553152;4276.97861510692;4046.231216068952;4147.585241475844;4193.811278492475;4200.7479551227025;4211.97894010531;3768.5854555998817;3800.6959443244473;3801.163743976321;3819.9618003819883;3853.7880416577077;5245.370555533361;5246.711430871301;5253.9737301313635;5255.500727430888;5290.735463226862;4774.785134668913;4886.926696099561;4919.139150648658;4926.630502712275;4931.161702510582;5556.749946252388;5573.41479144692;5575;5588;5653.349864765563;4275.957240427587;4278.614924656765;4283.443152390169;4289.206496798071;4311.8706438806885;5422.91865622012;5681.914771278434;5708.5433165347085;5714;5724.599278050579;4121.794839760713;4124.670026397881;4136.131412403429;4153.3769934510065;4187.706860519796;4213.0099426634715;4214.789260536961;4236.195122926634;4236.661067114624;4237.643953934711;4682.789274482657;4698.436187657435;4775.111057809975;4776.832810851603;4790.664653474223;5601.495865372136;5615.691136987505;5632.577556683265;5637.943620563824;5647.0964645656495;1379.4895888521312;1380.5513208207374;1388.8541703121027;1389.909655872365;1397.1128333508093;926.5036965300991;967.7870868408938;971.1017308989157;987.9308448408686;996.9052939970691;5251.10731175701;5352.866178345573;5388.407275199725;5392.487713667196;5393.6224464287125;5496.587755918322;5568.9443105569235;5569.498745112878;5572.5263352614675;5583.218349431083;5071.424645624826;5310.96436194946;5321.574274058066;5352.705601191972;5368.409474957752;2899.7970142089853;2908.1420980810713;2911.839848808336;2918.562215667667;2935.544990526472;5381.730913454312;5616.9157462638095;5630;5640.210370548127;5648;5452.59105567084;5558.027345214571;5560.805371812048;5575.776968921197;5602.515333436642;5661.1508273758445;5670.716464176857;5678.063119585222;5687.9431205687415;5694.544436445074;5231.8168864089575;5462.317210348707;5482.424345443671;5483.945160548344;5527.198556209247;5463.448758307132;5476.945230547803;5486.725663716878;5524;5529.585281103853;5663.858403539944;5672.546196304368;5673.546116310603;5679.403662615366;5708.600397972102;3909.6090390960676;3986.481757371639;4012.879613611596;4021.658159056513;4029.5164580250557;5538.390777014606;5580.330360356783;5601.6358936668985;5615.606907516598;5678.858028549319;5490;5495.5878309127775;5528.55771538284;5530.446955304437;5547.6408279971665;5512.381618731231;5555.860993517444;5560.471755183251;5573.414791446839;5589.826136511216;5266.42069372361;5483.972580137074;5548.583856210719;5553.9444605555045;5562.664960409554;5614.214010038516;5626.578006649435;5662.377138514842;5665.433456654301;5672.177534257427;5266.840595356039;5522.91715624274;5541.806036788693;5560.388357280615;5577.693226872561 20:42:55 INFO - PROCESS | 4729 | |5;yui.html;804.5324650755242;823.4482896459372;851.2551517422255;905.8414777413952;917.7659696777323;17565.385211517583;17658.29366825327;18191.454256372297;18295;18519.981401022957;15708.528744137695;15804.762928556082;15929.20353982302;16335;16650.250738716757;839.7900524868783;849.3926842307749;850.1625898489982;850.2177996243471;850.7490290364358;715.1668306423037;736.1093077376381;819.7090033038274;834.7954751085992;843.5318398288947;878.4421892098532;880.1418616848586;881.8104107616861;882.2104216726026;882.6381183714678;870.3820287595814;872.5113936195719;873.0789017586476;873.1661263493348;873.7771868173638;552.6435449135286;560.2576586023495;620.5935112501319;630.9716062777186;635.2916498104627;16799.832001679948;16800.83199168005;16837.663246734995;16849.494515164566;16862.915685421616;14367.353469093916;15339.309731062129;15352;15711.292991815395;16247.431339903047;13292.667683307898;13309.334533273395;13381.866181338159;13394.464221431132;13396.263205523692;3957.544882338539;3975.5428125765616;3989.082511022452;4042.636162745367;4061.1268577255737;86008.56995715044;86132;87399.56300218459;88913.11086889113;89346.5532672339;12328.972186461497;12569.183003104952;12591.496340146476;12767.425465853874;12814.743705125846;23879.52240955171;24078.87960560186;24097.518049638908;24234.878825606113;24541.75458245395;8757.956210218908;8873.822523549621;9053;9095.31785116119;9159.633614655473;4906.239532872411;4985.202367621163;5025.070361983045;5033.370828646419;5161.148410512224;3775.867844625425;3851.6533511984057;3856.151646637735;3857.286402015636;3865.7487263327794;627.9717412716439;681.819317880762;705.0657878311258;711.0578483509371;716.3266529462289;836.6820608168857;836.6904245429108;837.9916200837913;855.4995327733221;869.8216865542548;10657.84013239802;10790.24468287228;11027.669169924915;11142.721431964266;11182.552697891993;825.7068740597122;834.9900596421471;841.5834162089683;865.4288169807896;866.1901122450403;5245.868853278698;5284.524392804666;5370.409254981949;5387.653086728318;5409.323834520685;83873.16126838776;84241;84608.57695711483;85234.57382713171;85580.57209713911;56650;60664;61186.69406652938;71689.28310716826;74493;66010;66875.66562167158;67334;67874.66062669655;67885.66057169584;10149.289549731448;10393.324433911772;10565.20760942917;10567.89432105669;10586.36481811063;43019;43131.78434107935;43806.12387752163;44056.559434405244;44415.55584444114;53209.46790532045;53708.731456342466;53859.46140538701;54336.45663543472;57127.143092852806;12073.698157546132;12245.326507042199;12344.074194435274;12495.125341226027;12560.183588066746;14650.706985860435;14842.332095055965;15023.173725445205;15050.623734406727;15394.615134621725;14084.295785210494;14333.211673357648;14389.352479138255;14446.711065778416;14497.420103196177;13719.45122195101;13905.235212063433;13998.950078743932;14499.275036248357;14504.202268874891;13053.412596432994;13286.136401133896;13319.836643512908;13618.659533511742;13639.181649101085;11628.709282268012;11649.534018639159;11864.644060678194;12130.878691213327;12400.62798116058;11360.54557817678;11410.315381077162;11644.417779110841;11646.24299420535;11725.648230553097 20:42:55 INFO - PROCESS | 4729 | __end_tp_report 20:42:55 INFO - PROCESS | 4729 | __start_cc_report 20:42:55 INFO - PROCESS | 4729 | _x_x_mozilla_cycle_collect,1111 20:42:55 INFO - PROCESS | 4729 | __end_cc_report 20:42:55 INFO - PROCESS | 4729 | __startTimestamp1488516175830__endTimestamp 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | ------- Summary: start ------- 20:42:55 INFO - PROCESS | 4729 | Number of tests: 6 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | [#0] dojo.html Cycles:165 Average:5485.11 Median:5738.54 stddev:613.85 (10.7%) stddev-sans-first:591.36 20:42:55 INFO - PROCESS | 4729 | Values: 3302.0 3316.0 3322.4 3346.4 3357.1 5406.6 5473.5 5487.6 5518.8 5521.6 5479.3 5521.5 5534.7 5535.5 5558.1 4923.1 4941.9 4976.7 4984.6 5048.2 4878.3 4964.3 5000.6 5015.7 5021.9 4839.3 4909.2 4939.8 4948.4 5037.2 5006.4 5046.6 5050.6 5052.9 5074.6 4964.7 4989.6 4997.0 5011.8 5029.4 5248.1 5350.4 5407.9 5469.8 5476.8 5943.9 5950.4 5958.9 5961.9 5993.9 5930.0 5972.4 5986.8 6008.9 6017.2 5388.4 5422.3 5456.3 5598.6 5604.1 5954.7 6023.8 6025.6 6064.1 6064.9 5745.7 5800.7 5802.6 5806.9 5809.0 5738.8 5800.8 5896.9 5913.1 5914.1 5740.5 5747.6 5749.3 5785.8 5869.6 5815.2 5816.5 5820.2 5823.3 5841.9 4468.2 4513.4 4532.8 4539.1 4572.5 4019.3 4043.9 4085.0 4091.7 4096.1 5688.4 5695.3 5719.0 5809.4 5841.1 5769.9 5805.0 6005.5 6012.9 6019.6 5649.7 5652.1 5686.1 5687.6 5706.3 4938.1 4982.7 5007.8 5070.9 5071.5 6070.9 6126.3 6127.5 6144.8 6149.1 5935.1 5960.5 5967.7 5967.8 5975.4 5951.1 5976.8 6138.8 6139.9 6143.7 5766.1 5778.6 5803.4 5874.3 5899.9 5932.7 5942.1 5987.6 5991.7 5993.7 5525.9 5764.0 5764.2 5764.7 5770.2 5613.7 5654.5 5710.3 5738.5 5845.0 5789.8 5799.7 5800.8 5818.1 5825.7 5833.3 5868.4 5871.4 5872.6 5873.4 5517.6 5544.6 5663.0 5711.0 5724.5 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | [#1] ext.html Cycles:180 Average:51351.29 Median:217418.30 stddev:83985.72 (38.6%) stddev-sans-first:84134.78 20:42:55 INFO - PROCESS | 4729 | Values: 570.2 597.7 616.6 617.7 641.4 37247.0 40595.6 40698.4 40909.2 47927.8 47643.8 52139.9 52231.0 52708.0 52715.7 9431.2 9601.9 9621.4 9625.4 9625.7 8676.8 8932.0 9333.6 9459.1 9464.1 11191.6 11530.6 11537.2 11859.3 11873.5 11510.8 11566.0 11575.3 11597.5 11610.2 11206.7 11278.0 11311.1 11315.7 11319.1 48062.0 48292.0 48459.3 48465.8 48484.0 4553.0 4553.4 4611.0 4730.1 4738.5 34060.5 34664.5 34883.3 36069.5 36284.5 4657.1 4680.9 4706.7 4721.2 4734.6 343315.0 344181.3 344925.0 345911.0 346145.0 69294.0 69734.7 69766.7 69888.7 69940.7 74246.0 74437.3 74483.0 74767.6 74834.3 19401.5 19767.1 19859.5 19931.1 19932.6 15207.2 15443.4 15452.2 15458.0 15467.5 16379.9 17096.8 18121.6 18787.7 19878.8 6450.6 6496.2 6544.7 6625.2 6626.3 973.5 975.6 986.9 991.8 1006.0 33828.7 34483.0 34855.7 34881.8 35322.0 1061.5 1175.6 1187.4 1188.4 1226.9 17128.2 18260.9 18348.5 18372.2 18534.2 177527.0 177637.0 177959.0 178177.1 178284.0 72756.6 72788.0 72931.6 73129.3 73167.0 1489.2 1508.9 1535.5 1540.3 1540.9 31498.4 32982.8 33175.0 33182.2 33277.7 59837.0 60188.0 60483.0 60550.0 60580.7 389624.0 391419.0 395288.0 395525.0 395647.0 28385.7 29585.4 29593.6 30655.9 46121.3 40301.4 44242.3 47326.0 51322.0 52191.5 38520.6 39469.2 39548.6 39723.0 40759.4 40284.0 41461.4 41521.5 41974.0 42050.8 41046.8 41116.2 42067.8 42521.1 42590.4 32914.3 33836.8 35034.5 35802.3 36002.1 28066.3 28407.6 28520.4 28559.7 28566.4 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | [#2] jquery.html Cycles:180 Average:37541.99 Median:25778.61 stddev:122124.90 (473.7%) stddev-sans-first:122435.69 20:42:55 INFO - PROCESS | 4729 | Values: 427.8 442.8 444.1 446.8 446.9 20526.0 21143.2 22303.4 22477.4 22574.1 22547.9 22744.2 22825.8 22906.8 23377.0 6002.6 6051.4 6158.3 6183.9 6197.7 6061.2 6194.1 6196.5 6198.5 6202.7 5970.7 6164.1 6169.7 6170.0 6261.6 6286.1 6319.7 6323.9 6326.5 6334.9 5990.2 6191.8 6220.9 6228.1 6246.3 14605.3 14899.7 20035.0 25620.7 25936.5 3097.9 3122.8 3139.1 3197.4 3232.3 10894.8 10913.8 10949.5 11119.3 11544.2 1985.8 2020.8 2027.9 2051.8 2087.4 64308.0 82393.0 93406.0 101851.0 102394.5 12252.7 12285.9 12294.0 12408.9 12736.2 17930.2 17942.4 17974.7 17982.7 18006.5 9221.3 9233.4 9347.1 9453.1 9552.6 8994.7 8996.5 9076.2 9085.3 9233.8 4780.5 4890.2 4902.1 5068.3 5088.6 1976.6 2052.5 2107.9 2166.7 2186.8 939.7 950.4 961.4 971.4 973.8 20269.6 21205.0 21306.6 21453.5 21943.9 962.5 976.5 978.7 984.4 985.6 11347.1 11801.2 11928.7 11987.9 12088.3 712026.0 740021.0 746259.2 754164.0 768269.0 106643.5 110916.4 111896.0 113397.0 113613.0 1101.6 1106.3 1141.3 1142.0 1144.2 17718.8 17832.7 17886.0 18103.4 18230.2 44669.3 44787.0 45273.3 45871.0 46388.8 58261.1 61762.7 62196.0 62618.4 62638.0 8559.7 8647.1 9090.3 9139.7 9163.8 10954.9 13020.1 13155.9 13334.9 13418.7 10886.9 11450.7 11859.9 11938.2 12131.7 11375.5 11492.8 11622.8 11669.8 11937.9 11804.6 11877.1 12180.9 12284.6 12361.3 8533.8 8764.5 9022.8 9146.5 9424.5 11403.6 11704.1 11796.4 11865.2 12095.2 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | [#3] mootools.html Cycles:180 Average:5777.19 Median:4881.91 stddev:3207.65 (65.7%) stddev-sans-first:3200.56 20:42:55 INFO - PROCESS | 4729 | Values: 1502.1 1504.9 1506.1 1524.0 1539.7 8066.8 8513.5 8520.3 8523.4 8531.5 7512.5 7696.5 7702.7 7911.9 8386.9 2191.5 2407.4 2473.5 2478.1 2503.6 2463.0 2496.5 2506.9 2512.2 2515.1 2436.5 2440.3 2451.1 2453.3 2458.3 2292.5 2297.0 2321.5 2326.4 2329.1 2393.6 2425.2 2447.1 2453.0 2458.9 8943.6 9007.2 9010.7 9013.8 9021.5 5208.5 5345.4 5348.8 5354.0 5354.6 3292.9 3388.7 3425.6 3439.7 3467.2 1283.5 1347.8 1352.3 1399.6 1404.0 12086.2 12115.9 12160.5 12165.6 12201.8 4750.5 4810.2 4868.4 4895.4 5032.4 10488.0 10496.1 10498.1 10498.9 10507.3 5689.7 5697.9 5699.6 5704.5 5704.7 5017.0 5054.0 5077.4 5112.1 5157.9 3747.7 3780.2 3951.2 4053.3 4056.6 1186.0 1194.3 1195.2 1198.1 1200.8 1051.8 1065.5 1071.5 1071.9 1083.8 5747.5 5869.1 5880.6 5934.2 6012.8 1002.4 1071.5 1079.3 1088.3 1094.1 3911.7 4013.6 4108.2 4126.0 4232.5 10665.6 11005.2 11012.3 11015.2 11018.5 10767.1 10839.9 10858.8 10859.7 10881.8 8284.3 8509.4 8521.9 8534.5 8540.4 5664.7 5688.9 5791.5 5815.5 5831.0 9869.4 9889.4 9891.1 9969.1 10011.7 9680.6 9868.7 9991.8 10125.9 10137.1 7378.5 7555.6 7584.9 7585.4 7595.0 7433.2 7702.6 8326.1 8552.6 8620.7 7258.3 7285.5 7339.6 7341.4 7492.1 7612.4 7622.4 7668.5 7697.7 7809.1 6452.5 6562.7 6563.3 6569.3 6576.8 4980.2 5012.1 5080.8 5127.3 5138.5 5123.9 5216.8 5298.5 5328.5 5406.1 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | [#4] prototype.html Cycles:180 Average:4673.66 Median:5377.99 stddev:1333.42 (24.8%) stddev-sans-first:1298.10 20:42:55 INFO - PROCESS | 4729 | Values: 405.1 411.1 412.4 418.3 421.3 5327.3 5379.9 5384.9 5420.0 5426.7 4796.9 5113.8 5360.2 5361.9 5376.1 4102.7 4179.0 4188.2 4201.5 4250.4 4242.6 4261.1 4264.3 4270.3 4276.7 4067.7 4249.6 4251.8 4262.3 4277.0 4046.2 4147.6 4193.8 4200.7 4212.0 3768.6 3800.7 3801.2 3820.0 3853.8 5245.4 5246.7 5254.0 5255.5 5290.7 4774.8 4886.9 4919.1 4926.6 4931.2 5556.7 5573.4 5575.0 5588.0 5653.3 4276.0 4278.6 4283.4 4289.2 4311.9 5422.9 5681.9 5708.5 5714.0 5724.6 4121.8 4124.7 4136.1 4153.4 4187.7 4213.0 4214.8 4236.2 4236.7 4237.6 4682.8 4698.4 4775.1 4776.8 4790.7 5601.5 5615.7 5632.6 5637.9 5647.1 1379.5 1380.6 1388.9 1389.9 1397.1 926.5 967.8 971.1 987.9 996.9 5251.1 5352.9 5388.4 5392.5 5393.6 5496.6 5568.9 5569.5 5572.5 5583.2 5071.4 5311.0 5321.6 5352.7 5368.4 2899.8 2908.1 2911.8 2918.6 2935.5 5381.7 5616.9 5630.0 5640.2 5648.0 5452.6 5558.0 5560.8 5575.8 5602.5 5661.2 5670.7 5678.1 5687.9 5694.5 5231.8 5462.3 5482.4 5483.9 5527.2 5463.4 5476.9 5486.7 5524.0 5529.6 5663.9 5672.5 5673.5 5679.4 5708.6 3909.6 3986.5 4012.9 4021.7 4029.5 5538.4 5580.3 5601.6 5615.6 5678.9 5490.0 5495.6 5528.6 5530.4 5547.6 5512.4 5555.9 5560.5 5573.4 5589.8 5266.4 5484.0 5548.6 5553.9 5562.7 5614.2 5626.6 5662.4 5665.4 5672.2 5266.8 5522.9 5541.8 5560.4 5577.7 20:42:55 INFO - PROCESS | 4729 | 20:42:55 INFO - PROCESS | 4729 | [#5] yui.html Cycles:180 Average:18543.50 Median:43075.39 stddev:23509.62 (54.6%) stddev-sans-first:23537.84 20:42:55 INFO - PROCESS | 4729 | Values: 804.5 823.4 851.3 905.8 917.8 17565.4 17658.3 18191.5 18295.0 18520.0 15708.5 15804.8 15929.2 16335.0 16650.3 839.8 849.4 850.2 850.2 850.7 715.2 736.1 819.7 834.8 843.5 878.4 880.1 881.8 882.2 882.6 870.4 872.5 873.1 873.2 873.8 552.6 560.3 620.6 631.0 635.3 16799.8 16800.8 16837.7 16849.5 16862.9 14367.4 15339.3 15352.0 15711.3 16247.4 13292.7 13309.3 13381.9 13394.5 13396.3 3957.5 3975.5 3989.1 4042.6 4061.1 86008.6 86132.0 87399.6 88913.1 89346.6 12329.0 12569.2 12591.5 12767.4 12814.7 23879.5 24078.9 24097.5 24234.9 24541.8 8758.0 8873.8 9053.0 9095.3 9159.6 4906.2 4985.2 5025.1 5033.4 5161.1 3775.9 3851.7 3856.2 3857.3 3865.7 628.0 681.8 705.1 711.1 716.3 836.7 836.7 838.0 855.5 869.8 10657.8 10790.2 11027.7 11142.7 11182.6 825.7 835.0 841.6 865.4 866.2 5245.9 5284.5 5370.4 5387.7 5409.3 83873.2 84241.0 84608.6 85234.6 85580.6 56650.0 60664.0 61186.7 71689.3 74493.0 66010.0 66875.7 67334.0 67874.7 67885.7 10149.3 10393.3 10565.2 10567.9 10586.4 43019.0 43131.8 43806.1 44056.6 44415.6 53209.5 53708.7 53859.5 54336.5 57127.1 12073.7 12245.3 12344.1 12495.1 12560.2 14650.7 14842.3 15023.2 15050.6 15394.6 14084.3 14333.2 14389.4 14446.7 14497.4 13719.5 13905.2 13999.0 14499.3 14504.2 13053.4 13286.1 13319.8 13618.7 13639.2 11628.7 11649.5 11864.6 12130.9 12400.6 11360.5 11410.3 11644.4 11646.2 11725.6 20:42:55 INFO - PROCESS | 4729 | -------- Summary: end -------- 20:42:55 INFO - PROCESS | 4729 | 20:42:56 INFO - TEST-INFO | 4729: exit 0 20:42:56 INFO - TEST-OK | dromaeo_css | took 1113923ms 20:42:56 INFO - TEST-START | kraken 20:42:56 INFO - operating with platform_type : linux_ 20:42:56 INFO - Initialising browser for kraken test... 20:42:56 INFO - TEST-INFO | started process 5044 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSNCWHg/profile http://localhost:58864/getInfo.html) 20:42:56 CRITICAL - PROCESS | 5044 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 20:42:56 INFO - TEST-INFO | 5044: exit 127 20:42:56 INFO - Could not find __metrics(.*)__metrics in browser output 20:42:56 CRITICAL - Raw results:Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 20:42:56 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 20:42:56 ERROR - Traceback (most recent call last): 20:42:56 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 20:42:56 INFO - talos_results.add(mytest.runTest(browser_config, test)) 20:42:56 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 20:42:56 INFO - with FFSetup(browser_config, test_config) as setup: 20:42:56 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 162, in __enter__ 20:42:56 INFO - self._run_profile() 20:42:56 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 138, in _run_profile 20:42:56 INFO - raise TalosError("browser failed to close after being initialized") 20:42:56 INFO - TalosError: browser failed to close after being initialized 20:42:56 INFO - TEST-INFO took 277ms 20:42:56 INFO - SUITE-END | took 1114s 20:42:57 ERROR - Return code: 2 20:42:57 WARNING - setting return code to 2 20:42:57 CRITICAL - # TBPL RETRY # 20:42:57 WARNING - setting return code to 4 20:42:57 INFO - Running post-action listener: _resource_record_post_action 20:42:57 INFO - [mozharness: 2017-03-03 04:42:57.340304Z] Finished run-tests step (success) 20:42:57 INFO - Running post-run listener: _resource_record_post_run 20:42:57 INFO - Total resource usage - Wall time: 1131s; CPU: 13.0%; Read bytes: 266240; Write bytes: 317935616; Read time: 28; Write time: 265188 20:42:57 INFO - TinderboxPrint: CPU usage
13.2% 20:42:57 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 20:42:57 INFO - TinderboxPrint: I/O write bytes / time
317,935,616 / 265,188 20:42:57 INFO - TinderboxPrint: CPU idle
7,838.8 (86.8%) 20:42:57 INFO - TinderboxPrint: CPU user
1,155.2 (12.8%) 20:42:57 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:57 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 117940224; Read time: 0; Write time: 120860 20:42:57 INFO - run-tests - Wall time: 1115s; CPU: 13.0%; Read bytes: 266240; Write bytes: 189980672; Read time: 28; Write time: 142796 20:42:57 INFO - Running post-run listener: _upload_blobber_files 20:42:57 INFO - Blob upload gear active. 20:42:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:57 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 20:42:57 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', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:42:57 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 Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:42:58 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:01 INFO - (blobuploader) - INFO - Done attempting. 20:43:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:43:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:43:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:02 INFO - (blobuploader) - INFO - Done attempting. 20:43:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:43:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:43:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:03 INFO - (blobuploader) - INFO - Done attempting. 20:43:03 INFO - (blobuploader) - INFO - Iteration through files over. 20:43:03 INFO - Return code: 0 20:43:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:43:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:43:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f3fe276d5a0e5c679ce92a765143d57034340c7f7bd02a9e4f5f733a8de6a98f3609387a0af2c11e9404ec3fd267050d6a73bb0bb2993932a65510b437d0fd2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7b63ea409fdc64c67014446da734a5a1f457c315692d78cef921ba1529cfc112a656ef2cf8401cf12358d6c515483426bca92097c5e67795892d374a2b3a3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a95fa14611fd9ddf2b2ebaa5f4ffe093abee764c3c5b510337fe207dd7e3cdb9e16bfdd36d9d2e231a80cd94eba2f70550fdaefeea6125582d3cd834b37c191"} 20:43:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:43:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:43:03 INFO - Contents: 20:43:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f3fe276d5a0e5c679ce92a765143d57034340c7f7bd02a9e4f5f733a8de6a98f3609387a0af2c11e9404ec3fd267050d6a73bb0bb2993932a65510b437d0fd2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7b63ea409fdc64c67014446da734a5a1f457c315692d78cef921ba1529cfc112a656ef2cf8401cf12358d6c515483426bca92097c5e67795892d374a2b3a3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a95fa14611fd9ddf2b2ebaa5f4ffe093abee764c3c5b510337fe207dd7e3cdb9e16bfdd36d9d2e231a80cd94eba2f70550fdaefeea6125582d3cd834b37c191"} 20:43:03 INFO - Running post-run listener: copy_logs_to_upload_dir 20:43:03 INFO - Copying logs to upload dir... 20:43:03 INFO - mkdir: /builds/slave/test/build/upload/logs 20:43:03 INFO - Copying logs to upload dir... 20:43:03 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1175.735819 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 35 secs) (at 2017-03-02 20:43:02.922967) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 20:43:02.926487) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f3fe276d5a0e5c679ce92a765143d57034340c7f7bd02a9e4f5f733a8de6a98f3609387a0af2c11e9404ec3fd267050d6a73bb0bb2993932a65510b437d0fd2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7b63ea409fdc64c67014446da734a5a1f457c315692d78cef921ba1529cfc112a656ef2cf8401cf12358d6c515483426bca92097c5e67795892d374a2b3a3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a95fa14611fd9ddf2b2ebaa5f4ffe093abee764c3c5b510337fe207dd7e3cdb9e16bfdd36d9d2e231a80cd94eba2f70550fdaefeea6125582d3cd834b37c191"} build_url:https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011203 build_url: 'https://queue.taskcluster.net/v1/task/dBYxySc4S7aSQ5K_8xoWhA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f3fe276d5a0e5c679ce92a765143d57034340c7f7bd02a9e4f5f733a8de6a98f3609387a0af2c11e9404ec3fd267050d6a73bb0bb2993932a65510b437d0fd2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7b63ea409fdc64c67014446da734a5a1f457c315692d78cef921ba1529cfc112a656ef2cf8401cf12358d6c515483426bca92097c5e67795892d374a2b3a3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a95fa14611fd9ddf2b2ebaa5f4ffe093abee764c3c5b510337fe207dd7e3cdb9e16bfdd36d9d2e231a80cd94eba2f70550fdaefeea6125582d3cd834b37c191"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 20:43:02.951575) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:43:02.951911) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1488502995.57174-904038089 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 20:43:03.035404) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 20:43:03.035751) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 20:43:03.036123) ========= ========= Total master_lag: 0.27 =========