builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1469214763.24 results: success (0) buildid: 20160722101927 builduid: d5c5ac31492d4ffd8dee2a614a749b96 revision: 02b002977b46df0249540aed40854f0905340bd4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.237359) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.237792) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.238065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.258086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.258472) ========= 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=ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 12:12:43-- 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% 112M=0s 2016-07-22 12:12:43 (112 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128911 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.400975) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.401301) ========= 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=ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016721 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.456989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:43.461824) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 02b002977b46df0249540aed40854f0905340bd4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 02b002977b46df0249540aed40854f0905340bd4 --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=ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 12:12:43,545 truncating revision to first 12 chars 2016-07-22 12:12:43,545 Setting DEBUG logging. 2016-07-22 12:12:43,545 attempt 1/10 2016-07-22 12:12:43,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/02b002977b46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 12:12:43,856 unpacking tar archive at: mozilla-aurora-02b002977b46/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:44.184938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:44.185270) ========= script_repo_revision: 02b002977b46df0249540aed40854f0905340bd4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:44.185663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:44.185925) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:12:44.196884) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-22 12:12:44.197291) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:12:44 INFO - MultiFileLogger online at 20160722 12:12:44 in /builds/slave/test 12:12:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:12:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:12:44 INFO - {'append_to_log': False, 12:12:44 INFO - 'base_work_dir': '/builds/slave/test', 12:12:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:12:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:12:44 INFO - 'branch': 'Mozilla-Aurora', 12:12:44 INFO - 'buildbot_json_path': 'buildprops.json', 12:12:44 INFO - 'config_files': ('talos/linux_config.py',), 12:12:44 INFO - 'default_actions': ('clobber', 12:12:44 INFO - 'read-buildbot-config', 12:12:44 INFO - 'download-and-extract', 12:12:44 INFO - 'populate-webroot', 12:12:44 INFO - 'create-virtualenv', 12:12:44 INFO - 'install', 12:12:44 INFO - 'run-tests'), 12:12:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:12:44 INFO - 'download_minidump_stackwalk': True, 12:12:44 INFO - 'download_symbols': 'ondemand', 12:12:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:12:44 INFO - 'tooltool.py': '/tools/tooltool.py', 12:12:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:12:44 INFO - '/tools/misc-python/virtualenv.py')}, 12:12:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:12:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:12:44 INFO - 'installer_path': 'installer.exe', 12:12:44 INFO - 'log_level': 'info', 12:12:44 INFO - 'log_name': 'talos', 12:12:44 INFO - 'log_to_console': True, 12:12:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:12:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:12:44 INFO - 'opt_config_files': (), 12:12:44 INFO - 'pip_index': False, 12:12:44 INFO - 'sps_profile': False, 12:12:44 INFO - 'sps_profile_interval': 0, 12:12:44 INFO - 'suite': 'dromaeojs', 12:12:44 INFO - 'system_bits': '32', 12:12:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:12:44 INFO - 'title': 'talos-linux64-ix-035', 12:12:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:12:44 INFO - 'use_talos_json': True, 12:12:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:12:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:12:44 INFO - 'work_dir': 'build'} 12:12:44 INFO - ##### 12:12:44 INFO - ##### Running clobber step. 12:12:44 INFO - ##### 12:12:44 INFO - Running pre-action listener: _resource_record_pre_action 12:12:44 INFO - Running main action method: clobber 12:12:44 INFO - rmtree: /builds/slave/test/build 12:12:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:12:44 INFO - Running post-action listener: _resource_record_post_action 12:12:44 INFO - ##### 12:12:44 INFO - ##### Finished clobber step (success) 12:12:44 INFO - ##### 12:12:44 INFO - ##### 12:12:44 INFO - ##### Running read-buildbot-config step. 12:12:44 INFO - ##### 12:12:44 INFO - Running pre-action listener: _resource_record_pre_action 12:12:44 INFO - Running main action method: read_buildbot_config 12:12:44 INFO - Using buildbot properties: 12:12:44 INFO - { 12:12:44 INFO - "project": "", 12:12:44 INFO - "product": "firefox", 12:12:44 INFO - "script_repo_revision": "production", 12:12:44 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:12:44 INFO - "repository": "", 12:12:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:12:44 INFO - "buildid": "20160722101927", 12:12:44 INFO - "pgo_build": "True", 12:12:44 INFO - "basedir": "/builds/slave/test", 12:12:44 INFO - "buildnumber": 380, 12:12:44 INFO - "slavename": "talos-linux64-ix-035", 12:12:44 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:12:44 INFO - "platform": "ubuntu64_hw", 12:12:44 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:12:44 INFO - "branch": "mozilla-aurora", 12:12:44 INFO - "repo_path": "releases/mozilla-aurora", 12:12:44 INFO - "revision": "02b002977b46df0249540aed40854f0905340bd4", 12:12:44 INFO - "stage_platform": "linux64-pgo", 12:12:44 INFO - "builduid": "d5c5ac31492d4ffd8dee2a614a749b96", 12:12:44 INFO - "slavebuilddir": "test" 12:12:44 INFO - } 12:12:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 12:12:44 INFO - Running post-action listener: _resource_record_post_action 12:12:44 INFO - ##### 12:12:44 INFO - ##### Finished read-buildbot-config step (success) 12:12:44 INFO - ##### 12:12:44 INFO - ##### 12:12:44 INFO - ##### Running download-and-extract step. 12:12:44 INFO - ##### 12:12:44 INFO - Running pre-action listener: _resource_record_pre_action 12:12:44 INFO - Running main action method: download_and_extract 12:12:44 INFO - mkdir: /builds/slave/test/build/tests 12:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:44 INFO - https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:12:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:12:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:12:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:12:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:12:45 INFO - Downloaded 1361 bytes. 12:12:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:12:45 INFO - Using the following test package requirements: 12:12:45 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 12:12:45 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:12:45 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 12:12:45 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'jsshell-linux-x86_64.zip'], 12:12:45 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:12:45 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 12:12:45 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:12:45 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:12:45 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:12:45 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:12:45 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:12:45 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:45 INFO - https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:12:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:12:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:12:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:12:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:12:46 INFO - Downloaded 24494547 bytes. 12:12:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:12:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:12:47 INFO - Return code: 0 12:12:47 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:12: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')]} 12:12:47 INFO - https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:12:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:12:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:12:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:12:48 INFO - Downloaded 24494547 bytes. 12:12:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:12:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:12:50 INFO - Return code: 0 12:12:50 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')]} 12:12:50 INFO - https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:12:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 12:12:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 12:12:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 12:12:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:12:51 INFO - Downloaded 10833369 bytes. 12:12:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:12:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:12:51 INFO - Return code: 0 12:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:51 INFO - https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:12:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:12:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:12:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:12:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:12:52 INFO - Downloaded 63645125 bytes. 12:12:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:12:52 INFO - mkdir: /builds/slave/test/properties 12:12:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:12:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:12:52 INFO - Contents: 12:12:52 INFO - build_url:https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:12:53 INFO - Running post-action listener: _resource_record_post_action 12:12:53 INFO - Running post-action listener: set_extra_try_arguments 12:12:53 INFO - ##### 12:12:53 INFO - ##### Finished download-and-extract step (success) 12:12:53 INFO - ##### 12:12:53 INFO - ##### 12:12:53 INFO - ##### Running populate-webroot step. 12:12:53 INFO - ##### 12:12:53 INFO - Running pre-action listener: _resource_record_pre_action 12:12:53 INFO - Running main action method: populate_webroot 12:12:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:12:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:12:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:12:53 INFO - '--tppagecycles', 12:12:53 INFO - '7'], 12:12:53 INFO - 'tests': ['tsvgm']}}, 12:12:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:12:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:12:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:12:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:12:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['damp', 'tps']}, 12:12:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'tests': ['damp', 'tps']}, 12:12:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['dromaeo_dom']}, 12:12:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:12:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['a11yr', 12:12:53 INFO - 'ts_paint', 12:12:53 INFO - 'tpaint', 12:12:53 INFO - 'sessionrestore', 12:12:53 INFO - 'sessionrestore_no_auto_restore', 12:12:53 INFO - 'tabpaint']}, 12:12:53 INFO - 'other-e10s': {'tests': ['a11yr', 12:12:53 INFO - 'ts_paint', 12:12:53 INFO - 'tpaint', 12:12:53 INFO - 'sessionrestore', 12:12:53 INFO - 'sessionrestore_no_auto_restore', 12:12:53 INFO - 'tabpaint']}, 12:12:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:12:53 INFO - 'ts_paint', 12:12:53 INFO - 'tpaint', 12:12:53 INFO - 'sessionrestore', 12:12:53 INFO - 'sessionrestore_no_auto_restore', 12:12:53 INFO - 'tabpaint']}, 12:12:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:12:53 INFO - 'ts_paint', 12:12:53 INFO - 'tpaint', 12:12:53 INFO - 'sessionrestore', 12:12:53 INFO - 'sessionrestore_no_auto_restore', 12:12:53 INFO - 'tabpaint']}, 12:12:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['a11yr', 12:12:53 INFO - 'ts_paint', 12:12:53 INFO - 'tpaint', 12:12:53 INFO - 'sessionrestore', 12:12:53 INFO - 'sessionrestore_no_auto_restore', 12:12:53 INFO - 'tabpaint']}, 12:12:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['a11yr', 12:12:53 INFO - 'ts_paint', 12:12:53 INFO - 'tpaint', 12:12:53 INFO - 'sessionrestore', 12:12:53 INFO - 'sessionrestore_no_auto_restore', 12:12:53 INFO - 'tabpaint']}, 12:12:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['tsvgx', 12:12:53 INFO - 'tsvgr_opacity', 12:12:53 INFO - 'tart', 12:12:53 INFO - 'tscrollx', 12:12:53 INFO - 'cart']}, 12:12:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:12:53 INFO - 'tsvgr_opacity', 12:12:53 INFO - 'tart', 12:12:53 INFO - 'tscrollx', 12:12:53 INFO - 'cart']}, 12:12:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'talos_options': ['--disable-e10s'], 12:12:53 INFO - 'tests': ['tp5o']}, 12:12:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'tests': ['tp5o']}, 12:12:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'talos_options': ['--disable-e10s', 12:12:53 INFO - '--xperf_path', 12:12:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:53 INFO - 'tests': ['tp5n']}, 12:12:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:53 INFO - 'talos_options': ['--xperf_path', 12:12:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:53 INFO - 'tests': ['tp5n']}}, 12:12:53 INFO - 'talos.zip': {'path': '', 12:12:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:12:53 INFO - Running post-action listener: _resource_record_post_action 12:12:53 INFO - ##### 12:12:53 INFO - ##### Finished populate-webroot step (success) 12:12:53 INFO - ##### 12:12:53 INFO - ##### 12:12:53 INFO - ##### Running create-virtualenv step. 12:12:53 INFO - ##### 12:12:53 INFO - Running pre-action listener: _resource_record_pre_action 12:12:53 INFO - Running main action method: create_virtualenv 12:12:53 INFO - Creating virtualenv /builds/slave/test/build/venv 12:12:53 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 12:12:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:12:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:12:53 INFO - Using real prefix '/usr' 12:12:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:12:54 INFO - Installing distribute.............................................................................................................................................................................................done. 12:12:56 INFO - Installing pip.................done. 12:12:56 INFO - Return code: 0 12:12:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:12:56 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')]} 12:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:56 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')]} 12:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:56 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:12:56 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 12:12:56 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 12:12:56 INFO - Using env: {'DISPLAY': ':0', 12:12:56 INFO - 'HOME': '/home/cltbld', 12:12:56 INFO - 'LANG': 'en_US.UTF-8', 12:12:56 INFO - 'LANGUAGE': 'en_US:en', 12:12:56 INFO - 'LOGNAME': 'cltbld', 12:12:56 INFO - 'MAIL': '/var/mail/cltbld', 12:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:56 INFO - 'MOZ_NO_REMOTE': '1', 12:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:56 INFO - 'NO_EM_RESTART': '1', 12:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:56 INFO - 'PWD': '/builds/slave/test', 12:12:56 INFO - 'SHELL': '/bin/bash', 12:12:56 INFO - 'SHLVL': '1', 12:12:56 INFO - 'TERM': 'linux', 12:12:56 INFO - 'TMOUT': '86400', 12:12:56 INFO - 'USER': 'cltbld', 12:12:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:56 INFO - '_': '/tools/buildbot/bin/python'} 12:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:56 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 12:12:56 INFO - Cleaning up... 12:12:56 INFO - Return code: 0 12:12:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:12:56 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')]} 12:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:56 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')]} 12:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:56 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:12:56 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 12:12:56 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 12:12:56 INFO - Using env: {'DISPLAY': ':0', 12:12:56 INFO - 'HOME': '/home/cltbld', 12:12:56 INFO - 'LANG': 'en_US.UTF-8', 12:12:56 INFO - 'LANGUAGE': 'en_US:en', 12:12:56 INFO - 'LOGNAME': 'cltbld', 12:12:56 INFO - 'MAIL': '/var/mail/cltbld', 12:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:56 INFO - 'MOZ_NO_REMOTE': '1', 12:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:56 INFO - 'NO_EM_RESTART': '1', 12:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:56 INFO - 'PWD': '/builds/slave/test', 12:12:56 INFO - 'SHELL': '/bin/bash', 12:12:56 INFO - 'SHLVL': '1', 12:12:56 INFO - 'TERM': 'linux', 12:12:56 INFO - 'TMOUT': '86400', 12:12:56 INFO - 'USER': 'cltbld', 12:12:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:56 INFO - '_': '/tools/buildbot/bin/python'} 12:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:56 INFO - Downloading/unpacking psutil>=3.1.1 12:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:12:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:58 INFO - Installing collected packages: psutil 12:12:58 INFO - Running setup.py install for psutil 12:12:59 INFO - building 'psutil._psutil_linux' extension 12:12:59 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 12:12:59 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 12:12:59 INFO - building 'psutil._psutil_posix' extension 12:12:59 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 12:12:59 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 12:12:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:59 INFO - Successfully installed psutil 12:12:59 INFO - Cleaning up... 12:12:59 INFO - Return code: 0 12:12:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:12:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:12:59 INFO - Using env: {'DISPLAY': ':0', 12:12:59 INFO - 'HOME': '/home/cltbld', 12:12:59 INFO - 'LANG': 'en_US.UTF-8', 12:12:59 INFO - 'LANGUAGE': 'en_US:en', 12:12:59 INFO - 'LOGNAME': 'cltbld', 12:12:59 INFO - 'MAIL': '/var/mail/cltbld', 12:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:59 INFO - 'MOZ_NO_REMOTE': '1', 12:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:59 INFO - 'NO_EM_RESTART': '1', 12:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:59 INFO - 'PWD': '/builds/slave/test', 12:12:59 INFO - 'SHELL': '/bin/bash', 12:12:59 INFO - 'SHLVL': '1', 12:12:59 INFO - 'TERM': 'linux', 12:12:59 INFO - 'TMOUT': '86400', 12:12:59 INFO - 'USER': 'cltbld', 12:12:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:59 INFO - '_': '/tools/buildbot/bin/python'} 12:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:13:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:13:01 INFO - Installing collected packages: mozsystemmonitor 12:13:01 INFO - Running setup.py install for mozsystemmonitor 12:13:02 INFO - Successfully installed mozsystemmonitor 12:13:02 INFO - Cleaning up... 12:13:02 INFO - Return code: 0 12:13:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:13:02 INFO - Using env: {'DISPLAY': ':0', 12:13:02 INFO - 'HOME': '/home/cltbld', 12:13:02 INFO - 'LANG': 'en_US.UTF-8', 12:13:02 INFO - 'LANGUAGE': 'en_US:en', 12:13:02 INFO - 'LOGNAME': 'cltbld', 12:13:02 INFO - 'MAIL': '/var/mail/cltbld', 12:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:02 INFO - 'MOZ_NO_REMOTE': '1', 12:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:02 INFO - 'NO_EM_RESTART': '1', 12:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:02 INFO - 'PWD': '/builds/slave/test', 12:13:02 INFO - 'SHELL': '/bin/bash', 12:13:02 INFO - 'SHLVL': '1', 12:13:02 INFO - 'TERM': 'linux', 12:13:02 INFO - 'TMOUT': '86400', 12:13:02 INFO - 'USER': 'cltbld', 12:13:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:02 INFO - '_': '/tools/buildbot/bin/python'} 12:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:03 INFO - Downloading/unpacking jsonschema==2.5.1 12:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:13:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 INFO - Downloading functools32-3.2.3-2.tar.gz 12:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:13:06 INFO - warning: no files found matching '*.txt' 12:13:06 INFO - no previously-included directories found matching 'build' 12:13:06 INFO - no previously-included directories found matching 'dist' 12:13:06 INFO - no previously-included directories found matching '.git*' 12:13:06 INFO - Installing collected packages: jsonschema, functools32 12:13:06 INFO - Running setup.py install for functools32 12:13:06 INFO - warning: no files found matching '*.txt' 12:13:06 INFO - no previously-included directories found matching 'build' 12:13:06 INFO - no previously-included directories found matching 'dist' 12:13:06 INFO - no previously-included directories found matching '.git*' 12:13:06 INFO - Successfully installed jsonschema functools32 12:13:06 INFO - Cleaning up... 12:13:06 INFO - Return code: 0 12:13:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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')]} 12:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:06 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:06 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 12:13:06 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 12:13:06 INFO - Using env: {'DISPLAY': ':0', 12:13:06 INFO - 'HOME': '/home/cltbld', 12:13:06 INFO - 'LANG': 'en_US.UTF-8', 12:13:06 INFO - 'LANGUAGE': 'en_US:en', 12:13:06 INFO - 'LOGNAME': 'cltbld', 12:13:06 INFO - 'MAIL': '/var/mail/cltbld', 12:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:06 INFO - 'MOZ_NO_REMOTE': '1', 12:13:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:06 INFO - 'NO_EM_RESTART': '1', 12:13:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:06 INFO - 'PWD': '/builds/slave/test', 12:13:06 INFO - 'SHELL': '/bin/bash', 12:13:06 INFO - 'SHLVL': '1', 12:13:06 INFO - 'TERM': 'linux', 12:13:06 INFO - 'TMOUT': '86400', 12:13:06 INFO - 'USER': 'cltbld', 12:13:06 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:06 INFO - '_': '/tools/buildbot/bin/python'} 12:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:06 INFO - Downloading/unpacking blobuploader==1.2.4 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 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 12:13:06 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 12:13:08 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 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 12:13:09 INFO - Downloading docopt-0.6.1.tar.gz 12:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:09 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:09 INFO - Running setup.py install for blobuploader 12:13:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:09 INFO - Running setup.py install for requests 12:13:09 INFO - Running setup.py install for docopt 12:13:09 INFO - Successfully installed blobuploader requests docopt 12:13:09 INFO - Cleaning up... 12:13:10 INFO - Return code: 0 12:13:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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')]} 12:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:10 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:10 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 12:13:10 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 12:13:10 INFO - Using env: {'DISPLAY': ':0', 12:13:10 INFO - 'HOME': '/home/cltbld', 12:13:10 INFO - 'LANG': 'en_US.UTF-8', 12:13:10 INFO - 'LANGUAGE': 'en_US:en', 12:13:10 INFO - 'LOGNAME': 'cltbld', 12:13:10 INFO - 'MAIL': '/var/mail/cltbld', 12:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:10 INFO - 'MOZ_NO_REMOTE': '1', 12:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:10 INFO - 'NO_EM_RESTART': '1', 12:13:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:10 INFO - 'PWD': '/builds/slave/test', 12:13:10 INFO - 'SHELL': '/bin/bash', 12:13:10 INFO - 'SHLVL': '1', 12:13:10 INFO - 'TERM': 'linux', 12:13:10 INFO - 'TMOUT': '86400', 12:13:10 INFO - 'USER': 'cltbld', 12:13:10 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:10 INFO - '_': '/tools/buildbot/bin/python'} 12:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:10 INFO - Running setup.py (path:/tmp/pip-I7qOr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:10 INFO - Running setup.py (path:/tmp/pip-VGnBvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:10 INFO - Running setup.py (path:/tmp/pip-qveGqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:10 INFO - Running setup.py (path:/tmp/pip-m99AxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:10 INFO - Running setup.py (path:/tmp/pip-IAZsak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:10 INFO - Running setup.py (path:/tmp/pip-dYnsCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:10 INFO - Running setup.py (path:/tmp/pip-_af9Fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:10 INFO - Running setup.py (path:/tmp/pip-NivO2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:10 INFO - Running setup.py (path:/tmp/pip-muMTee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:10 INFO - Running setup.py (path:/tmp/pip-vkSfk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:11 INFO - Running setup.py (path:/tmp/pip-RXQ2LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:11 INFO - Running setup.py (path:/tmp/pip-5XIFEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:11 INFO - Running setup.py (path:/tmp/pip-C80jjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:11 INFO - Running setup.py (path:/tmp/pip-55jHoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:11 INFO - Running setup.py (path:/tmp/pip-FJ8ShZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:11 INFO - Running setup.py (path:/tmp/pip-nlPgJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:11 INFO - Running setup.py (path:/tmp/pip-Mo0atJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:11 INFO - Running setup.py install for manifestparser 12:13:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:11 INFO - Running setup.py install for mozcrash 12:13:11 INFO - Running setup.py install for mozdebug 12:13:11 INFO - Running setup.py install for mozdevice 12:13:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:13:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:11 INFO - Running setup.py install for mozfile 12:13:12 INFO - Running setup.py install for mozhttpd 12:13:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for mozinfo 12:13:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for mozInstall 12:13:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for mozleak 12:13:12 INFO - Running setup.py install for mozlog 12:13:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for moznetwork 12:13:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for mozprocess 12:13:12 INFO - Running setup.py install for mozprofile 12:13:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for mozrunner 12:13:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:13:12 INFO - Running setup.py install for mozscreenshot 12:13:13 INFO - Running setup.py install for moztest 12:13:13 INFO - Running setup.py install for mozversion 12:13:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:13:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:13:13 INFO - Cleaning up... 12:13:13 INFO - Return code: 0 12:13:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:13 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')]} 12:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:13 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')]} 12:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:13 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:13 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 12:13:13 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 12:13:13 INFO - Using env: {'DISPLAY': ':0', 12:13:13 INFO - 'HOME': '/home/cltbld', 12:13:13 INFO - 'LANG': 'en_US.UTF-8', 12:13:13 INFO - 'LANGUAGE': 'en_US:en', 12:13:13 INFO - 'LOGNAME': 'cltbld', 12:13:13 INFO - 'MAIL': '/var/mail/cltbld', 12:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:13 INFO - 'MOZ_NO_REMOTE': '1', 12:13:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:13 INFO - 'NO_EM_RESTART': '1', 12:13:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:13 INFO - 'PWD': '/builds/slave/test', 12:13:13 INFO - 'SHELL': '/bin/bash', 12:13:13 INFO - 'SHLVL': '1', 12:13:13 INFO - 'TERM': 'linux', 12:13:13 INFO - 'TMOUT': '86400', 12:13:13 INFO - 'USER': 'cltbld', 12:13:13 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:13 INFO - '_': '/tools/buildbot/bin/python'} 12:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:13 INFO - Running setup.py (path:/tmp/pip-JXtlUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:13 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)) 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:13 INFO - Running setup.py (path:/tmp/pip-J0rnDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:13 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)) 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:13 INFO - Running setup.py (path:/tmp/pip-s7_lez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:13 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)) 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:13 INFO - Running setup.py (path:/tmp/pip-yEz5m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:13 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)) 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:13 INFO - Running setup.py (path:/tmp/pip-9bKVuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:13 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)) 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:13 INFO - Running setup.py (path:/tmp/pip-2aiFyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:13 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)) 12:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:13 INFO - Running setup.py (path:/tmp/pip-0m1bVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:14 INFO - Running setup.py (path:/tmp/pip-vJAGdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:14 INFO - Running setup.py (path:/tmp/pip-Ey4b9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:14 INFO - Running setup.py (path:/tmp/pip-jVXOKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:14 INFO - Running setup.py (path:/tmp/pip-02vMH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:14 INFO - Running setup.py (path:/tmp/pip-K1WvjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:14 INFO - Running setup.py (path:/tmp/pip-SmA5Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:14 INFO - Running setup.py (path:/tmp/pip-lM8mHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:14 INFO - Running setup.py (path:/tmp/pip-fBWCha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:14 INFO - Running setup.py (path:/tmp/pip-_kOt99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:14 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)) 12:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:14 INFO - Running setup.py (path:/tmp/pip-w0BQP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:14 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)) 12:13:14 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)) 12:13:14 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)) 12:13:14 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)) 12:13:14 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)) 12:13:14 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)) 12:13:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13:14 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 12:13:14 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 12:13:14 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 12:13:14 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 12:13:14 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 12:13:14 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 12:13:17 INFO - Downloading blessings-1.6.tar.gz 12:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:13:18 INFO - Installing collected packages: blessings 12:13:18 INFO - Running setup.py install for blessings 12:13:18 INFO - Successfully installed blessings 12:13:18 INFO - Cleaning up... 12:13:18 INFO - Return code: 0 12:13:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:13:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:18 INFO - Reading from file tmpfile_stdout 12:13:18 INFO - Current package versions: 12:13:18 INFO - argparse == 1.2.1 12:13:18 INFO - blessings == 1.6 12:13:18 INFO - blobuploader == 1.2.4 12:13:18 INFO - docopt == 0.6.1 12:13:18 INFO - functools32 == 3.2.3-2 12:13:18 INFO - jsonschema == 2.5.1 12:13:18 INFO - manifestparser == 1.1 12:13:18 INFO - mozInstall == 1.12 12:13:18 INFO - mozcrash == 0.17 12:13:18 INFO - mozdebug == 0.1 12:13:18 INFO - mozdevice == 0.48 12:13:18 INFO - mozfile == 1.2 12:13:18 INFO - mozhttpd == 0.7 12:13:18 INFO - mozinfo == 0.9 12:13:18 INFO - mozleak == 0.1 12:13:18 INFO - mozlog == 3.2 12:13:18 INFO - moznetwork == 0.27 12:13:18 INFO - mozprocess == 0.23 12:13:18 INFO - mozprofile == 0.28 12:13:18 INFO - mozrunner == 6.12 12:13:18 INFO - mozscreenshot == 0.1 12:13:18 INFO - mozsystemmonitor == 0.3 12:13:18 INFO - moztest == 0.7 12:13:18 INFO - mozversion == 1.4 12:13:18 INFO - psutil == 3.1.1 12:13:18 INFO - requests == 1.2.3 12:13:18 INFO - wsgiref == 0.1.2 12:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:18 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')]} 12:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:18 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')]} 12:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:18 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:18 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 12:13:18 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 12:13:18 INFO - Using env: {'DISPLAY': ':0', 12:13:18 INFO - 'HOME': '/home/cltbld', 12:13:18 INFO - 'LANG': 'en_US.UTF-8', 12:13:18 INFO - 'LANGUAGE': 'en_US:en', 12:13:18 INFO - 'LOGNAME': 'cltbld', 12:13:18 INFO - 'MAIL': '/var/mail/cltbld', 12:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:18 INFO - 'MOZ_NO_REMOTE': '1', 12:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:18 INFO - 'NO_EM_RESTART': '1', 12:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:18 INFO - 'PWD': '/builds/slave/test', 12:13:18 INFO - 'SHELL': '/bin/bash', 12:13:18 INFO - 'SHLVL': '1', 12:13:18 INFO - 'TERM': 'linux', 12:13:18 INFO - 'TMOUT': '86400', 12:13:18 INFO - 'USER': 'cltbld', 12:13:18 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:18 INFO - '_': '/tools/buildbot/bin/python'} 12:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 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)) 12:13:18 INFO - Cleaning up... 12:13:18 INFO - Return code: 0 12:13:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:13:18 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')]} 12:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:18 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')]} 12:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:18 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:18 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 12:13:18 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 12:13:18 INFO - Using env: {'DISPLAY': ':0', 12:13:18 INFO - 'HOME': '/home/cltbld', 12:13:18 INFO - 'LANG': 'en_US.UTF-8', 12:13:18 INFO - 'LANGUAGE': 'en_US:en', 12:13:18 INFO - 'LOGNAME': 'cltbld', 12:13:18 INFO - 'MAIL': '/var/mail/cltbld', 12:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:18 INFO - 'MOZ_NO_REMOTE': '1', 12:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:18 INFO - 'NO_EM_RESTART': '1', 12:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:18 INFO - 'PWD': '/builds/slave/test', 12:13:18 INFO - 'SHELL': '/bin/bash', 12:13:18 INFO - 'SHLVL': '1', 12:13:18 INFO - 'TERM': 'linux', 12:13:18 INFO - 'TMOUT': '86400', 12:13:18 INFO - 'USER': 'cltbld', 12:13:18 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:18 INFO - '_': '/tools/buildbot/bin/python'} 12:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:13:18 INFO - Cleaning up... 12:13:18 INFO - Return code: 0 12:13:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:13:18 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')]} 12:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:18 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')]} 12:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:18 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 0x7f134fa04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, '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': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', '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 12:13:18 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 12:13:18 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 12:13:18 INFO - Using env: {'DISPLAY': ':0', 12:13:18 INFO - 'HOME': '/home/cltbld', 12:13:18 INFO - 'LANG': 'en_US.UTF-8', 12:13:18 INFO - 'LANGUAGE': 'en_US:en', 12:13:18 INFO - 'LOGNAME': 'cltbld', 12:13:18 INFO - 'MAIL': '/var/mail/cltbld', 12:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:18 INFO - 'MOZ_NO_REMOTE': '1', 12:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:18 INFO - 'NO_EM_RESTART': '1', 12:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:18 INFO - 'PWD': '/builds/slave/test', 12:13:18 INFO - 'SHELL': '/bin/bash', 12:13:18 INFO - 'SHLVL': '1', 12:13:18 INFO - 'TERM': 'linux', 12:13:18 INFO - 'TMOUT': '86400', 12:13:18 INFO - 'USER': 'cltbld', 12:13:18 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:18 INFO - '_': '/tools/buildbot/bin/python'} 12:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:19 INFO - Downloading/unpacking flake8 12:13:19 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 12:13:19 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 12:13:19 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 12:13:19 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 12:13:19 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 12:13:19 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 12:13:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:13:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:13:20 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 12:13:20 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 12:13:20 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 12:13:20 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 12:13:20 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 12:13:20 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 12:13:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:13:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 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 12:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:13:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:13:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:13:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:13:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:13:21 INFO - no previously-included directories found matching 'docs/_build' 12:13:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:13:21 INFO - Running setup.py install for pep8 12:13:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:13:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:13:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:13:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:13:21 INFO - no previously-included directories found matching 'docs/_build' 12:13:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:13:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:13:21 INFO - Cleaning up... 12:13:21 INFO - Return code: 0 12:13:21 INFO - Running post-action listener: _resource_record_post_action 12:13:21 INFO - Running post-action listener: _start_resource_monitoring 12:13:21 INFO - Starting resource monitoring. 12:13:21 INFO - ##### 12:13:21 INFO - ##### Finished create-virtualenv step (success) 12:13:21 INFO - ##### 12:13:21 INFO - ##### 12:13:21 INFO - ##### Running install step. 12:13:21 INFO - ##### 12:13:21 INFO - Running pre-action listener: _resource_record_pre_action 12:13:21 INFO - Running main action method: install 12:13:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:22 INFO - Reading from file tmpfile_stdout 12:13:22 INFO - Detecting whether we're running mozinstall >=1.0... 12:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:13:22 INFO - Reading from file tmpfile_stdout 12:13:22 INFO - Output received: 12:13:22 INFO - Usage: mozinstall [options] installer 12:13:22 INFO - Options: 12:13:22 INFO - -h, --help show this help message and exit 12:13:22 INFO - -d DEST, --destination=DEST 12:13:22 INFO - Directory to install application into. [default: 12:13:22 INFO - "/builds/slave/test"] 12:13:22 INFO - --app=APP Application being installed. [default: firefox] 12:13:22 INFO - mkdir: /builds/slave/test/build/application 12:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:13:38 INFO - Reading from file tmpfile_stdout 12:13:38 INFO - Output received: 12:13:38 INFO - /builds/slave/test/build/application/firefox/firefox 12:13:38 INFO - Running post-action listener: _resource_record_post_action 12:13:38 INFO - ##### 12:13:38 INFO - ##### Finished install step (success) 12:13:38 INFO - ##### 12:13:38 INFO - ##### 12:13:38 INFO - ##### Running run-tests step. 12:13:38 INFO - ##### 12:13:38 INFO - Running pre-action listener: _resource_record_pre_action 12:13:38 INFO - Running main action method: run_tests 12:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:13:38 INFO - Python 2.7.3 12:13:38 INFO - Return code: 0 12:13:38 INFO - grabbing minidump binary from tooltool 12:13: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')]} 12:13:38 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 0x27531c0>, '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 0x27540c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2754550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:13:38 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 12:13:38 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 12:13:38 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 12:13:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:13:38 INFO - Return code: 0 12:13:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:13:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:13:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:13:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:13:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:13:39 INFO - Return code: 0 12:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:13:39 INFO - Using env: {'DISPLAY': ':0', 12:13:39 INFO - 'HOME': '/home/cltbld', 12:13:39 INFO - 'LANG': 'en_US.UTF-8', 12:13:39 INFO - 'LANGUAGE': 'en_US:en', 12:13:39 INFO - 'LOGNAME': 'cltbld', 12:13:39 INFO - 'MAIL': '/var/mail/cltbld', 12:13:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:13:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:39 INFO - 'MOZ_NO_REMOTE': '1', 12:13:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:39 INFO - 'NO_EM_RESTART': '1', 12:13:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:39 INFO - 'PWD': '/builds/slave/test', 12:13:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:13:39 INFO - 'SHELL': '/bin/bash', 12:13:39 INFO - 'SHLVL': '1', 12:13:39 INFO - 'TERM': 'linux', 12:13:39 INFO - 'TMOUT': '86400', 12:13:39 INFO - 'USER': 'cltbld', 12:13:39 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418', 12:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:39 INFO - '_': '/tools/buildbot/bin/python'} 12:13:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:13:39 INFO - mozversion application_buildid: 20160722101927 12:13:39 INFO - mozversion application_changeset: 02b002977b46df0249540aed40854f0905340bd4 12:13:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:13:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:13:39 INFO - mozversion application_name: Firefox 12:13:39 INFO - mozversion application_remotingname: firefox-dev 12:13:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:13:39 INFO - mozversion application_vendor: Mozilla 12:13:39 INFO - mozversion application_version: 49.0a2 12:13:39 INFO - mozversion platform_buildid: 20160722101927 12:13:39 INFO - mozversion platform_changeset: 02b002977b46df0249540aed40854f0905340bd4 12:13:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:13:39 INFO - mozversion platform_version: 49.0a2 12:13:39 INFO - using testdate: 1469214819 12:13:39 INFO - actual date: 1469214819 12:13:39 INFO - starting webserver on 'localhost:45405' 12:13:39 INFO - SUITE-START | Running 2 tests 12:13:39 INFO - TEST-START | dromaeo_css 12:13:39 INFO - operating with platform_type : linux_ 12:13:39 INFO - Initialising browser for dromaeo_css test... 12:13:39 INFO - TEST-INFO | started process 4212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpipMc13/profile http://localhost:45405/getInfo.html) 12:13:43 INFO - PROCESS | 4212 | __metrics Screen width/height:1600/1200 12:13:43 INFO - PROCESS | 4212 | colorDepth:24 12:13:43 INFO - PROCESS | 4212 | Browser inner width/height: 1024/697 12:13:43 INFO - PROCESS | 4212 | __metrics 12:13:44 INFO - PROCESS | 4212 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:13:44 ERROR - PROCESS | 4212 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:13:45 INFO - TEST-INFO | 4212: exit 0 12:13:45 INFO - Browser initialized. 12:13:45 INFO - Running cycle 1/1 for dromaeo_css test... 12:13:45 INFO - TEST-INFO | started process 4303 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpipMc13/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:56 INFO - PROCESS | 4303 | RSS: Main: 145264640 12:13:56 INFO - PROCESS | 4303 | 12:14:48 INFO - PROCESS | 4303 | 1469214888140 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] 12:16:55 INFO - PROCESS | 4303 | Cycle 1(1): loaded http://localhost:45405/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45405/tests/dromaeo/cssquery-ext.html) 12:16:57 INFO - PROCESS | 4303 | RSS: Main: 255332352 12:16:57 INFO - PROCESS | 4303 | 12:19:59 INFO - PROCESS | 4303 | Cycle 1(1): loaded http://localhost:45405/tests/dromaeo/cssquery-ext.html (next: http://localhost:45405/tests/dromaeo/cssquery-jquery.html) 12:20:00 INFO - PROCESS | 4303 | RSS: Main: 228839424 12:20:00 INFO - PROCESS | 4303 | 12:23:02 INFO - PROCESS | 4303 | Cycle 1(1): loaded http://localhost:45405/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45405/tests/dromaeo/cssquery-mootools.html) 12:23:02 INFO - PROCESS | 4303 | RSS: Main: 218263552 12:23:02 INFO - PROCESS | 4303 | 12:26:04 INFO - PROCESS | 4303 | Cycle 1(1): loaded http://localhost:45405/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45405/tests/dromaeo/cssquery-prototype.html) 12:26:05 INFO - PROCESS | 4303 | RSS: Main: 220037120 12:26:05 INFO - PROCESS | 4303 | 12:29:07 INFO - PROCESS | 4303 | Cycle 1(1): loaded http://localhost:45405/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45405/tests/dromaeo/cssquery-yui.html) 12:29:08 INFO - PROCESS | 4303 | RSS: Main: 219107328 12:29:08 INFO - PROCESS | 4303 | 12:32:10 INFO - PROCESS | 4303 | Cycle 1(1): loaded http://localhost:45405/tests/dromaeo/cssquery-yui.html (next: http://localhost:45405/tests/dromaeo/cssquery-dojo.html) 12:32:11 INFO - PROCESS | 4303 | RSS: Main: 261718016 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | __start_tp_report 12:32:11 INFO - PROCESS | 4303 | _x_x_mozilla_page_load 12:32:11 INFO - PROCESS | 4303 | _x_x_mozilla_page_load_details 12:32:11 INFO - PROCESS | 4303 | |i|pagename|runs| 12:32:11 INFO - PROCESS | 4303 | |0;dojo.html;3352.966470335297;3356.9328613427733;3368.528406023157;3374.2070613405863;3402.0304213299214;5515.751791169397;5562.415946325638;5579.944200557993;5584.832455026346;5605.27131472909;5531.944680553193;5564.944350556493;5569.526590239824;5574.581906357019;5605.1872478490595;4927.211646136619;5017.297578339027;5036.899262014757;5075.695458272497;5117.846464606049;4891.388576427947;5023.748812559375;5053.444121146671;5061.089003979282;5069.315642388286;5000.399952005764;5022.82420115296;5048.747562621873;5050.141475949097;5088.720120393377;5130.666506677054;5142.305788718531;5147.716875571842;5148.974255128738;5166.250893620423;4942.357493525855;4990.476000020019;4998.12532806761;5050.040492306487;5079.619028572835;5423.566114710814;5437.782488700447;5442.074847275933;5464.562834973232;5495.890082198373;5953.970230148865;5959.672218028007;6006.249218847644;6007.8197654070445;6012.278526576795;5855.121731740273;5906.438888305604;5907.261592300963;5908.379620139866;6021.036634138561;5537.58468114889;5564.026295398329;5578.721063946787;5582.469665381782;5602.13166959122;5930.8813823723285;6060.606060606043;6065.144140771246;6069.687524973978;6081.087836824512;5747.4252574742195;5774.797882074107;5788.479036886701;5862.237909071793;5872.7357268923;5914.172015917775;5922.466977972003;5929.822105336847;5947.078202878561;5959.22530071088;5745.109508026263;5887.558433117533;5899.085641725539;5908.527317814564;5913.319968203626;5892.057270836732;5956.880862382728;5975.372585878507;5986.281646202484;6027.126066720301;4558.566936141061;4561.771911404483;4569.428821397325;4587.79354929029;4593.586577208001;4039.5354534228354;4064.9187016260103;4068.7762173080173;4120.1553681495225;4155.688323375759;5740.598158128974;5768.855778605568;5772.365039845614;5773.364929857712;5795.072788353843;6004.369541198199;6012.6392416455155;6020.879582408327;6031.909521357095;6033.4871535919065;5536.916946245809;5612.382637909826;5630.577706672016;5711.343195532567;5726;5117.078925793393;5118.820841270538;5119.976004799055;5126.256692779526;5137.845864624067;6264.5301602380005;6266.373362663697;6276.497880169575;6276.560640755195;6276.937230627726;5866.149408335682;5875.383084776037;5914.645121292736;5955.285365756137;5993.460588547052;6202.76700387851;6217.720202590985;6226.315105338319;6246.312905580292;6246.656433896179;5769.365369809401;5870.119482077723;5874.324452687995;5878.382769809108;5903.793367232126;5795.971020144872;5841.32824725145;5863.794767183128;5904.261967254093;5904.645721256738;5725.398833122629;5751.1373294006235;5754.971225143848;5755.3381361144;5755.683437410982;5699.344575373885;5701.287339082614;5738.110592858114;5756.136579513107;5760.539156867524;5669.262995810518;5745.2818397700285;5746.626469279484;5752.942470575241;5780.450857168562;5852.707364631666;5857.853553661193;5904.763809447574;5909.5863289569315;5920.733566989582;5602.13166959122;5656.519195868397;5700.087985922232;5745.971270143623;5774.105013722879 12:32:11 INFO - PROCESS | 4303 | |1;ext.html;570.0081857567833;630.4924535748722;638.1958731997681;640.0814830717919;648.6237981970457;32293;46850.765746171266;47985.28022079663;47996.52003479964;48025.279620805675;48139.759301203536;48739.268910966275;48757;48768.75615621926;48797.75601121989;8175.264226219639;9005.729828105152;9162.267018638493;9176.632934682604;10757.56969721214;10853.131749460024;10949.288296260758;10950.233483656148;10953.288036277616;10955.83566246507;11032.50353734084;11070.833937490945;11072.114230861513;11087.944560277187;11090.057345125673;8344.916550834505;8513.787155321104;10924.672259832218;10970.122390208764;11231.494582743797;10647.041766240998;11050.115990720804;11056.060234880046;11056.668299951014;11058.502367393487;46175.07649847018;46725.532744672455;46727.766361167975;46774;47150;3947.5262968443685;3967.6032396760384;4038.717289789716;4146.481689788777;4564.178447879381;35888.10279743001;36263.27473450542;36329.273414531825;36519;36613.084672883124;4661.510541393172;4824.0834241494;4833.130036593412;4867.3915760529935;4912.38595175603;418367;420050;420056;420722;424048;73062;73143.2685673147;74020.62989685017;74057.6297118511;74135.25864741391;41716;42526;42651.573484265384;44482;44607;19540.609187816448;19648.11583478747;19790.307339243056;19823;19823.107960141828;13280.269585172908;14634.780478292834;14863.18252496123;14875.256237188314;15269.236538173047;22966.770332296797;23009.88495057514;23024.309270721904;23057.308280751604;23174.8841255796;8050;8173.591320433955;8264.421490495728;8385.262326785347;8541.942577275744;1038.7039693687263;1053.1416895230363;1092.5629748100664;1093.9179561532917;1164.6331405607207;35236;35714.25005865337;35745.821270894;35749.28501429957;35752;1139.9658010259704;1147.6040765935738;1199.736058067224;1248.182440501473;1277.7508385864853;18695.252189912524;18850.905745471184;18910.338138165098;18950.336738214097;19027.90486047561;156359.21820391054;157275;157739;157826;158373;66050.33949660537;67210;67249;67608;67769.3223067763;1362.7955806629086;1458.9197594132213;1485.7994170787022;1490.5155824357128;1505.2925125191143;35103.29793404067;35822.46266305955;36266;36675.44986825146;37019.8149009264;56369.15446268227;56426.71786641041;56951.715241423524;57033.714831425576;57128.714356427954;431325;432802;433127;433594;434149;28143.155705328874;30804.22989425282;31735.84132079325;31794.6820531801;33442.83278583591;53713;54311.45688543064;54321.45678543164;54879;55084.44915550793;44836.77581612071;45860;45931;46628.53371466242;46657.53342466532;45323;45449;45568;45893.623191304316;46473.30290045584;44202;45005.774971124934;45808.541914580426;45905.540944590124;45934;37662;39249;39565.80217098896;40137;40427.797861010506;30078.827202605295;30218.337991410684;30687.386252275275;30810;30838.537421939138 12:32:11 INFO - PROCESS | 4303 | |2;jquery.html;417.09282310973634;419.3793186084593;419.50288907644443;420.35685401335957;432.47886297012104;18868.62262754746;19841.900790496064;20271.39185824424;20336.79663203371;20655.380338589846;18858.434246972578;19222;19244.61510769784;19570.11934462949;19590.118444669988;5585.413531579176;5853.50245229156;5866.354700982888;5866.6186697864505;5868.970655146718;4245.363195520666;5299.443558426348;5696.715164241792;5719.313682358123;6365.204349456318;6383.170187875569;6446.452051575622;6469.126667899844;6489.286178520359;6509.739610415578;6363.618182909017;6379.712912918883;6470.514711396664;6478.838029049264;6479.3520647935065;3908.9413658795143;5410.567154627621;5777.364489906107;5777.88444231113;5784.971075144597;23761.524769504686;25305.493890122278;25420.49159016828;25573;25629;2391.4021494626345;2525.7095434024955;2740.10946442405;2789.3166174287226;3078.5536097265995;13676.521321753791;14999.475018374305;15075.321610527553;15404.691906161814;15498.380064797395;2108.230495869012;2163.3942496101117;2175.3582693105413;2196.363054714147;2255.2670382125875;56953.43046569523;57208;79205;91391;91468.54265728629;11286.66140015801;11363.261388009752;11610.477528511237;11702.35637039971;11918.344491052902;16242.51272461828;16277.267522961494;16312.021278723314;16341.26464309109;16342.75485867713;8791.340649451316;9150.679726209552;9191.63233470667;9224.631014759334;9318.30112741547;9084.772880677903;9105.453672779655;9150.496722680316;9230.723078307661;9263.397879137969;7158.319959603829;7175.282471752783;7175.31834475724;7182.174049984214;7326.890096648554;1952.836405023842;2010.497375656086;2028.6449871272468;2033.5875732953598;2079.6152711748373;1033.509083185481;1101.6309536305266;1144.8339990701302;1147.002108165901;1199.8500187476566;21249.233010241533;21782.673259901116;21903.452413689465;22069.668954965688;22087.889560552416;1071.5713714514256;1089.9455027248764;1098.7253186703324;1107.828286615576;1121.2039451989017;11965.641030769091;12067.293755435387;12113.818292725617;12351.814722779165;12385.256884586774;499733;506464;526991.1703419453;534778;538366;101248.49375753074;102090;102341;103663.48168259111;104495;1061.5488417422564;1078.795028944498;1088.0642647323295;1093.191038631407;1123.9438028098562;16752.74870876962;18859.434216973514;19158.521036974187;19199.808001919802;19573.119209635315;39572;40804;40821.59178408178;42326.788366057976;42340;61030;61064;61405.07892381529;61718.0742288875;61950;8960.32797540174;9302.674406395743;9320.374876717713;9364.812703746024;9482.288828337765;14680.926595366955;14881.776773348192;14891.776623350876;14905.776413354026;15394.15332156742;11477.540898364306;11587.884121159017;12314.507419703112;12839.935800321313;12996.415161317953;12342.506299747909;12446.875531244934;12475.93762031184;12920.612381628565;12949.028822838512;12563;12769.808452873029;12810.615681529569;13051.347432628141;13116.606501804961;9126.361154719107;9269.814603707753;9399.431238295376;9401.10689484498;9543.809123817346;9103.089691030844;11712;12544.560940367044;12617.36913154357;13136.408861601441 12:32:11 INFO - PROCESS | 4303 | |3;mootools.html;1430.5708287513755;1585.3183131253563;1622.5375767906146;1861.6369807887468;1933.82595566399;9068.229200517955;9547.093026162513;9547.379420337673;9797;9804.55879485423;9398.483083430425;9548.61805527778;9597.424154550732;9610.855837162448;9614.519274036287;2234.1398561553756;2478.7025556933104;2534.404414962487;2536.6702328697243;2571.4985577812336;2517.924462266135;2530.772230499255;2542.0467324753217;2542.068028298488;2543.1480454047914;2538.657281267024;2569.1650213680628;2573.7297583753716;2574.8841302141354;2580.225932220336;2165.85921915075;2483.3046746910804;2544.8345857519203;2549.3116858448116;2573.408116133281;2294.5410917816503;2300.827437942144;2300.953980920389;2345.0502546468706;2372.0511795281855;9552.283578731623;9589.760255993586;9605.615775368977;9614.134727874454;9624.566894489695;5634.239377684042;5745.770169193227;5749.568782341299;5750.482456578886;5751.798687045934;3516.894493165209;3534.4344904815107;3600.6039335673054;3719.479272901796;3731.5149030625844;1069.1820757120809;1119.138263537072;1122.6183097746723;1137.9886201137965;1154.6766905266538;13022.609321720363;13070.477180912752;13124.540641077516;13148.145370550883;13152.53966111191;4897.461279259279;4925.975370123127;5014.949850501448;5015.673981191211;5032.79868805251;10742.08692261151;10776.353418794897;10788.56845726178;10804.027637512505;10841.295315804447;5192.065428222881;5601.187827765033;5697.059985102412;5702.51528620072;5702.971485142548;5247.422783493813;5359.571234301325;5384.40771515133;5417.891642167135;5421.918671219935;4358.084802191512;4372.475302963665;4417.160739459492;4450.2434586120435;4450.599446049806;1326.8208791813174;1338.8460327062319;1340;1349.7368013237324;1358.0968655843753;932.5197523275518;1053.6680945502142;1056.060106505211;1078.320657985464;1088.8312311591717;6517.902231466532;6661.666916654148;6688.765893193715;6724.4284235840505;6756.966215168893;477.16019805143196;485.18973314564056;1118.888111188891;1147.6098126636984;1170.6897672116895;4297.59172878576;4419.535948725402;4476.216662084123;4491.505934347285;4500.797464114123;11619.767604648028;11745.823812642817;12030.27818330903;12106.152569320064;12114.81827772584;12063.457144428521;12124.333161676017;12173;12173.452194651261;12215.572454964034;8730.563471826383;8923.955380223188;8926.509042002623;8928;9010.189082982562;6405.519586030973;6654.168228971379;6710.731570737116;6737.9326206737305;6739.528233023641;10935.45322733876;11035.503402346752;11132;11186.216964812385;11209.159313051718;11155.49800258974;11270.943645282048;11271.549138034386;11384.886151138382;11531.423428828357;7757.340626046849;8143.552104634065;8284.295834854105;8304.169583041648;8508.361872859436;9501;9503.334766566273;9557.952210238904;9586.281028922998;9602.375845570015;8417.158284171534;8611.655533778829;8634.740957771277;8754;8758.255548278468;8219.219174178443;8299.917000829915;8301.958490207511;8307.626156823078;8492.660293588187;7633.885491717518;7679.116901556169;7709.884351734616;7713.652885620048;7735.110462296908;5547.722613869371;5712.942870571241;5837.0368889132815;5880.676562789087;5893.44012318829;5598.3282006159525;5692.573057020657;5727.885442291047;5797.275340582427;5881.38245484218 12:32:11 INFO - PROCESS | 4303 | |4;prototype.html;323.4162336981747;327.6019636142087;331.23815224982536;332.6208122740075;334.61017914130025;5387.4073851876265;5484;5488.9725551372285;5499.395066542682;5505.614606977513;5423.511883930445;5441.782328706857;5451.945480545193;5455.372632147294;5459.12653975364;4073.959260407388;4083.959160408395;4214.620684138427;4219.071804203086;4224.176285624305;4152.875413737577;4192.580741925798;4193.245215861144;4195.244855925932;4196.916061678765;3986.322325204708;4134.793260336986;4137.2552940470705;4152.294110001307;4161.708680392374;4097.97951010246;4201.243776120297;4204.957950420488;4215.304474761691;4230.957690423087;3691.7480336994413;3696.1868388954385;3707.5550933888107;3716.7026637868908;3717.4163956050556;5248.874205946598;5250.9737451312885;5260.342457192851;5263.4999675031195;5280.788768449257;4556.316552517116;4757;4766.523347665206;4783.425988881322;4795.520447955211;5636.421801895476;5645.967451717378;5650.5479561634975;5655.08762987062;5672.149177623348;4015.9357770190923;4018.6383225509844;4048.352263637804;4055.5944405559208;4057.452243947059;5669.773209071632;5888.4700376966275;5896.970515147483;5945.346011938726;5948.524118070544;4061.6953728470485;4076.7146299758733;4080.449139366207;4089.182163567239;4094.6110119539194;4162.458880345596;4172.374143878443;4194.916101678011;4201.705880588391;4213.936790948138;4579.748113853703;4749.453812811571;4784.354112194878;4786;4853.490383509751;5641.80253691127;5664.518515926193;5671.829845104653;5691.4877661010705;5709.571782116275;1412.004536801573;1426.2298358886292;1439.294745574661;1440.4670271999428;1440.9351579178963;918.2562124679031;942.2038377570941;950.2920324358397;969.3892847506168;975.8972361231904;5546.528545073714;5553.694546799965;5569.721513924287;5575.832725018256;5590.217369568264;5851.736671849777;5858.707064646751;5876.1479585459865;5881.852953676193;5901.403382511681;5435.831296271321;5586.972065139648;5613.961417137843;5615.971920140373;5619.606627536034;3117.719405253538;3128.9687103128676;3132.843357832099;3140.277736120705;3146.65386807451;5948.583599148018;5976.8155973142975;6085.4523092921845;6139.693015349215;6160.260768707694;5899.203607513016;5901.675407852609;5927.9407205928255;5928.614640048383;5942.286925568959;5995.171472700781;6009.729562169713;6019.254416219247;6019.578629495893;6044.909326360108;5578.693171875586;5773.971130144322;5777.24895763548;5810.82567522975;5824.388439213822;5677.8864422710485;5696.231008813923;5723.971380143073;5789.884202316014;5801.245838041028;5859.183653067563;5981.401859813984;6052.727627256696;6103.7558497661375;6119.296280927751;4022.5172979241506;4045.211183819127;4072.144849581621;4080.224757296104;4082.7958602069184;5576.7211639417055;5746.770129194785;5753.683547404933;5760.366359700513;5846.269216347957;5574.749136288796;5656.547476201977;5682.352117885932;5757.798477053283;5783.942160578508;5746;5752.6836024019085;5766;5802.82591522255;5820.1560773688425;5555.22226888244;5560.804427048204;5591.552675785846;5597.704940974181;5644.209810626434;5732.828015159552;5747;5748.48263656273;5754.9136762947755;5773.595848290579;5469.507744303032;5612.326520817528;5631.493165615114;5632.5775566831835;5646.689432081275 12:32:11 INFO - PROCESS | 4303 | |5;yui.html;853.3685073045943;865.8484765166096;873.1181506678253;883.9955800220998;911.7902882337062;16024.278907449163;16877.493675189748;17070.82929170708;17165.65668686627;18195.09024548774;16947.406840760577;17552.561185970324;17595.64808703825;17718.20268087936;17737.733933991;733.8348871503896;837.6105111123327;866.380537915392;875.4572165257517;877.2630989968425;862.9697960571381;871.5859966515918;874.3224001398935;874.4796845876693;875.6147295190128;926.8887733471993;928.4475736936546;928.7631653928273;929.4423345992418;932.3287233192091;777.1373775109623;890.3856339125982;911.4941207629763;913.0048247410355;923.155312888706;675.9391654751047;676.4013847744732;676.8003438985511;677.1535580524345;677.9017042528806;16570.17149142538;16624.750628740578;16624.83375166245;16628.750568741478;16655.50033498997;14574.489892853699;14819.18494482803;14828.481003164945;15034.624134396618;15110.320035598425;14412.567622971328;14477.927610361881;14499.782503262459;14652.560423187215;14863.256837158207;4546.818127274905;4709.387779588666;4774.570288674001;4794.712317260941;4819.373481447425;75126.62436687782;76357;76494.23505764983;76514.6174269125;76620;11746.88253117475;12005.63983080509;12221.08341874363;12358.44387002587;12364.381780910919;21672.89163554172;21688.674669879965;21754.456138596663;21786.346409607737;21787.564248714938;8710.738677839676;9140.817183656289;9225.077492250855;9361;9520.619175232912;5064.49355064498;5182.119039763249;5229.111051121318;5247.501487358695;5387;3598.712103031751;3708.035910663193;3737.2151848111657;3740.7007439405324;3743.6256374362347;685.4002747595854;689.6620655878584;702.3362922038726;707.4552594502308;710.6304721544872;660.5244224158598;676.5737585321216;729.8284903047779;738.3539403022355;768.5388766739911;10183.236257280734;10402.47987600617;10500.789984200426;10553.788924221473;10611.787764244671;676.9864602708017;700.1598082301259;711.4308553157454;740.4076738609091;767.140802301426;4990.251462280687;5004;5005.699658020458;5069.315642388304;5153.433122356538;114462.42768786103;115160;117907;120296;120769.39615301868;76802.61598691971;88124.5593772027;100873;101600;102171.48914255529;90356.54821725849;90519;91048;91131;91153.54423227841;9718.173955213886;9779.119879210904;9785.60857565718;9788.951055244679;9792.85310720354;52662.473375265756;54866.17700734572;55051.89896202134;55955.44044559502;57273;52361;71704;76460.23539764532;76991.23008769841;79454.6027269883;13735.45058197661;14194.077384969944;14631.419215717271;14820.777688334469;15100.622484437978;16192.352305907632;16534.12298337162;16633.002019878844;16711;17130.828691712923;14409.567712968628;14722.926385368004;14822.703545929236;15051.623709407353;15458.690826183189;14617.707645846811;15168.089914604718;15779.289931953319;16501.174941253128;16517.174141292646;15542.222888855285;15572.92213538925;15672.92163539175;16061.518154455385;16541.66916661684;12342.75314493723;12693.873061269638;12697.93651031739;13088.869111308764;13269.46922123143;12324.137310388187;12354.25874447536;12669.619911402673;12772.10595258323;13197.802032969705 12:32:11 INFO - PROCESS | 4303 | __end_tp_report 12:32:11 INFO - PROCESS | 4303 | __start_cc_report 12:32:11 INFO - PROCESS | 4303 | _x_x_mozilla_cycle_collect,864 12:32:11 INFO - PROCESS | 4303 | __end_cc_report 12:32:11 INFO - PROCESS | 4303 | __startTimestamp1469215931144__endTimestamp 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | ------- Summary: start ------- 12:32:11 INFO - PROCESS | 4303 | Number of tests: 6 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | [#0] dojo.html Cycles:165 Average:5530.02 Median:5746.63 stddev:610.25 (10.6%) stddev-sans-first:587.74 12:32:11 INFO - PROCESS | 4303 | Values: 3353.0 3356.9 3368.5 3374.2 3402.0 5515.8 5562.4 5579.9 5584.8 5605.3 5531.9 5564.9 5569.5 5574.6 5605.2 4927.2 5017.3 5036.9 5075.7 5117.8 4891.4 5023.7 5053.4 5061.1 5069.3 5000.4 5022.8 5048.7 5050.1 5088.7 5130.7 5142.3 5147.7 5149.0 5166.3 4942.4 4990.5 4998.1 5050.0 5079.6 5423.6 5437.8 5442.1 5464.6 5495.9 5954.0 5959.7 6006.2 6007.8 6012.3 5855.1 5906.4 5907.3 5908.4 6021.0 5537.6 5564.0 5578.7 5582.5 5602.1 5930.9 6060.6 6065.1 6069.7 6081.1 5747.4 5774.8 5788.5 5862.2 5872.7 5914.2 5922.5 5929.8 5947.1 5959.2 5745.1 5887.6 5899.1 5908.5 5913.3 5892.1 5956.9 5975.4 5986.3 6027.1 4558.6 4561.8 4569.4 4587.8 4593.6 4039.5 4064.9 4068.8 4120.2 4155.7 5740.6 5768.9 5772.4 5773.4 5795.1 6004.4 6012.6 6020.9 6031.9 6033.5 5536.9 5612.4 5630.6 5711.3 5726.0 5117.1 5118.8 5120.0 5126.3 5137.8 6264.5 6266.4 6276.5 6276.6 6276.9 5866.1 5875.4 5914.6 5955.3 5993.5 6202.8 6217.7 6226.3 6246.3 6246.7 5769.4 5870.1 5874.3 5878.4 5903.8 5796.0 5841.3 5863.8 5904.3 5904.6 5725.4 5751.1 5755.0 5755.3 5755.7 5699.3 5701.3 5738.1 5756.1 5760.5 5669.3 5745.3 5746.6 5752.9 5780.5 5852.7 5857.9 5904.8 5909.6 5920.7 5602.1 5656.5 5700.1 5746.0 5774.1 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | [#1] ext.html Cycles:180 Average:53969.01 Median:22087.86 stddev:95243.56 (431.2%) stddev-sans-first:95426.35 12:32:11 INFO - PROCESS | 4303 | Values: 570.0 630.5 638.2 640.1 648.6 32293.0 46850.8 47985.3 47996.5 48025.3 48139.8 48739.3 48757.0 48768.8 48797.8 8175.3 9005.7 9162.3 9176.6 10757.6 10853.1 10949.3 10950.2 10953.3 10955.8 11032.5 11070.8 11072.1 11087.9 11090.1 8344.9 8513.8 10924.7 10970.1 11231.5 10647.0 11050.1 11056.1 11056.7 11058.5 46175.1 46725.5 46727.8 46774.0 47150.0 3947.5 3967.6 4038.7 4146.5 4564.2 35888.1 36263.3 36329.3 36519.0 36613.1 4661.5 4824.1 4833.1 4867.4 4912.4 418367.0 420050.0 420056.0 420722.0 424048.0 73062.0 73143.3 74020.6 74057.6 74135.3 41716.0 42526.0 42651.6 44482.0 44607.0 19540.6 19648.1 19790.3 19823.0 19823.1 13280.3 14634.8 14863.2 14875.3 15269.2 22966.8 23009.9 23024.3 23057.3 23174.9 8050.0 8173.6 8264.4 8385.3 8541.9 1038.7 1053.1 1092.6 1093.9 1164.6 35236.0 35714.3 35745.8 35749.3 35752.0 1140.0 1147.6 1199.7 1248.2 1277.8 18695.3 18850.9 18910.3 18950.3 19027.9 156359.2 157275.0 157739.0 157826.0 158373.0 66050.3 67210.0 67249.0 67608.0 67769.3 1362.8 1458.9 1485.8 1490.5 1505.3 35103.3 35822.5 36266.0 36675.4 37019.8 56369.2 56426.7 56951.7 57033.7 57128.7 431325.0 432802.0 433127.0 433594.0 434149.0 28143.2 30804.2 31735.8 31794.7 33442.8 53713.0 54311.5 54321.5 54879.0 55084.4 44836.8 45860.0 45931.0 46628.5 46657.5 45323.0 45449.0 45568.0 45893.6 46473.3 44202.0 45005.8 45808.5 45905.5 45934.0 37662.0 39249.0 39565.8 40137.0 40427.8 30078.8 30218.3 30687.4 30810.0 30838.5 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | [#2] jquery.html Cycles:180 Average:30850.42 Median:12133.02 stddev:85883.90 (707.9%) stddev-sans-first:86094.42 12:32:11 INFO - PROCESS | 4303 | Values: 417.1 419.4 419.5 420.4 432.5 18868.6 19841.9 20271.4 20336.8 20655.4 18858.4 19222.0 19244.6 19570.1 19590.1 5585.4 5853.5 5866.4 5866.6 5869.0 4245.4 5299.4 5696.7 5719.3 6365.2 6383.2 6446.5 6469.1 6489.3 6509.7 6363.6 6379.7 6470.5 6478.8 6479.4 3908.9 5410.6 5777.4 5777.9 5785.0 23761.5 25305.5 25420.5 25573.0 25629.0 2391.4 2525.7 2740.1 2789.3 3078.6 13676.5 14999.5 15075.3 15404.7 15498.4 2108.2 2163.4 2175.4 2196.4 2255.3 56953.4 57208.0 79205.0 91391.0 91468.5 11286.7 11363.3 11610.5 11702.4 11918.3 16242.5 16277.3 16312.0 16341.3 16342.8 8791.3 9150.7 9191.6 9224.6 9318.3 9084.8 9105.5 9150.5 9230.7 9263.4 7158.3 7175.3 7175.3 7182.2 7326.9 1952.8 2010.5 2028.6 2033.6 2079.6 1033.5 1101.6 1144.8 1147.0 1199.9 21249.2 21782.7 21903.5 22069.7 22087.9 1071.6 1089.9 1098.7 1107.8 1121.2 11965.6 12067.3 12113.8 12351.8 12385.3 499733.0 506464.0 526991.2 534778.0 538366.0 101248.5 102090.0 102341.0 103663.5 104495.0 1061.5 1078.8 1088.1 1093.2 1123.9 16752.7 18859.4 19158.5 19199.8 19573.1 39572.0 40804.0 40821.6 42326.8 42340.0 61030.0 61064.0 61405.1 61718.1 61950.0 8960.3 9302.7 9320.4 9364.8 9482.3 14680.9 14881.8 14891.8 14905.8 15394.2 11477.5 11587.9 12314.5 12839.9 12996.4 12342.5 12446.9 12475.9 12920.6 12949.0 12563.0 12769.8 12810.6 13051.3 13116.6 9126.4 9269.8 9399.4 9401.1 9543.8 9103.1 11712.0 12544.6 12617.4 13136.4 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | [#3] mootools.html Cycles:180 Average:6327.31 Median:4496.15 stddev:3612.24 (80.3%) stddev-sans-first:3603.62 12:32:11 INFO - PROCESS | 4303 | Values: 1430.6 1585.3 1622.5 1861.6 1933.8 9068.2 9547.1 9547.4 9797.0 9804.6 9398.5 9548.6 9597.4 9610.9 9614.5 2234.1 2478.7 2534.4 2536.7 2571.5 2517.9 2530.8 2542.0 2542.1 2543.1 2538.7 2569.2 2573.7 2574.9 2580.2 2165.9 2483.3 2544.8 2549.3 2573.4 2294.5 2300.8 2301.0 2345.1 2372.1 9552.3 9589.8 9605.6 9614.1 9624.6 5634.2 5745.8 5749.6 5750.5 5751.8 3516.9 3534.4 3600.6 3719.5 3731.5 1069.2 1119.1 1122.6 1138.0 1154.7 13022.6 13070.5 13124.5 13148.1 13152.5 4897.5 4926.0 5014.9 5015.7 5032.8 10742.1 10776.4 10788.6 10804.0 10841.3 5192.1 5601.2 5697.1 5702.5 5703.0 5247.4 5359.6 5384.4 5417.9 5421.9 4358.1 4372.5 4417.2 4450.2 4450.6 1326.8 1338.8 1340.0 1349.7 1358.1 932.5 1053.7 1056.1 1078.3 1088.8 6517.9 6661.7 6688.8 6724.4 6757.0 477.2 485.2 1118.9 1147.6 1170.7 4297.6 4419.5 4476.2 4491.5 4500.8 11619.8 11745.8 12030.3 12106.2 12114.8 12063.5 12124.3 12173.0 12173.5 12215.6 8730.6 8924.0 8926.5 8928.0 9010.2 6405.5 6654.2 6710.7 6737.9 6739.5 10935.5 11035.5 11132.0 11186.2 11209.2 11155.5 11270.9 11271.5 11384.9 11531.4 7757.3 8143.6 8284.3 8304.2 8508.4 9501.0 9503.3 9558.0 9586.3 9602.4 8417.2 8611.7 8634.7 8754.0 8758.3 8219.2 8299.9 8302.0 8307.6 8492.7 7633.9 7679.1 7709.9 7713.7 7735.1 5547.7 5712.9 5837.0 5880.7 5893.4 5598.3 5692.6 5727.9 5797.3 5881.4 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | [#4] prototype.html Cycles:180 Average:4780.85 Median:5565.26 stddev:1421.27 (25.5%) stddev-sans-first:1385.32 12:32:11 INFO - PROCESS | 4303 | Values: 323.4 327.6 331.2 332.6 334.6 5387.4 5484.0 5489.0 5499.4 5505.6 5423.5 5441.8 5451.9 5455.4 5459.1 4074.0 4084.0 4214.6 4219.1 4224.2 4152.9 4192.6 4193.2 4195.2 4196.9 3986.3 4134.8 4137.3 4152.3 4161.7 4098.0 4201.2 4205.0 4215.3 4231.0 3691.7 3696.2 3707.6 3716.7 3717.4 5248.9 5251.0 5260.3 5263.5 5280.8 4556.3 4757.0 4766.5 4783.4 4795.5 5636.4 5646.0 5650.5 5655.1 5672.1 4015.9 4018.6 4048.4 4055.6 4057.5 5669.8 5888.5 5897.0 5945.3 5948.5 4061.7 4076.7 4080.4 4089.2 4094.6 4162.5 4172.4 4194.9 4201.7 4213.9 4579.7 4749.5 4784.4 4786.0 4853.5 5641.8 5664.5 5671.8 5691.5 5709.6 1412.0 1426.2 1439.3 1440.5 1440.9 918.3 942.2 950.3 969.4 975.9 5546.5 5553.7 5569.7 5575.8 5590.2 5851.7 5858.7 5876.1 5881.9 5901.4 5435.8 5587.0 5614.0 5616.0 5619.6 3117.7 3129.0 3132.8 3140.3 3146.7 5948.6 5976.8 6085.5 6139.7 6160.3 5899.2 5901.7 5927.9 5928.6 5942.3 5995.2 6009.7 6019.3 6019.6 6044.9 5578.7 5774.0 5777.2 5810.8 5824.4 5677.9 5696.2 5724.0 5789.9 5801.2 5859.2 5981.4 6052.7 6103.8 6119.3 4022.5 4045.2 4072.1 4080.2 4082.8 5576.7 5746.8 5753.7 5760.4 5846.3 5574.7 5656.5 5682.4 5757.8 5783.9 5746.0 5752.7 5766.0 5802.8 5820.2 5555.2 5560.8 5591.6 5597.7 5644.2 5732.8 5747.0 5748.5 5754.9 5773.6 5469.5 5612.3 5631.5 5632.6 5646.7 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - PROCESS | 4303 | [#5] yui.html Cycles:180 Average:21617.11 Median:4784.64 stddev:29948.78 (625.9%) stddev-sans-first:29992.21 12:32:11 INFO - PROCESS | 4303 | Values: 853.4 865.8 873.1 884.0 911.8 16024.3 16877.5 17070.8 17165.7 18195.1 16947.4 17552.6 17595.6 17718.2 17737.7 733.8 837.6 866.4 875.5 877.3 863.0 871.6 874.3 874.5 875.6 926.9 928.4 928.8 929.4 932.3 777.1 890.4 911.5 913.0 923.2 675.9 676.4 676.8 677.2 677.9 16570.2 16624.8 16624.8 16628.8 16655.5 14574.5 14819.2 14828.5 15034.6 15110.3 14412.6 14477.9 14499.8 14652.6 14863.3 4546.8 4709.4 4774.6 4794.7 4819.4 75126.6 76357.0 76494.2 76514.6 76620.0 11746.9 12005.6 12221.1 12358.4 12364.4 21672.9 21688.7 21754.5 21786.3 21787.6 8710.7 9140.8 9225.1 9361.0 9520.6 5064.5 5182.1 5229.1 5247.5 5387.0 3598.7 3708.0 3737.2 3740.7 3743.6 685.4 689.7 702.3 707.5 710.6 660.5 676.6 729.8 738.4 768.5 10183.2 10402.5 10500.8 10553.8 10611.8 677.0 700.2 711.4 740.4 767.1 4990.3 5004.0 5005.7 5069.3 5153.4 114462.4 115160.0 117907.0 120296.0 120769.4 76802.6 88124.6 100873.0 101600.0 102171.5 90356.5 90519.0 91048.0 91131.0 91153.5 9718.2 9779.1 9785.6 9789.0 9792.9 52662.5 54866.2 55051.9 55955.4 57273.0 52361.0 71704.0 76460.2 76991.2 79454.6 13735.5 14194.1 14631.4 14820.8 15100.6 16192.4 16534.1 16633.0 16711.0 17130.8 14409.6 14722.9 14822.7 15051.6 15458.7 14617.7 15168.1 15779.3 16501.2 16517.2 15542.2 15572.9 15672.9 16061.5 16541.7 12342.8 12693.9 12697.9 13088.9 13269.5 12324.1 12354.3 12669.6 12772.1 13197.8 12:32:11 INFO - PROCESS | 4303 | -------- Summary: end -------- 12:32:11 INFO - PROCESS | 4303 | 12:32:11 INFO - TEST-INFO | 4303: exit 0 12:32:11 INFO - TEST-OK | dromaeo_css | took 1112546ms 12:32:11 INFO - TEST-START | kraken 12:32:11 INFO - operating with platform_type : linux_ 12:32:11 INFO - Initialising browser for kraken test... 12:32:11 INFO - TEST-INFO | started process 4496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuCbImr/profile http://localhost:45405/getInfo.html) 12:32:15 INFO - PROCESS | 4496 | __metrics Screen width/height:1600/1200 12:32:15 INFO - PROCESS | 4496 | colorDepth:24 12:32:15 INFO - PROCESS | 4496 | Browser inner width/height: 1024/697 12:32:15 INFO - PROCESS | 4496 | __metrics 12:32:16 INFO - PROCESS | 4496 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:32:16 ERROR - PROCESS | 4496 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:32:17 INFO - TEST-INFO | 4496: exit 0 12:32:17 INFO - Browser initialized. 12:32:17 INFO - Running cycle 1/1 for kraken test... 12:32:17 INFO - TEST-INFO | started process 4585 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuCbImr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:32:29 INFO - PROCESS | 4585 | RSS: Main: 145420288 12:32:29 INFO - PROCESS | 4585 | 12:32:31 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45405/tests/kraken/driver.html?testName=audio-beat-detection) 12:32:32 INFO - PROCESS | 4585 | RSS: Main: 194797568 12:32:32 INFO - PROCESS | 4585 | 12:32:35 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45405/tests/kraken/driver.html?testName=audio-dft) 12:32:35 INFO - PROCESS | 4585 | RSS: Main: 192417792 12:32:35 INFO - PROCESS | 4585 | 12:32:38 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45405/tests/kraken/driver.html?testName=audio-fft) 12:32:39 INFO - PROCESS | 4585 | RSS: Main: 278339584 12:32:39 INFO - PROCESS | 4585 | 12:32:41 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45405/tests/kraken/driver.html?testName=audio-oscillator) 12:32:41 INFO - PROCESS | 4585 | RSS: Main: 185905152 12:32:41 INFO - PROCESS | 4585 | 12:32:43 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45405/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:32:44 INFO - PROCESS | 4585 | RSS: Main: 185614336 12:32:44 INFO - PROCESS | 4585 | 12:32:47 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45405/tests/kraken/driver.html?testName=imaging-darkroom) 12:32:48 INFO - PROCESS | 4585 | RSS: Main: 221274112 12:32:48 INFO - PROCESS | 4585 | 12:32:51 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45405/tests/kraken/driver.html?testName=imaging-desaturate) 12:32:52 INFO - PROCESS | 4585 | RSS: Main: 221614080 12:32:52 INFO - PROCESS | 4585 | 12:32:54 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45405/tests/kraken/driver.html?testName=json-parse-financial) 12:32:55 INFO - PROCESS | 4585 | RSS: Main: 221487104 12:32:55 INFO - PROCESS | 4585 | 12:32:56 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45405/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:32:57 INFO - PROCESS | 4585 | RSS: Main: 199700480 12:32:57 INFO - PROCESS | 4585 | 12:32:59 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:32:59 INFO - PROCESS | 4585 | RSS: Main: 201293824 12:32:59 INFO - PROCESS | 4585 | 12:33:01 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:33:02 INFO - PROCESS | 4585 | RSS: Main: 210092032 12:33:02 INFO - PROCESS | 4585 | 12:33:04 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:33:05 INFO - PROCESS | 4585 | RSS: Main: 214147072 12:33:05 INFO - PROCESS | 4585 | 12:33:08 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:33:08 INFO - PROCESS | 4585 | RSS: Main: 215482368 12:33:08 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | Cycle 1(1): loaded http://localhost:45405/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45405/tests/kraken/driver.html?testName=ai-astar) 12:33:10 INFO - PROCESS | 4585 | RSS: Main: 257662976 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | __start_tp_report 12:33:10 INFO - PROCESS | 4585 | _x_x_mozilla_page_load 12:33:10 INFO - PROCESS | 4585 | _x_x_mozilla_page_load_details 12:33:10 INFO - PROCESS | 4585 | |i|pagename|runs| 12:33:10 INFO - PROCESS | 4585 | |0;ai-astar;110;106;110;109;111;110;110;110;110;108 12:33:10 INFO - PROCESS | 4585 | |1;audio-beat-detection;148;123;129;172;131;130;150;124;161;124 12:33:10 INFO - PROCESS | 4585 | |2;audio-dft;161;162;161;162;159;163;159;159;159;160 12:33:10 INFO - PROCESS | 4585 | |3;audio-fft;75;75;74;136;74;75;76;74;98;76 12:33:10 INFO - PROCESS | 4585 | |4;audio-oscillator;84;84;116;85;84;85;116;84;85;123 12:33:10 INFO - PROCESS | 4585 | |5;imaging-gaussian-blur;116;117;117;117;117;117;116;117;117;117 12:33:10 INFO - PROCESS | 4585 | |6;imaging-darkroom;145;145;145;145;145;145;145;145;145;144 12:33:10 INFO - PROCESS | 4585 | |7;imaging-desaturate;95;94;94;94;94;95;95;95;94;94 12:33:10 INFO - PROCESS | 4585 | |8;json-parse-financial;49;48;51;51;52;51;50;49;49;50 12:33:10 INFO - PROCESS | 4585 | |9;json-stringify-tinderbox;61;61;60;61;60;60;60;60;64;59 12:33:10 INFO - PROCESS | 4585 | |10;stanford-crypto-aes;76;75;76;82;76;81;82;77;109;77 12:33:10 INFO - PROCESS | 4585 | |11;stanford-crypto-ccm;123;126;117;126;128;115;115;124;130;119 12:33:10 INFO - PROCESS | 4585 | |12;stanford-crypto-pbkdf2;159;153;153;155;152;162;154;152;156;156 12:33:10 INFO - PROCESS | 4585 | |13;stanford-crypto-sha256-iterative;61;60;61;61;61;61;61;61;60;67 12:33:10 INFO - PROCESS | 4585 | __end_tp_report 12:33:10 INFO - PROCESS | 4585 | __start_cc_report 12:33:10 INFO - PROCESS | 4585 | _x_x_mozilla_cycle_collect,1595 12:33:10 INFO - PROCESS | 4585 | __end_cc_report 12:33:10 INFO - PROCESS | 4585 | __startTimestamp1469215990731__endTimestamp 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | ------- Summary: start ------- 12:33:10 INFO - PROCESS | 4585 | Number of tests: 14 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:1.43 (1.3%) stddev-sans-first:1.50 12:33:10 INFO - PROCESS | 4585 | Values: 110.0 106.0 110.0 109.0 111.0 110.0 110.0 110.0 110.0 108.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:139.50 stddev:17.40 (12.5%) stddev-sans-first:18.16 12:33:10 INFO - PROCESS | 4585 | Values: 148.0 123.0 129.0 172.0 131.0 130.0 150.0 124.0 161.0 124.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.51 (0.9%) stddev-sans-first:1.59 12:33:10 INFO - PROCESS | 4585 | Values: 161.0 162.0 161.0 162.0 159.0 163.0 159.0 159.0 159.0 160.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#3] audio-fft Cycles:10 Average:83.30 Median:75.00 stddev:19.91 (26.5%) stddev-sans-first:20.89 12:33:10 INFO - PROCESS | 4585 | Values: 75.0 75.0 74.0 136.0 74.0 75.0 76.0 74.0 98.0 76.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.00 stddev:16.49 (19.6%) stddev-sans-first:17.04 12:33:10 INFO - PROCESS | 4585 | Values: 84.0 84.0 116.0 85.0 84.0 85.0 116.0 84.0 85.0 123.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 12:33:10 INFO - PROCESS | 4585 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 12:33:10 INFO - PROCESS | 4585 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 144.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 12:33:10 INFO - PROCESS | 4585 | Values: 95.0 94.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 94.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.50 stddev:1.25 (2.5%) stddev-sans-first:1.27 12:33:10 INFO - PROCESS | 4585 | Values: 49.0 48.0 51.0 51.0 52.0 51.0 50.0 49.0 49.0 50.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.50 stddev:1.35 (2.2%) stddev-sans-first:1.42 12:33:10 INFO - PROCESS | 4585 | Values: 61.0 61.0 60.0 61.0 60.0 60.0 60.0 60.0 64.0 59.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:77.00 stddev:10.16 (13.2%) stddev-sans-first:10.61 12:33:10 INFO - PROCESS | 4585 | Values: 76.0 75.0 76.0 82.0 76.0 81.0 82.0 77.0 109.0 77.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:125.00 stddev:5.46 (4.4%) stddev-sans-first:5.78 12:33:10 INFO - PROCESS | 4585 | Values: 123.0 126.0 117.0 126.0 128.0 115.0 115.0 124.0 130.0 119.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:155.50 stddev:3.22 (2.1%) stddev-sans-first:3.11 12:33:10 INFO - PROCESS | 4585 | Values: 159.0 153.0 153.0 155.0 152.0 162.0 154.0 152.0 156.0 156.0 12:33:10 INFO - PROCESS | 4585 | 12:33:10 INFO - PROCESS | 4585 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.00 stddev:2.01 (3.3%) stddev-sans-first:2.13 12:33:10 INFO - PROCESS | 4585 | Values: 61.0 60.0 61.0 61.0 61.0 61.0 61.0 61.0 60.0 67.0 12:33:10 INFO - PROCESS | 4585 | -------- Summary: end -------- 12:33:10 INFO - PROCESS | 4585 | 12:33:11 INFO - TEST-INFO | 4585: exit 0 12:33:11 INFO - TEST-OK | kraken | took 59841ms 12:33:11 INFO - SUITE-END | took 1172s 12:33:11 INFO - Completed test suite (00:19:32) 12:33:11 INFO - javascript benchmark 12:33:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.966470335297, 3356.9328613427733, 3368.528406023157, 3374.2070613405863, 3402.0304213299214, 5515.751791169397, 5562.415946325638, 5579.944200557993, 5584.832455026346, 5605.27131472909, 5531.944680553193, 5564.944350556493, 5569.526590239824, 5574.581906357019, 5605.1872478490595, 4927.211646136619, 5017.297578339027, 5036.899262014757, 5075.695458272497, 5117.846464606049, 4891.388576427947, 5023.748812559375, 5053.444121146671, 5061.089003979282, 5069.315642388286, 5000.399952005764, 5022.82420115296, 5048.747562621873, 5050.141475949097, 5088.720120393377, 5130.666506677054, 5142.305788718531, 5147.716875571842, 5148.974255128738, 5166.250893620423, 4942.357493525855, 4990.476000020019, 4998.12532806761, 5050.040492306487, 5079.619028572835, 5423.566114710814, 5437.782488700447, 5442.074847275933, 5464.562834973232, 5495.890082198373, 5953.970230148865, 5959.672218028007, 6006.249218847644, 6007.8197654070445, 6012.278526576795, 5855.121731740273, 5906.438888305604, 5907.261592300963, 5908.379620139866, 6021.036634138561, 5537.58468114889, 5564.026295398329, 5578.721063946787, 5582.469665381782, 5602.13166959122, 5930.8813823723285, 6060.606060606043, 6065.144140771246, 6069.687524973978, 6081.087836824512, 5747.4252574742195, 5774.797882074107, 5788.479036886701, 5862.237909071793, 5872.7357268923, 5914.172015917775, 5922.466977972003, 5929.822105336847, 5947.078202878561, 5959.22530071088, 5745.109508026263, 5887.558433117533, 5899.085641725539, 5908.527317814564, 5913.319968203626, 5892.057270836732, 5956.880862382728, 5975.372585878507, 5986.281646202484, 6027.126066720301, 4558.566936141061, 4561.771911404483, 4569.428821397325, 4587.79354929029, 4593.586577208001, 4039.5354534228354, 4064.9187016260103, 4068.7762173080173, 4120.1553681495225, 4155.688323375759, 5740.598158128974, 5768.855778605568, 5772.365039845614, 5773.364929857712, 5795.072788353843, 6004.369541198199, 6012.6392416455155, 6020.879582408327, 6031.909521357095, 6033.4871535919065, 5536.916946245809, 5612.382637909826, 5630.577706672016, 5711.343195532567, 5726.0, 5117.078925793393, 5118.820841270538, 5119.976004799055, 5126.256692779526, 5137.845864624067, 6264.5301602380005, 6266.373362663697, 6276.497880169575, 6276.560640755195, 6276.937230627726, 5866.149408335682, 5875.383084776037, 5914.645121292736, 5955.285365756137, 5993.460588547052, 6202.76700387851, 6217.720202590985, 6226.315105338319, 6246.312905580292, 6246.656433896179, 5769.365369809401, 5870.119482077723, 5874.324452687995, 5878.382769809108, 5903.793367232126, 5795.971020144872, 5841.32824725145, 5863.794767183128, 5904.261967254093, 5904.645721256738, 5725.398833122629, 5751.1373294006235, 5754.971225143848, 5755.3381361144, 5755.683437410982, 5699.344575373885, 5701.287339082614, 5738.110592858114, 5756.136579513107, 5760.539156867524, 5669.262995810518, 5745.2818397700285, 5746.626469279484, 5752.942470575241, 5780.450857168562, 5852.707364631666, 5857.853553661193, 5904.763809447574, 5909.5863289569315, 5920.733566989582, 5602.13166959122, 5656.519195868397, 5700.087985922232, 5745.971270143623, 5774.105013722879], "value": 5490.796414986231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.0081857567833, 630.4924535748722, 638.1958731997681, 640.0814830717919, 648.6237981970457, 32293.0, 46850.765746171266, 47985.28022079663, 47996.52003479964, 48025.279620805675, 48139.759301203536, 48739.268910966275, 48757.0, 48768.75615621926, 48797.75601121989, 8175.264226219639, 9005.729828105152, 9162.267018638493, 9176.632934682604, 10757.56969721214, 10853.131749460024, 10949.288296260758, 10950.233483656148, 10953.288036277616, 10955.83566246507, 11032.50353734084, 11070.833937490945, 11072.114230861513, 11087.944560277187, 11090.057345125673, 8344.916550834505, 8513.787155321104, 10924.672259832218, 10970.122390208764, 11231.494582743797, 10647.041766240998, 11050.115990720804, 11056.060234880046, 11056.668299951014, 11058.502367393487, 46175.07649847018, 46725.532744672455, 46727.766361167975, 46774.0, 47150.0, 3947.5262968443685, 3967.6032396760384, 4038.717289789716, 4146.481689788777, 4564.178447879381, 35888.10279743001, 36263.27473450542, 36329.273414531825, 36519.0, 36613.084672883124, 4661.510541393172, 4824.0834241494, 4833.130036593412, 4867.3915760529935, 4912.38595175603, 418367.0, 420050.0, 420056.0, 420722.0, 424048.0, 73062.0, 73143.2685673147, 74020.62989685017, 74057.6297118511, 74135.25864741391, 41716.0, 42526.0, 42651.573484265384, 44482.0, 44607.0, 19540.609187816448, 19648.11583478747, 19790.307339243056, 19823.0, 19823.107960141828, 13280.269585172908, 14634.780478292834, 14863.18252496123, 14875.256237188314, 15269.236538173047, 22966.770332296797, 23009.88495057514, 23024.309270721904, 23057.308280751604, 23174.8841255796, 8050.0, 8173.591320433955, 8264.421490495728, 8385.262326785347, 8541.942577275744, 1038.7039693687263, 1053.1416895230363, 1092.5629748100664, 1093.9179561532917, 1164.6331405607207, 35236.0, 35714.25005865337, 35745.821270894, 35749.28501429957, 35752.0, 1139.9658010259704, 1147.6040765935738, 1199.736058067224, 1248.182440501473, 1277.7508385864853, 18695.252189912524, 18850.905745471184, 18910.338138165098, 18950.336738214097, 19027.90486047561, 156359.21820391054, 157275.0, 157739.0, 157826.0, 158373.0, 66050.33949660537, 67210.0, 67249.0, 67608.0, 67769.3223067763, 1362.7955806629086, 1458.9197594132213, 1485.7994170787022, 1490.5155824357128, 1505.2925125191143, 35103.29793404067, 35822.46266305955, 36266.0, 36675.44986825146, 37019.8149009264, 56369.15446268227, 56426.71786641041, 56951.715241423524, 57033.714831425576, 57128.714356427954, 431325.0, 432802.0, 433127.0, 433594.0, 434149.0, 28143.155705328874, 30804.22989425282, 31735.84132079325, 31794.6820531801, 33442.83278583591, 53713.0, 54311.45688543064, 54321.45678543164, 54879.0, 55084.44915550793, 44836.77581612071, 45860.0, 45931.0, 46628.53371466242, 46657.53342466532, 45323.0, 45449.0, 45568.0, 45893.623191304316, 46473.30290045584, 44202.0, 45005.774971124934, 45808.541914580426, 45905.540944590124, 45934.0, 37662.0, 39249.0, 39565.80217098896, 40137.0, 40427.797861010506, 30078.827202605295, 30218.337991410684, 30687.386252275275, 30810.0, 30838.537421939138], "value": 21534.312307875003, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.09282310973634, 419.3793186084593, 419.50288907644443, 420.35685401335957, 432.47886297012104, 18868.62262754746, 19841.900790496064, 20271.39185824424, 20336.79663203371, 20655.380338589846, 18858.434246972578, 19222.0, 19244.61510769784, 19570.11934462949, 19590.118444669988, 5585.413531579176, 5853.50245229156, 5866.354700982888, 5866.6186697864505, 5868.970655146718, 4245.363195520666, 5299.443558426348, 5696.715164241792, 5719.313682358123, 6365.204349456318, 6383.170187875569, 6446.452051575622, 6469.126667899844, 6489.286178520359, 6509.739610415578, 6363.618182909017, 6379.712912918883, 6470.514711396664, 6478.838029049264, 6479.3520647935065, 3908.9413658795143, 5410.567154627621, 5777.364489906107, 5777.88444231113, 5784.971075144597, 23761.524769504686, 25305.493890122278, 25420.49159016828, 25573.0, 25629.0, 2391.4021494626345, 2525.7095434024955, 2740.10946442405, 2789.3166174287226, 3078.5536097265995, 13676.521321753791, 14999.475018374305, 15075.321610527553, 15404.691906161814, 15498.380064797395, 2108.230495869012, 2163.3942496101117, 2175.3582693105413, 2196.363054714147, 2255.2670382125875, 56953.43046569523, 57208.0, 79205.0, 91391.0, 91468.54265728629, 11286.66140015801, 11363.261388009752, 11610.477528511237, 11702.35637039971, 11918.344491052902, 16242.51272461828, 16277.267522961494, 16312.021278723314, 16341.26464309109, 16342.75485867713, 8791.340649451316, 9150.679726209552, 9191.63233470667, 9224.631014759334, 9318.30112741547, 9084.772880677903, 9105.453672779655, 9150.496722680316, 9230.723078307661, 9263.397879137969, 7158.319959603829, 7175.282471752783, 7175.31834475724, 7182.174049984214, 7326.890096648554, 1952.836405023842, 2010.497375656086, 2028.6449871272468, 2033.5875732953598, 2079.6152711748373, 1033.509083185481, 1101.6309536305266, 1144.8339990701302, 1147.002108165901, 1199.8500187476566, 21249.233010241533, 21782.673259901116, 21903.452413689465, 22069.668954965688, 22087.889560552416, 1071.5713714514256, 1089.9455027248764, 1098.7253186703324, 1107.828286615576, 1121.2039451989017, 11965.641030769091, 12067.293755435387, 12113.818292725617, 12351.814722779165, 12385.256884586774, 499733.0, 506464.0, 526991.1703419453, 534778.0, 538366.0, 101248.49375753074, 102090.0, 102341.0, 103663.48168259111, 104495.0, 1061.5488417422564, 1078.795028944498, 1088.0642647323295, 1093.191038631407, 1123.9438028098562, 16752.74870876962, 18859.434216973514, 19158.521036974187, 19199.808001919802, 19573.119209635315, 39572.0, 40804.0, 40821.59178408178, 42326.788366057976, 42340.0, 61030.0, 61064.0, 61405.07892381529, 61718.0742288875, 61950.0, 8960.32797540174, 9302.674406395743, 9320.374876717713, 9364.812703746024, 9482.288828337765, 14680.926595366955, 14881.776773348192, 14891.776623350876, 14905.776413354026, 15394.15332156742, 11477.540898364306, 11587.884121159017, 12314.507419703112, 12839.935800321313, 12996.415161317953, 12342.506299747909, 12446.875531244934, 12475.93762031184, 12920.612381628565, 12949.028822838512, 12563.0, 12769.808452873029, 12810.615681529569, 13051.347432628141, 13116.606501804961, 9126.361154719107, 9269.814603707753, 9399.431238295376, 9401.10689484498, 9543.809123817346, 9103.089691030844, 11712.0, 12544.560940367044, 12617.36913154357, 13136.408861601441], "value": 10055.204124022915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.5708287513755, 1585.3183131253563, 1622.5375767906146, 1861.6369807887468, 1933.82595566399, 9068.229200517955, 9547.093026162513, 9547.379420337673, 9797.0, 9804.55879485423, 9398.483083430425, 9548.61805527778, 9597.424154550732, 9610.855837162448, 9614.519274036287, 2234.1398561553756, 2478.7025556933104, 2534.404414962487, 2536.6702328697243, 2571.4985577812336, 2517.924462266135, 2530.772230499255, 2542.0467324753217, 2542.068028298488, 2543.1480454047914, 2538.657281267024, 2569.1650213680628, 2573.7297583753716, 2574.8841302141354, 2580.225932220336, 2165.85921915075, 2483.3046746910804, 2544.8345857519203, 2549.3116858448116, 2573.408116133281, 2294.5410917816503, 2300.827437942144, 2300.953980920389, 2345.0502546468706, 2372.0511795281855, 9552.283578731623, 9589.760255993586, 9605.615775368977, 9614.134727874454, 9624.566894489695, 5634.239377684042, 5745.770169193227, 5749.568782341299, 5750.482456578886, 5751.798687045934, 3516.894493165209, 3534.4344904815107, 3600.6039335673054, 3719.479272901796, 3731.5149030625844, 1069.1820757120809, 1119.138263537072, 1122.6183097746723, 1137.9886201137965, 1154.6766905266538, 13022.609321720363, 13070.477180912752, 13124.540641077516, 13148.145370550883, 13152.53966111191, 4897.461279259279, 4925.975370123127, 5014.949850501448, 5015.673981191211, 5032.79868805251, 10742.08692261151, 10776.353418794897, 10788.56845726178, 10804.027637512505, 10841.295315804447, 5192.065428222881, 5601.187827765033, 5697.059985102412, 5702.51528620072, 5702.971485142548, 5247.422783493813, 5359.571234301325, 5384.40771515133, 5417.891642167135, 5421.918671219935, 4358.084802191512, 4372.475302963665, 4417.160739459492, 4450.2434586120435, 4450.599446049806, 1326.8208791813174, 1338.8460327062319, 1340.0, 1349.7368013237324, 1358.0968655843753, 932.5197523275518, 1053.6680945502142, 1056.060106505211, 1078.320657985464, 1088.8312311591717, 6517.902231466532, 6661.666916654148, 6688.765893193715, 6724.4284235840505, 6756.966215168893, 477.16019805143196, 485.18973314564056, 1118.888111188891, 1147.6098126636984, 1170.6897672116895, 4297.59172878576, 4419.535948725402, 4476.216662084123, 4491.505934347285, 4500.797464114123, 11619.767604648028, 11745.823812642817, 12030.27818330903, 12106.152569320064, 12114.81827772584, 12063.457144428521, 12124.333161676017, 12173.0, 12173.452194651261, 12215.572454964034, 8730.563471826383, 8923.955380223188, 8926.509042002623, 8928.0, 9010.189082982562, 6405.519586030973, 6654.168228971379, 6710.731570737116, 6737.9326206737305, 6739.528233023641, 10935.45322733876, 11035.503402346752, 11132.0, 11186.216964812385, 11209.159313051718, 11155.49800258974, 11270.943645282048, 11271.549138034386, 11384.886151138382, 11531.423428828357, 7757.340626046849, 8143.552104634065, 8284.295834854105, 8304.169583041648, 8508.361872859436, 9501.0, 9503.334766566273, 9557.952210238904, 9586.281028922998, 9602.375845570015, 8417.158284171534, 8611.655533778829, 8634.740957771277, 8754.0, 8758.255548278468, 8219.219174178443, 8299.917000829915, 8301.958490207511, 8307.626156823078, 8492.660293588187, 7633.885491717518, 7679.116901556169, 7709.884351734616, 7713.652885620048, 7735.110462296908, 5547.722613869371, 5712.942870571241, 5837.0368889132815, 5880.676562789087, 5893.44012318829, 5598.3282006159525, 5692.573057020657, 5727.885442291047, 5797.275340582427, 5881.38245484218], "value": 5018.262039803059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.4162336981747, 327.6019636142087, 331.23815224982536, 332.6208122740075, 334.61017914130025, 5387.4073851876265, 5484.0, 5488.9725551372285, 5499.395066542682, 5505.614606977513, 5423.511883930445, 5441.782328706857, 5451.945480545193, 5455.372632147294, 5459.12653975364, 4073.959260407388, 4083.959160408395, 4214.620684138427, 4219.071804203086, 4224.176285624305, 4152.875413737577, 4192.580741925798, 4193.245215861144, 4195.244855925932, 4196.916061678765, 3986.322325204708, 4134.793260336986, 4137.2552940470705, 4152.294110001307, 4161.708680392374, 4097.97951010246, 4201.243776120297, 4204.957950420488, 4215.304474761691, 4230.957690423087, 3691.7480336994413, 3696.1868388954385, 3707.5550933888107, 3716.7026637868908, 3717.4163956050556, 5248.874205946598, 5250.9737451312885, 5260.342457192851, 5263.4999675031195, 5280.788768449257, 4556.316552517116, 4757.0, 4766.523347665206, 4783.425988881322, 4795.520447955211, 5636.421801895476, 5645.967451717378, 5650.5479561634975, 5655.08762987062, 5672.149177623348, 4015.9357770190923, 4018.6383225509844, 4048.352263637804, 4055.5944405559208, 4057.452243947059, 5669.773209071632, 5888.4700376966275, 5896.970515147483, 5945.346011938726, 5948.524118070544, 4061.6953728470485, 4076.7146299758733, 4080.449139366207, 4089.182163567239, 4094.6110119539194, 4162.458880345596, 4172.374143878443, 4194.916101678011, 4201.705880588391, 4213.936790948138, 4579.748113853703, 4749.453812811571, 4784.354112194878, 4786.0, 4853.490383509751, 5641.80253691127, 5664.518515926193, 5671.829845104653, 5691.4877661010705, 5709.571782116275, 1412.004536801573, 1426.2298358886292, 1439.294745574661, 1440.4670271999428, 1440.9351579178963, 918.2562124679031, 942.2038377570941, 950.2920324358397, 969.3892847506168, 975.8972361231904, 5546.528545073714, 5553.694546799965, 5569.721513924287, 5575.832725018256, 5590.217369568264, 5851.736671849777, 5858.707064646751, 5876.1479585459865, 5881.852953676193, 5901.403382511681, 5435.831296271321, 5586.972065139648, 5613.961417137843, 5615.971920140373, 5619.606627536034, 3117.719405253538, 3128.9687103128676, 3132.843357832099, 3140.277736120705, 3146.65386807451, 5948.583599148018, 5976.8155973142975, 6085.4523092921845, 6139.693015349215, 6160.260768707694, 5899.203607513016, 5901.675407852609, 5927.9407205928255, 5928.614640048383, 5942.286925568959, 5995.171472700781, 6009.729562169713, 6019.254416219247, 6019.578629495893, 6044.909326360108, 5578.693171875586, 5773.971130144322, 5777.24895763548, 5810.82567522975, 5824.388439213822, 5677.8864422710485, 5696.231008813923, 5723.971380143073, 5789.884202316014, 5801.245838041028, 5859.183653067563, 5981.401859813984, 6052.727627256696, 6103.7558497661375, 6119.296280927751, 4022.5172979241506, 4045.211183819127, 4072.144849581621, 4080.224757296104, 4082.7958602069184, 5576.7211639417055, 5746.770129194785, 5753.683547404933, 5760.366359700513, 5846.269216347957, 5574.749136288796, 5656.547476201977, 5682.352117885932, 5757.798477053283, 5783.942160578508, 5746.0, 5752.6836024019085, 5766.0, 5802.82591522255, 5820.1560773688425, 5555.22226888244, 5560.804427048204, 5591.552675785846, 5597.704940974181, 5644.209810626434, 5732.828015159552, 5747.0, 5748.48263656273, 5754.9136762947755, 5773.595848290579, 5469.507744303032, 5612.326520817528, 5631.493165615114, 5632.5775566831835, 5646.689432081275], "value": 4323.691294219269, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [853.3685073045943, 865.8484765166096, 873.1181506678253, 883.9955800220998, 911.7902882337062, 16024.278907449163, 16877.493675189748, 17070.82929170708, 17165.65668686627, 18195.09024548774, 16947.406840760577, 17552.561185970324, 17595.64808703825, 17718.20268087936, 17737.733933991, 733.8348871503896, 837.6105111123327, 866.380537915392, 875.4572165257517, 877.2630989968425, 862.9697960571381, 871.5859966515918, 874.3224001398935, 874.4796845876693, 875.6147295190128, 926.8887733471993, 928.4475736936546, 928.7631653928273, 929.4423345992418, 932.3287233192091, 777.1373775109623, 890.3856339125982, 911.4941207629763, 913.0048247410355, 923.155312888706, 675.9391654751047, 676.4013847744732, 676.8003438985511, 677.1535580524345, 677.9017042528806, 16570.17149142538, 16624.750628740578, 16624.83375166245, 16628.750568741478, 16655.50033498997, 14574.489892853699, 14819.18494482803, 14828.481003164945, 15034.624134396618, 15110.320035598425, 14412.567622971328, 14477.927610361881, 14499.782503262459, 14652.560423187215, 14863.256837158207, 4546.818127274905, 4709.387779588666, 4774.570288674001, 4794.712317260941, 4819.373481447425, 75126.62436687782, 76357.0, 76494.23505764983, 76514.6174269125, 76620.0, 11746.88253117475, 12005.63983080509, 12221.08341874363, 12358.44387002587, 12364.381780910919, 21672.89163554172, 21688.674669879965, 21754.456138596663, 21786.346409607737, 21787.564248714938, 8710.738677839676, 9140.817183656289, 9225.077492250855, 9361.0, 9520.619175232912, 5064.49355064498, 5182.119039763249, 5229.111051121318, 5247.501487358695, 5387.0, 3598.712103031751, 3708.035910663193, 3737.2151848111657, 3740.7007439405324, 3743.6256374362347, 685.4002747595854, 689.6620655878584, 702.3362922038726, 707.4552594502308, 710.6304721544872, 660.5244224158598, 676.5737585321216, 729.8284903047779, 738.3539403022355, 768.5388766739911, 10183.236257280734, 10402.47987600617, 10500.789984200426, 10553.788924221473, 10611.787764244671, 676.9864602708017, 700.1598082301259, 711.4308553157454, 740.4076738609091, 767.140802301426, 4990.251462280687, 5004.0, 5005.699658020458, 5069.315642388304, 5153.433122356538, 114462.42768786103, 115160.0, 117907.0, 120296.0, 120769.39615301868, 76802.61598691971, 88124.5593772027, 100873.0, 101600.0, 102171.48914255529, 90356.54821725849, 90519.0, 91048.0, 91131.0, 91153.54423227841, 9718.173955213886, 9779.119879210904, 9785.60857565718, 9788.951055244679, 9792.85310720354, 52662.473375265756, 54866.17700734572, 55051.89896202134, 55955.44044559502, 57273.0, 52361.0, 71704.0, 76460.23539764532, 76991.23008769841, 79454.6027269883, 13735.45058197661, 14194.077384969944, 14631.419215717271, 14820.777688334469, 15100.622484437978, 16192.352305907632, 16534.12298337162, 16633.002019878844, 16711.0, 17130.828691712923, 14409.567712968628, 14722.926385368004, 14822.703545929236, 15051.623709407353, 15458.690826183189, 14617.707645846811, 15168.089914604718, 15779.289931953319, 16501.174941253128, 16517.174141292646, 15542.222888855285, 15572.92213538925, 15672.92163539175, 16061.518154455385, 16541.66916661684, 12342.75314493723, 12693.873061269638, 12697.93651031739, 13088.869111308764, 13269.46922123143, 12324.137310388187, 12354.25874447536, 12669.619911402673, 12772.10595258323, 13197.802032969705], "value": 8172.038822542015, "unit": "score"}], "name": "dromaeo_css", "value": 7714.777662039126, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 110.0, 109.0, 111.0, 110.0, 110.0, 110.0, 110.0, 108.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 129.0, 172.0, 131.0, 130.0, 150.0, 124.0, 161.0, 124.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 162.0, 159.0, 163.0, 159.0, 159.0, 159.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 136.0, 74.0, 75.0, 76.0, 74.0, 98.0, 76.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 85.0, 84.0, 85.0, 116.0, 84.0, 85.0, 123.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 144.0], "value": 144.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 51.0, 51.0, 52.0, 51.0, 50.0, 49.0, 49.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 64.0, 59.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 82.0, 76.0, 81.0, 82.0, 77.0, 109.0, 77.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 126.0, 117.0, 126.0, 128.0, 115.0, 115.0, 124.0, 130.0, 119.0], "value": 122.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 153.0, 153.0, 155.0, 152.0, 162.0, 154.0, 152.0, 156.0, 156.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 67.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1473.6999999999998, "alertThreshold": 2.0}]} 12:33:11 INFO - Return code: 0 12:33:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:33:12 ERROR - # TBPL SUCCESS # 12:33:12 INFO - Running post-action listener: _resource_record_post_action 12:33:12 INFO - ##### 12:33:12 INFO - ##### Finished run-tests step (success) 12:33:12 INFO - ##### 12:33:12 INFO - Running post-run listener: _resource_record_post_run 12:33:12 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 339968; Write bytes: 252690432; Read time: 104; Write time: 158400 12:33:12 INFO - TinderboxPrint: CPU usage
13.0% 12:33:12 INFO - TinderboxPrint: I/O read bytes / time
339,968 / 104 12:33:12 INFO - TinderboxPrint: I/O write bytes / time
252,690,432 / 158,400 12:33:12 INFO - TinderboxPrint: CPU idle
8,251.4 (86.9%) 12:33:12 INFO - TinderboxPrint: CPU user
1,196.4 (12.6%) 12:33:12 INFO - TinderboxPrint: Swap in / out
0 / 0 12:33:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 3133440; Read time: 0; Write time: 424 12:33:12 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 315392; Write bytes: 249090048; Read time: 88; Write time: 155200 12:33:12 INFO - Running post-run listener: _upload_blobber_files 12:33:12 INFO - Blob upload gear active. 12:33:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:33:12 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 12:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:33:13 INFO - (blobuploader) - INFO - Open directory for files ... 12:33:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:33:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:33:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:14 INFO - (blobuploader) - INFO - Done attempting. 12:33:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:33:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:33:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:30 INFO - (blobuploader) - INFO - Done attempting. 12:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:33:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:31 INFO - (blobuploader) - INFO - Done attempting. 12:33:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:33:31 INFO - Return code: 0 12:33:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:33:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:33:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf7aad0fad7c5a49d98d4ed438f15aa84cb3862f4b99b3fa672003ab8d9265dd35d2e967864c6e4ad7c4890d61d6aa9b2a6a9a873ebe22c17f6291b8c957c88e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5c86482833229d346c5af44ce9083a7d8bb23bf18094ab2805b5cc8cd79fa68e6e634c1bba2a034c96dbc16d12d442ac67c6d077806654c23d18a4772d7c85f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6abfa5e9227e39a2aa5691cd0f5ad9bdc0a8a94fec464e50d3c898554b83599b89aa5e49bbf96c35ae9630906754c85ce1a0a30bfe368ed91ecd7a1ce5672de"} 12:33:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:33:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:33:31 INFO - Contents: 12:33:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf7aad0fad7c5a49d98d4ed438f15aa84cb3862f4b99b3fa672003ab8d9265dd35d2e967864c6e4ad7c4890d61d6aa9b2a6a9a873ebe22c17f6291b8c957c88e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5c86482833229d346c5af44ce9083a7d8bb23bf18094ab2805b5cc8cd79fa68e6e634c1bba2a034c96dbc16d12d442ac67c6d077806654c23d18a4772d7c85f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6abfa5e9227e39a2aa5691cd0f5ad9bdc0a8a94fec464e50d3c898554b83599b89aa5e49bbf96c35ae9630906754c85ce1a0a30bfe368ed91ecd7a1ce5672de"} 12:33:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:33:31 INFO - Copying logs to upload dir... 12:33:31 INFO - mkdir: /builds/slave/test/build/upload/logs 12:33:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.170854 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-22 12:33:31.378949) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:33:31.382768) ========= 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=ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf7aad0fad7c5a49d98d4ed438f15aa84cb3862f4b99b3fa672003ab8d9265dd35d2e967864c6e4ad7c4890d61d6aa9b2a6a9a873ebe22c17f6291b8c957c88e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5c86482833229d346c5af44ce9083a7d8bb23bf18094ab2805b5cc8cd79fa68e6e634c1bba2a034c96dbc16d12d442ac67c6d077806654c23d18a4772d7c85f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6abfa5e9227e39a2aa5691cd0f5ad9bdc0a8a94fec464e50d3c898554b83599b89aa5e49bbf96c35ae9630906754c85ce1a0a30bfe368ed91ecd7a1ce5672de"} build_url:https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011460 build_url: 'https://queue.taskcluster.net/v1/task/LltfTM4iQbivceHsfZE7pQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf7aad0fad7c5a49d98d4ed438f15aa84cb3862f4b99b3fa672003ab8d9265dd35d2e967864c6e4ad7c4890d61d6aa9b2a6a9a873ebe22c17f6291b8c957c88e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5c86482833229d346c5af44ce9083a7d8bb23bf18094ab2805b5cc8cd79fa68e6e634c1bba2a034c96dbc16d12d442ac67c6d077806654c23d18a4772d7c85f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6abfa5e9227e39a2aa5691cd0f5ad9bdc0a8a94fec464e50d3c898554b83599b89aa5e49bbf96c35ae9630906754c85ce1a0a30bfe368ed91ecd7a1ce5672de"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:33:31.408419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:33:31.408758) ========= 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=ed14279e38b05397cf4aa391000001a4-1469214762.358011-979766418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:33:31.492325) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:33:31.492679) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:33:31.492995) ========= ========= Total master_lag: 0.18 =========