builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-069 starttime: 1457095338.59 results: success (0) buildid: 20160304040428 builduid: 73b6e99e971649808cbef769fa4de874 revision: d53f6871ec137d77e57f53fe8cf90da8afe0e671 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.593852) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.594244) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.594935) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006469 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.621397) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.621718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.642278) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.642552) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.691662) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.691966) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.692311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.692591) ========= 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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-04 04:42:18-- 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% 220M=0s 2016-03-04 04:42:18 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.157487 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.864924) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.865195) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018586 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.914546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:18.915379) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d53f6871ec137d77e57f53fe8cf90da8afe0e671 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d53f6871ec137d77e57f53fe8cf90da8afe0e671 --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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-04 04:42:19,067 truncating revision to first 12 chars 2016-03-04 04:42:19,067 Setting DEBUG logging. 2016-03-04 04:42:19,067 attempt 1/10 2016-03-04 04:42:19,067 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d53f6871ec13?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-04 04:42:19,342 unpacking tar archive at: fx-team-d53f6871ec13/testing/mozharness/ program finished with exit code 0 elapsedTime=0.568517 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:19.498823) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:19.499118) ========= echo d53f6871ec137d77e57f53fe8cf90da8afe0e671 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'd53f6871ec137d77e57f53fe8cf90da8afe0e671'] 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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False d53f6871ec137d77e57f53fe8cf90da8afe0e671 program finished with exit code 0 elapsedTime=0.003678 script_repo_revision: 'd53f6871ec137d77e57f53fe8cf90da8afe0e671' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:19.549201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:19.549490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-04 04:42:19.560529) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 50 secs) (at 2016-03-04 04:42:19.560819) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:42:19 INFO - MultiFileLogger online at 20160304 04:42:19 in /builds/slave/test 04:42:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 04:42:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:42:19 INFO - {'append_to_log': False, 04:42:19 INFO - 'base_work_dir': '/builds/slave/test', 04:42:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:42:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:42:19 INFO - 'branch': 'Fx-Team-Non-PGO', 04:42:19 INFO - 'buildbot_json_path': 'buildprops.json', 04:42:19 INFO - 'config_files': ('talos/linux_config.py',), 04:42:19 INFO - 'default_actions': ('clobber', 04:42:19 INFO - 'read-buildbot-config', 04:42:19 INFO - 'download-and-extract', 04:42:19 INFO - 'populate-webroot', 04:42:19 INFO - 'create-virtualenv', 04:42:19 INFO - 'install', 04:42:19 INFO - 'run-tests'), 04:42:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:42:19 INFO - 'download_minidump_stackwalk': True, 04:42:19 INFO - 'download_symbols': 'ondemand', 04:42:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:42:19 INFO - 'tooltool.py': '/tools/tooltool.py', 04:42:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:42:19 INFO - '/tools/misc-python/virtualenv.py')}, 04:42:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:42:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:42:19 INFO - 'installer_path': 'installer.exe', 04:42:19 INFO - 'log_level': 'info', 04:42:19 INFO - 'log_name': 'talos', 04:42:19 INFO - 'log_to_console': True, 04:42:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:42:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:42:19 INFO - 'opt_config_files': (), 04:42:19 INFO - 'pip_index': False, 04:42:19 INFO - 'sps_profile': False, 04:42:19 INFO - 'sps_profile_interval': 0, 04:42:19 INFO - 'suite': 'dromaeojs', 04:42:19 INFO - 'system_bits': '32', 04:42:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:42:19 INFO - 'title': 'talos-linux64-ix-069', 04:42:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:42:19 INFO - 'use_talos_json': True, 04:42:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:42:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:42:19 INFO - 'work_dir': 'build'} 04:42:19 INFO - ##### 04:42:19 INFO - ##### Running clobber step. 04:42:19 INFO - ##### 04:42:19 INFO - Running pre-action listener: _resource_record_pre_action 04:42:19 INFO - Running main action method: clobber 04:42:19 INFO - rmtree: /builds/slave/test/build 04:42:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:42:20 INFO - Running post-action listener: _resource_record_post_action 04:42:20 INFO - ##### 04:42:20 INFO - ##### Running read-buildbot-config step. 04:42:20 INFO - ##### 04:42:20 INFO - Running pre-action listener: _resource_record_pre_action 04:42:20 INFO - Running main action method: read_buildbot_config 04:42:20 INFO - Using buildbot properties: 04:42:20 INFO - { 04:42:20 INFO - "project": "", 04:42:20 INFO - "product": "firefox", 04:42:20 INFO - "script_repo_revision": "production", 04:42:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 04:42:20 INFO - "repository": "", 04:42:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 04:42:20 INFO - "buildid": "20160304040428", 04:42:20 INFO - "pgo_build": "False", 04:42:20 INFO - "basedir": "/builds/slave/test", 04:42:20 INFO - "buildnumber": 2598, 04:42:20 INFO - "slavename": "talos-linux64-ix-069", 04:42:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:42:20 INFO - "platform": "ubuntu64_hw", 04:42:20 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:42:20 INFO - "branch": "fx-team", 04:42:20 INFO - "repo_path": "integration/fx-team", 04:42:20 INFO - "revision": "d53f6871ec137d77e57f53fe8cf90da8afe0e671", 04:42:20 INFO - "stage_platform": "linux64", 04:42:20 INFO - "builduid": "73b6e99e971649808cbef769fa4de874", 04:42:20 INFO - "slavebuilddir": "test" 04:42:20 INFO - } 04:42:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 04:42:20 INFO - Running post-action listener: _resource_record_post_action 04:42:20 INFO - ##### 04:42:20 INFO - ##### Running download-and-extract step. 04:42:20 INFO - ##### 04:42:20 INFO - Running pre-action listener: _resource_record_pre_action 04:42:20 INFO - Running main action method: download_and_extract 04:42:20 INFO - mkdir: /builds/slave/test/build/tests 04:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:20 INFO - https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:42:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:42:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:42:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:42:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:42:21 INFO - Downloaded 1448 bytes. 04:42:21 INFO - Reading from file /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:42:21 INFO - Using the following test package requirements: 04:42:21 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 04:42:21 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:42:21 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:42:21 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'jsshell-linux-x86_64.zip'], 04:42:21 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:42:21 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 04:42:21 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:42:21 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:42:21 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:42:21 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 04:42:21 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:42:21 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:42:21 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:42:21 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')]} 04:42:21 INFO - https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:42:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:42:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:42:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:42:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:42:22 INFO - Downloaded 22081101 bytes. 04:42:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:42:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:42:23 INFO - Return code: 0 04:42:23 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:42: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')]} 04:42:23 INFO - https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:42:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:42:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:42:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:42:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:42:25 INFO - Downloaded 22081101 bytes. 04:42:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:42:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:42:26 INFO - Return code: 0 04:42: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')]} 04:42:26 INFO - https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:42:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 04:42:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 04:42:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 04:42:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:42:26 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 04:42:26 INFO - Running command: ['nslookup', u'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 04:42:26 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 04:42:26 INFO - Server: 10.26.75.40 04:42:26 INFO - Address: 10.26.75.40#53 04:42:26 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 04:42:26 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 04:42:26 INFO - Address: 10.26.48.46 04:42:26 INFO - Return code: 0 04:42:26 INFO - retry: attempt #1 caught exception: 04:42:26 INFO - retry: Failed, sleeping 30 seconds before retrying 04:42:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 04:42:59 INFO - Downloaded 10808282 bytes. 04:42:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:42:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:42:59 INFO - Return code: 0 04:42: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')]} 04:42:59 INFO - https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:42:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:42:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:42:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:43:00 INFO - Downloaded 54351125 bytes. 04:43:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:43:00 INFO - mkdir: /builds/slave/test/properties 04:43:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:43:00 INFO - Writing to file /builds/slave/test/properties/build_url 04:43:00 INFO - Contents: 04:43:00 INFO - build_url:https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:43:01 INFO - Running post-action listener: _resource_record_post_action 04:43:01 INFO - Running post-action listener: set_extra_try_arguments 04:43:01 INFO - ##### 04:43:01 INFO - ##### Running populate-webroot step. 04:43:01 INFO - ##### 04:43:01 INFO - Running pre-action listener: _resource_record_pre_action 04:43:01 INFO - Running main action method: populate_webroot 04:43:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:43:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:43:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:43:01 INFO - '--tppagecycles', 04:43:01 INFO - '7'], 04:43:01 INFO - 'tests': ['tsvgm']}}, 04:43:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:43:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:43:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:43:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:43:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'tests': ['damp', 'tps']}, 04:43:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['damp', 'tps']}, 04:43:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:43:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:43:01 INFO - 'other': {'tests': ['a11yr', 04:43:01 INFO - 'ts_paint', 04:43:01 INFO - 'tpaint', 04:43:01 INFO - 'sessionrestore', 04:43:01 INFO - 'sessionrestore_no_auto_restore']}, 04:43:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['a11yr', 04:43:01 INFO - 'ts_paint', 04:43:01 INFO - 'tpaint', 04:43:01 INFO - 'sessionrestore', 04:43:01 INFO - 'sessionrestore_no_auto_restore']}, 04:43:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['a11yr', 04:43:01 INFO - 'ts_paint', 04:43:01 INFO - 'tpaint', 04:43:01 INFO - 'sessionrestore', 04:43:01 INFO - 'sessionrestore_no_auto_restore']}, 04:43:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['a11yr', 04:43:01 INFO - 'ts_paint', 04:43:01 INFO - 'tpaint', 04:43:01 INFO - 'sessionrestore', 04:43:01 INFO - 'sessionrestore_no_auto_restore']}, 04:43:01 INFO - 'other_l64': {'tests': ['a11yr', 04:43:01 INFO - 'ts_paint', 04:43:01 INFO - 'tpaint', 04:43:01 INFO - 'sessionrestore', 04:43:01 INFO - 'sessionrestore_no_auto_restore']}, 04:43:01 INFO - 'other_nol64': {'tests': ['a11yr', 04:43:01 INFO - 'ts_paint', 04:43:01 INFO - 'tpaint', 04:43:01 INFO - 'sessionrestore', 04:43:01 INFO - 'sessionrestore_no_auto_restore']}, 04:43:01 INFO - 'svgr': {'tests': ['tsvgx', 04:43:01 INFO - 'tsvgr_opacity', 04:43:01 INFO - 'tart', 04:43:01 INFO - 'tscrollx', 04:43:01 INFO - 'cart']}, 04:43:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['tsvgx', 04:43:01 INFO - 'tsvgr_opacity', 04:43:01 INFO - 'tart', 04:43:01 INFO - 'tscrollx', 04:43:01 INFO - 'cart']}, 04:43:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'tests': ['tp5o']}, 04:43:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'talos_options': ['--e10s'], 04:43:01 INFO - 'tests': ['tp5o']}, 04:43:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'talos_options': ['--xperf_path', 04:43:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:01 INFO - 'tests': ['tp5n']}, 04:43:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:01 INFO - 'talos_options': ['--e10s', 04:43:01 INFO - '--xperf_path', 04:43:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:01 INFO - 'tests': ['tp5n']}}, 04:43:01 INFO - 'talos.zip': {'path': '', 04:43:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:43:01 INFO - Running post-action listener: _resource_record_post_action 04:43:01 INFO - ##### 04:43:01 INFO - ##### Running create-virtualenv step. 04:43:01 INFO - ##### 04:43:01 INFO - Running pre-action listener: _resource_record_pre_action 04:43:01 INFO - Running main action method: create_virtualenv 04:43:01 INFO - Creating virtualenv /builds/slave/test/build/venv 04:43:01 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 04:43:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:43:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:43:01 INFO - Using real prefix '/usr' 04:43:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:43:02 INFO - Installing distribute.............................................................................................................................................................................................done. 04:43:04 INFO - Installing pip.................done. 04:43:04 INFO - Return code: 0 04:43:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:43:04 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')]} 04:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:04 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')]} 04:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:04 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:04 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 04:43:04 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 04:43:04 INFO - Using env: {'DISPLAY': ':0', 04:43:04 INFO - 'HOME': '/home/cltbld', 04:43:04 INFO - 'LANG': 'en_US.UTF-8', 04:43:04 INFO - 'LANGUAGE': 'en_US:en', 04:43:04 INFO - 'LOGNAME': 'cltbld', 04:43:04 INFO - 'MAIL': '/var/mail/cltbld', 04:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:04 INFO - 'MOZ_NO_REMOTE': '1', 04:43:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:04 INFO - 'NO_EM_RESTART': '1', 04:43:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:04 INFO - 'PWD': '/builds/slave/test', 04:43:04 INFO - 'SHELL': '/bin/bash', 04:43:04 INFO - 'SHLVL': '1', 04:43:04 INFO - 'TERM': 'linux', 04:43:04 INFO - 'TMOUT': '86400', 04:43:04 INFO - 'USER': 'cltbld', 04:43:04 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:04 INFO - '_': '/tools/buildbot/bin/python'} 04:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:04 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 04:43:04 INFO - Cleaning up... 04:43:04 INFO - Return code: 0 04:43:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:43:04 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')]} 04:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:04 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')]} 04:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:04 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:04 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 04:43:04 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 04:43:04 INFO - Using env: {'DISPLAY': ':0', 04:43:04 INFO - 'HOME': '/home/cltbld', 04:43:04 INFO - 'LANG': 'en_US.UTF-8', 04:43:04 INFO - 'LANGUAGE': 'en_US:en', 04:43:04 INFO - 'LOGNAME': 'cltbld', 04:43:04 INFO - 'MAIL': '/var/mail/cltbld', 04:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:04 INFO - 'MOZ_NO_REMOTE': '1', 04:43:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:04 INFO - 'NO_EM_RESTART': '1', 04:43:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:04 INFO - 'PWD': '/builds/slave/test', 04:43:04 INFO - 'SHELL': '/bin/bash', 04:43:04 INFO - 'SHLVL': '1', 04:43:04 INFO - 'TERM': 'linux', 04:43:04 INFO - 'TMOUT': '86400', 04:43:04 INFO - 'USER': 'cltbld', 04:43:04 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:04 INFO - '_': '/tools/buildbot/bin/python'} 04:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:04 INFO - Downloading/unpacking psutil>=0.7.1 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:43:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:06 INFO - Installing collected packages: psutil 04:43:06 INFO - Running setup.py install for psutil 04:43:06 INFO - building 'psutil._psutil_linux' extension 04:43:06 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 04:43:06 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 04:43:06 INFO - building 'psutil._psutil_posix' extension 04:43:06 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 04:43:06 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 04:43:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:07 INFO - Successfully installed psutil 04:43:07 INFO - Cleaning up... 04:43:07 INFO - Return code: 0 04:43:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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', '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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:43: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 mozsystemmonitor==0.0.0 04:43:07 INFO - Using env: {'DISPLAY': ':0', 04:43:07 INFO - 'HOME': '/home/cltbld', 04:43:07 INFO - 'LANG': 'en_US.UTF-8', 04:43:07 INFO - 'LANGUAGE': 'en_US:en', 04:43:07 INFO - 'LOGNAME': 'cltbld', 04:43:07 INFO - 'MAIL': '/var/mail/cltbld', 04:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:07 INFO - 'MOZ_NO_REMOTE': '1', 04:43:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:07 INFO - 'NO_EM_RESTART': '1', 04:43:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:07 INFO - 'PWD': '/builds/slave/test', 04:43:07 INFO - 'SHELL': '/bin/bash', 04:43:07 INFO - 'SHLVL': '1', 04:43:07 INFO - 'TERM': 'linux', 04:43:07 INFO - 'TMOUT': '86400', 04:43:07 INFO - 'USER': 'cltbld', 04:43:07 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:07 INFO - '_': '/tools/buildbot/bin/python'} 04:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:43:07 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 04:43:07 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 04:43:07 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 04:43:07 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 04:43:07 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 04:43:07 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 04:43:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:43:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:43:09 INFO - Installing collected packages: mozsystemmonitor 04:43:09 INFO - Running setup.py install for mozsystemmonitor 04:43:09 INFO - Successfully installed mozsystemmonitor 04:43:09 INFO - Cleaning up... 04:43:09 INFO - Return code: 0 04:43:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:43:09 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')]} 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:09 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')]} 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:09 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:09 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 04:43:09 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 04:43:09 INFO - Using env: {'DISPLAY': ':0', 04:43:09 INFO - 'HOME': '/home/cltbld', 04:43:09 INFO - 'LANG': 'en_US.UTF-8', 04:43:09 INFO - 'LANGUAGE': 'en_US:en', 04:43:09 INFO - 'LOGNAME': 'cltbld', 04:43:09 INFO - 'MAIL': '/var/mail/cltbld', 04:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:09 INFO - 'MOZ_NO_REMOTE': '1', 04:43:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:09 INFO - 'NO_EM_RESTART': '1', 04:43:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:09 INFO - 'PWD': '/builds/slave/test', 04:43:09 INFO - 'SHELL': '/bin/bash', 04:43:09 INFO - 'SHLVL': '1', 04:43:09 INFO - 'TERM': 'linux', 04:43:09 INFO - 'TMOUT': '86400', 04:43:09 INFO - 'USER': 'cltbld', 04:43:09 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:09 INFO - '_': '/tools/buildbot/bin/python'} 04:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:09 INFO - Downloading/unpacking blobuploader==1.2.4 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:11 INFO - Downloading blobuploader-1.2.4.tar.gz 04:43:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:43:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:43:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:12 INFO - Downloading docopt-0.6.1.tar.gz 04:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:43:12 INFO - Installing collected packages: blobuploader, requests, docopt 04:43:12 INFO - Running setup.py install for blobuploader 04:43:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:43:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:43:12 INFO - Running setup.py install for requests 04:43:12 INFO - Running setup.py install for docopt 04:43:12 INFO - Successfully installed blobuploader requests docopt 04:43:12 INFO - Cleaning up... 04:43:12 INFO - Return code: 0 04:43:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:12 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')]} 04:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:12 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')]} 04:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:12 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:12 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 04:43:12 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 04:43:12 INFO - Using env: {'DISPLAY': ':0', 04:43:12 INFO - 'HOME': '/home/cltbld', 04:43:12 INFO - 'LANG': 'en_US.UTF-8', 04:43:12 INFO - 'LANGUAGE': 'en_US:en', 04:43:12 INFO - 'LOGNAME': 'cltbld', 04:43:12 INFO - 'MAIL': '/var/mail/cltbld', 04:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:12 INFO - 'MOZ_NO_REMOTE': '1', 04:43:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:12 INFO - 'NO_EM_RESTART': '1', 04:43:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:12 INFO - 'PWD': '/builds/slave/test', 04:43:12 INFO - 'SHELL': '/bin/bash', 04:43:12 INFO - 'SHLVL': '1', 04:43:12 INFO - 'TERM': 'linux', 04:43:12 INFO - 'TMOUT': '86400', 04:43:12 INFO - 'USER': 'cltbld', 04:43:12 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:12 INFO - '_': '/tools/buildbot/bin/python'} 04:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:13 INFO - Running setup.py (path:/tmp/pip-Wi5NuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:13 INFO - Running setup.py (path:/tmp/pip-ulXGqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:13 INFO - Running setup.py (path:/tmp/pip-rpYdCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:13 INFO - Running setup.py (path:/tmp/pip-7stWuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:13 INFO - Running setup.py (path:/tmp/pip-MuHEHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:13 INFO - Running setup.py (path:/tmp/pip-W2LJs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:13 INFO - Running setup.py (path:/tmp/pip-yPORan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:13 INFO - Running setup.py (path:/tmp/pip-otVrnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:13 INFO - Running setup.py (path:/tmp/pip-5FSkmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:13 INFO - Running setup.py (path:/tmp/pip-QDbD4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:13 INFO - Running setup.py (path:/tmp/pip-_SXMH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:13 INFO - Running setup.py (path:/tmp/pip-EctvX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:13 INFO - Running setup.py (path:/tmp/pip-VgIOsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:14 INFO - Running setup.py (path:/tmp/pip-Llo9zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:14 INFO - Running setup.py (path:/tmp/pip-3IxCtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:14 INFO - Running setup.py (path:/tmp/pip-ZfxkTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:14 INFO - Running setup.py (path:/tmp/pip-YUiOif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:43:14 INFO - Running setup.py install for manifestparser 04:43:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:43:14 INFO - Running setup.py install for mozcrash 04:43:14 INFO - Running setup.py install for mozdebug 04:43:14 INFO - Running setup.py install for mozdevice 04:43:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:43:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:43:14 INFO - Running setup.py install for mozfile 04:43:14 INFO - Running setup.py install for mozhttpd 04:43:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:43:14 INFO - Running setup.py install for mozinfo 04:43:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Running setup.py install for mozInstall 04:43:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Running setup.py install for mozleak 04:43:15 INFO - Running setup.py install for mozlog 04:43:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Running setup.py install for moznetwork 04:43:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Running setup.py install for mozprocess 04:43:15 INFO - Running setup.py install for mozprofile 04:43:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Running setup.py install for mozrunner 04:43:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:43:15 INFO - Running setup.py install for mozscreenshot 04:43:15 INFO - Running setup.py install for moztest 04:43:15 INFO - Running setup.py install for mozversion 04:43:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:43:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:43:16 INFO - Cleaning up... 04:43:16 INFO - Return code: 0 04:43:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:16 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:16 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 04:43:16 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 04:43:16 INFO - Using env: {'DISPLAY': ':0', 04:43:16 INFO - 'HOME': '/home/cltbld', 04:43:16 INFO - 'LANG': 'en_US.UTF-8', 04:43:16 INFO - 'LANGUAGE': 'en_US:en', 04:43:16 INFO - 'LOGNAME': 'cltbld', 04:43:16 INFO - 'MAIL': '/var/mail/cltbld', 04:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:16 INFO - 'MOZ_NO_REMOTE': '1', 04:43:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:16 INFO - 'NO_EM_RESTART': '1', 04:43:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:16 INFO - 'PWD': '/builds/slave/test', 04:43:16 INFO - 'SHELL': '/bin/bash', 04:43:16 INFO - 'SHLVL': '1', 04:43:16 INFO - 'TERM': 'linux', 04:43:16 INFO - 'TMOUT': '86400', 04:43:16 INFO - 'USER': 'cltbld', 04:43:16 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:16 INFO - '_': '/tools/buildbot/bin/python'} 04:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:16 INFO - Running setup.py (path:/tmp/pip-LjD5Pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:16 INFO - Running setup.py (path:/tmp/pip-NEAeWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:16 INFO - Running setup.py (path:/tmp/pip-m6WvVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:16 INFO - Running setup.py (path:/tmp/pip-jVfuoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:16 INFO - Running setup.py (path:/tmp/pip-gTV5_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:16 INFO - Running setup.py (path:/tmp/pip-AOr2ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:16 INFO - Running setup.py (path:/tmp/pip-X72D5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:16 INFO - Running setup.py (path:/tmp/pip-cHG97s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:16 INFO - Running setup.py (path:/tmp/pip-pESeMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:16 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)) 04:43:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:16 INFO - Running setup.py (path:/tmp/pip-orfPoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:17 INFO - Running setup.py (path:/tmp/pip-FnSZ1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:17 INFO - Running setup.py (path:/tmp/pip-yR9SwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:17 INFO - Running setup.py (path:/tmp/pip-KXIDI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:17 INFO - Running setup.py (path:/tmp/pip-C3Df3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:17 INFO - Running setup.py (path:/tmp/pip-Skuakz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:17 INFO - Running setup.py (path:/tmp/pip-gc_OGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:17 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)) 04:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:17 INFO - Running setup.py (path:/tmp/pip-o7HZ8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:17 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)) 04:43:17 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:43:17 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:43:17 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)) 04:43:17 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)) 04:43:17 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)) 04:43:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:43:17 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 04:43:17 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 04:43:17 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 04:43:17 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 04:43:17 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 04:43:17 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 04:43:19 INFO - Downloading blessings-1.6.tar.gz 04:43:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:43:19 INFO - Installing collected packages: blessings 04:43:19 INFO - Running setup.py install for blessings 04:43:19 INFO - Successfully installed blessings 04:43:19 INFO - Cleaning up... 04:43:19 INFO - Return code: 0 04:43:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:43:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:19 INFO - Reading from file tmpfile_stdout 04:43:19 INFO - Current package versions: 04:43:19 INFO - argparse == 1.2.1 04:43:19 INFO - blessings == 1.6 04:43:19 INFO - blobuploader == 1.2.4 04:43:19 INFO - docopt == 0.6.1 04:43:19 INFO - manifestparser == 1.1 04:43:19 INFO - mozInstall == 1.12 04:43:19 INFO - mozcrash == 0.16 04:43:19 INFO - mozdebug == 0.1 04:43:19 INFO - mozdevice == 0.48 04:43:19 INFO - mozfile == 1.2 04:43:19 INFO - mozhttpd == 0.7 04:43:19 INFO - mozinfo == 0.9 04:43:19 INFO - mozleak == 0.1 04:43:19 INFO - mozlog == 3.1 04:43:19 INFO - moznetwork == 0.27 04:43:19 INFO - mozprocess == 0.22 04:43:19 INFO - mozprofile == 0.28 04:43:19 INFO - mozrunner == 6.11 04:43:19 INFO - mozscreenshot == 0.1 04:43:19 INFO - mozsystemmonitor == 0.0 04:43:19 INFO - moztest == 0.7 04:43:19 INFO - mozversion == 1.4 04:43:19 INFO - psutil == 3.1.1 04:43:19 INFO - requests == 1.2.3 04:43:19 INFO - wsgiref == 0.1.2 04:43:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:19 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:19 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 04:43:19 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 04:43:19 INFO - Using env: {'DISPLAY': ':0', 04:43:19 INFO - 'HOME': '/home/cltbld', 04:43:19 INFO - 'LANG': 'en_US.UTF-8', 04:43:19 INFO - 'LANGUAGE': 'en_US:en', 04:43:19 INFO - 'LOGNAME': 'cltbld', 04:43:19 INFO - 'MAIL': '/var/mail/cltbld', 04:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:19 INFO - 'MOZ_NO_REMOTE': '1', 04:43:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:19 INFO - 'NO_EM_RESTART': '1', 04:43:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:19 INFO - 'PWD': '/builds/slave/test', 04:43:19 INFO - 'SHELL': '/bin/bash', 04:43:19 INFO - 'SHLVL': '1', 04:43:19 INFO - 'TERM': 'linux', 04:43:19 INFO - 'TMOUT': '86400', 04:43:19 INFO - 'USER': 'cltbld', 04:43:19 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:19 INFO - '_': '/tools/buildbot/bin/python'} 04:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:19 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 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)) 04:43:20 INFO - Cleaning up... 04:43:20 INFO - Return code: 0 04:43:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:43:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:43:20 INFO - Using env: {'DISPLAY': ':0', 04:43:20 INFO - 'HOME': '/home/cltbld', 04:43:20 INFO - 'LANG': 'en_US.UTF-8', 04:43:20 INFO - 'LANGUAGE': 'en_US:en', 04:43:20 INFO - 'LOGNAME': 'cltbld', 04:43:20 INFO - 'MAIL': '/var/mail/cltbld', 04:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:20 INFO - 'MOZ_NO_REMOTE': '1', 04:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:20 INFO - 'NO_EM_RESTART': '1', 04:43:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:20 INFO - 'PWD': '/builds/slave/test', 04:43:20 INFO - 'SHELL': '/bin/bash', 04:43:20 INFO - 'SHLVL': '1', 04:43:20 INFO - 'TERM': 'linux', 04:43:20 INFO - 'TMOUT': '86400', 04:43:20 INFO - 'USER': 'cltbld', 04:43:20 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:20 INFO - '_': '/tools/buildbot/bin/python'} 04:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:20 INFO - Downloading/unpacking jsonschema 04:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:43:22 INFO - Downloading/unpacking functools32 (from jsonschema) 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:43:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:43:22 INFO - warning: no files found matching '*.txt' 04:43:22 INFO - no previously-included directories found matching 'build' 04:43:22 INFO - no previously-included directories found matching 'dist' 04:43:22 INFO - no previously-included directories found matching '.git*' 04:43:22 INFO - Installing collected packages: jsonschema, functools32 04:43:22 INFO - Running setup.py install for functools32 04:43:22 INFO - warning: no files found matching '*.txt' 04:43:22 INFO - no previously-included directories found matching 'build' 04:43:22 INFO - no previously-included directories found matching 'dist' 04:43:22 INFO - no previously-included directories found matching '.git*' 04:43:22 INFO - Successfully installed jsonschema functools32 04:43:22 INFO - Cleaning up... 04:43:22 INFO - Return code: 0 04:43:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:22 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 0x7fa074fcff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f6410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, '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': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', '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 04:43:22 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 04:43:22 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 04:43:22 INFO - Using env: {'DISPLAY': ':0', 04:43:22 INFO - 'HOME': '/home/cltbld', 04:43:22 INFO - 'LANG': 'en_US.UTF-8', 04:43:22 INFO - 'LANGUAGE': 'en_US:en', 04:43:22 INFO - 'LOGNAME': 'cltbld', 04:43:22 INFO - 'MAIL': '/var/mail/cltbld', 04:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:22 INFO - 'MOZ_NO_REMOTE': '1', 04:43:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:22 INFO - 'NO_EM_RESTART': '1', 04:43:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:22 INFO - 'PWD': '/builds/slave/test', 04:43:22 INFO - 'SHELL': '/bin/bash', 04:43:22 INFO - 'SHLVL': '1', 04:43:22 INFO - 'TERM': 'linux', 04:43:22 INFO - 'TMOUT': '86400', 04:43:22 INFO - 'USER': 'cltbld', 04:43:22 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:22 INFO - '_': '/tools/buildbot/bin/python'} 04:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:22 INFO - Downloading/unpacking flake8 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:22 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 04:43:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:43:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:43:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:43:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:43:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:43:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:43:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:43:25 INFO - no previously-included directories found matching 'docs/_build' 04:43:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:43:25 INFO - Running setup.py install for pep8 04:43:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:43:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:43:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:43:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:43:25 INFO - no previously-included directories found matching 'docs/_build' 04:43:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:43:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:43:25 INFO - Cleaning up... 04:43:25 INFO - Return code: 0 04:43:25 INFO - Running post-action listener: _resource_record_post_action 04:43:25 INFO - Running post-action listener: _start_resource_monitoring 04:43:25 INFO - Starting resource monitoring. 04:43:25 INFO - ##### 04:43:25 INFO - ##### Running install step. 04:43:25 INFO - ##### 04:43:25 INFO - Running pre-action listener: _resource_record_pre_action 04:43:25 INFO - Running main action method: install 04:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:25 INFO - Reading from file tmpfile_stdout 04:43:25 INFO - Detecting whether we're running mozinstall >=1.0... 04:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:43:25 INFO - Reading from file tmpfile_stdout 04:43:25 INFO - Output received: 04:43:25 INFO - Usage: mozinstall [options] installer 04:43:25 INFO - Options: 04:43:25 INFO - -h, --help show this help message and exit 04:43:25 INFO - -d DEST, --destination=DEST 04:43:25 INFO - Directory to install application into. [default: 04:43:25 INFO - "/builds/slave/test"] 04:43:25 INFO - --app=APP Application being installed. [default: firefox] 04:43:25 INFO - mkdir: /builds/slave/test/build/application 04:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:43:41 INFO - Reading from file tmpfile_stdout 04:43:41 INFO - Output received: 04:43:41 INFO - /builds/slave/test/build/application/firefox/firefox 04:43:41 INFO - Running post-action listener: _resource_record_post_action 04:43:41 INFO - ##### 04:43:41 INFO - ##### Running run-tests step. 04:43:41 INFO - ##### 04:43:41 INFO - Running pre-action listener: _resource_record_pre_action 04:43:41 INFO - Running main action method: run_tests 04:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:43:41 INFO - Python 2.7.3 04:43:41 INFO - Return code: 0 04:43:41 INFO - grabbing minidump binary from tooltool 04:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:41 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 0x2385160>, '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 0x2386060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23864f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:43:41 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 04:43:41 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 04:43:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:43:41 INFO - Return code: 0 04:43:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:43:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:43:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:43:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:43:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:43:42 INFO - Return code: 0 04:43:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 04:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 04:43:42 INFO - Using env: {'DISPLAY': ':0', 04:43:42 INFO - 'HOME': '/home/cltbld', 04:43:42 INFO - 'LANG': 'en_US.UTF-8', 04:43:42 INFO - 'LANGUAGE': 'en_US:en', 04:43:42 INFO - 'LOGNAME': 'cltbld', 04:43:42 INFO - 'MAIL': '/var/mail/cltbld', 04:43:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:43:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:42 INFO - 'MOZ_NO_REMOTE': '1', 04:43:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:43:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:42 INFO - 'NO_EM_RESTART': '1', 04:43:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:42 INFO - 'PWD': '/builds/slave/test', 04:43:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:43:42 INFO - 'SHELL': '/bin/bash', 04:43:42 INFO - 'SHLVL': '1', 04:43:42 INFO - 'TERM': 'linux', 04:43:42 INFO - 'TMOUT': '86400', 04:43:42 INFO - 'USER': 'cltbld', 04:43:42 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1457094394.172766-352103562', 04:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:42 INFO - '_': '/tools/buildbot/bin/python'} 04:43:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 04:43:42 INFO - mozversion application_buildid: 20160304040428 04:43:42 INFO - mozversion application_changeset: d53f6871ec137d77e57f53fe8cf90da8afe0e671 04:43:42 INFO - mozversion application_display_name: Nightly 04:43:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:43:42 INFO - mozversion application_name: Firefox 04:43:42 INFO - mozversion application_remotingname: firefox 04:43:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:43:42 INFO - mozversion application_vendor: Mozilla 04:43:42 INFO - mozversion application_version: 47.0a1 04:43:42 INFO - mozversion platform_buildid: 20160304040428 04:43:42 INFO - mozversion platform_changeset: d53f6871ec137d77e57f53fe8cf90da8afe0e671 04:43:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:43:42 INFO - mozversion platform_version: 47.0a1 04:43:42 INFO - using testdate: 1457095422 04:43:42 INFO - actual date: 1457095422 04:43:42 INFO - starting webserver on 'localhost:57310' 04:43:42 INFO - SUITE-START | Running 2 tests 04:43:42 INFO - TEST-START | dromaeo_css 04:43:42 INFO - operating with platform_type : linux_ 04:43:42 INFO - Initialising browser for dromaeo_css test... 04:43:42 INFO - TEST-INFO | started process 7206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ugORA/profile http://localhost:57310/getInfo.html) 04:43:47 INFO - PROCESS | 7206 | __metrics Screen width/height:1600/1200 04:43:47 INFO - PROCESS | 7206 | colorDepth:24 04:43:47 INFO - PROCESS | 7206 | Browser inner width/height: 1024/697 04:43:47 INFO - PROCESS | 7206 | __metrics 04:43:48 INFO - PROCESS | 7206 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:43:49 INFO - TEST-INFO | 7206: exit 0 04:43:49 INFO - Browser initialized. 04:43:49 INFO - Running cycle 1/1 for dromaeo_css test... 04:43:49 INFO - TEST-INFO | started process 7300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ugORA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:01 INFO - PROCESS | 7300 | RSS: Main: 153436160 04:44:01 INFO - PROCESS | 7300 | 04:44:52 INFO - PROCESS | 7300 | 1457095492945 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] 04:47:04 INFO - PROCESS | 7300 | Cycle 1(1): loaded http://localhost:57310/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57310/tests/dromaeo/cssquery-ext.html) 04:47:05 INFO - PROCESS | 7300 | RSS: Main: 242872320 04:47:05 INFO - PROCESS | 7300 | 04:50:07 INFO - PROCESS | 7300 | Cycle 1(1): loaded http://localhost:57310/tests/dromaeo/cssquery-ext.html (next: http://localhost:57310/tests/dromaeo/cssquery-jquery.html) 04:50:08 INFO - PROCESS | 7300 | RSS: Main: 228306944 04:50:08 INFO - PROCESS | 7300 | 04:53:10 INFO - PROCESS | 7300 | Cycle 1(1): loaded http://localhost:57310/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57310/tests/dromaeo/cssquery-mootools.html) 04:53:10 INFO - PROCESS | 7300 | RSS: Main: 232284160 04:53:10 INFO - PROCESS | 7300 | 04:56:12 INFO - PROCESS | 7300 | Cycle 1(1): loaded http://localhost:57310/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57310/tests/dromaeo/cssquery-prototype.html) 04:56:13 INFO - PROCESS | 7300 | RSS: Main: 216133632 04:56:13 INFO - PROCESS | 7300 | 04:59:16 INFO - PROCESS | 7300 | Cycle 1(1): loaded http://localhost:57310/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57310/tests/dromaeo/cssquery-yui.html) 04:59:16 INFO - PROCESS | 7300 | RSS: Main: 221724672 04:59:16 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | Cycle 1(1): loaded http://localhost:57310/tests/dromaeo/cssquery-yui.html (next: http://localhost:57310/tests/dromaeo/cssquery-dojo.html) 05:02:19 INFO - PROCESS | 7300 | RSS: Main: 252112896 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | __start_tp_report 05:02:19 INFO - PROCESS | 7300 | _x_x_mozilla_page_load 05:02:19 INFO - PROCESS | 7300 | _x_x_mozilla_page_load_details 05:02:19 INFO - PROCESS | 7300 | |i|pagename|runs| 05:02:19 INFO - PROCESS | 7300 | |0;dojo.html;2566.3968967292685;2616.9084082057134;2647.8014148938823;2652.840829550227;2659.9867000664995;4414.095110502348;4503.594676479116;4538.636909047276;4582.816687332507;4623.907521849561;4569.154706379322;4576.130535198318;4623.676342656016;4631.421072365954;4633.536646335365;4208.747475151501;4227.04891399435;4229.302165142748;4236.110416812473;4253.851115210969;4042.1915616876595;4183.058811767359;4214.346776249687;4224.197402493532;4253.234417804794;4144.378343248507;4190;4233.195692818385;4271.572842715735;4279.935800962988;4177.394277829698;4206.936895946563;4209.6000879916355;4243.681723870722;4253.255680255974;4052.1287923096675;4178.519470260929;4189.183109293689;4207.789610519461;4260.446142001551;4458.732476051415;4507;4548.226801443762;4565.5206203348835;4569.977150114228;4857.1742803723455;4867.902641947141;4867.9269810952865;4870.025994801054;4875.293082503017;4624.445066591997;4827.131116399047;4888.193448081062;4896.828610998634;4921.630877684188;4509.594136527729;4512.819487220474;4515.209838278288;4536.160810250114;4557;4921.114199444063;4931.852044438672;4931.9753401232765;4935.654504184673;4978.1288274552535;4670.5329467053025;4752.1921273383605;4761.785719642625;4798.592119669797;4825.782839772219;4893.804247830047;4912.1158191525155;4913.542372206104;4918.065567542227;4924.187509060965;4855.150348688965;4856.95143048572;4860.635452341088;4876.731779752076;4906.2640603909695;4641.8375356863025;4831.903361932812;4834.854954351375;4922.556969872657;4928.088303663833;4074.083331250504;4084.1627466369323;4084.591540845892;4088.2232375848494;4112.280350938574;3600.459931010317;3608.8376023079027;3619;3628.3650361186687;3645.179834537208;4024.597540245952;4105.527864295645;4169.97835530297;4250.681198910094;4254.829806807755;4786.282057691375;4813.061453016699;4813.879653008703;4816.470188279286;4829.082474329936;4117.402976568381;4130.938035929464;4131.132462182975;4172.165566886635;4174.603412675791;4156.750594964312;4213.115245798416;4263.594958479001;4294.677899157513;4304.418903448057;4523.479799823062;4540.77296135192;4552.339910712862;4558.179527685049;4588.26587745959;4731.219348807546;4795.088933102699;4803.3995750531185;4804.3273941648895;4846.61227101824;4559.886002849956;4602.263637817933;4607.378003969556;4612.930806037845;4652.906941861212;4751.026039661931;4758.04839032188;4762.214234651244;4774.7851346688785;4788.353572267699;4646.767661616973;4735.076660051257;4817.494163112823;4823.131836269365;4852.053849499313;4564.589186973189;4607.723536587815;4632.606228470483;4634.189016922092;4670.602998745145;4600.355950166912;4631.235846085358;4640.721556706609;4666.789994450326;4673.158831410243;4458.219811533034;4545.545445455428;4559.635229181724;4638.350630911743;4736.8578942631775;4736.834210802744;4749.7625118743235;4754.857354279377;4780.760961951958;4797.160496912957;4589.862304130881;4648.674592778609;4649.65127615437;4682.765861706969;4706.223473126895 05:02:19 INFO - PROCESS | 7300 | |1;ext.html;467.3947238326367;481.74708278153986;495.1261024292125;504.30909653774324;516.7906997665946;24316.148934787285;31838.68161318386;32176.839115804418;32290.515642265385;32376.352472950526;35963;36167.4574881377;36227.81886090566;36281.45577816335;36306.63693363065;7607.353374963121;7785.026871641045;7787.260210280015;7794.142644309121;7802.297793198611;7658.578778167199;7734.419918506106;7766.10689770678;7772.028496437945;7788.260115289067;7754.922450775476;7982.2017798220295;8001.839963200732;8002.839943201162;8003.599820008976;7779.7666070017995;7840.764777056697;7849.0973538043;7849.136594974577;7892.881606775903;7768.844623107563;7884.132745398002;7895.328897043758;7900.091489478726;7904.486208396494;35129.824350878334;35138;35140.64859351399;35145.64854351475;35192.64807351945;2771.6812566554904;2823.294176455886;2835.815671981335;2896.7537759290485;2936.1925470495785;27205.319867003287;27263.318417039733;27543;27701;28147.43705125886;3166.3825554016976;3170.302533442639;3177.158053115926;3234.0782876879975;3305.223272530953;211544;212250;212432;212692;212818;37160.44259336112;51625.48374516282;51676.483235167165;51931.74034129805;52044.47955520472;35737;35876.28247435089;35937;35969.820150899075;35979.64020359815;11197.832032519518;11349.60276390339;11358.60244891425;11359.20485566019;11361.318320900773;8844.33667474942;8889.511076890703;8895.555222238992;8898;8918.241949434241;12754;12821.551245706356;13356.265405402795;13383.464661413434;13395.861351785208;5219.765110570033;5307.230451584576;5324.494173053554;5332.2801421808335;5411.648242864201;779.8128449172158;833.8915940927641;850.4684572142411;850.570461916728;864.8875646165958;25446.872765636053;25928;25953.14812340065;26061.73938260593;26090.608640870403;889.8965283048973;935.1770442010986;946.9289803264783;984.5963155106372;1017.308230403318;13343.599692009255;13542;13854.792178117335;13878.722425551432;13886;261347;265960;266817;267140;267518;43539.78230108829;44771.77614111909;44837.77581112074;44937.77531112324;44948.775256124165;968.1190116993643;1094.5348227003492;1134.4838098665089;1140.1107136433595;1186.9762604747857;22721.659175112058;24040.27879163628;25756.742432575433;26875.596866046635;28593.998420110394;42536;42790.57209427866;43003.56996429995;43065.354019689104;43069.784651076545;244493;246846;247095;247218;247409;30335.696643033287;36601.63398366089;37694.8115259422;38119.42820857745;38699.61300387073;34128.97613071612;34654.480182796775;35283;35328;35559;30072;30189.698103018687;30452;30547.54178687277;30736.385272294876;32330;32393.838030809697;32570.185745355608;32704.34591308208;32905;31860.522092168172;32481.675183247866;32770.50844237386;32956.50565241571;32985;28352.716472835007;28903.85548072246;29182.124536263946;29645.25886852846;29660.258493537833;23122.421939450975;23424;23555.646665300377;23662.526749465258;23769.405764856016 05:02:19 INFO - PROCESS | 7300 | |2;jquery.html;319.2354311424141;320.19151642103685;322.84664784227493;322.8514883153749;328.05847218482955;14976.326065327059;15551.589503626941;15555.066695998248;15747.685046299095;16349.83650163498;13796.10325328853;14056.086354386958;15159.271331160387;15278;15361.69276614467;4926.187179115442;5085.389753229618;5099.209622508517;5107.693538387709;5115.207142892839;4996.4254110777365;5009.0482808266315;5051.79792808289;5105.566026887719;5107;5074.061298659742;5080.949190508085;5117.078925793356;5118.744062796845;5127;4904.926426103611;5077.314562534048;5079.84760457185;5088.287639730441;5091.439941606421;4930.654854160211;4962.3548938638105;4966.602671786249;4966.6771659842;4970.179920313144;10519.631812886511;10597.470126493645;10634.361938283728;14680;19316.613667726706;1735.843774060328;1738.4958362074983;1771.4242871066954;1793.9282428702836;1837.8437832784095;8897.73306800797;9106.362554621179;9190.954045229799;11277.041451476634;12616.432260548298;1664.167916041979;1674.7571602117746;1807.8101799311146;1921.788603253641;2010.8190262876412;53766.462335376535;53772.46227537714;54285.45714542883;55881.72059139719;56207.718961404935;8612.8708069379;8804.251638610662;8936.927568691668;8945.73162805117;9114.726558203132;10931.453427328603;10937.234393592373;11036.613718519813;11051.392173430379;11052.392118433403;6030.819075427744;6168.136460895478;6215.285242197115;6367.840803979845;6466.515011374166;6017.646029643295;6065.8180254592435;6068.757249709962;6102.572819902564;6149.661768602769;3691.2063906259746;3899.8440062397754;4184.456020717287;4233.618974292329;4244.639205667553;1648.983510164883;1657.2790835986384;1658.1377683604458;1662.3516828436918;1676;809.9311558517475;830.9958450207831;850.608719988812;864.4899509289423;878.1438097854668;15322.310496027705;15487.612809679847;15525.301361438762;15933.521994340188;16109.838901611069;793.6150966781106;818.2799136759733;851.0595791650261;854.384842913114;888.560162719458;8794.076621954731;8866.42368246062;8922.955385223033;8947.776305592282;8992.415492992934;362351;365402;376819;377248;379101;61079.6946015276;64014.35985640084;64547.67726161339;64909.02636460457;65079;866.882970798934;869.1959937058195;874.4185116897319;876.4609764994483;877.8683197520423;13681.521146759816;13720.519781807589;14112.870970322554;14221.14673119616;14299.499517516837;30255.546166807955;31330.686693133688;31891;31908.680913190572;32022.839885801353;39473;42688.573114269704;42705.78647106745;43261.3510797332;43576.56423435852;6591.868162636816;6884.4148247399535;7036.014957905795;7064.717411303491;7078.4691148163065;7615.5430674159725;9540.475273860004;10233.422784582272;10519.947400263256;10779.299345542515;8713.43362681449;9099.59051842682;9157.77105572366;9247.445153290824;9316.906830931875;9185.219256363016;9255.67605133817;9549.376605977139;9578.473183974947;9653.46905920181;6927.965360173167;6995.160580730345;7290.927090729025;7385.113786345673;9799.755006124904;8387.580620968805;8758.343124265833;8773.473591584525;9424.293178011814;9856.704298871045;9178.816423671356;9441.669541566012;9660.806783864424;9834.213262938805;9966.915831295506 05:02:19 INFO - PROCESS | 7300 | |3;mootools.html;1116.771061932304;1345.30044376924;1352.6145048661133;1456.679530503289;1687.7637130801681;6991.965040174799;7155.212926578072;7157.713691452349;7169.247229040947;7176.748813791518;7155.642217889098;7301.634918254093;7307.451941104412;7332.156801967775;7334.449916256289;1715.1081437652413;2068.844836637258;2099.6010757956014;2126.5109024924277;2142.507223338633;2184.7050648162453;2195.9121635134657;2196.8132708719777;2197.3627647982066;2198.373463562885;1998.1308130963005;2052.8255098316663;2053.979460205401;2055.701923221132;2056.8251698605563;2050.805173508507;2056.156975639988;2067.348785132686;2103.2638576498257;2125.5961367340155;2110.9788902110936;2127.6383014887506;2136.060133541237;2139.0801955159277;2139.700441938114;7373.77878663641;7428.182899881009;7449.143348514882;7456.813579660497;7503.437242206856;4127.7936103195025;4252.447181866338;4261.4460120184485;4264.0832221072305;4276.080642661843;2606.126947472599;2627.067391076179;2657.5747880339245;2664.214056853233;2675.438157986825;783.0720596093647;790.3203245209115;801.0867610923552;840.8192238668715;848.215400754306;10138.391696498235;10192.490375481271;10198.643047493302;10224;10232.948835255776;3985.880423587239;4040.1919616076902;4064.451299074587;4088.8160032798596;4120.361343991686;8670.132986701405;8670.523121228267;8678.175573320525;8680.56597170139;8681.56592170389;3470.305938812248;4225.788710564521;4253.914921701548;4258.42511260982;4264.87205383839;3623.7825730456257;3673.4489826526233;3695.020819482839;3701.3337599232395;3703.7962912039557;3412.078738740526;3432.6910578047596;3478.4608385700258;3501.0372147659596;3520.2079532105586;1086.6848613902039;1090.340344091829;1091.748897753521;1100.7193165742685;1102.6195962392962;906.8413027720122;912.7170577121113;915.2403505090758;922.7462447826902;945.6879229854131;6665.266820649725;6736.2590115087305;6775.356341147583;6777.830554236183;6781.559198652072;418.2054097215254;429.17384035731715;430.3050573324059;434.04726625057697;436.112511040032;4756.286557016475;4764.309175169581;4806.831760888352;4836.419629644465;4841.15279826029;8742.606582703793;8829.64681412749;8970.102989700978;9037.68368107113;9103.317251206186;8868.778280542909;8904;8931.464112153291;8977.057408972094;9001.324900632348;6865.794026179223;6943.166819981533;7005.124359455068;7037.964810176019;7072.504924655328;6360.554761166674;6388.233411990591;6405.583637063763;6425.164728585254;6428.132202152649;7711.691532338644;7945.1657575953695;8025.9598702006115;8064.072631647436;8076.394270429859;8016;8231.09457959636;8231.794205144679;8323.334133269445;8347.081820999807;5286.180642000496;5418.376886658085;5489.094299440548;6300.149479820349;6340.270868849957;6727.360900714424;6945.374916257561;6946.687399066953;6949.895751563832;6960.686769095504;6071;6194.318624951342;6258.405451482102;6268.373162683877;6283.403076707706;5985.760569577343;6080.391960803884;6091.664958427329;6161.260648722182;6174.567780255339;6421.775237866652;6458.160439142881;6466.482681385383;6606.47148228131;6625.768098116543;5761.423857614205;5849.151872978479;5852.707364631837;5855.092460668603;5859.619124756877;5886.087656413263;6020.367861374668;6030.5778595497895;6043.8186854394435;6058.030715085565 05:02:19 INFO - PROCESS | 7300 | |4;prototype.html;291.71266302691856;300.13411308375333;300.4096949494245;305.20646319569136;309.2500685836846;5255.710935898529;5370.301860758101;5378.1663842104435;5414.729263536827;5428.240046393498;5271.578273738101;5307.575393968483;5321.973390133054;5322.387925388582;5345.946540534594;3903.1022864740985;3989.501312335958;4076.429299898017;4107.116969851481;4130.607592278729;4122.278601244785;4130.421740956269;4134.338505839067;4134.6692264618905;4139.089400331922;4089.0595163112503;4089.161721847014;4091.652209562176;4099.975006248438;4108.36320370343;3844.2119365529998;3972.6226008529143;4045.898852528681;4056.898577535556;4104.343305071175;3505.6844883960566;3509.5613048368955;3509.8947031588837;3516.349475347069;3528.2237907660274;5136.974315128437;5171.741412929376;5172.353455818023;5176.9741151294;5190.92213616796;4680.157571637104;4690.624750019991;4690.788914498856;4693.131770622445;4696.741679207642;5622.634528755659;5624.3813180550105;5632;5633.887322253573;5639.379668236491;3866.845326186949;3867.5552311483975;3870.0518372998795;3871.593482684306;3876.4185372194115;5671.291088613923;5819.272590926134;5952;5963.791267305667;6025.0359942409;3880.282147802609;3889.649931506178;3889.747166434173;3896.5713771485116;3898.590647981978;3944.8422063117155;3948.032731980683;3948.407738839197;3949.466821978996;3953.288408086572;4599.425071866017;4601.723896566216;4605.078984203173;4617.561331673485;4619.514950930171;5605.159226116115;5623.831285061455;5643.52030077448;5646.491815736604;5653.321601407775;1394.2262044565364;1415.3135729171345;1416.7449859025473;1419.5954153066327;1419.6805718713413;919.5540163020929;951.3911096897991;962.3840741925173;962.4562122400821;964.238251781099;4842.418909730784;4877.512248775094;4898.681585696918;4904.8773780655765;4909.5090490950615;5805.7387417566315;5812.389699081536;5814.680192589448;5815.302163740378;5816.447437493432;4879;4888.486708895586;4891.95108048915;4894.926576101361;4895.583875370633;3083.722464978163;3120.1887509247304;3120.985679654076;3122.35991621717;3122.6565077841874;5433.2936718227165;5442.482964118402;5458.5087342139395;5464.23500709901;5474.808381706701;5755.3381361143165;5960.37416071315;5961.850953726105;6020.638761674313;6044.063170208625;5434.429384914606;5443.972780136074;5451.945480545223;5457.918131227955;5461.453854614666;5778.451047150514;5794.159846822103;5794.884102318014;5796.188533605215;5800.738966746422;5647.237622920853;5654.604177707521;5664.688442135557;5665.376808551138;5669.092945128759;5444.50999410055;5447.155690867922;5449.509544141047;5450.809221677222;5451.972740136274;3842.558105817867;3964.1080756829483;3964.7224694271126;3967.940480892731;3972.702047346518;5562.72186390687;5772.567057470623;5792.391798861059;5792.652440853562;5799.072148456226;5510.669359838423;5671.319441666861;5672.5461963042035;5673.205751194919;5673.716314184357;5647.209390685225;5648.039833228278;5652.85867853307;5663.093904975184;5663.546916246608;5511.972440137773;5531.917021244604;5545.916811247916;5551.916721249266;5559.16612508127;5737.827865164052;5744.856378590569;5744.913826292693;5745.396733343106;5759.97120014414;5424.491084109131;5489.835304940858;5552.555795536428;5567.248421463212;5567.777288908398 05:02:19 INFO - PROCESS | 7300 | |5;yui.html;586.5864565481336;601.3054921565594;633.170546583975;642.7011439680548;683.5181197255935;10747.677569672902;10942.507587158578;10999.175061870352;11004.779904401907;11010.889891101087;11163.83254251187;11251.662450126509;11254.381009044499;11255.662330130088;11268.492917818698;602.3073465514649;742.391239183868;747.1818358896999;770.5877355614747;779.1079214299624;771.4252881603213;772.528757457953;776.095848336687;776.6505072717272;776.7397921696239;817.2971244729528;820.5281962871343;820.5363969357323;821.2034326703089;821.4044817507319;626.1515646299267;789.743333416639;809.7692157735017;809.8866158737782;810.4974915552336;595.1608232392306;600.0039933710063;600.2826622186489;600.4115966352998;600.4985836826255;10680.679579612624;10713.08938740208;10720.08879245265;10724.624638137706;10755.032047115797;11349.09207263417;11692.707682307926;11701.882981170165;12051.55381354243;12314.076444266715;8653.17794809492;8849.823003539894;8860.158284962918;9017.549122543913;9024.323175761778;3037.696230376967;3040.893568725084;3083.398737246238;3115.937681246385;3124.8593813278458;38937.805310973265;39144.80427597844;39485.60514394848;39616.99999999971;39933;8848.734537963872;8885.42244754089;8920.063393343735;9061.184493395494;9079.228265597367;15992.680146397239;16008.839911600735;16031.519054428385;16181.838181618268;16194;6451.903221451682;6485.643289619022;6494.805155845333;6497.805065848032;6666.633335166515;3398.3713013092656;3449.068751437098;3556.626554211822;3616.222512159846;3665.321915445651;2573.652556904794;2600.7139214686367;2603.2060221632205;2612.2163350994624;2622.711501734808;408.895731588443;431.1699977543217;447.5278581096909;461.25277051176636;499.96507230033484;476.3045952908792;520.7578476008674;535.7535533654485;537.2022546518417;538.0879409401027;7496.475246732785;7522.473426860067;7579.5073320234005;7606.315431611071;7638.256925899983;464.5749139537353;489.7404375680895;503.72295237619164;565.245397394483;571.245955338957;3495.3358861816682;3576;3599.2441587266444;3722.236941427001;3807.485989391452;45111.32333015007;45532;52174.73912630413;52834;53016.46983530193;43010.56989430128;43577.78211108924;43611;44220.55779442229;44419.77790111029;38992.610073899465;39867.60132398697;39907;40350.59649403527;41363.7931810339;7767.805804854924;7794.844103117793;7959.482633628795;8003.83992320162;8097.1093179749005;29630.55554166646;29827.85086074556;30688;30994.535081974238;31028.84485577648;35472.74327080767;35855.28289434144;36457.08857278483;38358;38717.806410967765;9997.250206234707;10024.448655324028;10082.33641449182;10220;10264.692059238234;10891.455427228766;11247.100231981258;11256.437178141225;11332.263402878787;11462.713432164262;10266.332688375232;10280.17758579297;10295;10513.737156570842;10620.89379106199;10081.445520496443;10191.898081019392;10280.17758579297;10307.484625768531;10406.11548018427;10071.19430445548;10249.795004100026;10268.435236062089;10597.15222782191;10620.946895265473;7817.218278172138;8036.959815200887;8044.934239553499;8095.352371810359;8178.304844088081;7835.764927052198;8119.39104567148;8322.126176751595;8456.41730949387;8491.490510569385 05:02:19 INFO - PROCESS | 7300 | __end_tp_report 05:02:19 INFO - PROCESS | 7300 | __start_cc_report 05:02:19 INFO - PROCESS | 7300 | _x_x_mozilla_cycle_collect,852 05:02:19 INFO - PROCESS | 7300 | __end_cc_report 05:02:19 INFO - PROCESS | 7300 | __startTimestamp1457096539308__endTimestamp 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | ------- Summary: start ------- 05:02:19 INFO - PROCESS | 7300 | Number of tests: 6 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | [#0] dojo.html Cycles:165 Average:4476.28 Median:4600.36 stddev:450.84 (9.8%) stddev-sans-first:426.60 05:02:19 INFO - PROCESS | 7300 | Values: 2566.4 2616.9 2647.8 2652.8 2660.0 4414.1 4503.6 4538.6 4582.8 4623.9 4569.2 4576.1 4623.7 4631.4 4633.5 4208.7 4227.0 4229.3 4236.1 4253.9 4042.2 4183.1 4214.3 4224.2 4253.2 4144.4 4190.0 4233.2 4271.6 4279.9 4177.4 4206.9 4209.6 4243.7 4253.3 4052.1 4178.5 4189.2 4207.8 4260.4 4458.7 4507.0 4548.2 4565.5 4570.0 4857.2 4867.9 4867.9 4870.0 4875.3 4624.4 4827.1 4888.2 4896.8 4921.6 4509.6 4512.8 4515.2 4536.2 4557.0 4921.1 4931.9 4932.0 4935.7 4978.1 4670.5 4752.2 4761.8 4798.6 4825.8 4893.8 4912.1 4913.5 4918.1 4924.2 4855.2 4857.0 4860.6 4876.7 4906.3 4641.8 4831.9 4834.9 4922.6 4928.1 4074.1 4084.2 4084.6 4088.2 4112.3 3600.5 3608.8 3619.0 3628.4 3645.2 4024.6 4105.5 4170.0 4250.7 4254.8 4786.3 4813.1 4813.9 4816.5 4829.1 4117.4 4130.9 4131.1 4172.2 4174.6 4156.8 4213.1 4263.6 4294.7 4304.4 4523.5 4540.8 4552.3 4558.2 4588.3 4731.2 4795.1 4803.4 4804.3 4846.6 4559.9 4602.3 4607.4 4612.9 4652.9 4751.0 4758.0 4762.2 4774.8 4788.4 4646.8 4735.1 4817.5 4823.1 4852.1 4564.6 4607.7 4632.6 4634.2 4670.6 4600.4 4631.2 4640.7 4666.8 4673.2 4458.2 4545.5 4559.6 4638.4 4736.9 4736.8 4749.8 4754.9 4780.8 4797.2 4589.9 4648.7 4649.7 4682.8 4706.2 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | [#1] ext.html Cycles:180 Average:38847.92 Median:18717.10 stddev:63259.78 (338.0%) stddev-sans-first:63371.60 05:02:19 INFO - PROCESS | 7300 | Values: 467.4 481.7 495.1 504.3 516.8 24316.1 31838.7 32176.8 32290.5 32376.4 35963.0 36167.5 36227.8 36281.5 36306.6 7607.4 7785.0 7787.3 7794.1 7802.3 7658.6 7734.4 7766.1 7772.0 7788.3 7754.9 7982.2 8001.8 8002.8 8003.6 7779.8 7840.8 7849.1 7849.1 7892.9 7768.8 7884.1 7895.3 7900.1 7904.5 35129.8 35138.0 35140.6 35145.6 35192.6 2771.7 2823.3 2835.8 2896.8 2936.2 27205.3 27263.3 27543.0 27701.0 28147.4 3166.4 3170.3 3177.2 3234.1 3305.2 211544.0 212250.0 212432.0 212692.0 212818.0 37160.4 51625.5 51676.5 51931.7 52044.5 35737.0 35876.3 35937.0 35969.8 35979.6 11197.8 11349.6 11358.6 11359.2 11361.3 8844.3 8889.5 8895.6 8898.0 8918.2 12754.0 12821.6 13356.3 13383.5 13395.9 5219.8 5307.2 5324.5 5332.3 5411.6 779.8 833.9 850.5 850.6 864.9 25446.9 25928.0 25953.1 26061.7 26090.6 889.9 935.2 946.9 984.6 1017.3 13343.6 13542.0 13854.8 13878.7 13886.0 261347.0 265960.0 266817.0 267140.0 267518.0 43539.8 44771.8 44837.8 44937.8 44948.8 968.1 1094.5 1134.5 1140.1 1187.0 22721.7 24040.3 25756.7 26875.6 28594.0 42536.0 42790.6 43003.6 43065.4 43069.8 244493.0 246846.0 247095.0 247218.0 247409.0 30335.7 36601.6 37694.8 38119.4 38699.6 34129.0 34654.5 35283.0 35328.0 35559.0 30072.0 30189.7 30452.0 30547.5 30736.4 32330.0 32393.8 32570.2 32704.3 32905.0 31860.5 32481.7 32770.5 32956.5 32985.0 28352.7 28903.9 29182.1 29645.3 29660.3 23122.4 23424.0 23555.6 23662.5 23769.4 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | [#2] jquery.html Cycles:180 Average:21895.20 Median:5089.86 stddev:61053.39 (1199.5%) stddev-sans-first:61203.17 05:02:19 INFO - PROCESS | 7300 | Values: 319.2 320.2 322.8 322.9 328.1 14976.3 15551.6 15555.1 15747.7 16349.8 13796.1 14056.1 15159.3 15278.0 15361.7 4926.2 5085.4 5099.2 5107.7 5115.2 4996.4 5009.0 5051.8 5105.6 5107.0 5074.1 5080.9 5117.1 5118.7 5127.0 4904.9 5077.3 5079.8 5088.3 5091.4 4930.7 4962.4 4966.6 4966.7 4970.2 10519.6 10597.5 10634.4 14680.0 19316.6 1735.8 1738.5 1771.4 1793.9 1837.8 8897.7 9106.4 9191.0 11277.0 12616.4 1664.2 1674.8 1807.8 1921.8 2010.8 53766.5 53772.5 54285.5 55881.7 56207.7 8612.9 8804.3 8936.9 8945.7 9114.7 10931.5 10937.2 11036.6 11051.4 11052.4 6030.8 6168.1 6215.3 6367.8 6466.5 6017.6 6065.8 6068.8 6102.6 6149.7 3691.2 3899.8 4184.5 4233.6 4244.6 1649.0 1657.3 1658.1 1662.4 1676.0 809.9 831.0 850.6 864.5 878.1 15322.3 15487.6 15525.3 15933.5 16109.8 793.6 818.3 851.1 854.4 888.6 8794.1 8866.4 8923.0 8947.8 8992.4 362351.0 365402.0 376819.0 377248.0 379101.0 61079.7 64014.4 64547.7 64909.0 65079.0 866.9 869.2 874.4 876.5 877.9 13681.5 13720.5 14112.9 14221.1 14299.5 30255.5 31330.7 31891.0 31908.7 32022.8 39473.0 42688.6 42705.8 43261.4 43576.6 6591.9 6884.4 7036.0 7064.7 7078.5 7615.5 9540.5 10233.4 10519.9 10779.3 8713.4 9099.6 9157.8 9247.4 9316.9 9185.2 9255.7 9549.4 9578.5 9653.5 6928.0 6995.2 7290.9 7385.1 9799.8 8387.6 8758.3 8773.5 9424.3 9856.7 9178.8 9441.7 9660.8 9834.2 9966.9 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | [#3] mootools.html Cycles:180 Average:5010.97 Median:6025.47 stddev:2717.08 (45.1%) stddev-sans-first:2708.94 05:02:19 INFO - PROCESS | 7300 | Values: 1116.8 1345.3 1352.6 1456.7 1687.8 6992.0 7155.2 7157.7 7169.2 7176.7 7155.6 7301.6 7307.5 7332.2 7334.4 1715.1 2068.8 2099.6 2126.5 2142.5 2184.7 2195.9 2196.8 2197.4 2198.4 1998.1 2052.8 2054.0 2055.7 2056.8 2050.8 2056.2 2067.3 2103.3 2125.6 2111.0 2127.6 2136.1 2139.1 2139.7 7373.8 7428.2 7449.1 7456.8 7503.4 4127.8 4252.4 4261.4 4264.1 4276.1 2606.1 2627.1 2657.6 2664.2 2675.4 783.1 790.3 801.1 840.8 848.2 10138.4 10192.5 10198.6 10224.0 10232.9 3985.9 4040.2 4064.5 4088.8 4120.4 8670.1 8670.5 8678.2 8680.6 8681.6 3470.3 4225.8 4253.9 4258.4 4264.9 3623.8 3673.4 3695.0 3701.3 3703.8 3412.1 3432.7 3478.5 3501.0 3520.2 1086.7 1090.3 1091.7 1100.7 1102.6 906.8 912.7 915.2 922.7 945.7 6665.3 6736.3 6775.4 6777.8 6781.6 418.2 429.2 430.3 434.0 436.1 4756.3 4764.3 4806.8 4836.4 4841.2 8742.6 8829.6 8970.1 9037.7 9103.3 8868.8 8904.0 8931.5 8977.1 9001.3 6865.8 6943.2 7005.1 7038.0 7072.5 6360.6 6388.2 6405.6 6425.2 6428.1 7711.7 7945.2 8026.0 8064.1 8076.4 8016.0 8231.1 8231.8 8323.3 8347.1 5286.2 5418.4 5489.1 6300.1 6340.3 6727.4 6945.4 6946.7 6949.9 6960.7 6071.0 6194.3 6258.4 6268.4 6283.4 5985.8 6080.4 6091.7 6161.3 6174.6 6421.8 6458.2 6466.5 6606.5 6625.8 5761.4 5849.2 5852.7 5855.1 5859.6 5886.1 6020.4 6030.6 6043.8 6058.0 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | [#4] prototype.html Cycles:180 Average:4630.97 Median:5358.12 stddev:1381.65 (25.8%) stddev-sans-first:1346.59 05:02:19 INFO - PROCESS | 7300 | Values: 291.7 300.1 300.4 305.2 309.3 5255.7 5370.3 5378.2 5414.7 5428.2 5271.6 5307.6 5322.0 5322.4 5345.9 3903.1 3989.5 4076.4 4107.1 4130.6 4122.3 4130.4 4134.3 4134.7 4139.1 4089.1 4089.2 4091.7 4100.0 4108.4 3844.2 3972.6 4045.9 4056.9 4104.3 3505.7 3509.6 3509.9 3516.3 3528.2 5137.0 5171.7 5172.4 5177.0 5190.9 4680.2 4690.6 4690.8 4693.1 4696.7 5622.6 5624.4 5632.0 5633.9 5639.4 3866.8 3867.6 3870.1 3871.6 3876.4 5671.3 5819.3 5952.0 5963.8 6025.0 3880.3 3889.6 3889.7 3896.6 3898.6 3944.8 3948.0 3948.4 3949.5 3953.3 4599.4 4601.7 4605.1 4617.6 4619.5 5605.2 5623.8 5643.5 5646.5 5653.3 1394.2 1415.3 1416.7 1419.6 1419.7 919.6 951.4 962.4 962.5 964.2 4842.4 4877.5 4898.7 4904.9 4909.5 5805.7 5812.4 5814.7 5815.3 5816.4 4879.0 4888.5 4892.0 4894.9 4895.6 3083.7 3120.2 3121.0 3122.4 3122.7 5433.3 5442.5 5458.5 5464.2 5474.8 5755.3 5960.4 5961.9 6020.6 6044.1 5434.4 5444.0 5451.9 5457.9 5461.5 5778.5 5794.2 5794.9 5796.2 5800.7 5647.2 5654.6 5664.7 5665.4 5669.1 5444.5 5447.2 5449.5 5450.8 5452.0 3842.6 3964.1 3964.7 3967.9 3972.7 5562.7 5772.6 5792.4 5792.7 5799.1 5510.7 5671.3 5672.5 5673.2 5673.7 5647.2 5648.0 5652.9 5663.1 5663.5 5512.0 5531.9 5545.9 5551.9 5559.2 5737.8 5744.9 5744.9 5745.4 5760.0 5424.5 5489.8 5552.6 5567.2 5567.8 05:02:19 INFO - PROCESS | 7300 | 05:02:19 INFO - PROCESS | 7300 | [#5] yui.html Cycles:180 Average:11899.99 Median:21720.87 stddev:13548.45 (62.4%) stddev-sans-first:13559.82 05:02:19 INFO - PROCESS | 7300 | Values: 586.6 601.3 633.2 642.7 683.5 10747.7 10942.5 10999.2 11004.8 11010.9 11163.8 11251.7 11254.4 11255.7 11268.5 602.3 742.4 747.2 770.6 779.1 771.4 772.5 776.1 776.7 776.7 817.3 820.5 820.5 821.2 821.4 626.2 789.7 809.8 809.9 810.5 595.2 600.0 600.3 600.4 600.5 10680.7 10713.1 10720.1 10724.6 10755.0 11349.1 11692.7 11701.9 12051.6 12314.1 8653.2 8849.8 8860.2 9017.5 9024.3 3037.7 3040.9 3083.4 3115.9 3124.9 38937.8 39144.8 39485.6 39617.0 39933.0 8848.7 8885.4 8920.1 9061.2 9079.2 15992.7 16008.8 16031.5 16181.8 16194.0 6451.9 6485.6 6494.8 6497.8 6666.6 3398.4 3449.1 3556.6 3616.2 3665.3 2573.7 2600.7 2603.2 2612.2 2622.7 408.9 431.2 447.5 461.3 500.0 476.3 520.8 535.8 537.2 538.1 7496.5 7522.5 7579.5 7606.3 7638.3 464.6 489.7 503.7 565.2 571.2 3495.3 3576.0 3599.2 3722.2 3807.5 45111.3 45532.0 52174.7 52834.0 53016.5 43010.6 43577.8 43611.0 44220.6 44419.8 38992.6 39867.6 39907.0 40350.6 41363.8 7767.8 7794.8 7959.5 8003.8 8097.1 29630.6 29827.9 30688.0 30994.5 31028.8 35472.7 35855.3 36457.1 38358.0 38717.8 9997.3 10024.4 10082.3 10220.0 10264.7 10891.5 11247.1 11256.4 11332.3 11462.7 10266.3 10280.2 10295.0 10513.7 10620.9 10081.4 10191.9 10280.2 10307.5 10406.1 10071.2 10249.8 10268.4 10597.2 10620.9 7817.2 8037.0 8044.9 8095.4 8178.3 7835.8 8119.4 8322.1 8456.4 8491.5 05:02:19 INFO - PROCESS | 7300 | -------- Summary: end -------- 05:02:19 INFO - PROCESS | 7300 | 05:02:20 INFO - TEST-INFO | 7300: exit 0 05:02:20 INFO - TEST-OK | dromaeo_css | took 1117208ms 05:02:20 INFO - TEST-START | kraken 05:02:20 INFO - operating with platform_type : linux_ 05:02:20 INFO - Initialising browser for kraken test... 05:02:20 INFO - TEST-INFO | started process 7482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp56n00/profile http://localhost:57310/getInfo.html) 05:02:25 INFO - PROCESS | 7482 | __metrics Screen width/height:1600/1200 05:02:25 INFO - PROCESS | 7482 | colorDepth:24 05:02:25 INFO - PROCESS | 7482 | Browser inner width/height: 1024/697 05:02:25 INFO - PROCESS | 7482 | __metrics 05:02:26 INFO - PROCESS | 7482 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:02:26 INFO - TEST-INFO | 7482: exit 0 05:02:26 INFO - Browser initialized. 05:02:26 INFO - Running cycle 1/1 for kraken test... 05:02:26 INFO - TEST-INFO | started process 7566 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp56n00/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:38 INFO - PROCESS | 7566 | RSS: Main: 153120768 05:02:38 INFO - PROCESS | 7566 | 05:02:41 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57310/tests/kraken/driver.html?testName=audio-beat-detection) 05:02:42 INFO - PROCESS | 7566 | RSS: Main: 197853184 05:02:42 INFO - PROCESS | 7566 | 05:02:44 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57310/tests/kraken/driver.html?testName=audio-dft) 05:02:45 INFO - PROCESS | 7566 | RSS: Main: 187006976 05:02:45 INFO - PROCESS | 7566 | 05:02:48 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57310/tests/kraken/driver.html?testName=audio-fft) 05:02:49 INFO - PROCESS | 7566 | RSS: Main: 174501888 05:02:49 INFO - PROCESS | 7566 | 05:02:51 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57310/tests/kraken/driver.html?testName=audio-oscillator) 05:02:51 INFO - PROCESS | 7566 | RSS: Main: 174755840 05:02:51 INFO - PROCESS | 7566 | 05:02:54 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57310/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:02:54 INFO - PROCESS | 7566 | RSS: Main: 175226880 05:02:54 INFO - PROCESS | 7566 | 05:02:57 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57310/tests/kraken/driver.html?testName=imaging-darkroom) 05:02:58 INFO - PROCESS | 7566 | RSS: Main: 174645248 05:02:58 INFO - PROCESS | 7566 | 05:03:01 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57310/tests/kraken/driver.html?testName=imaging-desaturate) 05:03:02 INFO - PROCESS | 7566 | RSS: Main: 174948352 05:03:02 INFO - PROCESS | 7566 | 05:03:05 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57310/tests/kraken/driver.html?testName=json-parse-financial) 05:03:05 INFO - PROCESS | 7566 | RSS: Main: 176586752 05:03:05 INFO - PROCESS | 7566 | 05:03:07 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57310/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:03:08 INFO - PROCESS | 7566 | RSS: Main: 188338176 05:03:08 INFO - PROCESS | 7566 | 05:03:10 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:10 INFO - PROCESS | 7566 | RSS: Main: 184131584 05:03:10 INFO - PROCESS | 7566 | 05:03:12 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:13 INFO - PROCESS | 7566 | RSS: Main: 194699264 05:03:13 INFO - PROCESS | 7566 | 05:03:15 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:03:16 INFO - PROCESS | 7566 | RSS: Main: 201052160 05:03:16 INFO - PROCESS | 7566 | 05:03:19 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:03:19 INFO - PROCESS | 7566 | RSS: Main: 200097792 05:03:19 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | Cycle 1(1): loaded http://localhost:57310/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57310/tests/kraken/driver.html?testName=ai-astar) 05:03:21 INFO - PROCESS | 7566 | RSS: Main: 261083136 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | __start_tp_report 05:03:21 INFO - PROCESS | 7566 | _x_x_mozilla_page_load 05:03:21 INFO - PROCESS | 7566 | _x_x_mozilla_page_load_details 05:03:21 INFO - PROCESS | 7566 | |i|pagename|runs| 05:03:21 INFO - PROCESS | 7566 | |0;ai-astar;112;113;109;113;116;114;103;113;113;113 05:03:21 INFO - PROCESS | 7566 | |1;audio-beat-detection;153;134;169;177;139;154;167;132;180;135 05:03:21 INFO - PROCESS | 7566 | |2;audio-dft;162;160;161;161;161;161;162;161;162;161 05:03:21 INFO - PROCESS | 7566 | |3;audio-fft;73;75;73;149;74;73;73;74;150;74 05:03:21 INFO - PROCESS | 7566 | |4;audio-oscillator;95;95;144;95;95;96;141;94;96;139 05:03:21 INFO - PROCESS | 7566 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;117;117;117 05:03:21 INFO - PROCESS | 7566 | |6;imaging-darkroom;147;149;146;148;146;146;146;147;146;148 05:03:21 INFO - PROCESS | 7566 | |7;imaging-desaturate;96;96;96;95;95;96;97;96;96;96 05:03:21 INFO - PROCESS | 7566 | |8;json-parse-financial;69;68;68;68;67;68;67;78;67;69 05:03:21 INFO - PROCESS | 7566 | |9;json-stringify-tinderbox;71;71;70;71;70;71;71;70;71;70 05:03:21 INFO - PROCESS | 7566 | |10;stanford-crypto-aes;94;84;85;84;84;83;85;82;84;84 05:03:21 INFO - PROCESS | 7566 | |11;stanford-crypto-ccm;120;124;120;130;121;119;129;128;122;122 05:03:21 INFO - PROCESS | 7566 | |12;stanford-crypto-pbkdf2;154;149;148;150;151;140;154;151;151;150 05:03:21 INFO - PROCESS | 7566 | |13;stanford-crypto-sha256-iterative;73;70;73;72;71;69;70;76;69;70 05:03:21 INFO - PROCESS | 7566 | __end_tp_report 05:03:21 INFO - PROCESS | 7566 | __start_cc_report 05:03:21 INFO - PROCESS | 7566 | _x_x_mozilla_cycle_collect,1160 05:03:21 INFO - PROCESS | 7566 | __end_cc_report 05:03:21 INFO - PROCESS | 7566 | __startTimestamp1457096601936__endTimestamp 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | ------- Summary: start ------- 05:03:21 INFO - PROCESS | 7566 | Number of tests: 14 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:3.57 (3.2%) stddev-sans-first:3.79 05:03:21 INFO - PROCESS | 7566 | Values: 112.0 113.0 109.0 113.0 116.0 114.0 103.0 113.0 113.0 113.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#1] audio-beat-detection Cycles:10 Average:154.00 Median:160.50 stddev:18.47 (11.5%) stddev-sans-first:19.59 05:03:21 INFO - PROCESS | 7566 | Values: 153.0 134.0 169.0 177.0 139.0 154.0 167.0 132.0 180.0 135.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 05:03:21 INFO - PROCESS | 7566 | Values: 162.0 160.0 161.0 161.0 161.0 161.0 162.0 161.0 162.0 161.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#3] audio-fft Cycles:10 Average:88.80 Median:73.50 stddev:32.00 (43.5%) stddev-sans-first:33.43 05:03:21 INFO - PROCESS | 7566 | Values: 73.0 75.0 73.0 149.0 74.0 73.0 73.0 74.0 150.0 74.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:95.00 stddev:22.35 (23.5%) stddev-sans-first:23.13 05:03:21 INFO - PROCESS | 7566 | Values: 95.0 95.0 144.0 95.0 95.0 96.0 141.0 94.0 96.0 139.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:03:21 INFO - PROCESS | 7566 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:03:21 INFO - PROCESS | 7566 | Values: 147.0 149.0 146.0 148.0 146.0 146.0 146.0 147.0 146.0 148.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:03:21 INFO - PROCESS | 7566 | Values: 96.0 96.0 96.0 95.0 95.0 96.0 97.0 96.0 96.0 96.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:68.00 stddev:3.28 (4.8%) stddev-sans-first:3.48 05:03:21 INFO - PROCESS | 7566 | Values: 69.0 68.0 68.0 68.0 67.0 68.0 67.0 78.0 67.0 69.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:0.52 (0.7%) stddev-sans-first:0.53 05:03:21 INFO - PROCESS | 7566 | Values: 71.0 71.0 70.0 71.0 70.0 71.0 71.0 70.0 71.0 70.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:84.00 stddev:3.31 (3.9%) stddev-sans-first:0.93 05:03:21 INFO - PROCESS | 7566 | Values: 94.0 84.0 85.0 84.0 84.0 83.0 85.0 82.0 84.0 84.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#11] stanford-crypto-ccm Cycles:10 Average:123.50 Median:123.00 stddev:4.06 (3.3%) stddev-sans-first:4.11 05:03:21 INFO - PROCESS | 7566 | Values: 120.0 124.0 120.0 130.0 121.0 119.0 129.0 128.0 122.0 122.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.80 Median:151.00 stddev:3.94 (2.6%) stddev-sans-first:3.87 05:03:21 INFO - PROCESS | 7566 | Values: 154.0 149.0 148.0 150.0 151.0 140.0 154.0 151.0 151.0 150.0 05:03:21 INFO - PROCESS | 7566 | 05:03:21 INFO - PROCESS | 7566 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:71.50 stddev:2.21 (3.1%) stddev-sans-first:2.26 05:03:21 INFO - PROCESS | 7566 | Values: 73.0 70.0 73.0 72.0 71.0 69.0 70.0 76.0 69.0 70.0 05:03:21 INFO - PROCESS | 7566 | -------- Summary: end -------- 05:03:21 INFO - PROCESS | 7566 | 05:03:22 INFO - TEST-INFO | 7566: exit 0 05:03:22 INFO - TEST-OK | kraken | took 62926ms 05:03:22 INFO - SUITE-END | took 1180s 05:03:23 INFO - Completed test suite (00:19:40) 05:03:23 INFO - Working with test: dromaeo_css 05:03:23 INFO - Generating results file: dromaeo_css 05:03:23 INFO - Working with test: kraken 05:03:23 INFO - Generating results file: kraken 05:03:23 INFO - javascript benchmark 05:03:23 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:03:23 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,132,35]] 05:03:23 INFO - process_Request line: dromaeo_css 6016.66 graph.html#tests=[[72,132,35]] 05:03:23 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:03:23 INFO - process_Request line: kraken 1553.80 graph.html#tests=[[232,132,35]] 05:03:23 INFO - RETURN: dromaeo_css: 6016.66 05:03:23 INFO - RETURN: kraken: 1553.80 05:03:23 INFO - javascript benchmark 05:03:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2566.3968967292685, 2616.9084082057134, 2647.8014148938823, 2652.840829550227, 2659.9867000664995, 4414.095110502348, 4503.594676479116, 4538.636909047276, 4582.816687332507, 4623.907521849561, 4569.154706379322, 4576.130535198318, 4623.676342656016, 4631.421072365954, 4633.536646335365, 4208.747475151501, 4227.04891399435, 4229.302165142748, 4236.110416812473, 4253.851115210969, 4042.1915616876595, 4183.058811767359, 4214.346776249687, 4224.197402493532, 4253.234417804794, 4144.378343248507, 4190.0, 4233.195692818385, 4271.572842715735, 4279.935800962988, 4177.394277829698, 4206.936895946563, 4209.6000879916355, 4243.681723870722, 4253.255680255974, 4052.1287923096675, 4178.519470260929, 4189.183109293689, 4207.789610519461, 4260.446142001551, 4458.732476051415, 4507.0, 4548.226801443762, 4565.5206203348835, 4569.977150114228, 4857.1742803723455, 4867.902641947141, 4867.9269810952865, 4870.025994801054, 4875.293082503017, 4624.445066591997, 4827.131116399047, 4888.193448081062, 4896.828610998634, 4921.630877684188, 4509.594136527729, 4512.819487220474, 4515.209838278288, 4536.160810250114, 4557.0, 4921.114199444063, 4931.852044438672, 4931.9753401232765, 4935.654504184673, 4978.1288274552535, 4670.5329467053025, 4752.1921273383605, 4761.785719642625, 4798.592119669797, 4825.782839772219, 4893.804247830047, 4912.1158191525155, 4913.542372206104, 4918.065567542227, 4924.187509060965, 4855.150348688965, 4856.95143048572, 4860.635452341088, 4876.731779752076, 4906.2640603909695, 4641.8375356863025, 4831.903361932812, 4834.854954351375, 4922.556969872657, 4928.088303663833, 4074.083331250504, 4084.1627466369323, 4084.591540845892, 4088.2232375848494, 4112.280350938574, 3600.459931010317, 3608.8376023079027, 3619.0, 3628.3650361186687, 3645.179834537208, 4024.597540245952, 4105.527864295645, 4169.97835530297, 4250.681198910094, 4254.829806807755, 4786.282057691375, 4813.061453016699, 4813.879653008703, 4816.470188279286, 4829.082474329936, 4117.402976568381, 4130.938035929464, 4131.132462182975, 4172.165566886635, 4174.603412675791, 4156.750594964312, 4213.115245798416, 4263.594958479001, 4294.677899157513, 4304.418903448057, 4523.479799823062, 4540.77296135192, 4552.339910712862, 4558.179527685049, 4588.26587745959, 4731.219348807546, 4795.088933102699, 4803.3995750531185, 4804.3273941648895, 4846.61227101824, 4559.886002849956, 4602.263637817933, 4607.378003969556, 4612.930806037845, 4652.906941861212, 4751.026039661931, 4758.04839032188, 4762.214234651244, 4774.7851346688785, 4788.353572267699, 4646.767661616973, 4735.076660051257, 4817.494163112823, 4823.131836269365, 4852.053849499313, 4564.589186973189, 4607.723536587815, 4632.606228470483, 4634.189016922092, 4670.602998745145, 4600.355950166912, 4631.235846085358, 4640.721556706609, 4666.789994450326, 4673.158831410243, 4458.219811533034, 4545.545445455428, 4559.635229181724, 4638.350630911743, 4736.8578942631775, 4736.834210802744, 4749.7625118743235, 4754.857354279377, 4780.760961951958, 4797.160496912957, 4589.862304130881, 4648.674592778609, 4649.65127615437, 4682.765861706969, 4706.223473126895], "unit": "score", "name": "dojo.html", "value": 4449.197320314204}, {"lowerIsBetter": false, "replicates": [467.3947238326367, 481.74708278153986, 495.1261024292125, 504.30909653774324, 516.7906997665946, 24316.148934787285, 31838.68161318386, 32176.839115804418, 32290.515642265385, 32376.352472950526, 35963.0, 36167.4574881377, 36227.81886090566, 36281.45577816335, 36306.63693363065, 7607.353374963121, 7785.026871641045, 7787.260210280015, 7794.142644309121, 7802.297793198611, 7658.578778167199, 7734.419918506106, 7766.10689770678, 7772.028496437945, 7788.260115289067, 7754.922450775476, 7982.2017798220295, 8001.839963200732, 8002.839943201162, 8003.599820008976, 7779.7666070017995, 7840.764777056697, 7849.0973538043, 7849.136594974577, 7892.881606775903, 7768.844623107563, 7884.132745398002, 7895.328897043758, 7900.091489478726, 7904.486208396494, 35129.824350878334, 35138.0, 35140.64859351399, 35145.64854351475, 35192.64807351945, 2771.6812566554904, 2823.294176455886, 2835.815671981335, 2896.7537759290485, 2936.1925470495785, 27205.319867003287, 27263.318417039733, 27543.0, 27701.0, 28147.43705125886, 3166.3825554016976, 3170.302533442639, 3177.158053115926, 3234.0782876879975, 3305.223272530953, 211544.0, 212250.0, 212432.0, 212692.0, 212818.0, 37160.44259336112, 51625.48374516282, 51676.483235167165, 51931.74034129805, 52044.47955520472, 35737.0, 35876.28247435089, 35937.0, 35969.820150899075, 35979.64020359815, 11197.832032519518, 11349.60276390339, 11358.60244891425, 11359.20485566019, 11361.318320900773, 8844.33667474942, 8889.511076890703, 8895.555222238992, 8898.0, 8918.241949434241, 12754.0, 12821.551245706356, 13356.265405402795, 13383.464661413434, 13395.861351785208, 5219.765110570033, 5307.230451584576, 5324.494173053554, 5332.2801421808335, 5411.648242864201, 779.8128449172158, 833.8915940927641, 850.4684572142411, 850.570461916728, 864.8875646165958, 25446.872765636053, 25928.0, 25953.14812340065, 26061.73938260593, 26090.608640870403, 889.8965283048973, 935.1770442010986, 946.9289803264783, 984.5963155106372, 1017.308230403318, 13343.599692009255, 13542.0, 13854.792178117335, 13878.722425551432, 13886.0, 261347.0, 265960.0, 266817.0, 267140.0, 267518.0, 43539.78230108829, 44771.77614111909, 44837.77581112074, 44937.77531112324, 44948.775256124165, 968.1190116993643, 1094.5348227003492, 1134.4838098665089, 1140.1107136433595, 1186.9762604747857, 22721.659175112058, 24040.27879163628, 25756.742432575433, 26875.596866046635, 28593.998420110394, 42536.0, 42790.57209427866, 43003.56996429995, 43065.354019689104, 43069.784651076545, 244493.0, 246846.0, 247095.0, 247218.0, 247409.0, 30335.696643033287, 36601.63398366089, 37694.8115259422, 38119.42820857745, 38699.61300387073, 34128.97613071612, 34654.480182796775, 35283.0, 35328.0, 35559.0, 30072.0, 30189.698103018687, 30452.0, 30547.54178687277, 30736.385272294876, 32330.0, 32393.838030809697, 32570.185745355608, 32704.34591308208, 32905.0, 31860.522092168172, 32481.675183247866, 32770.50844237386, 32956.50565241571, 32985.0, 28352.716472835007, 28903.85548072246, 29182.124536263946, 29645.25886852846, 29660.258493537833, 23122.421939450975, 23424.0, 23555.646665300377, 23662.526749465258, 23769.405764856016], "unit": "score", "name": "ext.html", "value": 15673.926920854137}, {"lowerIsBetter": false, "replicates": [319.2354311424141, 320.19151642103685, 322.84664784227493, 322.8514883153749, 328.05847218482955, 14976.326065327059, 15551.589503626941, 15555.066695998248, 15747.685046299095, 16349.83650163498, 13796.10325328853, 14056.086354386958, 15159.271331160387, 15278.0, 15361.69276614467, 4926.187179115442, 5085.389753229618, 5099.209622508517, 5107.693538387709, 5115.207142892839, 4996.4254110777365, 5009.0482808266315, 5051.79792808289, 5105.566026887719, 5107.0, 5074.061298659742, 5080.949190508085, 5117.078925793356, 5118.744062796845, 5127.0, 4904.926426103611, 5077.314562534048, 5079.84760457185, 5088.287639730441, 5091.439941606421, 4930.654854160211, 4962.3548938638105, 4966.602671786249, 4966.6771659842, 4970.179920313144, 10519.631812886511, 10597.470126493645, 10634.361938283728, 14680.0, 19316.613667726706, 1735.843774060328, 1738.4958362074983, 1771.4242871066954, 1793.9282428702836, 1837.8437832784095, 8897.73306800797, 9106.362554621179, 9190.954045229799, 11277.041451476634, 12616.432260548298, 1664.167916041979, 1674.7571602117746, 1807.8101799311146, 1921.788603253641, 2010.8190262876412, 53766.462335376535, 53772.46227537714, 54285.45714542883, 55881.72059139719, 56207.718961404935, 8612.8708069379, 8804.251638610662, 8936.927568691668, 8945.73162805117, 9114.726558203132, 10931.453427328603, 10937.234393592373, 11036.613718519813, 11051.392173430379, 11052.392118433403, 6030.819075427744, 6168.136460895478, 6215.285242197115, 6367.840803979845, 6466.515011374166, 6017.646029643295, 6065.8180254592435, 6068.757249709962, 6102.572819902564, 6149.661768602769, 3691.2063906259746, 3899.8440062397754, 4184.456020717287, 4233.618974292329, 4244.639205667553, 1648.983510164883, 1657.2790835986384, 1658.1377683604458, 1662.3516828436918, 1676.0, 809.9311558517475, 830.9958450207831, 850.608719988812, 864.4899509289423, 878.1438097854668, 15322.310496027705, 15487.612809679847, 15525.301361438762, 15933.521994340188, 16109.838901611069, 793.6150966781106, 818.2799136759733, 851.0595791650261, 854.384842913114, 888.560162719458, 8794.076621954731, 8866.42368246062, 8922.955385223033, 8947.776305592282, 8992.415492992934, 362351.0, 365402.0, 376819.0, 377248.0, 379101.0, 61079.6946015276, 64014.35985640084, 64547.67726161339, 64909.02636460457, 65079.0, 866.882970798934, 869.1959937058195, 874.4185116897319, 876.4609764994483, 877.8683197520423, 13681.521146759816, 13720.519781807589, 14112.870970322554, 14221.14673119616, 14299.499517516837, 30255.546166807955, 31330.686693133688, 31891.0, 31908.680913190572, 32022.839885801353, 39473.0, 42688.573114269704, 42705.78647106745, 43261.3510797332, 43576.56423435852, 6591.868162636816, 6884.4148247399535, 7036.014957905795, 7064.717411303491, 7078.4691148163065, 7615.5430674159725, 9540.475273860004, 10233.422784582272, 10519.947400263256, 10779.299345542515, 8713.43362681449, 9099.59051842682, 9157.77105572366, 9247.445153290824, 9316.906830931875, 9185.219256363016, 9255.67605133817, 9549.376605977139, 9578.473183974947, 9653.46905920181, 6927.965360173167, 6995.160580730345, 7290.927090729025, 7385.113786345673, 9799.755006124904, 8387.580620968805, 8758.343124265833, 8773.473591584525, 9424.293178011814, 9856.704298871045, 9178.816423671356, 9441.669541566012, 9660.806783864424, 9834.213262938805, 9966.915831295506], "unit": "score", "name": "jquery.html", "value": 7420.788475425525}, {"lowerIsBetter": false, "replicates": [1116.771061932304, 1345.30044376924, 1352.6145048661133, 1456.679530503289, 1687.7637130801681, 6991.965040174799, 7155.212926578072, 7157.713691452349, 7169.247229040947, 7176.748813791518, 7155.642217889098, 7301.634918254093, 7307.451941104412, 7332.156801967775, 7334.449916256289, 1715.1081437652413, 2068.844836637258, 2099.6010757956014, 2126.5109024924277, 2142.507223338633, 2184.7050648162453, 2195.9121635134657, 2196.8132708719777, 2197.3627647982066, 2198.373463562885, 1998.1308130963005, 2052.8255098316663, 2053.979460205401, 2055.701923221132, 2056.8251698605563, 2050.805173508507, 2056.156975639988, 2067.348785132686, 2103.2638576498257, 2125.5961367340155, 2110.9788902110936, 2127.6383014887506, 2136.060133541237, 2139.0801955159277, 2139.700441938114, 7373.77878663641, 7428.182899881009, 7449.143348514882, 7456.813579660497, 7503.437242206856, 4127.7936103195025, 4252.447181866338, 4261.4460120184485, 4264.0832221072305, 4276.080642661843, 2606.126947472599, 2627.067391076179, 2657.5747880339245, 2664.214056853233, 2675.438157986825, 783.0720596093647, 790.3203245209115, 801.0867610923552, 840.8192238668715, 848.215400754306, 10138.391696498235, 10192.490375481271, 10198.643047493302, 10224.0, 10232.948835255776, 3985.880423587239, 4040.1919616076902, 4064.451299074587, 4088.8160032798596, 4120.361343991686, 8670.132986701405, 8670.523121228267, 8678.175573320525, 8680.56597170139, 8681.56592170389, 3470.305938812248, 4225.788710564521, 4253.914921701548, 4258.42511260982, 4264.87205383839, 3623.7825730456257, 3673.4489826526233, 3695.020819482839, 3701.3337599232395, 3703.7962912039557, 3412.078738740526, 3432.6910578047596, 3478.4608385700258, 3501.0372147659596, 3520.2079532105586, 1086.6848613902039, 1090.340344091829, 1091.748897753521, 1100.7193165742685, 1102.6195962392962, 906.8413027720122, 912.7170577121113, 915.2403505090758, 922.7462447826902, 945.6879229854131, 6665.266820649725, 6736.2590115087305, 6775.356341147583, 6777.830554236183, 6781.559198652072, 418.2054097215254, 429.17384035731715, 430.3050573324059, 434.04726625057697, 436.112511040032, 4756.286557016475, 4764.309175169581, 4806.831760888352, 4836.419629644465, 4841.15279826029, 8742.606582703793, 8829.64681412749, 8970.102989700978, 9037.68368107113, 9103.317251206186, 8868.778280542909, 8904.0, 8931.464112153291, 8977.057408972094, 9001.324900632348, 6865.794026179223, 6943.166819981533, 7005.124359455068, 7037.964810176019, 7072.504924655328, 6360.554761166674, 6388.233411990591, 6405.583637063763, 6425.164728585254, 6428.132202152649, 7711.691532338644, 7945.1657575953695, 8025.9598702006115, 8064.072631647436, 8076.394270429859, 8016.0, 8231.09457959636, 8231.794205144679, 8323.334133269445, 8347.081820999807, 5286.180642000496, 5418.376886658085, 5489.094299440548, 6300.149479820349, 6340.270868849957, 6727.360900714424, 6945.374916257561, 6946.687399066953, 6949.895751563832, 6960.686769095504, 6071.0, 6194.318624951342, 6258.405451482102, 6268.373162683877, 6283.403076707706, 5985.760569577343, 6080.391960803884, 6091.664958427329, 6161.260648722182, 6174.567780255339, 6421.775237866652, 6458.160439142881, 6466.482681385383, 6606.47148228131, 6625.768098116543, 5761.423857614205, 5849.151872978479, 5852.707364631837, 5855.092460668603, 5859.619124756877, 5886.087656413263, 6020.367861374668, 6030.5778595497895, 6043.8186854394435, 6058.030715085565], "unit": "score", "name": "mootools.html", "value": 3987.585154076439}, {"lowerIsBetter": false, "replicates": [291.71266302691856, 300.13411308375333, 300.4096949494245, 305.20646319569136, 309.2500685836846, 5255.710935898529, 5370.301860758101, 5378.1663842104435, 5414.729263536827, 5428.240046393498, 5271.578273738101, 5307.575393968483, 5321.973390133054, 5322.387925388582, 5345.946540534594, 3903.1022864740985, 3989.501312335958, 4076.429299898017, 4107.116969851481, 4130.607592278729, 4122.278601244785, 4130.421740956269, 4134.338505839067, 4134.6692264618905, 4139.089400331922, 4089.0595163112503, 4089.161721847014, 4091.652209562176, 4099.975006248438, 4108.36320370343, 3844.2119365529998, 3972.6226008529143, 4045.898852528681, 4056.898577535556, 4104.343305071175, 3505.6844883960566, 3509.5613048368955, 3509.8947031588837, 3516.349475347069, 3528.2237907660274, 5136.974315128437, 5171.741412929376, 5172.353455818023, 5176.9741151294, 5190.92213616796, 4680.157571637104, 4690.624750019991, 4690.788914498856, 4693.131770622445, 4696.741679207642, 5622.634528755659, 5624.3813180550105, 5632.0, 5633.887322253573, 5639.379668236491, 3866.845326186949, 3867.5552311483975, 3870.0518372998795, 3871.593482684306, 3876.4185372194115, 5671.291088613923, 5819.272590926134, 5952.0, 5963.791267305667, 6025.0359942409, 3880.282147802609, 3889.649931506178, 3889.747166434173, 3896.5713771485116, 3898.590647981978, 3944.8422063117155, 3948.032731980683, 3948.407738839197, 3949.466821978996, 3953.288408086572, 4599.425071866017, 4601.723896566216, 4605.078984203173, 4617.561331673485, 4619.514950930171, 5605.159226116115, 5623.831285061455, 5643.52030077448, 5646.491815736604, 5653.321601407775, 1394.2262044565364, 1415.3135729171345, 1416.7449859025473, 1419.5954153066327, 1419.6805718713413, 919.5540163020929, 951.3911096897991, 962.3840741925173, 962.4562122400821, 964.238251781099, 4842.418909730784, 4877.512248775094, 4898.681585696918, 4904.8773780655765, 4909.5090490950615, 5805.7387417566315, 5812.389699081536, 5814.680192589448, 5815.302163740378, 5816.447437493432, 4879.0, 4888.486708895586, 4891.95108048915, 4894.926576101361, 4895.583875370633, 3083.722464978163, 3120.1887509247304, 3120.985679654076, 3122.35991621717, 3122.6565077841874, 5433.2936718227165, 5442.482964118402, 5458.5087342139395, 5464.23500709901, 5474.808381706701, 5755.3381361143165, 5960.37416071315, 5961.850953726105, 6020.638761674313, 6044.063170208625, 5434.429384914606, 5443.972780136074, 5451.945480545223, 5457.918131227955, 5461.453854614666, 5778.451047150514, 5794.159846822103, 5794.884102318014, 5796.188533605215, 5800.738966746422, 5647.237622920853, 5654.604177707521, 5664.688442135557, 5665.376808551138, 5669.092945128759, 5444.50999410055, 5447.155690867922, 5449.509544141047, 5450.809221677222, 5451.972740136274, 3842.558105817867, 3964.1080756829483, 3964.7224694271126, 3967.940480892731, 3972.702047346518, 5562.72186390687, 5772.567057470623, 5792.391798861059, 5792.652440853562, 5799.072148456226, 5510.669359838423, 5671.319441666861, 5672.5461963042035, 5673.205751194919, 5673.716314184357, 5647.209390685225, 5648.039833228278, 5652.85867853307, 5663.093904975184, 5663.546916246608, 5511.972440137773, 5531.917021244604, 5545.916811247916, 5551.916721249266, 5559.16612508127, 5737.827865164052, 5744.856378590569, 5744.913826292693, 5745.396733343106, 5759.97120014414, 5424.491084109131, 5489.835304940858, 5552.555795536428, 5567.248421463212, 5567.777288908398], "unit": "score", "name": "prototype.html", "value": 4185.00313110756}, {"lowerIsBetter": false, "replicates": [586.5864565481336, 601.3054921565594, 633.170546583975, 642.7011439680548, 683.5181197255935, 10747.677569672902, 10942.507587158578, 10999.175061870352, 11004.779904401907, 11010.889891101087, 11163.83254251187, 11251.662450126509, 11254.381009044499, 11255.662330130088, 11268.492917818698, 602.3073465514649, 742.391239183868, 747.1818358896999, 770.5877355614747, 779.1079214299624, 771.4252881603213, 772.528757457953, 776.095848336687, 776.6505072717272, 776.7397921696239, 817.2971244729528, 820.5281962871343, 820.5363969357323, 821.2034326703089, 821.4044817507319, 626.1515646299267, 789.743333416639, 809.7692157735017, 809.8866158737782, 810.4974915552336, 595.1608232392306, 600.0039933710063, 600.2826622186489, 600.4115966352998, 600.4985836826255, 10680.679579612624, 10713.08938740208, 10720.08879245265, 10724.624638137706, 10755.032047115797, 11349.09207263417, 11692.707682307926, 11701.882981170165, 12051.55381354243, 12314.076444266715, 8653.17794809492, 8849.823003539894, 8860.158284962918, 9017.549122543913, 9024.323175761778, 3037.696230376967, 3040.893568725084, 3083.398737246238, 3115.937681246385, 3124.8593813278458, 38937.805310973265, 39144.80427597844, 39485.60514394848, 39616.99999999971, 39933.0, 8848.734537963872, 8885.42244754089, 8920.063393343735, 9061.184493395494, 9079.228265597367, 15992.680146397239, 16008.839911600735, 16031.519054428385, 16181.838181618268, 16194.0, 6451.903221451682, 6485.643289619022, 6494.805155845333, 6497.805065848032, 6666.633335166515, 3398.3713013092656, 3449.068751437098, 3556.626554211822, 3616.222512159846, 3665.321915445651, 2573.652556904794, 2600.7139214686367, 2603.2060221632205, 2612.2163350994624, 2622.711501734808, 408.895731588443, 431.1699977543217, 447.5278581096909, 461.25277051176636, 499.96507230033484, 476.3045952908792, 520.7578476008674, 535.7535533654485, 537.2022546518417, 538.0879409401027, 7496.475246732785, 7522.473426860067, 7579.5073320234005, 7606.315431611071, 7638.256925899983, 464.5749139537353, 489.7404375680895, 503.72295237619164, 565.245397394483, 571.245955338957, 3495.3358861816682, 3576.0, 3599.2441587266444, 3722.236941427001, 3807.485989391452, 45111.32333015007, 45532.0, 52174.73912630413, 52834.0, 53016.46983530193, 43010.56989430128, 43577.78211108924, 43611.0, 44220.55779442229, 44419.77790111029, 38992.610073899465, 39867.60132398697, 39907.0, 40350.59649403527, 41363.7931810339, 7767.805804854924, 7794.844103117793, 7959.482633628795, 8003.83992320162, 8097.1093179749005, 29630.55554166646, 29827.85086074556, 30688.0, 30994.535081974238, 31028.84485577648, 35472.74327080767, 35855.28289434144, 36457.08857278483, 38358.0, 38717.806410967765, 9997.250206234707, 10024.448655324028, 10082.33641449182, 10220.0, 10264.692059238234, 10891.455427228766, 11247.100231981258, 11256.437178141225, 11332.263402878787, 11462.713432164262, 10266.332688375232, 10280.17758579297, 10295.0, 10513.737156570842, 10620.89379106199, 10081.445520496443, 10191.898081019392, 10280.17758579297, 10307.484625768531, 10406.11548018427, 10071.19430445548, 10249.795004100026, 10268.435236062089, 10597.15222782191, 10620.946895265473, 7817.218278172138, 8036.959815200887, 8044.934239553499, 8095.352371810359, 8178.304844088081, 7835.764927052198, 8119.39104567148, 8322.126176751595, 8456.41730949387, 8491.490510569385], "unit": "score", "name": "yui.html", "value": 5492.545640360867}], "name": "dromaeo_css", "value": 6016.656863184755}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 113.0, 109.0, 113.0, 116.0, 114.0, 103.0, 113.0, 113.0, 113.0], "unit": "score", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [153.0, 134.0, 169.0, 177.0, 139.0, 154.0, 167.0, 132.0, 180.0, 135.0], "unit": "score", "name": "audio-beat-detection", "value": 154.0}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [73.0, 75.0, 73.0, 149.0, 74.0, 73.0, 73.0, 74.0, 150.0, 74.0], "unit": "score", "name": "audio-fft", "value": 88.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 144.0, 95.0, 95.0, 96.0, 141.0, 94.0, 96.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 109.0}, {"lowerIsBetter": true, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.1}, {"lowerIsBetter": true, "replicates": [147.0, 149.0, 146.0, 148.0, 146.0, 146.0, 146.0, 147.0, 146.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 97.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 68.0, 67.0, 68.0, 67.0, 78.0, 67.0, 69.0], "unit": "score", "name": "json-parse-financial", "value": 68.9}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 70.0, 71.0, 70.0, 71.0, 71.0, 70.0, 71.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.6}, {"lowerIsBetter": true, "replicates": [94.0, 84.0, 85.0, 84.0, 84.0, 83.0, 85.0, 82.0, 84.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.9}, {"lowerIsBetter": true, "replicates": [120.0, 124.0, 120.0, 130.0, 121.0, 119.0, 129.0, 128.0, 122.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.5}, {"lowerIsBetter": true, "replicates": [154.0, 149.0, 148.0, 150.0, 151.0, 140.0, 154.0, 151.0, 151.0, 150.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.8}, {"lowerIsBetter": true, "replicates": [73.0, 70.0, 73.0, 72.0, 71.0, 69.0, 70.0, 76.0, 69.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.3}], "name": "kraken", "value": 1553.8}]} 05:03:23 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,35]]", "result": "1553.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,35]]", "result": "6016.66"}}} 05:03:23 INFO - Return code: 0 05:03:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:03:23 INFO - # TBPL SUCCESS # 05:03:23 INFO - Running post-action listener: _resource_record_post_action 05:03:23 INFO - Running post-run listener: _resource_record_post_run 05:03:24 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 394358784; Read time: 16; Write time: 450148 05:03:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110985216; Read time: 0; Write time: 111296 05:03:24 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282775552; Read time: 16; Write time: 338852 05:03:24 INFO - Running post-run listener: _upload_blobber_files 05:03:24 INFO - Blob upload gear active. 05:03:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:03:24 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 05:03:24 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:03:24 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:03:24 INFO - (blobuploader) - INFO - Open directory for files ... 05:03:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:03:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:03:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:26 INFO - (blobuploader) - INFO - Done attempting. 05:03:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:03:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:03:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:26 INFO - (blobuploader) - INFO - Done attempting. 05:03:26 INFO - (blobuploader) - INFO - Iteration through files over. 05:03:26 INFO - Return code: 0 05:03:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:03:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:03:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c381500635aae8a003b93866d32afe54e22816873e5fa5ffe642895eb59dcbad97a6f943e285ace1a80e0b8aad1939a769f0da0f7ba9910d1356d984778980da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/72e6a7387bfcdac0f1f700ab87f05ee8d17862affde59712fe3ba65ea8c6fd1908726adcc11b3c6568c48cfcc125bfa6843565131d5dcefc2d8f3065c55853f8"} 05:03:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:03:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:03:26 INFO - Contents: 05:03:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c381500635aae8a003b93866d32afe54e22816873e5fa5ffe642895eb59dcbad97a6f943e285ace1a80e0b8aad1939a769f0da0f7ba9910d1356d984778980da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/72e6a7387bfcdac0f1f700ab87f05ee8d17862affde59712fe3ba65ea8c6fd1908726adcc11b3c6568c48cfcc125bfa6843565131d5dcefc2d8f3065c55853f8"} 05:03:26 INFO - Running post-run listener: copy_logs_to_upload_dir 05:03:26 INFO - Copying logs to upload dir... 05:03:26 INFO - mkdir: /builds/slave/test/build/upload/logs 05:03:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.240513 ========= master_lag: 43.62 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 50 secs) (at 2016-03-04 05:04:10.421353) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-04 05:04:10.425044) ========= 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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c381500635aae8a003b93866d32afe54e22816873e5fa5ffe642895eb59dcbad97a6f943e285ace1a80e0b8aad1939a769f0da0f7ba9910d1356d984778980da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/72e6a7387bfcdac0f1f700ab87f05ee8d17862affde59712fe3ba65ea8c6fd1908726adcc11b3c6568c48cfcc125bfa6843565131d5dcefc2d8f3065c55853f8"} build_url:https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011386 build_url: 'https://queue.taskcluster.net/v1/task/WC4TqfAtQriLQsryeEzUuQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c381500635aae8a003b93866d32afe54e22816873e5fa5ffe642895eb59dcbad97a6f943e285ace1a80e0b8aad1939a769f0da0f7ba9910d1356d984778980da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/72e6a7387bfcdac0f1f700ab87f05ee8d17862affde59712fe3ba65ea8c6fd1908726adcc11b3c6568c48cfcc125bfa6843565131d5dcefc2d8f3065c55853f8"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-04 05:04:10.734419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 05:04:10.734754) ========= 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=1861a34ebfea852630dec091000001fa-1457094394.172766-352103562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004577 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 05:04:10.750677) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-04 05:04:10.750980) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-04 05:04:10.751269) ========= ========= Total master_lag: 44.10 =========