builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-016 starttime: 1486062280.76 results: retry (5) revision: cfb67ac16037824372bb5f90b9eb80800cd0a4bf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.756879) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.757362) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.757655) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.773963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.774263) ========= 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=00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-02 11:04:40-- 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% 54.7M=0s 2017-02-02 11:04:41 (54.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127755 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.915419) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.915761) ========= 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=00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017215 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.965661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:40.965995) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cfb67ac16037824372bb5f90b9eb80800cd0a4bf --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 cfb67ac16037824372bb5f90b9eb80800cd0a4bf --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=00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-02 11:04:41,228 truncating revision to first 12 chars 2017-02-02 11:04:41,228 Setting DEBUG logging. 2017-02-02 11:04:41,228 attempt 1/10 2017-02-02 11:04:41,228 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cfb67ac16037?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 11:04:41,601 unpacking tar archive at: mozilla-aurora-cfb67ac16037/testing/mozharness/ program finished with exit code 0 elapsedTime=0.688449 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:41.669100) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:41.669425) ========= script_repo_revision: cfb67ac16037824372bb5f90b9eb80800cd0a4bf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:41.669847) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:41.670110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 11:04:41.678949) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 33 secs) (at 2017-02-02 11:04:41.679223) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:04:42 INFO - MultiFileLogger online at 20170202 11:04:42 in /builds/slave/test 11:04:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 11:04:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:04:42 INFO - {'append_to_log': False, 11:04:42 INFO - 'base_work_dir': '/builds/slave/test', 11:04:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 11:04:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:04:42 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 11:04:42 INFO - 'buildbot_json_path': 'buildprops.json', 11:04:42 INFO - 'config_files': ('talos/linux_config.py',), 11:04:42 INFO - 'default_actions': ('clobber', 11:04:42 INFO - 'read-buildbot-config', 11:04:42 INFO - 'download-and-extract', 11:04:42 INFO - 'populate-webroot', 11:04:42 INFO - 'create-virtualenv', 11:04:42 INFO - 'install', 11:04:42 INFO - 'run-tests'), 11:04:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:04:42 INFO - 'download_minidump_stackwalk': True, 11:04:42 INFO - 'download_symbols': 'ondemand', 11:04:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:04:42 INFO - 'tooltool.py': '/tools/tooltool.py', 11:04:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:04:42 INFO - '/tools/misc-python/virtualenv.py')}, 11:04:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:04:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:04:42 INFO - 'installer_path': 'installer.exe', 11:04:42 INFO - 'log_level': 'info', 11:04:42 INFO - 'log_name': 'talos', 11:04:42 INFO - 'log_to_console': True, 11:04:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:04:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:04:42 INFO - 'opt_config_files': (), 11:04:42 INFO - 'pip_index': False, 11:04:42 INFO - 'sps_profile': False, 11:04:42 INFO - 'sps_profile_interval': 0, 11:04:42 INFO - 'suite': 'dromaeojs-e10s', 11:04:42 INFO - 'system_bits': '32', 11:04:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:04:42 INFO - 'title': 'talos-linux64-ix-016', 11:04:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:04:42 INFO - 'use_talos_json': True, 11:04:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:04:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:04:42 INFO - 'work_dir': 'build'} 11:04:42 INFO - [mozharness: 2017-02-02 19:04:42.040658Z] Running clobber step. 11:04:42 INFO - Running pre-action listener: _resource_record_pre_action 11:04:42 INFO - Running main action method: clobber 11:04:42 INFO - rmtree: /builds/slave/test/build 11:04:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:42 INFO - Running post-action listener: _resource_record_post_action 11:04:42 INFO - [mozharness: 2017-02-02 19:04:42.514917Z] Finished clobber step (success) 11:04:42 INFO - [mozharness: 2017-02-02 19:04:42.515018Z] Running read-buildbot-config step. 11:04:42 INFO - Running pre-action listener: _resource_record_pre_action 11:04:42 INFO - Running main action method: read_buildbot_config 11:04:42 INFO - Using buildbot properties: 11:04:42 INFO - { 11:04:42 INFO - "project": "", 11:04:42 INFO - "product": "firefox", 11:04:42 INFO - "who": "ryanvm@gmail.com", 11:04:42 INFO - "installer_path": "public/build/target.tar.bz2", 11:04:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 11:04:42 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 11:04:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs-e10s", 11:04:42 INFO - "stage_platform": "linux64", 11:04:42 INFO - "basedir": "/builds/slave/test", 11:04:42 INFO - "buildnumber": 13, 11:04:42 INFO - "platform": "ubuntu64_hw", 11:04:42 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:04:42 INFO - "slavebuilddir": "test", 11:04:42 INFO - "taskId": "Bk6i6rrjQmuTl4SKVNUXRg", 11:04:42 INFO - "branch": "mozilla-aurora", 11:04:42 INFO - "script_repo_revision": "production", 11:04:42 INFO - "revision": "cfb67ac16037824372bb5f90b9eb80800cd0a4bf", 11:04:42 INFO - "slavename": "talos-linux64-ix-016", 11:04:42 INFO - "repo_path": "releases/mozilla-aurora" 11:04:42 INFO - } 11:04:42 INFO - Finding installer, test and symbols from parent task. 11:04:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk6i6rrjQmuTl4SKVNUXRg'}, attempt #1 11:04:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk6i6rrjQmuTl4SKVNUXRg'}, attempt #1 11:04:43 INFO - Task dependencies: XLEqQSaRTBuGFvDNLMkyJA 11:04:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA'}, attempt #1 11:04:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2 11:04:43 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/test_packages.json 11:04:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.crashreporter-symbols.zip 11:04:43 INFO - Running post-action listener: _resource_record_post_action 11:04:43 INFO - [mozharness: 2017-02-02 19:04:43.914822Z] Finished read-buildbot-config step (success) 11:04:43 INFO - [mozharness: 2017-02-02 19:04:43.915040Z] Running download-and-extract step. 11:04:43 INFO - Running pre-action listener: _resource_record_pre_action 11:04:43 INFO - Running main action method: download_and_extract 11:04:43 INFO - mkdir: /builds/slave/test/build/tests 11:04:43 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')]} 11:04:43 INFO - trying https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.test_packages.json 11:04:43 INFO - Downloading https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:04:44 INFO - Downloaded 896 bytes. 11:04:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:04:44 INFO - Using the following test package requirements: 11:04:44 INFO - {u'common': [u'target.common.tests.zip'], 11:04:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:04:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:04:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:04:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:04:44 INFO - u'mozbase': [u'target.common.tests.zip'], 11:04:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:04:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:04:44 INFO - u'web-platform': [u'target.common.tests.zip', 11:04:44 INFO - u'target.web-platform.tests.zip'], 11:04:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:04:44 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:04:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.common.tests.zip 11:04:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:04:44 INFO - Fetch https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.common.tests.zip into memory 11:04:45 INFO - Expected file size: 21858447 11:04:45 INFO - Obtained file size: 21858447 11:04:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:04:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.common.tests.zip 11:04:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:04:49 INFO - Fetch https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.common.tests.zip into memory 11:04:50 INFO - Expected file size: 21858447 11:04:50 INFO - Obtained file size: 21858447 11:04:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.talos.tests.zip 11:04:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:04:54 INFO - Fetch https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.talos.tests.zip into memory 11:04:55 INFO - Expected file size: 14403485 11:04:55 INFO - Obtained file size: 14403485 11:04:55 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')]} 11:04:55 INFO - trying https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2 11:04:55 INFO - Downloading https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:04:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:04:56 INFO - Downloaded 66083798 bytes. 11:04:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2 11:04:56 INFO - mkdir: /builds/slave/test/properties 11:04:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:04:56 INFO - Writing to file /builds/slave/test/properties/build_url 11:04:56 INFO - Contents: 11:04:56 INFO - build_url:https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2 11:04:56 INFO - Running post-action listener: _resource_record_post_action 11:04:56 INFO - Running post-action listener: set_extra_try_arguments 11:04:56 INFO - [mozharness: 2017-02-02 19:04:56.710490Z] Finished download-and-extract step (success) 11:04:56 INFO - [mozharness: 2017-02-02 19:04:56.710587Z] Running populate-webroot step. 11:04:56 INFO - Running pre-action listener: _resource_record_pre_action 11:04:56 INFO - Running main action method: populate_webroot 11:04:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:04:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:04:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:04:56 INFO - '--tppagecycles', 11:04:56 INFO - '7'], 11:04:56 INFO - 'tests': ['tsvgm']}}, 11:04:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:04:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:04:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:04:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:04:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['damp', 'tps']}, 11:04:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'tests': ['damp', 'tps']}, 11:04:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['dromaeo_dom']}, 11:04:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:04:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['basic_compositor_video']}, 11:04:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:04:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['a11yr', 11:04:56 INFO - 'ts_paint', 11:04:56 INFO - 'tpaint', 11:04:56 INFO - 'sessionrestore', 11:04:56 INFO - 'sessionrestore_no_auto_restore', 11:04:56 INFO - 'tabpaint']}, 11:04:56 INFO - 'other-e10s': {'tests': ['a11yr', 11:04:56 INFO - 'ts_paint', 11:04:56 INFO - 'tpaint', 11:04:56 INFO - 'sessionrestore', 11:04:56 INFO - 'sessionrestore_no_auto_restore', 11:04:56 INFO - 'tabpaint']}, 11:04:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:04:56 INFO - 'ts_paint', 11:04:56 INFO - 'tpaint', 11:04:56 INFO - 'sessionrestore', 11:04:56 INFO - 'sessionrestore_no_auto_restore', 11:04:56 INFO - 'tabpaint']}, 11:04:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:04:56 INFO - 'ts_paint', 11:04:56 INFO - 'tpaint', 11:04:56 INFO - 'sessionrestore', 11:04:56 INFO - 'sessionrestore_no_auto_restore', 11:04:56 INFO - 'tabpaint']}, 11:04:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['a11yr', 11:04:56 INFO - 'ts_paint', 11:04:56 INFO - 'tpaint', 11:04:56 INFO - 'sessionrestore', 11:04:56 INFO - 'sessionrestore_no_auto_restore', 11:04:56 INFO - 'tabpaint']}, 11:04:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['a11yr', 11:04:56 INFO - 'ts_paint', 11:04:56 INFO - 'tpaint', 11:04:56 INFO - 'sessionrestore', 11:04:56 INFO - 'sessionrestore_no_auto_restore', 11:04:56 INFO - 'tabpaint']}, 11:04:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['tsvgx', 11:04:56 INFO - 'tsvgr_opacity', 11:04:56 INFO - 'tart', 11:04:56 INFO - 'tscrollx', 11:04:56 INFO - 'cart', 11:04:56 INFO - 'tsvg_static']}, 11:04:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:04:56 INFO - 'tsvgr_opacity', 11:04:56 INFO - 'tart', 11:04:56 INFO - 'tscrollx', 11:04:56 INFO - 'cart', 11:04:56 INFO - 'tsvg_static']}, 11:04:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'talos_options': ['--disable-e10s'], 11:04:56 INFO - 'tests': ['tp5o']}, 11:04:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:04:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'talos_options': ['--disable-e10s', 11:04:56 INFO - '--xperf_path', 11:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:56 INFO - 'tests': ['tp5n']}, 11:04:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:04:56 INFO - 'talos_options': ['--xperf_path', 11:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:56 INFO - 'tests': ['tp5n']}}, 11:04:56 INFO - 'talos.zip': {'path': '', 11:04:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:04:56 INFO - Running post-action listener: _resource_record_post_action 11:04:56 INFO - [mozharness: 2017-02-02 19:04:56.723609Z] Finished populate-webroot step (success) 11:04:56 INFO - [mozharness: 2017-02-02 19:04:56.723698Z] Running create-virtualenv step. 11:04:56 INFO - Running pre-action listener: _resource_record_pre_action 11:04:56 INFO - Running main action method: create_virtualenv 11:04:56 INFO - Creating virtualenv /builds/slave/test/build/venv 11:04:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:04:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:04:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:04:56 INFO - Using real prefix '/usr' 11:04:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:04:57 INFO - Installing distribute.............................................................................................................................................................................................done. 11:04:59 INFO - Installing pip.................done. 11:04:59 INFO - Return code: 0 11:04:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:04:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:04:59 INFO - Reading from file tmpfile_stdout 11:04:59 INFO - Output received: 11:04:59 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) 11:04:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:04:59 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')]} 11:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:59 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')]} 11:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:59 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:04:59 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 11:04:59 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 11:04:59 INFO - Using env: {'DISPLAY': ':0', 11:04:59 INFO - 'HOME': '/home/cltbld', 11:04:59 INFO - 'LANG': 'en_US.UTF-8', 11:04:59 INFO - 'LANGUAGE': 'en_US:en', 11:04:59 INFO - 'LOGNAME': 'cltbld', 11:04:59 INFO - 'MAIL': '/var/mail/cltbld', 11:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:59 INFO - 'MOZ_NO_REMOTE': '1', 11:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:59 INFO - 'NO_EM_RESTART': '1', 11:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:59 INFO - 'PWD': '/builds/slave/test', 11:04:59 INFO - 'SHELL': '/bin/bash', 11:04:59 INFO - 'SHLVL': '1', 11:04:59 INFO - 'TERM': 'linux', 11:04:59 INFO - 'TMOUT': '86400', 11:04:59 INFO - 'USER': 'cltbld', 11:04:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:59 INFO - '_': '/tools/buildbot/bin/python'} 11:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:04:59 INFO - Cleaning up... 11:04:59 INFO - Return code: 0 11:04:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:04:59 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')]} 11:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:59 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')]} 11:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:59 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:04:59 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 11:04:59 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 11:04:59 INFO - Using env: {'DISPLAY': ':0', 11:04:59 INFO - 'HOME': '/home/cltbld', 11:04:59 INFO - 'LANG': 'en_US.UTF-8', 11:04:59 INFO - 'LANGUAGE': 'en_US:en', 11:04:59 INFO - 'LOGNAME': 'cltbld', 11:04:59 INFO - 'MAIL': '/var/mail/cltbld', 11:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:59 INFO - 'MOZ_NO_REMOTE': '1', 11:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:59 INFO - 'NO_EM_RESTART': '1', 11:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:59 INFO - 'PWD': '/builds/slave/test', 11:04:59 INFO - 'SHELL': '/bin/bash', 11:04:59 INFO - 'SHLVL': '1', 11:04:59 INFO - 'TERM': 'linux', 11:04:59 INFO - 'TMOUT': '86400', 11:04:59 INFO - 'USER': 'cltbld', 11:04:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:59 INFO - '_': '/tools/buildbot/bin/python'} 11:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:59 INFO - Downloading/unpacking psutil>=3.1.1 11:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:59 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 11:04:59 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 11:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:05:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:02 INFO - Installing collected packages: psutil 11:05:02 INFO - Running setup.py install for psutil 11:05:02 INFO - building 'psutil._psutil_linux' extension 11:05:02 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 11:05:02 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 11:05:02 INFO - building 'psutil._psutil_posix' extension 11:05:02 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 11:05:02 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 11:05:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:02 INFO - Successfully installed psutil 11:05:02 INFO - Cleaning up... 11:05:02 INFO - Return code: 0 11:05:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:05:02 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')]} 11:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:02 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')]} 11:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:02 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:02 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 11:05:02 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 11:05:02 INFO - Using env: {'DISPLAY': ':0', 11:05:02 INFO - 'HOME': '/home/cltbld', 11:05:02 INFO - 'LANG': 'en_US.UTF-8', 11:05:02 INFO - 'LANGUAGE': 'en_US:en', 11:05:02 INFO - 'LOGNAME': 'cltbld', 11:05:02 INFO - 'MAIL': '/var/mail/cltbld', 11:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:02 INFO - 'MOZ_NO_REMOTE': '1', 11:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:02 INFO - 'NO_EM_RESTART': '1', 11:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:02 INFO - 'PWD': '/builds/slave/test', 11:05:02 INFO - 'SHELL': '/bin/bash', 11:05:02 INFO - 'SHLVL': '1', 11:05:02 INFO - 'TERM': 'linux', 11:05:02 INFO - 'TMOUT': '86400', 11:05:02 INFO - 'USER': 'cltbld', 11:05:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:02 INFO - '_': '/tools/buildbot/bin/python'} 11:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:03 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 11:05:03 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 11:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:05:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:05:05 INFO - Installing collected packages: mozsystemmonitor 11:05:05 INFO - Running setup.py install for mozsystemmonitor 11:05:05 INFO - Successfully installed mozsystemmonitor 11:05:05 INFO - Cleaning up... 11:05:05 INFO - Return code: 0 11:05:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05: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==2.5.1'] in /builds/slave/test/build 11:05: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==2.5.1 11:05:05 INFO - Using env: {'DISPLAY': ':0', 11:05:05 INFO - 'HOME': '/home/cltbld', 11:05:05 INFO - 'LANG': 'en_US.UTF-8', 11:05:05 INFO - 'LANGUAGE': 'en_US:en', 11:05:05 INFO - 'LOGNAME': 'cltbld', 11:05:05 INFO - 'MAIL': '/var/mail/cltbld', 11:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:05 INFO - 'MOZ_NO_REMOTE': '1', 11:05:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:05 INFO - 'NO_EM_RESTART': '1', 11:05:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:05 INFO - 'PWD': '/builds/slave/test', 11:05:05 INFO - 'SHELL': '/bin/bash', 11:05:05 INFO - 'SHLVL': '1', 11:05:05 INFO - 'TERM': 'linux', 11:05:05 INFO - 'TMOUT': '86400', 11:05:05 INFO - 'USER': 'cltbld', 11:05:05 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:05 INFO - '_': '/tools/buildbot/bin/python'} 11:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:05 INFO - Downloading/unpacking jsonschema==2.5.1 11:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:05 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 11:05:05 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 11:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:05:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:07 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 11:05:07 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 11:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:07 INFO - Downloading functools32-3.2.3-2.tar.gz 11:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:05:07 INFO - warning: no files found matching '*.txt' 11:05:07 INFO - no previously-included directories found matching 'build' 11:05:07 INFO - no previously-included directories found matching 'dist' 11:05:07 INFO - no previously-included directories found matching '.git*' 11:05:07 INFO - Installing collected packages: jsonschema, functools32 11:05:07 INFO - Running setup.py install for functools32 11:05:07 INFO - warning: no files found matching '*.txt' 11:05:07 INFO - no previously-included directories found matching 'build' 11:05:07 INFO - no previously-included directories found matching 'dist' 11:05:07 INFO - no previously-included directories found matching '.git*' 11:05:07 INFO - Successfully installed jsonschema functools32 11:05:07 INFO - Cleaning up... 11:05:07 INFO - Return code: 0 11:05:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:05:07 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')]} 11:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:07 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')]} 11:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:08 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 11:05:08 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 11:05:08 INFO - Using env: {'DISPLAY': ':0', 11:05:08 INFO - 'HOME': '/home/cltbld', 11:05:08 INFO - 'LANG': 'en_US.UTF-8', 11:05:08 INFO - 'LANGUAGE': 'en_US:en', 11:05:08 INFO - 'LOGNAME': 'cltbld', 11:05:08 INFO - 'MAIL': '/var/mail/cltbld', 11:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:08 INFO - 'MOZ_NO_REMOTE': '1', 11:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:08 INFO - 'NO_EM_RESTART': '1', 11:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:08 INFO - 'PWD': '/builds/slave/test', 11:05:08 INFO - 'SHELL': '/bin/bash', 11:05:08 INFO - 'SHLVL': '1', 11:05:08 INFO - 'TERM': 'linux', 11:05:08 INFO - 'TMOUT': '86400', 11:05:08 INFO - 'USER': 'cltbld', 11:05:08 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:08 INFO - '_': '/tools/buildbot/bin/python'} 11:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:05:08 INFO - Cleaning up... 11:05:08 INFO - Return code: 0 11:05:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:05:08 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')]} 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 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')]} 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:08 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 11:05:08 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 11:05:08 INFO - Using env: {'DISPLAY': ':0', 11:05:08 INFO - 'HOME': '/home/cltbld', 11:05:08 INFO - 'LANG': 'en_US.UTF-8', 11:05:08 INFO - 'LANGUAGE': 'en_US:en', 11:05:08 INFO - 'LOGNAME': 'cltbld', 11:05:08 INFO - 'MAIL': '/var/mail/cltbld', 11:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:08 INFO - 'MOZ_NO_REMOTE': '1', 11:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:08 INFO - 'NO_EM_RESTART': '1', 11:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:08 INFO - 'PWD': '/builds/slave/test', 11:05:08 INFO - 'SHELL': '/bin/bash', 11:05:08 INFO - 'SHLVL': '1', 11:05:08 INFO - 'TERM': 'linux', 11:05:08 INFO - 'TMOUT': '86400', 11:05:08 INFO - 'USER': 'cltbld', 11:05:08 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:08 INFO - '_': '/tools/buildbot/bin/python'} 11:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:08 INFO - Downloading/unpacking blobuploader==1.2.4 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:08 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 11:05:08 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 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:10 INFO - Downloading blobuploader-1.2.4.tar.gz 11:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:05:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:10 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 11:05:10 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 11:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:05:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:10 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 11:05:10 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 11:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:11 INFO - Downloading docopt-0.6.1.tar.gz 11:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:05:11 INFO - Installing collected packages: blobuploader, requests, docopt 11:05:11 INFO - Running setup.py install for blobuploader 11:05:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:05:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:05:11 INFO - Running setup.py install for requests 11:05:11 INFO - Running setup.py install for docopt 11:05:11 INFO - Successfully installed blobuploader requests docopt 11:05:11 INFO - Cleaning up... 11:05:11 INFO - Return code: 0 11:05:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:11 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')]} 11:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:11 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')]} 11:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:11 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:11 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 11:05:11 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 11:05:11 INFO - Using env: {'DISPLAY': ':0', 11:05:11 INFO - 'HOME': '/home/cltbld', 11:05:11 INFO - 'LANG': 'en_US.UTF-8', 11:05:11 INFO - 'LANGUAGE': 'en_US:en', 11:05:11 INFO - 'LOGNAME': 'cltbld', 11:05:11 INFO - 'MAIL': '/var/mail/cltbld', 11:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:11 INFO - 'MOZ_NO_REMOTE': '1', 11:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:11 INFO - 'NO_EM_RESTART': '1', 11:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:11 INFO - 'PWD': '/builds/slave/test', 11:05:11 INFO - 'SHELL': '/bin/bash', 11:05:11 INFO - 'SHLVL': '1', 11:05:11 INFO - 'TERM': 'linux', 11:05:11 INFO - 'TMOUT': '86400', 11:05:11 INFO - 'USER': 'cltbld', 11:05:11 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:11 INFO - '_': '/tools/buildbot/bin/python'} 11:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:12 INFO - Running setup.py (path:/tmp/pip-sDrnPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:12 INFO - Running setup.py (path:/tmp/pip-L_LXgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:12 INFO - Running setup.py (path:/tmp/pip-WdAJRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:12 INFO - Running setup.py (path:/tmp/pip-ko0d_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:12 INFO - Running setup.py (path:/tmp/pip-3YSlc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:12 INFO - Running setup.py (path:/tmp/pip-eOm0YY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:12 INFO - Running setup.py (path:/tmp/pip-pINeFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:12 INFO - Running setup.py (path:/tmp/pip-FHuLvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:12 INFO - Running setup.py (path:/tmp/pip-zfhNqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:12 INFO - Running setup.py (path:/tmp/pip-LS_Jc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:12 INFO - Running setup.py (path:/tmp/pip-BAMAOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:12 INFO - Running setup.py (path:/tmp/pip-_up1ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:12 INFO - Running setup.py (path:/tmp/pip-oehELF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:13 INFO - Running setup.py (path:/tmp/pip-_Cc2st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:13 INFO - Running setup.py (path:/tmp/pip-lnD_hE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:13 INFO - Running setup.py (path:/tmp/pip-9eLXql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:13 INFO - Running setup.py (path:/tmp/pip-Y1q4qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:05:13 INFO - Running setup.py install for manifestparser 11:05:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Running setup.py install for mozcrash 11:05:13 INFO - Running setup.py install for mozdebug 11:05:13 INFO - Running setup.py install for mozdevice 11:05:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Running setup.py install for mozfile 11:05:13 INFO - Running setup.py install for mozhttpd 11:05:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Running setup.py install for mozinfo 11:05:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Running setup.py install for mozInstall 11:05:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Running setup.py install for mozleak 11:05:14 INFO - Running setup.py install for mozlog 11:05:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Running setup.py install for moznetwork 11:05:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Running setup.py install for mozprocess 11:05:14 INFO - Running setup.py install for mozprofile 11:05:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Running setup.py install for mozrunner 11:05:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Running setup.py install for mozscreenshot 11:05:14 INFO - Running setup.py install for moztest 11:05:14 INFO - Running setup.py install for mozversion 11:05:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:05:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:05:15 INFO - Cleaning up... 11:05:15 INFO - Return code: 0 11:05:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:15 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')]} 11:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:15 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')]} 11:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:15 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:15 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 11:05:15 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 11:05:15 INFO - Using env: {'DISPLAY': ':0', 11:05:15 INFO - 'HOME': '/home/cltbld', 11:05:15 INFO - 'LANG': 'en_US.UTF-8', 11:05:15 INFO - 'LANGUAGE': 'en_US:en', 11:05:15 INFO - 'LOGNAME': 'cltbld', 11:05:15 INFO - 'MAIL': '/var/mail/cltbld', 11:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:15 INFO - 'MOZ_NO_REMOTE': '1', 11:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:15 INFO - 'NO_EM_RESTART': '1', 11:05:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:15 INFO - 'PWD': '/builds/slave/test', 11:05:15 INFO - 'SHELL': '/bin/bash', 11:05:15 INFO - 'SHLVL': '1', 11:05:15 INFO - 'TERM': 'linux', 11:05:15 INFO - 'TMOUT': '86400', 11:05:15 INFO - 'USER': 'cltbld', 11:05:15 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:15 INFO - '_': '/tools/buildbot/bin/python'} 11:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:15 INFO - Running setup.py (path:/tmp/pip-k2keFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:15 INFO - Running setup.py (path:/tmp/pip-iLgMod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:15 INFO - Running setup.py (path:/tmp/pip-yXChm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:15 INFO - Running setup.py (path:/tmp/pip-p02una-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:15 INFO - Running setup.py (path:/tmp/pip-o43D0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:15 INFO - Running setup.py (path:/tmp/pip-M8PgOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:15 INFO - Running setup.py (path:/tmp/pip-eG21QG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:15 INFO - Running setup.py (path:/tmp/pip-fSICt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:15 INFO - Running setup.py (path:/tmp/pip-BhXyeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:16 INFO - Running setup.py (path:/tmp/pip-im9K9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:16 INFO - Running setup.py (path:/tmp/pip-qY869q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:16 INFO - Running setup.py (path:/tmp/pip-RgVut9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:16 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)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:16 INFO - Running setup.py (path:/tmp/pip-gMBzIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:16 INFO - Running setup.py (path:/tmp/pip-QcbodM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:16 INFO - Running setup.py (path:/tmp/pip-OcxkiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:16 INFO - Running setup.py (path:/tmp/pip-ISZtZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:16 INFO - Running setup.py (path:/tmp/pip-nR8gig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:05:16 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)) 11:05:16 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)) 11:05:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:16 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 11:05:16 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 11:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:18 INFO - Downloading blessings-1.6.tar.gz 11:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:05:18 INFO - Installing collected packages: blessings 11:05:18 INFO - Running setup.py install for blessings 11:05:18 INFO - Successfully installed blessings 11:05:18 INFO - Cleaning up... 11:05:18 INFO - Return code: 0 11:05:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:19 INFO - Reading from file tmpfile_stdout 11:05:19 INFO - Current package versions: 11:05:19 INFO - argparse == 1.2.1 11:05:19 INFO - blessings == 1.6 11:05:19 INFO - blobuploader == 1.2.4 11:05:19 INFO - docopt == 0.6.1 11:05:19 INFO - functools32 == 3.2.3-2 11:05:19 INFO - jsonschema == 2.5.1 11:05:19 INFO - manifestparser == 1.1 11:05:19 INFO - mozInstall == 1.12 11:05:19 INFO - mozcrash == 1.0 11:05:19 INFO - mozdebug == 0.1 11:05:19 INFO - mozdevice == 0.49 11:05:19 INFO - mozfile == 1.2 11:05:19 INFO - mozhttpd == 0.7 11:05:19 INFO - mozinfo == 0.9 11:05:19 INFO - mozleak == 0.1 11:05:19 INFO - mozlog == 3.4 11:05:19 INFO - moznetwork == 0.27 11:05:19 INFO - mozprocess == 0.24 11:05:19 INFO - mozprofile == 0.28 11:05:19 INFO - mozrunner == 6.13 11:05:19 INFO - mozscreenshot == 0.1 11:05:19 INFO - mozsystemmonitor == 0.3 11:05:19 INFO - moztest == 0.8 11:05:19 INFO - mozversion == 1.4 11:05:19 INFO - psutil == 3.1.1 11:05:19 INFO - requests == 1.2.3 11:05:19 INFO - wsgiref == 0.1.2 11:05:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:19 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')]} 11:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 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')]} 11:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:19 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 11:05:19 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 11:05:19 INFO - Using env: {'DISPLAY': ':0', 11:05:19 INFO - 'HOME': '/home/cltbld', 11:05:19 INFO - 'LANG': 'en_US.UTF-8', 11:05:19 INFO - 'LANGUAGE': 'en_US:en', 11:05:19 INFO - 'LOGNAME': 'cltbld', 11:05:19 INFO - 'MAIL': '/var/mail/cltbld', 11:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:19 INFO - 'MOZ_NO_REMOTE': '1', 11:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:19 INFO - 'NO_EM_RESTART': '1', 11:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:19 INFO - 'PWD': '/builds/slave/test', 11:05:19 INFO - 'SHELL': '/bin/bash', 11:05:19 INFO - 'SHLVL': '1', 11:05:19 INFO - 'TERM': 'linux', 11:05:19 INFO - 'TMOUT': '86400', 11:05:19 INFO - 'USER': 'cltbld', 11:05:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:19 INFO - '_': '/tools/buildbot/bin/python'} 11:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:05:19 INFO - Cleaning up... 11:05:19 INFO - Return code: 0 11:05:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:05:19 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')]} 11:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 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')]} 11:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 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 0x22dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, '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': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', '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 11:05:19 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 11:05:19 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 11:05:19 INFO - Using env: {'DISPLAY': ':0', 11:05:19 INFO - 'HOME': '/home/cltbld', 11:05:19 INFO - 'LANG': 'en_US.UTF-8', 11:05:19 INFO - 'LANGUAGE': 'en_US:en', 11:05:19 INFO - 'LOGNAME': 'cltbld', 11:05:19 INFO - 'MAIL': '/var/mail/cltbld', 11:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:19 INFO - 'MOZ_NO_REMOTE': '1', 11:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:19 INFO - 'NO_EM_RESTART': '1', 11:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:19 INFO - 'PWD': '/builds/slave/test', 11:05:19 INFO - 'SHELL': '/bin/bash', 11:05:19 INFO - 'SHLVL': '1', 11:05:19 INFO - 'TERM': 'linux', 11:05:19 INFO - 'TMOUT': '86400', 11:05:19 INFO - 'USER': 'cltbld', 11:05:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:19 INFO - '_': '/tools/buildbot/bin/python'} 11:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:05:19 INFO - Cleaning up... 11:05:19 INFO - Return code: 0 11:05:19 INFO - Running post-action listener: _resource_record_post_action 11:05:19 INFO - Running post-action listener: _start_resource_monitoring 11:05:19 INFO - Starting resource monitoring. 11:05:19 INFO - [mozharness: 2017-02-02 19:05:19.582742Z] Finished create-virtualenv step (success) 11:05:19 INFO - [mozharness: 2017-02-02 19:05:19.583246Z] Running install step. 11:05:19 INFO - Running pre-action listener: _resource_record_pre_action 11:05:19 INFO - Running main action method: install 11:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:19 INFO - Reading from file tmpfile_stdout 11:05:19 INFO - Detecting whether we're running mozinstall >=1.0... 11:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:05:19 INFO - Reading from file tmpfile_stdout 11:05:19 INFO - Output received: 11:05:19 INFO - Usage: mozinstall [options] installer 11:05:19 INFO - Options: 11:05:19 INFO - -h, --help show this help message and exit 11:05:19 INFO - -d DEST, --destination=DEST 11:05:19 INFO - Directory to install application into. [default: 11:05:19 INFO - "/builds/slave/test"] 11:05:19 INFO - --app=APP Application being installed. [default: firefox] 11:05:19 INFO - mkdir: /builds/slave/test/build/application 11:05:19 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'] 11:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:05:36 INFO - Reading from file tmpfile_stdout 11:05:36 INFO - Output received: 11:05:36 INFO - /builds/slave/test/build/application/firefox/firefox 11:05:36 INFO - Running post-action listener: _resource_record_post_action 11:05:36 INFO - [mozharness: 2017-02-02 19:05:36.290495Z] Finished install step (success) 11:05:36 INFO - [mozharness: 2017-02-02 19:05:36.290776Z] Running run-tests step. 11:05:36 INFO - Running pre-action listener: _resource_record_pre_action 11:05:36 INFO - Running main action method: run_tests 11:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:05:36 INFO - Python 2.7.3 11:05:36 INFO - Return code: 0 11:05:36 INFO - grabbing minidump binary from tooltool 11:05:36 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')]} 11:05:36 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 0x264bd00>, '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 0x264ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264ceb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:05:36 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 11:05:36 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 11:05:36 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 11:05:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:05:36 INFO - Return code: 0 11:05:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:05:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:05:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:05:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:05:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:05:36 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:05:36 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:05:36 INFO - Using env: {'DISPLAY': ':0', 11:05:36 INFO - 'HOME': '/home/cltbld', 11:05:36 INFO - 'LANG': 'en_US.UTF-8', 11:05:36 INFO - 'LANGUAGE': 'en_US:en', 11:05:36 INFO - 'LOGNAME': 'cltbld', 11:05:36 INFO - 'MAIL': '/var/mail/cltbld', 11:05:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:05:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:36 INFO - 'MOZ_NO_REMOTE': '1', 11:05:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:36 INFO - 'NO_EM_RESTART': '1', 11:05:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:36 INFO - 'PWD': '/builds/slave/test', 11:05:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:05:36 INFO - 'SHELL': '/bin/bash', 11:05:36 INFO - 'SHLVL': '1', 11:05:36 INFO - 'TERM': 'linux', 11:05:36 INFO - 'TMOUT': '86400', 11:05:36 INFO - 'USER': 'cltbld', 11:05:36 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327', 11:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:36 INFO - '_': '/tools/buildbot/bin/python'} 11:05:36 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:05:36 INFO - mozversion application_buildid: 20170202181038 11:05:36 INFO - mozversion application_changeset: cfb67ac16037824372bb5f90b9eb80800cd0a4bf 11:05:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:05:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:05:36 INFO - mozversion application_name: Firefox 11:05:36 INFO - mozversion application_remotingname: firefox-dev 11:05:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:05:36 INFO - mozversion application_vendor: Mozilla 11:05:36 INFO - mozversion application_version: 53.0a2 11:05:36 INFO - mozversion platform_buildid: 20170202181038 11:05:36 INFO - mozversion platform_changeset: cfb67ac16037824372bb5f90b9eb80800cd0a4bf 11:05:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:05:36 INFO - mozversion platform_version: 53.0a2 11:05:36 INFO - using testdate: 1486062336 11:05:36 INFO - actual date: 1486062336 11:05:36 INFO - starting webserver on 'localhost:51417' 11:05:36 INFO - SUITE-START | Running 2 tests 11:05:36 INFO - TEST-START | dromaeo_css 11:05:36 INFO - operating with platform_type : linux_ 11:05:36 INFO - Initialising browser for dromaeo_css test... 11:05:36 INFO - TEST-INFO | started process 22324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptfFDq3/profile http://localhost:51417/getInfo.html) 11:05:40 INFO - PROCESS | 22324 | __metrics Screen width/height:1600/1200 11:05:40 INFO - PROCESS | 22324 | colorDepth:24 11:05:40 INFO - PROCESS | 22324 | Browser inner width/height: 1024/697 11:05:40 INFO - PROCESS | 22324 | __metrics 11:05:41 ERROR - PROCESS | 22324 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 11:05:42 INFO - TEST-INFO | 22324: exit 0 11:05:42 INFO - Browser initialized. 11:05:42 INFO - Running cycle 1/1 for dromaeo_css test... 11:05:42 INFO - TEST-INFO | started process 22453 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptfFDq3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:05:54 INFO - PROCESS | 22453 | RSS: Main: 144666624 11:05:54 INFO - PROCESS | 22453 | 11:08:53 INFO - PROCESS | 22453 | Cycle 1(1): loaded http://localhost:51417/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51417/tests/dromaeo/cssquery-ext.html) 11:08:55 INFO - PROCESS | 22453 | RSS: Main: 152231936 11:08:55 INFO - PROCESS | 22453 | 11:10:42 INFO - PROCESS | 22453 | ************************* 11:10:42 INFO - PROCESS | 22453 | A coding exception was thrown and uncaught in a Task. 11:10:42 INFO - PROCESS | 22453 | 11:10:42 ERROR - PROCESS | 22453 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:10:42 INFO - PROCESS | 22453 | Full stack: 11:10:42 INFO - PROCESS | 22453 | ************************* 11:11:57 INFO - PROCESS | 22453 | Cycle 1(1): loaded http://localhost:51417/tests/dromaeo/cssquery-ext.html (next: http://localhost:51417/tests/dromaeo/cssquery-jquery.html) 11:11:57 INFO - PROCESS | 22453 | RSS: Main: 149536768 11:11:57 INFO - PROCESS | 22453 | 11:14:59 INFO - PROCESS | 22453 | Cycle 1(1): loaded http://localhost:51417/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51417/tests/dromaeo/cssquery-mootools.html) 11:15:00 INFO - PROCESS | 22453 | RSS: Main: 154218496 11:15:00 INFO - PROCESS | 22453 | 11:18:01 INFO - PROCESS | 22453 | Cycle 1(1): loaded http://localhost:51417/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51417/tests/dromaeo/cssquery-prototype.html) 11:18:02 INFO - PROCESS | 22453 | RSS: Main: 151822336 11:18:02 INFO - PROCESS | 22453 | 11:21:04 INFO - PROCESS | 22453 | Cycle 1(1): loaded http://localhost:51417/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51417/tests/dromaeo/cssquery-yui.html) 11:21:05 INFO - PROCESS | 22453 | RSS: Main: 153006080 11:21:05 INFO - PROCESS | 22453 | 11:24:07 INFO - PROCESS | 22453 | Cycle 1(1): loaded http://localhost:51417/tests/dromaeo/cssquery-yui.html (next: http://localhost:51417/tests/dromaeo/cssquery-dojo.html) 11:24:08 INFO - PROCESS | 22453 | RSS: Main: 153300992 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | __start_tp_report 11:24:08 INFO - PROCESS | 22453 | _x_x_mozilla_page_load 11:24:08 INFO - PROCESS | 22453 | _x_x_mozilla_page_load_details 11:24:08 INFO - PROCESS | 22453 | |i|pagename|runs| 11:24:08 INFO - PROCESS | 22453 | |0;dojo.html;3318.5022246662998;3344.598648162221;3350.9497357539635;3369.6124945631227;3382.7462940279484;5488.259085023521;5495.835124946248;5506.531944784693;5523.751431185597;5533.114701647737;5482.671039737618;5549.944500554993;5558.221848941152;5561.165825126233;5584.329880414346;5048.7728052237735;5065.924011139836;5113.974430127844;5120.15517439622;5137.075326441239;5099.821506247282;5147.356580427447;5149.974250128744;5163.302954101186;5191.636585439021;4992.800287988476;4996.725180115092;5104.821331253407;5143.5113664201845;5151.124308867483;5047.167217409123;5051.444341122474;5096.235564665293;5098.311727916721;5160.6129540284255;5033.521815427529;5098.107831129574;5100.132977393852;5106.08090543702;5117.8464646060675;5486.902619476121;5509.586780991402;5574.693391863446;5574.777008919638;5592.273004509388;5695.5443564514735;5765.394633563499;5887.322957859858;5909.704514774287;5993.820185394445;5980.1328807323125;5995.760169593211;5998.4601385875485;5999.580029397944;6035.78874739382;5567.554595632339;5596.916046259309;5597;5602;5615.550755939515;5812.7674893003805;5891.617044892068;5895.587308888423;5963;5999.160117583542;5833.591648584558;5840.561957853178;5848.912266316009;5885.205497257901;5906.70466476683;5887.528997680175;5942.732577034044;5956.612820166675;5961.821145365646;5972.25346831646;5708.885822283531;5733.312002559636;5810.244668193108;5904.4095590441475;5922.940770592239;5840.1531777892815;5870.119482077723;5876.7649294028615;5905.025670764276;5923.200367950271;4542.931856022163;4564.2012647786505;4572.314152877028;4581.2440947243995;4592.609628181575;4022.597740225954;4040.6565441937178;4064.8170832312617;4087.2847251730827;4101.384792281122;5732.140178973104;5739.339975902742;5783.797567085132;5841.094630332221;5874.794382196603;5963.0459126539545;5996.67018313997;6001.4298641628975;6001.579889407787;6004.0693692477735;5584.190292407578;5683.488486036276;5711.628744131618;5809.912851307234;5836.037053886062;5000.949990500121;5087.23691446286;5088.084144853962;5117.565006974374;5131.2559678846355;6173.382661733881;6191.473724733358;6194.938050619436;6212.285587157445;6235.220597425322;5869.4717475427415;5925.229720136354;5992.49063829562;6005.819825405245;6025.638461692312;6010.3088144863905;6068.029115341524;6110.388961104032;6138.324784273816;6144.8771024580155;5797.884042319046;5828.53371730269;5835.299764028344;5840.503557197686;5849.561282903885;5884.499817515559;5955.880882382415;5965.8806823862415;5966.910496342645;5968.403159683997;5648.4633959773755;5657.43425657431;5745.1382292656435;5779.0464573344925;5792.507636850914;5811.099279611667;5824.970875145597;5830.7959221427045;5855;5861.7655293787775;5652.971735141298;5779.219805326227;5813.273340832396;5815.5929084963645;5815.680137592304;5674.77300907959;5726.65640061581;5806.44838740319;5812.418758124323;5823.35943046273;5685.459881311269;5771.509421699203;5774.422557744192;5777.133429985536;5806.1000544915605 11:24:08 INFO - PROCESS | 22453 | |1;ext.html;545.694470626755;597.8714576366082;616.8026231605888;680.3230785368559;705.1467724053891;44276.778616106916;45247;49985;49989.25016124752;50060.49939500604;52412.73793631036;53467.73266133673;53498.46501534993;53663;53696;9089.227415669677;9365.297602679791;9392.154706076451;9666.323357364987;9688.951555242249;9341.53292335384;9389.906100939006;9440.480773557452;9441.29190310726;9444.81110377792;9491.715248542554;9527.523623818815;9530.094641009093;9530.094641009093;9535.332526723098;9390.906090939072;9461.621535138585;9474.478903660261;9492.525373731354;9522.285828562852;9310.022447642968;9320.766980825465;9336.253099752004;9358.438493690332;9417.670381536613;43563;43949.56050439487;44071.55928440706;44247.11505769899;44306;4381.671374646914;4406.911861762779;4433.600975912151;4532.501424843264;4555.498895121534;32789.344213115604;32914.6708532914;33153.834230828936;33567.985920844825;34602.6539734602;4374.715643483195;4612.285095810154;4697.741624210667;4775.474697783241;4785.760711964388;376409;381317.9999999972;381777;382002;382503;62840.37159628436;63110;63125.68437157877;63246;63620.68189659022;65889.34110658833;66212.66893665501;66536.33463665398;66568.33431665621;66734.66632666903;17290.654186916192;17523.386681466087;17610.38363657266;17615.559611009827;18024.098795060196;13739.931300343434;13778.517751878837;13844.792328115087;13850.930745346208;13871.861281387057;20403;20548;20808.895955520125;20840.166393344098;20976.37070887876;7195.784126476214;7284.745033923893;7298.781036568911;7400.259974002664;7468.290512401313;970.7384581686402;975.5366201054443;979.9559019844124;985.5121714751244;994.8010397920444;34665;34769.30461390758;34821.82589087039;35005.47491787625;35263.29473410518;1070.9732256693644;1155.2375432214699;1171.8066519024264;1182.822576613515;1202.368756402878;17637;18249.17878695462;18437.63124737498;18541;18589.535261618297;566675;566912;573586;576744;577605;72440.27559724441;72681.6365918167;72705;73495.63252183705;73510.26489735142;1503.7142942840826;1510.5241848817586;1535.8617724404635;1551.7206902757612;1551.8991265567702;33729.83135084309;33833.66166338404;34143.317133657685;34171.31657366888;34436.48345274873;61830.38169618426;62521.37478625338;62593.37406626058;62824.37175628185;62877.37122628715;328323;330391;330515;330799;331901;29588.704112958596;30038.24904377408;30605.387892241586;32441;32601.510977335834;48768.2684759736;58176.70911645415;59160;59557.702211488664;65296.347036530926;45568;47029.76485117689;47122.764386179224;52044.73977630088;52190;49823;50369;50855;51576.74211628918;51727.74136129446;46565.53434465748;49729.50270497249;50806.74596626993;51221.48778512167;51345.229821553454;42151.57848421476;44111;44157;44392;44527;32908.67091329152;33018.33963320672;33229;33242.66757332492;33545 11:24:08 INFO - PROCESS | 22453 | |2;jquery.html;430.498469283285;431.5273482942738;437.6236436664468;438.2046585447414;447.82310987160054;20079.297224597125;21584.244551440705;21969.121235150575;22085.668714969288;22219.11123555058;21154.365369038955;22752.65871011936;23129.421764455896;23236.30291091266;23375.415614609643;6024.427679370453;6060.393960603938;6257.186565746447;6259.561830671855;6262.592931459463;5979.192808970798;6135.141080248769;6137.17148184996;6137.386261373871;6149.5080393568405;5888.263967004124;5981.401859814006;6056;6070.817875463743;6103.206583144207;5947.851303717398;6003.879922401549;6008;6011.939880601203;6014.75940962361;5407.918881216785;5410.945890541084;5503.724813759296;5721.399253078408;5740.339860916007;15524.689506209925;15769.132697701622;15802.841971580367;25256.747432525623;26289.868550657317;3149.700778426046;3192.3136525646864;3201.791883527563;3207.1180425382972;3210.7110360067472;11050.060744836617;11063.170262230284;11232.775344493064;15823.920880395639;15889.126098064602;1949.9415017549354;2020.1111510935289;2040.9897950510153;2053.774084850665;2055.1162999910034;87431;101800;101999;102183.48908255485;102311;12506.18709783861;12514.31171285588;12650.620481385573;12762.872371276168;12821.487140514462;18339;18382.816171838378;18418.447446576625;18440.17019234138;18451;7336.7798966031105;7479.102507699111;7500.812479687942;8373.995120585569;9468.95265523668;8820.735377938672;8857.955710221537;8876.245519130816;8923.063078376807;9047.230985366317;5346.492083252085;5414.620976531605;5435.782568697208;5469.535089517435;5637.408072152365;2075.1284460526617;2158.460384903774;2207.337798660428;2209.1053123485012;2212.6349152389676;940.4592359393376;946.3895787217207;948.5731420860641;960.2893858544627;962.7737481691797;20490.487737806674;21334.466638334165;21468.892655536936;21632.675509867364;21665;947.1475671895212;948.5494390164755;960.7598100474881;976.7069879036261;981.3081777346953;10986.176036797113;11239.291833238181;11344.205905586694;11368.94315528417;11414.771704565863;660945;716899;723721;725752;739715;120302;123827;126141.36929315478;128041;128664;518.4141919630794;536.342979849681;540.8999335123015;543.3669774712495;544.8746788238648;11721;11748;11771.176017678681;11981.700457488283;12020.819687704867;38740.6125938737;42492.787536063355;46329.768351158025;47457.28814067861;51230.74384628053;56089;67822.66088669524;68173.65913170601;71682.28317716965;72125.27874721396;9272.53637318118;9367.531623418665;9532;9643.758906027406;9759.80480390374;13931.164130152223;14552.927235363755;14684.486042988445;14715;14859.702805944036;12204.145709800583;12328.75342493163;12360.25838449696;12473.750524989631;12695.42870570808;12283.57007504733;12594.24434533931;12766.553170638983;12884;12912.612621621367;6866.690998905161;10058.59179714826;12103.636890893287;12180.086493512845;12346.506139754309;9623.652688623739;9625.133737963617;9750.902490974999;9766.46284454362;9954.203663706741;12247.897689208014;12275.815862761887;12325.260484370967;12480.563180288646;12745.808812868 11:24:08 INFO - PROCESS | 22453 | |3;mootools.html;1491.1127878912048;1497.6854860479298;1506.8945173837828;1520.1791032842268;1521.756518956967;7616.162222155558;7755.7673269802;7772.378209743221;8178.345732341413;8210.096889342176;8055.919440805605;8342.040665323475;8348.248657620812;8475.40672152948;8521.14788521149;2402.975970240297;2423.9151629692965;2438.6829712137396;2440.46309811842;2442.05980697431;2360.409897525619;2416.069813121952;2419.891104900275;2423.0792298926385;2423.6485709572103;2376.9049238030457;2390.725066617344;2404.7354790972977;2416.8429052111646;2437.1348171399163;2341.262502311766;2342.7891489765916;2355.9057637694473;2376.0733314007553;2385.2605692235375;2247.6740872573387;2272.579572779041;2296.0815673730476;2306.953860922772;2307.238611258281;8463.957680211559;8533.74398768038;8545.658173673108;8551.358648101355;8552.187542183483;5265.894682106336;5331.120365139747;5385.461453854622;5391.919121213185;5398.190271459273;3337.5327454156436;3369.882054128094;3388.915277118067;3419.5212670226188;3441.5525981622477;1347.7776166932445;1399.4052527675697;1461.7588097963824;1464.5020692964338;1504.1426386959438;10939.507722152523;11098.833517497244;11266.54933802655;11786.233894796811;11814.763704725858;4631.444226692818;4644.117617652635;4784;4795.20879054952;4808.422989241313;9585.760355991017;9589;9594.280428967855;9604;9647.9035209647;5503.477169668875;5533.889322213533;5584.134459158837;5585.469380408855;5587.497125258747;4826.758662066883;4948.455669876311;4952.678075925053;5073.213651883964;5227.320448341691;3809.961900381016;3916.9804150979635;3965.7620542767527;3993.7603743775467;3995.7202995790017;1177.7173478365303;1188.8811118888043;1191.3923898811668;1195.4381440722846;1204.9457774400173;943.9480828554358;988.1847475832467;989.5250279865704;994.6618149829093;999.91000809926;5664.716764161775;5717.199592057115;5741.7990370337675;5758;5758.193852860602;631.6904716688789;633.7655067625009;646.6540400885503;647.5337756815086;662.6487961380476;3820.8471661133244;3841.3853783394525;3850.1914597934747;3853.61463853618;3906.843726250975;9847.704568862946;10075;10084.546195421077;10149.34029288094;10253.025962533547;9840.311178217598;10019.949900250453;10036.899631003742;10038.247131465168;10052.849207261897;7750.837374393774;8030.678772849021;8059.355251579859;8104.8379032419025;8139.918600813916;5478.835634930959;5521.4478552144465;5569.387367389503;5576.721163941867;5662.518685911744;9237.12247336502;9241.953790231006;9243.676471323472;9278.257739380968;9378.531073446436;9330.626774929198;9389.29580281468;9488.573014214238;9500.239980801382;9520;7224.674889629874;7300.744473943387;7345.522541034816;7506.849863002819;7540.811479713051;8149.877751833608;8154.429189956646;8166.183381661786;8191.467554608931;8210.055843577826;7250.274972502708;7275.963620181865;7297.854042919006;7301.196868344372;7424.071991001125;6964.79105626832;6967.930320696728;6992.720291188499;7043.647817608997;7051.682674279567;6396.872062558629;6499.967500162658;6500;6529;6542.771003014872;4869.683470574401;4963.280324353021;4966.602671786176;5049.2173713074535;5059.367579052619;4895.8041678332465;4933.77797999098;5032.194848824171;5071;5094.592432605456 11:24:08 INFO - PROCESS | 22453 | |4;prototype.html;396.55783801061807;411.0381706806073;412.42878613120826;415.81911868337266;418.7236423960185;5154.731917956102;5389.676619402839;5490.203920431535;5505.871296384242;5606.691631960241;5418.322709661293;5451.563874890009;5452.345718513774;5472.042392581302;5489.533389661883;4319.805608747614;4341.782910854453;4350.607805502241;4363.432753742025;4376.19040477511;4264.530901600821;4311.935320970188;4323.697341186118;4369.694121411502;4377.5403582623685;4294.463192100988;4307.849225277116;4316.805743741524;4319.0498090420215;4331.263685173512;4287;4314.61168494837;4314.80583373749;4319.200947824646;4340.370646256275;3714.1086139326635;3807.752496087773;3842.269968705937;3848.749831260959;3858;5314.176302673091;5354.919676204821;5383.2732581101445;5405.675659460445;5412;4903.338049363361;4934.753262336904;4940.25896115582;4960.87597810054;4968.130577148984;5549.889002219973;5694.715264236772;5754.568407369422;5755.30936287644;5765.711714414262;4190.455240818705;4215.3044747616605;4215.557366476537;4222.1766755482695;4231.344141658048;5930.644161350289;5952.4642782149385;5981.132735753335;5984.940150598525;5992.2809262888295;4164.354525048622;4322.135572885435;4322.438083049184;4343.652507799368;4348.152110338517;4361.694681372337;4368.5194628590825;4370.628496577763;4391.880070582045;4413.271810151353;4975.751212439363;4991.875203119951;4992.90014199714;4996.7501624918605;5002.449730529639;5630.436956304418;5824.359320474744;5829.309500244966;5830.883382332415;5835.912461313083;1455.2214565207564;1472.34480656107;1475.232878902986;1477.6675248069098;1477.9063493015306;979.745925215725;1019.2559431614962;1030.2015937648382;1031.5512751952924;1034.110664828247;5397.541208997279;5439.184122381675;5443.265159203457;5471.23402723619;5580.693061881554;5687;5737.971310143423;5781.479666830005;5784.71076446176;5834.299884013945;5349.786008559692;5542.944570554324;5545.390007099216;5685.260916080883;5731.942680573225;3059.9694003059685;3061.8315992620173;3065.7547396208697;3067.4938635124954;3078.8614512347;5841.94158058414;5965.850853728604;5991.172692328794;6026.939730602726;6035.456808887221;5971.164037034819;6000.5799594028;6021.548383871227;6025.9096113558335;6072.995911349244;6158.876822463526;6169.105479705418;6188.319284878659;6193.194884665055;6201.255849298113;5753.338366087786;5916.31962324321;5919.122974451569;5921.223632910023;5949.2266005419015;5823.621464604787;5879.676617786063;5879.970600146972;5888.293404791452;5920.644761314335;5942.702864856654;6106.45041946227;6171.753129874754;6175.69121543912;6217.502599792097;4225.302825033835;4290.4636920384955;4301.247281725623;4309.439772829513;4343.587359200871;5908.3500814911185;5928.407159284037;5937;5938.257717785277;5951.7024148791525;5620.241267428844;5767.2986468992585;5801.5206122440195;5819.5053420459735;5823.242978412949;5744.569157313302;5796.029350021013;5807.128930660435;5808.751118509541;5823.330317013429;5768.913466298093;5784.392638773036;5799.681017543907;5803.767849285981;5820.621659592113;5577.832665020056;5803.361630220588;5836.941630583639;5841.970790146022;5851.64890106595;5699.059655156852;5703.914441283467;5715.456826656862;5717.3996730342715;5731.426857314235 11:24:08 INFO - PROCESS | 22453 | |5;yui.html;842.3766412854486;844.8099177685018;862.1077185113409;924.135932902736;931.7996630724396;15035.323410446528;16800.663986720258;16805.663886722257;16948.067856267884;17365.47903562892;14461.566153015427;14610.707785844277;14780.334884930176;15356.46252381167;15711.528654140395;804.4208170117506;814.1654803826096;816.4121832280748;818.8526065308242;820.2822530285999;829.9585020748968;837.660747397302;844.0167205205928;847.0555330806137;847.7583888591753;872.9916945927464;876.5660997806095;876.7194497760719;877.4340550345023;878.8901387326584;862.9266512346427;869.5565261716507;870.634333579898;871.2289623683087;872.8428882801124;634.7270673610345;635.1806170040688;640.7052755732369;640.9230892292908;641.3682522715122;15288.312025958747;15592.766108508267;15630.374785008586;15941;16306.429274975437;16491.6701665966;16499.58751031222;16830.15849207535;16853.325866965308;17386.217620206993;13483.66290842727;13595.456181752817;13675.658108547266;13691.931540342333;13799.89600831941;3977.2642061218767;4015.136745599681;4019.1760689058674;4021.97989010056;4076.5515793262716;91109;91408;91537.54231228802;91647;94118.52940735321;11789.469473873696;11851.585194518151;11954.880451195377;12081.154319197747;12184.147109702235;24721.011159553775;25221.369465763502;25468;25491.617625735627;25762.484750304888;9066.54667266634;9181.678641247658;9259.120383563552;9264.53791528041;9268.212201962773;5018.24726291052;5081.771320290596;5112.488751124858;5117.155669314521;5214.8957020860125;4102.446169767043;4108.8356465741035;4147.730397524151;4176.58234176586;4238.215930052888;677.4444955136839;681.4412182010703;685.9519833611599;690.975815846443;706.2725392845377;795.8368534450424;803.8151225218232;809.1706001348665;814.2915663372903;830.2652152844776;11084.722881928017;11477;11528.654140375802;11557.537698491966;11897.345645989553;826.4504104770381;828.2338836576143;835.3609488741118;844.0926004545139;847.174005344784;5045.5459008689395;5266.36572204412;5340.519353258225;5348.3849357323625;5381.0852155133725;76880.61559692166;81171.59414202892;81881.18118818855;82463.5876820612;84511;71836.64081679558;72104.2789572108;75146.24853751501;76177;77857.221427785;65589.34410655928;67378.3262167372;67441.32558674351;68136;68559;10185.287029908131;10334.761382883511;10385.324953877975;10410.271281010257;10577.153827693919;44789.77605111954;44870.77564612156;45850;46048.53951460576;46494;56306.71846640741;57738;58105;58325.70837145787;59804;12357.567485137977;12609.93695031519;12708.173968692005;12746.872531274568;12809.61571152867;14465.638359041108;14605.415783368546;14711.190884501028;14775.408983640535;14809.703805924037;14043;14048.438062477386;14195.29023548839;14602.123872567256;15057.698846022799;12662.113652044269;12752.74494510086;12795.552155674506;12839.550615728405;13334.26661533625;12188.57339993096;12288;12325.506979721069;12500.624981250578;12527.123101382816;11317.660470185907;11322.94338528302;11525;11528.769424611293;11798.11514136426;11221.831672525082;11226.573852599371;11286.66140015801;11492.94253528727;11496.310221386744 11:24:08 INFO - PROCESS | 22453 | __end_tp_report 11:24:08 INFO - PROCESS | 22453 | __start_cc_report 11:24:08 INFO - PROCESS | 22453 | _x_x_mozilla_cycle_collect,304 11:24:08 INFO - PROCESS | 22453 | __end_cc_report 11:24:08 INFO - PROCESS | 22453 | __startTimestamp1486063448058__endTimestamp 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | ------- Summary: start ------- 11:24:08 INFO - PROCESS | 22453 | Number of tests: 6 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | [#0] dojo.html Cycles:165 Average:5529.39 Median:5774.42 stddev:600.25 (10.4%) stddev-sans-first:576.49 11:24:08 INFO - PROCESS | 22453 | Values: 3318.5 3344.6 3350.9 3369.6 3382.7 5488.3 5495.8 5506.5 5523.8 5533.1 5482.7 5549.9 5558.2 5561.2 5584.3 5048.8 5065.9 5114.0 5120.2 5137.1 5099.8 5147.4 5150.0 5163.3 5191.6 4992.8 4996.7 5104.8 5143.5 5151.1 5047.2 5051.4 5096.2 5098.3 5160.6 5033.5 5098.1 5100.1 5106.1 5117.8 5486.9 5509.6 5574.7 5574.8 5592.3 5695.5 5765.4 5887.3 5909.7 5993.8 5980.1 5995.8 5998.5 5999.6 6035.8 5567.6 5596.9 5597.0 5602.0 5615.6 5812.8 5891.6 5895.6 5963.0 5999.2 5833.6 5840.6 5848.9 5885.2 5906.7 5887.5 5942.7 5956.6 5961.8 5972.3 5708.9 5733.3 5810.2 5904.4 5922.9 5840.2 5870.1 5876.8 5905.0 5923.2 4542.9 4564.2 4572.3 4581.2 4592.6 4022.6 4040.7 4064.8 4087.3 4101.4 5732.1 5739.3 5783.8 5841.1 5874.8 5963.0 5996.7 6001.4 6001.6 6004.1 5584.2 5683.5 5711.6 5809.9 5836.0 5000.9 5087.2 5088.1 5117.6 5131.3 6173.4 6191.5 6194.9 6212.3 6235.2 5869.5 5925.2 5992.5 6005.8 6025.6 6010.3 6068.0 6110.4 6138.3 6144.9 5797.9 5828.5 5835.3 5840.5 5849.6 5884.5 5955.9 5965.9 5966.9 5968.4 5648.5 5657.4 5745.1 5779.0 5792.5 5811.1 5825.0 5830.8 5855.0 5861.8 5653.0 5779.2 5813.3 5815.6 5815.7 5674.8 5726.7 5806.4 5812.4 5823.4 5685.5 5771.5 5774.4 5777.1 5806.1 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | [#1] ext.html Cycles:180 Average:62513.62 Median:26777.01 stddev:116507.27 (435.1%) stddev-sans-first:116741.21 11:24:08 INFO - PROCESS | 22453 | Values: 545.7 597.9 616.8 680.3 705.1 44276.8 45247.0 49985.0 49989.3 50060.5 52412.7 53467.7 53498.5 53663.0 53696.0 9089.2 9365.3 9392.2 9666.3 9689.0 9341.5 9389.9 9440.5 9441.3 9444.8 9491.7 9527.5 9530.1 9530.1 9535.3 9390.9 9461.6 9474.5 9492.5 9522.3 9310.0 9320.8 9336.3 9358.4 9417.7 43563.0 43949.6 44071.6 44247.1 44306.0 4381.7 4406.9 4433.6 4532.5 4555.5 32789.3 32914.7 33153.8 33568.0 34602.7 4374.7 4612.3 4697.7 4775.5 4785.8 376409.0 381318.0 381777.0 382002.0 382503.0 62840.4 63110.0 63125.7 63246.0 63620.7 65889.3 66212.7 66536.3 66568.3 66734.7 17290.7 17523.4 17610.4 17615.6 18024.1 13739.9 13778.5 13844.8 13850.9 13871.9 20403.0 20548.0 20808.9 20840.2 20976.4 7195.8 7284.7 7298.8 7400.3 7468.3 970.7 975.5 980.0 985.5 994.8 34665.0 34769.3 34821.8 35005.5 35263.3 1071.0 1155.2 1171.8 1182.8 1202.4 17637.0 18249.2 18437.6 18541.0 18589.5 566675.0 566912.0 573586.0 576744.0 577605.0 72440.3 72681.6 72705.0 73495.6 73510.3 1503.7 1510.5 1535.9 1551.7 1551.9 33729.8 33833.7 34143.3 34171.3 34436.5 61830.4 62521.4 62593.4 62824.4 62877.4 328323.0 330391.0 330515.0 330799.0 331901.0 29588.7 30038.2 30605.4 32441.0 32601.5 48768.3 58176.7 59160.0 59557.7 65296.3 45568.0 47029.8 47122.8 52044.7 52190.0 49823.0 50369.0 50855.0 51576.7 51727.7 46565.5 49729.5 50806.7 51221.5 51345.2 42151.6 44111.0 44157.0 44392.0 44527.0 32908.7 33018.3 33229.0 33242.7 33545.0 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | [#2] jquery.html Cycles:180 Average:37430.34 Median:3204.45 stddev:117660.85 (3671.8%) stddev-sans-first:117958.12 11:24:08 INFO - PROCESS | 22453 | Values: 430.5 431.5 437.6 438.2 447.8 20079.3 21584.2 21969.1 22085.7 22219.1 21154.4 22752.7 23129.4 23236.3 23375.4 6024.4 6060.4 6257.2 6259.6 6262.6 5979.2 6135.1 6137.2 6137.4 6149.5 5888.3 5981.4 6056.0 6070.8 6103.2 5947.9 6003.9 6008.0 6011.9 6014.8 5407.9 5410.9 5503.7 5721.4 5740.3 15524.7 15769.1 15802.8 25256.7 26289.9 3149.7 3192.3 3201.8 3207.1 3210.7 11050.1 11063.2 11232.8 15823.9 15889.1 1949.9 2020.1 2041.0 2053.8 2055.1 87431.0 101800.0 101999.0 102183.5 102311.0 12506.2 12514.3 12650.6 12762.9 12821.5 18339.0 18382.8 18418.4 18440.2 18451.0 7336.8 7479.1 7500.8 8374.0 9469.0 8820.7 8858.0 8876.2 8923.1 9047.2 5346.5 5414.6 5435.8 5469.5 5637.4 2075.1 2158.5 2207.3 2209.1 2212.6 940.5 946.4 948.6 960.3 962.8 20490.5 21334.5 21468.9 21632.7 21665.0 947.1 948.5 960.8 976.7 981.3 10986.2 11239.3 11344.2 11368.9 11414.8 660945.0 716899.0 723721.0 725752.0 739715.0 120302.0 123827.0 126141.4 128041.0 128664.0 518.4 536.3 540.9 543.4 544.9 11721.0 11748.0 11771.2 11981.7 12020.8 38740.6 42492.8 46329.8 47457.3 51230.7 56089.0 67822.7 68173.7 71682.3 72125.3 9272.5 9367.5 9532.0 9643.8 9759.8 13931.2 14552.9 14684.5 14715.0 14859.7 12204.1 12328.8 12360.3 12473.8 12695.4 12283.6 12594.2 12766.6 12884.0 12912.6 6866.7 10058.6 12103.6 12180.1 12346.5 9623.7 9625.1 9750.9 9766.5 9954.2 12247.9 12275.8 12325.3 12480.6 12745.8 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | [#3] mootools.html Cycles:180 Average:5547.71 Median:5388.69 stddev:3009.12 (55.8%) stddev-sans-first:3002.12 11:24:08 INFO - PROCESS | 22453 | Values: 1491.1 1497.7 1506.9 1520.2 1521.8 7616.2 7755.8 7772.4 8178.3 8210.1 8055.9 8342.0 8348.2 8475.4 8521.1 2403.0 2423.9 2438.7 2440.5 2442.1 2360.4 2416.1 2419.9 2423.1 2423.6 2376.9 2390.7 2404.7 2416.8 2437.1 2341.3 2342.8 2355.9 2376.1 2385.3 2247.7 2272.6 2296.1 2307.0 2307.2 8464.0 8533.7 8545.7 8551.4 8552.2 5265.9 5331.1 5385.5 5391.9 5398.2 3337.5 3369.9 3388.9 3419.5 3441.6 1347.8 1399.4 1461.8 1464.5 1504.1 10939.5 11098.8 11266.5 11786.2 11814.8 4631.4 4644.1 4784.0 4795.2 4808.4 9585.8 9589.0 9594.3 9604.0 9647.9 5503.5 5533.9 5584.1 5585.5 5587.5 4826.8 4948.5 4952.7 5073.2 5227.3 3810.0 3917.0 3965.8 3993.8 3995.7 1177.7 1188.9 1191.4 1195.4 1204.9 943.9 988.2 989.5 994.7 999.9 5664.7 5717.2 5741.8 5758.0 5758.2 631.7 633.8 646.7 647.5 662.6 3820.8 3841.4 3850.2 3853.6 3906.8 9847.7 10075.0 10084.5 10149.3 10253.0 9840.3 10019.9 10036.9 10038.2 10052.8 7750.8 8030.7 8059.4 8104.8 8139.9 5478.8 5521.4 5569.4 5576.7 5662.5 9237.1 9242.0 9243.7 9278.3 9378.5 9330.6 9389.3 9488.6 9500.2 9520.0 7224.7 7300.7 7345.5 7506.8 7540.8 8149.9 8154.4 8166.2 8191.5 8210.1 7250.3 7276.0 7297.9 7301.2 7424.1 6964.8 6967.9 6992.7 7043.6 7051.7 6396.9 6500.0 6500.0 6529.0 6542.8 4869.7 4963.3 4966.6 5049.2 5059.4 4895.8 4933.8 5032.2 5071.0 5094.6 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | [#4] prototype.html Cycles:180 Average:4877.58 Median:5489.87 stddev:1414.72 (25.8%) stddev-sans-first:1378.13 11:24:08 INFO - PROCESS | 22453 | Values: 396.6 411.0 412.4 415.8 418.7 5154.7 5389.7 5490.2 5505.9 5606.7 5418.3 5451.6 5452.3 5472.0 5489.5 4319.8 4341.8 4350.6 4363.4 4376.2 4264.5 4311.9 4323.7 4369.7 4377.5 4294.5 4307.8 4316.8 4319.0 4331.3 4287.0 4314.6 4314.8 4319.2 4340.4 3714.1 3807.8 3842.3 3848.7 3858.0 5314.2 5354.9 5383.3 5405.7 5412.0 4903.3 4934.8 4940.3 4960.9 4968.1 5549.9 5694.7 5754.6 5755.3 5765.7 4190.5 4215.3 4215.6 4222.2 4231.3 5930.6 5952.5 5981.1 5984.9 5992.3 4164.4 4322.1 4322.4 4343.7 4348.2 4361.7 4368.5 4370.6 4391.9 4413.3 4975.8 4991.9 4992.9 4996.8 5002.4 5630.4 5824.4 5829.3 5830.9 5835.9 1455.2 1472.3 1475.2 1477.7 1477.9 979.7 1019.3 1030.2 1031.6 1034.1 5397.5 5439.2 5443.3 5471.2 5580.7 5687.0 5738.0 5781.5 5784.7 5834.3 5349.8 5542.9 5545.4 5685.3 5731.9 3060.0 3061.8 3065.8 3067.5 3078.9 5841.9 5965.9 5991.2 6026.9 6035.5 5971.2 6000.6 6021.5 6025.9 6073.0 6158.9 6169.1 6188.3 6193.2 6201.3 5753.3 5916.3 5919.1 5921.2 5949.2 5823.6 5879.7 5880.0 5888.3 5920.6 5942.7 6106.5 6171.8 6175.7 6217.5 4225.3 4290.5 4301.2 4309.4 4343.6 5908.4 5928.4 5937.0 5938.3 5951.7 5620.2 5767.3 5801.5 5819.5 5823.2 5744.6 5796.0 5807.1 5808.8 5823.3 5768.9 5784.4 5799.7 5803.8 5820.6 5577.8 5803.4 5836.9 5842.0 5851.6 5699.1 5703.9 5715.5 5717.4 5731.4 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - PROCESS | 22453 | [#5] yui.html Cycles:180 Average:18912.80 Median:44830.28 stddev:24430.97 (54.5%) stddev-sans-first:24461.82 11:24:08 INFO - PROCESS | 22453 | Values: 842.4 844.8 862.1 924.1 931.8 15035.3 16800.7 16805.7 16948.1 17365.5 14461.6 14610.7 14780.3 15356.5 15711.5 804.4 814.2 816.4 818.9 820.3 830.0 837.7 844.0 847.1 847.8 873.0 876.6 876.7 877.4 878.9 862.9 869.6 870.6 871.2 872.8 634.7 635.2 640.7 640.9 641.4 15288.3 15592.8 15630.4 15941.0 16306.4 16491.7 16499.6 16830.2 16853.3 17386.2 13483.7 13595.5 13675.7 13691.9 13799.9 3977.3 4015.1 4019.2 4022.0 4076.6 91109.0 91408.0 91537.5 91647.0 94118.5 11789.5 11851.6 11954.9 12081.2 12184.1 24721.0 25221.4 25468.0 25491.6 25762.5 9066.5 9181.7 9259.1 9264.5 9268.2 5018.2 5081.8 5112.5 5117.2 5214.9 4102.4 4108.8 4147.7 4176.6 4238.2 677.4 681.4 686.0 691.0 706.3 795.8 803.8 809.2 814.3 830.3 11084.7 11477.0 11528.7 11557.5 11897.3 826.5 828.2 835.4 844.1 847.2 5045.5 5266.4 5340.5 5348.4 5381.1 76880.6 81171.6 81881.2 82463.6 84511.0 71836.6 72104.3 75146.2 76177.0 77857.2 65589.3 67378.3 67441.3 68136.0 68559.0 10185.3 10334.8 10385.3 10410.3 10577.2 44789.8 44870.8 45850.0 46048.5 46494.0 56306.7 57738.0 58105.0 58325.7 59804.0 12357.6 12609.9 12708.2 12746.9 12809.6 14465.6 14605.4 14711.2 14775.4 14809.7 14043.0 14048.4 14195.3 14602.1 15057.7 12662.1 12752.7 12795.6 12839.6 13334.3 12188.6 12288.0 12325.5 12500.6 12527.1 11317.7 11322.9 11525.0 11528.8 11798.1 11221.8 11226.6 11286.7 11492.9 11496.3 11:24:08 INFO - PROCESS | 22453 | -------- Summary: end -------- 11:24:08 INFO - PROCESS | 22453 | 11:24:08 INFO - TEST-INFO | 22453: exit 0 11:24:08 INFO - TEST-OK | dromaeo_css | took 1112255ms 11:24:08 INFO - TEST-START | kraken 11:24:08 INFO - operating with platform_type : linux_ 11:24:08 INFO - Initialising browser for kraken test... 11:24:08 INFO - TEST-INFO | started process 22821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXSCstp/profile http://localhost:51417/getInfo.html) 11:24:09 CRITICAL - PROCESS | 22821 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 11:24:09 INFO - TEST-INFO | 22821: exit 127 11:24:09 INFO - Could not find __metrics(.*)__metrics in browser output 11:24:09 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! 11:24:09 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 11:24:09 ERROR - Traceback (most recent call last): 11:24:09 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 11:24:09 INFO - talos_results.add(mytest.runTest(browser_config, test)) 11:24:09 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 11:24:09 INFO - with FFSetup(browser_config, test_config) as setup: 11:24:09 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 162, in __enter__ 11:24:09 INFO - self._run_profile() 11:24:09 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 138, in _run_profile 11:24:09 INFO - raise TalosError("browser failed to close after being initialized") 11:24:09 INFO - TalosError: browser failed to close after being initialized 11:24:09 INFO - TEST-INFO took 278ms 11:24:09 INFO - SUITE-END | took 1112s 11:24:09 ERROR - Return code: 2 11:24:09 WARNING - setting return code to 2 11:24:09 CRITICAL - # TBPL RETRY # 11:24:09 WARNING - setting return code to 4 11:24:09 INFO - Running post-action listener: _resource_record_post_action 11:24:09 INFO - [mozharness: 2017-02-02 19:24:09.610234Z] Finished run-tests step (success) 11:24:09 INFO - Running post-run listener: _resource_record_post_run 11:24:09 INFO - Total resource usage - Wall time: 1130s; CPU: 13.0%; Read bytes: 0; Write bytes: 300830720; Read time: 0; Write time: 184220 11:24:09 INFO - TinderboxPrint: CPU usage
13.3% 11:24:09 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:24:09 INFO - TinderboxPrint: I/O write bytes / time
300,830,720 / 184,220 11:24:09 INFO - TinderboxPrint: CPU idle
7,813.3 (86.6%) 11:24:09 INFO - TinderboxPrint: CPU user
1,159.6 (12.9%) 11:24:09 INFO - TinderboxPrint: Swap in / out
0 / 0 11:24:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109604864; Read time: 0; Write time: 61436 11:24:09 INFO - run-tests - Wall time: 1113s; CPU: 13.0%; Read bytes: 0; Write bytes: 190791680; Read time: 0; Write time: 122760 11:24:10 INFO - Running post-run listener: _upload_blobber_files 11:24:10 INFO - Blob upload gear active. 11:24:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:24:10 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 11:24:10 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'] 11:24:10 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 11:24:10 INFO - (blobuploader) - INFO - Open directory for files ... 11:24:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:24:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:24:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:11 INFO - (blobuploader) - INFO - Done attempting. 11:24:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:24:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:24:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:12 INFO - (blobuploader) - INFO - Done attempting. 11:24:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:24:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:24:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:15 INFO - (blobuploader) - INFO - Done attempting. 11:24:15 INFO - (blobuploader) - INFO - Iteration through files over. 11:24:15 INFO - Return code: 0 11:24:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:24:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:24:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b4c18c48ce0870c61b372b24c43d6c8459bf8baae716ea4fa81bf94716ec3ab730321baa161f98d6528beaf5a96169cc7a73112f1684bb110b753fb1122d109c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb823375c5c0bc949f749849c96bcd9b61a35b166565161e7b19e873c4a0fb4bfbeca62b68503728490e7c343333a0243a4429a43b7ab119385602c64aad13ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/694460993195e86c3ce5120b0f7a9a7c7d2b9a4a6c7568b23d89e1387df66829e654b7f2b31199c6306803ac91d0db756e16a3bf45df09d05caea0149e8fd293"} 11:24:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:24:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:24:15 INFO - Contents: 11:24:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b4c18c48ce0870c61b372b24c43d6c8459bf8baae716ea4fa81bf94716ec3ab730321baa161f98d6528beaf5a96169cc7a73112f1684bb110b753fb1122d109c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb823375c5c0bc949f749849c96bcd9b61a35b166565161e7b19e873c4a0fb4bfbeca62b68503728490e7c343333a0243a4429a43b7ab119385602c64aad13ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/694460993195e86c3ce5120b0f7a9a7c7d2b9a4a6c7568b23d89e1387df66829e654b7f2b31199c6306803ac91d0db756e16a3bf45df09d05caea0149e8fd293"} 11:24:15 INFO - Running post-run listener: copy_logs_to_upload_dir 11:24:15 INFO - Copying logs to upload dir... 11:24:15 INFO - mkdir: /builds/slave/test/build/upload/logs 11:24:15 INFO - Copying logs to upload dir... 11:24:15 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1173.622996 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 33 secs) (at 2017-02-02 11:24:15.319352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 11:24:15.322938) ========= 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=00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b4c18c48ce0870c61b372b24c43d6c8459bf8baae716ea4fa81bf94716ec3ab730321baa161f98d6528beaf5a96169cc7a73112f1684bb110b753fb1122d109c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb823375c5c0bc949f749849c96bcd9b61a35b166565161e7b19e873c4a0fb4bfbeca62b68503728490e7c343333a0243a4429a43b7ab119385602c64aad13ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/694460993195e86c3ce5120b0f7a9a7c7d2b9a4a6c7568b23d89e1387df66829e654b7f2b31199c6306803ac91d0db756e16a3bf45df09d05caea0149e8fd293"} build_url:https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011370 build_url: 'https://queue.taskcluster.net/v1/task/XLEqQSaRTBuGFvDNLMkyJA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/b4c18c48ce0870c61b372b24c43d6c8459bf8baae716ea4fa81bf94716ec3ab730321baa161f98d6528beaf5a96169cc7a73112f1684bb110b753fb1122d109c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb823375c5c0bc949f749849c96bcd9b61a35b166565161e7b19e873c4a0fb4bfbeca62b68503728490e7c343333a0243a4429a43b7ab119385602c64aad13ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/694460993195e86c3ce5120b0f7a9a7c7d2b9a4a6c7568b23d89e1387df66829e654b7f2b31199c6306803ac91d0db756e16a3bf45df09d05caea0149e8fd293"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 11:24:15.347446) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:24:15.347757) ========= 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=00204705a9daa57ed339a427000001b9-1486062280.413732-1772704327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 11:24:15.431724) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 11:24:15.432041) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 11:24:15.432333) ========= ========= Total master_lag: 0.17 =========