builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1469355656.92 results: success (0) buildid: 20160724004007 builduid: 7790b51952384ffe98b1663a6f752caf revision: b95e8981e41800c3b202ea73d096e3e3e184d70d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:56.920916) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:56.921392) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:56.921740) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:57.004235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:57.004611) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-24 03:20:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-07-24 03:20:57 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127987 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:57.168070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:57.168414) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014892 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:57.219397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:57.219956) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b95e8981e41800c3b202ea73d096e3e3e184d70d --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 b95e8981e41800c3b202ea73d096e3e3e184d70d --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=5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-24 03:20:57,516 truncating revision to first 12 chars 2016-07-24 03:20:57,517 Setting DEBUG logging. 2016-07-24 03:20:57,517 attempt 1/10 2016-07-24 03:20:57,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b95e8981e418?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-24 03:20:57,956 unpacking tar archive at: mozilla-aurora-b95e8981e418/testing/mozharness/ program finished with exit code 0 elapsedTime=0.780210 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:58.018287) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:58.019101) ========= script_repo_revision: b95e8981e41800c3b202ea73d096e3e3e184d70d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:58.020049) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:58.020773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-24 03:20:58.036805) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-07-24 03:20:58.037165) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:20:58 INFO - MultiFileLogger online at 20160724 03:20:58 in /builds/slave/test 03:20:58 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 03:20:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:58 INFO - {'append_to_log': False, 03:20:58 INFO - 'base_work_dir': '/builds/slave/test', 03:20:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:20:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:58 INFO - 'branch': 'Mozilla-Aurora', 03:20:58 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:58 INFO - 'config_files': ('talos/linux_config.py',), 03:20:58 INFO - 'default_actions': ('clobber', 03:20:58 INFO - 'read-buildbot-config', 03:20:58 INFO - 'download-and-extract', 03:20:58 INFO - 'populate-webroot', 03:20:58 INFO - 'create-virtualenv', 03:20:58 INFO - 'install', 03:20:58 INFO - 'run-tests'), 03:20:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:58 INFO - 'download_minidump_stackwalk': True, 03:20:58 INFO - 'download_symbols': 'ondemand', 03:20:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:58 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:58 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:58 INFO - 'installer_path': 'installer.exe', 03:20:58 INFO - 'log_level': 'info', 03:20:58 INFO - 'log_name': 'talos', 03:20:58 INFO - 'log_to_console': True, 03:20:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:20:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:20:58 INFO - 'opt_config_files': (), 03:20:58 INFO - 'pip_index': False, 03:20:58 INFO - 'sps_profile': False, 03:20:58 INFO - 'sps_profile_interval': 0, 03:20:58 INFO - 'suite': 'dromaeojs', 03:20:58 INFO - 'system_bits': '32', 03:20:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:58 INFO - 'title': 'talos-linux64-ix-051', 03:20:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:58 INFO - 'use_talos_json': True, 03:20:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:58 INFO - 'work_dir': 'build'} 03:20:58 INFO - ##### 03:20:58 INFO - ##### Running clobber step. 03:20:58 INFO - ##### 03:20:58 INFO - Running pre-action listener: _resource_record_pre_action 03:20:58 INFO - Running main action method: clobber 03:20:58 INFO - rmtree: /builds/slave/test/build 03:20:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:58 INFO - Running post-action listener: _resource_record_post_action 03:20:58 INFO - ##### 03:20:58 INFO - ##### Finished clobber step (success) 03:20:58 INFO - ##### 03:20:58 INFO - ##### 03:20:58 INFO - ##### Running read-buildbot-config step. 03:20:58 INFO - ##### 03:20:58 INFO - Running pre-action listener: _resource_record_pre_action 03:20:58 INFO - Running main action method: read_buildbot_config 03:20:58 INFO - Using buildbot properties: 03:20:58 INFO - { 03:20:58 INFO - "project": "", 03:20:58 INFO - "product": "firefox", 03:20:58 INFO - "script_repo_revision": "production", 03:20:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:20:58 INFO - "repository": "", 03:20:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:20:58 INFO - "buildid": "20160724004007", 03:20:58 INFO - "stage_platform": "linux64-pgo", 03:20:58 INFO - "basedir": "/builds/slave/test", 03:20:58 INFO - "nightly_build": "True", 03:20:58 INFO - "buildnumber": 625, 03:20:58 INFO - "slavename": "talos-linux64-ix-051", 03:20:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:20:58 INFO - "platform": "ubuntu64_hw", 03:20:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:20:58 INFO - "branch": "mozilla-aurora", 03:20:58 INFO - "repo_path": "releases/mozilla-aurora", 03:20:58 INFO - "revision": "b95e8981e41800c3b202ea73d096e3e3e184d70d", 03:20:58 INFO - "pgo_build": "True", 03:20:58 INFO - "builduid": "7790b51952384ffe98b1663a6f752caf", 03:20:58 INFO - "slavebuilddir": "test" 03:20:58 INFO - } 03:20:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:20:58 INFO - Running post-action listener: _resource_record_post_action 03:20:58 INFO - ##### 03:20:58 INFO - ##### Finished read-buildbot-config step (success) 03:20:58 INFO - ##### 03:20:58 INFO - ##### 03:20:58 INFO - ##### Running download-and-extract step. 03:20:58 INFO - ##### 03:20:58 INFO - Running pre-action listener: _resource_record_pre_action 03:20:58 INFO - Running main action method: download_and_extract 03:20:58 INFO - mkdir: /builds/slave/test/build/tests 03:20:58 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')]} 03:20:58 INFO - https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:20:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:20:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:20:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:03 INFO - Downloaded 1361 bytes. 03:21:03 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:21:03 INFO - Using the following test package requirements: 03:21:03 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:21:03 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:21:03 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:21:03 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'jsshell-linux-x86_64.zip'], 03:21:03 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:21:03 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:21:03 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:21:03 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:21:03 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:21:03 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:21:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:21:03 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:03 INFO - https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:21:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:21:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:21:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:06 INFO - Downloaded 24494256 bytes. 03:21:06 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'] 03:21:06 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 03:21:08 INFO - Return code: 0 03:21:08 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 03:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:08 INFO - https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:21:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:21:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:21:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:11 INFO - Downloaded 24494256 bytes. 03:21:11 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'] 03:21:11 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 03:21:13 INFO - Return code: 0 03:21: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')]} 03:21:13 INFO - https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:21:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:14 INFO - Downloaded 10833369 bytes. 03:21:14 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'] 03:21:14 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 03:21:15 INFO - Return code: 0 03:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:15 INFO - https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:21:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:21:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:21:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TKLh39J9RUa7u1MaVsrseg/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 03:21:16 INFO - Downloaded 63647055 bytes. 03:21:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:21:16 INFO - mkdir: /builds/slave/test/properties 03:21:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:16 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:16 INFO - Contents: 03:21:16 INFO - build_url:https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:21:17 INFO - Running post-action listener: _resource_record_post_action 03:21:17 INFO - Running post-action listener: set_extra_try_arguments 03:21:17 INFO - ##### 03:21:17 INFO - ##### Finished download-and-extract step (success) 03:21:17 INFO - ##### 03:21:17 INFO - ##### 03:21:17 INFO - ##### Running populate-webroot step. 03:21:17 INFO - ##### 03:21:17 INFO - Running pre-action listener: _resource_record_pre_action 03:21:17 INFO - Running main action method: populate_webroot 03:21:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:17 INFO - '--tppagecycles', 03:21:17 INFO - '7'], 03:21:17 INFO - 'tests': ['tsvgm']}}, 03:21:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['damp', 'tps']}, 03:21:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'tests': ['damp', 'tps']}, 03:21:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['dromaeo_dom']}, 03:21:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['a11yr', 03:21:17 INFO - 'ts_paint', 03:21:17 INFO - 'tpaint', 03:21:17 INFO - 'sessionrestore', 03:21:17 INFO - 'sessionrestore_no_auto_restore', 03:21:17 INFO - 'tabpaint']}, 03:21:17 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:17 INFO - 'ts_paint', 03:21:17 INFO - 'tpaint', 03:21:17 INFO - 'sessionrestore', 03:21:17 INFO - 'sessionrestore_no_auto_restore', 03:21:17 INFO - 'tabpaint']}, 03:21:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:17 INFO - 'ts_paint', 03:21:17 INFO - 'tpaint', 03:21:17 INFO - 'sessionrestore', 03:21:17 INFO - 'sessionrestore_no_auto_restore', 03:21:17 INFO - 'tabpaint']}, 03:21:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:17 INFO - 'ts_paint', 03:21:17 INFO - 'tpaint', 03:21:17 INFO - 'sessionrestore', 03:21:17 INFO - 'sessionrestore_no_auto_restore', 03:21:17 INFO - 'tabpaint']}, 03:21:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['a11yr', 03:21:17 INFO - 'ts_paint', 03:21:17 INFO - 'tpaint', 03:21:17 INFO - 'sessionrestore', 03:21:17 INFO - 'sessionrestore_no_auto_restore', 03:21:17 INFO - 'tabpaint']}, 03:21:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['a11yr', 03:21:17 INFO - 'ts_paint', 03:21:17 INFO - 'tpaint', 03:21:17 INFO - 'sessionrestore', 03:21:17 INFO - 'sessionrestore_no_auto_restore', 03:21:17 INFO - 'tabpaint']}, 03:21:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['tsvgx', 03:21:17 INFO - 'tsvgr_opacity', 03:21:17 INFO - 'tart', 03:21:17 INFO - 'tscrollx', 03:21:17 INFO - 'cart']}, 03:21:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:17 INFO - 'tsvgr_opacity', 03:21:17 INFO - 'tart', 03:21:17 INFO - 'tscrollx', 03:21:17 INFO - 'cart']}, 03:21:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'talos_options': ['--disable-e10s'], 03:21:17 INFO - 'tests': ['tp5o']}, 03:21:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'tests': ['tp5o']}, 03:21:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'talos_options': ['--disable-e10s', 03:21:17 INFO - '--xperf_path', 03:21:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:17 INFO - 'tests': ['tp5n']}, 03:21:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:17 INFO - 'talos_options': ['--xperf_path', 03:21:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:17 INFO - 'tests': ['tp5n']}}, 03:21:17 INFO - 'talos.zip': {'path': '', 03:21:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:17 INFO - Running post-action listener: _resource_record_post_action 03:21:17 INFO - ##### 03:21:17 INFO - ##### Finished populate-webroot step (success) 03:21:17 INFO - ##### 03:21:17 INFO - ##### 03:21:17 INFO - ##### Running create-virtualenv step. 03:21:17 INFO - ##### 03:21:17 INFO - Running pre-action listener: _resource_record_pre_action 03:21:17 INFO - Running main action method: create_virtualenv 03:21:17 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:17 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 03:21:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:17 INFO - Using real prefix '/usr' 03:21:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:18 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:20 INFO - Installing pip.................done. 03:21:20 INFO - Return code: 0 03:21:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:20 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')]} 03:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:20 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')]} 03:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:20 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:20 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 03:21:20 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 03:21:20 INFO - Using env: {'DISPLAY': ':0', 03:21:20 INFO - 'HOME': '/home/cltbld', 03:21:20 INFO - 'LANG': 'en_US.UTF-8', 03:21:20 INFO - 'LANGUAGE': 'en_US:en', 03:21:20 INFO - 'LOGNAME': 'cltbld', 03:21:20 INFO - 'MAIL': '/var/mail/cltbld', 03:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:20 INFO - 'MOZ_NO_REMOTE': '1', 03:21:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:20 INFO - 'NO_EM_RESTART': '1', 03:21:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:20 INFO - 'PWD': '/builds/slave/test', 03:21:20 INFO - 'SHELL': '/bin/bash', 03:21:20 INFO - 'SHLVL': '1', 03:21:20 INFO - 'TERM': 'linux', 03:21:20 INFO - 'TMOUT': '86400', 03:21:20 INFO - 'USER': 'cltbld', 03:21:20 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:20 INFO - '_': '/tools/buildbot/bin/python'} 03:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:20 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 03:21:20 INFO - Cleaning up... 03:21:20 INFO - Return code: 0 03:21:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:20 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')]} 03:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:20 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')]} 03:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:20 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:20 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 03:21:20 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 03:21:20 INFO - Using env: {'DISPLAY': ':0', 03:21:20 INFO - 'HOME': '/home/cltbld', 03:21:20 INFO - 'LANG': 'en_US.UTF-8', 03:21:20 INFO - 'LANGUAGE': 'en_US:en', 03:21:20 INFO - 'LOGNAME': 'cltbld', 03:21:20 INFO - 'MAIL': '/var/mail/cltbld', 03:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:20 INFO - 'MOZ_NO_REMOTE': '1', 03:21:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:20 INFO - 'NO_EM_RESTART': '1', 03:21:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:20 INFO - 'PWD': '/builds/slave/test', 03:21:20 INFO - 'SHELL': '/bin/bash', 03:21:20 INFO - 'SHLVL': '1', 03:21:20 INFO - 'TERM': 'linux', 03:21:20 INFO - 'TMOUT': '86400', 03:21:20 INFO - 'USER': 'cltbld', 03:21:20 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:20 INFO - '_': '/tools/buildbot/bin/python'} 03:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:20 INFO - Downloading/unpacking psutil>=3.1.1 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:22 INFO - Installing collected packages: psutil 03:21:22 INFO - Running setup.py install for psutil 03:21:22 INFO - building 'psutil._psutil_linux' extension 03:21:22 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 03:21:24 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 03:21:24 INFO - building 'psutil._psutil_posix' extension 03:21:24 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 03:21:25 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 03:21:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:25 INFO - Successfully installed psutil 03:21:25 INFO - Cleaning up... 03:21:25 INFO - Return code: 0 03:21:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:25 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:25 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 03:21:25 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 03:21:25 INFO - Using env: {'DISPLAY': ':0', 03:21:25 INFO - 'HOME': '/home/cltbld', 03:21:25 INFO - 'LANG': 'en_US.UTF-8', 03:21:25 INFO - 'LANGUAGE': 'en_US:en', 03:21:25 INFO - 'LOGNAME': 'cltbld', 03:21:25 INFO - 'MAIL': '/var/mail/cltbld', 03:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:25 INFO - 'MOZ_NO_REMOTE': '1', 03:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:25 INFO - 'NO_EM_RESTART': '1', 03:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:25 INFO - 'PWD': '/builds/slave/test', 03:21:25 INFO - 'SHELL': '/bin/bash', 03:21:25 INFO - 'SHLVL': '1', 03:21:25 INFO - 'TERM': 'linux', 03:21:25 INFO - 'TMOUT': '86400', 03:21:25 INFO - 'USER': 'cltbld', 03:21:25 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:25 INFO - '_': '/tools/buildbot/bin/python'} 03:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:25 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 03:21:25 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 03:21:25 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 03:21:25 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 03:21:25 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 03:21:25 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 03:21:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:27 INFO - Installing collected packages: mozsystemmonitor 03:21:27 INFO - Running setup.py install for mozsystemmonitor 03:21:27 INFO - Successfully installed mozsystemmonitor 03:21:27 INFO - Cleaning up... 03:21:27 INFO - Return code: 0 03:21:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:27 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')]} 03:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:27 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')]} 03:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:27 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:27 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 03:21:27 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 03:21:27 INFO - Using env: {'DISPLAY': ':0', 03:21:27 INFO - 'HOME': '/home/cltbld', 03:21:27 INFO - 'LANG': 'en_US.UTF-8', 03:21:27 INFO - 'LANGUAGE': 'en_US:en', 03:21:27 INFO - 'LOGNAME': 'cltbld', 03:21:27 INFO - 'MAIL': '/var/mail/cltbld', 03:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:27 INFO - 'MOZ_NO_REMOTE': '1', 03:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:27 INFO - 'NO_EM_RESTART': '1', 03:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:27 INFO - 'PWD': '/builds/slave/test', 03:21:27 INFO - 'SHELL': '/bin/bash', 03:21:27 INFO - 'SHLVL': '1', 03:21:27 INFO - 'TERM': 'linux', 03:21:27 INFO - 'TMOUT': '86400', 03:21:27 INFO - 'USER': 'cltbld', 03:21:27 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:27 INFO - '_': '/tools/buildbot/bin/python'} 03:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:27 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:27 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 03:21:27 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 03:21:27 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 03:21:27 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 03:21:27 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 03:21:27 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 03:21:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:29 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 03:21:29 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 03:21:29 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 03:21:29 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 03:21:29 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 03:21:29 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 03:21:29 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:29 INFO - warning: no files found matching '*.txt' 03:21:29 INFO - no previously-included directories found matching 'build' 03:21:29 INFO - no previously-included directories found matching 'dist' 03:21:29 INFO - no previously-included directories found matching '.git*' 03:21:29 INFO - Installing collected packages: jsonschema, functools32 03:21:29 INFO - Running setup.py install for functools32 03:21:30 INFO - warning: no files found matching '*.txt' 03:21:30 INFO - no previously-included directories found matching 'build' 03:21:30 INFO - no previously-included directories found matching 'dist' 03:21:30 INFO - no previously-included directories found matching '.git*' 03:21:30 INFO - Successfully installed jsonschema functools32 03:21:30 INFO - Cleaning up... 03:21:30 INFO - Return code: 0 03:21:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:21:30 INFO - Using env: {'DISPLAY': ':0', 03:21:30 INFO - 'HOME': '/home/cltbld', 03:21:30 INFO - 'LANG': 'en_US.UTF-8', 03:21:30 INFO - 'LANGUAGE': 'en_US:en', 03:21:30 INFO - 'LOGNAME': 'cltbld', 03:21:30 INFO - 'MAIL': '/var/mail/cltbld', 03:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:30 INFO - 'MOZ_NO_REMOTE': '1', 03:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:30 INFO - 'NO_EM_RESTART': '1', 03:21:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:30 INFO - 'PWD': '/builds/slave/test', 03:21:30 INFO - 'SHELL': '/bin/bash', 03:21:30 INFO - 'SHLVL': '1', 03:21:30 INFO - 'TERM': 'linux', 03:21:30 INFO - 'TMOUT': '86400', 03:21:30 INFO - 'USER': 'cltbld', 03:21:30 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:30 INFO - '_': '/tools/buildbot/bin/python'} 03:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:30 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 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 03:21:32 INFO - Downloading docopt-0.6.1.tar.gz 03:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:33 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:33 INFO - Running setup.py install for blobuploader 03:21:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:33 INFO - Running setup.py install for requests 03:21:33 INFO - Running setup.py install for docopt 03:21:33 INFO - Successfully installed blobuploader requests docopt 03:21:33 INFO - Cleaning up... 03:21:33 INFO - Return code: 0 03:21:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:33 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:33 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 03:21:33 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 03:21:33 INFO - Using env: {'DISPLAY': ':0', 03:21:33 INFO - 'HOME': '/home/cltbld', 03:21:33 INFO - 'LANG': 'en_US.UTF-8', 03:21:33 INFO - 'LANGUAGE': 'en_US:en', 03:21:33 INFO - 'LOGNAME': 'cltbld', 03:21:33 INFO - 'MAIL': '/var/mail/cltbld', 03:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:33 INFO - 'MOZ_NO_REMOTE': '1', 03:21:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:33 INFO - 'NO_EM_RESTART': '1', 03:21:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:33 INFO - 'PWD': '/builds/slave/test', 03:21:33 INFO - 'SHELL': '/bin/bash', 03:21:33 INFO - 'SHLVL': '1', 03:21:33 INFO - 'TERM': 'linux', 03:21:33 INFO - 'TMOUT': '86400', 03:21:33 INFO - 'USER': 'cltbld', 03:21:33 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:33 INFO - '_': '/tools/buildbot/bin/python'} 03:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:33 INFO - Running setup.py (path:/tmp/pip-tRPYJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:33 INFO - Running setup.py (path:/tmp/pip-XNlX5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:33 INFO - Running setup.py (path:/tmp/pip-0shUZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:34 INFO - Running setup.py (path:/tmp/pip-BCBDOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:34 INFO - Running setup.py (path:/tmp/pip-DqEB1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:34 INFO - Running setup.py (path:/tmp/pip-4GQQR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:34 INFO - Running setup.py (path:/tmp/pip-x4XItV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:34 INFO - Running setup.py (path:/tmp/pip-9jbHaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:34 INFO - Running setup.py (path:/tmp/pip-2FuFpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:34 INFO - Running setup.py (path:/tmp/pip-ltdr7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:34 INFO - Running setup.py (path:/tmp/pip-38zoI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:34 INFO - Running setup.py (path:/tmp/pip-vVyBT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:34 INFO - Running setup.py (path:/tmp/pip-S1BZq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:34 INFO - Running setup.py (path:/tmp/pip-NaKgZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:34 INFO - Running setup.py (path:/tmp/pip-HFFtSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:34 INFO - Running setup.py (path:/tmp/pip-pmnmbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:35 INFO - Running setup.py (path:/tmp/pip-d1s0Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:35 INFO - Running setup.py install for manifestparser 03:21:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Running setup.py install for mozcrash 03:21:35 INFO - Running setup.py install for mozdebug 03:21:35 INFO - Running setup.py install for mozdevice 03:21:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Running setup.py install for mozfile 03:21:35 INFO - Running setup.py install for mozhttpd 03:21:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Running setup.py install for mozinfo 03:21:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Running setup.py install for mozInstall 03:21:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:35 INFO - Running setup.py install for mozleak 03:21:35 INFO - Running setup.py install for mozlog 03:21:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Running setup.py install for moznetwork 03:21:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Running setup.py install for mozprocess 03:21:36 INFO - Running setup.py install for mozprofile 03:21:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Running setup.py install for mozrunner 03:21:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Running setup.py install for mozscreenshot 03:21:36 INFO - Running setup.py install for moztest 03:21:36 INFO - Running setup.py install for mozversion 03:21:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:36 INFO - Cleaning up... 03:21:36 INFO - Return code: 0 03:21:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:36 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:36 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 03:21:36 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 03:21:36 INFO - Using env: {'DISPLAY': ':0', 03:21:36 INFO - 'HOME': '/home/cltbld', 03:21:36 INFO - 'LANG': 'en_US.UTF-8', 03:21:36 INFO - 'LANGUAGE': 'en_US:en', 03:21:36 INFO - 'LOGNAME': 'cltbld', 03:21:36 INFO - 'MAIL': '/var/mail/cltbld', 03:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:36 INFO - 'MOZ_NO_REMOTE': '1', 03:21:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:36 INFO - 'NO_EM_RESTART': '1', 03:21:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:36 INFO - 'PWD': '/builds/slave/test', 03:21:36 INFO - 'SHELL': '/bin/bash', 03:21:36 INFO - 'SHLVL': '1', 03:21:36 INFO - 'TERM': 'linux', 03:21:36 INFO - 'TMOUT': '86400', 03:21:36 INFO - 'USER': 'cltbld', 03:21:36 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:36 INFO - '_': '/tools/buildbot/bin/python'} 03:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:37 INFO - Running setup.py (path:/tmp/pip-tNo0zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:37 INFO - Running setup.py (path:/tmp/pip-Cyfj7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:37 INFO - Running setup.py (path:/tmp/pip-KcVTUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:37 INFO - Running setup.py (path:/tmp/pip-8r6mz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:37 INFO - Running setup.py (path:/tmp/pip-2od2kH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:37 INFO - Running setup.py (path:/tmp/pip-mv7vO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:37 INFO - Running setup.py (path:/tmp/pip-gKPQEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:37 INFO - Running setup.py (path:/tmp/pip-3evkTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:37 INFO - Running setup.py (path:/tmp/pip-S_OwmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:37 INFO - Running setup.py (path:/tmp/pip-kp5atf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:37 INFO - Running setup.py (path:/tmp/pip-RupJM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:37 INFO - Running setup.py (path:/tmp/pip-t6cQCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:37 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)) 03:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:37 INFO - Running setup.py (path:/tmp/pip-JeS6d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:38 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)) 03:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:38 INFO - Running setup.py (path:/tmp/pip-g_BX7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:38 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)) 03:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:38 INFO - Running setup.py (path:/tmp/pip-LGQLof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:38 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)) 03:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:38 INFO - Running setup.py (path:/tmp/pip-c1zN5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:38 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)) 03:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:38 INFO - Running setup.py (path:/tmp/pip-IvnSnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:38 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)) 03:21:38 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)) 03:21:38 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)) 03:21:38 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)) 03:21:38 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)) 03:21:38 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)) 03:21:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:40 INFO - Downloading blessings-1.6.tar.gz 03:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:40 INFO - Installing collected packages: blessings 03:21:40 INFO - Running setup.py install for blessings 03:21:40 INFO - Successfully installed blessings 03:21:40 INFO - Cleaning up... 03:21:40 INFO - Return code: 0 03:21:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:40 INFO - Reading from file tmpfile_stdout 03:21:40 INFO - Current package versions: 03:21:40 INFO - argparse == 1.2.1 03:21:40 INFO - blessings == 1.6 03:21:40 INFO - blobuploader == 1.2.4 03:21:40 INFO - docopt == 0.6.1 03:21:40 INFO - functools32 == 3.2.3-2 03:21:40 INFO - jsonschema == 2.5.1 03:21:40 INFO - manifestparser == 1.1 03:21:40 INFO - mozInstall == 1.12 03:21:40 INFO - mozcrash == 0.17 03:21:40 INFO - mozdebug == 0.1 03:21:40 INFO - mozdevice == 0.48 03:21:40 INFO - mozfile == 1.2 03:21:40 INFO - mozhttpd == 0.7 03:21:40 INFO - mozinfo == 0.9 03:21:40 INFO - mozleak == 0.1 03:21:40 INFO - mozlog == 3.2 03:21:40 INFO - moznetwork == 0.27 03:21:40 INFO - mozprocess == 0.23 03:21:40 INFO - mozprofile == 0.28 03:21:40 INFO - mozrunner == 6.12 03:21:40 INFO - mozscreenshot == 0.1 03:21:40 INFO - mozsystemmonitor == 0.3 03:21:40 INFO - moztest == 0.7 03:21:40 INFO - mozversion == 1.4 03:21:40 INFO - psutil == 3.1.1 03:21:40 INFO - requests == 1.2.3 03:21:40 INFO - wsgiref == 0.1.2 03:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:40 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:40 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 03:21:40 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 03:21:40 INFO - Using env: {'DISPLAY': ':0', 03:21:40 INFO - 'HOME': '/home/cltbld', 03:21:40 INFO - 'LANG': 'en_US.UTF-8', 03:21:40 INFO - 'LANGUAGE': 'en_US:en', 03:21:40 INFO - 'LOGNAME': 'cltbld', 03:21:40 INFO - 'MAIL': '/var/mail/cltbld', 03:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:40 INFO - 'MOZ_NO_REMOTE': '1', 03:21:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:40 INFO - 'NO_EM_RESTART': '1', 03:21:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:40 INFO - 'PWD': '/builds/slave/test', 03:21:40 INFO - 'SHELL': '/bin/bash', 03:21:40 INFO - 'SHLVL': '1', 03:21:40 INFO - 'TERM': 'linux', 03:21:40 INFO - 'TMOUT': '86400', 03:21:40 INFO - 'USER': 'cltbld', 03:21:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:40 INFO - '_': '/tools/buildbot/bin/python'} 03:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 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)) 03:21:40 INFO - Cleaning up... 03:21:40 INFO - Return code: 0 03:21:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:40 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:40 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 03:21:40 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 03:21:40 INFO - Using env: {'DISPLAY': ':0', 03:21:40 INFO - 'HOME': '/home/cltbld', 03:21:40 INFO - 'LANG': 'en_US.UTF-8', 03:21:40 INFO - 'LANGUAGE': 'en_US:en', 03:21:40 INFO - 'LOGNAME': 'cltbld', 03:21:40 INFO - 'MAIL': '/var/mail/cltbld', 03:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:40 INFO - 'MOZ_NO_REMOTE': '1', 03:21:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:40 INFO - 'NO_EM_RESTART': '1', 03:21:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:40 INFO - 'PWD': '/builds/slave/test', 03:21:40 INFO - 'SHELL': '/bin/bash', 03:21:40 INFO - 'SHLVL': '1', 03:21:40 INFO - 'TERM': 'linux', 03:21:40 INFO - 'TMOUT': '86400', 03:21:40 INFO - 'USER': 'cltbld', 03:21:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:40 INFO - '_': '/tools/buildbot/bin/python'} 03:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:21:41 INFO - Cleaning up... 03:21:41 INFO - Return code: 0 03:21:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:41 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 0x7f7b15fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df4020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', '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 03:21:41 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 03:21:41 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 03:21:41 INFO - Using env: {'DISPLAY': ':0', 03:21:41 INFO - 'HOME': '/home/cltbld', 03:21:41 INFO - 'LANG': 'en_US.UTF-8', 03:21:41 INFO - 'LANGUAGE': 'en_US:en', 03:21:41 INFO - 'LOGNAME': 'cltbld', 03:21:41 INFO - 'MAIL': '/var/mail/cltbld', 03:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:41 INFO - 'MOZ_NO_REMOTE': '1', 03:21:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:41 INFO - 'NO_EM_RESTART': '1', 03:21:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:41 INFO - 'PWD': '/builds/slave/test', 03:21:41 INFO - 'SHELL': '/bin/bash', 03:21:41 INFO - 'SHLVL': '1', 03:21:41 INFO - 'TERM': 'linux', 03:21:41 INFO - 'TMOUT': '86400', 03:21:41 INFO - 'USER': 'cltbld', 03:21:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:41 INFO - '_': '/tools/buildbot/bin/python'} 03:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:41 INFO - Downloading/unpacking flake8 03:21:41 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 03:21:41 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 03:21:41 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 03:21:41 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 03:21:41 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 03:21:41 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 03:21:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:21:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:21:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:21:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:21:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:21:43 INFO - no previously-included directories found matching 'docs/_build' 03:21:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:21:44 INFO - Running setup.py install for pep8 03:21:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:21:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:21:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:21:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:21:44 INFO - no previously-included directories found matching 'docs/_build' 03:21:44 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:21:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:21:44 INFO - Cleaning up... 03:21:44 INFO - Return code: 0 03:21:44 INFO - Running post-action listener: _resource_record_post_action 03:21:44 INFO - Running post-action listener: _start_resource_monitoring 03:21:44 INFO - Starting resource monitoring. 03:21:44 INFO - ##### 03:21:44 INFO - ##### Finished create-virtualenv step (success) 03:21:44 INFO - ##### 03:21:44 INFO - ##### 03:21:44 INFO - ##### Running install step. 03:21:44 INFO - ##### 03:21:44 INFO - Running pre-action listener: _resource_record_pre_action 03:21:44 INFO - Running main action method: install 03:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:44 INFO - Reading from file tmpfile_stdout 03:21:44 INFO - Detecting whether we're running mozinstall >=1.0... 03:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:21:44 INFO - Reading from file tmpfile_stdout 03:21:44 INFO - Output received: 03:21:44 INFO - Usage: mozinstall [options] installer 03:21:44 INFO - Options: 03:21:44 INFO - -h, --help show this help message and exit 03:21:44 INFO - -d DEST, --destination=DEST 03:21:44 INFO - Directory to install application into. [default: 03:21:44 INFO - "/builds/slave/test"] 03:21:44 INFO - --app=APP Application being installed. [default: firefox] 03:21:44 INFO - mkdir: /builds/slave/test/build/application 03:21:44 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'] 03:21:44 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 03:22:00 INFO - Reading from file tmpfile_stdout 03:22:00 INFO - Output received: 03:22:00 INFO - /builds/slave/test/build/application/firefox/firefox 03:22:00 INFO - Running post-action listener: _resource_record_post_action 03:22:00 INFO - ##### 03:22:00 INFO - ##### Finished install step (success) 03:22:00 INFO - ##### 03:22:00 INFO - ##### 03:22:00 INFO - ##### Running run-tests step. 03:22:00 INFO - ##### 03:22:00 INFO - Running pre-action listener: _resource_record_pre_action 03:22:00 INFO - Running main action method: run_tests 03:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:00 INFO - Python 2.7.3 03:22:00 INFO - Return code: 0 03:22:00 INFO - grabbing minidump binary from tooltool 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:00 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 0x1e862e0>, '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 0x1df2140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df25d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:00 INFO - Return code: 0 03:22:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:22:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:22:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:22:01 INFO - Return code: 0 03:22:01 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/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 03:22:01 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/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 03:22:01 INFO - Using env: {'DISPLAY': ':0', 03:22:01 INFO - 'HOME': '/home/cltbld', 03:22:01 INFO - 'LANG': 'en_US.UTF-8', 03:22:01 INFO - 'LANGUAGE': 'en_US:en', 03:22:01 INFO - 'LOGNAME': 'cltbld', 03:22:01 INFO - 'MAIL': '/var/mail/cltbld', 03:22:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:01 INFO - 'MOZ_NO_REMOTE': '1', 03:22:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:01 INFO - 'NO_EM_RESTART': '1', 03:22:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:01 INFO - 'PWD': '/builds/slave/test', 03:22:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:01 INFO - 'SHELL': '/bin/bash', 03:22:01 INFO - 'SHLVL': '1', 03:22:01 INFO - 'TERM': 'linux', 03:22:01 INFO - 'TMOUT': '86400', 03:22:01 INFO - 'USER': 'cltbld', 03:22:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522', 03:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:01 INFO - '_': '/tools/buildbot/bin/python'} 03:22:01 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/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 03:22:01 INFO - mozversion application_buildid: 20160724004007 03:22:01 INFO - mozversion application_changeset: b95e8981e41800c3b202ea73d096e3e3e184d70d 03:22:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:01 INFO - mozversion application_name: Firefox 03:22:01 INFO - mozversion application_remotingname: firefox-dev 03:22:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:01 INFO - mozversion application_vendor: Mozilla 03:22:01 INFO - mozversion application_version: 49.0a2 03:22:01 INFO - mozversion platform_buildid: 20160724004007 03:22:01 INFO - mozversion platform_changeset: b95e8981e41800c3b202ea73d096e3e3e184d70d 03:22:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:01 INFO - mozversion platform_version: 49.0a2 03:22:01 INFO - using testdate: 1469355721 03:22:01 INFO - actual date: 1469355721 03:22:01 INFO - starting webserver on 'localhost:39150' 03:22:01 INFO - SUITE-START | Running 2 tests 03:22:01 INFO - TEST-START | dromaeo_css 03:22:01 INFO - operating with platform_type : linux_ 03:22:01 INFO - Initialising browser for dromaeo_css test... 03:22:01 INFO - TEST-INFO | started process 3367 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIePhhO/profile http://localhost:39150/getInfo.html) 03:22:07 INFO - PROCESS | 3367 | __metrics Screen width/height:1600/1200 03:22:07 INFO - PROCESS | 3367 | colorDepth:24 03:22:07 INFO - PROCESS | 3367 | Browser inner width/height: 1024/697 03:22:07 INFO - PROCESS | 3367 | __metrics 03:22:08 INFO - PROCESS | 3367 | 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" 03:22:08 ERROR - PROCESS | 3367 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:22:09 INFO - TEST-INFO | 3367: exit 0 03:22:09 INFO - Browser initialized. 03:22:09 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:09 INFO - TEST-INFO | started process 3460 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIePhhO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:20 INFO - PROCESS | 3460 | RSS: Main: 149168128 03:22:20 INFO - PROCESS | 3460 | 03:23:12 INFO - PROCESS | 3460 | 1469355792266 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] 03:25:20 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:39150/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39150/tests/dromaeo/cssquery-ext.html) 03:25:21 INFO - PROCESS | 3460 | RSS: Main: 254550016 03:25:21 INFO - PROCESS | 3460 | 03:28:24 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:39150/tests/dromaeo/cssquery-ext.html (next: http://localhost:39150/tests/dromaeo/cssquery-jquery.html) 03:28:24 INFO - PROCESS | 3460 | RSS: Main: 233017344 03:28:24 INFO - PROCESS | 3460 | 03:31:26 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:39150/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39150/tests/dromaeo/cssquery-mootools.html) 03:31:26 INFO - PROCESS | 3460 | RSS: Main: 226578432 03:31:26 INFO - PROCESS | 3460 | 03:34:28 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:39150/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39150/tests/dromaeo/cssquery-prototype.html) 03:34:29 INFO - PROCESS | 3460 | RSS: Main: 225382400 03:34:29 INFO - PROCESS | 3460 | 03:37:32 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:39150/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39150/tests/dromaeo/cssquery-yui.html) 03:37:32 INFO - PROCESS | 3460 | RSS: Main: 220622848 03:37:32 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:39150/tests/dromaeo/cssquery-yui.html (next: http://localhost:39150/tests/dromaeo/cssquery-dojo.html) 03:40:35 INFO - PROCESS | 3460 | RSS: Main: 265641984 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | __start_tp_report 03:40:35 INFO - PROCESS | 3460 | _x_x_mozilla_page_load 03:40:35 INFO - PROCESS | 3460 | _x_x_mozilla_page_load_details 03:40:35 INFO - PROCESS | 3460 | |i|pagename|runs| 03:40:35 INFO - PROCESS | 3460 | |0;dojo.html;3381.1040074380285;3407.4207384744604;3417.214040770622;3439.3981053315665;3461.9307613847723;5543.63966342188;5580.0792869176585;5599.496045355917;5604.859878503034;5608.214849921014;5516.724163791814;5579.8605034874145;5596.4403559644015;5604.187392828037;5605.607607467479;4865.927011094836;4870.366852309195;5078.111330517163;5084.720340381277;5114.872128196806;5007.574356179729;5097.362829646294;5105.361829771278;5107.821226257081;5111.1566591512355;5011.9976004799;5030.968651426467;5064.797408103672;5071.949280507203;5102.081625307444;5129.820456284031;5142.254373115878;5150.742462876841;5154.020736060136;5171.508706672879;4844.394450693663;4948.183549714328;5004.099262132815;5058.342415486;5061.089003979282;5336.973315133399;5380.3274590676165;5388.7305634718105;5403.78384864605;5431.511163995259;5900.911486327708;5980;5984.640921544677;5985.970070149665;5995.070764031539;5867.647941123546;5923.437273459014;5948.464638182584;5956.076808094752;6029.969850150721;5577.470140336662;5582.33012038558;5591.049521581341;5593.860153496154;5600.52395546379;5863.091220860773;6056.969715151484;6062.054645135237;6089.512838972872;6096.177016102858;5805.535557155502;5877.588568800228;5922.466977972003;5935.940640593625;5950.523958083343;5999.1901093351835;6040.486558642477;6040.939590604038;6041.063635136561;6043.36544662804;5678.801241956512;5729.369769325371;5975.820725378246;5982.850428739229;5986.132010858401;5803.071508558694;5852.063669812809;5852.736626851802;5853.5317174625925;5882.235309409835;4447.911041779211;4449.911001779947;4455.509893911667;4463.04044630406;4479;4017.055991841915;4034.8184331705143;4064.04494943688;4081.653059489977;4092.7544347339253;5655;5724.77100915967;5727.914081288784;5728.427157284322;5730.598858079894;5994.730237139339;5998;6001.459868611846;6019.819405417845;6025.216721826135;5338.412774594792;5395.73021348931;5472.288602481652;5664.122061080539;5697.974364614326;4941.901161976741;4968.552830245295;4980.701157930535;4983.476734942851;4993.251012348104;6038.607490513103;6047.848803779941;6054.737220411361;6065.2418447694035;6090.177825993434;5785.56608254391;5876.08920617305;5900.085486749561;5930.021546444961;5944.910826337695;6232.897233596216;6259.937400625936;6265.8120256392385;6267.185265915402;6274.905876411766;5856.238688970407;5858.5898987070495;5867.383924688017;5880.852978675567;5916.822495325147;5632.436756324335;5765.654060756368;5858.736356864037;5869.4717475427415;5918.822435326947;5771.076627739541;5775.133729940542;5778.85552861165;5786.594938354275;5786.971065144648;5615.634983726045;5724.112762521816;5750.9424905752085;5760.1359796029255;5779.190913272061;5617.915731264116;5670.688112153871;5687.829365119053;5692.402297758676;5713.600047996601;5792.53659707214;5867.442592953663;5870.55970802183;5884.852878677896;5901.498372638373;5440.646357986719;5620.775168993195;5692.829215123553;5702.8289151325525;5707.8858422832145 03:40:35 INFO - PROCESS | 3460 | |1;ext.html;604.0848115105616;649.2403887451683;649.9610023398596;663.5288944849158;665.8535122273098;32918.67081329186;46645;47186.0562788744;47383.28925066118;47416;49999.25001124986;50372.748136259266;50433;50460.74769626147;50562.49437505624;8475.703350382737;9151.862722059157;9292.349535532516;9310.813783724321;11043.72390690231;10680.626178083769;11102.722431939226;11106;11110.777784444306;11112.277701949348;11205;11272.098232141409;11273.605423810168;11283.210175287733;11317;8633.179847914438;8767.386282960195;10983.340999540053;11144.60993865215;11302.265352752045;10801.783964320748;11170.888291117148;11196;11205.159613029055;11209.439528023566;47020;47256.05487890257;47412;47576.762116189195;47644;3764.661180493769;3793.146542028049;3824.1204522959874;4034.253663072341;4470.658802359279;36105.45841812375;36277.63722362769;36432.63567364345;36584.81707591445;36586.268274634356;4722.693024953402;4771.165046116916;4843.215151287988;4916.5820905222745;4948.901021979576;422436;430095;430640;577783;580272.9999999958;74749.6262518684;74863.62568187233;75486;75615.62192189004;75802.62098689472;41357;42459.57540424618;42459.787701061294;79501.60249198832;80239.59880200561;20285.492862678257;20354.592908141753;20475.283365082447;20492.282770102975;20512.794872051087;12529.74940501185;14412.855871441361;14583;14597.854021459649;14986;22674.206402775824;23017.654735178985;23165.53668926612;23257.3022809316;23315.300540983797;8314.877491538686;8401.243888050105;8417.957910210409;8454.61954212062;8509.361797865186;983.6016413352461;1039.1686650679426;1098.181854518389;1113.493360520961;1146.8681101673415;36420.45369319462;36532;36864.26271474556;36891.63108368882;36998;1177.1877404590805;1195.5397172088808;1211.7515909238584;1273.045216087934;1282.2050328796206;18819.435416937515;18910.905445472683;18965.146568404456;19101.2359505621;19115.71326430105;690193;698924;698941;700797;701718;67636.66181669159;68144.3185568138;68191.3180868185;68299.65850170718;68334.31665683379;1382.1154461144874;1461.3862177885314;1482.688635386581;1505.9397624094913;1513.6442935910052;35424.82287588545;37207.81396093002;37435.62564374322;37479.625203747615;38078;56711;56995.43004569901;57782;57823.710881445324;57841.710791447455;438904;443193;444751;445271;445781;28468;31208.843955780078;31816.5227521592;31910.52134217942;33598;54532.454675454326;55003;55553.16670249869;55890.16164757613;56106.438935610124;44428.777856110515;45813.31280030736;45825.54174458213;46188.7690561545;46219.3067103987;44747.32879006752;44807.77596111999;45624.54375456203;45633.31550026819;46922.53077469182;45670.31494527518;46262.07475850397;46307;46687;46755.53244467648;39273;40362.596374035886;41305.793471032455;43689;44408.77795611001;29606.259843504085;29991.250218744706;30147.602391406985;30523.38953220879;30776.384472310878 03:40:35 INFO - PROCESS | 3460 | |2;jquery.html;426.94449721536205;432.22847217716384;435.0580992151991;436.66158726986583;439.65143330310315;18917.7162342565;19719.704204436926;20730.067146978385;20936.685949710765;21023.579528409424;19347.322843700473;20167.09248083836;20445.693314600256;20513.38459846203;20516.589668206627;5783.248177736909;5993.46058854703;6012.158297838306;6015.248093988252;6019.668918209497;5708.914366284509;5921.289445266552;5964.493018093467;6003.429674180968;6008.3390827009;5957.970210148965;5962.463378295953;5991.790287339944;6008.3390827009;6026.126211699301;5934.31755348134;5997.190379298782;6114;6117.724702388403;6122.234720659918;5133;5897.262842144732;5914.2311499504785;5934.851628709273;5936.554758393095;14305.070170438992;14623;14634.926825365912;14678.412863485448;19552.31566895166;2295.7704229577075;2301.344116926668;2312.629979203319;2336.9883150584137;3010.578519007319;10117.291789574732;10190.133838623726;10245.948770256176;10557.366558006468;11150.052245559138;1531.7242896278665;1845.5478407790042;1914.0908068667272;1922.5962547864965;2010.326540608883;81140;86869.56565217196;87378;88111;88244.11755882423;11077.612283570037;11212.383318917375;11219.046381057538;11255.549778008952;11277;14906.329215185342;14935.17856517902;14977;15034.248287585577;15037.624059398602;8460.915390846012;8683.261922736512;8748.825023499494;8781.868271975925;8810.647574096964;8538.231559159705;8615.483071015758;8649;8753.956230218808;8774.078721734126;4742.288656701453;4766.6901651392545;4813.638977076734;4847.151748444008;4876.390451193601;1992.0736857361396;2005.7965220867363;2078.5738923520644;2081.40679906226;2097.6643737002005;794.89666343375;947.8862536495527;967.5645959318194;1025.5385076715509;1118.854548908653;19768.604627907647;19872.801271987384;19963.401097967086;20201.898990504953;20350.133474640068;991.6430085169334;1039.963601273967;1057.6774083904484;1065.4459680965854;1089.929154604948;10814.837777433344;11044.779104418027;11085.334879907232;11225.719357015976;11338.886611133948;501207;504719;510120;519056;524081;92432;92537;95900;96010.51994740122;96968;955.7276176289724;979.7158823940978;1009.1119814563284;1038.1383451735041;1039.6465201831413;17364.652706945537;17369.13154342303;17519.47441576755;17674.116294185496;17760.111994400486;41001.179976400905;41141.58858411497;41722.58277417187;42160.789196053825;42834.14331713286;59028;59242.70378648252;59523.107153391866;60190;61602.38397615967;8860.379773416056;9143.04943165247;9181.816363672822;9197.86203206939;9360;12487.687807804878;13354.93322533381;13827.377768000262;14027;14226.928865355607;12079.456424461096;12483.188592741442;12520.499180032697;12580.496780128693;12604.49582016709;11286.604968826052;11522.481488332878;12089.879101209228;12342.13605047638;12411.255324680547;12519.874801251872;12794.936025319814;12801.231926084465;12817;12880.420381082686;8872;8968.23769979559;9113.863292050492;9376.437413754924;9395.859062113937;11391.829122563002;12372.690682733004;12461.688457788629;12544.372781361079;12620.55828046014 03:40:35 INFO - PROCESS | 3460 | |3;mootools.html;1401.4884567133;1572.355334312932;1621.3757703284236;1806.6386722655463;1940.3596813051686;9366.969633340335;9722.659706910259;9758.902410975888;10001.449920254383;10010;9705.17506011988;9872.210223182146;9884.901150988488;9911.207103431727;9917.206623470123;2173.739151301838;2466.4943686544216;2467.457159424933;2496.3634273260295;2545.9943322387644;2511.221521328385;2524.810639202058;2526.090607381341;2527.279725278296;2527.684039495063;2433.8052955763583;2535.949281014379;2538.885750141239;2541.682289713786;2542.6058960861005;2141.5609799991043;2216.634255347874;2405.891734871926;2408.7350391456926;2476.021971321336;2445.8532488050773;2452.509498100369;2470.468849197431;2472.5302192583354;2474.455619763667;9768.120869121813;9812.656557020542;9835.901640983642;9840.65557705484;9846.556904939294;5788.42115788422;5943.257092863392;5947.762089516457;5949.25634295713;5951;3593.82030898454;3628.1473853644375;3670.3393389189937;3678.889633311005;3703.8518459261595;1304.478208716521;1326.7611829870618;1341.496938648007;1343.6640839790052;1359.0758284366627;14175.43298268068;14390.784138237936;14446.349914253884;14446.49437269701;14447;5028.069807085701;5078.238264260391;5124.15451450514;5315.920261196085;5325.201219817059;11793.705157371134;11906.166568340133;11911.166218364631;11913.106517011085;11913.16607837443;6010.3689112643415;6011.368806275365;6018.946684330224;6023.608465449732;6026.21659184312;4798.136335459651;5202.323697919246;5342;5352.063388906926;5422.457754224625;4377.912441751211;4428.357888106207;4475.194464996267;4501.482329532146;4521.344405061248;1391.8399384070758;1392.2064423278837;1411.287299913537;1415.419677932043;1427.257825930531;1067.4129228924216;1074.9086327662237;1077.7682798198425;1079.0558261521169;1081.7944590527932;6521.217453905561;6533.901991470132;6582.736690532325;6628.602283862983;6692.86614267712;475.310799340957;480.68515122594755;497.3459900231184;498.5612660858494;498.61855680404517;4509.887252818706;4591.1965406053805;4603.4015577975315;4615.884602884955;4617.145828021759;12025;12232.633021009384;12373;12388.070894682935;12449.128561000643;11837.467313970892;12202.206856554296;12290.75418491643;12300.83142101499;12367.75264494723;9092.090790920854;9157.175854173156;9214.98635150133;9215.90784092164;9283.628654853865;6695.765648202289;6882.208546017272;6898.8620227596175;6903.447724182154;7031.156261248683;11424.600138995096;11584.420778961186;11667.474963626826;11679.182457227913;11743.941280293886;11516.30902145874;11673.533058677898;11739.941300293443;11812.055035597303;11934.52261909514;7807.492512986638;7839.813644586434;8833.955830220808;8989.550522473719;9114.179723824887;9558.187554057982;9564.760880977752;9647.1317581418;9662.613495460102;9686.951565242129;8489.533075680896;8612.956935215283;8731.869021964803;8785.472871627468;8824.294056475595;8445.915540844759;8479.067302596586;8592.097829727789;8612.78468038279;8667.523286219066;7850.136484986533;7925;7938.682452702058;7943.84112317762;7949.125596184531;5678.829635110953;5694.259746232963;5725.341585717696;5763.164341170423;5874.706264686663;5835.854103647443;5919.7040147991565;5973.49225315853;6055.273367195965;6210.472109870711 03:40:35 INFO - PROCESS | 3460 | |4;prototype.html;347.06639140711485;351.92081781599137;356.34610489751304;360.0817914318487;360.88090929993103;5172.767225474853;5313.256144139824;5331.413544510106;5339;5343.358796944367;5275.894482110365;5293.841184764454;5304.655197412175;5322.574194064475;5345.893082138355;4212.831486740534;4237.173751118533;4250;4260.7656578888145;4264.87205383839;4031.8387264509383;4036.8587099451524;4237.809298581571;4239.215745087154;4265.808038638269;4173.791310434482;4204.516480604724;4221.965618423491;4225.809838557257;4225.880141762435;4189.518205406372;4192.832286708528;4200.915981680365;4203.747775133502;4205.558416366268;3673.1918977824835;3705.7220708446976;3766.7174961877695;3801.5669971218445;3802.1065049713297;5125.743712814382;5138.74306284688;5148.6910785353;5162.328897243372;5181.35233095863;4757.14371413149;4783.856484305476;4794.37673102498;4797.9520204797855;4809.639277054235;5378.489043540857;5518.696471694056;5553.77784888608;5577.442255774431;5622.269105016363;4161.459010328638;4164.167166566668;4176.770277634729;4195.3916682081235;4197;5730.68481233532;5731.398203188689;5741.741621627037;5760.164776107441;5811.936762449312;4155.439015732898;4249.618873866006;4253.191893540217;4262.232798096373;4283.507396649426;4300.871068820954;4300.935485967713;4305.763183024901;4317.7193482423545;4325.901937560281;4802.207635740134;4823.758812059383;4825.710457372569;4841.515848415201;4844.224924012212;5375.3280839895015;5610.635308704921;5619.494245517923;5621.156826476062;5633.4084921082695;1418.8935829812806;1447.5512591096583;1487.4645127754;1493.2608358862215;1497.1840347010905;965.7826988927435;968.7674958010027;981.8120074709539;983.4492684096786;994.9602015919427;4836.419629644465;4888.608911287089;4900.166971614834;4905.533974272438;4931.260310953386;5133.666311689803;5138.332016837787;5141.511556402136;5152.407473140637;5186.8848197636935;4692.835750748707;4878.268259760993;4888.95111048885;4910.656254062182;4925.359703238556;2732.9726702733115;2738.5755207942802;2757.993332433647;2761.558150695879;2770.6813716422466;5334.253204551366;5338.412774594792;5400.91898621521;5408.729563521808;5410.78356865721;4846.684965477232;5073.8731531712;5198.844034678966;5246.97376513115;5275.736213189325;5342.557509472498;5388.137897936312;5412.864678383072;5424.7016414096815;5439.836804895859;5096.51583099605;5110.9488905110475;5111.159982406405;5151.484851514819;5160.535551800356;4655.487896331469;4946.307516947558;4961.975190124026;4998.3002379667605;5050.3182070420025;5212.279947617201;5244.89510209786;5307.548858347083;5310.601704872072;5333.9466605335;3842.50047493824;3860.749051311656;3867.400552914303;3903.4730311407598;3919.3729003359326;4887.87780305481;5092.923606145837;5100.438951715234;5551.389347171727;5605.1872478491205;5596.692181930033;5611.158326251095;5614.971925140511;5615.2700148980375;5639.295088113986;5616.915746263728;5630.127330263816;5635.182898479779;5645.576581756467;5665.801696940587;5415.4313797050745;5467.316585426822;5529.917051244154;5530.391656917655;5550.722463876709;5628.240187574625;5654.293213348332;5656.321241450888;5661.98999355223;5666.631668941505;5404.813459767509;5448.4006759257245;5527.668339899619;5528.364238112507;5575.1915972184615 03:40:35 INFO - PROCESS | 3460 | |5;yui.html;819.1153554161506;857.8198578298557;866.4541338956457;877.9780505487362;924.9768755781105;13886.166829990183;15232.847671523281;16038.198090095508;16406.097664628454;16498.91750541247;15589.766153507706;15595.1422671753;15755.054696718205;15756.606084847885;15794.921025394857;734.5005396330494;873.8907636545432;888.9822203555941;892.6117139044504;892.995535022324;883.5493898111945;887.9866801997975;890.4835195586544;892.0677891603258;892.6697122064845;921.9723408297729;924.653255029364;926.3515539122608;927.8422668146432;928.0441145620022;770.3682979956438;895.789489469974;911.7811725185974;922.3313098003961;922.5156792683861;663.2803408301999;668.6723505482328;669.1668872254074;669.3641041011059;671.2850813883248;14938.62653433662;14940.103593784408;14940.775888361683;14949.327280272413;14973.401063957428;14017.229052402114;14631.487897923627;14726.926365368212;14841.109533428029;14905.180215088165;11905.702357441047;12205.20666156706;12236.449359778831;12616.306103164323;12630.936845315715;3977.0687936191425;4597.885052873705;4724.669273150881;4726.787294571753;4748.5014073522125;78766;80241;80762;80790;80820.59589702073;11585.420728963518;11970.940145299217;12155.696107597203;12188;12392.194507356993;21828.672569911465;21866.453338666342;21871.453213669785;21877.234296799536;21914.452138696342;8750.781230469162;8773.517456539952;8824.294056475466;8847.955760221157;9286.396384235004;5130.384353877484;5173.793048278102;5231.398389185217;5235.764390602356;5448.9727551362785;3826.330392181357;3951.150502641946;3951.7628942263555;3995.400689896481;3997.8200980955926;595.2767387624058;643.4594940250213;656.7340227207989;663.5885750834514;669.6350488983516;694.3785312145601;742.8180095876545;779.984400312002;817.6858005144048;821.0680877204296;10120.240981926385;10305.84541231882;10311.175105991502;10375;10424.270301078852;719.2807192807193;765.2500549461558;796.677345674991;809.7773112394021;810.7608255564622;5045.445001049881;5286.369986030748;5362.865928351823;5488.423715509894;5515.420880807537;112502;114623;116416.41791791156;116698.41650791862;120027.39986300013;81125;97439.51280243554;100820.49589752004;101221.49389253007;103622.48188759008;86126;86941;88689.33931982052;89806.55096724605;90300.54849725841;10595.205359598214;10952.233343665866;11052.944735276273;11205.913263501823;11270.49282782293;37687.434688479145;41590.376144358466;52413.737931311625;52858;55317.72341138429;66921.66539167272;71879;75669;75804.62097689476;76237;12643.747125057631;12649.367531623198;12999.870001299865;14078.859211408164;14508;14363.281835907954;14710.779338310149;16133.515994520183;16249.756253656442;16472.176391180634;14679.19264440466;14889.553313400615;15067.698646026798;15082.54752357431;15838.366465341258;15250.923745381646;15435;15453.304601292744;15914;16606.335746570487;14471.204083775072;14544.345504452536;15040.548783536511;15158.16630085356;15163.166025868682;12002.63992080239;12063.638090857288;12203.328816915155;12541.811872822098;12876.613701588967;12118.818217726917;12143.453544590691;12251.69370765738;12436.19164754288;12478.87521124777 03:40:35 INFO - PROCESS | 3460 | __end_tp_report 03:40:35 INFO - PROCESS | 3460 | __start_cc_report 03:40:35 INFO - PROCESS | 3460 | _x_x_mozilla_cycle_collect,841 03:40:35 INFO - PROCESS | 3460 | __end_cc_report 03:40:35 INFO - PROCESS | 3460 | __startTimestamp1469356835573__endTimestamp 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | ------- Summary: start ------- 03:40:35 INFO - PROCESS | 3460 | Number of tests: 6 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | [#0] dojo.html Cycles:165 Average:5507.25 Median:5707.89 stddev:610.05 (10.7%) stddev-sans-first:588.68 03:40:35 INFO - PROCESS | 3460 | Values: 3381.1 3407.4 3417.2 3439.4 3461.9 5543.6 5580.1 5599.5 5604.9 5608.2 5516.7 5579.9 5596.4 5604.2 5605.6 4865.9 4870.4 5078.1 5084.7 5114.9 5007.6 5097.4 5105.4 5107.8 5111.2 5012.0 5031.0 5064.8 5071.9 5102.1 5129.8 5142.3 5150.7 5154.0 5171.5 4844.4 4948.2 5004.1 5058.3 5061.1 5337.0 5380.3 5388.7 5403.8 5431.5 5900.9 5980.0 5984.6 5986.0 5995.1 5867.6 5923.4 5948.5 5956.1 6030.0 5577.5 5582.3 5591.0 5593.9 5600.5 5863.1 6057.0 6062.1 6089.5 6096.2 5805.5 5877.6 5922.5 5935.9 5950.5 5999.2 6040.5 6040.9 6041.1 6043.4 5678.8 5729.4 5975.8 5982.9 5986.1 5803.1 5852.1 5852.7 5853.5 5882.2 4447.9 4449.9 4455.5 4463.0 4479.0 4017.1 4034.8 4064.0 4081.7 4092.8 5655.0 5724.8 5727.9 5728.4 5730.6 5994.7 5998.0 6001.5 6019.8 6025.2 5338.4 5395.7 5472.3 5664.1 5698.0 4941.9 4968.6 4980.7 4983.5 4993.3 6038.6 6047.8 6054.7 6065.2 6090.2 5785.6 5876.1 5900.1 5930.0 5944.9 6232.9 6259.9 6265.8 6267.2 6274.9 5856.2 5858.6 5867.4 5880.9 5916.8 5632.4 5765.7 5858.7 5869.5 5918.8 5771.1 5775.1 5778.9 5786.6 5787.0 5615.6 5724.1 5750.9 5760.1 5779.2 5617.9 5670.7 5687.8 5692.4 5713.6 5792.5 5867.4 5870.6 5884.9 5901.5 5440.6 5620.8 5692.8 5702.8 5707.9 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | [#1] ext.html Cycles:180 Average:71892.64 Median:430367.50 stddev:148103.21 (34.4%) stddev-sans-first:148421.96 03:40:35 INFO - PROCESS | 3460 | Values: 604.1 649.2 650.0 663.5 665.9 32918.7 46645.0 47186.1 47383.3 47416.0 49999.3 50372.7 50433.0 50460.7 50562.5 8475.7 9151.9 9292.3 9310.8 11043.7 10680.6 11102.7 11106.0 11110.8 11112.3 11205.0 11272.1 11273.6 11283.2 11317.0 8633.2 8767.4 10983.3 11144.6 11302.3 10801.8 11170.9 11196.0 11205.2 11209.4 47020.0 47256.1 47412.0 47576.8 47644.0 3764.7 3793.1 3824.1 4034.3 4470.7 36105.5 36277.6 36432.6 36584.8 36586.3 4722.7 4771.2 4843.2 4916.6 4948.9 422436.0 430095.0 430640.0 577783.0 580273.0 74749.6 74863.6 75486.0 75615.6 75802.6 41357.0 42459.6 42459.8 79501.6 80239.6 20285.5 20354.6 20475.3 20492.3 20512.8 12529.7 14412.9 14583.0 14597.9 14986.0 22674.2 23017.7 23165.5 23257.3 23315.3 8314.9 8401.2 8418.0 8454.6 8509.4 983.6 1039.2 1098.2 1113.5 1146.9 36420.5 36532.0 36864.3 36891.6 36998.0 1177.2 1195.5 1211.8 1273.0 1282.2 18819.4 18910.9 18965.1 19101.2 19115.7 690193.0 698924.0 698941.0 700797.0 701718.0 67636.7 68144.3 68191.3 68299.7 68334.3 1382.1 1461.4 1482.7 1505.9 1513.6 35424.8 37207.8 37435.6 37479.6 38078.0 56711.0 56995.4 57782.0 57823.7 57841.7 438904.0 443193.0 444751.0 445271.0 445781.0 28468.0 31208.8 31816.5 31910.5 33598.0 54532.5 55003.0 55553.2 55890.2 56106.4 44428.8 45813.3 45825.5 46188.8 46219.3 44747.3 44807.8 45624.5 45633.3 46922.5 45670.3 46262.1 46307.0 46687.0 46755.5 39273.0 40362.6 41305.8 43689.0 44408.8 29606.3 29991.3 30147.6 30523.4 30776.4 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | [#2] jquery.html Cycles:180 Average:29970.93 Median:2306.99 stddev:84428.06 (3659.7%) stddev-sans-first:84635.76 03:40:35 INFO - PROCESS | 3460 | Values: 426.9 432.2 435.1 436.7 439.7 18917.7 19719.7 20730.1 20936.7 21023.6 19347.3 20167.1 20445.7 20513.4 20516.6 5783.2 5993.5 6012.2 6015.2 6019.7 5708.9 5921.3 5964.5 6003.4 6008.3 5958.0 5962.5 5991.8 6008.3 6026.1 5934.3 5997.2 6114.0 6117.7 6122.2 5133.0 5897.3 5914.2 5934.9 5936.6 14305.1 14623.0 14634.9 14678.4 19552.3 2295.8 2301.3 2312.6 2337.0 3010.6 10117.3 10190.1 10245.9 10557.4 11150.1 1531.7 1845.5 1914.1 1922.6 2010.3 81140.0 86869.6 87378.0 88111.0 88244.1 11077.6 11212.4 11219.0 11255.5 11277.0 14906.3 14935.2 14977.0 15034.2 15037.6 8460.9 8683.3 8748.8 8781.9 8810.6 8538.2 8615.5 8649.0 8754.0 8774.1 4742.3 4766.7 4813.6 4847.2 4876.4 1992.1 2005.8 2078.6 2081.4 2097.7 794.9 947.9 967.6 1025.5 1118.9 19768.6 19872.8 19963.4 20201.9 20350.1 991.6 1040.0 1057.7 1065.4 1089.9 10814.8 11044.8 11085.3 11225.7 11338.9 501207.0 504719.0 510120.0 519056.0 524081.0 92432.0 92537.0 95900.0 96010.5 96968.0 955.7 979.7 1009.1 1038.1 1039.6 17364.7 17369.1 17519.5 17674.1 17760.1 41001.2 41141.6 41722.6 42160.8 42834.1 59028.0 59242.7 59523.1 60190.0 61602.4 8860.4 9143.0 9181.8 9197.9 9360.0 12487.7 13354.9 13827.4 14027.0 14226.9 12079.5 12483.2 12520.5 12580.5 12604.5 11286.6 11522.5 12089.9 12342.1 12411.3 12519.9 12794.9 12801.2 12817.0 12880.4 8872.0 8968.2 9113.9 9376.4 9395.9 11391.8 12372.7 12461.7 12544.4 12620.6 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | [#3] mootools.html Cycles:180 Average:6515.82 Median:4616.52 stddev:3814.49 (82.6%) stddev-sans-first:3805.82 03:40:35 INFO - PROCESS | 3460 | Values: 1401.5 1572.4 1621.4 1806.6 1940.4 9367.0 9722.7 9758.9 10001.4 10010.0 9705.2 9872.2 9884.9 9911.2 9917.2 2173.7 2466.5 2467.5 2496.4 2546.0 2511.2 2524.8 2526.1 2527.3 2527.7 2433.8 2535.9 2538.9 2541.7 2542.6 2141.6 2216.6 2405.9 2408.7 2476.0 2445.9 2452.5 2470.5 2472.5 2474.5 9768.1 9812.7 9835.9 9840.7 9846.6 5788.4 5943.3 5947.8 5949.3 5951.0 3593.8 3628.1 3670.3 3678.9 3703.9 1304.5 1326.8 1341.5 1343.7 1359.1 14175.4 14390.8 14446.3 14446.5 14447.0 5028.1 5078.2 5124.2 5315.9 5325.2 11793.7 11906.2 11911.2 11913.1 11913.2 6010.4 6011.4 6018.9 6023.6 6026.2 4798.1 5202.3 5342.0 5352.1 5422.5 4377.9 4428.4 4475.2 4501.5 4521.3 1391.8 1392.2 1411.3 1415.4 1427.3 1067.4 1074.9 1077.8 1079.1 1081.8 6521.2 6533.9 6582.7 6628.6 6692.9 475.3 480.7 497.3 498.6 498.6 4509.9 4591.2 4603.4 4615.9 4617.1 12025.0 12232.6 12373.0 12388.1 12449.1 11837.5 12202.2 12290.8 12300.8 12367.8 9092.1 9157.2 9215.0 9215.9 9283.6 6695.8 6882.2 6898.9 6903.4 7031.2 11424.6 11584.4 11667.5 11679.2 11743.9 11516.3 11673.5 11739.9 11812.1 11934.5 7807.5 7839.8 8834.0 8989.6 9114.2 9558.2 9564.8 9647.1 9662.6 9687.0 8489.5 8613.0 8731.9 8785.5 8824.3 8445.9 8479.1 8592.1 8612.8 8667.5 7850.1 7925.0 7938.7 7943.8 7949.1 5678.8 5694.3 5725.3 5763.2 5874.7 5835.9 5919.7 5973.5 6055.3 6210.5 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | [#4] prototype.html Cycles:180 Average:4562.66 Median:5105.69 stddev:1287.62 (25.2%) stddev-sans-first:1251.75 03:40:35 INFO - PROCESS | 3460 | Values: 347.1 351.9 356.3 360.1 360.9 5172.8 5313.3 5331.4 5339.0 5343.4 5275.9 5293.8 5304.7 5322.6 5345.9 4212.8 4237.2 4250.0 4260.8 4264.9 4031.8 4036.9 4237.8 4239.2 4265.8 4173.8 4204.5 4222.0 4225.8 4225.9 4189.5 4192.8 4200.9 4203.7 4205.6 3673.2 3705.7 3766.7 3801.6 3802.1 5125.7 5138.7 5148.7 5162.3 5181.4 4757.1 4783.9 4794.4 4798.0 4809.6 5378.5 5518.7 5553.8 5577.4 5622.3 4161.5 4164.2 4176.8 4195.4 4197.0 5730.7 5731.4 5741.7 5760.2 5811.9 4155.4 4249.6 4253.2 4262.2 4283.5 4300.9 4300.9 4305.8 4317.7 4325.9 4802.2 4823.8 4825.7 4841.5 4844.2 5375.3 5610.6 5619.5 5621.2 5633.4 1418.9 1447.6 1487.5 1493.3 1497.2 965.8 968.8 981.8 983.4 995.0 4836.4 4888.6 4900.2 4905.5 4931.3 5133.7 5138.3 5141.5 5152.4 5186.9 4692.8 4878.3 4889.0 4910.7 4925.4 2733.0 2738.6 2758.0 2761.6 2770.7 5334.3 5338.4 5400.9 5408.7 5410.8 4846.7 5073.9 5198.8 5247.0 5275.7 5342.6 5388.1 5412.9 5424.7 5439.8 5096.5 5110.9 5111.2 5151.5 5160.5 4655.5 4946.3 4962.0 4998.3 5050.3 5212.3 5244.9 5307.5 5310.6 5333.9 3842.5 3860.7 3867.4 3903.5 3919.4 4887.9 5092.9 5100.4 5551.4 5605.2 5596.7 5611.2 5615.0 5615.3 5639.3 5616.9 5630.1 5635.2 5645.6 5665.8 5415.4 5467.3 5529.9 5530.4 5550.7 5628.2 5654.3 5656.3 5662.0 5666.6 5404.8 5448.4 5527.7 5528.4 5575.2 03:40:35 INFO - PROCESS | 3460 | 03:40:35 INFO - PROCESS | 3460 | [#5] yui.html Cycles:180 Average:21273.72 Median:3951.46 stddev:29969.13 (758.4%) stddev-sans-first:30013.85 03:40:35 INFO - PROCESS | 3460 | Values: 819.1 857.8 866.5 878.0 925.0 13886.2 15232.8 16038.2 16406.1 16498.9 15589.8 15595.1 15755.1 15756.6 15794.9 734.5 873.9 889.0 892.6 893.0 883.5 888.0 890.5 892.1 892.7 922.0 924.7 926.4 927.8 928.0 770.4 895.8 911.8 922.3 922.5 663.3 668.7 669.2 669.4 671.3 14938.6 14940.1 14940.8 14949.3 14973.4 14017.2 14631.5 14726.9 14841.1 14905.2 11905.7 12205.2 12236.4 12616.3 12630.9 3977.1 4597.9 4724.7 4726.8 4748.5 78766.0 80241.0 80762.0 80790.0 80820.6 11585.4 11970.9 12155.7 12188.0 12392.2 21828.7 21866.5 21871.5 21877.2 21914.5 8750.8 8773.5 8824.3 8848.0 9286.4 5130.4 5173.8 5231.4 5235.8 5449.0 3826.3 3951.2 3951.8 3995.4 3997.8 595.3 643.5 656.7 663.6 669.6 694.4 742.8 780.0 817.7 821.1 10120.2 10305.8 10311.2 10375.0 10424.3 719.3 765.3 796.7 809.8 810.8 5045.4 5286.4 5362.9 5488.4 5515.4 112502.0 114623.0 116416.4 116698.4 120027.4 81125.0 97439.5 100820.5 101221.5 103622.5 86126.0 86941.0 88689.3 89806.6 90300.5 10595.2 10952.2 11052.9 11205.9 11270.5 37687.4 41590.4 52413.7 52858.0 55317.7 66921.7 71879.0 75669.0 75804.6 76237.0 12643.7 12649.4 12999.9 14078.9 14508.0 14363.3 14710.8 16133.5 16249.8 16472.2 14679.2 14889.6 15067.7 15082.5 15838.4 15250.9 15435.0 15453.3 15914.0 16606.3 14471.2 14544.3 15040.5 15158.2 15163.2 12002.6 12063.6 12203.3 12541.8 12876.6 12118.8 12143.5 12251.7 12436.2 12478.9 03:40:35 INFO - PROCESS | 3460 | -------- Summary: end -------- 03:40:35 INFO - PROCESS | 3460 | 03:40:36 INFO - TEST-INFO | 3460: exit 0 03:40:36 INFO - TEST-OK | dromaeo_css | took 1114552ms 03:40:36 INFO - TEST-START | kraken 03:40:36 INFO - operating with platform_type : linux_ 03:40:36 INFO - Initialising browser for kraken test... 03:40:36 INFO - TEST-INFO | started process 3655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoJ133s/profile http://localhost:39150/getInfo.html) 03:40:40 INFO - PROCESS | 3655 | __metrics Screen width/height:1600/1200 03:40:40 INFO - PROCESS | 3655 | colorDepth:24 03:40:40 INFO - PROCESS | 3655 | Browser inner width/height: 1024/697 03:40:40 INFO - PROCESS | 3655 | __metrics 03:40:41 INFO - PROCESS | 3655 | 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" 03:40:41 ERROR - PROCESS | 3655 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:40:42 INFO - TEST-INFO | 3655: exit 0 03:40:42 INFO - Browser initialized. 03:40:42 INFO - Running cycle 1/1 for kraken test... 03:40:42 INFO - TEST-INFO | started process 3747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoJ133s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:53 INFO - PROCESS | 3747 | RSS: Main: 145600512 03:40:53 INFO - PROCESS | 3747 | 03:40:56 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39150/tests/kraken/driver.html?testName=audio-beat-detection) 03:40:57 INFO - PROCESS | 3747 | RSS: Main: 194658304 03:40:57 INFO - PROCESS | 3747 | 03:40:59 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39150/tests/kraken/driver.html?testName=audio-dft) 03:41:00 INFO - PROCESS | 3747 | RSS: Main: 191918080 03:41:00 INFO - PROCESS | 3747 | 03:41:03 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39150/tests/kraken/driver.html?testName=audio-fft) 03:41:03 INFO - PROCESS | 3747 | RSS: Main: 279384064 03:41:03 INFO - PROCESS | 3747 | 03:41:05 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39150/tests/kraken/driver.html?testName=audio-oscillator) 03:41:06 INFO - PROCESS | 3747 | RSS: Main: 187559936 03:41:06 INFO - PROCESS | 3747 | 03:41:08 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39150/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:09 INFO - PROCESS | 3747 | RSS: Main: 188706816 03:41:09 INFO - PROCESS | 3747 | 03:41:12 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39150/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:12 INFO - PROCESS | 3747 | RSS: Main: 223006720 03:41:12 INFO - PROCESS | 3747 | 03:41:16 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39150/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:16 INFO - PROCESS | 3747 | RSS: Main: 223825920 03:41:16 INFO - PROCESS | 3747 | 03:41:19 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39150/tests/kraken/driver.html?testName=json-parse-financial) 03:41:20 INFO - PROCESS | 3747 | RSS: Main: 223703040 03:41:20 INFO - PROCESS | 3747 | 03:41:21 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39150/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:22 INFO - PROCESS | 3747 | RSS: Main: 200855552 03:41:22 INFO - PROCESS | 3747 | 03:41:23 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:24 INFO - PROCESS | 3747 | RSS: Main: 202227712 03:41:24 INFO - PROCESS | 3747 | 03:41:26 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:41:27 INFO - PROCESS | 3747 | RSS: Main: 209911808 03:41:27 INFO - PROCESS | 3747 | 03:41:29 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:41:30 INFO - PROCESS | 3747 | RSS: Main: 215334912 03:41:30 INFO - PROCESS | 3747 | 03:41:32 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:41:33 INFO - PROCESS | 3747 | RSS: Main: 213008384 03:41:33 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:39150/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39150/tests/kraken/driver.html?testName=ai-astar) 03:41:35 INFO - PROCESS | 3747 | RSS: Main: 256106496 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | __start_tp_report 03:41:35 INFO - PROCESS | 3747 | _x_x_mozilla_page_load 03:41:35 INFO - PROCESS | 3747 | _x_x_mozilla_page_load_details 03:41:35 INFO - PROCESS | 3747 | |i|pagename|runs| 03:41:35 INFO - PROCESS | 3747 | |0;ai-astar;111;103;110;112;110;110;110;109;109;110 03:41:35 INFO - PROCESS | 3747 | |1;audio-beat-detection;157;124;124;168;130;133;131;158;167;123 03:41:35 INFO - PROCESS | 3747 | |2;audio-dft;161;162;160;160;160;159;159;160;162;160 03:41:35 INFO - PROCESS | 3747 | |3;audio-fft;75;75;75;121;75;76;75;73;107;74 03:41:35 INFO - PROCESS | 3747 | |4;audio-oscillator;84;84;117;84;84;83;124;84;84;125 03:41:35 INFO - PROCESS | 3747 | |5;imaging-gaussian-blur;116;117;116;117;117;117;116;116;116;116 03:41:35 INFO - PROCESS | 3747 | |6;imaging-darkroom;145;145;145;145;146;146;146;146;145;146 03:41:35 INFO - PROCESS | 3747 | |7;imaging-desaturate;95;95;95;96;95;94;95;99;95;95 03:41:35 INFO - PROCESS | 3747 | |8;json-parse-financial;51;50;51;50;51;52;52;51;53;51 03:41:35 INFO - PROCESS | 3747 | |9;json-stringify-tinderbox;62;61;61;61;61;61;61;61;62;61 03:41:35 INFO - PROCESS | 3747 | |10;stanford-crypto-aes;82;82;78;76;77;78;79;76;106;78 03:41:35 INFO - PROCESS | 3747 | |11;stanford-crypto-ccm;123;118;116;117;121;116;124;129;121;124 03:41:35 INFO - PROCESS | 3747 | |12;stanford-crypto-pbkdf2;154;152;154;153;160;159;156;154;154;151 03:41:35 INFO - PROCESS | 3747 | |13;stanford-crypto-sha256-iterative;61;62;62;62;61;62;62;60;62;58 03:41:35 INFO - PROCESS | 3747 | __end_tp_report 03:41:35 INFO - PROCESS | 3747 | __start_cc_report 03:41:35 INFO - PROCESS | 3747 | _x_x_mozilla_cycle_collect,1544 03:41:35 INFO - PROCESS | 3747 | __end_cc_report 03:41:35 INFO - PROCESS | 3747 | __startTimestamp1469356895457__endTimestamp 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | ------- Summary: start ------- 03:41:35 INFO - PROCESS | 3747 | Number of tests: 14 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.41 (2.2%) stddev-sans-first:2.49 03:41:35 INFO - PROCESS | 3747 | Values: 111.0 103.0 110.0 112.0 110.0 110.0 110.0 109.0 109.0 110.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:145.00 stddev:18.66 (12.9%) stddev-sans-first:18.93 03:41:35 INFO - PROCESS | 3747 | Values: 157.0 124.0 124.0 168.0 130.0 133.0 131.0 158.0 167.0 123.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 03:41:35 INFO - PROCESS | 3747 | Values: 161.0 162.0 160.0 160.0 160.0 159.0 159.0 160.0 162.0 160.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.89 (22.5%) stddev-sans-first:17.69 03:41:35 INFO - PROCESS | 3747 | Values: 75.0 75.0 75.0 121.0 75.0 76.0 75.0 73.0 107.0 74.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:84.00 stddev:18.54 (22.1%) stddev-sans-first:19.21 03:41:35 INFO - PROCESS | 3747 | Values: 84.0 84.0 117.0 84.0 84.0 83.0 124.0 84.0 84.0 125.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:41:35 INFO - PROCESS | 3747 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 03:41:35 INFO - PROCESS | 3747 | Values: 145.0 145.0 145.0 145.0 146.0 146.0 146.0 146.0 145.0 146.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 03:41:35 INFO - PROCESS | 3747 | Values: 95.0 95.0 95.0 96.0 95.0 94.0 95.0 99.0 95.0 95.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:0.92 (1.8%) stddev-sans-first:0.97 03:41:35 INFO - PROCESS | 3747 | Values: 51.0 50.0 51.0 50.0 51.0 52.0 52.0 51.0 53.0 51.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 03:41:35 INFO - PROCESS | 3747 | Values: 62.0 61.0 61.0 61.0 61.0 61.0 61.0 61.0 62.0 61.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:78.00 stddev:8.97 (11.5%) stddev-sans-first:9.51 03:41:35 INFO - PROCESS | 3747 | Values: 82.0 82.0 78.0 76.0 77.0 78.0 79.0 76.0 106.0 78.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#11] stanford-crypto-ccm Cycles:10 Average:120.90 Median:122.00 stddev:4.23 (3.5%) stddev-sans-first:4.42 03:41:35 INFO - PROCESS | 3747 | Values: 123.0 118.0 116.0 117.0 121.0 116.0 124.0 129.0 121.0 124.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:154.00 stddev:2.87 (1.9%) stddev-sans-first:3.03 03:41:35 INFO - PROCESS | 3747 | Values: 154.0 152.0 154.0 153.0 160.0 159.0 156.0 154.0 154.0 151.0 03:41:35 INFO - PROCESS | 3747 | 03:41:35 INFO - PROCESS | 3747 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:62.00 stddev:1.32 (2.1%) stddev-sans-first:1.39 03:41:35 INFO - PROCESS | 3747 | Values: 61.0 62.0 62.0 62.0 61.0 62.0 62.0 60.0 62.0 58.0 03:41:35 INFO - PROCESS | 3747 | -------- Summary: end -------- 03:41:35 INFO - PROCESS | 3747 | 03:41:36 INFO - TEST-INFO | 3747: exit 0 03:41:36 INFO - TEST-OK | kraken | took 60233ms 03:41:36 INFO - SUITE-END | took 1174s 03:41:36 INFO - Completed test suite (00:19:34) 03:41:36 INFO - javascript benchmark 03:41:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.1040074380285, 3407.4207384744604, 3417.214040770622, 3439.3981053315665, 3461.9307613847723, 5543.63966342188, 5580.0792869176585, 5599.496045355917, 5604.859878503034, 5608.214849921014, 5516.724163791814, 5579.8605034874145, 5596.4403559644015, 5604.187392828037, 5605.607607467479, 4865.927011094836, 4870.366852309195, 5078.111330517163, 5084.720340381277, 5114.872128196806, 5007.574356179729, 5097.362829646294, 5105.361829771278, 5107.821226257081, 5111.1566591512355, 5011.9976004799, 5030.968651426467, 5064.797408103672, 5071.949280507203, 5102.081625307444, 5129.820456284031, 5142.254373115878, 5150.742462876841, 5154.020736060136, 5171.508706672879, 4844.394450693663, 4948.183549714328, 5004.099262132815, 5058.342415486, 5061.089003979282, 5336.973315133399, 5380.3274590676165, 5388.7305634718105, 5403.78384864605, 5431.511163995259, 5900.911486327708, 5980.0, 5984.640921544677, 5985.970070149665, 5995.070764031539, 5867.647941123546, 5923.437273459014, 5948.464638182584, 5956.076808094752, 6029.969850150721, 5577.470140336662, 5582.33012038558, 5591.049521581341, 5593.860153496154, 5600.52395546379, 5863.091220860773, 6056.969715151484, 6062.054645135237, 6089.512838972872, 6096.177016102858, 5805.535557155502, 5877.588568800228, 5922.466977972003, 5935.940640593625, 5950.523958083343, 5999.1901093351835, 6040.486558642477, 6040.939590604038, 6041.063635136561, 6043.36544662804, 5678.801241956512, 5729.369769325371, 5975.820725378246, 5982.850428739229, 5986.132010858401, 5803.071508558694, 5852.063669812809, 5852.736626851802, 5853.5317174625925, 5882.235309409835, 4447.911041779211, 4449.911001779947, 4455.509893911667, 4463.04044630406, 4479.0, 4017.055991841915, 4034.8184331705143, 4064.04494943688, 4081.653059489977, 4092.7544347339253, 5655.0, 5724.77100915967, 5727.914081288784, 5728.427157284322, 5730.598858079894, 5994.730237139339, 5998.0, 6001.459868611846, 6019.819405417845, 6025.216721826135, 5338.412774594792, 5395.73021348931, 5472.288602481652, 5664.122061080539, 5697.974364614326, 4941.901161976741, 4968.552830245295, 4980.701157930535, 4983.476734942851, 4993.251012348104, 6038.607490513103, 6047.848803779941, 6054.737220411361, 6065.2418447694035, 6090.177825993434, 5785.56608254391, 5876.08920617305, 5900.085486749561, 5930.021546444961, 5944.910826337695, 6232.897233596216, 6259.937400625936, 6265.8120256392385, 6267.185265915402, 6274.905876411766, 5856.238688970407, 5858.5898987070495, 5867.383924688017, 5880.852978675567, 5916.822495325147, 5632.436756324335, 5765.654060756368, 5858.736356864037, 5869.4717475427415, 5918.822435326947, 5771.076627739541, 5775.133729940542, 5778.85552861165, 5786.594938354275, 5786.971065144648, 5615.634983726045, 5724.112762521816, 5750.9424905752085, 5760.1359796029255, 5779.190913272061, 5617.915731264116, 5670.688112153871, 5687.829365119053, 5692.402297758676, 5713.600047996601, 5792.53659707214, 5867.442592953663, 5870.55970802183, 5884.852878677896, 5901.498372638373, 5440.646357986719, 5620.775168993195, 5692.829215123553, 5702.8289151325525, 5707.8858422832145], "value": 5468.399912071815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.0848115105616, 649.2403887451683, 649.9610023398596, 663.5288944849158, 665.8535122273098, 32918.67081329186, 46645.0, 47186.0562788744, 47383.28925066118, 47416.0, 49999.25001124986, 50372.748136259266, 50433.0, 50460.74769626147, 50562.49437505624, 8475.703350382737, 9151.862722059157, 9292.349535532516, 9310.813783724321, 11043.72390690231, 10680.626178083769, 11102.722431939226, 11106.0, 11110.777784444306, 11112.277701949348, 11205.0, 11272.098232141409, 11273.605423810168, 11283.210175287733, 11317.0, 8633.179847914438, 8767.386282960195, 10983.340999540053, 11144.60993865215, 11302.265352752045, 10801.783964320748, 11170.888291117148, 11196.0, 11205.159613029055, 11209.439528023566, 47020.0, 47256.05487890257, 47412.0, 47576.762116189195, 47644.0, 3764.661180493769, 3793.146542028049, 3824.1204522959874, 4034.253663072341, 4470.658802359279, 36105.45841812375, 36277.63722362769, 36432.63567364345, 36584.81707591445, 36586.268274634356, 4722.693024953402, 4771.165046116916, 4843.215151287988, 4916.5820905222745, 4948.901021979576, 422436.0, 430095.0, 430640.0, 577783.0, 580272.9999999958, 74749.6262518684, 74863.62568187233, 75486.0, 75615.62192189004, 75802.62098689472, 41357.0, 42459.57540424618, 42459.787701061294, 79501.60249198832, 80239.59880200561, 20285.492862678257, 20354.592908141753, 20475.283365082447, 20492.282770102975, 20512.794872051087, 12529.74940501185, 14412.855871441361, 14583.0, 14597.854021459649, 14986.0, 22674.206402775824, 23017.654735178985, 23165.53668926612, 23257.3022809316, 23315.300540983797, 8314.877491538686, 8401.243888050105, 8417.957910210409, 8454.61954212062, 8509.361797865186, 983.6016413352461, 1039.1686650679426, 1098.181854518389, 1113.493360520961, 1146.8681101673415, 36420.45369319462, 36532.0, 36864.26271474556, 36891.63108368882, 36998.0, 1177.1877404590805, 1195.5397172088808, 1211.7515909238584, 1273.045216087934, 1282.2050328796206, 18819.435416937515, 18910.905445472683, 18965.146568404456, 19101.2359505621, 19115.71326430105, 690193.0, 698924.0, 698941.0, 700797.0, 701718.0, 67636.66181669159, 68144.3185568138, 68191.3180868185, 68299.65850170718, 68334.31665683379, 1382.1154461144874, 1461.3862177885314, 1482.688635386581, 1505.9397624094913, 1513.6442935910052, 35424.82287588545, 37207.81396093002, 37435.62564374322, 37479.625203747615, 38078.0, 56711.0, 56995.43004569901, 57782.0, 57823.710881445324, 57841.710791447455, 438904.0, 443193.0, 444751.0, 445271.0, 445781.0, 28468.0, 31208.843955780078, 31816.5227521592, 31910.52134217942, 33598.0, 54532.454675454326, 55003.0, 55553.16670249869, 55890.16164757613, 56106.438935610124, 44428.777856110515, 45813.31280030736, 45825.54174458213, 46188.7690561545, 46219.3067103987, 44747.32879006752, 44807.77596111999, 45624.54375456203, 45633.31550026819, 46922.53077469182, 45670.31494527518, 46262.07475850397, 46307.0, 46687.0, 46755.53244467648, 39273.0, 40362.596374035886, 41305.793471032455, 43689.0, 44408.77795611001, 29606.259843504085, 29991.250218744706, 30147.602391406985, 30523.38953220879, 30776.384472310878], "value": 22953.64471768821, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.94449721536205, 432.22847217716384, 435.0580992151991, 436.66158726986583, 439.65143330310315, 18917.7162342565, 19719.704204436926, 20730.067146978385, 20936.685949710765, 21023.579528409424, 19347.322843700473, 20167.09248083836, 20445.693314600256, 20513.38459846203, 20516.589668206627, 5783.248177736909, 5993.46058854703, 6012.158297838306, 6015.248093988252, 6019.668918209497, 5708.914366284509, 5921.289445266552, 5964.493018093467, 6003.429674180968, 6008.3390827009, 5957.970210148965, 5962.463378295953, 5991.790287339944, 6008.3390827009, 6026.126211699301, 5934.31755348134, 5997.190379298782, 6114.0, 6117.724702388403, 6122.234720659918, 5133.0, 5897.262842144732, 5914.2311499504785, 5934.851628709273, 5936.554758393095, 14305.070170438992, 14623.0, 14634.926825365912, 14678.412863485448, 19552.31566895166, 2295.7704229577075, 2301.344116926668, 2312.629979203319, 2336.9883150584137, 3010.578519007319, 10117.291789574732, 10190.133838623726, 10245.948770256176, 10557.366558006468, 11150.052245559138, 1531.7242896278665, 1845.5478407790042, 1914.0908068667272, 1922.5962547864965, 2010.326540608883, 81140.0, 86869.56565217196, 87378.0, 88111.0, 88244.11755882423, 11077.612283570037, 11212.383318917375, 11219.046381057538, 11255.549778008952, 11277.0, 14906.329215185342, 14935.17856517902, 14977.0, 15034.248287585577, 15037.624059398602, 8460.915390846012, 8683.261922736512, 8748.825023499494, 8781.868271975925, 8810.647574096964, 8538.231559159705, 8615.483071015758, 8649.0, 8753.956230218808, 8774.078721734126, 4742.288656701453, 4766.6901651392545, 4813.638977076734, 4847.151748444008, 4876.390451193601, 1992.0736857361396, 2005.7965220867363, 2078.5738923520644, 2081.40679906226, 2097.6643737002005, 794.89666343375, 947.8862536495527, 967.5645959318194, 1025.5385076715509, 1118.854548908653, 19768.604627907647, 19872.801271987384, 19963.401097967086, 20201.898990504953, 20350.133474640068, 991.6430085169334, 1039.963601273967, 1057.6774083904484, 1065.4459680965854, 1089.929154604948, 10814.837777433344, 11044.779104418027, 11085.334879907232, 11225.719357015976, 11338.886611133948, 501207.0, 504719.0, 510120.0, 519056.0, 524081.0, 92432.0, 92537.0, 95900.0, 96010.51994740122, 96968.0, 955.7276176289724, 979.7158823940978, 1009.1119814563284, 1038.1383451735041, 1039.6465201831413, 17364.652706945537, 17369.13154342303, 17519.47441576755, 17674.116294185496, 17760.111994400486, 41001.179976400905, 41141.58858411497, 41722.58277417187, 42160.789196053825, 42834.14331713286, 59028.0, 59242.70378648252, 59523.107153391866, 60190.0, 61602.38397615967, 8860.379773416056, 9143.04943165247, 9181.816363672822, 9197.86203206939, 9360.0, 12487.687807804878, 13354.93322533381, 13827.377768000262, 14027.0, 14226.928865355607, 12079.456424461096, 12483.188592741442, 12520.499180032697, 12580.496780128693, 12604.49582016709, 11286.604968826052, 11522.481488332878, 12089.879101209228, 12342.13605047638, 12411.255324680547, 12519.874801251872, 12794.936025319814, 12801.231926084465, 12817.0, 12880.420381082686, 8872.0, 8968.23769979559, 9113.863292050492, 9376.437413754924, 9395.859062113937, 11391.829122563002, 12372.690682733004, 12461.688457788629, 12544.372781361079, 12620.55828046014], "value": 9457.09256098067, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.4884567133, 1572.355334312932, 1621.3757703284236, 1806.6386722655463, 1940.3596813051686, 9366.969633340335, 9722.659706910259, 9758.902410975888, 10001.449920254383, 10010.0, 9705.17506011988, 9872.210223182146, 9884.901150988488, 9911.207103431727, 9917.206623470123, 2173.739151301838, 2466.4943686544216, 2467.457159424933, 2496.3634273260295, 2545.9943322387644, 2511.221521328385, 2524.810639202058, 2526.090607381341, 2527.279725278296, 2527.684039495063, 2433.8052955763583, 2535.949281014379, 2538.885750141239, 2541.682289713786, 2542.6058960861005, 2141.5609799991043, 2216.634255347874, 2405.891734871926, 2408.7350391456926, 2476.021971321336, 2445.8532488050773, 2452.509498100369, 2470.468849197431, 2472.5302192583354, 2474.455619763667, 9768.120869121813, 9812.656557020542, 9835.901640983642, 9840.65557705484, 9846.556904939294, 5788.42115788422, 5943.257092863392, 5947.762089516457, 5949.25634295713, 5951.0, 3593.82030898454, 3628.1473853644375, 3670.3393389189937, 3678.889633311005, 3703.8518459261595, 1304.478208716521, 1326.7611829870618, 1341.496938648007, 1343.6640839790052, 1359.0758284366627, 14175.43298268068, 14390.784138237936, 14446.349914253884, 14446.49437269701, 14447.0, 5028.069807085701, 5078.238264260391, 5124.15451450514, 5315.920261196085, 5325.201219817059, 11793.705157371134, 11906.166568340133, 11911.166218364631, 11913.106517011085, 11913.16607837443, 6010.3689112643415, 6011.368806275365, 6018.946684330224, 6023.608465449732, 6026.21659184312, 4798.136335459651, 5202.323697919246, 5342.0, 5352.063388906926, 5422.457754224625, 4377.912441751211, 4428.357888106207, 4475.194464996267, 4501.482329532146, 4521.344405061248, 1391.8399384070758, 1392.2064423278837, 1411.287299913537, 1415.419677932043, 1427.257825930531, 1067.4129228924216, 1074.9086327662237, 1077.7682798198425, 1079.0558261521169, 1081.7944590527932, 6521.217453905561, 6533.901991470132, 6582.736690532325, 6628.602283862983, 6692.86614267712, 475.310799340957, 480.68515122594755, 497.3459900231184, 498.5612660858494, 498.61855680404517, 4509.887252818706, 4591.1965406053805, 4603.4015577975315, 4615.884602884955, 4617.145828021759, 12025.0, 12232.633021009384, 12373.0, 12388.070894682935, 12449.128561000643, 11837.467313970892, 12202.206856554296, 12290.75418491643, 12300.83142101499, 12367.75264494723, 9092.090790920854, 9157.175854173156, 9214.98635150133, 9215.90784092164, 9283.628654853865, 6695.765648202289, 6882.208546017272, 6898.8620227596175, 6903.447724182154, 7031.156261248683, 11424.600138995096, 11584.420778961186, 11667.474963626826, 11679.182457227913, 11743.941280293886, 11516.30902145874, 11673.533058677898, 11739.941300293443, 11812.055035597303, 11934.52261909514, 7807.492512986638, 7839.813644586434, 8833.955830220808, 8989.550522473719, 9114.179723824887, 9558.187554057982, 9564.760880977752, 9647.1317581418, 9662.613495460102, 9686.951565242129, 8489.533075680896, 8612.956935215283, 8731.869021964803, 8785.472871627468, 8824.294056475595, 8445.915540844759, 8479.067302596586, 8592.097829727789, 8612.78468038279, 8667.523286219066, 7850.136484986533, 7925.0, 7938.682452702058, 7943.84112317762, 7949.125596184531, 5678.829635110953, 5694.259746232963, 5725.341585717696, 5763.164341170423, 5874.706264686663, 5835.854103647443, 5919.7040147991565, 5973.49225315853, 6055.273367195965, 6210.472109870711], "value": 5071.804239656154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.06639140711485, 351.92081781599137, 356.34610489751304, 360.0817914318487, 360.88090929993103, 5172.767225474853, 5313.256144139824, 5331.413544510106, 5339.0, 5343.358796944367, 5275.894482110365, 5293.841184764454, 5304.655197412175, 5322.574194064475, 5345.893082138355, 4212.831486740534, 4237.173751118533, 4250.0, 4260.7656578888145, 4264.87205383839, 4031.8387264509383, 4036.8587099451524, 4237.809298581571, 4239.215745087154, 4265.808038638269, 4173.791310434482, 4204.516480604724, 4221.965618423491, 4225.809838557257, 4225.880141762435, 4189.518205406372, 4192.832286708528, 4200.915981680365, 4203.747775133502, 4205.558416366268, 3673.1918977824835, 3705.7220708446976, 3766.7174961877695, 3801.5669971218445, 3802.1065049713297, 5125.743712814382, 5138.74306284688, 5148.6910785353, 5162.328897243372, 5181.35233095863, 4757.14371413149, 4783.856484305476, 4794.37673102498, 4797.9520204797855, 4809.639277054235, 5378.489043540857, 5518.696471694056, 5553.77784888608, 5577.442255774431, 5622.269105016363, 4161.459010328638, 4164.167166566668, 4176.770277634729, 4195.3916682081235, 4197.0, 5730.68481233532, 5731.398203188689, 5741.741621627037, 5760.164776107441, 5811.936762449312, 4155.439015732898, 4249.618873866006, 4253.191893540217, 4262.232798096373, 4283.507396649426, 4300.871068820954, 4300.935485967713, 4305.763183024901, 4317.7193482423545, 4325.901937560281, 4802.207635740134, 4823.758812059383, 4825.710457372569, 4841.515848415201, 4844.224924012212, 5375.3280839895015, 5610.635308704921, 5619.494245517923, 5621.156826476062, 5633.4084921082695, 1418.8935829812806, 1447.5512591096583, 1487.4645127754, 1493.2608358862215, 1497.1840347010905, 965.7826988927435, 968.7674958010027, 981.8120074709539, 983.4492684096786, 994.9602015919427, 4836.419629644465, 4888.608911287089, 4900.166971614834, 4905.533974272438, 4931.260310953386, 5133.666311689803, 5138.332016837787, 5141.511556402136, 5152.407473140637, 5186.8848197636935, 4692.835750748707, 4878.268259760993, 4888.95111048885, 4910.656254062182, 4925.359703238556, 2732.9726702733115, 2738.5755207942802, 2757.993332433647, 2761.558150695879, 2770.6813716422466, 5334.253204551366, 5338.412774594792, 5400.91898621521, 5408.729563521808, 5410.78356865721, 4846.684965477232, 5073.8731531712, 5198.844034678966, 5246.97376513115, 5275.736213189325, 5342.557509472498, 5388.137897936312, 5412.864678383072, 5424.7016414096815, 5439.836804895859, 5096.51583099605, 5110.9488905110475, 5111.159982406405, 5151.484851514819, 5160.535551800356, 4655.487896331469, 4946.307516947558, 4961.975190124026, 4998.3002379667605, 5050.3182070420025, 5212.279947617201, 5244.89510209786, 5307.548858347083, 5310.601704872072, 5333.9466605335, 3842.50047493824, 3860.749051311656, 3867.400552914303, 3903.4730311407598, 3919.3729003359326, 4887.87780305481, 5092.923606145837, 5100.438951715234, 5551.389347171727, 5605.1872478491205, 5596.692181930033, 5611.158326251095, 5614.971925140511, 5615.2700148980375, 5639.295088113986, 5616.915746263728, 5630.127330263816, 5635.182898479779, 5645.576581756467, 5665.801696940587, 5415.4313797050745, 5467.316585426822, 5529.917051244154, 5530.391656917655, 5550.722463876709, 5628.240187574625, 5654.293213348332, 5656.321241450888, 5661.98999355223, 5666.631668941505, 5404.813459767509, 5448.4006759257245, 5527.668339899619, 5528.364238112507, 5575.1915972184615], "value": 4164.050814245468, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.1153554161506, 857.8198578298557, 866.4541338956457, 877.9780505487362, 924.9768755781105, 13886.166829990183, 15232.847671523281, 16038.198090095508, 16406.097664628454, 16498.91750541247, 15589.766153507706, 15595.1422671753, 15755.054696718205, 15756.606084847885, 15794.921025394857, 734.5005396330494, 873.8907636545432, 888.9822203555941, 892.6117139044504, 892.995535022324, 883.5493898111945, 887.9866801997975, 890.4835195586544, 892.0677891603258, 892.6697122064845, 921.9723408297729, 924.653255029364, 926.3515539122608, 927.8422668146432, 928.0441145620022, 770.3682979956438, 895.789489469974, 911.7811725185974, 922.3313098003961, 922.5156792683861, 663.2803408301999, 668.6723505482328, 669.1668872254074, 669.3641041011059, 671.2850813883248, 14938.62653433662, 14940.103593784408, 14940.775888361683, 14949.327280272413, 14973.401063957428, 14017.229052402114, 14631.487897923627, 14726.926365368212, 14841.109533428029, 14905.180215088165, 11905.702357441047, 12205.20666156706, 12236.449359778831, 12616.306103164323, 12630.936845315715, 3977.0687936191425, 4597.885052873705, 4724.669273150881, 4726.787294571753, 4748.5014073522125, 78766.0, 80241.0, 80762.0, 80790.0, 80820.59589702073, 11585.420728963518, 11970.940145299217, 12155.696107597203, 12188.0, 12392.194507356993, 21828.672569911465, 21866.453338666342, 21871.453213669785, 21877.234296799536, 21914.452138696342, 8750.781230469162, 8773.517456539952, 8824.294056475466, 8847.955760221157, 9286.396384235004, 5130.384353877484, 5173.793048278102, 5231.398389185217, 5235.764390602356, 5448.9727551362785, 3826.330392181357, 3951.150502641946, 3951.7628942263555, 3995.400689896481, 3997.8200980955926, 595.2767387624058, 643.4594940250213, 656.7340227207989, 663.5885750834514, 669.6350488983516, 694.3785312145601, 742.8180095876545, 779.984400312002, 817.6858005144048, 821.0680877204296, 10120.240981926385, 10305.84541231882, 10311.175105991502, 10375.0, 10424.270301078852, 719.2807192807193, 765.2500549461558, 796.677345674991, 809.7773112394021, 810.7608255564622, 5045.445001049881, 5286.369986030748, 5362.865928351823, 5488.423715509894, 5515.420880807537, 112502.0, 114623.0, 116416.41791791156, 116698.41650791862, 120027.39986300013, 81125.0, 97439.51280243554, 100820.49589752004, 101221.49389253007, 103622.48188759008, 86126.0, 86941.0, 88689.33931982052, 89806.55096724605, 90300.54849725841, 10595.205359598214, 10952.233343665866, 11052.944735276273, 11205.913263501823, 11270.49282782293, 37687.434688479145, 41590.376144358466, 52413.737931311625, 52858.0, 55317.72341138429, 66921.66539167272, 71879.0, 75669.0, 75804.62097689476, 76237.0, 12643.747125057631, 12649.367531623198, 12999.870001299865, 14078.859211408164, 14508.0, 14363.281835907954, 14710.779338310149, 16133.515994520183, 16249.756253656442, 16472.176391180634, 14679.19264440466, 14889.553313400615, 15067.698646026798, 15082.54752357431, 15838.366465341258, 15250.923745381646, 15435.0, 15453.304601292744, 15914.0, 16606.335746570487, 14471.204083775072, 14544.345504452536, 15040.548783536511, 15158.16630085356, 15163.166025868682, 12002.63992080239, 12063.638090857288, 12203.328816915155, 12541.811872822098, 12876.613701588967, 12118.818217726917, 12143.453544590691, 12251.69370765738, 12436.19164754288, 12478.87521124777], "value": 8043.198552284975, "unit": "score"}], "name": "dromaeo_css", "value": 7657.801857084083, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 103.0, 110.0, 112.0, 110.0, 110.0, 110.0, 109.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 124.0, 168.0, 130.0, 133.0, 131.0, 158.0, 167.0, 123.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 160.0, 160.0, 159.0, 159.0, 160.0, 162.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 121.0, 75.0, 76.0, 75.0, 73.0, 107.0, 74.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 117.0, 84.0, 84.0, 83.0, 124.0, 84.0, 84.0, 125.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 99.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 50.0, 51.0, 52.0, 52.0, 51.0, 53.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 78.0, 76.0, 77.0, 78.0, 79.0, 76.0, 106.0, 78.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 118.0, 116.0, 117.0, 121.0, 116.0, 124.0, 129.0, 121.0, 124.0], "value": 120.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 154.0, 153.0, 160.0, 159.0, 156.0, 154.0, 154.0, 151.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 60.0, 62.0, 58.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1476.8000000000002, "alertThreshold": 2.0}]} 03:41:36 INFO - Return code: 0 03:41:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:41:36 ERROR - # TBPL SUCCESS # 03:41:36 INFO - Running post-action listener: _resource_record_post_action 03:41:36 INFO - ##### 03:41:36 INFO - ##### Finished run-tests step (success) 03:41:36 INFO - ##### 03:41:36 INFO - Running post-run listener: _resource_record_post_run 03:41:36 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 7782400; Write bytes: 383201280; Read time: 1052; Write time: 291780 03:41:36 INFO - TinderboxPrint: CPU usage
13.1% 03:41:36 INFO - TinderboxPrint: I/O read bytes / time
7,782,400 / 1,052 03:41:36 INFO - TinderboxPrint: I/O write bytes / time
383,201,280 / 291,780 03:41:36 INFO - TinderboxPrint: CPU idle
8,261.9 (86.8%) 03:41:36 INFO - TinderboxPrint: CPU user
1,204.2 (12.7%) 03:41:36 INFO - TinderboxPrint: Swap in / out
0 / 0 03:41:36 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 25100288; Read time: 0; Write time: 18484 03:41:36 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 6021120; Write bytes: 348954624; Read time: 1012; Write time: 271708 03:41:37 INFO - Running post-run listener: _upload_blobber_files 03:41:37 INFO - Blob upload gear active. 03:41:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:41:37 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 03:41:37 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'] 03:41:37 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 03:41:37 INFO - (blobuploader) - INFO - Open directory for files ... 03:41:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:41:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:41:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:38 INFO - (blobuploader) - INFO - Done attempting. 03:41:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:41:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:41:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:39 INFO - (blobuploader) - INFO - Done attempting. 03:41:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:41:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:56 INFO - (blobuploader) - INFO - Done attempting. 03:41:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:41:56 INFO - Return code: 0 03:41:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:41:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:41:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b86e5d8588a883b74105b690100adad842d931326066d9d0d98e371ca318cb4a37c507e5380d3f7c8c09ad2f7371e7396413a0e4c758230e699ce598a40f5ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d59e3d0d447ce27842964e8a4417f64c2bf9d47d0c02290f4dc707adcac7dcac901b7344bbafe215d6dd2f03105473b1bd1ff55ecb8ad154a93fcc01980aee07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f697304b5e1b7c15dda07a59b51ece45037ab921be6dfb75ce71616bb1069297f7fd53372c720c008a6a0654fe67dfef935786037923e8c49d2ce17c285ed04a"} 03:41:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:41:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:41:56 INFO - Contents: 03:41:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b86e5d8588a883b74105b690100adad842d931326066d9d0d98e371ca318cb4a37c507e5380d3f7c8c09ad2f7371e7396413a0e4c758230e699ce598a40f5ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d59e3d0d447ce27842964e8a4417f64c2bf9d47d0c02290f4dc707adcac7dcac901b7344bbafe215d6dd2f03105473b1bd1ff55ecb8ad154a93fcc01980aee07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f697304b5e1b7c15dda07a59b51ece45037ab921be6dfb75ce71616bb1069297f7fd53372c720c008a6a0654fe67dfef935786037923e8c49d2ce17c285ed04a"} 03:41:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:41:56 INFO - Copying logs to upload dir... 03:41:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:41:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.076574 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-07-24 03:41:56.127242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-24 03:41:56.130960) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522 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/9b86e5d8588a883b74105b690100adad842d931326066d9d0d98e371ca318cb4a37c507e5380d3f7c8c09ad2f7371e7396413a0e4c758230e699ce598a40f5ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d59e3d0d447ce27842964e8a4417f64c2bf9d47d0c02290f4dc707adcac7dcac901b7344bbafe215d6dd2f03105473b1bd1ff55ecb8ad154a93fcc01980aee07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f697304b5e1b7c15dda07a59b51ece45037ab921be6dfb75ce71616bb1069297f7fd53372c720c008a6a0654fe67dfef935786037923e8c49d2ce17c285ed04a"} build_url:https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/TKLh39J9RUa7u1MaVsrseg/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/9b86e5d8588a883b74105b690100adad842d931326066d9d0d98e371ca318cb4a37c507e5380d3f7c8c09ad2f7371e7396413a0e4c758230e699ce598a40f5ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d59e3d0d447ce27842964e8a4417f64c2bf9d47d0c02290f4dc707adcac7dcac901b7344bbafe215d6dd2f03105473b1bd1ff55ecb8ad154a93fcc01980aee07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f697304b5e1b7c15dda07a59b51ece45037ab921be6dfb75ce71616bb1069297f7fd53372c720c008a6a0654fe67dfef935786037923e8c49d2ce17c285ed04a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-24 03:41:56.157248) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:41:56.157659) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1469341932.645424-1322485522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 03:41:56.241856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-24 03:41:56.242253) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-24 03:41:56.242635) ========= ========= Total master_lag: 0.20 =========