builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-064 starttime: 1466226025.73 results: success (0) buildid: 20160617203624 builduid: 03bd6c32d55a469ca26f3389a5956bdd revision: 3ce53bd1e25b93140484d3933c9339a829e0c1eb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:25.727737) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:25.728205) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:25.728495) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:25.831049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:25.831303) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-17 22:00:27-- 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% 42.5M=0s 2016-06-17 22:00:27 (42.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146982 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.000191) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.000476) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022450 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.054820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.055166) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3ce53bd1e25b93140484d3933c9339a829e0c1eb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3ce53bd1e25b93140484d3933c9339a829e0c1eb --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=a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-17 22:00:27,626 truncating revision to first 12 chars 2016-06-17 22:00:27,626 Setting DEBUG logging. 2016-06-17 22:00:27,626 attempt 1/10 2016-06-17 22:00:27,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3ce53bd1e25b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-17 22:00:27,931 unpacking tar archive at: mozilla-central-3ce53bd1e25b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.690049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.757969) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.758239) ========= script_repo_revision: 3ce53bd1e25b93140484d3933c9339a829e0c1eb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.758601) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.758913) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-17 22:00:26.770037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-17 22:00:26.770305) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:28 INFO - MultiFileLogger online at 20160617 22:00:28 in /builds/slave/test 22:00:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:00:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:28 INFO - {'append_to_log': False, 22:00:28 INFO - 'base_work_dir': '/builds/slave/test', 22:00:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:00:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:28 INFO - 'branch': 'Firefox-Non-PGO', 22:00:28 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:28 INFO - 'config_files': ('talos/linux_config.py',), 22:00:28 INFO - 'default_actions': ('clobber', 22:00:28 INFO - 'read-buildbot-config', 22:00:28 INFO - 'download-and-extract', 22:00:28 INFO - 'populate-webroot', 22:00:28 INFO - 'create-virtualenv', 22:00:28 INFO - 'install', 22:00:28 INFO - 'run-tests'), 22:00:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:28 INFO - 'download_minidump_stackwalk': True, 22:00:28 INFO - 'download_symbols': 'ondemand', 22:00:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:28 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:28 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:28 INFO - 'installer_path': 'installer.exe', 22:00:28 INFO - 'log_level': 'info', 22:00:28 INFO - 'log_name': 'talos', 22:00:28 INFO - 'log_to_console': True, 22:00:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:28 INFO - 'opt_config_files': (), 22:00:28 INFO - 'pip_index': False, 22:00:28 INFO - 'sps_profile': False, 22:00:28 INFO - 'sps_profile_interval': 0, 22:00:28 INFO - 'suite': 'dromaeojs-e10s', 22:00:28 INFO - 'system_bits': '32', 22:00:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:28 INFO - 'title': 'talos-linux64-ix-064', 22:00:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:28 INFO - 'use_talos_json': True, 22:00:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:28 INFO - 'work_dir': 'build'} 22:00:28 INFO - ##### 22:00:28 INFO - ##### Running clobber step. 22:00:28 INFO - ##### 22:00:28 INFO - Running pre-action listener: _resource_record_pre_action 22:00:28 INFO - Running main action method: clobber 22:00:28 INFO - rmtree: /builds/slave/test/build 22:00:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:29 INFO - Running post-action listener: _resource_record_post_action 22:00:29 INFO - ##### 22:00:29 INFO - ##### Finished clobber step (success) 22:00:29 INFO - ##### 22:00:29 INFO - ##### 22:00:29 INFO - ##### Running read-buildbot-config step. 22:00:29 INFO - ##### 22:00:29 INFO - Running pre-action listener: _resource_record_pre_action 22:00:29 INFO - Running main action method: read_buildbot_config 22:00:29 INFO - Using buildbot properties: 22:00:29 INFO - { 22:00:29 INFO - "project": "", 22:00:29 INFO - "product": "firefox", 22:00:29 INFO - "script_repo_revision": "production", 22:00:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:00:29 INFO - "repository": "", 22:00:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 22:00:29 INFO - "buildid": "20160617203624", 22:00:29 INFO - "pgo_build": "False", 22:00:29 INFO - "basedir": "/builds/slave/test", 22:00:29 INFO - "buildnumber": 349, 22:00:29 INFO - "slavename": "talos-linux64-ix-064", 22:00:29 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:00:29 INFO - "platform": "ubuntu64_hw", 22:00:29 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 22:00:29 INFO - "branch": "mozilla-central", 22:00:29 INFO - "repo_path": "mozilla-central", 22:00:29 INFO - "revision": "3ce53bd1e25b93140484d3933c9339a829e0c1eb", 22:00:29 INFO - "stage_platform": "linux64", 22:00:29 INFO - "builduid": "03bd6c32d55a469ca26f3389a5956bdd", 22:00:29 INFO - "slavebuilddir": "test" 22:00:29 INFO - } 22:00:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:00:29 INFO - Running post-action listener: _resource_record_post_action 22:00:29 INFO - ##### 22:00:29 INFO - ##### Finished read-buildbot-config step (success) 22:00:29 INFO - ##### 22:00:29 INFO - ##### 22:00:29 INFO - ##### Running download-and-extract step. 22:00:29 INFO - ##### 22:00:29 INFO - Running pre-action listener: _resource_record_pre_action 22:00:29 INFO - Running main action method: download_and_extract 22:00:29 INFO - mkdir: /builds/slave/test/build/tests 22:00:29 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:00:29 INFO - https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:00:38 INFO - Downloaded 1361 bytes. 22:00:38 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:38 INFO - Using the following test package requirements: 22:00:38 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:38 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:38 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:38 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'jsshell-linux-x86_64.zip'], 22:00:38 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:38 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:38 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:38 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:38 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:38 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00: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:00:38 INFO - https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:39 INFO - Downloaded 22654556 bytes. 22:00:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:40 INFO - Return code: 0 22:00:40 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:00:40 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:00:40 INFO - https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:44 INFO - Downloaded 22654556 bytes. 22:00:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:46 INFO - Return code: 0 22:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:00:46 INFO - https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:00:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:00:47 INFO - Downloaded 14405384 bytes. 22:00:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:00:47 INFO - Return code: 0 22:00:47 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:00:47 INFO - https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:00:48 INFO - Downloaded 56218805 bytes. 22:00:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:48 INFO - mkdir: /builds/slave/test/properties 22:00:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:00:48 INFO - Writing to file /builds/slave/test/properties/build_url 22:00:48 INFO - Contents: 22:00:48 INFO - build_url:https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:49 INFO - Running post-action listener: _resource_record_post_action 22:00:49 INFO - Running post-action listener: set_extra_try_arguments 22:00:49 INFO - ##### 22:00:49 INFO - ##### Finished download-and-extract step (success) 22:00:49 INFO - ##### 22:00:49 INFO - ##### 22:00:49 INFO - ##### Running populate-webroot step. 22:00:49 INFO - ##### 22:00:49 INFO - Running pre-action listener: _resource_record_pre_action 22:00:49 INFO - Running main action method: populate_webroot 22:00:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:49 INFO - '--tppagecycles', 22:00:49 INFO - '7'], 22:00:49 INFO - 'tests': ['tsvgm']}}, 22:00:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:00:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:00:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:00:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['damp', 'tps']}, 22:00:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'tests': ['damp', 'tps']}, 22:00:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['dromaeo_dom']}, 22:00:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['basic_compositor_video']}, 22:00:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:00:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['a11yr', 22:00:49 INFO - 'ts_paint', 22:00:49 INFO - 'tpaint', 22:00:49 INFO - 'sessionrestore', 22:00:49 INFO - 'sessionrestore_no_auto_restore', 22:00:49 INFO - 'tabpaint']}, 22:00:49 INFO - 'other-e10s': {'tests': ['a11yr', 22:00:49 INFO - 'ts_paint', 22:00:49 INFO - 'tpaint', 22:00:49 INFO - 'sessionrestore', 22:00:49 INFO - 'sessionrestore_no_auto_restore', 22:00:49 INFO - 'tabpaint']}, 22:00:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:00:49 INFO - 'ts_paint', 22:00:49 INFO - 'tpaint', 22:00:49 INFO - 'sessionrestore', 22:00:49 INFO - 'sessionrestore_no_auto_restore', 22:00:49 INFO - 'tabpaint']}, 22:00:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:00:49 INFO - 'ts_paint', 22:00:49 INFO - 'tpaint', 22:00:49 INFO - 'sessionrestore', 22:00:49 INFO - 'sessionrestore_no_auto_restore', 22:00:49 INFO - 'tabpaint']}, 22:00:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['a11yr', 22:00:49 INFO - 'ts_paint', 22:00:49 INFO - 'tpaint', 22:00:49 INFO - 'sessionrestore', 22:00:49 INFO - 'sessionrestore_no_auto_restore', 22:00:49 INFO - 'tabpaint']}, 22:00:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['a11yr', 22:00:49 INFO - 'ts_paint', 22:00:49 INFO - 'tpaint', 22:00:49 INFO - 'sessionrestore', 22:00:49 INFO - 'sessionrestore_no_auto_restore', 22:00:49 INFO - 'tabpaint']}, 22:00:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['tsvgx', 22:00:49 INFO - 'tsvgr_opacity', 22:00:49 INFO - 'tart', 22:00:49 INFO - 'tscrollx', 22:00:49 INFO - 'cart']}, 22:00:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:00:49 INFO - 'tsvgr_opacity', 22:00:49 INFO - 'tart', 22:00:49 INFO - 'tscrollx', 22:00:49 INFO - 'cart']}, 22:00:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'talos_options': ['--disable-e10s'], 22:00:49 INFO - 'tests': ['tp5o']}, 22:00:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'tests': ['tp5o']}, 22:00:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'talos_options': ['--disable-e10s', 22:00:49 INFO - '--xperf_path', 22:00:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:49 INFO - 'tests': ['tp5n']}, 22:00:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:49 INFO - 'talos_options': ['--xperf_path', 22:00:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:49 INFO - 'tests': ['tp5n']}}, 22:00:49 INFO - 'talos.zip': {'path': '', 22:00:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:49 INFO - Running post-action listener: _resource_record_post_action 22:00:49 INFO - ##### 22:00:49 INFO - ##### Finished populate-webroot step (success) 22:00:49 INFO - ##### 22:00:49 INFO - ##### 22:00:49 INFO - ##### Running create-virtualenv step. 22:00:49 INFO - ##### 22:00:49 INFO - Running pre-action listener: _resource_record_pre_action 22:00:49 INFO - Running main action method: create_virtualenv 22:00:49 INFO - Creating virtualenv /builds/slave/test/build/venv 22:00:49 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:00:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:00:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:00:49 INFO - Using real prefix '/usr' 22:00:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:00:50 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:52 INFO - Installing pip.................done. 22:00:52 INFO - Return code: 0 22:00:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00:52 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:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 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:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:00:52 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:00:52 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:00:52 INFO - Using env: {'DISPLAY': ':0', 22:00:52 INFO - 'HOME': '/home/cltbld', 22:00:52 INFO - 'LANG': 'en_US.UTF-8', 22:00:52 INFO - 'LANGUAGE': 'en_US:en', 22:00:52 INFO - 'LOGNAME': 'cltbld', 22:00:52 INFO - 'MAIL': '/var/mail/cltbld', 22:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:52 INFO - 'MOZ_NO_REMOTE': '1', 22:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:52 INFO - 'NO_EM_RESTART': '1', 22:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:52 INFO - 'PWD': '/builds/slave/test', 22:00:52 INFO - 'SHELL': '/bin/bash', 22:00:52 INFO - 'SHLVL': '1', 22:00:52 INFO - 'TERM': 'linux', 22:00:52 INFO - 'TMOUT': '86400', 22:00:52 INFO - 'USER': 'cltbld', 22:00:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:52 INFO - '_': '/tools/buildbot/bin/python'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 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:00:52 INFO - Cleaning up... 22:00:52 INFO - Return code: 0 22:00:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:00:52 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:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 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:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:00:52 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:00:52 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:00:52 INFO - Using env: {'DISPLAY': ':0', 22:00:52 INFO - 'HOME': '/home/cltbld', 22:00:52 INFO - 'LANG': 'en_US.UTF-8', 22:00:52 INFO - 'LANGUAGE': 'en_US:en', 22:00:52 INFO - 'LOGNAME': 'cltbld', 22:00:52 INFO - 'MAIL': '/var/mail/cltbld', 22:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:52 INFO - 'MOZ_NO_REMOTE': '1', 22:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:52 INFO - 'NO_EM_RESTART': '1', 22:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:52 INFO - 'PWD': '/builds/slave/test', 22:00:52 INFO - 'SHELL': '/bin/bash', 22:00:52 INFO - 'SHLVL': '1', 22:00:52 INFO - 'TERM': 'linux', 22:00:52 INFO - 'TMOUT': '86400', 22:00:52 INFO - 'USER': 'cltbld', 22:00:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:52 INFO - '_': '/tools/buildbot/bin/python'} 22:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:52 INFO - Downloading/unpacking psutil>=3.1.1 22:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:52 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:00:52 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:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:54 INFO - Installing collected packages: psutil 22:00:54 INFO - Running setup.py install for psutil 22:00:54 INFO - building 'psutil._psutil_linux' extension 22:00:54 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:00:54 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:00:54 INFO - building 'psutil._psutil_posix' extension 22:00:54 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:00:55 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:00:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:55 INFO - Successfully installed psutil 22:00:55 INFO - Cleaning up... 22:00:55 INFO - Return code: 0 22:00:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:55 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:00:55 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:00:55 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:00:55 INFO - Using env: {'DISPLAY': ':0', 22:00:55 INFO - 'HOME': '/home/cltbld', 22:00:55 INFO - 'LANG': 'en_US.UTF-8', 22:00:55 INFO - 'LANGUAGE': 'en_US:en', 22:00:55 INFO - 'LOGNAME': 'cltbld', 22:00:55 INFO - 'MAIL': '/var/mail/cltbld', 22:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:55 INFO - 'MOZ_NO_REMOTE': '1', 22:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:55 INFO - 'NO_EM_RESTART': '1', 22:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:55 INFO - 'PWD': '/builds/slave/test', 22:00:55 INFO - 'SHELL': '/bin/bash', 22:00:55 INFO - 'SHLVL': '1', 22:00:55 INFO - 'TERM': 'linux', 22:00:55 INFO - 'TMOUT': '86400', 22:00:55 INFO - 'USER': 'cltbld', 22:00:55 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:55 INFO - '_': '/tools/buildbot/bin/python'} 22:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:00:55 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:00:55 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:00:55 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:00:55 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:00:55 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:00:55 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:00:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:00:57 INFO - Installing collected packages: mozsystemmonitor 22:00:57 INFO - Running setup.py install for mozsystemmonitor 22:00:57 INFO - Successfully installed mozsystemmonitor 22:00:57 INFO - Cleaning up... 22:00:57 INFO - Return code: 0 22:00:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:57 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:00:57 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:00:57 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:00:57 INFO - Using env: {'DISPLAY': ':0', 22:00:57 INFO - 'HOME': '/home/cltbld', 22:00:57 INFO - 'LANG': 'en_US.UTF-8', 22:00:57 INFO - 'LANGUAGE': 'en_US:en', 22:00:57 INFO - 'LOGNAME': 'cltbld', 22:00:57 INFO - 'MAIL': '/var/mail/cltbld', 22:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:57 INFO - 'MOZ_NO_REMOTE': '1', 22:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:57 INFO - 'NO_EM_RESTART': '1', 22:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:57 INFO - 'PWD': '/builds/slave/test', 22:00:57 INFO - 'SHELL': '/bin/bash', 22:00:57 INFO - 'SHLVL': '1', 22:00:57 INFO - 'TERM': 'linux', 22:00:57 INFO - 'TMOUT': '86400', 22:00:57 INFO - 'USER': 'cltbld', 22:00:57 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:57 INFO - '_': '/tools/buildbot/bin/python'} 22:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:57 INFO - Downloading/unpacking jsonschema==2.5.1 22:00:57 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:00:57 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:00:57 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:00:57 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:00:57 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:00:57 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:00:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:59 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:59 INFO - warning: no files found matching '*.txt' 22:00:59 INFO - no previously-included directories found matching 'build' 22:00:59 INFO - no previously-included directories found matching 'dist' 22:00:59 INFO - no previously-included directories found matching '.git*' 22:00:59 INFO - Installing collected packages: jsonschema, functools32 22:00:59 INFO - Running setup.py install for functools32 22:01:00 INFO - warning: no files found matching '*.txt' 22:01:00 INFO - no previously-included directories found matching 'build' 22:01:00 INFO - no previously-included directories found matching 'dist' 22:01:00 INFO - no previously-included directories found matching '.git*' 22:01:00 INFO - Successfully installed jsonschema functools32 22:01:00 INFO - Cleaning up... 22:01:00 INFO - Return code: 0 22:01:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01:00 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:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:00 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:01:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:00 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:01:00 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:01:00 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:01:00 INFO - Using env: {'DISPLAY': ':0', 22:01:00 INFO - 'HOME': '/home/cltbld', 22:01:00 INFO - 'LANG': 'en_US.UTF-8', 22:01:00 INFO - 'LANGUAGE': 'en_US:en', 22:01:00 INFO - 'LOGNAME': 'cltbld', 22:01:00 INFO - 'MAIL': '/var/mail/cltbld', 22:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:00 INFO - 'MOZ_NO_REMOTE': '1', 22:01:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:00 INFO - 'NO_EM_RESTART': '1', 22:01:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:00 INFO - 'PWD': '/builds/slave/test', 22:01:00 INFO - 'SHELL': '/bin/bash', 22:01:00 INFO - 'SHLVL': '1', 22:01:00 INFO - 'TERM': 'linux', 22:01:00 INFO - 'TMOUT': '86400', 22:01:00 INFO - 'USER': 'cltbld', 22:01:00 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:00 INFO - '_': '/tools/buildbot/bin/python'} 22:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:00 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:00 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:01:00 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:01:00 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:01:00 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:01:00 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:01:00 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:01:02 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:02 INFO - Downloading docopt-0.6.1.tar.gz 22:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:02 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:02 INFO - Running setup.py install for blobuploader 22:01:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:03 INFO - Running setup.py install for requests 22:01:03 INFO - Running setup.py install for docopt 22:01:03 INFO - Successfully installed blobuploader requests docopt 22:01:03 INFO - Cleaning up... 22:01:03 INFO - Return code: 0 22:01:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:03 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:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:03 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:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:03 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:01:03 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:01:03 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:01:03 INFO - Using env: {'DISPLAY': ':0', 22:01:03 INFO - 'HOME': '/home/cltbld', 22:01:03 INFO - 'LANG': 'en_US.UTF-8', 22:01:03 INFO - 'LANGUAGE': 'en_US:en', 22:01:03 INFO - 'LOGNAME': 'cltbld', 22:01:03 INFO - 'MAIL': '/var/mail/cltbld', 22:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:03 INFO - 'MOZ_NO_REMOTE': '1', 22:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:03 INFO - 'NO_EM_RESTART': '1', 22:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:03 INFO - 'PWD': '/builds/slave/test', 22:01:03 INFO - 'SHELL': '/bin/bash', 22:01:03 INFO - 'SHLVL': '1', 22:01:03 INFO - 'TERM': 'linux', 22:01:03 INFO - 'TMOUT': '86400', 22:01:03 INFO - 'USER': 'cltbld', 22:01:03 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:03 INFO - '_': '/tools/buildbot/bin/python'} 22:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:03 INFO - Running setup.py (path:/tmp/pip-cCalv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:03 INFO - Running setup.py (path:/tmp/pip-HgDW0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:03 INFO - Running setup.py (path:/tmp/pip-88DD8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:03 INFO - Running setup.py (path:/tmp/pip-5rnK8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:04 INFO - Running setup.py (path:/tmp/pip-CWndJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:04 INFO - Running setup.py (path:/tmp/pip-cy7vf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:04 INFO - Running setup.py (path:/tmp/pip-8QeRw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:04 INFO - Running setup.py (path:/tmp/pip-ecGQyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:04 INFO - Running setup.py (path:/tmp/pip-lM0YPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:04 INFO - Running setup.py (path:/tmp/pip-DMG2lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:04 INFO - Running setup.py (path:/tmp/pip-mK68zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:04 INFO - Running setup.py (path:/tmp/pip-VwxmbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:04 INFO - Running setup.py (path:/tmp/pip-y6ajVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:04 INFO - Running setup.py (path:/tmp/pip-urDsUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:04 INFO - Running setup.py (path:/tmp/pip-joyTOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:04 INFO - Running setup.py (path:/tmp/pip-zOOgiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:04 INFO - Running setup.py (path:/tmp/pip-aLsiAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:04 INFO - Running setup.py install for manifestparser 22:01:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Running setup.py install for mozcrash 22:01:05 INFO - Running setup.py install for mozdebug 22:01:05 INFO - Running setup.py install for mozdevice 22:01:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Running setup.py install for mozfile 22:01:05 INFO - Running setup.py install for mozhttpd 22:01:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Running setup.py install for mozinfo 22:01:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Running setup.py install for mozInstall 22:01:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:05 INFO - Running setup.py install for mozleak 22:01:05 INFO - Running setup.py install for mozlog 22:01:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Running setup.py install for moznetwork 22:01:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Running setup.py install for mozprocess 22:01:06 INFO - Running setup.py install for mozprofile 22:01:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Running setup.py install for mozrunner 22:01:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Running setup.py install for mozscreenshot 22:01:06 INFO - Running setup.py install for moztest 22:01:06 INFO - Running setup.py install for mozversion 22:01:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:06 INFO - Cleaning up... 22:01:06 INFO - Return code: 0 22:01:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:06 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:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:06 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:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:06 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:01:06 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:01:06 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:01:06 INFO - Using env: {'DISPLAY': ':0', 22:01:06 INFO - 'HOME': '/home/cltbld', 22:01:06 INFO - 'LANG': 'en_US.UTF-8', 22:01:06 INFO - 'LANGUAGE': 'en_US:en', 22:01:06 INFO - 'LOGNAME': 'cltbld', 22:01:06 INFO - 'MAIL': '/var/mail/cltbld', 22:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:06 INFO - 'MOZ_NO_REMOTE': '1', 22:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:06 INFO - 'NO_EM_RESTART': '1', 22:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:06 INFO - 'PWD': '/builds/slave/test', 22:01:06 INFO - 'SHELL': '/bin/bash', 22:01:06 INFO - 'SHLVL': '1', 22:01:06 INFO - 'TERM': 'linux', 22:01:06 INFO - 'TMOUT': '86400', 22:01:06 INFO - 'USER': 'cltbld', 22:01:06 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:06 INFO - '_': '/tools/buildbot/bin/python'} 22:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:06 INFO - Running setup.py (path:/tmp/pip-gfnQts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:07 INFO - Running setup.py (path:/tmp/pip-Xk60eY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:07 INFO - Running setup.py (path:/tmp/pip-IxkHeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:07 INFO - Running setup.py (path:/tmp/pip-y5iDhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:07 INFO - Running setup.py (path:/tmp/pip-HmclBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:07 INFO - Running setup.py (path:/tmp/pip-HTJE70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:07 INFO - Running setup.py (path:/tmp/pip-O_Vr5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:07 INFO - Running setup.py (path:/tmp/pip-pmhqU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:07 INFO - Running setup.py (path:/tmp/pip-3zWZdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:07 INFO - Running setup.py (path:/tmp/pip-oB4Zoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:07 INFO - Running setup.py (path:/tmp/pip-yiIk_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:07 INFO - Running setup.py (path:/tmp/pip-IlXxwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:07 INFO - Running setup.py (path:/tmp/pip-lH19Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:07 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:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:07 INFO - Running setup.py (path:/tmp/pip-nPFHLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:08 INFO - Running setup.py (path:/tmp/pip-mXhaG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:08 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:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:08 INFO - Running setup.py (path:/tmp/pip-aZNDlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:08 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:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:08 INFO - Running setup.py (path:/tmp/pip-Gyqny4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:08 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:01:08 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:01:08 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:01:08 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:01:08 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:01:08 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:01:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:10 INFO - Downloading blessings-1.6.tar.gz 22:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:10 INFO - Installing collected packages: blessings 22:01:10 INFO - Running setup.py install for blessings 22:01:10 INFO - Successfully installed blessings 22:01:10 INFO - Cleaning up... 22:01:10 INFO - Return code: 0 22:01:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:10 INFO - Reading from file tmpfile_stdout 22:01:10 INFO - Current package versions: 22:01:10 INFO - argparse == 1.2.1 22:01:10 INFO - blessings == 1.6 22:01:10 INFO - blobuploader == 1.2.4 22:01:10 INFO - docopt == 0.6.1 22:01:10 INFO - functools32 == 3.2.3-2 22:01:10 INFO - jsonschema == 2.5.1 22:01:10 INFO - manifestparser == 1.1 22:01:10 INFO - mozInstall == 1.12 22:01:10 INFO - mozcrash == 0.17 22:01:10 INFO - mozdebug == 0.1 22:01:10 INFO - mozdevice == 0.48 22:01:10 INFO - mozfile == 1.2 22:01:10 INFO - mozhttpd == 0.7 22:01:10 INFO - mozinfo == 0.9 22:01:10 INFO - mozleak == 0.1 22:01:10 INFO - mozlog == 3.2 22:01:10 INFO - moznetwork == 0.27 22:01:10 INFO - mozprocess == 0.22 22:01:10 INFO - mozprofile == 0.28 22:01:10 INFO - mozrunner == 6.11 22:01:10 INFO - mozscreenshot == 0.1 22:01:10 INFO - mozsystemmonitor == 0.3 22:01:10 INFO - moztest == 0.7 22:01:10 INFO - mozversion == 1.4 22:01:10 INFO - psutil == 3.1.1 22:01:10 INFO - requests == 1.2.3 22:01:10 INFO - wsgiref == 0.1.2 22:01:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:10 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:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 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:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:01:10 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:01:10 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:01:10 INFO - Using env: {'DISPLAY': ':0', 22:01:10 INFO - 'HOME': '/home/cltbld', 22:01:10 INFO - 'LANG': 'en_US.UTF-8', 22:01:10 INFO - 'LANGUAGE': 'en_US:en', 22:01:10 INFO - 'LOGNAME': 'cltbld', 22:01:10 INFO - 'MAIL': '/var/mail/cltbld', 22:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:10 INFO - 'MOZ_NO_REMOTE': '1', 22:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:10 INFO - 'NO_EM_RESTART': '1', 22:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:10 INFO - 'PWD': '/builds/slave/test', 22:01:10 INFO - 'SHELL': '/bin/bash', 22:01:10 INFO - 'SHLVL': '1', 22:01:10 INFO - 'TERM': 'linux', 22:01:10 INFO - 'TMOUT': '86400', 22:01:10 INFO - 'USER': 'cltbld', 22:01:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:10 INFO - '_': '/tools/buildbot/bin/python'} 22:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 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:01:10 INFO - Cleaning up... 22:01:10 INFO - Return code: 0 22:01:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:10 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:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 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:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:01:10 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:01:10 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:01:10 INFO - Using env: {'DISPLAY': ':0', 22:01:10 INFO - 'HOME': '/home/cltbld', 22:01:10 INFO - 'LANG': 'en_US.UTF-8', 22:01:10 INFO - 'LANGUAGE': 'en_US:en', 22:01:10 INFO - 'LOGNAME': 'cltbld', 22:01:10 INFO - 'MAIL': '/var/mail/cltbld', 22:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:10 INFO - 'MOZ_NO_REMOTE': '1', 22:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:10 INFO - 'NO_EM_RESTART': '1', 22:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:10 INFO - 'PWD': '/builds/slave/test', 22:01:10 INFO - 'SHELL': '/bin/bash', 22:01:10 INFO - 'SHLVL': '1', 22:01:10 INFO - 'TERM': 'linux', 22:01:10 INFO - 'TMOUT': '86400', 22:01:10 INFO - 'USER': 'cltbld', 22:01:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:10 INFO - '_': '/tools/buildbot/bin/python'} 22:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:11 INFO - Cleaning up... 22:01:11 INFO - Return code: 0 22:01:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:11 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 0x7ff4cb3e4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd11350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', '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:01:11 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:01:11 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:01:11 INFO - Using env: {'DISPLAY': ':0', 22:01:11 INFO - 'HOME': '/home/cltbld', 22:01:11 INFO - 'LANG': 'en_US.UTF-8', 22:01:11 INFO - 'LANGUAGE': 'en_US:en', 22:01:11 INFO - 'LOGNAME': 'cltbld', 22:01:11 INFO - 'MAIL': '/var/mail/cltbld', 22:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:11 INFO - 'MOZ_NO_REMOTE': '1', 22:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:11 INFO - 'NO_EM_RESTART': '1', 22:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:11 INFO - 'PWD': '/builds/slave/test', 22:01:11 INFO - 'SHELL': '/bin/bash', 22:01:11 INFO - 'SHLVL': '1', 22:01:11 INFO - 'TERM': 'linux', 22:01:11 INFO - 'TMOUT': '86400', 22:01:11 INFO - 'USER': 'cltbld', 22:01:11 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:11 INFO - '_': '/tools/buildbot/bin/python'} 22:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:11 INFO - Downloading/unpacking flake8 22:01:11 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:01:11 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:01:11 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:01:11 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:01:11 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:01:11 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:01:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:01:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:01:13 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:01:13 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:01:13 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:01:13 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:01:13 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:01:13 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:01:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:01:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:01:13 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:01:13 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:01:13 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:01:13 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:01:13 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:01:13 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:01:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:01:13 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:01:13 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:01:13 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:01:13 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:01:13 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:01:13 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:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:13 INFO - no previously-included directories found matching 'docs/_build' 22:01:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:01:13 INFO - Running setup.py install for pep8 22:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:13 INFO - no previously-included directories found matching 'docs/_build' 22:01:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:01:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:01:14 INFO - Cleaning up... 22:01:14 INFO - Return code: 0 22:01:14 INFO - Running post-action listener: _resource_record_post_action 22:01:14 INFO - Running post-action listener: _start_resource_monitoring 22:01:14 INFO - Starting resource monitoring. 22:01:14 INFO - ##### 22:01:14 INFO - ##### Finished create-virtualenv step (success) 22:01:14 INFO - ##### 22:01:14 INFO - ##### 22:01:14 INFO - ##### Running install step. 22:01:14 INFO - ##### 22:01:14 INFO - Running pre-action listener: _resource_record_pre_action 22:01:14 INFO - Running main action method: install 22:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:14 INFO - Reading from file tmpfile_stdout 22:01:14 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:14 INFO - Reading from file tmpfile_stdout 22:01:14 INFO - Output received: 22:01:14 INFO - Usage: mozinstall [options] installer 22:01:14 INFO - Options: 22:01:14 INFO - -h, --help show this help message and exit 22:01:14 INFO - -d DEST, --destination=DEST 22:01:14 INFO - Directory to install application into. [default: 22:01:14 INFO - "/builds/slave/test"] 22:01:14 INFO - --app=APP Application being installed. [default: firefox] 22:01:14 INFO - mkdir: /builds/slave/test/build/application 22:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:01:30 INFO - Reading from file tmpfile_stdout 22:01:30 INFO - Output received: 22:01:30 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:30 INFO - Running post-action listener: _resource_record_post_action 22:01:30 INFO - ##### 22:01:30 INFO - ##### Finished install step (success) 22:01:30 INFO - ##### 22:01:30 INFO - ##### 22:01:30 INFO - ##### Running run-tests step. 22:01:30 INFO - ##### 22:01:30 INFO - Running pre-action listener: _resource_record_pre_action 22:01:30 INFO - Running main action method: run_tests 22:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:30 INFO - Python 2.7.3 22:01:30 INFO - Return code: 0 22:01:30 INFO - grabbing minidump binary from tooltool 22:01: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:01:30 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 0xda01c0>, '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 0xda10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xda1550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:30 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:01:30 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:01:30 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:01:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:30 INFO - Return code: 0 22:01:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:01:31 INFO - Return code: 0 22:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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:01:31 INFO - Using env: {'DISPLAY': ':0', 22:01:31 INFO - 'HOME': '/home/cltbld', 22:01:31 INFO - 'LANG': 'en_US.UTF-8', 22:01:31 INFO - 'LANGUAGE': 'en_US:en', 22:01:31 INFO - 'LOGNAME': 'cltbld', 22:01:31 INFO - 'MAIL': '/var/mail/cltbld', 22:01:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:31 INFO - 'MOZ_NO_REMOTE': '1', 22:01:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:31 INFO - 'NO_EM_RESTART': '1', 22:01:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:31 INFO - 'PWD': '/builds/slave/test', 22:01:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:31 INFO - 'SHELL': '/bin/bash', 22:01:31 INFO - 'SHLVL': '1', 22:01:31 INFO - 'TERM': 'linux', 22:01:31 INFO - 'TMOUT': '86400', 22:01:31 INFO - 'USER': 'cltbld', 22:01:31 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975', 22:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:31 INFO - '_': '/tools/buildbot/bin/python'} 22:01:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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:01:31 INFO - mozversion application_buildid: 20160617203624 22:01:31 INFO - mozversion application_changeset: 3ce53bd1e25b93140484d3933c9339a829e0c1eb 22:01:31 INFO - mozversion application_display_name: Nightly 22:01:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:31 INFO - mozversion application_name: Firefox 22:01:31 INFO - mozversion application_remotingname: firefox 22:01:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:01:31 INFO - mozversion application_vendor: Mozilla 22:01:31 INFO - mozversion application_version: 50.0a1 22:01:31 INFO - mozversion platform_buildid: 20160617203624 22:01:31 INFO - mozversion platform_changeset: 3ce53bd1e25b93140484d3933c9339a829e0c1eb 22:01:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:01:31 INFO - mozversion platform_version: 50.0a1 22:01:31 INFO - using testdate: 1466226091 22:01:31 INFO - actual date: 1466226091 22:01:31 INFO - starting webserver on 'localhost:58504' 22:01:31 INFO - SUITE-START | Running 2 tests 22:01:31 INFO - TEST-START | dromaeo_css 22:01:31 INFO - operating with platform_type : linux_ 22:01:31 INFO - Initialising browser for dromaeo_css test... 22:01:31 INFO - TEST-INFO | started process 4450 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUIHiio/profile http://localhost:58504/getInfo.html) 22:01:37 INFO - PROCESS | 4450 | __metrics Screen width/height:1600/1200 22:01:37 INFO - PROCESS | 4450 | colorDepth:24 22:01:37 INFO - PROCESS | 4450 | Browser inner width/height: 1024/697 22:01:37 INFO - PROCESS | 4450 | __metrics 22:01:38 ERROR - PROCESS | 4450 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 22:01:38 INFO - TEST-INFO | 4450: exit 0 22:01:38 INFO - Browser initialized. 22:01:38 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:38 INFO - TEST-INFO | started process 4570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUIHiio/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:51 INFO - PROCESS | 4570 | RSS: Main: 186417152 22:01:51 INFO - PROCESS | 4570 | 22:02:40 INFO - PROCESS | 4570 | 1466226160698 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:04:53 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58504/tests/dromaeo/cssquery-ext.html) 22:04:54 INFO - PROCESS | 4570 | RSS: Main: 189423616 22:04:54 INFO - PROCESS | 4570 | 22:07:56 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-ext.html (next: http://localhost:58504/tests/dromaeo/cssquery-jquery.html) 22:07:57 INFO - PROCESS | 4570 | RSS: Main: 188022784 22:07:57 INFO - PROCESS | 4570 | 22:10:59 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58504/tests/dromaeo/cssquery-mootools.html) 22:10:59 INFO - PROCESS | 4570 | RSS: Main: 189517824 22:10:59 INFO - PROCESS | 4570 | 22:14:01 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58504/tests/dromaeo/cssquery-prototype.html) 22:14:02 INFO - PROCESS | 4570 | RSS: Main: 187318272 22:14:02 INFO - PROCESS | 4570 | 22:17:04 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58504/tests/dromaeo/cssquery-yui.html) 22:17:05 INFO - PROCESS | 4570 | RSS: Main: 189374464 22:17:05 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-yui.html (next: http://localhost:58504/tests/dromaeo/cssquery-dojo.html) 22:20:08 INFO - PROCESS | 4570 | RSS: Main: 190185472 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | __start_tp_report 22:20:08 INFO - PROCESS | 4570 | _x_x_mozilla_page_load 22:20:08 INFO - PROCESS | 4570 | _x_x_mozilla_page_load_details 22:20:08 INFO - PROCESS | 4570 | |i|pagename|runs| 22:20:08 INFO - PROCESS | 4570 | |0;dojo.html;2589.9352516187096;2643.748843859834;2658.082961378324;2664.0808920922277;2674.66566679165;4662.067586482709;4723.102610504006;4758.0959617672615;4796.4244290685165;4841.588464980481;4697.342372067913;4782.378290822188;4797.664163508556;4819.951800481994;4866.926996095061;4356.281213599752;4424.823007079713;4478.104379124172;4484.260097083978;4484.461864576239;536.6311952529782;4271.466066741657;4297.806598703066;4308.956910430903;4378.912421751563;4335.024619460612;4369.978150109261;4375.890602734925;4405.867823965286;4413;4334.284843000901;4383.978080109611;4386.945483632155;4411.205982923073;4429.6899217054815;4315.784210789479;4321.135772845412;4348.717333373353;4386.670999675005;4389.582989615981;4743.928841067387;4755.976220118877;4779.091972525244;4787.61699064074;4848.030393921194;5054.721990290533;5134.264338656916;5135.666181698178;5148.150555158394;5206.088934436459;5122.795088196468;5125.513076257749;5126.5642420394315;5140.922886156711;5157.6647517911215;4769.3799806025345;4848.078865015671;4853.3447984522345;4868.14807408702;4891.192953162759;5189.247559103909;5226.980738755906;5290.68255904647;5291.153415453509;5309.707966061904;5027.748612569357;5044.520770526794;5054.949450505522;5056.191009438546;5096.439391666913;5085.516875896784;5142.537171654569;5148.9227661584355;5154.793808247703;5166.4058633257655;4961.007798440326;5047.772850221749;5062.873428164326;5067.315912351859;5088.491150884956;5061.114304996611;5072.442031376546;5076.517730815566;5100.846974590694;5114.74426278692;4251.659867210616;4272.380504826782;4287.421198138211;4291.29193683045;4362.345648152739;3703.5926048134684;3750.043738846631;3777.0557360659836;3794.317022935843;3823.1780167263614;4363.432753741993;4431.512533621363;4467.642588592905;4467.843625473093;4503.189425903369;5211.869703257449;5212.68723876561;5213.2701421800975;5216.660917040456;5260.447652996457;4373.1472362888935;4409.382686423904;4437.955620443819;4457.398251236106;4489.708168969007;4543.636509079266;4554.316852472156;4554.840580579664;4585.564371384713;4599.586037256596;4796.784144713426;4855.223164293767;4860.198067318995;4869.342638743724;4904.6566740327835;4987.0524600325825;5191.299174611379;5201.687898726089;5250.1074817281915;5250.97374513125;4861.295112208639;4868.63678170113;4877.975610121926;4906.705597664151;4963.131451995959;5091.847244582668;5137.717425541631;5149.150390185728;5153.45888681698;5160.277561141368;5177.870553236199;5204.401493828258;5224.9738751306;5228.37259528859;5278.208268759717;5077.035363280965;5080;5082.313887625123;5094.363204599425;5132.230165475208;4806.158922188533;4946.985867897;5035.395752509722;5055.064813009605;5063.367079115111;4978.775955082103;4994.525520075587;5024.944761600105;5038.420581633012;5073.061483625541;5148.536631703015;5155.664881782672;5159.871003224949;5162.896742065062;5185.066687996197;4858.781354839111;4947.233178857283;4991.625628077982;5004.724740139327;5018.121828679893 22:20:08 INFO - PROCESS | 4570 | |1;ext.html;531.3750343950308;562.5921207124834;582.8746819433824;595.5890435599438;605.7274226598032;34512.4823127653;38975.61024389755;39013;39063;39209;38883.22233555334;39271.41092883609;39388.212235755265;39461.60538394615;39690.80154599223;7613.162552119263;7799.649015794274;7800.648970796327;7807.492512986638;7820.648070836797;7740.458167928247;7772.494787838772;7798.337141342992;7863.017122859643;7892.4869883457395;7826.060872695284;7843.254890785395;7887.092984306821;7890.56601886896;7893.447458677866;7850.136484986647;7861.29248367644;7862.567558784236;7867;7880.763577092639;7801.492902961346;7849.332806711436;7851.21487851216;7867.8033049173655;7881.605919703992;29346.706532934826;37172.628273717455;37410.43884341737;37443.251134977145;37492;2970.0941212930047;3121.4537455945347;3186.1715953851935;3189.2505261263586;3200.471922132845;27564.86217568899;30027.549586756217;30036;30284.394312113855;30665;3468.6357932416986;3513.385157597436;3537.133402316423;3555.253396786678;3562.323158599858;412085;414717;416679;419967.999999997;421257;51674;51910.48089519132;52159.47840521622;52569.211461828105;52666.47333526692;33604.1598960028;34775.30449390998;54972.725136374866;56233.71883140558;56379.43620563824;14425.134491930517;14606.415743370359;14679.48621798232;14708.705825883637;14723.190224537753;10861.565537378574;11093.1125509959;11122.55509779616;11183.049440797624;11228.214025018171;19022.714659280122;19091.140898659592;19232.423027309203;19288.1320340585;19296.1316740747;7497.0253866996945;7702.422318326148;7702.653380597886;7725.961370193113;7828.373730101578;821.8356328734277;859.1537335724306;869.6956065377194;908.7591788176138;952.9898307793761;27562.173134805988;28097.297567560974;28408.573871391945;28437.431251374855;28512.85743571269;895.4671970177734;914.0767824497306;930.9953450232706;955.3599088610648;1029.9588016479406;14726.63183420423;14811.851881481263;14847.183404912834;15032.473863414729;15441.15073670959;381457;385765;388398;388932;390118;48408.515914841104;48639.270410943864;48978;49069;49311.50688493141;1117.1174771930084;1159.8666153392298;1181.9349935753617;1183.828344889986;1197.4731118307916;26923.596146057433;27801.860990694917;27925.022624208053;28128.85935570309;28491.148075375033;42455.7877210612;42481.36277955895;42756;42793.57206427896;42807.3578896323;336086;337001;339226;339726;340412;25381.619275711248;25391.238262852145;25767.742322577284;25796.871015644803;26246.081387151356;41367.793161034;41456.79271603622;42124.789376052926;42353.5764642362;42575.3613695801;35466;36276;36401;36615.26769464542;37125.47308005735;35243;35273;35315.823420882734;35397;35452.46821297734;34849.8257508721;35011;35897.46153807747;36374.27251454903;36881.44677829889;30922.845385772926;32707.34585308328;32889.67110328866;32932.34135317232;33141.17147071246;24979.375515612253;25486.49027019412;25561.714911250205;25736.485270294863;26039 22:20:08 INFO - PROCESS | 4570 | |2;jquery.html;351.5619180979906;352.3516729218238;352.5100110845923;352.67553850457574;365.1255243691359;16041.919790401047;16630.750538741915;17741.112944352797;17744.46766597001;17849.464516064505;15879.206039698027;16286.674266514663;17049.914750426262;17055.82944170558;17077.40229091982;4983.401991760994;5173.482651734816;5174.456682048387;5174.767135478913;5179.689218646874;3720.4047352423618;5073.441921388663;5701;6051.243594550681;6063.939360606381;5987.4311940365815;6011.519078473733;6018.6689732064715;6020.307664618581;6024.0662697282;5931.614445061057;5944.375840536724;6009.4591486766185;6082.2701275847185;6108.053251745987;5137.203733421288;5256.763445644955;5324.201369794523;5330.360356757175;5336.199570064521;17342.393016244372;18370;20597.5880482391;20873.478163045893;21041.263555775473;1797.2451570340488;1821.4535639308156;1831.359024341483;1881.2475009995974;1886.528367908023;8956.641734330618;10451.372917624967;12202.267863928193;13249.403776830066;13278.46886124554;1748.956276093095;1806.3768000039966;1833.3949796567233;1837.4303965770655;1879.9436016919515;61743.69128154375;62479.37520624781;63139;63910.36089639091;66260.68696565152;9256.352055356196;9578.90421095794;9994.800623925175;10104.848427273597;10240.078392944672;13413.865861341457;13447.32763361828;13479.258640774655;13492.460301587827;13663.521776737965;6840.3501667341525;6905.585664860024;7105.92894071063;7155.499115062;7235.529690570096;6512.446442052478;6593.307702691244;6737.629430381278;6794.0827988221945;6797.898031529531;4203.201391735561;4231.576842315744;4252.192083504125;4310.612044915973;4325.070109926381;1501.6621260216364;1682.9242684079245;1765.6556971390712;1832.5510249988572;1854.3509771579838;739.1241378965942;814.2061490047275;860.401587206645;890.8262888736634;935.1770442010986;15933.920330398274;16596.751048734277;16819.91590042042;16823.242954067096;17024.91487542579;843.4138273899704;848.325581162977;856.7815207122269;882.6248844241172;938.3572253006712;8288.83422331562;8412;9425.764355891157;9433.433993960385;9647.903520964841;403148;404822;409260;418632;428129;78565;78773;79105;80067.59966200132;80919;931.6226928094133;934.0612684252283;941.7975135345933;959.4147569982305;977.9070988256246;14943.701125977637;15028.39973247368;15061.849381506045;15115.168665723491;15448.768268476206;35970;36082.27835443224;36188.63811361853;36250.09374765652;37070.8146459266;47566;48315;48557.75721121372;54429.18356224739;54953;6940.41006514452;7149;7207.71169153228;7306.525075870051;7345.412367010519;10904.182186335898;11385.430728463709;11746.647600571996;12210.633680989584;12434.751304973668;9758.951205243928;9772.853625605603;9899.604015839286;10173.08442240202;10208.59165633396;10485.633002844865;10645.787084258116;10646.148308135485;10653.041226289672;10762.946185269024;9939.80722313327;10134.087932085853;10384;10446.537484752293;10696.893031069902;7193.712251509881;7291.234420385996;7389.261073892568;7410.4442166836625;7475.2151024141685;7253;10012.749681258027;10335.638252661121;10343.37939723619;10454.42500662471 22:20:08 INFO - PROCESS | 4570 | |3;mootools.html;1355.8034085057666;1380.1650001748942;1441.9279036048194;1579.2419638573476;1581.3279356273595;6451.451626611732;7193.4245260379175;7238.638068096588;7677.5393476391455;7712.730054448096;7328.3038111379465;7515.473916825825;7542.245775422455;7554.395648348132;7564.886526702104;2127.819135373495;2175.1082056356895;2175.7389113306344;2178.6296329623924;2180.040782055898;2095.277129390358;2106.852520323578;2156.762756096828;2160.103557023833;2165.7401111866598;2114.9471263218466;2116.0689296709397;2129.0632121866406;2147.4309308033385;2151.66649169379;2013.8791672499624;2024.9696254556193;2026.6149431607948;2029.5636438165866;2030.9187632494682;1994.7107669387858;2019.5556977464935;2020.4140799168224;2039.775624681284;2043.5606344635826;7516.624168791594;7548.6225688715895;7571.053618297713;7600.619969001583;7602.125755538129;4356.150550642626;4415.116976604692;4426.18115648606;4426.468823741172;4432.844850430251;2762.861856907167;2827.5475923852086;2851.7290857368725;2882.2506148401358;2938.44169607776;859.8366310401004;865.2299453486402;873.3973558244854;886.0607755778894;886.1581497577328;9819.705408837746;9863.457509836957;9913.15738162257;9954.950225248827;9986.35088719231;3915.8629447969183;3985.501812273466;3985.501812273466;4065.57311482296;4076.144009757925;8368.288695460955;8429.662813487514;8492.150784921581;8525.957370213233;8585.969683638003;4386.0569977455;4409.470863496401;4416.315471101984;4433.29067349229;4450.287953927421;3474.8262586870555;3946.2896678598127;4054.0675644601906;4055.9391609125887;4095.058136628592;3427.143214196451;3436.3127374524697;3467.549218601566;3538.6992105670793;3555.3955827509385;1106.9778604427866;1130.63819577736;1146.3064845768356;1158.7219067423757;1159.8318243854762;735.9963200183964;758.0562200060959;794.5510786405662;817.9754607361789;826.8718348655968;4821.325014497973;4840.975795121002;4872.220444728827;4879.5120487951635;4909.435414927258;849.163573879728;862.5514732339274;864.4121997041946;936.1247233836249;941.4868896451451;3222.935541289161;3313.204830840581;3368.1916340078205;3397.167693914957;3399.983000084984;8841.204291613785;9148.679796207234;9178.954105229432;9216.07839216073;9225.861612075823;8940.240079593177;8971.730848074438;9033.05152958943;9077.500737459372;9092.090790920854;6439.710213040425;6695.430888374445;6737.730490780412;6890.1731792184255;6927.792166235021;5012.47369026247;5062.848114556569;5123.077845987758;5233.450487698889;5435.592330575144;8054.718084867001;8125.8781118282095;8278.461899976483;8288.58557072132;8328.62521186536;8357.582120894052;8584.240230752555;8593.8710919335;8601.22588966996;8751.168638979287;5697.658140511582;6125.84685382869;6229.813105606839;6320.08358787964;6354.491640668828;6943.895841562482;7021.508494405342;7034.050403195708;7054.576725396492;7057.435405167472;6236.968815155895;6322.968385158045;6476.287608363171;6542.738290468328;6566.540342176002;6395.456386207225;6452.935470645233;6555.901661475177;6581.80254592363;6691.866162676622;5885.411458854081;5951.732172052181;5990.221271234712;6002.789902353396;6027.065804800263;4090.836366545424;4120;4159.292920203512;4223.619874211336;4236.4069030336395;4176.749395036308;4337.392765012838;4392.934105988476;4401.383806267189;4507.594316511399 22:20:08 INFO - PROCESS | 4570 | |4;prototype.html;283.3610208978753;284.5793561392066;286.44858647104326;289.13548490014864;289.94780939430905;5068.619853510983;5104.239037532055;5114.411842638097;5151.381834179903;5177.378714554254;5020.698758074509;5028.371453568304;5039.924401133986;5042.974785126078;5043.31915191449;3708.4053856841547;3746.5504139503296;3854.1520865409707;3869.593692662272;3953.169834334786;3855.691544676419;3858.3633700439536;3858.884233473;3872.7869967151796;3875.5736868944396;3838.4242363645535;3849.095462566295;3851.826667799942;3861.6524512793844;3868.2456920900436;3779.130799916035;3794.2790869734663;3872.7289089763726;3873.2640798248385;3898.2203559288255;3304.4052070627276;3306.206510437502;3307.4873394623874;3312.569365982407;3315.96684033159;4903.376693469013;4908.239222920453;4964.429090654548;4968.652194346397;4974.502549745032;4383.912321753579;4390.648748100144;4392.802323895432;4408.40486534317;4447.933281000787;5114.693118412907;5271.472852714736;5271.789128434896;5278.392984806758;5302.322327224765;3632.70938324936;3656.122530592665;3670.9809604717298;3672.59601443841;3683.355412802749;5478.342598888119;5486.780528778844;5500.614956953055;5505.174223866412;5531.419200983879;3548.3080799244417;3674.9448758268645;3720.404735242348;3750.268697603941;3759.323321802102;3803.6196380362294;3834.559025712023;3840.8847734568008;3845.750026248285;3857.3442514772555;4397.889013273582;4439.378487011821;4439.533848945879;4444.888877778017;4461.420015397977;4999.525045120708;5247.871707582888;5348.598855085884;5358.419266316446;5405.4324295949145;1296.027979015738;1308.7644224039764;1312.061875758837;1315.7763180259378;1316.7300703355788;800.2877439079224;809.906860711014;823.4194892600702;831.960049937578;833.3624777045565;4488.910221795546;4563.630910726835;4711.858644240679;4711.986922811543;4718.174319494144;5503.752331145108;5513.61404701675;5515.696636685021;5536.833894983157;5554.416786237468;4598.448186217608;4702.9764851176205;4714.198586240347;4719.528047195322;4739.644526660445;2821.111349924767;2928.0089539113183;2931.5016447204025;2936.2365784895655;2938.397628486155;5142.61430392722;5157.535821776059;5168.914527949098;5180.352455943007;5254.868628284324;5339.946600533945;5580.637258578179;5628.971855140698;5646.2942132233475;5714.514266287412;5189.325387699575;5204.1673332266655;5206.375234971828;5230.189320655304;5232.659877108052;5545.251391062155;5585.357683866408;5591.27313449249;5594.356648985418;5640.71796410186;5234.476552344735;5423.647462914898;5428.37573679032;5467.316585426822;5467.863303417446;5151.587872970078;5220.660657057279;5222.399424066129;5225.738713064407;5242.5281724644965;3679.632036796406;3708.9443658344608;3709.925801483901;3727.589965103891;3727.9254414912093;5386.299781028441;5554.972225138848;5565;5571.0807716726285;5641.37944826077;5289.942011597773;5311.521963023347;5319.654222475681;5322.654027488201;5343.4389389113585;5321.175217841241;5322.654027488201;5362.356517217802;5395.92081583677;5401.783928642811;4977.402711674622;5240.554552863049;5249.530131563169;5269.499397557226;5316.2823018893505;5063.594912407072;5201.973990130025;5326.334208223972;5348.358197016383;5354.223637572608;5250.894982100413;5270.973645131749;5277.736113194248;5279.366476022902;5300.5494532963585 22:20:08 INFO - PROCESS | 4570 | |5;yui.html;637.6556659403922;695.4332219241318;699.367072799117;702.0557350363762;734.8971144039832;11363.261388009774;13052;13063.216207027584;14233.14601123932;14243.92878035611;13957.860421395782;14482;14715.48495802647;14724.411023559045;14751.704965900703;792.9960350198241;800.6477150053987;801.5911884938694;802.7591722483261;804.2118723650826;785.5483097219093;786.7128498098182;793.4683761879526;793.9841203175962;794.2494342846013;833.6540335760652;834.028356964136;835.6281454752607;837.5226121110969;839.492107275099;798.1738900238283;817.2603793566832;828.5401602110796;829.9751007469755;832.2343444031505;593.6912805341199;596.4423264247943;597.4801922327559;598.5211830535554;599.2748773983485;13534.390952407068;13806.378712957841;13880;13888.652783680387;13890.166590004632;13930.51243206493;14541.709165816625;14566.344514496766;14570.854291457055;14640.926795366062;11152.219344645879;11197.49611267495;11197.552097916074;11269.887301127048;11297.49161287744;3344.4147274227157;3364.5457863188403;3408.829558522064;3417.111550996734;3434.3303055904107;70368.6481567594;70637.99999999949;71603.64198178975;72056.279437206;72129;10612.522436490377;10642.414667193223;10651;10748.247622666338;10765.89234107649;21416.464588385104;21469.46326341854;21495.247666331605;21579.460513486974;21589.56820863574;6869;6876.071730316443;6895.724171033103;6942.340477654615;6998.195207551095;3698.7041036716996;3767.5102236709054;3772.0946972726897;3840.5007349044445;3934.1738234970976;2957.3937342844665;3093.458644737162;3102.2089367211215;3112.9221769455494;3124.172094394987;499.6003197442032;502.0786856119062;505.61320589748874;513.4531723714232;514.5214950096446;489.42003725584584;493.53854146373254;495.17306098814345;509.2259765156941;510.2321006884615;8077;8087.5551844647935;8115.1073381927945;8213.38399620019;8285.710000149966;498.24764605446035;510.4436164580625;520.2482412913304;521.8460554136539;531.7766538054027;3801.752886062397;3864.7681139131178;3872.225554888977;3939.527256729211;3945.8816235512404;81492.59253703694;103974;104578;104963.4751826236;106864.46567767266;93229.53385233166;94190;95110.52444737732;95413;95497.52251238699;83461.58269208614;83554;83617.16382836094;86078;86429.56785216034;7474.364679002346;7527.510711803715;7656.846863062596;7673.994127450078;7700.306972372513;35160.47259291061;40653;47263.29105063496;48231.758841206974;48521.5147848517;67393;70714;71137;71475;71688.28311716816;9440.291978101532;9525.285603579621;9621.470819105016;9713.805723885342;9811.313208075366;10573.312734672221;10635.361878287327;10658.253922225369;10667.893321067;10677.540088246498;9810;9883;10118.696439106545;10204.795904082026;10292.536835842255;9606.375585586915;9677.806443870943;9827.459489728133;9857.30998830075;9879.753006174615;9346.392484488486;9392.825201232074;9731.270154738282;10065.547050382604;10181.84727229107;8470.788230294293;8789;8847.823043538965;9009.369344145847;9028.458292502472;8069.878951815845;8380.371472139735;8427.0730219677;8536.146385361415;8624.698135565224 22:20:08 INFO - PROCESS | 4570 | __end_tp_report 22:20:08 INFO - PROCESS | 4570 | __start_cc_report 22:20:08 INFO - PROCESS | 4570 | _x_x_mozilla_cycle_collect,420 22:20:08 INFO - PROCESS | 4570 | __end_cc_report 22:20:08 INFO - PROCESS | 4570 | __startTimestamp1466227208329__endTimestamp 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | ------- Summary: start ------- 22:20:08 INFO - PROCESS | 4570 | Number of tests: 6 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | [#0] dojo.html Cycles:165 Average:4728.94 Median:4891.19 stddev:614.32 (12.6%) stddev-sans-first:592.84 22:20:08 INFO - PROCESS | 4570 | Values: 2589.9 2643.7 2658.1 2664.1 2674.7 4662.1 4723.1 4758.1 4796.4 4841.6 4697.3 4782.4 4797.7 4820.0 4866.9 4356.3 4424.8 4478.1 4484.3 4484.5 536.6 4271.5 4297.8 4309.0 4378.9 4335.0 4370.0 4375.9 4405.9 4413.0 4334.3 4384.0 4386.9 4411.2 4429.7 4315.8 4321.1 4348.7 4386.7 4389.6 4743.9 4756.0 4779.1 4787.6 4848.0 5054.7 5134.3 5135.7 5148.2 5206.1 5122.8 5125.5 5126.6 5140.9 5157.7 4769.4 4848.1 4853.3 4868.1 4891.2 5189.2 5227.0 5290.7 5291.2 5309.7 5027.7 5044.5 5054.9 5056.2 5096.4 5085.5 5142.5 5148.9 5154.8 5166.4 4961.0 5047.8 5062.9 5067.3 5088.5 5061.1 5072.4 5076.5 5100.8 5114.7 4251.7 4272.4 4287.4 4291.3 4362.3 3703.6 3750.0 3777.1 3794.3 3823.2 4363.4 4431.5 4467.6 4467.8 4503.2 5211.9 5212.7 5213.3 5216.7 5260.4 4373.1 4409.4 4438.0 4457.4 4489.7 4543.6 4554.3 4554.8 4585.6 4599.6 4796.8 4855.2 4860.2 4869.3 4904.7 4987.1 5191.3 5201.7 5250.1 5251.0 4861.3 4868.6 4878.0 4906.7 4963.1 5091.8 5137.7 5149.2 5153.5 5160.3 5177.9 5204.4 5225.0 5228.4 5278.2 5077.0 5080.0 5082.3 5094.4 5132.2 4806.2 4947.0 5035.4 5055.1 5063.4 4978.8 4994.5 5024.9 5038.4 5073.1 5148.5 5155.7 5159.9 5162.9 5185.1 4858.8 4947.2 4991.6 5004.7 5018.1 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | [#1] ext.html Cycles:180 Average:52294.28 Median:37291.53 stddev:100979.28 (270.8%) stddev-sans-first:101187.76 22:20:08 INFO - PROCESS | 4570 | Values: 531.4 562.6 582.9 595.6 605.7 34512.5 38975.6 39013.0 39063.0 39209.0 38883.2 39271.4 39388.2 39461.6 39690.8 7613.2 7799.6 7800.6 7807.5 7820.6 7740.5 7772.5 7798.3 7863.0 7892.5 7826.1 7843.3 7887.1 7890.6 7893.4 7850.1 7861.3 7862.6 7867.0 7880.8 7801.5 7849.3 7851.2 7867.8 7881.6 29346.7 37172.6 37410.4 37443.3 37492.0 2970.1 3121.5 3186.2 3189.3 3200.5 27564.9 30027.5 30036.0 30284.4 30665.0 3468.6 3513.4 3537.1 3555.3 3562.3 412085.0 414717.0 416679.0 419968.0 421257.0 51674.0 51910.5 52159.5 52569.2 52666.5 33604.2 34775.3 54972.7 56233.7 56379.4 14425.1 14606.4 14679.5 14708.7 14723.2 10861.6 11093.1 11122.6 11183.0 11228.2 19022.7 19091.1 19232.4 19288.1 19296.1 7497.0 7702.4 7702.7 7726.0 7828.4 821.8 859.2 869.7 908.8 953.0 27562.2 28097.3 28408.6 28437.4 28512.9 895.5 914.1 931.0 955.4 1030.0 14726.6 14811.9 14847.2 15032.5 15441.2 381457.0 385765.0 388398.0 388932.0 390118.0 48408.5 48639.3 48978.0 49069.0 49311.5 1117.1 1159.9 1181.9 1183.8 1197.5 26923.6 27801.9 27925.0 28128.9 28491.1 42455.8 42481.4 42756.0 42793.6 42807.4 336086.0 337001.0 339226.0 339726.0 340412.0 25381.6 25391.2 25767.7 25796.9 26246.1 41367.8 41456.8 42124.8 42353.6 42575.4 35466.0 36276.0 36401.0 36615.3 37125.5 35243.0 35273.0 35315.8 35397.0 35452.5 34849.8 35011.0 35897.5 36374.3 36881.4 30922.8 32707.3 32889.7 32932.3 33141.2 24979.4 25486.5 25561.7 25736.5 26039.0 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | [#2] jquery.html Cycles:180 Average:24811.32 Median:4317.84 stddev:67990.93 (1574.7%) stddev-sans-first:68156.85 22:20:08 INFO - PROCESS | 4570 | Values: 351.6 352.4 352.5 352.7 365.1 16041.9 16630.8 17741.1 17744.5 17849.5 15879.2 16286.7 17049.9 17055.8 17077.4 4983.4 5173.5 5174.5 5174.8 5179.7 3720.4 5073.4 5701.0 6051.2 6063.9 5987.4 6011.5 6018.7 6020.3 6024.1 5931.6 5944.4 6009.5 6082.3 6108.1 5137.2 5256.8 5324.2 5330.4 5336.2 17342.4 18370.0 20597.6 20873.5 21041.3 1797.2 1821.5 1831.4 1881.2 1886.5 8956.6 10451.4 12202.3 13249.4 13278.5 1749.0 1806.4 1833.4 1837.4 1879.9 61743.7 62479.4 63139.0 63910.4 66260.7 9256.4 9578.9 9994.8 10104.8 10240.1 13413.9 13447.3 13479.3 13492.5 13663.5 6840.4 6905.6 7105.9 7155.5 7235.5 6512.4 6593.3 6737.6 6794.1 6797.9 4203.2 4231.6 4252.2 4310.6 4325.1 1501.7 1682.9 1765.7 1832.6 1854.4 739.1 814.2 860.4 890.8 935.2 15933.9 16596.8 16819.9 16823.2 17024.9 843.4 848.3 856.8 882.6 938.4 8288.8 8412.0 9425.8 9433.4 9647.9 403148.0 404822.0 409260.0 418632.0 428129.0 78565.0 78773.0 79105.0 80067.6 80919.0 931.6 934.1 941.8 959.4 977.9 14943.7 15028.4 15061.8 15115.2 15448.8 35970.0 36082.3 36188.6 36250.1 37070.8 47566.0 48315.0 48557.8 54429.2 54953.0 6940.4 7149.0 7207.7 7306.5 7345.4 10904.2 11385.4 11746.6 12210.6 12434.8 9759.0 9772.9 9899.6 10173.1 10208.6 10485.6 10645.8 10646.1 10653.0 10762.9 9939.8 10134.1 10384.0 10446.5 10696.9 7193.7 7291.2 7389.3 7410.4 7475.2 7253.0 10012.7 10335.6 10343.4 10454.4 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | [#3] mootools.html Cycles:180 Average:4865.09 Median:5791.53 stddev:2689.20 (46.4%) stddev-sans-first:2683.81 22:20:08 INFO - PROCESS | 4570 | Values: 1355.8 1380.2 1441.9 1579.2 1581.3 6451.5 7193.4 7238.6 7677.5 7712.7 7328.3 7515.5 7542.2 7554.4 7564.9 2127.8 2175.1 2175.7 2178.6 2180.0 2095.3 2106.9 2156.8 2160.1 2165.7 2114.9 2116.1 2129.1 2147.4 2151.7 2013.9 2025.0 2026.6 2029.6 2030.9 1994.7 2019.6 2020.4 2039.8 2043.6 7516.6 7548.6 7571.1 7600.6 7602.1 4356.2 4415.1 4426.2 4426.5 4432.8 2762.9 2827.5 2851.7 2882.3 2938.4 859.8 865.2 873.4 886.1 886.2 9819.7 9863.5 9913.2 9955.0 9986.4 3915.9 3985.5 3985.5 4065.6 4076.1 8368.3 8429.7 8492.2 8526.0 8586.0 4386.1 4409.5 4416.3 4433.3 4450.3 3474.8 3946.3 4054.1 4055.9 4095.1 3427.1 3436.3 3467.5 3538.7 3555.4 1107.0 1130.6 1146.3 1158.7 1159.8 736.0 758.1 794.6 818.0 826.9 4821.3 4841.0 4872.2 4879.5 4909.4 849.2 862.6 864.4 936.1 941.5 3222.9 3313.2 3368.2 3397.2 3400.0 8841.2 9148.7 9179.0 9216.1 9225.9 8940.2 8971.7 9033.1 9077.5 9092.1 6439.7 6695.4 6737.7 6890.2 6927.8 5012.5 5062.8 5123.1 5233.5 5435.6 8054.7 8125.9 8278.5 8288.6 8328.6 8357.6 8584.2 8593.9 8601.2 8751.2 5697.7 6125.8 6229.8 6320.1 6354.5 6943.9 7021.5 7034.1 7054.6 7057.4 6237.0 6323.0 6476.3 6542.7 6566.5 6395.5 6452.9 6555.9 6581.8 6691.9 5885.4 5951.7 5990.2 6002.8 6027.1 4090.8 4120.0 4159.3 4223.6 4236.4 4176.7 4337.4 4392.9 4401.4 4507.6 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | [#4] prototype.html Cycles:180 Average:4386.09 Median:5043.15 stddev:1317.97 (26.1%) stddev-sans-first:1285.19 22:20:08 INFO - PROCESS | 4570 | Values: 283.4 284.6 286.4 289.1 289.9 5068.6 5104.2 5114.4 5151.4 5177.4 5020.7 5028.4 5039.9 5043.0 5043.3 3708.4 3746.6 3854.2 3869.6 3953.2 3855.7 3858.4 3858.9 3872.8 3875.6 3838.4 3849.1 3851.8 3861.7 3868.2 3779.1 3794.3 3872.7 3873.3 3898.2 3304.4 3306.2 3307.5 3312.6 3316.0 4903.4 4908.2 4964.4 4968.7 4974.5 4383.9 4390.6 4392.8 4408.4 4447.9 5114.7 5271.5 5271.8 5278.4 5302.3 3632.7 3656.1 3671.0 3672.6 3683.4 5478.3 5486.8 5500.6 5505.2 5531.4 3548.3 3674.9 3720.4 3750.3 3759.3 3803.6 3834.6 3840.9 3845.8 3857.3 4397.9 4439.4 4439.5 4444.9 4461.4 4999.5 5247.9 5348.6 5358.4 5405.4 1296.0 1308.8 1312.1 1315.8 1316.7 800.3 809.9 823.4 832.0 833.4 4488.9 4563.6 4711.9 4712.0 4718.2 5503.8 5513.6 5515.7 5536.8 5554.4 4598.4 4703.0 4714.2 4719.5 4739.6 2821.1 2928.0 2931.5 2936.2 2938.4 5142.6 5157.5 5168.9 5180.4 5254.9 5339.9 5580.6 5629.0 5646.3 5714.5 5189.3 5204.2 5206.4 5230.2 5232.7 5545.3 5585.4 5591.3 5594.4 5640.7 5234.5 5423.6 5428.4 5467.3 5467.9 5151.6 5220.7 5222.4 5225.7 5242.5 3679.6 3708.9 3709.9 3727.6 3727.9 5386.3 5555.0 5565.0 5571.1 5641.4 5289.9 5311.5 5319.7 5322.7 5343.4 5321.2 5322.7 5362.4 5395.9 5401.8 4977.4 5240.6 5249.5 5269.5 5316.3 5063.6 5202.0 5326.3 5348.4 5354.2 5250.9 5271.0 5277.7 5279.4 5300.5 22:20:08 INFO - PROCESS | 4570 | 22:20:08 INFO - PROCESS | 4570 | [#5] yui.html Cycles:180 Average:18746.08 Median:596.96 stddev:27981.26 (4687.3%) stddev-sans-first:28026.72 22:20:08 INFO - PROCESS | 4570 | Values: 637.7 695.4 699.4 702.1 734.9 11363.3 13052.0 13063.2 14233.1 14243.9 13957.9 14482.0 14715.5 14724.4 14751.7 793.0 800.6 801.6 802.8 804.2 785.5 786.7 793.5 794.0 794.2 833.7 834.0 835.6 837.5 839.5 798.2 817.3 828.5 830.0 832.2 593.7 596.4 597.5 598.5 599.3 13534.4 13806.4 13880.0 13888.7 13890.2 13930.5 14541.7 14566.3 14570.9 14640.9 11152.2 11197.5 11197.6 11269.9 11297.5 3344.4 3364.5 3408.8 3417.1 3434.3 70368.6 70638.0 71603.6 72056.3 72129.0 10612.5 10642.4 10651.0 10748.2 10765.9 21416.5 21469.5 21495.2 21579.5 21589.6 6869.0 6876.1 6895.7 6942.3 6998.2 3698.7 3767.5 3772.1 3840.5 3934.2 2957.4 3093.5 3102.2 3112.9 3124.2 499.6 502.1 505.6 513.5 514.5 489.4 493.5 495.2 509.2 510.2 8077.0 8087.6 8115.1 8213.4 8285.7 498.2 510.4 520.2 521.8 531.8 3801.8 3864.8 3872.2 3939.5 3945.9 81492.6 103974.0 104578.0 104963.5 106864.5 93229.5 94190.0 95110.5 95413.0 95497.5 83461.6 83554.0 83617.2 86078.0 86429.6 7474.4 7527.5 7656.8 7674.0 7700.3 35160.5 40653.0 47263.3 48231.8 48521.5 67393.0 70714.0 71137.0 71475.0 71688.3 9440.3 9525.3 9621.5 9713.8 9811.3 10573.3 10635.4 10658.3 10667.9 10677.5 9810.0 9883.0 10118.7 10204.8 10292.5 9606.4 9677.8 9827.5 9857.3 9879.8 9346.4 9392.8 9731.3 10065.5 10181.8 8470.8 8789.0 8847.8 9009.4 9028.5 8069.9 8380.4 8427.1 8536.1 8624.7 22:20:08 INFO - PROCESS | 4570 | -------- Summary: end -------- 22:20:08 INFO - PROCESS | 4570 | 22:20:09 INFO - TEST-INFO | 4570: exit 0 22:20:09 INFO - TEST-OK | dromaeo_css | took 1117362ms 22:20:09 INFO - TEST-START | kraken 22:20:09 INFO - operating with platform_type : linux_ 22:20:09 INFO - Initialising browser for kraken test... 22:20:09 INFO - TEST-INFO | started process 4805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpurwjh3/profile http://localhost:58504/getInfo.html) 22:20:14 INFO - PROCESS | 4805 | __metrics Screen width/height:1600/1200 22:20:14 INFO - PROCESS | 4805 | colorDepth:24 22:20:14 INFO - PROCESS | 4805 | Browser inner width/height: 1024/697 22:20:14 INFO - PROCESS | 4805 | __metrics 22:20:15 ERROR - PROCESS | 4805 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 22:20:16 INFO - TEST-INFO | 4805: exit 0 22:20:16 INFO - Browser initialized. 22:20:16 INFO - Running cycle 1/1 for kraken test... 22:20:16 INFO - TEST-INFO | started process 4924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpurwjh3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:28 INFO - PROCESS | 4924 | RSS: Main: 185954304 22:20:28 INFO - PROCESS | 4924 | 22:20:31 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:32 INFO - PROCESS | 4924 | RSS: Main: 190427136 22:20:32 INFO - PROCESS | 4924 | 22:20:34 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-dft) 22:20:35 INFO - PROCESS | 4924 | RSS: Main: 190541824 22:20:35 INFO - PROCESS | 4924 | 22:20:38 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-fft) 22:20:39 INFO - PROCESS | 4924 | RSS: Main: 186134528 22:20:39 INFO - PROCESS | 4924 | 22:20:41 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-oscillator) 22:20:41 INFO - PROCESS | 4924 | RSS: Main: 186675200 22:20:41 INFO - PROCESS | 4924 | 22:20:44 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58504/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:44 INFO - PROCESS | 4924 | RSS: Main: 186859520 22:20:44 INFO - PROCESS | 4924 | 22:20:47 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58504/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:48 INFO - PROCESS | 4924 | RSS: Main: 187183104 22:20:48 INFO - PROCESS | 4924 | 22:20:52 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58504/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:52 INFO - PROCESS | 4924 | RSS: Main: 184270848 22:20:52 INFO - PROCESS | 4924 | 22:20:55 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58504/tests/kraken/driver.html?testName=json-parse-financial) 22:20:56 INFO - PROCESS | 4924 | RSS: Main: 186380288 22:20:56 INFO - PROCESS | 4924 | 22:20:58 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58504/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:20:58 INFO - PROCESS | 4924 | RSS: Main: 186593280 22:20:58 INFO - PROCESS | 4924 | 22:21:00 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:01 INFO - PROCESS | 4924 | RSS: Main: 187453440 22:21:01 INFO - PROCESS | 4924 | 22:21:03 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:04 INFO - PROCESS | 4924 | RSS: Main: 187621376 22:21:04 INFO - PROCESS | 4924 | 22:21:06 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:07 INFO - PROCESS | 4924 | RSS: Main: 187822080 22:21:07 INFO - PROCESS | 4924 | 22:21:10 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:11 INFO - PROCESS | 4924 | RSS: Main: 187297792 22:21:11 INFO - PROCESS | 4924 | 22:21:12 INFO - PROCESS | 4924 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58504/tests/kraken/driver.html?testName=ai-astar) 22:21:13 INFO - PROCESS | 4924 | RSS: Main: 188694528 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | __start_tp_report 22:21:13 INFO - PROCESS | 4924 | _x_x_mozilla_page_load 22:21:13 INFO - PROCESS | 4924 | _x_x_mozilla_page_load_details 22:21:13 INFO - PROCESS | 4924 | |i|pagename|runs| 22:21:13 INFO - PROCESS | 4924 | |0;ai-astar;114;108;113;114;115;115;113;114;113;113 22:21:13 INFO - PROCESS | 4924 | |1;audio-beat-detection;157;131;155;182;140;165;163;134;188;134 22:21:13 INFO - PROCESS | 4924 | |2;audio-dft;163;167;171;167;165;162;163;163;165;163 22:21:13 INFO - PROCESS | 4924 | |3;audio-fft;76;78;77;173;79;77;76;76;172;78 22:21:13 INFO - PROCESS | 4924 | |4;audio-oscillator;88;85;136;88;86;88;139;95;86;140 22:21:13 INFO - PROCESS | 4924 | |5;imaging-gaussian-blur;119;118;117;119;118;118;118;117;118;118 22:21:13 INFO - PROCESS | 4924 | |6;imaging-darkroom;148;150;148;149;148;148;147;149;150;151 22:21:13 INFO - PROCESS | 4924 | |7;imaging-desaturate;95;96;95;95;96;95;96;96;96;96 22:21:13 INFO - PROCESS | 4924 | |8;json-parse-financial;63;65;66;65;67;65;64;64;64;64 22:21:13 INFO - PROCESS | 4924 | |9;json-stringify-tinderbox;65;66;65;66;67;65;65;65;67;67 22:21:13 INFO - PROCESS | 4924 | |10;stanford-crypto-aes;87;85;85;86;86;86;92;84;109;92 22:21:13 INFO - PROCESS | 4924 | |11;stanford-crypto-ccm;134;149;133;132;135;133;138;152;137;160 22:21:13 INFO - PROCESS | 4924 | |12;stanford-crypto-pbkdf2;175;169;173;176;172;176;169;182;170;172 22:21:13 INFO - PROCESS | 4924 | |13;stanford-crypto-sha256-iterative;70;72;70;68;69;70;71;71;69;67 22:21:13 INFO - PROCESS | 4924 | __end_tp_report 22:21:13 INFO - PROCESS | 4924 | __start_cc_report 22:21:13 INFO - PROCESS | 4924 | _x_x_mozilla_cycle_collect,1021 22:21:13 INFO - PROCESS | 4924 | __end_cc_report 22:21:13 INFO - PROCESS | 4924 | __startTimestamp1466227273177__endTimestamp 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | ------- Summary: start ------- 22:21:13 INFO - PROCESS | 4924 | Number of tests: 14 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:1.99 (1.7%) stddev-sans-first:2.09 22:21:13 INFO - PROCESS | 4924 | Values: 114.0 108.0 113.0 114.0 115.0 115.0 113.0 114.0 113.0 113.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#1] audio-beat-detection Cycles:10 Average:154.90 Median:160.00 stddev:20.19 (12.6%) stddev-sans-first:21.40 22:21:13 INFO - PROCESS | 4924 | Values: 157.0 131.0 155.0 182.0 140.0 165.0 163.0 134.0 188.0 134.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#2] audio-dft Cycles:10 Average:164.90 Median:165.00 stddev:2.77 (1.7%) stddev-sans-first:2.85 22:21:13 INFO - PROCESS | 4924 | Values: 163.0 167.0 171.0 167.0 165.0 162.0 163.0 163.0 165.0 163.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#3] audio-fft Cycles:10 Average:96.20 Median:77.00 stddev:40.23 (52.2%) stddev-sans-first:42.00 22:21:13 INFO - PROCESS | 4924 | Values: 76.0 78.0 77.0 173.0 79.0 77.0 76.0 76.0 172.0 78.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:87.00 stddev:24.48 (28.1%) stddev-sans-first:25.35 22:21:13 INFO - PROCESS | 4924 | Values: 88.0 85.0 136.0 88.0 86.0 88.0 139.0 95.0 86.0 140.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 22:21:13 INFO - PROCESS | 4924 | Values: 119.0 118.0 117.0 119.0 118.0 118.0 118.0 117.0 118.0 118.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 22:21:13 INFO - PROCESS | 4924 | Values: 148.0 150.0 148.0 149.0 148.0 148.0 147.0 149.0 150.0 151.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:21:13 INFO - PROCESS | 4924 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:1.16 (1.8%) stddev-sans-first:1.05 22:21:13 INFO - PROCESS | 4924 | Values: 63.0 65.0 66.0 65.0 67.0 65.0 64.0 64.0 64.0 64.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 22:21:13 INFO - PROCESS | 4924 | Values: 65.0 66.0 65.0 66.0 67.0 65.0 65.0 65.0 67.0 67.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:86.00 stddev:7.50 (8.7%) stddev-sans-first:7.91 22:21:13 INFO - PROCESS | 4924 | Values: 87.0 85.0 85.0 86.0 86.0 86.0 92.0 84.0 109.0 92.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:137.50 stddev:9.78 (7.1%) stddev-sans-first:10.10 22:21:13 INFO - PROCESS | 4924 | Values: 134.0 149.0 133.0 132.0 135.0 133.0 138.0 152.0 137.0 160.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:174.00 stddev:4.01 (2.3%) stddev-sans-first:4.21 22:21:13 INFO - PROCESS | 4924 | Values: 175.0 169.0 173.0 176.0 172.0 176.0 169.0 182.0 170.0 172.0 22:21:13 INFO - PROCESS | 4924 | 22:21:13 INFO - PROCESS | 4924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.49 (2.1%) stddev-sans-first:1.58 22:21:13 INFO - PROCESS | 4924 | Values: 70.0 72.0 70.0 68.0 69.0 70.0 71.0 71.0 69.0 67.0 22:21:13 INFO - PROCESS | 4924 | -------- Summary: end -------- 22:21:13 INFO - PROCESS | 4924 | 22:21:14 INFO - TEST-INFO | 4924: exit 0 22:21:14 INFO - TEST-OK | kraken | took 65160ms 22:21:14 INFO - SUITE-END | took 1182s 22:21:14 INFO - Completed test suite (00:19:42) 22:21:14 INFO - javascript benchmark 22:21:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6409.566922043825, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2589.9352516187096, 2643.748843859834, 2658.082961378324, 2664.0808920922277, 2674.66566679165, 4662.067586482709, 4723.102610504006, 4758.0959617672615, 4796.4244290685165, 4841.588464980481, 4697.342372067913, 4782.378290822188, 4797.664163508556, 4819.951800481994, 4866.926996095061, 4356.281213599752, 4424.823007079713, 4478.104379124172, 4484.260097083978, 4484.461864576239, 536.6311952529782, 4271.466066741657, 4297.806598703066, 4308.956910430903, 4378.912421751563, 4335.024619460612, 4369.978150109261, 4375.890602734925, 4405.867823965286, 4413.0, 4334.284843000901, 4383.978080109611, 4386.945483632155, 4411.205982923073, 4429.6899217054815, 4315.784210789479, 4321.135772845412, 4348.717333373353, 4386.670999675005, 4389.582989615981, 4743.928841067387, 4755.976220118877, 4779.091972525244, 4787.61699064074, 4848.030393921194, 5054.721990290533, 5134.264338656916, 5135.666181698178, 5148.150555158394, 5206.088934436459, 5122.795088196468, 5125.513076257749, 5126.5642420394315, 5140.922886156711, 5157.6647517911215, 4769.3799806025345, 4848.078865015671, 4853.3447984522345, 4868.14807408702, 4891.192953162759, 5189.247559103909, 5226.980738755906, 5290.68255904647, 5291.153415453509, 5309.707966061904, 5027.748612569357, 5044.520770526794, 5054.949450505522, 5056.191009438546, 5096.439391666913, 5085.516875896784, 5142.537171654569, 5148.9227661584355, 5154.793808247703, 5166.4058633257655, 4961.007798440326, 5047.772850221749, 5062.873428164326, 5067.315912351859, 5088.491150884956, 5061.114304996611, 5072.442031376546, 5076.517730815566, 5100.846974590694, 5114.74426278692, 4251.659867210616, 4272.380504826782, 4287.421198138211, 4291.29193683045, 4362.345648152739, 3703.5926048134684, 3750.043738846631, 3777.0557360659836, 3794.317022935843, 3823.1780167263614, 4363.432753741993, 4431.512533621363, 4467.642588592905, 4467.843625473093, 4503.189425903369, 5211.869703257449, 5212.68723876561, 5213.2701421800975, 5216.660917040456, 5260.447652996457, 4373.1472362888935, 4409.382686423904, 4437.955620443819, 4457.398251236106, 4489.708168969007, 4543.636509079266, 4554.316852472156, 4554.840580579664, 4585.564371384713, 4599.586037256596, 4796.784144713426, 4855.223164293767, 4860.198067318995, 4869.342638743724, 4904.6566740327835, 4987.0524600325825, 5191.299174611379, 5201.687898726089, 5250.1074817281915, 5250.97374513125, 4861.295112208639, 4868.63678170113, 4877.975610121926, 4906.705597664151, 4963.131451995959, 5091.847244582668, 5137.717425541631, 5149.150390185728, 5153.45888681698, 5160.277561141368, 5177.870553236199, 5204.401493828258, 5224.9738751306, 5228.37259528859, 5278.208268759717, 5077.035363280965, 5080.0, 5082.313887625123, 5094.363204599425, 5132.230165475208, 4806.158922188533, 4946.985867897, 5035.395752509722, 5055.064813009605, 5063.367079115111, 4978.775955082103, 4994.525520075587, 5024.944761600105, 5038.420581633012, 5073.061483625541, 5148.536631703015, 5155.664881782672, 5159.871003224949, 5162.896742065062, 5185.066687996197, 4858.781354839111, 4947.233178857283, 4991.625628077982, 5004.724740139327, 5018.121828679893], "value": 4689.726652001808, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.3750343950308, 562.5921207124834, 582.8746819433824, 595.5890435599438, 605.7274226598032, 34512.4823127653, 38975.61024389755, 39013.0, 39063.0, 39209.0, 38883.22233555334, 39271.41092883609, 39388.212235755265, 39461.60538394615, 39690.80154599223, 7613.162552119263, 7799.649015794274, 7800.648970796327, 7807.492512986638, 7820.648070836797, 7740.458167928247, 7772.494787838772, 7798.337141342992, 7863.017122859643, 7892.4869883457395, 7826.060872695284, 7843.254890785395, 7887.092984306821, 7890.56601886896, 7893.447458677866, 7850.136484986647, 7861.29248367644, 7862.567558784236, 7867.0, 7880.763577092639, 7801.492902961346, 7849.332806711436, 7851.21487851216, 7867.8033049173655, 7881.605919703992, 29346.706532934826, 37172.628273717455, 37410.43884341737, 37443.251134977145, 37492.0, 2970.0941212930047, 3121.4537455945347, 3186.1715953851935, 3189.2505261263586, 3200.471922132845, 27564.86217568899, 30027.549586756217, 30036.0, 30284.394312113855, 30665.0, 3468.6357932416986, 3513.385157597436, 3537.133402316423, 3555.253396786678, 3562.323158599858, 412085.0, 414717.0, 416679.0, 419967.999999997, 421257.0, 51674.0, 51910.48089519132, 52159.47840521622, 52569.211461828105, 52666.47333526692, 33604.1598960028, 34775.30449390998, 54972.725136374866, 56233.71883140558, 56379.43620563824, 14425.134491930517, 14606.415743370359, 14679.48621798232, 14708.705825883637, 14723.190224537753, 10861.565537378574, 11093.1125509959, 11122.55509779616, 11183.049440797624, 11228.214025018171, 19022.714659280122, 19091.140898659592, 19232.423027309203, 19288.1320340585, 19296.1316740747, 7497.0253866996945, 7702.422318326148, 7702.653380597886, 7725.961370193113, 7828.373730101578, 821.8356328734277, 859.1537335724306, 869.6956065377194, 908.7591788176138, 952.9898307793761, 27562.173134805988, 28097.297567560974, 28408.573871391945, 28437.431251374855, 28512.85743571269, 895.4671970177734, 914.0767824497306, 930.9953450232706, 955.3599088610648, 1029.9588016479406, 14726.63183420423, 14811.851881481263, 14847.183404912834, 15032.473863414729, 15441.15073670959, 381457.0, 385765.0, 388398.0, 388932.0, 390118.0, 48408.515914841104, 48639.270410943864, 48978.0, 49069.0, 49311.50688493141, 1117.1174771930084, 1159.8666153392298, 1181.9349935753617, 1183.828344889986, 1197.4731118307916, 26923.596146057433, 27801.860990694917, 27925.022624208053, 28128.85935570309, 28491.148075375033, 42455.7877210612, 42481.36277955895, 42756.0, 42793.57206427896, 42807.3578896323, 336086.0, 337001.0, 339226.0, 339726.0, 340412.0, 25381.619275711248, 25391.238262852145, 25767.742322577284, 25796.871015644803, 26246.081387151356, 41367.793161034, 41456.79271603622, 42124.789376052926, 42353.5764642362, 42575.3613695801, 35466.0, 36276.0, 36401.0, 36615.26769464542, 37125.47308005735, 35243.0, 35273.0, 35315.823420882734, 35397.0, 35452.46821297734, 34849.8257508721, 35011.0, 35897.46153807747, 36374.27251454903, 36881.44677829889, 30922.845385772926, 32707.34585308328, 32889.67110328866, 32932.34135317232, 33141.17147071246, 24979.375515612253, 25486.49027019412, 25561.714911250205, 25736.485270294863, 26039.0], "value": 17760.75596684079, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.5619180979906, 352.3516729218238, 352.5100110845923, 352.67553850457574, 365.1255243691359, 16041.919790401047, 16630.750538741915, 17741.112944352797, 17744.46766597001, 17849.464516064505, 15879.206039698027, 16286.674266514663, 17049.914750426262, 17055.82944170558, 17077.40229091982, 4983.401991760994, 5173.482651734816, 5174.456682048387, 5174.767135478913, 5179.689218646874, 3720.4047352423618, 5073.441921388663, 5701.0, 6051.243594550681, 6063.939360606381, 5987.4311940365815, 6011.519078473733, 6018.6689732064715, 6020.307664618581, 6024.0662697282, 5931.614445061057, 5944.375840536724, 6009.4591486766185, 6082.2701275847185, 6108.053251745987, 5137.203733421288, 5256.763445644955, 5324.201369794523, 5330.360356757175, 5336.199570064521, 17342.393016244372, 18370.0, 20597.5880482391, 20873.478163045893, 21041.263555775473, 1797.2451570340488, 1821.4535639308156, 1831.359024341483, 1881.2475009995974, 1886.528367908023, 8956.641734330618, 10451.372917624967, 12202.267863928193, 13249.403776830066, 13278.46886124554, 1748.956276093095, 1806.3768000039966, 1833.3949796567233, 1837.4303965770655, 1879.9436016919515, 61743.69128154375, 62479.37520624781, 63139.0, 63910.36089639091, 66260.68696565152, 9256.352055356196, 9578.90421095794, 9994.800623925175, 10104.848427273597, 10240.078392944672, 13413.865861341457, 13447.32763361828, 13479.258640774655, 13492.460301587827, 13663.521776737965, 6840.3501667341525, 6905.585664860024, 7105.92894071063, 7155.499115062, 7235.529690570096, 6512.446442052478, 6593.307702691244, 6737.629430381278, 6794.0827988221945, 6797.898031529531, 4203.201391735561, 4231.576842315744, 4252.192083504125, 4310.612044915973, 4325.070109926381, 1501.6621260216364, 1682.9242684079245, 1765.6556971390712, 1832.5510249988572, 1854.3509771579838, 739.1241378965942, 814.2061490047275, 860.401587206645, 890.8262888736634, 935.1770442010986, 15933.920330398274, 16596.751048734277, 16819.91590042042, 16823.242954067096, 17024.91487542579, 843.4138273899704, 848.325581162977, 856.7815207122269, 882.6248844241172, 938.3572253006712, 8288.83422331562, 8412.0, 9425.764355891157, 9433.433993960385, 9647.903520964841, 403148.0, 404822.0, 409260.0, 418632.0, 428129.0, 78565.0, 78773.0, 79105.0, 80067.59966200132, 80919.0, 931.6226928094133, 934.0612684252283, 941.7975135345933, 959.4147569982305, 977.9070988256246, 14943.701125977637, 15028.39973247368, 15061.849381506045, 15115.168665723491, 15448.768268476206, 35970.0, 36082.27835443224, 36188.63811361853, 36250.09374765652, 37070.8146459266, 47566.0, 48315.0, 48557.75721121372, 54429.18356224739, 54953.0, 6940.41006514452, 7149.0, 7207.71169153228, 7306.525075870051, 7345.412367010519, 10904.182186335898, 11385.430728463709, 11746.647600571996, 12210.633680989584, 12434.751304973668, 9758.951205243928, 9772.853625605603, 9899.604015839286, 10173.08442240202, 10208.59165633396, 10485.633002844865, 10645.787084258116, 10646.148308135485, 10653.041226289672, 10762.946185269024, 9939.80722313327, 10134.087932085853, 10384.0, 10446.537484752293, 10696.893031069902, 7193.712251509881, 7291.234420385996, 7389.261073892568, 7410.4442166836625, 7475.2151024141685, 7253.0, 10012.749681258027, 10335.638252661121, 10343.37939723619, 10454.42500662471], "value": 8192.080532031112, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.8034085057666, 1380.1650001748942, 1441.9279036048194, 1579.2419638573476, 1581.3279356273595, 6451.451626611732, 7193.4245260379175, 7238.638068096588, 7677.5393476391455, 7712.730054448096, 7328.3038111379465, 7515.473916825825, 7542.245775422455, 7554.395648348132, 7564.886526702104, 2127.819135373495, 2175.1082056356895, 2175.7389113306344, 2178.6296329623924, 2180.040782055898, 2095.277129390358, 2106.852520323578, 2156.762756096828, 2160.103557023833, 2165.7401111866598, 2114.9471263218466, 2116.0689296709397, 2129.0632121866406, 2147.4309308033385, 2151.66649169379, 2013.8791672499624, 2024.9696254556193, 2026.6149431607948, 2029.5636438165866, 2030.9187632494682, 1994.7107669387858, 2019.5556977464935, 2020.4140799168224, 2039.775624681284, 2043.5606344635826, 7516.624168791594, 7548.6225688715895, 7571.053618297713, 7600.619969001583, 7602.125755538129, 4356.150550642626, 4415.116976604692, 4426.18115648606, 4426.468823741172, 4432.844850430251, 2762.861856907167, 2827.5475923852086, 2851.7290857368725, 2882.2506148401358, 2938.44169607776, 859.8366310401004, 865.2299453486402, 873.3973558244854, 886.0607755778894, 886.1581497577328, 9819.705408837746, 9863.457509836957, 9913.15738162257, 9954.950225248827, 9986.35088719231, 3915.8629447969183, 3985.501812273466, 3985.501812273466, 4065.57311482296, 4076.144009757925, 8368.288695460955, 8429.662813487514, 8492.150784921581, 8525.957370213233, 8585.969683638003, 4386.0569977455, 4409.470863496401, 4416.315471101984, 4433.29067349229, 4450.287953927421, 3474.8262586870555, 3946.2896678598127, 4054.0675644601906, 4055.9391609125887, 4095.058136628592, 3427.143214196451, 3436.3127374524697, 3467.549218601566, 3538.6992105670793, 3555.3955827509385, 1106.9778604427866, 1130.63819577736, 1146.3064845768356, 1158.7219067423757, 1159.8318243854762, 735.9963200183964, 758.0562200060959, 794.5510786405662, 817.9754607361789, 826.8718348655968, 4821.325014497973, 4840.975795121002, 4872.220444728827, 4879.5120487951635, 4909.435414927258, 849.163573879728, 862.5514732339274, 864.4121997041946, 936.1247233836249, 941.4868896451451, 3222.935541289161, 3313.204830840581, 3368.1916340078205, 3397.167693914957, 3399.983000084984, 8841.204291613785, 9148.679796207234, 9178.954105229432, 9216.07839216073, 9225.861612075823, 8940.240079593177, 8971.730848074438, 9033.05152958943, 9077.500737459372, 9092.090790920854, 6439.710213040425, 6695.430888374445, 6737.730490780412, 6890.1731792184255, 6927.792166235021, 5012.47369026247, 5062.848114556569, 5123.077845987758, 5233.450487698889, 5435.592330575144, 8054.718084867001, 8125.8781118282095, 8278.461899976483, 8288.58557072132, 8328.62521186536, 8357.582120894052, 8584.240230752555, 8593.8710919335, 8601.22588966996, 8751.168638979287, 5697.658140511582, 6125.84685382869, 6229.813105606839, 6320.08358787964, 6354.491640668828, 6943.895841562482, 7021.508494405342, 7034.050403195708, 7054.576725396492, 7057.435405167472, 6236.968815155895, 6322.968385158045, 6476.287608363171, 6542.738290468328, 6566.540342176002, 6395.456386207225, 6452.935470645233, 6555.901661475177, 6581.80254592363, 6691.866162676622, 5885.411458854081, 5951.732172052181, 5990.221271234712, 6002.789902353396, 6027.065804800263, 4090.836366545424, 4120.0, 4159.292920203512, 4223.619874211336, 4236.4069030336395, 4176.749395036308, 4337.392765012838, 4392.934105988476, 4401.383806267189, 4507.594316511399], "value": 3943.8127274593235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.3610208978753, 284.5793561392066, 286.44858647104326, 289.13548490014864, 289.94780939430905, 5068.619853510983, 5104.239037532055, 5114.411842638097, 5151.381834179903, 5177.378714554254, 5020.698758074509, 5028.371453568304, 5039.924401133986, 5042.974785126078, 5043.31915191449, 3708.4053856841547, 3746.5504139503296, 3854.1520865409707, 3869.593692662272, 3953.169834334786, 3855.691544676419, 3858.3633700439536, 3858.884233473, 3872.7869967151796, 3875.5736868944396, 3838.4242363645535, 3849.095462566295, 3851.826667799942, 3861.6524512793844, 3868.2456920900436, 3779.130799916035, 3794.2790869734663, 3872.7289089763726, 3873.2640798248385, 3898.2203559288255, 3304.4052070627276, 3306.206510437502, 3307.4873394623874, 3312.569365982407, 3315.96684033159, 4903.376693469013, 4908.239222920453, 4964.429090654548, 4968.652194346397, 4974.502549745032, 4383.912321753579, 4390.648748100144, 4392.802323895432, 4408.40486534317, 4447.933281000787, 5114.693118412907, 5271.472852714736, 5271.789128434896, 5278.392984806758, 5302.322327224765, 3632.70938324936, 3656.122530592665, 3670.9809604717298, 3672.59601443841, 3683.355412802749, 5478.342598888119, 5486.780528778844, 5500.614956953055, 5505.174223866412, 5531.419200983879, 3548.3080799244417, 3674.9448758268645, 3720.404735242348, 3750.268697603941, 3759.323321802102, 3803.6196380362294, 3834.559025712023, 3840.8847734568008, 3845.750026248285, 3857.3442514772555, 4397.889013273582, 4439.378487011821, 4439.533848945879, 4444.888877778017, 4461.420015397977, 4999.525045120708, 5247.871707582888, 5348.598855085884, 5358.419266316446, 5405.4324295949145, 1296.027979015738, 1308.7644224039764, 1312.061875758837, 1315.7763180259378, 1316.7300703355788, 800.2877439079224, 809.906860711014, 823.4194892600702, 831.960049937578, 833.3624777045565, 4488.910221795546, 4563.630910726835, 4711.858644240679, 4711.986922811543, 4718.174319494144, 5503.752331145108, 5513.61404701675, 5515.696636685021, 5536.833894983157, 5554.416786237468, 4598.448186217608, 4702.9764851176205, 4714.198586240347, 4719.528047195322, 4739.644526660445, 2821.111349924767, 2928.0089539113183, 2931.5016447204025, 2936.2365784895655, 2938.397628486155, 5142.61430392722, 5157.535821776059, 5168.914527949098, 5180.352455943007, 5254.868628284324, 5339.946600533945, 5580.637258578179, 5628.971855140698, 5646.2942132233475, 5714.514266287412, 5189.325387699575, 5204.1673332266655, 5206.375234971828, 5230.189320655304, 5232.659877108052, 5545.251391062155, 5585.357683866408, 5591.27313449249, 5594.356648985418, 5640.71796410186, 5234.476552344735, 5423.647462914898, 5428.37573679032, 5467.316585426822, 5467.863303417446, 5151.587872970078, 5220.660657057279, 5222.399424066129, 5225.738713064407, 5242.5281724644965, 3679.632036796406, 3708.9443658344608, 3709.925801483901, 3727.589965103891, 3727.9254414912093, 5386.299781028441, 5554.972225138848, 5565.0, 5571.0807716726285, 5641.37944826077, 5289.942011597773, 5311.521963023347, 5319.654222475681, 5322.654027488201, 5343.4389389113585, 5321.175217841241, 5322.654027488201, 5362.356517217802, 5395.92081583677, 5401.783928642811, 4977.402711674622, 5240.554552863049, 5249.530131563169, 5269.499397557226, 5316.2823018893505, 5063.594912407072, 5201.973990130025, 5326.334208223972, 5348.358197016383, 5354.223637572608, 5250.894982100413, 5270.973645131749, 5277.736113194248, 5279.366476022902, 5300.5494532963585], "value": 3953.717857072818, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.6556659403922, 695.4332219241318, 699.367072799117, 702.0557350363762, 734.8971144039832, 11363.261388009774, 13052.0, 13063.216207027584, 14233.14601123932, 14243.92878035611, 13957.860421395782, 14482.0, 14715.48495802647, 14724.411023559045, 14751.704965900703, 792.9960350198241, 800.6477150053987, 801.5911884938694, 802.7591722483261, 804.2118723650826, 785.5483097219093, 786.7128498098182, 793.4683761879526, 793.9841203175962, 794.2494342846013, 833.6540335760652, 834.028356964136, 835.6281454752607, 837.5226121110969, 839.492107275099, 798.1738900238283, 817.2603793566832, 828.5401602110796, 829.9751007469755, 832.2343444031505, 593.6912805341199, 596.4423264247943, 597.4801922327559, 598.5211830535554, 599.2748773983485, 13534.390952407068, 13806.378712957841, 13880.0, 13888.652783680387, 13890.166590004632, 13930.51243206493, 14541.709165816625, 14566.344514496766, 14570.854291457055, 14640.926795366062, 11152.219344645879, 11197.49611267495, 11197.552097916074, 11269.887301127048, 11297.49161287744, 3344.4147274227157, 3364.5457863188403, 3408.829558522064, 3417.111550996734, 3434.3303055904107, 70368.6481567594, 70637.99999999949, 71603.64198178975, 72056.279437206, 72129.0, 10612.522436490377, 10642.414667193223, 10651.0, 10748.247622666338, 10765.89234107649, 21416.464588385104, 21469.46326341854, 21495.247666331605, 21579.460513486974, 21589.56820863574, 6869.0, 6876.071730316443, 6895.724171033103, 6942.340477654615, 6998.195207551095, 3698.7041036716996, 3767.5102236709054, 3772.0946972726897, 3840.5007349044445, 3934.1738234970976, 2957.3937342844665, 3093.458644737162, 3102.2089367211215, 3112.9221769455494, 3124.172094394987, 499.6003197442032, 502.0786856119062, 505.61320589748874, 513.4531723714232, 514.5214950096446, 489.42003725584584, 493.53854146373254, 495.17306098814345, 509.2259765156941, 510.2321006884615, 8077.0, 8087.5551844647935, 8115.1073381927945, 8213.38399620019, 8285.710000149966, 498.24764605446035, 510.4436164580625, 520.2482412913304, 521.8460554136539, 531.7766538054027, 3801.752886062397, 3864.7681139131178, 3872.225554888977, 3939.527256729211, 3945.8816235512404, 81492.59253703694, 103974.0, 104578.0, 104963.4751826236, 106864.46567767266, 93229.53385233166, 94190.0, 95110.52444737732, 95413.0, 95497.52251238699, 83461.58269208614, 83554.0, 83617.16382836094, 86078.0, 86429.56785216034, 7474.364679002346, 7527.510711803715, 7656.846863062596, 7673.994127450078, 7700.306972372513, 35160.47259291061, 40653.0, 47263.29105063496, 48231.758841206974, 48521.5147848517, 67393.0, 70714.0, 71137.0, 71475.0, 71688.28311716816, 9440.291978101532, 9525.285603579621, 9621.470819105016, 9713.805723885342, 9811.313208075366, 10573.312734672221, 10635.361878287327, 10658.253922225369, 10667.893321067, 10677.540088246498, 9810.0, 9883.0, 10118.696439106545, 10204.795904082026, 10292.536835842255, 9606.375585586915, 9677.806443870943, 9827.459489728133, 9857.30998830075, 9879.753006174615, 9346.392484488486, 9392.825201232074, 9731.270154738282, 10065.547050382604, 10181.84727229107, 8470.788230294293, 8789.0, 8847.823043538965, 9009.369344145847, 9028.458292502472, 8069.878951815845, 8380.371472139735, 8427.0730219677, 8536.146385361415, 8624.698135565224], "value": 6516.236573072991, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 113.0, 114.0, 115.0, 115.0, 113.0, 114.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 131.0, 155.0, 182.0, 140.0, 165.0, 163.0, 134.0, 188.0, 134.0], "value": 154.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 171.0, 167.0, 165.0, 162.0, 163.0, 163.0, 165.0, 163.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 173.0, 79.0, 77.0, 76.0, 76.0, 172.0, 78.0], "value": 96.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 136.0, 88.0, 86.0, 88.0, 139.0, 95.0, 86.0, 140.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 117.0, 119.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 148.0, 149.0, 148.0, 148.0, 147.0, 149.0, 150.0, 151.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 66.0, 65.0, 67.0, 65.0, 64.0, 64.0, 64.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 67.0, 65.0, 65.0, 65.0, 67.0, 67.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 86.0, 86.0, 86.0, 92.0, 84.0, 109.0, 92.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 149.0, 133.0, 132.0, 135.0, 133.0, 138.0, 152.0, 137.0, 160.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 169.0, 173.0, 176.0, 172.0, 176.0, 169.0, 182.0, 170.0, 172.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 70.0, 68.0, 69.0, 70.0, 71.0, 71.0, 69.0, 67.0], "value": 69.7, "unit": "score"}]}]} 22:21:14 INFO - Return code: 0 22:21:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:14 ERROR - # TBPL SUCCESS # 22:21:14 INFO - Running post-action listener: _resource_record_post_action 22:21:14 INFO - ##### 22:21:14 INFO - ##### Finished run-tests step (success) 22:21:14 INFO - ##### 22:21:14 INFO - Running post-run listener: _resource_record_post_run 22:21:14 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 543997952; Read time: 28; Write time: 577404 22:21:14 INFO - TinderboxPrint: CPU usage
13.4% 22:21:14 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 22:21:14 INFO - TinderboxPrint: I/O write bytes / time
543,997,952 / 577,404 22:21:14 INFO - TinderboxPrint: CPU idle
8,283.3 (86.5%) 22:21:14 INFO - TinderboxPrint: CPU user
1,230.3 (12.9%) 22:21:14 INFO - TinderboxPrint: Swap in / out
0 / 0 22:21:14 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 245891072; Read time: 0; Write time: 302096 22:21:14 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 8192; Write bytes: 298106880; Read time: 28; Write time: 275308 22:21:15 INFO - Running post-run listener: _upload_blobber_files 22:21:15 INFO - Blob upload gear active. 22:21:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:15 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:21:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:21:15 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:21:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:21:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:16 INFO - (blobuploader) - INFO - Done attempting. 22:21:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:19 INFO - (blobuploader) - INFO - Done attempting. 22:21:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:21:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:21:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:20 INFO - (blobuploader) - INFO - Done attempting. 22:21:20 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:20 INFO - Return code: 0 22:21:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed4723fd4e3fb2aba4220730d0a769fc74d6fc744c3810e00ad3d091ccf1cf38deb8fd7e1e7db3114c7e7f40ac89bc2fe03d7e3685dbb1fdddaaf41822c45bdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64ae0ebfd067b5ffb8e7fa6861dc479f3729df1b95d7be361ef9ac166b5d256117c158b4f1c4a98c49c9fac5524976aea909a9bde4f90dd76103136dfb8e821e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3583be8b5f065dbb9fcf728a850bce83c6c899c5fa39398384cdfdcbe3cf81265096b774e7700698d2e70305ba1ac57ce488ad7e94d90fb317b634442cbd5ff6"} 22:21:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:20 INFO - Contents: 22:21:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed4723fd4e3fb2aba4220730d0a769fc74d6fc744c3810e00ad3d091ccf1cf38deb8fd7e1e7db3114c7e7f40ac89bc2fe03d7e3685dbb1fdddaaf41822c45bdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64ae0ebfd067b5ffb8e7fa6861dc479f3729df1b95d7be361ef9ac166b5d256117c158b4f1c4a98c49c9fac5524976aea909a9bde4f90dd76103136dfb8e821e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3583be8b5f065dbb9fcf728a850bce83c6c899c5fa39398384cdfdcbe3cf81265096b774e7700698d2e70305ba1ac57ce488ad7e94d90fb317b634442cbd5ff6"} 22:21:20 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:20 INFO - Copying logs to upload dir... 22:21:20 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.153405 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-17 22:21:18.915216) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:18.918927) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975 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/Firefox-Non-PGO/sha512/ed4723fd4e3fb2aba4220730d0a769fc74d6fc744c3810e00ad3d091ccf1cf38deb8fd7e1e7db3114c7e7f40ac89bc2fe03d7e3685dbb1fdddaaf41822c45bdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64ae0ebfd067b5ffb8e7fa6861dc479f3729df1b95d7be361ef9ac166b5d256117c158b4f1c4a98c49c9fac5524976aea909a9bde4f90dd76103136dfb8e821e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3583be8b5f065dbb9fcf728a850bce83c6c899c5fa39398384cdfdcbe3cf81265096b774e7700698d2e70305ba1ac57ce488ad7e94d90fb317b634442cbd5ff6"} build_url:https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/AJbk2RdqQEiXspb1t3ZmJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed4723fd4e3fb2aba4220730d0a769fc74d6fc744c3810e00ad3d091ccf1cf38deb8fd7e1e7db3114c7e7f40ac89bc2fe03d7e3685dbb1fdddaaf41822c45bdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64ae0ebfd067b5ffb8e7fa6861dc479f3729df1b95d7be361ef9ac166b5d256117c158b4f1c4a98c49c9fac5524976aea909a9bde4f90dd76103136dfb8e821e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3583be8b5f065dbb9fcf728a850bce83c6c899c5fa39398384cdfdcbe3cf81265096b774e7700698d2e70305ba1ac57ce488ad7e94d90fb317b634442cbd5ff6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:18.943737) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:18.944060) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466224630.503439-726696975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-17 22:21:19.029575) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 22:21:19.029894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-17 22:21:19.030219) ========= ========= Total master_lag: 0.15 =========