builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1462770035.76 results: success (0) buildid: 20160508193137 builduid: 49205ae2634743e6b10e02a6836dc9f9 revision: 827f631db4deadf61200f3e5ab05e44834f73ee0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.756935) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.757407) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.757700) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.869182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:35.869472) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-08 22:00:36-- 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% 70.2M=0s 2016-05-08 22:00:36 (70.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130162 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.017126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.017460) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018524 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.067255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.067637) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 827f631db4deadf61200f3e5ab05e44834f73ee0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 827f631db4deadf61200f3e5ab05e44834f73ee0 --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=a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-08 22:00:36,549 truncating revision to first 12 chars 2016-05-08 22:00:36,549 Setting DEBUG logging. 2016-05-08 22:00:36,549 attempt 1/10 2016-05-08 22:00:36,549 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/827f631db4de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-08 22:00:36,820 unpacking tar archive at: mozilla-inbound-827f631db4de/testing/mozharness/ program finished with exit code 0 elapsedTime=0.573953 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.654488) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.654811) ========= script_repo_revision: 827f631db4deadf61200f3e5ab05e44834f73ee0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.655207) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.655490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 22:00:36.665534) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-08 22:00:36.665950) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:37 INFO - MultiFileLogger online at 20160508 22:00:37 in /builds/slave/test 22:00:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:00:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:37 INFO - {'append_to_log': False, 22:00:37 INFO - 'base_work_dir': '/builds/slave/test', 22:00:37 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:00:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:37 INFO - 'branch': 'Mozilla-Inbound', 22:00:37 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:37 INFO - 'config_files': ('talos/linux_config.py',), 22:00:37 INFO - 'default_actions': ('clobber', 22:00:37 INFO - 'read-buildbot-config', 22:00:37 INFO - 'download-and-extract', 22:00:37 INFO - 'populate-webroot', 22:00:37 INFO - 'create-virtualenv', 22:00:37 INFO - 'install', 22:00:37 INFO - 'run-tests'), 22:00:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:37 INFO - 'download_minidump_stackwalk': True, 22:00:37 INFO - 'download_symbols': 'ondemand', 22:00:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:37 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:37 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:37 INFO - 'installer_path': 'installer.exe', 22:00:37 INFO - 'log_level': 'info', 22:00:37 INFO - 'log_name': 'talos', 22:00:37 INFO - 'log_to_console': True, 22:00:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:37 INFO - 'opt_config_files': (), 22:00:37 INFO - 'pip_index': False, 22:00:37 INFO - 'sps_profile': False, 22:00:37 INFO - 'sps_profile_interval': 0, 22:00:37 INFO - 'suite': 'dromaeojs', 22:00:37 INFO - 'system_bits': '32', 22:00:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:37 INFO - 'title': 'talos-linux64-ix-062', 22:00:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:37 INFO - 'use_talos_json': True, 22:00:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:37 INFO - 'work_dir': 'build'} 22:00:37 INFO - ##### 22:00:37 INFO - ##### Running clobber step. 22:00:37 INFO - ##### 22:00:37 INFO - Running pre-action listener: _resource_record_pre_action 22:00:37 INFO - Running main action method: clobber 22:00:37 INFO - rmtree: /builds/slave/test/build 22:00:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:37 INFO - Running post-action listener: _resource_record_post_action 22:00:37 INFO - ##### 22:00:37 INFO - ##### Finished clobber step (success) 22:00:37 INFO - ##### 22:00:37 INFO - ##### 22:00:37 INFO - ##### Running read-buildbot-config step. 22:00:37 INFO - ##### 22:00:37 INFO - Running pre-action listener: _resource_record_pre_action 22:00:37 INFO - Running main action method: read_buildbot_config 22:00:37 INFO - Using buildbot properties: 22:00:37 INFO - { 22:00:37 INFO - "project": "", 22:00:37 INFO - "product": "firefox", 22:00:37 INFO - "script_repo_revision": "production", 22:00:37 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:00:37 INFO - "repository": "", 22:00:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:00:37 INFO - "buildid": "20160508193137", 22:00:37 INFO - "pgo_build": "True", 22:00:37 INFO - "basedir": "/builds/slave/test", 22:00:37 INFO - "buildnumber": 959, 22:00:37 INFO - "slavename": "talos-linux64-ix-062", 22:00:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:00:37 INFO - "platform": "ubuntu64_hw", 22:00:37 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:00:37 INFO - "branch": "mozilla-inbound", 22:00:37 INFO - "repo_path": "integration/mozilla-inbound", 22:00:37 INFO - "revision": "827f631db4deadf61200f3e5ab05e44834f73ee0", 22:00:37 INFO - "stage_platform": "linux64-pgo", 22:00:37 INFO - "builduid": "49205ae2634743e6b10e02a6836dc9f9", 22:00:37 INFO - "slavebuilddir": "test" 22:00:37 INFO - } 22:00:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:00:37 INFO - Running post-action listener: _resource_record_post_action 22:00:37 INFO - ##### 22:00:37 INFO - ##### Finished read-buildbot-config step (success) 22:00:37 INFO - ##### 22:00:37 INFO - ##### 22:00:37 INFO - ##### Running download-and-extract step. 22:00:37 INFO - ##### 22:00:37 INFO - Running pre-action listener: _resource_record_pre_action 22:00:37 INFO - Running main action method: download_and_extract 22:00:37 INFO - mkdir: /builds/slave/test/build/tests 22:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:37 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:00:47 INFO - Downloaded 1361 bytes. 22:00:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:47 INFO - Using the following test package requirements: 22:00:47 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'jsshell-linux-x86_64.zip'], 22:00:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:47 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:47 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:00:57 INFO - Downloaded 23896445 bytes. 22:00:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:00:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:00:59 INFO - Return code: 0 22:00:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:59 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:01:00 INFO - Downloaded 23896445 bytes. 22:01:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:01:02 INFO - Return code: 0 22:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:02 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:01:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:01:03 INFO - Downloaded 10840152 bytes. 22:01:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:01:03 INFO - Return code: 0 22:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:03 INFO - https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:01:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:01:04 INFO - Downloaded 58423036 bytes. 22:01:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:04 INFO - mkdir: /builds/slave/test/properties 22:01:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:04 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:04 INFO - Contents: 22:01:04 INFO - build_url:https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:01:05 INFO - Running post-action listener: _resource_record_post_action 22:01:05 INFO - Running post-action listener: set_extra_try_arguments 22:01:05 INFO - ##### 22:01:05 INFO - ##### Finished download-and-extract step (success) 22:01:05 INFO - ##### 22:01:05 INFO - ##### 22:01:05 INFO - ##### Running populate-webroot step. 22:01:05 INFO - ##### 22:01:05 INFO - Running pre-action listener: _resource_record_pre_action 22:01:05 INFO - Running main action method: populate_webroot 22:01:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:05 INFO - '--tppagecycles', 22:01:05 INFO - '7'], 22:01:05 INFO - 'tests': ['tsvgm']}}, 22:01:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['damp', 'tps']}, 22:01:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'tests': ['damp', 'tps']}, 22:01:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['dromaeo_dom']}, 22:01:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['a11yr', 22:01:05 INFO - 'ts_paint', 22:01:05 INFO - 'tpaint', 22:01:05 INFO - 'sessionrestore', 22:01:05 INFO - 'sessionrestore_no_auto_restore', 22:01:05 INFO - 'tabpaint']}, 22:01:05 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:05 INFO - 'ts_paint', 22:01:05 INFO - 'tpaint', 22:01:05 INFO - 'sessionrestore', 22:01:05 INFO - 'sessionrestore_no_auto_restore', 22:01:05 INFO - 'tabpaint']}, 22:01:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:05 INFO - 'ts_paint', 22:01:05 INFO - 'tpaint', 22:01:05 INFO - 'sessionrestore', 22:01:05 INFO - 'sessionrestore_no_auto_restore', 22:01:05 INFO - 'tabpaint']}, 22:01:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:05 INFO - 'ts_paint', 22:01:05 INFO - 'tpaint', 22:01:05 INFO - 'sessionrestore', 22:01:05 INFO - 'sessionrestore_no_auto_restore', 22:01:05 INFO - 'tabpaint']}, 22:01:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['a11yr', 22:01:05 INFO - 'ts_paint', 22:01:05 INFO - 'tpaint', 22:01:05 INFO - 'sessionrestore', 22:01:05 INFO - 'sessionrestore_no_auto_restore', 22:01:05 INFO - 'tabpaint']}, 22:01:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['a11yr', 22:01:05 INFO - 'ts_paint', 22:01:05 INFO - 'tpaint', 22:01:05 INFO - 'sessionrestore', 22:01:05 INFO - 'sessionrestore_no_auto_restore', 22:01:05 INFO - 'tabpaint']}, 22:01:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['tsvgx', 22:01:05 INFO - 'tsvgr_opacity', 22:01:05 INFO - 'tart', 22:01:05 INFO - 'tscrollx', 22:01:05 INFO - 'cart']}, 22:01:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:05 INFO - 'tsvgr_opacity', 22:01:05 INFO - 'tart', 22:01:05 INFO - 'tscrollx', 22:01:05 INFO - 'cart']}, 22:01:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'talos_options': ['--disable-e10s'], 22:01:05 INFO - 'tests': ['tp5o']}, 22:01:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'tests': ['tp5o']}, 22:01:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'talos_options': ['--disable-e10s', 22:01:05 INFO - '--xperf_path', 22:01:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:05 INFO - 'tests': ['tp5n']}, 22:01:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:05 INFO - 'talos_options': ['--xperf_path', 22:01:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:05 INFO - 'tests': ['tp5n']}}, 22:01:05 INFO - 'talos.zip': {'path': '', 22:01:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:05 INFO - Running post-action listener: _resource_record_post_action 22:01:05 INFO - ##### 22:01:05 INFO - ##### Finished populate-webroot step (success) 22:01:05 INFO - ##### 22:01:05 INFO - ##### 22:01:05 INFO - ##### Running create-virtualenv step. 22:01:05 INFO - ##### 22:01:05 INFO - Running pre-action listener: _resource_record_pre_action 22:01:05 INFO - Running main action method: create_virtualenv 22:01:05 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:01:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:05 INFO - Using real prefix '/usr' 22:01:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:05 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:07 INFO - Installing pip.................done. 22:01:07 INFO - Return code: 0 22:01:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:07 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:01:07 INFO - Using env: {'DISPLAY': ':0', 22:01:07 INFO - 'HOME': '/home/cltbld', 22:01:07 INFO - 'LANG': 'en_US.UTF-8', 22:01:07 INFO - 'LANGUAGE': 'en_US:en', 22:01:07 INFO - 'LOGNAME': 'cltbld', 22:01:07 INFO - 'MAIL': '/var/mail/cltbld', 22:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:07 INFO - 'MOZ_NO_REMOTE': '1', 22:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:07 INFO - 'NO_EM_RESTART': '1', 22:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:07 INFO - 'PWD': '/builds/slave/test', 22:01:07 INFO - 'SHELL': '/bin/bash', 22:01:07 INFO - 'SHLVL': '1', 22:01:07 INFO - 'TERM': 'linux', 22:01:07 INFO - 'TMOUT': '86400', 22:01:07 INFO - 'USER': 'cltbld', 22:01:07 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:07 INFO - '_': '/tools/buildbot/bin/python'} 22:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:01:08 INFO - Cleaning up... 22:01:08 INFO - Return code: 0 22:01:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:01:08 INFO - Using env: {'DISPLAY': ':0', 22:01:08 INFO - 'HOME': '/home/cltbld', 22:01:08 INFO - 'LANG': 'en_US.UTF-8', 22:01:08 INFO - 'LANGUAGE': 'en_US:en', 22:01:08 INFO - 'LOGNAME': 'cltbld', 22:01:08 INFO - 'MAIL': '/var/mail/cltbld', 22:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:08 INFO - 'MOZ_NO_REMOTE': '1', 22:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:08 INFO - 'NO_EM_RESTART': '1', 22:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:08 INFO - 'PWD': '/builds/slave/test', 22:01:08 INFO - 'SHELL': '/bin/bash', 22:01:08 INFO - 'SHLVL': '1', 22:01:08 INFO - 'TERM': 'linux', 22:01:08 INFO - 'TMOUT': '86400', 22:01:08 INFO - 'USER': 'cltbld', 22:01:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:08 INFO - '_': '/tools/buildbot/bin/python'} 22:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:08 INFO - Downloading/unpacking psutil>=0.7.1 22:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:10 INFO - Installing collected packages: psutil 22:01:10 INFO - Running setup.py install for psutil 22:01:10 INFO - building 'psutil._psutil_linux' extension 22:01:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:01:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:01:10 INFO - building 'psutil._psutil_posix' extension 22:01:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:01:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:01:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:10 INFO - Successfully installed psutil 22:01:10 INFO - Cleaning up... 22:01:10 INFO - Return code: 0 22:01:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:01:10 INFO - Using env: {'DISPLAY': ':0', 22:01:10 INFO - 'HOME': '/home/cltbld', 22:01:10 INFO - 'LANG': 'en_US.UTF-8', 22:01:10 INFO - 'LANGUAGE': 'en_US:en', 22:01:10 INFO - 'LOGNAME': 'cltbld', 22:01:10 INFO - 'MAIL': '/var/mail/cltbld', 22:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:10 INFO - 'MOZ_NO_REMOTE': '1', 22:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:10 INFO - 'NO_EM_RESTART': '1', 22:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:10 INFO - 'PWD': '/builds/slave/test', 22:01:10 INFO - 'SHELL': '/bin/bash', 22:01:10 INFO - 'SHLVL': '1', 22:01:10 INFO - 'TERM': 'linux', 22:01:10 INFO - 'TMOUT': '86400', 22:01:10 INFO - 'USER': 'cltbld', 22:01:10 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:10 INFO - '_': '/tools/buildbot/bin/python'} 22:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:01:13 INFO - Installing collected packages: mozsystemmonitor 22:01:13 INFO - Running setup.py install for mozsystemmonitor 22:01:13 INFO - Successfully installed mozsystemmonitor 22:01:13 INFO - Cleaning up... 22:01:13 INFO - Return code: 0 22:01:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:13 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:01:13 INFO - Using env: {'DISPLAY': ':0', 22:01:13 INFO - 'HOME': '/home/cltbld', 22:01:13 INFO - 'LANG': 'en_US.UTF-8', 22:01:13 INFO - 'LANGUAGE': 'en_US:en', 22:01:13 INFO - 'LOGNAME': 'cltbld', 22:01:13 INFO - 'MAIL': '/var/mail/cltbld', 22:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:13 INFO - 'MOZ_NO_REMOTE': '1', 22:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:13 INFO - 'NO_EM_RESTART': '1', 22:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:13 INFO - 'PWD': '/builds/slave/test', 22:01:13 INFO - 'SHELL': '/bin/bash', 22:01:13 INFO - 'SHLVL': '1', 22:01:13 INFO - 'TERM': 'linux', 22:01:13 INFO - 'TMOUT': '86400', 22:01:13 INFO - 'USER': 'cltbld', 22:01:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:13 INFO - '_': '/tools/buildbot/bin/python'} 22:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:13 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - Downloading docopt-0.6.1.tar.gz 22:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:16 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:16 INFO - Running setup.py install for blobuploader 22:01:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:16 INFO - Running setup.py install for requests 22:01:16 INFO - Running setup.py install for docopt 22:01:16 INFO - Successfully installed blobuploader requests docopt 22:01:16 INFO - Cleaning up... 22:01:16 INFO - Return code: 0 22:01:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:16 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:01:16 INFO - Using env: {'DISPLAY': ':0', 22:01:16 INFO - 'HOME': '/home/cltbld', 22:01:16 INFO - 'LANG': 'en_US.UTF-8', 22:01:16 INFO - 'LANGUAGE': 'en_US:en', 22:01:16 INFO - 'LOGNAME': 'cltbld', 22:01:16 INFO - 'MAIL': '/var/mail/cltbld', 22:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:16 INFO - 'MOZ_NO_REMOTE': '1', 22:01:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:16 INFO - 'NO_EM_RESTART': '1', 22:01:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:16 INFO - 'PWD': '/builds/slave/test', 22:01:16 INFO - 'SHELL': '/bin/bash', 22:01:16 INFO - 'SHLVL': '1', 22:01:16 INFO - 'TERM': 'linux', 22:01:16 INFO - 'TMOUT': '86400', 22:01:16 INFO - 'USER': 'cltbld', 22:01:16 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:16 INFO - '_': '/tools/buildbot/bin/python'} 22:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:16 INFO - Running setup.py (path:/tmp/pip-VGRym5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:16 INFO - Running setup.py (path:/tmp/pip-ORWAL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:16 INFO - Running setup.py (path:/tmp/pip-VtfhOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:17 INFO - Running setup.py (path:/tmp/pip-jSQYiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:17 INFO - Running setup.py (path:/tmp/pip-bEiauB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:17 INFO - Running setup.py (path:/tmp/pip-peh_Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:17 INFO - Running setup.py (path:/tmp/pip-0wDGJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:17 INFO - Running setup.py (path:/tmp/pip-PXsH3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:17 INFO - Running setup.py (path:/tmp/pip-jkwgJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:17 INFO - Running setup.py (path:/tmp/pip-V3jAIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:17 INFO - Running setup.py (path:/tmp/pip-3ZfYgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:17 INFO - Running setup.py (path:/tmp/pip-MQcAZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:17 INFO - Running setup.py (path:/tmp/pip-_kPxtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:17 INFO - Running setup.py (path:/tmp/pip-_aDWff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:17 INFO - Running setup.py (path:/tmp/pip-Glu6IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:17 INFO - Running setup.py (path:/tmp/pip-UFaKvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:18 INFO - Running setup.py (path:/tmp/pip-xBunkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:18 INFO - Running setup.py install for manifestparser 22:01:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Running setup.py install for mozcrash 22:01:18 INFO - Running setup.py install for mozdebug 22:01:18 INFO - Running setup.py install for mozdevice 22:01:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Running setup.py install for mozfile 22:01:18 INFO - Running setup.py install for mozhttpd 22:01:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Running setup.py install for mozinfo 22:01:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Running setup.py install for mozInstall 22:01:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:18 INFO - Running setup.py install for mozleak 22:01:18 INFO - Running setup.py install for mozlog 22:01:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Running setup.py install for moznetwork 22:01:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Running setup.py install for mozprocess 22:01:19 INFO - Running setup.py install for mozprofile 22:01:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Running setup.py install for mozrunner 22:01:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Running setup.py install for mozscreenshot 22:01:19 INFO - Running setup.py install for moztest 22:01:19 INFO - Running setup.py install for mozversion 22:01:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:19 INFO - Cleaning up... 22:01:19 INFO - Return code: 0 22:01:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:19 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:01:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:01:19 INFO - Using env: {'DISPLAY': ':0', 22:01:19 INFO - 'HOME': '/home/cltbld', 22:01:19 INFO - 'LANG': 'en_US.UTF-8', 22:01:19 INFO - 'LANGUAGE': 'en_US:en', 22:01:19 INFO - 'LOGNAME': 'cltbld', 22:01:19 INFO - 'MAIL': '/var/mail/cltbld', 22:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:19 INFO - 'MOZ_NO_REMOTE': '1', 22:01:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:19 INFO - 'NO_EM_RESTART': '1', 22:01:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:19 INFO - 'PWD': '/builds/slave/test', 22:01:19 INFO - 'SHELL': '/bin/bash', 22:01:19 INFO - 'SHLVL': '1', 22:01:19 INFO - 'TERM': 'linux', 22:01:19 INFO - 'TMOUT': '86400', 22:01:19 INFO - 'USER': 'cltbld', 22:01:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:19 INFO - '_': '/tools/buildbot/bin/python'} 22:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:20 INFO - Running setup.py (path:/tmp/pip-pnygDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:20 INFO - Running setup.py (path:/tmp/pip-WR7Mwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:20 INFO - Running setup.py (path:/tmp/pip-F4OvVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:20 INFO - Running setup.py (path:/tmp/pip-CPLQXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:20 INFO - Running setup.py (path:/tmp/pip-qXGfBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:20 INFO - Running setup.py (path:/tmp/pip-hHE3MC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:20 INFO - Running setup.py (path:/tmp/pip-c5mMfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:20 INFO - Running setup.py (path:/tmp/pip-KK2KZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:20 INFO - Running setup.py (path:/tmp/pip-pOtuaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:20 INFO - Running setup.py (path:/tmp/pip-WnsrFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:20 INFO - Running setup.py (path:/tmp/pip-wW9h4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:20 INFO - Running setup.py (path:/tmp/pip-9IKoMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:20 INFO - Running setup.py (path:/tmp/pip-PsNGgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:21 INFO - Running setup.py (path:/tmp/pip-9O4JUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:21 INFO - Running setup.py (path:/tmp/pip-_Me6wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:21 INFO - Running setup.py (path:/tmp/pip-m7AJwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:21 INFO - Running setup.py (path:/tmp/pip-z0KORB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:23 INFO - Downloading blessings-1.6.tar.gz 22:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:23 INFO - Installing collected packages: blessings 22:01:23 INFO - Running setup.py install for blessings 22:01:23 INFO - Successfully installed blessings 22:01:23 INFO - Cleaning up... 22:01:23 INFO - Return code: 0 22:01:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:23 INFO - Reading from file tmpfile_stdout 22:01:23 INFO - Current package versions: 22:01:23 INFO - argparse == 1.2.1 22:01:23 INFO - blessings == 1.6 22:01:23 INFO - blobuploader == 1.2.4 22:01:23 INFO - docopt == 0.6.1 22:01:23 INFO - manifestparser == 1.1 22:01:23 INFO - mozInstall == 1.12 22:01:23 INFO - mozcrash == 0.17 22:01:23 INFO - mozdebug == 0.1 22:01:23 INFO - mozdevice == 0.48 22:01:23 INFO - mozfile == 1.2 22:01:23 INFO - mozhttpd == 0.7 22:01:23 INFO - mozinfo == 0.9 22:01:23 INFO - mozleak == 0.1 22:01:23 INFO - mozlog == 3.1 22:01:23 INFO - moznetwork == 0.27 22:01:23 INFO - mozprocess == 0.22 22:01:23 INFO - mozprofile == 0.28 22:01:23 INFO - mozrunner == 6.11 22:01:23 INFO - mozscreenshot == 0.1 22:01:23 INFO - mozsystemmonitor == 0.0 22:01:23 INFO - moztest == 0.7 22:01:23 INFO - mozversion == 1.4 22:01:23 INFO - psutil == 3.1.1 22:01:23 INFO - requests == 1.2.3 22:01:23 INFO - wsgiref == 0.1.2 22:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:23 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:01:23 INFO - Using env: {'DISPLAY': ':0', 22:01:23 INFO - 'HOME': '/home/cltbld', 22:01:23 INFO - 'LANG': 'en_US.UTF-8', 22:01:23 INFO - 'LANGUAGE': 'en_US:en', 22:01:23 INFO - 'LOGNAME': 'cltbld', 22:01:23 INFO - 'MAIL': '/var/mail/cltbld', 22:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:23 INFO - 'MOZ_NO_REMOTE': '1', 22:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:23 INFO - 'NO_EM_RESTART': '1', 22:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:23 INFO - 'PWD': '/builds/slave/test', 22:01:23 INFO - 'SHELL': '/bin/bash', 22:01:23 INFO - 'SHLVL': '1', 22:01:23 INFO - 'TERM': 'linux', 22:01:23 INFO - 'TMOUT': '86400', 22:01:23 INFO - 'USER': 'cltbld', 22:01:23 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:23 INFO - '_': '/tools/buildbot/bin/python'} 22:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:01:23 INFO - Cleaning up... 22:01:23 INFO - Return code: 0 22:01:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:23 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:01:23 INFO - Using env: {'DISPLAY': ':0', 22:01:23 INFO - 'HOME': '/home/cltbld', 22:01:23 INFO - 'LANG': 'en_US.UTF-8', 22:01:23 INFO - 'LANGUAGE': 'en_US:en', 22:01:23 INFO - 'LOGNAME': 'cltbld', 22:01:23 INFO - 'MAIL': '/var/mail/cltbld', 22:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:23 INFO - 'MOZ_NO_REMOTE': '1', 22:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:23 INFO - 'NO_EM_RESTART': '1', 22:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:23 INFO - 'PWD': '/builds/slave/test', 22:01:23 INFO - 'SHELL': '/bin/bash', 22:01:23 INFO - 'SHLVL': '1', 22:01:23 INFO - 'TERM': 'linux', 22:01:23 INFO - 'TMOUT': '86400', 22:01:23 INFO - 'USER': 'cltbld', 22:01:23 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:23 INFO - '_': '/tools/buildbot/bin/python'} 22:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:24 INFO - Downloading/unpacking jsonschema 22:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:25 INFO - Downloading/unpacking functools32 (from jsonschema) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:26 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:26 INFO - warning: no files found matching '*.txt' 22:01:26 INFO - no previously-included directories found matching 'build' 22:01:26 INFO - no previously-included directories found matching 'dist' 22:01:26 INFO - no previously-included directories found matching '.git*' 22:01:26 INFO - Installing collected packages: jsonschema, functools32 22:01:26 INFO - Running setup.py install for functools32 22:01:26 INFO - warning: no files found matching '*.txt' 22:01:26 INFO - no previously-included directories found matching 'build' 22:01:26 INFO - no previously-included directories found matching 'dist' 22:01:26 INFO - no previously-included directories found matching '.git*' 22:01:26 INFO - Successfully installed jsonschema functools32 22:01:26 INFO - Cleaning up... 22:01:26 INFO - Return code: 0 22:01:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:26 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 0x7ffaf9033f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e89770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:01:26 INFO - Using env: {'DISPLAY': ':0', 22:01:26 INFO - 'HOME': '/home/cltbld', 22:01:26 INFO - 'LANG': 'en_US.UTF-8', 22:01:26 INFO - 'LANGUAGE': 'en_US:en', 22:01:26 INFO - 'LOGNAME': 'cltbld', 22:01:26 INFO - 'MAIL': '/var/mail/cltbld', 22:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:26 INFO - 'MOZ_NO_REMOTE': '1', 22:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:26 INFO - 'NO_EM_RESTART': '1', 22:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:26 INFO - 'PWD': '/builds/slave/test', 22:01:26 INFO - 'SHELL': '/bin/bash', 22:01:26 INFO - 'SHLVL': '1', 22:01:26 INFO - 'TERM': 'linux', 22:01:26 INFO - 'TMOUT': '86400', 22:01:26 INFO - 'USER': 'cltbld', 22:01:26 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:26 INFO - '_': '/tools/buildbot/bin/python'} 22:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:26 INFO - Downloading/unpacking flake8 22:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:01:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:01:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:29 INFO - no previously-included directories found matching 'docs/_build' 22:01:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:01:29 INFO - Running setup.py install for pep8 22:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:29 INFO - no previously-included directories found matching 'docs/_build' 22:01:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:01:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:01:29 INFO - Cleaning up... 22:01:29 INFO - Return code: 0 22:01:29 INFO - Running post-action listener: _resource_record_post_action 22:01:29 INFO - Running post-action listener: _start_resource_monitoring 22:01:29 INFO - Starting resource monitoring. 22:01:29 INFO - ##### 22:01:29 INFO - ##### Finished create-virtualenv step (success) 22:01:29 INFO - ##### 22:01:29 INFO - ##### 22:01:29 INFO - ##### Running install step. 22:01:29 INFO - ##### 22:01:29 INFO - Running pre-action listener: _resource_record_pre_action 22:01:29 INFO - Running main action method: install 22:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:29 INFO - Reading from file tmpfile_stdout 22:01:29 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:29 INFO - Reading from file tmpfile_stdout 22:01:29 INFO - Output received: 22:01:29 INFO - Usage: mozinstall [options] installer 22:01:29 INFO - Options: 22:01:29 INFO - -h, --help show this help message and exit 22:01:29 INFO - -d DEST, --destination=DEST 22:01:29 INFO - Directory to install application into. [default: 22:01:29 INFO - "/builds/slave/test"] 22:01:29 INFO - --app=APP Application being installed. [default: firefox] 22:01:29 INFO - mkdir: /builds/slave/test/build/application 22:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:01:46 INFO - Reading from file tmpfile_stdout 22:01:46 INFO - Output received: 22:01:46 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:46 INFO - Running post-action listener: _resource_record_post_action 22:01:46 INFO - ##### 22:01:46 INFO - ##### Finished install step (success) 22:01:46 INFO - ##### 22:01:46 INFO - ##### 22:01:46 INFO - ##### Running run-tests step. 22:01:46 INFO - ##### 22:01:46 INFO - Running pre-action listener: _resource_record_pre_action 22:01:46 INFO - Running main action method: run_tests 22:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:46 INFO - Python 2.7.3 22:01:46 INFO - Return code: 0 22:01:46 INFO - grabbing minidump binary from tooltool 22:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:46 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f189c0>, '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 0x1f198c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f19d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:01:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:01:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:46 INFO - Return code: 0 22:01:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:01:47 INFO - Return code: 0 22:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 22:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 22:01:47 INFO - Using env: {'DISPLAY': ':0', 22:01:47 INFO - 'HOME': '/home/cltbld', 22:01:47 INFO - 'LANG': 'en_US.UTF-8', 22:01:47 INFO - 'LANGUAGE': 'en_US:en', 22:01:47 INFO - 'LOGNAME': 'cltbld', 22:01:47 INFO - 'MAIL': '/var/mail/cltbld', 22:01:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:47 INFO - 'MOZ_NO_REMOTE': '1', 22:01:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:47 INFO - 'NO_EM_RESTART': '1', 22:01:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:47 INFO - 'PWD': '/builds/slave/test', 22:01:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:47 INFO - 'SHELL': '/bin/bash', 22:01:47 INFO - 'SHLVL': '1', 22:01:47 INFO - 'TERM': 'linux', 22:01:47 INFO - 'TMOUT': '86400', 22:01:47 INFO - 'USER': 'cltbld', 22:01:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615', 22:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:47 INFO - '_': '/tools/buildbot/bin/python'} 22:01:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 22:01:47 INFO - mozversion application_buildid: 20160508193137 22:01:47 INFO - mozversion application_changeset: 827f631db4deadf61200f3e5ab05e44834f73ee0 22:01:47 INFO - mozversion application_display_name: Nightly 22:01:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:47 INFO - mozversion application_name: Firefox 22:01:47 INFO - mozversion application_remotingname: firefox 22:01:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:47 INFO - mozversion application_vendor: Mozilla 22:01:47 INFO - mozversion application_version: 49.0a1 22:01:47 INFO - mozversion platform_buildid: 20160508193137 22:01:47 INFO - mozversion platform_changeset: 827f631db4deadf61200f3e5ab05e44834f73ee0 22:01:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:47 INFO - mozversion platform_version: 49.0a1 22:01:47 INFO - using testdate: 1462770107 22:01:47 INFO - actual date: 1462770107 22:01:47 INFO - starting webserver on 'localhost:56697' 22:01:47 INFO - SUITE-START | Running 2 tests 22:01:47 INFO - TEST-START | dromaeo_css 22:01:47 INFO - operating with platform_type : linux_ 22:01:47 INFO - Initialising browser for dromaeo_css test... 22:01:47 INFO - TEST-INFO | started process 16048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRVcN78/profile http://localhost:56697/getInfo.html) 22:01:51 INFO - PROCESS | 16048 | __metrics Screen width/height:1600/1200 22:01:51 INFO - PROCESS | 16048 | colorDepth:24 22:01:51 INFO - PROCESS | 16048 | Browser inner width/height: 1024/697 22:01:51 INFO - PROCESS | 16048 | __metrics 22:01:52 ERROR - PROCESS | 16048 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:01:53 INFO - TEST-INFO | 16048: exit 0 22:01:53 INFO - Browser initialized. 22:01:53 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:53 INFO - TEST-INFO | started process 16130 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRVcN78/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:04 INFO - PROCESS | 16130 | RSS: Main: 152494080 22:02:04 INFO - PROCESS | 16130 | 22:02:55 INFO - PROCESS | 16130 | 1462770175922 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:05:03 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:56697/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56697/tests/dromaeo/cssquery-ext.html) 22:05:04 INFO - PROCESS | 16130 | RSS: Main: 260300800 22:05:04 INFO - PROCESS | 16130 | 22:08:06 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:56697/tests/dromaeo/cssquery-ext.html (next: http://localhost:56697/tests/dromaeo/cssquery-jquery.html) 22:08:07 INFO - PROCESS | 16130 | RSS: Main: 237694976 22:08:07 INFO - PROCESS | 16130 | 22:11:09 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:56697/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56697/tests/dromaeo/cssquery-mootools.html) 22:11:10 INFO - PROCESS | 16130 | RSS: Main: 246792192 22:11:10 INFO - PROCESS | 16130 | 22:14:12 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:56697/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56697/tests/dromaeo/cssquery-prototype.html) 22:14:12 INFO - PROCESS | 16130 | RSS: Main: 230387712 22:14:12 INFO - PROCESS | 16130 | 22:17:15 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:56697/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56697/tests/dromaeo/cssquery-yui.html) 22:17:15 INFO - PROCESS | 16130 | RSS: Main: 217337856 22:17:15 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | Cycle 1(1): loaded http://localhost:56697/tests/dromaeo/cssquery-yui.html (next: http://localhost:56697/tests/dromaeo/cssquery-dojo.html) 22:20:18 INFO - PROCESS | 16130 | RSS: Main: 279658496 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | __start_tp_report 22:20:18 INFO - PROCESS | 16130 | _x_x_mozilla_page_load 22:20:18 INFO - PROCESS | 16130 | _x_x_mozilla_page_load_details 22:20:18 INFO - PROCESS | 16130 | |i|pagename|runs| 22:20:18 INFO - PROCESS | 16130 | |0;dojo.html;3127.452695778238;3179.284660951285;3189.266468712196;3206.663300353461;3231.9173077019195;5253.133233016548;5265.236540701606;5409.404965453802;5477.726113694314;5516.3931967483595;5347.786088556462;5368.221607866857;5392.595555333346;5401.70290634015;5446.074167391543;4820.614350851933;4882.755862206894;4908.484609116045;4916;5096.337476128117;4949.851504454872;5011.373578302712;5051.924221136685;5061.240813877929;5068.366454193226;4757.500462451445;4800.231962885939;4803.687760295569;4825.155597770411;4885.291632713237;4811.061842940628;4833.791552111972;4913.557779799835;4931.950680493185;4973.030259099477;4807.495213002654;4878.804847806084;4883.316335713003;4920.21276595743;4942.728149951751;5243.606729495303;5292.311999440087;5296.602754793406;5371.979323928441;5383.75773090208;5725.6278341908055;5739.684317362585;5744.368119506851;5757.337906140806;5763.654180749127;5651.321841379061;5729.598928075036;5742.712864356766;5767.307923049219;5781.71091445426;5482.369527504348;5483.8080667176855;5485.122380419114;5502.394736578973;5511.779528818883;5944.464998150187;5946.256717910262;5960.225170727865;5964.8807023860145;5976.372480889531;5503.6147469677535;5619.7471113799975;5659.490645841894;5660.943390566124;5702.942970570324;5777.653340799566;5784.8719499698;5785.132230165509;5835.5915085944425;5855.180274761537;5725.570582206351;5750.763585828983;5754.136879468029;5761.769529218785;5768.423157684198;5530.446955304437;5566.749496272678;5578.525825304813;5613.7193140342815;5617.241672374177;4220.599043090901;4229.13302772937;4234.407182994383;4243.915121697549;4271.402003719481;3788.962110378861;3832.7125465590193;3847.172857835523;3847.8076096195077;3880.049387899926;5383.461653834664;5430.375506816687;5430.484104010113;5448.291722076184;5456.836294911159;5747.827565173052;5755.59710820247;5776.786874756348;5781.566382521328;5784.537237021028;5364.624476286701;5418.729063546807;5431.375391829911;5433.646812957145;5441.564674826004;4857.587105096107;4873.439554451284;4881.365422495053;4890.462049174588;4939.456659767423;6014.879702405928;6017.909731354033;6018.729157187937;6025.457708806229;6040.063790112539;5594;5740.512056475246;5785.190073389813;5820.330661973927;5890.440408161217;6025.487833534024;6043.8186854394435;6045.335013148462;6047.879042419215;6052.515798736179;5705.771769129188;5728.2266893970445;5747.051736463437;5747.252857128547;5750.769969201352;5679;5739.827805165852;5768.394318596487;5823.912641310469;5837.182794408701;5680.886382272414;5682.715864206691;5688.40271771458;5695.345035320991;5699.487046165865;5640.830775076754;5678.233438485917;5681.545476361964;5686.346070201815;5695.544356451391;5344.652597581145;5489.807856724996;5514.393416724238;5564.276644036249;5601.719914004202;5718.742656580381;5728.226689396878;5737.885242295048;5792.7103644818435;5795.913061304;5490.258815059916;5640.464155905182;5642.322921249476;5644.153376993484;5681.147827825859 22:20:18 INFO - PROCESS | 16130 | |1;ext.html;609.4119174996127;637.2703254773285;641.4419455074084;652.6671397587229;656.1568384625757;33397;43539;44306;44308.33537496936;48502.27246591295;44670.77664611673;44787.77606111965;45128;45446.77276613612;45472;8740.082291359427;8765;8864.73405797827;8875.556222188865;8879.77800554988;8414.873776893352;8761.430507017056;8766.824663506726;8793.428427152247;8801.823963520726;8836.116388361144;8854.734357969272;8859.29125669945;8865.556722163898;8882.333824963122;8788.736337909873;8834.160754728291;8834.86747698785;8840.160184782435;8841.027486976427;8686.131386861327;8769.473831570127;8769.868451973225;8777.517236551987;8781.956090219572;41981;41998;42304.365434518506;42317.36523952143;42413.151736965396;3272.4764037753844;3321.4187517184396;3336.666333366644;3701.2782507411066;3824.617538246181;31606;31996.360072798412;32241;32347;33353.49969750456;4100.2414553307735;4185.622392039089;4211.936820947688;4275.5724427557;4361.651067914591;279333.6033319841;279742;279862;280038;280308;51726;54718.726406367714;55450.44549554533;55480;55555.44444555583;50761.238581421305;55886.72056639772;56021;58952.70523647354;59458.10812837811;16656.41702540405;16884.324627015027;16910.239039243264;16911.746323804906;16932.40736574214;13037.087403881636;13184.143030702973;13211.66970825737;13231.73536529264;13233.007524435707;14474.638134046732;15493.767593485882;15580.298886549906;15761.133137677538;16422.507324780036;6024.252966639626;6024.457798798129;6040.818775436656;6115.419035191739;6128.142060111588;1056.1814593689799;1107.7064577886867;1114.793763153819;1117.586492997596;1148.121686909515;30997.69002309948;31726.36547269088;31839.36321273608;31880.84059579687;32323.191920201712;1100.0759362135843;1109.0794640448407;1120.361394005409;1189.1319336884123;1192.9821052684217;16434.103914393076;17027;17220.31118755261;17251.223694933757;17441.7383739244;425854;426821;426877;428727;429144;57592.71203643955;57595;57617.423825762045;57641.71179144077;57719.71140144272;1380.6962468256968;1427.5075099090798;1432.684809341964;1433.7634290342185;1435.6339133521094;29748.702512974593;30430.56005179617;30532.694673052985;31182.532262016543;31268.374632506766;49414.75292623514;49579.25631115608;49644.50355496399;49725.25412118893;49780.50658480251;359890;361512;361740;363564;364179;39238.21523569455;39730.40404393994;41344.37983430311;41620.375694364004;41635.583644164384;47903.5209647913;48508.757456212494;48726.26910596273;49291.753541232065;49731.75134124306;39293.803530982164;40142;41047.589524104376;41564;41684.79157604193;39176.412353815285;39338.80330598329;40045;40262;40543.391849122876;40125.799371002955;40387.1922561553;40533.39199911945;41332;41372.58627413688;34860;35761.46357804583;36139.27721445504;36297.27405451929;36427.08932276714;26434.86782566075;26969.224308597204;27142;27155.456890862468;27562.5865612012 22:20:18 INFO - PROCESS | 16130 | |2;jquery.html;389.188541890159;405.8741790045086;407.6046235151903;413.70833562338544;425.9872203833886;15367.769483457743;18895.33866314679;18924.621507569875;19065;19092.809071909258;12242.265464072161;12909.74180516389;12960.092793504458;13308.46766129356;18818.529536761587;5189.6107791915565;5236.869078273055;5292.867678308054;5300.496452836975;5349.251104845305;3896.7467114637598;6854;7264.891026634605;7282.599457029861;7302.81742956425;7320.524165929224;7344.449166312521;7390.076240469942;7400.185979542246;7416.925830741678;7025.543339682904;7109.324614161646;7200.423966082753;7364.5949472778975;7388.261173882595;6836.589804611739;6978.546394484393;6997.475189360767;7025.086738723933;7028.92971070293;24873;25265;25298;25407.61888571673;25879.35301617456;2644.2067379785985;2682.369643133862;2692.17888543994;2728.740769626902;2736.849473278969;15228.771568426482;15781.368745250176;15790.526284211492;15803.841961580352;16310.75533866993;1956.344624550777;2027.6147531969027;2170.316350349635;2259.344790010895;2314.5370925814905;96664;99944.50027749887;99975;100311;101882;14584.025838075617;17392.565185870455;17419.25807419248;17717.7342339865;17859.821401786077;32641.67358326434;32701;32864.835675821465;33256.833715831264;33262.33475330528;9382.95308523453;9758.658446954323;9926.30515863897;10002.749931251778;10047;7756.457048006585;7778;8230.711925082593;8390.202930721694;8499.150084991576;8691.478511289342;8904.688335908211;9068.183863452321;9105.362624616346;9167.816643667089;3332.950005749916;3519.648035196511;3522.330757156132;3571.285742851388;3648.1791596891017;951.5432592355708;1000.1049061090283;1009.268280496634;1088.7931293054453;1115.3252282369212;17984;18555.072246387626;19116.139773710216;19531;19571.608567828564;1022.4478781458079;1029.2537910015178;1032.9276950613385;1034.8551202831609;1122.7586068995206;10148.238882083873;10759;10778.622748203776;10807.891921080845;10822.134229261641;471455;474671;484986;487540;488430;52760.47239527632;63148.36851631425;92136;92791;93760;1024.6516184497157;1058.25921854702;1058.7564860082377;1089.0688461365576;1089.5859573362072;16460.506184814476;16696.916515417346;16760.832391675925;17008.65982680315;17137.22882470317;27005.189844304703;30392.088237352913;37755.62244377631;39764;40580;57515.424845751004;57790.7110464445;58569.4143058581;58691.70654146702;59572.106418404626;7649.732259370895;7875.409344299086;7986.800329991796;8101.716439924574;8876.556172191236;12611.621651350475;12819.871801281868;13373.197608143542;13452.663683407993;13479.32603369808;10456.215783816093;10921.290116142425;11032.724181895517;11381.772364552828;11545.711357216136;11070.446477675923;11405.144614153805;11441.542338306375;11539.711507212387;11980.820287695517;11275.210735248455;11299.265547739371;11507.942460287644;11841.46713397882;11949.820752688891;7387.076615423072;7437.181909989788;7524.774256772305;7541.962290188514;7554.508956917783;10765.623203187852;10807.08139808125;10874.401907895142;11060.502277397698;11114.110871130451 22:20:18 INFO - PROCESS | 16130 | |3;mootools.html;1364.9863501364996;1523.8038140060053;1567.9764803527949;1717.5362652083938;1816.9364072257465;8013.719519816807;8366.414350995432;8385.11956244594;8620.827583448327;8621.267192288647;8349.582520873962;8538;8560.614772335244;8561.443506172094;8562.443441176334;2030.0560239488634;2082.979170208301;2293.6674182243564;2328.9417764555897;2373.5727569037567;2397.4965257295903;2420.2860156253946;2421.019487107724;2428.720697119836;2436.3909022744315;2336.100601268507;2354.1289722802585;2371.715394152704;2372.6559648850907;2374.9406264843346;2040.9387718368473;2110.356341315898;2123.7451505819245;2206.2719302630176;2227.376334626307;2274.1471948019494;2297.287840769367;2304.2165663674264;2306.7116610423695;2311;8357.874631880526;8414.158584141598;8439.240468357877;8454.87317690235;8468.618912148968;4997.975010124963;5105.668131571436;5149.897002059975;5160.354955630546;5175.81884634036;3277.655846136169;3314.303996160785;3416.3167366526795;3437.1750779812915;3453.2057626745736;1068.2575609951086;1072.1958531101673;1095.5179720922768;1145.4501839117188;1159.0669511043607;11903.345316007617;11965.401729913556;11999.340036298001;12012.279263244145;12053;4579.335996280521;4658.86023419298;4659.6505262105475;4779.0680817241;4782.258749893772;10014;10027.448490332956;10028.849567256497;10043.397396156253;10047.145992590711;5110.310108135354;5129;5152.793888244429;5159.329287192641;5159.819406320761;4255.085156691326;4621.052684199798;4695.835645752451;4713.505081966413;4785.282207668877;4041.9797901010893;4061.9394011078584;4078.5928854545136;4084.7344922579937;4104.117614712792;1326.694860182144;1335.4057444437133;1335.6794369351285;1337.9965026230327;1349.4332380400256;780.1691198873182;835.1189495082625;851.7019043334783;862.017300277684;872.31959071924;5455.509004189563;5551.084071128219;5563.471470210324;5655.519280861173;5679.290088738908;1005.4017859373807;1043.415687215162;1044.9059584637266;1049;1098.2477003252798;3531.788092714445;3603.1892824114366;3704.3147017801793;3707.4994875691978;3760.4958016792953;10632.46837658114;10944.781104378028;11112.888871111185;11115.888841111486;11126.721831954266;10796.244262901522;10940;10953.507092180871;10970;10995.560177592806;7403.8149046274275;7772.339351155102;7861.449698521049;8126.918730812735;8146.714864979697;5261;5530.281063461885;5662.462066103713;5709.942900570941;5717.542596592346;9343.34596578233;9482.762930926781;9508.952455237679;9533.332666713264;9787.16809071237;9499.857502137611;9675.9032409675;9719.611215551298;9784;9881.160101391462;6344.111824344688;6911.239763626098;7050.753223637148;7559.433042521723;7720.961395192988;7816.374690024671;8034.47775894565;8084.353251739729;8092.231238032377;8107.594620269081;7211.1707153678;7305.634718263959;7382.114146302478;7415.592142432218;7509.549427034395;6895.482838787211;7001.4748893834185;7078.1860086090755;7129.7861064168155;7207.9279207207255;6341.778037768656;6374.6175229484525;6408.871822563616;6414.390632889868;6451.225852897683;4725.196716558262;4755.239161734106;4881.389826271716;4884.438289596742;4943.036107958914;4848.5636292733825;4860.149473841992;4941.75291235444;4953.331300274417;5036.949630503648 22:20:18 INFO - PROCESS | 16130 | |4;prototype.html;342.3358684152744;345.16297977404787;350.7106637024456;351.7678332300682;354.81726910641027;5196.844094677161;5302.6213184572;5316.308879845625;5332.33345831771;5349.946500534994;5094.847154585359;5270.4729527047275;5282.23407605897;5283.894322113555;5295.894082118355;3930.7445016073934;3950.743201691881;4004.299247631667;4010.258102251078;4106.958930410688;4066.759638310314;4109.363048727452;4121.8969525761795;4125.288005477738;4131.586841315874;4090.836366545335;4098.262312783698;4099.323611604082;4103.507579090498;4111.383292506133;3908.7459315144565;3925.8233379497847;4083.3870621104497;4095.4471146395153;4122.711410201287;3655.159313357916;3665.963340366589;3681.5214022177215;3683.9263214735556;3697.4655517960014;5020.196768517057;5021.246812978046;5033.949660503421;5056.721880296583;5061.279959229963;4546.454425468932;4673.415823022122;4687.484376718558;4708.952910470885;4725.3384526166365;5368.033753924292;5384.67691938481;5405.486478784509;5420.430854760272;5423.240746295522;4047.251258517184;4058.492688413948;4077.3883917412627;4106.876793696194;4130.318497447917;5583.804566840182;5674.546036317126;5680.5514593778325;5705.885882282372;5709.857253568653;4143.7306575072525;4149.958500414958;4159.209750147463;4162.646175075152;4163.167366526707;4175.705531285311;4196.391523229176;4198.706090573692;4218.7679677618025;4234.957650423518;4582.335561343587;4686.835960741358;4700.143443339862;4723;4725.621950243973;5442.727863606883;5443.374011988672;5454.59090568209;5467.753951072212;5472.781088756406;1386.8682475164844;1390.9721805563831;1398.1401438115486;1410.9506167284096;1410.957671269843;939.6147579492512;961.8364877970762;980.4705459052032;991.1377101921374;1027.0038063078803;5086.008228395502;5086.440491545927;5103.897922041539;5106.821261255838;5113.642045056885;5324.281222035053;5331;5365.22204280385;5378.273933019071;5418.674879507243;4891.755412229374;5010.04407399636;5091.8981620367385;5099.974500127476;5100.209467532464;2808.648918885139;2825.39254060378;2841.8010739248466;2856.2573730830136;2862.3845873137375;5505.6696598204235;5527.198556209408;5537.726322945745;5567.359753628384;5597.720113994284;5425.810096646598;5565.0817615093865;5727.971360143172;5740.512056475246;5759.884802303931;5842.919059973919;5862.824115276378;5864.736086876101;5867.618604790675;5869.853253668693;5579.163125531203;5662.773489060556;5694.17434471991;5696.287964004499;5705.515031222227;5631.239782629456;5633.8591535211945;5655.575831812548;5657.906574567436;5663.66018038919;5849.824505264849;5876.620942957902;5883.941160588511;5897.7935772247765;5908.911366329595;3924.843006279717;4018.312308830357;4032.6169013943627;4055.6413601443846;4070.9185816284103;5528.889422211453;5589.916151257653;5595.35653399848;5662.207290979349;5667.603267771216;5571.498565129158;5576.076253692469;5576.944230557805;5584.441555844383;5602.075657516464;5297.523222909803;5449.3460784706085;5457.308234447334;5468;5532.585056120726;5167.896642067213;5369.677819330853;5384.7576859040655;5401.6218864679095;5429.642589352662;5401.972990135024;5492.340919089735;5505.614606977633;5509.429812503417;5523.558115350843;5335.946640533545;5355.259540649383;5374.0864053111845;5384.488473595002;5396.757145928521 22:20:18 INFO - PROCESS | 16130 | |5;yui.html;705.8164877131945;722.7362012865306;729.7409419656022;733.1092722342356;741.2180149941812;12802.103852730312;13032.674183145402;13403.798943015863;13465.259410732406;13496.325183740822;12442.93778531106;12462.314572698499;12465.314407707572;12490.687732806684;12526.123171378007;685.7736946807541;821.7493664432345;830.4685001598951;834.8121672623672;835.8913341265627;794.3248238996864;830.3149901331426;841.9831603367929;843.2158092973532;843.5444859775745;870.7170169694842;874.7026011156206;875.7722992022088;875.7810547363159;876.2595606712318;719.0544434069235;850.6172222499899;869.6173683579236;870.6038752367689;871.7384784564637;634.8190765631773;635.2821311917504;635.348767513298;642.604798049201;644.5971267957526;12026.819597706042;12051;12052.578159764365;12060.336681482515;12067;13429.328533573282;13739.175649461064;14194.645133871736;14255.857441425556;14361.900037206995;12472.750544989049;12549.435275412628;12551.729600221797;12699.365031748468;12967.351632418342;4472.709273897219;4495.393121928563;4542.931856022163;4544.636429085698;4628.907421851578;55720;55736.163957540666;55843.72078139583;56459.153112703345;57262.713686431714;10741.462926853626;10893.346399215914;11058.336499810037;11068.335899846035;11089.27919685218;18685;18866.24535018584;19049.90475047616;19059.04704764784;19066.04669766506;7748.883766743504;7853.725119620786;7858.685652573948;8044.678212871537;8171.387145963958;4580.972263644458;4610.53894610543;4625.398698169217;4637.095766325601;4744.335792988984;3127.62468503781;3173.174974506645;3180.25264062945;3203.599550056243;3220.2915358621067;622.3745136138152;622.5859803230801;627.2065836716557;646.7219095788762;647.3526473526474;603.3634515586099;612.3570251236184;637.5537124013209;642.6754488983128;649.1107183159008;8705.521196334135;8882.555872206363;9140.725778226664;9174.357794954423;10197;551.4133673272719;637.9425851673371;649.0750680280581;652.3313603556393;652.8139480248107;4178.749275043447;4194.832206711759;4282.743035417885;4282.957170428256;4419.337099435046;57495;58786;59111.40888591059;59374.70312648496;61727.6913615438;52101.47898521042;53083.73458132762;53116.20325695118;53158.20262696063;55233;45919.540804591525;46421;46967.29549056767;48840;49646.25530617044;9251.67619133327;9310.95344523273;9418.387804792666;9464.526773661428;9817.754556135867;32264.677353227107;32404.837975810915;33051.74708072225;33432;33487.33025339528;41673;42198;42357.36463953105;42834.57165428431;43214;11979.600281169298;12615.558455454015;12713.11008229415;12928.353582321035;13312.400941957441;13196.60410187696;13214.669633259246;13344.933275333562;13458.125221860548;14111.717765644835;12604.55884044054;12773.10588258813;12780.297083660487;12920.741585168433;12969.610911672666;12367.75264494723;12431.75136497247;12636.2418254905;12753.74492510126;12815.231086134861;12035.819462707892;12296.938515307365;12523.624291270911;12820.61538153857;12884.291363974697;10680.62617808373;10715;10965.122790176927;10974.573305470238;11619.767604648028;10700.93574547723;10922;11175.441227938732;11266.830997535208;11421 22:20:18 INFO - PROCESS | 16130 | __end_tp_report 22:20:18 INFO - PROCESS | 16130 | __start_cc_report 22:20:18 INFO - PROCESS | 16130 | _x_x_mozilla_cycle_collect,890 22:20:18 INFO - PROCESS | 16130 | __end_cc_report 22:20:18 INFO - PROCESS | 16130 | __startTimestamp1462771218878__endTimestamp 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | ------- Summary: start ------- 22:20:18 INFO - PROCESS | 16130 | Number of tests: 6 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | [#0] dojo.html Cycles:165 Average:5354.42 Median:5566.75 stddev:624.16 (11.2%) stddev-sans-first:601.13 22:20:18 INFO - PROCESS | 16130 | Values: 3127.5 3179.3 3189.3 3206.7 3231.9 5253.1 5265.2 5409.4 5477.7 5516.4 5347.8 5368.2 5392.6 5401.7 5446.1 4820.6 4882.8 4908.5 4916.0 5096.3 4949.9 5011.4 5051.9 5061.2 5068.4 4757.5 4800.2 4803.7 4825.2 4885.3 4811.1 4833.8 4913.6 4932.0 4973.0 4807.5 4878.8 4883.3 4920.2 4942.7 5243.6 5292.3 5296.6 5372.0 5383.8 5725.6 5739.7 5744.4 5757.3 5763.7 5651.3 5729.6 5742.7 5767.3 5781.7 5482.4 5483.8 5485.1 5502.4 5511.8 5944.5 5946.3 5960.2 5964.9 5976.4 5503.6 5619.7 5659.5 5660.9 5702.9 5777.7 5784.9 5785.1 5835.6 5855.2 5725.6 5750.8 5754.1 5761.8 5768.4 5530.4 5566.7 5578.5 5613.7 5617.2 4220.6 4229.1 4234.4 4243.9 4271.4 3789.0 3832.7 3847.2 3847.8 3880.0 5383.5 5430.4 5430.5 5448.3 5456.8 5747.8 5755.6 5776.8 5781.6 5784.5 5364.6 5418.7 5431.4 5433.6 5441.6 4857.6 4873.4 4881.4 4890.5 4939.5 6014.9 6017.9 6018.7 6025.5 6040.1 5594.0 5740.5 5785.2 5820.3 5890.4 6025.5 6043.8 6045.3 6047.9 6052.5 5705.8 5728.2 5747.1 5747.3 5750.8 5679.0 5739.8 5768.4 5823.9 5837.2 5680.9 5682.7 5688.4 5695.3 5699.5 5640.8 5678.2 5681.5 5686.3 5695.5 5344.7 5489.8 5514.4 5564.3 5601.7 5718.7 5728.2 5737.9 5792.7 5795.9 5490.3 5640.5 5642.3 5644.2 5681.1 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | [#1] ext.html Cycles:180 Average:53083.11 Median:41338.19 stddev:95107.34 (230.1%) stddev-sans-first:95292.54 22:20:18 INFO - PROCESS | 16130 | Values: 609.4 637.3 641.4 652.7 656.2 33397.0 43539.0 44306.0 44308.3 48502.3 44670.8 44787.8 45128.0 45446.8 45472.0 8740.1 8765.0 8864.7 8875.6 8879.8 8414.9 8761.4 8766.8 8793.4 8801.8 8836.1 8854.7 8859.3 8865.6 8882.3 8788.7 8834.2 8834.9 8840.2 8841.0 8686.1 8769.5 8769.9 8777.5 8782.0 41981.0 41998.0 42304.4 42317.4 42413.2 3272.5 3321.4 3336.7 3701.3 3824.6 31606.0 31996.4 32241.0 32347.0 33353.5 4100.2 4185.6 4211.9 4275.6 4361.7 279333.6 279742.0 279862.0 280038.0 280308.0 51726.0 54718.7 55450.4 55480.0 55555.4 50761.2 55886.7 56021.0 58952.7 59458.1 16656.4 16884.3 16910.2 16911.7 16932.4 13037.1 13184.1 13211.7 13231.7 13233.0 14474.6 15493.8 15580.3 15761.1 16422.5 6024.3 6024.5 6040.8 6115.4 6128.1 1056.2 1107.7 1114.8 1117.6 1148.1 30997.7 31726.4 31839.4 31880.8 32323.2 1100.1 1109.1 1120.4 1189.1 1193.0 16434.1 17027.0 17220.3 17251.2 17441.7 425854.0 426821.0 426877.0 428727.0 429144.0 57592.7 57595.0 57617.4 57641.7 57719.7 1380.7 1427.5 1432.7 1433.8 1435.6 29748.7 30430.6 30532.7 31182.5 31268.4 49414.8 49579.3 49644.5 49725.3 49780.5 359890.0 361512.0 361740.0 363564.0 364179.0 39238.2 39730.4 41344.4 41620.4 41635.6 47903.5 48508.8 48726.3 49291.8 49731.8 39293.8 40142.0 41047.6 41564.0 41684.8 39176.4 39338.8 40045.0 40262.0 40543.4 40125.8 40387.2 40533.4 41332.0 41372.6 34860.0 35761.5 36139.3 36297.3 36427.1 26434.9 26969.2 27142.0 27155.5 27562.6 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | [#2] jquery.html Cycles:180 Average:29706.55 Median:14848.68 stddev:79465.26 (535.2%) stddev-sans-first:79657.69 22:20:18 INFO - PROCESS | 16130 | Values: 389.2 405.9 407.6 413.7 426.0 15367.8 18895.3 18924.6 19065.0 19092.8 12242.3 12909.7 12960.1 13308.5 18818.5 5189.6 5236.9 5292.9 5300.5 5349.3 3896.7 6854.0 7264.9 7282.6 7302.8 7320.5 7344.4 7390.1 7400.2 7416.9 7025.5 7109.3 7200.4 7364.6 7388.3 6836.6 6978.5 6997.5 7025.1 7028.9 24873.0 25265.0 25298.0 25407.6 25879.4 2644.2 2682.4 2692.2 2728.7 2736.8 15228.8 15781.4 15790.5 15803.8 16310.8 1956.3 2027.6 2170.3 2259.3 2314.5 96664.0 99944.5 99975.0 100311.0 101882.0 14584.0 17392.6 17419.3 17717.7 17859.8 32641.7 32701.0 32864.8 33256.8 33262.3 9383.0 9758.7 9926.3 10002.7 10047.0 7756.5 7778.0 8230.7 8390.2 8499.2 8691.5 8904.7 9068.2 9105.4 9167.8 3333.0 3519.6 3522.3 3571.3 3648.2 951.5 1000.1 1009.3 1088.8 1115.3 17984.0 18555.1 19116.1 19531.0 19571.6 1022.4 1029.3 1032.9 1034.9 1122.8 10148.2 10759.0 10778.6 10807.9 10822.1 471455.0 474671.0 484986.0 487540.0 488430.0 52760.5 63148.4 92136.0 92791.0 93760.0 1024.7 1058.3 1058.8 1089.1 1089.6 16460.5 16696.9 16760.8 17008.7 17137.2 27005.2 30392.1 37755.6 39764.0 40580.0 57515.4 57790.7 58569.4 58691.7 59572.1 7649.7 7875.4 7986.8 8101.7 8876.6 12611.6 12819.9 13373.2 13452.7 13479.3 10456.2 10921.3 11032.7 11381.8 11545.7 11070.4 11405.1 11441.5 11539.7 11980.8 11275.2 11299.3 11507.9 11841.5 11949.8 7387.1 7437.2 7524.8 7542.0 7554.5 10765.6 10807.1 10874.4 11060.5 11114.1 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | [#3] mootools.html Cycles:180 Average:5592.67 Median:4783.77 stddev:3176.81 (66.4%) stddev-sans-first:3169.83 22:20:18 INFO - PROCESS | 16130 | Values: 1365.0 1523.8 1568.0 1717.5 1816.9 8013.7 8366.4 8385.1 8620.8 8621.3 8349.6 8538.0 8560.6 8561.4 8562.4 2030.1 2083.0 2293.7 2328.9 2373.6 2397.5 2420.3 2421.0 2428.7 2436.4 2336.1 2354.1 2371.7 2372.7 2374.9 2040.9 2110.4 2123.7 2206.3 2227.4 2274.1 2297.3 2304.2 2306.7 2311.0 8357.9 8414.2 8439.2 8454.9 8468.6 4998.0 5105.7 5149.9 5160.4 5175.8 3277.7 3314.3 3416.3 3437.2 3453.2 1068.3 1072.2 1095.5 1145.5 1159.1 11903.3 11965.4 11999.3 12012.3 12053.0 4579.3 4658.9 4659.7 4779.1 4782.3 10014.0 10027.4 10028.8 10043.4 10047.1 5110.3 5129.0 5152.8 5159.3 5159.8 4255.1 4621.1 4695.8 4713.5 4785.3 4042.0 4061.9 4078.6 4084.7 4104.1 1326.7 1335.4 1335.7 1338.0 1349.4 780.2 835.1 851.7 862.0 872.3 5455.5 5551.1 5563.5 5655.5 5679.3 1005.4 1043.4 1044.9 1049.0 1098.2 3531.8 3603.2 3704.3 3707.5 3760.5 10632.5 10944.8 11112.9 11115.9 11126.7 10796.2 10940.0 10953.5 10970.0 10995.6 7403.8 7772.3 7861.4 8126.9 8146.7 5261.0 5530.3 5662.5 5709.9 5717.5 9343.3 9482.8 9509.0 9533.3 9787.2 9499.9 9675.9 9719.6 9784.0 9881.2 6344.1 6911.2 7050.8 7559.4 7721.0 7816.4 8034.5 8084.4 8092.2 8107.6 7211.2 7305.6 7382.1 7415.6 7509.5 6895.5 7001.5 7078.2 7129.8 7207.9 6341.8 6374.6 6408.9 6414.4 6451.2 4725.2 4755.2 4881.4 4884.4 4943.0 4848.6 4860.1 4941.8 4953.3 5036.9 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | [#4] prototype.html Cycles:180 Average:4611.23 Median:5283.06 stddev:1340.55 (25.4%) stddev-sans-first:1305.45 22:20:18 INFO - PROCESS | 16130 | Values: 342.3 345.2 350.7 351.8 354.8 5196.8 5302.6 5316.3 5332.3 5349.9 5094.8 5270.5 5282.2 5283.9 5295.9 3930.7 3950.7 4004.3 4010.3 4107.0 4066.8 4109.4 4121.9 4125.3 4131.6 4090.8 4098.3 4099.3 4103.5 4111.4 3908.7 3925.8 4083.4 4095.4 4122.7 3655.2 3666.0 3681.5 3683.9 3697.5 5020.2 5021.2 5033.9 5056.7 5061.3 4546.5 4673.4 4687.5 4709.0 4725.3 5368.0 5384.7 5405.5 5420.4 5423.2 4047.3 4058.5 4077.4 4106.9 4130.3 5583.8 5674.5 5680.6 5705.9 5709.9 4143.7 4150.0 4159.2 4162.6 4163.2 4175.7 4196.4 4198.7 4218.8 4235.0 4582.3 4686.8 4700.1 4723.0 4725.6 5442.7 5443.4 5454.6 5467.8 5472.8 1386.9 1391.0 1398.1 1411.0 1411.0 939.6 961.8 980.5 991.1 1027.0 5086.0 5086.4 5103.9 5106.8 5113.6 5324.3 5331.0 5365.2 5378.3 5418.7 4891.8 5010.0 5091.9 5100.0 5100.2 2808.6 2825.4 2841.8 2856.3 2862.4 5505.7 5527.2 5537.7 5567.4 5597.7 5425.8 5565.1 5728.0 5740.5 5759.9 5842.9 5862.8 5864.7 5867.6 5869.9 5579.2 5662.8 5694.2 5696.3 5705.5 5631.2 5633.9 5655.6 5657.9 5663.7 5849.8 5876.6 5883.9 5897.8 5908.9 3924.8 4018.3 4032.6 4055.6 4070.9 5528.9 5589.9 5595.4 5662.2 5667.6 5571.5 5576.1 5576.9 5584.4 5602.1 5297.5 5449.3 5457.3 5468.0 5532.6 5167.9 5369.7 5384.8 5401.6 5429.6 5402.0 5492.3 5505.6 5509.4 5523.6 5335.9 5355.3 5374.1 5384.5 5396.8 22:20:18 INFO - PROCESS | 16130 | 22:20:18 INFO - PROCESS | 16130 | [#5] yui.html Cycles:180 Average:14503.78 Median:4446.02 stddev:16539.61 (372.0%) stddev-sans-first:16553.55 22:20:18 INFO - PROCESS | 16130 | Values: 705.8 722.7 729.7 733.1 741.2 12802.1 13032.7 13403.8 13465.3 13496.3 12442.9 12462.3 12465.3 12490.7 12526.1 685.8 821.7 830.5 834.8 835.9 794.3 830.3 842.0 843.2 843.5 870.7 874.7 875.8 875.8 876.3 719.1 850.6 869.6 870.6 871.7 634.8 635.3 635.3 642.6 644.6 12026.8 12051.0 12052.6 12060.3 12067.0 13429.3 13739.2 14194.6 14255.9 14361.9 12472.8 12549.4 12551.7 12699.4 12967.4 4472.7 4495.4 4542.9 4544.6 4628.9 55720.0 55736.2 55843.7 56459.2 57262.7 10741.5 10893.3 11058.3 11068.3 11089.3 18685.0 18866.2 19049.9 19059.0 19066.0 7748.9 7853.7 7858.7 8044.7 8171.4 4581.0 4610.5 4625.4 4637.1 4744.3 3127.6 3173.2 3180.3 3203.6 3220.3 622.4 622.6 627.2 646.7 647.4 603.4 612.4 637.6 642.7 649.1 8705.5 8882.6 9140.7 9174.4 10197.0 551.4 637.9 649.1 652.3 652.8 4178.7 4194.8 4282.7 4283.0 4419.3 57495.0 58786.0 59111.4 59374.7 61727.7 52101.5 53083.7 53116.2 53158.2 55233.0 45919.5 46421.0 46967.3 48840.0 49646.3 9251.7 9311.0 9418.4 9464.5 9817.8 32264.7 32404.8 33051.7 33432.0 33487.3 41673.0 42198.0 42357.4 42834.6 43214.0 11979.6 12615.6 12713.1 12928.4 13312.4 13196.6 13214.7 13344.9 13458.1 14111.7 12604.6 12773.1 12780.3 12920.7 12969.6 12367.8 12431.8 12636.2 12753.7 12815.2 12035.8 12296.9 12523.6 12820.6 12884.3 10680.6 10715.0 10965.1 10974.6 11619.8 10700.9 10922.0 11175.4 11266.8 11421.0 22:20:18 INFO - PROCESS | 16130 | -------- Summary: end -------- 22:20:18 INFO - PROCESS | 16130 | 22:20:19 INFO - TEST-INFO | 16130: exit 0 22:20:19 INFO - TEST-OK | dromaeo_css | took 1111997ms 22:20:19 INFO - TEST-START | kraken 22:20:19 INFO - operating with platform_type : linux_ 22:20:19 INFO - Initialising browser for kraken test... 22:20:19 INFO - TEST-INFO | started process 16315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVQWrNx/profile http://localhost:56697/getInfo.html) 22:20:23 INFO - PROCESS | 16315 | __metrics Screen width/height:1600/1200 22:20:23 INFO - PROCESS | 16315 | colorDepth:24 22:20:23 INFO - PROCESS | 16315 | Browser inner width/height: 1024/697 22:20:23 INFO - PROCESS | 16315 | __metrics 22:20:24 ERROR - PROCESS | 16315 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:20:25 INFO - TEST-INFO | 16315: exit 0 22:20:25 INFO - Browser initialized. 22:20:25 INFO - Running cycle 1/1 for kraken test... 22:20:25 INFO - TEST-INFO | started process 16396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVQWrNx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:37 INFO - PROCESS | 16396 | RSS: Main: 152367104 22:20:37 INFO - PROCESS | 16396 | 22:20:39 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56697/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:40 INFO - PROCESS | 16396 | RSS: Main: 208035840 22:20:40 INFO - PROCESS | 16396 | 22:20:43 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56697/tests/kraken/driver.html?testName=audio-dft) 22:20:43 INFO - PROCESS | 16396 | RSS: Main: 197500928 22:20:43 INFO - PROCESS | 16396 | 22:20:47 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56697/tests/kraken/driver.html?testName=audio-fft) 22:20:47 INFO - PROCESS | 16396 | RSS: Main: 265682944 22:20:47 INFO - PROCESS | 16396 | 22:20:49 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56697/tests/kraken/driver.html?testName=audio-oscillator) 22:20:50 INFO - PROCESS | 16396 | RSS: Main: 174649344 22:20:50 INFO - PROCESS | 16396 | 22:20:52 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56697/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:53 INFO - PROCESS | 16396 | RSS: Main: 179298304 22:20:53 INFO - PROCESS | 16396 | 22:20:56 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56697/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:57 INFO - PROCESS | 16396 | RSS: Main: 215126016 22:20:57 INFO - PROCESS | 16396 | 22:21:00 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56697/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:01 INFO - PROCESS | 16396 | RSS: Main: 213671936 22:21:01 INFO - PROCESS | 16396 | 22:21:04 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56697/tests/kraken/driver.html?testName=json-parse-financial) 22:21:04 INFO - PROCESS | 16396 | RSS: Main: 214179840 22:21:04 INFO - PROCESS | 16396 | 22:21:06 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56697/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:07 INFO - PROCESS | 16396 | RSS: Main: 204386304 22:21:07 INFO - PROCESS | 16396 | 22:21:08 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:09 INFO - PROCESS | 16396 | RSS: Main: 208637952 22:21:09 INFO - PROCESS | 16396 | 22:21:11 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:12 INFO - PROCESS | 16396 | RSS: Main: 217628672 22:21:12 INFO - PROCESS | 16396 | 22:21:15 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:15 INFO - PROCESS | 16396 | RSS: Main: 222085120 22:21:15 INFO - PROCESS | 16396 | 22:21:18 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:19 INFO - PROCESS | 16396 | RSS: Main: 218992640 22:21:19 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | Cycle 1(1): loaded http://localhost:56697/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56697/tests/kraken/driver.html?testName=ai-astar) 22:21:21 INFO - PROCESS | 16396 | RSS: Main: 264224768 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | __start_tp_report 22:21:21 INFO - PROCESS | 16396 | _x_x_mozilla_page_load 22:21:21 INFO - PROCESS | 16396 | _x_x_mozilla_page_load_details 22:21:21 INFO - PROCESS | 16396 | |i|pagename|runs| 22:21:21 INFO - PROCESS | 16396 | |0;ai-astar;116;107;111;112;112;113;110;112;114;114 22:21:21 INFO - PROCESS | 16396 | |1;audio-beat-detection;159;142;154;216;127;159;160;126;190;127 22:21:21 INFO - PROCESS | 16396 | |2;audio-dft;162;162;162;163;161;162;161;160;162;161 22:21:21 INFO - PROCESS | 16396 | |3;audio-fft;76;76;76;151;77;76;76;75;135;76 22:21:21 INFO - PROCESS | 16396 | |4;audio-oscillator;94;93;146;94;95;94;130;94;94;126 22:21:21 INFO - PROCESS | 16396 | |5;imaging-gaussian-blur;116;117;116;116;116;117;117;117;117;117 22:21:21 INFO - PROCESS | 16396 | |6;imaging-darkroom;148;147;147;145;146;147;146;146;145;147 22:21:21 INFO - PROCESS | 16396 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 22:21:21 INFO - PROCESS | 16396 | |8;json-parse-financial;56;57;57;56;57;58;58;110;55;55 22:21:21 INFO - PROCESS | 16396 | |9;json-stringify-tinderbox;61;61;61;62;61;62;62;67;62;61 22:21:21 INFO - PROCESS | 16396 | |10;stanford-crypto-aes;79;84;82;80;80;81;81;81;85;81 22:21:21 INFO - PROCESS | 16396 | |11;stanford-crypto-ccm;131;132;125;141;125;124;132;127;242;132 22:21:21 INFO - PROCESS | 16396 | |12;stanford-crypto-pbkdf2;174;173;167;168;170;168;165;168;166;170 22:21:21 INFO - PROCESS | 16396 | |13;stanford-crypto-sha256-iterative;66;67;67;65;65;65;64;63;65;63 22:21:21 INFO - PROCESS | 16396 | __end_tp_report 22:21:21 INFO - PROCESS | 16396 | __start_cc_report 22:21:21 INFO - PROCESS | 16396 | _x_x_mozilla_cycle_collect,1844 22:21:21 INFO - PROCESS | 16396 | __end_cc_report 22:21:21 INFO - PROCESS | 16396 | __startTimestamp1462771281283__endTimestamp 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | ------- Summary: start ------- 22:21:21 INFO - PROCESS | 16396 | Number of tests: 14 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.50 stddev:2.47 (2.2%) stddev-sans-first:2.18 22:21:21 INFO - PROCESS | 16396 | Values: 116.0 107.0 111.0 112.0 112.0 113.0 110.0 112.0 114.0 114.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#1] audio-beat-detection Cycles:10 Average:156.00 Median:159.00 stddev:29.01 (18.2%) stddev-sans-first:30.74 22:21:21 INFO - PROCESS | 16396 | Values: 159.0 142.0 154.0 216.0 127.0 159.0 160.0 126.0 190.0 127.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 22:21:21 INFO - PROCESS | 16396 | Values: 162.0 162.0 162.0 163.0 161.0 162.0 161.0 160.0 162.0 161.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#3] audio-fft Cycles:10 Average:89.40 Median:76.00 stddev:28.50 (37.5%) stddev-sans-first:29.82 22:21:21 INFO - PROCESS | 16396 | Values: 76.0 76.0 76.0 151.0 77.0 76.0 76.0 75.0 135.0 76.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#4] audio-oscillator Cycles:10 Average:106.00 Median:94.00 stddev:19.96 (21.2%) stddev-sans-first:20.69 22:21:21 INFO - PROCESS | 16396 | Values: 94.0 93.0 146.0 94.0 95.0 94.0 130.0 94.0 94.0 126.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:21:21 INFO - PROCESS | 16396 | Values: 116.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.97 (0.7%) stddev-sans-first:0.83 22:21:21 INFO - PROCESS | 16396 | Values: 148.0 147.0 147.0 145.0 146.0 147.0 146.0 146.0 145.0 147.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:21:21 INFO - PROCESS | 16396 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:57.00 stddev:16.93 (29.7%) stddev-sans-first:17.83 22:21:21 INFO - PROCESS | 16396 | Values: 56.0 57.0 57.0 56.0 57.0 58.0 58.0 110.0 55.0 55.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:1.83 (2.9%) stddev-sans-first:1.90 22:21:21 INFO - PROCESS | 16396 | Values: 61.0 61.0 61.0 62.0 61.0 62.0 62.0 67.0 62.0 61.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:1.84 (2.3%) stddev-sans-first:1.73 22:21:21 INFO - PROCESS | 16396 | Values: 79.0 84.0 82.0 80.0 80.0 81.0 81.0 81.0 85.0 81.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:132.00 stddev:35.81 (27.1%) stddev-sans-first:37.79 22:21:21 INFO - PROCESS | 16396 | Values: 131.0 132.0 125.0 141.0 125.0 124.0 132.0 127.0 242.0 132.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.00 stddev:2.88 (1.7%) stddev-sans-first:2.40 22:21:21 INFO - PROCESS | 16396 | Values: 174.0 173.0 167.0 168.0 170.0 168.0 165.0 168.0 166.0 170.0 22:21:21 INFO - PROCESS | 16396 | 22:21:21 INFO - PROCESS | 16396 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:1.41 (2.2%) stddev-sans-first:1.45 22:21:21 INFO - PROCESS | 16396 | Values: 66.0 67.0 67.0 65.0 65.0 65.0 64.0 63.0 65.0 63.0 22:21:21 INFO - PROCESS | 16396 | -------- Summary: end -------- 22:21:21 INFO - PROCESS | 16396 | 22:21:22 INFO - TEST-INFO | 16396: exit 0 22:21:22 INFO - TEST-OK | kraken | took 62733ms 22:21:22 INFO - SUITE-END | took 1174s 22:21:22 INFO - Completed test suite (00:19:34) 22:21:22 INFO - javascript benchmark 22:21:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.452695778238, 3179.284660951285, 3189.266468712196, 3206.663300353461, 3231.9173077019195, 5253.133233016548, 5265.236540701606, 5409.404965453802, 5477.726113694314, 5516.3931967483595, 5347.786088556462, 5368.221607866857, 5392.595555333346, 5401.70290634015, 5446.074167391543, 4820.614350851933, 4882.755862206894, 4908.484609116045, 4916.0, 5096.337476128117, 4949.851504454872, 5011.373578302712, 5051.924221136685, 5061.240813877929, 5068.366454193226, 4757.500462451445, 4800.231962885939, 4803.687760295569, 4825.155597770411, 4885.291632713237, 4811.061842940628, 4833.791552111972, 4913.557779799835, 4931.950680493185, 4973.030259099477, 4807.495213002654, 4878.804847806084, 4883.316335713003, 4920.21276595743, 4942.728149951751, 5243.606729495303, 5292.311999440087, 5296.602754793406, 5371.979323928441, 5383.75773090208, 5725.6278341908055, 5739.684317362585, 5744.368119506851, 5757.337906140806, 5763.654180749127, 5651.321841379061, 5729.598928075036, 5742.712864356766, 5767.307923049219, 5781.71091445426, 5482.369527504348, 5483.8080667176855, 5485.122380419114, 5502.394736578973, 5511.779528818883, 5944.464998150187, 5946.256717910262, 5960.225170727865, 5964.8807023860145, 5976.372480889531, 5503.6147469677535, 5619.7471113799975, 5659.490645841894, 5660.943390566124, 5702.942970570324, 5777.653340799566, 5784.8719499698, 5785.132230165509, 5835.5915085944425, 5855.180274761537, 5725.570582206351, 5750.763585828983, 5754.136879468029, 5761.769529218785, 5768.423157684198, 5530.446955304437, 5566.749496272678, 5578.525825304813, 5613.7193140342815, 5617.241672374177, 4220.599043090901, 4229.13302772937, 4234.407182994383, 4243.915121697549, 4271.402003719481, 3788.962110378861, 3832.7125465590193, 3847.172857835523, 3847.8076096195077, 3880.049387899926, 5383.461653834664, 5430.375506816687, 5430.484104010113, 5448.291722076184, 5456.836294911159, 5747.827565173052, 5755.59710820247, 5776.786874756348, 5781.566382521328, 5784.537237021028, 5364.624476286701, 5418.729063546807, 5431.375391829911, 5433.646812957145, 5441.564674826004, 4857.587105096107, 4873.439554451284, 4881.365422495053, 4890.462049174588, 4939.456659767423, 6014.879702405928, 6017.909731354033, 6018.729157187937, 6025.457708806229, 6040.063790112539, 5594.0, 5740.512056475246, 5785.190073389813, 5820.330661973927, 5890.440408161217, 6025.487833534024, 6043.8186854394435, 6045.335013148462, 6047.879042419215, 6052.515798736179, 5705.771769129188, 5728.2266893970445, 5747.051736463437, 5747.252857128547, 5750.769969201352, 5679.0, 5739.827805165852, 5768.394318596487, 5823.912641310469, 5837.182794408701, 5680.886382272414, 5682.715864206691, 5688.40271771458, 5695.345035320991, 5699.487046165865, 5640.830775076754, 5678.233438485917, 5681.545476361964, 5686.346070201815, 5695.544356451391, 5344.652597581145, 5489.807856724996, 5514.393416724238, 5564.276644036249, 5601.719914004202, 5718.742656580381, 5728.226689396878, 5737.885242295048, 5792.7103644818435, 5795.913061304, 5490.258815059916, 5640.464155905182, 5642.322921249476, 5644.153376993484, 5681.147827825859], "value": 5311.603670239547, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.4119174996127, 637.2703254773285, 641.4419455074084, 652.6671397587229, 656.1568384625757, 33397.0, 43539.0, 44306.0, 44308.33537496936, 48502.27246591295, 44670.77664611673, 44787.77606111965, 45128.0, 45446.77276613612, 45472.0, 8740.082291359427, 8765.0, 8864.73405797827, 8875.556222188865, 8879.77800554988, 8414.873776893352, 8761.430507017056, 8766.824663506726, 8793.428427152247, 8801.823963520726, 8836.116388361144, 8854.734357969272, 8859.29125669945, 8865.556722163898, 8882.333824963122, 8788.736337909873, 8834.160754728291, 8834.86747698785, 8840.160184782435, 8841.027486976427, 8686.131386861327, 8769.473831570127, 8769.868451973225, 8777.517236551987, 8781.956090219572, 41981.0, 41998.0, 42304.365434518506, 42317.36523952143, 42413.151736965396, 3272.4764037753844, 3321.4187517184396, 3336.666333366644, 3701.2782507411066, 3824.617538246181, 31606.0, 31996.360072798412, 32241.0, 32347.0, 33353.49969750456, 4100.2414553307735, 4185.622392039089, 4211.936820947688, 4275.5724427557, 4361.651067914591, 279333.6033319841, 279742.0, 279862.0, 280038.0, 280308.0, 51726.0, 54718.726406367714, 55450.44549554533, 55480.0, 55555.44444555583, 50761.238581421305, 55886.72056639772, 56021.0, 58952.70523647354, 59458.10812837811, 16656.41702540405, 16884.324627015027, 16910.239039243264, 16911.746323804906, 16932.40736574214, 13037.087403881636, 13184.143030702973, 13211.66970825737, 13231.73536529264, 13233.007524435707, 14474.638134046732, 15493.767593485882, 15580.298886549906, 15761.133137677538, 16422.507324780036, 6024.252966639626, 6024.457798798129, 6040.818775436656, 6115.419035191739, 6128.142060111588, 1056.1814593689799, 1107.7064577886867, 1114.793763153819, 1117.586492997596, 1148.121686909515, 30997.69002309948, 31726.36547269088, 31839.36321273608, 31880.84059579687, 32323.191920201712, 1100.0759362135843, 1109.0794640448407, 1120.361394005409, 1189.1319336884123, 1192.9821052684217, 16434.103914393076, 17027.0, 17220.31118755261, 17251.223694933757, 17441.7383739244, 425854.0, 426821.0, 426877.0, 428727.0, 429144.0, 57592.71203643955, 57595.0, 57617.423825762045, 57641.71179144077, 57719.71140144272, 1380.6962468256968, 1427.5075099090798, 1432.684809341964, 1433.7634290342185, 1435.6339133521094, 29748.702512974593, 30430.56005179617, 30532.694673052985, 31182.532262016543, 31268.374632506766, 49414.75292623514, 49579.25631115608, 49644.50355496399, 49725.25412118893, 49780.50658480251, 359890.0, 361512.0, 361740.0, 363564.0, 364179.0, 39238.21523569455, 39730.40404393994, 41344.37983430311, 41620.375694364004, 41635.583644164384, 47903.5209647913, 48508.757456212494, 48726.26910596273, 49291.753541232065, 49731.75134124306, 39293.803530982164, 40142.0, 41047.589524104376, 41564.0, 41684.79157604193, 39176.412353815285, 39338.80330598329, 40045.0, 40262.0, 40543.391849122876, 40125.799371002955, 40387.1922561553, 40533.39199911945, 41332.0, 41372.58627413688, 34860.0, 35761.46357804583, 36139.27721445504, 36297.27405451929, 36427.08932276714, 26434.86782566075, 26969.224308597204, 27142.0, 27155.456890862468, 27562.5865612012], "value": 19701.797835718615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.188541890159, 405.8741790045086, 407.6046235151903, 413.70833562338544, 425.9872203833886, 15367.769483457743, 18895.33866314679, 18924.621507569875, 19065.0, 19092.809071909258, 12242.265464072161, 12909.74180516389, 12960.092793504458, 13308.46766129356, 18818.529536761587, 5189.6107791915565, 5236.869078273055, 5292.867678308054, 5300.496452836975, 5349.251104845305, 3896.7467114637598, 6854.0, 7264.891026634605, 7282.599457029861, 7302.81742956425, 7320.524165929224, 7344.449166312521, 7390.076240469942, 7400.185979542246, 7416.925830741678, 7025.543339682904, 7109.324614161646, 7200.423966082753, 7364.5949472778975, 7388.261173882595, 6836.589804611739, 6978.546394484393, 6997.475189360767, 7025.086738723933, 7028.92971070293, 24873.0, 25265.0, 25298.0, 25407.61888571673, 25879.35301617456, 2644.2067379785985, 2682.369643133862, 2692.17888543994, 2728.740769626902, 2736.849473278969, 15228.771568426482, 15781.368745250176, 15790.526284211492, 15803.841961580352, 16310.75533866993, 1956.344624550777, 2027.6147531969027, 2170.316350349635, 2259.344790010895, 2314.5370925814905, 96664.0, 99944.50027749887, 99975.0, 100311.0, 101882.0, 14584.025838075617, 17392.565185870455, 17419.25807419248, 17717.7342339865, 17859.821401786077, 32641.67358326434, 32701.0, 32864.835675821465, 33256.833715831264, 33262.33475330528, 9382.95308523453, 9758.658446954323, 9926.30515863897, 10002.749931251778, 10047.0, 7756.457048006585, 7778.0, 8230.711925082593, 8390.202930721694, 8499.150084991576, 8691.478511289342, 8904.688335908211, 9068.183863452321, 9105.362624616346, 9167.816643667089, 3332.950005749916, 3519.648035196511, 3522.330757156132, 3571.285742851388, 3648.1791596891017, 951.5432592355708, 1000.1049061090283, 1009.268280496634, 1088.7931293054453, 1115.3252282369212, 17984.0, 18555.072246387626, 19116.139773710216, 19531.0, 19571.608567828564, 1022.4478781458079, 1029.2537910015178, 1032.9276950613385, 1034.8551202831609, 1122.7586068995206, 10148.238882083873, 10759.0, 10778.622748203776, 10807.891921080845, 10822.134229261641, 471455.0, 474671.0, 484986.0, 487540.0, 488430.0, 52760.47239527632, 63148.36851631425, 92136.0, 92791.0, 93760.0, 1024.6516184497157, 1058.25921854702, 1058.7564860082377, 1089.0688461365576, 1089.5859573362072, 16460.506184814476, 16696.916515417346, 16760.832391675925, 17008.65982680315, 17137.22882470317, 27005.189844304703, 30392.088237352913, 37755.62244377631, 39764.0, 40580.0, 57515.424845751004, 57790.7110464445, 58569.4143058581, 58691.70654146702, 59572.106418404626, 7649.732259370895, 7875.409344299086, 7986.800329991796, 8101.716439924574, 8876.556172191236, 12611.621651350475, 12819.871801281868, 13373.197608143542, 13452.663683407993, 13479.32603369808, 10456.215783816093, 10921.290116142425, 11032.724181895517, 11381.772364552828, 11545.711357216136, 11070.446477675923, 11405.144614153805, 11441.542338306375, 11539.711507212387, 11980.820287695517, 11275.210735248455, 11299.265547739371, 11507.942460287644, 11841.46713397882, 11949.820752688891, 7387.076615423072, 7437.181909989788, 7524.774256772305, 7541.962290188514, 7554.508956917783, 10765.623203187852, 10807.08139808125, 10874.401907895142, 11060.502277397698, 11114.110871130451], "value": 10151.412738373469, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.9863501364996, 1523.8038140060053, 1567.9764803527949, 1717.5362652083938, 1816.9364072257465, 8013.719519816807, 8366.414350995432, 8385.11956244594, 8620.827583448327, 8621.267192288647, 8349.582520873962, 8538.0, 8560.614772335244, 8561.443506172094, 8562.443441176334, 2030.0560239488634, 2082.979170208301, 2293.6674182243564, 2328.9417764555897, 2373.5727569037567, 2397.4965257295903, 2420.2860156253946, 2421.019487107724, 2428.720697119836, 2436.3909022744315, 2336.100601268507, 2354.1289722802585, 2371.715394152704, 2372.6559648850907, 2374.9406264843346, 2040.9387718368473, 2110.356341315898, 2123.7451505819245, 2206.2719302630176, 2227.376334626307, 2274.1471948019494, 2297.287840769367, 2304.2165663674264, 2306.7116610423695, 2311.0, 8357.874631880526, 8414.158584141598, 8439.240468357877, 8454.87317690235, 8468.618912148968, 4997.975010124963, 5105.668131571436, 5149.897002059975, 5160.354955630546, 5175.81884634036, 3277.655846136169, 3314.303996160785, 3416.3167366526795, 3437.1750779812915, 3453.2057626745736, 1068.2575609951086, 1072.1958531101673, 1095.5179720922768, 1145.4501839117188, 1159.0669511043607, 11903.345316007617, 11965.401729913556, 11999.340036298001, 12012.279263244145, 12053.0, 4579.335996280521, 4658.86023419298, 4659.6505262105475, 4779.0680817241, 4782.258749893772, 10014.0, 10027.448490332956, 10028.849567256497, 10043.397396156253, 10047.145992590711, 5110.310108135354, 5129.0, 5152.793888244429, 5159.329287192641, 5159.819406320761, 4255.085156691326, 4621.052684199798, 4695.835645752451, 4713.505081966413, 4785.282207668877, 4041.9797901010893, 4061.9394011078584, 4078.5928854545136, 4084.7344922579937, 4104.117614712792, 1326.694860182144, 1335.4057444437133, 1335.6794369351285, 1337.9965026230327, 1349.4332380400256, 780.1691198873182, 835.1189495082625, 851.7019043334783, 862.017300277684, 872.31959071924, 5455.509004189563, 5551.084071128219, 5563.471470210324, 5655.519280861173, 5679.290088738908, 1005.4017859373807, 1043.415687215162, 1044.9059584637266, 1049.0, 1098.2477003252798, 3531.788092714445, 3603.1892824114366, 3704.3147017801793, 3707.4994875691978, 3760.4958016792953, 10632.46837658114, 10944.781104378028, 11112.888871111185, 11115.888841111486, 11126.721831954266, 10796.244262901522, 10940.0, 10953.507092180871, 10970.0, 10995.560177592806, 7403.8149046274275, 7772.339351155102, 7861.449698521049, 8126.918730812735, 8146.714864979697, 5261.0, 5530.281063461885, 5662.462066103713, 5709.942900570941, 5717.542596592346, 9343.34596578233, 9482.762930926781, 9508.952455237679, 9533.332666713264, 9787.16809071237, 9499.857502137611, 9675.9032409675, 9719.611215551298, 9784.0, 9881.160101391462, 6344.111824344688, 6911.239763626098, 7050.753223637148, 7559.433042521723, 7720.961395192988, 7816.374690024671, 8034.47775894565, 8084.353251739729, 8092.231238032377, 8107.594620269081, 7211.1707153678, 7305.634718263959, 7382.114146302478, 7415.592142432218, 7509.549427034395, 6895.482838787211, 7001.4748893834185, 7078.1860086090755, 7129.7861064168155, 7207.9279207207255, 6341.778037768656, 6374.6175229484525, 6408.871822563616, 6414.390632889868, 6451.225852897683, 4725.196716558262, 4755.239161734106, 4881.389826271716, 4884.438289596742, 4943.036107958914, 4848.5636292733825, 4860.149473841992, 4941.75291235444, 4953.331300274417, 5036.949630503648], "value": 4503.012552675686, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.3358684152744, 345.16297977404787, 350.7106637024456, 351.7678332300682, 354.81726910641027, 5196.844094677161, 5302.6213184572, 5316.308879845625, 5332.33345831771, 5349.946500534994, 5094.847154585359, 5270.4729527047275, 5282.23407605897, 5283.894322113555, 5295.894082118355, 3930.7445016073934, 3950.743201691881, 4004.299247631667, 4010.258102251078, 4106.958930410688, 4066.759638310314, 4109.363048727452, 4121.8969525761795, 4125.288005477738, 4131.586841315874, 4090.836366545335, 4098.262312783698, 4099.323611604082, 4103.507579090498, 4111.383292506133, 3908.7459315144565, 3925.8233379497847, 4083.3870621104497, 4095.4471146395153, 4122.711410201287, 3655.159313357916, 3665.963340366589, 3681.5214022177215, 3683.9263214735556, 3697.4655517960014, 5020.196768517057, 5021.246812978046, 5033.949660503421, 5056.721880296583, 5061.279959229963, 4546.454425468932, 4673.415823022122, 4687.484376718558, 4708.952910470885, 4725.3384526166365, 5368.033753924292, 5384.67691938481, 5405.486478784509, 5420.430854760272, 5423.240746295522, 4047.251258517184, 4058.492688413948, 4077.3883917412627, 4106.876793696194, 4130.318497447917, 5583.804566840182, 5674.546036317126, 5680.5514593778325, 5705.885882282372, 5709.857253568653, 4143.7306575072525, 4149.958500414958, 4159.209750147463, 4162.646175075152, 4163.167366526707, 4175.705531285311, 4196.391523229176, 4198.706090573692, 4218.7679677618025, 4234.957650423518, 4582.335561343587, 4686.835960741358, 4700.143443339862, 4723.0, 4725.621950243973, 5442.727863606883, 5443.374011988672, 5454.59090568209, 5467.753951072212, 5472.781088756406, 1386.8682475164844, 1390.9721805563831, 1398.1401438115486, 1410.9506167284096, 1410.957671269843, 939.6147579492512, 961.8364877970762, 980.4705459052032, 991.1377101921374, 1027.0038063078803, 5086.008228395502, 5086.440491545927, 5103.897922041539, 5106.821261255838, 5113.642045056885, 5324.281222035053, 5331.0, 5365.22204280385, 5378.273933019071, 5418.674879507243, 4891.755412229374, 5010.04407399636, 5091.8981620367385, 5099.974500127476, 5100.209467532464, 2808.648918885139, 2825.39254060378, 2841.8010739248466, 2856.2573730830136, 2862.3845873137375, 5505.6696598204235, 5527.198556209408, 5537.726322945745, 5567.359753628384, 5597.720113994284, 5425.810096646598, 5565.0817615093865, 5727.971360143172, 5740.512056475246, 5759.884802303931, 5842.919059973919, 5862.824115276378, 5864.736086876101, 5867.618604790675, 5869.853253668693, 5579.163125531203, 5662.773489060556, 5694.17434471991, 5696.287964004499, 5705.515031222227, 5631.239782629456, 5633.8591535211945, 5655.575831812548, 5657.906574567436, 5663.66018038919, 5849.824505264849, 5876.620942957902, 5883.941160588511, 5897.7935772247765, 5908.911366329595, 3924.843006279717, 4018.312308830357, 4032.6169013943627, 4055.6413601443846, 4070.9185816284103, 5528.889422211453, 5589.916151257653, 5595.35653399848, 5662.207290979349, 5667.603267771216, 5571.498565129158, 5576.076253692469, 5576.944230557805, 5584.441555844383, 5602.075657516464, 5297.523222909803, 5449.3460784706085, 5457.308234447334, 5468.0, 5532.585056120726, 5167.896642067213, 5369.677819330853, 5384.7576859040655, 5401.6218864679095, 5429.642589352662, 5401.972990135024, 5492.340919089735, 5505.614606977633, 5509.429812503417, 5523.558115350843, 5335.946640533545, 5355.259540649383, 5374.0864053111845, 5384.488473595002, 5396.757145928521], "value": 4192.0946221357635, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.8164877131945, 722.7362012865306, 729.7409419656022, 733.1092722342356, 741.2180149941812, 12802.103852730312, 13032.674183145402, 13403.798943015863, 13465.259410732406, 13496.325183740822, 12442.93778531106, 12462.314572698499, 12465.314407707572, 12490.687732806684, 12526.123171378007, 685.7736946807541, 821.7493664432345, 830.4685001598951, 834.8121672623672, 835.8913341265627, 794.3248238996864, 830.3149901331426, 841.9831603367929, 843.2158092973532, 843.5444859775745, 870.7170169694842, 874.7026011156206, 875.7722992022088, 875.7810547363159, 876.2595606712318, 719.0544434069235, 850.6172222499899, 869.6173683579236, 870.6038752367689, 871.7384784564637, 634.8190765631773, 635.2821311917504, 635.348767513298, 642.604798049201, 644.5971267957526, 12026.819597706042, 12051.0, 12052.578159764365, 12060.336681482515, 12067.0, 13429.328533573282, 13739.175649461064, 14194.645133871736, 14255.857441425556, 14361.900037206995, 12472.750544989049, 12549.435275412628, 12551.729600221797, 12699.365031748468, 12967.351632418342, 4472.709273897219, 4495.393121928563, 4542.931856022163, 4544.636429085698, 4628.907421851578, 55720.0, 55736.163957540666, 55843.72078139583, 56459.153112703345, 57262.713686431714, 10741.462926853626, 10893.346399215914, 11058.336499810037, 11068.335899846035, 11089.27919685218, 18685.0, 18866.24535018584, 19049.90475047616, 19059.04704764784, 19066.04669766506, 7748.883766743504, 7853.725119620786, 7858.685652573948, 8044.678212871537, 8171.387145963958, 4580.972263644458, 4610.53894610543, 4625.398698169217, 4637.095766325601, 4744.335792988984, 3127.62468503781, 3173.174974506645, 3180.25264062945, 3203.599550056243, 3220.2915358621067, 622.3745136138152, 622.5859803230801, 627.2065836716557, 646.7219095788762, 647.3526473526474, 603.3634515586099, 612.3570251236184, 637.5537124013209, 642.6754488983128, 649.1107183159008, 8705.521196334135, 8882.555872206363, 9140.725778226664, 9174.357794954423, 10197.0, 551.4133673272719, 637.9425851673371, 649.0750680280581, 652.3313603556393, 652.8139480248107, 4178.749275043447, 4194.832206711759, 4282.743035417885, 4282.957170428256, 4419.337099435046, 57495.0, 58786.0, 59111.40888591059, 59374.70312648496, 61727.6913615438, 52101.47898521042, 53083.73458132762, 53116.20325695118, 53158.20262696063, 55233.0, 45919.540804591525, 46421.0, 46967.29549056767, 48840.0, 49646.25530617044, 9251.67619133327, 9310.95344523273, 9418.387804792666, 9464.526773661428, 9817.754556135867, 32264.677353227107, 32404.837975810915, 33051.74708072225, 33432.0, 33487.33025339528, 41673.0, 42198.0, 42357.36463953105, 42834.57165428431, 43214.0, 11979.600281169298, 12615.558455454015, 12713.11008229415, 12928.353582321035, 13312.400941957441, 13196.60410187696, 13214.669633259246, 13344.933275333562, 13458.125221860548, 14111.717765644835, 12604.55884044054, 12773.10588258813, 12780.297083660487, 12920.741585168433, 12969.610911672666, 12367.75264494723, 12431.75136497247, 12636.2418254905, 12753.74492510126, 12815.231086134861, 12035.819462707892, 12296.938515307365, 12523.624291270911, 12820.61538153857, 12884.291363974697, 10680.62617808373, 10715.0, 10965.122790176927, 10974.573305470238, 11619.767604648028, 10700.93574547723, 10922.0, 11175.441227938732, 11266.830997535208, 11421.0], "value": 6647.4241036098265, "unit": "score"}], "name": "dromaeo_css", "value": 7147.412284767874, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 107.0, 111.0, 112.0, 112.0, 113.0, 110.0, 112.0, 114.0, 114.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 142.0, 154.0, 216.0, 127.0, 159.0, 160.0, 126.0, 190.0, 127.0], "value": 156.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 161.0, 162.0, 161.0, 160.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 151.0, 77.0, 76.0, 76.0, 75.0, 135.0, 76.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 146.0, 94.0, 95.0, 94.0, 130.0, 94.0, 94.0, 126.0], "value": 106.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 56.0, 57.0, 58.0, 58.0, 110.0, 55.0, 55.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 61.0, 62.0, 62.0, 67.0, 62.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 82.0, 80.0, 80.0, 81.0, 81.0, 81.0, 85.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 125.0, 141.0, 125.0, 124.0, 132.0, 127.0, 242.0, 132.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 167.0, 168.0, 170.0, 168.0, 165.0, 168.0, 166.0, 170.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 65.0, 65.0, 64.0, 63.0, 65.0, 63.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1563.3000000000002, "alertThreshold": 2.0}]} 22:21:22 INFO - Return code: 0 22:21:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:21:22 ERROR - # TBPL SUCCESS # 22:21:22 INFO - Running post-action listener: _resource_record_post_action 22:21:22 INFO - ##### 22:21:22 INFO - ##### Finished run-tests step (success) 22:21:22 INFO - ##### 22:21:22 INFO - Running post-run listener: _resource_record_post_run 22:21:23 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 535953408; Read time: 0; Write time: 573536 22:21:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244764672; Read time: 0; Write time: 276756 22:21:23 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 291131392; Read time: 0; Write time: 296760 22:21:23 INFO - Running post-run listener: _upload_blobber_files 22:21:23 INFO - Blob upload gear active. 22:21:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:21:23 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:21:23 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:21:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:24 INFO - (blobuploader) - INFO - Done attempting. 22:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:25 INFO - (blobuploader) - INFO - Done attempting. 22:21:25 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:25 INFO - Return code: 0 22:21:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7939e45c6a84efb2c8bc2fc240e8dcc6c2020e52859db0faec8651c8685cc9f5416ab9134baeb15a57964d00ac5c608e84a51fa11a863cf49a593e15f0381a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8c344a5bfe8651dedaf3233ae62c72ab66fbfdaecffbe9f5fcb16e680c2960282c1218992039aa93de1c3478eaf6a7b57788cd31140a943ec007e887df9df02b"} 22:21:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:25 INFO - Contents: 22:21:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7939e45c6a84efb2c8bc2fc240e8dcc6c2020e52859db0faec8651c8685cc9f5416ab9134baeb15a57964d00ac5c608e84a51fa11a863cf49a593e15f0381a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8c344a5bfe8651dedaf3233ae62c72ab66fbfdaecffbe9f5fcb16e680c2960282c1218992039aa93de1c3478eaf6a7b57788cd31140a943ec007e887df9df02b"} 22:21:25 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:25 INFO - Copying logs to upload dir... 22:21:25 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.493755 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-08 22:21:25.174612) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:25.177851) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615 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-Inbound/sha512/0c7939e45c6a84efb2c8bc2fc240e8dcc6c2020e52859db0faec8651c8685cc9f5416ab9134baeb15a57964d00ac5c608e84a51fa11a863cf49a593e15f0381a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8c344a5bfe8651dedaf3233ae62c72ab66fbfdaecffbe9f5fcb16e680c2960282c1218992039aa93de1c3478eaf6a7b57788cd31140a943ec007e887df9df02b"} build_url:https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/ZgyMKxmgSkaI63v1fgIRYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7939e45c6a84efb2c8bc2fc240e8dcc6c2020e52859db0faec8651c8685cc9f5416ab9134baeb15a57964d00ac5c608e84a51fa11a863cf49a593e15f0381a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8c344a5bfe8651dedaf3233ae62c72ab66fbfdaecffbe9f5fcb16e680c2960282c1218992039aa93de1c3478eaf6a7b57788cd31140a943ec007e887df9df02b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:25.204594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:25.204905) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1462764431.845957-1993051615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 22:21:25.290230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-08 22:21:25.290563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-08 22:21:25.290853) ========= ========= Total master_lag: 0.17 =========