builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-067 starttime: 1469683799.46 results: success (0) buildid: 20160727194525 builduid: 636c6e16805c4eedbc21278135578887 revision: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.461249) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.462104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.462442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.530634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.530944) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 22:29:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.8M=0s 2016-07-27 22:29:59 (34.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088347 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.660183) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.660600) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018218 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.735115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 22:29:59.735500) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 73ce55a62039981fd0f7843ab3edd0dfbd8b839f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 73ce55a62039981fd0f7843ab3edd0dfbd8b839f --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=e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 22:29:59,987 truncating revision to first 12 chars 2016-07-27 22:29:59,987 Setting DEBUG logging. 2016-07-27 22:29:59,987 attempt 1/10 2016-07-27 22:29:59,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/73ce55a62039?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 22:30:00,289 unpacking tar archive at: mozilla-aurora-73ce55a62039/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656210 ========= master_lag: 0.39 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 22:30:00.776987) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.777302) ========= script_repo_revision: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.777739) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.778059) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.963987) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-27 22:30:00.964541) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:30:01 INFO - MultiFileLogger online at 20160727 22:30:01 in /builds/slave/test 22:30:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:30:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:30:01 INFO - {'append_to_log': False, 22:30:01 INFO - 'base_work_dir': '/builds/slave/test', 22:30:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:30:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:30:01 INFO - 'branch': 'Mozilla-Aurora', 22:30:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:30:01 INFO - 'config_files': ('talos/linux_config.py',), 22:30:01 INFO - 'default_actions': ('clobber', 22:30:01 INFO - 'read-buildbot-config', 22:30:01 INFO - 'download-and-extract', 22:30:01 INFO - 'populate-webroot', 22:30:01 INFO - 'create-virtualenv', 22:30:01 INFO - 'install', 22:30:01 INFO - 'run-tests'), 22:30:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:30:01 INFO - 'download_minidump_stackwalk': True, 22:30:01 INFO - 'download_symbols': 'ondemand', 22:30:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:30:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:30:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:30:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:30:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:30:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:30:01 INFO - 'installer_path': 'installer.exe', 22:30:01 INFO - 'log_level': 'info', 22:30:01 INFO - 'log_name': 'talos', 22:30:01 INFO - 'log_to_console': True, 22:30:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:30:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:30:01 INFO - 'opt_config_files': (), 22:30:01 INFO - 'pip_index': False, 22:30:01 INFO - 'sps_profile': False, 22:30:01 INFO - 'sps_profile_interval': 0, 22:30:01 INFO - 'suite': 'dromaeojs-e10s', 22:30:01 INFO - 'system_bits': '32', 22:30:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:30:01 INFO - 'title': 'talos-linux64-ix-067', 22:30:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:30:01 INFO - 'use_talos_json': True, 22:30:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:30:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:30:01 INFO - 'work_dir': 'build'} 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running clobber step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: clobber 22:30:01 INFO - rmtree: /builds/slave/test/build 22:30:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:30:01 INFO - Running post-action listener: _resource_record_post_action 22:30:01 INFO - ##### 22:30:01 INFO - ##### Finished clobber step (success) 22:30:01 INFO - ##### 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running read-buildbot-config step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: read_buildbot_config 22:30:01 INFO - Using buildbot properties: 22:30:01 INFO - { 22:30:01 INFO - "project": "", 22:30:01 INFO - "product": "firefox", 22:30:01 INFO - "script_repo_revision": "production", 22:30:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 22:30:01 INFO - "repository": "", 22:30:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 22:30:01 INFO - "buildid": "20160727194525", 22:30:01 INFO - "pgo_build": "True", 22:30:01 INFO - "basedir": "/builds/slave/test", 22:30:01 INFO - "buildnumber": 469, 22:30:01 INFO - "slavename": "talos-linux64-ix-067", 22:30:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:30:01 INFO - "platform": "ubuntu64_hw", 22:30:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:30:01 INFO - "branch": "mozilla-aurora", 22:30:01 INFO - "repo_path": "releases/mozilla-aurora", 22:30:01 INFO - "revision": "73ce55a62039981fd0f7843ab3edd0dfbd8b839f", 22:30:01 INFO - "stage_platform": "linux64-pgo", 22:30:01 INFO - "builduid": "636c6e16805c4eedbc21278135578887", 22:30:01 INFO - "slavebuilddir": "test" 22:30:01 INFO - } 22:30:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 22:30:01 INFO - Running post-action listener: _resource_record_post_action 22:30:01 INFO - ##### 22:30:01 INFO - ##### Finished read-buildbot-config step (success) 22:30:01 INFO - ##### 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running download-and-extract step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: download_and_extract 22:30:01 INFO - mkdir: /builds/slave/test/build/tests 22:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:01 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:30:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:30:05 INFO - Downloaded 1361 bytes. 22:30:05 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:05 INFO - Using the following test package requirements: 22:30:05 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 22:30:05 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:30:05 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:30:05 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'jsshell-linux-x86_64.zip'], 22:30:05 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:30:05 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 22:30:05 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:30:05 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:30:05 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:30:05 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:30:05 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:05 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:20 INFO - Downloaded 24495303 bytes. 22:30:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:30:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:30:22 INFO - Return code: 0 22:30:22 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:22 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:23 INFO - Downloaded 24495303 bytes. 22:30:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:30:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:30:24 INFO - Return code: 0 22:30:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:24 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:30:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:30:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:30:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:30:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:30:25 INFO - Downloaded 10833369 bytes. 22:30:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:30:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:30:25 INFO - Return code: 0 22:30:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:25 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:30:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:30:27 INFO - Downloaded 63654269 bytes. 22:30:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:27 INFO - mkdir: /builds/slave/test/properties 22:30:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:30:27 INFO - Writing to file /builds/slave/test/properties/build_url 22:30:27 INFO - Contents: 22:30:27 INFO - build_url:https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:27 INFO - Running post-action listener: _resource_record_post_action 22:30:27 INFO - Running post-action listener: set_extra_try_arguments 22:30:27 INFO - ##### 22:30:27 INFO - ##### Finished download-and-extract step (success) 22:30:27 INFO - ##### 22:30:27 INFO - ##### 22:30:27 INFO - ##### Running populate-webroot step. 22:30:27 INFO - ##### 22:30:27 INFO - Running pre-action listener: _resource_record_pre_action 22:30:27 INFO - Running main action method: populate_webroot 22:30:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:30:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:30:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:30:27 INFO - '--tppagecycles', 22:30:27 INFO - '7'], 22:30:27 INFO - 'tests': ['tsvgm']}}, 22:30:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:30:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:30:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:30:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:30:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['damp', 'tps']}, 22:30:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'tests': ['damp', 'tps']}, 22:30:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['dromaeo_dom']}, 22:30:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:30:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['a11yr', 22:30:27 INFO - 'ts_paint', 22:30:27 INFO - 'tpaint', 22:30:27 INFO - 'sessionrestore', 22:30:27 INFO - 'sessionrestore_no_auto_restore', 22:30:27 INFO - 'tabpaint']}, 22:30:27 INFO - 'other-e10s': {'tests': ['a11yr', 22:30:27 INFO - 'ts_paint', 22:30:27 INFO - 'tpaint', 22:30:27 INFO - 'sessionrestore', 22:30:27 INFO - 'sessionrestore_no_auto_restore', 22:30:27 INFO - 'tabpaint']}, 22:30:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:30:27 INFO - 'ts_paint', 22:30:27 INFO - 'tpaint', 22:30:27 INFO - 'sessionrestore', 22:30:27 INFO - 'sessionrestore_no_auto_restore', 22:30:27 INFO - 'tabpaint']}, 22:30:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:30:27 INFO - 'ts_paint', 22:30:27 INFO - 'tpaint', 22:30:27 INFO - 'sessionrestore', 22:30:27 INFO - 'sessionrestore_no_auto_restore', 22:30:27 INFO - 'tabpaint']}, 22:30:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['a11yr', 22:30:27 INFO - 'ts_paint', 22:30:27 INFO - 'tpaint', 22:30:27 INFO - 'sessionrestore', 22:30:27 INFO - 'sessionrestore_no_auto_restore', 22:30:27 INFO - 'tabpaint']}, 22:30:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['a11yr', 22:30:27 INFO - 'ts_paint', 22:30:27 INFO - 'tpaint', 22:30:27 INFO - 'sessionrestore', 22:30:27 INFO - 'sessionrestore_no_auto_restore', 22:30:27 INFO - 'tabpaint']}, 22:30:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['tsvgx', 22:30:27 INFO - 'tsvgr_opacity', 22:30:27 INFO - 'tart', 22:30:27 INFO - 'tscrollx', 22:30:27 INFO - 'cart']}, 22:30:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:30:27 INFO - 'tsvgr_opacity', 22:30:27 INFO - 'tart', 22:30:27 INFO - 'tscrollx', 22:30:27 INFO - 'cart']}, 22:30:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'talos_options': ['--disable-e10s'], 22:30:27 INFO - 'tests': ['tp5o']}, 22:30:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'tests': ['tp5o']}, 22:30:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'talos_options': ['--disable-e10s', 22:30:27 INFO - '--xperf_path', 22:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:27 INFO - 'tests': ['tp5n']}, 22:30:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:27 INFO - 'talos_options': ['--xperf_path', 22:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:27 INFO - 'tests': ['tp5n']}}, 22:30:27 INFO - 'talos.zip': {'path': '', 22:30:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:30:27 INFO - Running post-action listener: _resource_record_post_action 22:30:27 INFO - ##### 22:30:27 INFO - ##### Finished populate-webroot step (success) 22:30:27 INFO - ##### 22:30:27 INFO - ##### 22:30:27 INFO - ##### Running create-virtualenv step. 22:30:27 INFO - ##### 22:30:27 INFO - Running pre-action listener: _resource_record_pre_action 22:30:27 INFO - Running main action method: create_virtualenv 22:30:27 INFO - Creating virtualenv /builds/slave/test/build/venv 22:30:27 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 22:30:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:30:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:30:27 INFO - Using real prefix '/usr' 22:30:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:30:28 INFO - Installing distribute.............................................................................................................................................................................................done. 22:30:30 INFO - Installing pip.................done. 22:30:30 INFO - Return code: 0 22:30:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:30 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 22:30:30 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 22:30:30 INFO - Using env: {'DISPLAY': ':0', 22:30:30 INFO - 'HOME': '/home/cltbld', 22:30:30 INFO - 'LANG': 'en_US.UTF-8', 22:30:30 INFO - 'LANGUAGE': 'en_US:en', 22:30:30 INFO - 'LOGNAME': 'cltbld', 22:30:30 INFO - 'MAIL': '/var/mail/cltbld', 22:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:30 INFO - 'MOZ_NO_REMOTE': '1', 22:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:30 INFO - 'NO_EM_RESTART': '1', 22:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:30 INFO - 'PWD': '/builds/slave/test', 22:30:30 INFO - 'SHELL': '/bin/bash', 22:30:30 INFO - 'SHLVL': '1', 22:30:30 INFO - 'TERM': 'linux', 22:30:30 INFO - 'TMOUT': '86400', 22:30:30 INFO - 'USER': 'cltbld', 22:30:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:30 INFO - '_': '/tools/buildbot/bin/python'} 22:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:30 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 22:30:30 INFO - Cleaning up... 22:30:30 INFO - Return code: 0 22:30:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:30 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 22:30:30 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 22:30:30 INFO - Using env: {'DISPLAY': ':0', 22:30:30 INFO - 'HOME': '/home/cltbld', 22:30:30 INFO - 'LANG': 'en_US.UTF-8', 22:30:30 INFO - 'LANGUAGE': 'en_US:en', 22:30:30 INFO - 'LOGNAME': 'cltbld', 22:30:30 INFO - 'MAIL': '/var/mail/cltbld', 22:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:30 INFO - 'MOZ_NO_REMOTE': '1', 22:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:30 INFO - 'NO_EM_RESTART': '1', 22:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:30 INFO - 'PWD': '/builds/slave/test', 22:30:30 INFO - 'SHELL': '/bin/bash', 22:30:30 INFO - 'SHLVL': '1', 22:30:30 INFO - 'TERM': 'linux', 22:30:30 INFO - 'TMOUT': '86400', 22:30:30 INFO - 'USER': 'cltbld', 22:30:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:30 INFO - '_': '/tools/buildbot/bin/python'} 22:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:30 INFO - Downloading/unpacking psutil>=3.1.1 22:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:30 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 22:30:30 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 22:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:30:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:32 INFO - Installing collected packages: psutil 22:30:32 INFO - Running setup.py install for psutil 22:30:32 INFO - building 'psutil._psutil_linux' extension 22:30:32 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 22:30:32 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 22:30:32 INFO - building 'psutil._psutil_posix' extension 22:30:32 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 22:30:33 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 22:30:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:33 INFO - Successfully installed psutil 22:30:33 INFO - Cleaning up... 22:30:33 INFO - Return code: 0 22:30:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:30:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:33 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 22:30:33 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 22:30:33 INFO - Using env: {'DISPLAY': ':0', 22:30:33 INFO - 'HOME': '/home/cltbld', 22:30:33 INFO - 'LANG': 'en_US.UTF-8', 22:30:33 INFO - 'LANGUAGE': 'en_US:en', 22:30:33 INFO - 'LOGNAME': 'cltbld', 22:30:33 INFO - 'MAIL': '/var/mail/cltbld', 22:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:33 INFO - 'MOZ_NO_REMOTE': '1', 22:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:33 INFO - 'NO_EM_RESTART': '1', 22:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:33 INFO - 'PWD': '/builds/slave/test', 22:30:33 INFO - 'SHELL': '/bin/bash', 22:30:33 INFO - 'SHLVL': '1', 22:30:33 INFO - 'TERM': 'linux', 22:30:33 INFO - 'TMOUT': '86400', 22:30:33 INFO - 'USER': 'cltbld', 22:30:33 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:33 INFO - '_': '/tools/buildbot/bin/python'} 22:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:33 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 22:30:33 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 22:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:30:35 INFO - Installing collected packages: mozsystemmonitor 22:30:35 INFO - Running setup.py install for mozsystemmonitor 22:30:35 INFO - Successfully installed mozsystemmonitor 22:30:35 INFO - Cleaning up... 22:30:35 INFO - Return code: 0 22:30:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:30:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:35 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 22:30:35 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 22:30:35 INFO - Using env: {'DISPLAY': ':0', 22:30:35 INFO - 'HOME': '/home/cltbld', 22:30:35 INFO - 'LANG': 'en_US.UTF-8', 22:30:35 INFO - 'LANGUAGE': 'en_US:en', 22:30:35 INFO - 'LOGNAME': 'cltbld', 22:30:35 INFO - 'MAIL': '/var/mail/cltbld', 22:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:35 INFO - 'MOZ_NO_REMOTE': '1', 22:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:35 INFO - 'NO_EM_RESTART': '1', 22:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:35 INFO - 'PWD': '/builds/slave/test', 22:30:35 INFO - 'SHELL': '/bin/bash', 22:30:35 INFO - 'SHLVL': '1', 22:30:35 INFO - 'TERM': 'linux', 22:30:35 INFO - 'TMOUT': '86400', 22:30:35 INFO - 'USER': 'cltbld', 22:30:35 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:35 INFO - '_': '/tools/buildbot/bin/python'} 22:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:35 INFO - Downloading/unpacking jsonschema==2.5.1 22:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:35 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 22:30:35 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 22:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:30:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:37 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 22:30:37 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 22:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:37 INFO - Downloading functools32-3.2.3-2.tar.gz 22:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:30:37 INFO - warning: no files found matching '*.txt' 22:30:37 INFO - no previously-included directories found matching 'build' 22:30:37 INFO - no previously-included directories found matching 'dist' 22:30:37 INFO - no previously-included directories found matching '.git*' 22:30:37 INFO - Installing collected packages: jsonschema, functools32 22:30:37 INFO - Running setup.py install for functools32 22:30:38 INFO - warning: no files found matching '*.txt' 22:30:38 INFO - no previously-included directories found matching 'build' 22:30:38 INFO - no previously-included directories found matching 'dist' 22:30:38 INFO - no previously-included directories found matching '.git*' 22:30:38 INFO - Successfully installed jsonschema functools32 22:30:38 INFO - Cleaning up... 22:30:38 INFO - Return code: 0 22:30:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:30:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:38 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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:38 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 22:30:38 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 22:30:38 INFO - Using env: {'DISPLAY': ':0', 22:30:38 INFO - 'HOME': '/home/cltbld', 22:30:38 INFO - 'LANG': 'en_US.UTF-8', 22:30:38 INFO - 'LANGUAGE': 'en_US:en', 22:30:38 INFO - 'LOGNAME': 'cltbld', 22:30:38 INFO - 'MAIL': '/var/mail/cltbld', 22:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:38 INFO - 'MOZ_NO_REMOTE': '1', 22:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:38 INFO - 'NO_EM_RESTART': '1', 22:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:38 INFO - 'PWD': '/builds/slave/test', 22:30:38 INFO - 'SHELL': '/bin/bash', 22:30:38 INFO - 'SHLVL': '1', 22:30:38 INFO - 'TERM': 'linux', 22:30:38 INFO - 'TMOUT': '86400', 22:30:38 INFO - 'USER': 'cltbld', 22:30:38 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:38 INFO - '_': '/tools/buildbot/bin/python'} 22:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:38 INFO - Downloading/unpacking blobuploader==1.2.4 22:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:38 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 22:30:38 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 22:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:40 INFO - Downloading blobuploader-1.2.4.tar.gz 22:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:30:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:40 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 22:30:40 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 22:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:30:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:40 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 22:30:40 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 22:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:40 INFO - Downloading docopt-0.6.1.tar.gz 22:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:30:41 INFO - Installing collected packages: blobuploader, requests, docopt 22:30:41 INFO - Running setup.py install for blobuploader 22:30:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:30:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:30:41 INFO - Running setup.py install for requests 22:30:41 INFO - Running setup.py install for docopt 22:30:41 INFO - Successfully installed blobuploader requests docopt 22:30:41 INFO - Cleaning up... 22:30:41 INFO - Return code: 0 22:30:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:41 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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:41 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 22:30:41 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 22:30:41 INFO - Using env: {'DISPLAY': ':0', 22:30:41 INFO - 'HOME': '/home/cltbld', 22:30:41 INFO - 'LANG': 'en_US.UTF-8', 22:30:41 INFO - 'LANGUAGE': 'en_US:en', 22:30:41 INFO - 'LOGNAME': 'cltbld', 22:30:41 INFO - 'MAIL': '/var/mail/cltbld', 22:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:41 INFO - 'MOZ_NO_REMOTE': '1', 22:30:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:41 INFO - 'NO_EM_RESTART': '1', 22:30:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:41 INFO - 'PWD': '/builds/slave/test', 22:30:41 INFO - 'SHELL': '/bin/bash', 22:30:41 INFO - 'SHLVL': '1', 22:30:41 INFO - 'TERM': 'linux', 22:30:41 INFO - 'TMOUT': '86400', 22:30:41 INFO - 'USER': 'cltbld', 22:30:41 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:41 INFO - '_': '/tools/buildbot/bin/python'} 22:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:41 INFO - Running setup.py (path:/tmp/pip-yyMDm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:41 INFO - Running setup.py (path:/tmp/pip-65u7NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:41 INFO - Running setup.py (path:/tmp/pip-Fnu6j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:42 INFO - Running setup.py (path:/tmp/pip-kp29Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:42 INFO - Running setup.py (path:/tmp/pip-cpwjVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:42 INFO - Running setup.py (path:/tmp/pip-0ciAqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:42 INFO - Running setup.py (path:/tmp/pip-aRcXpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:42 INFO - Running setup.py (path:/tmp/pip-mEm47M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:42 INFO - Running setup.py (path:/tmp/pip-wcc3bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:42 INFO - Running setup.py (path:/tmp/pip-YzIQv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:42 INFO - Running setup.py (path:/tmp/pip-QaBh63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:42 INFO - Running setup.py (path:/tmp/pip-v0Spbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:42 INFO - Running setup.py (path:/tmp/pip-wg1xUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:42 INFO - Running setup.py (path:/tmp/pip-tD4ffb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:42 INFO - Running setup.py (path:/tmp/pip-KwUg1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:42 INFO - Running setup.py (path:/tmp/pip-IvU0nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:42 INFO - Running setup.py (path:/tmp/pip-PvDL16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:30:43 INFO - Running setup.py install for manifestparser 22:30:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Running setup.py install for mozcrash 22:30:43 INFO - Running setup.py install for mozdebug 22:30:43 INFO - Running setup.py install for mozdevice 22:30:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Running setup.py install for mozfile 22:30:43 INFO - Running setup.py install for mozhttpd 22:30:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Running setup.py install for mozinfo 22:30:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Running setup.py install for mozInstall 22:30:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:30:43 INFO - Running setup.py install for mozleak 22:30:43 INFO - Running setup.py install for mozlog 22:30:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for moznetwork 22:30:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for mozprocess 22:30:44 INFO - Running setup.py install for mozprofile 22:30:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for mozrunner 22:30:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Running setup.py install for mozscreenshot 22:30:44 INFO - Running setup.py install for moztest 22:30:44 INFO - Running setup.py install for mozversion 22:30:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:30:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:30:44 INFO - Cleaning up... 22:30:44 INFO - Return code: 0 22:30:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:44 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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:44 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 22:30:44 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 22:30:44 INFO - Using env: {'DISPLAY': ':0', 22:30:44 INFO - 'HOME': '/home/cltbld', 22:30:44 INFO - 'LANG': 'en_US.UTF-8', 22:30:44 INFO - 'LANGUAGE': 'en_US:en', 22:30:44 INFO - 'LOGNAME': 'cltbld', 22:30:44 INFO - 'MAIL': '/var/mail/cltbld', 22:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:44 INFO - 'MOZ_NO_REMOTE': '1', 22:30:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:44 INFO - 'NO_EM_RESTART': '1', 22:30:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:44 INFO - 'PWD': '/builds/slave/test', 22:30:44 INFO - 'SHELL': '/bin/bash', 22:30:44 INFO - 'SHLVL': '1', 22:30:44 INFO - 'TERM': 'linux', 22:30:44 INFO - 'TMOUT': '86400', 22:30:44 INFO - 'USER': 'cltbld', 22:30:44 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:44 INFO - '_': '/tools/buildbot/bin/python'} 22:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:45 INFO - Running setup.py (path:/tmp/pip-lwQTh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:45 INFO - Running setup.py (path:/tmp/pip-kWdtOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:45 INFO - Running setup.py (path:/tmp/pip-rv2TNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:45 INFO - Running setup.py (path:/tmp/pip-DDvBHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:45 INFO - Running setup.py (path:/tmp/pip-8qt5Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:45 INFO - Running setup.py (path:/tmp/pip-xuVtDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:45 INFO - Running setup.py (path:/tmp/pip-OqlRhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:45 INFO - Running setup.py (path:/tmp/pip-XY77Is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:45 INFO - Running setup.py (path:/tmp/pip-CIT2c_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:45 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:45 INFO - Running setup.py (path:/tmp/pip-oElRDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:45 INFO - Running setup.py (path:/tmp/pip-TZRyX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:45 INFO - Running setup.py (path:/tmp/pip-AldUZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 22:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:45 INFO - Running setup.py (path:/tmp/pip-i8nsd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:46 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)) 22:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:46 INFO - Running setup.py (path:/tmp/pip-dnYtC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 22:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:46 INFO - Running setup.py (path:/tmp/pip-W4bugt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:46 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)) 22:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:46 INFO - Running setup.py (path:/tmp/pip-A7s_MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 22:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:46 INFO - Running setup.py (path:/tmp/pip-hhnYkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:46 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)) 22:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:46 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)) 22:30:46 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:30:46 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:30:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:48 INFO - Downloading blessings-1.6.tar.gz 22:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:30:48 INFO - Installing collected packages: blessings 22:30:48 INFO - Running setup.py install for blessings 22:30:48 INFO - Successfully installed blessings 22:30:48 INFO - Cleaning up... 22:30:48 INFO - Return code: 0 22:30:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:30:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:48 INFO - Reading from file tmpfile_stdout 22:30:48 INFO - Current package versions: 22:30:48 INFO - argparse == 1.2.1 22:30:48 INFO - blessings == 1.6 22:30:48 INFO - blobuploader == 1.2.4 22:30:48 INFO - docopt == 0.6.1 22:30:48 INFO - functools32 == 3.2.3-2 22:30:48 INFO - jsonschema == 2.5.1 22:30:48 INFO - manifestparser == 1.1 22:30:48 INFO - mozInstall == 1.12 22:30:48 INFO - mozcrash == 0.17 22:30:48 INFO - mozdebug == 0.1 22:30:48 INFO - mozdevice == 0.48 22:30:48 INFO - mozfile == 1.2 22:30:48 INFO - mozhttpd == 0.7 22:30:48 INFO - mozinfo == 0.9 22:30:48 INFO - mozleak == 0.1 22:30:48 INFO - mozlog == 3.2 22:30:48 INFO - moznetwork == 0.27 22:30:48 INFO - mozprocess == 0.23 22:30:48 INFO - mozprofile == 0.28 22:30:48 INFO - mozrunner == 6.12 22:30:48 INFO - mozscreenshot == 0.1 22:30:48 INFO - mozsystemmonitor == 0.3 22:30:48 INFO - moztest == 0.7 22:30:48 INFO - mozversion == 1.4 22:30:48 INFO - psutil == 3.1.1 22:30:48 INFO - requests == 1.2.3 22:30:48 INFO - wsgiref == 0.1.2 22:30:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:48 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 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:48 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 22:30:48 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 22:30:48 INFO - Using env: {'DISPLAY': ':0', 22:30:48 INFO - 'HOME': '/home/cltbld', 22:30:48 INFO - 'LANG': 'en_US.UTF-8', 22:30:48 INFO - 'LANGUAGE': 'en_US:en', 22:30:48 INFO - 'LOGNAME': 'cltbld', 22:30:48 INFO - 'MAIL': '/var/mail/cltbld', 22:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:48 INFO - 'MOZ_NO_REMOTE': '1', 22:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:48 INFO - 'NO_EM_RESTART': '1', 22:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:48 INFO - 'PWD': '/builds/slave/test', 22:30:48 INFO - 'SHELL': '/bin/bash', 22:30:48 INFO - 'SHLVL': '1', 22:30:48 INFO - 'TERM': 'linux', 22:30:48 INFO - 'TMOUT': '86400', 22:30:48 INFO - 'USER': 'cltbld', 22:30:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:48 INFO - '_': '/tools/buildbot/bin/python'} 22:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:30:48 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)) 22:30:48 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)) 22:30:48 INFO - Cleaning up... 22:30:48 INFO - Return code: 0 22:30:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:30:48 INFO - Using env: {'DISPLAY': ':0', 22:30:48 INFO - 'HOME': '/home/cltbld', 22:30:48 INFO - 'LANG': 'en_US.UTF-8', 22:30:48 INFO - 'LANGUAGE': 'en_US:en', 22:30:48 INFO - 'LOGNAME': 'cltbld', 22:30:48 INFO - 'MAIL': '/var/mail/cltbld', 22:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:48 INFO - 'MOZ_NO_REMOTE': '1', 22:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:48 INFO - 'NO_EM_RESTART': '1', 22:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:48 INFO - 'PWD': '/builds/slave/test', 22:30:48 INFO - 'SHELL': '/bin/bash', 22:30:48 INFO - 'SHLVL': '1', 22:30:48 INFO - 'TERM': 'linux', 22:30:48 INFO - 'TMOUT': '86400', 22:30:48 INFO - 'USER': 'cltbld', 22:30:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:48 INFO - '_': '/tools/buildbot/bin/python'} 22:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:30:49 INFO - Cleaning up... 22:30:49 INFO - Return code: 0 22:30:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:30:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8137b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', '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 22:30:49 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', 'flake8'] in /builds/slave/test/build 22:30:49 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 flake8 22:30:49 INFO - Using env: {'DISPLAY': ':0', 22:30:49 INFO - 'HOME': '/home/cltbld', 22:30:49 INFO - 'LANG': 'en_US.UTF-8', 22:30:49 INFO - 'LANGUAGE': 'en_US:en', 22:30:49 INFO - 'LOGNAME': 'cltbld', 22:30:49 INFO - 'MAIL': '/var/mail/cltbld', 22:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:49 INFO - 'MOZ_NO_REMOTE': '1', 22:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:49 INFO - 'NO_EM_RESTART': '1', 22:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:49 INFO - 'PWD': '/builds/slave/test', 22:30:49 INFO - 'SHELL': '/bin/bash', 22:30:49 INFO - 'SHLVL': '1', 22:30:49 INFO - 'TERM': 'linux', 22:30:49 INFO - 'TMOUT': '86400', 22:30:49 INFO - 'USER': 'cltbld', 22:30:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:49 INFO - '_': '/tools/buildbot/bin/python'} 22:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:49 INFO - Downloading/unpacking flake8 22:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:30:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:30:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:30:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:30:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:30:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:30:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:30:51 INFO - no previously-included directories found matching 'docs/_build' 22:30:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:30:52 INFO - Running setup.py install for pep8 22:30:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:30:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:30:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:30:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:30:52 INFO - no previously-included directories found matching 'docs/_build' 22:30:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:30:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:30:52 INFO - Cleaning up... 22:30:52 INFO - Return code: 0 22:30:52 INFO - Running post-action listener: _resource_record_post_action 22:30:52 INFO - Running post-action listener: _start_resource_monitoring 22:30:52 INFO - Starting resource monitoring. 22:30:52 INFO - ##### 22:30:52 INFO - ##### Finished create-virtualenv step (success) 22:30:52 INFO - ##### 22:30:52 INFO - ##### 22:30:52 INFO - ##### Running install step. 22:30:52 INFO - ##### 22:30:52 INFO - Running pre-action listener: _resource_record_pre_action 22:30:52 INFO - Running main action method: install 22:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:52 INFO - Reading from file tmpfile_stdout 22:30:52 INFO - Detecting whether we're running mozinstall >=1.0... 22:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:30:52 INFO - Reading from file tmpfile_stdout 22:30:52 INFO - Output received: 22:30:52 INFO - Usage: mozinstall [options] installer 22:30:52 INFO - Options: 22:30:52 INFO - -h, --help show this help message and exit 22:30:52 INFO - -d DEST, --destination=DEST 22:30:52 INFO - Directory to install application into. [default: 22:30:52 INFO - "/builds/slave/test"] 22:30:52 INFO - --app=APP Application being installed. [default: firefox] 22:30:52 INFO - mkdir: /builds/slave/test/build/application 22:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:31:08 INFO - Reading from file tmpfile_stdout 22:31:08 INFO - Output received: 22:31:08 INFO - /builds/slave/test/build/application/firefox/firefox 22:31:08 INFO - Running post-action listener: _resource_record_post_action 22:31:08 INFO - ##### 22:31:08 INFO - ##### Finished install step (success) 22:31:08 INFO - ##### 22:31:08 INFO - ##### 22:31:08 INFO - ##### Running run-tests step. 22:31:08 INFO - ##### 22:31:08 INFO - Running pre-action listener: _resource_record_pre_action 22:31:08 INFO - Running main action method: run_tests 22:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:31:08 INFO - Python 2.7.3 22:31:08 INFO - Return code: 0 22:31:08 INFO - grabbing minidump binary from tooltool 22:31: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:31:08 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 0x162a1c0>, '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 0x162b0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162b550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:31:08 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 22:31:08 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 22:31:08 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 22:31:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:31:08 INFO - Return code: 0 22:31:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:31:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:31:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:31:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:31:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:31:09 INFO - Return code: 0 22:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 22:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 22:31:09 INFO - Using env: {'DISPLAY': ':0', 22:31:09 INFO - 'HOME': '/home/cltbld', 22:31:09 INFO - 'LANG': 'en_US.UTF-8', 22:31:09 INFO - 'LANGUAGE': 'en_US:en', 22:31:09 INFO - 'LOGNAME': 'cltbld', 22:31:09 INFO - 'MAIL': '/var/mail/cltbld', 22:31:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:31:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:09 INFO - 'MOZ_NO_REMOTE': '1', 22:31:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:31:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:09 INFO - 'NO_EM_RESTART': '1', 22:31:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:09 INFO - 'PWD': '/builds/slave/test', 22:31:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:31:09 INFO - 'SHELL': '/bin/bash', 22:31:09 INFO - 'SHLVL': '1', 22:31:09 INFO - 'TERM': 'linux', 22:31:09 INFO - 'TMOUT': '86400', 22:31:09 INFO - 'USER': 'cltbld', 22:31:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939', 22:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:09 INFO - '_': '/tools/buildbot/bin/python'} 22:31:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 22:31:09 INFO - mozversion application_buildid: 20160727194525 22:31:09 INFO - mozversion application_changeset: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f 22:31:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:31:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:31:09 INFO - mozversion application_name: Firefox 22:31:09 INFO - mozversion application_remotingname: firefox-dev 22:31:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:31:09 INFO - mozversion application_vendor: Mozilla 22:31:09 INFO - mozversion application_version: 49.0a2 22:31:09 INFO - mozversion platform_buildid: 20160727194525 22:31:09 INFO - mozversion platform_changeset: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f 22:31:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:31:09 INFO - mozversion platform_version: 49.0a2 22:31:09 INFO - using testdate: 1469683869 22:31:09 INFO - actual date: 1469683869 22:31:09 INFO - starting webserver on 'localhost:40730' 22:31:09 INFO - SUITE-START | Running 2 tests 22:31:09 INFO - TEST-START | dromaeo_css 22:31:09 INFO - operating with platform_type : linux_ 22:31:09 INFO - Initialising browser for dromaeo_css test... 22:31:09 INFO - TEST-INFO | started process 7015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6mRsv_/profile http://localhost:40730/getInfo.html) 22:31:13 INFO - PROCESS | 7015 | __metrics Screen width/height:1600/1200 22:31:13 INFO - PROCESS | 7015 | colorDepth:24 22:31:13 INFO - PROCESS | 7015 | Browser inner width/height: 1024/697 22:31:13 INFO - PROCESS | 7015 | __metrics 22:31:14 ERROR - PROCESS | 7015 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 22:31:14 INFO - TEST-INFO | 7015: exit 0 22:31:14 INFO - Browser initialized. 22:31:14 INFO - Running cycle 1/1 for dromaeo_css test... 22:31:14 INFO - TEST-INFO | started process 7145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6mRsv_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:31:26 INFO - PROCESS | 7145 | RSS: Main: 156389376 22:31:26 INFO - PROCESS | 7145 | 22:32:16 INFO - PROCESS | 7145 | 1469683936355 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:34:24 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:40730/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40730/tests/dromaeo/cssquery-ext.html) 22:34:25 INFO - PROCESS | 7145 | RSS: Main: 160448512 22:34:25 INFO - PROCESS | 7145 | 22:37:27 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:40730/tests/dromaeo/cssquery-ext.html (next: http://localhost:40730/tests/dromaeo/cssquery-jquery.html) 22:37:28 INFO - PROCESS | 7145 | RSS: Main: 157720576 22:37:28 INFO - PROCESS | 7145 | 22:40:29 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:40730/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40730/tests/dromaeo/cssquery-mootools.html) 22:40:30 INFO - PROCESS | 7145 | RSS: Main: 157134848 22:40:30 INFO - PROCESS | 7145 | 22:43:32 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:40730/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40730/tests/dromaeo/cssquery-prototype.html) 22:43:32 INFO - PROCESS | 7145 | RSS: Main: 157478912 22:43:32 INFO - PROCESS | 7145 | 22:46:35 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:40730/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40730/tests/dromaeo/cssquery-yui.html) 22:46:35 INFO - PROCESS | 7145 | RSS: Main: 159223808 22:46:35 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | Cycle 1(1): loaded http://localhost:40730/tests/dromaeo/cssquery-yui.html (next: http://localhost:40730/tests/dromaeo/cssquery-dojo.html) 22:49:38 INFO - PROCESS | 7145 | RSS: Main: 159408128 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | __start_tp_report 22:49:38 INFO - PROCESS | 7145 | _x_x_mozilla_page_load 22:49:38 INFO - PROCESS | 7145 | _x_x_mozilla_page_load_details 22:49:38 INFO - PROCESS | 7145 | |i|pagename|runs| 22:49:38 INFO - PROCESS | 7145 | |0;dojo.html;3352.4300868852292;3369.3092915952234;3381.0364046246796;3391.8304084795764;3409.403354412979;5527.115661494162;5545.9999999999945;5599.32808063032;5603.523700485459;5653.830385088454;5638.605297629168;5642.4357564243555;5690.089585666295;5698.658080515172;5704.857378565538;5122.871928201787;5204.245384419257;5225.111731005738;5240.633155679104;5253.028189784902;5033.748312584375;5048.368953880765;5278.445763194867;5279.86800329991;5279.894402111955;5105.438401775802;5155.5360017598405;5183.2743415921805;5189.3772747270195;5231.973840130794;5115.9744201278945;5146.639735218536;5238.8690282742855;5242.711650859202;5258.81594144207;5129.17933130701;5176.430592634807;5229;5235.005348983681;5265.894682106336;5379.354477462691;5467;5474.42518535552;5559.555235581144;5588.804391846266;6028.367021462727;6078.665673387962;6079.088136779475;6082.330943596201;6096.878062438726;5992;6137.324894261627;6142.907856382158;6147.385261473862;6150.477209437203;5598.468145526169;5607.831765047055;5626.127950167731;5673.461021202979;5686.09022556389;6155.199824022849;6167.321594624587;6177.536684748662;6200.441960223601;6201.131841542187;5804.709764511757;5839.766409343663;5877.970610146922;5939.673317967467;5941.613795103304;5913.113033045078;6015.54883383748;6018.8194354169445;6040.184575082396;6058.939410605837;5918.644881307136;5990.161377407167;5997.790077347272;6000.969995149996;6015.218021657157;5902.8819423611285;5938.138969849348;5983.880322393615;5997.970010149921;6011.579189456696;4607.585317321457;4650.51169627191;4651.697639653412;4666.066786642617;4681.882952926204;4143.357779544175;4149.170165966819;4174.979125104355;4183.874483765492;4203.852865149705;5741.052726300197;5766.048601980626;5799.3040835100055;5814.709264536757;5840.59115861894;5927.911081333784;6009.338972712998;6025;6072.39276072398;6135.938640613626;5798.362180160179;5800.984827655144;5833.300003999547;5852;5857.970710146422;5088.6946783193125;5221.7128057956415;5227.529522343008;5248.6325957182635;5298.549623282064;6321.804750811341;6386.9680651596445;6387.776427825004;6402.135711678957;6427.132337134521;5928.110783382527;6152.8154155376305;6154.169187159676;6160.229971253593;6171.907421388593;6197.25632924052;6230.937690623217;6263.561550691408;6377.107204991212;6380.266269378895;5970.253718285215;5973.611715238496;5984.551158663205;5984.700764961647;6041.818745437644;5933.703314834327;5986.790462333972;6074.635521868702;6090.330063692902;6103.725332359965;5818.2436283282905;5914.792982245601;5919.141724450017;5932.317783454958;5977.5217982560425;5870.17817505541;5887.646741195542;5926.4666180044005;5936.7922122725495;5941.940580594139;5677.914831277617;5769.105788602773;5786.537077033911;5791.623544469596;5861.9706901465215;5885.970570147293;5931.406859314034;5942;5966.164736936746;6029.969850150721;5775.306963164448;5784.132380143013;5817.243758311392;5853.9121913172185;5862.765489380377 22:49:38 INFO - PROCESS | 7145 | |1;ext.html;599.8590331272151;644.3170239546082;645.1999520594464;661.6956200147935;665.1253601514007;34848.30303393934;38204;46595.5340446595;47044.764776176155;47306;48210.75894620531;48366.27450588235;48707.269390959074;48747;48754;9198.586063627135;9329.626814927395;9344.392614480072;9350.90649093507;9362.81274374512;9155.313351498631;9227.215686666641;9250.028746981538;9263.166315031645;9267.629294828199;9254.814903701921;9275.907240927605;9293.349465537414;9295.349325547215;9326.766830829216;9178.219851312613;9220.492872891988;9242.075792420772;9266.860997085016;9301.953490232505;9074.591643376063;9132.36073474857;9146.86279705805;9155.313351498598;9231.399959002643;45654.77172614149;45677;45873.311900321525;45930.54069459296;46049.53950460519;3356.3790698720813;3408.233147541808;3494.318607871466;3543.698785603227;3942.9014274643077;34126.82936585301;34848;34853.651463485294;35051.64948350509;35989.10027249314;4747.643926705477;4832.251001094801;4885.828995985124;4895.6083513318845;4932.728699921503;364318;364758;365015;366352;367160;65937.34062659409;66174.66912665406;66545;66582;66717;38824.805875971004;39636.60363396387;39645.40531892024;41401.1719765603;41438.79280603638;19023.714644280346;19058.42824715261;19236.71144932827;19253.518662033282;19311.324103656305;14960.102393856403;14987.250637468083;15123.31945062475;15192.392304307925;15244.23778811055;21373.893130534558;21546.56906861854;21652.566948660937;21657.67513487299;21710.782892170875;7443.069616297963;7465.776026719207;7620.161782203953;7741.61291935401;7890.277854140312;1065.4619417194267;1089.493385575711;1092.1481244629201;1109.2457129152253;1112.0880877680386;32620;32943.68225270962;32951.67048329534;33110.50334244988;33290.334193316;1192.7435601345753;1217.0689422591724;1221.8167274908658;1234.8765123487758;1255.0837888341562;17173.31306747716;17331;17345.219465123846;17390.47828565145;17638.647227055644;594406;874416;894482;897095;1044022;59549.40450595525;59748.506287342294;59895;60227.69886150629;60470.697646512366;1468.4640106361314;1469.5003698742478;1473.152937061194;1479.7114562660392;1482.7553453680023;32237.35525289434;32588.674113258563;32823.34353312968;33424.66575334215;33971.320573588884;52708.20937685861;53212;53233.46766532285;53360.466395337105;53544.46455535551;378185;379714;381505;381552;382700;29881.402371952874;29916;30370.696293037672;30465.847670761505;30662;50965.745171273906;51060;51732;52106.739466303945;52709;42430.36354454624;43052.56947430611;43732.34401484044;43828;44702;41942;42111.36832947447;42144;42727.359089613055;42977;42060.789696051324;42525.78737106295;43043.7847810759;43608.34587481254;43630.12739745124;36985.260294793414;37466.438003430514;37827.43258851174;37955.81022094872;38272.80863595776;28308.43383132367;28706.856465717538;28867.422651546432;29002.41995160127;29030.7096929028 22:49:38 INFO - PROCESS | 7145 | |2;jquery.html;391.8491380818385;393.65555139253155;395.94060890866365;396.2589956780819;419.50288907644443;17771.933683978972;18252.17865196066;19933.202671893127;19965.201391944323;21221;13110.213387196774;13356.465741370333;13402.195868247913;18679.439616811484;19490.512737181543;5200.661956972804;5361.0618141825025;5379.300690910176;5380.569554435636;5589.832305030855;5910.083936989773;5938.792142275021;5939.287285525743;5942.910856337158;5968.641881487103;5499.037668408013;5520.503154716075;5857.238558987326;5864.560157988147;5910.083936989752;5496.0931446311315;5496.917546236809;5505.256790333294;5538.6122971391815;5545.889082218353;4705.55297246761;4730.408698912636;4834.371531700892;5347.957148356072;5379.5696344292755;15107.39570417182;15168.696626067416;17129.743053854203;23860.687662178745;24060.759392406027;2307.953840923189;2413.3604594782396;2414.1429792423787;2485.117783186961;2620.3694959566087;10403.167746580255;10562.15502759785;10811.891881081247;10823.891761082366;11020.559177632886;1971.3888694504749;2014.3252010576475;2025.7771645119026;2079;2082.5938941906334;79465.20534794636;82997.58501207516;83337.99999999939;83349.58325208396;84777.57611211904;11082.944585277022;11186.216964812385;11404.543818247177;11809.350485723367;11942.104342174198;16370.672586548202;16389.426370077228;16427.83572164287;16509;16511.33954641825;8578.313734901192;8684.652613895374;8760;8861.113888611217;8906.064863189402;8364.790880228044;8567.357448191411;8585.441946273471;8600.182982616641;8736.781580460412;4781.450133234653;4793.089313030583;4949.059678661043;4985.8005679772405;5056.873578160502;2084.832493803475;2101;2126.5109024924354;2129.8189653879285;2147.3987283560627;909.3134683314056;1027.5632856036154;1058.2433560004633;1060.6764936694228;1116.983245251322;17548.824511754974;19427.708584371245;19624.70562941557;19663.41009769709;19841.107150178275;1019.6329321444274;1036.891126431729;1078.44460103047;1097.9011888930036;1107.7452185997265;10570;11029.558817647365;11142.944285278521;11202.103831693445;11221.607243746594;495534;500877;501835;509112;521994;98220.50889745506;100450.99999999854;102184.48907755563;103118.48440757749;103293.48353258186;1062.665260442958;1071.983920241197;1075.8440026196158;1083.7940791249637;1093.868735751715;17427.21577528989;17626.73559896577;17785.199666014803;17796.110194490484;17953.820461795738;39765.2046959065;41003.794981024905;41286.38070428886;41783.7910810444;42165;57208.42791572197;58017.709911451864;58790.41209587849;58832.705836470544;58997.70501147467;7619.733309334147;8726.896832772649;8901.109889011048;9090.36367454272;9150.359474836963;9460;12377.876221237673;13074;13281.136726112773;13472;11024.952629500185;11853.52585896589;11879;12002.579909703118;12380.319082450551;12042.157049006486;12173.573924912585;12190.57332993341;12272.754544908874;12562.560310389092;12298.139130261154;12333.444994975069;12344.567940122053;12469.75060498803;12658.936705316415;8971.865422018805;9100.772480688036;9161.70178735668;9188.219001384958;9296.674616388394;9501.619935202514;12224.44989975471;12311.953483953606;12361;12512.499500019898 22:49:38 INFO - PROCESS | 7145 | |3;mootools.html;1602.3110062673045;1627.414130912871;1861.972070418943;1897.0988780329353;1961.2841312920789;8595.226429621332;9136;9240.445573265608;9787.70636880893;9848.310618256724;9221;9515.143637072662;9590.088941550559;9632.422054676725;9640.710778676634;2520.0549793827313;2574.987125064372;2583.638290639312;2584.8707564621786;2588.1588483742858;2468.3829042739317;2539.162076514746;2542.084849454195;2546.2106746908426;2550.821442499026;2491.713452952906;2547.0957809977467;2558.2708927955537;2558.4115653399635;2558.9104381346656;2495.563276426618;2508.059477695864;2508.385445565848;2510.8619025953567;2548.808839337048;2414.9034038638433;2441.963370549443;2452.938676533101;2457.7665121813397;2461.064795377763;9577.138057574783;9639.421634701941;9659.951700241523;9691.370060946016;9695.466749328783;5617.354004289518;5706.971465142689;5722.513586345166;5727.656340619576;5747.453991870766;3631.310051090311;3662.505561749156;3718;3766.4162054881535;3795.411711184771;1185.5731936502852;1206.619914726867;1224.1675660550823;1225.650689553482;1226.3193927370305;13412.865871341259;13461.259630720306;13594.456221751117;13644;13658.521951731742;4994.9500504994485;5152.3044389007755;5179.870503237449;5193.1171700810955;5213.921791173136;11457.427128643534;11557.88442115585;11614.70963225926;11706.063514918957;11718.88281117195;5870.853228669232;5879.735411906389;5881.9705901470215;5885.882282354414;5888.146218798251;4779.928301075487;5309.681419114865;5372.677639341652;5374.27447294618;5493.835184944458;4370.2352088384405;4392.231359512072;4395.099004704028;4440.023194897118;4441.977790111094;1335.8797708206307;1390.7149034448116;1401.9228942408265;1403.7964495148146;1412.1738782811965;928.3223247029706;1105.4472763618191;1108.661858133277;1111.6942840718866;1121.472907733364;6565.80302590923;6776.186857577024;6818.4886133540185;6847.657617119125;6871.175458944958;497.4677095507772;507.0366304022313;510.7752588860889;516.9767360468788;525.4404059676509;4653.53464653532;4672.462666793294;4714.528547145326;4723.409573803275;4729.125111854318;11575.479103440366;11768.529258829722;11815.822762658567;11948.34284114382;12156;12159.756804863853;12210.145289829627;12224.877751222552;12292.938535307267;12311.507539698312;8973.955130224307;9021.50381728998;9116.49859257734;9131.543422828832;9153.267738580898;6593.967030164818;6822.351876571718;6828.351306625863;6880.277570854988;6884.552504087218;11046.944765276123;11079.50142243585;11380.829287560528;11413.029892459233;11482.253653512495;11441.542338306375;11532.654020379403;11535.134864885;11599.478023489131;11622.6513204604;7997.320227780471;8507.8723819144;8589.613467394107;8781.121887811169;8883.777905552413;9530.85703714431;9589.472579008221;9595.712128636153;9609.567569459252;9691.9030809691;8529.829403411773;8748.825023499367;8789.42868713557;8828.161324674145;8843.159899809507;8055.677772889018;8214.178582141738;8498.915010849812;8517.148285171434;8767.035626080999;7595.5442673439775;7611.923880761122;7634.732784352521;7677.539347639159;7693.346065584488;5356.330458692663;5435.972820135874;5462.535684466865;5554.944450555443;5632.662040277596;5419.132938729785;5619.634723742944;5662.801801936913;5702.059160238514;5719.914201287067 22:49:38 INFO - PROCESS | 7145 | |4;prototype.html;359.69246294418286;361.2341835309145;361.8100497238951;363.369553824115;364.3496358999187;5732.2261494698205;5746.48281654651;5747;5749.425057494249;5750.137479378095;5546.972265138679;5660.886782264352;5662.433756624336;5679.432056794319;5684.317881854184;4383.561643835623;4384.758838263894;4408.8016039278145;4409.889752756175;4432.157890000905;4394.121175764843;4410.71330363527;4431.911361772763;4462.620677242438;4464.46426428829;4386.8903277418;4410.603045725884;4424.358468022135;4429.667774916878;4439.66702497314;4194.525230114232;4325.307950727868;4358.978205108986;4375.6937014409;4407.3388991651345;3876.341022026234;3881.7282790204695;3890.144168282973;3917.6865850731874;3930.78380689062;5424.891502169935;5456.826782241797;5517.475839795213;5518.751656175482;5519.558435325164;4765.999140180566;5048.419431765321;5055.368078990126;5067.797288108508;5078.873028174288;5931.555133365015;5931.724679193994;5954.196183515257;5960.672163031031;5973.163757074013;4290.935635965463;4299.333603291464;4301.161273551658;4342.174986752539;4357;5943.3165186003735;5976.521878249773;6137.386261373871;6148.862460444832;6206.875862482771;4385.223984286369;4402.911941761146;4404.71369360997;4421.955780442218;4426.11477704454;4459.175052615276;4469.5530446955045;4472.172648060055;4476.7090139140855;4479.843205487858;4916.680415772963;5045.839456924928;5105.15764898795;5117.155669314596;5126.564242039469;5932.843095596403;5938.61399009063;5942.316633587193;5943.791967281124;5975.701214939236;1525.8245301790214;1534.6470311828118;1535.7466018106886;1546.5283088658068;1556.5719427157621;932.9113734195377;971.9368241064308;976.9413835169912;984.6061575369766;993.096282383028;5123.308353372247;5164.638475306692;5168.844934651891;5197.22041693749;5205.713685747321;5318.335208098988;5389.838304850782;5403.8651883104985;5415.079436495806;5487.396386397493;4971.45314015458;5161.870953226125;5164.664296820694;5257.316548848626;5300.814471493556;2900.2894290898444;2900.941981160407;2904.840233787162;2914.781391395654;2918.2558447596152;5671.773129074791;5675.75807556013;5695.857603559944;5731.484166425042;5737.799177028784;5228.640553456053;5420.864478387993;5502.2296878437055;5543.916841247385;5557.944420555743;5628.1427129047315;5646.463585959327;5678.886422271531;5703.37453825666;5742.253507044141;5051.646384753032;5401.702906340189;5421.349438067457;5442.782288708408;5518.530924871431;5376.489233522809;5392.056390131633;5417.485338892799;5494.36814766307;5919.822405327847;6414.486841052798;6415.390537898892;6429.903551446639;6454.774082907075;6460.8707825842275;4343.869683909487;4366.476022877275;4379.715318504287;4392.824287028611;4410;6117.969410153099;6121.908171377522;6182.28903676083;6192.380761923771;6261.5616906816085;5947.821565353046;6017.819465416044;6039.63762174271;6046.637201767908;6068.48287928018;5631.577631677558;5956.344802071855;6004.669743164168;6018.699065046818;6045.879082418415;5844.211031510862;5881.382619779595;5888.086371929226;5900.616459930091;5917.230760001173;6017.939820601738;6034.668093254913;6040.275166979991;6064.272287325549;6082.2093127893095;5605.467480589337;5847.210626565359;5862.94137058624;5881.441263080001;5900.144479050599 22:49:38 INFO - PROCESS | 7145 | |5;yui.html;751.3951269228272;811.4279432999732;854.3122786157143;879.1428357351581;918.7197904639082;15876.12681302528;16842.91578542107;16965.660686786257;17213.22540485678;17332.826671733277;18031.81968180318;18224.362147324846;18268.72596911044;18278;18285.634287314246;867.8611422172454;875.916787905148;878.6836738774036;879.0989236033085;879.5865943006777;856.9657213711444;862.4954401675028;871.960761765722;887.1217494680285;887.6493784954939;919.9816003679922;924.9537523123817;925.9351845370827;929.8558723397884;930.8789857318573;916.8533034714413;919.691903212418;922.2115091596655;924.0943875002469;925.6204955968054;672.6872004517874;677.00818301189;677.5392732941598;678.0270312102149;680.7753441364388;16882.493525194262;17100.91449542744;17103.828961710347;17108.973461592344;17162.57093572658;15812.367505299775;15942;15993.200339982954;16130.596735081717;16315.673686526321;12339.383030848421;12851.550195743197;12926.09517333787;12936.676583085404;12994.22034677913;4009.8997525061814;4097.836086556535;4136.420901073852;4144.088300573869;4164.875053748392;80057.59971200165;82188;83800.58099709524;83979;84185.57907210485;10948.726281843017;11850.170488065925;12153.69615759613;12401.875981240071;12402.379881006094;22830.086796528285;22835.20076797304;22867.656985145237;22907.656385154238;22961.655575166387;8441.746747597583;8770.46516859547;8817.07420720828;8885.693314759104;8985.910140898639;4956.107900577858;5143;5150.768215430315;5175.741212939338;5186.740662966837;3930.3121953657997;4035.8183881725395;4066.755994640331;4069.0234343757284;4070.4504891839224;659.4032400677395;663.0485253661012;664.1996394344803;665.2150462454254;674.416629615389;753.1075675324757;783.1268136028287;790.2255789326377;844.8943882014748;847.7753395350237;10560.419176945188;10605.204609654307;10612.310199836986;10659.94670026645;10799.244052916378;710.9751158709421;758.9772306830804;774.9883751743729;814.8248126652799;851.7700220940311;5084.872878178001;5142.125838607446;5213.661112027706;5251.76367063483;5262.394824595144;98600;113006;113552;120207.39896300463;120391;72880.63559682168;99182.5040874791;100576.49711751395;101821;102826.48586757018;91540.08459915315;91983.54008229917;92734.53632731928;93396.53301733448;93675.53162234146;10094.899051009395;10363.378197307884;10633.415162165846;10660.786784264426;10733.516991735547;49260.50739492559;49768.25347619716;50428.74785626048;51257.23114153216;52446;45536.77231613953;45728.31407528956;79066;79429.60285198537;79432;13168.078234523491;13492.662683432993;13611;13858.930705346409;14027.50903718365;15833.841661583238;16038.589387918415;16115.435959741353;16352.427665031666;17240.827591723923;15182;15340.079595223875;15495.380184792482;15826;16388.0167189969;14977.625559361104;15035.624109397353;15206.847931520542;15316.310766015778;15827.050376977419;14656.853431465548;14861.108333499593;14996.550103496913;15114;15146.621334466727;12108.152225302121;12338.444769985193;12468.812967805308;12473.126881118234;12565.811512827499;11834.881651183723;11837.940810295893;12076.51693932233;12077.335746534025;12581.87418125807 22:49:38 INFO - PROCESS | 7145 | __end_tp_report 22:49:38 INFO - PROCESS | 7145 | __start_cc_report 22:49:38 INFO - PROCESS | 7145 | _x_x_mozilla_cycle_collect,346 22:49:38 INFO - PROCESS | 7145 | __end_cc_report 22:49:38 INFO - PROCESS | 7145 | __startTimestamp1469684978306__endTimestamp 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | ------- Summary: start ------- 22:49:38 INFO - PROCESS | 7145 | Number of tests: 6 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | [#0] dojo.html Cycles:165 Average:5631.54 Median:5840.59 stddev:620.78 (10.6%) stddev-sans-first:596.38 22:49:38 INFO - PROCESS | 7145 | Values: 3352.4 3369.3 3381.0 3391.8 3409.4 5527.1 5546.0 5599.3 5603.5 5653.8 5638.6 5642.4 5690.1 5698.7 5704.9 5122.9 5204.2 5225.1 5240.6 5253.0 5033.7 5048.4 5278.4 5279.9 5279.9 5105.4 5155.5 5183.3 5189.4 5232.0 5116.0 5146.6 5238.9 5242.7 5258.8 5129.2 5176.4 5229.0 5235.0 5265.9 5379.4 5467.0 5474.4 5559.6 5588.8 6028.4 6078.7 6079.1 6082.3 6096.9 5992.0 6137.3 6142.9 6147.4 6150.5 5598.5 5607.8 5626.1 5673.5 5686.1 6155.2 6167.3 6177.5 6200.4 6201.1 5804.7 5839.8 5878.0 5939.7 5941.6 5913.1 6015.5 6018.8 6040.2 6058.9 5918.6 5990.2 5997.8 6001.0 6015.2 5902.9 5938.1 5983.9 5998.0 6011.6 4607.6 4650.5 4651.7 4666.1 4681.9 4143.4 4149.2 4175.0 4183.9 4203.9 5741.1 5766.0 5799.3 5814.7 5840.6 5927.9 6009.3 6025.0 6072.4 6135.9 5798.4 5801.0 5833.3 5852.0 5858.0 5088.7 5221.7 5227.5 5248.6 5298.5 6321.8 6387.0 6387.8 6402.1 6427.1 5928.1 6152.8 6154.2 6160.2 6171.9 6197.3 6230.9 6263.6 6377.1 6380.3 5970.3 5973.6 5984.6 5984.7 6041.8 5933.7 5986.8 6074.6 6090.3 6103.7 5818.2 5914.8 5919.1 5932.3 5977.5 5870.2 5887.6 5926.5 5936.8 5941.9 5677.9 5769.1 5786.5 5791.6 5862.0 5886.0 5931.4 5942.0 5966.2 6030.0 5775.3 5784.1 5817.2 5853.9 5862.8 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | [#1] ext.html Cycles:180 Average:68954.88 Median:42127.68 stddev:159029.41 (377.5%) stddev-sans-first:159392.71 22:49:38 INFO - PROCESS | 7145 | Values: 599.9 644.3 645.2 661.7 665.1 34848.3 38204.0 46595.5 47044.8 47306.0 48210.8 48366.3 48707.3 48747.0 48754.0 9198.6 9329.6 9344.4 9350.9 9362.8 9155.3 9227.2 9250.0 9263.2 9267.6 9254.8 9275.9 9293.3 9295.3 9326.8 9178.2 9220.5 9242.1 9266.9 9302.0 9074.6 9132.4 9146.9 9155.3 9231.4 45654.8 45677.0 45873.3 45930.5 46049.5 3356.4 3408.2 3494.3 3543.7 3942.9 34126.8 34848.0 34853.7 35051.6 35989.1 4747.6 4832.3 4885.8 4895.6 4932.7 364318.0 364758.0 365015.0 366352.0 367160.0 65937.3 66174.7 66545.0 66582.0 66717.0 38824.8 39636.6 39645.4 41401.2 41438.8 19023.7 19058.4 19236.7 19253.5 19311.3 14960.1 14987.3 15123.3 15192.4 15244.2 21373.9 21546.6 21652.6 21657.7 21710.8 7443.1 7465.8 7620.2 7741.6 7890.3 1065.5 1089.5 1092.1 1109.2 1112.1 32620.0 32943.7 32951.7 33110.5 33290.3 1192.7 1217.1 1221.8 1234.9 1255.1 17173.3 17331.0 17345.2 17390.5 17638.6 594406.0 874416.0 894482.0 897095.0 1044022.0 59549.4 59748.5 59895.0 60227.7 60470.7 1468.5 1469.5 1473.2 1479.7 1482.8 32237.4 32588.7 32823.3 33424.7 33971.3 52708.2 53212.0 53233.5 53360.5 53544.5 378185.0 379714.0 381505.0 381552.0 382700.0 29881.4 29916.0 30370.7 30465.8 30662.0 50965.7 51060.0 51732.0 52106.7 52709.0 42430.4 43052.6 43732.3 43828.0 44702.0 41942.0 42111.4 42144.0 42727.4 42977.0 42060.8 42525.8 43043.8 43608.3 43630.1 36985.3 37466.4 37827.4 37955.8 38272.8 28308.4 28706.9 28867.4 29002.4 29030.7 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | [#2] jquery.html Cycles:180 Average:29690.29 Median:11673.76 stddev:83580.91 (716.0%) stddev-sans-first:83786.42 22:49:38 INFO - PROCESS | 7145 | Values: 391.8 393.7 395.9 396.3 419.5 17771.9 18252.2 19933.2 19965.2 21221.0 13110.2 13356.5 13402.2 18679.4 19490.5 5200.7 5361.1 5379.3 5380.6 5589.8 5910.1 5938.8 5939.3 5942.9 5968.6 5499.0 5520.5 5857.2 5864.6 5910.1 5496.1 5496.9 5505.3 5538.6 5545.9 4705.6 4730.4 4834.4 5348.0 5379.6 15107.4 15168.7 17129.7 23860.7 24060.8 2308.0 2413.4 2414.1 2485.1 2620.4 10403.2 10562.2 10811.9 10823.9 11020.6 1971.4 2014.3 2025.8 2079.0 2082.6 79465.2 82997.6 83338.0 83349.6 84777.6 11082.9 11186.2 11404.5 11809.4 11942.1 16370.7 16389.4 16427.8 16509.0 16511.3 8578.3 8684.7 8760.0 8861.1 8906.1 8364.8 8567.4 8585.4 8600.2 8736.8 4781.5 4793.1 4949.1 4985.8 5056.9 2084.8 2101.0 2126.5 2129.8 2147.4 909.3 1027.6 1058.2 1060.7 1117.0 17548.8 19427.7 19624.7 19663.4 19841.1 1019.6 1036.9 1078.4 1097.9 1107.7 10570.0 11029.6 11142.9 11202.1 11221.6 495534.0 500877.0 501835.0 509112.0 521994.0 98220.5 100451.0 102184.5 103118.5 103293.5 1062.7 1072.0 1075.8 1083.8 1093.9 17427.2 17626.7 17785.2 17796.1 17953.8 39765.2 41003.8 41286.4 41783.8 42165.0 57208.4 58017.7 58790.4 58832.7 58997.7 7619.7 8726.9 8901.1 9090.4 9150.4 9460.0 12377.9 13074.0 13281.1 13472.0 11025.0 11853.5 11879.0 12002.6 12380.3 12042.2 12173.6 12190.6 12272.8 12562.6 12298.1 12333.4 12344.6 12469.8 12658.9 8971.9 9100.8 9161.7 9188.2 9296.7 9501.6 12224.4 12312.0 12361.0 12512.5 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | [#3] mootools.html Cycles:180 Average:6408.54 Median:2746.21 stddev:3684.97 (134.2%) stddev-sans-first:3677.61 22:49:38 INFO - PROCESS | 7145 | Values: 1602.3 1627.4 1862.0 1897.1 1961.3 8595.2 9136.0 9240.4 9787.7 9848.3 9221.0 9515.1 9590.1 9632.4 9640.7 2520.1 2575.0 2583.6 2584.9 2588.2 2468.4 2539.2 2542.1 2546.2 2550.8 2491.7 2547.1 2558.3 2558.4 2558.9 2495.6 2508.1 2508.4 2510.9 2548.8 2414.9 2442.0 2452.9 2457.8 2461.1 9577.1 9639.4 9660.0 9691.4 9695.5 5617.4 5707.0 5722.5 5727.7 5747.5 3631.3 3662.5 3718.0 3766.4 3795.4 1185.6 1206.6 1224.2 1225.7 1226.3 13412.9 13461.3 13594.5 13644.0 13658.5 4995.0 5152.3 5179.9 5193.1 5213.9 11457.4 11557.9 11614.7 11706.1 11718.9 5870.9 5879.7 5882.0 5885.9 5888.1 4779.9 5309.7 5372.7 5374.3 5493.8 4370.2 4392.2 4395.1 4440.0 4442.0 1335.9 1390.7 1401.9 1403.8 1412.2 928.3 1105.4 1108.7 1111.7 1121.5 6565.8 6776.2 6818.5 6847.7 6871.2 497.5 507.0 510.8 517.0 525.4 4653.5 4672.5 4714.5 4723.4 4729.1 11575.5 11768.5 11815.8 11948.3 12156.0 12159.8 12210.1 12224.9 12292.9 12311.5 8974.0 9021.5 9116.5 9131.5 9153.3 6594.0 6822.4 6828.4 6880.3 6884.6 11046.9 11079.5 11380.8 11413.0 11482.3 11441.5 11532.7 11535.1 11599.5 11622.7 7997.3 8507.9 8589.6 8781.1 8883.8 9530.9 9589.5 9595.7 9609.6 9691.9 8529.8 8748.8 8789.4 8828.2 8843.2 8055.7 8214.2 8498.9 8517.1 8767.0 7595.5 7611.9 7634.7 7677.5 7693.3 5356.3 5436.0 5462.5 5554.9 5632.7 5419.1 5619.6 5662.8 5702.1 5719.9 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | [#4] prototype.html Cycles:180 Average:4876.52 Median:5421.11 stddev:1415.81 (26.1%) stddev-sans-first:1378.59 22:49:38 INFO - PROCESS | 7145 | Values: 359.7 361.2 361.8 363.4 364.3 5732.2 5746.5 5747.0 5749.4 5750.1 5547.0 5660.9 5662.4 5679.4 5684.3 4383.6 4384.8 4408.8 4409.9 4432.2 4394.1 4410.7 4431.9 4462.6 4464.5 4386.9 4410.6 4424.4 4429.7 4439.7 4194.5 4325.3 4359.0 4375.7 4407.3 3876.3 3881.7 3890.1 3917.7 3930.8 5424.9 5456.8 5517.5 5518.8 5519.6 4766.0 5048.4 5055.4 5067.8 5078.9 5931.6 5931.7 5954.2 5960.7 5973.2 4290.9 4299.3 4301.2 4342.2 4357.0 5943.3 5976.5 6137.4 6148.9 6206.9 4385.2 4402.9 4404.7 4422.0 4426.1 4459.2 4469.6 4472.2 4476.7 4479.8 4916.7 5045.8 5105.2 5117.2 5126.6 5932.8 5938.6 5942.3 5943.8 5975.7 1525.8 1534.6 1535.7 1546.5 1556.6 932.9 971.9 976.9 984.6 993.1 5123.3 5164.6 5168.8 5197.2 5205.7 5318.3 5389.8 5403.9 5415.1 5487.4 4971.5 5161.9 5164.7 5257.3 5300.8 2900.3 2900.9 2904.8 2914.8 2918.3 5671.8 5675.8 5695.9 5731.5 5737.8 5228.6 5420.9 5502.2 5543.9 5557.9 5628.1 5646.5 5678.9 5703.4 5742.3 5051.6 5401.7 5421.3 5442.8 5518.5 5376.5 5392.1 5417.5 5494.4 5919.8 6414.5 6415.4 6429.9 6454.8 6460.9 4343.9 4366.5 4379.7 4392.8 4410.0 6118.0 6121.9 6182.3 6192.4 6261.6 5947.8 6017.8 6039.6 6046.6 6068.5 5631.6 5956.3 6004.7 6018.7 6045.9 5844.2 5881.4 5888.1 5900.6 5917.2 6017.9 6034.7 6040.3 6064.3 6082.2 5605.5 5847.2 5862.9 5881.4 5900.1 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - PROCESS | 7145 | [#5] yui.html Cycles:180 Average:21456.95 Median:4067.89 stddev:29897.44 (735.0%) stddev-sans-first:29940.88 22:49:38 INFO - PROCESS | 7145 | Values: 751.4 811.4 854.3 879.1 918.7 15876.1 16842.9 16965.7 17213.2 17332.8 18031.8 18224.4 18268.7 18278.0 18285.6 867.9 875.9 878.7 879.1 879.6 857.0 862.5 872.0 887.1 887.6 920.0 925.0 925.9 929.9 930.9 916.9 919.7 922.2 924.1 925.6 672.7 677.0 677.5 678.0 680.8 16882.5 17100.9 17103.8 17109.0 17162.6 15812.4 15942.0 15993.2 16130.6 16315.7 12339.4 12851.6 12926.1 12936.7 12994.2 4009.9 4097.8 4136.4 4144.1 4164.9 80057.6 82188.0 83800.6 83979.0 84185.6 10948.7 11850.2 12153.7 12401.9 12402.4 22830.1 22835.2 22867.7 22907.7 22961.7 8441.7 8770.5 8817.1 8885.7 8985.9 4956.1 5143.0 5150.8 5175.7 5186.7 3930.3 4035.8 4066.8 4069.0 4070.5 659.4 663.0 664.2 665.2 674.4 753.1 783.1 790.2 844.9 847.8 10560.4 10605.2 10612.3 10659.9 10799.2 711.0 759.0 775.0 814.8 851.8 5084.9 5142.1 5213.7 5251.8 5262.4 98600.0 113006.0 113552.0 120207.4 120391.0 72880.6 99182.5 100576.5 101821.0 102826.5 91540.1 91983.5 92734.5 93396.5 93675.5 10094.9 10363.4 10633.4 10660.8 10733.5 49260.5 49768.3 50428.7 51257.2 52446.0 45536.8 45728.3 79066.0 79429.6 79432.0 13168.1 13492.7 13611.0 13858.9 14027.5 15833.8 16038.6 16115.4 16352.4 17240.8 15182.0 15340.1 15495.4 15826.0 16388.0 14977.6 15035.6 15206.8 15316.3 15827.1 14656.9 14861.1 14996.6 15114.0 15146.6 12108.2 12338.4 12468.8 12473.1 12565.8 11834.9 11837.9 12076.5 12077.3 12581.9 22:49:38 INFO - PROCESS | 7145 | -------- Summary: end -------- 22:49:38 INFO - PROCESS | 7145 | 22:49:38 INFO - TEST-INFO | 7145: exit 0 22:49:38 INFO - TEST-OK | dromaeo_css | took 1109379ms 22:49:38 INFO - TEST-START | kraken 22:49:38 INFO - operating with platform_type : linux_ 22:49:38 INFO - Initialising browser for kraken test... 22:49:38 INFO - TEST-INFO | started process 7392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4Z1gTV/profile http://localhost:40730/getInfo.html) 22:49:43 INFO - PROCESS | 7392 | __metrics Screen width/height:1600/1200 22:49:43 INFO - PROCESS | 7392 | colorDepth:24 22:49:43 INFO - PROCESS | 7392 | Browser inner width/height: 1024/697 22:49:43 INFO - PROCESS | 7392 | __metrics 22:49:43 ERROR - PROCESS | 7392 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 22:49:44 INFO - TEST-INFO | 7392: exit 0 22:49:44 INFO - Browser initialized. 22:49:44 INFO - Running cycle 1/1 for kraken test... 22:49:44 INFO - TEST-INFO | started process 7521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4Z1gTV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:56 INFO - PROCESS | 7521 | RSS: Main: 156041216 22:49:56 INFO - PROCESS | 7521 | 22:49:58 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40730/tests/kraken/driver.html?testName=audio-beat-detection) 22:49:59 INFO - PROCESS | 7521 | RSS: Main: 161550336 22:49:59 INFO - PROCESS | 7521 | 22:50:02 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40730/tests/kraken/driver.html?testName=audio-dft) 22:50:02 INFO - PROCESS | 7521 | RSS: Main: 160731136 22:50:02 INFO - PROCESS | 7521 | 22:50:05 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40730/tests/kraken/driver.html?testName=audio-fft) 22:50:06 INFO - PROCESS | 7521 | RSS: Main: 161226752 22:50:06 INFO - PROCESS | 7521 | 22:50:08 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40730/tests/kraken/driver.html?testName=audio-oscillator) 22:50:09 INFO - PROCESS | 7521 | RSS: Main: 160673792 22:50:09 INFO - PROCESS | 7521 | 22:50:11 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40730/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:50:11 INFO - PROCESS | 7521 | RSS: Main: 161017856 22:50:11 INFO - PROCESS | 7521 | 22:50:15 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40730/tests/kraken/driver.html?testName=imaging-darkroom) 22:50:15 INFO - PROCESS | 7521 | RSS: Main: 161366016 22:50:15 INFO - PROCESS | 7521 | 22:50:19 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40730/tests/kraken/driver.html?testName=imaging-desaturate) 22:50:19 INFO - PROCESS | 7521 | RSS: Main: 157237248 22:50:19 INFO - PROCESS | 7521 | 22:50:22 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40730/tests/kraken/driver.html?testName=json-parse-financial) 22:50:23 INFO - PROCESS | 7521 | RSS: Main: 159248384 22:50:23 INFO - PROCESS | 7521 | 22:50:24 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40730/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:50:25 INFO - PROCESS | 7521 | RSS: Main: 160088064 22:50:25 INFO - PROCESS | 7521 | 22:50:27 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:50:27 INFO - PROCESS | 7521 | RSS: Main: 159866880 22:50:27 INFO - PROCESS | 7521 | 22:50:29 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:50:30 INFO - PROCESS | 7521 | RSS: Main: 160149504 22:50:30 INFO - PROCESS | 7521 | 22:50:33 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:50:33 INFO - PROCESS | 7521 | RSS: Main: 160329728 22:50:33 INFO - PROCESS | 7521 | 22:50:36 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:50:36 INFO - PROCESS | 7521 | RSS: Main: 160305152 22:50:36 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | Cycle 1(1): loaded http://localhost:40730/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40730/tests/kraken/driver.html?testName=ai-astar) 22:50:38 INFO - PROCESS | 7521 | RSS: Main: 160817152 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | __start_tp_report 22:50:38 INFO - PROCESS | 7521 | _x_x_mozilla_page_load 22:50:38 INFO - PROCESS | 7521 | _x_x_mozilla_page_load_details 22:50:38 INFO - PROCESS | 7521 | |i|pagename|runs| 22:50:38 INFO - PROCESS | 7521 | |0;ai-astar;112;106;111;110;109;113;112;111;111;112 22:50:38 INFO - PROCESS | 7521 | |1;audio-beat-detection;158;125;156;172;125;161;157;124;188;125 22:50:38 INFO - PROCESS | 7521 | |2;audio-dft;162;163;165;161;163;162;164;161;161;163 22:50:38 INFO - PROCESS | 7521 | |3;audio-fft;74;75;74;175;74;77;74;75;183;76 22:50:38 INFO - PROCESS | 7521 | |4;audio-oscillator;88;84;154;84;85;85;142;85;84;147 22:50:38 INFO - PROCESS | 7521 | |5;imaging-gaussian-blur;117;117;117;117;118;118;118;117;118;118 22:50:38 INFO - PROCESS | 7521 | |6;imaging-darkroom;149;146;147;146;148;146;147;147;148;147 22:50:38 INFO - PROCESS | 7521 | |7;imaging-desaturate;95;96;96;96;95;96;102;97;95;95 22:50:38 INFO - PROCESS | 7521 | |8;json-parse-financial;54;53;52;51;52;52;51;51;51;54 22:50:38 INFO - PROCESS | 7521 | |9;json-stringify-tinderbox;61;60;60;60;59;60;58;60;60;59 22:50:38 INFO - PROCESS | 7521 | |10;stanford-crypto-aes;81;75;76;79;76;82;77;83;110;82 22:50:38 INFO - PROCESS | 7521 | |11;stanford-crypto-ccm;122;124;129;116;118;133;119;127;123;127 22:50:38 INFO - PROCESS | 7521 | |12;stanford-crypto-pbkdf2;163;151;150;153;156;150;151;152;153;155 22:50:38 INFO - PROCESS | 7521 | |13;stanford-crypto-sha256-iterative;63;61;63;63;61;60;61;62;60;61 22:50:38 INFO - PROCESS | 7521 | __end_tp_report 22:50:38 INFO - PROCESS | 7521 | __start_cc_report 22:50:38 INFO - PROCESS | 7521 | _x_x_mozilla_cycle_collect,863 22:50:38 INFO - PROCESS | 7521 | __end_cc_report 22:50:38 INFO - PROCESS | 7521 | __startTimestamp1469685038795__endTimestamp 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | ------- Summary: start ------- 22:50:38 INFO - PROCESS | 7521 | Number of tests: 14 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.50 stddev:2.00 (1.8%) stddev-sans-first:2.07 22:50:38 INFO - PROCESS | 7521 | Values: 112.0 106.0 111.0 110.0 109.0 113.0 112.0 111.0 111.0 112.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:157.50 stddev:22.95 (14.6%) stddev-sans-first:24.12 22:50:38 INFO - PROCESS | 7521 | Values: 158.0 125.0 156.0 172.0 125.0 161.0 157.0 124.0 188.0 125.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 22:50:38 INFO - PROCESS | 7521 | Values: 162.0 163.0 165.0 161.0 163.0 162.0 164.0 161.0 161.0 163.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#3] audio-fft Cycles:10 Average:95.70 Median:74.50 stddev:43.95 (59.0%) stddev-sans-first:45.91 22:50:38 INFO - PROCESS | 7521 | Values: 74.0 75.0 74.0 175.0 74.0 77.0 74.0 75.0 183.0 76.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:84.50 stddev:30.43 (36.0%) stddev-sans-first:31.73 22:50:38 INFO - PROCESS | 7521 | Values: 88.0 84.0 154.0 84.0 85.0 85.0 142.0 85.0 84.0 147.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 22:50:38 INFO - PROCESS | 7521 | Values: 117.0 117.0 117.0 117.0 118.0 118.0 118.0 117.0 118.0 118.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.99 (0.7%) stddev-sans-first:0.78 22:50:38 INFO - PROCESS | 7521 | Values: 149.0 146.0 147.0 146.0 148.0 146.0 147.0 147.0 148.0 147.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:2.11 (2.2%) stddev-sans-first:2.19 22:50:38 INFO - PROCESS | 7521 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 102.0 97.0 95.0 95.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:1.20 (2.3%) stddev-sans-first:1.05 22:50:38 INFO - PROCESS | 7521 | Values: 54.0 53.0 52.0 51.0 52.0 52.0 51.0 51.0 51.0 54.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.73 22:50:38 INFO - PROCESS | 7521 | Values: 61.0 60.0 60.0 60.0 59.0 60.0 58.0 60.0 60.0 59.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:80.00 stddev:10.22 (12.8%) stddev-sans-first:10.84 22:50:38 INFO - PROCESS | 7521 | Values: 81.0 75.0 76.0 79.0 76.0 82.0 77.0 83.0 110.0 82.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:125.50 stddev:5.31 (4.2%) stddev-sans-first:5.59 22:50:38 INFO - PROCESS | 7521 | Values: 122.0 124.0 129.0 116.0 118.0 133.0 119.0 127.0 123.0 127.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.00 stddev:3.92 (2.6%) stddev-sans-first:2.12 22:50:38 INFO - PROCESS | 7521 | Values: 163.0 151.0 150.0 153.0 156.0 150.0 151.0 152.0 153.0 155.0 22:50:38 INFO - PROCESS | 7521 | 22:50:38 INFO - PROCESS | 7521 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:1.18 (1.9%) stddev-sans-first:1.12 22:50:38 INFO - PROCESS | 7521 | Values: 63.0 61.0 63.0 63.0 61.0 60.0 61.0 62.0 60.0 61.0 22:50:38 INFO - PROCESS | 7521 | -------- Summary: end -------- 22:50:38 INFO - PROCESS | 7521 | 22:50:39 INFO - TEST-INFO | 7521: exit 0 22:50:39 INFO - TEST-OK | kraken | took 60715ms 22:50:39 INFO - SUITE-END | took 1170s 22:50:39 INFO - Completed test suite (00:19:30) 22:50:39 INFO - javascript benchmark 22:50:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7659.420831914683, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.4300868852292, 3369.3092915952234, 3381.0364046246796, 3391.8304084795764, 3409.403354412979, 5527.115661494162, 5545.9999999999945, 5599.32808063032, 5603.523700485459, 5653.830385088454, 5638.605297629168, 5642.4357564243555, 5690.089585666295, 5698.658080515172, 5704.857378565538, 5122.871928201787, 5204.245384419257, 5225.111731005738, 5240.633155679104, 5253.028189784902, 5033.748312584375, 5048.368953880765, 5278.445763194867, 5279.86800329991, 5279.894402111955, 5105.438401775802, 5155.5360017598405, 5183.2743415921805, 5189.3772747270195, 5231.973840130794, 5115.9744201278945, 5146.639735218536, 5238.8690282742855, 5242.711650859202, 5258.81594144207, 5129.17933130701, 5176.430592634807, 5229.0, 5235.005348983681, 5265.894682106336, 5379.354477462691, 5467.0, 5474.42518535552, 5559.555235581144, 5588.804391846266, 6028.367021462727, 6078.665673387962, 6079.088136779475, 6082.330943596201, 6096.878062438726, 5992.0, 6137.324894261627, 6142.907856382158, 6147.385261473862, 6150.477209437203, 5598.468145526169, 5607.831765047055, 5626.127950167731, 5673.461021202979, 5686.09022556389, 6155.199824022849, 6167.321594624587, 6177.536684748662, 6200.441960223601, 6201.131841542187, 5804.709764511757, 5839.766409343663, 5877.970610146922, 5939.673317967467, 5941.613795103304, 5913.113033045078, 6015.54883383748, 6018.8194354169445, 6040.184575082396, 6058.939410605837, 5918.644881307136, 5990.161377407167, 5997.790077347272, 6000.969995149996, 6015.218021657157, 5902.8819423611285, 5938.138969849348, 5983.880322393615, 5997.970010149921, 6011.579189456696, 4607.585317321457, 4650.51169627191, 4651.697639653412, 4666.066786642617, 4681.882952926204, 4143.357779544175, 4149.170165966819, 4174.979125104355, 4183.874483765492, 4203.852865149705, 5741.052726300197, 5766.048601980626, 5799.3040835100055, 5814.709264536757, 5840.59115861894, 5927.911081333784, 6009.338972712998, 6025.0, 6072.39276072398, 6135.938640613626, 5798.362180160179, 5800.984827655144, 5833.300003999547, 5852.0, 5857.970710146422, 5088.6946783193125, 5221.7128057956415, 5227.529522343008, 5248.6325957182635, 5298.549623282064, 6321.804750811341, 6386.9680651596445, 6387.776427825004, 6402.135711678957, 6427.132337134521, 5928.110783382527, 6152.8154155376305, 6154.169187159676, 6160.229971253593, 6171.907421388593, 6197.25632924052, 6230.937690623217, 6263.561550691408, 6377.107204991212, 6380.266269378895, 5970.253718285215, 5973.611715238496, 5984.551158663205, 5984.700764961647, 6041.818745437644, 5933.703314834327, 5986.790462333972, 6074.635521868702, 6090.330063692902, 6103.725332359965, 5818.2436283282905, 5914.792982245601, 5919.141724450017, 5932.317783454958, 5977.5217982560425, 5870.17817505541, 5887.646741195542, 5926.4666180044005, 5936.7922122725495, 5941.940580594139, 5677.914831277617, 5769.105788602773, 5786.537077033911, 5791.623544469596, 5861.9706901465215, 5885.970570147293, 5931.406859314034, 5942.0, 5966.164736936746, 6029.969850150721, 5775.306963164448, 5784.132380143013, 5817.243758311392, 5853.9121913172185, 5862.765489380377], "value": 5591.4178460690055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.8590331272151, 644.3170239546082, 645.1999520594464, 661.6956200147935, 665.1253601514007, 34848.30303393934, 38204.0, 46595.5340446595, 47044.764776176155, 47306.0, 48210.75894620531, 48366.27450588235, 48707.269390959074, 48747.0, 48754.0, 9198.586063627135, 9329.626814927395, 9344.392614480072, 9350.90649093507, 9362.81274374512, 9155.313351498631, 9227.215686666641, 9250.028746981538, 9263.166315031645, 9267.629294828199, 9254.814903701921, 9275.907240927605, 9293.349465537414, 9295.349325547215, 9326.766830829216, 9178.219851312613, 9220.492872891988, 9242.075792420772, 9266.860997085016, 9301.953490232505, 9074.591643376063, 9132.36073474857, 9146.86279705805, 9155.313351498598, 9231.399959002643, 45654.77172614149, 45677.0, 45873.311900321525, 45930.54069459296, 46049.53950460519, 3356.3790698720813, 3408.233147541808, 3494.318607871466, 3543.698785603227, 3942.9014274643077, 34126.82936585301, 34848.0, 34853.651463485294, 35051.64948350509, 35989.10027249314, 4747.643926705477, 4832.251001094801, 4885.828995985124, 4895.6083513318845, 4932.728699921503, 364318.0, 364758.0, 365015.0, 366352.0, 367160.0, 65937.34062659409, 66174.66912665406, 66545.0, 66582.0, 66717.0, 38824.805875971004, 39636.60363396387, 39645.40531892024, 41401.1719765603, 41438.79280603638, 19023.714644280346, 19058.42824715261, 19236.71144932827, 19253.518662033282, 19311.324103656305, 14960.102393856403, 14987.250637468083, 15123.31945062475, 15192.392304307925, 15244.23778811055, 21373.893130534558, 21546.56906861854, 21652.566948660937, 21657.67513487299, 21710.782892170875, 7443.069616297963, 7465.776026719207, 7620.161782203953, 7741.61291935401, 7890.277854140312, 1065.4619417194267, 1089.493385575711, 1092.1481244629201, 1109.2457129152253, 1112.0880877680386, 32620.0, 32943.68225270962, 32951.67048329534, 33110.50334244988, 33290.334193316, 1192.7435601345753, 1217.0689422591724, 1221.8167274908658, 1234.8765123487758, 1255.0837888341562, 17173.31306747716, 17331.0, 17345.219465123846, 17390.47828565145, 17638.647227055644, 594406.0, 874416.0, 894482.0, 897095.0, 1044022.0, 59549.40450595525, 59748.506287342294, 59895.0, 60227.69886150629, 60470.697646512366, 1468.4640106361314, 1469.5003698742478, 1473.152937061194, 1479.7114562660392, 1482.7553453680023, 32237.35525289434, 32588.674113258563, 32823.34353312968, 33424.66575334215, 33971.320573588884, 52708.20937685861, 53212.0, 53233.46766532285, 53360.466395337105, 53544.46455535551, 378185.0, 379714.0, 381505.0, 381552.0, 382700.0, 29881.402371952874, 29916.0, 30370.696293037672, 30465.847670761505, 30662.0, 50965.745171273906, 51060.0, 51732.0, 52106.739466303945, 52709.0, 42430.36354454624, 43052.56947430611, 43732.34401484044, 43828.0, 44702.0, 41942.0, 42111.36832947447, 42144.0, 42727.359089613055, 42977.0, 42060.789696051324, 42525.78737106295, 43043.7847810759, 43608.34587481254, 43630.12739745124, 36985.260294793414, 37466.438003430514, 37827.43258851174, 37955.81022094872, 38272.80863595776, 28308.43383132367, 28706.856465717538, 28867.422651546432, 29002.41995160127, 29030.7096929028], "value": 21254.41790987777, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.8491380818385, 393.65555139253155, 395.94060890866365, 396.2589956780819, 419.50288907644443, 17771.933683978972, 18252.17865196066, 19933.202671893127, 19965.201391944323, 21221.0, 13110.213387196774, 13356.465741370333, 13402.195868247913, 18679.439616811484, 19490.512737181543, 5200.661956972804, 5361.0618141825025, 5379.300690910176, 5380.569554435636, 5589.832305030855, 5910.083936989773, 5938.792142275021, 5939.287285525743, 5942.910856337158, 5968.641881487103, 5499.037668408013, 5520.503154716075, 5857.238558987326, 5864.560157988147, 5910.083936989752, 5496.0931446311315, 5496.917546236809, 5505.256790333294, 5538.6122971391815, 5545.889082218353, 4705.55297246761, 4730.408698912636, 4834.371531700892, 5347.957148356072, 5379.5696344292755, 15107.39570417182, 15168.696626067416, 17129.743053854203, 23860.687662178745, 24060.759392406027, 2307.953840923189, 2413.3604594782396, 2414.1429792423787, 2485.117783186961, 2620.3694959566087, 10403.167746580255, 10562.15502759785, 10811.891881081247, 10823.891761082366, 11020.559177632886, 1971.3888694504749, 2014.3252010576475, 2025.7771645119026, 2079.0, 2082.5938941906334, 79465.20534794636, 82997.58501207516, 83337.99999999939, 83349.58325208396, 84777.57611211904, 11082.944585277022, 11186.216964812385, 11404.543818247177, 11809.350485723367, 11942.104342174198, 16370.672586548202, 16389.426370077228, 16427.83572164287, 16509.0, 16511.33954641825, 8578.313734901192, 8684.652613895374, 8760.0, 8861.113888611217, 8906.064863189402, 8364.790880228044, 8567.357448191411, 8585.441946273471, 8600.182982616641, 8736.781580460412, 4781.450133234653, 4793.089313030583, 4949.059678661043, 4985.8005679772405, 5056.873578160502, 2084.832493803475, 2101.0, 2126.5109024924354, 2129.8189653879285, 2147.3987283560627, 909.3134683314056, 1027.5632856036154, 1058.2433560004633, 1060.6764936694228, 1116.983245251322, 17548.824511754974, 19427.708584371245, 19624.70562941557, 19663.41009769709, 19841.107150178275, 1019.6329321444274, 1036.891126431729, 1078.44460103047, 1097.9011888930036, 1107.7452185997265, 10570.0, 11029.558817647365, 11142.944285278521, 11202.103831693445, 11221.607243746594, 495534.0, 500877.0, 501835.0, 509112.0, 521994.0, 98220.50889745506, 100450.99999999854, 102184.48907755563, 103118.48440757749, 103293.48353258186, 1062.665260442958, 1071.983920241197, 1075.8440026196158, 1083.7940791249637, 1093.868735751715, 17427.21577528989, 17626.73559896577, 17785.199666014803, 17796.110194490484, 17953.820461795738, 39765.2046959065, 41003.794981024905, 41286.38070428886, 41783.7910810444, 42165.0, 57208.42791572197, 58017.709911451864, 58790.41209587849, 58832.705836470544, 58997.70501147467, 7619.733309334147, 8726.896832772649, 8901.109889011048, 9090.36367454272, 9150.359474836963, 9460.0, 12377.876221237673, 13074.0, 13281.136726112773, 13472.0, 11024.952629500185, 11853.52585896589, 11879.0, 12002.579909703118, 12380.319082450551, 12042.157049006486, 12173.573924912585, 12190.57332993341, 12272.754544908874, 12562.560310389092, 12298.139130261154, 12333.444994975069, 12344.567940122053, 12469.75060498803, 12658.936705316415, 8971.865422018805, 9100.772480688036, 9161.70178735668, 9188.219001384958, 9296.674616388394, 9501.619935202514, 12224.44989975471, 12311.953483953606, 12361.0, 12512.499500019898], "value": 9346.854179282678, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1602.3110062673045, 1627.414130912871, 1861.972070418943, 1897.0988780329353, 1961.2841312920789, 8595.226429621332, 9136.0, 9240.445573265608, 9787.70636880893, 9848.310618256724, 9221.0, 9515.143637072662, 9590.088941550559, 9632.422054676725, 9640.710778676634, 2520.0549793827313, 2574.987125064372, 2583.638290639312, 2584.8707564621786, 2588.1588483742858, 2468.3829042739317, 2539.162076514746, 2542.084849454195, 2546.2106746908426, 2550.821442499026, 2491.713452952906, 2547.0957809977467, 2558.2708927955537, 2558.4115653399635, 2558.9104381346656, 2495.563276426618, 2508.059477695864, 2508.385445565848, 2510.8619025953567, 2548.808839337048, 2414.9034038638433, 2441.963370549443, 2452.938676533101, 2457.7665121813397, 2461.064795377763, 9577.138057574783, 9639.421634701941, 9659.951700241523, 9691.370060946016, 9695.466749328783, 5617.354004289518, 5706.971465142689, 5722.513586345166, 5727.656340619576, 5747.453991870766, 3631.310051090311, 3662.505561749156, 3718.0, 3766.4162054881535, 3795.411711184771, 1185.5731936502852, 1206.619914726867, 1224.1675660550823, 1225.650689553482, 1226.3193927370305, 13412.865871341259, 13461.259630720306, 13594.456221751117, 13644.0, 13658.521951731742, 4994.9500504994485, 5152.3044389007755, 5179.870503237449, 5193.1171700810955, 5213.921791173136, 11457.427128643534, 11557.88442115585, 11614.70963225926, 11706.063514918957, 11718.88281117195, 5870.853228669232, 5879.735411906389, 5881.9705901470215, 5885.882282354414, 5888.146218798251, 4779.928301075487, 5309.681419114865, 5372.677639341652, 5374.27447294618, 5493.835184944458, 4370.2352088384405, 4392.231359512072, 4395.099004704028, 4440.023194897118, 4441.977790111094, 1335.8797708206307, 1390.7149034448116, 1401.9228942408265, 1403.7964495148146, 1412.1738782811965, 928.3223247029706, 1105.4472763618191, 1108.661858133277, 1111.6942840718866, 1121.472907733364, 6565.80302590923, 6776.186857577024, 6818.4886133540185, 6847.657617119125, 6871.175458944958, 497.4677095507772, 507.0366304022313, 510.7752588860889, 516.9767360468788, 525.4404059676509, 4653.53464653532, 4672.462666793294, 4714.528547145326, 4723.409573803275, 4729.125111854318, 11575.479103440366, 11768.529258829722, 11815.822762658567, 11948.34284114382, 12156.0, 12159.756804863853, 12210.145289829627, 12224.877751222552, 12292.938535307267, 12311.507539698312, 8973.955130224307, 9021.50381728998, 9116.49859257734, 9131.543422828832, 9153.267738580898, 6593.967030164818, 6822.351876571718, 6828.351306625863, 6880.277570854988, 6884.552504087218, 11046.944765276123, 11079.50142243585, 11380.829287560528, 11413.029892459233, 11482.253653512495, 11441.542338306375, 11532.654020379403, 11535.134864885, 11599.478023489131, 11622.6513204604, 7997.320227780471, 8507.8723819144, 8589.613467394107, 8781.121887811169, 8883.777905552413, 9530.85703714431, 9589.472579008221, 9595.712128636153, 9609.567569459252, 9691.9030809691, 8529.829403411773, 8748.825023499367, 8789.42868713557, 8828.161324674145, 8843.159899809507, 8055.677772889018, 8214.178582141738, 8498.915010849812, 8517.148285171434, 8767.035626080999, 7595.5442673439775, 7611.923880761122, 7634.732784352521, 7677.539347639159, 7693.346065584488, 5356.330458692663, 5435.972820135874, 5462.535684466865, 5554.944450555443, 5632.662040277596, 5419.132938729785, 5619.634723742944, 5662.801801936913, 5702.059160238514, 5719.914201287067], "value": 5029.5952085379195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.69246294418286, 361.2341835309145, 361.8100497238951, 363.369553824115, 364.3496358999187, 5732.2261494698205, 5746.48281654651, 5747.0, 5749.425057494249, 5750.137479378095, 5546.972265138679, 5660.886782264352, 5662.433756624336, 5679.432056794319, 5684.317881854184, 4383.561643835623, 4384.758838263894, 4408.8016039278145, 4409.889752756175, 4432.157890000905, 4394.121175764843, 4410.71330363527, 4431.911361772763, 4462.620677242438, 4464.46426428829, 4386.8903277418, 4410.603045725884, 4424.358468022135, 4429.667774916878, 4439.66702497314, 4194.525230114232, 4325.307950727868, 4358.978205108986, 4375.6937014409, 4407.3388991651345, 3876.341022026234, 3881.7282790204695, 3890.144168282973, 3917.6865850731874, 3930.78380689062, 5424.891502169935, 5456.826782241797, 5517.475839795213, 5518.751656175482, 5519.558435325164, 4765.999140180566, 5048.419431765321, 5055.368078990126, 5067.797288108508, 5078.873028174288, 5931.555133365015, 5931.724679193994, 5954.196183515257, 5960.672163031031, 5973.163757074013, 4290.935635965463, 4299.333603291464, 4301.161273551658, 4342.174986752539, 4357.0, 5943.3165186003735, 5976.521878249773, 6137.386261373871, 6148.862460444832, 6206.875862482771, 4385.223984286369, 4402.911941761146, 4404.71369360997, 4421.955780442218, 4426.11477704454, 4459.175052615276, 4469.5530446955045, 4472.172648060055, 4476.7090139140855, 4479.843205487858, 4916.680415772963, 5045.839456924928, 5105.15764898795, 5117.155669314596, 5126.564242039469, 5932.843095596403, 5938.61399009063, 5942.316633587193, 5943.791967281124, 5975.701214939236, 1525.8245301790214, 1534.6470311828118, 1535.7466018106886, 1546.5283088658068, 1556.5719427157621, 932.9113734195377, 971.9368241064308, 976.9413835169912, 984.6061575369766, 993.096282383028, 5123.308353372247, 5164.638475306692, 5168.844934651891, 5197.22041693749, 5205.713685747321, 5318.335208098988, 5389.838304850782, 5403.8651883104985, 5415.079436495806, 5487.396386397493, 4971.45314015458, 5161.870953226125, 5164.664296820694, 5257.316548848626, 5300.814471493556, 2900.2894290898444, 2900.941981160407, 2904.840233787162, 2914.781391395654, 2918.2558447596152, 5671.773129074791, 5675.75807556013, 5695.857603559944, 5731.484166425042, 5737.799177028784, 5228.640553456053, 5420.864478387993, 5502.2296878437055, 5543.916841247385, 5557.944420555743, 5628.1427129047315, 5646.463585959327, 5678.886422271531, 5703.37453825666, 5742.253507044141, 5051.646384753032, 5401.702906340189, 5421.349438067457, 5442.782288708408, 5518.530924871431, 5376.489233522809, 5392.056390131633, 5417.485338892799, 5494.36814766307, 5919.822405327847, 6414.486841052798, 6415.390537898892, 6429.903551446639, 6454.774082907075, 6460.8707825842275, 4343.869683909487, 4366.476022877275, 4379.715318504287, 4392.824287028611, 4410.0, 6117.969410153099, 6121.908171377522, 6182.28903676083, 6192.380761923771, 6261.5616906816085, 5947.821565353046, 6017.819465416044, 6039.63762174271, 6046.637201767908, 6068.48287928018, 5631.577631677558, 5956.344802071855, 6004.669743164168, 6018.699065046818, 6045.879082418415, 5844.211031510862, 5881.382619779595, 5888.086371929226, 5900.616459930091, 5917.230760001173, 6017.939820601738, 6034.668093254913, 6040.275166979991, 6064.272287325549, 6082.2093127893095, 5605.467480589337, 5847.210626565359, 5862.94137058624, 5881.441263080001, 5900.144479050599], "value": 4429.137377960165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.3951269228272, 811.4279432999732, 854.3122786157143, 879.1428357351581, 918.7197904639082, 15876.12681302528, 16842.91578542107, 16965.660686786257, 17213.22540485678, 17332.826671733277, 18031.81968180318, 18224.362147324846, 18268.72596911044, 18278.0, 18285.634287314246, 867.8611422172454, 875.916787905148, 878.6836738774036, 879.0989236033085, 879.5865943006777, 856.9657213711444, 862.4954401675028, 871.960761765722, 887.1217494680285, 887.6493784954939, 919.9816003679922, 924.9537523123817, 925.9351845370827, 929.8558723397884, 930.8789857318573, 916.8533034714413, 919.691903212418, 922.2115091596655, 924.0943875002469, 925.6204955968054, 672.6872004517874, 677.00818301189, 677.5392732941598, 678.0270312102149, 680.7753441364388, 16882.493525194262, 17100.91449542744, 17103.828961710347, 17108.973461592344, 17162.57093572658, 15812.367505299775, 15942.0, 15993.200339982954, 16130.596735081717, 16315.673686526321, 12339.383030848421, 12851.550195743197, 12926.09517333787, 12936.676583085404, 12994.22034677913, 4009.8997525061814, 4097.836086556535, 4136.420901073852, 4144.088300573869, 4164.875053748392, 80057.59971200165, 82188.0, 83800.58099709524, 83979.0, 84185.57907210485, 10948.726281843017, 11850.170488065925, 12153.69615759613, 12401.875981240071, 12402.379881006094, 22830.086796528285, 22835.20076797304, 22867.656985145237, 22907.656385154238, 22961.655575166387, 8441.746747597583, 8770.46516859547, 8817.07420720828, 8885.693314759104, 8985.910140898639, 4956.107900577858, 5143.0, 5150.768215430315, 5175.741212939338, 5186.740662966837, 3930.3121953657997, 4035.8183881725395, 4066.755994640331, 4069.0234343757284, 4070.4504891839224, 659.4032400677395, 663.0485253661012, 664.1996394344803, 665.2150462454254, 674.416629615389, 753.1075675324757, 783.1268136028287, 790.2255789326377, 844.8943882014748, 847.7753395350237, 10560.419176945188, 10605.204609654307, 10612.310199836986, 10659.94670026645, 10799.244052916378, 710.9751158709421, 758.9772306830804, 774.9883751743729, 814.8248126652799, 851.7700220940311, 5084.872878178001, 5142.125838607446, 5213.661112027706, 5251.76367063483, 5262.394824595144, 98600.0, 113006.0, 113552.0, 120207.39896300463, 120391.0, 72880.63559682168, 99182.5040874791, 100576.49711751395, 101821.0, 102826.48586757018, 91540.08459915315, 91983.54008229917, 92734.53632731928, 93396.53301733448, 93675.53162234146, 10094.899051009395, 10363.378197307884, 10633.415162165846, 10660.786784264426, 10733.516991735547, 49260.50739492559, 49768.25347619716, 50428.74785626048, 51257.23114153216, 52446.0, 45536.77231613953, 45728.31407528956, 79066.0, 79429.60285198537, 79432.0, 13168.078234523491, 13492.662683432993, 13611.0, 13858.930705346409, 14027.50903718365, 15833.841661583238, 16038.589387918415, 16115.435959741353, 16352.427665031666, 17240.827591723923, 15182.0, 15340.079595223875, 15495.380184792482, 15826.0, 16388.0167189969, 14977.625559361104, 15035.624109397353, 15206.847931520542, 15316.310766015778, 15827.050376977419, 14656.853431465548, 14861.108333499593, 14996.550103496913, 15114.0, 15146.621334466727, 12108.152225302121, 12338.444769985193, 12468.812967805308, 12473.126881118234, 12565.811512827499, 11834.881651183723, 11837.940810295893, 12076.51693932233, 12077.335746534025, 12581.87418125807], "value": 8159.1476124224555, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 110.0, 109.0, 113.0, 112.0, 111.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 156.0, 172.0, 125.0, 161.0, 157.0, 124.0, 188.0, 125.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 165.0, 161.0, 163.0, 162.0, 164.0, 161.0, 161.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 175.0, 74.0, 77.0, 74.0, 75.0, 183.0, 76.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 154.0, 84.0, 85.0, 85.0, 142.0, 85.0, 84.0, 147.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 147.0, 146.0, 148.0, 146.0, 147.0, 147.0, 148.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 102.0, 97.0, 95.0, 95.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 51.0, 52.0, 52.0, 51.0, 51.0, 51.0, 54.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 59.0, 60.0, 58.0, 60.0, 60.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 76.0, 79.0, 76.0, 82.0, 77.0, 83.0, 110.0, 82.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 124.0, 129.0, 116.0, 118.0, 133.0, 119.0, 127.0, 123.0, 127.0], "value": 123.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 151.0, 150.0, 153.0, 156.0, 150.0, 151.0, 152.0, 153.0, 155.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 63.0, 63.0, 61.0, 60.0, 61.0, 62.0, 60.0, 61.0], "value": 61.5, "unit": "score"}]}]} 22:50:40 INFO - Return code: 0 22:50:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:50:40 ERROR - # TBPL SUCCESS # 22:50:40 INFO - Running post-action listener: _resource_record_post_action 22:50:40 INFO - ##### 22:50:40 INFO - ##### Finished run-tests step (success) 22:50:40 INFO - ##### 22:50:40 INFO - Running post-run listener: _resource_record_post_run 22:50:40 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 299008; Write bytes: 507633664; Read time: 388; Write time: 366496 22:50:40 INFO - TinderboxPrint: CPU usage
13.4% 22:50:40 INFO - TinderboxPrint: I/O read bytes / time
299,008 / 388 22:50:40 INFO - TinderboxPrint: I/O write bytes / time
507,633,664 / 366,496 22:50:40 INFO - TinderboxPrint: CPU idle
8,199.5 (86.6%) 22:50:40 INFO - TinderboxPrint: CPU user
1,217.0 (12.8%) 22:50:40 INFO - TinderboxPrint: Swap in / out
0 / 0 22:50:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 252563456; Read time: 180; Write time: 214556 22:50:40 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 270336; Write bytes: 255070208; Read time: 200; Write time: 151940 22:50:40 INFO - Running post-run listener: _upload_blobber_files 22:50:40 INFO - Blob upload gear active. 22:50:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:50:40 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 22:50:40 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:50:40 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:50:41 INFO - (blobuploader) - INFO - Open directory for files ... 22:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:41 INFO - (blobuploader) - INFO - Done attempting. 22:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:47 INFO - (blobuploader) - INFO - Done attempting. 22:50:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:50:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:47 INFO - (blobuploader) - INFO - Done attempting. 22:50:47 INFO - (blobuploader) - INFO - Iteration through files over. 22:50:48 INFO - Return code: 0 22:50:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:50:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:50:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7eb64f9db85ddff81f2a6af73f2bf00af31b84116de308e5e16e0112696f78205086893c287990256c180d31f3c474965f45d9f16ed8abb3006a72d05b72b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18750b94fac717e452781eb3731f07ea144d1c8145e7720d3c98409b226e39c570e7f99d78a6ceff52962f689562609055c49fb23acfb03d7ffc0a2821558a4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a9eac93a4fdce19bda3047f306d7ac0956ea9dd3570f35b9f9afa4365ee2ea3a247321b4515274f1668735e2e41e8183d1021a053965acbb928b8c247b1c4b"} 22:50:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:50:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:50:48 INFO - Contents: 22:50:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7eb64f9db85ddff81f2a6af73f2bf00af31b84116de308e5e16e0112696f78205086893c287990256c180d31f3c474965f45d9f16ed8abb3006a72d05b72b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18750b94fac717e452781eb3731f07ea144d1c8145e7720d3c98409b226e39c570e7f99d78a6ceff52962f689562609055c49fb23acfb03d7ffc0a2821558a4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a9eac93a4fdce19bda3047f306d7ac0956ea9dd3570f35b9f9afa4365ee2ea3a247321b4515274f1668735e2e41e8183d1021a053965acbb928b8c247b1c4b"} 22:50:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:50:48 INFO - Copying logs to upload dir... 22:50:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:50:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.868477 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-27 22:50:47.842038) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:47.845832) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939 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/sha512/f7eb64f9db85ddff81f2a6af73f2bf00af31b84116de308e5e16e0112696f78205086893c287990256c180d31f3c474965f45d9f16ed8abb3006a72d05b72b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18750b94fac717e452781eb3731f07ea144d1c8145e7720d3c98409b226e39c570e7f99d78a6ceff52962f689562609055c49fb23acfb03d7ffc0a2821558a4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a9eac93a4fdce19bda3047f306d7ac0956ea9dd3570f35b9f9afa4365ee2ea3a247321b4515274f1668735e2e41e8183d1021a053965acbb928b8c247b1c4b"} build_url:https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011406 build_url: 'https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7eb64f9db85ddff81f2a6af73f2bf00af31b84116de308e5e16e0112696f78205086893c287990256c180d31f3c474965f45d9f16ed8abb3006a72d05b72b59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18750b94fac717e452781eb3731f07ea144d1c8145e7720d3c98409b226e39c570e7f99d78a6ceff52962f689562609055c49fb23acfb03d7ffc0a2821558a4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a9eac93a4fdce19bda3047f306d7ac0956ea9dd3570f35b9f9afa4365ee2ea3a247321b4515274f1668735e2e41e8183d1021a053965acbb928b8c247b1c4b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:47.873078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:47.873509) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469682571.936091-1059233939 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:47.957600) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 22:50:47.957968) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 22:50:47.958379) ========= ========= Total master_lag: 0.59 =========